search

open-power / hostboot

System initialization firmware for Power systems
Apache License 2.0
75 stars 97 forks source link

Read attribute(ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH) failed at istep 14.8 #210

Closed lili-lilili closed 2 years ago

lili-lilili commented 2 years ago

We build a pnor and run it on the rainier，hostboot shutdown at istep 14.8

Here is the console log: --== Welcome to SBE - CommitId[0xa9dd6dee] ==-- istep 3.20 istep 3.21 istep 3.22 istep 3.23 istep 4.1 istep 4.2 istep 4.3 istep 4.4 istep 4.5 istep 4.6 istep 4.7 istep 4.8 istep 4.9 istep 4.10 istep 4.11 istep 4.12 istep 4.13 istep 4.14 istep 4.15 istep 4.16 istep 4.17 istep 4.18 istep 4.19 istep 4.20 istep 5.1 istep 5.2 istep 5.3 SBE starting hostboot istep6.1 Loading boot firmware Invoking boot firmware

--== Welcome to Hostboot hostboot-p10-7e966f4/hbicore.bin Boot Status (VUART1) ==--

--== FW a5f8d2a a5f8d2a ==--

1.12541|secure|SecureROM valid - enabling functionality 1.14390|Current time (Y/M/D H:M:S) : 1970/01/01 00:05:42 @TB=585676161 1.59679|secure|Booting in non-secure mode (minimum secure version=0x00) 6.37387|SMT=8, Fuse=0

7.58207|Booting from Temp side. 7.60953|Booting from SBE side 0 on master proc=00050000 7.62071|1970/01/01 00:05:48|ISTEP 6. 4 - host_init_fsi 13.31740|1970/01/01 00:05:54|ISTEP 6. 5 - host_set_ipl_parms 13.36366|1970/01/01 00:05:54|ISTEP 6. 6 - host_discover_targets 19.78645|HWAS|--------------------------------- 19.78645|HWAS|PRESENT> 19.78842|TARG|PROCS=F0000000 19.78843|TARG|PROC[00]: 19.78843|TARG| CORE=CC0CCCFC DIMM=8800000000000000 19.78848|TARG| CACHE=CC0CCCFC OCMB=FF00 19.78849|TARG|PROC[01]: 19.78849|TARG| CORE=00000000 DIMM=0000000000000000 19.78850|TARG| CACHE=00000000 OCMB=0000 19.78851|TARG|PROC[02]: 19.78851|TARG| CORE=CF30C33C DIMM=8800000000000000 19.78852|TARG| CACHE=CF30C33C OCMB=FF00 19.78853|TARG|PROC[03]: 19.78853|TARG| CORE=00000000 DIMM=0000000000000000 19.78854|TARG| CACHE=00000000 OCMB=0000 19.78855|HWAS|--------------------------------- 19.78881|devtree|Syncing to BMC 23.97040|1970/01/01 00:06:04|ISTEP 6. 7 - host_update_primary_tpm 23.99098|1970/01/01 00:06:04|ISTEP 6. 8 - host_gard 24.06329|HWAS|--------------------------------- 24.06330|HWAS|FUNCTIONAL> 24.06453|TARG|PROCS=F0000000 24.06454|TARG|PROC[00]: 24.06454|TARG| CORE=CC0CCCFC DIMM=8800000000000000 24.06455|TARG| CACHE=CC0CCCFC OCMB=FF00 24.06456|TARG|PROC[01]: 24.06456|TARG| CORE=00000000 DIMM=0000000000000000 24.06457|TARG| CACHE=00000000 OCMB=0000 24.06458|TARG|PROC[02]: 24.06458|TARG| CORE=CF30C33C DIMM=8800000000000000 24.06459|TARG| CACHE=CF30C33C OCMB=FF00 24.06460|TARG|PROC[03]: 24.06460|TARG| CORE=00000000 DIMM=0000000000000000 24.06461|TARG| CACHE=00000000 OCMB=0000 24.06462|HWAS|--------------------------------- 24.71534|SECURE|Security Access Bit> 0x0000000000000000 24.71534|SECURE|Secure Mode Disable (via Jumper)> 0xF000000000000000 24.95477|1970/01/01 00:06:05|ISTEP 6. 9 - host_voltage_config 25.96584|1970/01/01 00:06:06|ISTEP 7. 1 - mss_attr_cleanup 27.01120|1970/01/01 00:06:07|ISTEP 7. 2 - mss_volt 27.19791|1970/01/01 00:06:08|ISTEP 7. 3 - mss_freq 27.62807|1970/01/01 00:06:08|ISTEP 7. 4 - mss_eff_config 28.30535|1970/01/01 00:06:09|ISTEP 7. 5 - mss_attr_update 28.36949|1970/01/01 00:06:09|ISTEP 8. 1 - host_setup_sbe 28.59018|1970/01/01 00:06:09|ISTEP 8. 2 - host_secondary_sbe_config 28.71655|1970/01/01 00:06:09|ISTEP 8. 3 - host_cbs_start 28.76719|1970/01/01 00:06:09|ISTEP 8. 4 - proc_check_secondary_sbe_seeprom_complete 44.02630|1970/01/01 00:06:24|ISTEP 8. 5 - host_attnlisten_proc 44.16484|1970/01/01 00:06:25|ISTEP 8. 6 - proc_fbc_eff_config 44.24190|1970/01/01 00:06:25|ISTEP 8. 7 - proc_eff_config_links 44.32879|1970/01/01 00:06:25|ISTEP 8. 8 - proc_attr_update 44.39696|1970/01/01 00:06:25|ISTEP 8. 9 - proc_chiplet_fabric_scominit 45.62604|1970/01/01 00:06:26|ISTEP 8.10 - host_set_voltages 45.83907|1970/01/01 00:06:26|ISTEP 8.11 - proc_io_scominit 46.96895|1970/01/01 00:06:27|ISTEP 8.12 - proc_load_ioppe 49.55555|1970/01/01 00:06:30|ISTEP 8.13 - proc_iohs_enable_ridi 49.60738|1970/01/01 00:06:30|ISTEP 8.14 - proc_init_ioppe 53.56197|1970/01/01 00:06:34|ISTEP 9. 1 - proc_io_dccal_done 53.87013|1970/01/01 00:06:34|ISTEP 9. 2 - fabric_dl_pre_trainadv 53.88946|1970/01/01 00:06:34|ISTEP 9. 3 - fabric_dl_setup_training 53.93503|1970/01/01 00:06:34|ISTEP 9. 4 - proc_fabric_link_layer 54.00329|1970/01/01 00:06:34|ISTEP 9. 5 - fabric_dl_post_trainadv 54.02488|1970/01/01 00:06:34|ISTEP 9. 6 - proc_fabric_iovalid 54.30395|1970/01/01 00:06:35|ISTEP 9. 7 - proc_fbc_eff_config_aggregate 54.34287|1970/01/01 00:06:35|ISTEP 10. 1 - proc_build_smp 55.79832|1970/01/01 00:06:36|ISTEP 10. 2 - host_sbe_update 63.22325|sbe|System Performing SBE Update for PROC 3, side 1 63.22333|sbe|System Performing SBE Update for PROC 0, side 1 63.22335|sbe|System Performing SBE Update for PROC 2, side 1 63.22337|sbe|System Performing SBE Update for PROC 1, side 1 157.31192|1970/01/01 00:08:18|ISTEP 10. 3 - host_secureboot_lockdown 161.11255|1970/01/01 00:08:21|ISTEP 10. 4 - proc_chiplet_scominit 161.74637|1970/01/01 00:08:22|ISTEP 10. 5 - proc_pau_scominit 161.84839|1970/01/01 00:08:22|ISTEP 10. 6 - proc_pcie_scominit 161.98813|1970/01/01 00:08:22|ISTEP 10. 7 - proc_scomoverride_chiplets 162.01978|1970/01/01 00:08:22|ISTEP 10. 9 - host_rng_bist 162.04578|1970/01/01 00:08:22|ISTEP 11. 1 - host_prd_hwreconfig 163.28373|1970/01/01 00:08:24|ISTEP 11. 2 - host_set_mem_volt 164.15929|1970/01/01 00:08:25|ISTEP 11. 3 - proc_ocmb_enable 166.30930|1970/01/01 00:08:27|ISTEP 11. 4 - ocmb_check_for_ready 176.84151|1970/01/01 00:08:37|ISTEP 12. 1 - mss_getecid 177.66286|1970/01/01 00:08:38|ISTEP 12. 2 - omi_attr_update 177.68424|1970/01/01 00:08:38|ISTEP 12. 3 - proc_omi_scominit 177.80148|1970/01/01 00:08:38|ISTEP 12. 4 - ocmb_omi_scominit 177.82554|1970/01/01 00:08:38|ISTEP 12. 5 - omi_pre_trainadv 178.02092|1970/01/01 00:08:38|ISTEP 12. 6 - omi_setup 187.08408|1970/01/01 00:08:47|ISTEP 12. 7 - omi_io_run_training 187.96768|1970/01/01 00:08:48|ISTEP 12. 8 - omi_train_check 188.69434|1970/01/01 00:08:49|ISTEP 12. 9 - omi_post_trainadv 189.25617|1970/01/01 00:08:50|ISTEP 12.10 - host_attnlisten_memb 189.46883|1970/01/01 00:08:50|ISTEP 12.11 - host_omi_init 190.26814|1970/01/01 00:08:51|ISTEP 12.12 - update_omi_firmware 190.99071|1970/01/01 00:08:51|ISTEP 13. 1 - mss_scominit 191.68042|1970/01/01 00:08:52|ISTEP 13. 2 - mss_draminit 192.24401|1970/01/01 00:08:53|ISTEP 13. 3 - mss_draminit_mc 193.37218|1970/01/01 00:08:54|ISTEP 14. 1 - mss_memdiag 196.86365|1970/01/01 00:08:57|ISTEP 14. 2 - mss_thermal_init 197.64199|1970/01/01 00:08:58|ISTEP 14. 3 - proc_load_iop_xram 198.24088|1970/01/01 00:08:59|ISTEP 14. 4 - proc_pcie_config 198.31376|1970/01/01 00:08:59|ISTEP 14. 5 - proc_setup_mmio_bars 198.35547|1970/01/01 00:08:59|ISTEP 14. 6 - host_secure_rng 198.39247|1970/01/01 00:08:59|ISTEP 14. 7 - host_enable_memory_encryption 198.71736|1970/01/01 00:08:59|ISTEP 14. 8 - proc_exit_cache_contained 199.04684|================================================ 199.06696|Error reported by fapi2 (0x3300) EID 0x90000A5B 199.08138| Invalid attribute read request 199.08139| ModuleId 0x0c fapi2::MOD_FAPI2_GET_TARGETING_ATTR 199.08139| ReasonCode 0x332a RC_INVALID_ATTRIBUTE 199.08139| UserData1 FAPI2 Target Type : 0xffffffff00010000 199.09749| UserData2 HB Target HUID : 0x00000000055ddfeb 199.09749|------------------------------------------------ 199.09750| p10_exit_cache_contained 199.09750|------------------------------------------------ 199.09750| Callout type : Procedure Callout 199.09751| Procedure : EPUB_PRC_HB_CODE 199.09751| Priority : SRCI_PRIORITY_LOW 199.09751|------------------------------------------------ 199.09752| Hostboot Build ID: hostboot-p10-7e966f4/hbicore.bin 199.09752|================================================ 199.47717|System shutting down with error status 0x90000A5B 200.04092|================================================ 200.04092|Error reported by istep (0x1700) EID 0x90000A5C 200.06103| IStep failed, Check other log(s) with the same PLID for reason. 200.06103| ModuleId 0x01 MOD_REPORTING_ERROR 200.06104| ReasonCode 0x1703 RC_FAILURE 200.06104| UserData1 eid of first error : 0x90000a5b0000332a 200.06104| UserData2 Reason code of first error : 0x0000000100000e08 200.06105|------------------------------------------------ 200.06105| proc_exit_cache_contained 200.06105|------------------------------------------------ 200.06106| Callout type : Procedure Callout 200.06106| Procedure : EPUB_PRC_HB_CODE 200.06106| Priority : SRCI_PRIORITY_LOW 200.06107|------------------------------------------------ 200.06107| Hostboot Build ID: hostboot-p10-7e966f4/hbicore.bin 200.06107|================================================ 200.13360|devtree|Syncing to BMC

