Nov 8 09:30:49.680497 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 8 09:30:49.680513 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 Nov 8 09:30:49.680521 kernel: efi: EFI v2.70 by EDK II Nov 8 09:30:49.680527 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Nov 8 09:30:49.680531 kernel: efi: seeding entropy pool Nov 8 09:30:49.680536 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Nov 8 09:30:49.680542 kernel: ACPI: Early table checksum verification disabled Nov 8 09:30:49.680547 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 8 09:30:49.680552 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 8 09:30:49.680557 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680563 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680568 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680573 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680578 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680584 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680589 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680596 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680601 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:30:49.680606 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 8 09:30:49.680611 kernel: ACPI: NUMA: Failed to initialise from firmware Nov 8 09:30:49.680617 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 8 09:30:49.680622 kernel: NUMA: NODE_DATA [mem 0xdc959900-0xdc95efff] Nov 8 09:30:49.680627 kernel: Zone ranges: Nov 8 09:30:49.680632 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 8 09:30:49.680637 kernel: DMA32 empty Nov 8 09:30:49.680642 kernel: Normal empty Nov 8 09:30:49.680647 kernel: Movable zone start for each node Nov 8 09:30:49.680653 kernel: Early memory node ranges Nov 8 09:30:49.680658 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Nov 8 09:30:49.680663 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 8 09:30:49.680669 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 8 09:30:49.680674 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 8 09:30:49.680679 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 8 09:30:49.680684 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 8 09:30:49.680689 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 8 09:30:49.680694 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 8 09:30:49.680699 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 8 09:30:49.680704 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 8 09:30:49.680709 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 8 09:30:49.680715 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 8 09:30:49.680723 kernel: On node 0 totalpages: 643072 Nov 8 09:30:49.680728 kernel: DMA zone: 10048 pages used for memmap Nov 8 09:30:49.680734 kernel: DMA zone: 0 pages reserved Nov 8 09:30:49.680739 kernel: DMA zone: 643072 pages, LIFO batch:63 Nov 8 09:30:49.680745 kernel: psci: probing for conduit method from ACPI. Nov 8 09:30:49.680750 kernel: psci: PSCIv1.1 detected in firmware. Nov 8 09:30:49.680755 kernel: psci: Using standard PSCI v0.2 function IDs Nov 8 09:30:49.680761 kernel: psci: Trusted OS migration not required Nov 8 09:30:49.680768 kernel: psci: SMC Calling Convention v1.1 Nov 8 09:30:49.680773 kernel: ACPI: SRAT not present Nov 8 09:30:49.680779 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Nov 8 09:30:49.680784 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Nov 8 09:30:49.680790 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 8 09:30:49.680795 kernel: Detected PIPT I-cache on CPU0 Nov 8 09:30:49.680801 kernel: CPU features: detected: GIC system register CPU interface Nov 8 09:30:49.680806 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 8 09:30:49.680812 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 8 09:30:49.680817 kernel: CPU features: detected: Hardware dirty bit management Nov 8 09:30:49.680823 kernel: CPU features: detected: Spectre-v4 Nov 8 09:30:49.680829 kernel: CPU features: detected: ARM erratum 1418040 Nov 8 09:30:49.680834 kernel: CPU features: detected: Spectre-BHB Nov 8 09:30:49.680840 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 8 09:30:49.680845 kernel: Policy zone: DMA Nov 8 09:30:49.680852 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 Nov 8 09:30:49.680858 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 8 09:30:49.680863 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 8 09:30:49.680869 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 8 09:30:49.680874 kernel: Memory: 2452096K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120192K reserved, 0K cma-reserved) Nov 8 09:30:49.680880 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Nov 8 09:30:49.680886 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 8 09:30:49.680892 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 8 09:30:49.680898 kernel: rcu: RCU event tracing is enabled. Nov 8 09:30:49.680903 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 8 09:30:49.680909 kernel: Trampoline variant of Tasks RCU enabled. Nov 8 09:30:49.680915 kernel: Tracing variant of Tasks RCU enabled. Nov 8 09:30:49.680920 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 8 09:30:49.680926 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 8 09:30:49.680931 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 8 09:30:49.680936 kernel: GICv3: 256 SPIs implemented Nov 8 09:30:49.680942 kernel: GICv3: 0 Extended SPIs implemented Nov 8 09:30:49.680947 kernel: GICv3: Distributor has no Range Selector support Nov 8 09:30:49.680952 kernel: GICv3: 16 PPIs implemented Nov 8 09:30:49.680959 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 8 09:30:49.680964 kernel: ACPI: SRAT not present Nov 8 09:30:49.680970 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 8 09:30:49.680975 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Nov 8 09:30:49.680981 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Nov 8 09:30:49.680986 kernel: GICv3: using LPI property table @0x00000000400d0000 Nov 8 09:30:49.680992 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Nov 8 09:30:49.680997 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:30:49.681003 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 8 09:30:49.681009 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 8 09:30:49.681015 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 8 09:30:49.681020 kernel: arm-pv: using stolen time PV Nov 8 09:30:49.681027 kernel: Console: colour dummy device 80x25 Nov 8 09:30:49.681033 kernel: ACPI: Core revision 20200925 Nov 8 09:30:49.681039 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 8 09:30:49.681045 kernel: pid_max: default: 32768 minimum: 301 Nov 8 09:30:49.681050 kernel: LSM: Security Framework initializing Nov 8 09:30:49.681056 kernel: SELinux: Initializing. Nov 8 09:30:49.681062 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 09:30:49.681068 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 09:30:49.681073 kernel: rcu: Hierarchical SRCU implementation. Nov 8 09:30:49.681080 kernel: Platform MSI: ITS@0x8080000 domain created Nov 8 09:30:49.681086 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 8 09:30:49.681092 kernel: Remapping and enabling EFI services. Nov 8 09:30:49.681097 kernel: smp: Bringing up secondary CPUs ... Nov 8 09:30:49.681103 kernel: Detected PIPT I-cache on CPU1 Nov 8 09:30:49.681109 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 8 09:30:49.681115 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Nov 8 09:30:49.681121 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:30:49.681132 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 8 09:30:49.681138 kernel: Detected PIPT I-cache on CPU2 Nov 8 09:30:49.681146 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 8 09:30:49.681152 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Nov 8 09:30:49.681192 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:30:49.681200 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 8 09:30:49.681206 kernel: Detected PIPT I-cache on CPU3 Nov 8 09:30:49.681217 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 8 09:30:49.681223 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Nov 8 09:30:49.681230 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:30:49.681236 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 8 09:30:49.681242 kernel: smp: Brought up 1 node, 4 CPUs Nov 8 09:30:49.681248 kernel: SMP: Total of 4 processors activated. Nov 8 09:30:49.681254 kernel: CPU features: detected: Privileged Access Never Nov 8 09:30:49.681260 kernel: CPU features: detected: LSE atomic instructions Nov 8 09:30:49.681266 kernel: CPU features: detected: User Access Override Nov 8 09:30:49.681271 kernel: CPU features: detected: 32-bit EL0 Support Nov 8 09:30:49.681277 kernel: CPU features: detected: Common not Private translations Nov 8 09:30:49.681283 kernel: CPU features: detected: RAS Extension Support Nov 8 09:30:49.681290 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 8 09:30:49.681297 kernel: CPU features: detected: CRC32 instructions Nov 8 09:30:49.681303 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 8 09:30:49.681308 kernel: CPU: All CPU(s) started at EL1 Nov 8 09:30:49.681314 kernel: alternatives: patching kernel code Nov 8 09:30:49.681321 kernel: devtmpfs: initialized Nov 8 09:30:49.681327 kernel: KASLR enabled Nov 8 09:30:49.681333 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 8 09:30:49.681340 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 8 09:30:49.681346 kernel: pinctrl core: initialized pinctrl subsystem Nov 8 09:30:49.681351 kernel: SMBIOS 3.0.0 present. Nov 8 09:30:49.681358 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 8 09:30:49.681363 kernel: NET: Registered protocol family 16 Nov 8 09:30:49.681369 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 8 09:30:49.681376 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 8 09:30:49.681382 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 8 09:30:49.681389 kernel: audit: initializing netlink subsys (disabled) Nov 8 09:30:49.681395 kernel: audit: type=2000 audit(0.029:1): state=initialized audit_enabled=0 res=1 Nov 8 09:30:49.681401 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 8 09:30:49.681407 kernel: cpuidle: using governor menu Nov 8 09:30:49.681412 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 8 09:30:49.681419 kernel: ASID allocator initialised with 32768 entries Nov 8 09:30:49.681424 kernel: ACPI: bus type PCI registered Nov 8 09:30:49.681430 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 8 09:30:49.681437 kernel: Serial: AMBA PL011 UART driver Nov 8 09:30:49.681443 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Nov 8 09:30:49.681449 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Nov 8 09:30:49.681455 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Nov 8 09:30:49.681461 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Nov 8 09:30:49.681467 kernel: cryptd: max_cpu_qlen set to 1000 Nov 8 09:30:49.681473 kernel: ACPI: Added _OSI(Module Device) Nov 8 09:30:49.681479 kernel: ACPI: Added _OSI(Processor Device) Nov 8 09:30:49.681485 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 8 09:30:49.681491 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 8 09:30:49.681498 kernel: ACPI: Added _OSI(Linux-Dell-Video) Nov 8 09:30:49.681504 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Nov 8 09:30:49.681510 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Nov 8 09:30:49.681516 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 8 09:30:49.681522 kernel: ACPI: Interpreter enabled Nov 8 09:30:49.681528 kernel: ACPI: Using GIC for interrupt routing Nov 8 09:30:49.681534 kernel: ACPI: MCFG table detected, 1 entries Nov 8 09:30:49.681540 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 8 09:30:49.681546 kernel: printk: console [ttyAMA0] enabled Nov 8 09:30:49.681553 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 8 09:30:49.681662 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 8 09:30:49.681721 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 8 09:30:49.681774 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 8 09:30:49.681826 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 8 09:30:49.681881 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 8 09:30:49.681889 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 8 09:30:49.681897 kernel: PCI host bridge to bus 0000:00 Nov 8 09:30:49.681954 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 8 09:30:49.682001 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 8 09:30:49.682048 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 8 09:30:49.682095 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 8 09:30:49.682185 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 8 09:30:49.682249 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 8 09:30:49.682307 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 8 09:30:49.682362 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 8 09:30:49.682416 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 8 09:30:49.682470 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 8 09:30:49.682525 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 8 09:30:49.682579 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 8 09:30:49.682627 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 8 09:30:49.682678 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 8 09:30:49.682737 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 8 09:30:49.682746 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Nov 8 09:30:49.682752 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Nov 8 09:30:49.682758 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Nov 8 09:30:49.682764 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Nov 8 09:30:49.682770 kernel: vgaarb: loaded Nov 8 09:30:49.682776 kernel: Registered efivars operations Nov 8 09:30:49.682782 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 8 09:30:49.682789 kernel: VFS: Disk quotas dquot_6.6.0 Nov 8 09:30:49.682795 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 8 09:30:49.682801 kernel: pnp: PnP ACPI init Nov 8 09:30:49.682865 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 8 09:30:49.682913 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Nov 8 09:30:49.682921 kernel: pnp: PnP ACPI: found 1 devices Nov 8 09:30:49.682928 kernel: NET: Registered protocol family 2 Nov 8 09:30:49.682934 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 8 09:30:49.682941 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 8 09:30:49.682948 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 8 09:30:49.682954 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Nov 8 09:30:49.682960 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 8 09:30:49.682966 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 09:30:49.682972 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 09:30:49.682978 kernel: NET: Registered protocol family 1 Nov 8 09:30:49.682984 kernel: PCI: CLS 0 bytes, default 64 Nov 8 09:30:49.682990 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 8 09:30:49.682997 kernel: kvm [1]: HYP mode not available Nov 8 09:30:49.683003 kernel: Initialise system trusted keyrings Nov 8 09:30:49.683009 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 8 09:30:49.683015 kernel: Key type asymmetric registered Nov 8 09:30:49.683021 kernel: Asymmetric key parser 'x509' registered Nov 8 09:30:49.683027 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 8 09:30:49.683033 kernel: io scheduler mq-deadline registered Nov 8 09:30:49.683039 kernel: io scheduler kyber registered Nov 8 09:30:49.683045 kernel: io scheduler bfq registered Nov 8 09:30:49.683051 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 8 09:30:49.683058 kernel: ACPI: Power Button [PWRB] Nov 8 09:30:49.683064 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Nov 8 09:30:49.683118 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 8 09:30:49.683131 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 8 09:30:49.683138 kernel: msm_serial: driver initialized Nov 8 09:30:49.683144 kernel: random: fast init done Nov 8 09:30:49.683150 kernel: random: crng init done Nov 8 09:30:49.683161 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Nov 8 09:30:49.683169 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Nov 8 09:30:49.683177 kernel: thunder_xcv, ver 1.0 Nov 8 09:30:49.683183 kernel: thunder_bgx, ver 1.0 Nov 8 09:30:49.683189 kernel: nicpf, ver 1.0 Nov 8 09:30:49.683195 kernel: nicvf, ver 1.0 Nov 8 09:30:49.683257 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 8 09:30:49.683308 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-08T09:30:49 UTC (1762594249) Nov 8 09:30:49.683316 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 8 09:30:49.683322 kernel: NET: Registered protocol family 10 Nov 8 09:30:49.683330 kernel: Segment Routing with IPv6 Nov 8 09:30:49.683336 kernel: NET: Registered protocol family 17 Nov 8 09:30:49.683342 kernel: Key type dns_resolver registered Nov 8 09:30:49.683348 kernel: registered taskstats version 1 Nov 8 09:30:49.683354 kernel: Loading compiled-in X.509 certificates Nov 8 09:30:49.683360 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Nov 8 09:30:49.683366 kernel: Key type ._fscrypt registered Nov 8 09:30:49.683372 kernel: Key type .fscrypt registered Nov 8 09:30:49.683378 kernel: Key type fscrypt-provisioning registered Nov 8 09:30:49.683385 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 8 09:30:49.683391 kernel: ima: Allocated hash algorithm: sha1 Nov 8 09:30:49.683397 kernel: ima: No architecture policies found Nov 8 09:30:49.683403 kernel: Freeing unused kernel memory: 34240K Nov 8 09:30:49.683409 kernel: Run /init as init process Nov 8 09:30:49.683415 kernel: with arguments: Nov 8 09:30:49.683421 kernel: /init Nov 8 09:30:49.683427 kernel: with environment: Nov 8 09:30:49.683432 kernel: HOME=/ Nov 8 09:30:49.683438 kernel: TERM=linux Nov 8 09:30:49.683445 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 8 09:30:49.683453 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) Nov 8 09:30:49.683461 systemd[1]: Detected virtualization kvm. Nov 8 09:30:49.683468 systemd[1]: Detected architecture arm64. Nov 8 09:30:49.683474 systemd[1]: Running in initial RAM disk. Nov 8 09:30:49.683480 systemd[1]: No hostname configured, using default hostname. Nov 8 09:30:49.683486 systemd[1]: Hostname set to . Nov 8 09:30:49.683494 systemd[1]: Initializing machine ID from VM UUID. Nov 8 09:30:49.683500 systemd[1]: Queued start job for default target Initrd Default Target. Nov 8 09:30:49.683507 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 8 09:30:49.683513 systemd[1]: Reached target Local Encrypted Volumes. Nov 8 09:30:49.683519 systemd[1]: Reached target Path Units. Nov 8 09:30:49.683525 systemd[1]: Reached target Slice Units. Nov 8 09:30:49.683532 systemd[1]: Reached target Swaps. Nov 8 09:30:49.683538 systemd[1]: Reached target Timer Units. Nov 8 09:30:49.683546 systemd[1]: Listening on Open-iSCSI iscsid Socket. Nov 8 09:30:49.683552 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Nov 8 09:30:49.683559 systemd[1]: Listening on Journal Audit Socket. Nov 8 09:30:49.683565 systemd[1]: Listening on Journal Socket (/dev/log). Nov 8 09:30:49.683572 systemd[1]: Listening on Journal Socket. Nov 8 09:30:49.683578 systemd[1]: Listening on Network Service Netlink Socket. Nov 8 09:30:49.683585 systemd[1]: Listening on udev Control Socket. Nov 8 09:30:49.683591 systemd[1]: Listening on udev Kernel Socket. Nov 8 09:30:49.683598 systemd[1]: Reached target Socket Units. Nov 8 09:30:49.683605 systemd[1]: Starting Create List of Static Device Nodes... Nov 8 09:30:49.683612 systemd[1]: Finished Network Cleanup. Nov 8 09:30:49.683618 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Nov 8 09:30:49.683624 systemd[1]: Starting Journal Service... Nov 8 09:30:49.683631 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 8 09:30:49.683637 systemd[1]: Starting Apply Kernel Variables... Nov 8 09:30:49.683643 systemd[1]: Starting Setup Virtual Console... Nov 8 09:30:49.683650 systemd[1]: Finished Create List of Static Device Nodes. Nov 8 09:30:49.683656 systemd[1]: Finished Apply Kernel Variables. Nov 8 09:30:49.683662 systemd[1]: Finished Setup Virtual Console. Nov 8 09:30:49.683670 systemd[1]: Starting dracut ask for additional cmdline parameters... Nov 8 09:30:49.683678 systemd-journald[278]: Journal started Nov 8 09:30:49.683711 systemd-journald[278]: Runtime Journal (/run/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194) is 6.0M, max 48.5M, 42.4M free. Nov 8 09:30:49.685822 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 8 09:30:49.687210 systemd[1]: Started Journal Service. Nov 8 09:30:49.687596 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 8 09:30:49.696258 systemd[1]: Finished dracut ask for additional cmdline parameters. Nov 8 09:30:49.697812 systemd[1]: Starting dracut cmdline hook... Nov 8 09:30:49.706238 dracut-cmdline[295]: dracut-dracut-053 Nov 8 09:30:49.708434 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 Nov 8 09:30:49.764185 kernel: SCSI subsystem initialized Nov 8 09:30:49.767184 kernel: Loading iSCSI transport class v2.0-870. Nov 8 09:30:49.773181 kernel: iscsi: registered transport (tcp) Nov 8 09:30:49.784533 kernel: iscsi: registered transport (qla4xxx) Nov 8 09:30:49.784547 kernel: QLogic iSCSI HBA Driver Nov 8 09:30:49.794021 systemd[1]: Finished dracut cmdline hook. Nov 8 09:30:49.795519 systemd[1]: Starting dracut pre-udev hook... Nov 8 09:30:49.808604 kernel: device-mapper: uevent: version 1.0.3 Nov 8 09:30:49.808632 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Nov 8 09:30:49.843188 kernel: raid6: neonx8 gen() 13792 MB/s Nov 8 09:30:49.860184 kernel: raid6: neonx8 xor() 10754 MB/s Nov 8 09:30:49.877182 kernel: raid6: neonx4 gen() 13634 MB/s Nov 8 09:30:49.894182 kernel: raid6: neonx4 xor() 11545 MB/s Nov 8 09:30:49.911179 kernel: raid6: neonx2 gen() 13028 MB/s Nov 8 09:30:49.928180 kernel: raid6: neonx2 xor() 9880 MB/s Nov 8 09:30:49.945182 kernel: raid6: neonx1 gen() 10981 MB/s Nov 8 09:30:49.962179 kernel: raid6: neonx1 xor() 8831 MB/s Nov 8 09:30:49.979187 kernel: raid6: int64x8 gen() 6829 MB/s Nov 8 09:30:49.996178 kernel: raid6: int64x8 xor() 3792 MB/s Nov 8 09:30:50.013181 kernel: raid6: int64x4 gen() 6909 MB/s Nov 8 09:30:50.030182 kernel: raid6: int64x4 xor() 3835 MB/s Nov 8 09:30:50.047179 kernel: raid6: int64x2 gen() 6181 MB/s Nov 8 09:30:50.064178 kernel: raid6: int64x2 xor() 3320 MB/s Nov 8 09:30:50.081180 kernel: raid6: int64x1 gen() 5005 MB/s Nov 8 09:30:50.098387 kernel: raid6: int64x1 xor() 2597 MB/s Nov 8 09:30:50.098400 kernel: raid6: using algorithm neonx8 gen() 13792 MB/s Nov 8 09:30:50.098407 kernel: raid6: .... xor() 10754 MB/s, rmw enabled Nov 8 09:30:50.099529 kernel: raid6: using neon recovery algorithm Nov 8 09:30:50.109183 kernel: xor: measuring software checksum speed Nov 8 09:30:50.110177 kernel: 8regs : 17166 MB/sec Nov 8 09:30:50.111185 kernel: 32regs : 20770 MB/sec Nov 8 09:30:50.112606 kernel: arm64_neon : 29163 MB/sec Nov 8 09:30:50.112619 kernel: xor: using function: arm64_neon (29163 MB/sec) Nov 8 09:30:50.157178 kernel: Btrfs loaded, crc32c=crc32c-generic Nov 8 09:30:50.166938 systemd[1]: Finished dracut pre-udev hook. Nov 8 09:30:50.168000 audit: BPF prog-id=6 op=LOAD Nov 8 09:30:50.168887 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 8 09:30:50.173146 kernel: audit: type=1334 audit(1762594250.168:2): prog-id=6 op=LOAD Nov 8 09:30:50.173183 kernel: audit: type=1334 audit(1762594250.168:3): prog-id=7 op=LOAD Nov 8 09:30:50.173194 kernel: audit: type=1334 audit(1762594250.168:4): prog-id=8 op=LOAD Nov 8 09:30:50.168000 audit: BPF prog-id=7 op=LOAD Nov 8 09:30:50.168000 audit: BPF prog-id=8 op=LOAD Nov 8 09:30:50.182701 systemd-udevd[414]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 8 09:30:50.184401 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 8 09:30:50.187242 systemd[1]: Starting dracut pre-trigger hook... Nov 8 09:30:50.189000 audit: BPF prog-id=9 op=LOAD Nov 8 09:30:50.190700 systemd[1]: Starting Network Configuration... Nov 8 09:30:50.192557 kernel: audit: type=1334 audit(1762594250.189:5): prog-id=9 op=LOAD Nov 8 09:30:50.197629 dracut-pre-trigger[424]: rd.md=0: removing MD RAID activation Nov 8 09:30:50.207478 systemd-networkd[427]: lo: Link UP Nov 8 09:30:50.207485 systemd-networkd[427]: lo: Gained carrier Nov 8 09:30:50.209000 audit: BPF prog-id=10 op=LOAD Nov 8 09:30:50.207758 systemd-networkd[427]: Enumeration completed Nov 8 09:30:50.211289 kernel: audit: type=1334 audit(1762594250.209:6): prog-id=10 op=LOAD Nov 8 09:30:50.208248 systemd[1]: Started Network Configuration. Nov 8 09:30:50.210584 systemd[1]: Starting Network Name Resolution... Nov 8 09:30:50.222035 systemd[1]: Finished dracut pre-trigger hook. Nov 8 09:30:50.223562 systemd[1]: Starting Coldplug All udev Devices... Nov 8 09:30:50.232878 systemd-udevd[414]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 8 09:30:50.242320 systemd-resolved[449]: Positive Trust Anchors: Nov 8 09:30:50.242334 systemd-resolved[449]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 8 09:30:50.242364 systemd-resolved[449]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Nov 8 09:30:50.242648 systemd-resolved[449]: Defaulting to hostname 'linux'. Nov 8 09:30:50.250135 systemd[1]: Started Network Name Resolution. Nov 8 09:30:50.251049 systemd[1]: Reached target Network. Nov 8 09:30:50.251802 systemd[1]: Reached target Host and Network Name Lookups. Nov 8 09:30:50.253369 systemd[1]: Starting iSCSI UserSpace I/O driver... Nov 8 09:30:50.263781 iscsid[481]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Nov 8 09:30:50.263781 iscsid[481]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log Nov 8 09:30:50.263781 iscsid[481]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Nov 8 09:30:50.263781 iscsid[481]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Nov 8 09:30:50.263781 iscsid[481]: If using hardware iscsi like qla4xxx this message can be ignored. Nov 8 09:30:50.263781 iscsid[481]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Nov 8 09:30:50.263781 iscsid[481]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Nov 8 09:30:50.296177 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 8 09:30:50.296295 kernel: vda: detected capacity change from 0 to 4756340736 Nov 8 09:30:50.296305 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 8 09:30:50.254403 systemd[1]: Finished Coldplug All udev Devices. Nov 8 09:30:50.259113 systemd[1]: Started iSCSI UserSpace I/O driver. Nov 8 09:30:50.260686 systemd[1]: Starting Open-iSCSI... Nov 8 09:30:50.269427 systemd[1]: Started Open-iSCSI. Nov 8 09:30:50.270865 systemd[1]: Starting dracut initqueue hook... Nov 8 09:30:50.295539 systemd-udevd[465]: Using default interface naming scheme 'v249'. Nov 8 09:30:50.300418 systemd-networkd[427]: eth0: Link UP Nov 8 09:30:50.312653 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Nov 8 09:30:50.314434 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (467) Nov 8 09:30:50.318498 systemd[1]: Found device /dev/disk/by-label/ROOT. Nov 8 09:30:50.321171 systemd[1]: Found device /dev/disk/by-label/OEM. Nov 8 09:30:50.323741 systemd[1]: Finished dracut initqueue hook. Nov 8 09:30:50.326072 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 8 09:30:50.327230 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Nov 8 09:30:50.329110 systemd[1]: Reached target Initrd Root Device. Nov 8 09:30:50.330627 systemd[1]: Reached target Preparation for Remote File Systems. Nov 8 09:30:50.332190 systemd[1]: Reached target Remote Encrypted Volumes. Nov 8 09:30:50.333961 systemd[1]: Reached target Remote File Systems. Nov 8 09:30:50.336113 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Nov 8 09:30:50.337778 systemd[1]: Starting dracut pre-mount hook... Nov 8 09:30:50.346184 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 8 09:30:50.347069 systemd[1]: Finished dracut pre-mount hook. Nov 8 09:30:51.320191 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Nov 8 09:30:51.320149 systemd-networkd[427]: eth0: Gained carrier Nov 8 09:30:51.331227 systemd-networkd[427]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Nov 8 09:30:51.353176 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 8 09:30:51.353342 disk-uuid[504]: The operation has completed successfully. Nov 8 09:30:51.378250 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 8 09:30:51.378374 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Nov 8 09:30:51.380190 systemd[1]: Starting Ignition (setup)... Nov 8 09:30:51.388465 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 8 09:30:51.388499 kernel: BTRFS info (device vda6): has skinny extents Nov 8 09:30:51.394239 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 8 09:30:51.399017 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 8 09:30:51.399141 systemd[1]: Finished Ignition (setup). Nov 8 09:30:51.401503 systemd[1]: Starting Ignition (disks)... Nov 8 09:30:51.411171 ignition[539]: Ignition v0.36.1-15-gde4e6cc9 Nov 8 09:30:51.411186 ignition[539]: Stage: disks Nov 8 09:30:51.411196 ignition[539]: reading system config file "/usr/lib/ignition/base.ign" Nov 8 09:30:51.411207 ignition[539]: no config at "/usr/lib/ignition/base.ign" Nov 8 09:30:51.411251 ignition[539]: parsed url from cmdline: "" Nov 8 09:30:51.411254 ignition[539]: no config URL provided Nov 8 09:30:51.411258 ignition[539]: reading system config file "/usr/lib/ignition/user.ign" Nov 8 09:30:51.411265 ignition[539]: no config at "/usr/lib/ignition/user.ign" Nov 8 09:30:51.411284 ignition[539]: op(1): [started] loading QEMU firmware config module Nov 8 09:30:51.411289 ignition[539]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 8 09:30:51.412382 ignition[539]: op(1): [finished] loading QEMU firmware config module Nov 8 09:30:51.426419 ignition[539]: parsing config with SHA512: f9ee4112a89115516715bc08ae2c17870fb3263e9993314c83079904b4f2d41ce206468b8f00af7ff6e864a261f8c5647b941a6eaf6939480f5fcf9f7e0dcaec Nov 8 09:30:51.428544 ignition[539]: disks: disks passed Nov 8 09:30:51.428553 ignition[539]: Ignition finished successfully Nov 8 09:30:51.429378 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 8 09:30:51.429485 systemd[1]: Finished Ignition (disks). Nov 8 09:30:51.430953 systemd[1]: Reached target Preparation for Local File Systems. Nov 8 09:30:51.433393 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Nov 8 09:30:51.434892 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Nov 8 09:30:51.443978 systemd-fsck[553]: ROOT: clean, 528/553792 files, 37752/553472 blocks Nov 8 09:30:51.447167 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 8 09:30:51.448002 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Nov 8 09:30:51.450006 systemd[1]: Mounting /sysroot... Nov 8 09:30:51.466203 systemd[1]: Found device /dev/mapper/usr. Nov 8 09:30:51.467592 systemd[1]: Starting File System Check on /dev/mapper/usr... Nov 8 09:30:51.468922 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Nov 8 09:30:51.478504 systemd-fsck[569]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Nov 8 09:30:51.478504 systemd-fsck[569]: You must have r/w access to the filesystem or be root Nov 8 09:30:51.479472 systemd-fsck[566]: fsck failed with exit status 8. Nov 8 09:30:51.479475 systemd-fsck[566]: Ignoring error. Nov 8 09:30:51.482854 systemd[1]: Finished File System Check on /dev/mapper/usr. Nov 8 09:30:51.484486 systemd[1]: Mounting /sysusr/usr... Nov 8 09:30:51.497524 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Nov 8 09:30:51.497559 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Nov 8 09:30:51.498049 systemd[1]: Mounted /sysroot. Nov 8 09:30:51.498809 systemd[1]: Reached target Initrd Root File System. Nov 8 09:30:51.515905 systemd[1]: Mounted /sysusr/usr. Nov 8 09:30:51.517152 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Nov 8 09:30:51.516680 systemd[1]: Reached target Local File Systems. Nov 8 09:30:51.517939 systemd[1]: Reached target System Initialization. Nov 8 09:30:51.519567 systemd[1]: Reached target Basic System. Nov 8 09:30:51.522345 systemd[1]: Mounting /sysroot/usr... Nov 8 09:30:51.525906 systemd[1]: Mounted /sysroot/usr. Nov 8 09:30:51.527258 systemd[1]: Starting Root filesystem setup... Nov 8 09:30:51.552535 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 8 09:30:51.552673 systemd[1]: Finished Root filesystem setup. Nov 8 09:30:51.555055 systemd[1]: Starting Ignition (files)... Nov 8 09:30:51.556387 systemd[1]: Starting /sysroot/boot... Nov 8 09:30:51.557875 ignition[585]: Ignition v0.36.1-15-gde4e6cc9 Nov 8 09:30:51.557881 ignition[585]: Stage: files Nov 8 09:30:51.557891 ignition[585]: reading system config file "/usr/lib/ignition/base.ign" Nov 8 09:30:51.557899 ignition[585]: no config at "/usr/lib/ignition/base.ign" Nov 8 09:30:51.558411 ignition[585]: files: compiled without relabeling support, skipping Nov 8 09:30:51.573957 systemd[1]: Finished /sysroot/boot. Nov 8 09:30:51.577029 ignition[585]: files: createUsers: op(1): [started] creating or modifying user "core" Nov 8 09:30:51.577043 ignition[585]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Nov 8 09:30:51.578863 ignition[585]: files: createUsers: op(1): [finished] creating or modifying user "core" Nov 8 09:30:51.578875 ignition[585]: files: createUsers: op(2): [started] adding ssh keys to user "core" Nov 8 09:30:51.582033 ignition[585]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Nov 8 09:30:51.582066 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Nov 8 09:30:51.583420 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Nov 8 09:30:51.583427 ignition[585]: files: op(4): [started] processing unit "coreos-metadata.service" Nov 8 09:30:51.585781 systemd[1]: ignition-files.service: Deactivated successfully. Nov 8 09:30:51.583516 ignition[585]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Nov 8 09:30:51.585892 systemd[1]: Finished Ignition (files). Nov 8 09:30:51.585191 ignition[585]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Nov 8 09:30:51.587663 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Nov 8 09:30:51.585197 ignition[585]: files: op(4): [finished] processing unit "coreos-metadata.service" Nov 8 09:30:51.588315 systemd[1]: Starting Ignition (record completion)... Nov 8 09:30:51.585202 ignition[585]: files: files passed Nov 8 09:30:51.589955 systemd[1]: Starting Reload Configuration from the Real Root... Nov 8 09:30:51.585205 ignition[585]: Ignition finished successfully Nov 8 09:30:51.606261 kernel: audit: type=1334 audit(1762594251.601:7): prog-id=3 op=UNLOAD Nov 8 09:30:51.606283 kernel: audit: type=1334 audit(1762594251.601:8): prog-id=10 op=UNLOAD Nov 8 09:30:51.606292 kernel: audit: type=1334 audit(1762594251.602:9): prog-id=6 op=UNLOAD Nov 8 09:30:51.606306 kernel: audit: type=1334 audit(1762594251.602:10): prog-id=9 op=UNLOAD Nov 8 09:30:51.601000 audit: BPF prog-id=3 op=UNLOAD Nov 8 09:30:51.601000 audit: BPF prog-id=10 op=UNLOAD Nov 8 09:30:51.602000 audit: BPF prog-id=6 op=UNLOAD Nov 8 09:30:51.602000 audit: BPF prog-id=9 op=UNLOAD Nov 8 09:30:51.591558 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 8 09:30:51.591671 systemd[1]: Finished Ignition (record completion). Nov 8 09:30:51.595151 systemd[1]: Reloading. Nov 8 09:30:51.687000 audit: BPF prog-id=11 op=LOAD Nov 8 09:30:51.687000 audit: BPF prog-id=12 op=LOAD Nov 8 09:30:51.687000 audit: BPF prog-id=13 op=LOAD Nov 8 09:30:51.687000 audit: BPF prog-id=4 op=UNLOAD Nov 8 09:30:51.687000 audit: BPF prog-id=5 op=UNLOAD Nov 8 09:30:51.687000 audit: BPF prog-id=14 op=LOAD Nov 8 09:30:51.687000 audit: BPF prog-id=15 op=LOAD Nov 8 09:30:51.687000 audit: BPF prog-id=16 op=LOAD Nov 8 09:30:51.688000 audit: BPF prog-id=17 op=LOAD Nov 8 09:30:51.688000 audit: BPF prog-id=7 op=UNLOAD Nov 8 09:30:51.688000 audit: BPF prog-id=8 op=UNLOAD Nov 8 09:30:51.688000 audit: BPF prog-id=18 op=LOAD Nov 8 09:30:51.702844 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 8 09:30:51.702965 systemd[1]: Finished Reload Configuration from the Real Root. Nov 8 09:30:51.704988 systemd[1]: Reached target Initrd File Systems. Nov 8 09:30:51.706683 systemd[1]: Reached target Initrd Default Target. Nov 8 09:30:51.708264 systemd[1]: Condition check resulted in dracut mount hook being skipped. Nov 8 09:30:51.708899 systemd[1]: Starting dracut pre-pivot and cleanup hook... Nov 8 09:30:51.719043 systemd[1]: Finished dracut pre-pivot and cleanup hook. Nov 8 09:30:51.720808 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Nov 8 09:30:51.726751 systemd[1]: Stopped target Host and Network Name Lookups. Nov 8 09:30:51.727897 systemd[1]: Stopped target Remote Encrypted Volumes. Nov 8 09:30:51.729860 systemd[1]: Stopped target Timer Units. Nov 8 09:30:51.731496 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 8 09:30:51.731611 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Nov 8 09:30:51.733166 systemd[1]: Stopped target Initrd Default Target. Nov 8 09:30:51.734854 systemd[1]: Stopped target Basic System. Nov 8 09:30:51.736374 systemd[1]: Stopped target Initrd Root Device. Nov 8 09:30:51.737879 systemd[1]: Stopped target Path Units. Nov 8 09:30:51.739186 systemd[1]: Stopped target Remote File Systems. Nov 8 09:30:51.740757 systemd[1]: Stopped target Preparation for Remote File Systems. Nov 8 09:30:51.742370 systemd[1]: Stopped target Slice Units. Nov 8 09:30:51.743889 systemd[1]: Stopped target Socket Units. Nov 8 09:30:51.745515 systemd[1]: Stopped target System Initialization. Nov 8 09:30:51.746961 systemd[1]: Stopped target Local File Systems. Nov 8 09:30:51.748355 systemd[1]: Stopped target Preparation for Local File Systems. Nov 8 09:30:51.750056 systemd[1]: Stopped target Swaps. Nov 8 09:30:51.751472 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 8 09:30:51.751580 systemd[1]: Stopped dracut pre-mount hook. Nov 8 09:30:51.753088 systemd[1]: Stopped target Local Encrypted Volumes. Nov 8 09:30:51.754435 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 8 09:30:51.758168 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Nov 8 09:30:51.759300 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 8 09:30:51.759405 systemd[1]: Stopped dracut initqueue hook. Nov 8 09:30:51.764549 iscsid[481]: iscsid shutting down. Nov 8 09:30:51.761189 systemd[1]: Stopping Open-iSCSI... Nov 8 09:30:51.764558 systemd[1]: Stopping /sysroot/boot... Nov 8 09:30:51.765263 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 8 09:30:51.765395 systemd[1]: Stopped Coldplug All udev Devices. Nov 8 09:30:51.766717 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 8 09:30:51.766795 systemd[1]: Stopped dracut pre-trigger hook. Nov 8 09:30:51.768966 systemd[1]: iscsid.service: Deactivated successfully. Nov 8 09:30:51.769083 systemd[1]: Stopped Open-iSCSI. Nov 8 09:30:51.771259 systemd[1]: iscsid.socket: Deactivated successfully. Nov 8 09:30:51.771320 systemd[1]: Closed Open-iSCSI iscsid Socket. Nov 8 09:30:51.772585 systemd[1]: Stopping iSCSI UserSpace I/O driver... Nov 8 09:30:51.775731 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 8 09:30:51.775835 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Nov 8 09:30:51.777385 systemd[1]: iscsiuio.service: Deactivated successfully. Nov 8 09:30:51.777494 systemd[1]: Stopped iSCSI UserSpace I/O driver. Nov 8 09:30:51.779098 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 8 09:30:51.779222 systemd[1]: Stopped /sysroot/boot. Nov 8 09:30:51.780841 systemd[1]: Stopped target Network. Nov 8 09:30:51.781702 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 8 09:30:51.781764 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Nov 8 09:30:51.794000 audit: BPF prog-id=14 op=UNLOAD Nov 8 09:30:51.783276 systemd[1]: Stopping Network Name Resolution... Nov 8 09:30:51.787757 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 8 09:30:51.787882 systemd[1]: Stopped Network Name Resolution. Nov 8 09:30:51.789580 systemd[1]: Stopping Network Configuration... Nov 8 09:30:51.794273 systemd-networkd[427]: eth0: DHCP lease lost Nov 8 09:30:51.803210 systemd-networkd[427]: eth0: DHCPv6 lease lost Nov 8 09:30:51.804339 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 8 09:30:51.804462 systemd[1]: Stopped Network Configuration. Nov 8 09:30:51.806296 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 8 09:30:51.806326 systemd[1]: Closed Network Service Netlink Socket. Nov 8 09:30:51.808307 systemd[1]: Stopping Network Cleanup... Nov 8 09:30:51.813000 audit: BPF prog-id=18 op=UNLOAD Nov 8 09:30:51.809127 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 8 09:30:51.809213 systemd[1]: Stopped Apply Kernel Variables. Nov 8 09:30:51.810816 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Nov 8 09:30:51.816466 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 8 09:30:51.816584 systemd[1]: Stopped Network Cleanup. Nov 8 09:30:51.819816 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 8 09:30:51.819941 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Nov 8 09:30:51.822033 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 8 09:30:51.823000 audit: BPF prog-id=15 op=UNLOAD Nov 8 09:30:51.822072 systemd[1]: Closed udev Control Socket. Nov 8 09:30:51.823727 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 8 09:30:51.823760 systemd[1]: Closed udev Kernel Socket. Nov 8 09:30:51.825584 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 8 09:30:51.825627 systemd[1]: Stopped dracut pre-udev hook. Nov 8 09:30:51.826932 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 8 09:30:51.826970 systemd[1]: Stopped dracut cmdline hook. Nov 8 09:30:51.828532 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 8 09:30:51.828571 systemd[1]: Stopped dracut ask for additional cmdline parameters. Nov 8 09:30:51.830747 systemd[1]: Starting Cleanup udev Database... Nov 8 09:30:51.831777 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Nov 8 09:30:51.833205 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 8 09:30:51.833267 systemd[1]: Stopped Create Static Device Nodes in /dev. Nov 8 09:30:51.835074 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 8 09:30:51.835113 systemd[1]: Stopped Create List of Static Device Nodes. Nov 8 09:30:51.836634 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 8 09:30:51.836673 systemd[1]: Stopped Setup Virtual Console. Nov 8 09:30:51.838537 systemd[1]: rngd.service: Deactivated successfully. Nov 8 09:30:51.838652 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Nov 8 09:30:51.851000 audit: BPF prog-id=11 op=UNLOAD Nov 8 09:30:51.840361 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 8 09:30:51.840464 systemd[1]: Finished Cleanup udev Database. Nov 8 09:30:51.841793 systemd[1]: Reached target Switch Root. Nov 8 09:30:51.843870 systemd[1]: Starting Switch Root... Nov 8 09:30:51.848892 systemd[1]: Switching root. Nov 8 09:30:51.865452 systemd-journald[278]: Journal stopped Nov 8 09:30:54.024180 systemd-journald[278]: Received SIGTERM from PID 1 (systemd). Nov 8 09:30:54.024233 kernel: SELinux: policy capability network_peer_controls=1 Nov 8 09:30:54.024246 kernel: SELinux: policy capability open_perms=1 Nov 8 09:30:54.024260 kernel: SELinux: policy capability extended_socket_class=1 Nov 8 09:30:54.024273 kernel: SELinux: policy capability always_check_network=0 Nov 8 09:30:54.024282 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 8 09:30:54.024292 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 8 09:30:54.024302 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 8 09:30:54.024314 systemd[1]: Successfully loaded SELinux policy in 30.379ms. Nov 8 09:30:54.024327 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.661ms. Nov 8 09:30:54.024338 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) Nov 8 09:30:54.024349 systemd[1]: Detected virtualization kvm. Nov 8 09:30:54.024359 systemd[1]: Detected architecture arm64. Nov 8 09:30:54.024369 systemd[1]: Detected first boot. Nov 8 09:30:54.024379 systemd[1]: Initializing machine ID from VM UUID. Nov 8 09:30:54.024388 systemd[1]: Populated /etc with preset unit settings. Nov 8 09:30:54.024400 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. Nov 8 09:30:54.024413 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 8 09:30:54.024424 kernel: kauditd_printk_skb: 24 callbacks suppressed Nov 8 09:30:54.024433 kernel: audit: type=1334 audit(1762594253.889:35): prog-id=21 op=LOAD Nov 8 09:30:54.024442 kernel: audit: type=1334 audit(1762594253.889:36): prog-id=22 op=LOAD Nov 8 09:30:54.024450 kernel: audit: type=1334 audit(1762594253.890:37): prog-id=23 op=LOAD Nov 8 09:30:54.024459 kernel: audit: type=1334 audit(1762594253.890:38): prog-id=12 op=UNLOAD Nov 8 09:30:54.024468 kernel: audit: type=1334 audit(1762594253.890:39): prog-id=13 op=UNLOAD Nov 8 09:30:54.024477 kernel: audit: type=1334 audit(1762594253.901:40): prog-id=24 op=LOAD Nov 8 09:30:54.024487 kernel: audit: type=1334 audit(1762594253.901:41): prog-id=21 op=UNLOAD Nov 8 09:30:54.024495 kernel: audit: type=1334 audit(1762594253.902:42): prog-id=25 op=LOAD Nov 8 09:30:54.024504 kernel: audit: type=1334 audit(1762594253.903:43): prog-id=26 op=LOAD Nov 8 09:30:54.024513 kernel: audit: type=1334 audit(1762594253.903:44): prog-id=22 op=UNLOAD Nov 8 09:30:54.024523 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 8 09:30:54.024532 systemd[1]: Stopped Switch Root. Nov 8 09:30:54.024542 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 8 09:30:54.024552 systemd[1]: Created slice Slice /system/addon-config. Nov 8 09:30:54.024562 systemd[1]: Created slice Slice /system/addon-run. Nov 8 09:30:54.024572 systemd[1]: Created slice Slice /system/getty. Nov 8 09:30:54.024581 systemd[1]: Created slice Slice /system/modprobe. Nov 8 09:30:54.024591 systemd[1]: Created slice Slice /system/serial-getty. Nov 8 09:30:54.024601 systemd[1]: Created slice Slice /system/system-cloudinit. Nov 8 09:30:54.024610 systemd[1]: Created slice Slice /system/systemd-fsck. Nov 8 09:30:54.024620 systemd[1]: Created slice User and Session Slice. Nov 8 09:30:54.024629 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 8 09:30:54.024639 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Nov 8 09:30:54.024650 systemd[1]: Set up automount Boot partition Automount Point. Nov 8 09:30:54.024659 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Nov 8 09:30:54.024669 systemd[1]: Stopped target Switch Root. Nov 8 09:30:54.024678 systemd[1]: Stopped target Initrd File Systems. Nov 8 09:30:54.024688 systemd[1]: Stopped target Initrd Root File System. Nov 8 09:30:54.024698 systemd[1]: Reached target Remote Encrypted Volumes. Nov 8 09:30:54.024707 systemd[1]: Reached target Remote File Systems. Nov 8 09:30:54.024717 systemd[1]: Reached target Slice Units. Nov 8 09:30:54.024726 systemd[1]: Reached target Swaps. Nov 8 09:30:54.024736 systemd[1]: Reached target Verify torcx succeeded. Nov 8 09:30:54.024747 systemd[1]: Reached target Local Verity Protected Volumes. Nov 8 09:30:54.024757 systemd[1]: Listening on Process Core Dump Socket. Nov 8 09:30:54.024767 systemd[1]: Listening on initctl Compatibility Named Pipe. Nov 8 09:30:54.024776 systemd[1]: Listening on Network Service Netlink Socket. Nov 8 09:30:54.024786 systemd[1]: Listening on udev Control Socket. Nov 8 09:30:54.024796 systemd[1]: Listening on udev Kernel Socket. Nov 8 09:30:54.024808 systemd[1]: Mounting Huge Pages File System... Nov 8 09:30:54.024818 systemd[1]: Mounting POSIX Message Queue File System... Nov 8 09:30:54.024828 systemd[1]: Mounting External Media Directory... Nov 8 09:30:54.024837 systemd[1]: Mounting Kernel Debug File System... Nov 8 09:30:54.024848 systemd[1]: Mounting Kernel Trace File System... Nov 8 09:30:54.024857 systemd[1]: Mounting Temporary Directory /tmp... Nov 8 09:30:54.024867 systemd[1]: Starting Create missing system files... Nov 8 09:30:54.024877 systemd[1]: Starting Create List of Static Device Nodes... Nov 8 09:30:54.024886 systemd[1]: Starting Load Kernel Module configfs... Nov 8 09:30:54.024896 systemd[1]: Starting Load Kernel Module drm... Nov 8 09:30:54.024905 systemd[1]: Starting Load Kernel Module fuse... Nov 8 09:30:54.024915 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Nov 8 09:30:54.024925 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 8 09:30:54.024935 systemd[1]: Stopped File System Check on Root Device. Nov 8 09:30:54.024946 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 8 09:30:54.024956 systemd[1]: Stopped systemd-fsck-usr.service. Nov 8 09:30:54.024965 kernel: fuse: init (API version 7.32) Nov 8 09:30:54.024974 systemd[1]: Stopped Journal Service. Nov 8 09:30:54.024983 systemd[1]: Starting Journal Service... Nov 8 09:30:54.024993 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 8 09:30:54.025003 systemd[1]: Starting Remount Root and Kernel File Systems... Nov 8 09:30:54.025012 systemd[1]: Starting Apply Kernel Variables... Nov 8 09:30:54.025022 systemd[1]: Starting Coldplug All udev Devices... Nov 8 09:30:54.025032 systemd[1]: verity-setup.service: Deactivated successfully. Nov 8 09:30:54.025042 systemd[1]: Stopped verity-setup.service. Nov 8 09:30:54.025052 systemd-journald[800]: Journal started Nov 8 09:30:54.025087 systemd-journald[800]: Runtime Journal (/run/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194) is 6.0M, max 48.5M, 42.4M free. Nov 8 09:30:51.929000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 8 09:30:51.962000 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 Nov 8 09:30:51.962000 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 Nov 8 09:30:51.962000 audit: BPF prog-id=19 op=LOAD Nov 8 09:30:51.962000 audit: BPF prog-id=19 op=UNLOAD Nov 8 09:30:51.962000 audit: BPF prog-id=20 op=LOAD Nov 8 09:30:51.962000 audit: BPF prog-id=20 op=UNLOAD Nov 8 09:30:51.993000 audit[750]: AVC avc: denied { associate } for pid=750 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 Nov 8 09:30:53.889000 audit: BPF prog-id=21 op=LOAD Nov 8 09:30:53.889000 audit: BPF prog-id=22 op=LOAD Nov 8 09:30:53.890000 audit: BPF prog-id=23 op=LOAD Nov 8 09:30:53.890000 audit: BPF prog-id=12 op=UNLOAD Nov 8 09:30:53.890000 audit: BPF prog-id=13 op=UNLOAD Nov 8 09:30:53.901000 audit: BPF prog-id=24 op=LOAD Nov 8 09:30:53.901000 audit: BPF prog-id=21 op=UNLOAD Nov 8 09:30:53.902000 audit: BPF prog-id=25 op=LOAD Nov 8 09:30:53.903000 audit: BPF prog-id=26 op=LOAD Nov 8 09:30:53.903000 audit: BPF prog-id=22 op=UNLOAD Nov 8 09:30:53.903000 audit: BPF prog-id=23 op=UNLOAD Nov 8 09:30:53.904000 audit: BPF prog-id=27 op=LOAD Nov 8 09:30:53.904000 audit: BPF prog-id=24 op=UNLOAD Nov 8 09:30:53.905000 audit: BPF prog-id=28 op=LOAD Nov 8 09:30:53.905000 audit: BPF prog-id=29 op=LOAD Nov 8 09:30:53.905000 audit: BPF prog-id=25 op=UNLOAD Nov 8 09:30:53.905000 audit: BPF prog-id=26 op=UNLOAD Nov 8 09:30:53.916000 audit: BPF prog-id=27 op=UNLOAD Nov 8 09:30:54.009000 audit: BPF prog-id=30 op=LOAD Nov 8 09:30:54.009000 audit: BPF prog-id=31 op=LOAD Nov 8 09:30:54.009000 audit: BPF prog-id=32 op=LOAD Nov 8 09:30:54.009000 audit: BPF prog-id=28 op=UNLOAD Nov 8 09:30:54.009000 audit: BPF prog-id=29 op=UNLOAD Nov 8 09:30:54.022000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Nov 8 09:30:53.888421 systemd[1]: Queued start job for default target Multi-User System. Nov 8 09:30:51.992035 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Nov 8 09:30:53.906198 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 8 09:30:51.992411 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 8 09:30:51.992431 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 8 09:30:51.992584 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Nov 8 09:30:51.992597 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="skipped missing lower profile" missing profile=oem Nov 8 09:30:51.992628 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Nov 8 09:30:51.992641 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Nov 8 09:30:51.992842 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Nov 8 09:30:51.992875 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 8 09:30:51.992889 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 8 09:30:51.993282 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Nov 8 09:30:51.993312 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Nov 8 09:30:51.993332 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Nov 8 09:30:54.026767 systemd[1]: Started Journal Service. Nov 8 09:30:51.993348 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Nov 8 09:30:51.993366 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Nov 8 09:30:51.993379 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:51Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Nov 8 09:30:53.696903 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:53Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 8 09:30:53.697202 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:53Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 8 09:30:53.697308 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:53Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 8 09:30:53.697431 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:53Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 8 09:30:53.697486 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:53Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Nov 8 09:30:53.697541 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-08T09:30:53Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Nov 8 09:30:54.027265 systemd[1]: Mounted Huge Pages File System. Nov 8 09:30:54.028210 systemd[1]: Mounted POSIX Message Queue File System. Nov 8 09:30:54.029209 systemd[1]: Mounted External Media Directory. Nov 8 09:30:54.030107 systemd[1]: Mounted Kernel Debug File System. Nov 8 09:30:54.031093 systemd[1]: Mounted Kernel Trace File System. Nov 8 09:30:54.032090 systemd[1]: Mounted Temporary Directory /tmp. Nov 8 09:30:54.033329 systemd[1]: Finished Create missing system files. Nov 8 09:30:54.034528 systemd[1]: Finished Create List of Static Device Nodes. Nov 8 09:30:54.035717 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 8 09:30:54.035886 systemd[1]: Finished Load Kernel Module configfs. Nov 8 09:30:54.037029 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 8 09:30:54.037246 systemd[1]: Finished Load Kernel Module drm. Nov 8 09:30:54.038259 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 8 09:30:54.038425 systemd[1]: Finished Load Kernel Module fuse. Nov 8 09:30:54.039552 systemd[1]: Finished Remount Root and Kernel File Systems. Nov 8 09:30:54.040845 systemd[1]: Finished Apply Kernel Variables. Nov 8 09:30:54.053793 systemd[1]: Mounting FUSE Control File System... Nov 8 09:30:54.055514 systemd[1]: Mounting Kernel Configuration File System... Nov 8 09:30:54.056430 systemd[1]: Condition check resulted in Remount Root File System being skipped. Nov 8 09:30:54.057753 systemd[1]: Starting Rebuild Hardware Database... Nov 8 09:30:54.059568 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 8 09:30:54.060719 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Nov 8 09:30:54.061760 systemd[1]: Starting Load/Save Random Seed... Nov 8 09:30:54.063217 systemd-journald[800]: Time spent on flushing to /var/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194 is 8.205ms for 784 entries. Nov 8 09:30:54.063217 systemd-journald[800]: System Journal (/var/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194) is 8.0M, max 203.0M, 195.0M free. Nov 8 09:30:54.063706 systemd[1]: Starting Create System Users... Nov 8 09:30:54.066677 systemd[1]: Mounted FUSE Control File System. Nov 8 09:30:54.067849 systemd[1]: Mounted Kernel Configuration File System. Nov 8 09:30:54.070529 systemd[1]: Finished Load/Save Random Seed. Nov 8 09:30:54.071533 systemd[1]: Reached target First Boot Complete. Nov 8 09:30:54.074808 systemd[1]: Finished Coldplug All udev Devices. Nov 8 09:30:54.083970 systemd[1]: Starting Wait for udev To Complete Device Initialization... Nov 8 09:30:54.086223 systemd[1]: Finished Flush Journal to Persistent Storage. Nov 8 09:30:54.088351 systemd-sysusers[812]: Creating group sgx with gid 999. Nov 8 09:30:54.089366 systemd-sysusers[812]: Creating group systemd-oom with gid 998. Nov 8 09:30:54.090015 systemd-sysusers[812]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Nov 8 09:30:54.091000 systemd-sysusers[812]: Creating group systemd-timesync with gid 997. Nov 8 09:30:54.091810 systemd-sysusers[812]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Nov 8 09:30:54.092660 systemd-sysusers[812]: Creating group systemd-coredump with gid 996. Nov 8 09:30:54.093323 systemd-sysusers[812]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Nov 8 09:30:54.102283 systemd[1]: Finished Create System Users. Nov 8 09:30:54.110915 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 8 09:30:54.124307 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 8 09:30:54.310247 systemd[1]: Finished Rebuild Hardware Database. Nov 8 09:30:54.324000 audit: BPF prog-id=33 op=LOAD Nov 8 09:30:54.324000 audit: BPF prog-id=34 op=LOAD Nov 8 09:30:54.324000 audit: BPF prog-id=35 op=LOAD Nov 8 09:30:54.324000 audit: BPF prog-id=16 op=UNLOAD Nov 8 09:30:54.324000 audit: BPF prog-id=17 op=UNLOAD Nov 8 09:30:54.325432 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 8 09:30:54.347917 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 8 09:30:54.356000 audit: BPF prog-id=36 op=LOAD Nov 8 09:30:54.357449 systemd[1]: Starting Network Configuration... Nov 8 09:30:54.388212 systemd-networkd[836]: lo: Link UP Nov 8 09:30:54.388222 systemd-networkd[836]: lo: Gained carrier Nov 8 09:30:54.388518 systemd-networkd[836]: Enumeration completed Nov 8 09:30:54.388638 systemd[1]: Started Network Configuration. Nov 8 09:30:54.393994 systemd-udevd[823]: Using default interface naming scheme 'v249'. Nov 8 09:30:54.417898 udevadm[815]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 8 09:30:54.426775 systemd-networkd[836]: eth0: Link UP Nov 8 09:30:54.433217 systemd-networkd[836]: eth0: Gained carrier Nov 8 09:30:54.435285 systemd[1]: Finished Wait for udev To Complete Device Initialization. Nov 8 09:30:54.444310 systemd-networkd[836]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Nov 8 09:30:54.446009 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 8 09:30:54.453769 lvm[845]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 09:30:54.484102 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 8 09:30:54.485277 systemd[1]: Reached target Local Encrypted Volumes. Nov 8 09:30:54.498060 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 8 09:30:54.501246 lvm[846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 09:30:54.530141 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 8 09:30:54.531240 systemd[1]: Reached target Preparation for Local File Systems. Nov 8 09:30:54.532334 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Nov 8 09:30:54.532358 systemd[1]: Reached target Containers. Nov 8 09:30:54.544402 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Nov 8 09:30:54.555943 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Nov 8 09:30:54.558239 systemd[1]: Mounting /usr/share/oem... Nov 8 09:30:54.564269 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 8 09:30:54.564324 kernel: BTRFS info (device vda6): has skinny extents Nov 8 09:30:54.566557 systemd[1]: Mounted /usr/share/oem. Nov 8 09:30:54.567443 systemd[1]: Reached target Local File Systems. Nov 8 09:30:54.569325 systemd[1]: Starting Rebuild Dynamic Linker Cache... Nov 8 09:30:54.570547 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 8 09:30:54.570588 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Nov 8 09:30:54.571510 systemd[1]: Starting Commit a transient machine-id on disk... Nov 8 09:30:54.573582 systemd[1]: Starting Create Volatile Files and Directories... Nov 8 09:30:54.580533 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Nov 8 09:30:54.581571 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Nov 8 09:30:54.591397 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 8 09:30:54.592022 systemd[1]: Finished Commit a transient machine-id on disk. Nov 8 09:30:54.628544 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Nov 8 09:30:54.628553 systemd-tmpfiles[871]: Skipping /boot Nov 8 09:30:54.633481 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Nov 8 09:30:54.633590 systemd-tmpfiles[871]: Skipping /boot Nov 8 09:30:54.650500 systemd[1]: Finished Rebuild Dynamic Linker Cache. Nov 8 09:30:54.666583 systemd[1]: Finished Create Volatile Files and Directories. Nov 8 09:30:54.668906 systemd[1]: Starting Load Security Auditing Rules... Nov 8 09:30:54.670721 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Nov 8 09:30:54.674000 audit: BPF prog-id=37 op=LOAD Nov 8 09:30:54.672981 systemd[1]: Starting Rebuild Journal Catalog... Nov 8 09:30:54.675495 systemd[1]: Starting Network Name Resolution... Nov 8 09:30:54.677000 audit: BPF prog-id=38 op=LOAD Nov 8 09:30:54.678325 systemd[1]: Starting Network Time Synchronization... Nov 8 09:30:54.680328 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Nov 8 09:30:54.682071 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Nov 8 09:30:54.683565 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Nov 8 09:30:54.693520 systemd[1]: Finished Rebuild Journal Catalog. Nov 8 09:30:54.695588 systemd[1]: Starting Update is Completed... Nov 8 09:30:54.696697 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Nov 8 09:30:54.703887 systemd[1]: Finished Update is Completed. Nov 8 09:30:54.709000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Nov 8 09:30:54.710051 augenrules[894]: No rules Nov 8 09:30:54.710985 systemd[1]: Finished Load Security Auditing Rules. Nov 8 09:30:54.717989 systemd-timesyncd[882]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Nov 8 09:30:54.720128 systemd-resolved[881]: Positive Trust Anchors: Nov 8 09:30:54.720135 systemd-resolved[881]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 8 09:30:54.720173 systemd-resolved[881]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Nov 8 09:30:54.720877 systemd-resolved[881]: Defaulting to hostname 'linux'. Nov 8 09:30:54.726323 systemd[1]: Started Network Time Synchronization. Nov 8 09:30:54.727429 systemd[1]: Started Network Name Resolution. Nov 8 09:30:54.728346 systemd[1]: Reached target Network. Nov 8 09:30:54.729074 systemd[1]: Reached target Host and Network Name Lookups. Nov 8 09:30:54.730200 systemd[1]: Reached target System Initialization. Nov 8 09:30:54.731205 systemd[1]: Started Watch for update engine configuration changes. Nov 8 09:30:54.732370 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 8 09:30:54.733557 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 8 09:30:54.734601 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Nov 8 09:30:54.734631 systemd[1]: Reached target Path Units. Nov 8 09:30:54.735406 systemd[1]: Reached target System Time Set. Nov 8 09:30:54.736396 systemd[1]: Started Daily Log Rotation. Nov 8 09:30:54.737358 systemd[1]: Started Weekly check for MD array's redundancy information.. Nov 8 09:30:54.738518 systemd[1]: Reached target Timer Units. Nov 8 09:30:54.739654 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 8 09:30:54.750825 systemd[1]: Starting Docker Socket for the API... Nov 8 09:30:54.754035 systemd[1]: Listening on OpenSSH Server Socket. Nov 8 09:30:54.755520 systemd[1]: Listening on Docker Socket for the API. Nov 8 09:30:54.756519 systemd[1]: Reached target Socket Units. Nov 8 09:30:54.757325 systemd[1]: Reached target Basic System. Nov 8 09:30:54.758170 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Nov 8 09:30:54.758200 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Nov 8 09:30:54.759281 systemd[1]: Started D-Bus System Message Bus. Nov 8 09:30:54.762750 systemd[1]: Starting Extend Filesystems... Nov 8 09:30:54.763676 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Nov 8 09:30:54.764752 systemd[1]: Starting Generate /run/flatcar/motd... Nov 8 09:30:54.766576 systemd[1]: Starting Install an ssh key from /proc/cmdline... Nov 8 09:30:54.769966 systemd[1]: Starting Generate sshd host keys... Nov 8 09:30:54.770925 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Nov 8 09:30:54.770962 systemd[1]: Reached target Load system-provided cloud configs. Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda1 Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda2 Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda3 Nov 8 09:30:54.773328 extend-filesystems[903]: Found usr Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda4 Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda6 Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda7 Nov 8 09:30:54.773328 extend-filesystems[903]: Found vda9 Nov 8 09:30:54.773328 extend-filesystems[903]: Checking size of /dev/vda9 Nov 8 09:30:54.773968 systemd[1]: Starting User Login Management... Nov 8 09:30:54.775339 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 8 09:30:54.775973 systemd[1]: Starting Update Engine... Nov 8 09:30:54.776810 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Nov 8 09:30:54.776835 systemd[1]: Reached target Load user-provided cloud configs. Nov 8 09:30:54.778582 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 8 09:30:54.778833 systemd[1]: Finished Install an ssh key from /proc/cmdline. Nov 8 09:30:54.788440 systemd[1]: motdgen.service: Deactivated successfully. Nov 8 09:30:54.788673 systemd[1]: Finished Generate /run/flatcar/motd. Nov 8 09:30:54.804456 update_engine[919]: I1108 09:30:54.804177 919 main.cc:89] Flatcar Update Engine starting Nov 8 09:30:54.807031 systemd[1]: Started Update Engine. Nov 8 09:30:54.807461 update_engine[919]: I1108 09:30:54.807423 919 update_check_scheduler.cc:74] Next update check in 5m54s Nov 8 09:30:54.808244 extend-filesystems[903]: Old size kept for /dev/vda9 Nov 8 09:30:54.816392 systemd[1]: Started Cluster reboot manager. Nov 8 09:30:54.817803 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 8 09:30:54.818057 systemd[1]: Finished Extend Filesystems. Nov 8 09:30:54.821899 systemd-logind[917]: Watching system buttons on /dev/input/event0 (Power Button) Nov 8 09:30:54.822099 systemd-logind[917]: New seat seat0. Nov 8 09:30:54.824901 systemd[1]: Started User Login Management. Nov 8 09:30:54.845717 locksmithd[927]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 8 09:30:55.468238 sshd_keygen[920]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Nov 8 09:30:55.486893 systemd[1]: Finished Generate sshd host keys. Nov 8 09:30:55.498307 systemd[1]: Starting Generate /run/issue... Nov 8 09:30:55.502932 systemd[1]: issuegen.service: Deactivated successfully. Nov 8 09:30:55.503176 systemd[1]: Finished Generate /run/issue. Nov 8 09:30:55.505349 systemd[1]: Starting Permit User Sessions... Nov 8 09:30:55.509987 systemd[1]: Finished Permit User Sessions. Nov 8 09:30:55.512183 systemd[1]: Started Getty on tty1. Nov 8 09:30:55.514071 systemd[1]: Started Serial Getty on ttyAMA0. Nov 8 09:30:55.515200 systemd[1]: Reached target Login Prompts. Nov 8 09:30:55.516228 systemd[1]: Reached target Multi-User System. Nov 8 09:30:55.518340 systemd[1]: Starting Record Runlevel Change in UTMP... Nov 8 09:30:55.523485 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Nov 8 09:30:55.523708 systemd[1]: Finished Record Runlevel Change in UTMP. Nov 8 09:30:55.524813 systemd[1]: Startup finished in 703ms (kernel) + 2.337s (initrd) + 3.627s (userspace) = 6.668s. Nov 8 09:30:55.735378 systemd-networkd[836]: eth0: Gained IPv6LL Nov 8 09:31:01.397237 systemd[1]: Created slice Slice /system/sshd. Nov 8 09:31:01.398322 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45296). Nov 8 09:31:01.440199 sshd[949]: Accepted publickey for core from 10.0.0.1 port 45296 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:01.442026 sshd[949]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:01.456616 systemd[1]: Created slice User Slice of UID 500. Nov 8 09:31:01.457596 systemd[1]: Starting User Runtime Directory /run/user/500... Nov 8 09:31:01.459192 systemd-logind[917]: New session 1 of user core. Nov 8 09:31:01.463524 systemd[1]: Finished User Runtime Directory /run/user/500. Nov 8 09:31:01.464649 systemd[1]: Starting User Manager for UID 500... Nov 8 09:31:01.466730 systemd[952]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:01.515543 systemd[952]: Queued start job for default target Main User Target. Nov 8 09:31:01.515709 systemd[952]: Reached target Paths. Nov 8 09:31:01.515723 systemd[952]: Reached target Sockets. Nov 8 09:31:01.515734 systemd[952]: Reached target Timers. Nov 8 09:31:01.515745 systemd[952]: Reached target Basic System. Nov 8 09:31:01.515789 systemd[952]: Reached target Main User Target. Nov 8 09:31:01.515802 systemd[952]: Startup finished in 45ms. Nov 8 09:31:01.515874 systemd[1]: Started User Manager for UID 500. Nov 8 09:31:01.528324 systemd[1]: Started Session 1 of User core. Nov 8 09:31:01.586643 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45304). Nov 8 09:31:01.623894 sshd[961]: Accepted publickey for core from 10.0.0.1 port 45304 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:01.624800 sshd[961]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:01.627291 systemd-logind[917]: New session 2 of user core. Nov 8 09:31:01.633308 systemd[1]: Started Session 2 of User core. Nov 8 09:31:01.684797 sshd[961]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:01.694639 systemd[1]: sshd@1-10.0.0.4:22-10.0.0.1:45304.service: Deactivated successfully. Nov 8 09:31:01.695260 systemd[1]: session-2.scope: Deactivated successfully. Nov 8 09:31:01.695844 systemd-logind[917]: Session 2 logged out. Waiting for processes to exit. Nov 8 09:31:01.696794 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45318). Nov 8 09:31:01.697475 systemd-logind[917]: Removed session 2. Nov 8 09:31:01.733925 sshd[967]: Accepted publickey for core from 10.0.0.1 port 45318 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:01.734997 sshd[967]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:01.737246 systemd-logind[917]: New session 3 of user core. Nov 8 09:31:01.750289 systemd[1]: Started Session 3 of User core. Nov 8 09:31:01.799115 sshd[967]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:01.807910 systemd[1]: sshd@2-10.0.0.4:22-10.0.0.1:45318.service: Deactivated successfully. Nov 8 09:31:01.808500 systemd[1]: session-3.scope: Deactivated successfully. Nov 8 09:31:01.809070 systemd-logind[917]: Session 3 logged out. Waiting for processes to exit. Nov 8 09:31:01.809964 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45328). Nov 8 09:31:01.810646 systemd-logind[917]: Removed session 3. Nov 8 09:31:01.846855 sshd[973]: Accepted publickey for core from 10.0.0.1 port 45328 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:01.847930 sshd[973]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:01.850225 systemd-logind[917]: New session 4 of user core. Nov 8 09:31:01.871300 systemd[1]: Started Session 4 of User core. Nov 8 09:31:01.921904 sshd[973]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:01.933994 systemd[1]: sshd@3-10.0.0.4:22-10.0.0.1:45328.service: Deactivated successfully. Nov 8 09:31:01.934590 systemd[1]: session-4.scope: Deactivated successfully. Nov 8 09:31:01.935173 systemd-logind[917]: Session 4 logged out. Waiting for processes to exit. Nov 8 09:31:01.936380 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45342). Nov 8 09:31:01.937054 systemd-logind[917]: Removed session 4. Nov 8 09:31:01.973449 sshd[979]: Accepted publickey for core from 10.0.0.1 port 45342 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:01.974587 sshd[979]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:01.977011 systemd-logind[917]: New session 5 of user core. Nov 8 09:31:01.986376 systemd[1]: Started Session 5 of User core. Nov 8 09:31:02.040382 sudo[982]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Nov 8 09:31:02.040586 sudo[982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:02.053716 sudo[982]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:02.055728 sshd[979]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:02.068649 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45352). Nov 8 09:31:02.069769 dbus-daemon[902]: [system] Reloaded configuration Nov 8 09:31:02.072277 systemd[1]: sshd@4-10.0.0.4:22-10.0.0.1:45342.service: Deactivated successfully. Nov 8 09:31:02.072907 systemd[1]: session-5.scope: Deactivated successfully. Nov 8 09:31:02.073589 systemd-logind[917]: Session 5 logged out. Waiting for processes to exit. Nov 8 09:31:02.074389 systemd-logind[917]: Removed session 5. Nov 8 09:31:02.106060 sshd[985]: Accepted publickey for core from 10.0.0.1 port 45352 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:02.106927 sshd[985]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:02.109184 systemd-logind[917]: New session 6 of user core. Nov 8 09:31:02.120278 systemd[1]: Started Session 6 of User core. Nov 8 09:31:02.170062 sudo[990]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 8 09:31:02.170292 sudo[990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:02.172312 sudo[990]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:02.175561 sudo[989]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Nov 8 09:31:02.175750 sudo[989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:02.190987 systemd[1]: Stopping Load Security Auditing Rules... Nov 8 09:31:02.191000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 8 09:31:02.192980 kernel: kauditd_printk_skb: 23 callbacks suppressed Nov 8 09:31:02.193021 kernel: audit: type=1305 audit(1762594262.191:68): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 8 09:31:02.193130 auditctl[993]: No rules Nov 8 09:31:02.193480 systemd[1]: audit-rules.service: Deactivated successfully. Nov 8 09:31:02.193717 systemd[1]: Stopped Load Security Auditing Rules. Nov 8 09:31:02.194921 systemd[1]: Starting Load Security Auditing Rules... Nov 8 09:31:02.208884 augenrules[1010]: No rules Nov 8 09:31:02.209542 systemd[1]: Finished Load Security Auditing Rules. Nov 8 09:31:02.210290 sudo[989]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:02.211406 sshd[985]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:02.222689 systemd[1]: sshd@5-10.0.0.4:22-10.0.0.1:45352.service: Deactivated successfully. Nov 8 09:31:02.223212 systemd[1]: session-6.scope: Deactivated successfully. Nov 8 09:31:02.223762 systemd-logind[917]: Session 6 logged out. Waiting for processes to exit. Nov 8 09:31:02.224630 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45354). Nov 8 09:31:02.225328 systemd-logind[917]: Removed session 6. Nov 8 09:31:02.262601 sshd[1017]: Accepted publickey for core from 10.0.0.1 port 45354 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:02.264476 sshd[1017]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:02.266197 kernel: audit: type=1006 audit(1762594262.262:69): pid=1017 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Nov 8 09:31:02.267792 systemd-logind[917]: New session 7 of user core. Nov 8 09:31:02.276307 systemd[1]: Started Session 7 of User core. Nov 8 09:31:02.325976 sudo[1020]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: Nov 8 09:31:05.087222 update_engine[1267]: I1108 09:31:05.087142 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Nov 8 09:31:05.091962 update_engine[1267]: I1108 09:31:05.091701 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Nov 8 09:31:05.091962 update_engine[1267]: I1108 09:31:05.091918 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Nov 8 09:31:05.093226 update_engine[1267]: I1108 09:31:05.093009 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Nov 8 09:31:05.093226 update_engine[1267]: I1108 09:31:05.093083 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Nov 8 09:31:05.093226 update_engine[1267]: I1108 09:31:05.093091 1267 omaha_request_action.cc:619] Omaha request response: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.093226 update_engine[1267]: Nov 8 09:31:05.095289 update_engine[1267]: I1108 09:31:05.095265 1267 omaha_request_action.cc:447] Omaha Response manifest version = Nov 8 09:31:05.095386 update_engine[1267]: I1108 09:31:05.095376 1267 omaha_request_action.cc:470] Found 1 url(s) Nov 8 09:31:05.095445 update_engine[1267]: I1108 09:31:05.095434 1267 omaha_request_action.cc:506] Processing first of 1 package(s) Nov 8 09:31:05.095499 update_engine[1267]: I1108 09:31:05.095489 1267 omaha_request_action.cc:513] Omaha Response package name = update.gz Nov 8 09:31:05.095548 update_engine[1267]: I1108 09:31:05.095535 1267 omaha_request_action.cc:529] Url0: http://10.0.0.3:34567/packages/update.gz Nov 8 09:31:05.095598 update_engine[1267]: I1108 09:31:05.095587 1267 omaha_request_action.cc:541] Payload size = 420852543 bytes Nov 8 09:31:05.095655 update_engine[1267]: I1108 09:31:05.095644 1267 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Nov 8 09:31:05.095711 update_engine[1267]: I1108 09:31:05.095700 1267 payload_state.cc:51] Resetting all persisted state as this is a new response Nov 8 09:31:05.095758 update_engine[1267]: I1108 09:31:05.095748 1267 payload_state.cc:360] Current Response Signature = Nov 8 09:31:05.095758 update_engine[1267]: NumURLs = 1 Nov 8 09:31:05.095758 update_engine[1267]: Url0 = http://10.0.0.3:34567/packages/update.gz Nov 8 09:31:05.095758 update_engine[1267]: Payload Size = 420852543 Nov 8 09:31:05.095758 update_engine[1267]: Payload Sha256 Hash = R5b5k53y1oVPdKrjN61IArcbBwUMF9e/B1Zefwv2T78= Nov 8 09:31:05.095758 update_engine[1267]: Is Delta Payload = 0 Nov 8 09:31:05.095758 update_engine[1267]: Max Failure Count Per Url = 10 Nov 8 09:31:05.095758 update_engine[1267]: Disable Payload Backoff = 1 Nov 8 09:31:05.096035 update_engine[1267]: I1108 09:31:05.096020 1267 payload_state.cc:381] Payload Attempt Number = 0 Nov 8 09:31:05.096698 update_engine[1267]: I1108 09:31:05.096678 1267 payload_state.cc:404] Current URL Index = 0 Nov 8 09:31:05.096804 update_engine[1267]: I1108 09:31:05.096792 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Nov 8 09:31:05.096889 update_engine[1267]: I1108 09:31:05.096877 1267 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Nov 8 09:31:05.096942 update_engine[1267]: I1108 09:31:05.096925 1267 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Nov 8 09:31:05.097040 update_engine[1267]: I1108 09:31:05.097028 1267 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Nov 8 09:31:05.097113 update_engine[1267]: I1108 09:31:05.097101 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Nov 8 09:31:05.097180 update_engine[1267]: I1108 09:31:05.097152 1267 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Nov 8 09:31:05.097239 update_engine[1267]: I1108 09:31:05.097229 1267 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Nov 8 09:31:05.097282 update_engine[1267]: E1108 09:31:05.097271 1267 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Nov 8 09:31:05.098036 update_engine[1267]: I1108 09:31:05.098018 1267 omaha_response_handler_action.cc:85] Using this install plan: Nov 8 09:31:05.098104 update_engine[1267]: I1108 09:31:05.098093 1267 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.3:34567/packages/update.gz, payload size: 420852543, payload hash: R5b5k53y1oVPdKrjN61IArcbBwUMF9e/B1Zefwv2T78=, 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 Nov 8 09:31:05.098236 update_engine[1267]: I1108 09:31:05.098223 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Nov 8 09:31:05.099246 update_engine[1267]: I1108 09:31:05.099228 1267 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Nov 8 09:31:05.121356 sshd[1284]: Accepted publickey for core from 10.0.0.1 port 45494 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:05.122417 sshd[1284]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:05.125907 systemd-logind[917]: New session 25 of user core. Nov 8 09:31:05.132322 systemd[1]: Started Session 25 of User core. Nov 8 09:31:05.191985 sshd[1284]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:05.193923 systemd[1]: sshd@24-10.0.0.4:22-10.0.0.1:45494.service: Deactivated successfully. Nov 8 09:31:05.194602 systemd[1]: session-25.scope: Deactivated successfully. Nov 8 09:31:05.195210 systemd-logind[917]: Session 25 logged out. Waiting for processes to exit. Nov 8 09:31:05.195873 systemd-logind[917]: Removed session 25. Nov 8 09:31:06.914003 update_engine[1267]: I1108 09:31:06.913965 1267 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Nov 8 09:31:06.914003 update_engine[1267]: I1108 09:31:06.914005 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Nov 8 09:31:06.985280 update_engine[1267]: E1108 09:31:06.985231 1267 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Nov 8 09:31:06.985280 update_engine[1267]: I1108 09:31:06.985275 1267 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Nov 8 09:31:06.985280 update_engine[1267]: I1108 09:31:06.985280 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Nov 8 09:31:06.985280 update_engine[1267]: I1108 09:31:06.985283 1267 update_attempter.cc:302] Processing Done. Nov 8 09:31:06.985464 update_engine[1267]: E1108 09:31:06.985327 1267 update_attempter.cc:615] Update failed. Nov 8 09:31:06.985464 update_engine[1267]: I1108 09:31:06.985338 1267 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Nov 8 09:31:06.985464 update_engine[1267]: I1108 09:31:06.985343 1267 payload_state.cc:276] Incrementing the URL failure count Nov 8 09:31:06.985464 update_engine[1267]: I1108 09:31:06.985347 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Nov 8 09:31:06.985552 update_engine[1267]: I1108 09:31:06.985512 1267 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Nov 8 09:31:06.985629 update_engine[1267]: I1108 09:31:06.985577 1267 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.3:34567/v1/update Nov 8 09:31:06.985629 update_engine[1267]: I1108 09:31:06.985589 1267 omaha_request_action.cc:269] Request: Nov 8 09:31:06.985629 update_engine[1267]: Nov 8 09:31:06.985629 update_engine[1267]: Nov 8 09:31:06.985629 update_engine[1267]: Nov 8 09:31:06.985629 update_engine[1267]: Nov 8 09:31:06.985629 update_engine[1267]: Nov 8 09:31:06.985629 update_engine[1267]: Nov 8 09:31:06.985629 update_engine[1267]: I1108 09:31:06.985594 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Nov 8 09:31:06.985807 update_engine[1267]: I1108 09:31:06.985649 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Nov 8 09:31:06.985807 update_engine[1267]: I1108 09:31:06.985752 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Nov 8 09:31:07.096970 update_engine[1267]: I1108 09:31:07.096913 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Nov 8 09:31:07.097078 update_engine[1267]: I1108 09:31:07.097005 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Nov 8 09:31:07.097078 update_engine[1267]: I1108 09:31:07.097013 1267 omaha_request_action.cc:619] Omaha request response: Nov 8 09:31:07.097078 update_engine[1267]: Nov 8 09:31:07.097078 update_engine[1267]: Nov 8 09:31:07.097078 update_engine[1267]: Nov 8 09:31:07.097078 update_engine[1267]: Nov 8 09:31:07.097078 update_engine[1267]: Nov 8 09:31:07.097078 update_engine[1267]: Nov 8 09:31:07.097078 update_engine[1267]: E1108 09:31:07.097052 1267 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Nov 8 09:31:07.097078 update_engine[1267]: I1108 09:31:07.097059 1267 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Nov 8 09:31:07.097078 update_engine[1267]: I1108 09:31:07.097062 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Nov 8 09:31:07.097078 update_engine[1267]: I1108 09:31:07.097065 1267 update_attempter.cc:302] Processing Done. Nov 8 09:31:07.097078 update_engine[1267]: I1108 09:31:07.097070 1267 update_attempter.cc:306] Error event sent. Nov 8 09:31:15.198471 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43776). Nov 8 09:31:15.238064 sshd[1304]: Accepted publickey for core from 10.0.0.1 port 43776 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:15.239554 kernel: kauditd_printk_skb: 287 callbacks suppressed Nov 8 09:31:15.239604 kernel: audit: type=1006 audit(1762594275.237:365): pid=1304 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=26 res=1 Nov 8 09:31:15.240088 sshd[1304]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:15.243555 systemd-logind[917]: New session 26 of user core. Nov 8 09:31:15.259355 systemd[1]: Started Session 26 of User core. Nov 8 09:31:15.317137 sshd[1304]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:15.331111 systemd-logind[917]: Session 26 logged out. Waiting for processes to exit. Nov 8 09:31:15.331255 systemd[1]: sshd@25-10.0.0.4:22-10.0.0.1:43776.service: Deactivated successfully. Nov 8 09:31:15.331822 systemd[1]: session-26.scope: Deactivated successfully. Nov 8 09:31:15.333323 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43778). Nov 8 09:31:15.333693 systemd-logind[917]: Removed session 26. Nov 8 09:31:15.370961 sshd[1311]: Accepted publickey for core from 10.0.0.1 port 43778 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:15.374177 kernel: audit: type=1006 audit(1762594275.370:366): pid=1311 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=27 res=1 Nov 8 09:31:15.374252 sshd[1311]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:15.377117 systemd-logind[917]: New session 27 of user core. Nov 8 09:31:15.392328 systemd[1]: Started Session 27 of User core. Nov 8 09:31:15.446304 sshd[1311]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:15.453802 systemd[1]: sshd@26-10.0.0.4:22-10.0.0.1:43778.service: Deactivated successfully. Nov 8 09:31:15.454347 systemd[1]: session-27.scope: Deactivated successfully. Nov 8 09:31:15.454890 systemd-logind[917]: Session 27 logged out. Waiting for processes to exit. Nov 8 09:31:15.455842 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43790). Nov 8 09:31:15.456448 systemd-logind[917]: Removed session 27. Nov 8 09:31:15.493376 sshd[1319]: Accepted publickey for core from 10.0.0.1 port 43790 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:15.496173 kernel: audit: type=1006 audit(1762594275.492:367): pid=1319 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=28 res=1 Nov 8 09:31:15.496341 sshd[1319]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:15.498890 systemd-logind[917]: New session 28 of user core. Nov 8 09:31:15.511376 systemd[1]: Started Session 28 of User core. Nov 8 09:31:15.564876 sshd[1319]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:15.575079 systemd[1]: sshd@27-10.0.0.4:22-10.0.0.1:43790.service: Deactivated successfully. Nov 8 09:31:15.575621 systemd[1]: session-28.scope: Deactivated successfully. Nov 8 09:31:15.576302 systemd-logind[917]: Session 28 logged out. Waiting for processes to exit. Nov 8 09:31:15.577136 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43794). Nov 8 09:31:15.578062 systemd-logind[917]: Removed session 28. Nov 8 09:31:15.614492 sshd[1325]: Accepted publickey for core from 10.0.0.1 port 43794 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:15.617172 kernel: audit: type=1006 audit(1762594275.615:368): pid=1325 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=29 res=1 Nov 8 09:31:15.617499 sshd[1325]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:15.620669 systemd-logind[917]: New session 29 of user core. Nov 8 09:31:15.630397 systemd[1]: Started Session 29 of User core. Nov 8 09:31:15.681902 sshd[1325]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:15.691824 systemd[1]: sshd@28-10.0.0.4:22-10.0.0.1:43794.service: Deactivated successfully. Nov 8 09:31:15.692387 systemd[1]: session-29.scope: Deactivated successfully. Nov 8 09:31:15.692928 systemd-logind[917]: Session 29 logged out. Waiting for processes to exit. Nov 8 09:31:15.693878 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43808). Nov 8 09:31:15.697520 systemd-logind[917]: Removed session 29. Nov 8 09:31:15.735229 sshd[1331]: Accepted publickey for core from 10.0.0.1 port 43808 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:15.736487 sshd[1331]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:15.739177 kernel: audit: type=1006 audit(1762594275.736:369): pid=1331 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=30 res=1 Nov 8 09:31:15.740582 systemd-logind[917]: New session 30 of user core. Nov 8 09:31:15.746373 systemd[1]: Started Session 30 of User core. Nov 8 09:31:15.795844 sudo[1335]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Nov 8 09:31:15.796047 sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:15.801628 systemd[1]: sshd.socket: Deactivated successfully. Nov 8 09:31:15.801941 systemd[1]: Closed OpenSSH Server Socket. Nov 8 09:31:15.803034 sudo[1335]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:15.806427 sudo[1334]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Nov 8 09:31:15.806612 sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:15.820547 systemd[1]: Stopping Session 1 of User core... Nov 8 09:31:15.821480 sshd[949]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:15.821605 systemd[1]: Stopping Session 30 of User core... Nov 8 09:31:15.821885 sshd[1331]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:15.822642 systemd[1]: Removed slice Slice /system/addon-config. Nov 8 09:31:15.822791 sudo[1334]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:15.824288 systemd[1]: Removed slice Slice /system/addon-run. Nov 8 09:31:15.825518 systemd[1]: Removed slice Slice /system/modprobe. Nov 8 09:31:15.826634 systemd[1]: Removed slice Slice /system/system-cloudinit. Nov 8 09:31:15.827715 systemd[1]: Stopped target Multi-User System. Nov 8 09:31:15.828727 systemd[1]: Stopped target Login Prompts. Nov 8 09:31:15.829553 systemd[1]: Stopped target Containers. Nov 8 09:31:15.830295 systemd[1]: Stopped target Host and Network Name Lookups. Nov 8 09:31:15.831216 systemd[1]: Stopped target Remote Encrypted Volumes. Nov 8 09:31:15.832087 systemd[1]: Stopped target Timer Units. Nov 8 09:31:15.833089 systemd[1]: logrotate.timer: Deactivated successfully. Nov 8 09:31:15.833139 systemd[1]: Stopped Daily Log Rotation. Nov 8 09:31:15.834004 systemd[1]: mdadm.timer: Deactivated successfully. Nov 8 09:31:15.834045 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Nov 8 09:31:15.835151 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Nov 8 09:31:15.835209 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Nov 8 09:31:15.836273 systemd[1]: Stopped target System Time Set. Nov 8 09:31:15.837056 systemd[1]: Stopped target Load user-provided cloud configs. Nov 8 09:31:15.838090 systemd[1]: Stopped target Load system-provided cloud configs. Nov 8 09:31:15.839147 systemd[1]: systemd-coredump.socket: Deactivated successfully. Nov 8 09:31:15.839257 systemd[1]: Closed Process Core Dump Socket. Nov 8 09:31:15.840681 systemd[1]: Unmounting Boot partition... Nov 8 09:31:15.841736 systemd[1]: Stopping Getty on tty1... Nov 8 09:31:15.842555 systemd[1]: Stopping Serial Getty on ttyAMA0... Nov 8 09:31:15.843420 systemd[1]: Stopping OpenSSH per-connection server daemon... Nov 8 09:31:15.844321 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:43808)... Nov 8 09:31:15.845243 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Nov 8 09:31:15.845370 systemd[1]: Stopped Commit a transient machine-id on disk. Nov 8 09:31:15.846386 systemd[1]: Stopped target First Boot Complete. Nov 8 09:31:15.848047 systemd[1]: Stopping Load/Save Random Seed... Nov 8 09:31:15.849457 systemd[1]: Stopping Update Engine... Nov 8 09:31:15.852488 systemd[1]: getty@tty1.service: Deactivated successfully. Nov 8 09:31:15.852682 systemd[1]: Stopped Getty on tty1. Nov 8 09:31:15.853881 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Nov 8 09:31:15.859237 systemd[1]: Stopped Serial Getty on ttyAMA0. Nov 8 09:31:15.860541 systemd[1]: sshd@0-10.0.0.4:22-10.0.0.1:45296.service: Deactivated successfully. Nov 8 09:31:15.860725 systemd[1]: Stopped OpenSSH per-connection server daemon. Nov 8 09:31:15.862151 systemd[1]: sshd@29-10.0.0.4:22-10.0.0.1:43808.service: Deactivated successfully. Nov 8 09:31:15.862449 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:43808). Nov 8 09:31:15.865953 systemd[1]: session-1.scope: Deactivated successfully. Nov 8 09:31:15.869230 systemd[1]: Stopped Session 1 of User core. Nov 8 09:31:15.870297 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Nov 8 09:31:15.870364 systemd[1]: update-engine.service: Failed with result 'exit-code'. Nov 8 09:31:15.870589 systemd[1]: Stopped Update Engine. Nov 8 09:31:15.873600 systemd[1]: update-engine.service: Consumed 2.387s CPU time. Nov 8 09:31:15.873851 systemd[1]: systemd-random-seed.service: Deactivated successfully. Nov 8 09:31:15.874042 systemd[1]: Stopped Load/Save Random Seed. Nov 8 09:31:15.874995 systemd[1]: session-30.scope: Deactivated successfully. Nov 8 09:31:15.877345 systemd[1]: Stopped Session 30 of User core. Nov 8 09:31:15.878656 systemd-logind[917]: Session 30 logged out. Waiting for processes to exit. Nov 8 09:31:15.882811 systemd-logind[917]: Session 1 logged out. Waiting for processes to exit. Nov 8 09:31:15.886434 systemd[1]: Removed slice Slice /system/getty. Nov 8 09:31:15.887560 systemd[1]: Removed slice Slice /system/serial-getty. Nov 8 09:31:15.888927 systemd[1]: Removed slice Slice /system/sshd. Nov 8 09:31:15.891274 systemd[1]: sshd-keygen.service: Deactivated successfully. Nov 8 09:31:15.891378 systemd[1]: Stopped Generate sshd host keys. Nov 8 09:31:15.892298 systemd[1]: Stopping User Login Management... Nov 8 09:31:15.893014 systemd[1]: Stopping User Manager for UID 500... Nov 8 09:31:15.894676 systemd[1]: boot.mount: Deactivated successfully. Nov 8 09:31:15.894979 systemd[1]: Unmounted Boot partition. Nov 8 09:31:15.895270 systemd[952]: Stopped target Main User Target. Nov 8 09:31:15.895579 systemd[952]: Stopped target Basic System. Nov 8 09:31:15.895588 systemd[952]: Stopped target Paths. Nov 8 09:31:15.895596 systemd[952]: Stopped target Sockets. Nov 8 09:31:15.895610 systemd[952]: Reached target Shutdown. Nov 8 09:31:15.895617 systemd[952]: Stopped target Timers. Nov 8 09:31:15.895725 systemd[952]: Finished Exit the Session. Nov 8 09:31:15.895749 systemd[952]: Reached target Exit the Session. Nov 8 09:31:15.896529 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Nov 8 09:31:15.896651 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Nov 8 09:31:15.898083 systemd[1]: user@500.service: Deactivated successfully. Nov 8 09:31:15.898282 systemd[1]: Stopped User Manager for UID 500. Nov 8 09:31:15.904594 systemd[1]: Stopping User Runtime Directory /run/user/500... Nov 8 09:31:15.906647 systemd[1]: run-user-500.mount: Deactivated successfully. Nov 8 09:31:15.906703 systemd[1]: Unmounted /run/user/500. Nov 8 09:31:15.908056 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Nov 8 09:31:15.908276 systemd[1]: Stopped User Runtime Directory /run/user/500. Nov 8 09:31:15.910019 systemd[1]: Removed slice User Slice of UID 500. Nov 8 09:31:15.911717 systemd[1]: Stopping D-Bus System Message Bus... Nov 8 09:31:15.914289 systemd[1]: Stopping Permit User Sessions... Nov 8 09:31:15.916452 systemd[1]: dbus.service: Deactivated successfully. Nov 8 09:31:15.916712 systemd[1]: Stopped D-Bus System Message Bus. Nov 8 09:31:15.918065 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Nov 8 09:31:15.918370 systemd[1]: Stopped Permit User Sessions. Nov 8 09:31:15.919270 systemd[1]: Stopped target Network. Nov 8 09:31:15.919949 systemd[1]: Stopped target Remote File Systems. Nov 8 09:31:15.921770 systemd[1]: Stopping Network Name Resolution... Nov 8 09:31:15.923427 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 8 09:31:15.923744 systemd[1]: Stopped Network Name Resolution. Nov 8 09:31:15.925101 systemd[1]: Stopping Network Configuration... Nov 8 09:31:15.930042 systemd[1]: systemd-logind.service: Deactivated successfully. Nov 8 09:31:15.930387 systemd[1]: Stopped User Login Management. Nov 8 09:31:15.931000 audit: BPF prog-id=56 op=UNLOAD Nov 8 09:31:15.932240 kernel: audit: type=1334 audit(1762594275.931:370): prog-id=56 op=UNLOAD Nov 8 09:31:15.932489 systemd[1]: Stopped target Basic System. Nov 8 09:31:15.933315 systemd[1]: Stopped target Path Units. Nov 8 09:31:15.934105 systemd[1]: motdgen.path: Deactivated successfully. Nov 8 09:31:15.938210 systemd[1]: Stopped Watch for update engine configuration changes. Nov 8 09:31:15.939342 systemd-networkd[836]: eth0: DHCPv6 lease lost Nov 8 09:31:15.939871 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Nov 8 09:31:15.944000 audit: BPF prog-id=65 op=UNLOAD Nov 8 09:31:15.943208 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 8 09:31:15.944263 systemd[1]: Stopped target Slice Units. Nov 8 09:31:15.945224 kernel: audit: type=1334 audit(1762594275.944:371): prog-id=65 op=UNLOAD Nov 8 09:31:15.946235 systemd[1]: Removed slice User and Session Slice. Nov 8 09:31:15.947062 systemd[1]: Stopped target Socket Units. Nov 8 09:31:15.947926 systemd[1]: dbus.socket: Deactivated successfully. Nov 8 09:31:15.947986 systemd[1]: Closed D-Bus System Message Bus Socket. Nov 8 09:31:15.949027 systemd[1]: docker.socket: Deactivated successfully. Nov 8 09:31:15.949292 systemd[1]: Closed Docker Socket for the API. Nov 8 09:31:15.950095 systemd[1]: Stopped target System Initialization. Nov 8 09:31:15.951009 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Nov 8 09:31:15.951135 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Nov 8 09:31:15.952365 systemd[1]: Stopped target Local Verity Protected Volumes. Nov 8 09:31:15.954140 systemd[1]: Stopping Load Security Auditing Rules... Nov 8 09:31:15.955254 systemd[1]: Stopping Network Time Synchronization... Nov 8 09:31:15.956186 systemd[1]: systemd-update-done.service: Deactivated successfully. Nov 8 09:31:15.956320 systemd[1]: Stopped Update is Completed. Nov 8 09:31:15.956696 auditctl[1354]: No rules Nov 8 09:31:15.957099 systemd[1]: ldconfig.service: Deactivated successfully. Nov 8 09:31:15.957285 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Nov 8 09:31:15.958188 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Nov 8 09:31:15.958244 systemd[1]: Stopped Rebuild Hardware Database. Nov 8 09:31:15.959045 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Nov 8 09:31:15.959101 systemd[1]: Stopped Rebuild Journal Catalog. Nov 8 09:31:15.960832 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Nov 8 09:31:15.963352 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 8 09:31:15.963595 systemd[1]: Stopped Network Configuration. Nov 8 09:31:15.964632 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Nov 8 09:31:15.964872 systemd[1]: Stopped Network Time Synchronization. Nov 8 09:31:15.966195 systemd[1]: audit-rules.service: Deactivated successfully. Nov 8 09:31:15.966405 systemd[1]: Stopped Load Security Auditing Rules. Nov 8 09:31:15.967000 audit: BPF prog-id=58 op=UNLOAD Nov 8 09:31:15.968748 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 8 09:31:15.968789 systemd[1]: Closed Network Service Netlink Socket. Nov 8 09:31:15.969165 kernel: audit: type=1334 audit(1762594275.967:372): prog-id=58 op=UNLOAD Nov 8 09:31:15.969738 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 8 09:31:15.969796 systemd[1]: Stopped Apply Kernel Variables. Nov 8 09:31:15.970000 audit: BPF prog-id=57 op=UNLOAD Nov 8 09:31:15.971812 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Nov 8 09:31:15.972019 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Nov 8 09:31:15.972163 kernel: audit: type=1334 audit(1762594275.970:373): prog-id=57 op=UNLOAD Nov 8 09:31:15.973644 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 8 09:31:15.973736 systemd[1]: Stopped Create Volatile Files and Directories. Nov 8 09:31:15.974901 systemd[1]: Stopped target Local File Systems. Nov 8 09:31:15.976626 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Nov 8 09:31:15.978115 systemd[1]: Unmounting External Media Directory... Nov 8 09:31:15.979956 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Nov 8 09:31:15.981799 systemd[1]: Unmounting /run/torcx/unpack... Nov 8 09:31:15.983078 systemd[1]: Unmounting Temporary Directory /tmp... Nov 8 09:31:15.984541 systemd[1]: Unmounting /usr/share/oem... Nov 8 09:31:15.986087 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Nov 8 09:31:15.988928 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Nov 8 09:31:15.989188 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Nov 8 09:31:15.990473 systemd[1]: media.mount: Deactivated successfully. Nov 8 09:31:15.990721 systemd[1]: Unmounted External Media Directory. Nov 8 09:31:15.991725 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Nov 8 09:31:15.991943 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Nov 8 09:31:15.993340 systemd[1]: tmp.mount: Deactivated successfully. Nov 8 09:31:15.993588 systemd[1]: Unmounted Temporary Directory /tmp. Nov 8 09:31:15.994580 systemd[1]: usr-share-oem.mount: Deactivated successfully. Nov 8 09:31:15.994831 systemd[1]: Unmounted /usr/share/oem. Nov 8 09:31:15.995915 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Nov 8 09:31:15.996130 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Nov 8 09:31:15.997579 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Nov 8 09:31:15.997658 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Nov 8 09:31:15.999014 systemd[1]: Removed slice Slice /system/systemd-fsck. Nov 8 09:31:16.002756 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Nov 8 09:31:16.002977 systemd[1]: Unmounted /run/torcx/unpack. Nov 8 09:31:16.004015 systemd[1]: Stopped target Preparation for Local File Systems. Nov 8 09:31:16.005133 systemd[1]: Stopped target Swaps. Nov 8 09:31:16.005857 systemd[1]: Reached target Unmount All Filesystems. Nov 8 09:31:16.006840 systemd[1]: lvm2-activation.service: Deactivated successfully. Nov 8 09:31:16.006913 systemd[1]: Stopped Activation of LVM2 logical volumes. Nov 8 09:31:16.008153 systemd[1]: Stopped target Local Encrypted Volumes. Nov 8 09:31:16.009056 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 8 09:31:16.009122 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Nov 8 09:31:16.010252 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Nov 8 09:31:16.014197 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Nov 8 09:31:16.015287 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Nov 8 09:31:16.015355 systemd[1]: Stopped Activation of LVM2 logical volumes. Nov 8 09:31:16.016293 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 8 09:31:16.016353 systemd[1]: Stopped Create Static Device Nodes in /dev. Nov 8 09:31:16.017275 systemd[1]: systemd-sysusers.service: Deactivated successfully. Nov 8 09:31:16.017333 systemd[1]: Stopped Create System Users. Nov 8 09:31:16.018096 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Nov 8 09:31:16.018184 systemd[1]: Stopped Remount Root and Kernel File Systems. Nov 8 09:31:16.019117 systemd[1]: Reached target System Shutdown. Nov 8 09:31:16.020147 systemd[1]: Reached target Late Shutdown Services. Nov 8 09:31:16.021041 systemd[1]: systemd-reboot.service: Deactivated successfully. Nov 8 09:31:16.021080 systemd[1]: Finished System Reboot. Nov 8 09:31:16.021842 systemd[1]: Reached target System Reboot. Nov 8 09:31:16.022631 systemd[1]: Shutting down. Nov 8 09:31:16.026000 audit: BPF prog-id=67 op=UNLOAD Nov 8 09:31:16.026000 audit: BPF prog-id=66 op=UNLOAD Nov 8 09:31:16.026000 audit: BPF prog-id=61 op=UNLOAD Nov 8 09:31:16.026000 audit: BPF prog-id=60 op=UNLOAD Nov 8 09:31:16.026000 audit: BPF prog-id=59 op=UNLOAD Nov 8 09:31:16.026000 audit: BPF prog-id=64 op=UNLOAD Nov 8 09:31:16.026000 audit: BPF prog-id=63 op=UNLOAD Nov 8 09:31:16.026000 audit: BPF prog-id=62 op=UNLOAD Nov 8 09:31:16.028186 kernel: audit: type=1334 audit(1762594276.026:374): prog-id=67 op=UNLOAD Nov 8 09:31:16.062221 systemd-shutdown[1]: Syncing filesystems and block devices. Nov 8 09:31:16.069578 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Nov 8 09:31:16.069659 systemd-journald[800]: Journal stopped -- Reboot -- Nov 8 09:31:24.678657 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 8 09:31:24.678674 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 Nov 8 09:31:24.678682 kernel: efi: EFI v2.70 by EDK II Nov 8 09:31:24.678688 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Nov 8 09:31:24.678692 kernel: efi: seeding entropy pool Nov 8 09:31:24.678697 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Nov 8 09:31:24.678704 kernel: ACPI: Early table checksum verification disabled Nov 8 09:31:24.678709 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 8 09:31:24.678713 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 8 09:31:24.678719 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678725 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678730 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678735 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678740 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678746 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678752 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678767 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678773 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 8 09:31:24.678778 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 8 09:31:24.678784 kernel: ACPI: NUMA: Failed to initialise from firmware Nov 8 09:31:24.678789 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 8 09:31:24.678794 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Nov 8 09:31:24.678799 kernel: Zone ranges: Nov 8 09:31:24.678805 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 8 09:31:24.678810 kernel: DMA32 empty Nov 8 09:31:24.678815 kernel: Normal empty Nov 8 09:31:24.678820 kernel: Movable zone start for each node Nov 8 09:31:24.678826 kernel: Early memory node ranges Nov 8 09:31:24.678832 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Nov 8 09:31:24.678837 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 8 09:31:24.678842 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 8 09:31:24.678848 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 8 09:31:24.678853 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 8 09:31:24.678858 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 8 09:31:24.678863 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 8 09:31:24.678868 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 8 09:31:24.678874 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 8 09:31:24.678879 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 8 09:31:24.678884 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 8 09:31:24.678889 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 8 09:31:24.678898 kernel: On node 0 totalpages: 643072 Nov 8 09:31:24.678903 kernel: DMA zone: 10048 pages used for memmap Nov 8 09:31:24.678909 kernel: DMA zone: 0 pages reserved Nov 8 09:31:24.678914 kernel: DMA zone: 643072 pages, LIFO batch:63 Nov 8 09:31:24.678920 kernel: psci: probing for conduit method from ACPI. Nov 8 09:31:24.678925 kernel: psci: PSCIv1.1 detected in firmware. Nov 8 09:31:24.678931 kernel: psci: Using standard PSCI v0.2 function IDs Nov 8 09:31:24.678937 kernel: psci: Trusted OS migration not required Nov 8 09:31:24.678943 kernel: psci: SMC Calling Convention v1.1 Nov 8 09:31:24.678949 kernel: ACPI: SRAT not present Nov 8 09:31:24.678954 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Nov 8 09:31:24.678960 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Nov 8 09:31:24.678965 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 8 09:31:24.678971 kernel: Detected PIPT I-cache on CPU0 Nov 8 09:31:24.678976 kernel: CPU features: detected: GIC system register CPU interface Nov 8 09:31:24.678982 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 8 09:31:24.678988 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 8 09:31:24.678993 kernel: CPU features: detected: Hardware dirty bit management Nov 8 09:31:24.678999 kernel: CPU features: detected: Spectre-v4 Nov 8 09:31:24.679005 kernel: CPU features: detected: ARM erratum 1418040 Nov 8 09:31:24.679011 kernel: CPU features: detected: Spectre-BHB Nov 8 09:31:24.679016 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 8 09:31:24.679022 kernel: Policy zone: DMA Nov 8 09:31:24.679028 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 Nov 8 09:31:24.679034 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 8 09:31:24.679040 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 8 09:31:24.679046 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 8 09:31:24.679051 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Nov 8 09:31:24.679057 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Nov 8 09:31:24.679063 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 8 09:31:24.679070 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 8 09:31:24.679080 kernel: rcu: RCU event tracing is enabled. Nov 8 09:31:24.679089 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 8 09:31:24.679096 kernel: Trampoline variant of Tasks RCU enabled. Nov 8 09:31:24.679118 kernel: Tracing variant of Tasks RCU enabled. Nov 8 09:31:24.679124 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 8 09:31:24.679130 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 8 09:31:24.679135 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 8 09:31:24.679141 kernel: GICv3: 256 SPIs implemented Nov 8 09:31:24.679146 kernel: GICv3: 0 Extended SPIs implemented Nov 8 09:31:24.679152 kernel: GICv3: Distributor has no Range Selector support Nov 8 09:31:24.679158 kernel: GICv3: 16 PPIs implemented Nov 8 09:31:24.679165 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 8 09:31:24.679173 kernel: ACPI: SRAT not present Nov 8 09:31:24.679178 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 8 09:31:24.679184 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Nov 8 09:31:24.679190 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Nov 8 09:31:24.679195 kernel: GICv3: using LPI property table @0x00000000400d0000 Nov 8 09:31:24.679201 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Nov 8 09:31:24.679207 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:31:24.679216 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 8 09:31:24.679223 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 8 09:31:24.679229 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 8 09:31:24.679237 kernel: arm-pv: using stolen time PV Nov 8 09:31:24.679245 kernel: Console: colour dummy device 80x25 Nov 8 09:31:24.679250 kernel: ACPI: Core revision 20200925 Nov 8 09:31:24.679256 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 8 09:31:24.679262 kernel: pid_max: default: 32768 minimum: 301 Nov 8 09:31:24.679268 kernel: LSM: Security Framework initializing Nov 8 09:31:24.679274 kernel: SELinux: Initializing. Nov 8 09:31:24.679280 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 09:31:24.679286 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 09:31:24.679292 kernel: rcu: Hierarchical SRCU implementation. Nov 8 09:31:24.679298 kernel: Platform MSI: ITS@0x8080000 domain created Nov 8 09:31:24.679304 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 8 09:31:24.679310 kernel: Remapping and enabling EFI services. Nov 8 09:31:24.679315 kernel: smp: Bringing up secondary CPUs ... Nov 8 09:31:24.679321 kernel: Detected PIPT I-cache on CPU1 Nov 8 09:31:24.679327 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 8 09:31:24.679333 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Nov 8 09:31:24.679339 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:31:24.679345 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 8 09:31:24.679361 kernel: Detected PIPT I-cache on CPU2 Nov 8 09:31:24.679368 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 8 09:31:24.679375 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Nov 8 09:31:24.679381 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:31:24.679386 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 8 09:31:24.679393 kernel: Detected PIPT I-cache on CPU3 Nov 8 09:31:24.679403 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 8 09:31:24.679409 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Nov 8 09:31:24.679416 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 8 09:31:24.679422 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 8 09:31:24.679429 kernel: smp: Brought up 1 node, 4 CPUs Nov 8 09:31:24.679435 kernel: SMP: Total of 4 processors activated. Nov 8 09:31:24.679441 kernel: CPU features: detected: Privileged Access Never Nov 8 09:31:24.679448 kernel: CPU features: detected: LSE atomic instructions Nov 8 09:31:24.679454 kernel: CPU features: detected: User Access Override Nov 8 09:31:24.679460 kernel: CPU features: detected: 32-bit EL0 Support Nov 8 09:31:24.679466 kernel: CPU features: detected: Common not Private translations Nov 8 09:31:24.679472 kernel: CPU features: detected: RAS Extension Support Nov 8 09:31:24.679480 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 8 09:31:24.679486 kernel: CPU features: detected: CRC32 instructions Nov 8 09:31:24.679492 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 8 09:31:24.679498 kernel: CPU: All CPU(s) started at EL1 Nov 8 09:31:24.679504 kernel: alternatives: patching kernel code Nov 8 09:31:24.679512 kernel: devtmpfs: initialized Nov 8 09:31:24.679518 kernel: KASLR enabled Nov 8 09:31:24.679524 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 8 09:31:24.679530 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 8 09:31:24.679536 kernel: pinctrl core: initialized pinctrl subsystem Nov 8 09:31:24.679542 kernel: SMBIOS 3.0.0 present. Nov 8 09:31:24.679548 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 8 09:31:24.679554 kernel: NET: Registered protocol family 16 Nov 8 09:31:24.679561 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 8 09:31:24.679567 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 8 09:31:24.679574 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 8 09:31:24.679580 kernel: audit: initializing netlink subsys (disabled) Nov 8 09:31:24.679587 kernel: audit: type=2000 audit(0.028:1): state=initialized audit_enabled=0 res=1 Nov 8 09:31:24.679593 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 8 09:31:24.679599 kernel: cpuidle: using governor menu Nov 8 09:31:24.679605 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 8 09:31:24.679611 kernel: ASID allocator initialised with 32768 entries Nov 8 09:31:24.679617 kernel: ACPI: bus type PCI registered Nov 8 09:31:24.679623 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 8 09:31:24.679630 kernel: Serial: AMBA PL011 UART driver Nov 8 09:31:24.679637 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Nov 8 09:31:24.679643 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Nov 8 09:31:24.679649 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Nov 8 09:31:24.679655 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Nov 8 09:31:24.679661 kernel: cryptd: max_cpu_qlen set to 1000 Nov 8 09:31:24.679667 kernel: ACPI: Added _OSI(Module Device) Nov 8 09:31:24.679673 kernel: ACPI: Added _OSI(Processor Device) Nov 8 09:31:24.679679 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 8 09:31:24.679685 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 8 09:31:24.679693 kernel: ACPI: Added _OSI(Linux-Dell-Video) Nov 8 09:31:24.679699 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Nov 8 09:31:24.679705 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Nov 8 09:31:24.679711 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 8 09:31:24.679717 kernel: ACPI: Interpreter enabled Nov 8 09:31:24.679723 kernel: ACPI: Using GIC for interrupt routing Nov 8 09:31:24.679729 kernel: ACPI: MCFG table detected, 1 entries Nov 8 09:31:24.679735 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 8 09:31:24.679742 kernel: printk: console [ttyAMA0] enabled Nov 8 09:31:24.679749 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 8 09:31:24.679860 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 8 09:31:24.679916 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 8 09:31:24.679968 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 8 09:31:24.680030 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 8 09:31:24.680088 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 8 09:31:24.680097 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 8 09:31:24.680105 kernel: PCI host bridge to bus 0000:00 Nov 8 09:31:24.680166 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 8 09:31:24.680214 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 8 09:31:24.680261 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 8 09:31:24.680307 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 8 09:31:24.680372 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 8 09:31:24.680433 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 8 09:31:24.680490 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 8 09:31:24.680544 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 8 09:31:24.680599 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 8 09:31:24.680689 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 8 09:31:24.680801 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 8 09:31:24.680870 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 8 09:31:24.680929 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 8 09:31:24.680978 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 8 09:31:24.681026 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 8 09:31:24.681035 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Nov 8 09:31:24.681041 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Nov 8 09:31:24.681047 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Nov 8 09:31:24.681102 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Nov 8 09:31:24.681111 kernel: vgaarb: loaded Nov 8 09:31:24.681117 kernel: Registered efivars operations Nov 8 09:31:24.681124 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 8 09:31:24.681133 kernel: VFS: Disk quotas dquot_6.6.0 Nov 8 09:31:24.681139 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 8 09:31:24.681145 kernel: pnp: PnP ACPI init Nov 8 09:31:24.681221 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 8 09:31:24.681272 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Nov 8 09:31:24.681280 kernel: pnp: PnP ACPI: found 1 devices Nov 8 09:31:24.681287 kernel: NET: Registered protocol family 2 Nov 8 09:31:24.681293 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 8 09:31:24.681301 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 8 09:31:24.681308 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 8 09:31:24.681314 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Nov 8 09:31:24.681320 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 8 09:31:24.681326 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 09:31:24.681333 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 09:31:24.681339 kernel: NET: Registered protocol family 1 Nov 8 09:31:24.681345 kernel: PCI: CLS 0 bytes, default 64 Nov 8 09:31:24.681351 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 8 09:31:24.681359 kernel: kvm [1]: HYP mode not available Nov 8 09:31:24.681365 kernel: Initialise system trusted keyrings Nov 8 09:31:24.681371 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 8 09:31:24.681377 kernel: Key type asymmetric registered Nov 8 09:31:24.681383 kernel: Asymmetric key parser 'x509' registered Nov 8 09:31:24.681390 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 8 09:31:24.681396 kernel: io scheduler mq-deadline registered Nov 8 09:31:24.681402 kernel: io scheduler kyber registered Nov 8 09:31:24.681408 kernel: io scheduler bfq registered Nov 8 09:31:24.681416 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 8 09:31:24.681422 kernel: ACPI: Power Button [PWRB] Nov 8 09:31:24.681428 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Nov 8 09:31:24.681484 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 8 09:31:24.681492 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 8 09:31:24.681498 kernel: msm_serial: driver initialized Nov 8 09:31:24.681505 kernel: random: fast init done Nov 8 09:31:24.681511 kernel: random: crng init done Nov 8 09:31:24.681517 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Nov 8 09:31:24.681523 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Nov 8 09:31:24.681531 kernel: thunder_xcv, ver 1.0 Nov 8 09:31:24.681537 kernel: thunder_bgx, ver 1.0 Nov 8 09:31:24.681543 kernel: nicpf, ver 1.0 Nov 8 09:31:24.681549 kernel: nicvf, ver 1.0 Nov 8 09:31:24.681616 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 8 09:31:24.681667 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-08T09:31:24 UTC (1762594284) Nov 8 09:31:24.681675 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 8 09:31:24.681681 kernel: NET: Registered protocol family 10 Nov 8 09:31:24.681689 kernel: Segment Routing with IPv6 Nov 8 09:31:24.681695 kernel: NET: Registered protocol family 17 Nov 8 09:31:24.681701 kernel: Key type dns_resolver registered Nov 8 09:31:24.681707 kernel: registered taskstats version 1 Nov 8 09:31:24.681713 kernel: Loading compiled-in X.509 certificates Nov 8 09:31:24.681720 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Nov 8 09:31:24.681726 kernel: Key type ._fscrypt registered Nov 8 09:31:24.681732 kernel: Key type .fscrypt registered Nov 8 09:31:24.681739 kernel: Key type fscrypt-provisioning registered Nov 8 09:31:24.681746 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 8 09:31:24.681752 kernel: ima: Allocated hash algorithm: sha1 Nov 8 09:31:24.681768 kernel: ima: No architecture policies found Nov 8 09:31:24.681783 kernel: Freeing unused kernel memory: 34240K Nov 8 09:31:24.681790 kernel: Run /init as init process Nov 8 09:31:24.681796 kernel: with arguments: Nov 8 09:31:24.681802 kernel: /init Nov 8 09:31:24.681808 kernel: with environment: Nov 8 09:31:24.681814 kernel: HOME=/ Nov 8 09:31:24.681820 kernel: TERM=linux Nov 8 09:31:24.681828 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 8 09:31:24.681836 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) Nov 8 09:31:24.681844 systemd[1]: Detected virtualization kvm. Nov 8 09:31:24.681851 systemd[1]: Detected architecture arm64. Nov 8 09:31:24.681857 systemd[1]: Running in initial RAM disk. Nov 8 09:31:24.681864 systemd[1]: No hostname configured, using default hostname. Nov 8 09:31:24.681870 systemd[1]: Hostname set to . Nov 8 09:31:24.681879 systemd[1]: Initializing machine ID from VM UUID. Nov 8 09:31:24.681885 systemd[1]: Queued start job for default target Initrd Default Target. Nov 8 09:31:24.681892 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 8 09:31:24.681899 systemd[1]: Reached target Local Encrypted Volumes. Nov 8 09:31:24.681905 systemd[1]: Reached target Path Units. Nov 8 09:31:24.681911 systemd[1]: Reached target Slice Units. Nov 8 09:31:24.681918 systemd[1]: Reached target Swaps. Nov 8 09:31:24.681924 systemd[1]: Reached target Timer Units. Nov 8 09:31:24.681932 systemd[1]: Listening on Open-iSCSI iscsid Socket. Nov 8 09:31:24.681939 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Nov 8 09:31:24.681945 systemd[1]: Listening on Journal Audit Socket. Nov 8 09:31:24.681952 systemd[1]: Listening on Journal Socket (/dev/log). Nov 8 09:31:24.681959 systemd[1]: Listening on Journal Socket. Nov 8 09:31:24.681965 systemd[1]: Listening on udev Control Socket. Nov 8 09:31:24.681972 systemd[1]: Listening on udev Kernel Socket. Nov 8 09:31:24.681978 systemd[1]: Reached target Socket Units. Nov 8 09:31:24.681985 systemd[1]: Starting iSCSI UserSpace I/O driver... Nov 8 09:31:24.681993 systemd[1]: Starting Create List of Static Device Nodes... Nov 8 09:31:24.682000 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Nov 8 09:31:24.682006 systemd[1]: Starting Journal Service... Nov 8 09:31:24.682013 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 8 09:31:24.682019 systemd[1]: Starting Apply Kernel Variables... Nov 8 09:31:24.682026 systemd[1]: Starting Setup Virtual Console... Nov 8 09:31:24.682033 systemd[1]: Started iSCSI UserSpace I/O driver. Nov 8 09:31:24.682039 kernel: SCSI subsystem initialized Nov 8 09:31:24.682045 systemd[1]: Finished Create List of Static Device Nodes. Nov 8 09:31:24.682052 systemd[1]: Finished Apply Kernel Variables. Nov 8 09:31:24.682060 kernel: Loading iSCSI transport class v2.0-870. Nov 8 09:31:24.682066 systemd[1]: Finished Setup Virtual Console. Nov 8 09:31:24.682075 systemd-journald[270]: Journal started Nov 8 09:31:24.682121 systemd-journald[270]: Runtime Journal (/run/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194) is 6.0M, max 48.5M, 42.4M free. Nov 8 09:31:24.683796 systemd[1]: Started Journal Service. Nov 8 09:31:24.685211 systemd[1]: Starting dracut ask for additional cmdline parameters... Nov 8 09:31:24.686707 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 8 09:31:24.689977 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 8 09:31:24.702840 systemd[1]: Finished dracut ask for additional cmdline parameters. Nov 8 09:31:24.704481 systemd[1]: Starting dracut cmdline hook... Nov 8 09:31:24.713069 dracut-cmdline[290]: dracut-dracut-053 Nov 8 09:31:24.715305 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Nov 8 09:31:24.772778 kernel: iscsi: registered transport (tcp) Nov 8 09:31:24.784776 kernel: iscsi: registered transport (qla4xxx) Nov 8 09:31:24.784801 kernel: QLogic iSCSI HBA Driver Nov 8 09:31:24.795213 systemd[1]: Finished dracut cmdline hook. Nov 8 09:31:24.796811 systemd[1]: Starting dracut pre-udev hook... Nov 8 09:31:24.798071 systemd[1]: Starting Open-iSCSI... Nov 8 09:31:24.800644 iscsid[376]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Nov 8 09:31:24.800644 iscsid[376]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Nov 8 09:31:24.800644 iscsid[376]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Nov 8 09:31:24.800644 iscsid[376]: If using hardware iscsi like qla4xxx this message can be ignored. Nov 8 09:31:24.800644 iscsid[376]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Nov 8 09:31:24.800644 iscsid[376]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Nov 8 09:31:24.814876 kernel: device-mapper: uevent: version 1.0.3 Nov 8 09:31:24.814897 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Nov 8 09:31:24.805150 systemd[1]: Started Open-iSCSI. Nov 8 09:31:24.847797 kernel: raid6: neonx8 gen() 13807 MB/s Nov 8 09:31:24.864780 kernel: raid6: neonx8 xor() 10756 MB/s Nov 8 09:31:24.881782 kernel: raid6: neonx4 gen() 13645 MB/s Nov 8 09:31:24.898775 kernel: raid6: neonx4 xor() 11578 MB/s Nov 8 09:31:24.915772 kernel: raid6: neonx2 gen() 13026 MB/s Nov 8 09:31:24.932771 kernel: raid6: neonx2 xor() 9896 MB/s Nov 8 09:31:24.949773 kernel: raid6: neonx1 gen() 10981 MB/s Nov 8 09:31:24.966770 kernel: raid6: neonx1 xor() 8833 MB/s Nov 8 09:31:24.983773 kernel: raid6: int64x8 gen() 6839 MB/s Nov 8 09:31:25.000776 kernel: raid6: int64x8 xor() 3801 MB/s Nov 8 09:31:25.017774 kernel: raid6: int64x4 gen() 6921 MB/s Nov 8 09:31:25.034777 kernel: raid6: int64x4 xor() 3837 MB/s Nov 8 09:31:25.051778 kernel: raid6: int64x2 gen() 6193 MB/s Nov 8 09:31:25.068770 kernel: raid6: int64x2 xor() 3317 MB/s Nov 8 09:31:25.085772 kernel: raid6: int64x1 gen() 5014 MB/s Nov 8 09:31:25.102931 kernel: raid6: int64x1 xor() 2597 MB/s Nov 8 09:31:25.102951 kernel: raid6: using algorithm neonx8 gen() 13807 MB/s Nov 8 09:31:25.102959 kernel: raid6: .... xor() 10756 MB/s, rmw enabled Nov 8 09:31:25.104066 kernel: raid6: using neon recovery algorithm Nov 8 09:31:25.113775 kernel: xor: measuring software checksum speed Nov 8 09:31:25.114778 kernel: 8regs : 17156 MB/sec Nov 8 09:31:25.116267 kernel: 32regs : 20755 MB/sec Nov 8 09:31:25.116277 kernel: arm64_neon : 29163 MB/sec Nov 8 09:31:25.116285 kernel: xor: using function: arm64_neon (29163 MB/sec) Nov 8 09:31:25.161780 kernel: Btrfs loaded, crc32c=crc32c-generic Nov 8 09:31:25.171582 systemd[1]: Finished dracut pre-udev hook. Nov 8 09:31:25.175837 kernel: audit: type=1334 audit(1762594285.172:2): prog-id=6 op=LOAD Nov 8 09:31:25.175858 kernel: audit: type=1334 audit(1762594285.173:3): prog-id=7 op=LOAD Nov 8 09:31:25.175868 kernel: audit: type=1334 audit(1762594285.174:4): prog-id=8 op=LOAD Nov 8 09:31:25.172000 audit: BPF prog-id=6 op=LOAD Nov 8 09:31:25.173000 audit: BPF prog-id=7 op=LOAD Nov 8 09:31:25.174000 audit: BPF prog-id=8 op=LOAD Nov 8 09:31:25.175381 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 8 09:31:25.187231 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 8 09:31:25.188967 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 8 09:31:25.192691 systemd[1]: Starting dracut pre-trigger hook... Nov 8 09:31:25.204235 dracut-pre-trigger[419]: rd.md=0: removing MD RAID activation Nov 8 09:31:25.226427 systemd[1]: Finished dracut pre-trigger hook. Nov 8 09:31:25.227957 systemd[1]: Starting Coldplug All udev Devices... Nov 8 09:31:25.237321 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 8 09:31:25.256789 systemd[1]: Finished Coldplug All udev Devices. Nov 8 09:31:25.258591 systemd[1]: Starting dracut initqueue hook... Nov 8 09:31:25.279152 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 8 09:31:25.279292 kernel: vda: detected capacity change from 0 to 4756340736 Nov 8 09:31:25.288618 systemd-udevd[454]: Using default interface naming scheme 'v249'. Nov 8 09:31:25.294772 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 8 09:31:25.316783 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (457) Nov 8 09:31:25.319717 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 8 09:31:25.320833 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Nov 8 09:31:25.324499 systemd[1]: Found device /dev/disk/by-label/ROOT. Nov 8 09:31:25.327259 systemd[1]: Reached target Initrd Root Device. Nov 8 09:31:25.328719 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Nov 8 09:31:25.329926 systemd[1]: Finished dracut initqueue hook. Nov 8 09:31:25.331562 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 8 09:31:25.331672 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Nov 8 09:31:25.333142 systemd[1]: Reached target Preparation for Local File Systems. Nov 8 09:31:25.334693 systemd[1]: Reached target Preparation for Remote File Systems. Nov 8 09:31:25.336607 systemd[1]: Reached target Remote Encrypted Volumes. Nov 8 09:31:25.338334 systemd[1]: Reached target Remote File Systems. Nov 8 09:31:25.340612 systemd[1]: Starting dracut pre-mount hook... Nov 8 09:31:25.341921 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Nov 8 09:31:25.348346 systemd[1]: Finished dracut pre-mount hook. Nov 8 09:31:25.349914 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Nov 8 09:31:25.355769 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 8 09:31:25.359819 systemd-fsck[504]: ROOT: clean, 640/553792 files, 39961/553472 blocks Nov 8 09:31:25.363647 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Nov 8 09:31:25.365601 systemd[1]: Mounting /sysroot... Nov 8 09:31:25.379571 systemd[1]: Found device /dev/mapper/usr. Nov 8 09:31:25.381575 systemd[1]: Starting File System Check on /dev/mapper/usr... Nov 8 09:31:25.383484 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Nov 8 09:31:25.391234 systemd-fsck[516]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Nov 8 09:31:25.391234 systemd-fsck[516]: You must have r/w access to the filesystem or be root Nov 8 09:31:25.394183 systemd-fsck[513]: fsck failed with exit status 8. Nov 8 09:31:25.394192 systemd-fsck[513]: Ignoring error. Nov 8 09:31:25.394807 systemd[1]: Finished File System Check on /dev/mapper/usr. Nov 8 09:31:25.396962 systemd[1]: Mounting /sysusr/usr... Nov 8 09:31:25.404466 systemd[1]: Mounted /sysusr/usr. Nov 8 09:31:25.405707 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Nov 8 09:31:25.405344 systemd[1]: Reached target Local File Systems. Nov 8 09:31:25.406482 systemd[1]: Reached target System Initialization. Nov 8 09:31:25.407926 systemd[1]: Reached target Basic System. Nov 8 09:31:25.412304 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Nov 8 09:31:25.412333 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Nov 8 09:31:25.413033 systemd[1]: Mounted /sysroot. Nov 8 09:31:25.413718 systemd[1]: Reached target Initrd Root File System. Nov 8 09:31:25.416378 systemd[1]: Mounting /sysroot/usr... Nov 8 09:31:25.417524 systemd[1]: Starting Reload Configuration from the Real Root... Nov 8 09:31:25.419043 systemd[1]: Mounted /sysroot/usr. Nov 8 09:31:25.421736 systemd[1]: Reloading. Nov 8 09:31:25.422000 audit: BPF prog-id=6 op=UNLOAD Nov 8 09:31:25.424772 kernel: audit: type=1334 audit(1762594285.422:5): prog-id=6 op=UNLOAD Nov 8 09:31:25.428000 audit: BPF prog-id=3 op=UNLOAD Nov 8 09:31:25.430797 kernel: audit: type=1334 audit(1762594285.428:6): prog-id=3 op=UNLOAD Nov 8 09:31:25.506000 audit: BPF prog-id=9 op=LOAD Nov 8 09:31:25.507000 audit: BPF prog-id=10 op=LOAD Nov 8 09:31:25.507000 audit: BPF prog-id=11 op=LOAD Nov 8 09:31:25.509488 kernel: audit: type=1334 audit(1762594285.506:7): prog-id=9 op=LOAD Nov 8 09:31:25.509509 kernel: audit: type=1334 audit(1762594285.507:8): prog-id=10 op=LOAD Nov 8 09:31:25.509518 kernel: audit: type=1334 audit(1762594285.507:9): prog-id=11 op=LOAD Nov 8 09:31:25.509532 kernel: audit: type=1334 audit(1762594285.507:10): prog-id=7 op=UNLOAD Nov 8 09:31:25.507000 audit: BPF prog-id=7 op=UNLOAD Nov 8 09:31:25.507000 audit: BPF prog-id=8 op=UNLOAD Nov 8 09:31:25.508000 audit: BPF prog-id=12 op=LOAD Nov 8 09:31:25.509000 audit: BPF prog-id=13 op=LOAD Nov 8 09:31:25.509000 audit: BPF prog-id=14 op=LOAD Nov 8 09:31:25.509000 audit: BPF prog-id=4 op=UNLOAD Nov 8 09:31:25.509000 audit: BPF prog-id=5 op=UNLOAD Nov 8 09:31:25.523622 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 8 09:31:25.523749 systemd[1]: Finished Reload Configuration from the Real Root. Nov 8 09:31:25.525707 systemd[1]: Reached target Initrd File Systems. Nov 8 09:31:25.527312 systemd[1]: Reached target Initrd Default Target. Nov 8 09:31:25.528774 systemd[1]: Condition check resulted in dracut mount hook being skipped. Nov 8 09:31:25.529415 systemd[1]: Starting dracut pre-pivot and cleanup hook... Nov 8 09:31:25.539209 systemd[1]: Finished dracut pre-pivot and cleanup hook. Nov 8 09:31:25.540828 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Nov 8 09:31:25.546600 systemd[1]: Stopped target Remote Encrypted Volumes. Nov 8 09:31:25.547630 systemd[1]: Stopped target Timer Units. Nov 8 09:31:25.549056 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 8 09:31:25.549169 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Nov 8 09:31:25.550583 systemd[1]: Stopped target Initrd Default Target. Nov 8 09:31:25.552259 systemd[1]: Stopped target Basic System. Nov 8 09:31:25.553691 systemd[1]: Stopped target Initrd Root Device. Nov 8 09:31:25.555161 systemd[1]: Stopped target Path Units. Nov 8 09:31:25.556438 systemd[1]: Stopped target Remote File Systems. Nov 8 09:31:25.557854 systemd[1]: Stopped target Preparation for Remote File Systems. Nov 8 09:31:25.559393 systemd[1]: Stopped target Slice Units. Nov 8 09:31:25.560693 systemd[1]: Stopped target Socket Units. Nov 8 09:31:25.562248 systemd[1]: Stopped target System Initialization. Nov 8 09:31:25.563641 systemd[1]: Stopped target Local File Systems. Nov 8 09:31:25.565025 systemd[1]: Stopped target Preparation for Local File Systems. Nov 8 09:31:25.566623 systemd[1]: Stopped target Swaps. Nov 8 09:31:25.567901 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 8 09:31:25.568008 systemd[1]: Stopped dracut pre-mount hook. Nov 8 09:31:25.569472 systemd[1]: Stopped target Local Encrypted Volumes. Nov 8 09:31:25.570692 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 8 09:31:25.574814 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Nov 8 09:31:25.575970 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 8 09:31:25.576082 systemd[1]: Stopped dracut initqueue hook. Nov 8 09:31:25.581020 iscsid[376]: iscsid shutting down. Nov 8 09:31:25.577719 systemd[1]: Stopping Open-iSCSI... Nov 8 09:31:25.580363 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 8 09:31:25.580478 systemd[1]: Stopped Apply Kernel Variables. Nov 8 09:31:25.581778 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 8 09:31:25.581860 systemd[1]: Stopped Coldplug All udev Devices. Nov 8 09:31:25.583089 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 8 09:31:25.583173 systemd[1]: Stopped dracut pre-trigger hook. Nov 8 09:31:25.586089 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Nov 8 09:31:25.589447 systemd[1]: iscsid.service: Deactivated successfully. Nov 8 09:31:25.589551 systemd[1]: Stopped Open-iSCSI. Nov 8 09:31:25.591069 systemd[1]: iscsid.socket: Deactivated successfully. Nov 8 09:31:25.591144 systemd[1]: Closed Open-iSCSI iscsid Socket. Nov 8 09:31:25.601000 audit: BPF prog-id=9 op=UNLOAD Nov 8 09:31:25.593319 systemd[1]: Stopping iSCSI UserSpace I/O driver... Nov 8 09:31:25.594810 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 8 09:31:25.594924 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Nov 8 09:31:25.596839 systemd[1]: iscsiuio.service: Deactivated successfully. Nov 8 09:31:25.596937 systemd[1]: Stopped iSCSI UserSpace I/O driver. Nov 8 09:31:25.598404 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 8 09:31:25.598505 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Nov 8 09:31:25.600335 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 8 09:31:25.600380 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Nov 8 09:31:25.601287 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 8 09:31:25.601323 systemd[1]: Closed udev Control Socket. Nov 8 09:31:25.602550 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 8 09:31:25.602581 systemd[1]: Closed udev Kernel Socket. Nov 8 09:31:25.603903 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 8 09:31:25.603942 systemd[1]: Stopped dracut pre-udev hook. Nov 8 09:31:25.605464 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 8 09:31:25.605501 systemd[1]: Stopped dracut cmdline hook. Nov 8 09:31:25.607093 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 8 09:31:25.607131 systemd[1]: Stopped dracut ask for additional cmdline parameters. Nov 8 09:31:25.609352 systemd[1]: Starting Cleanup udev Database... Nov 8 09:31:25.610202 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Nov 8 09:31:25.628000 audit: BPF prog-id=12 op=UNLOAD Nov 8 09:31:25.611594 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 8 09:31:25.611648 systemd[1]: Stopped Create Static Device Nodes in /dev. Nov 8 09:31:25.613274 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 8 09:31:25.613317 systemd[1]: Stopped Create List of Static Device Nodes. Nov 8 09:31:25.614882 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 8 09:31:25.614921 systemd[1]: Stopped Setup Virtual Console. Nov 8 09:31:25.616449 systemd[1]: rngd.service: Deactivated successfully. Nov 8 09:31:25.616560 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Nov 8 09:31:25.618125 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 8 09:31:25.618221 systemd[1]: Finished Cleanup udev Database. Nov 8 09:31:25.619407 systemd[1]: Reached target Switch Root. Nov 8 09:31:25.621421 systemd[1]: Starting Switch Root... Nov 8 09:31:25.626963 systemd[1]: Switching root. Nov 8 09:31:25.642934 systemd-journald[270]: Journal stopped Nov 8 09:31:27.681518 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Nov 8 09:31:27.681561 kernel: SELinux: policy capability network_peer_controls=1 Nov 8 09:31:27.681573 kernel: SELinux: policy capability open_perms=1 Nov 8 09:31:27.681590 kernel: SELinux: policy capability extended_socket_class=1 Nov 8 09:31:27.681605 kernel: SELinux: policy capability always_check_network=0 Nov 8 09:31:27.681614 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 8 09:31:27.681623 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 8 09:31:27.681633 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 8 09:31:27.681642 systemd[1]: Successfully loaded SELinux policy in 31.717ms. Nov 8 09:31:27.681669 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.039ms. Nov 8 09:31:27.681680 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) Nov 8 09:31:27.681690 systemd[1]: Detected virtualization kvm. Nov 8 09:31:27.681701 systemd[1]: Detected architecture arm64. Nov 8 09:31:27.681713 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. Nov 8 09:31:27.681723 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 8 09:31:27.681733 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 8 09:31:27.681743 systemd[1]: Stopped Switch Root. Nov 8 09:31:27.681752 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 8 09:31:27.681784 systemd[1]: Created slice Slice /system/addon-config. Nov 8 09:31:27.681794 systemd[1]: Created slice Slice /system/addon-run. Nov 8 09:31:27.681804 systemd[1]: Created slice Slice /system/getty. Nov 8 09:31:27.681814 systemd[1]: Created slice Slice /system/modprobe. Nov 8 09:31:27.681823 systemd[1]: Created slice Slice /system/serial-getty. Nov 8 09:31:27.681833 systemd[1]: Created slice Slice /system/system-cloudinit. Nov 8 09:31:27.681842 systemd[1]: Created slice Slice /system/systemd-fsck. Nov 8 09:31:27.681851 systemd[1]: Created slice User and Session Slice. Nov 8 09:31:27.681860 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 8 09:31:27.681870 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Nov 8 09:31:27.681879 systemd[1]: Set up automount Boot partition Automount Point. Nov 8 09:31:27.681890 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Nov 8 09:31:27.681900 systemd[1]: Stopped target Switch Root. Nov 8 09:31:27.681909 systemd[1]: Stopped target Initrd File Systems. Nov 8 09:31:27.681919 systemd[1]: Stopped target Initrd Root File System. Nov 8 09:31:27.681928 systemd[1]: Reached target Remote Encrypted Volumes. Nov 8 09:31:27.681937 systemd[1]: Reached target Remote File Systems. Nov 8 09:31:27.681947 systemd[1]: Reached target Slice Units. Nov 8 09:31:27.681956 systemd[1]: Reached target Swaps. Nov 8 09:31:27.681966 systemd[1]: Reached target Verify torcx succeeded. Nov 8 09:31:27.681976 systemd[1]: Reached target Local Verity Protected Volumes. Nov 8 09:31:27.681986 systemd[1]: Listening on Process Core Dump Socket. Nov 8 09:31:27.681996 systemd[1]: Listening on initctl Compatibility Named Pipe. Nov 8 09:31:27.682005 systemd[1]: Listening on Network Service Netlink Socket. Nov 8 09:31:27.682015 systemd[1]: Listening on udev Control Socket. Nov 8 09:31:27.682024 systemd[1]: Listening on udev Kernel Socket. Nov 8 09:31:27.682034 systemd[1]: Mounting Huge Pages File System... Nov 8 09:31:27.682043 systemd[1]: Mounting POSIX Message Queue File System... Nov 8 09:31:27.682052 systemd[1]: Mounting External Media Directory... Nov 8 09:31:27.682062 systemd[1]: Mounting Kernel Debug File System... Nov 8 09:31:27.682079 systemd[1]: Mounting Kernel Trace File System... Nov 8 09:31:27.682089 systemd[1]: Mounting Temporary Directory /tmp... Nov 8 09:31:27.682115 systemd[1]: Starting Create missing system files... Nov 8 09:31:27.682137 systemd[1]: Starting Create List of Static Device Nodes... Nov 8 09:31:27.682152 systemd[1]: Starting Load Kernel Module configfs... Nov 8 09:31:27.682163 systemd[1]: Starting Load Kernel Module drm... Nov 8 09:31:27.682173 systemd[1]: Starting Load Kernel Module fuse... Nov 8 09:31:27.682184 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Nov 8 09:31:27.682194 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 8 09:31:27.682204 systemd[1]: Stopped File System Check on Root Device. Nov 8 09:31:27.682216 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 8 09:31:27.682226 systemd[1]: Stopped systemd-fsck-usr.service. Nov 8 09:31:27.682235 kernel: fuse: init (API version 7.32) Nov 8 09:31:27.682244 systemd[1]: Stopped Journal Service. Nov 8 09:31:27.682253 systemd[1]: Starting Journal Service... Nov 8 09:31:27.682263 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 8 09:31:27.682272 systemd[1]: Starting Remount Root and Kernel File Systems... Nov 8 09:31:27.682281 systemd[1]: Starting Apply Kernel Variables... Nov 8 09:31:27.682291 systemd[1]: Starting Coldplug All udev Devices... Nov 8 09:31:27.682300 systemd[1]: verity-setup.service: Deactivated successfully. Nov 8 09:31:27.682312 systemd-journald[694]: Journal started Nov 8 09:31:27.682351 systemd-journald[694]: Runtime Journal (/run/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194) is 6.0M, max 48.5M, 42.4M free. Nov 8 09:31:25.721000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 8 09:31:25.747000 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 Nov 8 09:31:25.747000 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 Nov 8 09:31:25.747000 audit: BPF prog-id=15 op=LOAD Nov 8 09:31:25.747000 audit: BPF prog-id=15 op=UNLOAD Nov 8 09:31:25.747000 audit: BPF prog-id=16 op=LOAD Nov 8 09:31:25.747000 audit: BPF prog-id=16 op=UNLOAD Nov 8 09:31:25.777000 audit[645]: AVC avc: denied { associate } for pid=645 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Nov 8 09:31:27.570000 audit: BPF prog-id=17 op=LOAD Nov 8 09:31:27.570000 audit: BPF prog-id=18 op=LOAD Nov 8 09:31:27.570000 audit: BPF prog-id=19 op=LOAD Nov 8 09:31:27.570000 audit: BPF prog-id=13 op=UNLOAD Nov 8 09:31:27.570000 audit: BPF prog-id=14 op=UNLOAD Nov 8 09:31:27.584000 audit: BPF prog-id=17 op=UNLOAD Nov 8 09:31:27.669000 audit: BPF prog-id=20 op=LOAD Nov 8 09:31:27.669000 audit: BPF prog-id=21 op=LOAD Nov 8 09:31:27.669000 audit: BPF prog-id=22 op=LOAD Nov 8 09:31:27.669000 audit: BPF prog-id=18 op=UNLOAD Nov 8 09:31:27.669000 audit: BPF prog-id=19 op=UNLOAD Nov 8 09:31:27.679000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Nov 8 09:31:27.559525 systemd[1]: Queued start job for default target Multi-User System. Nov 8 09:31:25.775857 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" 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]" Nov 8 09:31:27.571637 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 8 09:31:25.776162 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 8 09:31:25.776188 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 8 09:31:25.776461 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Nov 8 09:31:25.776472 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=debug msg="skipped missing lower profile" missing profile=oem Nov 8 09:31:25.776504 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Nov 8 09:31:25.776517 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Nov 8 09:31:25.776814 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Nov 8 09:31:25.776850 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 8 09:31:25.776865 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 8 09:31:25.777207 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" 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 Nov 8 09:31:25.777238 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" 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 Nov 8 09:31:27.683901 systemd[1]: Stopped verity-setup.service. Nov 8 09:31:25.777313 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" 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 Nov 8 09:31:25.777332 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Nov 8 09:31:25.777419 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" 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 Nov 8 09:31:25.777434 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:25Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Nov 8 09:31:27.473222 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:27Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 8 09:31:27.473484 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:27Z" 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 Nov 8 09:31:27.473590 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:27Z" 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 Nov 8 09:31:27.473712 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:27Z" 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 Nov 8 09:31:27.473823 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:27Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Nov 8 09:31:27.473883 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-08T09:31:27Z" 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 Nov 8 09:31:27.687783 systemd[1]: Started Journal Service. Nov 8 09:31:27.688143 systemd[1]: Mounted Huge Pages File System. Nov 8 09:31:27.689188 systemd[1]: Mounted POSIX Message Queue File System. Nov 8 09:31:27.690187 systemd[1]: Mounted External Media Directory. Nov 8 09:31:27.691101 systemd[1]: Mounted Kernel Debug File System. Nov 8 09:31:27.691989 systemd[1]: Mounted Kernel Trace File System. Nov 8 09:31:27.692889 systemd[1]: Mounted Temporary Directory /tmp. Nov 8 09:31:27.694050 systemd[1]: Finished Create missing system files. Nov 8 09:31:27.695226 systemd[1]: Finished Create List of Static Device Nodes. Nov 8 09:31:27.696373 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 8 09:31:27.696573 systemd[1]: Finished Load Kernel Module configfs. Nov 8 09:31:27.697688 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 8 09:31:27.697915 systemd[1]: Finished Load Kernel Module drm. Nov 8 09:31:27.698926 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 8 09:31:27.699110 systemd[1]: Finished Load Kernel Module fuse. Nov 8 09:31:27.700206 systemd[1]: Finished Remount Root and Kernel File Systems. Nov 8 09:31:27.715169 systemd[1]: Finished Apply Kernel Variables. Nov 8 09:31:27.717978 systemd[1]: Mounting FUSE Control File System... Nov 8 09:31:27.719710 systemd[1]: Mounting Kernel Configuration File System... Nov 8 09:31:27.720672 systemd[1]: Condition check resulted in Remount Root File System being skipped. Nov 8 09:31:27.721611 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 8 09:31:27.722531 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 8 09:31:27.723567 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Nov 8 09:31:27.724559 systemd[1]: Starting Load/Save Random Seed... Nov 8 09:31:27.725620 systemd[1]: Condition check resulted in Create System Users being skipped. Nov 8 09:31:27.728048 systemd-journald[694]: Time spent on flushing to /var/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194 is 13.252ms for 655 entries. Nov 8 09:31:27.728048 systemd-journald[694]: System Journal (/var/log/journal/cfcf38a4aa3a4fb8b40ef5a18bc01194) is 8.0M, max 203.0M, 195.0M free. Nov 8 09:31:27.744000 audit: BPF prog-id=23 op=LOAD Nov 8 09:31:27.744000 audit: BPF prog-id=24 op=LOAD Nov 8 09:31:27.744000 audit: BPF prog-id=25 op=LOAD Nov 8 09:31:27.744000 audit: BPF prog-id=10 op=UNLOAD Nov 8 09:31:27.744000 audit: BPF prog-id=11 op=UNLOAD Nov 8 09:31:27.726593 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 8 09:31:27.728511 systemd[1]: Mounted FUSE Control File System. Nov 8 09:31:27.730279 systemd[1]: Mounted Kernel Configuration File System. Nov 8 09:31:27.734690 systemd[1]: Finished Load/Save Random Seed. Nov 8 09:31:27.736316 systemd[1]: Finished Coldplug All udev Devices. Nov 8 09:31:27.737438 systemd[1]: Condition check resulted in First Boot Complete being skipped. Nov 8 09:31:27.741812 systemd[1]: Starting Wait for udev To Complete Device Initialization... Nov 8 09:31:27.743258 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 8 09:31:27.745603 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 8 09:31:27.753410 systemd[1]: Finished Flush Journal to Persistent Storage. Nov 8 09:31:27.774904 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 8 09:31:27.776000 audit: BPF prog-id=26 op=LOAD Nov 8 09:31:27.777801 systemd[1]: Starting Network Configuration... Nov 8 09:31:27.797679 systemd-udevd[720]: Using default interface naming scheme 'v249'. Nov 8 09:31:27.807810 systemd-networkd[727]: lo: Link UP Nov 8 09:31:27.807816 systemd-networkd[727]: lo: Gained carrier Nov 8 09:31:27.808144 systemd-networkd[727]: Enumeration completed Nov 8 09:31:27.808271 systemd[1]: Started Network Configuration. Nov 8 09:31:27.810347 systemd-networkd[727]: eth0: Link UP Nov 8 09:31:27.819872 systemd-networkd[727]: eth0: Gained carrier Nov 8 09:31:27.824353 udevadm[705]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 8 09:31:27.825954 systemd-networkd[727]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Nov 8 09:31:27.845873 systemd[1]: Finished Wait for udev To Complete Device Initialization. Nov 8 09:31:27.858607 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 8 09:31:27.866574 lvm[735]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 09:31:27.894621 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 8 09:31:27.895706 systemd[1]: Reached target Local Encrypted Volumes. Nov 8 09:31:27.908706 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 8 09:31:27.911864 lvm[736]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 09:31:27.937665 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 8 09:31:27.938768 systemd[1]: Reached target Preparation for Local File Systems. Nov 8 09:31:27.939784 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Nov 8 09:31:27.939805 systemd[1]: Reached target Containers. Nov 8 09:31:27.949997 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Nov 8 09:31:27.962011 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Nov 8 09:31:27.964063 systemd[1]: Mounting /usr/share/oem... Nov 8 09:31:27.969818 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 8 09:31:27.969894 kernel: BTRFS info (device vda6): has skinny extents Nov 8 09:31:27.971909 systemd[1]: Mounted /usr/share/oem. Nov 8 09:31:27.972674 systemd[1]: Reached target Local File Systems. Nov 8 09:31:27.973671 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Nov 8 09:31:27.974220 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 8 09:31:27.974290 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Nov 8 09:31:27.974341 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 8 09:31:27.975419 systemd[1]: Starting Create Volatile Files and Directories... Nov 8 09:31:27.981133 systemd-tmpfiles[759]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Nov 8 09:31:27.982202 systemd-tmpfiles[759]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Nov 8 09:31:28.008720 systemd-tmpfiles[759]: Detected autofs mount point /boot during canonicalization of /boot. Nov 8 09:31:28.008732 systemd-tmpfiles[759]: Skipping /boot Nov 8 09:31:28.013947 systemd-tmpfiles[759]: Detected autofs mount point /boot during canonicalization of /boot. Nov 8 09:31:28.013961 systemd-tmpfiles[759]: Skipping /boot Nov 8 09:31:28.041790 systemd[1]: Finished Create Volatile Files and Directories. Nov 8 09:31:28.050630 systemd[1]: Starting Load Security Auditing Rules... Nov 8 09:31:28.052469 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Nov 8 09:31:28.053564 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Nov 8 09:31:28.053000 audit: BPF prog-id=27 op=LOAD Nov 8 09:31:28.055163 systemd[1]: Starting Network Name Resolution... Nov 8 09:31:28.055000 audit: BPF prog-id=28 op=LOAD Nov 8 09:31:28.057674 systemd[1]: Starting Network Time Synchronization... Nov 8 09:31:28.058830 systemd[1]: Condition check resulted in Update is Completed being skipped. Nov 8 09:31:28.061517 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Nov 8 09:31:28.062949 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Nov 8 09:31:28.064248 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Nov 8 09:31:28.068795 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Nov 8 09:31:28.079000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Nov 8 09:31:28.080560 augenrules[780]: No rules Nov 8 09:31:28.081379 systemd[1]: Finished Load Security Auditing Rules. Nov 8 09:31:27.021937 systemd-timesyncd[769]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Nov 8 09:31:27.025807 systemd-resolved[763]: Positive Trust Anchors: Nov 8 09:31:27.025815 systemd-resolved[763]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 8 09:31:27.025842 systemd-resolved[763]: 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 Nov 8 09:31:27.026666 systemd-resolved[763]: Defaulting to hostname 'linux'. Nov 8 09:31:27.031343 systemd[1]: Started Network Time Synchronization. Nov 8 09:31:27.032549 systemd[1]: Started Network Name Resolution. Nov 8 09:31:27.033586 systemd[1]: Reached target Network. Nov 8 09:31:27.034387 systemd[1]: Reached target Host and Network Name Lookups. Nov 8 09:31:27.035430 systemd[1]: Reached target System Initialization. Nov 8 09:31:27.036481 systemd[1]: Started Watch for update engine configuration changes. Nov 8 09:31:27.037726 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 8 09:31:27.038968 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 8 09:31:27.041609 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Nov 8 09:31:27.041640 systemd[1]: Reached target Path Units. Nov 8 09:31:27.042499 systemd[1]: Reached target System Time Set. Nov 8 09:31:27.045402 systemd[1]: Started Daily Log Rotation. Nov 8 09:31:27.046345 systemd[1]: Started Weekly check for MD array's redundancy information.. Nov 8 09:31:27.047511 systemd[1]: Reached target Timer Units. Nov 8 09:31:27.048782 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 8 09:31:27.056772 systemd[1]: Starting Docker Socket for the API... Nov 8 09:31:27.059859 systemd[1]: Listening on OpenSSH Server Socket. Nov 8 09:31:27.061169 systemd[1]: Listening on Docker Socket for the API. Nov 8 09:31:27.062070 systemd[1]: Reached target Socket Units. Nov 8 09:31:27.062893 systemd[1]: Reached target Basic System. Nov 8 09:31:27.063714 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Nov 8 09:31:27.063746 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Nov 8 09:31:27.064669 systemd[1]: Started D-Bus System Message Bus. Nov 8 09:31:27.068102 systemd[1]: Starting Extend Filesystems... Nov 8 09:31:27.068970 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Nov 8 09:31:27.070009 systemd[1]: Starting Generate /run/flatcar/motd... Nov 8 09:31:27.071942 systemd[1]: Starting Install an ssh key from /proc/cmdline... Nov 8 09:31:27.074527 systemd[1]: Starting Generate sshd host keys... Nov 8 09:31:27.075805 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Nov 8 09:31:27.075869 systemd[1]: Reached target Load system-provided cloud configs. Nov 8 09:31:27.079373 systemd[1]: Starting User Login Management... Nov 8 09:31:27.080477 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 8 09:31:27.081061 systemd[1]: Starting Update Engine... Nov 8 09:31:27.081837 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Nov 8 09:31:27.081859 systemd[1]: Reached target Load user-provided cloud configs. Nov 8 09:31:27.083776 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 8 09:31:27.083996 systemd[1]: Finished Install an ssh key from /proc/cmdline. Nov 8 09:31:27.084528 extend-filesystems[790]: Found vda Nov 8 09:31:27.084528 extend-filesystems[790]: Found vda1 Nov 8 09:31:27.086694 extend-filesystems[790]: Found vda2 Nov 8 09:31:27.086694 extend-filesystems[790]: Found vda3 Nov 8 09:31:27.086694 extend-filesystems[790]: Found usr Nov 8 09:31:27.086694 extend-filesystems[790]: Found vda4 Nov 8 09:31:27.086694 extend-filesystems[790]: Found vda6 Nov 8 09:31:27.086694 extend-filesystems[790]: Found vda7 Nov 8 09:31:27.086694 extend-filesystems[790]: Found vda9 Nov 8 09:31:27.086694 extend-filesystems[790]: Checking size of /dev/vda9 Nov 8 09:31:27.085916 systemd[1]: motdgen.service: Deactivated successfully. Nov 8 09:31:27.107681 extend-filesystems[790]: Old size kept for /dev/vda9 Nov 8 09:31:27.086121 systemd[1]: Finished Generate /run/flatcar/motd. Nov 8 09:31:27.104480 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 8 09:31:27.104696 systemd[1]: Finished Extend Filesystems. Nov 8 09:31:27.125738 systemd-logind[803]: Watching system buttons on /dev/input/event0 (Power Button) Nov 8 09:31:27.126216 systemd-logind[803]: New seat seat0. Nov 8 09:31:27.129314 systemd[1]: Started User Login Management. Nov 8 09:31:27.130068 update_engine[805]: I1108 09:31:27.129814 805 main.cc:89] Flatcar Update Engine starting Nov 8 09:31:27.130888 update_engine[805]: I1108 09:31:27.130863 805 payload_state.cc:360] Current Response Signature = Nov 8 09:31:27.130888 update_engine[805]: NumURLs = 1 Nov 8 09:31:27.130888 update_engine[805]: Url0 = http://10.0.0.3:34567/packages/update.gz Nov 8 09:31:27.130888 update_engine[805]: Payload Size = 420852543 Nov 8 09:31:27.130888 update_engine[805]: Payload Sha256 Hash = R5b5k53y1oVPdKrjN61IArcbBwUMF9e/B1Zefwv2T78= Nov 8 09:31:27.130888 update_engine[805]: Is Delta Payload = 0 Nov 8 09:31:27.130888 update_engine[805]: Max Failure Count Per Url = 10 Nov 8 09:31:27.130888 update_engine[805]: Disable Payload Backoff = 1 Nov 8 09:31:27.131076 update_engine[805]: I1108 09:31:27.131065 805 payload_state.cc:381] Payload Attempt Number = 0 Nov 8 09:31:27.131273 update_engine[805]: I1108 09:31:27.131254 805 payload_state.cc:404] Current URL Index = 0 Nov 8 09:31:27.131429 update_engine[805]: I1108 09:31:27.131413 805 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Nov 8 09:31:27.131605 update_engine[805]: I1108 09:31:27.131572 805 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Nov 8 09:31:27.133543 systemd[1]: Started Update Engine. Nov 8 09:31:27.133809 update_engine[805]: I1108 09:31:27.133781 805 update_check_scheduler.cc:74] Next update check in 6m39s Nov 8 09:31:27.140343 systemd[1]: Finished Generate sshd host keys. Nov 8 09:31:27.152217 systemd[1]: Starting Generate /run/issue... Nov 8 09:31:27.156385 systemd[1]: issuegen.service: Deactivated successfully. Nov 8 09:31:27.156609 systemd[1]: Finished Generate /run/issue. Nov 8 09:31:27.158566 systemd[1]: Starting Permit User Sessions... Nov 8 09:31:27.162788 systemd[1]: Finished Permit User Sessions. Nov 8 09:31:27.164759 systemd[1]: Started Getty on tty1. Nov 8 09:31:27.166677 systemd[1]: Started Serial Getty on ttyAMA0. Nov 8 09:31:27.167645 systemd[1]: Reached target Login Prompts. Nov 8 09:31:27.168551 systemd[1]: Reached target Multi-User System. Nov 8 09:31:27.170401 systemd[1]: Starting Record Runlevel Change in UTMP... Nov 8 09:31:27.175273 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Nov 8 09:31:27.175488 systemd[1]: Finished Record Runlevel Change in UTMP. Nov 8 09:31:27.176495 systemd[1]: Startup finished in 715ms (kernel) + 1.127s (initrd) + 2.563s (userspace) = 4.405s. Nov 8 09:31:28.755345 systemd-networkd[727]: eth0: Gained IPv6LL Nov 8 09:31:36.909095 systemd[1]: Created slice Slice /system/sshd. Nov 8 09:31:36.910229 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36960). Nov 8 09:31:36.951309 sshd[829]: Accepted publickey for core from 10.0.0.1 port 36960 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:36.953295 sshd[829]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:36.965575 systemd[1]: Created slice User Slice of UID 500. Nov 8 09:31:36.966508 systemd[1]: Starting User Runtime Directory /run/user/500... Nov 8 09:31:36.968002 systemd-logind[803]: New session 1 of user core. Nov 8 09:31:36.972182 systemd[1]: Finished User Runtime Directory /run/user/500. Nov 8 09:31:36.973238 systemd[1]: Starting User Manager for UID 500... Nov 8 09:31:36.975300 systemd[832]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.021810 systemd[832]: Queued start job for default target Main User Target. Nov 8 09:31:37.022262 systemd[832]: Reached target Paths. Nov 8 09:31:37.022287 systemd[832]: Reached target Sockets. Nov 8 09:31:37.022298 systemd[832]: Reached target Timers. Nov 8 09:31:37.022309 systemd[832]: Reached target Basic System. Nov 8 09:31:37.022354 systemd[832]: Reached target Main User Target. Nov 8 09:31:37.022370 systemd[832]: Startup finished in 43ms. Nov 8 09:31:37.022444 systemd[1]: Started User Manager for UID 500. Nov 8 09:31:37.037269 systemd[1]: Started Session 1 of User core. Nov 8 09:31:37.098039 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36964). Nov 8 09:31:37.135410 sshd[841]: Accepted publickey for core from 10.0.0.1 port 36964 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:37.136398 sshd[841]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.139188 systemd-logind[803]: New session 2 of user core. Nov 8 09:31:37.148298 systemd[1]: Started Session 2 of User core. Nov 8 09:31:37.199091 sshd[841]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:37.217033 systemd[1]: sshd@1-10.0.0.4:22-10.0.0.1:36964.service: Deactivated successfully. Nov 8 09:31:37.217612 systemd[1]: session-2.scope: Deactivated successfully. Nov 8 09:31:37.218194 systemd-logind[803]: Session 2 logged out. Waiting for processes to exit. Nov 8 09:31:37.219102 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36970). Nov 8 09:31:37.219772 systemd-logind[803]: Removed session 2. Nov 8 09:31:37.258710 sshd[847]: Accepted publickey for core from 10.0.0.1 port 36970 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:37.259696 sshd[847]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.262509 systemd-logind[803]: New session 3 of user core. Nov 8 09:31:37.273290 systemd[1]: Started Session 3 of User core. Nov 8 09:31:37.320305 sshd[847]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:37.329698 systemd[1]: sshd@2-10.0.0.4:22-10.0.0.1:36970.service: Deactivated successfully. Nov 8 09:31:37.330246 systemd[1]: session-3.scope: Deactivated successfully. Nov 8 09:31:37.330841 systemd-logind[803]: Session 3 logged out. Waiting for processes to exit. Nov 8 09:31:37.331802 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36976). Nov 8 09:31:37.332375 systemd-logind[803]: Removed session 3. Nov 8 09:31:37.371605 sshd[853]: Accepted publickey for core from 10.0.0.1 port 36976 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:37.372558 sshd[853]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.375151 systemd-logind[803]: New session 4 of user core. Nov 8 09:31:37.381289 systemd[1]: Started Session 4 of User core. Nov 8 09:31:37.431248 sshd[853]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:37.438910 systemd[1]: sshd@3-10.0.0.4:22-10.0.0.1:36976.service: Deactivated successfully. Nov 8 09:31:37.439576 systemd[1]: session-4.scope: Deactivated successfully. Nov 8 09:31:37.440239 systemd-logind[803]: Session 4 logged out. Waiting for processes to exit. Nov 8 09:31:37.441406 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:36986). Nov 8 09:31:37.442212 systemd-logind[803]: Removed session 4. Nov 8 09:31:37.479036 sshd[860]: Accepted publickey for core from 10.0.0.1 port 36986 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:37.480173 sshd[860]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.482648 systemd-logind[803]: New session 5 of user core. Nov 8 09:31:37.498270 systemd[1]: Started Session 5 of User core. Nov 8 09:31:37.554213 sudo[863]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Nov 8 09:31:37.554663 sudo[863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:37.563631 sudo[863]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:37.565670 sshd[860]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:37.579670 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37002). Nov 8 09:31:37.581707 dbus-daemon[789]: [system] Reloaded configuration Nov 8 09:31:37.584196 systemd[1]: sshd@4-10.0.0.4:22-10.0.0.1:36986.service: Deactivated successfully. Nov 8 09:31:37.584810 systemd[1]: session-5.scope: Deactivated successfully. Nov 8 09:31:37.585416 systemd-logind[803]: Session 5 logged out. Waiting for processes to exit. Nov 8 09:31:37.586175 systemd-logind[803]: Removed session 5. Nov 8 09:31:37.619867 sshd[866]: Accepted publickey for core from 10.0.0.1 port 37002 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:37.620980 sshd[866]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.623409 systemd-logind[803]: New session 6 of user core. Nov 8 09:31:37.636279 systemd[1]: Started Session 6 of User core. Nov 8 09:31:37.686468 sudo[871]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 8 09:31:37.686671 sudo[871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:37.688812 sudo[871]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:37.692254 sudo[870]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Nov 8 09:31:37.692458 sudo[870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 8 09:31:37.705023 systemd[1]: Stopping Load Security Auditing Rules... Nov 8 09:31:37.704000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 8 09:31:37.706801 kernel: kauditd_printk_skb: 37 callbacks suppressed Nov 8 09:31:37.706829 kernel: audit: type=1305 audit(1762594297.704:48): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 8 09:31:37.706945 auditctl[874]: No rules Nov 8 09:31:37.707203 systemd[1]: audit-rules.service: Deactivated successfully. Nov 8 09:31:37.707436 systemd[1]: Stopped Load Security Auditing Rules. Nov 8 09:31:37.708653 systemd[1]: Starting Load Security Auditing Rules... Nov 8 09:31:37.722731 augenrules[891]: No rules Nov 8 09:31:37.723409 systemd[1]: Finished Load Security Auditing Rules. Nov 8 09:31:37.724175 sudo[870]: pam_unix(sudo:session): session closed for user root Nov 8 09:31:37.725269 sshd[866]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:37.728238 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37018). Nov 8 09:31:37.728645 systemd[1]: sshd@5-10.0.0.4:22-10.0.0.1:37002.service: Deactivated successfully. Nov 8 09:31:37.729334 systemd[1]: session-6.scope: Deactivated successfully. Nov 8 09:31:37.730210 systemd-logind[803]: Session 6 logged out. Waiting for processes to exit. Nov 8 09:31:37.730879 systemd-logind[803]: Removed session 6. Nov 8 09:31:37.766285 sshd[896]: Accepted publickey for core from 10.0.0.1 port 37018 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:37.767298 sshd[896]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.769203 kernel: audit: type=1006 audit(1762594297.765:49): pid=896 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Nov 8 09:31:37.770889 systemd-logind[803]: New session 7 of user core. Nov 8 09:31:37.785332 systemd[1]: Started Session 7 of User core. Nov 8 09:31:37.832819 sshd[896]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:37.849078 systemd[1]: sshd@6-10.0.0.4:22-10.0.0.1:37018.service: Deactivated successfully. Nov 8 09:31:37.849970 systemd[1]: session-7.scope: Deactivated successfully. Nov 8 09:31:37.850732 systemd-logind[803]: Session 7 logged out. Waiting for processes to exit. Nov 8 09:31:37.852213 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:37030). Nov 8 09:31:37.853228 systemd-logind[803]: Removed session 7. Nov 8 09:31:37.890206 sshd[903]: Accepted publickey for core from 10.0.0.1 port 37030 ssh2: RSA SHA256:xuf35YSh/lZGh1H5drUZLfEgZqjq3sa5zAL/dcfNyys Nov 8 09:31:37.891229 sshd[903]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 8 09:31:37.893175 kernel: audit: type=1006 audit(1762594297.889:50): pid=903 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Nov 8 09:31:37.893912 systemd-logind[803]: New session 8 of user core. Nov 8 09:31:37.904386 systemd[1]: Started Session 8 of User core. Nov 8 09:31:37.952818 sshd[903]: pam_unix(sshd:session): session closed for user core Nov 8 09:31:37.954607 systemd[1]: sshd@7-10.0.0.4:22-10.0.0.1:37030.service: Deactivated successfully. Nov 8 09:31:37.955230 systemd[1]: session-8.scope: Deactivated successfully. Nov 8 09:31:37.955744 systemd-logind[803]: Session 8 logged out. Waiting for processes to exit. Nov 8 09:31:37.956362 systemd-logind[803]: Removed session 8.