Nov 12 22:21:38.227580 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Nov 12 22:21:38.227631 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Tue Nov 12 21:07:55 -00 2024 Nov 12 22:21:38.227656 kernel: KASLR disabled due to lack of seed Nov 12 22:21:38.227673 kernel: efi: EFI v2.7 by EDK II Nov 12 22:21:38.227689 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x78503d98 Nov 12 22:21:38.227706 kernel: secureboot: Secure boot disabled Nov 12 22:21:38.227723 kernel: ACPI: Early table checksum verification disabled Nov 12 22:21:38.227738 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Nov 12 22:21:38.227788 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Nov 12 22:21:38.227805 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Nov 12 22:21:38.227829 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Nov 12 22:21:38.227846 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Nov 12 22:21:38.227861 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Nov 12 22:21:38.227877 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Nov 12 22:21:38.227896 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Nov 12 22:21:38.227916 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Nov 12 22:21:38.227934 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Nov 12 22:21:38.227950 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Nov 12 22:21:38.227967 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Nov 12 22:21:38.227983 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Nov 12 22:21:38.228000 kernel: printk: bootconsole [uart0] enabled Nov 12 22:21:38.228016 kernel: NUMA: Failed to initialise from firmware Nov 12 22:21:38.228033 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 22:21:38.228050 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Nov 12 22:21:38.228072 kernel: Zone ranges: Nov 12 22:21:38.228088 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Nov 12 22:21:38.228110 kernel: DMA32 empty Nov 12 22:21:38.228127 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Nov 12 22:21:38.228144 kernel: Movable zone start for each node Nov 12 22:21:38.228161 kernel: Early memory node ranges Nov 12 22:21:38.228177 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Nov 12 22:21:38.228193 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Nov 12 22:21:38.228210 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Nov 12 22:21:38.228226 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Nov 12 22:21:38.228242 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Nov 12 22:21:38.228258 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Nov 12 22:21:38.228274 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Nov 12 22:21:38.228290 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Nov 12 22:21:38.228312 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 22:21:38.228328 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Nov 12 22:21:38.228351 kernel: psci: probing for conduit method from ACPI. Nov 12 22:21:38.228369 kernel: psci: PSCIv1.0 detected in firmware. Nov 12 22:21:38.228386 kernel: psci: Using standard PSCI v0.2 function IDs Nov 12 22:21:38.228407 kernel: psci: Trusted OS migration not required Nov 12 22:21:38.228424 kernel: psci: SMC Calling Convention v1.1 Nov 12 22:21:38.228441 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Nov 12 22:21:38.228458 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Nov 12 22:21:38.228476 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 12 22:21:38.228493 kernel: Detected PIPT I-cache on CPU0 Nov 12 22:21:38.228515 kernel: CPU features: detected: GIC system register CPU interface Nov 12 22:21:38.228532 kernel: CPU features: detected: Spectre-v2 Nov 12 22:21:38.228549 kernel: CPU features: detected: Spectre-v3a Nov 12 22:21:38.228566 kernel: CPU features: detected: Spectre-BHB Nov 12 22:21:38.228583 kernel: CPU features: detected: ARM erratum 1742098 Nov 12 22:21:38.228601 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Nov 12 22:21:38.228622 kernel: alternatives: applying boot alternatives Nov 12 22:21:38.228641 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=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:21:38.228660 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Nov 12 22:21:38.228677 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 12 22:21:38.229016 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 12 22:21:38.229043 kernel: Fallback order for Node 0: 0 Nov 12 22:21:38.229061 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Nov 12 22:21:38.229078 kernel: Policy zone: Normal Nov 12 22:21:38.229095 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 12 22:21:38.229112 kernel: software IO TLB: area num 2. Nov 12 22:21:38.229136 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Nov 12 22:21:38.229154 kernel: Memory: 3819960K/4030464K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39680K init, 897K bss, 210504K reserved, 0K cma-reserved) Nov 12 22:21:38.229172 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 12 22:21:38.229189 kernel: trace event string verifier disabled Nov 12 22:21:38.229205 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 12 22:21:38.229224 kernel: rcu: RCU event tracing is enabled. Nov 12 22:21:38.229241 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 12 22:21:38.229259 kernel: Trampoline variant of Tasks RCU enabled. Nov 12 22:21:38.229276 kernel: Tracing variant of Tasks RCU enabled. Nov 12 22:21:38.229293 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 12 22:21:38.229310 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 12 22:21:38.229331 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 12 22:21:38.229348 kernel: GICv3: 96 SPIs implemented Nov 12 22:21:38.229365 kernel: GICv3: 0 Extended SPIs implemented Nov 12 22:21:38.229382 kernel: Root IRQ handler: gic_handle_irq Nov 12 22:21:38.229398 kernel: GICv3: GICv3 features: 16 PPIs Nov 12 22:21:38.229415 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Nov 12 22:21:38.229432 kernel: ITS [mem 0x10080000-0x1009ffff] Nov 12 22:21:38.229449 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Nov 12 22:21:38.229466 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Nov 12 22:21:38.229483 kernel: GICv3: using LPI property table @0x00000004000d0000 Nov 12 22:21:38.229499 kernel: ITS: Using hypervisor restricted LPI range [128] Nov 12 22:21:38.229516 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Nov 12 22:21:38.229537 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 12 22:21:38.229554 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Nov 12 22:21:38.229571 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Nov 12 22:21:38.229588 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Nov 12 22:21:38.229605 kernel: Console: colour dummy device 80x25 Nov 12 22:21:38.229623 kernel: printk: console [tty1] enabled Nov 12 22:21:38.229640 kernel: ACPI: Core revision 20230628 Nov 12 22:21:38.229658 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Nov 12 22:21:38.229675 kernel: pid_max: default: 32768 minimum: 301 Nov 12 22:21:38.229693 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 12 22:21:38.229714 kernel: landlock: Up and running. Nov 12 22:21:38.229731 kernel: SELinux: Initializing. Nov 12 22:21:38.229799 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:21:38.229820 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:21:38.229838 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 22:21:38.229855 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 22:21:38.229873 kernel: rcu: Hierarchical SRCU implementation. Nov 12 22:21:38.229891 kernel: rcu: Max phase no-delay instances is 400. Nov 12 22:21:38.229908 kernel: Platform MSI: ITS@0x10080000 domain created Nov 12 22:21:38.229932 kernel: PCI/MSI: ITS@0x10080000 domain created Nov 12 22:21:38.229949 kernel: Remapping and enabling EFI services. Nov 12 22:21:38.229966 kernel: smp: Bringing up secondary CPUs ... Nov 12 22:21:38.229983 kernel: Detected PIPT I-cache on CPU1 Nov 12 22:21:38.230000 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Nov 12 22:21:38.230018 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Nov 12 22:21:38.230035 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Nov 12 22:21:38.230052 kernel: smp: Brought up 1 node, 2 CPUs Nov 12 22:21:38.230070 kernel: SMP: Total of 2 processors activated. Nov 12 22:21:38.230091 kernel: CPU features: detected: 32-bit EL0 Support Nov 12 22:21:38.230108 kernel: CPU features: detected: 32-bit EL1 Support Nov 12 22:21:38.230138 kernel: CPU features: detected: CRC32 instructions Nov 12 22:21:38.230159 kernel: CPU: All CPU(s) started at EL1 Nov 12 22:21:38.230177 kernel: alternatives: applying system-wide alternatives Nov 12 22:21:38.230195 kernel: devtmpfs: initialized Nov 12 22:21:38.230213 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 12 22:21:38.230231 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 12 22:21:38.230250 kernel: pinctrl core: initialized pinctrl subsystem Nov 12 22:21:38.230272 kernel: SMBIOS 3.0.0 present. Nov 12 22:21:38.230290 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Nov 12 22:21:38.230308 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 12 22:21:38.230326 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 12 22:21:38.230344 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 12 22:21:38.230362 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 12 22:21:38.230380 kernel: audit: initializing netlink subsys (disabled) Nov 12 22:21:38.230402 kernel: audit: type=2000 audit(0.229:1): state=initialized audit_enabled=0 res=1 Nov 12 22:21:38.230420 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 12 22:21:38.230439 kernel: cpuidle: using governor menu Nov 12 22:21:38.230457 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 12 22:21:38.230475 kernel: ASID allocator initialised with 65536 entries Nov 12 22:21:38.230493 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 12 22:21:38.230511 kernel: Serial: AMBA PL011 UART driver Nov 12 22:21:38.230529 kernel: Modules: 17440 pages in range for non-PLT usage Nov 12 22:21:38.230548 kernel: Modules: 508960 pages in range for PLT usage Nov 12 22:21:38.230566 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 12 22:21:38.230588 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 12 22:21:38.230606 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 12 22:21:38.230624 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 12 22:21:38.230643 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 12 22:21:38.230661 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 12 22:21:38.230679 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 12 22:21:38.230697 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 12 22:21:38.230715 kernel: ACPI: Added _OSI(Module Device) Nov 12 22:21:38.230733 kernel: ACPI: Added _OSI(Processor Device) Nov 12 22:21:38.230774 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 12 22:21:38.230793 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 12 22:21:38.230812 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 12 22:21:38.230830 kernel: ACPI: Interpreter enabled Nov 12 22:21:38.230849 kernel: ACPI: Using GIC for interrupt routing Nov 12 22:21:38.230867 kernel: ACPI: MCFG table detected, 1 entries Nov 12 22:21:38.230885 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Nov 12 22:21:38.231211 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 12 22:21:38.231426 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 12 22:21:38.231627 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 12 22:21:38.231857 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Nov 12 22:21:38.232057 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Nov 12 22:21:38.232082 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Nov 12 22:21:38.232101 kernel: acpiphp: Slot [1] registered Nov 12 22:21:38.232120 kernel: acpiphp: Slot [2] registered Nov 12 22:21:38.232138 kernel: acpiphp: Slot [3] registered Nov 12 22:21:38.232164 kernel: acpiphp: Slot [4] registered Nov 12 22:21:38.232181 kernel: acpiphp: Slot [5] registered Nov 12 22:21:38.232199 kernel: acpiphp: Slot [6] registered Nov 12 22:21:38.232217 kernel: acpiphp: Slot [7] registered Nov 12 22:21:38.232235 kernel: acpiphp: Slot [8] registered Nov 12 22:21:38.232253 kernel: acpiphp: Slot [9] registered Nov 12 22:21:38.232271 kernel: acpiphp: Slot [10] registered Nov 12 22:21:38.232289 kernel: acpiphp: Slot [11] registered Nov 12 22:21:38.232307 kernel: acpiphp: Slot [12] registered Nov 12 22:21:38.232329 kernel: acpiphp: Slot [13] registered Nov 12 22:21:38.232347 kernel: acpiphp: Slot [14] registered Nov 12 22:21:38.232364 kernel: acpiphp: Slot [15] registered Nov 12 22:21:38.232382 kernel: acpiphp: Slot [16] registered Nov 12 22:21:38.232400 kernel: acpiphp: Slot [17] registered Nov 12 22:21:38.232418 kernel: acpiphp: Slot [18] registered Nov 12 22:21:38.232436 kernel: acpiphp: Slot [19] registered Nov 12 22:21:38.232454 kernel: acpiphp: Slot [20] registered Nov 12 22:21:38.232472 kernel: acpiphp: Slot [21] registered Nov 12 22:21:38.232490 kernel: acpiphp: Slot [22] registered Nov 12 22:21:38.232511 kernel: acpiphp: Slot [23] registered Nov 12 22:21:38.232529 kernel: acpiphp: Slot [24] registered Nov 12 22:21:38.232547 kernel: acpiphp: Slot [25] registered Nov 12 22:21:38.232565 kernel: acpiphp: Slot [26] registered Nov 12 22:21:38.232583 kernel: acpiphp: Slot [27] registered Nov 12 22:21:38.232601 kernel: acpiphp: Slot [28] registered Nov 12 22:21:38.232619 kernel: acpiphp: Slot [29] registered Nov 12 22:21:38.232636 kernel: acpiphp: Slot [30] registered Nov 12 22:21:38.232654 kernel: acpiphp: Slot [31] registered Nov 12 22:21:38.232676 kernel: PCI host bridge to bus 0000:00 Nov 12 22:21:38.232933 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Nov 12 22:21:38.233126 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 12 22:21:38.233312 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Nov 12 22:21:38.233496 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Nov 12 22:21:38.233759 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Nov 12 22:21:38.235988 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Nov 12 22:21:38.236248 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Nov 12 22:21:38.236474 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Nov 12 22:21:38.236683 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Nov 12 22:21:38.239179 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 22:21:38.239435 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Nov 12 22:21:38.239665 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Nov 12 22:21:38.244009 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Nov 12 22:21:38.244249 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Nov 12 22:21:38.244462 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 22:21:38.244685 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Nov 12 22:21:38.245073 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Nov 12 22:21:38.245464 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Nov 12 22:21:38.245677 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Nov 12 22:21:38.246109 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Nov 12 22:21:38.246319 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Nov 12 22:21:38.246512 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 12 22:21:38.246703 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Nov 12 22:21:38.246728 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 12 22:21:38.246899 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 12 22:21:38.247231 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 12 22:21:38.247343 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 12 22:21:38.247397 kernel: iommu: Default domain type: Translated Nov 12 22:21:38.247418 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 12 22:21:38.247437 kernel: efivars: Registered efivars operations Nov 12 22:21:38.247456 kernel: vgaarb: loaded Nov 12 22:21:38.247475 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 12 22:21:38.247493 kernel: VFS: Disk quotas dquot_6.6.0 Nov 12 22:21:38.247513 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 12 22:21:38.247531 kernel: pnp: PnP ACPI init Nov 12 22:21:38.247798 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Nov 12 22:21:38.247840 kernel: pnp: PnP ACPI: found 1 devices Nov 12 22:21:38.247859 kernel: NET: Registered PF_INET protocol family Nov 12 22:21:38.247877 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 12 22:21:38.247897 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 12 22:21:38.247916 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 12 22:21:38.247936 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 12 22:21:38.247954 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 12 22:21:38.247972 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 12 22:21:38.247995 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:21:38.248014 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:21:38.248032 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 12 22:21:38.248050 kernel: PCI: CLS 0 bytes, default 64 Nov 12 22:21:38.248068 kernel: kvm [1]: HYP mode not available Nov 12 22:21:38.248086 kernel: Initialise system trusted keyrings Nov 12 22:21:38.248105 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 12 22:21:38.248123 kernel: Key type asymmetric registered Nov 12 22:21:38.248141 kernel: Asymmetric key parser 'x509' registered Nov 12 22:21:38.248163 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 12 22:21:38.248182 kernel: io scheduler mq-deadline registered Nov 12 22:21:38.248200 kernel: io scheduler kyber registered Nov 12 22:21:38.248218 kernel: io scheduler bfq registered Nov 12 22:21:38.248470 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Nov 12 22:21:38.248499 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 12 22:21:38.248517 kernel: ACPI: button: Power Button [PWRB] Nov 12 22:21:38.248536 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Nov 12 22:21:38.248554 kernel: ACPI: button: Sleep Button [SLPB] Nov 12 22:21:38.248580 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 12 22:21:38.248599 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Nov 12 22:21:38.248851 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Nov 12 22:21:38.248880 kernel: printk: console [ttyS0] disabled Nov 12 22:21:38.248899 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Nov 12 22:21:38.248917 kernel: printk: console [ttyS0] enabled Nov 12 22:21:38.248935 kernel: printk: bootconsole [uart0] disabled Nov 12 22:21:38.248953 kernel: thunder_xcv, ver 1.0 Nov 12 22:21:38.248971 kernel: thunder_bgx, ver 1.0 Nov 12 22:21:38.248996 kernel: nicpf, ver 1.0 Nov 12 22:21:38.249014 kernel: nicvf, ver 1.0 Nov 12 22:21:38.249236 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 12 22:21:38.249439 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-11-12T22:21:37 UTC (1731450097) Nov 12 22:21:38.249465 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 12 22:21:38.249483 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Nov 12 22:21:38.249502 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 12 22:21:38.249520 kernel: watchdog: Hard watchdog permanently disabled Nov 12 22:21:38.249544 kernel: NET: Registered PF_INET6 protocol family Nov 12 22:21:38.249562 kernel: Segment Routing with IPv6 Nov 12 22:21:38.249580 kernel: In-situ OAM (IOAM) with IPv6 Nov 12 22:21:38.249598 kernel: NET: Registered PF_PACKET protocol family Nov 12 22:21:38.249616 kernel: Key type dns_resolver registered Nov 12 22:21:38.249633 kernel: registered taskstats version 1 Nov 12 22:21:38.249655 kernel: Loading compiled-in X.509 certificates Nov 12 22:21:38.249678 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: 27dd0d090d7a0971a24582c9198f7e80123ea69f' Nov 12 22:21:38.249700 kernel: Key type .fscrypt registered Nov 12 22:21:38.249727 kernel: Key type fscrypt-provisioning registered Nov 12 22:21:38.249770 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 12 22:21:38.249795 kernel: ima: Allocated hash algorithm: sha1 Nov 12 22:21:38.249814 kernel: ima: No architecture policies found Nov 12 22:21:38.249832 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 12 22:21:38.249854 kernel: clk: Disabling unused clocks Nov 12 22:21:38.249873 kernel: Freeing unused kernel memory: 39680K Nov 12 22:21:38.249892 kernel: Run /init as init process Nov 12 22:21:38.249914 kernel: with arguments: Nov 12 22:21:38.249942 kernel: /init Nov 12 22:21:38.249961 kernel: with environment: Nov 12 22:21:38.249978 kernel: HOME=/ Nov 12 22:21:38.249996 kernel: TERM=linux Nov 12 22:21:38.250014 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 12 22:21:38.250042 systemd[1]: systemd 255 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 +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 12 22:21:38.250072 systemd[1]: Detected virtualization amazon. Nov 12 22:21:38.250097 systemd[1]: Detected architecture arm64. Nov 12 22:21:38.250200 systemd[1]: Running in initrd. Nov 12 22:21:38.250277 systemd[1]: No hostname configured, using default hostname. Nov 12 22:21:38.250302 systemd[1]: Hostname set to . Nov 12 22:21:38.250326 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:21:38.250350 systemd[1]: Queued start job for default target initrd.target. Nov 12 22:21:38.250373 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:21:38.250396 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:21:38.250419 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 12 22:21:38.250448 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:21:38.250475 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 12 22:21:38.250501 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 12 22:21:38.250530 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 12 22:21:38.250557 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 12 22:21:38.250577 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:21:38.250603 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:21:38.250627 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:21:38.250646 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:21:38.250666 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:21:38.250686 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:21:38.250705 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:21:38.250725 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:21:38.250858 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 22:21:38.251261 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 22:21:38.251456 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:21:38.251479 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:21:38.251505 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:21:38.251525 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:21:38.251547 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 12 22:21:38.251567 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:21:38.251586 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 12 22:21:38.251606 systemd[1]: Starting systemd-fsck-usr.service... Nov 12 22:21:38.251625 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:21:38.251649 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:21:38.251669 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:21:38.251688 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 12 22:21:38.251708 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:21:38.251727 systemd[1]: Finished systemd-fsck-usr.service. Nov 12 22:21:38.251830 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:21:38.252149 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 12 22:21:38.252464 systemd-journald[252]: Collecting audit messages is disabled. Nov 12 22:21:38.252515 kernel: Bridge firewalling registered Nov 12 22:21:38.252537 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:21:38.252558 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:21:38.252578 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:21:38.252597 systemd-journald[252]: Journal started Nov 12 22:21:38.252635 systemd-journald[252]: Runtime Journal (/run/log/journal/ec279caeeae5efc1c81e75decabda22e) is 8.0M, max 75.3M, 67.3M free. Nov 12 22:21:38.179772 systemd-modules-load[253]: Inserted module 'overlay' Nov 12 22:21:38.225831 systemd-modules-load[253]: Inserted module 'br_netfilter' Nov 12 22:21:38.267603 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:21:38.278679 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:21:38.289157 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:21:38.289251 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:21:38.310148 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:21:38.315538 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:21:38.331055 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:21:38.342168 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 12 22:21:38.357138 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:21:38.368552 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:21:38.384152 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:21:38.392671 dracut-cmdline[285]: dracut-dracut-053 Nov 12 22:21:38.398973 dracut-cmdline[285]: 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=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:21:38.463636 systemd-resolved[292]: Positive Trust Anchors: Nov 12 22:21:38.463698 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:21:38.463792 systemd-resolved[292]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 12 22:21:38.572784 kernel: SCSI subsystem initialized Nov 12 22:21:38.582777 kernel: Loading iSCSI transport class v2.0-870. Nov 12 22:21:38.594784 kernel: iscsi: registered transport (tcp) Nov 12 22:21:38.616844 kernel: iscsi: registered transport (qla4xxx) Nov 12 22:21:38.616931 kernel: QLogic iSCSI HBA Driver Nov 12 22:21:38.700801 kernel: random: crng init done Nov 12 22:21:38.701215 systemd-resolved[292]: Defaulting to hostname 'linux'. Nov 12 22:21:38.704982 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:21:38.709033 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:21:38.733868 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 12 22:21:38.745185 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 12 22:21:38.780110 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 12 22:21:38.780189 kernel: device-mapper: uevent: version 1.0.3 Nov 12 22:21:38.780216 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 12 22:21:38.848805 kernel: raid6: neonx8 gen() 6663 MB/s Nov 12 22:21:38.865781 kernel: raid6: neonx4 gen() 6547 MB/s Nov 12 22:21:38.882795 kernel: raid6: neonx2 gen() 5456 MB/s Nov 12 22:21:38.899804 kernel: raid6: neonx1 gen() 3950 MB/s Nov 12 22:21:38.917795 kernel: raid6: int64x8 gen() 3774 MB/s Nov 12 22:21:38.934796 kernel: raid6: int64x4 gen() 3673 MB/s Nov 12 22:21:38.951791 kernel: raid6: int64x2 gen() 3571 MB/s Nov 12 22:21:38.969626 kernel: raid6: int64x1 gen() 2746 MB/s Nov 12 22:21:38.969700 kernel: raid6: using algorithm neonx8 gen() 6663 MB/s Nov 12 22:21:38.987580 kernel: raid6: .... xor() 4836 MB/s, rmw enabled Nov 12 22:21:38.987622 kernel: raid6: using neon recovery algorithm Nov 12 22:21:38.996216 kernel: xor: measuring software checksum speed Nov 12 22:21:38.996283 kernel: 8regs : 10592 MB/sec Nov 12 22:21:38.997330 kernel: 32regs : 11924 MB/sec Nov 12 22:21:38.998511 kernel: arm64_neon : 9561 MB/sec Nov 12 22:21:38.998544 kernel: xor: using function: 32regs (11924 MB/sec) Nov 12 22:21:39.083790 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 12 22:21:39.102889 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:21:39.112050 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:21:39.155893 systemd-udevd[471]: Using default interface naming scheme 'v255'. Nov 12 22:21:39.164850 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:21:39.184298 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 12 22:21:39.214637 dracut-pre-trigger[483]: rd.md=0: removing MD RAID activation Nov 12 22:21:39.273810 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:21:39.285074 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:21:39.415389 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:21:39.432151 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 12 22:21:39.486704 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 12 22:21:39.492628 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:21:39.511464 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:21:39.517311 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:21:39.527106 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 12 22:21:39.581218 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:21:39.639768 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 12 22:21:39.639835 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Nov 12 22:21:39.677114 kernel: ena 0000:00:05.0: ENA device version: 0.10 Nov 12 22:21:39.677483 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Nov 12 22:21:39.677723 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:d5:85:7b:0a:8d Nov 12 22:21:39.678572 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Nov 12 22:21:39.642712 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:21:39.643209 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:21:39.686891 kernel: nvme nvme0: pci function 0000:00:04.0 Nov 12 22:21:39.646202 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:21:39.648487 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:21:39.648796 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:21:39.653882 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:21:39.663224 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:21:39.703717 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 12 22:21:39.711487 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 12 22:21:39.711565 kernel: GPT:9289727 != 16777215 Nov 12 22:21:39.711592 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 12 22:21:39.713583 kernel: GPT:9289727 != 16777215 Nov 12 22:21:39.713619 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 12 22:21:39.713644 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:21:39.716424 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:21:39.727654 (udev-worker)[537]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:21:39.728099 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:21:39.789860 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:21:39.807811 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (544) Nov 12 22:21:39.887002 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Nov 12 22:21:39.892170 kernel: BTRFS: device fsid 337794e4-53df-462b-aefc-e93e6a958f34 devid 1 transid 41 /dev/nvme0n1p3 scanned by (udev-worker) (517) Nov 12 22:21:39.949499 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Nov 12 22:21:39.979580 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 22:21:39.995445 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Nov 12 22:21:39.995625 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Nov 12 22:21:40.014108 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 12 22:21:40.033910 disk-uuid[662]: Primary Header is updated. Nov 12 22:21:40.033910 disk-uuid[662]: Secondary Entries is updated. Nov 12 22:21:40.033910 disk-uuid[662]: Secondary Header is updated. Nov 12 22:21:40.043775 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:21:41.060979 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:21:41.063305 disk-uuid[663]: The operation has completed successfully. Nov 12 22:21:41.246676 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 12 22:21:41.246902 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 12 22:21:41.295044 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 12 22:21:41.312809 sh[924]: Success Nov 12 22:21:41.337806 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 12 22:21:41.458399 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 12 22:21:41.464587 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 12 22:21:41.469841 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 12 22:21:41.511336 kernel: BTRFS info (device dm-0): first mount of filesystem 337794e4-53df-462b-aefc-e93e6a958f34 Nov 12 22:21:41.511400 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:21:41.511439 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 12 22:21:41.513023 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 12 22:21:41.514245 kernel: BTRFS info (device dm-0): using free space tree Nov 12 22:21:41.616796 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 12 22:21:41.651201 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 12 22:21:41.654994 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 12 22:21:41.661074 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 12 22:21:41.667048 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 12 22:21:41.713309 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:21:41.713406 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:21:41.713438 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:21:41.726663 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:21:41.742349 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 12 22:21:41.745087 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:21:41.753532 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 12 22:21:41.766172 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 12 22:21:41.860534 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:21:41.888953 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:21:41.941643 systemd-networkd[1116]: lo: Link UP Nov 12 22:21:41.941669 systemd-networkd[1116]: lo: Gained carrier Nov 12 22:21:41.945936 systemd-networkd[1116]: Enumeration completed Nov 12 22:21:41.946124 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:21:41.947049 systemd-networkd[1116]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:21:41.947057 systemd-networkd[1116]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:21:41.950818 systemd[1]: Reached target network.target - Network. Nov 12 22:21:41.954221 systemd-networkd[1116]: eth0: Link UP Nov 12 22:21:41.954230 systemd-networkd[1116]: eth0: Gained carrier Nov 12 22:21:41.954251 systemd-networkd[1116]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:21:41.977848 systemd-networkd[1116]: eth0: DHCPv4 address 172.31.25.242/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 22:21:42.206965 ignition[1045]: Ignition 2.20.0 Nov 12 22:21:42.207495 ignition[1045]: Stage: fetch-offline Nov 12 22:21:42.207978 ignition[1045]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:21:42.212382 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:21:42.208002 ignition[1045]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:21:42.208489 ignition[1045]: Ignition finished successfully Nov 12 22:21:42.233038 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 12 22:21:42.255234 ignition[1128]: Ignition 2.20.0 Nov 12 22:21:42.255263 ignition[1128]: Stage: fetch Nov 12 22:21:42.256140 ignition[1128]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:21:42.256167 ignition[1128]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:21:42.256353 ignition[1128]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:21:42.282118 ignition[1128]: PUT result: OK Nov 12 22:21:42.285029 ignition[1128]: parsed url from cmdline: "" Nov 12 22:21:42.285045 ignition[1128]: no config URL provided Nov 12 22:21:42.285081 ignition[1128]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 22:21:42.285111 ignition[1128]: no config at "/usr/lib/ignition/user.ign" Nov 12 22:21:42.285144 ignition[1128]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:21:42.290632 ignition[1128]: PUT result: OK Nov 12 22:21:42.292408 ignition[1128]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Nov 12 22:21:42.294715 ignition[1128]: GET result: OK Nov 12 22:21:42.294799 ignition[1128]: parsing config with SHA512: e2a9524183ac93bdc6eff7c78fd8d8c0233a701e9caad4f9860c72c90e902b25fd32eb7e7bd75fab6cf9912978c4deef789216bf380d479318615be7451924a4 Nov 12 22:21:42.304769 unknown[1128]: fetched base config from "system" Nov 12 22:21:42.305108 ignition[1128]: fetch: fetch complete Nov 12 22:21:42.304789 unknown[1128]: fetched base config from "system" Nov 12 22:21:42.305120 ignition[1128]: fetch: fetch passed Nov 12 22:21:42.304804 unknown[1128]: fetched user config from "aws" Nov 12 22:21:42.305211 ignition[1128]: Ignition finished successfully Nov 12 22:21:42.315656 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 12 22:21:42.334282 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 12 22:21:42.358423 ignition[1134]: Ignition 2.20.0 Nov 12 22:21:42.358452 ignition[1134]: Stage: kargs Nov 12 22:21:42.359071 ignition[1134]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:21:42.359098 ignition[1134]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:21:42.359272 ignition[1134]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:21:42.361129 ignition[1134]: PUT result: OK Nov 12 22:21:42.371563 ignition[1134]: kargs: kargs passed Nov 12 22:21:42.371683 ignition[1134]: Ignition finished successfully Nov 12 22:21:42.375619 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 12 22:21:42.387107 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 12 22:21:42.417588 ignition[1140]: Ignition 2.20.0 Nov 12 22:21:42.417618 ignition[1140]: Stage: disks Nov 12 22:21:42.419240 ignition[1140]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:21:42.419267 ignition[1140]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:21:42.420343 ignition[1140]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:21:42.424422 ignition[1140]: PUT result: OK Nov 12 22:21:42.429946 ignition[1140]: disks: disks passed Nov 12 22:21:42.430037 ignition[1140]: Ignition finished successfully Nov 12 22:21:42.434817 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 12 22:21:42.439288 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 12 22:21:42.442099 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 22:21:42.444775 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:21:42.446712 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:21:42.455862 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:21:42.466101 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 12 22:21:42.519171 systemd-fsck[1149]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 12 22:21:42.526252 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 12 22:21:42.536997 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 12 22:21:42.637775 kernel: EXT4-fs (nvme0n1p9): mounted filesystem be7e07bb-77fc-4aec-a4f6-d76dc4498784 r/w with ordered data mode. Quota mode: none. Nov 12 22:21:42.639194 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 12 22:21:42.642589 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 12 22:21:42.661005 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:21:42.667993 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 12 22:21:42.672209 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 12 22:21:42.675961 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 12 22:21:42.676017 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:21:42.698137 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 12 22:21:42.707809 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1168) Nov 12 22:21:42.708212 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 12 22:21:42.717985 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:21:42.718022 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:21:42.718047 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:21:42.724828 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:21:42.730007 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:21:43.150411 initrd-setup-root[1192]: cut: /sysroot/etc/passwd: No such file or directory Nov 12 22:21:43.160148 initrd-setup-root[1199]: cut: /sysroot/etc/group: No such file or directory Nov 12 22:21:43.180731 initrd-setup-root[1206]: cut: /sysroot/etc/shadow: No such file or directory Nov 12 22:21:43.190534 initrd-setup-root[1213]: cut: /sysroot/etc/gshadow: No such file or directory Nov 12 22:21:43.332946 systemd-networkd[1116]: eth0: Gained IPv6LL Nov 12 22:21:43.542163 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 12 22:21:43.556950 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 12 22:21:43.562296 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 12 22:21:43.582816 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:21:43.582646 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 12 22:21:43.626477 ignition[1280]: INFO : Ignition 2.20.0 Nov 12 22:21:43.626477 ignition[1280]: INFO : Stage: mount Nov 12 22:21:43.631136 ignition[1280]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:21:43.631136 ignition[1280]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:21:43.631136 ignition[1280]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:21:43.637630 ignition[1280]: INFO : PUT result: OK Nov 12 22:21:43.637666 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 12 22:21:43.658339 ignition[1280]: INFO : mount: mount passed Nov 12 22:21:43.658339 ignition[1280]: INFO : Ignition finished successfully Nov 12 22:21:43.665848 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 12 22:21:43.680108 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 12 22:21:43.700931 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:21:43.733433 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1292) Nov 12 22:21:43.733496 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:21:43.733539 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:21:43.736138 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:21:43.740778 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:21:43.744855 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:21:43.782802 ignition[1309]: INFO : Ignition 2.20.0 Nov 12 22:21:43.782802 ignition[1309]: INFO : Stage: files Nov 12 22:21:43.782802 ignition[1309]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:21:43.782802 ignition[1309]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:21:43.782802 ignition[1309]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:21:43.793185 ignition[1309]: INFO : PUT result: OK Nov 12 22:21:43.797134 ignition[1309]: DEBUG : files: compiled without relabeling support, skipping Nov 12 22:21:43.800241 ignition[1309]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 12 22:21:43.800241 ignition[1309]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 12 22:21:43.819005 ignition[1309]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 12 22:21:43.821955 ignition[1309]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 12 22:21:43.825259 unknown[1309]: wrote ssh authorized keys file for user: core Nov 12 22:21:43.827665 ignition[1309]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 12 22:21:43.836464 ignition[1309]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:21:43.841118 ignition[1309]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:21:43.841118 ignition[1309]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:21:43.841118 ignition[1309]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:21:43.841118 ignition[1309]: INFO : files: files passed Nov 12 22:21:43.841118 ignition[1309]: INFO : Ignition finished successfully Nov 12 22:21:43.855790 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 12 22:21:43.869049 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 12 22:21:43.875224 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 12 22:21:43.884012 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 12 22:21:43.886276 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 12 22:21:43.917050 initrd-setup-root-after-ignition[1337]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:21:43.917050 initrd-setup-root-after-ignition[1337]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:21:43.923338 initrd-setup-root-after-ignition[1341]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:21:43.927865 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:21:43.933270 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 12 22:21:43.944995 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 12 22:21:44.016493 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 12 22:21:44.018565 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 12 22:21:44.023564 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 12 22:21:44.027461 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 12 22:21:44.031411 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 12 22:21:44.041063 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 12 22:21:44.079086 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:21:44.096154 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 12 22:21:44.122056 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:21:44.126770 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:21:44.129208 systemd[1]: Stopped target timers.target - Timer Units. Nov 12 22:21:44.131531 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 12 22:21:44.131789 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:21:44.136225 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 12 22:21:44.146330 systemd[1]: Stopped target basic.target - Basic System. Nov 12 22:21:44.148864 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 12 22:21:44.154441 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:21:44.156990 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 12 22:21:44.159519 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 12 22:21:44.167529 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:21:44.170229 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 12 22:21:44.172384 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 12 22:21:44.174579 systemd[1]: Stopped target swap.target - Swaps. Nov 12 22:21:44.180508 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 12 22:21:44.180739 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:21:44.190631 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:21:44.194975 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:21:44.197339 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 12 22:21:44.203224 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:21:44.206090 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 12 22:21:44.206562 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 12 22:21:44.214625 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 12 22:21:44.215103 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:21:44.222006 systemd[1]: ignition-files.service: Deactivated successfully. Nov 12 22:21:44.222683 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 12 22:21:44.237214 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 12 22:21:44.244222 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 12 22:21:44.245992 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 12 22:21:44.246255 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:21:44.256193 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 12 22:21:44.258643 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:21:44.281489 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 12 22:21:44.284451 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 12 22:21:44.295818 ignition[1361]: INFO : Ignition 2.20.0 Nov 12 22:21:44.295818 ignition[1361]: INFO : Stage: umount Nov 12 22:21:44.295818 ignition[1361]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:21:44.295818 ignition[1361]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:21:44.304884 ignition[1361]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:21:44.308336 ignition[1361]: INFO : PUT result: OK Nov 12 22:21:44.316072 ignition[1361]: INFO : umount: umount passed Nov 12 22:21:44.319700 ignition[1361]: INFO : Ignition finished successfully Nov 12 22:21:44.318989 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 12 22:21:44.321217 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 12 22:21:44.330243 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 12 22:21:44.330443 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 12 22:21:44.332641 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 12 22:21:44.333500 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 12 22:21:44.334014 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 12 22:21:44.334105 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 12 22:21:44.334707 systemd[1]: Stopped target network.target - Network. Nov 12 22:21:44.336169 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 12 22:21:44.336277 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:21:44.336910 systemd[1]: Stopped target paths.target - Path Units. Nov 12 22:21:44.337565 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 12 22:21:44.353036 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:21:44.355790 systemd[1]: Stopped target slices.target - Slice Units. Nov 12 22:21:44.371325 systemd[1]: Stopped target sockets.target - Socket Units. Nov 12 22:21:44.379583 systemd[1]: iscsid.socket: Deactivated successfully. Nov 12 22:21:44.379685 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:21:44.387398 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 12 22:21:44.387538 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:21:44.392190 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 12 22:21:44.392315 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 12 22:21:44.404509 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 12 22:21:44.404625 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 12 22:21:44.410582 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 12 22:21:44.411480 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 12 22:21:44.416907 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 12 22:21:44.418156 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 12 22:21:44.420458 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 12 22:21:44.435002 systemd-networkd[1116]: eth0: DHCPv6 lease lost Nov 12 22:21:44.439647 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 12 22:21:44.439891 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 12 22:21:44.450841 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 12 22:21:44.451367 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 12 22:21:44.460961 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 12 22:21:44.461275 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 12 22:21:44.468846 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 12 22:21:44.469479 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:21:44.482149 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 12 22:21:44.489158 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 12 22:21:44.489487 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:21:44.496864 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 12 22:21:44.496982 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:21:44.499668 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 12 22:21:44.499815 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 12 22:21:44.503090 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 12 22:21:44.503216 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:21:44.527322 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:21:44.554261 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 12 22:21:44.556713 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:21:44.562346 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 12 22:21:44.562437 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 12 22:21:44.566494 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 12 22:21:44.566574 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:21:44.568830 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 12 22:21:44.568947 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:21:44.571465 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 12 22:21:44.571583 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 12 22:21:44.578010 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:21:44.578147 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:21:44.605135 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 12 22:21:44.607511 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 12 22:21:44.607627 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:21:44.612110 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 12 22:21:44.612201 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:21:44.614664 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 12 22:21:44.614764 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:21:44.617262 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:21:44.617345 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:21:44.620240 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 12 22:21:44.620843 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 12 22:21:44.639590 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 12 22:21:44.641847 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 12 22:21:44.657575 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 12 22:21:44.667043 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 12 22:21:44.690709 systemd[1]: Switching root. Nov 12 22:21:44.728661 systemd-journald[252]: Journal stopped Nov 12 22:21:47.072430 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). Nov 12 22:21:47.072557 kernel: SELinux: policy capability network_peer_controls=1 Nov 12 22:21:47.072601 kernel: SELinux: policy capability open_perms=1 Nov 12 22:21:47.072632 kernel: SELinux: policy capability extended_socket_class=1 Nov 12 22:21:47.072662 kernel: SELinux: policy capability always_check_network=0 Nov 12 22:21:47.072692 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 12 22:21:47.072726 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 12 22:21:47.072794 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 12 22:21:47.072830 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 12 22:21:47.072860 kernel: audit: type=1403 audit(1731450105.186:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 12 22:21:47.072898 systemd[1]: Successfully loaded SELinux policy in 85.146ms. Nov 12 22:21:47.072947 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.790ms. Nov 12 22:21:47.072981 systemd[1]: systemd 255 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 +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 12 22:21:47.073022 systemd[1]: Detected virtualization amazon. Nov 12 22:21:47.073051 systemd[1]: Detected architecture arm64. Nov 12 22:21:47.073087 systemd[1]: Detected first boot. Nov 12 22:21:47.073120 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:21:47.073154 zram_generator::config[1403]: No configuration found. Nov 12 22:21:47.073188 systemd[1]: Populated /etc with preset unit settings. Nov 12 22:21:47.073220 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 12 22:21:47.073252 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 12 22:21:47.073286 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 12 22:21:47.073320 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 12 22:21:47.073355 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 12 22:21:47.073387 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 12 22:21:47.073419 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 12 22:21:47.073455 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 12 22:21:47.073487 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 12 22:21:47.073519 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 12 22:21:47.073552 systemd[1]: Created slice user.slice - User and Session Slice. Nov 12 22:21:47.073583 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:21:47.073615 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:21:47.073649 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 12 22:21:47.073681 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 12 22:21:47.073714 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 12 22:21:47.076192 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:21:47.076245 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 12 22:21:47.076285 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:21:47.076315 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 12 22:21:47.076344 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 12 22:21:47.076374 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 12 22:21:47.076403 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 12 22:21:47.076436 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:21:47.076467 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:21:47.076501 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:21:47.076539 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:21:47.076569 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 12 22:21:47.076599 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 12 22:21:47.076635 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:21:47.076669 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:21:47.076701 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:21:47.076732 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 12 22:21:47.078313 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 12 22:21:47.078358 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 12 22:21:47.078400 systemd[1]: Mounting media.mount - External Media Directory... Nov 12 22:21:47.078430 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 12 22:21:47.078459 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 12 22:21:47.078495 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 12 22:21:47.078527 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 12 22:21:47.078559 systemd[1]: Reached target machines.target - Containers. Nov 12 22:21:47.078590 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 12 22:21:47.078622 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:21:47.078651 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:21:47.078684 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 12 22:21:47.078716 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:21:47.079064 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:21:47.079118 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:21:47.079160 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 12 22:21:47.079192 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:21:47.079224 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 12 22:21:47.079258 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 12 22:21:47.079293 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 12 22:21:47.079322 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 12 22:21:47.079351 systemd[1]: Stopped systemd-fsck-usr.service. Nov 12 22:21:47.079380 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:21:47.079413 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:21:47.079444 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 12 22:21:47.079475 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 12 22:21:47.079505 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:21:47.079536 systemd[1]: verity-setup.service: Deactivated successfully. Nov 12 22:21:47.079571 systemd[1]: Stopped verity-setup.service. Nov 12 22:21:47.079601 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 12 22:21:47.079634 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 12 22:21:47.079664 systemd[1]: Mounted media.mount - External Media Directory. Nov 12 22:21:47.079696 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 12 22:21:47.079729 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 12 22:21:47.079784 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 12 22:21:47.079816 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:21:47.080028 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 12 22:21:47.080061 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 12 22:21:47.080090 kernel: fuse: init (API version 7.39) Nov 12 22:21:47.080123 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:21:47.080152 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:21:47.080180 kernel: loop: module loaded Nov 12 22:21:47.080216 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:21:47.080245 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:21:47.080274 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 12 22:21:47.080304 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 12 22:21:47.080333 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:21:47.080364 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:21:47.080399 kernel: ACPI: bus type drm_connector registered Nov 12 22:21:47.080428 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:21:47.080457 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:21:47.080486 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:21:47.080515 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 12 22:21:47.080543 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 12 22:21:47.080572 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 12 22:21:47.080651 systemd-journald[1485]: Collecting audit messages is disabled. Nov 12 22:21:47.080707 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 12 22:21:47.080736 systemd-journald[1485]: Journal started Nov 12 22:21:47.088576 systemd-journald[1485]: Runtime Journal (/run/log/journal/ec279caeeae5efc1c81e75decabda22e) is 8.0M, max 75.3M, 67.3M free. Nov 12 22:21:47.088679 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:21:46.466413 systemd[1]: Queued start job for default target multi-user.target. Nov 12 22:21:46.515396 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 12 22:21:46.516194 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 12 22:21:47.105811 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:21:47.125309 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:21:47.125394 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:21:47.134621 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 12 22:21:47.138166 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 12 22:21:47.140675 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 12 22:21:47.180673 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 12 22:21:47.196406 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 12 22:21:47.196532 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:21:47.203342 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 12 22:21:47.219094 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 12 22:21:47.231193 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 12 22:21:47.234173 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:21:47.260229 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 12 22:21:47.273068 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 12 22:21:47.276918 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:21:47.278263 systemd-tmpfiles[1507]: ACLs are not supported, ignoring. Nov 12 22:21:47.280004 systemd-tmpfiles[1507]: ACLs are not supported, ignoring. Nov 12 22:21:47.280035 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 12 22:21:47.288325 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 12 22:21:47.293853 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:21:47.296828 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 12 22:21:47.311925 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:21:47.324514 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 12 22:21:47.354410 systemd-journald[1485]: Time spent on flushing to /var/log/journal/ec279caeeae5efc1c81e75decabda22e is 121.144ms for 889 entries. Nov 12 22:21:47.354410 systemd-journald[1485]: System Journal (/var/log/journal/ec279caeeae5efc1c81e75decabda22e) is 8.0M, max 195.6M, 187.6M free. Nov 12 22:21:47.490720 systemd-journald[1485]: Received client request to flush runtime journal. Nov 12 22:21:47.491057 kernel: loop0: detected capacity change from 0 to 53784 Nov 12 22:21:47.491136 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 12 22:21:47.399157 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 12 22:21:47.402270 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 12 22:21:47.430143 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 12 22:21:47.481841 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:21:47.498462 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 12 22:21:47.504271 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 12 22:21:47.524281 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 12 22:21:47.527954 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 12 22:21:47.539805 kernel: loop1: detected capacity change from 0 to 113536 Nov 12 22:21:47.553171 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 12 22:21:47.562824 udevadm[1549]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 12 22:21:47.578190 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:21:47.635811 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Nov 12 22:21:47.635850 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Nov 12 22:21:47.655879 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:21:47.687996 kernel: loop2: detected capacity change from 0 to 116808 Nov 12 22:21:47.816421 kernel: loop3: detected capacity change from 0 to 53784 Nov 12 22:21:47.844023 kernel: loop4: detected capacity change from 0 to 113536 Nov 12 22:21:47.859932 kernel: loop5: detected capacity change from 0 to 116808 Nov 12 22:21:47.873164 (sd-merge)[1559]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-ami'. Nov 12 22:21:47.874036 (sd-merge)[1559]: Merged extensions into '/usr'. Nov 12 22:21:47.882845 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 12 22:21:47.895233 systemd[1]: Starting ensure-sysext.service... Nov 12 22:21:47.906085 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:21:47.926887 systemd[1]: Reloading requested from client PID 1561 ('systemctl') (unit ensure-sysext.service)... Nov 12 22:21:47.926931 systemd[1]: Reloading... Nov 12 22:21:47.969080 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 12 22:21:47.969722 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 12 22:21:47.976157 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 12 22:21:47.976731 systemd-tmpfiles[1562]: ACLs are not supported, ignoring. Nov 12 22:21:47.976920 systemd-tmpfiles[1562]: ACLs are not supported, ignoring. Nov 12 22:21:47.989944 systemd-tmpfiles[1562]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:21:47.989972 systemd-tmpfiles[1562]: Skipping /boot Nov 12 22:21:48.028936 systemd-tmpfiles[1562]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:21:48.028967 systemd-tmpfiles[1562]: Skipping /boot Nov 12 22:21:48.162806 zram_generator::config[1589]: No configuration found. Nov 12 22:21:48.514972 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 12 22:21:48.654658 systemd[1]: Reloading finished in 726 ms. Nov 12 22:21:48.711937 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:21:48.715604 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 12 22:21:48.749458 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 12 22:21:48.758265 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 12 22:21:48.774227 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 12 22:21:48.781232 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:21:48.797234 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:21:48.808157 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 12 22:21:48.822546 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:21:48.832820 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:21:48.842329 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:21:48.854361 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:21:48.857076 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:21:48.875301 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:21:48.888584 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:21:48.891520 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:21:48.892242 systemd[1]: Reached target time-set.target - System Time Set. Nov 12 22:21:48.901449 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 12 22:21:48.907913 systemd[1]: Finished ensure-sysext.service. Nov 12 22:21:48.929854 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 12 22:21:48.942403 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 12 22:21:48.957373 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:21:48.957676 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:21:48.963063 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:21:48.963475 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:21:48.968911 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:21:48.984345 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:21:48.984708 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:21:48.992072 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:21:48.992492 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:21:48.995652 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:21:49.041920 systemd-udevd[1648]: Using default interface naming scheme 'v255'. Nov 12 22:21:49.081685 augenrules[1681]: No rules Nov 12 22:21:49.090618 systemd[1]: audit-rules.service: Deactivated successfully. Nov 12 22:21:49.091875 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 12 22:21:49.112806 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 12 22:21:49.119241 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 12 22:21:49.123528 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 12 22:21:49.142485 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:21:49.158319 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:21:49.181002 ldconfig[1534]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 12 22:21:49.199854 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 12 22:21:49.226145 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 12 22:21:49.267654 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 12 22:21:49.363723 systemd-resolved[1647]: Positive Trust Anchors: Nov 12 22:21:49.364838 systemd-resolved[1647]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:21:49.364903 systemd-resolved[1647]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 12 22:21:49.376139 systemd-resolved[1647]: Defaulting to hostname 'linux'. Nov 12 22:21:49.381150 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:21:49.383594 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:21:49.395804 systemd-networkd[1693]: lo: Link UP Nov 12 22:21:49.397120 systemd-networkd[1693]: lo: Gained carrier Nov 12 22:21:49.398619 systemd-networkd[1693]: Enumeration completed Nov 12 22:21:49.400205 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:21:49.405364 systemd[1]: Reached target network.target - Network. Nov 12 22:21:49.439977 (udev-worker)[1712]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:21:49.448531 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 12 22:21:49.451187 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 12 22:21:49.456604 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1697) Nov 12 22:21:49.476808 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1697) Nov 12 22:21:49.555894 systemd-networkd[1693]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:21:49.556720 systemd-networkd[1693]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:21:49.560145 systemd-networkd[1693]: eth0: Link UP Nov 12 22:21:49.561059 systemd-networkd[1693]: eth0: Gained carrier Nov 12 22:21:49.561433 systemd-networkd[1693]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:21:49.578967 systemd-networkd[1693]: eth0: DHCPv4 address 172.31.25.242/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 22:21:49.588816 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (1697) Nov 12 22:21:49.804930 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:21:49.854324 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 22:21:49.865109 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 12 22:21:49.869960 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 12 22:21:49.882354 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 12 22:21:49.904669 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 12 22:21:49.921785 lvm[1817]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:21:49.957022 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 12 22:21:49.961308 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:21:49.975150 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 12 22:21:49.988832 lvm[1822]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:21:49.993866 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:21:49.997003 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:21:50.000108 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 12 22:21:50.014088 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 12 22:21:50.017590 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 12 22:21:50.020351 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 12 22:21:50.023272 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 12 22:21:50.025970 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 12 22:21:50.026046 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:21:50.028052 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:21:50.031429 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 12 22:21:50.036610 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 22:21:50.049264 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 12 22:21:50.053231 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 12 22:21:50.056529 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 22:21:50.060043 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:21:50.062050 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:21:50.064004 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:21:50.064064 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:21:50.077697 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 22:21:50.083944 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 12 22:21:50.090368 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 12 22:21:50.098318 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 12 22:21:50.112117 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 12 22:21:50.114328 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 12 22:21:50.120356 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 12 22:21:50.138072 systemd[1]: Started ntpd.service - Network Time Service. Nov 12 22:21:50.149999 jq[1831]: false Nov 12 22:21:50.152007 systemd[1]: Starting setup-oem.service - Setup OEM... Nov 12 22:21:50.164166 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 12 22:21:50.179366 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 12 22:21:50.195179 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 12 22:21:50.198408 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 12 22:21:50.200599 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 12 22:21:50.204251 systemd[1]: Starting update-engine.service - Update Engine... Nov 12 22:21:50.210900 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 12 22:21:50.226574 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 12 22:21:50.227014 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 12 22:21:50.229610 dbus-daemon[1830]: [system] SELinux support is enabled Nov 12 22:21:50.231615 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 12 22:21:50.240148 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 12 22:21:50.242901 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 12 22:21:50.256665 dbus-daemon[1830]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1693 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Nov 12 22:21:50.258202 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 12 22:21:50.258293 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 12 22:21:50.258528 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 12 22:21:50.259086 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 12 22:21:50.302169 ntpd[1834]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 20:41:47 UTC 2024 (1): Starting Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 20:41:47 UTC 2024 (1): Starting Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: ---------------------------------------------------- Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: ntp-4 is maintained by Network Time Foundation, Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: corporation. Support and training for ntp-4 are Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: available at https://www.nwtime.org/support Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: ---------------------------------------------------- Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: proto: precision = 0.108 usec (-23) Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: basedate set to 2024-10-31 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: gps base set to 2024-11-03 (week 2339) Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Listen normally on 3 eth0 172.31.25.242:123 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Listen normally on 4 lo [::1]:123 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: bind(21) AF_INET6 fe80::4d5:85ff:fe7b:a8d%2#123 flags 0x11 failed: Cannot assign requested address Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: unable to create socket on eth0 (5) for fe80::4d5:85ff:fe7b:a8d%2#123 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: failed to init interface for address fe80::4d5:85ff:fe7b:a8d%2 Nov 12 22:21:50.321106 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: Listening on routing socket on fd #21 for interface updates Nov 12 22:21:50.303154 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Nov 12 22:21:50.302525 ntpd[1834]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 22:21:50.302547 ntpd[1834]: ---------------------------------------------------- Nov 12 22:21:50.334718 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:21:50.334718 ntpd[1834]: 12 Nov 22:21:50 ntpd[1834]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:21:50.302566 ntpd[1834]: ntp-4 is maintained by Network Time Foundation, Nov 12 22:21:50.302584 ntpd[1834]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 22:21:50.302606 ntpd[1834]: corporation. Support and training for ntp-4 are Nov 12 22:21:50.302626 ntpd[1834]: available at https://www.nwtime.org/support Nov 12 22:21:50.302645 ntpd[1834]: ---------------------------------------------------- Nov 12 22:21:50.308378 ntpd[1834]: proto: precision = 0.108 usec (-23) Nov 12 22:21:50.309879 ntpd[1834]: basedate set to 2024-10-31 Nov 12 22:21:50.309922 ntpd[1834]: gps base set to 2024-11-03 (week 2339) Nov 12 22:21:50.314594 ntpd[1834]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 22:21:50.314680 ntpd[1834]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 22:21:50.317063 ntpd[1834]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 22:21:50.317135 ntpd[1834]: Listen normally on 3 eth0 172.31.25.242:123 Nov 12 22:21:50.317203 ntpd[1834]: Listen normally on 4 lo [::1]:123 Nov 12 22:21:50.317281 ntpd[1834]: bind(21) AF_INET6 fe80::4d5:85ff:fe7b:a8d%2#123 flags 0x11 failed: Cannot assign requested address Nov 12 22:21:50.317319 ntpd[1834]: unable to create socket on eth0 (5) for fe80::4d5:85ff:fe7b:a8d%2#123 Nov 12 22:21:50.317351 ntpd[1834]: failed to init interface for address fe80::4d5:85ff:fe7b:a8d%2 Nov 12 22:21:50.317404 ntpd[1834]: Listening on routing socket on fd #21 for interface updates Nov 12 22:21:50.323672 ntpd[1834]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:21:50.323724 ntpd[1834]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:21:50.351268 (ntainerd)[1856]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 22:21:50.391079 jq[1840]: true Nov 12 22:21:50.416777 extend-filesystems[1832]: Found loop3 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found loop4 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found loop5 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1p1 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1p2 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1p3 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found usr Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1p4 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1p6 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1p7 Nov 12 22:21:50.416777 extend-filesystems[1832]: Found nvme0n1p9 Nov 12 22:21:50.416777 extend-filesystems[1832]: Checking size of /dev/nvme0n1p9 Nov 12 22:21:50.442334 systemd[1]: motdgen.service: Deactivated successfully. Nov 12 22:21:50.444927 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 12 22:21:50.498482 extend-filesystems[1832]: Resized partition /dev/nvme0n1p9 Nov 12 22:21:50.516168 extend-filesystems[1876]: resize2fs 1.47.1 (20-May-2024) Nov 12 22:21:50.521503 jq[1866]: true Nov 12 22:21:50.532036 update_engine[1839]: I20241112 22:21:50.520823 1839 main.cc:92] Flatcar Update Engine starting Nov 12 22:21:50.534782 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Nov 12 22:21:50.544396 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 12 22:21:50.547406 systemd[1]: Started update-engine.service - Update Engine. Nov 12 22:21:50.553731 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 12 22:21:50.562983 update_engine[1839]: I20241112 22:21:50.553807 1839 update_check_scheduler.cc:74] Next update check in 10m56s Nov 12 22:21:50.570803 coreos-metadata[1829]: Nov 12 22:21:50.568 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 22:21:50.575893 coreos-metadata[1829]: Nov 12 22:21:50.575 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Nov 12 22:21:50.584385 coreos-metadata[1829]: Nov 12 22:21:50.583 INFO Fetch successful Nov 12 22:21:50.584385 coreos-metadata[1829]: Nov 12 22:21:50.583 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Nov 12 22:21:50.584385 coreos-metadata[1829]: Nov 12 22:21:50.584 INFO Fetch successful Nov 12 22:21:50.584385 coreos-metadata[1829]: Nov 12 22:21:50.585 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Nov 12 22:21:50.584219 systemd[1]: Finished setup-oem.service - Setup OEM. Nov 12 22:21:50.591059 coreos-metadata[1829]: Nov 12 22:21:50.588 INFO Fetch successful Nov 12 22:21:50.591059 coreos-metadata[1829]: Nov 12 22:21:50.588 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Nov 12 22:21:50.590307 systemd-logind[1838]: Watching system buttons on /dev/input/event0 (Power Button) Nov 12 22:21:50.590344 systemd-logind[1838]: Watching system buttons on /dev/input/event1 (Sleep Button) Nov 12 22:21:50.596336 coreos-metadata[1829]: Nov 12 22:21:50.593 INFO Fetch successful Nov 12 22:21:50.596336 coreos-metadata[1829]: Nov 12 22:21:50.593 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Nov 12 22:21:50.594154 systemd-logind[1838]: New seat seat0. Nov 12 22:21:50.599410 coreos-metadata[1829]: Nov 12 22:21:50.597 INFO Fetch failed with 404: resource not found Nov 12 22:21:50.599410 coreos-metadata[1829]: Nov 12 22:21:50.598 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Nov 12 22:21:50.601329 coreos-metadata[1829]: Nov 12 22:21:50.600 INFO Fetch successful Nov 12 22:21:50.601784 coreos-metadata[1829]: Nov 12 22:21:50.601 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Nov 12 22:21:50.604082 systemd[1]: Started systemd-logind.service - User Login Management. Nov 12 22:21:50.611058 coreos-metadata[1829]: Nov 12 22:21:50.610 INFO Fetch successful Nov 12 22:21:50.611058 coreos-metadata[1829]: Nov 12 22:21:50.610 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Nov 12 22:21:50.620083 coreos-metadata[1829]: Nov 12 22:21:50.615 INFO Fetch successful Nov 12 22:21:50.620083 coreos-metadata[1829]: Nov 12 22:21:50.615 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Nov 12 22:21:50.621051 coreos-metadata[1829]: Nov 12 22:21:50.620 INFO Fetch successful Nov 12 22:21:50.621051 coreos-metadata[1829]: Nov 12 22:21:50.620 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Nov 12 22:21:50.626925 coreos-metadata[1829]: Nov 12 22:21:50.623 INFO Fetch successful Nov 12 22:21:50.690455 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Nov 12 22:21:50.693002 systemd-networkd[1693]: eth0: Gained IPv6LL Nov 12 22:21:50.701571 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 12 22:21:50.710940 systemd[1]: Reached target network-online.target - Network is Online. Nov 12 22:21:50.733794 extend-filesystems[1876]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 12 22:21:50.733794 extend-filesystems[1876]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 12 22:21:50.733794 extend-filesystems[1876]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Nov 12 22:21:50.768872 extend-filesystems[1832]: Resized filesystem in /dev/nvme0n1p9 Nov 12 22:21:50.733951 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Nov 12 22:21:50.742373 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 12 22:21:50.758152 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 12 22:21:50.758559 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 12 22:21:50.795072 bash[1903]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:21:50.800620 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 12 22:21:50.809011 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 12 22:21:50.814718 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 12 22:21:50.834558 systemd[1]: Starting sshkeys.service... Nov 12 22:21:50.879909 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 12 22:21:50.933335 dbus-daemon[1830]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 12 22:21:50.933856 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Nov 12 22:21:50.946217 dbus-daemon[1830]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1850 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Nov 12 22:21:50.948251 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 12 22:21:50.973851 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (1720) Nov 12 22:21:50.977895 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 12 22:21:50.993127 systemd[1]: Starting polkit.service - Authorization Manager... Nov 12 22:21:51.025760 amazon-ssm-agent[1901]: Initializing new seelog logger Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: New Seelog Logger Creation Complete Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 processing appconfig overrides Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 processing appconfig overrides Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 processing appconfig overrides Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO Proxy environment variables: Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:21:51.039961 amazon-ssm-agent[1901]: 2024/11/12 22:21:51 processing appconfig overrides Nov 12 22:21:51.106899 polkitd[1933]: Started polkitd version 121 Nov 12 22:21:51.135834 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO https_proxy: Nov 12 22:21:51.154260 polkitd[1933]: Loading rules from directory /etc/polkit-1/rules.d Nov 12 22:21:51.154407 polkitd[1933]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 12 22:21:51.162836 polkitd[1933]: Finished loading, compiling and executing 2 rules Nov 12 22:21:51.164277 dbus-daemon[1830]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 12 22:21:51.164656 systemd[1]: Started polkit.service - Authorization Manager. Nov 12 22:21:51.174465 polkitd[1933]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 12 22:21:51.197123 containerd[1856]: time="2024-11-12T22:21:51.196997228Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 12 22:21:51.219975 locksmithd[1880]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 12 22:21:51.239876 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO http_proxy: Nov 12 22:21:51.259251 systemd-hostnamed[1850]: Hostname set to (transient) Nov 12 22:21:51.262027 systemd-resolved[1647]: System hostname changed to 'ip-172-31-25-242'. Nov 12 22:21:51.326888 coreos-metadata[1930]: Nov 12 22:21:51.325 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 22:21:51.327647 coreos-metadata[1930]: Nov 12 22:21:51.327 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Nov 12 22:21:51.328178 coreos-metadata[1930]: Nov 12 22:21:51.327 INFO Fetch successful Nov 12 22:21:51.328178 coreos-metadata[1930]: Nov 12 22:21:51.328 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Nov 12 22:21:51.330794 coreos-metadata[1930]: Nov 12 22:21:51.329 INFO Fetch successful Nov 12 22:21:51.334697 unknown[1930]: wrote ssh authorized keys file for user: core Nov 12 22:21:51.337327 containerd[1856]: time="2024-11-12T22:21:51.336308204Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:21:51.340882 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO no_proxy: Nov 12 22:21:51.350278 containerd[1856]: time="2024-11-12T22:21:51.350184284Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:21:51.350278 containerd[1856]: time="2024-11-12T22:21:51.350263448Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 22:21:51.350456 containerd[1856]: time="2024-11-12T22:21:51.350304116Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 22:21:51.350703 containerd[1856]: time="2024-11-12T22:21:51.350645588Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 22:21:51.350893 containerd[1856]: time="2024-11-12T22:21:51.350697920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 22:21:51.354081 containerd[1856]: time="2024-11-12T22:21:51.354000944Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:21:51.354081 containerd[1856]: time="2024-11-12T22:21:51.354068696Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:21:51.354515 containerd[1856]: time="2024-11-12T22:21:51.354447500Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:21:51.354515 containerd[1856]: time="2024-11-12T22:21:51.354508016Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 22:21:51.354694 containerd[1856]: time="2024-11-12T22:21:51.354546164Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:21:51.354694 containerd[1856]: time="2024-11-12T22:21:51.354572864Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 22:21:51.361522 containerd[1856]: time="2024-11-12T22:21:51.361373060Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:21:51.364210 containerd[1856]: time="2024-11-12T22:21:51.361935776Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:21:51.364210 containerd[1856]: time="2024-11-12T22:21:51.362190560Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:21:51.364210 containerd[1856]: time="2024-11-12T22:21:51.362224952Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 22:21:51.364210 containerd[1856]: time="2024-11-12T22:21:51.362432900Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 22:21:51.364210 containerd[1856]: time="2024-11-12T22:21:51.362537612Z" level=info msg="metadata content store policy set" policy=shared Nov 12 22:21:51.386206 containerd[1856]: time="2024-11-12T22:21:51.385454624Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 22:21:51.386206 containerd[1856]: time="2024-11-12T22:21:51.385539272Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 22:21:51.386206 containerd[1856]: time="2024-11-12T22:21:51.385591220Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 22:21:51.386206 containerd[1856]: time="2024-11-12T22:21:51.385630916Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 22:21:51.386206 containerd[1856]: time="2024-11-12T22:21:51.385668188Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 22:21:51.389222 containerd[1856]: time="2024-11-12T22:21:51.388648004Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 22:21:51.395633 containerd[1856]: time="2024-11-12T22:21:51.395338785Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398115957Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398190909Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398240997Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398286393Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398329545Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398360565Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398392581Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398433297Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398465853Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398497293Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398525325Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398567349Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398616957Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.399134 containerd[1856]: time="2024-11-12T22:21:51.398648649Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.399953 containerd[1856]: time="2024-11-12T22:21:51.398679357Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.399953 containerd[1856]: time="2024-11-12T22:21:51.398711997Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.402366 containerd[1856]: time="2024-11-12T22:21:51.401854365Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.401958129Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.404796669Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.404912349Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405023085Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405115749Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405200697Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405284157Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405330045Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405409401Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405473001Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.406260 containerd[1856]: time="2024-11-12T22:21:51.405503577Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 22:21:51.408713 containerd[1856]: time="2024-11-12T22:21:51.406529445Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 22:21:51.408713 containerd[1856]: time="2024-11-12T22:21:51.407880705Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 12 22:21:51.408713 containerd[1856]: time="2024-11-12T22:21:51.407920449Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 22:21:51.410138 containerd[1856]: time="2024-11-12T22:21:51.409171953Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 22:21:51.410138 containerd[1856]: time="2024-11-12T22:21:51.409787001Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.410138 containerd[1856]: time="2024-11-12T22:21:51.409978317Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 22:21:51.410138 containerd[1856]: time="2024-11-12T22:21:51.410092893Z" level=info msg="NRI interface is disabled by configuration." Nov 12 22:21:51.413449 containerd[1856]: time="2024-11-12T22:21:51.410677761Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 22:21:51.413902 containerd[1856]: time="2024-11-12T22:21:51.413730669Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 12 22:21:51.414864 containerd[1856]: time="2024-11-12T22:21:51.414494697Z" level=info msg="Connect containerd service" Nov 12 22:21:51.416648 containerd[1856]: time="2024-11-12T22:21:51.415112349Z" level=info msg="using legacy CRI server" Nov 12 22:21:51.417002 containerd[1856]: time="2024-11-12T22:21:51.416934573Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 22:21:51.420192 containerd[1856]: time="2024-11-12T22:21:51.417429525Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 22:21:51.423353 containerd[1856]: time="2024-11-12T22:21:51.423259077Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 12 22:21:51.424389 update-ssh-keys[2004]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:21:51.428521 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 12 22:21:51.433609 containerd[1856]: time="2024-11-12T22:21:51.432325665Z" level=info msg="Start subscribing containerd event" Nov 12 22:21:51.433609 containerd[1856]: time="2024-11-12T22:21:51.432422337Z" level=info msg="Start recovering state" Nov 12 22:21:51.433609 containerd[1856]: time="2024-11-12T22:21:51.432587505Z" level=info msg="Start event monitor" Nov 12 22:21:51.433609 containerd[1856]: time="2024-11-12T22:21:51.432624525Z" level=info msg="Start snapshots syncer" Nov 12 22:21:51.433609 containerd[1856]: time="2024-11-12T22:21:51.432650013Z" level=info msg="Start cni network conf syncer for default" Nov 12 22:21:51.433609 containerd[1856]: time="2024-11-12T22:21:51.432680217Z" level=info msg="Start streaming server" Nov 12 22:21:51.440000 systemd[1]: Finished sshkeys.service. Nov 12 22:21:51.447969 containerd[1856]: time="2024-11-12T22:21:51.441380985Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 22:21:51.447969 containerd[1856]: time="2024-11-12T22:21:51.441507693Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 22:21:51.447969 containerd[1856]: time="2024-11-12T22:21:51.441618981Z" level=info msg="containerd successfully booted in 0.247132s" Nov 12 22:21:51.443584 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 22:21:51.449157 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO Checking if agent identity type OnPrem can be assumed Nov 12 22:21:51.547830 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO Checking if agent identity type EC2 can be assumed Nov 12 22:21:51.645500 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO Agent will take identity from EC2 Nov 12 22:21:51.744890 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:21:51.844350 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:21:51.945872 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:21:52.043072 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Nov 12 22:21:52.135021 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Nov 12 22:21:52.135342 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [amazon-ssm-agent] Starting Core Agent Nov 12 22:21:52.135460 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [amazon-ssm-agent] registrar detected. Attempting registration Nov 12 22:21:52.135586 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [Registrar] Starting registrar module Nov 12 22:21:52.135703 amazon-ssm-agent[1901]: 2024-11-12 22:21:51 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Nov 12 22:21:52.135830 amazon-ssm-agent[1901]: 2024-11-12 22:21:52 INFO [EC2Identity] EC2 registration was successful. Nov 12 22:21:52.135941 amazon-ssm-agent[1901]: 2024-11-12 22:21:52 INFO [CredentialRefresher] credentialRefresher has started Nov 12 22:21:52.136049 amazon-ssm-agent[1901]: 2024-11-12 22:21:52 INFO [CredentialRefresher] Starting credentials refresher loop Nov 12 22:21:52.136187 amazon-ssm-agent[1901]: 2024-11-12 22:21:52 INFO EC2RoleProvider Successfully connected with instance profile role credentials Nov 12 22:21:52.143430 amazon-ssm-agent[1901]: 2024-11-12 22:21:52 INFO [CredentialRefresher] Next credential rotation will be in 32.483305311533336 minutes Nov 12 22:21:52.449193 sshd_keygen[1867]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 12 22:21:52.492859 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 12 22:21:52.504267 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 12 22:21:52.511950 systemd[1]: Started sshd@0-172.31.25.242:22-147.75.109.163:53252.service - OpenSSH per-connection server daemon (147.75.109.163:53252). Nov 12 22:21:52.528256 systemd[1]: issuegen.service: Deactivated successfully. Nov 12 22:21:52.528587 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 12 22:21:52.541331 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 12 22:21:52.570057 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 12 22:21:52.583455 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 12 22:21:52.601002 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 12 22:21:52.603480 systemd[1]: Reached target getty.target - Login Prompts. Nov 12 22:21:52.605977 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 12 22:21:52.610404 systemd[1]: Startup finished in 1.170s (kernel) + 7.352s (initrd) + 7.506s (userspace) = 16.029s. Nov 12 22:21:52.761487 sshd[2057]: Accepted publickey for core from 147.75.109.163 port 53252 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:21:52.765707 sshd-session[2057]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:21:52.786351 systemd-logind[1838]: New session 1 of user core. Nov 12 22:21:52.788293 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 12 22:21:52.799479 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 12 22:21:52.821434 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 12 22:21:52.829355 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 12 22:21:52.843671 (systemd)[2072]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 12 22:21:53.060139 systemd[2072]: Queued start job for default target default.target. Nov 12 22:21:53.073384 systemd[2072]: Created slice app.slice - User Application Slice. Nov 12 22:21:53.073465 systemd[2072]: Reached target paths.target - Paths. Nov 12 22:21:53.073499 systemd[2072]: Reached target timers.target - Timers. Nov 12 22:21:53.076494 systemd[2072]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 12 22:21:53.101386 systemd[2072]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 12 22:21:53.101705 systemd[2072]: Reached target sockets.target - Sockets. Nov 12 22:21:53.101800 systemd[2072]: Reached target basic.target - Basic System. Nov 12 22:21:53.101953 systemd[2072]: Reached target default.target - Main User Target. Nov 12 22:21:53.102050 systemd[2072]: Startup finished in 247ms. Nov 12 22:21:53.102971 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 12 22:21:53.114115 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 12 22:21:53.166070 amazon-ssm-agent[1901]: 2024-11-12 22:21:53 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Nov 12 22:21:53.267970 amazon-ssm-agent[1901]: 2024-11-12 22:21:53 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2082) started Nov 12 22:21:53.280949 systemd[1]: Started sshd@1-172.31.25.242:22-147.75.109.163:49598.service - OpenSSH per-connection server daemon (147.75.109.163:49598). Nov 12 22:21:53.303498 ntpd[1834]: Listen normally on 6 eth0 [fe80::4d5:85ff:fe7b:a8d%2]:123 Nov 12 22:21:53.306489 ntpd[1834]: 12 Nov 22:21:53 ntpd[1834]: Listen normally on 6 eth0 [fe80::4d5:85ff:fe7b:a8d%2]:123 Nov 12 22:21:53.367849 amazon-ssm-agent[1901]: 2024-11-12 22:21:53 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Nov 12 22:21:53.473396 sshd[2089]: Accepted publickey for core from 147.75.109.163 port 49598 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:21:53.476170 sshd-session[2089]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:21:53.484946 systemd-logind[1838]: New session 2 of user core. Nov 12 22:21:53.492047 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 12 22:21:53.623591 sshd[2096]: Connection closed by 147.75.109.163 port 49598 Nov 12 22:21:53.622791 sshd-session[2089]: pam_unix(sshd:session): session closed for user core Nov 12 22:21:53.628407 systemd[1]: sshd@1-172.31.25.242:22-147.75.109.163:49598.service: Deactivated successfully. Nov 12 22:21:53.631565 systemd[1]: session-2.scope: Deactivated successfully. Nov 12 22:21:53.634240 systemd-logind[1838]: Session 2 logged out. Waiting for processes to exit. Nov 12 22:21:53.636039 systemd-logind[1838]: Removed session 2. Nov 12 22:21:53.668240 systemd[1]: Started sshd@2-172.31.25.242:22-147.75.109.163:49606.service - OpenSSH per-connection server daemon (147.75.109.163:49606). Nov 12 22:21:53.847266 sshd[2101]: Accepted publickey for core from 147.75.109.163 port 49606 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:21:53.849610 sshd-session[2101]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:21:53.857700 systemd-logind[1838]: New session 3 of user core. Nov 12 22:21:53.866101 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 12 22:21:53.987349 sshd[2103]: Connection closed by 147.75.109.163 port 49606 Nov 12 22:21:53.988390 sshd-session[2101]: pam_unix(sshd:session): session closed for user core Nov 12 22:21:53.994651 systemd[1]: sshd@2-172.31.25.242:22-147.75.109.163:49606.service: Deactivated successfully. Nov 12 22:21:53.998476 systemd[1]: session-3.scope: Deactivated successfully. Nov 12 22:21:54.000035 systemd-logind[1838]: Session 3 logged out. Waiting for processes to exit. Nov 12 22:21:54.001730 systemd-logind[1838]: Removed session 3. Nov 12 22:21:54.024307 systemd[1]: Started sshd@3-172.31.25.242:22-147.75.109.163:49622.service - OpenSSH per-connection server daemon (147.75.109.163:49622). Nov 12 22:21:54.216480 sshd[2108]: Accepted publickey for core from 147.75.109.163 port 49622 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:21:54.218914 sshd-session[2108]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:21:54.228029 systemd-logind[1838]: New session 4 of user core. Nov 12 22:21:54.238044 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 12 22:21:54.366696 sshd[2110]: Connection closed by 147.75.109.163 port 49622 Nov 12 22:21:54.366017 sshd-session[2108]: pam_unix(sshd:session): session closed for user core Nov 12 22:21:54.372375 systemd-logind[1838]: Session 4 logged out. Waiting for processes to exit. Nov 12 22:21:54.374378 systemd[1]: sshd@3-172.31.25.242:22-147.75.109.163:49622.service: Deactivated successfully. Nov 12 22:21:54.377822 systemd[1]: session-4.scope: Deactivated successfully. Nov 12 22:21:54.380025 systemd-logind[1838]: Removed session 4. Nov 12 22:21:54.406316 systemd[1]: Started sshd@4-172.31.25.242:22-147.75.109.163:49628.service - OpenSSH per-connection server daemon (147.75.109.163:49628). Nov 12 22:21:54.595520 sshd[2115]: Accepted publickey for core from 147.75.109.163 port 49628 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:21:54.598282 sshd-session[2115]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:21:54.605879 systemd-logind[1838]: New session 5 of user core. Nov 12 22:21:54.617041 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 12 22:21:54.759733 sudo[2118]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 12 22:21:54.760420 sudo[2118]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:21:54.775424 sudo[2118]: pam_unix(sudo:session): session closed for user root Nov 12 22:21:54.799543 sshd[2117]: Connection closed by 147.75.109.163 port 49628 Nov 12 22:21:54.799526 sshd-session[2115]: pam_unix(sshd:session): session closed for user core Nov 12 22:21:54.805407 systemd[1]: sshd@4-172.31.25.242:22-147.75.109.163:49628.service: Deactivated successfully. Nov 12 22:21:54.809340 systemd[1]: session-5.scope: Deactivated successfully. Nov 12 22:21:54.813571 systemd-logind[1838]: Session 5 logged out. Waiting for processes to exit. Nov 12 22:21:54.816257 systemd-logind[1838]: Removed session 5. Nov 12 22:21:54.839333 systemd[1]: Started sshd@5-172.31.25.242:22-147.75.109.163:49644.service - OpenSSH per-connection server daemon (147.75.109.163:49644). Nov 12 22:21:55.036794 sshd[2123]: Accepted publickey for core from 147.75.109.163 port 49644 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:21:55.039324 sshd-session[2123]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:21:55.046843 systemd-logind[1838]: New session 6 of user core. Nov 12 22:21:55.059018 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 12 22:21:55.164545 sudo[2127]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 12 22:21:55.165330 sudo[2127]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:21:55.172664 sudo[2127]: pam_unix(sudo:session): session closed for user root Nov 12 22:21:55.185169 sudo[2126]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 12 22:21:55.186656 sudo[2126]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:21:55.219684 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 12 22:21:55.274459 augenrules[2149]: No rules Nov 12 22:21:55.277097 systemd[1]: audit-rules.service: Deactivated successfully. Nov 12 22:21:55.278930 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 12 22:21:55.281287 sudo[2126]: pam_unix(sudo:session): session closed for user root Nov 12 22:21:55.304318 sshd[2125]: Connection closed by 147.75.109.163 port 49644 Nov 12 22:21:55.305104 sshd-session[2123]: pam_unix(sshd:session): session closed for user core Nov 12 22:21:55.312669 systemd[1]: sshd@5-172.31.25.242:22-147.75.109.163:49644.service: Deactivated successfully. Nov 12 22:21:55.316139 systemd[1]: session-6.scope: Deactivated successfully. Nov 12 22:21:55.317774 systemd-logind[1838]: Session 6 logged out. Waiting for processes to exit. Nov 12 22:21:55.319687 systemd-logind[1838]: Removed session 6. Nov 12 22:21:57.558381 systemd-resolved[1647]: Clock change detected. Flushing caches. Nov 12 22:22:03.090824 systemd[1]: Started sshd@6-172.31.25.242:22-147.75.109.163:56796.service - OpenSSH per-connection server daemon (147.75.109.163:56796). Nov 12 22:22:03.280480 sshd[2157]: Accepted publickey for core from 147.75.109.163 port 56796 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:22:03.282924 sshd-session[2157]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:22:03.290153 systemd-logind[1838]: New session 7 of user core. Nov 12 22:22:03.298569 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 12 22:22:03.409499 sudo[2164]: core : PWD=/tmp/tmp.6gTFGk2lPR ; USER=root ; COMMAND=/usr/sbin/ldd /usr/bin/nc /usr/bin/timeout Nov 12 22:22:03.411321 sudo[2164]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:22:03.431739 sudo[2164]: pam_unix(sudo:session): session closed for user root Nov 12 22:22:03.442866 sudo[2176]: core : PWD=/tmp/tmp.6gTFGk2lPR ; USER=root ; COMMAND=/usr/sbin/rsync -av --relative --copy-links /usr/bin/nc /usr/bin/timeout /lib/ld-linux-aarch64.so.1 /lib64/ld-linux-aarch64.so.1 /lib64/libbsd.so.0 /lib64/libc.so.6 /lib64/libmd.so.0 /lib64/libresolv.so.2 ./ Nov 12 22:22:03.443523 sudo[2176]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:22:03.520246 sudo[2176]: pam_unix(sudo:session): session closed for user root Nov 12 22:22:03.531236 sudo[2160]: core : PWD=/tmp/tmp.6gTFGk2lPR ; USER=root ; COMMAND=/usr/sbin/docker build -t netcat . Nov 12 22:22:03.531994 sudo[2160]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:22:03.725881 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 12 22:22:03.742827 (dockerd)[2187]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Nov 12 22:22:04.090967 dockerd[2187]: time="2024-11-12T22:22:04.090813252Z" level=info msg="Starting up" Nov 12 22:22:04.226686 systemd[1]: var-lib-docker-metacopy\x2dcheck573363034-merged.mount: Deactivated successfully. Nov 12 22:22:04.241998 dockerd[2187]: time="2024-11-12T22:22:04.241921573Z" level=info msg="Loading containers: start." Nov 12 22:22:04.491339 kernel: Initializing XFRM netlink socket Nov 12 22:22:04.525446 (udev-worker)[2209]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:22:04.624589 systemd-networkd[1693]: docker0: Link UP Nov 12 22:22:04.658737 dockerd[2187]: time="2024-11-12T22:22:04.658581183Z" level=info msg="Loading containers: done." Nov 12 22:22:04.682155 dockerd[2187]: time="2024-11-12T22:22:04.682076355Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Nov 12 22:22:04.682407 dockerd[2187]: time="2024-11-12T22:22:04.682223439Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Nov 12 22:22:04.682490 dockerd[2187]: time="2024-11-12T22:22:04.682458759Z" level=info msg="Daemon has completed initialization" Nov 12 22:22:04.737950 dockerd[2187]: time="2024-11-12T22:22:04.737771523Z" level=info msg="API listen on /run/docker.sock" Nov 12 22:22:04.738902 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 12 22:22:05.305286 systemd[1]: var-lib-docker-tmp-buildkit\x2dmount1067871265.mount: Deactivated successfully. Nov 12 22:22:05.394475 sudo[2160]: pam_unix(sudo:session): session closed for user root Nov 12 22:22:05.419111 sshd[2159]: Connection closed by 147.75.109.163 port 56796 Nov 12 22:22:05.420207 sshd-session[2157]: pam_unix(sshd:session): session closed for user core Nov 12 22:22:05.428194 systemd[1]: sshd@6-172.31.25.242:22-147.75.109.163:56796.service: Deactivated successfully. Nov 12 22:22:05.431680 systemd[1]: session-7.scope: Deactivated successfully. Nov 12 22:22:05.433083 systemd-logind[1838]: Session 7 logged out. Waiting for processes to exit. Nov 12 22:22:05.436148 systemd-logind[1838]: Removed session 7. Nov 12 22:22:09.645425 systemd[1]: Started sshd@7-172.31.25.242:22-147.75.109.163:57606.service - OpenSSH per-connection server daemon (147.75.109.163:57606). Nov 12 22:22:09.838195 sshd[2393]: Accepted publickey for core from 147.75.109.163 port 57606 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:22:09.840596 sshd-session[2393]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:22:09.849668 systemd-logind[1838]: New session 8 of user core. Nov 12 22:22:09.861568 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 12 22:22:10.004088 systemd[1]: var-lib-docker-overlay2-5965ba9f82926bf34d76f8a05a564913fae29e4fadd0a53ce99db9fbea661876\x2dinit-merged.mount: Deactivated successfully. Nov 12 22:22:10.055350 kernel: docker0: port 1(veth9f8a5a5) entered blocking state Nov 12 22:22:10.055564 kernel: docker0: port 1(veth9f8a5a5) entered disabled state Nov 12 22:22:10.055627 kernel: veth9f8a5a5: entered allmulticast mode Nov 12 22:22:10.056837 kernel: veth9f8a5a5: entered promiscuous mode Nov 12 22:22:10.058259 systemd-networkd[1693]: veth9f8a5a5: Link UP Nov 12 22:22:10.064528 (udev-worker)[2409]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:22:10.065475 (udev-worker)[2408]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:22:10.231916 containerd[1856]: time="2024-11-12T22:22:10.231573018Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:22:10.234028 containerd[1856]: time="2024-11-12T22:22:10.231948822Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:22:10.234028 containerd[1856]: time="2024-11-12T22:22:10.232045254Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:22:10.234028 containerd[1856]: time="2024-11-12T22:22:10.232358382Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:22:10.265611 systemd[1]: Started docker-1dfba4c4d19b44e1189c283b654d333b9d82601056236538cb1994389b09c0b8.scope - libcontainer container 1dfba4c4d19b44e1189c283b654d333b9d82601056236538cb1994389b09c0b8. Nov 12 22:22:10.349497 kernel: eth0: renamed from vethdf7a708 Nov 12 22:22:10.357063 kernel: docker0: port 1(veth9f8a5a5) entered blocking state Nov 12 22:22:10.357233 kernel: docker0: port 1(veth9f8a5a5) entered forwarding state Nov 12 22:22:10.357194 systemd-networkd[1693]: veth9f8a5a5: Gained carrier Nov 12 22:22:10.358099 systemd-networkd[1693]: docker0: Gained carrier Nov 12 22:22:10.419704 systemd[1]: docker-1dfba4c4d19b44e1189c283b654d333b9d82601056236538cb1994389b09c0b8.scope: Deactivated successfully. Nov 12 22:22:10.454816 dockerd[2187]: time="2024-11-12T22:22:10.454715108Z" level=info msg="ignoring event" container=1dfba4c4d19b44e1189c283b654d333b9d82601056236538cb1994389b09c0b8 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 12 22:22:10.455815 containerd[1856]: time="2024-11-12T22:22:10.455625596Z" level=info msg="shim disconnected" id=1dfba4c4d19b44e1189c283b654d333b9d82601056236538cb1994389b09c0b8 namespace=moby Nov 12 22:22:10.455815 containerd[1856]: time="2024-11-12T22:22:10.455760464Z" level=warning msg="cleaning up after shim disconnected" id=1dfba4c4d19b44e1189c283b654d333b9d82601056236538cb1994389b09c0b8 namespace=moby Nov 12 22:22:10.455815 containerd[1856]: time="2024-11-12T22:22:10.455783132Z" level=info msg="cleaning up dead shim" namespace=moby Nov 12 22:22:10.493332 kernel: vethdf7a708: renamed from eth0 Nov 12 22:22:10.507345 kernel: docker0: port 1(veth9f8a5a5) entered disabled state Nov 12 22:22:10.507989 systemd-networkd[1693]: veth9f8a5a5: Lost carrier Nov 12 22:22:10.511341 kernel: docker0: port 1(veth9f8a5a5) entered disabled state Nov 12 22:22:10.511633 systemd-networkd[1693]: veth9f8a5a5: Link DOWN Nov 12 22:22:10.515787 kernel: veth9f8a5a5 (unregistering): left allmulticast mode Nov 12 22:22:10.515907 kernel: veth9f8a5a5 (unregistering): left promiscuous mode Nov 12 22:22:10.515954 kernel: docker0: port 1(veth9f8a5a5) entered disabled state Nov 12 22:22:10.571471 sshd[2395]: Connection closed by 147.75.109.163 port 57606 Nov 12 22:22:10.572451 sshd-session[2393]: pam_unix(sshd:session): session closed for user core Nov 12 22:22:10.578824 systemd[1]: sshd@7-172.31.25.242:22-147.75.109.163:57606.service: Deactivated successfully. Nov 12 22:22:10.582312 systemd[1]: session-8.scope: Deactivated successfully. Nov 12 22:22:10.584167 systemd-logind[1838]: Session 8 logged out. Waiting for processes to exit. Nov 12 22:22:10.586415 systemd-logind[1838]: Removed session 8. Nov 12 22:22:10.992384 systemd[1]: var-lib-docker-overlay2-5965ba9f82926bf34d76f8a05a564913fae29e4fadd0a53ce99db9fbea661876-merged.mount: Deactivated successfully. Nov 12 22:22:11.107657 systemd-networkd[1693]: docker0: Lost carrier Nov 12 22:22:12.003712 systemd-networkd[1693]: docker0: Gained IPv6LL