root@talos:~# obmcutil poweron
root@talos:~# tail -f /var/log/messages
Apr 20 22:08:52 talos daemon.info systemd[1]: Stopped Phosphor Hwmon Poller.
Apr 20 22:08:52 talos daemon.info systemd[1]: Started Phosphor Hwmon Poller.
Apr 20 22:08:52 talos daemon.info systemd[1]: Started Wait for /xyz/openbmc_project/watchdog/host0.
Apr 20 22:08:54 talos kern.err phosphor-hwmon-readd[1160]: Failed to read from device.
Apr 20 22:08:54 talos kern.info phosphor-log-manager[879]: Failed to find metadata
Apr 20 22:08:54 talos kern.err phosphor-fru-fault-monitor[970]: /xyz/openbmc_project/logging/entry/346
Apr 20 22:08:54 talos kern.info phosphor-hwmon-readd[1160]: Logging failing sysfs file
Apr 20 22:08:54 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 22:08:54 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Unit entered failed state.
Apr 20 22:08:54 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Apr 20 22:08:56 talos daemon.info systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Apr 20 22:08:56 talos daemon.info systemd[1]: Stopped Phosphor Hwmon Poller.
Apr 20 22:08:56 talos daemon.info systemd[1]: Started Phosphor Hwmon Poller.
Apr 20 22:08:57 talos kern.err phosphor-hwmon-readd[1232]: Failed to read from device.
Apr 20 22:08:58 talos kern.info phosphor-log-manager[879]: Failed to find metadata
Apr 20 22:08:58 talos kern.err phosphor-fru-fault-monitor[970]: /xyz/openbmc_project/logging/entry/347
Apr 20 22:08:58 talos kern.info phosphor-hwmon-readd[1232]: Logging failing sysfs file
Apr 20 22:08:58 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 22:08:58 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Unit entered failed state.
Apr 20 22:08:58 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Apr 20 22:08:59 talos daemon.info systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Apr 20 22:08:59 talos daemon.info systemd[1]: Stopped Phosphor Hwmon Poller.
Apr 20 22:08:59 talos daemon.info systemd[1]: Started Phosphor Hwmon Poller.
Apr 20 22:09:00 talos daemon.info avsbus-workaround.sh[1133]: Error: Write failed
Apr 20 22:09:01 talos kern.err phosphor-hwmon-readd[1302]: Failed to read from device.
Apr 20 22:09:02 talos kern.info phosphor-log-manager[879]: Failed to find metadata
Apr 20 22:09:02 talos kern.err phosphor-fru-fault-monitor[970]: /xyz/openbmc_project/logging/entry/348
Apr 20 22:09:02 talos kern.info phosphor-hwmon-readd[1302]: Logging failing sysfs file
Apr 20 22:09:02 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 22:09:02 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Unit entered failed state.
Apr 20 22:09:02 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Apr 20 22:09:02 talos daemon.info power_control.exe[1032]: ERROR PowerControl: Pgood poll timeout
Apr 20 22:09:03 talos daemon.info systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Apr 20 22:09:03 talos daemon.info systemd[1]: Stopped Phosphor Hwmon Poller.
Apr 20 22:09:03 talos daemon.info systemd[1]: Started Phosphor Hwmon Poller.
Apr 20 22:09:04 talos kern.err phosphor-hwmon-readd[1376]: Failed to read from device.
Apr 20 22:09:05 talos kern.info phosphor-log-manager[879]: Failed to find metadata
Apr 20 22:09:05 talos kern.err phosphor-fru-fault-monitor[970]: /xyz/openbmc_project/logging/entry/349
Apr 20 22:09:05 talos kern.info phosphor-hwmon-readd[1376]: Logging failing sysfs file
Apr 20 22:09:05 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 22:09:05 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Unit entered failed state.
Apr 20 22:09:05 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Apr 20 22:09:06 talos daemon.info systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Apr 20 22:09:06 talos daemon.info systemd[1]: Stopped Phosphor Hwmon Poller.
Apr 20 22:09:06 talos daemon.info systemd[1]: Started Phosphor Hwmon Poller.
Apr 20 22:09:08 talos kern.err phosphor-hwmon-readd[1402]: Failed to read from device.
Apr 20 22:09:08 talos kern.info phosphor-log-manager[879]: Failed to find metadata
Apr 20 22:09:08 talos kern.err phosphor-fru-fault-monitor[970]: /xyz/openbmc_project/logging/entry/350
Apr 20 22:09:08 talos kern.info phosphor-hwmon-readd[1402]: Logging failing sysfs file
Apr 20 22:09:08 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 22:09:08 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Unit entered failed state.
Apr 20 22:09:08 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Apr 20 22:09:09 talos daemon.info systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Apr 20 22:09:09 talos daemon.info systemd[1]: Stopped Phosphor Hwmon Poller.
Apr 20 22:09:09 talos daemon.info systemd[1]: Started Phosphor Hwmon Poller.
Apr 20 22:09:10 talos daemon.info avsbus-workaround.sh[1133]: Error: Write failed
Apr 20 22:09:11 talos kern.err phosphor-hwmon-readd[1405]: Failed to read from device.
Apr 20 22:09:11 talos kern.info phosphor-log-manager[879]: Failed to find metadata
Apr 20 22:09:11 talos kern.err phosphor-fru-fault-monitor[970]: /xyz/openbmc_project/logging/entry/351
Apr 20 22:09:11 talos kern.info phosphor-hwmon-readd[1405]: Logging failing sysfs file
Apr 20 22:09:11 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Main process exited, code=exited, status=1/FAILURE
Apr 20 22:09:11 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Unit entered failed state.
Apr 20 22:09:11 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Apr 20 22:09:13 talos daemon.info systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Service hold-off time over, scheduling restart.
Apr 20 22:09:13 talos daemon.info systemd[1]: Stopped Phosphor Hwmon Poller.
Apr 20 22:09:13 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Start request repeated too quickly.
Apr 20 22:09:13 talos daemon.err systemd[1]: Failed to start Phosphor Hwmon Poller.
Apr 20 22:09:13 talos daemon.notice systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Unit entered failed state.
Apr 20 22:09:13 talos daemon.warn systemd[1]: xyz.openbmc_project.Hwmon@ahb-apb-pwm\x2dtacho\x2dcontroller\x401e786000.service: Failed with result 'exit-code'.
Apr 20 22:09:15 talos daemon.warn systemd[1]: op-wait-power-on@0.service: Start operation timed out. Terminating.
Apr 20 22:09:15 talos daemon.warn systemd[1]: op-wait-power-on@0.service: Main process exited, code=killed, status=15/TERM
Apr 20 22:09:15 talos daemon.err systemd[1]: Failed to start Wait for Power0 to turn on.
Apr 20 22:09:15 talos daemon.warn systemd[1]: Dependency failed for Chassis0 (Power On).
Apr 20 22:09:15 talos daemon.warn systemd[1]: Dependency failed for Start Host0.
Apr 20 22:09:15 talos daemon.notice systemd[1]: obmc-host-start@0.target: Job obmc-host-start@0.target/start failed with result 'dependency'.
Apr 20 22:09:15 talos daemon.info systemd[1]: obmc-host-start@0.target: Triggering OnFailure= dependencies.
Apr 20 22:09:15 talos daemon.notice systemd[1]: obmc-chassis-poweron@0.target: Job obmc-chassis-poweron@0.target/start failed with result 'dependency'.
Apr 20 22:09:15 talos daemon.info systemd[1]: obmc-chassis-poweron@0.target: Triggering OnFailure= dependencies.
Apr 20 22:09:15 talos daemon.notice systemd[1]: op-wait-power-on@0.service: Unit entered failed state.
Apr 20 22:09:15 talos daemon.warn systemd[1]: op-wait-power-on@0.service: Failed with result 'timeout'.
Apr 20 22:09:15 talos daemon.info systemd[1]: Created slice system-op\x2docc\x2ddisable.slice.
Apr 20 22:09:15 talos daemon.info systemd[1]: Stopped target Host0 (Reset Check).
Apr 20 22:09:15 talos daemon.info systemd[1]: Stopped target Host0 running after reset.
Apr 20 22:09:15 talos daemon.info systemd[1]: Starting OpenPOWER OCC Active Disable...
Apr 20 22:09:15 talos daemon.info systemd[1]: Created slice system-op\x2dpower\x2dstop.slice.
Apr 20 22:09:15 talos daemon.info systemd[1]: Created slice system-op\x2dwait\x2dpower\x2doff.slice.
Apr 20 22:09:15 talos daemon.info systemd[1]: Stopped Assert power_on LED.
Apr 20 22:09:15 talos daemon.info systemd[1]: Created slice system-obmc\x2dled\x2dgroup\x2dstop.slice.
Apr 20 22:09:15 talos daemon.info systemd[1]: Starting Assert power_on LED...
Apr 20 22:09:15 talos daemon.info systemd[1]: Stopping Phosphor GPIO checkstop monitor...
Apr 20 22:09:15 talos daemon.info systemd[1]: Stopping Phosphor poweron watchdog...
Apr 20 22:09:15 talos daemon.info occ-active.sh[1411]: Found 2 CPU(s)
Apr 20 22:09:16 talos daemon.info systemd[1]: Stopped Phosphor poweron watchdog.
Apr 20 22:09:16 talos daemon.info systemd[1]: Stopped Phosphor GPIO checkstop monitor.
Apr 20 22:09:16 talos daemon.warn systemd[1]: avsbus-workaround@0.service: Main process exited, code=killed, status=15/TERM
Apr 20 22:09:16 talos daemon.info occ-active.sh[1411]: /usr/bin/occ-active.sh: line 54: echo: write error: No such device
Apr 20 22:09:16 talos daemon.info occ-active.sh[1411]: /usr/bin/occ-active.sh: line 55: echo: write error: No such device
Apr 20 22:09:16 talos daemon.info occ-active.sh[1411]: /usr/bin/occ-active.sh: line 56: echo: write error: No such device
Apr 20 22:09:16 talos daemon.info occ-active.sh[1411]: /usr/bin/occ-active.sh: line 57: echo: write error: No such device
Apr 20 22:09:16 talos daemon.info systemd[1]: Started OpenPOWER OCC Active Disable.
Apr 20 22:09:16 talos daemon.info systemd[1]: Reached target Stop Host0 (Pre).
Apr 20 22:09:16 talos daemon.info systemd[1]: Reached target Host0 (Stopping).
Apr 20 22:09:16 talos daemon.info systemd[1]: Reached target Host0 (Stopped).
Apr 20 22:09:16 talos daemon.info systemd[1]: Reached target Power0 Off (Pre).
Apr 20 22:09:16 talos daemon.info systemd[1]: Starting Wait for Power0 to turn off...
Apr 20 22:09:16 talos daemon.info systemd[1]: Started Stop Power0.
Apr 20 22:09:18 talos daemon.info system_manager.py[955]: Running System State: HOST_POWERING_OFF
Apr 20 22:09:18 talos daemon.info power_control.exe[1032]: PowerControl: setting power up BMC_POWER_UP to 0
Apr 20 22:09:18 talos daemon.info phosphor-ledmanager[886]: Asserting LEDs
Apr 20 22:09:19 talos daemon.info systemd[1]: Started Assert power_on LED.
Apr 20 22:09:19 talos daemon.info systemd[1]: Started Wait for Power0 to turn off.
Apr 20 22:09:19 talos daemon.info systemd[1]: Reached target Power0 Off.
Apr 20 22:09:19 talos daemon.info systemd[1]: Reached target Chassis0 (Power Off).
Apr 20 22:09:19 talos daemon.info systemd[1]: Reached target Stop Host0.
Apr 20 22:09:19 talos kern.info phosphor-chassis-state-manager[1023]: Received signal that power OFF is complete
Apr 20 22:09:19 talos kern.info phosphor-chassis-state-manager[1023]: Change to Chassis Power State
Apr 20 22:09:19 talos kern.info phosphor-host-state-manager[1068]: Received signal that host is off
Apr 20 22:09:19 talos kern.info phosphor-host-state-manager[1068]: Change to Host State
Apr 20 22:09:20 talos daemon.info systemd[1]: Stopped Perform AVS bus workaround on VRMs.
Apr 20 22:09:20 talos daemon.notice systemd[1]: avsbus-workaround@0.service: Unit entered failed state.
Apr 20 22:09:20 talos daemon.warn systemd[1]: avsbus-workaround@0.service: Failed with result 'signal'.
Apr 20 22:09:20 talos daemon.info systemd[1]: Stopped Start Power0.
Apr 20 22:09:20 talos daemon.info systemd[1]: Stopped target Power0 On (Pre).
Apr 20 22:09:20 talos daemon.info systemd[1]: Stopped Reload mboxd during power on.