lili-lilili commented 2 years ago

Here is the log with trace:

210.15929|ATTN_FAST|I>...intr task woke up 210.15932|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.15933|INTR|IntrRp::msgHandler(): LSI Interrupt Detected 210.15934|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.15934|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.15935|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.15936|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.15938|INTR|IntrRp::completeInterruptProcessing() Removing pending interrupt for pir: 0x0,interrupt type: 4 210.15992|PRDF|I>PRD Signature 00050000 34EB0000 210.15993|PRDF|I>PRD Signature 00050000 34EB000D 210.15995|PRDF|I>PRD Signature 00050000 34EB000D 210.16033|INTR|IntrRp::completeInterruptProcessing() Removing pending interrupt for pir: 0x400,interrupt type: 4 210.16037|PRDF|I>PRD Signature 00440001 00000000 210.16067|PRDF|I>PRD Signature 00440001 65CC0000 210.16069|PRDF|I>PRD Signature 00440001 65CC0007 210.16070|PRDF|I>PRD Signature 00440001 65CC0007 210.16074|PRDF|I>PRD Signature 00490003 00000000 210.16096|PRDF|I>PRD Signature 00490003 E6E60000 210.16113|PRDF|I>PRD Signature 00490003 E6E60000 210.16114|PRDF|I>PRD Signature 00490003 E6E60000 210.16124|PRDF|I>PRD Signature 00490003 F0C60000 210.16127|PRDF|I>PRD Signature 00490003 F0C60006 210.16129|PRDF|I>PRD Signature 00490003 F0C60006 210.16135|PRDF|I>PRD Signature 004B0007 00000000 210.16153|PRDF|I>PRD Signature 004B0007 1CD40000 210.16155|PRDF|I>PRD Signature 004B0007 1CD40005 210.16156|PRDF|I>PRD Signature 004B0007 1CD40005 210.16252|PRDF|I>PRD Signature 004B0007 59930000 210.16255|PRDF|I>PRD Signature 004B0007 5993000A 210.16256|PRDF|I>PRD Signature 004B0007 5993000A 210.16257|MDIA_FAST|I>sm: processMaintCommandEvent 210.16258|MDIA_FAST|I>sm: processing event for: 0x004b0007, target: 0x004b0007, type: 2 210.16259|PLDM|I> >>resetWatchdogTimer 210.18625|PLDM|I> <<resetWatchdogTimer 210.18640|INITSVC|sendProgressCodeToBmc(): sending CC009381 progress code 210.19038|PRDF|[MemTdCtlr::defaultStep] The TD command made it to the end of memory on chip: 0x004b0007 210.19047|MDIA_FAST|I>sm: processMaintCommandEvent 210.19048|MDIA_FAST|I>sm: processing event for: 0x004b0007, target: 0x004b0007, type: 0 210.19049|PLDM|I> >>resetWatchdogTimer 210.19860|PLDM|I> <<resetWatchdogTimer 210.19873|INITSVC|sendProgressCodeToBmc(): sending CC009381 progress code 210.20384|MDIA_FAST|I>sm: dispatching work item 13 for: 0x004b0007, priority: 2, 210.20437|MDIA_FAST|I>sm: executing work item 13 for: 0x004b0007 210.20445|MDIA_FAST|I>sm: dispatching work item 15 for: 0x004b0007, priority: 1, 210.20446|MDIA_FAST|I>sm: executing work item 15 for: 0x004b0007 210.20446|ERRL|E>Error deleted without commit : PLID=90000D89, EID=90000D89 210.20447|MDIA_FAST|I>Running mss::unmask::after_memdiags HWP call on OCMB target HUID 0x004B0007. 210.20450|PRDF|<<PRDF::main() 210.20455|ATTN_SLOW|I>callPrd with Attn Count of 1 210.20456|PRDF|>>PRDF::main() Global attnType=0005 210.20457|INTR|IntrRp::msgHandler(): LSI Interrupt Detected 210.20458|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.20458|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.20459|INTR|IntrRp::msgHandler() lsiIntStatus 0x0800000000000000 210.20460|INTR|IntrRp::msgHandler() External Interrupt found for pir: 0x400,interrupt type: 4 210.20461|MDIA_FAST|I>SUCCESS: mss::unmask::after_memdiags HWP call on OCMB target HUID 0x004b0007. 210.20463|MDIA_FAST|I>Running mss::reset_reorder_queue_settings HWP call on OCMB target HUID 0x004B0007. 210.20471|ERRL|E>Error created : PLID=90000D8A, EID=90000D8A, RC=E580, Mod=0B, Userdata=0000000000000000 0000000000000000, Sev=RECOVERED 210.20471|MDIA_FAST|I>SUCCESS: mss::reset_reorder_queue_settings HWP call on OCMB target HUID 0x004b0007. 210.20473|PRDF|I>PRD Signature 00000000 00000000 210.20473|PRDF|I>PRD Signature 00050002 00000000 210.20484|ATTN_FAST|I>...intr task woke up 210.20561|INTR|IntrRp::msgHandler() lsiIntStatus 0x0000000000000000 210.20572|INTR|IntrRp::completeInterruptProcessing() Removing pending interrupt for pir: 0x400,interrupt type: 4 210.20614|PRDF|I>PRD Signature 00050002 34EB0000 210.20615|PRDF|I>PRD Signature 00050002 34EB000D 210.20616|PRDF|I>PRD Signature 00050002 34EB000D 210.20636|PRDF|I>PRD Signature 00440009 00000000 210.20663|PRDF|I>PRD Signature 00440009 65CC0000 210.20665|PRDF|I>PRD Signature 00440009 65CC0007 210.20666|PRDF|I>PRD Signature 00440009 65CC0007 210.20670|PRDF|I>PRD Signature 00490013 00000000 210.20689|PRDF|I>PRD Signature 00490013 E6E60000 210.20704|PRDF|I>PRD Signature 00490013 E6E60000 210.20705|PRDF|I>PRD Signature 00490013 E6E60000 210.20714|PRDF|I>PRD Signature 00490013 F0C60000 210.20717|PRDF|I>PRD Signature 00490013 F0C60006 210.20718|PRDF|I>PRD Signature 00490013 F0C60006 210.20724|PRDF|I>PRD Signature 004B0027 00000000 210.20740|PRDF|I>PRD Signature 004B0027 1CD40000 210.20741|PRDF|I>PRD Signature 004B0027 1CD40005 210.20742|PRDF|I>PRD Signature 004B0027 1CD40005 210.20830|PRDF|I>PRD Signature 004B0027 59930000 210.20833|PRDF|I>PRD Signature 004B0027 5993000A 210.20834|PRDF|I>PRD Signature 004B0027 5993000A 210.20835|MDIA_FAST|I>sm: processMaintCommandEvent 210.20836|MDIA_FAST|I>sm: processing event for: 0x004b0027, target: 0x004b0027, type: 2 210.20837|PLDM|I> >>resetWatchdogTimer 210.25212|PLDM|I> <<resetWatchdogTimer 210.25227|INITSVC|sendProgressCodeToBmc(): sending CC009381 progress code 210.25641|PRDF|[MemTdCtlr::defaultStep] The TD command made it to the end of memory on chip: 0x004b0027 210.25650|MDIA_FAST|I>sm: processMaintCommandEvent 210.25651|MDIA_FAST|I>sm: processing event for: 0x004b0027, target: 0x004b0027, type: 0 210.25651|PLDM|I> >>resetWatchdogTimer 210.26359|PLDM|I> <<resetWatchdogTimer 210.26372|INITSVC|sendProgressCodeToBmc(): sending CC009381 progress code 210.26878|MDIA_FAST|I>sm: dispatching work item 13 for: 0x004b0027, priority: 2, 210.26934|ERRL|E>Error deleted without commit : PLID=90000D8A, EID=90000D8A 210.26938|PRDF|<<PRDF::main() 210.26940|MDIA_FAST|I>sm: executing work item 13 for: 0x004b0027 210.26941|ATTN_SLOW|I>callPrd with Attn Count of 0 210.26943|ATTN_FAST|I>...prd task woke up, shutdown: 0, pending: 1 210.26946|ATTN_SLOW|I>callPrd with Attn Count of 0 210.26947|MDIA_FAST|I>sm: dispatching work item 15 for: 0x004b0027, priority: 1, 210.26948|MDIA_FAST|I>sm: executing work item 15 for: 0x004b0027 210.26949|MDIA_FAST|I>Running mss::unmask::after_memdiags HWP call on OCMB target HUID 0x004B0027. 210.26962|MDIA_FAST|I>SUCCESS: mss::unmask::after_memdiags HWP call on OCMB target HUID 0x004b0027. 210.26963|MDIA_FAST|I>Running mss::reset_reorder_queue_settings HWP call on OCMB target HUID 0x004B0027. 210.26971|MDIA_FAST|I>SUCCESS: mss::reset_reorder_queue_settings HWP call on OCMB target HUID 0x004b0027. 210.26971|MDIA_FAST|I>sm: all workflows finished 210.26973|MDIA_FAST|I>sm: shutting down... 210.26973|MDIA_FAST|I>Stopping threadPool... 210.26982|MDIA_FAST|I>Stopping monitor... 210.27644|MDIA_FAST|I>cm: CommandMonitor will be shutdown 210.27745|MDIA_FAST|I>sm: ...shutdown complete 210.27746|ATTN_SLOW|I>shutting down... 210.27822|INTR|INTR remove registration of interrupt type = 0x4 210.27823|INTR|I>Removing interrupt listener: 4 210.27824|ATTN_FAST|I>...intr task woke up 210.27880|ATTN_FAST|I>interrupt task shutting down 210.27883|ATTN_FAST|I>...prd task woke up, shutdown: 1, pending: 0 210.27884|ATTN_FAST|I>prd task shutting down 210.27980|ATTN_SLOW|I>..shutdown complete 210.27981|ISTEPS_TRACE|<<call_mss_memdiag, returning success 210.28207|ATTN_SLOW|I>checkForIplAttentions: 4 chips 210.28210|ATTN_SLOW|I>handleAttns 0 active( PRD) 210.28213|ATTN_SLOW|I>handleAttns 0 active( PRD) 210.28215|ATTN_SLOW|I>handleAttns 0 active( PRD) 210.28218|ATTN_SLOW|I>handleAttns 0 active( PRD) 210.28239|ERRL|I>Got an error log Msg - Type: 0x00000039 210.28240|ERRL|I>Flush message received 210.28241|INITSVC|<<doIstep: step 14, substep 1 210.28242|INITSVC|>>doIstep: step 14, substep 2, task mss_thermal_init 210.28244|1970/01/01 00:10:24|ISTEP 14. 2 - mss_thermal_init 210.28607|PLDM|I> >>resetWatchdogTimer 210.29220|PLDM|I> <<resetWatchdogTimer 210.29234|INITSVC|sendProgressCodeToBmc(): sending CC009382 progress code 210.31629|ISTEPS_TRACE|>>call_mss_thermal_init 210.31705|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0000, chipId 0x000060D2 210.34753|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p00 Start thermal_init 210.41759|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p00 End thermal_init 210.42411|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0000, chipId 0x000060D2 210.42412|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0001, chipId 0x000060D2 210.42413|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p01 Start thermal_init 210.46441|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p01 End thermal_init 210.46443|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0001, chipId 0x000060D2 210.46444|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0002, chipId 0x000060D2 210.46445|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p02 Start thermal_init 210.50473|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p02 End thermal_init 210.50475|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0002, chipId 0x000060D2 210.50476|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0003, chipId 0x000060D2 210.50477|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p03 Start thermal_init 210.54505|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p03 End thermal_init 210.54507|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0003, chipId 0x000060D2 210.54508|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0004, chipId 0x000060D2 210.54509|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p04 Start thermal_init 210.58536|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p04 End thermal_init 210.58538|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0004, chipId 0x000060D2 210.58539|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0005, chipId 0x000060D2 210.58540|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p05 Start thermal_init 210.62568|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p05 End thermal_init 210.62570|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0005, chipId 0x000060D2 210.62571|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0006, chipId 0x000060D2 210.62572|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p06 Start thermal_init 210.66601|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p06 End thermal_init 210.66602|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0006, chipId 0x000060D2 210.66604|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0007, chipId 0x000060D2 210.66604|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p07 Start thermal_init 210.70633|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p07 End thermal_init 210.70634|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0007, chipId 0x000060D2 210.70636|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0020, chipId 0x000060D2 210.70637|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p32 Start thermal_init 210.74665|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p32 End thermal_init 210.74666|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0020, chipId 0x000060D2 210.74668|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0021, chipId 0x000060D2 210.74669|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p33 Start thermal_init 210.78696|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p33 End thermal_init 210.78697|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0021, chipId 0x000060D2 210.78699|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0022, chipId 0x000060D2 210.78700|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p34 Start thermal_init 210.82728|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p34 End thermal_init 210.82730|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0022, chipId 0x000060D2 210.82731|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0023, chipId 0x000060D2 210.82732|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p35 Start thermal_init 210.86762|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p35 End thermal_init 210.86764|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0023, chipId 0x000060D2 210.86766|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0024, chipId 0x000060D2 210.86766|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p36 Start thermal_init 210.90796|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p36 End thermal_init 210.90797|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0024, chipId 0x000060D2 210.90799|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0025, chipId 0x000060D2 210.90799|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p37 Start thermal_init 210.94828|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p37 End thermal_init 210.94830|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0025, chipId 0x000060D2 210.94831|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0026, chipId 0x000060D2 210.94832|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p38 Start thermal_init 210.98860|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p38 End thermal_init 210.98861|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0026, chipId 0x000060D2 210.98862|ISTEPS_TRACE|I>Running exp_mss_thermal_init HWP call on Explorer chip, target HUID 0x004B0027, chipId 0x000060D2 210.98863|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p39 Start thermal_init 211.02889|FAPI|exp_mss_thermal_init.C: ocmb:k0:n0:s0:p39 End thermal_init 211.02891|ISTEPS_TRACE|I>SUCCESS: exp_mss_thermal_init HWP on Explorer chip, target HUID 0x004B0027, chipId 0x000060D2 211.02970|ISTEPS_TRACE|I>Running p10_throttle_sync HWP on PROC target HUID 0x00050000 211.09225|ISTEPS_TRACE|I>SUCCESS : p10_throttle_sync HWP call on PROC target HUID 0x00050000 211.09226|ISTEPS_TRACE|I>Running p10_throttle_sync HWP on PROC target HUID 0x00050001 211.10520|FAPI|p10_throttle_sync.C: No MIs found under pu:k0:n0:s0:p01 -- skipping 211.10522|ISTEPS_TRACE|I>SUCCESS : p10_throttle_sync HWP call on PROC target HUID 0x00050001 211.10522|ISTEPS_TRACE|I>Running p10_throttle_sync HWP on PROC target HUID 0x00050002 211.10671|ISTEPS_TRACE|I>SUCCESS : p10_throttle_sync HWP call on PROC target HUID 0x00050002 211.10672|ISTEPS_TRACE|I>Running p10_throttle_sync HWP on PROC target HUID 0x00050003 211.10692|FAPI|p10_throttle_sync.C: No MIs found under pu:k0:n0:s0:p03 -- skipping 211.10693|ISTEPS_TRACE|I>SUCCESS : p10_throttle_sync HWP call on PROC target HUID 0x00050003 211.10693|ISTEPS_TRACE|<<call_mss_thermal_init, returning success 211.11148|ATTN_SLOW|I>checkForIplAttentions: 4 chips 211.11152|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.11154|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.11156|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.11158|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.11178|ERRL|I>Got an error log Msg - Type: 0x00000039 211.11179|ERRL|I>Flush message received 211.11181|INITSVC|<<doIstep: step 14, substep 2 211.11181|INITSVC|>>doIstep: step 14, substep 3, task proc_load_iop_xram 211.11183|1970/01/01 00:10:24|ISTEP 14. 3 - proc_load_iop_xram 211.11566|PLDM|I> >>resetWatchdogTimer 211.12218|PLDM|I> <<resetWatchdogTimer 211.12232|INITSVC|sendProgressCodeToBmc(): sending CC009383 progress code 211.12978|ISTEPS_TRACE|call_proc_load_iop_xram entry 211.12980|PNOR|loadSecureSection i_section = 4 (HCODE) 211.12984|PNOR|PNOR::verifySections> called on secure section HCODE 211.12985|PNOR|PNOR::verifySections> hasHashTable FOUND l_info.name=HCODE 211.12989|PNOR|SPnorRP::verifySections section start address in temp space l_tempAddr=0x0000000144000000, section start address in unsecured space l_unsecureAddr=0x0000000084000000, l_info.size = 0x0000000000084000, l_info.secureProtectedPayloadSize = 0x0000000000002000, 211.14146|PNOR|SPnorRP::verifySections> Prefix hdr flags:0x40000000, secure_version:0x0 211.14147|PNOR|SPnorRP::verifySections Total container size = 0x0000000000084000 211.14148|PNOR|SPnorRP::verifySections Total Protected size with Header = 0x0000000000003000 211.14152|ISTEPS_TRACE|HCODE addr = 0x0x204003000 211.14231|ISTEPS_TRACE|call_proc_load_iop_xram target HUID 00050000 211.16936|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Calling p9_xip_get_section (HW image) 211.18381|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: i_hw_image 0x204003000, l_xramImgPtr 0x204072ee0, l_xramFwDataPtr 0x2040731d0, l_xramFwSize 32768 211.18383|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Write XRAM FW data: Ptr 0x2040731d0, size 32768. 211.19911|FAPI|p10_iop_xram_utils.C: Target: pu:k0:n0:s0:p00 211.21975|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 0. 211.21976|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.21977|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 1 .. executing hwp p10_putsram on host 211.24131|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 0. 211.31744|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.31746|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 0. 211.31747|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 1. 211.31748|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.31749|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 1 .. executing hwp p10_putsram on host 211.31755|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 1. 211.33038|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.33040|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 1. 211.33050|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 0. 211.33051|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.33052|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 1 .. executing hwp p10_putsram on host 211.33058|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 0. 211.34341|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.34342|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 0. 211.34343|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 1. 211.34344|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.34346|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 1 .. executing hwp p10_putsram on host 211.34410|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 1. 211.35652|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.35653|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 1. 211.39002|ISTEPS_TRACE|SUCCESS : proc_load_iop_xram 211.39003|ISTEPS_TRACE|call_proc_load_iop_xram target HUID 00050001 211.39010|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Calling p9_xip_get_section (HW image) 211.39012|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: i_hw_image 0x204003000, l_xramImgPtr 0x204072ee0, l_xramFwDataPtr 0x2040731d0, l_xramFwSize 32768 211.39035|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Write XRAM FW data: Ptr 0x2040731d0, size 32768. 211.39036|FAPI|p10_iop_xram_utils.C: Target: pu:k0:n0:s0:p01 211.39125|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 0. 211.39126|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.39127|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.39133|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 0. 211.40419|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.40421|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 0. 211.40422|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 1. 211.40423|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.40424|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.40431|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 1. 211.41728|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.41729|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 1. 211.41738|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 0. 211.41739|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.41740|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.41746|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 0. 211.43021|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.43022|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 0. 211.43023|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 1. 211.43024|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.43026|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.43032|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 1. 211.44287|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.44289|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 1. 211.46598|ISTEPS_TRACE|SUCCESS : proc_load_iop_xram 211.46598|ISTEPS_TRACE|call_proc_load_iop_xram target HUID 00050002 211.46606|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Calling p9_xip_get_section (HW image) 211.46614|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: i_hw_image 0x204003000, l_xramImgPtr 0x204072ee0, l_xramFwDataPtr 0x2040731d0, l_xramFwSize 32768 211.46616|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Write XRAM FW data: Ptr 0x2040731d0, size 32768. 211.46616|FAPI|p10_iop_xram_utils.C: Target: pu:k0:n0:s0:p02 211.46708|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 0. 211.46709|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.46710|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.46717|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 0. 211.47998|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.48000|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 0. 211.48001|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 1. 211.48002|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.48003|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.48009|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 1. 211.49260|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.49261|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 1. 211.49270|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 0. 211.49271|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.49272|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.49278|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 0. 211.50539|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.50540|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 0. 211.50541|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 1. 211.50542|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.50543|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.50549|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 1. 211.51800|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.51801|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 1. 211.52816|ISTEPS_TRACE|SUCCESS : proc_load_iop_xram 211.52817|ISTEPS_TRACE|call_proc_load_iop_xram target HUID 00050003 211.52824|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Calling p9_xip_get_section (HW image) 211.52827|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: i_hw_image 0x204003000, l_xramImgPtr 0x204072ee0, l_xramFwDataPtr 0x2040731d0, l_xramFwSize 32768 211.52832|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Write XRAM FW data: Ptr 0x2040731d0, size 32768. 211.52833|FAPI|p10_iop_xram_utils.C: Target: pu:k0:n0:s0:p03 211.52923|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 0. 211.52924|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.52925|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.52931|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 0. 211.54184|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.54185|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 0. 211.54186|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 0, phy 1. 211.54187|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.54189|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.54195|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 0, i_phy 1. 211.55453|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.55454|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 0, phy 1. 211.55463|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 0. 211.55464|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.55465|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.55471|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 0. 211.56718|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.56719|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 0. 211.56720|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Attemp to write PCIE img to XRAM: iop_top 1, phy 1. 211.56721|FAPI|p10_load_iop_xram.C: subroutine_executor: executing hwp p10_putsram via SBE chip-op 211.56723|FAPI|p10_load_iop_xram.C: subroutine_executor: isBootProc = 0 .. executing hwp p10_putsram on host 211.56729|FAPI|p10_write_xram.C: p10_write_xram: i_offset 0x0, i_bytes 32768, i_top 1, i_phy 1. 211.58008|FAPI|p10_load_iop_xram.C: subroutine_executor: SBE HWP invoking successful. 211.58009|FAPI|p10_load_iop_xram.C: p10_load_iop_xram: Successfully wrote PCIE img to XRAM: iop_top 1, phy 1. 211.59450|ISTEPS_TRACE|SUCCESS : proc_load_iop_xram 211.59450|PNOR|unloadSecureSection i_section = 4 (HCODE) 211.59458|ISTEPS_TRACE|call_proc_load_iop_xram exit 211.59657|ATTN_SLOW|I>checkForIplAttentions: 4 chips 211.59660|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.59663|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.59665|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.59668|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.59688|ERRL|I>Got an error log Msg - Type: 0x00000039 211.59689|ERRL|I>Flush message received 211.59690|INITSVC|<<doIstep: step 14, substep 3 211.59691|INITSVC|>>doIstep: step 14, substep 4, task proc_pcie_config 211.59693|1970/01/01 00:10:25|ISTEP 14. 4 - proc_pcie_config 211.60068|PLDM|I> >>resetWatchdogTimer 211.60681|PLDM|I> <<resetWatchdogTimer 211.60695|INITSVC|sendProgressCodeToBmc(): sending CC009384 progress code 211.61712|ISTEPS_TRACE|call_proc_pcie_config entry 211.61787|ISTEPS_TRACE|target HUID 00050000 211.66786|ISTEPS_TRACE|SUCCESS : proc_pcie_config 211.66786|ISTEPS_TRACE|target HUID 00050001 211.66964|ISTEPS_TRACE|SUCCESS : proc_pcie_config 211.66964|ISTEPS_TRACE|target HUID 00050002 211.67092|ISTEPS_TRACE|SUCCESS : proc_pcie_config 211.67092|ISTEPS_TRACE|target HUID 00050003 211.67234|ISTEPS_TRACE|SUCCESS : proc_pcie_config 211.67236|ISTEPS_TRACE|call_proc_pcie_config exit 211.67385|ATTN_SLOW|I>checkForIplAttentions: 4 chips 211.67388|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.67390|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.67392|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.67394|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.67413|ERRL|I>Got an error log Msg - Type: 0x00000039 211.67414|ERRL|I>Flush message received 211.67415|INITSVC|<<doIstep: step 14, substep 4 211.67416|INITSVC|>>doIstep: step 14, substep 5, task proc_setup_mmio_bars 211.67417|1970/01/01 00:10:25|ISTEP 14. 5 - proc_setup_mmio_bars 211.67804|PLDM|I> >>resetWatchdogTimer 211.68418|PLDM|I> <<resetWatchdogTimer 211.68431|INITSVC|sendProgressCodeToBmc(): sending CC009385 progress code 211.69216|ISTEPS_TRACE|call p10_setup_mmio_bars: Target HUID 00050000 211.70820|FAPI|p10_setup_mmio_bars.C: Start 211.73424|FAPI|p10_setup_mmio_bars.C: End 211.73425|ISTEPS_TRACE|SUCCESS: p10_setup_mmio_bars (chip HUID 0x00050000) 211.73425|ISTEPS_TRACE|call p10_setup_mmio_bars: Target HUID 00050001 211.73426|FAPI|p10_setup_mmio_bars.C: Start 211.73446|FAPI|p10_setup_mmio_bars.C: End 211.73447|ISTEPS_TRACE|SUCCESS: p10_setup_mmio_bars (chip HUID 0x00050001) 211.73447|ISTEPS_TRACE|call p10_setup_mmio_bars: Target HUID 00050002 211.73448|FAPI|p10_setup_mmio_bars.C: Start 211.73468|FAPI|p10_setup_mmio_bars.C: End 211.73469|ISTEPS_TRACE|SUCCESS: p10_setup_mmio_bars (chip HUID 0x00050002) 211.73469|ISTEPS_TRACE|call p10_setup_mmio_bars: Target HUID 00050003 211.73470|FAPI|p10_setup_mmio_bars.C: Start 211.73490|FAPI|p10_setup_mmio_bars.C: End 211.73491|ISTEPS_TRACE|SUCCESS: p10_setup_mmio_bars (chip HUID 0x00050003) 211.74110|ATTN_SLOW|I>checkForIplAttentions: 4 chips 211.74113|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.74115|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.74117|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.74119|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.74139|ERRL|I>Got an error log Msg - Type: 0x00000039 211.74139|ERRL|I>Flush message received 211.74140|INITSVC|<<doIstep: step 14, substep 5 211.74141|INITSVC|>>doIstep: step 14, substep 6, task host_secure_rng 211.74142|1970/01/01 00:10:25|ISTEP 14. 6 - host_secure_rng 211.74504|PLDM|I> >>resetWatchdogTimer 211.75218|PLDM|I> <<resetWatchdogTimer 211.75232|INITSVC|sendProgressCodeToBmc(): sending CC009386 progress code 211.76248|ISTEPS_TRACE|call_host_secure_rng entry 211.79332|FAPI|p10_rng_init_phase2.C: Start 211.80106|FAPI|p10_rng_init_phase2.C: End 211.80123|FAPI|p10_rng_init_phase2.C: Start 211.80127|FAPI|p10_rng_init_phase2.C: End 211.80144|FAPI|p10_rng_init_phase2.C: Start 211.80147|FAPI|p10_rng_init_phase2.C: End 211.80165|FAPI|p10_rng_init_phase2.C: Start 211.80168|FAPI|p10_rng_init_phase2.C: End 211.80905|ISTEPS_TRACE|call_host_secure_rng exit 211.81023|ATTN_SLOW|I>checkForIplAttentions: 4 chips 211.81026|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.81028|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.81030|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.81032|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.81051|ERRL|I>Got an error log Msg - Type: 0x00000039 211.81052|ERRL|I>Flush message received 211.81053|INITSVC|<<doIstep: step 14, substep 6 211.81054|INITSVC|>>doIstep: step 14, substep 7, task host_enable_memory_encryption 211.81055|1970/01/01 00:10:25|ISTEP 14. 7 - host_enable_memory_encryption 211.81421|PLDM|I> >>resetWatchdogTimer 211.82319|PLDM|I> <<resetWatchdogTimer 211.82332|INITSVC|sendProgressCodeToBmc(): sending CC009387 progress code 211.83148|ISTEPS_TRACE|>>call_host_enable_memory_encryption 211.83238|ISTEPS_TRACE|Memory encryption: Initializing keys for a total of 0 MCCs on node 0x00020000 211.83392|TARG|getBootCore: found 16 cores on boot proc,l_bootCore PIR:0x0 211.83392|TARG|found boot core: 0x0, PIR=0x0 : 211.85745|TARG|EntityPath ~[0x0000] 50687973 6963616c 3a2f5379 73302f4e Physical:/Sys0/N ~[0x0010] 6f646530 2f50726f 63302f45 51302f46 ode0/Proc0/EQ0/F ~[0x0020] 43302f43 6f726530 C0/Core0 211.87173|FAPI|p10_ncu_enable_darn.C: >>p10_ncu_enable_darn 211.87177|FAPI|p10_ncu_enable_darn.C: <<p10_ncu_enable_darn 211.87245|ERRL|E>Error created : PLID=90000D8B, EID=90000D8B, RC=1761, Mod=36, Userdata=0000000000000000 0000000000000000, Sev=INFORMATIONAL 211.87248|ERRL|commitErrLog() called by 1700 for eid=90000d8b, Reasoncode=1761, Sev=INFORMATIONAL 211.87249|ERRL|>>ErrlManager::sendErrlogToMessageQueue 211.87250|ERRL|I>Send an error log to message queue to commit. eid=90000D8B 211.87250|ERRL|<<ErrlManager::sendErrlogToMessageQueue 211.87251|ISTEPS_TRACE|Memory encryption: setup_memory_crypto_keys succeeded 211.87278|ERRL|I>Got an error log Msg - Type: 0x00000030 211.87280|ERRL|>>saveErrLogToPnor eid=90000d8b 211.87327|ISTEPS_TRACE|<<call_host_enable_memory_encryption 211.87854|ATTN_SLOW|I>checkForIplAttentions: 4 chips 211.87895|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.87897|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.87899|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.87902|ATTN_SLOW|I>handleAttns 0 active( PRD) 211.89100|ERRL|I>saveErrLogToPnor: 1012 bytes flattened into 0x89013000, slot 19 211.89711|ERRL|<<saveErrLogToPnor returning true 211.89713|ERRL|>>sendErrLogToBmc errlogId 0x90000D8B, i_isPrevBootErr 0 211.91965|ERRL|>>ackErrLogInPnor(90000d8b) 212.07274|ERRL|<<ackErrLogInPnor returning true 212.07275|ERRL|<<sendErrLogToBmc errlogId 0x90000D8B successfully sent 212.07276|ERRL|I>Got an error log Msg - Type: 0x00000039 212.07276|ERRL|I>Flush message received 212.07278|INITSVC|<<doIstep: step 14, substep 7 212.07278|INITSVC|>>doIstep: step 14, substep 8, task proc_exit_cache_contained 212.07279|1970/01/01 00:10:25|ISTEP 14. 8 - proc_exit_cache_contained 212.07570|PLDM|I> >>resetWatchdogTimer 212.08385|PLDM|I> <<resetWatchdogTimer 212.08399|INITSVC|sendProgressCodeToBmc(): sending CC009388 progress code 212.11264|HWAS_I|I>hwas.C: checkMinimumHardware entry 212.12034|HWAS_I|I>hwas.C: checkMinimumHardware: i_nodeOrSys 0x00010000 212.12066|HWAS_I|I>hwas.C: checkMinimumHardware: 8 functional and functionalOverride non-ECO FCs found 212.12067|HWAS_I|I>hwas.C: checkMinimumHardware: enough_cores_to_boot = TRUE 212.12068|HWAS_I|I>hwas.C: checkMinimumHardware: 7 valid cache FCs >= 2 needed : TRUE 212.13087|HWAS_I|I>hwas.C: checkMinimumHardware exit - minimum hardware available 212.13759|TARG|check_proc0_memory_config entry 212.14652|TARG|check_proc0_memory_config: 8 functional dimms behind proc0 00050000 212.15658|ISTEPS_TRACE|call_proc_exit_cache_contained:: Payload base address is 0x0000000000000000 212.15804|ISTEPS_TRACE|call_proc_exit_cache_contained:: Memory range : 0-802000000000 212.21338|MBOX|I>MailboxSp::_reclaimDmaBfrsFromFsp - Start. DmaBuffer = ~[0x0000] 00000000 00210000 00000000 e0210000 .....!.......!.. ~[0x0010] ffffffff ffffffff 00000000 00000000 ................ ~[0x0020] 00000000 00000000 00000000 00000000 ................ ~[0x0030] 00000000 00000000 ........ 212.23711|MBOX|I>MBOXSP Ignored request to suspend a disabled mailbox 212.23711|ISTEPS_TRACE|call_proc_exit_cache_contained:: draining interrupt Q 212.28721|FAPI_I|attribute_service.C: getTargetingAttr: Error from getTargetingAttrHelper for target 0x00010000 and attribute 0x55ddfeb 212.28738|ERRL|E>Error created : PLID=90000D8C, EID=90000D8C, RC=332A, Mod=0C, Userdata=FFFFFFFF00010000 00000000055DDFEB, Sev=UNRECOVERABLE 212.30483|FAPI_I|p10_exit_cache_contained.C: (fapi2::failIfPrivileged(), fapi2::Target(FAPI_SYSTEM), fapi2::checkIdType(fapi2::ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH, l_TGT1_ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH), fapi2::platAttrSvc::getTargetingAttr(FAPI_SYSTEM, (const TARGETING::ATTRIBUTE_ID)fapiToTargeting::fapi2::ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH, sizeof(l_TGT1_ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH), &(l_TGT1_ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH))) failed. 212.30484|FAPI_I|p10_exit_cache_contained.C: Error from mi initfile xscoms 212.30485|FAPI_I|p10_exit_cache_contained.C: Error from p10_exit_cache_contained_set_mc_initfile_inits (master) 212.30486|FAPI_I|plat_utils.C: rcToErrl: PLAT error: 0x02000001 212.30491|ISTEPS_TRACE|call_proc_exit_cache_contained:: failed in p10_exit_cache_contained do-while loop 212.30507|ERRL|E>Error created : PLID=90000D8D, EID=90000D8D, RC=1703, Mod=01, Userdata=90000D8C0000332A 0000000100000E08, Sev=UNRECOVERABLE 212.30509|ERRL|commitErrLog() called by 0900 for eid=90000d8c, Reasoncode=332A, Sev=UNRECOVERABLE 212.30510|ERRL|>>ErrlManager::sendErrlogToMessageQueue 212.30510|ERRL|I>Send an error log to message queue to commit. eid=90000D8C 212.30511|ERRL|<<ErrlManager::sendErrlogToMessageQueue 212.30513|MBOX|I>Mailbox function resumed 212.30513|ERRL|I>Got an error log Msg - Type: 0x00000030 212.30514|ERRL|WRN>> No callouts in elog 90000D8C 212.30514|ERRL|Adding default callout EPUB_PRC_HB_CODE 212.30515|ERRL|>>addProcedureCallout(0x55, 0x1) 212.30520|INITSVC|ERROR : task_wait_tid(0x25a). 'proc_exit_cache_contained', l_childerrl=0x1ae3598 212.30520|INITSVC|Error returned from istep : 90000D8D=1703 212.36574|================================================ 212.38920|Error reported by fapi2 (0x3300) EID 0x90000D8C 212.40464| Invalid attribute read request 212.40464| ModuleId 0x0c fapi2::MOD_FAPI2_GET_TARGETING_ATTR 212.40464| ReasonCode 0x332a RC_INVALID_ATTRIBUTE 212.40465| UserData1 FAPI2 Target Type : 0xffffffff00010000 212.42123| UserData2 HB Target HUID : 0x00000000055ddfeb 212.42124|------------------------------------------------ 212.42124| p10_exit_cache_contained 212.42125|------------------------------------------------ 212.42125| Callout type : Procedure Callout 212.42125| Procedure : EPUB_PRC_HB_CODE 212.42126| Priority : SRCI_PRIORITY_LOW 212.42126|------------------------------------------------ 212.42126| Hostboot Build ID: hostboot-p10-7e966f4/hbicore.bin 212.42127|================================================ 212.42536|ERRL|>>saveErrLogToPnor eid=90000d8c 212.43419|ERRL|I>saveErrLogToPnor: 2664 bytes flattened into 0x89014000, slot 20 212.44231|ERRL|<<saveErrLogToPnor returning true 212.44236|ERRL|>>sendErrLogToBmc errlogId 0x90000D8C, i_isPrevBootErr 0 212.66831|ERRL|>>ackErrLogInPnor(90000d8c) 212.69722|PLDM|I> Handling inbound PLDM request 212.70964|PLDM|I> Invoking handler for category 2, message type 10, instance 0 212.72306|PLDM|>> handlePdrRepoChangeEventRequest 212.72306|PLDM|E> Invalid event class 0 in platform event handler 212.72323|ERRL|E>Error created : PLID=90000D8E, EID=90000D8E, RC=470B, Mod=0E, Userdata=0000000000000004 0000000000000000, Sev=INFORMATIONAL 212.72326|ERRL|>>addProcedureCallout(0x4, 0x6) 212.72327|ERRL|>>addProcedureCallout(0x55, 0x5) 212.72329|ERRL|commitErrLog() called by 4700 for eid=90000d8e, Reasoncode=470B, Sev=INFORMATIONAL 212.72329|ERRL|>>ErrlManager::sendErrlogToMessageQueue 212.72330|ERRL|I>Send an error log to message queue to commit. eid=90000D8E 212.72330|ERRL|<<ErrlManager::sendErrlogToMessageQueue 212.73627|PLDM|<< handlePdrRepoChangeEventRequest 212.85543|ERRL|<<ackErrLogInPnor returning true 212.85545|ERRL|<<sendErrLogToBmc errlogId 0x90000D8C successfully sent 212.85546|HWAS_I|I>hwasCallout.C: Procedure callout; proc 0x55 priority 0x1 212.85548|ERRL|I>Got an error log Msg - Type: 0x00000039 212.85549|ERRL|I>Flush message received 212.85550|INITSVC|<<doIstep: step 14, substep 8 212.85551|INITSVC|E>executeAllISteps: IStep Error on 14:8 212.85551|INITSVC|<<IStepDispatcher::executeAllISteps() 212.85553|INITSVC|ERROR: Failed executing all isteps, see 90000d8d for details 212.85555|INITSVC|IStepDispatcher finished. 212.85558|ERRL|I>Got an error log Msg - Type: 0x00000030 212.85561|ERRL|>>saveErrLogToPnor eid=90000d8e 212.86314|ERRL|I>saveErrLogToPnor: 1840 bytes flattened into 0x89015000, slot 21 212.87734|ERRL|<<saveErrLogToPnor returning true 212.87736|ERRL|>>sendErrLogToBmc errlogId 0x90000D8E, i_isPrevBootErr 0 212.90522|ERRL|>>ackErrLogInPnor(90000d8e) 212.91397|INITSVC|ERROR: task libistepdisp.so returned errlog=0x1ae3598 212.91398|INITSVC|ExtInitSvc: ERROR: return to initsvc with errlog 0x1ae3598 212.91425|INITSVC|ERROR: dispatching task, errorlog=0x0x1ae3598 212.91425|INITSVC|InitService: Committing errorlog 0x1ae3598 212.91426|ERRL|commitErrLog() called by 0500 for eid=90000d8d, Reasoncode=1703, Sev=UNRECOVERABLE 212.91427|ERRL|>>ErrlManager::sendErrlogToMessageQueue 212.91428|ERRL|I>Send an error log to message queue to commit. eid=90000D8D 212.91428|ERRL|<<ErrlManager::sendErrlogToMessageQueue 212.91429|INITSVC|InitService finished, shutdown = 0x90000d8c. 212.91429|INITSVC|_setShutdownStatus(i_status=0x0000000090000D8C) 212.91430|INITSVC|_doShutdown(i_status=0000000090000D8C) 212.91431|System shutting down with error status 0x90000D8C 212.91984|INITSVC|_doShutdown> status=0000000090000D8C 212.91985|INITSVC|notify priority=0x0, queue=0x1fd4008, msgType=0x0, componentID=0x4700 212.91985|PLDM|I> >>Invoking PLDM shutdown events 212.92972|PLDM|>> sendSensorStateChangedEvent (target = 0x00530000, state set = 1, sensor id = 21846, offset = 0, state = 1) 212.93174|PLDM|I> Sent Sensor State Changed Event successfully 212.93174|PLDM|<< sendSensorStateChangedEvent 212.93179|PLDM|>> sendSensorStateChangedEvent (target = 0x00530002, state set = 1, sensor id = 21847, offset = 0, state = 1) 212.94062|PLDM|I> Sent Sensor State Changed Event successfully 212.94062|PLDM|<< sendSensorStateChangedEvent 212.94067|PLDM|>> sendSensorStateChangedEvent (target = 0x00530006, state set = 1, sensor id = 21848, offset = 0, state = 1) 212.95016|PLDM|I> Sent Sensor State Changed Event successfully 212.95016|PLDM|<< sendSensorStateChangedEvent 212.95021|PLDM|>> sendSensorStateChangedEvent (target = 0x00530008, state set = 1, sensor id = 21849, offset = 0, state = 1) 212.95838|PLDM|I> Sent Sensor State Changed Event successfully 212.95839|PLDM|<< sendSensorStateChangedEvent 212.95843|PLDM|>> sendSensorStateChangedEvent (target = 0x0053000a, state set = 1, sensor id = 21850, offset = 0, state = 1) 212.96694|PLDM|I> Sent Sensor State Changed Event successfully 212.96695|PLDM|<< sendSensorStateChangedEvent 212.96701|PLDM|>> sendSensorStateChangedEvent (target = 0x0053000c, state set = 1, sensor id = 21851, offset = 0, state = 1) 212.97696|PLDM|I> Sent Sensor State Changed Event successfully 212.97697|PLDM|<< sendSensorStateChangedEvent 212.97702|PLDM|>> sendSensorStateChangedEvent (target = 0x0053000d, state set = 1, sensor id = 21852, offset = 0, state = 1) 212.98550|PLDM|I> Sent Sensor State Changed Event successfully 212.98550|PLDM|<< sendSensorStateChangedEvent 212.98555|PLDM|>> sendSensorStateChangedEvent (target = 0x0053000e, state set = 1, sensor id = 21853, offset = 0, state = 1) 212.99401|PLDM|I> Sent Sensor State Changed Event successfully 212.99401|PLDM|<< sendSensorStateChangedEvent 212.99412|PLDM|>> sendSensorStateChangedEvent (target = 0x00530020, state set = 1, sensor id = 21854, offset = 0, state = 1) 213.00295|PLDM|I> Sent Sensor State Changed Event successfully 213.00295|PLDM|<< sendSensorStateChangedEvent 213.00305|PLDM|>> sendSensorStateChangedEvent (target = 0x00530022, state set = 1, sensor id = 21855, offset = 0, state = 1) 213.01153|PLDM|I> Sent Sensor State Changed Event successfully 213.01154|PLDM|<< sendSensorStateChangedEvent 213.01164|PLDM|>> sendSensorStateChangedEvent (target = 0x00530023, state set = 1, sensor id = 21856, offset = 0, state = 1) 213.02008|PLDM|I> Sent Sensor State Changed Event successfully 213.02008|PLDM|<< sendSensorStateChangedEvent 213.02019|PLDM|>> sendSensorStateChangedEvent (target = 0x00530025, state set = 1, sensor id = 21857, offset = 0, state = 1) 213.02858|PLDM|I> Sent Sensor State Changed Event successfully 213.02858|PLDM|<< sendSensorStateChangedEvent 213.02868|PLDM|>> sendSensorStateChangedEvent (target = 0x00530028, state set = 1, sensor id = 21858, offset = 0, state = 1) 213.03673|PLDM|I> Sent Sensor State Changed Event successfully 213.03673|PLDM|<< sendSensorStateChangedEvent 213.03683|PLDM|>> sendSensorStateChangedEvent (target = 0x0053002b, state set = 1, sensor id = 21859, offset = 0, state = 1) 213.04491|PLDM|I> Sent Sensor State Changed Event successfully 213.04491|PLDM|<< sendSensorStateChangedEvent 213.04501|PLDM|>> sendSensorStateChangedEvent (target = 0x0053002d, state set = 1, sensor id = 21860, offset = 0, state = 1) 213.05316|PLDM|I> Sent Sensor State Changed Event successfully 213.05316|PLDM|<< sendSensorStateChangedEvent 213.05326|PLDM|>> sendSensorStateChangedEvent (target = 0x0053002e, state set = 1, sensor id = 21861, offset = 0, state = 1) 213.06118|PLDM|I> Sent Sensor State Changed Event successfully 213.06118|PLDM|<< sendSensorStateChangedEvent 213.06122|PLDM|>> sendSensorStateChangedEvent (target = 0x00050000, state set = 1, sensor id = 21873, offset = 0, state = 1) 213.06974|PLDM|I> Sent Sensor State Changed Event successfully 213.06974|PLDM|<< sendSensorStateChangedEvent 213.06981|PLDM|>> sendSensorStateChangedEvent (target = 0x00050001, state set = 1, sensor id = 21874, offset = 0, state = 1) 213.07828|PLDM|I> Sent Sensor State Changed Event successfully 213.07828|PLDM|<< sendSensorStateChangedEvent 213.07837|PLDM|>> sendSensorStateChangedEvent (target = 0x00050002, state set = 1, sensor id = 21875, offset = 0, state = 1) 213.08687|PLDM|I> Sent Sensor State Changed Event successfully 213.08688|PLDM|<< sendSensorStateChangedEvent 213.08699|PLDM|>> sendSensorStateChangedEvent (target = 0x00050003, state set = 1, sensor id = 21876, offset = 0, state = 1) 213.09586|PLDM|I> Sent Sensor State Changed Event successfully 213.09587|PLDM|<< sendSensorStateChangedEvent 213.09589|PLDM|>> sendSensorStateChangedEvent (target = 0x00030000, state set = 1, sensor id = 21877, offset = 0, state = 1) 213.10740|PLDM|I> Sent Sensor State Changed Event successfully 213.10741|PLDM|<< sendSensorStateChangedEvent 213.10742|PLDM|>> sendSensorStateChangedEvent (target = 0x00030004, state set = 1, sensor id = 21878, offset = 0, state = 1) 213.11896|PLDM|I> Sent Sensor State Changed Event successfully 213.11897|PLDM|<< sendSensorStateChangedEvent 213.11899|PLDM|>> sendSensorStateChangedEvent (target = 0x00030008, state set = 1, sensor id = 21879, offset = 0, state = 1) 213.12554|ERRL|<<ackErrLogInPnor returning true 213.12556|ERRL|<<sendErrLogToBmc errlogId 0x90000D8E successfully sent 213.12557|ERRL|I>Got an error log Msg - Type: 0x00000030 213.12557|ERRL|WRN>> No callouts in elog 90000D8D 213.12558|ERRL|Adding default callout EPUB_PRC_HB_CODE 213.12558|ERRL|>>addProcedureCallout(0x55, 0x1) 213.13111|PLDM|I> Sent Sensor State Changed Event successfully 213.13111|PLDM|<< sendSensorStateChangedEvent 213.13113|PLDM|>> sendSensorStateChangedEvent (target = 0x0003000c, state set = 1, sensor id = 21880, offset = 0, state = 1) 213.14262|PLDM|I> Sent Sensor State Changed Event successfully 213.14263|PLDM|<< sendSensorStateChangedEvent 213.14265|PLDM|>> sendSensorStateChangedEvent (target = 0x00030010, state set = 1, sensor id = 21881, offset = 0, state = 1) 213.14948|================================================ 213.14949|Error reported by istep (0x1700) EID 0x90000D8D 213.15451|PLDM|I> Sent Sensor State Changed Event successfully 213.15451|PLDM|<< sendSensorStateChangedEvent 213.15453|PLDM|>> sendSensorStateChangedEvent (target = 0x00030014, state set = 1, sensor id = 21882, offset = 0, state = 1) 213.16631|PLDM|I> Sent Sensor State Changed Event successfully 213.16631|PLDM|<< sendSensorStateChangedEvent 213.16633|PLDM|>> sendSensorStateChangedEvent (target = 0x00030018, state set = 1, sensor id = 21883, offset = 0, state = 1) 213.17274| IStep failed, Check other log(s) with the same PLID for reason. 213.17275| ModuleId 0x01 MOD_REPORTING_ERROR 213.17275| ReasonCode 0x1703 RC_FAILURE 213.17275| UserData1 eid of first error : 0x90000d8c0000332a 213.17276| UserData2 Reason code of first error : 0x0000000100000e08 213.17276|------------------------------------------------ 213.17277| proc_exit_cache_contained 213.17277|------------------------------------------------ 213.17277| Callout type : Procedure Callout 213.17278| Procedure : EPUB_PRC_HB_CODE 213.17278| Priority : SRCI_PRIORITY_LOW 213.17278|------------------------------------------------ 213.17279| Hostboot Build ID: hostboot-p10-7e966f4/hbicore.bin 213.17279|================================================ 213.17873|ERRL|>>saveErrLogToPnor eid=90000d8d 213.17915|PLDM|I> Sent Sensor State Changed Event successfully 213.17916|PLDM|<< sendSensorStateChangedEvent 213.17918|PLDM|>> sendSensorStateChangedEvent (target = 0x0003001c, state set = 1, sensor id = 21884, offset = 0, state = 1) 213.18564|ERRL|I>saveErrLogToPnor: 840 bytes flattened into 0x89016000, slot 22 213.19071|PLDM|I> Sent Sensor State Changed Event successfully 213.19072|PLDM|<< sendSensorStateChangedEvent 213.19074|PLDM|>> sendSensorStateChangedEvent (target = 0x00030080, state set = 1, sensor id = 21885, offset = 0, state = 1) 213.19788|ERRL|<<saveErrLogToPnor returning true 213.19789|ERRL|>>sendErrLogToBmc errlogId 0x90000D8D, i_isPrevBootErr 0 213.20397|PLDM|I> Sent Sensor State Changed Event successfully 213.20397|PLDM|<< sendSensorStateChangedEvent 213.20399|PLDM|>> sendSensorStateChangedEvent (target = 0x00030084, state set = 1, sensor id = 21886, offset = 0, state = 1) 213.22420|ERRL|>>ackErrLogInPnor(90000d8d) 213.23131|PLDM|I> Sent Sensor State Changed Event successfully 213.23131|PLDM|<< sendSensorStateChangedEvent 213.23133|PLDM|>> sendSensorStateChangedEvent (target = 0x00030088, state set = 1, sensor id = 21887, offset = 0, state = 1) 213.24385|PLDM|I> Sent Sensor State Changed Event successfully 213.24386|PLDM|<< sendSensorStateChangedEvent 213.24388|PLDM|>> sendSensorStateChangedEvent (target = 0x0003008c, state set = 1, sensor id = 21888, offset = 0, state = 1) 213.25638|PLDM|I> Sent Sensor State Changed Event successfully 213.25639|PLDM|<< sendSensorStateChangedEvent 213.25641|PLDM|>> sendSensorStateChangedEvent (target = 0x00030090, state set = 1, sensor id = 21889, offset = 0, state = 1) 213.26891|PLDM|I> Sent Sensor State Changed Event successfully 213.26891|PLDM|<< sendSensorStateChangedEvent 213.26893|PLDM|>> sendSensorStateChangedEvent (target = 0x00030094, state set = 1, sensor id = 21890, offset = 0, state = 1) 213.28143|PLDM|I> Sent Sensor State Changed Event successfully 213.28144|PLDM|<< sendSensorStateChangedEvent 213.28146|PLDM|>> sendSensorStateChangedEvent (target = 0x00030098, state set = 1, sensor id = 21891, offset = 0, state = 1) 213.29398|PLDM|I> Sent Sensor State Changed Event successfully 213.29398|PLDM|<< sendSensorStateChangedEvent 213.29400|PLDM|>> sendSensorStateChangedEvent (target = 0x0003009c, state set = 1, sensor id = 21892, offset = 0, state = 1) 213.30650|PLDM|I> Sent Sensor State Changed Event successfully 213.30651|PLDM|<< sendSensorStateChangedEvent 213.30651|PLDM|I> <<Finished invoking PLDM shutdown events 213.30653|INITSVC|notify priority=0x0, queue=0x1ffe888, msgType=0x40000000, componentID=0x1500 213.30867|INITSVC|notify priority=0x10, queue=0x1e08a58, msgType=0x5, componentID=0xb00 213.36234|TARG|I>AttrRP: attrSyncTask: Received message of type = 0x00000005. 213.36235|TARG|I>AttrRP: attrSyncTask: Shutdown attribute sync. 213.36237|TARG|I>_invokeAttrSync: iv_attrSyncPrimed=1 iv_attrSyncWindowOpen=1 213.36238|TARG|I>_invokeAttrSync: Calling devtree attribute sync for BMC. 213.45911|DEVTREE|>>DEVTREE::devtreeSyncAttrs 213.45912|devtree|Syncing to BMC 213.55529|ERRL|<<ackErrLogInPnor returning true 213.55530|ERRL|<<sendErrLogToBmc errlogId 0x90000D8D successfully sent 213.55530|HWAS_I|I>hwasCallout.C: Procedure callout; proc 0x55 priority 0x1 215.29878|DEVTREE|<<DEVTREE::devtreeSyncAttrs 215.29879|TARG|>>AttrRP::updatePreservedAttrSection 215.29880|PNOR|loadSecureSection i_section = 7 (HBD) 215.62962|TARG|<<AttrRP::updatePreservedAttrSection 215.62963|INITSVC|notify priority=0x10, queue=0x1ffebf8, msgType=0x33, componentID=0x100 215.62964|ERRL|I>Got an error log Msg - Type: 0x00000033 215.62964|ERRL|I>Shutdown event received 215.62965|INITSVC|notify priority=0x10, queue=0x1fc4b28, msgType=0x4, componentID=0x2b00 215.62967|INITSVC|notify priority=0x13, queue=0x1dec378, msgType=0x5, componentID=0xf00 215.62967|INTR|Shutdown event received 215.62968|INTR|IntrRp::shutDown - Sending shutdown message to registered handlers 215.62969|SBEIO|psudd: SbePsu::msgHandler Handle Shutdown 215.62970|MBOX|I>Shutdown Message sent for IPC, ignoring. 215.62987|ERRL|I>Shutdown event processed 215.62994|INTR|Reset PSIHB Interrupt Space 215.63000|INTR|INTR: Target 00050001. PSI BRIDGE ESB BAR value: 0x0006430202000000 215.63005|INTR|INTR: Target 00050002. PSI BRIDGE ESB BAR value: 0x0006830202000000 215.63010|INTR|INTR: Target 00050003. PSI BRIDGE ESB BAR value: 0x0006c30202000000 215.63013|INTR|Reset PSIHB INTR Complete 215.63015|INTR|INTR: Target 00050000. PSI BRIDGE ESB BAR value: 0x0006030202000000 215.63018|INTR|I>INTR is shutdown 215.63019|INITSVC|notify priority=0x7f, queue=0x1fff888, msgType=0x2, componentID=0x1300 215.63862|INITSVC|notify priority=0x7f, queue=0x1ffe888, msgType=0x40000000, componentID=0x1500 215.63937|INITSVC|Begin virtual mem flush 215.64175|INITSVC|flushing virtual memory 0x101f000 215.64178|INITSVC|flushing virtual memory 0xa1000000 215.64179|INITSVC|flushing virtual memory 0xa0000000 215.64180|INITSVC|flushing virtual memory 0x9f000000 215.64180|INITSVC|flushing virtual memory 0x9e000000 215.64181|INITSVC|flushing virtual memory 0x9d000000 215.64185|INITSVC|flushing virtual memory 0x9c000000 215.64186|INITSVC|flushing virtual memory 0x9a000000 215.64186|INITSVC|flushing virtual memory 0x99000000 215.64187|INITSVC|flushing virtual memory 0x98000000 215.64187|INITSVC|flushing virtual memory 0x97000000 215.64188|INITSVC|flushing virtual memory 0x93000000 215.64188|INITSVC|flushing virtual memory 0x92000000 215.64189|INITSVC|flushing virtual memory 0x90000000 215.64189|INITSVC|flushing virtual memory 0x8f000000 215.64190|INITSVC|flushing virtual memory 0x8d000000 215.64191|INITSVC|flushing virtual memory 0x8c000000 215.64191|INITSVC|flushing virtual memory 0x89000000 215.64192|INITSVC|flushing virtual memory 0x88000000 215.64192|INITSVC|flushing virtual memory 0x87000000 215.64217|INITSVC|flushing virtual memory 0x86000000 215.64219|INITSVC|flushing virtual memory 0x85000000 215.64219|INITSVC|flushing virtual memory 0x84000000 215.64222|INITSVC|flushing virtual memory 0x83000000 215.64222|INITSVC|flushing virtual memory 0x82000000 215.64223|INITSVC|flushing virtual memory 0x81000000 215.64418|INITSVC|Do post mem flush callbacks 215.64418|PNOR|PnorRP::Shutdown message recieved 215.65420|INITSVC|_doShutdown>

