· 6 years ago · Oct 15, 2019, 03:38 PM
1Google, Inc.
2Serial Graphics Adapter 03/05/17
3SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (generic@generic) Sun, 05 Mar 2017 16:09:17 +0100
44 0
5SeaBIOS (version 1.10.2-1ubuntu1)
6
7M
8a
9c
10h
11i
12n
13e
14
15U
16U
17I
18D
19
202
216
227
23c
249
254
26e
271
28-
29e
305
317
328
33-
344
354
368
378
38-
39a
407
41c
429
43-
44b
451
46b
475
48e
49d
50d
51f
526
536
54e
550
56
57
58
59
60iPXE (http://ipxe.org) 01:01.0 CA80 PCI2.10 PnP PMM+17F92E00+17ED2E00 CA80
61
62
63
64
65
66iPXE (http://ipxe.org) 01:02.0 CB80 PCI2.10 PnP PMM 17F92E00 17ED2E00 CB80
67
68
69
70Booting from ROM...
71iPXE (PCI 01:01.0) starting execution...ok
72iPXE initialising devices...ok
73
74
75
76 -- hhttttpp::////iippxxee..oorrgg18800112244..ffbbee88cc5522dd--00uubbuunnttuu22..22 -- Open Source Network Boot Firmware
77Features: DNS HTTP HTTPS iSCSI NFS TFTP AoE ELF MBOOT PXE bzImage Menu PXEXT
78
79net0: 52:54:00:f1:ad:5f using 82540em on 0000:01:01.0 (open)
80 [Link:up, TX:0 TXE:0 RX:0 RXE:0]
81Configuring (net0 52:54:00:f1:ad:5f)...... ok
82net0: 10.1.0.7/255.255.255.192 gw 10.1.0.1
83net0: fd57:ee75:3ab9:0:5054:ff:fef1:ad5f/64 gw fe80::f816:3eff:fe54:ea7c
84net0: fe80::5054:ff:fef1:ad5f/64
85net1: fe80::5054:ff:fe94:bd20/64 (inaccessible)
86Next server: 192.168.0.5
87Filename: http://192.168.0.5:3928/boot.ipxe
88http://192.168.0.5:3928/boot.ipxe... ok
89boot.ipxe : 758 bytes [script]
90Attempting to boot from MAC 52-54-00-f1-ad-5f
91pxelinux.cfg/52-54-00-f1-ad-5f... ok
92http://192.168.0.5:3928/736535df-5753-4929-a5e2-14fda6e008ab/deploy_kernel... ok
93 ok ://192.168.0.5:3928/736535df-5753-4929-a5e2-14fda6e008ab/deploy_ramdisk... 7
94Linux version 4.14.10-tinycore64 (tc@box) (gcc version 7.2.0 (GCC)) #2018 SMP Mon Jan 1 16:07:42 UTC 2018
95Command line: selinux=0 troubleshoot=0 text nofb nomodeset vga=normal console=ttyS0 systemd.journald.forward_to_console=yes ipa-insecure=1 ipa-debug=1 BOOTIF=52:54:00:f1:ad:5f ipa-api-url=https://192.168.0.5/baremetal initrd=deploy_ramdisk
96x86/fpu: x87 FPU will use FXSAVE
97e820: BIOS-provided physical RAM map:
98BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable
99BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved
100BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
101BIOS-e820: [mem 0x0000000000100000-0x0000000017ff8fff] usable
102BIOS-e820: [mem 0x0000000017ff9000-0x0000000017ffffff] reserved
103BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
104NX (Execute Disable) protection: active
105random: fast init done
106SMBIOS 2.4 present.
107DMI: Bochs Bochs, BIOS Bochs 01/01/2011
108tsc: Fast TSC calibration using PIT
109e820: last_pfn = 0x17ff9 max_arch_pfn = 0x400000000
110x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
111found SMP MP-table at [mem 0x000f1220-0x000f122f] mapped at [ffffffffff200220]
112RAMDISK: [mem 0x14afe000-0x17ed2fff]
113ACPI: Early table checksum verification disabled
114ACPI: RSDP 0x00000000000F10D0 000014 (v00 BOCHS )
115ACPI: RSDT 0x0000000017FFE400 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
116ACPI: FACP 0x0000000017FFFF80 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
117ACPI: DSDT 0x0000000017FFE440 001189 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
118ACPI: FACS 0x0000000017FFFF40 000040
119ACPI: SSDT 0x0000000017FFF700 00083B (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001)
120ACPI: APIC 0x0000000017FFF610 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
121ACPI: HPET 0x0000000017FFF5D0 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
122Zone ranges:
123 DMA [mem 0x0000000000001000-0x0000000000ffffff]
124 DMA32 [mem 0x0000000001000000-0x0000000017ff8fff]
125 Normal empty
126Movable zone start for each node
127Early memory node ranges
128 node 0: [mem 0x0000000000001000-0x000000000009efff]
129 node 0: [mem 0x0000000000100000-0x0000000017ff8fff]
130Initmem setup node 0 [mem 0x0000000000001000-0x0000000017ff8fff]
131ACPI: PM-Timer IO Port: 0xb008
132ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
133IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
134ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
135ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
136ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
137ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
138ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
139Using ACPI (MADT) for SMP configuration information
140ACPI: HPET id: 0x8086a201 base: 0xfed00000
141smpboot: Allowing 1 CPUs, 0 hotplug CPUs
142PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
143PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
144PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
145PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
146e820: [mem 0x18000000-0xfffbffff] available for PCI devices
147Booting paravirtualized kernel on bare hardware
148clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns
149setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:1 nr_node_ids:1
150percpu: Embedded 39 pages/cpu @ffff880014800000 s120856 r8192 d30696 u2097152
151Built 1 zonelists, mobility grouping on. Total pages: 96831
152Kernel command line: selinux=0 troubleshoot=0 text nofb nomodeset vga=normal console=ttyS0 systemd.journald.forward_to_console=yes ipa-insecure=1 ipa-debug=1 BOOTIF=52:54:00:f1:ad:5f ipa-api-url=https://192.168.0.5/baremetal initrd=deploy_ramdisk
153PID hash table entries: 2048 (order: 2, 16384 bytes)
154Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
155Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
156Memory: 319740K/392796K available (6156K kernel code, 711K rwdata, 1652K rodata, 1068K init, 620K bss, 73056K reserved, 0K cma-reserved)
157Hierarchical RCU implementation.
158 RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1.
159RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
160NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16
161Console: colour VGA+ 80x25
162console [ttyS0] enabled
163clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
164tsc: Fast TSC calibration using PIT
165tsc: Detected 2299.978 MHz processor
166Calibrating delay loop (skipped), value calculated using timer frequency.. 4601.62 BogoMIPS (lpj=7666593)
167pid_max: default: 32768 minimum: 301
168ACPI: Core revision 20170728
169ACPI: 2 ACPI AML tables successfully acquired and loaded
170Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
171Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
172mce: CPU supports 10 MCE banks
173Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
174Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
175Freeing SMP alternatives memory: 20K
176smpboot: Max logical packages: 1
177..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
178smpboot: CPU0: AMD QEMU Virtual CPU version 1.0 (family: 0x6, model: 0x2, stepping: 0x3)
179Performance Events: PMU not available due to virtualization, using software events only.
180Hierarchical SRCU implementation.
181smp: Bringing up secondary CPUs ...
182smp: Brought up 1 node, 1 CPU
183smpboot: Total of 1 processors activated (4601.62 BogoMIPS)
184clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
185futex hash table entries: 256 (order: 2, 16384 bytes)
186pinctrl core: initialized pinctrl subsystem
187NET: Registered protocol family 16
188cpuidle: using governor ladder
189cpuidle: using governor menu
190ACPI: bus type PCI registered
191acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
192PCI: Using configuration type 1 for base access
193ACPI: Added _OSI(Module Device)
194ACPI: Added _OSI(Processor Device)
195ACPI: Added _OSI(3.0 _SCP Extensions)
196ACPI: Added _OSI(Processor Aggregator Device)
197ACPI: Executed 2 blocks of module-level executable AML code
198ACPI: Interpreter enabled
199ACPI: (supports S0 S3 S4 S5)
200ACPI: Using IOAPIC for interrupt routing
201PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
202ACPI: Enabled 16 GPEs in block 00 to 0F
203ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
204acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
205acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
206acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
207acpiphp: Slot [4] registered
208acpiphp: Slot [5] registered
209acpiphp: Slot [6] registered
210acpiphp: Slot [7] registered
211acpiphp: Slot [8] registered
212acpiphp: Slot [9] registered
213acpiphp: Slot [10] registered
214acpiphp: Slot [11] registered
215acpiphp: Slot [12] registered
216acpiphp: Slot [13] registered
217acpiphp: Slot [14] registered
218acpiphp: Slot [15] registered
219acpiphp: Slot [16] registered
220acpiphp: Slot [17] registered
221acpiphp: Slot [18] registered
222acpiphp: Slot [19] registered
223acpiphp: Slot [20] registered
224acpiphp: Slot [21] registered
225acpiphp: Slot [22] registered
226acpiphp: Slot [23] registered
227acpiphp: Slot [24] registered
228acpiphp: Slot [25] registered
229acpiphp: Slot [26] registered
230acpiphp: Slot [27] registered
231acpiphp: Slot [28] registered
232acpiphp: Slot [29] registered
233acpiphp: Slot [30] registered
234acpiphp: Slot [31] registered
235PCI host bridge to bus 0000:00
236pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
237pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
238pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
239pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
240pci_bus 0000:00: root bus resource [bus 00-ff]
241pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
242pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
243pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
244pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
245pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI
246pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB
247pci 0000:00:03.0: PCI bridge to [bus 01]
248ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
249ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
250ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
251ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
252ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
253pci 0000:00:02.0: vgaarb: setting as boot VGA device
254pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
255pci 0000:00:02.0: vgaarb: bridge control possible
256vgaarb: loaded
257SCSI subsystem initialized
258ACPI: bus type USB registered
259usbcore: registered new interface driver usbfs
260usbcore: registered new interface driver hub
261usbcore: registered new device driver usb
262Linux video capture interface: v2.00
263PCI: Using ACPI for IRQ routing
264HPET: 3 timers in total, 0 timers will be used for per-cpu timer
265hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
266hpet0: 3 comparators, 64-bit 100.000000 MHz counter
267clocksource: Switched to clocksource hpet
268pnp: PnP ACPI init
269pnp: PnP ACPI: found 6 devices
270clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
271pci 0000:00:03.0: PCI bridge to [bus 01]
272pci 0000:00:03.0: bridge window [io 0xc000-0xcfff]
273pci 0000:00:03.0: bridge window [mem 0xfe800000-0xfe9fffff]
274pci 0000:00:03.0: bridge window [mem 0xfe000000-0xfe1fffff 64bit pref]
275NET: Registered protocol family 2
276TCP established hash table entries: 4096 (order: 3, 32768 bytes)
277TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
278TCP: Hash tables configured (established 4096 bind 4096)
279UDP hash table entries: 256 (order: 1, 8192 bytes)
280UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
281NET: Registered protocol family 1
282RPC: Registered named UNIX socket transport module.
283RPC: Registered udp transport module.
284RPC: Registered tcp transport module.
285RPC: Registered tcp NFSv4.1 backchannel transport module.
286pci 0000:00:00.0: Limiting direct PCI/PCI transfers
287pci 0000:00:01.0: PIIX3: Enabling Passive Release
288pci 0000:00:01.0: Activating ISA DMA hang workarounds
289ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
290pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
291Trying to unpack rootfs image as initramfs...
292Freeing initrd memory: 53076K
293workingset: timestamp_bits=62 max_order=17 bucket_order=0
294NFS: Registering the id_resolver key type
295Key type id_resolver registered
296Key type id_legacy registered
297nfs4filelayout_init: NFSv4 File Layout Driver Registering...
298fuse init (API version 7.26)
299NET: Registered protocol family 38
300Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
301io scheduler noop registered
302io scheduler deadline registered (default)
303io scheduler mq-deadline registered
304io scheduler kyber registered
305input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
306ACPI: Power Button [PWRF]
307ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
308virtio-pci 0000:00:07.0: virtio_pci: leaving for legacy driver
309ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
310virtio-pci 0000:00:0a.0: virtio_pci: leaving for legacy driver
311virtio-pci 0000:00:0b.0: virtio_pci: leaving for legacy driver
312Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
31300:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
31400:05: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
315Non-volatile memory driver v1.3
316Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
317AMD IOMMUv2 driver by Joerg Roedel <jroedel@suse.de>
318AMD IOMMUv2 functionality not available on this system
319brd: module loaded
320mtip32xx Version 1.3.1
321zram: Added device: zram0
322VMware PVSCSI driver - version 1.0.7.0-k
323scsi host0: ata_piix
324scsi host1: ata_piix
325ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xd0c0 irq 14
326ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xd0c8 irq 15
327MACsec IEEE 802.1AE
328libphy: Fixed MDIO Bus: probed
329tun: Universal TUN/TAP device driver, 1.6
330PPP generic driver version 2.4.2
331PPP Deflate Compression module registered
332NET: Registered protocol family 24
333VFIO - User Level meta-driver version: 0.3
334ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
335ehci-pci: EHCI PCI platform driver
336ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
337ohci-pci: OHCI PCI platform driver
338uhci_hcd: USB Universal Host Controller Interface driver
339uhci_hcd 0000:00:01.2: UHCI Host Controller
340uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
341uhci_hcd 0000:00:01.2: irq 11, io base 0x0000d080
342hub 1-0:1.0: USB hub found
343hub 1-0:1.0: 2 ports detected
344usbcore: registered new interface driver uas
345usbcore: registered new interface driver usb-storage
346usbcore: registered new interface driver ums-alauda
347usbcore: registered new interface driver ums-cypress
348usbcore: registered new interface driver ums-datafab
349usbcore: registered new interface driver ums_eneub6250
350usbcore: registered new interface driver ums-freecom
351usbcore: registered new interface driver ums-isd200
352usbcore: registered new interface driver ums-jumpshot
353usbcore: registered new interface driver ums-karma
354usbcore: registered new interface driver ums-onetouch
355usbcore: registered new interface driver ums-sddr09
356usbcore: registered new interface driver ums-sddr55
357usbcore: registered new interface driver ums-usbat
358i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
359serio: i8042 KBD port at 0x60,0x64 irq 1
360serio: i8042 AUX port at 0x60,0x64 irq 12
361mousedev: PS/2 mouse device common for all mice
362input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
363usbcore: registered new interface driver appletouch
364usbcore: registered new interface driver bcm5974
365usbcore: registered new interface driver synaptics_usb
366rtc_cmos 00:00: RTC can wake from S4
367rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
368rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
369intel_powerclamp: CPU does not support MWAIT
370softdog: initialized. soft_noboot=0 soft_margin=60 sec soft_panic=0 (nowayout=0)
371ledtrig-cpu: registered to indicate activity on CPUs
372hidraw: raw HID events driver (C) Jiri Kosina
373usbcore: registered new interface driver usbhid
374usbhid: USB HID core driver
375Netfilter messages via NETLINK v0.30.
376nfnl_acct: registering with nfnetlink.
377ipip: IPv4 and MPLS over IPv4 tunneling driver
378IPv4 over IPsec tunneling driver
379ip_tables: (C) 2000-2006 Netfilter Core Team
380Initializing XFRM netlink socket
381NET: Registered protocol family 17
382NET: Registered protocol family 15
383Key type dns_resolver registered
384sched_clock: Marking stable (9956779478, 0)->(10053281099, -96501621)
385registered taskstats version 1
386Key type encrypted registered
387rtc_cmos 00:00: setting system clock to 2019-10-15 11:30:41 UTC (1571139041)
388Freeing unused kernel memory: 1068K
389Write protecting the kernel read-only data: 10240k
390Freeing unused kernel memory: 2020K
391Freeing unused kernel memory: 396K
392x86/mm: Checked W+X mappings: passed, no W+X pages found.
393tsc: Refined TSC clocksource calibration: 2299.998 MHz
394clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns
395init started: BusyBox v1.27.2 (2017-10-30 14:58:40 UTC)
396input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
397Booting Core 9.0
398Running Linux Kernel 4.14.10-tinycore64.
399Checking boot options... Done.
400Starting udev daemon for hotplug support...udevd[109]: IMPORT{builtin}: 'blkid' unknown /etc/udev/rules.d/63-md-raid-arrays.rules:29
401
402clocksource: Switched to clocksource tsc
403 Done.
404loop: module loaded
405Skipping compressed swap in ram as requested from the boot command line.
406Scanning hard disk partitions to create /etc/fstab
407Setting Language to C Done.
408udevd[109]: IMPORT{builtin}: 'blkid' unknown /etc/udev/rules.d/63-md-raid-arrays.rules:29
409
410udevd[109]: IMPORT{builtin}: 'blkid' unknown /etc/udev/rules.d/63-md-raid-arrays.rules:29
411
412input: PC Speaker as /devices/platform/pcspkr/input/input4
413squashfs: version 4.0 (2009/01/31) Phillip Lougher
414e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
415e1000: Copyright (c) 1999-2006 Intel Corporation.
416Possible swap partition(s) enabled.
417Loading extensions... Done.
418Setting keymap to us Done.
419FDC 0 is a S82078B
420e1000 0000:01:01.0 eth0: (PCI:33MHz:32-bit) 52:54:00:f1:ad:5f
421e1000 0000:01:01.0 eth0: Intel(R) PRO/1000 Network Connection
422ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
423Setting hostname to box Done.
424+ echo Starting bootlocal script:
425Starting bootlocal script:
426+ date
427Tue Oct 15 11:30:57 UTC 2019
428+ export HOME=/root
429+ [ -f /usr/local/etc/init.d/openssh ]
430+ echo Starting OpenSSH server:
431Starting OpenSSH server:
432+ /usr/local/etc/init.d/openssh start
433ppdev: user-space parallel port driver
434Generating public/private ecdsa key pair.
435Your identification has been saved in /usr/local/etc/ssh/ssh_host_ecdsa_key.
436Your public key has been saved in /usr/local/etc/ssh/ssh_host_ecdsa_key.pub.
437The key fingerprint is:
438SHA256:y8NbY42rpS9NV62c36nRkEnqw/+qfoCaN65K/Wm2LZA root@box
439The key's randomart image is:
440+---[ECDSA 256]---+
441| |
442| |
443| . . |
444| o + .|
445| S .. * o |
446| .E o++. * |
447| . .Oo*=o. oo|
448| . oo&+oo...o|
449| ...XOB+o++. |
450+----[SHA256]-----+
451login[423]: root login on 'tty1'
452NET: Registered protocol family 10
453Segment Routing with IPv6
454e1000 0000:01:02.0 eth1: (PCI:33MHz:32-bit) 52:54:00:94:bd:20
455e1000 0000:01:02.0 eth1: Intel(R) PRO/1000 Network Connection
456+ type ironic-python-agent
457+ sudo mkdir -p /etc/ipa-rescue-config
458+ export PYTHONOPTIMIZE=1
459+ echo Starting Ironic Python Agent:
460Starting Ironic Python Agent:
461+ date
462Tue Oct 15 11:31:01 UTC 2019
463+ ironic-python-agent
464+ tee /var/log/ironic-python-agent.log
465/etc/init.d/dhcp.sh: Discovered network devices: eth0
466 eth1
467
468Network device eth0 detected, DHCP broadcasting for IP.
469udhcpc: started, v1.27.2
470e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
471IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
472IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
473udhcpc: sending discover
474udhcpc: sending select for 10.1.0.7
475udhcpc: lease of 10.1.0.7 obtained, lease time 86400
476deleting routers
477route: SIOCDELRT: No such process
478adding dns 10.1.0.2
479
480Network device eth1 detected, DHCP broadcasting for IP.
481udhcpc: started, v1.27.2
482e1000: eth1 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
483IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
484IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
485udhcpc: sending discover
486udhcpc: sending discover
487udhcpc: sending discover
488udhcpc: no lease, forking to background
4892019-10-15 11:31:36.880 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/ip_vti0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
4902019-10-15 11:31:37.020 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/ip_vti0/device/driver/module" returned: 1 in 0.140s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
4912019-10-15 11:31:37.032 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/ip_vti0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
4922019-10-15 11:31:37.040 508 DEBUG root [-] Get driver for interface ip_vti0 failed. Error: Unexpected error while running command.
493Command: readlink -v /sys/class/net/ip_vti0/device/driver/module
494Exit code: 1
495Stdout: ''
496Stderr: 'readlink: /sys/class/net/ip_vti0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
4972019-10-15 11:31:37.049 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth1/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
4982019-10-15 11:31:37.138 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth1/device/driver/module" returned: 0 in 0.089s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
4992019-10-15 11:31:37.148 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/eth1/device/driver/module" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
5002019-10-15 11:31:37.155 508 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/e1000
501" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
5022019-10-15 11:31:37.160 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
5032019-10-15 11:31:37.167 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/tunl0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5042019-10-15 11:31:37.347 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/tunl0/device/driver/module" returned: 1 in 0.177s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5052019-10-15 11:31:37.385 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/tunl0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
5062019-10-15 11:31:37.399 508 DEBUG root [-] Get driver for interface tunl0 failed. Error: Unexpected error while running command.
507Command: readlink -v /sys/class/net/tunl0/device/driver/module
508Exit code: 1
509Stdout: ''
510Stderr: 'readlink: /sys/class/net/tunl0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
5112019-10-15 11:31:37.413 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/dummy0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5122019-10-15 11:31:37.579 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/dummy0/device/driver/module" returned: 1 in 0.166s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5132019-10-15 11:31:37.592 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/dummy0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
5142019-10-15 11:31:37.598 508 DEBUG root [-] Get driver for interface dummy0 failed. Error: Unexpected error while running command.
515Command: readlink -v /sys/class/net/dummy0/device/driver/module
516Exit code: 1
517Stdout: ''
518Stderr: 'readlink: /sys/class/net/dummy0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
5192019-10-15 11:31:37.608 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5202019-10-15 11:31:37.694 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.085s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5212019-10-15 11:31:37.704 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/eth0/device/driver/module" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
5222019-10-15 11:31:37.710 508 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/e1000
523" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
5242019-10-15 11:31:37.715 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
5252019-10-15 11:31:37.721 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5262019-10-15 11:31:37.811 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.090s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5272019-10-15 11:31:37.821 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
5282019-10-15 11:31:37.828 508 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command.
529Command: readlink -v /sys/class/net/lo/device/driver/module
530Exit code: 1
531Stdout: ''
532Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
5332019-10-15 11:31:37.836 508 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:81
5342019-10-15 11:31:37.842 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5352019-10-15 11:31:37.900 508 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
5362019-10-15 11:31:37.910 508 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: [Errno 2] No such file or directory: 'iscsistart': 'iscsistart' _check_for_iscsi /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:110
5372019-10-15 11:31:37.948 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5382019-10-15 11:31:38.134 508 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.186s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5392019-10-15 11:31:38.145 508 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
5402019-10-15 11:31:38.153 508 INFO root [-] No new RAID devices assembled during start-up
5412019-10-15 11:31:38.166 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5422019-10-15 11:31:38.298 508 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.132s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5432019-10-15 11:31:38.308 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
5442019-10-15 11:31:38.317 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
5452019-10-15 11:31:38.322 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
5462019-10-15 11:31:38.334 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5472019-10-15 11:31:38.493 508 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.158s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5482019-10-15 11:31:38.503 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
5492019-10-15 11:31:38.509 508 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"
550KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"
551KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"
552KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"
553KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"
554KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"
555KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"
556KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"
557KNAME="vda" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
558KNAME="vdb" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
559KNAME="zram0" MODEL="" SIZE="" ROTA="0" TYPE="disk"
560" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
5612019-10-15 11:31:38.517 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
5622019-10-15 11:31:38.743 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5632019-10-15 11:31:38.754 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5642019-10-15 11:31:38.763 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5652019-10-15 11:31:38.771 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5662019-10-15 11:31:38.780 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5672019-10-15 11:31:38.788 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5682019-10-15 11:31:38.796 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5692019-10-15 11:31:38.805 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
5702019-10-15 11:31:38.832 508 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'
5712019-10-15 11:31:38.859 508 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vdb. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vdb/device/scsi_device'
5722019-10-15 11:31:38.872 508 DEBUG root [-] Skipping RAM device {'KNAME': 'zram0', 'MODEL': '', 'SIZE': '', 'ROTA': '0', 'TYPE': 'disk'} list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:345
5732019-10-15 11:31:38.880 508 INFO root [-] Picked root device /dev/vda for node None based on root device hints None
5742019-10-15 11:31:38.886 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5752019-10-15 11:31:38.949 508 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
5762019-10-15 11:31:38.960 508 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: [Errno 2] No such file or directory: 'iscsistart': 'iscsistart' _check_for_iscsi /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:110
5772019-10-15 11:31:38.968 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5782019-10-15 11:31:39.117 508 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.148s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5792019-10-15 11:31:39.126 508 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
5802019-10-15 11:31:39.133 508 INFO root [-] No new RAID devices assembled during start-up
5812019-10-15 11:31:39.140 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5822019-10-15 11:31:39.231 508 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.091s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5832019-10-15 11:31:39.241 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
5842019-10-15 11:31:39.247 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
5852019-10-15 11:31:39.252 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
5862019-10-15 11:31:39.261 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
5872019-10-15 11:31:39.401 508 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.140s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
5882019-10-15 11:31:39.411 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
5892019-10-15 11:31:39.418 508 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"
590KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"
591KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"
592KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"
593KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"
594KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"
595KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"
596KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"
597KNAME="vda" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
598KNAME="vdb" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
599KNAME="zram0" MODEL="" SIZE="" ROTA="0" TYPE="disk"
600" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
6012019-10-15 11:31:39.426 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
6022019-10-15 11:31:39.613 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6032019-10-15 11:31:39.625 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6042019-10-15 11:31:39.634 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6052019-10-15 11:31:39.643 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6062019-10-15 11:31:39.652 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6072019-10-15 11:31:39.662 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6082019-10-15 11:31:39.671 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6092019-10-15 11:31:39.680 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6102019-10-15 11:31:39.698 508 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'
6112019-10-15 11:31:39.720 508 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vdb. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vdb/device/scsi_device'
6122019-10-15 11:31:39.739 508 DEBUG root [-] Skipping RAM device {'KNAME': 'zram0', 'MODEL': '', 'SIZE': '', 'ROTA': '0', 'TYPE': 'disk'} list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:345
6132019-10-15 11:31:39.746 508 INFO root [-] Picked root device /dev/vda for node None based on root device hints None
6142019-10-15 11:31:39.756 508 WARNING root [-] Can't find field vendor for device ip_vti0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/ip_vti0/device/vendor'
6152019-10-15 11:31:39.768 508 WARNING root [-] Can't find field vendor for device tunl0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/tunl0/device/vendor'
6162019-10-15 11:31:39.778 508 WARNING root [-] Can't find field vendor for device dummy0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/dummy0/device/vendor'
6172019-10-15 11:31:39.788 508 WARNING root [-] Can't find field vendor for device lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'
6182019-10-15 11:31:39.795 508 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84
6192019-10-15 11:31:39.805 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/ip_vti0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6202019-10-15 11:31:39.911 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/ip_vti0/device/driver/module" returned: 1 in 0.104s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6212019-10-15 11:31:39.923 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/ip_vti0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
6222019-10-15 11:31:39.930 508 DEBUG root [-] Get driver for interface ip_vti0 failed. Error: Unexpected error while running command.
623Command: readlink -v /sys/class/net/ip_vti0/device/driver/module
624Exit code: 1
625Stdout: ''
626Stderr: 'readlink: /sys/class/net/ip_vti0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
6272019-10-15 11:31:39.938 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth1/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6282019-10-15 11:31:40.030 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth1/device/driver/module" returned: 0 in 0.091s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6292019-10-15 11:31:40.040 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/eth1/device/driver/module" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
6302019-10-15 11:31:40.046 508 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/e1000
631" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
6322019-10-15 11:31:40.051 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
6332019-10-15 11:31:40.058 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/tunl0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6342019-10-15 11:31:40.160 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/tunl0/device/driver/module" returned: 1 in 0.101s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6352019-10-15 11:31:40.170 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/tunl0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
6362019-10-15 11:31:40.177 508 DEBUG root [-] Get driver for interface tunl0 failed. Error: Unexpected error while running command.
637Command: readlink -v /sys/class/net/tunl0/device/driver/module
638Exit code: 1
639Stdout: ''
640Stderr: 'readlink: /sys/class/net/tunl0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
6412019-10-15 11:31:40.185 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/dummy0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6422019-10-15 11:31:40.287 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/dummy0/device/driver/module" returned: 1 in 0.101s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6432019-10-15 11:31:40.298 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/dummy0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
6442019-10-15 11:31:40.310 508 DEBUG root [-] Get driver for interface dummy0 failed. Error: Unexpected error while running command.
645Command: readlink -v /sys/class/net/dummy0/device/driver/module
646Exit code: 1
647Stdout: ''
648Stderr: 'readlink: /sys/class/net/dummy0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
6492019-10-15 11:31:40.320 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6502019-10-15 11:31:40.406 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.086s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6512019-10-15 11:31:40.416 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/eth0/device/driver/module" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
6522019-10-15 11:31:40.422 508 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/e1000
653" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
6542019-10-15 11:31:40.427 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
6552019-10-15 11:31:40.434 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6562019-10-15 11:31:40.523 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.088s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6572019-10-15 11:31:40.532 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
6582019-10-15 11:31:40.539 508 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command.
659Command: readlink -v /sys/class/net/lo/device/driver/module
660Exit code: 1
661Stdout: ''
662Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
6632019-10-15 11:31:40.547 508 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:81
6642019-10-15 11:31:40.554 508 WARNING root [-] Can't find field vendor for device ip_vti0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/ip_vti0/device/vendor'
6652019-10-15 11:31:40.566 508 WARNING root [-] Can't find field vendor for device tunl0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/tunl0/device/vendor'
6662019-10-15 11:31:40.574 508 WARNING root [-] Can't find field vendor for device dummy0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/dummy0/device/vendor'
6672019-10-15 11:31:40.585 508 WARNING root [-] Can't find field vendor for device lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'
6682019-10-15 11:31:40.591 508 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84
6692019-10-15 11:31:40.598 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6702019-10-15 11:31:40.652 508 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
6712019-10-15 11:31:40.663 508 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: [Errno 2] No such file or directory: 'iscsistart': 'iscsistart' _check_for_iscsi /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:110
6722019-10-15 11:31:40.671 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6732019-10-15 11:31:40.829 508 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.157s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6742019-10-15 11:31:40.839 508 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
6752019-10-15 11:31:40.845 508 INFO root [-] No new RAID devices assembled during start-up
6762019-10-15 11:31:40.852 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6772019-10-15 11:31:40.937 508 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.085s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6782019-10-15 11:31:40.947 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
6792019-10-15 11:31:40.952 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
6802019-10-15 11:31:40.957 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
6812019-10-15 11:31:40.965 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
6822019-10-15 11:31:41.100 508 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.135s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
6832019-10-15 11:31:41.110 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
6842019-10-15 11:31:41.115 508 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"
685KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"
686KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"
687KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"
688KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"
689KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"
690KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"
691KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"
692KNAME="vda" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
693KNAME="vdb" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
694KNAME="zram0" MODEL="" SIZE="" ROTA="0" TYPE="disk"
695" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
6962019-10-15 11:31:41.122 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
6972019-10-15 11:31:41.297 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6982019-10-15 11:31:41.308 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
6992019-10-15 11:31:41.317 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
7002019-10-15 11:31:41.325 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
7012019-10-15 11:31:41.333 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
7022019-10-15 11:31:41.341 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
7032019-10-15 11:31:41.350 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
7042019-10-15 11:31:41.360 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
7052019-10-15 11:31:41.377 508 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'
7062019-10-15 11:31:41.400 508 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vdb. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vdb/device/scsi_device'
7072019-10-15 11:31:41.412 508 DEBUG root [-] Skipping RAM device {'KNAME': 'zram0', 'MODEL': '', 'SIZE': '', 'ROTA': '0', 'TYPE': 'disk'} list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:345
7082019-10-15 11:31:41.420 508 INFO root [-] Picked root device /dev/vda for node None based on root device hints None
7092019-10-15 11:31:41.425 508 INFO root [-] Hardware manager found: ironic_python_agent.hardware:GenericHardwareManager
7102019-10-15 11:31:41.434 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/ip_vti0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7112019-10-15 11:31:41.532 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/ip_vti0/device/driver/module" returned: 1 in 0.098s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7122019-10-15 11:31:41.543 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/ip_vti0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7132019-10-15 11:31:41.550 508 DEBUG root [-] Get driver for interface ip_vti0 failed. Error: Unexpected error while running command.
714Command: readlink -v /sys/class/net/ip_vti0/device/driver/module
715Exit code: 1
716Stdout: ''
717Stderr: 'readlink: /sys/class/net/ip_vti0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
7182019-10-15 11:31:41.559 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth1/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7192019-10-15 11:31:41.647 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth1/device/driver/module" returned: 0 in 0.087s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7202019-10-15 11:31:41.657 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/eth1/device/driver/module" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
7212019-10-15 11:31:41.663 508 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/e1000
722" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
7232019-10-15 11:31:41.668 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
7242019-10-15 11:31:41.675 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/tunl0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7252019-10-15 11:31:41.763 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/tunl0/device/driver/module" returned: 1 in 0.087s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7262019-10-15 11:31:41.773 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/tunl0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7272019-10-15 11:31:41.780 508 DEBUG root [-] Get driver for interface tunl0 failed. Error: Unexpected error while running command.
728Command: readlink -v /sys/class/net/tunl0/device/driver/module
729Exit code: 1
730Stdout: ''
731Stderr: 'readlink: /sys/class/net/tunl0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
7322019-10-15 11:31:41.789 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/dummy0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7332019-10-15 11:31:41.878 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/dummy0/device/driver/module" returned: 1 in 0.089s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7342019-10-15 11:31:41.888 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/dummy0/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7352019-10-15 11:31:41.895 508 DEBUG root [-] Get driver for interface dummy0 failed. Error: Unexpected error while running command.
736Command: readlink -v /sys/class/net/dummy0/device/driver/module
737Exit code: 1
738Stdout: ''
739Stderr: 'readlink: /sys/class/net/dummy0/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
7402019-10-15 11:31:41.904 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth0/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7412019-10-15 11:31:41.997 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.091s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7422019-10-15 11:31:42.008 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/eth0/device/driver/module" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
7432019-10-15 11:31:42.014 508 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/e1000
744" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
7452019-10-15 11:31:42.021 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
7462019-10-15 11:31:42.030 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7472019-10-15 11:31:42.127 508 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.096s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7482019-10-15 11:31:42.137 508 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7492019-10-15 11:31:42.145 508 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command.
750Command: readlink -v /sys/class/net/lo/device/driver/module
751Exit code: 1
752Stdout: ''
753Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42
7542019-10-15 11:31:42.154 508 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:81
7552019-10-15 11:31:42.164 508 WARNING root [-] Can't find field vendor for device ip_vti0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/ip_vti0/device/vendor'
7562019-10-15 11:31:42.175 508 WARNING root [-] Can't find field vendor for device tunl0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/tunl0/device/vendor'
7572019-10-15 11:31:42.184 508 WARNING root [-] Can't find field vendor for device dummy0 in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/dummy0/device/vendor'
7582019-10-15 11:31:42.194 508 WARNING root [-] Can't find field vendor for device lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'
7592019-10-15 11:31:42.201 508 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84
7602019-10-15 11:31:42.248 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i eth1 execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7612019-10-15 11:31:42.305 508 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth1' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7622019-10-15 11:31:42.316 508 WARNING root [-] Executable 'biosdevname' not found: FileNotFoundError: [Errno 2] No such file or directory: 'biosdevname': 'biosdevname'
7632019-10-15 11:31:42.345 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i eth0 execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7642019-10-15 11:31:42.415 508 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth0' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7652019-10-15 11:31:42.450 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i eth1 execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7662019-10-15 11:31:42.510 508 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth1' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7672019-10-15 11:31:42.541 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i eth0 execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7682019-10-15 11:31:42.600 508 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth0' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
7692019-10-15 11:31:42.612 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lscpu execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7702019-10-15 11:31:42.750 508 DEBUG oslo_concurrency.processutils [-] CMD "lscpu" returned: 0 in 0.137s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7712019-10-15 11:31:42.760 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lscpu" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
7722019-10-15 11:31:42.765 508 DEBUG ironic_lib.utils [-] Command stdout is: "Architecture: x86_64
773CPU op-mode(s): 32-bit, 64-bit
774Byte Order: Little Endian
775CPU(s): 1
776On-line CPU(s) list: 0
777Thread(s) per core: 1
778Core(s) per socket: 1
779Socket(s): 1
780Vendor ID: AuthenticAMD
781CPU family: 6
782Model: 2
783Model name: QEMU Virtual CPU version 1.0
784Stepping: 3
785CPU MHz: 2299.978
786BogoMIPS: 4601.62
787Virtualization: AMD-V
788L1d cache: 64K
789L1i cache: 64K
790L2 cache: 512K
791Flags: fpu de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx lm nopl cpuid pni cx16 popcnt hypervisor lahf_lm svm abm sse4a 3dnowprefetch vmmcall
792" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
7932019-10-15 11:31:42.773 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
7942019-10-15 11:31:42.781 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): grep -Em1 ^flags /proc/cpuinfo execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
7952019-10-15 11:31:42.882 508 DEBUG oslo_concurrency.processutils [-] CMD "grep -Em1 ^flags /proc/cpuinfo" returned: 0 in 0.101s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
7962019-10-15 11:31:42.891 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "grep -Em1 ^flags /proc/cpuinfo" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
7972019-10-15 11:31:42.897 508 DEBUG ironic_lib.utils [-] Command stdout is: "flags : fpu de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx lm nopl cpuid pni cx16 popcnt hypervisor lahf_lm svm abm sse4a 3dnowprefetch vmmcall
798" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
7992019-10-15 11:31:42.903 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8002019-10-15 11:31:42.910 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8012019-10-15 11:31:43.001 508 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.091s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8022019-10-15 11:31:43.011 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8032019-10-15 11:31:43.017 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8042019-10-15 11:31:43.023 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8052019-10-15 11:31:43.033 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8062019-10-15 11:31:43.187 508 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.153s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8072019-10-15 11:31:43.198 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8082019-10-15 11:31:43.205 508 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"
809KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"
810KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"
811KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"
812KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"
813KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"
814KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"
815KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"
816KNAME="vda" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
817KNAME="vdb" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
818KNAME="zram0" MODEL="" SIZE="" ROTA="0" TYPE="disk"
819" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8202019-10-15 11:31:43.214 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8212019-10-15 11:31:43.410 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8222019-10-15 11:31:43.423 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8232019-10-15 11:31:43.432 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8242019-10-15 11:31:43.441 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8252019-10-15 11:31:43.450 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8262019-10-15 11:31:43.459 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8272019-10-15 11:31:43.468 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8282019-10-15 11:31:43.478 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
8292019-10-15 11:31:43.497 508 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'
8302019-10-15 11:31:43.520 508 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vdb. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vdb/device/scsi_device'
8312019-10-15 11:31:43.534 508 DEBUG root [-] Skipping RAM device {'KNAME': 'zram0', 'MODEL': '', 'SIZE': '', 'ROTA': '0', 'TYPE': 'disk'} list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:345
8322019-10-15 11:31:43.554 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lshw -quiet -json execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8332019-10-15 11:31:48.630 508 DEBUG oslo_concurrency.processutils [-] CMD "lshw -quiet -json" returned: 0 in 5.072s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8342019-10-15 11:31:48.669 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lshw -quiet -json" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8352019-10-15 11:31:48.680 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8362019-10-15 11:31:48.743 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_msghandler execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8372019-10-15 11:31:48.895 508 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_msghandler" returned: 1 in 0.152s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8382019-10-15 11:31:48.909 508 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_msghandler' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
8392019-10-15 11:31:48.917 508 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command.
840Command: modprobe ipmi_msghandler
841Exit code: 1
842Stdout: ''
843Stderr: 'modprobe: module ipmi_msghandler not found in modules.dep\n' try_execute /usr/local/lib/python3.6/site-packages/ironic_python_agent/utils.py:92
8442019-10-15 11:31:48.960 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_devintf execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8452019-10-15 11:31:49.116 508 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_devintf" returned: 1 in 0.156s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8462019-10-15 11:31:49.129 508 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_devintf' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
8472019-10-15 11:31:49.136 508 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command.
848Command: modprobe ipmi_devintf
849Exit code: 1
850Stdout: ''
851Stderr: 'modprobe: module ipmi_devintf not found in modules.dep\n' try_execute /usr/local/lib/python3.6/site-packages/ironic_python_agent/utils.py:92
8522019-10-15 11:31:49.148 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_si execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8532019-10-15 11:31:49.287 508 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_si" returned: 1 in 0.139s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8542019-10-15 11:31:49.301 508 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_si' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
8552019-10-15 11:31:49.312 508 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command.
856Command: modprobe ipmi_si
857Exit code: 1
858Stdout: ''
859Stderr: 'modprobe: module ipmi_si not found in modules.dep\n' try_execute /usr/local/lib/python3.6/site-packages/ironic_python_agent/utils.py:92
8602019-10-15 11:31:49.324 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 1 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8612019-10-15 11:31:49.532 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 1 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.208s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8622019-10-15 11:31:49.543 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 1 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8632019-10-15 11:31:49.549 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8642019-10-15 11:31:49.554 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
865" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8662019-10-15 11:31:49.564 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
8672019-10-15 11:31:49.573 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 2 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8682019-10-15 11:31:49.751 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 2 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.178s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8692019-10-15 11:31:49.762 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 2 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8702019-10-15 11:31:49.796 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8712019-10-15 11:31:49.802 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
872" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8732019-10-15 11:31:49.813 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
8742019-10-15 11:31:49.824 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 3 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8752019-10-15 11:31:50.045 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 3 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.221s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8762019-10-15 11:31:50.057 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 3 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8772019-10-15 11:31:50.063 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8782019-10-15 11:31:50.069 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
879" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8802019-10-15 11:31:50.077 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
8812019-10-15 11:31:50.085 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 4 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8822019-10-15 11:31:50.277 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 4 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.191s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8832019-10-15 11:31:50.287 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 4 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8842019-10-15 11:31:50.293 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8852019-10-15 11:31:50.298 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
886" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8872019-10-15 11:31:50.306 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
8882019-10-15 11:31:50.314 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 5 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8892019-10-15 11:31:50.501 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 5 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.186s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8902019-10-15 11:31:50.511 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 5 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8912019-10-15 11:31:50.517 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8922019-10-15 11:31:50.523 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
893" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
8942019-10-15 11:31:50.531 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
8952019-10-15 11:31:50.539 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 6 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
8962019-10-15 11:31:50.725 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 6 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.185s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
8972019-10-15 11:31:50.735 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 6 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
8982019-10-15 11:31:50.741 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
8992019-10-15 11:31:50.747 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
900" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9012019-10-15 11:31:50.754 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
9022019-10-15 11:31:50.763 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 7 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9032019-10-15 11:31:50.944 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 7 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.181s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9042019-10-15 11:31:50.954 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 7 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9052019-10-15 11:31:50.961 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9062019-10-15 11:31:50.966 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
907" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9082019-10-15 11:31:50.974 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
9092019-10-15 11:31:50.982 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 8 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9102019-10-15 11:31:51.197 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 8 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.213s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9112019-10-15 11:31:51.211 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 8 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9122019-10-15 11:31:51.217 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9132019-10-15 11:31:51.225 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
914" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9152019-10-15 11:31:51.236 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
9162019-10-15 11:31:51.246 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 9 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9172019-10-15 11:31:51.457 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 9 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.210s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9182019-10-15 11:31:51.468 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 9 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9192019-10-15 11:31:51.475 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9202019-10-15 11:31:51.480 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
921" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9222019-10-15 11:31:51.491 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
9232019-10-15 11:31:51.501 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 10 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9242019-10-15 11:31:51.727 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 10 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.225s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9252019-10-15 11:31:51.738 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 10 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9262019-10-15 11:31:51.744 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9272019-10-15 11:31:51.748 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
928" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9292019-10-15 11:31:51.757 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
9302019-10-15 11:31:51.765 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 11 | awk '/IP Address[ \t]*:/ {print $4}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9312019-10-15 11:31:51.953 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 11 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.187s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9322019-10-15 11:31:51.963 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 11 | awk '/IP Address[ \t]*:/ {print $4}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9332019-10-15 11:31:51.968 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9342019-10-15 11:31:51.973 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
935" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9362019-10-15 11:31:51.980 508 WARNING root [-] Invalid IP address: : netaddr.core.AddrFormatError: failed to detect a valid IP address from ''
9372019-10-15 11:31:51.989 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_msghandler execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9382019-10-15 11:31:52.123 508 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_msghandler" returned: 1 in 0.134s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9392019-10-15 11:31:52.134 508 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_msghandler' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
9402019-10-15 11:31:52.141 508 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command.
941Command: modprobe ipmi_msghandler
942Exit code: 1
943Stdout: ''
944Stderr: 'modprobe: module ipmi_msghandler not found in modules.dep\n' try_execute /usr/local/lib/python3.6/site-packages/ironic_python_agent/utils.py:92
9452019-10-15 11:31:52.149 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_devintf execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9462019-10-15 11:31:52.288 508 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_devintf" returned: 1 in 0.139s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9472019-10-15 11:31:52.299 508 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_devintf' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
9482019-10-15 11:31:52.307 508 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command.
949Command: modprobe ipmi_devintf
950Exit code: 1
951Stdout: ''
952Stderr: 'modprobe: module ipmi_devintf not found in modules.dep\n' try_execute /usr/local/lib/python3.6/site-packages/ironic_python_agent/utils.py:92
9532019-10-15 11:31:52.316 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_si execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9542019-10-15 11:31:52.474 508 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_si" returned: 1 in 0.158s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9552019-10-15 11:31:52.486 508 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_si' failed. Not Retrying. execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:457
9562019-10-15 11:31:52.494 508 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command.
957Command: modprobe ipmi_si
958Exit code: 1
959Stdout: ''
960Stderr: 'modprobe: module ipmi_si not found in modules.dep\n' try_execute /usr/local/lib/python3.6/site-packages/ironic_python_agent/utils.py:92
9612019-10-15 11:31:52.518 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 1 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9622019-10-15 11:31:52.735 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 1 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.217s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9632019-10-15 11:31:52.748 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 1 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9642019-10-15 11:31:52.755 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9652019-10-15 11:31:52.760 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
966" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9672019-10-15 11:31:52.769 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 2 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9682019-10-15 11:31:52.994 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 2 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.223s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9692019-10-15 11:31:53.006 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 2 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9702019-10-15 11:31:53.013 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9712019-10-15 11:31:53.018 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
972" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9732019-10-15 11:31:53.026 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 3 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9742019-10-15 11:31:53.205 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 3 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.179s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9752019-10-15 11:31:53.215 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 3 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9762019-10-15 11:31:53.221 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9772019-10-15 11:31:53.226 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
978" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9792019-10-15 11:31:53.233 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 4 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9802019-10-15 11:31:53.413 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 4 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.179s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9812019-10-15 11:31:53.424 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 4 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9822019-10-15 11:31:53.430 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9832019-10-15 11:31:53.435 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
984" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9852019-10-15 11:31:53.442 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 5 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9862019-10-15 11:31:53.642 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 5 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.199s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9872019-10-15 11:31:53.652 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 5 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9882019-10-15 11:31:53.659 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9892019-10-15 11:31:53.665 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
990" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9912019-10-15 11:31:53.673 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 6 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9922019-10-15 11:31:54.020 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 6 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.343s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9932019-10-15 11:31:54.065 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 6 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
9942019-10-15 11:31:54.074 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
9952019-10-15 11:31:54.080 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
996" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
9972019-10-15 11:31:54.092 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 7 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
9982019-10-15 11:31:54.357 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 7 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.264s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
9992019-10-15 11:31:54.368 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 7 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10002019-10-15 11:31:54.374 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10012019-10-15 11:31:54.379 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
1002" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10032019-10-15 11:31:54.385 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 8 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10042019-10-15 11:31:54.600 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 8 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.213s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10052019-10-15 11:31:54.614 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 8 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10062019-10-15 11:31:54.621 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10072019-10-15 11:31:54.628 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
1008" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10092019-10-15 11:31:54.635 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 9 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10102019-10-15 11:31:54.862 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 9 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.226s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10112019-10-15 11:31:54.875 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 9 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10122019-10-15 11:31:54.882 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10132019-10-15 11:31:54.889 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
1014" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10152019-10-15 11:31:54.899 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 10 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10162019-10-15 11:31:55.098 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 10 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.199s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10172019-10-15 11:31:55.108 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 10 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10182019-10-15 11:31:55.114 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10192019-10-15 11:31:55.119 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
1020" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10212019-10-15 11:31:55.127 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 11 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10222019-10-15 11:31:55.301 508 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 11 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.173s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10232019-10-15 11:31:55.310 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 11 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10242019-10-15 11:31:55.316 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10252019-10-15 11:31:55.321 508 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory
1026" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10272019-10-15 11:31:55.328 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lshw -quiet -json execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10282019-10-15 11:31:59.991 508 DEBUG oslo_concurrency.processutils [-] CMD "lshw -quiet -json" returned: 0 in 4.663s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10292019-10-15 11:32:00.007 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lshw -quiet -json" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10302019-10-15 11:32:00.016 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10312019-10-15 11:32:00.037 508 DEBUG root [-] The current boot mode is bios get_boot_info /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:986
10322019-10-15 11:32:00.567 508 DEBUG ironic_python_agent.agent [-] Received lookup results: {'node': {'uuid': '736535df-5753-4929-a5e2-14fda6e008ab', 'instance_info': {'image_source': '9c28be57-85f7-4b80-81fe-8900084d8896', 'root_gb': 3, 'image_url': '******', 'image_checksum': '443b7623e27ecf03dc9e01ee93f67afe', 'image_disk_format': 'qcow2', 'image_os_hash_algo': 'sha512', 'image_os_hash_value': '6513f21e44aa3da349f248188a44bc304a3653a04122d8fb4535423c8e1d14cd6a153f735bb0982e2161b5b5186106570c17a9e58b64dd39390617cd5a350f78', 'image_container_format': 'bare', 'image_tags': [], 'image_properties': {'os_hidden': False, 'virtual_size': None}, 'image_type': 'whole-disk-image', 'image_mem_req': 22}, 'driver_internal_info': {'is_whole_disk_image': True, 'deploy_boot_mode': 'bios', 'deploy_steps': [{'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}], 'deploy_step_index': 0, 'last_power_state_change': '2019-10-15T11:30:12.505461'}, 'properties': {'cpu_arch': 'x86_64'}, 'links': [{'href': 'https://192.168.0.5/baremetal/v1/nodes/736535df-5753-4929-a5e2-14fda6e008ab', 'rel': 'self'}, {'href': 'https://192.168.0.5/baremetal/nodes/736535df-5753-4929-a5e2-14fda6e008ab', 'rel': 'bookmark'}]}, 'config': {'metrics': {'backend': 'noop', 'prepend_host': False, 'prepend_uuid': False, 'prepend_host_reverse': True, 'global_prefix': None}, 'metrics_statsd': {'statsd_host': 'localhost', 'statsd_port': 8125}, 'heartbeat_timeout': 30}} run /usr/local/lib/python3.6/site-packages/ironic_python_agent/agent.py:407
10332019-10-15 11:32:00.587 508 INFO ironic_python_agent.agent [-] Lookup succeeded, node UUID is 736535df-5753-4929-a5e2-14fda6e008ab
10342019-10-15 11:32:00.592 508 INFO root [-] Cached node 736535df-5753-4929-a5e2-14fda6e008ab, waiting for its root device to appear
10352019-10-15 11:32:00.599 508 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:941
10362019-10-15 11:32:00.624 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10372019-10-15 11:32:00.741 508 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.116s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10382019-10-15 11:32:00.752 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10392019-10-15 11:32:00.758 508 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10402019-10-15 11:32:00.764 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10412019-10-15 11:32:00.775 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10422019-10-15 11:32:00.935 508 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.160s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10432019-10-15 11:32:00.948 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10442019-10-15 11:32:00.955 508 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1045KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1046KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1047KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1048KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1049KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1050KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1051KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"
1052KNAME="vda" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
1053KNAME="vdb" MODEL="" SIZE="5368709120" ROTA="1" TYPE="disk"
1054KNAME="zram0" MODEL="" SIZE="" ROTA="0" TYPE="disk"
1055" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10562019-10-15 11:32:00.969 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10572019-10-15 11:32:01.158 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop0" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10582019-10-15 11:32:01.170 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop1" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10592019-10-15 11:32:01.180 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop2" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10602019-10-15 11:32:01.193 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop3" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10612019-10-15 11:32:01.202 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop4" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10622019-10-15 11:32:01.212 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop5" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10632019-10-15 11:32:01.223 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop6" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10642019-10-15 11:32:01.235 508 DEBUG root [-] TYPE did not match. Wanted: 'disk' but found: 'KNAME="loop7" MODEL="" SIZE="" ROTA="1" TYPE="loop"' list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:332
10652019-10-15 11:32:01.268 508 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'
10662019-10-15 11:32:01.300 508 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vdb. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vdb/device/scsi_device'
10672019-10-15 11:32:01.318 508 DEBUG root [-] Skipping RAM device {'KNAME': 'zram0', 'MODEL': '', 'SIZE': '', 'ROTA': '0', 'TYPE': 'disk'} list_all_block_devices /usr/local/lib/python3.6/site-packages/ironic_python_agent/hardware.py:345
10682019-10-15 11:32:01.327 508 INFO root [-] Picked root device /dev/vda for node 736535df-5753-4929-a5e2-14fda6e008ab based on root device hints None
10692019-10-15 11:32:01.362 508 INFO ironic_python_agent.agent [-] starting heartbeater
10702019-10-15 11:32:01.382 508 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ip route get 192.168.0.5 execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:372
10712019-10-15 11:32:01.503 508 DEBUG oslo_concurrency.processutils [-] CMD "ip route get 192.168.0.5" returned: 0 in 0.121s execute /usr/local/lib/python3.6/site-packages/oslo_concurrency/processutils.py:409
10722019-10-15 11:32:01.514 508 DEBUG ironic_lib.utils [-] Execution completed, command line is "ip route get 192.168.0.5" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:101
10732019-10-15 11:32:01.519 508 DEBUG ironic_lib.utils [-] Command stdout is: "192.168.0.5 via 10.1.0.1 dev eth0 src 10.1.0.7
1074 cache
1075" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:103
10762019-10-15 11:32:01.524 508 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /usr/local/lib/python3.6/site-packages/ironic_lib/utils.py:104
10772019-10-15 11:32:01.956 508 INFO ironic_python_agent.agent [-] heartbeat successful
10782019-10-15 11:32:01.965 508 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 13.326625062001046