* RE: help: OpenBMC host power on from power button press
2018-10-29 19:04 ` Matt Spinler
@ 2018-11-09 8:20 ` A, Maheswari
0 siblings, 0 replies; 5+ messages in thread
From: A, Maheswari @ 2018-11-09 8:20 UTC (permalink / raw)
To: Matt Spinler; +Cc: OpenBMC Maillist
[-- Attachment #1.1: Type: text/plain, Size: 6924 bytes --]
Hi Matt,
I was not able to work on OpenBMC project last 1 week due to some hardware unavailability
Sorry for the delay in response.
Able to find button power related services in standby target.
/etc/systemd/system/obmc-standby.target.wants/org.openbmc.buttons.Power@0.service<mailto:/etc/systemd/system/obmc-standby.target.wants/org.openbmc.buttons.Power@0.service>
hwmi:~# journalctl --no-pager | grep button
Oct 22 14:10:14 bullhwmi systemd[1]: Started Phosphor GPIO power_button monitor.
Oct 22 14:10:18 bullhwmi systemd[1]: Started Phosphor GPIO id_button monitor.
hwmi:~# journalctl --no-pager | grep power
Oct 22 14:10:14 bullhwmi systemd[1]: Started Phosphor GPIO power_button monitor.
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/power_restore_policy...
Oct 22 14:10:46 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/power_restore_policy.
Oct 22 14:11:01 bullhwmi phosphor-discover-system-state[1088]: Host power is off, checking power policy.
OBMC_POWER_BUTTON_INSTANCES is defined in
./meta-bull/conf/distro/include/bull-defaults.inc and phosphor-defaults.inc.
hwmi:~# button_power.exe
GPIO Lookup: POWER_BUTTON = 34,both
ERROR PowerButton: GPIO setup (rc=16) . (I hope this error in coming from button_power_obj.c.)
Trying to put debug message in “obmc-button-power/1.0-r1/git/pwrbutton/button_power_obj.c” which will be in build directory (git).
// get gpio device paths
int rc = GPIO_OK;
do {
rc = gpio_init(&gpio_button);
gpio_inits_done();
if(rc != GPIO_OK) { break; }
rc = gpio_open_interrupt(&gpio_button,on_button_interrupt,object);
if(rc != GPIO_OK) { break; }
} while(0);
if(rc != GPIO_OK)
{
printf("ERROR PowerButton: GPIO setup (rc=%d)\n",rc);
exit(-1);
}
After building my workspace all /debug message are gone.
For power button press – host-poweron.sh GPIO pulse script should run.
For power button long press – host-poweroff.sh GPIO pulse script should run.
How do integrate script into button-power.exe. ?
Please give your advice on how do create debug version of obmc image to check by debug log.
Please help to proceed further on ERROR PowerButton: GPIO setup (rc=16).
Attached journal log for reference.
Thanks
Maheswari A
From: Matt Spinler <mspinler@linux.vnet.ibm.com>
Sent: Tuesday, October 30, 2018 12:35 AM
To: A, Maheswari <maheswari.a@atos.net>
Cc: OpenBMC Maillist <openbmc@lists.ozlabs.org>
Subject: Re: help: OpenBMC host power on from power button press
On 10/29/2018 7:05 AM, A, Maheswari wrote:
Hi Matt,
Thanks for your response.
I am able to power on the Host by monitoring GPIO using “phosphor-gpio-monitor” service.
But still we need to achieve same result by org.openbmc.buttons.Power@0.service<mailto:org.openbmc.buttons.Power@0.service> in future.
Here is the response :
# systemctl status org.openbmc.buttons.Power@0.service<mailto:org.openbmc.buttons.Power@0.service>
● org.openbmc.buttons.Power@0.service<mailto:org.openbmc.buttons.Power@0.service> - Phosphor Power Button0
Loaded: loaded (/lib/systemd/system/org.openbmc.buttons.Power@.service<mailto:/lib/systemd/system/org.openbmc.buttons.Power@.service>; indirect; vendor preset: enabled)
Active: inactive (dead)
hwmi~# ps | grep button_power.exe
1205 root 3100 S grep button_power.exe
hwmi:~#
button_power.exe is not running in BMC. Service status with instance 0 is not showing any error. But button_power.exe is not running. So that I tried with instance 1.
Can you check if this link exists?
/etc/systemd/system/obmc-standby.target.wants/org.openbmc.buttons.Power@0.service<mailto:/etc/systemd/system/obmc-standby.target.wants/org.openbmc.buttons.Power@0.service>
That will start the service on the way to standby. If it does exist, then it should be running, so maybe check
the journal for errors, or try running button_power.exe from the command line and see if it crashes.
(can also check that the standby target was reached with systemctl status obmc-standby.target)
If it doesn't exist, then you probably don't have OBMC_POWER_BUTTON_INSTANCES defined, though
that should be in phosphor-defaults.inc.
I think below file is responsible for button power controlling in OBMC.
c/skeleton/blob/master/pwrbutton/button_power_obj.c
Here is the receipe file.
Cat ./meta-phosphor/recipes-phosphor/chassis/obmc-button-power.bb
SUMMARY = "OpenBMC org.openbmc.Button example implementation"
DESCRIPTION = "A sample implementation for a button controlling a power domain."
PR = "r1"
inherit skeleton-gdbus
inherit obmc-phosphor-dbus-service
inherit pkgconfig
SKELETON_DIR = "pwrbutton"
FMT = "org.openbmc.buttons.Power@{0}.service"<mailto:org.openbmc.buttons.Power@%7b0%7d.service>
DBUS_SERVICE_${PN} += "${@compose_list(d, 'FMT', 'OBMC_POWER_BUTTON_INSTANCES')}"
If you need journal log, then I can share it. I need to understand the flow.
Please help to achieve.
Thanks
Maheswari A
From: Matt Spinler <mspinler@linux.vnet.ibm.com><mailto:mspinler@linux.vnet.ibm.com>
Sent: Saturday, October 27, 2018 1:45 AM
To: A, Maheswari <maheswari.a@atos.net><mailto:maheswari.a@atos.net>
Cc: OpenBMC Maillist <openbmc@lists.ozlabs.org><mailto:openbmc@lists.ozlabs.org>
Subject: Re: help: OpenBMC host power on from power button press
On 10/25/2018 11:52 PM, A, Maheswari wrote:
Hi,
I am new to openBMC project.
We are in the process of porting power button gpio interrupt handling in openBMC.
After some a lot of analysis, I found that obmc-button-power related service are necessary to power on the host using power button press event.
I have added POWER_BUTTON and machine specific GPIO number and polarity in gpio_defs.json.
While starting the “org.openbmc.button.Power” service, I am getting the below error.
org.openbmc.buttons.Power@1.service<mailto:org.openbmc.buttons.Power@1.service> - Phosphor Power Button1
Loaded: [[0;1;31merror[[0m (Reason: File exists)
Active: inactive (dead)
Oct 22 14:14:13 hwmi systemd[1]: [[0;1;31m[[0;1;39m[[0;1;31morg.openbmc.buttons.Power@1.service<mailto:0;1;31morg.openbmc.buttons.Power@1.service>: Two services allocated for the same bus name org.open
bmc.butto.Power, refusing operation.[[0m
Please help to figure out and proceed.
Hi Maheswari,
I'm guessing you already have an org.openbmc.buttons.Power@0.service<mailto:org.openbmc.buttons.Power@0.service> running.
You can check with:
# systemctl status org.openbmc.buttons.Power@0.service<mailto:org.openbmc.buttons.Power@0.service>
or just check for the button_power.exe process already running.
Thanks
Maheswari A
[-- Attachment #1.2: Type: text/html, Size: 23619 bytes --]
[-- Attachment #2: journalctl-log.txt --]
[-- Type: text/plain, Size: 62638 bytes --]
-- Logs begin at Mon 2018-10-22 14:10:05 UTC, end at Mon 2018-10-22 14:12:45 UTC. --
Oct 22 14:10:05 bullhwmi systemd-journald[608]: Journal started
Oct 22 14:10:05 bullhwmi systemd-journald[608]: Runtime journal (/run/log/journal/a59fe45e10454b37960ea43375d668d2) is 8.0M, max 64.0M, 56.0M free.
Oct 22 14:10:05 bullhwmi systemd-sysctl[614]: Couldn't write '16' to 'kernel/sysrq', ignoring: No such file or directory
Oct 22 14:10:05 bullhwmi systemd-sysctl[614]: Couldn't write 'fq_codel' to 'net/core/default_qdisc', ignoring: No such file or directory
Oct 22 14:10:05 bullhwmi systemd[1]: Mounted Kernel Debug File System.
Oct 22 14:10:05 bullhwmi systemd[1]: Started Remount Root and Kernel File Systems.
Oct 22 14:10:05 bullhwmi systemd[1]: Started Apply Kernel Variables.
Oct 22 14:10:05 bullhwmi systemd[1]: Mounted Temporary Directory (/tmp).
Oct 22 14:10:05 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:05 bullhwmi systemd[1]: Starting Create System Users...
Oct 22 14:10:06 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:06 bullhwmi systemd[1]: Starting Rebuild Hardware Database...
Oct 22 14:10:06 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:06 bullhwmi systemd[1]: Starting Flush Journal to Persistent Storage...
Oct 22 14:10:06 bullhwmi systemd[1]: Started Rebuild Hardware Database.
Oct 22 14:10:06 bullhwmi systemd[1]: Started Create System Users.
Oct 22 14:10:06 bullhwmi systemd-journald[608]: Runtime journal (/run/log/journal/a59fe45e10454b37960ea43375d668d2) is 8.0M, max 64.0M, 56.0M free.
Oct 22 14:10:06 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:06 bullhwmi systemd[1]: Starting Create Static Device Nodes in /dev...
Oct 22 14:10:06 bullhwmi systemd[1]: Started Flush Journal to Persistent Storage.
Oct 22 14:10:07 bullhwmi systemd[1]: Started Create Static Device Nodes in /dev.
Oct 22 14:10:07 bullhwmi systemd[1]: Attaching egress BPF program to cgroup /sys/fs/cgroup/unified/system.slice/systemd-udevd.service failed: Invalid argument
Oct 22 14:10:07 bullhwmi systemd[1]: Starting udev Kernel Device Manager...
Oct 22 14:10:07 bullhwmi systemd[1]: Reached target Local File Systems (Pre).
Oct 22 14:10:07 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:07 bullhwmi systemd[1]: Mounting /var/volatile...
Oct 22 14:10:07 bullhwmi systemd[1]: Started udev Coldplug all Devices.
Oct 22 14:10:07 bullhwmi systemd[1]: Mounted /var/volatile.
Oct 22 14:10:07 bullhwmi systemd[1]: Started udev Kernel Device Manager.
Oct 22 14:10:07 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:07 bullhwmi systemd[1]: Starting Network Service...
Oct 22 14:10:07 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:07 bullhwmi systemd[1]: Starting Load/Save Random Seed...
Oct 22 14:10:07 bullhwmi systemd[1]: Reached target Local File Systems.
Oct 22 14:10:07 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:07 bullhwmi systemd[1]: Starting Create Volatile Files and Directories...
Oct 22 14:10:07 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:07 bullhwmi systemd[1]: Starting Rebuild Journal Catalog...
Oct 22 14:10:08 bullhwmi systemd[1]: Started Load/Save Random Seed.
Oct 22 14:10:08 bullhwmi systemd-tmpfiles[941]: "/var/log" already exists and is not a directory.
Oct 22 14:10:08 bullhwmi systemd-tmpfiles[941]: "/var/tmp" already exists and is not a directory.
Oct 22 14:10:08 bullhwmi systemd[1]: Started Rebuild Journal Catalog.
Oct 22 14:10:08 bullhwmi systemd[1]: Started Create Volatile Files and Directories.
Oct 22 14:10:08 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:08 bullhwmi systemd[1]: Starting Network Time Synchronization...
Oct 22 14:10:08 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:08 bullhwmi systemd[1]: Starting Update is Completed...
Oct 22 14:10:09 bullhwmi systemd[1]: Started Update is Completed.
Oct 22 14:10:09 bullhwmi systemd-udevd[933]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 22 14:10:09 bullhwmi systemd-networkd[939]: Enumeration completed
Oct 22 14:10:09 bullhwmi systemd-udevd[937]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 22 14:10:09 bullhwmi systemd-networkd[939]: sit0: Link is not managed by us
Oct 22 14:10:09 bullhwmi systemd-networkd[939]: lo: Link is not managed by us
Oct 22 14:10:09 bullhwmi systemd-networkd[939]: eth1: Link is not managed by us
Oct 22 14:10:09 bullhwmi systemd-networkd[939]: eth0: IPv6 successfully enabled
Oct 22 14:10:10 bullhwmi systemd-networkd[939]: eth0: Gained carrier
Oct 22 14:10:10 bullhwmi systemd[1]: Started Network Service.
Oct 22 14:10:10 bullhwmi systemd-networkd[939]: sit0: Link is not managed by us
Oct 22 14:10:10 bullhwmi systemd-networkd[939]: lo: Link is not managed by us
Oct 22 14:10:10 bullhwmi systemd-networkd[939]: eth1: IPv6 successfully enabled
Oct 22 14:10:11 bullhwmi systemd[1]: Started Network Time Synchronization.
Oct 22 14:10:11 bullhwmi systemd[1]: Found device /dev/ttyS4.
Oct 22 14:10:11 bullhwmi systemd-udevd[934]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 22 14:10:11 bullhwmi systemd-udevd[938]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 22 14:10:11 bullhwmi systemd[1]: Found device /dev/ttyVUART0.
Oct 22 14:10:12 bullhwmi systemd-networkd[939]: eth0: Gained IPv6LL
Oct 22 14:10:12 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:13 bullhwmi systemd[1]: Created slice system-xyz.openbmc_project.Hwmon.slice.
Oct 22 14:10:13 bullhwmi systemd[1]: Reached target System Initialization.
Oct 22 14:10:13 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on phosphor-ipmi-net.socket.
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on RPCbind Server Activation Socket.
Oct 22 14:10:13 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on Phosphor DBUS Browser socket.
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on D-Bus System Message Bus Socket.
Oct 22 14:10:13 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on Phosphor Webserver socket.
Oct 22 14:10:13 bullhwmi systemd[1]: Started Daily Cleanup of Temporary Directories.
Oct 22 14:10:13 bullhwmi systemd[1]: Reached target Timers.
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Oct 22 14:10:13 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on Phosphor redfish socket (bmcweb).
Oct 22 14:10:13 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:13 bullhwmi systemd[1]: Listening on dropbear.socket.
Oct 22 14:10:13 bullhwmi systemd-networkd[939]: eth1: Gained carrier
Oct 22 14:10:14 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi systemd[1]: Listening on Phosphor Host Console SSH Per-Connection socket.
Oct 22 14:10:14 bullhwmi systemd[1]: Reached target Sockets.
Oct 22 14:10:14 bullhwmi systemd[1]: Reached target Basic System.
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi systemd[1]: Started Phosphor System Manager.
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi systemd[1]: Started Phosphor GPIO power_button monitor.
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi systemd[1]: Started Kernel Logging Service.
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi kernel[975]: klogd started: BusyBox v1.27.2 (2018-10-22 20:18:35 UTC)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Booting Linux on physical CPU 0x0
Oct 22 14:10:14 bullhwmi systemd[1]: Starting Phosphor Download Manager...
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Linux version 4.18.8 (oe-user@oe-host) (gcc version 7.3.0 (GCC)) #1 Mon Oct 22 17:29:12 UTC 2018
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] OF: fdt: Machine model: ATOS AST2500 MI
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Memory policy: Data cache writeback
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] On node 0 totalpages: 126976
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Normal zone: 992 pages used for memmap
Oct 22 14:10:14 bullhwmi systemd[1]: Starting Phosphor LED Group Management Daemon...
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Normal zone: 0 pages reserved
Oct 22 14:10:14 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Normal zone: 126976 pages, LIFO batch:31
Oct 22 14:10:14 bullhwmi systemd[1]: Started Phosphor Dump core monitor..
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] random: get_random_bytes called from start_kernel+0x8c/0x468 with crng_init=0
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] pcpu-alloc: [0] 0
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 125984
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Kernel command line: console=ttyS4,115200n8 root=/dev/ram rw
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Memory: 474316K/507904K available (6144K kernel code, 458K rwdata, 1676K rodata, 1024K init, 116K bss, 33588K reserved, 0K cma-reserved)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] Virtual kernel memory layout:
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] vmalloc : 0x9f800000 - 0xff800000 (1536 MB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] lowmem : 0x80000000 - 0x9f000000 ( 496 MB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (7136 kB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] .init : 0x(ptrval) - 0x(ptrval) (1024 kB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 459 kB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 117 kB)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] ftrace: allocating 20712 entries in 61 pages
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] i2c controller registered, irq 16
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000000] clocksource: FTTMR010-TIMER2: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 77222644334 ns
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000018] sched_clock: 32 bits at 24MHz, resolution 40ns, wraps every 86767015915ns
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000090] Switching to timer-based delay loop, resolution 40ns
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.000961] Calibrating delay loop (skipped), value calculated using timer frequency.. 49.50 BogoMIPS (lpj=247500)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.001021] pid_max: default: 32768 minimum: 301
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.001702] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.001747] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.003169] CPU: Testing write buffer coherency: ok
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.004773] Setting up static identity map for 0x80100000 - 0x8010005c
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.010643] devtmpfs: initialized
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.024031] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.024089] futex hash table entries: 256 (order: -1, 3072 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.024536] pinctrl core: initialized pinctrl subsystem
Oct 22 14:10:15 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.025498] NET: Registered protocol family 16
Oct 22 14:10:15 bullhwmi systemd[1]: Starting RPC Bind Service...
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.026010] DMA: preallocated 256 KiB pool for atomic coherent allocations
Oct 22 14:10:15 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.028049] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Oct 22 14:10:15 bullhwmi systemd[1]: Started D-Bus System Message Bus.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.028084] hw-breakpoint: maximum watchpoint size is 4 bytes.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.130193] usbcore: registered new interface driver usbfs
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.130365] usbcore: registered new interface driver hub
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.130565] usbcore: registered new device driver usb
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.140577] clocksource: Switched to clocksource FTTMR010-TIMER2
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.195006] NET: Registered protocol family 2
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.196120] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.196195] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.196291] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.196366] TCP: Hash tables configured (established 4096 bind 4096)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.196604] UDP hash table entries: 256 (order: 0, 4096 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.196665] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.197060] NET: Registered protocol family 1
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.199488] RPC: Registered named UNIX socket transport module.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.199526] RPC: Registered udp transport module.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.199542] RPC: Registered tcp transport module.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.199558] RPC: Registered tcp NFSv4.1 backchannel transport module.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 0.200226] Trying to unpack rootfs image as initramfs...
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.419831] Freeing initrd memory: 1844K
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.428573] workingset: timestamp_bits=30 max_order=17 bucket_order=0
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.429072] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.430709] jffs2: version 2.2. (SUMMARY) © 2001-2006 Red Hat, Inc.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.444506] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.444549] io scheduler noop registered
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.444706] io scheduler cfq registered (default)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.446847] ipmi-bt-host 1e789140.ibt: Found bt bmc device
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.447910] ipmi-bt-host 1e789140.ibt: Using IRQ 32
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.457821] Serial: 8250/16550 driver, 6 ports, IRQ sharing enabled
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.466696] 1e787000.serial: ttyS5 at MMIO 0x1e787000 (irq = 32, base_baud = 1546875) is a 16550A
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.470870] 1e783000.serial: ttyS0 at MMIO 0x1e783000 (irq = 30, base_baud = 1500000) is a 16550A
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.472923] 1e784000.serial: ttyS4 at MMIO 0x1e784000 (irq = 31, base_baud = 1500000) is a 16550A
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.918521] console [ttyS4] enabled
Oct 22 14:10:15 bullhwmi systemd-networkd[939]: eth1: Gained IPv6LL
Oct 22 14:10:14 bullhwmi kernel[975]: [ 2.984646] timeriomem_rng 1e6e2078.hwrng: 32bits from 0x(ptrval) @ 1us
Oct 22 14:10:15 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.009054] random: fast init done
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.039035] brd: module loaded
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.042662] aspeed-lpc-clk 1e789080.lpc-clk: LPC Controller Clock Enabled
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.053929] aspeed_sgpio 1e780200.sgpio: SGPIO Device driver probed successfully
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.064982] aspeed-smc 1e620000.flash-controller: Using 133 MHz SPI frequency
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.072414] aspeed-smc 1e620000.flash-controller: w25q256 (32768 Kbytes)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.079149] aspeed-smc 1e620000.flash-controller: CE0 window [ 0x20000000 - 0x22000000 ] 32MB
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.087682] aspeed-smc 1e620000.flash-controller: CE1 window [ 0x22000000 - 0x22800000 ] 8MB
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.096129] aspeed-smc 1e620000.flash-controller: read control register: 203b0041
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.103760] aspeed-smc 1e620000.flash-controller: SPI Calibration OK for cs 0, HCLK=198000000MHz, clock divisor=2, read timing compensation=00000090 (1-4)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.122980] 5 fixed-partitions partitions found on MTD device bmc
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.129104] Creating 5 MTD partitions on "bmc":
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.133654] 0x000000000000-0x000000080000 : "u-boot"
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.142931] 0x000000080000-0x0000000c0000 : "u-boot-env"
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.152225] 0x0000000c0000-0x000000700000 : "kernel"
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.157891] random: crng init done
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.165622] 0x000000700000-0x000001f00000 : "rofs"
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.175084] 0x000001f00000-0x000002000000 : "rwfs"
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.186377] aspeed-smc 1e630000.flash-controller: Using 133 MHz SPI frequency
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.193716] aspeed-smc 1e630000.flash-controller: unrecognized JEDEC id bytes: 00, 00, 00
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.201920] aspeed-smc 1e630000.flash-controller: Probe failed on chip cs 0
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.208900] aspeed-smc 1e630000.flash-controller: Aspeed SMC probe failed -2
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.222234] aspeed-smc: probe of 1e630000.flash-controller failed with error -2
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.231790] libphy: Fixed MDIO Bus: probed
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.237622] ftgmac100 1e660000.ethernet: Read MAC address 08:00:38:b7:bb:00 from chip
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.245622] ftgmac100 1e660000.ethernet: Using NCSI interface
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.252574] ftgmac100 1e660000.ethernet eth0: irq 19, mapped at bb6b3653
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.259874] ftgmac100 1e680000.ethernet: Read MAC address 08:00:38:b7:bb:01 from chip
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.275134] libphy: ftgmac100_mdio: probed
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.341313] Marvell 88E1510 1e680000.ethernet--1:00: attached PHY driver [Marvell 88E1510] (mii_bus:phy_addr=1e680000.ethernet--1:00, irq=POLL)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.355358] ftgmac100 1e680000.ethernet eth1: irq 20, mapped at 7d9ef99f
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.362420] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.369017] ehci-platform: EHCI generic platform driver
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.375324] i2c /dev entries driver
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.384074] aspeed-i2c-bus 1e78a040.i2c-bus: i2c bus 0 registered, irq 33
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.393205] aspeed-i2c-bus 1e78a080.i2c-bus: i2c bus 1 registered, irq 34
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.402674] aspeed-i2c-bus 1e78a0c0.i2c-bus: i2c bus 2 registered, irq 35
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.412079] aspeed-i2c-bus 1e78a100.i2c-bus: i2c bus 3 registered, irq 36
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.422725] aspeed-i2c-bus 1e78a140.i2c-bus: i2c bus 4 registered, irq 37
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.433491] aspeed-i2c-bus 1e78a180.i2c-bus: i2c bus 5 registered, irq 38
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.446011] at24 6-0054: 1024 byte 24c08 EEPROM, writable, 16 bytes/write
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.454172] aspeed-i2c-bus 1e78a1c0.i2c-bus: i2c bus 6 registered, irq 39
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.466978] aspeed-i2c-bus 1e78a300.i2c-bus: i2c bus 7 registered, irq 40
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.474477] Driver for 1-wire Dallas network protocol.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.484387] lm75 6-004f: hwmon2: sensor 'tmp75'
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.494643] lm75 6-004a: hwmon3: sensor 'tmp75'
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.500572] lm75 6-004c: hwmon4: sensor 'tmp75'
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.513843] ir38163 4-007c: Failed to read PMBUS_MFR_ID
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.519934] pxm1310 4-003a: Failed to read PMBUS_MFR_ID
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.525916] pxm1310 4-003c: Failed to read PMBUS_MFR_ID
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.531812] pxm1310 4-0038: Failed to read PMBUS_MFR_ID
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.538180] usbcore: registered new interface driver usbhid
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.543876] usbhid: USB HID core driver
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.551454] NET: Registered protocol family 10
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.563404] Segment Routing with IPv6
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.567806] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.575142] NET: Registered protocol family 17
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.579654] 8021q: 802.1Q VLAN Support v1.8
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.592979] console [netcon0] enabled
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.596666] netconsole: network logging started
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.602288] input: gpio-keys as /devices/platform/gpio-keys/input/input0
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.610890] hctosys: unable to open rtc device (rtc0)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 3.622926] Freeing unused kernel memory: 1024K
Oct 22 14:10:14 bullhwmi kernel[975]: [ 4.657871] jffs2: notice: (589) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 4.707485] overlayfs: upper fs does not support tmpfile.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 6.759740] systemd[1]: System time before build time, advancing clock.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 6.855413] systemd[1]: Failed to insert module 'autofs4': No such file or directory
Oct 22 14:10:14 bullhwmi kernel[975]: [ 6.937385] systemd[1]: systemd 237 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK +SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
Oct 22 14:10:14 bullhwmi kernel[975]: [ 6.960131] systemd[1]: Detected architecture arm.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 7.021544] systemd[1]: Set hostname to <bullhwmi>.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 8.833419] systemd[1]: Created slice System Slice.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 8.973320] systemd[1]: Listening on Process Core Dump Socket.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 9.004716] systemd[1]: Listening on Journal Socket (/dev/log).
Oct 22 14:10:14 bullhwmi kernel[975]: [ 9.123009] systemd[1]: Created slice system-phosphor\x2ddiscover\x2dsystem\x2dstate.slice.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 9.332372] systemd[1]: Listening on Syslog Socket.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 9.552115] systemd[1]: Listening on udev Kernel Socket.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 9.783003] systemd[1]: Created slice system-phosphor\x2dcooling\x2dtype.slice.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 11.912922] systemd[1]: Starting udev Coldplug all Devices...
Oct 22 14:10:14 bullhwmi kernel[975]: [ 11.938673] systemd[1]: Reached target Swap.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 12.027571] systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:14 bullhwmi kernel[975]: [ 12.039471] systemd[1]: Mounting Temporary Directory (/tmp)...
Oct 22 14:10:14 bullhwmi kernel[975]: [ 12.142263] systemd[1]: Started Journal Service.
Oct 22 14:10:14 bullhwmi kernel[975]: [ 13.494827] systemd-journald[608]: Received request to flush runtime journal from PID 1
Oct 22 14:10:14 bullhwmi kernel[975]: [ 16.735992] 8021q: adding VLAN 0 to HW filter on device eth0
Oct 22 14:10:14 bullhwmi kernel[975]: [ 16.744392] ftgmac100 1e660000.ethernet eth0: NCSI: Handler for packet type 0x82 returned -19
Oct 22 14:10:14 bullhwmi kernel[975]: [ 17.394120] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
Oct 22 14:10:14 bullhwmi kernel[975]: [ 20.721959] ftgmac100 1e680000.ethernet eth1: Link is Up - 1Gbps/Full - flow control rx/tx
Oct 22 14:10:14 bullhwmi kernel[975]: [ 20.730289] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Oct 22 14:10:17 bullhwmi avahi-daemon[973]: Found user 'avahi' (UID 991) and group 'avahi' (GID 989).
Oct 22 14:10:17 bullhwmi avahi-daemon[973]: Successfully dropped root privileges.
Oct 22 14:10:17 bullhwmi avahi-daemon[973]: avahi-daemon 0.7 starting up.
Oct 22 14:10:17 bullhwmi systemd-networkd[939]: eth1: DHCPv4 address 192.168.81.195/24 via 192.168.81.1
Oct 22 14:10:17 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:17 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:17 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:17 bullhwmi systemd[1]: Started Hardware RNG Entropy Gatherer Daemon.
Oct 22 14:10:17 bullhwmi avahi-daemon[973]: Successfully called chroot().
Oct 22 14:10:17 bullhwmi avahi-daemon[973]: Successfully dropped remaining capabilities.
Oct 22 14:10:17 bullhwmi avahi-daemon[982]: ../../avahi-0.7/avahi-daemon/chroot.c: open() failed: No such file or directory
Oct 22 14:10:17 bullhwmi avahi-daemon[973]: Failed to open /etc/resolv.conf: Invalid argument
Oct 22 14:10:17 bullhwmi avahi-daemon[973]: Loading service file /services/obmc_console.service.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Loading service file /services/phosphor_rest.service.
Oct 22 14:10:18 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:18 bullhwmi systemd[1]: Starting Phosphor Download Manager...
Oct 22 14:10:18 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Loading service file /services/sftp-ssh.service.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Loading service file /services/ssh.service.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Joining mDNS multicast group on interface eth1.IPv6 with address fe80::a00:38ff:feb7:bb01.
Oct 22 14:10:18 bullhwmi systemd[1]: Starting Configure GPIOs and set default state...
Oct 22 14:10:18 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: New relevant interface eth1.IPv6 for mDNS.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Joining mDNS multicast group on interface eth1.IPv4 with address 192.168.81.195.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: New relevant interface eth1.IPv4 for mDNS.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::a00:38ff:feb7:bb00.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: New relevant interface eth0.IPv6 for mDNS.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.157.70.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: New relevant interface eth0.IPv4 for mDNS.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Network interface enumeration completed.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb01 on eth1.*.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for 192.168.81.195 on eth1.IPv4.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb00 on eth0.*.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for 169.254.157.70 on eth0.IPv4.
Oct 22 14:10:18 bullhwmi systemd[1]: Started Phosphor GPIO id_button monitor.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Withdrawing address record for fe80::a00:38ff:feb7:bb01 on eth1.
Oct 22 14:10:18 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Withdrawing address record for 192.168.81.195 on eth1.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Withdrawing address record for 169.254.157.70 on eth0.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Host name conflict, retrying with bullhwmi-2
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb01 on eth1.*.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for 192.168.81.195 on eth1.IPv4.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb00 on eth0.*.
Oct 22 14:10:18 bullhwmi avahi-daemon[973]: Registering new address record for 169.254.157.70 on eth0.IPv4.
Oct 22 14:10:18 bullhwmi systemd[1]: Starting Phosphor Settings Daemon...
Oct 22 14:10:18 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:18 bullhwmi systemd[1]: Starting Phosphor DBus Service Discovery Manager...
Oct 22 14:10:18 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:18 bullhwmi systemd[1]: Starting Phosphor Inventory Manager...
Oct 22 14:10:18 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:19 bullhwmi systemd[1]: Starting Phosphor User Manager...
Oct 22 14:10:19 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:19 bullhwmi systemd[1]: Starting Phosphor Dump Manager...
Oct 22 14:10:19 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:19 bullhwmi systemd-networkd[939]: eth1: Configured
Oct 22 14:10:19 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:19 bullhwmi systemd[1]: Started System Logging Service.
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Withdrawing address record for fe80::a00:38ff:feb7:bb01 on eth1.
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Withdrawing address record for 192.168.81.195 on eth1.
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Withdrawing address record for 169.254.157.70 on eth0.
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Host name conflict, retrying with bullhwmi-3
Oct 22 14:10:19 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb01 on eth1.*.
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Registering new address record for 192.168.81.195 on eth1.IPv4.
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb00 on eth0.*.
Oct 22 14:10:19 bullhwmi systemd[1]: Starting Clear one time boot overrides...
Oct 22 14:10:19 bullhwmi avahi-daemon[973]: Registering new address record for 169.254.157.70 on eth0.IPv4.
Oct 22 14:10:19 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:19 bullhwmi systemd[1]: Started Phosphor Console Muxer listening on device /dev/ttyVUART0.
Oct 22 14:10:20 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:20 bullhwmi fw_setenv[1004]: Warning: Bad CRC, using default environment
Oct 22 14:10:20 bullhwmi systemd[1]: Starting SSH Key Generation...
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Withdrawing address record for fe80::a00:38ff:feb7:bb01 on eth1.
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Withdrawing address record for 192.168.81.195 on eth1.
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Withdrawing address record for 169.254.157.70 on eth0.
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Host name conflict, retrying with bullhwmi-4
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb01 on eth1.*.
Oct 22 14:10:20 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Registering new address record for 192.168.81.195 on eth1.IPv4.
Oct 22 14:10:20 bullhwmi systemd[1]: Starting Phosphor Log Manager...
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Registering new address record for fe80::a00:38ff:feb7:bb00 on eth0.*.
Oct 22 14:10:20 bullhwmi avahi-daemon[973]: Registering new address record for 169.254.157.70 on eth0.IPv4.
Oct 22 14:10:20 bullhwmi systemd[1]: Reached target System Time Synchronized.
Oct 22 14:10:20 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:20 bullhwmi systemd[1]: Starting Network Name Resolution...
Oct 22 14:10:22 bullhwmi avahi-daemon[973]: Server startup complete. Host name is bullhwmi-4.local. Local service cookie is 4276813784.
Oct 22 14:10:22 bullhwmi systemd-resolved[1008]: Positive Trust Anchors:
Oct 22 14:10:22 bullhwmi systemd-resolved[1008]: . IN DS 19036 8 2 49aac11d7b6f6446702e54a1607371607a1a41855200fd2ce1cdde32f24e8fb5
Oct 22 14:10:22 bullhwmi systemd-resolved[1008]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 22 14:10:22 bullhwmi systemd-resolved[1008]: 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.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test
Oct 22 14:10:22 bullhwmi systemd-resolved[1008]: Using system hostname 'bullhwmi'.
Oct 22 14:10:22 bullhwmi avahi-daemon[973]: Service name conflict for "phosphor_rest" (/services/phosphor_rest.service), retrying with "phosphor_rest #2".
Oct 22 14:10:22 bullhwmi avahi-daemon[973]: Service name conflict for "obmc_console" (/services/obmc_console.service), retrying with "obmc_console #2".
Oct 22 14:10:23 bullhwmi avahi-daemon[973]: Service "bullhwmi-4" (/services/ssh.service) successfully established.
Oct 22 14:10:23 bullhwmi avahi-daemon[973]: Service "bullhwmi-4" (/services/sftp-ssh.service) successfully established.
Oct 22 14:10:23 bullhwmi avahi-daemon[973]: Service name conflict for "obmc_console" (/services/obmc_console.service), retrying with "obmc_console #3".
Oct 22 14:10:23 bullhwmi avahi-daemon[973]: Service name conflict for "phosphor_rest" (/services/phosphor_rest.service), retrying with "phosphor_rest #3".
Oct 22 14:10:24 bullhwmi systemd[1]: Started Network Name Resolution.
Oct 22 14:10:24 bullhwmi systemd[1]: Started RPC Bind Service.
Oct 22 14:10:24 bullhwmi avahi-daemon[973]: Service name conflict for "obmc_console" (/services/obmc_console.service), retrying with "obmc_console #4".
Oct 22 14:10:24 bullhwmi avahi-daemon[973]: Service name conflict for "phosphor_rest" (/services/phosphor_rest.service), retrying with "phosphor_rest #4".
Oct 22 14:10:25 bullhwmi systemd[1]: Started Configure GPIOs and set default state.
Oct 22 14:10:25 bullhwmi systemd[1]: Started SSH Key Generation.
Oct 22 14:10:25 bullhwmi systemd[1]: Started Phosphor Download Manager.
Oct 22 14:10:26 bullhwmi systemd[1]: Started Phosphor LED Group Management Daemon.
Oct 22 14:10:26 bullhwmi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Oct 22 14:10:26 bullhwmi systemd[1]: Started Phosphor User Manager.
Oct 22 14:10:26 bullhwmi systemd[1]: Started Clear one time boot overrides.
Oct 22 14:10:26 bullhwmi avahi-daemon[973]: Service "phosphor_rest #4" (/services/phosphor_rest.service) successfully established.
Oct 22 14:10:26 bullhwmi avahi-daemon[973]: Service "obmc_console #4" (/services/obmc_console.service) successfully established.
Oct 22 14:10:26 bullhwmi systemd[1]: Started Phosphor Settings Daemon.
Oct 22 14:10:26 bullhwmi systemd[1]: Started Phosphor Inventory Manager.
Oct 22 14:10:27 bullhwmi systemd[1]: Started Phosphor Log Manager.
Oct 22 14:10:27 bullhwmi systemd[1]: Started Phosphor Dump Manager.
Oct 22 14:10:27 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:27 bullhwmi systemd[1]: Starting IBM Log Manager...
Oct 22 14:10:27 bullhwmi systemd[1]: Reached target Network.
Oct 22 14:10:27 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:28 bullhwmi systemd[1]: Starting The NGINX HTTP and reverse proxy server...
Oct 22 14:10:28 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:28 bullhwmi systemd[1]: Starting Permit User Sessions...
Oct 22 14:10:28 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:28 bullhwmi systemd[1]: Started Lightweight SLP Server.
Oct 22 14:10:28 bullhwmi systemd[1]: Reached target Host and Network Name Lookups.
Oct 22 14:10:28 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:28 bullhwmi systemd[1]: Started NFS status monitor for NFSv2/3 locking..
Oct 22 14:10:29 bullhwmi rpc.statd[1015]: sm-notify: Version 2.1.1 starting
Oct 22 14:10:29 bullhwmi rpc.statd[1015]: sm-notify: No hosts to notify; exiting
Oct 22 14:10:30 bullhwmi systemd[1]: Started Permit User Sessions.
Oct 22 14:10:30 bullhwmi systemd[1]: Started IBM Log Manager.
Oct 22 14:10:30 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:30 bullhwmi systemd[1]: Started Serial Getty on ttyS4.
Oct 22 14:10:30 bullhwmi systemd[1]: Reached target Login Prompts.
Oct 22 14:10:31 bullhwmi systemd[1]: Reached target Multi-User System.
Oct 22 14:10:31 bullhwmi nginx[1012]: Generating a 2048 bit RSA private key
Oct 22 14:10:34 bullhwmi nginx[1012]: ............+++++
Oct 22 14:10:35 bullhwmi phosphor-mapper[990]: ObjectMapper startup complete. Discovery in progress...
Oct 22 14:10:35 bullhwmi avahi-daemon[982]: ../../avahi-0.7/avahi-daemon/chroot.c: open() failed: No such file or directory
Oct 22 14:10:35 bullhwmi avahi-daemon[973]: Failed to open /usr/share/dbus-1/interfaces/org.freedesktop.Avahi.Server.xml: Invalid argument
Oct 22 14:10:35 bullhwmi avahi-daemon[973]: Failed to load introspection data.
Oct 22 14:10:36 bullhwmi systemd[1]: Started Phosphor Download Manager.
Oct 22 14:10:36 bullhwmi download_manager.py[985]: Running Download Manager
Oct 22 14:10:37 bullhwmi nginx[1012]: ...............+++++
Oct 22 14:10:37 bullhwmi nginx[1012]: writing new private key to '/etc/ssl/certs/nginx/cert.pem'
Oct 22 14:10:37 bullhwmi nginx[1012]: -----
Oct 22 14:10:38 bullhwmi nginx[1020]: nginx: the configuration file /etc/nginx/nginx.conf syntax is ok
Oct 22 14:10:38 bullhwmi nginx[1020]: nginx: configuration file /etc/nginx/nginx.conf test is successful
Oct 22 14:10:38 bullhwmi systemd[1]: Started The NGINX HTTP and reverse proxy server.
Oct 22 14:10:40 bullhwmi phosphor-mapper[990]: ObjectMapper discovery complete
Oct 22 14:10:40 bullhwmi systemd[1]: Started Phosphor DBus Service Discovery Manager.
Oct 22 14:10:40 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:40 bullhwmi systemd[1]: Started Phosphor Hwmon Poller.
Oct 22 14:10:40 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:40 bullhwmi systemd[1]: Started Phosphor Hwmon Poller.
Oct 22 14:10:40 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:40 bullhwmi systemd[1]: Started Phosphor Hwmon Poller.
Oct 22 14:10:40 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:40 bullhwmi systemd[1]: Started Phosphor Hwmon Poller.
Oct 22 14:10:40 bullhwmi systemd[1]: Reached target Phosphor Object Mapper.
Oct 22 14:10:40 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:40 bullhwmi systemd[1]: Started Phosphor DBus Monitor.
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/boot...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Phosphor Network Manager...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Phosphor SNMP conf Manager...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/boot/one_time...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/inventory...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/restriction_mode...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/led/groups/bmc_booted...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Phosphor BMC State Manager...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Wait for /org/openbmc/managers/Download...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:41 bullhwmi systemd[1]: Starting Phosphor Chassis State Manager...
Oct 22 14:10:41 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/led/groups...
Oct 22 14:10:42 bullhwmi systemd[1]: Reached target Webserver (pre).
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /org/openbmc/managers/System...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/state/chassis0...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting OpenBMC Software Update Manager...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/time/owner...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/time/sync_method...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/power_restore_policy...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:42 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/state/host0...
Oct 22 14:10:42 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:43 bullhwmi systemd[1]: Starting Wait for /xyz/openbmc_project/control/host0/auto_reboot...
Oct 22 14:10:43 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/boot.
Oct 22 14:10:43 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/boot/one_time.
Oct 22 14:10:43 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/inventory.
Oct 22 14:10:43 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/restriction_mode.
Oct 22 14:10:43 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/led/groups/bmc_booted.
Oct 22 14:10:44 bullhwmi systemd[1]: Started Wait for /org/openbmc/managers/Download.
Oct 22 14:10:44 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/led/groups.
Oct 22 14:10:44 bullhwmi phosphor-hwmon-readd[1027]: Failed to read from device.
Oct 22 14:10:44 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/time/owner.
Oct 22 14:10:44 bullhwmi systemd[1]: Started Phosphor Network Manager.
Oct 22 14:10:44 bullhwmi systemd[1]: Started Phosphor SNMP conf Manager.
Oct 22 14:10:44 bullhwmi phosphor-chassis-state-manager[1038]: Initial Chassis State will be Off
Oct 22 14:10:44 bullhwmi systemd[1]: Created slice system-dropbear.slice.
Oct 22 14:10:44 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:44 bullhwmi systemd[1]: Started SSH Per-Connection Server (192.168.81.14:51197).
Oct 22 14:10:44 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:44 bullhwmi systemd[1]: Started FRU Fault monitor service.
Oct 22 14:10:44 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:45 bullhwmi systemd[1]: Starting Phosphor BMC Code Update...
Oct 22 14:10:45 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:45 bullhwmi systemd[1]: Started Phosphor Cooling Type.
Oct 22 14:10:45 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:45 bullhwmi systemd[1]: Starting OpenPOWER OCC controller...
Oct 22 14:10:45 bullhwmi phosphor-bmc-state-manager[1036]: Setting the BMCState field
Oct 22 14:10:45 bullhwmi phosphor-bmc-state-manager[1036]: Setting the BMCState field
Oct 22 14:10:45 bullhwmi systemd[1]: Started Phosphor Chassis State Manager.
Oct 22 14:10:45 bullhwmi systemd[1]: Started Phosphor BMC State Manager.
Oct 22 14:10:46 bullhwmi systemd[1]: Started OpenBMC Software Update Manager.
Oct 22 14:10:46 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:46 bullhwmi systemd[1]: Starting Phosphor Version Software Manager...
Oct 22 14:10:46 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/time/sync_method.
Oct 22 14:10:46 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/power_restore_policy.
Oct 22 14:10:46 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/control/host0/auto_reboot.
Oct 22 14:10:46 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:47 bullhwmi systemd[1]: Started Phosphor Inband IPMI.
Oct 22 14:10:47 bullhwmi systemd[1]: Started OpenPOWER OCC controller.
Oct 22 14:10:47 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:47 bullhwmi systemd-journald[608]: Forwarding to syslog missed 1 messages.
Oct 22 14:10:47 bullhwmi systemd[1]: Stopping Network Service...
Oct 22 14:10:47 bullhwmi phosphor-log-manager[1007]: Failed to open journal synced file
Oct 22 14:10:47 bullhwmi systemd[1]: Stopped Network Service.
Oct 22 14:10:47 bullhwmi systemd[1]: Starting Network Service...
Oct 22 14:10:47 bullhwmi phosphor-log-manager[1007]: Failed to find metadata
Oct 22 14:10:47 bullhwmi systemd[1]: Started Phosphor Version Software Manager.
Oct 22 14:10:47 bullhwmi phosphor-hwmon-readd[1027]: Logging failing sysfs file
Oct 22 14:10:47 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Oct 22 14:10:47 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Oct 22 14:10:48 bullhwmi systemd-networkd[1071]: eth1: Gained IPv6LL
Oct 22 14:10:48 bullhwmi phosphor-fru-fault-monitor[1059]: /xyz/openbmc_project/logging/entry/1 created
Oct 22 14:10:48 bullhwmi systemd-networkd[1071]: eth0: Gained IPv6LL
Oct 22 14:10:48 bullhwmi systemd-timesyncd[943]: Network configuration changed, trying to establish connection.
Oct 22 14:10:48 bullhwmi systemd-networkd[1071]: Enumeration completed
Oct 22 14:10:48 bullhwmi systemd[1]: Started Network Service.
Oct 22 14:10:48 bullhwmi avahi-daemon[973]: Withdrawing address record for 192.168.81.195 on eth1.
Oct 22 14:10:48 bullhwmi avahi-daemon[973]: Leaving mDNS multicast group on interface eth1.IPv4 with address 192.168.81.195.
Oct 22 14:10:48 bullhwmi avahi-daemon[973]: Joining mDNS multicast group on interface eth1.IPv4 with address 169.254.217.153.
Oct 22 14:10:48 bullhwmi systemd-networkd[1071]: lo: Link is not managed by us
Oct 22 14:10:48 bullhwmi avahi-daemon[973]: Registering new address record for 169.254.217.153 on eth1.IPv4.
Oct 22 14:10:48 bullhwmi systemd-networkd[1071]: sit0: Link is not managed by us
Oct 22 14:10:48 bullhwmi avahi-daemon[973]: Withdrawing address record for 169.254.217.153 on eth1.
Oct 22 14:10:48 bullhwmi systemd-networkd[1071]: eth0: Link is not managed by us
Oct 22 14:10:48 bullhwmi avahi-daemon[973]: Leaving mDNS multicast group on interface eth1.IPv4 with address 169.254.217.153.
Oct 22 14:10:48 bullhwmi avahi-daemon[973]: Interface eth1.IPv4 no longer relevant for mDNS.
Oct 22 14:10:49 bullhwmi systemd-networkd[1071]: eth1: DHCPv4 address 192.168.81.195/24 via 192.168.81.1
Oct 22 14:10:49 bullhwmi avahi-daemon[973]: Joining mDNS multicast group on interface eth1.IPv4 with address 192.168.81.195.
Oct 22 14:10:49 bullhwmi avahi-daemon[973]: New relevant interface eth1.IPv4 for mDNS.
Oct 22 14:10:49 bullhwmi avahi-daemon[973]: Registering new address record for 192.168.81.195 on eth1.IPv4.
Oct 22 14:10:49 bullhwmi avahi-daemon[973]: Withdrawing address record for 169.254.157.70 on eth0.
Oct 22 14:10:49 bullhwmi systemd-networkd[1071]: lo: Link is not managed by us
Oct 22 14:10:49 bullhwmi avahi-daemon[973]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.157.70.
Oct 22 14:10:49 bullhwmi systemd-networkd[1071]: sit0: Link is not managed by us
Oct 22 14:10:49 bullhwmi avahi-daemon[973]: Interface eth0.IPv4 no longer relevant for mDNS.
Oct 22 14:10:49 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Oct 22 14:10:49 bullhwmi systemd-resolved[1008]: Detected conflict on bullhwmi IN A 192.168.81.195
Oct 22 14:10:49 bullhwmi systemd-resolved[1008]: Hostname conflict, changing published hostname from 'bullhwmi' to 'bullhwmi7'.
Oct 22 14:10:49 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Scheduled restart job, restart counter is at 1.
Oct 22 14:10:49 bullhwmi systemd[1]: Stopped Phosphor Hwmon Poller.
Oct 22 14:10:49 bullhwmi systemd[1]: Started Phosphor Hwmon Poller.
Oct 22 14:10:52 bullhwmi phosphor-hwmon-readd[1073]: Failed to read from device.
Oct 22 14:10:53 bullhwmi phosphor-network-manager[1030]: Refreshing the objects.
Oct 22 14:10:53 bullhwmi systemd-networkd[1071]: eth1: Configured
Oct 22 14:10:53 bullhwmi phosphor-network-manager[1030]: Unable to get the value for Network[NTP]
Oct 22 14:10:53 bullhwmi phosphor-network-manager[1030]: Unable to get the value for network[DNS]
Oct 22 14:10:54 bullhwmi phosphor-network-manager[1030]: Unable to get the value for Network[NTP]
Oct 22 14:10:54 bullhwmi phosphor-network-manager[1030]: Unable to get the value for network[DNS]
Oct 22 14:10:54 bullhwmi systemd[1]: Attaching egress BPF program to cgroup /sys/fs/cgroup/unified/system.slice/systemd-hostnamed.service failed: Invalid argument
Oct 22 14:10:54 bullhwmi systemd[1]: Starting Hostname Service...
Oct 22 14:10:54 bullhwmi systemd[1077]: systemd-hostnamed.service: PrivateNetwork=yes is configured, but the kernel does not support network namespaces, ignoring.
Oct 22 14:10:54 bullhwmi phosphor-log-manager[1007]: Failed to open journal synced file
Oct 22 14:10:54 bullhwmi phosphor-log-manager[1007]: Failed to find metadata
Oct 22 14:10:54 bullhwmi phosphor-fru-fault-monitor[1059]: /xyz/openbmc_project/logging/entry/2 created
Oct 22 14:10:54 bullhwmi phosphor-hwmon-readd[1073]: Logging failing sysfs file
Oct 22 14:10:54 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Oct 22 14:10:54 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Oct 22 14:10:55 bullhwmi systemd[1]: Started Hostname Service.
Oct 22 14:10:55 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:10:55 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:10:55 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:10:55 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:10:55 bullhwmi phosphor-network-manager[1030]: Refreshing complete.
Oct 22 14:10:55 bullhwmi systemd[1]: Started Phosphor BMC Code Update.
Oct 22 14:10:55 bullhwmi bmc_update.py[1060]: Running Bmc Flash Control
Oct 22 14:10:55 bullhwmi avahi-daemon[973]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.157.70.
Oct 22 14:10:55 bullhwmi avahi-daemon[973]: New relevant interface eth0.IPv4 for mDNS.
Oct 22 14:10:55 bullhwmi avahi-daemon[973]: Registering new address record for 169.254.157.70 on eth0.IPv4.
Oct 22 14:10:56 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Oct 22 14:10:56 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Scheduled restart job, restart counter is at 2.
Oct 22 14:10:56 bullhwmi systemd[1]: Stopped Phosphor Hwmon Poller.
Oct 22 14:10:56 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Start request repeated too quickly.
Oct 22 14:10:56 bullhwmi systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Oct 22 14:10:56 bullhwmi systemd[1]: Failed to start Phosphor Hwmon Poller.
Oct 22 14:10:59 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/state/chassis0.
Oct 22 14:10:59 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/control/host0.
Oct 22 14:10:59 bullhwmi systemd[1]: Reached target Host0 running after reset.
Oct 22 14:10:59 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:10:59 bullhwmi systemd[1]: Starting Phosphor Host State Manager...
Oct 22 14:11:00 bullhwmi phosphor-host-state-manager[1084]: Initial Host State will be Off
Oct 22 14:11:00 bullhwmi phosphor-host-state-manager[1084]: External request to reset reboot count
Oct 22 14:11:00 bullhwmi systemd[1]: Started Phosphor Host State Manager.
Oct 22 14:11:00 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:11:00 bullhwmi systemd[1]: Started UART render controller.
Oct 22 14:11:00 bullhwmi systemd[1]: Started Wait for /xyz/openbmc_project/state/host0.
Oct 22 14:11:01 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:11:01 bullhwmi systemd[1]: Starting Phosphor Time Manager daemon...
Oct 22 14:11:01 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:11:01 bullhwmi systemd[1]: Starting Reboot If Enabled...
Oct 22 14:11:01 bullhwmi systemd[1]: Detaching egress BPF program from cgroup failed: Invalid argument
Oct 22 14:11:01 bullhwmi systemd[1]: Starting Reset host sensors...
Oct 22 14:11:01 bullhwmi phosphor-discover-system-state[1088]: Host power is off, checking power policy
Oct 22 14:11:01 bullhwmi systemd[1]: Started Reboot If Enabled.
Oct 22 14:11:01 bullhwmi systemd[1]: Attaching egress BPF program to cgroup /sys/fs/cgroup/unified/system.slice/systemd-timedated.service failed: Invalid argument
Oct 22 14:11:01 bullhwmi systemd[1]: Starting Time & Date Service...
Oct 22 14:11:02 bullhwmi systemd[1]: Started Reset host sensors.
Oct 22 14:11:02 bullhwmi systemd[1]: Reached target Host0 (Reset Check).
Oct 22 14:11:02 bullhwmi systemd-timedated[1095]: Failed to get target of /etc/localtime: No such file or directory
Oct 22 14:11:02 bullhwmi systemd[1]: Started Time & Date Service.
Oct 22 14:11:02 bullhwmi systemd[1]: Started Phosphor Time Manager daemon.
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Refreshing the objects.
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value for Network[NTP]
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value for network[DNS]
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value for Network[NTP]
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value for network[DNS]
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Unable to get the value from section DHCP
Oct 22 14:11:06 bullhwmi phosphor-network-manager[1030]: Refreshing complete.
Oct 22 14:11:31 bullhwmi systemd-timesyncd[943]: Timed out waiting for reply from 192.168.81.1:123 (192.168.81.1).
Oct 22 14:11:31 bullhwmi systemd-journald[608]: Forwarding to syslog missed 110 messages.
Oct 22 14:11:34 bullhwmi systemd[1]: dev-aspeed\x2dmbox.device: Job dev-aspeed\x2dmbox.device/start timed out.
Oct 22 14:11:34 bullhwmi systemd[1]: Timed out waiting for device dev-aspeed\x2dmbox.device.
Oct 22 14:11:34 bullhwmi systemd[1]: Dependency failed for Phosphor MBOX Daemon.
Oct 22 14:11:34 bullhwmi systemd[1]: mboxd.service: Job mboxd.service/start failed with result 'dependency'.
Oct 22 14:11:34 bullhwmi systemd[1]: dev-aspeed\x2dmbox.device: Job dev-aspeed\x2dmbox.device/start failed with result 'timeout'.
Oct 22 14:11:34 bullhwmi systemd[1]: Unnecessary job for dev-aspeed\x2dlpc\x2dctrl.device was removed.
Oct 22 14:12:45 bullhwmi systemd-timesyncd[943]: Timed out waiting for reply from 192.168.81.1:123 (192.168.81.1).
Oct 22 14:12:45 bullhwmi systemd-journald[608]: Forwarding to syslog missed 7 messages.
^ permalink raw reply [flat|nested] 5+ messages in thread