dcrowell77 commented 2 years ago

This kind of error tends to be a problem with patching the system. Was this a complete flash build or were there code patches involved?

lili-lilili commented 2 years ago

This kind of error tends to be a problem with patching the system. Was this a complete flash build or were there code patches involved?

Hi dcrowell： There is no extended patch. I did a full compilation with op-build ibm-release-fw1020 branch(https://github.com/open-power/op-build/tree/ibm-release-fw1020)。

dcrowell77 commented 2 years ago

How did you package the op-build output into the BMC image? That is where I suspect a problem. The attribute data comes from a lid on the BMC. If you run pldmtool bios GetBIOSAttributeCurrentValueByHandle -a hb_lid_ids and look for the HBD entry it will tell you the specific lid that holds our attributes.

The failing attribute is ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH (see obj/genfiles/attributeenums.H in the hostboot tree for the decode). I know that attribute was added pretty recently. I suspect that you haven't properly integrated the op-build into the BMC image correctly somehow such that your BMC image is using an older version of the data than the Hostboot code expects. (That part of the flow is not my area of expertise.)

lili-lilili commented 2 years ago

How did you package the op-build output into the BMC image? That is where I suspect a problem. The attribute data comes from a lid on the BMC. If you run pldmtool bios GetBIOSAttributeCurrentValueByHandle -a hb_lid_ids and look for the HBD entry it will tell you the specific lid that holds our attributes.

The failing attribute is ATTR_PROC_FAVOR_AGGRESSIVE_PREFETCH (see obj/genfiles/attributeenums.H in the hostboot tree for the decode). I know that attribute was added pretty recently. I suspect that you haven't properly integrated the op-build into the BMC image correctly somehow such that your BMC image is using an older version of the data than the Hostboot code expects. (That part of the flow is not my area of expertise.)

Thanks a lot, this is indeed a problem caused by lid mapping。