1. BdsDxe: failed to load Boot0001 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x1,0x2)/Pci(0x0,0x0): Not Found
2.  
3. >>Start PXE over IPv4.
4.   Station IP address is 192.168.24.15
5.  
6.   Server IP address is 192.168.24.1
7.   NBP filename is snponly.efi
8.   NBP filesize is 218624 Bytes
9.  Downloading NBP file...
10.  
11.   NBP file downloaded successfully.
12. BdsDxe: loading Boot0002 "UEFI PXEv4 (MAC:525400123458)" from VenHw(837DCA9E-E874-4D82-B29A-23FE0E23D1E2,003E000A00000000)/MAC(525400123458,0x1)/IPv4(0.0.0.0,0x0,DHCP,0.0.0.0,0.0.0.0,0.0.0.0)
13. BdsDxe: starting Boot0002 "UEFI PXEv4 (MAC:525400123458)" from VenHw(837DCA9E-E874-4D82-B29A-23FE0E23D1E2,003E000A00000000)/MAC(525400123458,0x1)/IPv4(0.0.0.0,0x0,DHCP,0.0.0.0,0.0.0.0,0.0.0.0)
14. iPXE initialising devices...ok
15.  
16.  
17.  
18. iPXE 1.21.1+ (g98dd) -- Open Source Network Boot Firmware -- https://ipxe.org
19. Features: DNS HTTP iSCSI TFTP VLAN AoE EFI Menu
20.  
21. net0: 52:54:00:12:34:58 using SNP on SNP-0x23f01ac18 (Ethernet) [open]
22.   [Link:up, TX:0 TXE:1 RX:0 RXE:0]
23.   [TXE: 1 x "Network unreachable (https://ipxe.org/28086090)"]
24. Configuring (net0 52:54:00:12:34:58)...... ok
25. net0: 192.168.24.15/255.255.255.0 gw 192.168.24.1
26. net0: fe80::5054:ff:fe12:3458/64
27. Next server: 192.168.24.1
28. Filename: http://192.168.24.1:8088/boot.ipxe
29. http://192.168.24.1:8088/boot.ipxe... ok
30. boot.ipxe : 758 bytes [script]
31. Attempting to boot from MAC 52-54-00-12-34-58
32. pxelinux.cfg/52-54-00-12-34-58... ok
33. http://192.168.24.1:8088/8d5ed008-efea-4df7-a190-aab8274a3bd5/deploy_kernel... ok
34. http://192.168.24.1:8088/8d5ed008-efea-4df7-a190-aab8274a3bd5/deploy_ramdisk... ok
35. EFI stub: Booting Linux Kernel...
36. EFI stub: Loaded initrd from LINUX_EFI_INITRD_MEDIA_GUID device path
37. EFI stub: Generating empty DTB
38. EFI stub: Exiting boot services...
39. [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd070]
40. [    0.000000] Linux version 5.14.0-386.el9.aarch64 (mockbuild@aarch64-01.stream.rdu2.redhat.com) (gcc (GCC) 11.4.1 20230605 (Red Hat 11.4.1-2), GNU ld version 2.35.2-42.el9) #1 SMP PREEMPT_DYNAMIC Wed Nov 15 11:33:32 UTC 2023
41. [    0.000000] The list of certified hardware and cloud instances for Red Hat Enterprise Linux 9 can be viewed at the Red Hat Ecosystem Catalog, https://catalog.redhat.com.
42. [    0.000000] efi: EFI v2.7 by EDK II
43. [    0.000000] efi: SMBIOS 3.0=0x23fed0000 MEMATTR=0x23f010018 ACPI 2.0=0x23c740018 INITRD=0x23cb43c18 RNG=0x23c74fe18 MEMRESERVE=0x23cb43c98
44. [    0.000000] ACPI: Early table checksum verification disabled
45. [    0.000000] ACPI: RSDP 0x000000023C740018 000024 (v02 BOCHS )
46. [    0.000000] ACPI: XSDT 0x000000023C74FE98 00006C (v01 BOCHS  BXPC     00000001      01000013)
47. [    0.000000] ACPI: FACP 0x000000023C74FA98 000114 (v06 BOCHS  BXPC     00000001 BXPC 00000001)
48. [    0.000000] ACPI: DSDT 0x000000023C747518 001516 (v02 BOCHS  BXPC     00000001 BXPC 00000001)
49. [    0.000000] ACPI: APIC 0x000000023C74D898 0002DC (v04 BOCHS  BXPC     00000001 BXPC 00000001)
50. [    0.000000] ACPI: PPTT 0x000000023C74E718 000164 (v02 BOCHS  BXPC     00000001 BXPC 00000001)
51. [    0.000000] ACPI: GTDT 0x000000023C74FC18 000060 (v02 BOCHS  BXPC     00000001 BXPC 00000001)
52. [    0.000000] ACPI: MCFG 0x000000023C74FF98 00003C (v01 BOCHS  BXPC     00000001 BXPC 00000001)
53. [    0.000000] ACPI: SPCR 0x000000023C74FD18 000050 (v02 BOCHS  BXPC     00000001 BXPC 00000001)
54. [    0.000000] ACPI: DBG2 0x000000023C74FD98 000057 (v00 BOCHS  BXPC     00000001 BXPC 00000001)
55. [    0.000000] ACPI: IORT 0x000000023C74DC98 000080 (v03 BOCHS  BXPC     00000001 BXPC 00000001)
56. [    0.000000] ACPI: BGRT 0x000000023C74E698 000038 (v01 INTEL  EDK2     00000002      01000013)
57. [    0.000000] ACPI: SPCR: console: pl011,mmio,0x9000000,9600
58. [    0.000000] NUMA: Failed to initialise from firmware
59. [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000023fffffff]
60. [    0.000000] NUMA: NODE_DATA [mem 0x23ec29f80-0x23ec36fff]
61. [    0.000000] Zone ranges:
62. [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]
63. [    0.000000]   DMA32    empty
64. [    0.000000]   Normal   [mem 0x0000000100000000-0x000000023fffffff]
65. [    0.000000]   Device   empty
66. [    0.000000] Movable zone start for each node
67. [    0.000000] Early memory node ranges
68. [    0.000000]   node   0: [mem 0x0000000040000000-0x000000023c76ffff]
69. [    0.000000]   node   0: [mem 0x000000023c770000-0x000000023cb3ffff]
70. [    0.000000]   node   0: [mem 0x000000023cb40000-0x000000023fe1ffff]
71. [    0.000000]   node   0: [mem 0x000000023fe20000-0x000000023feaffff]
72. [    0.000000]   node   0: [mem 0x000000023feb0000-0x000000023febffff]
73. [    0.000000]   node   0: [mem 0x000000023fec0000-0x000000023ffdffff]
74. [    0.000000]   node   0: [mem 0x000000023ffe0000-0x000000023fffffff]
75. [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000023fffffff]
76. [    0.000000] psci: probing for conduit method from ACPI.
77. [    0.000000] psci: PSCIv1.1 detected in firmware.
78. [    0.000000] psci: Using standard PSCI v0.2 function IDs
79. [    0.000000] psci: Trusted OS migration not required
80. [    0.000000] psci: SMC Calling Convention v1.0
81. [    0.000000] percpu: Embedded 32 pages/cpu s93160 r8192 d29720 u131072
82. [    0.000000] Detected PIPT I-cache on CPU0
83. [    0.000000] CPU features: detected: Spectre-v3a
84. [    0.000000] CPU features: detected: Spectre-v4
85. [    0.000000] CPU features: detected: Spectre-BHB
86. [    0.000000] CPU features: kernel page table isolation forced ON by KASLR
87. [    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
88. [    0.000000] CPU features: detected: ARM erratum 834220
89. [    0.000000] CPU features: detected: ARM erratum 832075
90. [    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
91. [    0.000000] alternatives: applying boot alternatives
92. [    0.000000] Fallback order for Node 0: 0
93. [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2064384
94. [    0.000000] Policy zone: Normal
95. [    0.000000] Kernel command line: deploy_kernel selinux=0 troubleshoot=0 text nofb nomodeset vga=normal ipa-debug=1 ipa-api-url=http://192.168.24.1:6385 ipa-global-request-id=req-f843aae4-963b-42aa-8165-31fed61bf55e BOOTIF=52:54:00:12:34:58 initrd=deploy_ramdisk
96. [    0.000000] Booted with the nomodeset parameter. Only the system framebuffer will be available
97. [    0.000000] Unknown kernel command line parameters "deploy_kernel text nofb troubleshoot=0 vga=normal ipa-debug=1 ipa-api-url=http://192.168.24.1:6385 ipa-global-request-id=req-f843aae4-963b-42aa-8165-31fed61bf55e BOOTIF=52:54:00:12:34:58", will be passed to user space.
98. [    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
99. [    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
100. [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
101. [    0.000000] software IO TLB: area num 8.
102. [    0.000000] software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB)
103. [    0.000000] Memory: 7657556K/8388608K available (13056K kernel code, 5464K rwdata, 10880K rodata, 5888K init, 11408K bss, 731052K reserved, 0K cma-reserved)
104. [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
105. [    0.000000] ftrace: allocating 47261 entries in 185 pages
106. [    0.000000] ftrace: allocated 185 pages with 5 groups
107. [    0.000000] trace event string verifier disabled
108. [    0.000000] Dynamic Preempt: voluntary
109. [    0.000000] rcu: Preemptible hierarchical RCU implementation.
110. [    0.000000] rcu:  RCU restricting CPUs from NR_CPUS=4096 to nr_cpu_ids=8.
111. [    0.000000]  Trampoline variant of Tasks RCU enabled.
112. [    0.000000]  Rude variant of Tasks RCU enabled.
113. [    0.000000]  Tracing variant of Tasks RCU enabled.
114. [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
115. [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
116. [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
117. [    0.000000] Root IRQ handler: gic_handle_irq
118. [    0.000000] GICv2m: ACPI overriding V2M MSI_TYPER (base:80, num:64)
119. [    0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143]
120. [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
121. [    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt).
122. [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
123. [    0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
124. [    0.000169] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
125. [    0.000233] Console: colour dummy device 80x25
126. [    0.000282] ACPI: Core revision 20221020
127. [    0.000423] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000)
128. [    0.000424] pid_max: default: 32768 minimum: 301
129. [    0.000639] LSM: initializing lsm=lockdown,capability,yama,integrity,bpf
130. [    0.000662] Yama: becoming mindful.
131. [    0.000681] LSM support for eBPF active
132. [    0.000856] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
133. [    0.000890] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
134. [    0.001713] cblist_init_generic: Setting adjustable number of callback queues.
135. [    0.001714] cblist_init_generic: Setting shift to 3 and lim to 1.
136. [    0.001726] cblist_init_generic: Setting shift to 3 and lim to 1.
137. [    0.001761] cblist_init_generic: Setting shift to 3 and lim to 1.
138. [    0.001800] rcu: Hierarchical SRCU implementation.
139. [    0.001801] rcu:  Max phase no-delay instances is 1000.
140. [    0.002196] Remapping and enabling EFI services.
141. [    0.002498] smp: Bringing up secondary CPUs ...
142. [    0.002837] Detected PIPT I-cache on CPU1
143. [    0.002906] CPU1: Booted secondary processor 0x0000000001 [0x411fd070]
144. [    0.003245] Detected PIPT I-cache on CPU2
145. [    0.003328] CPU2: Booted secondary processor 0x0000000002 [0x411fd070]
146. [    0.003650] Detected PIPT I-cache on CPU3
147. [    0.003747] CPU3: Booted secondary processor 0x0000000003 [0x411fd070]
148. [    0.004112] Detected PIPT I-cache on CPU4
149. [    0.004227] CPU4: Booted secondary processor 0x0000000004 [0x411fd070]
150. [    0.004530] Detected PIPT I-cache on CPU5
151. [    0.004659] CPU5: Booted secondary processor 0x0000000005 [0x411fd070]
152. [    0.004924] Detected PIPT I-cache on CPU6
153. [    0.005068] CPU6: Booted secondary processor 0x0000000006 [0x411fd070]
154. [    0.005349] Detected PIPT I-cache on CPU7
155. [    0.005509] CPU7: Booted secondary processor 0x0000000007 [0x411fd070]
156. [    0.005580] smp: Brought up 1 node, 8 CPUs
157. [    0.005581] SMP: Total of 8 processors activated.
158. [    0.005582] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
159. [    0.005582] CPU features: detected: CRC32 instructions
160. [    0.005583] CPU features: detected: Data independent timing control (DIT)
161. [    0.005583] CPU features: detected: RAS Extension Support
162. [    0.014100] CPU: All CPU(s) started at EL1
163. [    0.014111] alternatives: applying system-wide alternatives
164. [    0.019141] devtmpfs: initialized
165. [    0.019653] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
166. [    0.019655] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
167. [    0.019719] pinctrl core: initialized pinctrl subsystem
168. [    0.019785] SMBIOS 3.0.0 present.
169. [    0.019787] DMI: QEMU QEMU Virtual Machine, BIOS edk2-stable202302-for-qemu 03/01/2023
170. [    0.020210] NET: Registered PF_NETLINK/PF_ROUTE protocol family
171. [    0.020860] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
172. [    0.021181] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
173. [    0.021448] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
174. [    0.021473] audit: initializing netlink subsys (disabled)
175. [    0.021595] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1
176. [    0.021632] thermal_sys: Registered thermal governor 'fair_share'
177. [    0.021632] thermal_sys: Registered thermal governor 'step_wise'
178. [    0.021632] thermal_sys: Registered thermal governor 'user_space'
179. [    0.021643] cpuidle: using governor menu
180. [    0.021668] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
181. [    0.022132] ASID allocator initialised with 128 entries
182. [    0.022155] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
183. [    0.022195] Serial: AMBA PL011 UART driver
184. [    0.022294] KASLR enabled
185. [    0.024282] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
186. [    0.024284] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
187. [    0.024284] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
188. [    0.024285] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
189. [    0.024285] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
190. [    0.024285] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
191. [    0.024285] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
192. [    0.024286] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
193. [    0.025223] cryptd: max_cpu_qlen set to 1000
194. [    0.025727] ACPI: Added _OSI(Module Device)
195. [    0.025728] ACPI: Added _OSI(Processor Device)
196. [    0.025728] ACPI: Added _OSI(3.0 _SCP Extensions)
197. [    0.025729] ACPI: Added _OSI(Processor Aggregator Device)
198. [    0.025729] ACPI: Added _OSI(Linux-Dell-Video)
199. [    0.025729] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
200. [    0.025730] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
201. [    0.026020] ACPI: 1 ACPI AML tables successfully acquired and loaded
202. [    0.026166] ACPI: Interpreter enabled
203. [    0.026167] ACPI: Using GIC for interrupt routing
204. [    0.026188] ACPI: MCFG table detected, 1 entries
205. [    0.026931] ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA
206. [    0.026999] printk: console [ttyAMA0] enabled
207. [    0.027078] printk: console [ttyAMA0] printing thread started
208. [    0.027569] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
209. [    0.027590] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI EDR HPX-Type3]
210. [    0.027611] acpi PNP0A08:00: _OSC: platform does not support [LTR DPC]
211. [    0.027634] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability]
212. [    0.027688] acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00
213. [    0.027694] acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
214. [    0.027699] ACPI: Remapped I/O 0x000000003eff0000 to [io  0x0000-0xffff window]
215. [    0.027721] PCI host bridge to bus 0000:00
216. [    0.027721] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window]
217. [    0.027722] pci_bus 0000:00: root bus resource [io  0x0000-0xffff window]
218. [    0.027723] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window]
219. [    0.027723] pci_bus 0000:00: root bus resource [bus 00-ff]
220. [    0.027740] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000
221. [    0.028040] pci 0000:00:01.0: [1b36:000c] type 01 class 0x060400
222. [    0.029140] pci 0000:00:01.0: reg 0x10: [mem 0x1200e000-0x1200efff]
223. [    0.043022] pci 0000:00:01.1: [1b36:000c] type 01 class 0x060400
224. [    0.044132] pci 0000:00:01.1: reg 0x10: [mem 0x1200d000-0x1200dfff]
225. [    0.046612] pci 0000:00:01.2: [1b36:000c] type 01 class 0x060400
226. [    0.047725] pci 0000:00:01.2: reg 0x10: [mem 0x1200c000-0x1200cfff]
227. [    0.050074] pci 0000:00:01.3: [1b36:000c] type 01 class 0x060400
228. [    0.051074] pci 0000:00:01.3: reg 0x10: [mem 0x1200b000-0x1200bfff]
229. [    0.056318] pci 0000:00:01.4: [1b36:000c] type 01 class 0x060400
230. [    0.057300] pci 0000:00:01.4: reg 0x10: [mem 0x1200a000-0x1200afff]
231. [    0.060047] pci 0000:00:01.5: [1b36:000c] type 01 class 0x060400
232. [    0.061029] pci 0000:00:01.5: reg 0x10: [mem 0x12009000-0x12009fff]
233. [    0.063773] pci 0000:00:01.6: [1b36:000c] type 01 class 0x060400
234. [    0.064800] pci 0000:00:01.6: reg 0x10: [mem 0x12008000-0x12008fff]
235. [    0.067277] pci 0000:00:01.7: [1b36:000c] type 01 class 0x060400
236. [    0.068420] pci 0000:00:01.7: reg 0x10: [mem 0x12007000-0x12007fff]
237. [    0.071424] pci 0000:00:02.0: [1b36:000c] type 01 class 0x060400
238. [    0.072484] pci 0000:00:02.0: reg 0x10: [mem 0x12006000-0x12006fff]
239. [    0.075385] pci 0000:00:02.1: [1b36:000c] type 01 class 0x060400
240. [    0.076506] pci 0000:00:02.1: reg 0x10: [mem 0x12005000-0x12005fff]
241. [    0.079118] pci 0000:00:02.2: [1b36:000c] type 01 class 0x060400
242. [    0.096194] pci 0000:00:02.2: reg 0x10: [mem 0x12004000-0x12004fff]
243. [    0.098736] pci 0000:00:02.3: [1b36:000c] type 01 class 0x060400
244. [    0.099802] pci 0000:00:02.3: reg 0x10: [mem 0x12003000-0x12003fff]
245. [    0.102165] pci 0000:00:02.4: [1b36:000c] type 01 class 0x060400
246. [    0.103100] pci 0000:00:02.4: reg 0x10: [mem 0x12002000-0x12002fff]
247. [    0.105513] pci 0000:00:02.5: [1b36:000c] type 01 class 0x060400
248. [    0.106530] pci 0000:00:02.5: reg 0x10: [mem 0x12001000-0x12001fff]
249. [    0.108949] pci 0000:00:02.6: [1b36:000c] type 01 class 0x060400
250. [    0.109961] pci 0000:00:02.6: reg 0x10: [mem 0x12000000-0x12000fff]
251. [    0.114243] pci 0000:01:00.0: [1b36:000d] type 00 class 0x0c0330
252. [    0.114330] pci 0000:01:00.0: reg 0x10: [mem 0x11e00000-0x11e03fff 64bit]
253. [    0.115637] pci 0000:02:00.0: [1af4:1043] type 00 class 0x078000
254. [    0.117086] pci 0000:02:00.0: reg 0x14: [mem 0x11c00000-0x11c00fff]
255. [    0.119092] pci 0000:02:00.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref]
256. [    0.126210] pci 0000:03:00.0: [1af4:1042] type 00 class 0x010000
257. [    0.127496] pci 0000:03:00.0: reg 0x14: [mem 0x11a00000-0x11a00fff]
258. [    0.129396] pci 0000:03:00.0: reg 0x20: [mem 0x8000100000-0x8000103fff 64bit pref]
259. [    0.131479] pci 0000:04:00.0: [1af4:1045] type 00 class 0x00ff00
260. [    0.133337] pci 0000:04:00.0: reg 0x20: [mem 0x8000200000-0x8000203fff 64bit pref]
261. [    0.135568] pci 0000:05:00.0: [1af4:1044] type 00 class 0x00ff00
262. [    0.138118] pci 0000:05:00.0: reg 0x14: [mem 0x11600000-0x11600fff]
263. [    0.139905] pci 0000:05:00.0: reg 0x20: [mem 0x8000300000-0x8000303fff 64bit pref]
264. [    0.142489] pci 0000:06:00.0: [1af4:1050] type 00 class 0x038000
265. [    0.143812] pci 0000:06:00.0: reg 0x14: [mem 0x11400000-0x11400fff]
266. [    0.145439] pci 0000:06:00.0: reg 0x20: [mem 0x8000400000-0x8000403fff 64bit pref]
267. [    0.147306] pci 0000:07:00.0: [1b36:000e] type 01 class 0x060400
268. [    0.148377] pci 0000:07:00.0: reg 0x10: [mem 0x11200000-0x112000ff 64bit]
269. [    0.150341] pci_bus 0000:08: extended config space not accessible
270. [    0.150401] pci 0000:08:01.0: [1000:0012] type 00 class 0x010000
271. [    0.151369] pci 0000:08:01.0: reg 0x10: [io  0x8000-0x80ff]
272. [    0.152241] pci 0000:08:01.0: reg 0x14: [mem 0x11002000-0x110023ff]
273. [    0.153187] pci 0000:08:01.0: reg 0x18: [mem 0x11000000-0x11001fff]
274. [    0.166226] pci 0000:00:01.0: bridge window [io  0x1000-0x0fff] to [bus 01] add_size 1000
275. [    0.166229] pci 0000:00:01.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 01] add_size 200000 add_align 100000
276. [    0.166230] pci 0000:00:01.0: bridge window [mem 0x00100000-0x001fffff] to [bus 01] add_size 100000 add_align 100000
277. [    0.166232] pci 0000:00:01.1: bridge window [io  0x1000-0x0fff] to [bus 02] add_size 1000
278. [    0.166232] pci 0000:00:01.1: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 02] add_size 100000 add_align 100000
279. [    0.166233] pci 0000:00:01.1: bridge window [mem 0x00100000-0x001fffff] to [bus 02] add_size 100000 add_align 100000
280. [    0.166234] pci 0000:00:01.2: bridge window [io  0x1000-0x0fff] to [bus 03] add_size 1000
281. [    0.166235] pci 0000:00:01.2: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 03] add_size 100000 add_align 100000
282. [    0.166236] pci 0000:00:01.2: bridge window [mem 0x00100000-0x001fffff] to [bus 03] add_size 100000 add_align 100000
283. [    0.166237] pci 0000:00:01.3: bridge window [io  0x1000-0x0fff] to [bus 04] add_size 1000
284. [    0.166237] pci 0000:00:01.3: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 04] add_size 100000 add_align 100000
285. [    0.166238] pci 0000:00:01.3: bridge window [mem 0x00100000-0x000fffff] to [bus 04] add_size 200000 add_align 100000
286. [    0.166242] pci 0000:00:01.4: bridge window [io  0x1000-0x0fff] to [bus 05] add_size 1000
287. [    0.166248] pci 0000:00:01.4: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 05] add_size 100000 add_align 100000
288. [    0.166249] pci 0000:00:01.4: bridge window [mem 0x00100000-0x001fffff] to [bus 05] add_size 100000 add_align 100000
289. [    0.166249] pci 0000:00:01.5: bridge window [io  0x1000-0x0fff] to [bus 06] add_size 1000
290. [    0.166250] pci 0000:00:01.5: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 06] add_size 100000 add_align 100000
291. [    0.166251] pci 0000:00:01.5: bridge window [mem 0x00100000-0x001fffff] to [bus 06] add_size 100000 add_align 100000
292. [    0.166252] pci 0000:00:01.6: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 07-08] add_size 200000 add_align 100000
293. [    0.166253] pci 0000:00:01.7: bridge window [io  0x1000-0x0fff] to [bus 09] add_size 1000
294. [    0.166254] pci 0000:00:01.7: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 09] add_size 200000 add_align 100000
295. [    0.166255] pci 0000:00:01.7: bridge window [mem 0x00100000-0x000fffff] to [bus 09] add_size 200000 add_align 100000
296. [    0.166255] pci 0000:00:02.0: bridge window [io  0x1000-0x0fff] to [bus 0a] add_size 1000
297. [    0.166256] pci 0000:00:02.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0a] add_size 200000 add_align 100000
298. [    0.166257] pci 0000:00:02.0: bridge window [mem 0x00100000-0x000fffff] to [bus 0a] add_size 200000 add_align 100000
299. [    0.166257] pci 0000:00:02.1: bridge window [io  0x1000-0x0fff] to [bus 0b] add_size 1000
300. [    0.166258] pci 0000:00:02.1: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0b] add_size 200000 add_align 100000
301. [    0.166258] pci 0000:00:02.1: bridge window [mem 0x00100000-0x000fffff] to [bus 0b] add_size 200000 add_align 100000
302. [    0.166262] pci 0000:00:02.2: bridge window [io  0x1000-0x0fff] to [bus 0c] add_size 1000
303. [    0.166263] pci 0000:00:02.2: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0c] add_size 200000 add_align 100000
304. [    0.166263] pci 0000:00:02.2: bridge window [mem 0x00100000-0x000fffff] to [bus 0c] add_size 200000 add_align 100000
305. [    0.166264] pci 0000:00:02.3: bridge window [io  0x1000-0x0fff] to [bus 0d] add_size 1000
306. [    0.166265] pci 0000:00:02.3: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0d] add_size 200000 add_align 100000
307. [    0.166265] pci 0000:00:02.3: bridge window [mem 0x00100000-0x000fffff] to [bus 0d] add_size 200000 add_align 100000
308. [    0.166266] pci 0000:00:02.4: bridge window [io  0x1000-0x0fff] to [bus 0e] add_size 1000
309. [    0.166267] pci 0000:00:02.4: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0e] add_size 200000 add_align 100000
310. [    0.166267] pci 0000:00:02.4: bridge window [mem 0x00100000-0x000fffff] to [bus 0e] add_size 200000 add_align 100000
311. [    0.166268] pci 0000:00:02.5: bridge window [io  0x1000-0x0fff] to [bus 0f] add_size 1000
312. [    0.166269] pci 0000:00:02.5: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0f] add_size 200000 add_align 100000
313. [    0.166269] pci 0000:00:02.5: bridge window [mem 0x00100000-0x000fffff] to [bus 0f] add_size 200000 add_align 100000
314. [    0.166270] pci 0000:00:02.6: bridge window [io  0x1000-0x0fff] to [bus 10] add_size 1000
315. [    0.166271] pci 0000:00:02.6: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 10] add_size 200000 add_align 100000
316. [    0.166271] pci 0000:00:02.6: bridge window [mem 0x00100000-0x000fffff] to [bus 10] add_size 200000 add_align 100000
317. [    0.166293] pci 0000:00:01.0: BAR 14: assigned [mem 0x10000000-0x101fffff]
318. [    0.166294] pci 0000:00:01.0: BAR 15: assigned [mem 0x8000000000-0x80001fffff 64bit pref]
319. [    0.166295] pci 0000:00:01.1: BAR 14: assigned [mem 0x10200000-0x103fffff]
320. [    0.166296] pci 0000:00:01.1: BAR 15: assigned [mem 0x8000200000-0x80003fffff 64bit pref]
321. [    0.166296] pci 0000:00:01.2: BAR 14: assigned [mem 0x10400000-0x105fffff]
322. [    0.166297] pci 0000:00:01.2: BAR 15: assigned [mem 0x8000400000-0x80005fffff 64bit pref]
323. [    0.166298] pci 0000:00:01.3: BAR 14: assigned [mem 0x10600000-0x107fffff]
324. [    0.166298] pci 0000:00:01.3: BAR 15: assigned [mem 0x8000600000-0x80007fffff 64bit pref]
325. [    0.166299] pci 0000:00:01.4: BAR 14: assigned [mem 0x10800000-0x109fffff]
326. [    0.166299] pci 0000:00:01.4: BAR 15: assigned [mem 0x8000800000-0x80009fffff 64bit pref]
327. [    0.166300] pci 0000:00:01.5: BAR 14: assigned [mem 0x10a00000-0x10bfffff]
328. [    0.166300] pci 0000:00:01.5: BAR 15: assigned [mem 0x8000a00000-0x8000bfffff 64bit pref]
329. [    0.166301] pci 0000:00:01.6: BAR 14: assigned [mem 0x10c00000-0x10dfffff]
330. [    0.166302] pci 0000:00:01.6: BAR 15: assigned [mem 0x8000c00000-0x8000dfffff 64bit pref]
331. [    0.166302] pci 0000:00:01.7: BAR 14: assigned [mem 0x10e00000-0x10ffffff]
332. [    0.166303] pci 0000:00:01.7: BAR 15: assigned [mem 0x8000e00000-0x8000ffffff 64bit pref]
333. [    0.166303] pci 0000:00:02.0: BAR 14: assigned [mem 0x11000000-0x111fffff]
334. [    0.166304] pci 0000:00:02.0: BAR 15: assigned [mem 0x8001000000-0x80011fffff 64bit pref]
335. [    0.166304] pci 0000:00:02.1: BAR 14: assigned [mem 0x11200000-0x113fffff]
336. [    0.166305] pci 0000:00:02.1: BAR 15: assigned [mem 0x8001200000-0x80013fffff 64bit pref]
337. [    0.166305] pci 0000:00:02.2: BAR 14: assigned [mem 0x11400000-0x115fffff]
338. [    0.166306] pci 0000:00:02.2: BAR 15: assigned [mem 0x8001400000-0x80015fffff 64bit pref]
339. [    0.166307] pci 0000:00:02.3: BAR 14: assigned [mem 0x11600000-0x117fffff]
340. [    0.166307] pci 0000:00:02.3: BAR 15: assigned [mem 0x8001600000-0x80017fffff 64bit pref]
341. [    0.166308] pci 0000:00:02.4: BAR 14: assigned [mem 0x11800000-0x119fffff]
342. [    0.166308] pci 0000:00:02.4: BAR 15: assigned [mem 0x8001800000-0x80019fffff 64bit pref]
343. [    0.166309] pci 0000:00:02.5: BAR 14: assigned [mem 0x11a00000-0x11bfffff]
344. [    0.166309] pci 0000:00:02.5: BAR 15: assigned [mem 0x8001a00000-0x8001bfffff 64bit pref]
345. [    0.166310] pci 0000:00:02.6: BAR 14: assigned [mem 0x11c00000-0x11dfffff]
346. [    0.166311] pci 0000:00:02.6: BAR 15: assigned [mem 0x8001c00000-0x8001dfffff 64bit pref]
347. [    0.166311] pci 0000:00:01.0: BAR 0: assigned [mem 0x11e00000-0x11e00fff]
348. [    0.166699] pci 0000:00:01.0: BAR 13: assigned [io  0x1000-0x1fff]
349. [    0.166700] pci 0000:00:01.1: BAR 0: assigned [mem 0x11e01000-0x11e01fff]
350. [    0.167066] pci 0000:00:01.1: BAR 13: assigned [io  0x2000-0x2fff]
351. [    0.167067] pci 0000:00:01.2: BAR 0: assigned [mem 0x11e02000-0x11e02fff]
352. [    0.167392] pci 0000:00:01.2: BAR 13: assigned [io  0x3000-0x3fff]
353. [    0.167392] pci 0000:00:01.3: BAR 0: assigned [mem 0x11e03000-0x11e03fff]
354. [    0.167735] pci 0000:00:01.3: BAR 13: assigned [io  0x4000-0x4fff]
355. [    0.167736] pci 0000:00:01.4: BAR 0: assigned [mem 0x11e04000-0x11e04fff]
356. [    0.168054] pci 0000:00:01.4: BAR 13: assigned [io  0x5000-0x5fff]
357. [    0.168055] pci 0000:00:01.5: BAR 0: assigned [mem 0x11e05000-0x11e05fff]
358. [    0.168391] pci 0000:00:01.5: BAR 13: assigned [io  0x6000-0x6fff]
359. [    0.168392] pci 0000:00:01.6: BAR 0: assigned [mem 0x11e06000-0x11e06fff]
360. [    0.168729] pci 0000:00:01.6: BAR 13: assigned [io  0x7000-0x7fff]
361. [    0.168730] pci 0000:00:01.7: BAR 0: assigned [mem 0x11e07000-0x11e07fff]
362. [    0.169063] pci 0000:00:01.7: BAR 13: assigned [io  0x8000-0x8fff]
363. [    0.169064] pci 0000:00:02.0: BAR 0: assigned [mem 0x11e08000-0x11e08fff]
364. [    0.169413] pci 0000:00:02.0: BAR 13: assigned [io  0x9000-0x9fff]
365. [    0.169413] pci 0000:00:02.1: BAR 0: assigned [mem 0x11e09000-0x11e09fff]
366. [    0.169754] pci 0000:00:02.1: BAR 13: assigned [io  0xa000-0xafff]
367. [    0.169754] pci 0000:00:02.2: BAR 0: assigned [mem 0x11e0a000-0x11e0afff]
368. [    0.170091] pci 0000:00:02.2: BAR 13: assigned [io  0xb000-0xbfff]
369. [    0.170092] pci 0000:00:02.3: BAR 0: assigned [mem 0x11e0b000-0x11e0bfff]
370. [    0.170436] pci 0000:00:02.3: BAR 13: assigned [io  0xc000-0xcfff]
371. [    0.170437] pci 0000:00:02.4: BAR 0: assigned [mem 0x11e0c000-0x11e0cfff]
372. [    0.170781] pci 0000:00:02.4: BAR 13: assigned [io  0xd000-0xdfff]
373. [    0.170782] pci 0000:00:02.5: BAR 0: assigned [mem 0x11e0d000-0x11e0dfff]
374. [    0.171127] pci 0000:00:02.5: BAR 13: assigned [io  0xe000-0xefff]
375. [    0.171128] pci 0000:00:02.6: BAR 0: assigned [mem 0x11e0e000-0x11e0efff]
376. [    0.171474] pci 0000:00:02.6: BAR 13: assigned [io  0xf000-0xffff]
377. [    0.171482] pci 0000:01:00.0: BAR 0: assigned [mem 0x10000000-0x10003fff 64bit]
378. [    0.171720] pci 0000:00:01.0: PCI bridge to [bus 01]
379. [    0.171723] pci 0000:00:01.0:   bridge window [io  0x1000-0x1fff]
380. [    0.172346] pci 0000:00:01.0:   bridge window [mem 0x10000000-0x101fffff]
381. [    0.172735] pci 0000:00:01.0:   bridge window [mem 0x8000000000-0x80001fffff 64bit pref]
382. [    0.173472] pci 0000:02:00.0: BAR 4: assigned [mem 0x8000200000-0x8000203fff 64bit pref]
383. [    0.174175] pci 0000:02:00.0: BAR 1: assigned [mem 0x10200000-0x10200fff]
384. [    0.174500] pci 0000:00:01.1: PCI bridge to [bus 02]
385. [    0.174503] pci 0000:00:01.1:   bridge window [io  0x2000-0x2fff]
386. [    0.175033] pci 0000:00:01.1:   bridge window [mem 0x10200000-0x103fffff]
387. [    0.175401] pci 0000:00:01.1:   bridge window [mem 0x8000200000-0x80003fffff 64bit pref]
388. [    0.176107] pci 0000:03:00.0: BAR 4: assigned [mem 0x8000400000-0x8000403fff 64bit pref]
389. [    0.176745] pci 0000:03:00.0: BAR 1: assigned [mem 0x10400000-0x10400fff]
390. [    0.177042] pci 0000:00:01.2: PCI bridge to [bus 03]
391. [    0.177045] pci 0000:00:01.2:   bridge window [io  0x3000-0x3fff]
392. [    0.177539] pci 0000:00:01.2:   bridge window [mem 0x10400000-0x105fffff]
393. [    0.177874] pci 0000:00:01.2:   bridge window [mem 0x8000400000-0x80005fffff 64bit pref]
394. [    0.178551] pci 0000:04:00.0: BAR 4: assigned [mem 0x8000600000-0x8000603fff 64bit pref]
395. [    0.178878] pci 0000:00:01.3: PCI bridge to [bus 04]
396. [    0.178881] pci 0000:00:01.3:   bridge window [io  0x4000-0x4fff]
397. [    0.179384] pci 0000:00:01.3:   bridge window [mem 0x10600000-0x107fffff]
398. [    0.179715] pci 0000:00:01.3:   bridge window [mem 0x8000600000-0x80007fffff 64bit pref]
399. [    0.180563] pci 0000:05:00.0: BAR 4: assigned [mem 0x8000800000-0x8000803fff 64bit pref]
400. [    0.181250] pci 0000:05:00.0: BAR 1: assigned [mem 0x10800000-0x10800fff]
401. [    0.181573] pci 0000:00:01.4: PCI bridge to [bus 05]
402. [    0.181576] pci 0000:00:01.4:   bridge window [io  0x5000-0x5fff]
403. [    0.201647] pci 0000:00:01.4:   bridge window [mem 0x10800000-0x109fffff]
404. [    0.202019] pci 0000:00:01.4:   bridge window [mem 0x8000800000-0x80009fffff 64bit pref]
405. [    0.202635] pci 0000:06:00.0: BAR 4: assigned [mem 0x8000a00000-0x8000a03fff 64bit pref]
406. [    0.203203] pci 0000:06:00.0: BAR 1: assigned [mem 0x10a00000-0x10a00fff]
407. [    0.203491] pci 0000:00:01.5: PCI bridge to [bus 06]
408. [    0.203492] pci 0000:00:01.5:   bridge window [io  0x6000-0x6fff]
409. [    0.203981] pci 0000:00:01.5:   bridge window [mem 0x10a00000-0x10bfffff]
410. [    0.204322] pci 0000:00:01.5:   bridge window [mem 0x8000a00000-0x8000bfffff 64bit pref]
411. [    0.204964] pci 0000:07:00.0: BAR 14: assigned [mem 0x10c00000-0x10cfffff]
412. [    0.204965] pci 0000:07:00.0: BAR 13: assigned [io  0x7000-0x7fff]
413. [    0.204966] pci 0000:07:00.0: BAR 0: assigned [mem 0x10d00000-0x10d000ff 64bit]
414. [    0.205661] pci 0000:08:01.0: BAR 2: assigned [mem 0x10c00000-0x10c01fff]
415. [    0.205985] pci 0000:08:01.0: BAR 1: assigned [mem 0x10c02000-0x10c023ff]
416. [    0.206305] pci 0000:08:01.0: BAR 0: assigned [io  0x7000-0x70ff]
417. [    0.206601] pci 0000:07:00.0: PCI bridge to [bus 08]
418. [    0.206603] pci 0000:07:00.0:   bridge window [io  0x7000-0x7fff]
419. [    0.207106] pci 0000:07:00.0:   bridge window [mem 0x10c00000-0x10cfffff]
420. [    0.208057] pci 0000:00:01.6: PCI bridge to [bus 07-08]
421. [    0.208058] pci 0000:00:01.6:   bridge window [io  0x7000-0x7fff]
422. [    0.208562] pci 0000:00:01.6:   bridge window [mem 0x10c00000-0x10dfffff]
423. [    0.208913] pci 0000:00:01.6:   bridge window [mem 0x8000c00000-0x8000dfffff 64bit pref]
424. [    0.209601] pci 0000:00:01.7: PCI bridge to [bus 09]
425. [    0.209603] pci 0000:00:01.7:   bridge window [io  0x8000-0x8fff]
426. [    0.210125] pci 0000:00:01.7:   bridge window [mem 0x10e00000-0x10ffffff]
427. [    0.210496] pci 0000:00:01.7:   bridge window [mem 0x8000e00000-0x8000ffffff 64bit pref]
428. [    0.211222] pci 0000:00:02.0: PCI bridge to [bus 0a]
429. [    0.211226] pci 0000:00:02.0:   bridge window [io  0x9000-0x9fff]
430. [    0.211724] pci 0000:00:02.0:   bridge window [mem 0x11000000-0x111fffff]
431. [    0.212048] pci 0000:00:02.0:   bridge window [mem 0x8001000000-0x80011fffff 64bit pref]
432. [    0.212726] pci 0000:00:02.1: PCI bridge to [bus 0b]
433. [    0.212728] pci 0000:00:02.1:   bridge window [io  0xa000-0xafff]
434. [    0.213233] pci 0000:00:02.1:   bridge window [mem 0x11200000-0x113fffff]
435. [    0.213573] pci 0000:00:02.1:   bridge window [mem 0x8001200000-0x80013fffff 64bit pref]
436. [    0.214265] pci 0000:00:02.2: PCI bridge to [bus 0c]
437. [    0.214266] pci 0000:00:02.2:   bridge window [io  0xb000-0xbfff]
438. [    0.214814] pci 0000:00:02.2:   bridge window [mem 0x11400000-0x115fffff]
439. [    0.215177] pci 0000:00:02.2:   bridge window [mem 0x8001400000-0x80015fffff 64bit pref]
440. [    0.215905] pci 0000:00:02.3: PCI bridge to [bus 0d]
441. [    0.215907] pci 0000:00:02.3:   bridge window [io  0xc000-0xcfff]
442. [    0.216481] pci 0000:00:02.3:   bridge window [mem 0x11600000-0x117fffff]
443. [    0.216849] pci 0000:00:02.3:   bridge window [mem 0x8001600000-0x80017fffff 64bit pref]
444. [    0.217685] pci 0000:00:02.4: PCI bridge to [bus 0e]
445. [    0.217689] pci 0000:00:02.4:   bridge window [io  0xd000-0xdfff]
446. [    0.218182] pci 0000:00:02.4:   bridge window [mem 0x11800000-0x119fffff]
447. [    0.218492] pci 0000:00:02.4:   bridge window [mem 0x8001800000-0x80019fffff 64bit pref]
448. [    0.219108] pci 0000:00:02.5: PCI bridge to [bus 0f]
449. [    0.219110] pci 0000:00:02.5:   bridge window [io  0xe000-0xefff]
450. [    0.219570] pci 0000:00:02.5:   bridge window [mem 0x11a00000-0x11bfffff]
451. [    0.219880] pci 0000:00:02.5:   bridge window [mem 0x8001a00000-0x8001bfffff 64bit pref]
452. [    0.220532] pci 0000:00:02.6: PCI bridge to [bus 10]
453. [    0.220534] pci 0000:00:02.6:   bridge window [io  0xf000-0xffff]
454. [    0.221060] pci 0000:00:02.6:   bridge window [mem 0x11c00000-0x11dfffff]
455. [    0.221407] pci 0000:00:02.6:   bridge window [mem 0x8001c00000-0x8001dfffff 64bit pref]
456. [    0.222252] pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window]
457. [    0.222254] pci_bus 0000:00: resource 5 [io  0x0000-0xffff window]
458. [    0.222255] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window]
459. [    0.222256] pci_bus 0000:01: resource 0 [io  0x1000-0x1fff]
460. [    0.222256] pci_bus 0000:01: resource 1 [mem 0x10000000-0x101fffff]
461. [    0.222257] pci_bus 0000:01: resource 2 [mem 0x8000000000-0x80001fffff 64bit pref]
462. [    0.222257] pci_bus 0000:02: resource 0 [io  0x2000-0x2fff]
463. [    0.222258] pci_bus 0000:02: resource 1 [mem 0x10200000-0x103fffff]
464. [    0.222258] pci_bus 0000:02: resource 2 [mem 0x8000200000-0x80003fffff 64bit pref]
465. [    0.222259] pci_bus 0000:03: resource 0 [io  0x3000-0x3fff]
466. [    0.222259] pci_bus 0000:03: resource 1 [mem 0x10400000-0x105fffff]
467. [    0.222260] pci_bus 0000:03: resource 2 [mem 0x8000400000-0x80005fffff 64bit pref]
468. [    0.222260] pci_bus 0000:04: resource 0 [io  0x4000-0x4fff]
469. [    0.222261] pci_bus 0000:04: resource 1 [mem 0x10600000-0x107fffff]
470. [    0.222261] pci_bus 0000:04: resource 2 [mem 0x8000600000-0x80007fffff 64bit pref]
471. [    0.222262] pci_bus 0000:05: resource 0 [io  0x5000-0x5fff]
472. [    0.222262] pci_bus 0000:05: resource 1 [mem 0x10800000-0x109fffff]
473. [    0.222263] pci_bus 0000:05: resource 2 [mem 0x8000800000-0x80009fffff 64bit pref]
474. [    0.222264] pci_bus 0000:06: resource 0 [io  0x6000-0x6fff]
475. [    0.222264] pci_bus 0000:06: resource 1 [mem 0x10a00000-0x10bfffff]
476. [    0.222264] pci_bus 0000:06: resource 2 [mem 0x8000a00000-0x8000bfffff 64bit pref]
477. [    0.222265] pci_bus 0000:07: resource 0 [io  0x7000-0x7fff]
478. [    0.222266] pci_bus 0000:07: resource 1 [mem 0x10c00000-0x10dfffff]
479. [    0.222266] pci_bus 0000:07: resource 2 [mem 0x8000c00000-0x8000dfffff 64bit pref]
480. [    0.222267] pci_bus 0000:08: resource 0 [io  0x7000-0x7fff]
481. [    0.222267] pci_bus 0000:08: resource 1 [mem 0x10c00000-0x10cfffff]
482. [    0.222268] pci_bus 0000:09: resource 0 [io  0x8000-0x8fff]
483. [    0.222268] pci_bus 0000:09: resource 1 [mem 0x10e00000-0x10ffffff]
484. [    0.222269] pci_bus 0000:09: resource 2 [mem 0x8000e00000-0x8000ffffff 64bit pref]
485. [    0.222269] pci_bus 0000:0a: resource 0 [io  0x9000-0x9fff]
486. [    0.222270] pci_bus 0000:0a: resource 1 [mem 0x11000000-0x111fffff]
487. [    0.222270] pci_bus 0000:0a: resource 2 [mem 0x8001000000-0x80011fffff 64bit pref]
488. [    0.222276] pci_bus 0000:0b: resource 0 [io  0xa000-0xafff]
489. [    0.222277] pci_bus 0000:0b: resource 1 [mem 0x11200000-0x113fffff]
490. [    0.222277] pci_bus 0000:0b: resource 2 [mem 0x8001200000-0x80013fffff 64bit pref]
491. [    0.222278] pci_bus 0000:0c: resource 0 [io  0xb000-0xbfff]
492. [    0.222278] pci_bus 0000:0c: resource 1 [mem 0x11400000-0x115fffff]
493. [    0.222278] pci_bus 0000:0c: resource 2 [mem 0x8001400000-0x80015fffff 64bit pref]
494. [    0.222282] pci_bus 0000:0d: resource 0 [io  0xc000-0xcfff]
495. [    0.222283] pci_bus 0000:0d: resource 1 [mem 0x11600000-0x117fffff]
496. [    0.222283] pci_bus 0000:0d: resource 2 [mem 0x8001600000-0x80017fffff 64bit pref]
497. [    0.222284] pci_bus 0000:0e: resource 0 [io  0xd000-0xdfff]
498. [    0.222284] pci_bus 0000:0e: resource 1 [mem 0x11800000-0x119fffff]
499. [    0.222284] pci_bus 0000:0e: resource 2 [mem 0x8001800000-0x80019fffff 64bit pref]
500. [    0.222285] pci_bus 0000:0f: resource 0 [io  0xe000-0xefff]
501. [    0.222286] pci_bus 0000:0f: resource 1 [mem 0x11a00000-0x11bfffff]
502. [    0.222286] pci_bus 0000:0f: resource 2 [mem 0x8001a00000-0x8001bfffff 64bit pref]
503. [    0.222287] pci_bus 0000:10: resource 0 [io  0xf000-0xffff]
504. [    0.222287] pci_bus 0000:10: resource 1 [mem 0x11c00000-0x11dfffff]
505. [    0.222287] pci_bus 0000:10: resource 2 [mem 0x8001c00000-0x8001dfffff 64bit pref]
506. [    0.222458] ACPI: PCI: Interrupt link GSI0 configured for IRQ 35
507. [    0.222462] ACPI: PCI: Interrupt link GSI1 configured for IRQ 36
508. [    0.222467] ACPI: PCI: Interrupt link GSI2 configured for IRQ 37
509. [    0.222470] ACPI: PCI: Interrupt link GSI3 configured for IRQ 38
510. [    0.222935] iommu: Default domain type: Translated
511. [    0.222937] iommu: DMA domain TLB invalidation policy: lazy mode
512. [    0.223095] SCSI subsystem initialized
513. [    0.223455] ACPI: bus type USB registered
514. [    0.223479] usbcore: registered new interface driver usbfs
515. [    0.223484] usbcore: registered new interface driver hub
516. [    0.223486] usbcore: registered new device driver usb
517. [    0.223517] pps_core: LinuxPPS API ver. 1 registered
518. [    0.223518] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
519. [    0.223519] PTP clock support registered
520. [    0.223953] EDAC MC: Ver: 3.0.0
521. [    0.225134] Registered efivars operations
522. [    0.225404] NetLabel: Initializing
523. [    0.225404] NetLabel:  domain hash size = 128
524. [    0.225404] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
525. [    0.225411] NetLabel:  unlabeled traffic allowed by default
526. [    0.225942] vgaarb: loaded
527. [    0.231645] clocksource: Switched to clocksource arch_sys_counter
528. [    0.231968] VFS: Disk quotas dquot_6.6.0
529. [    0.231976] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
530. [    0.232040] pnp: PnP ACPI init
531. [    0.232084] system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved
532. [    0.232089] pnp: PnP ACPI: found 1 devices
533. [    0.268370] NET: Registered PF_INET protocol family
534. [    0.268738] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
535. [    0.269846] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
536. [    0.269869] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
537. [    0.269874] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
538. [    0.270065] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
539. [    0.270391] TCP: Hash tables configured (established 65536 bind 65536)
540. [    0.270535] MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear)
541. [    0.270546] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
542. [    0.270590] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
543. [    0.270662] NET: Registered PF_UNIX/PF_LOCAL protocol family
544. [    0.270665] NET: Registered PF_XDP protocol family
545. [    0.270863] pci 0000:01:00.0: enabling device (0000 -> 0002)
546. [    0.271316] PCI: CLS 0 bytes, default 64
547. [    0.271336] ACPI: bus type thunderbolt registered
548. [    0.271490] Trying to unpack rootfs image as initramfs...
549. [    0.273896] kvm [1]: HYP mode not available
550. [    0.274433] Initialise system trusted keyrings
551. [    0.274442] Key type blacklist registered
552. [    0.274780] workingset: timestamp_bits=38 max_order=21 bucket_order=0
553. [    0.275648] zbud: loaded
554. [    0.276515] integrity: Platform Keyring initialized
555. [    0.276518] integrity: Machine keyring initialized
556. [    0.286629] NET: Registered PF_ALG protocol family
557. [    0.286632] xor: measuring software checksum speed
558. [    0.286926]    8regs           : 34281 MB/sec
559. [    0.287284]    32regs          : 27730 MB/sec
560. [    0.287469]    arm64_neon      : 53901 MB/sec
561. [    0.287470] xor: using function: arm64_neon (53901 MB/sec)
562. [    0.287472] Key type asymmetric registered
563. [    0.287472] Asymmetric key parser 'x509' registered
564. [    0.287473] Running certificate verification selftests
565. [    0.327478] Loaded X.509 cert 'Certificate verification self-testing key: f58703bb33ce1b73ee02eccdee5b8817518fe3db'
566. [    0.327933] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
567. [    0.328287] io scheduler mq-deadline registered
568. [    0.328288] io scheduler kyber registered
569. [    0.328318] io scheduler bfq registered
570. [    0.333533] atomic64_test: passed
571. [    0.334051] ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36
572. [    0.334488] pcieport 0000:00:01.0: PME: Signaling with IRQ 50
573. [    0.334802] pcieport 0000:00:01.0: AER: enabled with IRQ 50
574. [    0.334877] pcieport 0000:00:01.0: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
575. [    0.336421] pcieport 0000:00:01.1: PME: Signaling with IRQ 51
576. [    0.336857] pcieport 0000:00:01.1: AER: enabled with IRQ 51
577. [    0.336889] pcieport 0000:00:01.1: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
578. [    0.338302] pcieport 0000:00:01.2: PME: Signaling with IRQ 52
579. [    0.338711] pcieport 0000:00:01.2: AER: enabled with IRQ 52
580. [    0.338752] pcieport 0000:00:01.2: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
581. [    0.340574] pcieport 0000:00:01.3: PME: Signaling with IRQ 53
582. [    0.341273] pcieport 0000:00:01.3: AER: enabled with IRQ 53
583. [    0.341302] pcieport 0000:00:01.3: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
584. [    0.343536] pcieport 0000:00:01.4: PME: Signaling with IRQ 54
585. [    0.343925] pcieport 0000:00:01.4: AER: enabled with IRQ 54
586. [    0.343943] pcieport 0000:00:01.4: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
587. [    0.345682] pcieport 0000:00:01.5: PME: Signaling with IRQ 55
588. [    0.346094] pcieport 0000:00:01.5: AER: enabled with IRQ 55
589. [    0.346155] pcieport 0000:00:01.5: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
590. [    0.347971] pcieport 0000:00:01.6: PME: Signaling with IRQ 56
591. [    0.348857] pcieport 0000:00:01.6: AER: enabled with IRQ 56
592. [    0.349015] pcieport 0000:00:01.6: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
593. [    0.350696] pcieport 0000:00:01.7: PME: Signaling with IRQ 57
594. [    0.351332] pcieport 0000:00:01.7: AER: enabled with IRQ 57
595. [    0.351451] pcieport 0000:00:01.7: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
596. [    0.352534] ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37
597. [    0.353457] pcieport 0000:00:02.0: PME: Signaling with IRQ 58
598. [    0.353782] pcieport 0000:00:02.0: AER: enabled with IRQ 58
599. [    0.353825] pcieport 0000:00:02.0: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
600. [    0.355119] pcieport 0000:00:02.1: PME: Signaling with IRQ 59
601. [    0.355374] pcieport 0000:00:02.1: AER: enabled with IRQ 59
602. [    0.355459] pcieport 0000:00:02.1: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
603. [    0.357695] pcieport 0000:00:02.2: PME: Signaling with IRQ 60
604. [    0.357966] pcieport 0000:00:02.2: AER: enabled with IRQ 60
605. [    0.358027] pcieport 0000:00:02.2: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
606. [    0.360667] pcieport 0000:00:02.3: PME: Signaling with IRQ 61
607. [    0.361200] pcieport 0000:00:02.3: AER: enabled with IRQ 61
608. [    0.361223] pcieport 0000:00:02.3: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
609. [    0.363630] pcieport 0000:00:02.4: PME: Signaling with IRQ 62
610. [    0.363965] pcieport 0000:00:02.4: AER: enabled with IRQ 62
611. [    0.364035] pcieport 0000:00:02.4: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
612. [    0.365136] pcieport 0000:00:02.5: PME: Signaling with IRQ 63
613. [    0.365376] pcieport 0000:00:02.5: AER: enabled with IRQ 63
614. [    0.365460] pcieport 0000:00:02.5: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
615. [    0.366302] pcieport 0000:00:02.6: PME: Signaling with IRQ 64
616. [    0.366668] pcieport 0000:00:02.6: AER: enabled with IRQ 64
617. [    0.366847] pcieport 0000:00:02.6: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+
618. [    0.368113] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
619. [    0.368122] ACPI: button: Power Button [PWRB]
620. [    0.373823] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
621. [    0.377828] random: crng init done
622. [    0.381922] rdac: device handler registered
623. [    0.382656] hp_sw: device handler registered
624. [    0.382657] emc: device handler registered
625. [    0.383111] alua: device handler registered
626. [    0.383905] xhci_hcd 0000:01:00.0: xHCI Host Controller
627. [    0.384131] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
628. [    0.384429] xhci_hcd 0000:01:00.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010
629. [    0.386367] xhci_hcd 0000:01:00.0: xHCI Host Controller
630. [    0.386566] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
631. [    0.386569] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
632. [    0.386601] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.14
633. [    0.386602] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
634. [    0.386602] usb usb1: Product: xHCI Host Controller
635. [    0.386603] usb usb1: Manufacturer: Linux 5.14.0-386.el9.aarch64 xhci-hcd
636. [    0.386603] usb usb1: SerialNumber: 0000:01:00.0
637. [    0.387067] hub 1-0:1.0: USB hub found
638. [    0.387086] hub 1-0:1.0: 15 ports detected
639. [    0.387710] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
640. [    0.387722] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.14
641. [    0.387723] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
642. [    0.387724] usb usb2: Product: xHCI Host Controller
643. [    0.387725] usb usb2: Manufacturer: Linux 5.14.0-386.el9.aarch64 xhci-hcd
644. [    0.387725] usb usb2: SerialNumber: 0000:01:00.0
645. [    0.388166] hub 2-0:1.0: USB hub found
646. [    0.388340] hub 2-0:1.0: 15 ports detected
647. [    0.400532] usbcore: registered new interface driver usbserial_generic
648. [    0.400534] usbserial: USB Serial support registered for generic
649. [    0.400965] mousedev: PS/2 mouse device common for all mice
650. [    0.402854] rtc-efi rtc-efi.0: registered as rtc0
651. [    0.403038] rtc-efi rtc-efi.0: setting system clock to 2023-12-11T17:58:55 UTC (1702317535)
652. [    0.403102] ghes_edac: GHES probing device list is empty
653. [    0.408127] hid: raw HID events driver (C) Jiri Kosina
654. [    0.408144] usbcore: registered new interface driver usbhid
655. [    0.408144] usbhid: USB HID core driver
656. [    0.410801] hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available
657. [    0.410891] drop_monitor: Initializing network drop monitor service
658. [    0.418087] Initializing XFRM netlink socket
659. [    0.418194] NET: Registered PF_INET6 protocol family
660. [    0.715415] usb 1-1: new high-speed USB device number 2 using xhci_hcd
661. [    0.984605] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
662. [    0.984608] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
663. [    0.984609] usb 1-1: Product: QEMU USB Tablet
664. [    0.984609] usb 1-1: Manufacturer: QEMU
665. [    0.984610] usb 1-1: SerialNumber: 28754-0000:00:01.0:00.0-1
666. [    0.986272] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.0/0000:01:00.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1
667. [    0.986672] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:01:00.0-1/input0
668. [    1.167609] usb 1-2: new high-speed USB device number 3 using xhci_hcd
669. [    1.373597] usb 1-2: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
670. [    1.373600] usb 1-2: New USB device strings: Mfr=1, Product=4, SerialNumber=11
671. [    1.373601] usb 1-2: Product: QEMU USB Keyboard
672. [    1.373602] usb 1-2: Manufacturer: QEMU
673. [    1.373605] usb 1-2: SerialNumber: 68284-0000:00:01.0:00.0-2
674. [    1.375406] input: QEMU QEMU USB Keyboard as /devices/pci0000:00/0000:00:01.0/0000:01:00.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2
675. [    1.442717] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:01:00.0-2/input0
676. [    3.145403] Freeing initrd memory: 448996K
677. [    3.153357] Segment Routing with IPv6
678. [    3.153376] NET: Registered PF_PACKET protocol family
679. [    3.153591] mpls_gso: MPLS GSO support
680. [    3.153792] registered taskstats version 1
681. [    3.155417] Loading compiled-in X.509 certificates
682. [    3.156618] Loaded X.509 cert 'The CentOS Project: CentOS Stream kernel signing key: e37d4e7421df551c55f489f3eb3766ee78bb932b'
683. [    3.156852] Loaded X.509 cert 'Red Hat Enterprise Linux Driver Update Program (key 3): bf57f3e87362bc7229d9f465321773dfd1f77a80'
684. [    3.157066] Loaded X.509 cert 'Red Hat Enterprise Linux kpatch signing key: 4d38fd864ebe18c5f0b72e3852e2014c3a676fc8'
685. [    3.157171] Loaded X.509 cert 'RH-IMA-CA: Red Hat IMA CA: fb31825dd0e073685b264e3038963673f753959a'
686. [    3.157618] zswap: loaded using pool lzo/zbud
687. [    3.157740] page_owner is disabled
688. [    3.157749] Key type big_key registered
689. [    3.158172] Key type encrypted registered
690. [    3.159427] ima: secureboot mode disabled
691. [    3.159429] ima: No TPM chip found, activating TPM-bypass!
692. [    3.159432] Loading compiled-in module X.509 certificates
693. [    3.159662] Loaded X.509 cert 'The CentOS Project: CentOS Stream kernel signing key: e37d4e7421df551c55f489f3eb3766ee78bb932b'
694. [    3.159663] ima: Allocated hash algorithm: sha256
695. [    3.159669] ima: No architecture policies found
696. [    3.159676] evm: Initialising EVM extended attributes:
697. [    3.159677] evm: security.selinux
698. [    3.159677] evm: security.SMACK64 (disabled)
699. [    3.159677] evm: security.SMACK64EXEC &#40;disabled&#41;
700. [    3.159677] evm: security.SMACK64TRANSMUTE (disabled)
701. [    3.159678] evm: security.SMACK64MMAP (disabled)
702. [    3.159678] evm: security.apparmor (disabled)
703. [    3.159678] evm: security.ima
704. [    3.159678] evm: security.capability
705. [    3.159679] evm: HMAC attrs: 0x1
706. [    3.272991] Freeing unused kernel memory: 5888K
707. [    3.404465] Checked W+X mappings: passed, no W+X pages found
708. [    3.404480] Run /init as init process
709.  
710. Welcome to CentOS Stream 9!
711.  
712. [    3.418241] systemd[1]: systemd 252-18.el9 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)
713. [    3.418305] systemd[1]: Detected virtualization qemu.
714. [    3.418307] systemd[1]: Detected architecture arm64.
715. [    3.418325] systemd[1]: Detected first boot.
716. [    3.418775] systemd[1]: No hostname configured, using default hostname.
717. [    3.418798] systemd[1]: Hostname set to <localhost>.
718. [    3.418872] systemd[1]: Initializing machine ID from VM UUID.
719. [    3.426422] systemd-rc-local-generator[239]: /etc/rc.d/rc.local is not marked executable, skipping.
720. [    3.429304] systemd-sysv-generator[242]: SysV service '/etc/rc.d/init.d/network' lacks a native systemd unit file. Automatically generating a unit file for compatibility. Please update package to include a native systemd unit file, in order to make it more safe and robust.
721. [    3.432101] systemd-gpt-auto-generator[235]: EFI loader partition unknown, exiting.
722. [    3.432103] systemd-gpt-auto-generator[235]: (The boot loader did not set EFI variable LoaderDevicePartUUID.)
723. [    3.527411] systemd[1]: Populated /etc with preset unit settings.
724. [  OK  ] Created slice Slice /system/getty.
725. [  OK  ] Created slice Slice /system/modprobe.
726. [  OK  ] Created slice Slice /system/serial-getty.
727. [  OK  ] Created slice Slice /system/sshd-keygen.
728. [  OK  ] Created slice User and Session Slice.
729. [  OK  ] Started Dispatch Password …ts to Console Directory Watch.
730. [  OK  ] Started Forward Password R…uests to Wall Directory Watch.
731. [  OK  ] Set up automount Arbitrary…s File System Automount Point.
732. [  OK  ] Reached target Local Encrypted Volumes.
733. [  OK  ] Reached target Local Integrity Protected Volumes.
734. [  OK  ] Reached target Slice Units.
735. [  OK  ] Reached target Swaps.[    3.552464] systemd[1]: Queued start job for default target Multi-User System.
736.  
737. [    3.553098] systemd[1]: Created slice Slice /system/getty.
738. [    3.553863] systemd[1]: Created slice Slice /system/modprobe.
739. [    3.554826] systemd[1]: Created slice Slice /system/serial-getty.
740. [    3.555709] systemd[1]: Created slice Slice /system/sshd-keygen.
741. [    3.556467] systemd[1]: Created slice User and Session Slice.
742. [    3.557231] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
743. [  OK  ] Reached target Local Verity Protected Volumes.[    3.558227] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
744.  
745. [    3.559192] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
746. [    3.559752] systemd[1]: Reached target Local Encrypted Volumes.
747. [    3.560141] systemd[1]: Reached target Local Integrity Protected Volumes.
748. [    3.560968] systemd[1]: Reached target Slice Units.
749. [    3.561618] systemd[1]: Reached target Swaps.
750. [    3.564392] systemd[1]: Reached target Local Verity Protected Volumes.
751. [    3.570621] systemd[1]: multipathd control socket was skipped because of an unmet condition check (ConditionPathExists=/etc/multipath.conf).
752. [    3.573605] systemd[1]: Listening on RPCbind Server Activation Socket.
753. [  OK  ] Listening on RPCbind Server Activation Socket.
754. [  OK  ] Reached target RPC Port Mapper.
755. [  OK  ] Listening on Process Core Dump Socket.
756. [  OK  ] Listening on initctl Compatibility Named Pipe.
757. [  OK  ] Listening on Journal Socket (/dev/log).
758. [  OK  ] Listening on Journal Socket.
759. [  OK  ] Listening on udev Control Socket.[    3.577626] systemd[1]: Reached target RPC Port Mapper.
760.  
761. [    3.578870] systemd[1]: Listening on Process Core Dump Socket.
762. [    3.579613] systemd[1]: Listening on initctl Compatibility Named Pipe.
763. [    3.580365] systemd[1]: Listening on Journal Socket (/dev/log).
764. [    3.581142] systemd[1]: Listening on Journal Socket.
765. [  OK  ] Listening on udev Kernel Socket.[    3.581741] systemd[1]: Listening on udev Control Socket.
766.  
767. [    3.583931] systemd[1]: Listening on udev Kernel Socket.
768.          Mounting Huge Pages File System...
769.          Mounting POSIX Message Queue File System...
770.          Mounting Kernel Debug File System...
771.          Mounting Kernel Trace File System...[    3.587920] systemd[1]: Mounting Huge Pages File System...
772. [    3.588892] systemd[1]: Mounting POSIX Message Queue File System...
773.  
774. [    3.589912] systemd[1]: Mounting Kernel Debug File System...
775. [    3.590965] systemd[1]: Mounting Kernel Trace File System...
776.          Starting Create List of Static Device Nodes...
777.          Starting Load Kernel Module configfs...
778.          Starting Load Kernel Module drm...
779.          Starting Load Kernel Module efi_pstore...
780.          Starting Load Kernel Module fuse...
781.          Starting Read and set NIS …from /etc/sysconfig/network...
782. [    3.594649] systemd[1]: Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab).
783. [    3.595195] systemd[1]: Starting Create List of Static Device Nodes...
784. [    3.595974] systemd[1]: Load legacy module configuration was skipped because no trigger condition checks were met.
785. [    3.596512] systemd[1]: Starting Load Kernel Module configfs...
786. [    3.597614] systemd[1]: Starting Load Kernel Module drm...
787. [    3.598403] systemd[1]: Starting Load Kernel Module efi_pstore...
788. [    3.599545] systemd[1]: Starting Load Kernel Module fuse...
789. [    3.601317] systemd[1]: Starting Read and set NIS domainname from /etc/sysconfig/network...
790.          Starting Journal Service...
791. [    3.609383] systemd[1]: Starting Journal Service...
792.          Starting Load Kernel Modules...
793.          Starting Generate network …ts from Kernel command line...
794.          Starting Remount Root and Kernel File Systems...
795.          Starting Coldplug All udev Devices...[    3.610927] fuse: init (API version 7.36)
796. [    3.612891] systemd[1]: Starting Load Kernel Modules...
797.  
798. [    3.615820] systemd[1]: Starting Generate network units from Kernel command line...
799. [    3.617869] systemd[1]: Starting Remount Root and Kernel File Systems...
800. [    3.618520] systemd[1]: Repartition Root Disk was skipped because no trigger condition checks were met.
801. [    3.621201] ACPI: bus type drm_connector registered
802. [    3.622078] systemd[1]: Starting Coldplug All udev Devices...
803. [    3.626346] Loading iSCSI transport class v2.0-870.
804. [    3.628593] systemd[1]: Mounted Huge Pages File System.
805. [  OK  ] Mounted Huge Pages File System.
806. [  OK  ] Mounted POSIX Message Queue File System.
807. [  OK  ] Mounted Kernel Debug File System.
808. [    3.632525] systemd[1]: Mounted POSIX Message Queue File System.
809. [    3.635374] systemd[1]: Mounted Kernel Debug File System.
810. [  OK  ] Mounted Kernel Trace File System.
811. [    3.649270] systemd[1]: Mounted Kernel Trace File System.
812. [  OK  ] Finished Create List of Static Device Nodes.
813. [  OK  ] Finished Load Kernel Module configfs.
814. [  OK  ] Finished Load Kernel Module drm.
815. [    3.654043] systemd[1]: Finished Create List of Static Device Nodes.
816. [  OK  ] Finished Load Kernel Module efi_pstore.
817. [  OK  ] Finished Load Kernel Module fuse.
818. [  OK  ] Finished Read and set NIS …e from /etc/sysconfig/network.
819. [  OK  ] Finished Generate network units from Kernel command line.
820. [  OK  ] Finished Remount Root and Kernel File Systems.
821. [  OK  ] Reached target Preparation for Network.
822.          Mounting FUSE Control File System...[    3.657735] systemd[1]: modprobe@configfs.service: Deactivated successfully.
823.  
824. [    3.657836] systemd[1]: Finished Load Kernel Module configfs.
825. [    3.660335] systemd[1]: modprobe@drm.service: Deactivated successfully.
826. [    3.661000] systemd[1]: Finished Load Kernel Module drm.
827. [    3.663165] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
828. [    3.663267] systemd[1]: Finished Load Kernel Module efi_pstore.
829.          Mounting Kernel Configuration File System...
830.          Starting First Boot Wizard...
831.          Starting Rebuild Hardware Database...
832.          Starting Load/Save Random Seed...
833. [  OK  ] Mounted FUSE Control File System.[    3.665111] systemd[1]: modprobe@fuse.service: Deactivated successfully.
834.  
835. [    3.665276] systemd[1]: Finished Load Kernel Module fuse.
836. [    3.666833] systemd[1]: Finished Read and set NIS domainname from /etc/sysconfig/network.
837. [    3.667776] systemd[1]: Finished Generate network units from Kernel command line.
838. [  OK  ] Started Journal Service.[    3.669312] systemd[1]: Finished Remount Root and Kernel File Systems.
839.  
840. [    3.669776] systemd[1]: Reached target Preparation for Network.
841. [    3.671966] systemd[1]: Mounting FUSE Control File System...
842. [    3.674494] iscsi: registered transport (tcp)
843. [    3.677279] systemd[1]: Mounting Kernel Configuration File System...
844. [    3.677896] systemd[1]: Special handling of early boot iSCSI sessions was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/iscsi_session).
845. [  OK  ] Mounted Kernel Configuration File System.
846. [  OK  ] Finished Load/Save Random Seed.
847.          Starting Flush Journal to Persistent Storage...
848. [  OK  ] Finished Flush Journal to Persistent Storage.[    3.678788] systemd[1]: Starting First Boot Wizard...
849. [    3.679763] systemd[1]: Starting Rebuild Hardware Database...
850.  
851. [    3.679998] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
852. [    3.680577] systemd[1]: Starting Load/Save Random Seed...
853. [    3.681821] systemd[1]: Mounted FUSE Control File System.
854. [    3.683834] systemd[1]: Started Journal Service.
855. [    3.691796] iscsi: registered transport (qla4xxx)
856. [    3.691819] QLogic iSCSI HBA Driver
857. [    3.699950] systemd-journald[261]: Received client request to flush runtime journal.
858. [  OK  ] Finished Coldplug All udev Devices.[    3.700191] libcxgbi:libcxgbi_init_module: Chelsio iSCSI driver library libcxgbi v0.9.1-ko (Apr. 2015)
859.  
860. [  OK  ] Finished First Boot Wizard.
861. [  OK  ] Reached target First Boot Complete.
862.          Starting Create System Users...
863.          Starting Wait for udev To …plete Device Initialization...
864. [  OK  ] Finished Create System Users.
865.          Starting Create Static Device Nodes in /dev...
866. [  OK  ] Finished Create Static Device Nodes in /dev.
867. [    3.738837] Chelsio T4-T6 iSCSI Driver cxgb4i v0.9.5-ko (Apr. 2015)
868. [    3.738851] iscsi: registered transport (cxgb4i)
869. [    3.745743] iscsi: registered transport (be2iscsi)
870. [    3.745744] In beiscsi_module_init, tt=00000000cd0044c3
871. [  OK  ] Finished Load Kernel Modules.
872.          Starting Apply Kernel Variables...
873. [  OK  ] Finished Apply Kernel Variables.[    3.762248] Rounding down aligned max_sectors from 4294967295 to 4294967288
874.  
875. [  OK  ] Finished Rebuild Hardware Database.
876.          Starting Rule-based Manage…for Device Events and Files...
877. [  OK  ] Started Rule-based Manager for Device Events and Files.
878. [  OK  ] Found device /dev/ttyAMA0.
879. [    4.129099] virtio_blk virtio1: 8/0/0 default/read/poll queues
880. [    4.184775] virtio_blk virtio1: [vda] 104857600 512-byte logical blocks (53.7 GB/50.0 GiB)
881.          Starting Load Kernel Module efi_pstore...
882. [  OK  ] Finished Load Kernel Module efi_pstore.
883. [  OK  ] Created slice Slice /system/dhcp-interface.
884.          Starting Load Kernel Module efi_pstore...
885. [  OK  ] Finished Load Kernel Module efi_pstore.
886. [    4.292778] virtio_gpu: probe of virtio4 failed with error -22
887. [  OK  ] Finished Wait for udev To Complete Device Initialization.
888. [  OK  ] Reached target Preparation for Local File Systems.
889. [  OK  ] Reached target Local File Systems.
890.          Starting Rebuild Dynamic Linker Cache...
891.          Starting Mark the need to relabel after reboot...
892.          Starting Automatic Boot Loader Update...
893.          Starting Commit a transient machine-id on disk...
894.          Starting Create Volatile Files and Directories...
895. [  OK  ] Finished Mark the need to relabel after reboot.
896. [  OK  ] Finished Automatic Boot Loader Update.
897. [  OK  ] Finished Commit a transient machine-id on disk.
898. [  OK  ] Finished Rebuild Dynamic Linker Cache.
899. [  OK  ] Finished Create Volatile Files and Directories.
900.          Starting Security Auditing Service...
901.          Starting RPC Bind...
902.          Starting Rebuild Journal Catalog...
903. [FAILED] Failed to start Security Auditing Service.
904. See 'systemctl status auditd.service' for details.
905.          Starting Record System Boot/Shutdown in UTMP...
906. [  OK  ] Finished Rebuild Journal Catalog.
907.          Starting Update is Completed...
908. [  OK  ] Started RPC Bind.
909. [  OK  ] Finished Record System Boot/Shutdown in UTMP.
910. [  OK  ] Finished Update is Completed.
911. [  OK  ] Reached target System Initialization.
912. [  OK  ] Started Ironic user rescue - notify path existence.
913. [  OK  ] Started dnf makecache --timer.
914. [  OK  ] Started Daily rotation of log files.
915. [  OK  ] Started Daily Cleanup of Temporary Directories.
916. [  OK  ] Reached target Path Units.
917. [  OK  ] Reached target Timer Units.
918. [  OK  ] Listening on D-Bus System Message Bus Socket.
919. [  OK  ] Listening on Open-iSCSI iscsid Socket.
920. [  OK  ] Listening on Open-iSCSI iscsiuio Socket.
921. [  OK  ] Listening on SSSD Kerberos…ache Manager responder socket.
922. [  OK  ] Reached target Socket Units.
923.          Starting D-Bus System Message Bus...
924.          Mounting RPC Pipe File System...
925. [  OK  ] Started D-Bus System Message Bus.
926. [  OK  ] Reached target Basic System.
927.          Starting Network Manager...
928.          Starting NTP client/server...
929.          Starting Restore /run/initramfs on shutdown...
930. [  OK  ] Started irqbalance daemon.
931. [  OK  ] Started QEMU Guest Agent.
932. [  OK  ] Started Hardware RNG Entropy Gatherer Daemon.
933.          Starting OpenSSH ecdsa Server Key Generation...
934.          Starting OpenSSH ed25519 Server Key Generation...
935.          Starting OpenSSH rsa Server Key Generation...
936. [  OK  ] Reached target User and Group Name Lookups.
937.          Starting User Login Management...
938. [  OK  ] Finished Restore /run/initramfs on shutdown.
939. [  OK  ] Mounted RPC Pipe File System.
940. [  OK  ] Finished OpenSSH ecdsa Server Key Generation.
941. [  OK  ] Finished OpenSSH ed25519 Server Key Generation.
942. [  OK  ] Reached target rpc_pipefs.target.[    4.674100] RPC: Registered named UNIX socket transport module.
943. [    4.674102] RPC: Registered udp transport module.
944.  
945. [    4.674102] RPC: Registered tcp transport module.
946. [    4.674102] RPC: Registered tcp NFSv4.1 backchannel transport module.
947. [  OK  ] Started NTP client/server.
948.          Starting Hostname Service...
949. [  OK  ] Stopped Security Auditing Service.
950.          Starting Security Auditing Service...
951. [  OK  ] Started Hostname Service.
952. [  OK  ] Started User Login Management.
953. [FAILED] Failed to start Security Auditing Service.
954. See 'systemctl status auditd.service' for details.
955. [  OK  ] Listening on Load/Save RF …itch Status /dev/rfkill Watch.
956.          Starting Network Manager Script Dispatcher Service...
957. [  OK  ] Started Network Manager.
958. [  OK  ] Reached target Network.
959.          Starting Network Manager Wait Online...
960.          Starting DHCP interface eth0...
961.          Starting Dynamic Login...
962.          Starting GSSAPI Proxy Daemon...
963.          Starting Initialize iBFT network interfaces...
964. [  OK  ] Finished Dynamic Login.
965. [  OK  ] Started GSSAPI Proxy Daemon.
966. [  OK  ] Started Network Manager Script Dispatcher Service.
967. [  OK  ] Reached target NFS client services.
968. [  OK  ] Finished Initialize iBFT network interfaces.
969. [  OK  ] Finished Network Manager Wait Online.
970. [  OK  ] Finished DHCP interface eth0.
971. [  OK  ] Reached target Network is Online.
972. [  OK  ] Reached target Preparation for Remote File Systems.
973. [  OK  ] Reached target Remote Encrypted Volumes.
974. [  OK  ] Reached target Remote File Systems.
975. [  OK  ] Started Ironic Python Agent.
976.          Starting Notify NFS peers of a restart...
977.          Starting System Logging Service...
978.          Starting Permit User Sessions...
979. [  OK  ] Started Notify NFS peers of a restart.
980. [  OK  ] Finished Permit User Sessions.
981. [  OK  ] Started Command Scheduler.
982. [  OK  ] Started Getty on tty1.
983. [  OK  ] Started Serial Getty on ttyAMA0.
984. [  OK  ] Reached target Login Prompts.
985. [  OK  ] Stopped Security Auditing Service.
986.          Starting Security Auditing Service...
987. [FAILED] Failed to start Security Auditing Service.
988. See 'systemctl status auditd.service' for details.
989. [  OK  ] Started System Logging Service.
990. [  OK  ] Stopped Security Auditing Service.
991.          Starting Security Auditing Service...
992. [FAILED] Failed to start Security Auditing Service.
993. See 'systemctl status auditd.service' for details.
994. [    5.281761] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
995. [  OK  ] Finished OpenSSH rsa Server Key Generation.
996. [  OK  ] Reached target sshd-keygen.target.
997. [  OK  ] Stopped Security Auditing Service.
998.          Starting Security Auditing Service...
999.          Starting OpenSSH server daemon...
1000. [  OK  ] Started OpenSSH server daemon.
1001. [  OK  ] Reached target Multi-User System.
1002.          Starting Record Runlevel Change in UTMP...
1003. [FAILED] Failed to start Security Auditing Service.
1004. See 'systemctl status auditd.service' for details.
1005. [  OK  ] Finished Record Runlevel Change in UTMP.
1006. ironic-python-agent: Did not identify any virtual media candidates devices.
1007. ironic-python-agent: No virtual media device detected
1008. 2023-12-11 12:59:01.117 503 DEBUG ironic_python_agent.cmd.agent [-] Configuration: run /usr/lib/python3.9/site-packages/ironic_python_agent/cmd/agent.py:47
1009. 2023-12-11 12:59:01.118 503 DEBUG ironic_python_agent.cmd.agent [-] ******************************************************************************** log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2593
1010. 2023-12-11 12:59:01.121 503 DEBUG ironic_python_agent.cmd.agent [-] Configuration options gathered from: log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2594
1011. 2023-12-11 12:59:01.123 503 DEBUG ironic_python_agent.cmd.agent [-] command line args: ['--config-dir', '/etc/ironic-python-agent'] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2595
1012. 2023-12-11 12:59:01.128 503 DEBUG ironic_python_agent.cmd.agent [-] config files: [] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2596
1013. 2023-12-11 12:59:01.132 503 DEBUG ironic_python_agent.cmd.agent [-] ================================================================================ log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2598
1014. 2023-12-11 12:59:01.136 503 DEBUG ironic_python_agent.cmd.agent [-] advertise_host                 = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1015. 2023-12-11 12:59:01.138 503 DEBUG ironic_python_agent.cmd.agent [-] advertise_port                 = 9999 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1016. 2023-12-11 12:59:01.144 503 DEBUG ironic_python_agent.cmd.agent [-] advertise_protocol             = http log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1017. 2023-12-11 12:59:01.149 503 DEBUG ironic_python_agent.cmd.agent [-] agent_token                    = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1018. 2023-12-11 12:59:01.152 503 DEBUG ironic_python_agent.cmd.agent [-] agent_token_required           = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1019. 2023-12-11 12:59:01.157 503 DEBUG ironic_python_agent.cmd.agent [-] api_url                        = http://192.168.24.1:6385 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1020. 2023-12-11 12:59:01.162 503 DEBUG ironic_python_agent.cmd.agent [-] auto_tls_allowed_clock_skew    = 3600 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1021. 2023-12-11 12:59:01.165 503 DEBUG ironic_python_agent.cmd.agent [-] cafile                         = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1022. 2023-12-11 12:59:01.167 503 DEBUG ironic_python_agent.cmd.agent [-] certfile                       = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1023. 2023-12-11 12:59:01.171 503 DEBUG ironic_python_agent.cmd.agent [-] collect_lldp                   = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1024. 2023-12-11 12:59:01.174 503 DEBUG ironic_python_agent.cmd.agent [-] config_dir                     = ['/etc/ironic-python-agent'] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1025. 2023-12-11 12:59:01.178 503 DEBUG ironic_python_agent.cmd.agent [-] config_file                    = [] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1026. 2023-12-11 12:59:01.182 503 DEBUG ironic_python_agent.cmd.agent [-] config_source                  = [] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1027. 2023-12-11 12:59:01.185 503 DEBUG ironic_python_agent.cmd.agent [-] debug                          = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1028. 2023-12-11 12:59:01.189 503 DEBUG ironic_python_agent.cmd.agent [-] default_log_levels             = ['amqp=WARN', 'amqplib=WARN', 'boto=WARN', 'qpid=WARN', 'sqlalchemy=WARN', 'suds=INFO', 'oslo.messaging=INFO', 'oslo_messaging=INFO', 'iso8601=WARN', 'requests.packages.urllib3.connectionpool=WARN', 'urllib3.connectionpool=WARN', 'websocket=WARN', 'requests.packages.urllib3.util.retry=WARN', 'urllib3.util.retry=WARN', 'keystonemiddleware=WARN', 'routes.middleware=WARN', 'stevedore=WARN', 'taskflow=WARN', 'keystoneauth=WARN', 'oslo.cache=INFO', 'oslo_policy=INFO', 'dogpile.core.dogpile=INFO'] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1029. 2023-12-11 12:59:01.197 503 DEBUG ironic_python_agent.cmd.agent [-] disk_wait_attempts             = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1030. 2023-12-11 12:59:01.201 503 DEBUG ironic_python_agent.cmd.agent [-] disk_wait_delay                = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1031. 2023-12-11 12:59:01.204 503 DEBUG ironic_python_agent.cmd.agent [-] enable_auto_tls                = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1032. 2023-12-11 12:59:01.206 503 DEBUG ironic_python_agent.cmd.agent [-] enable_vlan_interfaces         =  log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1033. 2023-12-11 12:59:01.211 503 DEBUG ironic_python_agent.cmd.agent [-] fail_if_clock_not_set          = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1034. 2023-12-11 12:59:01.215 503 DEBUG ironic_python_agent.cmd.agent [-] global_request_id              = req-f843aae4-963b-42aa-8165-31fed61bf55e log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1035. 2023-12-11 12:59:01.221 503 DEBUG ironic_python_agent.cmd.agent [-] hardware_initialization_delay  = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1036. 2023-12-11 12:59:01.223 503 DEBUG ironic_python_agent.cmd.agent [-] ignore_bootloader_failure      = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1037. 2023-12-11 12:59:01.227 503 DEBUG ironic_python_agent.cmd.agent [-] image_download_connection_retries = 9 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1038. 2023-12-11 12:59:01.231 503 DEBUG ironic_python_agent.cmd.agent [-] image_download_connection_retry_interval = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1039. 2023-12-11 12:59:01.235 503 DEBUG ironic_python_agent.cmd.agent [-] image_download_connection_timeout = 60 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1040. 2023-12-11 12:59:01.239 503 DEBUG ironic_python_agent.cmd.agent [-] inject_files_priority          = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1041. 2023-12-11 12:59:01.241 503 DEBUG ironic_python_agent.cmd.agent [-] insecure                       = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1042. 2023-12-11 12:59:01.244 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_callback_url        = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1043. 2023-12-11 12:59:01.247 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_collectors          = default,logs log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1044. 2023-12-11 12:59:01.252 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_dhcp_all_interfaces = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1045. 2023-12-11 12:59:01.256 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_dhcp_wait_timeout   = 60 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1046. 2023-12-11 12:59:01.260 503 DEBUG ironic_python_agent.cmd.agent [-] instance_format                = [instance: %(uuid)s]  log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1047. 2023-12-11 12:59:01.264 503 DEBUG ironic_python_agent.cmd.agent [-] instance_uuid_format           = [instance: %(uuid)s]  log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1048. 2023-12-11 12:59:01.268 503 DEBUG ironic_python_agent.cmd.agent [-] introspection_daemon           = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1049. 2023-12-11 12:59:01.270 503 DEBUG ironic_python_agent.cmd.agent [-] introspection_daemon_post_interval = 300 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1050. 2023-12-11 12:59:01.273 503 DEBUG ironic_python_agent.cmd.agent [-] ip_lookup_attempts             = 6 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1051. 2023-12-11 12:59:01.276 503 DEBUG ironic_python_agent.cmd.agent [-] ip_lookup_sleep                = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1052. 2023-12-11 12:59:01.280 503 DEBUG ironic_python_agent.cmd.agent [-] ironic_api_version             = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1053. 2023-12-11 12:59:01.283 503 DEBUG ironic_python_agent.cmd.agent [-] keyfile                        = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1054. 2023-12-11 12:59:01.285 503 DEBUG ironic_python_agent.cmd.agent [-] listen_host                    = :: log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1055. 2023-12-11 12:59:01.286 503 DEBUG ironic_python_agent.cmd.agent [-] listen_port                    = 9999 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1056. 2023-12-11 12:59:01.288 503 DEBUG ironic_python_agent.cmd.agent [-] listen_tls                     = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1057. 2023-12-11 12:59:01.292 503 DEBUG ironic_python_agent.cmd.agent [-] lldp_timeout                   = 30.0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1058. 2023-12-11 12:59:01.296 503 DEBUG ironic_python_agent.cmd.agent [-] log_config_append              = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1059. 2023-12-11 12:59:01.299 503 DEBUG ironic_python_agent.cmd.agent [-] log_date_format                = %Y-%m-%d %H:%M:%S log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1060. 2023-12-11 12:59:01.302 503 DEBUG ironic_python_agent.cmd.agent [-] log_dir                        = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1061. 2023-12-11 12:59:01.304 503 DEBUG ironic_python_agent.cmd.agent [-] log_file                       = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1062. 2023-12-11 12:59:01.306 503 DEBUG ironic_python_agent.cmd.agent [-] log_rotate_interval            = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1063. 2023-12-11 12:59:01.309 503 DEBUG ironic_python_agent.cmd.agent [-] log_rotate_interval_type       = days log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1064. 2023-12-11 12:59:01.313 503 DEBUG ironic_python_agent.cmd.agent [-] log_rotation_type              = none log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1065. 2023-12-11 12:59:01.316 503 DEBUG ironic_python_agent.cmd.agent [-] logging_context_format_string  = %(asctime)s.%(msecs)03d %(process)d %(levelname)s %(name)s [%(request_id)s %(user_identity)s] %(instance)s%(message)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1066. 2023-12-11 12:59:01.324 503 DEBUG ironic_python_agent.cmd.agent [-] logging_debug_format_suffix    = %(funcName)s %(pathname)s:%(lineno)d log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1067. 2023-12-11 12:59:01.330 503 DEBUG ironic_python_agent.cmd.agent [-] logging_default_format_string  = %(asctime)s.%(msecs)03d %(process)d %(levelname)s %(name)s [-] %(instance)s%(message)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1068. 2023-12-11 12:59:01.334 503 DEBUG ironic_python_agent.cmd.agent [-] logging_exception_prefix       = %(asctime)s.%(msecs)03d %(process)d ERROR %(name)s %(instance)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1069. 2023-12-11 12:59:01.339 503 DEBUG ironic_python_agent.cmd.agent [-] logging_user_identity_format   = %(user)s %(tenant)s %(domain)s %(user_domain)s %(project_domain)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1070. 2023-12-11 12:59:01.342 503 DEBUG ironic_python_agent.cmd.agent [-] lookup_interval                = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1071. 2023-12-11 12:59:01.345 503 DEBUG ironic_python_agent.cmd.agent [-] lookup_timeout                 = 300 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1072. 2023-12-11 12:59:01.348 503 DEBUG ironic_python_agent.cmd.agent [-] max_logfile_count              = 30 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1073. 2023-12-11 12:59:01.352 503 DEBUG ironic_python_agent.cmd.agent [-] max_logfile_size_mb            = 200 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1074. 2023-12-11 12:59:01.357 503 DEBUG ironic_python_agent.cmd.agent [-] network_interface              = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1075. 2023-12-11 12:59:01.361 503 DEBUG ironic_python_agent.cmd.agent [-] ntp_server                     = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1076. 2023-12-11 12:59:01.364 503 DEBUG ironic_python_agent.cmd.agent [-] publish_errors                 = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1077. 2023-12-11 12:59:01.365 503 DEBUG ironic_python_agent.cmd.agent [-] rate_limit_burst               = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1078. 2023-12-11 12:59:01.369 503 DEBUG ironic_python_agent.cmd.agent [-] rate_limit_except_level        = CRITICAL log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1079. 2023-12-11 12:59:01.373 503 DEBUG ironic_python_agent.cmd.agent [-] rate_limit_interval            = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1080. 2023-12-11 12:59:01.376 503 DEBUG ironic_python_agent.cmd.agent [-] syslog_log_facility            = LOG_USER log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1081. 2023-12-11 12:59:01.381 503 DEBUG ironic_python_agent.cmd.agent [-] use_eventlog                   = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1082. 2023-12-11 12:59:01.385 503 DEBUG ironic_python_agent.cmd.agent [-] use_journal                    = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1083. 2023-12-11 12:59:01.388 503 DEBUG ironic_python_agent.cmd.agent [-] use_json                       = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1084. 2023-12-11 12:59:01.389 503 DEBUG ironic_python_agent.cmd.agent [-] use_stderr                     = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1085. 2023-12-11 12:59:01.390 503 DEBUG ironic_python_agent.cmd.agent [-] use_syslog                     = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1086. 2023-12-11 12:59:01.393 503 DEBUG ironic_python_agent.cmd.agent [-] watch_log_file                 = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606
1087. 2023-12-11 12:59:01.395 503 DEBUG ironic_python_agent.cmd.agent [-] ironic_lib.fatal_exception_format_errors = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1088. 2023-12-11 12:59:01.398 503 DEBUG ironic_python_agent.cmd.agent [-] ironic_lib.root_helper         = sudo ironic-rootwrap /etc/ironic/rootwrap.conf log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1089. 2023-12-11 12:59:01.406 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.interfaces                = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1090. 2023-12-11 12:59:01.409 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.lookup_attempts           = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1091. 2023-12-11 12:59:01.413 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.params                    = {} log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1092. 2023-12-11 12:59:01.417 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.registration_attempts     = 5 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1093. 2023-12-11 12:59:01.422 503 DEBUG ironic_python_agent.cmd.agent [-] metrics_statsd.statsd_host     = localhost log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1094. 2023-12-11 12:59:01.423 503 DEBUG ironic_python_agent.cmd.agent [-] metrics_statsd.statsd_port     = 8125 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1095. 2023-12-11 12:59:01.427 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.backend                = noop log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1096. 2023-12-11 12:59:01.432 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.global_prefix          = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1097. 2023-12-11 12:59:01.435 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.prepend_host           = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1098. 2023-12-11 12:59:01.439 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.prepend_host_reverse   = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1099. 2023-12-11 12:59:01.443 503 DEBUG ironic_python_agent.cmd.agent [-] oslo_concurrency.disable_process_locking = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1100. 2023-12-11 12:59:01.447 503 DEBUG ironic_python_agent.cmd.agent [-] oslo_concurrency.lock_path     = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1101. 2023-12-11 12:59:01.451 503 DEBUG ironic_python_agent.cmd.agent [-] disk_partitioner.check_device_interval = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1102. 2023-12-11 12:59:01.455 503 DEBUG ironic_python_agent.cmd.agent [-] disk_partitioner.check_device_max_retries = 20 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1103. 2023-12-11 12:59:01.459 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.bios_boot_partition_size = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1104. 2023-12-11 12:59:01.463 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.dd_block_size       = 1M log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1105. 2023-12-11 12:59:01.466 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.efi_system_partition_size = 200 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1106. 2023-12-11 12:59:01.471 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.image_convert_attempts = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1107. 2023-12-11 12:59:01.475 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.image_convert_memory_limit = 2048 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1108. 2023-12-11 12:59:01.477 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.partition_detection_attempts = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1109. 2023-12-11 12:59:01.480 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.partprobe_attempts  = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1110. 2023-12-11 12:59:01.484 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.ca_file                    = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1111. 2023-12-11 12:59:01.488 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.cert_file                  = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1112. 2023-12-11 12:59:01.492 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.ciphers                    = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1113. 2023-12-11 12:59:01.495 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.key_file                   = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1114. 2023-12-11 12:59:01.499 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.version                    = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613
1115. 2023-12-11 12:59:01.502 503 DEBUG ironic_python_agent.cmd.agent [-] ******************************************************************************** log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2617
1116. 2023-12-11 12:59:01.507 503 INFO ironic_python_agent.utils [-] Early logging: Did not identify any virtual media candidates devices.
1117. 2023-12-11 12:59:01.515 503 INFO ironic_python_agent.utils [-] Early logging: No virtual media device detected
1118. 2023-12-11 12:59:01.561 503 INFO ironic_python_agent.agent [-] Starting ironic-python-agent version: 7.1.1.dev6
1119. 2023-12-11 12:59:01.566 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: readlink -v /sys/class/net/lo/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1120. 2023-12-11 12:59:01.575 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.009s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1121. 2023-12-11 12:59:01.577 503 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1122. 2023-12-11 12:59:01.583 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1123. 2023-12-11 12:59:01.585 503 DEBUG ironic_lib.utils [-] Command stderr is: "readlink: /sys/class/net/lo/device/driver/module: No such file or directory
1124. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1125. 2023-12-11 12:59:01.590 503 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command.
1126. Command: readlink -v /sys/class/net/lo/device/driver/module
1127. Exit code: 1
1128. Stdout: ''
1129. Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:40
1130. 2023-12-11 12:59:01.596 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: readlink -v /sys/class/net/eth0/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1131. 2023-12-11 12:59:01.605 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.009s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1132. 2023-12-11 12:59:01.610 503 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net
1133. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1134. 2023-12-11 12:59:01.614 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1135. 2023-12-11 12:59:01.616 503 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:87
1136.  
1137. CentOS Stream 9
1138. Kernel 5.14.0-386.el9.aarch64 on an aarch64
1139.  
1140. Activate the web console with: systemctl enable --now cockpit.socket
1141.  
1142. 2023-12-11 12:59:01.621 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1143. host-192-168-24-15 login: 2023-12-11 12:59:01.626 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1144. 2023-12-11 12:59:01.631 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1145. 2023-12-11 12:59:01.635 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware.
1146. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1147. 2023-12-11 12:59:01.639 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1148. 2023-12-11 12:59:01.641 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command.
1149. Command: iscsistart -f
1150. Exit code: 21
1151. Stdout: 'Could not get list of targets from firmware.\n'
1152. Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171
1153. 2023-12-11 12:59:01.648 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1154. 2023-12-11 12:59:01.657 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.009s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1155. 2023-12-11 12:59:01.664 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1156. 2023-12-11 12:59:01.668 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1157. 2023-12-11 12:59:01.671 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly
1158. mdadm: no recogniseable superblock on /dev/vda
1159. mdadm: No arrays found in config file or automatically
1160. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1161. 2023-12-11 12:59:01.675 503 INFO root [-] No new RAID devices assembled during start-up
1162. 2023-12-11 12:59:01.677 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: /usr/sbin/mpathconf --enable --find_multipaths yes --with_module y --with_multipathd y execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1163. [    6.616897] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
1164. [    6.616913] device-mapper: uevent: version 1.0.3
1165. [    6.617162] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
1166. 2023-12-11 12:59:01.739 503 DEBUG oslo_concurrency.processutils [-] CMD "/usr/sbin/mpathconf --enable --find_multipaths yes --with_module y --with_multipathd y" returned: 0 in 0.061s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1167. 2023-12-11 12:59:01.741 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1168. 2023-12-11 12:59:01.742 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1169. 2023-12-11 12:59:01.743 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipathd execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1170. 2023-12-11 12:59:01.752 503 DEBUG oslo_concurrency.processutils [-] CMD "multipathd" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1171. 2023-12-11 12:59:01.754 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1172. 2023-12-11 12:59:01.757 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1173. 2023-12-11 12:59:01.760 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipath -ll execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1174. 2023-12-11 12:59:01.768 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -ll" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1175. 2023-12-11 12:59:01.774 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1176. 2023-12-11 12:59:01.778 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1177. 2023-12-11 12:59:01.785 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1178. 2023-12-11 12:59:01.796 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.010s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1179. 2023-12-11 12:59:01.801 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1180. 2023-12-11 12:59:01.803 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1181. 2023-12-11 12:59:01.804 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1182. 2023-12-11 12:59:01.809 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1183. 2023-12-11 12:59:01.812 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID=""
1184. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1185. 2023-12-11 12:59:01.814 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1186. 2023-12-11 12:59:01.819 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1187. 2023-12-11 12:59:01.823 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1188. 2023-12-11 12:59:01.827 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1189. 2023-12-11 12:59:01.830 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0"
1190. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1191. 2023-12-11 12:59:01.832 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1192. 2023-12-11 12:59:01.834 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command.
1193. Command: multipath -c /dev/vda
1194. Exit code: 1
1195. Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n'
1196. Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254
1197. 2023-12-11 12:59:01.837 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device'
1198. 2023-12-11 12:59:01.841 503 INFO root [-] Picked root device /dev/vda for node None based on root device hints None
1199. 2023-12-11 12:59:01.842 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1200. 2023-12-11 12:59:01.847 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1201. 2023-12-11 12:59:01.850 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1202. 2023-12-11 12:59:01.852 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware.
1203. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1204. 2023-12-11 12:59:01.854 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1205. 2023-12-11 12:59:01.855 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command.
1206. Command: iscsistart -f
1207. Exit code: 21
1208. Stdout: 'Could not get list of targets from firmware.\n'
1209. Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171
1210. 2023-12-11 12:59:01.860 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1211. 2023-12-11 12:59:01.866 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1212. 2023-12-11 12:59:01.868 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1213. 2023-12-11 12:59:01.870 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1214. 2023-12-11 12:59:01.871 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly
1215. mdadm: no recogniseable superblock on /dev/vda
1216. mdadm: No arrays found in config file or automatically
1217. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1218. 2023-12-11 12:59:01.873 503 INFO root [-] No new RAID devices assembled during start-up
1219. 2023-12-11 12:59:01.874 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1220. 2023-12-11 12:59:01.881 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1221. 2023-12-11 12:59:01.883 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1222. 2023-12-11 12:59:01.885 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1223. 2023-12-11 12:59:01.886 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1224. 2023-12-11 12:59:01.892 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1225. 2023-12-11 12:59:01.894 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID=""
1226. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1227. 2023-12-11 12:59:01.896 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1228. 2023-12-11 12:59:01.901 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1229. 2023-12-11 12:59:01.906 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1230. 2023-12-11 12:59:01.910 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1231. 2023-12-11 12:59:01.914 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0"
1232. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1233. 2023-12-11 12:59:01.916 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1234. 2023-12-11 12:59:01.919 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command.
1235. Command: multipath -c /dev/vda
1236. Exit code: 1
1237. Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n'
1238. Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254
1239. 2023-12-11 12:59:01.923 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device'
1240. 2023-12-11 12:59:01.925 503 INFO root [-] Picked root device /dev/vda for node None based on root device hints None
1241. 2023-12-11 12:59:01.926 503 WARNING root [-] Can't find field vendor fordevice lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'
1242. 2023-12-11 12:59:01.929 503 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84
1243. 2023-12-11 12:59:01.932 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: readlink -v /sys/class/net/lo/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1244. 2023-12-11 12:59:01.937 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1245. 2023-12-11 12:59:01.940 503 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1246. 2023-12-11 12:59:01.943 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1247. 2023-12-11 12:59:01.945 503 DEBUG ironic_lib.utils [-] Command stderr is: "readlink: /sys/class/net/lo/device/driver/module: No such file or directory
1248. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1249. 2023-12-11 12:59:01.947 503 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command.
1250. Command: readlink -v /sys/class/net/lo/device/driver/module
1251. Exit code: 1
1252. Stdout: ''
1253. Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:40
1254. 2023-12-11 12:59:01.953 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: readlink -v /sys/class/net/eth0/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1255. 2023-12-11 12:59:01.958 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1256. 2023-12-11 12:59:01.961 503 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net
1257. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1258. 2023-12-11 12:59:01.964 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1259. 2023-12-11 12:59:01.966 503 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:87
1260. 2023-12-11 12:59:01.969 503 WARNING root [-] Can't find field vendor fordevice lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'
1261. 2023-12-11 12:59:01.971 503 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84
1262. 2023-12-11 12:59:01.973 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1263. 2023-12-11 12:59:01.977 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1264. 2023-12-11 12:59:01.980 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1265. 2023-12-11 12:59:01.984 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware.
1266. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1267. 2023-12-11 12:59:01.986 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1268. 2023-12-11 12:59:01.987 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command.
1269. Command: iscsistart -f
1270. Exit code: 21
1271. Stdout: 'Could not get list of targets from firmware.\n'
1272. Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171
1273. 2023-12-11 12:59:01.991 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1274. 2023-12-11 12:59:01.999 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1275. 2023-12-11 12:59:02.002 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1276. 2023-12-11 12:59:02.005 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1277. 2023-12-11 12:59:02.006 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly
1278. mdadm: no recogniseable superblock on /dev/vda
1279. mdadm: No arrays found in config file or automatically
1280. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1281. 2023-12-11 12:59:02.011 503 INFO root [-] No new RAID devices assembled during start-up
1282. 2023-12-11 12:59:02.012 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1283. 2023-12-11 12:59:02.019 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1284. 2023-12-11 12:59:02.022 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1285. 2023-12-11 12:59:02.024 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1286. 2023-12-11 12:59:02.025 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1287. 2023-12-11 12:59:02.031 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1288. 2023-12-11 12:59:02.034 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID=""
1289. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1290. 2023-12-11 12:59:02.037 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1291. 2023-12-11 12:59:02.041 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1292. 2023-12-11 12:59:02.046 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1293. 2023-12-11 12:59:02.050 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1294. 2023-12-11 12:59:02.051 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0"
1295. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1296. 2023-12-11 12:59:02.053 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1297. 2023-12-11 12:59:02.054 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command.
1298. Command: multipath -c /dev/vda
1299. Exit code: 1
1300. Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n'
1301. Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254
1302. 2023-12-11 12:59:02.058 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device'
1303. 2023-12-11 12:59:02.060 503 INFO root [-] Picked root device /dev/vda for node None based on root device hints None
1304. 2023-12-11 12:59:02.062 503 INFO root [-] Hardware manager found: ironic_python_agent.hardware:GenericHardwareManager
1305. 2023-12-11 12:59:02.064 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: readlink -v /sys/class/net/lo/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1306. 2023-12-11 12:59:02.068 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1307. 2023-12-11 12:59:02.070 503 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1308. 2023-12-11 12:59:02.072 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1309. 2023-12-11 12:59:02.073 503 DEBUG ironic_lib.utils [-] Command stderr is: "readlink: /sys/class/net/lo/device/driver/module: No such file or directory
1310. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1311. 2023-12-11 12:59:02.076 503 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command.
1312. Command: readlink -v /sys/class/net/lo/device/driver/module
1313. Exit code: 1
1314. Stdout: ''
1315. Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:40
1316. 2023-12-11 12:59:02.080 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: readlink -v /sys/class/net/eth0/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1317. 2023-12-11 12:59:02.084 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1318. 2023-12-11 12:59:02.088 503 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net
1319. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1320. 2023-12-11 12:59:02.091 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1321. 2023-12-11 12:59:02.093 503 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:87
1322. 2023-12-11 12:59:02.095 503 WARNING root [-] Can't find field vendor fordevice lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'
1323. 2023-12-11 12:59:02.098 503 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84
1324. 2023-12-11 12:59:02.101 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: biosdevname -i eth0 execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1325. 2023-12-11 12:59:02.105 503 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth0' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1326. 2023-12-11 12:59:02.108 503 DEBUG ironic_lib.utils [-] Command not found: "biosdevname -i eth0" execute /usr/lib/python3.9/site-packages/ironic_lib/utils.py:106
1327. 2023-12-11 12:59:02.110 503 WARNING root [-] Executable 'biosdevname' not found: FileNotFoundError: [Errno 2] No such file or directory: 'biosdevname'
1328. 2023-12-11 12:59:02.112 503 INFO root [-] Collecting full inventory
1329. 2023-12-11 12:59:02.112 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: biosdevname -i eth0 execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1330. 2023-12-11 12:59:02.116 503 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth0' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1331. 2023-12-11 12:59:02.118 503 DEBUG ironic_lib.utils [-] Command not found: "biosdevname -i eth0" execute /usr/lib/python3.9/site-packages/ironic_lib/utils.py:106
1332. 2023-12-11 12:59:02.121 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lscpu execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1333. 2023-12-11 12:59:02.124 503 DEBUG oslo_concurrency.processutils [-] CMD "lscpu" returned: 0 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1334. 2023-12-11 12:59:02.127 503 DEBUG ironic_lib.utils [-] Command stdout is: "Architecture:                       aarch64
1335. CPU op-mode(s):                     64-bit
1336. Byte Order:                         Little Endian
1337. CPU(s):                             8
1338. On-line CPU(s) list:                0-7
1339. Vendor ID:                          ARM
1340. BIOS Vendor ID:                     QEMU
1341. Model name:                         Cortex-A57
1342. BIOS Model name:                    virt-8.1
1343. Model:                              0
1344. Thread(s) per core:                 1
1345. Core(s) per socket:                 1
1346. Socket(s):                          8
1347. Stepping:                           r1p0
1348. BogoMIPS:                           48.00
1349. Flags:                              fp asimd evtstrm aes pmull sha1 sha2 crc32 fphp asimdhp cpuid dit
1350. NUMA node(s):                       1
1351. NUMA node0 CPU(s):                  0-7
1352. Vulnerability Gather data sampling: Not affected
1353. Vulnerability Itlb multihit:        Not affected
1354. Vulnerability L1tf:                 Not affected
1355. Vulnerability Mds:                  Not affected
1356. Vulnerability Meltdown:             Not affected
1357. Vulnerability Mmio stale [removed] 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}]} get_deploy_steps /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py:40
1358. 2023-12-11 12:59:03.927 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1359. 2023-12-11 12:59:03.932 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1360. 2023-12-11 12:59:03.936 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1361. 2023-12-11 12:59:03.939 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware.
1362. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1363. 2023-12-11 12:59:03.941 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1364. 2023-12-11 12:59:03.943 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command.
1365. Command: iscsistart -f
1366. Exit code: 21
1367. Stdout: 'Could not get list of targets from firmware.\n'
1368. Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171
1369. 2023-12-11 12:59:03.946 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1370. 2023-12-11 12:59:03.952 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1371. 2023-12-11 12:59:03.955 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1372. 2023-12-11 12:59:03.957 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1373. 2023-12-11 12:59:03.959 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly
1374. mdadm: no recogniseable superblock on /dev/vda
1375. mdadm: No arrays found in config file or automatically
1376. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1377. 2023-12-11 12:59:03.963 503 INFO root [-] No new RAID devices assembled during start-up
1378. 2023-12-11 12:59:03.964 503 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:1354
1379. 2023-12-11 12:59:03.966 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1380. 2023-12-11 12:59:03.972 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1381. 2023-12-11 12:59:03.975 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1382. 2023-12-11 12:59:03.977 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1383. 2023-12-11 12:59:03.980 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1384. 2023-12-11 12:59:03.985 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1385. 2023-12-11 12:59:03.989 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID=""
1386. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1387. 2023-12-11 12:59:03.992 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1388. 2023-12-11 12:59:03.995 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1389. 2023-12-11 12:59:04.001 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1390. 2023-12-11 12:59:04.004 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1391. 2023-12-11 12:59:04.007 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0"
1392. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1393. 2023-12-11 12:59:04.009 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1394. 2023-12-11 12:59:04.010 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command.
1395. Command: multipath -c /dev/vda
1396. Exit code: 1
1397. Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n'
1398. Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254
1399. 2023-12-11 12:59:04.014 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device'
1400. 2023-12-11 12:59:04.015 503 INFO root [-] Picked root device /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5 based on root device hints None
1401. 2023-12-11 12:59:04.016 503 DEBUG root [-] Returning deploy steps: defaultdict(<class 'list'>, {'GenericHardwareManager': [{'step': 'erase_devices_metadata', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'apply_configuration', 'priority': 0, 'interface': 'raid', 'reboot_requested': False, 'argsinfo': {'raid_config': {'description': 'The RAID configuration to apply.', 'required': True}, 'delete_existing': {'description': "Setting this to 'True' indicates to delete existing RAID configuration prior to creating the new configuration. Default value is 'True'.", 'required': False}}}, {'step': 'write_image', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'inject_files', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False, 'argsinfo': {'files': {'description': "Files to inject, a list of file structures with keys: 'path' (path to the file), 'partition' (partition specifier), 'content' (base64 encoded string), 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}]}) get_deploy_steps /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py:42
1402. 2023-12-11 12:59:04.031 503 INFO root [-] Synchronous command get_deploy_steps completed: {'deploy_steps': {'GenericHardwareManager': [{'step': 'erase_devices_metadata', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'apply_configuration', 'priority': 0, 'interface': 'raid', 'reboot_requested': False, 'argsinfo': {'raid_config': {'description': 'The RAID configuration to apply.', 'required': True}, 'delete_existing': {'description': "Setting this to 'True' indicates to delete existing RAID configuration prior to creating the new configuration. Default value is 'True'.", 'required': False}}}, {'step': 'write_image', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'inject_files', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False, 'argsinfo': {'files': {'description': "Files to inject, a list of file structures with keys: 'path' (path to the file), 'partition' (partition specifier), 'content' (base64 encoded string), 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}]}, 'hardware_manager_version': {'generic_hardware_manager': '1.1'}}
1403. 2023-12-11 12:59:04.048 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "POST /v1/commands/?wait=true&agent_token=0_wU51Y9lpnHrUNgrtxboO1vn09RqBpi0bjJ6C0WdoU HTTP/1.1" status: 200  len: 6266 time: 0.2470188
1404. 2023-12-11 12:59:04.063 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "GET /v1/commands/ HTTP/1.1" status: 200  len: 6282 time: 0.0002947
1405. 2023-12-11 12:59:04.117 503 DEBUG eventlet.wsgi.server [-] (503) accepted ('::ffff:192.168.24.1', 55364, 0, 0) server /usr/lib/python3.9/site-packages/eventlet/wsgi.py:992
1406. 2023-12-11 12:59:04.122 503 DEBUG root [-] Executing command: deploy.execute_deploy_step with args: {'step': {'interface': 'deploy', 'step': 'write_image', 'args': {'image_info': {'id': 'overcloud-full.raw', 'urls': ['http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5'], 'disk_format': 'raw', 'container_format': None, 'stream_raw_images': True, 'os_hash_algo': 'sha256', 'os_hash_value': 'e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2', 'node_uuid': '8d5ed008-efea-4df7-a190-aab8274a3bd5'}, 'configdrive': '<...>'}}, 'node': {'id': 1, 'uuid': '8d5ed008-efea-4df7-a190-aab8274a3bd5', 'name': 'controller', 'chassis_id': None, 'instance_uuid': '7570d05e-785b-41c0-9fc0-ad0f8c3710b5', 'driver': 'ipmi', 'driver_info': {'ipmi_username': 'admin', 'ipmi_password': '******', 'ipmi_address': '10.0.2.2', 'deploy_kernel': 'file:///var/lib/ironic/httpboot/arm64/agent.kernel', 'deploy_ramdisk': 'file:///var/lib/ironic/httpboot/arm64/agent.ramdisk', 'rescue_ramdisk': 'file:///var/lib/ironic/httpboot/arm64/agent.ramdisk', 'rescue_kernel': 'file:///var/lib/ironic/httpboot/arm64/agent.kernel'}, 'driver_internal_info': {'last_power_state_change': '2023-12-11T17:58:23.429968', 'deploy_steps': [{'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'write_image', 'priority': 80, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'prepare_instance_boot', 'priority': 60, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'tear_down_agent', 'priority': 40, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'switch_to_tenant_network', 'priority': 30, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'boot_instance', 'priority': 20, 'argsinfo': None, 'interface': 'deploy'}], 'agent_version': '7.1.1.dev6', 'agent_last_heartbeat': '2023-12-11T17:59:03.580135', 'hardware_manager_version': {'generic_hardware_manager': '1.1'}, 'agent_cached_deploy_steps_refreshed': '2023-12-11 17:59:03.853433', 'clean_steps': None, 'agent_erase_devices_iterations': 1, 'agent_erase_devices_zeroize': True, 'agent_continue_if_secure_erase_failed': False, 'agent_continue_if_ata_erase_failed': False, 'agent_enable_nvme_secure_erase': True, 'agent_enable_ata_secure_erase': True, 'disk_erasure_concurrency': 1, 'agent_erase_skip_read_only': False, 'agent_cached_clean_steps_refreshed': '2023-12-11 17:43:40.216462', 'is_whole_disk_image': True, 'deploy_step_index': 1, 'agent_secret_token': '******', 'agent_url': 'https://192.168.24.15:9999', 'agent_verify_ca': '/var/lib/ironic/certificates/8d5ed008-efea-4df7-a190-aab8274a3bd5.crt', 'agent_cached_deploy_steps': {'deploy': [{'step': 'erase_devices_metadata', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'write_image', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'inject_files', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False, 'argsinfo': {'files': {'description': "Files to inject, a list of file structures with keys: 'path' (path to the file), 'partition' (partition specifier), 'content' (base64 encoded string), 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}], 'raid': [{'step': 'apply_configuration', 'priority': 0, 'interface': 'raid', 'reboot_requested': False, 'argsinfo': {'raid_config': {'description': 'The RAID configuration to apply.', 'required': True}, 'delete_existing': {'description': "Setting this to 'True' indicates to delete existing RAID configuration prior to creating the new configuration. Default value is 'True'.", 'required': False}}}]}, 'steps_validated': True}, 'clean_step': {}, 'deploy_step': {'step': 'write_image', 'priority': 80, 'argsinfo': None, 'interface': 'deploy'}, 'raid_config': {}, 'target_raid_config': {}, 'properties': {'capabilities': 'boot_mode:uefi,profile:controller,disk_label:gpt', 'vendor': 'unknown', 'local_gb': '49', 'cpus': '8', 'cpu_arch': 'aarch64', 'memory_mb': '8192'}, 'reservation': 'undercloud.localdomain', 'conductor_affinity': 1, 'conductor_group': '', 'power_state': 'power on', 'target_power_state': None, 'provision_state': 'deploying', 'provision_updated_at': '2023-12-11T17:59:03.000000', 'target_provision_state': 'active', 'maintenance': False, 'maintenance_reason': None, 'fault': None, 'console_enabled': False, 'last_error': None, 'resource_class': 'baremetal', 'inspection_finished_at': None, 'inspection_started_at': '2023-12-11T13:30:45.000000', 'extra': {'metalsmith_attached_ports': ['b5cb64b0-c130-44bd-ae85-dbe69fc21f53'], 'metalsmith_created_ports': ['b5cb64b0-c130-44bd-ae85-dbe69fc21f53']}, 'automated_clean': None, 'protected': False, 'protected_reason': None, 'allocation_id': 17, 'bios_interface': 'no-bios', 'boot_interface': 'ipxe', 'console_interface': 'ipmitool-socat', 'deploy_interface': 'direct', 'inspect_interface': 'inspector', 'management_interface': 'ipmitool', 'network_interface': 'flat', 'power_interface': 'ipmitool', 'raid_interface': 'no-raid', 'rescue_interface': 'agent', 'storage_interface': 'noop', 'vendor_interface': 'ipmitool', 'traits': {'objects': []}, 'owner': None, 'lessee': None, 'description': None, 'retired': False, 'retired_reason': None, 'network_data': {}, 'created_at': '2023-12-11T13:30:33.000000', 'updated_at': '2023-12-11T17:59:03.910029', 'instance_info': {'traits': [], 'capabilities': {'profile': 'controller'}, 'display_name': 'overcloud-controllernoceph-0', 'image_source': 'file:///var/lib/ironic/images/aarch64/overcloud-full.raw', 'root_gb': 48, 'image_disk_format': 'raw', 'image_checksum': None, 'image_os_hash_algo': 'sha256', 'image_os_hash_value': 'e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2', 'image_url': 'http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5', 'image_type': 'whole-disk-image', 'configdrive': '<...>'}}, 'ports': [{'id': 1, 'uuid': '6e6afff9-41a1-48d8-a2a2-e1eb358bbbb6', 'node_id': 1, 'address': '52:54:00:12:34:58', 'extra': {}, 'local_link_connection': {}, 'portgroup_id': None, 'pxe_enabled': True, 'internal_info': {'tenant_vif_port_id': 'b5cb64b0-c130-44bd-ae85-dbe69fc21f53'}, 'physical_network': 'ctlplane', 'is_smartnic': False, 'name': None, 'created_at': '2023-12-11T13:30:33.000000', 'updated_at': '2023-12-11T17:58:10.000000'}], 'deploy_version': {'generic_hardware_manager': '1.1'}} execute_command /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py:253
1407. 2023-12-11 12:59:04.194 503 DEBUG root [-] Executing deploy step {'interface': 'deploy', 'step': 'write_image', 'args': {'image_info': {'id': 'overcloud-full.raw', 'urls': ['http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5'], 'disk_format': 'raw', 'container_format': None, 'stream_raw_images': True, 'os_hash_algo': 'sha256', 'os_hash_value': 'e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2', 'node_uuid': '8d5ed008-efea-4df7-a190-aab8274a3bd5'}, 'configdrive': 'H4sICLJNd2UC/3RtcGRfMzNwMm0wAO3ca1Mj2XkA4Na4ylmvtzbJ7nqdrNeJzM664mJhdOUysVMRQgzyAAIJZoAkhXVpBg1CAiSYga394CpXJX8q+QX+7l/h8k9IuiWYy+4YbM84s+N9HiH15bzndEs0/XYfNR0EAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAECiOJ9KpRNB8hrNXne3/WAi8/vKL9v77+cGVyw3+omfwVtvBT8dzfrp3z0t/vv4ZSx4fzT1fvBWPHgr+Pyd7/7t7beDG5f1k6/Zcmm9sFRbLq8vvp7lb1aq1XKtMpGezE/mkplUJj2ZSk9mU5PpfCr6tX6WXCrPReVzG9WVUczlnIXaM9NPi5NcJfqAs+lMOp2ezs+kow84+MqM1Jd8dcbr32h5vSQdvjZuXOT/IHnNoxn0gm6wG7SDB8FEkPlK+Zfy/6e3Sn9w/l8fzVr/h6fF/zjK/x+Npj76ffn/2rX+cz8OgjAYBPWgE/Sj8XY0vvf/uvzNoBJUo0c5qEVjE0E6mAzy0TMXlWWCKEEN56SGr9loOBmN5aNhajjvsyhqKao7d1F/LtiI2lp5rp0vxyxEr0/Lk2/A49UFvZr8D/C18L+X+R8A+Mb41bV97LXVv0r8z++C1c2biYNCHFI4SDw5h3/e+sJHiXcv6j8/KJY+TnwwCvrgMvq3l4NfXLcOiZdb+GnwvVHI917c/nd6h2G3P6g3919uOSvL7yaCJ23ZtgD4+ipVf5t4e/0/E9VqeXUnPTubLqwvlpLVSvFuslqev1NKllfWS9XiYmElGl+tVtYrxcpSPHKvPF+qJWsbq6uV6npyoVJNrlZq5c3kQnmplKxt1dZLy8laabmwsl4u1laXSoVaKVmsrKwXiuvJ+XKtmFzdmFsq1xZL1WHl2mqpWF4oFwvr5cpKslbZqBZLk8mohdIzgdEio+YWyvHoSrQS5eVCdSt5r7K0sVxKRqtTjN7EemXU4OWyyivR1PKw2Um/bgAY+sU158aJlzzx/3Of258HH45CPnxx+29nUunMTmpmJ50KXvbs/q8TQdzaRGpmImrtOPj+qMr3X7zkb3fqg7A/eOmlfjcRXLQEAK8u/1+dP18+/19zfPGS+f9XwY9GIT/KfysefCt/UTBqJvE3B+GgvtOqD+qTD/u97j+nh+/n5i/j8l/e/FPy8fuJ4Pk2g/8KxkbVxn4zbCnxm+fX4f1uOHjUO95/djVedi0+TARfafU8+GRU8ZNf34gHN379/Hq8c9IPj0fhr+BzeDcRPGnP3xHAm5f/rz6L/frn/5ujkJuvM/9/Oqr26evO/z8eVfyx/A/A1RLD/vl4x/+dYaZ+9gK2t4d9AzeCp13o3x4eLdzQIQ0Ab3j+D+Lv5hNx/h+epSeezf9B/L3AjWfz/7Cn4Ib8DwBvsuvvsXNtRCIX/GAU8YMXR7wX9ILDIAy6QX94s5xm4J/jAOD15v+rc3fi+ojEVPDxKOLjF0d8MLwVXTp6nYiGM8Pbx8W3ovvhqNYPX1zrnaATHSsMouOG+KgBAHiV+f/q3J24PiLxL9dcg//xk9vk7gStYU6vR9n/YZTX41sKz19z9fxYFBPXfhRFHwf7L2wjd82V7+8FJ1FkGNV/WhsAvsn5/+qz8MT1EVH+v/oa/Ovy/9VXz/9h+f/qK9/lfwB4VmL47X6ceN8b9vNHY1/5sv6DYQ9AlFhf0I3/zvDoICrTWQ8Ab1D+D+Ir+xJx/g/i7/gTL8r/Qdz7f+PF+X/YM3BD/gcAAICvr8/H2vF/+3eb4cTg7DAcu50cK/a6g+NepxMer/SK4eHe2GfJscOTRqfd3NkPz/pxSL+/N3HcrycLkbnsynm9mD5rZkrx5HxhrTAXz36wVty/v9nLhb3ZhweNuUcLqfL4Rnaxdv/szvbgYXo82ziZm07tFsuFwWl1bnVjdqs7/nB9vHq2VMhnG7Ob6dLG9p3ZjeN8qVVdmA57e4tTy63s8b36WmZ8c/1ge3uwXr87v1Y5mLnXqT/YXTjp//zO4OeZ6fHiWnM/Nb6wvvxg+1Z/Zfkwd/5o/N7+4uZKMXw4PX++PLW2mlpYmT7emjlqL57ey57vLx63p9fnNh6lws3MQe9RuvTwcLa/Vtk+v7V38rhxOJPPNmePtrbSlXsby0etavdsvp/Z6OwWzxYypdRSr7DQz5wUu7nN/NpWeqk8qDXu9jp79w6m53sbZxsbjwena9V06l7q8XbYmNoe3y+HzburG4vbx4sn9d2lpcGtheXC/sPt8blbM9PtzbmzbmkwWJye6z8+qrRPHh8tbYSFW527YXN+MN6ojt/Knm8cPmju11rjja1CdnVz5fFa42z58dHRWljozG8tzJ8+2s893FqsbZ+MHy4Wbt2fmbpzuHo0aE8vPyi0u9m9mQcrqdXqbuVe8SibDcOj87nNR+WFW4PZh7mwVjstNrOl+c3l/fGVw+XMYuHk/spRo1Gvdh+t1nNLSwuVzc2j7PRpJny8sRumF2e27i6M57brm2fju4tzu7e2po7v35mvb6+e1zZ+ljzu9Qb/etJthcfNTu+kNdnpNeudVu+g3u7+ezfesE5O2q14i5pp5cNWKjUzEe6G9Ylca3d6op6eTU3U642ZzHSunm208nF8t34w3EibTzbSeO5erz+4LOmdXixs4mlMt9eMNuSJVBzbqZ90m3s77WilHkfxqWhW/bTe7tQb7U57cLZz3usO24ljd9udMN7g/+0/oon4ro/R+OdffGG3AfxF5P9Ou7s/3MdFhwLDXXEj32xM5RqpiWY6m5rI5RqtiXo4k59oNcKp2d1mJr2bz8Y7x8vjhcO9s3jyYHASTaVzs1PRRDjYi/a64WDnoN7cqbdax2F/eOCQz9zO526nUrfTmdvZ3O38zNgX8a714la2z61Gc9A57NS74XCnfXmv24uimfrM9Ew+yhNTuzNRssiFE40wOoLJ7danpprN+mwjkxvu6qO39se8o/bhs+uans1MpqdmJjO5yXT+YiUO6v1hi5l8fvLymXr2w2gfnuZ2WnvNw3jmce9k8DR/9MPj03YzvHiTlxVa3X4c+uxyU5OpycxkNvpovvhT69mwAbjSJ09OlXbbD97+fCy+pftFprk8oRoctw87YW+i3jpoD8/ZHkR57XAYNPZoLww7Y8MsddLqxdGFpaWf/VP08pPkSmW1UKvdn78dTcXV+v29nfrJYK933D4PW/oS9CW8yr4EBz0Af+T5v/5/OVv/v/5/4JuX//X/6/8H4JtG/7++BP3/AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMCb4P8AiEKWpwDoBQA='}} execute_deploy_step /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py:65
1408. 2023-12-11 12:59:04.194 503 INFO root [-] Asynchronous command execute_deploy_step started execution
1409. 2023-12-11 12:59:04.224 503 DEBUG ironic_python_agent.extensions.standby [-] Preparing image overcloud-full.raw prepare_image /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/standby.py:681
1410. 2023-12-11 12:59:04.225 503 INFO root [-] Asynchronous command prepare_image started execution
1411. 2023-12-11 12:59:04.227 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "POST /v1/commands/?wait=false&agent_token=0_wU51Y9lpnHrUNgrtxboO1vn09RqBpi0bjJ6C0WdoU HTTP/1.1" status: 200  len: 14135 time: 0.1055114
1412. 2023-12-11 12:59:04.230 503 INFO root [-] Agent is requesting to perform an explicit node cache update. This is to pickup any changes in the cache before deployment.
1413. 2023-12-11 12:59:04.234 503 DEBUG ironic_python_agent.ironic_api_client [-] Looking up node with addresses '52:54:00:12:34:58' and UUID 8d5ed008-efea-4df7-a190-aab8274a3bd5 at http://192.168.24.1:6385 _do_lookup /usr/lib/python3.9/site-packages/ironic_python_agent/ironic_api_client.py:205
1414. 2023-12-11 12:59:04.262 503 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:1354
1415. 2023-12-11 12:59:04.264 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1416. 2023-12-11 12:59:04.271 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1417. 2023-12-11 12:59:04.274 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1418. 2023-12-11 12:59:04.276 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1419. 2023-12-11 12:59:04.279 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1420. 2023-12-11 12:59:04.286 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1421. 2023-12-11 12:59:04.288 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID=""
1422. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1423. 2023-12-11 12:59:04.291 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1424. 2023-12-11 12:59:04.295 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1425. 2023-12-11 12:59:04.300 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1426. 2023-12-11 12:59:04.303 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473
1427. 2023-12-11 12:59:04.306 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0"
1428. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1429. 2023-12-11 12:59:04.309 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1430. 2023-12-11 12:59:04.311 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command.
1431. Command: multipath -c /dev/vda
1432. Exit code: 1
1433. Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n'
1434. Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254
1435. 2023-12-11 12:59:04.317 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device'
1436. 2023-12-11 12:59:04.320 503 INFO root [-] Picked root device /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5 based on root device hints None
1437. 2023-12-11 12:59:04.322 503 INFO ironic_python_agent.extensions.standby [-] Attempting to download image from http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5
1438. 2023-12-11 12:59:15.191 503 INFO ironic_python_agent.extensions.standby [-] Image streamed onto device /dev/vda in 10.868741989135742 seconds
1439. 2023-12-11 12:59:15.195 503 DEBUG ironic_python_agent.extensions.standby [-] Verifying image at /dev/vda against sha256 checksum e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2 verify_image /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/standby.py:385
1440. 2023-12-11 12:59:15.199 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: parted --script /dev/vda -- print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1441. 2023-12-11 12:59:15.629 503 DEBUG oslo_concurrency.processutils [-] CMD "parted --script /dev/vda -- print" returned: 0 in 0.431s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1442. 2023-12-11 12:59:15.632 503 DEBUG ironic_lib.utils [-] Command stdout is: "Model: Virtio Block Device (virtblk)
1443. Disk /dev/vda: 53.7GB
1444. Sector size (logical/physical): 512B/512B
1445. Partition Table: loop
1446. Disk Flags:
1447.  
1448. Number  Start  End     Size    File system  Flags
1449.  1      0.00B  53.7GB  53.7GB  xfs
1450.  
1451. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1452. 2023-12-11 12:59:15.636 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1453. 2023-12-11 12:59:15.638 503 WARNING ironic_lib.disk_utils [-] Unable to get partition table type for device /dev/vda
1454. 2023-12-11 12:59:15.640 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk /dev/vda --pairs --bytes --ascii --nodeps --output UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1455. 2023-12-11 12:59:15.647 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk /dev/vda --pairs --bytes --ascii --nodeps --output UUID,PARTUUID" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1456. 2023-12-11 12:59:15.650 503 DEBUG ironic_lib.utils [-] Command stdout is: "UUID="c2a96066-f0e8-42f8-a3aa-7d4672858571" PARTUUID=""
1457. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1458. 2023-12-11 12:59:15.651 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1459. 2023-12-11 12:59:15.652 503 INFO ironic_python_agent.extensions.standby [-] /dev/vda UUID is now c2a96066-f0e8-42f8-a3aa-7d4672858571
1460. 2023-12-11 12:59:15.665 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: partprobe /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1461. 2023-12-11 12:59:15.683 503 DEBUG oslo_concurrency.processutils [-] CMD "partprobe /dev/vda" returned: 0 in 0.018s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1462. 2023-12-11 12:59:15.686 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1463. 2023-12-11 12:59:15.690 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1464. 2023-12-11 12:59:15.691 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: parted -s -m /dev/vda unit MiB print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1465. 2023-12-11 12:59:15.703 503 DEBUG oslo_concurrency.processutils [-] CMD "parted -s -m /dev/vda unit MiB print" returned: 0 in 0.013s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1466. 2023-12-11 12:59:15.706 503 DEBUG ironic_lib.utils [-] Command stdout is: "BYT;
1467. /dev/vda:51200MiB:virtblk:512:512:loop:Virtio Block Device:;
1468. 1:0.00MiB:51200MiB:51200MiB:xfs::;
1469. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1470. 2023-12-11 12:59:15.711 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1471. 2023-12-11 12:59:15.714 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: partprobe /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1472. 2023-12-11 12:59:15.721 503 DEBUG oslo_concurrency.processutils [-] CMD "partprobe /dev/vda" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1473. 2023-12-11 12:59:15.724 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1474. 2023-12-11 12:59:15.727 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1475. 2023-12-11 12:59:15.730 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk -Po name,label /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1476. 2023-12-11 12:59:15.735 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Po name,label /dev/vda" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1477. 2023-12-11 12:59:15.736 503 DEBUG ironic_lib.utils [-] Command stdout is: "NAME="vda" LABEL="img-rootfs"
1478. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1479. 2023-12-11 12:59:15.738 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1480. 2023-12-11 12:59:15.742 503 DEBUG ironic_lib.disk_utils [-] Adding config drive partition 1 MiB to device: /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5 create_config_drive_partition /usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py:1113
1481. 2023-12-11 12:59:15.744 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: parted --script /dev/vda -- print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1482. 2023-12-11 12:59:15.751 503 DEBUG oslo_concurrency.processutils [-] CMD "parted --script /dev/vda -- print" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1483. 2023-12-11 12:59:15.754 503 DEBUG ironic_lib.utils [-] Command stdout is: "Model: Virtio Block Device (virtblk)
1484. Disk /dev/vda: 53.7GB
1485. Sector size (logical/physical): 512B/512B
1486. Partition Table: loop
1487. Disk Flags:
1488.  
1489. Number  Start  End     Size    File system  Flags
1490.  1      0.00B  53.7GB  53.7GB  xfs
1491.  
1492. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1493. 2023-12-11 12:59:15.759 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1494. 2023-12-11 12:59:15.760 503 WARNING ironic_lib.disk_utils [-] Unable to get partition table type for device /dev/vda
1495. 2023-12-11 12:59:15.762 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: parted --script /dev/vda -- print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1496. 2023-12-11 12:59:15.769 503 DEBUG oslo_concurrency.processutils [-] CMD "parted --script /dev/vda -- print" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1497. 2023-12-11 12:59:15.772 503 DEBUG ironic_lib.utils [-] Command stdout is: "Model: Virtio Block Device (virtblk)
1498. Disk /dev/vda: 53.7GB
1499. Sector size (logical/physical): 512B/512B
1500. Partition Table: loop
1501. Disk Flags:
1502.  
1503. Number  Start  End     Size    File system  Flags
1504.  1      0.00B  53.7GB  53.7GB  xfs
1505.  
1506. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1507. 2023-12-11 12:59:15.778 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1508. 2023-12-11 12:59:15.780 503 WARNING ironic_lib.disk_utils [-] Unable to get partition table type for device /dev/vda
1509. 2023-12-11 12:59:15.780 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: parted -s -m /dev/vda unit MiB print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1510. 2023-12-11 12:59:15.787 503 DEBUG oslo_concurrency.processutils [-] CMD "parted -s -m /dev/vda unit MiB print" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1511. 2023-12-11 12:59:15.791 503 DEBUG ironic_lib.utils [-] Command stdout is: "BYT;
1512. /dev/vda:51200MiB:virtblk:512:512:loop:Virtio Block Device:;
1513. 1:0.00MiB:51200MiB:51200MiB:xfs::;
1514. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1515. 2023-12-11 12:59:15.794 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1516. 2023-12-11 12:59:15.796 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: partprobe -d -s /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1517. 2023-12-11 12:59:15.803 503 DEBUG oslo_concurrency.processutils [-] CMD "partprobe -d -s /dev/vda" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1518. 2023-12-11 12:59:15.806 503 DEBUG ironic_lib.utils [-] Command stdout is: "/dev/vda: loop partitions 1
1519. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99
1520. 2023-12-11 12:59:15.809 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1521. 2023-12-11 12:59:15.811 503 ERROR root [-] Command failed: prepare_image, error: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_lib.exception.InstanceDeployFailure: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1522. 2023-12-11 12:59:15.811 503 ERROR root Traceback (most recent call last):
1523. 2023-12-11 12:59:15.811 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py", line 1141, in create_config_drive_partition
1524. 2023-12-11 12:59:15.811 503 ERROR root     pp_count, lp_count = count_mbr_partitions(device)
1525. 2023-12-11 12:59:15.811 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py", line 153, in count_mbr_partitions
1526. 2023-12-11 12:59:15.811 503 ERROR root     raise ValueError('The device %s does not have a valid MBR '
1527. 2023-12-11 12:59:15.811 503 ERROR root ValueError: The device /dev/vda does not have a valid MBR partition table
1528. 2023-12-11 12:59:15.811 503 ERROR root
1529. 2023-12-11 12:59:15.811 503 ERROR root During handling of the above exception, another exception occurred:
1530. 2023-12-11 12:59:15.811 503 ERROR root
1531. 2023-12-11 12:59:15.811 503 ERROR root Traceback (most recent call last):
1532. 2023-12-11 12:59:15.811 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 174, in run
1533. 2023-12-11 12:59:15.811 503 ERROR root     result = self.execute_method(**self.command_params)
1534. 2023-12-11 12:59:15.811 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/standby.py", line 718, in prepare_image
1535. 2023-12-11 12:59:15.811 503 ERROR root     disk_utils.create_config_drive_partition(node_uuid,
1536. 2023-12-11 12:59:15.811 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py", line 1143, in create_config_drive_partition
1537. 2023-12-11 12:59:15.811 503 ERROR root     raise exception.InstanceDeployFailure(
1538. 2023-12-11 12:59:15.811 503 ERROR root ironic_lib.exception.InstanceDeployFailure: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1539. 2023-12-11 12:59:15.811 503 ERROR root
1540. 2023-12-11 12:59:15.824 503 ERROR root [-] Unexpected error dispatching write_image to manager <ironic_python_agent.hardware.GenericHardwareManager object at 0xffff8820dbb0>: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1541. 2023-12-11 12:59:15.824 503 ERROR root Traceback (most recent call last):
1542. 2023-12-11 12:59:15.824 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2617, in dispatch_to_managers
1543. 2023-12-11 12:59:15.824 503 ERROR root     return getattr(manager, method)(*args, **kwargs)
1544. 2023-12-11 12:59:15.824 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2487, in write_image
1545. 2023-12-11 12:59:15.824 503 ERROR root     return cmd.wait()
1546. 2023-12-11 12:59:15.824 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 89, in wait
1547. 2023-12-11 12:59:15.824 503 ERROR root     raise self.command_error
1548. 2023-12-11 12:59:15.824 503 ERROR root ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1549. 2023-12-11 12:59:15.824 503 ERROR root
1550. 2023-12-11 12:59:15.834 503 ERROR root [-] Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1551. 2023-12-11 12:59:15.834 503 ERROR root Traceback (most recent call last):
1552. 2023-12-11 12:59:15.834 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py", line 76, in execute_deploy_step
1553. 2023-12-11 12:59:15.834 503 ERROR root     result = hardware.dispatch_to_managers(step['step'], node, ports,
1554. 2023-12-11 12:59:15.834 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2617, in dispatch_to_managers
1555. 2023-12-11 12:59:15.834 503 ERROR root     return getattr(manager, method)(*args, **kwargs)
1556. 2023-12-11 12:59:15.834 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2487, in write_image
1557. 2023-12-11 12:59:15.834 503 ERROR root     return cmd.wait()
1558. 2023-12-11 12:59:15.834 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 89, in wait
1559. 2023-12-11 12:59:15.834 503 ERROR root     raise self.command_error
1560. 2023-12-11 12:59:15.834 503 ERROR root ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1561. 2023-12-11 12:59:15.834 503 ERROR root
1562. 2023-12-11 12:59:15.845 503 ERROR root [-] Command failed: execute_deploy_step, error: Deploy step failed: Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_python_agent.errors.DeploymentError: Deploy step failed: Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1563. 2023-12-11 12:59:15.845 503 ERROR root Traceback (most recent call last):
1564. 2023-12-11 12:59:15.845 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py", line 76, in execute_deploy_step
1565. 2023-12-11 12:59:15.845 503 ERROR root     result = hardware.dispatch_to_managers(step['step'], node, ports,
1566. 2023-12-11 12:59:15.845 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2617, in dispatch_to_managers
1567. 2023-12-11 12:59:15.845 503 ERROR root     return getattr(manager, method)(*args, **kwargs)
1568. 2023-12-11 12:59:15.845 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2487, in write_image
1569. 2023-12-11 12:59:15.845 503 ERROR root     return cmd.wait()
1570. 2023-12-11 12:59:15.845 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 89, in wait
1571. 2023-12-11 12:59:15.845 503 ERROR root     raise self.command_error
1572. 2023-12-11 12:59:15.845 503 ERROR root ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1573. 2023-12-11 12:59:15.845 503 ERROR root
1574. 2023-12-11 12:59:15.845 503 ERROR root During handling of the above exception, another exception occurred:
1575. 2023-12-11 12:59:15.845 503 ERROR root
1576. 2023-12-11 12:59:15.845 503 ERROR root Traceback (most recent call last):
1577. 2023-12-11 12:59:15.845 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 174, in run
1578. 2023-12-11 12:59:15.845 503 ERROR root     result = self.execute_method(**self.command_params)
1579. 2023-12-11 12:59:15.845 503 ERROR root   File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py", line 82, in execute_deploy_step
1580. 2023-12-11 12:59:15.845 503 ERROR root     raise errors.DeploymentError(msg)
1581. 2023-12-11 12:59:15.845 503 ERROR root ironic_python_agent.errors.DeploymentError: Deploy step failed: Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table
1582. 2023-12-11 12:59:15.845 503 ERROR root
1583. 2023-12-11 12:59:18.849 503 DEBUG ironic_python_agent.ironic_api_client [-] Heartbeat: announcing callback URL https://192.168.24.15:9999, API version is 1.68 heartbeat /usr/lib/python3.9/site-packages/ironic_python_agent/ironic_api_client.py:164
1584. 2023-12-11 12:59:18.903 503 INFO ironic_python_agent.agent [-] heartbeat successful
1585. 2023-12-11 12:59:18.903 503 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 125.57153486729219
1586. 2023-12-11 12:59:18.924 503 DEBUG eventlet.wsgi.server [-] (503) accepted ('::ffff:192.168.24.1', 52252, 0, 0) server /usr/lib/python3.9/site-packages/eventlet/wsgi.py:992
1587. 2023-12-11 12:59:18.949 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "GET /v1/commands/ HTTP/1.1" status: 200  len: 20620 time: 0.0130451
1588. 2023-12-11 12:59:18.953 503 DEBUG eventlet.wsgi.server [-] (503) accepted ('::ffff:192.168.24.1', 52254, 0, 0) server /usr/lib/python3.9/site-packages/eventlet/wsgi.py:992
1589. 2023-12-11 12:59:18.958 503 DEBUG root [-] Executing command: log.collect_system_logs with args: {} execute_command /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py:253
1590. 2023-12-11 12:59:18.959 503 INFO ironic_python_agent.utils [-] Collecting system logs and debugging information
1591. 2023-12-11 12:59:18.965 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: journalctl --full --no-pager -b execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1592. 2023-12-11 12:59:18.982 503 DEBUG oslo_concurrency.processutils [-] CMD "journalctl --full --no-pager -b" returned: 0 in 0.017s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1593. 2023-12-11 12:59:18.984 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1594. 2023-12-11 12:59:18.986 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: ps au execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1595. 2023-12-11 12:59:18.997 503 DEBUG oslo_concurrency.processutils [-] CMD "ps au" returned: 0 in 0.011s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1596. 2023-12-11 12:59:18.999 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1597. 2023-12-11 12:59:19.001 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: df -a execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1598. 2023-12-11 12:59:19.013 503 DEBUG oslo_concurrency.processutils [-] CMD "df -a" returned: 0 in 0.013s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1599. 2023-12-11 12:59:19.015 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1600. 2023-12-11 12:59:19.017 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: iptables -L execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1601. 2023-12-11 12:59:19.041 503 DEBUG oslo_concurrency.processutils [-] CMD "iptables -L" returned: 0 in 0.024s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1602. 2023-12-11 12:59:19.043 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1603. 2023-12-11 12:59:19.044 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: ip addr execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1604. 2023-12-11 12:59:19.060 503 DEBUG oslo_concurrency.processutils [-] CMD "ip addr" returned: 0 in 0.016s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1605. 2023-12-11 12:59:19.061 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1606. 2023-12-11 12:59:19.062 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lshw -quiet -json execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1607. 2023-12-11 12:59:19.240 503 DEBUG oslo_concurrency.processutils [-] CMD "lshw -quiet -json" returned: 0 in 0.177s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1608. 2023-12-11 12:59:19.241 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1609. 2023-12-11 12:59:19.242 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: lsblk --all -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1610. 2023-12-11 12:59:19.246 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk --all -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1611. 2023-12-11 12:59:19.249 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1612. 2023-12-11 12:59:19.250 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: cat /proc/mdstat execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1613. 2023-12-11 12:59:19.253 503 DEBUG oslo_concurrency.processutils [-] CMD "cat /proc/mdstat" returned: 0 in 0.003s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1614. 2023-12-11 12:59:19.254 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1615. 2023-12-11 12:59:19.254 503 DEBUG oslo_concurrency.processutils [-] Running cmd &#40;subprocess&#41;: multipath -ll execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384
1616. 2023-12-11 12:59:19.259 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -ll" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422
1617. 2023-12-11 12:59:19.260 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100
1618. 2023-12-11 12:59:19.270 503 INFO root [-] Synchronous command collect_system_logs completed: {'system_logs': '<...>'}
1619. 2023-12-11 12:59:19.271 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "POST /v1/commands/?wait=true&agent_token=0_wU51Y9lpnHrUNgrtxboO1vn09RqBpi0bjJ6C0WdoU HTTP/1.1" status: 200  len: 52895 time: 0.3135779