Apr 20 22:09:49 talos daemon.info chassis_control.py[874]: Turn on power and boot Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-avsbus\x2denable.slice. Apr 20 22:09:49 talos daemon.info systemd[1]: Starting Wait for /xyz/openbmc_project/led/groups/power_on... Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-ast_vga_disable.slice. Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-fsi\x2dscan.slice. Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-avsbus\x2ddisable.slice. Apr 20 22:09:49 talos daemon.info systemd[1]: Starting Wait for /xyz/openbmc_project/watchdog/host0... Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-op\x2dpower\x2dstart.slice. Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-phosphor\x2dwatchdog.slice. Apr 20 22:09:49 talos daemon.info systemd[1]: Started Phosphor poweron watchdog. Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-cfam_override.slice. Apr 20 22:09:49 talos daemon.info systemd[1]: Created slice system-start_host.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-fsi\x2dbind.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-avsbus\x2dworkaround.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-pcie\x2dslot\x2ddetect.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Starting Wait for /org/openbmc/control/chassis0... Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-mapper\x2dsubtree\x2dremove.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Starting mapper subtree-remove /xyz/openbmc_project/software:xyz.openbmc_project.Software.ActivationBlocksTransition... Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-mboxd\x2dreload.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-vrm\x2dcontrol.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-obmc\x2denable\x2dhost\x2dwatchdog.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-phosphor\x2dfan\x2dcontrol\x2dinit.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-phosphor\x2dfan\x2dmonitor\x2dinit.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Starting Reload mboxd during power on... Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-phosphor\x2dfan\x2dpresence\x2dtach.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-op\x2docc\x2denable.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-phosphor\x2dgpio\x2dmonitor.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Started Phosphor GPIO checkstop monitor. Apr 20 22:09:50 talos daemon.info systemd[1]: Created slice system-op\x2dwait\x2dpower\x2don.slice. Apr 20 22:09:50 talos daemon.info systemd[1]: Starting Wait for Power0 to turn on... Apr 20 22:09:51 talos daemon.info mboxctl[1385]: Reset: Success Apr 20 22:09:51 talos daemon.info systemd[1]: Started Wait for /xyz/openbmc_project/led/groups/power_on. Apr 20 22:09:51 talos daemon.info systemd[1]: Started mapper subtree-remove /xyz/openbmc_project/software:xyz.openbmc_project.Software.ActivationBlocksTransition. Apr 20 22:09:51 talos daemon.info systemd[1]: Started Wait for /org/openbmc/control/chassis0. Apr 20 22:09:51 talos daemon.info systemd[1]: Started Reload mboxd during power on. Apr 20 22:09:51 talos daemon.info systemd[1]: Reached target Power0 On (Pre). Apr 20 22:09:51 talos daemon.info systemd[1]: Starting Start Power0... Apr 20 22:09:51 talos daemon.info systemd[1]: Starting Assert power_on LED... Apr 20 22:09:51 talos daemon.info power_control.exe[1026]: PowerControl: setting power up BMC_POWER_UP to 1 Apr 20 22:09:51 talos daemon.info systemd[1]: Started Start Power0. Apr 20 22:09:52 talos daemon.info system_manager.py[959]: Running System State: HOST_POWERING_ON Apr 20 22:09:52 talos daemon.info systemd[1]: Starting Perform AVS bus workaround on VRMs... Apr 20 22:09:52 talos daemon.info phosphor-ledmanager[893]: Asserting LEDs Apr 20 22:09:52 talos daemon.info systemd[1]: Started Assert power_on LED. Apr 20 22:09:52 talos daemon.info systemd[1]: Started Wait for /xyz/openbmc_project/watchdog/host0. Apr 20 22:10:02 talos daemon.info avsbus-workaround.sh[1398]: Error: Write failed Apr 20 22:10:05 talos daemon.info power_control.exe[1026]: ERROR PowerControl: Pgood poll timeout Apr 20 22:10:12 talos daemon.info avsbus-workaround.sh[1398]: Error: Write failed Apr 20 22:10:20 talos daemon.warn systemd[1]: op-wait-power-on@0.service: Start operation timed out. Terminating. Apr 20 22:10:20 talos daemon.warn systemd[1]: op-wait-power-on@0.service: Main process exited, code=killed, status=15/TERM Apr 20 22:10:20 talos daemon.err systemd[1]: Failed to start Wait for Power0 to turn on. Apr 20 22:10:20 talos daemon.warn systemd[1]: Dependency failed for Chassis0 (Power On). Apr 20 22:10:20 talos daemon.warn systemd[1]: Dependency failed for Start Host0. Apr 20 22:10:20 talos daemon.notice systemd[1]: obmc-host-start@0.target: Job obmc-host-start@0.target/start failed with result 'dependency'. Apr 20 22:10:20 talos daemon.info systemd[1]: obmc-host-start@0.target: Triggering OnFailure= dependencies. Apr 20 22:10:20 talos daemon.notice systemd[1]: obmc-chassis-poweron@0.target: Job obmc-chassis-poweron@0.target/start failed with result 'dependency'. Apr 20 22:10:20 talos daemon.info systemd[1]: obmc-chassis-poweron@0.target: Triggering OnFailure= dependencies. Apr 20 22:10:20 talos daemon.notice systemd[1]: op-wait-power-on@0.service: Unit entered failed state. Apr 20 22:10:20 talos daemon.warn systemd[1]: op-wait-power-on@0.service: Failed with result 'timeout'. Apr 20 22:10:20 talos daemon.info systemd[1]: Stopped target Host0 (Reset Check). Apr 20 22:10:20 talos daemon.info systemd[1]: Stopped target Host0 running after reset. Apr 20 22:10:20 talos daemon.info systemd[1]: Created slice system-op\x2docc\x2ddisable.slice. Apr 20 22:10:20 talos daemon.info systemd[1]: Stopped Assert power_on LED. Apr 20 22:10:20 talos daemon.info systemd[1]: Stopping Phosphor GPIO checkstop monitor... Apr 20 22:10:20 talos daemon.info systemd[1]: Created slice system-op\x2dwait\x2dpower\x2doff.slice. Apr 20 22:10:20 talos daemon.info systemd[1]: Created slice system-obmc\x2dled\x2dgroup\x2dstop.slice. Apr 20 22:10:20 talos daemon.info systemd[1]: Starting OpenPOWER OCC Active Disable... Apr 20 22:10:21 talos daemon.info systemd[1]: Starting Assert power_on LED... Apr 20 22:10:21 talos daemon.info systemd[1]: Created slice system-op\x2dpower\x2dstop.slice. Apr 20 22:10:21 talos daemon.info systemd[1]: Stopping Phosphor poweron watchdog... Apr 20 22:10:21 talos daemon.info occ-active.sh[1409]: Found 2 CPU(s) Apr 20 22:10:21 talos daemon.info systemd[1]: Stopped Phosphor poweron watchdog. Apr 20 22:10:21 talos daemon.info systemd[1]: Stopped Phosphor GPIO checkstop monitor. Apr 20 22:10:21 talos daemon.warn systemd[1]: avsbus-workaround@0.service: Main process exited, code=killed, status=15/TERM Apr 20 22:10:22 talos daemon.info occ-active.sh[1409]: /usr/bin/occ-active.sh: line 54: echo: write error: No such device Apr 20 22:10:22 talos daemon.info occ-active.sh[1409]: /usr/bin/occ-active.sh: line 55: echo: write error: No such device Apr 20 22:10:22 talos daemon.info occ-active.sh[1409]: /usr/bin/occ-active.sh: line 56: echo: write error: No such device Apr 20 22:10:22 talos daemon.info occ-active.sh[1409]: /usr/bin/occ-active.sh: line 57: echo: write error: No such device Apr 20 22:10:22 talos daemon.info systemd[1]: Started OpenPOWER OCC Active Disable. Apr 20 22:10:22 talos daemon.info systemd[1]: Reached target Stop Host0 (Pre). Apr 20 22:10:22 talos daemon.info systemd[1]: Reached target Host0 (Stopping). Apr 20 22:10:22 talos daemon.info systemd[1]: Reached target Host0 (Stopped). Apr 20 22:10:22 talos daemon.info systemd[1]: Reached target Power0 Off (Pre). Apr 20 22:10:22 talos daemon.info systemd[1]: Starting Wait for Power0 to turn off... Apr 20 22:10:22 talos daemon.info systemd[1]: Started Stop Power0. Apr 20 22:10:22 talos daemon.info systemd[1]: Stopped Perform AVS bus workaround on VRMs. Apr 20 22:10:22 talos daemon.notice systemd[1]: avsbus-workaround@0.service: Unit entered failed state. Apr 20 22:10:22 talos daemon.warn systemd[1]: avsbus-workaround@0.service: Failed with result 'signal'. Apr 20 22:10:22 talos daemon.info systemd[1]: Stopped Start Power0. Apr 20 22:10:22 talos daemon.info systemd[1]: Stopped target Power0 On (Pre). Apr 20 22:10:22 talos daemon.info systemd[1]: Stopped Reload mboxd during power on. Apr 20 22:10:24 talos daemon.info power_control.exe[1026]: PowerControl: setting power up BMC_POWER_UP to 0 Apr 20 22:10:24 talos daemon.info system_manager.py[959]: Running System State: HOST_POWERING_OFF Apr 20 22:10:24 talos daemon.info phosphor-ledmanager[893]: Asserting LEDs Apr 20 22:10:24 talos daemon.info systemd[1]: Started Assert power_on LED. Apr 20 22:10:24 talos daemon.info systemd[1]: Started Wait for Power0 to turn off. Apr 20 22:10:24 talos daemon.info systemd[1]: Reached target Chassis0 (Power Off). Apr 20 22:10:24 talos daemon.info systemd[1]: Reached target Stop Host0. Apr 20 22:10:24 talos daemon.info systemd[1]: Reached target Power0 Off. Apr 20 22:10:25 talos kern.info phosphor-chassis-state-manager[1028]: Received signal that power OFF is complete Apr 20 22:10:25 talos kern.info phosphor-chassis-state-manager[1028]: Change to Chassis Power State Apr 20 22:10:25 talos kern.info phosphor-host-state-manager[1068]: Received signal that host is off Apr 20 22:10:25 talos kern.info phosphor-host-state-manager[1068]: Change to Host State