root@talos:~# uptime 20:45:42 up 21:30, 4 users, load average: 0.00, 0.00, 0.00 root@talos:~# shutdown -r now The system is going down for reboot NOW!tk0.net (hvc0) (Sat May 12 20:45:46 2 INIT: Switching to runlevel: 6 INIT: Sending processes the TERM signal [info] Using makefile-style concurrent boot in runlevel 6. [ ok ] Asking all remaining processes to terminate...done. [ ok ] All processes ended within 2 seconds...done. [ ok ] Stopping enhanced syslogd: rsyslogd. [ ok ] Stopping RPC port mapper daemon: rpcbind. [ ok ] Deconfiguring network interfaces...done. [info] Saving the system clock. [info] Hardware Clock updated to Fri May 11 22:48:07 UTC 2018. [ ok ] Deactivating swap...done. [ ok ] Unmounting local filesystems...done. EXT4-fs (nvme0n1p4): re-mounted. Opts: (null) [info] Will now restart. kvm: exiting hardware virtualization reboot: Restarting system [77655.350851513,5] OPAL: Reboot request... --== Welcome to Hostboot ==-- 2.76762|secure|SecureROM valid - enabling functionality 5.31710|Booting from SBE side 0 on master proc=00050000 5.36511|ISTEP 6. 5 - host_init_fsi 5.71787|ISTEP 6. 6 - host_set_ipl_parms 6.25591|ISTEP 6. 7 - host_discover_targets 10.03683|HWAS|PRESENT> DIMM[03]=2000000000000000 10.03684|HWAS|PRESENT> Proc[05]=8800000000000000 10.03685|HWAS|PRESENT> Core[07]=5590505050590000 10.12197|ISTEP 6. 8 - host_update_master_tpm 10.12326|SECURE|Security Access Bit> 0x0000000000000000 10.12326|SECURE|Secure Mode Disable (via Jumper)> 0xC000000000000000 10.12338|ISTEP 6. 9 - host_gard 10.14698|HWAS|FUNCTIONAL> DIMM[03]=2000000000000000 10.14699|HWAS|FUNCTIONAL> Proc[05]=8800000000000000 10.14701|HWAS|FUNCTIONAL> Core[07]=5590505050590000 10.15092|ISTEP 6.10 - host_revert_sbe_mcs_setup 10.15178|ISTEP 6.11 - host_start_occ_xstop_handler 11.18090|ISTEP 6.12 - host_voltage_config 11.22942|ISTEP 7. 1 - mss_attr_cleanup 13.22410|ISTEP 7. 2 - mss_volt 13.27739|ISTEP 7. 3 - mss_freq 14.07707|ISTEP 7. 4 - mss_eff_config 14.60928|ISTEP 7. 5 - mss_attr_update 14.62347|ISTEP 8. 1 - host_slave_sbe_config 15.56798|================================================ 15.56912|Error reported by fsi (0x0A00) PLID 0x90000023 15.57026| FsiDD::handleOpbErrors> Error during FSI access 15.57027| ModuleId 0x04 FSI::MOD_FSIDD_HANDLEOPBERRORS 15.57028| ReasonCode 0x0a04 FSI::RC_OPB_ERROR 15.57029| UserData1 Relative FSI Address : 0x000028fc001028fc 15.57029| UserData2 Absolute FSI Address : 0x0002008000050000 15.57030|------------------------------------------------ 15.57145| Bus Type : FSI_BUS_TYPE 15.57260| Target : Physical:/Sys0/Node0/Proc0 15.57262| Target : Physical:/Sys0/Node0/Proc1 15.57262| Priority : SRCI_PRIORITY_MED 15.57263|------------------------------------------------ 15.57264| Callout type : Hardware Callout 15.57265| CPU id : 5 15.57266| Target : Physical:/Sys0/Node0/Proc1 15.57267| Deconfig State : DELAYED_DECONFIG 15.57267| GARD Error Type : GARD_Predictive 15.57268| Priority : SRCI_PRIORITY_LOW 15.57269|------------------------------------------------ 15.57269| Hostboot Build ID: 15.57270|================================================ 16.36942|System shutting down with error status 0x90000023 16.36945|================================================ 16.36946|Error reported by istep (0x1700) PLID 0x90000023 16.42696| IStep failed, see other log(s) with the same PLID for reason. 16.42697| ModuleId 0x01 MOD_REPORTING_ERROR 16.42698| ReasonCode 0x1703 RC_FAILURE 16.42698| UserData1 eid of first error : 0x9000002300000a04 16.42699| UserData2 Reason code of first error : 0x0000000100000801 16.42700|------------------------------------------------ 16.42701| host_slave_sbe_config 16.42701|------------------------------------------------ 16.42702| Callout type : Procedure Callout 16.42703| Procedure : EPUB_PRC_HB_CODE 16.42704| Priority : SRCI_PRIORITY_LOW 16.42704|------------------------------------------------ 16.42705| Hostboot Build ID: 16.42706|================================================ --== Welcome to Hostboot ==-- 2.65410|secure|SecureROM valid - enabling functionality 5.22581|ERRL|Dumping errors reported prior to registration 5.23442|================================================ 5.23443|Error reported by fsi (0x0A00) PLID 0x90000023 5.23749| FsiDD::handleOpbErrors> Error during FSI access 5.23750| ModuleId 0x04 FSI::MOD_FSIDD_HANDLEOPBERRORS 5.23750| ReasonCode 0x0a04 FSI::RC_OPB_ERROR 5.23751| UserData1 Relative FSI Address : 0x000028fc001028fc 5.23751| UserData2 Absolute FSI Address : 0x0002008000050000 5.23752|------------------------------------------------ 5.23866| Bus Type : FSI_BUS_TYPE 5.23983| Target : Physical:/Sys0/Node0/Proc0 5.23984| Target : Physical:/Sys0/Node0/Proc1 5.23985| Priority : SRCI_PRIORITY_MED 5.23985|------------------------------------------------ 5.23985| Callout type : Hardware Callout 5.23986| CPU id : 5 5.23987| Target : Physical:/Sys0/Node0/Proc1 5.23987| Deconfig State : DELAYED_DECONFIG 5.23988| GARD Error Type : GARD_Predictive 5.23988| Priority : SRCI_PRIORITY_LOW 5.23988|------------------------------------------------ 5.23989| Hostboot Build ID: 5.23989|================================================ 5.60488|================================================ 5.60489|Error reported by istep (0x1700) PLID 0x90000023 5.68775| IStep failed, see other log(s) with the same PLID for reason. 5.68776| ModuleId 0x01 MOD_REPORTING_ERROR 5.68776| ReasonCode 0x1703 RC_FAILURE 5.68777| UserData1 eid of first error : 0x9000002300000a04 5.68778| UserData2 Reason code of first error : 0x0000000100000801 5.68779|------------------------------------------------ 5.68780| Callout type : Procedure Callout 5.68781| Procedure : EPUB_PRC_HB_CODE 5.68781| Priority : SRCI_PRIORITY_LOW 5.68782|------------------------------------------------ 5.68783| host_slave_sbe_config 5.68784|------------------------------------------------ 5.68784| Hostboot Build ID: 5.68823|================================================ 5.68927|Booting from SBE side 1 on master proc=00050000 5.86384|ISTEP 6. 5 - host_init_fsi 6.24055|ISTEP 6. 6 - host_set_ipl_parms 6.55956|ISTEP 6. 7 - host_discover_targets 9.40197|HWAS|PRESENT> DIMM[03]=2000000000000000 9.40198|HWAS|PRESENT> Proc[05]=8800000000000000 9.40199|HWAS|PRESENT> Core[07]=5590505050590000 9.44328|ISTEP 6. 8 - host_update_master_tpm 9.44458|SECURE|Security Access Bit> 0x0000000000000000 9.44459|SECURE|Secure Mode Disable (via Jumper)> 0xC000000000000000 9.44472|ISTEP 6. 9 - host_gard 9.48068|HWAS|Deconfig HUID 0x00050001, Physical:/Sys0/Node0/Proc1 9.48302|HWAS|FUNCTIONAL> DIMM[03]=2000000000000000 9.48303|HWAS|FUNCTIONAL> Proc[05]=8000000000000000 9.48365|HWAS|FUNCTIONAL> Core[07]=5590500000000000 9.49114|ISTEP 6.10 - host_revert_sbe_mcs_setup 9.49202|ISTEP 6.11 - host_start_occ_xstop_handler 10.41645|ISTEP 6.12 - host_voltage_config 10.46116|ISTEP 7. 1 - mss_attr_cleanup 11.05469|ISTEP 7. 2 - mss_volt 11.10914|ISTEP 7. 3 - mss_freq 11.32219|ISTEP 7. 4 - mss_eff_config 11.68189|ISTEP 7. 5 - mss_attr_update 11.68949|ISTEP 8. 1 - host_slave_sbe_config 11.91696|ISTEP 8. 2 - host_setup_sbe 11.92057|ISTEP 8. 3 - host_cbs_start 11.92204|ISTEP 8. 4 - proc_check_slave_sbe_seeprom_complete 11.93158|ISTEP 8. 5 - host_attnlisten_proc 11.93247|ISTEP 8. 6 - host_p9_fbc_eff_config 11.93654|ISTEP 8. 7 - host_p9_eff_config_links 11.94232|ISTEP 8. 8 - proc_attr_update 11.94369|ISTEP 8. 9 - proc_chiplet_fabric_scominit 11.95034|ISTEP 8.10 - proc_xbus_scominit 11.95160|ISTEP 8.11 - proc_xbus_enable_ridi 11.98794|ISTEP 8.12 - host_set_voltages 12.00398|ISTEP 9. 1 - fabric_erepair 12.03479|ISTEP 9. 2 - fabric_io_dccal 12.03871|ISTEP 9. 3 - fabric_pre_trainadv 12.10216|ISTEP 9. 4 - fabric_io_run_training 12.10517|ISTEP 9. 5 - fabric_post_trainadv 12.10702|ISTEP 9. 6 - proc_smp_link_layer 12.11273|ISTEP 9. 7 - proc_fab_iovalid 12.25427|ISTEP 9. 8 - host_fbc_eff_config_aggregate 12.26260|ISTEP 10. 1 - proc_build_smp 12.31859|ISTEP 10. 2 - host_slave_sbe_update 14.94272|ISTEP 10. 4 - proc_cen_ref_clk_enable 14.99841|ISTEP 10. 5 - proc_enable_osclite 14.99973|ISTEP 10. 6 - proc_chiplet_scominit 15.03982|ISTEP 10. 7 - proc_abus_scominit 15.05087|ISTEP 10. 8 - proc_obus_scominit 15.05551|ISTEP 10. 9 - proc_npu_scominit 15.05972|ISTEP 10.10 - proc_pcie_scominit 15.08349|ISTEP 10.11 - proc_scomoverride_chiplets 15.08653|ISTEP 10.12 - proc_chiplet_enable_ridi 15.08856|ISTEP 10.13 - host_rng_bist 15.09162|ISTEP 10.14 - host_update_redundant_tpm 15.09453|ISTEP 11. 1 - host_prd_hwreconfig 15.11094|ISTEP 11. 2 - cen_tp_chiplet_init1 15.11393|ISTEP 11. 3 - cen_pll_initf 15.11682|ISTEP 11. 4 - cen_pll_setup 15.11964|ISTEP 11. 5 - cen_tp_chiplet_init2 15.12517|ISTEP 11. 6 - cen_tp_arrayinit 15.12814|ISTEP 11. 7 - cen_tp_chiplet_init3 15.13095|ISTEP 11. 8 - cen_chiplet_init 15.13384|ISTEP 11. 9 - cen_arrayinit 15.13790|ISTEP 11.10 - cen_initf 15.14178|ISTEP 11.11 - cen_do_manual_inits 15.14530|ISTEP 11.12 - cen_startclocks 15.14856|ISTEP 11.13 - cen_scominits 15.15277|ISTEP 12. 1 - mss_getecid 15.95862|ISTEP 12. 2 - dmi_attr_update 15.96338|ISTEP 12. 3 - proc_dmi_scominit 15.96643|ISTEP 12. 4 - cen_dmi_scominit 15.96928|ISTEP 12. 5 - dmi_erepair 15.97351|ISTEP 12. 6 - dmi_io_dccal 16.13705|ISTEP 12. 7 - dmi_pre_trainadv 16.14030|ISTEP 12. 8 - dmi_io_run_training 16.15318|ISTEP 12. 9 - dmi_post_trainadv 16.15640|ISTEP 12.10 - proc_cen_framelock 16.15946|ISTEP 12.11 - host_startprd_dmi 16.16294|ISTEP 12.12 - host_attnlisten_memb 16.16561|ISTEP 12.13 - cen_set_inband_addr 16.18096|ISTEP 13. 1 - host_disable_memvolt 17.09790|ISTEP 13. 2 - mem_pll_reset 17.11014|ISTEP 13. 3 - mem_pll_initf 17.13930|ISTEP 13. 4 - mem_pll_setup 17.18010|ISTEP 13. 6 - mem_startclocks 17.19751|ISTEP 13. 7 - host_enable_memvolt 17.20008|ISTEP 13. 8 - mss_scominit 17.43405|ISTEP 13. 9 - mss_ddr_phy_reset 17.47676|ISTEP 13.10 - mss_draminit 17.57790|ISTEP 13.11 - mss_draminit_training 17.91680|ISTEP 13.12 - mss_draminit_trainadv 18.02877|ISTEP 13.13 - mss_draminit_mc 18.06304|ISTEP 14. 1 - mss_memdiag 23.43568|ISTEP 14. 2 - mss_thermal_init 23.46265|ISTEP 14. 3 - proc_pcie_config 23.50428|ISTEP 14. 4 - mss_power_cleanup 23.50824|ISTEP 14. 5 - proc_setup_bars 23.55074|ISTEP 14. 6 - proc_htm_setup 23.55714|ISTEP 14. 7 - proc_exit_cache_contained 23.58156|ISTEP 15. 1 - host_build_stop_image 26.37951|ISTEP 15. 2 - proc_set_pba_homer_bar 26.38667|ISTEP 15. 3 - host_establish_ex_chiplet 26.39297|ISTEP 15. 4 - host_start_stop_engine 26.41387|ISTEP 16. 1 - host_activate_master 27.59180|ISTEP 16. 2 - host_activate_slave_cores 27.68332|ISTEP 16. 3 - host_secure_rng 27.69288|ISTEP 16. 4 - mss_scrub 27.72521|ISTEP 16. 5 - host_ipl_complete 27.94491|ISTEP 16. 6 - host_load_io_ppe 27.94764|ISTEP 18.11 - proc_tod_setup 27.99151|ISTEP 18.12 - proc_tod_init 28.00590|ISTEP 20. 1 - host_load_payload 28.44264|ISTEP 20. 2 - host_load_hdat 31.21310|ISTEP 21. 1 - host_runtime_setup 38.94830|htmgt|OCCs are now running in ACTIVE state 44.78273|ISTEP 21. 2 - host_verify_hdat 44.78368|ISTEP 21. 3 - host_start_payload [ 46.231977101,5] OPAL skiboot-2d1519e starting... [ 46.231980538,7] initial console log level: memory 7, driver 5 [ 46.231982568,6] CPU: P9 generation processor (max 4 threads/core) [ 46.231984318,7] CPU: Boot CPU PIR is 0x001c PVR is 0x004e1202 [ 46.231986782,7] OPAL table: 0x300ff030 .. 0x300ff5e0, branch table: 0x30002000 [ 46.231989874,7] Assigning physical memory map table for nimbus [ 46.231992444,7] Parsing HDAT... [ 46.231993705,5] SPIRA-S found. [ 46.231995954,6] BMC #0: HW version 3, SW version 2, chip DD1.0 [ 46.232023759,4] SENSORS: Duplicate sensor ID : 0 [ 46.232025533,4] SENSORS: Duplicate sensor ID : 0 [ 46.232033818,4] SENSORS: Duplicate sensor ID : 0 [ 46.232039323,4] SENSORS: Duplicate sensor ID : 0 [ 46.232078820,6] SP Family is ibm,ast2500,openbmc [ 46.232084916,7] LPC: IOPATH chip id = 0 [ 46.232086236,7] LPC: FW BAR = f0000000 [ 46.232087713,7] LPC: MEM BAR = e0000000 [ 46.232089223,7] LPC: IO BAR = d0010000 [ 46.232090680,7] LPC: Internal BAR = c0012000 [ 46.232102956,7] LPC UART: base addr = 3f8 (3f8) size = 1 clk = 1843200, baud = 115200 [ 46.232105667,7] LPC: BT [0, 0] sms_int: 0, bmc_int: 0 [ 46.233019730,5] UART: Using UART at 0x60300d00103f8 [ 46.233374227,4] CORE[8]: HW_PROC_ID=0 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.233513001,4] CORE[9]: HW_PROC_ID=1 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.233609953,4] CORE[10]: HW_PROC_ID=2 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.233720097,4] CORE[11]: HW_PROC_ID=3 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.233825251,4] CORE[12]: HW_PROC_ID=4 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.233927746,4] CORE[13]: HW_PROC_ID=5 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.234037518,4] CORE[14]: HW_PROC_ID=6 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.234152106,4] CORE[15]: HW_PROC_ID=7 PROC_CHIP_ID=1 EC=0x22 UNAVAILABLE [ 46.235253576,5] P9 DD2.21 detected [ 46.235292781,5] CHIP: Chip ID 0000 type: P9N DD2.2 [ 46.235772536,5] PLAT: Using virtual UART [ 46.235959930,7] LPC: Routing irq 9, policy: 0 (r=1) [ 46.235961687,7] LPC: SerIRQ 9 using route 0 targetted at OPAL [ 46.236107283,5] PLAT: Detected Talos platform [ 46.251281586,5] CPU: All 32 processors called in... [ 48.424982118,5] FLASH: Found system flash: id:0 [ 48.425065182,5] BT: Interface initialized, IO 0x00e4 [ 48.425111259,7] LPC: Routing irq 10, policy: 0 (r=1) [ 48.425112110,7] LPC: SerIRQ 10 using route 1 targetted at OPAL [ 50.069565562,3] STB: container NOT VERIFIED, resource_id=4 secureboot not yet initialized [ 50.069970507,5] NVRAM: Size is 576 KB [ 50.267451254,5] STB: Found ibm,secureboot-v2 [ 50.267499849,5] STB: secure mode off [ 50.267538394,5] STB: trusted mode off [ 50.267570602,5] OPAL: Using OPAL UART console [ 50.267584769,7] LPC-MBOX: Sending BMC interrupt [ 50.267646298,7] LPC: Routing irq 4, policy: 0 (r=1) [ 50.267647169,7] LPC: SerIRQ 4 using route 2 targetted at OPAL [ 50.267736896,5] SLW: Configuring self-restore for HRMOR [ 50.267792844,5] SLW: Configuring self-restore for HRMOR [ 50.267833124,5] SLW: Configuring self-restore for NCU_SPEC_BAR [ 50.267879607,5] SLW: Configuring self-restore for P9X_EX_NCU_DARN_BAR [ 50.267932571,5] SLW: Enabling: stop0_lite [ 50.267960657,5] SLW: Enabling: stop0 [ 50.267980742,5] SLW: Enabling: stop1_lite [ 50.268002096,5] SLW: Enabling: stop1 [ 50.268024441,5] SLW: Enabling: stop2_lite [ 50.268046478,5] SLW: Enabling: stop2 [ 50.268064533,5] SLW: Disabling: stop4 in OPAL [ 50.268101657,5] SLW: Disabling: stop5 in OPAL [ 50.268693325,5] VAS: Initialized chip 0 [ 50.268771596,5] Found ibm,power9-nx [ 50.487134446,3] STB: IMA_CATALOG verification FAILED. log=0xffffffffffff8160 [ 51.307575898,3] FLASH: flash subpartition TOC version unknown -1 [ 51.307659427,3] FLASH: Failed to parse subpart info for CAPP [ 51.310046490,3] CAPP: Error loading ucode lid. index=202d1 [ 51.324709287,5] PCI: Resetting PHBs and training links... Exiting petitboot. Type 'exit' to return. You may run 'pb-sos' to gather diagnostic data / # --- bmc root@talos:~# vrm-control.sh rail set read current ------- ------- ------- ------- vdda 1.102V 0.695V 13.750A vcsa 1.031V 0.949V 0.062A vdna 1.000V 0.688V 6.000A vioa 1.039V 1.039V 2.375A vddb 1.102V 1.105V 0.000A vcsb 1.031V 1.031V 0.000A vdnb 1.000V 1.000V 0.750A viob 1.039V 1.039V 1.375A ---