· 7 years ago · Jan 24, 2019, 08:14 AM
1Before test:
2root@romulus:~# ip ad
31: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue qlen 1000
4 link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
5 inet 127.0.0.1/8 scope host lo
6 valid_lft forever preferred_lft forever
7 inet6 ::1/128 scope host
8 valid_lft forever preferred_lft forever
92: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast qlen 1000
10 link/ether aa:e2:84:14:28:79 brd ff:ff:ff:ff:ff:ff
11 inet 192.168.122.2/24 brd 192.168.122.255 scope global eth0
12 valid_lft forever preferred_lft forever
13 inet6 fe80::a8e2:84ff:fe14:2879/64 scope link
14 valid_lft forever preferred_lft forever
153: sit0@NONE: <NOARP> mtu 1480 qdisc noop qlen 1000
16 link/sit 0.0.0.0 brd 0.0.0.0
17
18After test:
19root@romulus:~# ip ad
201: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue qlen 1000
21 link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
22 inet 127.0.0.1/8 scope host lo
23 valid_lft forever preferred_lft forever
24 inet6 ::1/128 scope host
25 valid_lft forever preferred_lft forever
262: eth0: <BROADCAST,MULTICAST> mtu 1500 qdisc pfifo_fast qlen 1000
27 link/ether aa:e2:84:14:28:cc brd ff:ff:ff:ff:ff:ff
283: sit0@NONE: <NOARP> mtu 1480 qdisc noop qlen 1000
29 link/sit 0.0.0.0 brd 0.0.0.0
30
31Error at OpenBmc console:
32root@romulus:~# [ 317.871549] ftgmac100 1e660000.ethernet eth0: NCSI: No channel found with link
33
34Full logs:
35journalctl --no-pager -b
36-- Logs begin at Thu 2019-01-24 07:57:44 UTC, end at Thu 2019-01-24 08:02:59 UTC. --
37Jan 24 07:57:44 romulus kernel: Booting Linux on physical CPU 0x0
38Jan 24 07:57:44 romulus kernel: Linux version 4.19.14-659bbe7f501770c49248b1c74a700342e8957273 (oe-user@oe-host) (gcc version 8.2.0 (GCC)) #1 Wed Jan 16 01:24:14 UTC 2019
39Jan 24 07:57:44 romulus kernel: CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
40Jan 24 07:57:44 romulus kernel: CPU: VIPT aliasing data cache, unknown instruction cache
41Jan 24 07:57:44 romulus kernel: OF: fdt: Machine model: Romulus BMC
42Jan 24 07:57:44 romulus kernel: Memory policy: Data cache writeback
43Jan 24 07:57:44 romulus kernel: Reserved memory: created CMA memory pool at 0x8d000000, size 16 MiB
44Jan 24 07:57:44 romulus kernel: OF: reserved mem: initialized node framebuffer, compatible id shared-dma-pool
45Jan 24 07:57:44 romulus kernel: cma: Reserved 16 MiB at 0x8c000000
46Jan 24 07:57:44 romulus kernel: random: get_random_bytes called from start_kernel+0x8c/0x4b0 with crng_init=0
47Jan 24 07:57:44 romulus kernel: Built 1 zonelists, mobility grouping on. Total pages: 60960
48Jan 24 07:57:44 romulus kernel: Kernel command line: console=ttyS4,115200n8 root=/dev/ram rw
49Jan 24 07:57:44 romulus kernel: Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
50Jan 24 07:57:44 romulus kernel: Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
51Jan 24 07:57:44 romulus kernel: Memory: 199216K/245760K available (6144K kernel code, 376K rwdata, 1220K rodata, 1024K init, 143K bss, 13776K reserved, 32768K cma-reserve
52Jan 24 07:57:44 romulus kernel: Virtual kernel memory layout:
53 vector : 0xffff0000 - 0xffff1000 ( 4 kB)
54 fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
55 vmalloc : 0x8f800000 - 0xff800000 (1792 MB)
56 lowmem : 0x80000000 - 0x8f000000 ( 240 MB)
57 .text : 0x(ptrval) - 0x(ptrval) (7136 kB)
58 .init : 0x(ptrval) - 0x(ptrval) (1024 kB)
59 .data : 0x(ptrval) - 0x(ptrval) ( 377 kB)
60 .bss : 0x(ptrval) - 0x(ptrval) ( 144 kB)
61Jan 24 07:57:44 romulus kernel: ftrace: allocating 19574 entries in 58 pages
62Jan 24 07:57:44 romulus kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
63Jan 24 07:57:44 romulus kernel: i2c controller registered, irq 16
64Jan 24 07:57:44 romulus kernel: clocksource: FTTMR010-TIMER2: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 77222644334 ns
65Jan 24 07:57:44 romulus kernel: sched_clock: 32 bits at 24MHz, resolution 40ns, wraps every 86767015915ns
66Jan 24 07:57:44 romulus kernel: Switching to timer-based delay loop, resolution 40ns
67Jan 24 07:57:44 romulus kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 49.50 BogoMIPS (lpj=247500)
68Jan 24 07:57:44 romulus kernel: pid_max: default: 32768 minimum: 301
69Jan 24 07:57:44 romulus kernel: Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
70Jan 24 07:57:44 romulus kernel: Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
71Jan 24 07:57:44 romulus kernel: CPU: Testing write buffer coherency: ok
72Jan 24 07:57:44 romulus kernel: Setting up static identity map for 0x80100000 - 0x80100038
73Jan 24 07:57:44 romulus kernel: devtmpfs: initialized
74Jan 24 07:57:44 romulus kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
75Jan 24 07:57:44 romulus kernel: futex hash table entries: 256 (order: -1, 3072 bytes)
76Jan 24 07:57:44 romulus kernel: pinctrl core: initialized pinctrl subsystem
77Jan 24 07:57:44 romulus kernel: NET: Registered protocol family 16
78Jan 24 07:57:44 romulus kernel: DMA: preallocated 256 KiB pool for atomic coherent allocations
79Jan 24 07:57:44 romulus kernel: hw-breakpoint: debug architecture 0x0 unsupported.
80Jan 24 07:57:44 romulus kernel: usbcore: registered new interface driver usbfs
81Jan 24 07:57:44 romulus kernel: usbcore: registered new interface driver hub
82Jan 24 07:57:44 romulus kernel: usbcore: registered new device driver usb
83Jan 24 07:57:44 romulus kernel: clocksource: Switched to clocksource FTTMR010-TIMER2
84Jan 24 07:57:44 romulus kernel: NET: Registered protocol family 2
85Jan 24 07:57:44 romulus kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes)
86Jan 24 07:57:44 romulus kernel: TCP established hash table entries: 2048 (order: 1, 8192 bytes)
87Jan 24 07:57:44 romulus kernel: TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
88Jan 24 07:57:44 romulus kernel: TCP: Hash tables configured (established 2048 bind 2048)
89Jan 24 07:57:44 romulus kernel: UDP hash table entries: 256 (order: 0, 4096 bytes)
90Jan 24 07:57:44 romulus kernel: UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
91Jan 24 07:57:44 romulus kernel: NET: Registered protocol family 1
92Jan 24 07:57:44 romulus kernel: Unpacking initramfs...
93Jan 24 07:57:44 romulus kernel: Freeing initrd memory: 1648K
94Jan 24 07:57:44 romulus kernel: workingset: timestamp_bits=30 max_order=16 bucket_order=0
95Jan 24 07:57:44 romulus kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
96Jan 24 07:57:44 romulus kernel: jffs2: version 2.2. (SUMMARY) © 2001-2006 Red Hat, Inc.
97Jan 24 07:57:44 romulus kernel: NET: Registered protocol family 38
98Jan 24 07:57:44 romulus kernel: io scheduler noop registered
99Jan 24 07:57:44 romulus kernel: io scheduler cfq registered (default)
100Jan 24 07:57:44 romulus kernel: GPIO line 307 (nic_func_mode0) hogged as output/low
101Jan 24 07:57:44 romulus kernel: GPIO line 308 (nic_func_mode1) hogged as output/low
102Jan 24 07:57:44 romulus kernel: GPIO line 431 (seq_cont) hogged as output/low
103Jan 24 07:57:44 romulus kernel: ipmi-bt-host 1e789140.ibt: Found bt bmc device
104Jan 24 07:57:44 romulus kernel: ipmi-bt-host 1e789140.ibt: Using IRQ 32
105Jan 24 07:57:44 romulus kernel: Serial: 8250/16550 driver, 6 ports, IRQ sharing enabled
106Jan 24 07:57:44 romulus kernel: 1e787000.serial: ttyS5 at MMIO 0x1e787000 (irq = 32, base_baud = 1546875) is a 16550A
107Jan 24 07:57:44 romulus kernel: 1e783000.serial: ttyS0 at MMIO 0x1e783000 (irq = 30, base_baud = 1500000) is a 16550A
108Jan 24 07:57:44 romulus kernel: 1e784000.serial: ttyS4 at MMIO 0x1e784000 (irq = 31, base_baud = 1500000) is a 16550A
109Jan 24 07:57:44 romulus kernel: console [ttyS4] enabled
110Jan 24 07:57:44 romulus kernel: timeriomem_rng 1e6e2078.hwrng: 32bits from 0x(ptrval) @ 1us
111Jan 24 07:57:44 romulus kernel: aspeed_gfx 1e6e6000.display: assigned reserved memory node framebuffer
112Jan 24 07:57:44 romulus kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
113Jan 24 07:57:44 romulus kernel: random: fast init done
114Jan 24 07:57:44 romulus kernel: [drm] No driver support for vblank timestamp query.
115Jan 24 07:57:44 romulus kernel: aspeed_gfx 1e6e6000.display: fb0: DRM emulated frame buffer device
116Jan 24 07:57:44 romulus kernel: [drm] Initialized aspeed-gfx-drm 1.0.0 20180319 for 1e6e6000.display on minor 0
117Jan 24 07:57:44 romulus kernel: loop: module loaded
118Jan 24 07:57:44 romulus kernel: aspeed-lpc-ctrl 1e789080.lpc-ctrl: Loaded at [mem 0x98000000-0x9bffffff flags 0x200]
119Jan 24 07:57:44 romulus kernel: aspeed-smc 1e620000.flash-controller: Using 50 MHz SPI frequency
120Jan 24 07:57:44 romulus kernel: aspeed-smc 1e620000.flash-controller: n25q256a (32768 Kbytes)
121Jan 24 07:57:45 romulus kernel: aspeed-smc 1e620000.flash-controller: CE0 window [ 0x20000000 - 0x22000000 ] 32MB
122Jan 24 07:57:45 romulus kernel: aspeed-smc 1e620000.flash-controller: CE1 window [ 0x22000000 - 0x2a000000 ] 128MB
123Jan 24 07:57:45 romulus kernel: aspeed-smc 1e620000.flash-controller: read control register: 203b0641
124Jan 24 07:57:45 romulus kernel: 5 fixed-partitions partitions found on MTD device bmc
125Jan 24 07:57:45 romulus kernel: Creating 5 MTD partitions on "bmc":
126Jan 24 07:57:45 romulus kernel: 0x000000000000-0x000000060000 : "u-boot"
127Jan 24 07:57:45 romulus kernel: random: crng init done
128Jan 24 07:57:45 romulus kernel: 0x000000060000-0x000000080000 : "u-boot-env"
129Jan 24 07:57:45 romulus kernel: 0x000000080000-0x0000004c0000 : "kernel"
130Jan 24 07:57:45 romulus kernel: 0x0000004c0000-0x000001c00000 : "rofs"
131Jan 24 07:57:45 romulus kernel: 0x000001c00000-0x000002000000 : "rwfs"
132Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: Using 100 MHz SPI frequency
133Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: mx66l1g45g (131072 Kbytes)
134Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: CE0 window resized to 120MB (AST2500 HW quirk)
135Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: CE0 window [ 0x30000000 - 0x37800000 ] 120MB
136Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: CE1 window [ 0x37800000 - 0x38000000 ] 8MB
137Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: CE0 window too small for chip 128MB
138Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: read control register: 203b0045
139Jan 24 07:57:45 romulus kernel: aspeed-smc 1e630000.flash-controller: Calibration area too uniform, using low speed
140Jan 24 07:57:45 romulus kernel: libphy: Fixed MDIO Bus: probed
141Jan 24 07:57:45 romulus kernel: ftgmac100 1e660000.ethernet: Read MAC address aa:e2:84:14:28:79 from chip
142Jan 24 07:57:45 romulus kernel: ftgmac100 1e660000.ethernet: Using NCSI interface
143Jan 24 07:57:45 romulus kernel: ftgmac100 1e660000.ethernet eth0: irq 19, mapped at 462a4ab4
144Jan 24 07:57:45 romulus kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
145Jan 24 07:57:45 romulus kernel: ehci-platform: EHCI generic platform driver
146Jan 24 07:57:45 romulus kernel: i2c /dev entries driver
147Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a080.i2c-bus: i2c bus 1 registered, irq 34
148Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a0c0.i2c-bus: i2c bus 2 registered, irq 35
149Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a100.i2c-bus: i2c bus 3 registered, irq 36
150Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a140.i2c-bus: i2c bus 4 registered, irq 37
151Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a180.i2c-bus: i2c bus 5 registered, irq 38
152Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a1c0.i2c-bus: i2c bus 6 registered, irq 39
153Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a300.i2c-bus: i2c bus 7 registered, irq 40
154Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a340.i2c-bus: i2c bus 8 registered, irq 41
155Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a380.i2c-bus: i2c bus 9 registered, irq 42
156Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a3c0.i2c-bus: i2c bus 10 registered, irq 43
157Jan 24 07:57:45 romulus kernel: rtc-rv8803 11-0032: registered as rtc0
158Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a400.i2c-bus: i2c bus 11 registered, irq 44
159Jan 24 07:57:45 romulus kernel: aspeed-i2c-bus 1e78a440.i2c-bus: i2c bus 12 registered, irq 45
160Jan 24 07:57:45 romulus kernel: Driver for 1-wire Dallas network protocol.
161Jan 24 07:57:45 romulus kernel: w83773g 12-004c: error writing config rate register
162Jan 24 07:57:45 romulus kernel: usbcore: registered new interface driver usbhid
163Jan 24 07:57:45 romulus kernel: usbhid: USB HID core driver
164Jan 24 07:57:46 romulus kernel: fsi-master-acf gpio-fsi: ColdFire initialized, firmware v0 API v0.0 (trace disabled)
165Jan 24 07:57:46 romulus kernel: fsi-master-acf gpio-fsi: Unsupported coprocessor API version !
166Jan 24 07:57:46 romulus kernel: NET: Registered protocol family 10
167Jan 24 07:57:46 romulus kernel: Segment Routing with IPv6
168Jan 24 07:57:46 romulus kernel: sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
169Jan 24 07:57:46 romulus kernel: NET: Registered protocol family 17
170Jan 24 07:57:46 romulus kernel: 8021q: 802.1Q VLAN Support v1.8
171Jan 24 07:57:46 romulus kernel: console [netcon0] enabled
172Jan 24 07:57:46 romulus kernel: netconsole: network logging started
173Jan 24 07:57:46 romulus kernel: input: gpio-keys as /devices/platform/gpio-keys/input/input0
174Jan 24 07:57:46 romulus kernel: rtc-rv8803 11-0032: setting system clock to 2019-01-24 07:57:34 UTC (1548316654)
175Jan 24 07:57:46 romulus kernel: Freeing unused kernel memory: 1024K
176Jan 24 07:57:46 romulus kernel: Checked W+X mappings: passed, no W+X pages found
177Jan 24 07:57:46 romulus kernel: Run /init as init process
178Jan 24 07:57:46 romulus kernel: jffs2: notice: (544) jffs2_build_xattr_subsystem: complete building xattr subsystem, 7 of xdatum (3 unchecked, 4 orphan) and 74 of xref (1
179Jan 24 07:57:46 romulus kernel: overlayfs: upper fs does not support tmpfile.
180Jan 24 07:57:46 romulus systemd[1]: Failed to insert module 'autofs4': No such file or directory
181Jan 24 07:57:46 romulus systemd[1]: systemd 239 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK +SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +X
182UTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
183Jan 24 07:57:46 romulus systemd[1]: Detected architecture arm.
184Jan 24 07:57:46 romulus systemd[1]: Set hostname to <romulus>.
185Jan 24 07:57:46 romulus systemd[1]: File /lib/systemd/system/systemd-journald.service:36 configures an IP firewall (IPAddressDeny=any), but the local system does not supp
186lling.
187Jan 24 07:57:46 romulus systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
188Jan 24 07:57:46 romulus systemd[1]: Created slice system-mapper\x2dwait.slice.
189Jan 24 07:57:46 romulus systemd[1]: Created slice system-phosphor\x2ddiscover\x2dsystem\x2dstate.slice.
190Jan 24 07:57:46 romulus systemd[1]: Created slice system-phosphor\x2dreset\x2dhost\x2drunning.slice.
191Jan 24 07:57:46 romulus systemd[1]: Reached target Remote File Systems.
192Jan 24 07:57:46 romulus systemd[1]: Created slice system-phosphor\x2dgpio\x2dmonitor.slice.
193Jan 24 07:57:46 romulus systemd[1]: Started Remount Root and Kernel File Systems.
194Jan 24 07:57:46 romulus systemd[1]: Mounted Kernel Debug File System.
195Jan 24 07:57:46 romulus systemd[1]: Started Apply Kernel Variables.
196Jan 24 07:57:46 romulus systemd[1]: Starting Create Static Device Nodes in /dev...
197Jan 24 07:57:46 romulus systemd[1]: Started Create Static Device Nodes in /dev.
198Jan 24 07:57:46 romulus systemd[1]: Starting udev Kernel Device Manager...
199Jan 24 07:57:46 romulus systemd[1]: Reached target Local File Systems (Pre).
200Jan 24 07:57:46 romulus systemd[1]: Mounting /var/volatile...
201Jan 24 07:57:46 romulus systemd[1]: Mounted /var/volatile.
202Jan 24 07:57:46 romulus systemd[1]: Starting Load/Save Random Seed...
203Jan 24 07:57:46 romulus systemd-journald[571]: Journal started
204Jan 24 07:57:46 romulus systemd-journald[571]: Runtime journal (/run/log/journal/7bfeb5fa0c7d4f9e849333aef5feb61f) is 8.0M, max 64.0M, 56.0M free.
205Jan 24 07:57:46 romulus systemd-sysctl[566]: Couldn't write '16' to 'kernel/sysrq', ignoring: No such file or directory
206Jan 24 07:57:46 romulus systemd-sysctl[566]: Couldn't write 'fq_codel' to 'net/core/default_qdisc', ignoring: No such file or directory
207Jan 24 07:57:46 romulus systemd[1]: Starting Flush Journal to Persistent Storage...
208Jan 24 07:57:46 romulus systemd-journald[571]: Runtime journal (/run/log/journal/7bfeb5fa0c7d4f9e849333aef5feb61f) is 8.0M, max 64.0M, 56.0M free.
209Jan 24 07:57:46 romulus systemd[1]: Started udev Kernel Device Manager.
210Jan 24 07:57:46 romulus systemd[1]: Started Flush Journal to Persistent Storage.
211Jan 24 07:57:47 romulus systemd[1]: Starting Create Volatile Files and Directories...
212Jan 24 07:57:47 romulus systemd-tmpfiles[698]: "/var/log" already exists and is not a directory.
213Jan 24 07:57:47 romulus systemd-tmpfiles[698]: "/var/tmp" already exists and is not a directory.
214Jan 24 07:57:48 romulus systemd[1]: Started Create Volatile Files and Directories.
215Jan 24 07:57:48 romulus systemd[1]: Starting Network Service...
216Jan 24 07:57:48 romulus systemd[1]: Starting Network Time Synchronization...
217Jan 24 07:57:49 romulus systemd[1]: Started Load/Save Random Seed.
218Jan 24 07:57:50 romulus systemd-networkd[700]: Enumeration completed
219Jan 24 07:57:51 romulus systemd-udevd[775]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
220Jan 24 07:57:53 romulus kernel: 8021q: adding VLAN 0 to HW filter on device eth0
221Jan 24 07:57:53 romulus systemd-networkd[700]: eth0: Gained carrier
222Jan 24 07:57:53 romulus systemd[1]: Started Network Service.
223Jan 24 07:57:53 romulus systemd[1]: Started udev Coldplug all Devices.
224Jan 24 07:57:54 romulus systemd-udevd[783]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
225Jan 24 07:57:54 romulus systemd-udevd[784]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
226Jan 24 07:57:55 romulus systemd-networkd[700]: eth0: Gained IPv6LL
227Jan 24 07:57:55 romulus kernel: ftgmac100 1e660000.ethernet eth0: NCSI: No channel found with link
228Jan 24 07:57:55 romulus systemd[1]: Started Network Time Synchronization.
229Jan 24 07:57:57 romulus systemd[1]: Found device /dev/ttyS4.
230Jan 24 07:57:57 romulus systemd[1]: Found device /dev/ttyVUART0.
231Jan 24 07:57:58 romulus systemd[1]: Found device /dev/aspeed-lpc-ctrl.
232Jan 24 07:57:58 romulus systemd[1]: Found device /dev/aspeed-mbox.
233Jan 24 07:58:00 romulus systemd[1]: Created slice system-xyz.openbmc_project.led.controller.slice.
234Jan 24 07:58:00 romulus systemd[1]: Created slice system-xyz.openbmc_project.Hwmon.slice.
235Jan 24 07:58:00 romulus systemd-networkd[700]: eth0: Configured
236Jan 24 07:58:00 romulus systemd-timesyncd[701]: Network configuration changed, trying to establish connection.
237Jan 24 07:58:00 romulus systemd[1]: Reached target System Time Synchronized.
238Jan 24 07:58:00 romulus systemd[1]: Reached target System Initialization.
239Jan 24 07:58:00 romulus systemd[1]: Started Daily Cleanup of Temporary Directories.
240Jan 24 07:58:00 romulus systemd[1]: Listening on Phosphor Webserver socket.
241Jan 24 07:58:00 romulus systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
242Jan 24 07:58:00 romulus systemd[1]: Listening on D-Bus System Message Bus Socket.
243Jan 24 07:58:00 romulus systemd[1]: Starting D-Bus System Message Bus...
244Jan 24 07:58:01 romulus systemd[1]: Listening on Phosphor Host Console SSH Per-Connection socket.
245Jan 24 07:58:01 romulus systemd[1]: Listening on Phosphor DBUS Browser socket.
246Jan 24 07:58:02 romulus systemd[1]: Listening on dropbear.socket.
247Jan 24 07:58:02 romulus systemd[1]: Listening on Phosphor redfish socket (bmcweb).
248Jan 24 07:58:02 romulus systemd[1]: Started Daily rotation of log files.
249Jan 24 07:58:02 romulus systemd[1]: Reached target Timers.
250Jan 24 07:58:02 romulus systemd[1]: Listening on phosphor-ipmi-net.socket.
251Jan 24 07:58:02 romulus systemd[1]: Reached target Sockets.
252Jan 24 07:58:02 romulus systemd[1]: Starting Network Name Resolution...
253Jan 24 07:58:03 romulus systemd[1]: Created slice system-dropbear.slice.
254Jan 24 07:58:03 romulus systemd[1]: Started D-Bus System Message Bus.
255Jan 24 07:58:03 romulus systemd[1]: Reached target Basic System.
256Jan 24 07:58:03 romulus systemd[1]: Starting Phosphor Dump Manager...
257Jan 24 07:58:03 romulus systemd[1]: Started Hardware RNG Entropy Gatherer Daemon.
258Jan 24 07:58:04 romulus systemd[1]: Started Phosphor Dump core monitor..
259Jan 24 07:58:04 romulus systemd[1]: Started Phosphor sysfs LED controller.
260Jan 24 07:58:04 romulus systemd[1]: Starting SSH Key Generation...
261Jan 24 07:58:04 romulus systemd-timesyncd[701]: request_name_destroy_callback n_ref=1
262Jan 24 07:58:04 romulus systemd-networkd[700]: request_name_destroy_callback n_ref=1
263Jan 24 07:58:04 romulus systemd[1]: Started Phosphor certificate manager for authority.
264Jan 24 07:58:04 romulus systemd[1]: Starting Clear one time boot overrides...
265Jan 24 07:58:05 romulus systemd[1]: Starting Name Service Cache Daemon...
266Jan 24 07:58:05 romulus systemd[1]: Starting Phosphor Log Manager...
267Jan 24 07:58:05 romulus systemd[1]: Starting Phosphor LDAP privilege mapper...
268Jan 24 07:58:05 romulus systemd[1]: Starting Phosphor User Manager...
269Jan 24 07:58:05 romulus systemd[1]: Started Phosphor GPIO id_button monitor.
270Jan 24 07:58:06 romulus systemd[1]: Starting Phosphor IPMI BT DBus Bridge...
271Jan 24 07:58:06 romulus systemd[1]: Starting Rsyslog config updater...
272Jan 24 07:58:06 romulus systemd[1]: Started Phosphor sysfs LED controller.
273Jan 24 07:58:06 romulus nscd[922]: 922 monitoring file `/etc/passwd` (1)
274Jan 24 07:58:06 romulus nscd[922]: 922 monitoring directory `/etc` (2)
275Jan 24 07:58:06 romulus systemd[1]: Starting Phosphor Fan Control...
276Jan 24 07:58:06 romulus nscd[922]: 922 monitoring file `/etc/group` (3)
277Jan 24 07:58:06 romulus systemd-resolved[907]: Positive Trust Anchors:
278Jan 24 07:58:06 romulus nscd[922]: 922 monitoring directory `/etc` (2)
279Jan 24 07:58:06 romulus systemd-resolved[907]: . IN DS 19036 8 2 49aac11d7b6f6446702e54a1607371607a1a41855200fd2ce1cdde32f24e8fb5
280Jan 24 07:58:06 romulus nscd[922]: 922 monitoring file `/etc/hosts` (4)
281Jan 24 07:58:06 romulus systemd-resolved[907]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
282Jan 24 07:58:06 romulus nscd[922]: 922 monitoring directory `/etc` (2)
283Jan 24 07:58:06 romulus systemd-resolved[907]: Negative trust anchors: 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.
284addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa
285in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test
286Jan 24 07:58:06 romulus nscd[922]: 922 disabled inotify-based monitoring for file `/etc/resolv.conf': No such file or directory
287Jan 24 07:58:06 romulus systemd[1]: Starting Phosphor Download Manager...
288Jan 24 07:58:06 romulus nscd[922]: 922 stat failed for file `/etc/resolv.conf'; will try again later: No such file or directory
289Jan 24 07:58:06 romulus systemd-resolved[907]: Using system hostname 'romulus'.
290Jan 24 07:58:06 romulus nscd[922]: 922 monitoring file `/etc/services` (5)
291Jan 24 07:58:07 romulus systemd[1]: Started Phosphor certificate manager for nslcd.
292Jan 24 07:58:06 romulus nscd[922]: 922 monitoring directory `/etc` (2)
293Jan 24 07:58:07 romulus nscd[922]: 922 cannot write to database file /var/db/nscd/passwd: Invalid argument
294Jan 24 07:58:07 romulus systemd[1]: Starting Phosphor Inventory Manager...
295Jan 24 07:58:07 romulus nscd[922]: 922 cannot write to database file /var/db/nscd/group: Invalid argument
296Jan 24 07:58:07 romulus systemd[1]: Starting Phosphor Power Button0...
297Jan 24 07:58:07 romulus nscd[922]: 922 cannot write to database file /var/db/nscd/hosts: Invalid argument
298Jan 24 07:58:07 romulus systemd[1]: Starting Phosphor Reset Button0...
299Jan 24 07:58:07 romulus systemd[1]: Starting Phosphor LED Group Management Daemon...
300Jan 24 07:58:07 romulus systemd[1]: Starting Phosphor Download Manager...
301Jan 24 07:58:07 romulus nscd[922]: 922 cannot write to database file /var/db/nscd/services: Invalid argument
302Jan 24 07:58:08 romulus systemd-resolved[907]: request_name_destroy_callback n_ref=1
303Jan 24 07:58:08 romulus systemd[1]: Starting Phosphor Power0 Control...
304Jan 24 07:58:08 romulus systemd[1]: Starting Phosphor Chassis0 Control...
305Jan 24 07:58:08 romulus systemd[1]: Started Phosphor sysfs LED controller.
306Jan 24 07:58:08 romulus systemd[1]: Starting Phosphor Settings Daemon...
307Jan 24 07:58:08 romulus systemd[1]: Starting Hostboot Boot Settings...
308Jan 24 07:58:09 romulus systemd[1]: Started Phosphor certificate manager for nginx.
309Jan 24 07:58:09 romulus systemd[1]: Started Phosphor Console Muxer listening on device /dev/ttyVUART0.
310Jan 24 07:58:09 romulus hb_settings[946]: Hostboot serial output on VUART
311Jan 24 07:58:09 romulus systemd[1]: Starting Phosphor DBus Service Discovery Manager...
312Jan 24 07:58:11 romulus systemd[1]: Started Network Name Resolution.
313Jan 24 07:58:12 romulus systemd[1]: Started SSH Key Generation.
314Jan 24 07:58:13 romulus systemd[1]: Started Name Service Cache Daemon.
315Jan 24 07:58:13 romulus systemd[1]: Started Hostboot Boot Settings.
316Jan 24 07:58:14 romulus button_reset.exe[930]: GPIO Lookup: RESET_BUTTON = 353,both
317Jan 24 07:58:14 romulus power_control.exe[942]: Power GPIO power good input: SYS_PWROK_BUFF
318Jan 24 07:58:14 romulus power_control.exe[942]: Power GPIO 2 power_up outputs
319Jan 24 07:58:14 romulus power_control.exe[942]: Power GPIO power_up[0] = SOFTWARE_PGOOD active HIGH
320Jan 24 07:58:14 romulus power_control.exe[942]: Power GPIO power_up[1] = BMC_POWER_UP active HIGH
321Jan 24 07:58:14 romulus power_control.exe[942]: Power GPIO 0 reset outputs
322Jan 24 07:58:14 romulus power_control.exe[942]: Power GPIO 0 pci reset outputs
323Jan 24 07:58:14 romulus power_control.exe[942]: GPIO Lookup: SYS_PWROK_BUFF = 306,in
324Jan 24 07:58:14 romulus systemd[1]: Started Clear one time boot overrides.
325Jan 24 07:58:15 romulus button_power.exe[929]: GPIO Lookup: POWER_BUTTON = 347,both
326Jan 24 07:58:15 romulus power_control.exe[942]: GPIO Lookup: SOFTWARE_PGOOD = 417,out
327Jan 24 07:58:15 romulus power_control.exe[942]: GPIO Lookup: BMC_POWER_UP = 305,out
328Jan 24 07:58:15 romulus power_control.exe[942]: Pgood state: 0
329Jan 24 07:58:15 romulus systemd[1]: Started SSH Per-Connection Server (192.168.122.1:45850).
330Jan 24 07:58:16 romulus systemd[1]: Reached target Host and Network Name Lookups.
331Jan 24 07:58:16 romulus systemd[1]: Reached target Network.
332Jan 24 07:58:16 romulus systemd[1]: Starting The NGINX HTTP and reverse proxy server...
333Jan 24 07:58:16 romulus systemd[1]: Starting LDAP daemon...
334Jan 24 07:58:17 romulus systemd[1]: Starting Permit User Sessions...
335Jan 24 07:58:17 romulus systemd[1]: Started Lightweight SLP Server.
336Jan 24 07:58:17 romulus systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
337Jan 24 07:58:18 romulus dropbear[968]: Child connection from ::ffff:192.168.122.1:45850
338Jan 24 07:58:19 romulus systemd[1]: Started Permit User Sessions.
339Jan 24 07:58:19 romulus systemd[1]: Started Phosphor User Manager.
340Jan 24 07:58:20 romulus systemd[1]: Started Phosphor Log Manager.
341Jan 24 07:58:20 romulus systemd[1]: Starting IBM Log Manager...
342Jan 24 07:58:20 romulus avahi-daemon[973]: Found user 'avahi' (UID 994) and group 'avahi' (GID 992).
343Jan 24 07:58:20 romulus avahi-daemon[973]: Successfully dropped root privileges.
344Jan 24 07:58:20 romulus systemd[1]: Started Serial Getty on ttyS4.
345Jan 24 07:58:20 romulus systemd[1]: Reached target Login Prompts.
346Jan 24 07:58:20 romulus avahi-daemon[973]: avahi-daemon 0.7 starting up.
347Jan 24 07:58:21 romulus avahi-daemon[973]: Successfully called chroot().
348Jan 24 07:58:21 romulus avahi-daemon[973]: Successfully dropped remaining capabilities.
349Jan 24 07:58:21 romulus avahi-daemon[973]: Loading service file /services/obmc_console.service.
350Jan 24 07:58:21 romulus systemd[1]: Started Avahi mDNS/DNS-SD Stack.
351Jan 24 07:58:21 romulus avahi-daemon[973]: Loading service file /services/phosphor_rest.service.
352Jan 24 07:58:21 romulus avahi-daemon[973]: Loading service file /services/sftp-ssh.service.
353Jan 24 07:58:21 romulus avahi-daemon[973]: Loading service file /services/ssh.service.
354Jan 24 07:58:21 romulus avahi-daemon[973]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::a8e2:84ff:fe14:2879.
355Jan 24 07:58:21 romulus avahi-daemon[973]: New relevant interface eth0.IPv6 for mDNS.
356Jan 24 07:58:21 romulus avahi-daemon[973]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.122.2.
357Jan 24 07:58:21 romulus avahi-daemon[973]: New relevant interface eth0.IPv4 for mDNS.
358Jan 24 07:58:21 romulus avahi-daemon[973]: Network interface enumeration completed.
359Jan 24 07:58:21 romulus avahi-daemon[973]: Registering new address record for fe80::a8e2:84ff:fe14:2879 on eth0.*.
360Jan 24 07:58:21 romulus avahi-daemon[973]: Registering new address record for 192.168.122.2 on eth0.IPv4.
361Jan 24 07:58:22 romulus nslcd[980]: version 0.9.8 starting
362Jan 24 07:58:22 romulus nslcd[980]: accepting connections
363Jan 24 07:58:22 romulus systemd[1]: Started IBM Log Manager.
364Jan 24 07:58:22 romulus systemd[1]: Started LDAP daemon.
365Jan 24 07:58:22 romulus avahi-daemon[973]: Server startup complete. Host name is romulus.local. Local service cookie is 38338744.
366Jan 24 07:58:22 romulus systemd[1]: Starting Phosphor Ldap config updater...
367Jan 24 07:58:23 romulus systemd[1]: Reached target Multi-User System.
368Jan 24 07:58:23 romulus dropbear[968]: PAM password auth succeeded for 'root' from ::ffff:192.168.122.1:45850
369Jan 24 07:58:23 romulus dropbear[987]: lastlog_perform_login: Couldn't stat /var/log/lastlog: No such file or directory
370Jan 24 07:58:23 romulus avahi-daemon[973]: Service "romulus" (/services/ssh.service) successfully established.
371Jan 24 07:58:24 romulus dropbear[987]: lastlog_openseek: /var/log/lastlog is not a file or directory!
372Jan 24 07:58:24 romulus avahi-daemon[973]: Service "romulus" (/services/sftp-ssh.service) successfully established.
373Jan 24 07:58:24 romulus avahi-daemon[973]: Service "phosphor_rest" (/services/phosphor_rest.service) successfully established.
374Jan 24 07:58:24 romulus avahi-daemon[973]: Service "obmc_console" (/services/obmc_console.service) successfully established.
375Jan 24 07:58:25 romulus nscd[922]: 922 monitored file `/etc/resolv.conf` changed (mtime)
376Jan 24 07:58:25 romulus nscd[922]: 922 monitoring file `/etc/resolv.conf` (6)
377Jan 24 07:58:25 romulus nscd[922]: 922 monitoring directory `/etc` (2)
378Jan 24 07:58:25 romulus phosphor-ldap-conf[986]: LDAP config parameter value missing
379Jan 24 07:58:26 romulus systemd[1]: Started Phosphor Ldap config updater.
380Jan 24 07:58:28 romulus nginx[975]: nginx: [warn] the "ssl" directive is deprecated, use the "listen ... ssl" directive instead in /etc/nginx/nginx.conf:61
381Jan 24 07:58:28 romulus nginx[975]: nginx: the configuration file /etc/nginx/nginx.conf syntax is ok
382Jan 24 07:58:28 romulus nginx[975]: nginx: configuration file /etc/nginx/nginx.conf test is successful
383Jan 24 07:58:29 romulus nginx[991]: nginx: [warn] the "ssl" directive is deprecated, use the "listen ... ssl" directive instead in /etc/nginx/nginx.conf:61
384Jan 24 07:58:29 romulus systemd[1]: Started The NGINX HTTP and reverse proxy server.
385Jan 24 07:58:30 romulus systemd-timesyncd[701]: Synchronized to time server 216.239.35.8:123 (time3.google.com).
386Jan 24 07:58:33 romulus systemd[1]: syslog.socket: Trigger limit hit, refusing further activation.
387Jan 24 07:58:33 romulus systemd[1]: syslog.socket: Failed with result 'trigger-limit-hit'.
388Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Download Manager.
389Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Reset Button0.
390Jan 24 07:58:33 romulus systemd[1]: Started Phosphor DBus Service Discovery Manager.
391Jan 24 07:58:33 romulus systemd[1]: Started Phosphor IPMI BT DBus Bridge.
392Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Dump Manager.
393Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Power0 Control.
394Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Fan Control.
395Jan 24 07:58:33 romulus systemd[1]: Started Phosphor LDAP privilege mapper.
396Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Power Button0.
397Jan 24 07:58:33 romulus systemd[1]: Started Phosphor LED Group Management Daemon.
398Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Inventory Manager.
399Jan 24 07:58:33 romulus systemd[1]: Started Rsyslog config updater.
400Jan 24 07:58:33 romulus systemd[1]: Started Phosphor Settings Daemon.
401Jan 24 07:58:34 romulus systemd[1]: Reached target Fan Control.
402Jan 24 07:58:34 romulus systemd[1]: Started Phosphor Hwmon Poller.
403Jan 24 07:58:34 romulus systemd[1]: Started Phosphor Hwmon Poller.
404Jan 24 07:58:34 romulus systemd[1]: Reached target Phosphor Object Mapper.
405Jan 24 07:58:34 romulus systemd[1]: Starting Phosphor SNMP conf Manager...
406Jan 24 07:58:34 romulus systemd[1]: Starting Phosphor Chassis State Manager...
407Jan 24 07:58:35 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/power_restore_policy...
408Jan 24 07:58:35 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/auto_reboot...
409Jan 24 07:58:35 romulus systemd[1]: Starting Phosphor BMC State Manager...
410Jan 24 07:58:35 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/time/sync_method...
411Jan 24 07:58:35 romulus systemd[1]: Starting Phosphor Network Manager...
412Jan 24 07:58:36 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/led/groups...
413Jan 24 07:58:36 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/inventory...
414Jan 24 07:58:36 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/boot...
415Jan 24 07:58:37 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0...
416Jan 24 07:58:37 romulus systemd[1]: Starting Wait for /org/openbmc/control/power0...
417Jan 24 07:58:37 romulus systemd[1]: Started Phosphor DBus Monitor.
418Jan 24 07:58:38 romulus systemd[1]: Starting Wait for /org/openbmc/managers/Download...
419Jan 24 07:58:38 romulus chassis_control.py[943]: Running ChassisControlService
420Jan 24 07:58:38 romulus systemd[1]: Starting Wait for /org/openbmc/control/flash/bios...
421Jan 24 07:58:38 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/time/owner...
422Jan 24 07:58:38 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/restriction_mode...
423Jan 24 07:58:38 romulus systemd[1]: Starting OpenBMC Software Update Manager...
424Jan 24 07:58:38 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/state/chassis0...
425Jan 24 07:58:39 romulus nscd[922]: 922 monitored file `/etc/resolv.conf` was written to
426Jan 24 07:58:39 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/state/host0...
427Jan 24 07:58:39 romulus systemd[1]: Reached target Webserver (pre).
428Jan 24 07:58:39 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/led/groups/bmc_booted...
429Jan 24 07:58:39 romulus systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/boot/one_time...
430Jan 24 07:58:40 romulus download_manager.py[932]: Running Download Manager
431Jan 24 07:58:40 romulus phosphor-mapper[949]: Found invalid association on path /xyz/openbmc_project/software/32ea97a5
432Jan 24 07:58:40 romulus systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/power_restore_policy.
433Jan 24 07:58:40 romulus systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/auto_reboot.
434Jan 24 07:58:41 romulus systemd[1]: Started Wait for /xyz/openbmc_project/time/sync_method.
435Jan 24 07:58:41 romulus systemd[1]: Started Wait for /xyz/openbmc_project/led/groups.
436Jan 24 07:58:41 romulus systemd[1]: Started Wait for /xyz/openbmc_project/inventory.
437Jan 24 07:58:41 romulus systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/boot.
438Jan 24 07:58:41 romulus systemd[1]: Started Wait for /xyz/openbmc_project/control/host0.
439Jan 24 07:58:41 romulus systemd[1]: Started Wait for /org/openbmc/control/power0.
440Jan 24 07:58:41 romulus systemd[1]: Started Wait for /org/openbmc/managers/Download.
441Jan 24 07:58:41 romulus systemd[1]: Started Wait for /xyz/openbmc_project/time/owner.
442Jan 24 07:58:41 romulus systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/restriction_mode.
443Jan 24 07:58:42 romulus systemd[1]: Started Wait for /xyz/openbmc_project/led/groups/bmc_booted.
444Jan 24 07:58:42 romulus systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/boot/one_time.
445Jan 24 07:58:42 romulus systemd[1]: Started Phosphor SNMP conf Manager.
446Jan 24 07:58:42 romulus phosphor-chassis-state-manager[997]: Initial Chassis State will be Off
447Jan 24 07:58:42 romulus systemd[1]: Started Phosphor Chassis0 Control.
448Jan 24 07:58:42 romulus systemd[1]: Started Phosphor Network Manager.
449Jan 24 07:58:42 romulus systemd[1]: Started Phosphor Download Manager.
450Jan 24 07:58:43 romulus systemd[1]: Started Wait for /xyz/openbmc_project/state/chassis0.
451Jan 24 07:58:43 romulus phosphor-mapper[949]: Found invalid association on path /xyz/openbmc_project/software/32ea97a5
452Jan 24 07:58:43 romulus systemd[1]: Started Phosphor Chassis State Manager.
453Jan 24 07:58:43 romulus systemd[1]: Started OpenBMC Software Update Manager.
454Jan 24 07:58:43 romulus phosphor-bmc-state-manager[1000]: Setting the BMCState field
455Jan 24 07:58:43 romulus phosphor-bmc-state-manager[1000]: Setting the BMCState field
456Jan 24 07:58:43 romulus systemd[1]: Starting Phosphor Version Software Manager...
457Jan 24 07:58:44 romulus systemd[1]: Stopping Network Service...
458Jan 24 07:58:44 romulus systemd[1]: Created slice system-obmc\x2dflash\x2dbmc\x2dsetenv.slice.
459Jan 24 07:58:44 romulus systemd[1]: Starting Set U-Boot environment variable...
460Jan 24 07:58:44 romulus nscd[922]: 922 ignored inotify event for `/etc/resolv.conf` (file exists)
461Jan 24 07:58:44 romulus nscd[922]: 922 ignored inotify event for `/etc/resolv.conf` (file exists)
462Jan 24 07:58:44 romulus systemd[1]: Starting Phosphor BMC Code Update...
463Jan 24 07:58:44 romulus systemd[1]: Starting Phosphor BIOS Code Update...
464Jan 24 07:58:44 romulus systemd[1]: Starting Check Chassis0 pgood and create a file to indicate it...
465Jan 24 07:58:44 romulus systemd[1]: Starting OpenPOWER OCC controller...
466Jan 24 07:58:44 romulus systemd[1]: Started Phosphor Cooling Type.
467Jan 24 07:58:45 romulus systemd[1]: Started FRU Fault monitor service.
468Jan 24 07:58:45 romulus systemd[1]: Started Phosphor Inband IPMI.
469Jan 24 07:58:45 romulus systemd[1]: Started Phosphor MBOX Daemon.
470Jan 24 07:58:47 romulus systemd[1]: Stopped Network Service.
471Jan 24 07:58:48 romulus systemd[1]: Started Check Chassis0 pgood and create a file to indicate it.
472Jan 24 07:58:48 romulus systemd[1]: Started Wait for /org/openbmc/control/flash/bios.
473Jan 24 07:58:48 romulus phosphor-network-manager[1002]: Refreshing the objects.
474Jan 24 07:58:48 romulus systemd[1]: Started Set U-Boot environment variable.
475Jan 24 07:58:49 romulus systemd[1]: Started Phosphor BMC State Manager.
476Jan 24 07:58:49 romulus systemd[1]: Started Phosphor BIOS Code Update.
477Jan 24 07:58:49 romulus systemd[1]: Started OpenPOWER OCC controller.
478Jan 24 07:58:49 romulus systemd[1]: Started Phosphor Version Software Manager.
479Jan 24 07:58:49 romulus ipmid[1048]: JSON file not found
480Jan 24 07:58:49 romulus systemd[1]: Started Initialize BIOS Flash.
481Jan 24 07:58:49 romulus systemd[1]: Reached target Chassis0 power on after reset.
482Jan 24 07:58:49 romulus systemd[1]: Reached target Host0 running after reset.
483Jan 24 07:58:49 romulus systemd[1]: Starting Phosphor Host State Manager...
484Jan 24 07:58:49 romulus systemd[1]: Reached target Chassis0 (Reset Check).
485Jan 24 07:58:49 romulus systemd[1]: Starting Network Service...
486Jan 24 07:58:51 romulus phosphor-host-state-manager[1061]: Initial Host State will be Off
487Jan 24 07:58:52 romulus systemd[1]: Started Phosphor REST Server.
488Jan 24 07:58:52 romulus systemd-networkd[1062]: eth0: Gained IPv6LL
489Jan 24 07:58:52 romulus systemd[1]: Starting Hostname Service...
490Jan 24 07:58:52 romulus systemd-networkd[1062]: Enumeration completed
491Jan 24 07:58:52 romulus systemd[1070]: systemd-hostnamed.service: PrivateNetwork=yes is configured, but the kernel does not support network namespaces, ignoring.
492Jan 24 07:58:52 romulus systemd[1]: Started Network Service.
493Jan 24 07:58:52 romulus systemd[1]: Started Wait for /xyz/openbmc_project/state/host0.
494Jan 24 07:58:52 romulus systemd-networkd[1062]: request_name_destroy_callback n_ref=1
495Jan 24 07:58:52 romulus avahi-daemon[973]: Withdrawing address record for 192.168.122.2 on eth0.
496Jan 24 07:58:52 romulus avahi-daemon[973]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.122.2.
497Jan 24 07:58:52 romulus systemd[1]: Started Phosphor Host State Manager.
498Jan 24 07:58:52 romulus avahi-daemon[973]: Interface eth0.IPv4 no longer relevant for mDNS.
499Jan 24 07:58:52 romulus avahi-daemon[973]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.122.2.
500Jan 24 07:58:52 romulus avahi-daemon[973]: New relevant interface eth0.IPv4 for mDNS.
501Jan 24 07:58:52 romulus avahi-daemon[973]: Registering new address record for 192.168.122.2 on eth0.IPv4.
502Jan 24 07:58:52 romulus systemd[1]: Started UART render controller.
503Jan 24 07:58:53 romulus systemd[1]: Starting Reboot If Enabled...
504Jan 24 07:58:53 romulus systemd[1]: Starting Reset host sensors...
505Jan 24 07:58:53 romulus systemd[1]: Starting Phosphor Time Manager daemon...
506Jan 24 07:58:53 romulus flash_bios.exe[1035]: Starting flasher: bios,,/org/openbmc/control/flash/bios,
507Jan 24 07:58:53 romulus flash_bios.exe[1035]: flasher: , BMC = 1, address = 0x0
508Jan 24 07:58:53 romulus flash_bios.exe[1035]: Setting up flash
509Jan 24 07:58:53 romulus phosphor-mapper[949]: Introspect call failed with error: generic:110, Connection timed out on process: org.openbmc.control.Flasher path: /
510Jan 24 07:58:54 romulus phosphor-discover-system-state[1073]: Host power is off, checking power policy
511Jan 24 07:58:54 romulus systemd[1]: Started Reboot If Enabled.
512Jan 24 07:58:54 romulus flash_bios.exe[1035]: Starting flasher: bios,,/org/openbmc/control/flash/bios,
513Jan 24 07:58:54 romulus flash_bios.exe[1035]: flasher: , BMC = 1, address = 0x0
514Jan 24 07:58:54 romulus flash_bios.exe[1035]: Setting up flash
515Jan 24 07:58:56 romulus systemd[1]: Starting Time & Date Service...
516Jan 24 07:58:56 romulus systemd[1]: Started Hostname Service.
517Jan 24 07:58:56 romulus phosphor-network-manager[1002]: Refreshing complete.
518Jan 24 07:58:57 romulus systemd[1]: Started Reset host sensors.
519Jan 24 07:58:57 romulus systemd[1]: Reached target Host0 (Reset Check).
520Jan 24 07:58:58 romulus systemd-networkd[1062]: eth0: Configured
521Jan 24 07:58:58 romulus systemd-timedated[1083]: Failed to get target of /etc/localtime: No such file or directory
522Jan 24 07:58:58 romulus systemd[1]: Started Time & Date Service.
523Jan 24 07:58:59 romulus systemd-timedated[1083]: Set NTP to enabled
524Jan 24 07:59:00 romulus phosphor-timemanager[1075]: Updated NTP setting
525Jan 24 07:59:00 romulus systemd[1]: Started Phosphor Time Manager daemon.
526Jan 24 07:59:01 romulus systemd[1]: Started Phosphor BMC Code Update.
527Jan 24 07:59:01 romulus bmc_update.py[1034]: Running Bmc Flash Control
528Jan 24 07:59:01 romulus systemd[1]: Reached target Phosphor System - Standby.
529Jan 24 07:59:01 romulus phosphor-bmc-state-manager[1000]: BMC_READY
530Jan 24 07:59:01 romulus phosphor-bmc-state-manager[1000]: Setting the BMCState field
531Jan 24 07:59:01 romulus systemd[1]: Starting Assert bmc_booted LED...
532Jan 24 07:59:01 romulus systemd[1]: Started Assert bmc_booted LED.
533Jan 24 07:59:02 romulus systemd[1]: Startup finished in 7.319s (kernel) + 1min 23.644s (userspace) = 1min 30.963s.
534Jan 24 07:59:06 romulus phosphor-network-manager[1002]: Refreshing the objects.
535Jan 24 07:59:06 romulus phosphor-network-manager[1002]: Refreshing complete.
536Jan 24 07:59:27 romulus dropbear[968]: Exit (root): Exited normally
537Jan 24 08:01:37 romulus login[977]: pam_lastlog(login:session): file /var/log/lastlog created
538Jan 24 08:01:37 romulus login[977]: pam_unix(login:session): session opened for user root by LOGIN(uid=0)
539Jan 24 08:01:37 romulus login[1107]: ROOT LOGIN on '/dev/ttyS4'
540Jan 24 08:01:57 romulus phosphor-network-manager[1002]: Refreshing the objects.
541Jan 24 08:01:58 romulus systemd[1]: Starting Hostname Service...
542Jan 24 08:01:58 romulus systemd[1115]: systemd-hostnamed.service: PrivateNetwork=yes is configured, but the kernel does not support network namespaces, ignoring.
543Jan 24 08:01:58 romulus systemd[1]: Started Hostname Service.
544Jan 24 08:01:59 romulus phosphor-network-manager[1002]: Refreshing complete.
545Jan 24 08:02:44 romulus systemd[1]: Started SSH Per-Connection Server (192.168.122.1:46068).
546Jan 24 08:02:44 romulus dropbear[1118]: Child connection from ::ffff:192.168.122.1:46068
547Jan 24 08:02:45 romulus dropbear[1118]: PAM password auth succeeded for 'root' from ::ffff:192.168.122.1:46068
548Jan 24 08:02:46 romulus systemd[1]: Started SSH Per-Connection Server (192.168.122.1:46072).
549Jan 24 08:02:47 romulus dropbear[1122]: Child connection from ::ffff:192.168.122.1:46072
550Jan 24 08:02:47 romulus dropbear[1122]: PAM password auth succeeded for 'root' from ::ffff:192.168.122.1:46072
551Jan 24 08:02:48 romulus avahi-daemon[973]: Interface eth0.IPv6 no longer relevant for mDNS.
552Jan 24 08:02:48 romulus avahi-daemon[973]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::a8e2:84ff:fe14:2879.
553Jan 24 08:02:48 romulus avahi-daemon[973]: Interface eth0.IPv4 no longer relevant for mDNS.
554Jan 24 08:02:48 romulus avahi-daemon[973]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.122.2.
555Jan 24 08:02:48 romulus avahi-daemon[973]: Withdrawing address record for fe80::a8e2:84ff:fe14:2879 on eth0.
556Jan 24 08:02:48 romulus avahi-daemon[973]: Withdrawing address record for 192.168.122.2 on eth0.
557Jan 24 08:02:48 romulus systemd-networkd[1062]: eth0: Lost carrier
558Jan 24 08:02:48 romulus systemd-timesyncd[701]: No network connectivity, watching for changes.
559Jan 24 08:02:48 romulus kernel: ftgmac100 1e660000.ethernet eth0: NCSI: No channel found with link
560Jan 24 08:02:48 romulus phosphor-network-manager[1002]: ip: SIOCSIFFLAGS: No such device
561Jan 24 08:02:58 romulus phosphor-network-manager[1002]: Refreshing the objects.
562Jan 24 08:02:59 romulus systemd[1]: Starting Hostname Service...
563Jan 24 08:02:59 romulus systemd[1133]: systemd-hostnamed.service: PrivateNetwork=yes is configured, but the kernel does not support network namespaces, ignoring.
564Jan 24 08:02:59 romulus systemd[1]: Started Hostname Service.
565Jan 24 08:02:59 romulus phosphor-network-manager[1002]: sdeventplus: timeCallback: basic_string::_M_construct null not valid
566root@romulus:~# logout