skiboot icon indicating copy to clipboard operation
skiboot copied to clipboard

STB: primary CPU thread not aborting immedaitely after a secondary CPU job got abort.

Open pridhiviraj opened this issue 7 years ago • 3 comments

 66.01168|====================[   66.011887081,5] OPAL skiboot-v5.9-240-g081882690163-pcbedce4 starting...
[   66.011894300,7] initial console log level: memory 7, driver 5
[   66.011896673,6] CPU: P9 generation processor (max 4 threads/core)
[   66.011898763,7] CPU: Boot CPU PIR is 0x0004 PVR is 0x004e1200
[   66.011901029,7] CPU: Initial max PIR set to 0x7fff
[   66.013275671,7] OPAL table: 0x300f3430 .. 0x300f3980, branch table: 0x30002000
[   66.013279680,7] Assigning physical memory map table for nimbus
[   66.013282901,7] Parsing HDAT...
[   66.013284388,5] SPIRA-S found.
[   66.013287548,6] BMC #0: HW version 3, SW version 2, chip DD1.0
[   66.013594020,6] SP Family is ibm,ast2500,supermicro
[   66.013601470,7] LPC: IOPATH chip id = 0
[   66.013602989,7] LPC: FW BAR       = f0000000
[   66.013604654,7] LPC: MEM BAR      = e0000000
[   66.013606323,7] LPC: IO BAR       = d0010000
[   66.013607996,7] LPC: Internal BAR = c0012000
[   66.013621312,7] LPC UART: base addr = 3f8 (3f8) size = 1 clk = 1843200, baud = 115200
[   66.013624456,7] LPC: BT [0, 0] sms_int: 0, bmc_int: 0
[   66.069159648,5] UART: Using UART at 0x60300d00103f8
[   66.074079250,3] I2C: v2 found, but not supported. Parsing as v1
[   66.077302945,3] I2C: v2 found, but not supported. Parsing as v1
[   66.082093710,5] P9 DD2.00 detected
[   66.083712465,5] CHIP: Chip ID 0000 type: P9N DD2.0
[   66.086219502,5] P9 DD2.00 detected
[   66.088692685,5] CHIP: Chip ID 0008 type: P9N DD2.0
[   66.091625140,5] PLAT: Using���m���r�ʚ������b���
                                                     �('H�outing irq 9, policy: 0 (r=1)
[   66.093776222,7] LPC: SerIRQ 9 using route 0 targetted at OPAL
[   66.099718681,5] PLAT: Detected p9dsu2u platform
[   66.102200394,5] PLAT: Detected BMC platform SMC
[   66.133107624,5] CPU: All 128 processors called in...
[   68.004878257,3] MBOX-FLASH: Bad response code from BMC 2
[   68.007064573,3] MBOX-FLASH: Error waiting for BMC
[   68.420329767,5] FLASH: Found system flash:  id:0
[   68.422313593,5] BT: Interface initialized, IO 0x00e4
[   68.425131211,7] LPC: Routing irq 10, policy: 0 (r=1)
[   68.425132341,7] LPC: SerIRQ 10 using route 1 targetted at OPAL
[   69.483549221,0] STB: VERSION NOT VERIFIED, invalid param. buf=0x305ed9e0, len=4096 key-hash=0x0 hash-size=0
[   69.489470246,5] NVRAM: Size is 576 KB
[   70.079277952,5] console: Setting driver log level to 7
[   70.079287356,7] LPC-MBOX: Sending BMC interrupt
[   70.079383270,6] BT: seq 0x05 netfn 0x06 cmd 0x06: IPMI MSG done
[   70.079437430,6] BT: seq 0x06 netfn 0x06 cmd 0x2e: Message sent to host
[   70.090028497,7] NVRAM: 'log-level-memory' not found
[   70.092866731,5] STB: Found ibm,secureboot-v2
[   70.094995784,7] NVRAM: Searched for 'force-secure-mode' found 'always'
[   70.098544526,5] STB: secure mode on (FORCED by nvram)
[   70.100682149,6] STB: Found CVC @ 203ffd260000-203ffd26ffff
[   70.103520390,6] STB: Found CVC-sha512 @ 203ffd260040, version=1
[   70.107065944,6] STB: Found CVC-verify @ 203ffd260050, version=1
[   70.109907450,7] NVRAM: 'force-trusted-mode' not found
[   70.112745453,5] STB: trusted mode off
[   70.114170054,5] OPAL: Using OPAL UART console
[   70.117004278,7] NVRAM: 'uart-con-policy' not found
[   70.119141261,7] LPC: Routing irq 4, policy: 0 (r=1)
[   70.119142308,7] LPC: SerIRQ 4 using route 2 targetted at OPAL
[   70.124816744,7] SLW: Init chip 0x0
[   70.126240442,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.129789010,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.133335923,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.136179875,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.139729120,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.143276085,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.146825633,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.149668887,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.153217803,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.156764344,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.159610267,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.163156883,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.166706109,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.169549685,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.173096936,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.176645879,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.179495069,5] SLW: Configuring self-restore for HRMOR
[   70.182251252,6] BT: seq 0x06 netfn 0x06 cmd 0x2e: IPMI MSG done
[   70.182331312,6] BT: seq 0x07 netfn 0x04 cmd 0x30: Message sent to host
[   70.182334006,7] blocklevel_read: 0x0	0x31c03b20	0x30
[   70.182335298,7] blocklevel_raw_read: 0x0	0x31c03b20	0x30
[   70.182345173,7] FFS: Partition map size: 0x1000
[   70.182346088,7] blocklevel_read: 0x0	0x306c1528	0x1000
[   70.182347281,7] blocklevel_raw_read: 0x0	0x306c1528	0x1000
[   70.185117604,7] FLASH: IMA_CATALOG partition has ECC
[   70.185118664,7] blocklevel_read: 0x3149000	0x3067f0e0	0x1000
[   70.185119959,7] blocklevel_read: region has ECC
[   70.185120931,7] blocklevel_raw_read: 0x3149000	0x306c2ee8	0x1200
[   70.186501582,7] FLASH: IMA_CATALOG partition is signed
[   70.186502607,7] blocklevel_read: 0x314a200	0x306800e0	0x11000
[   70.186503951,7] blocklevel_read: region has ECC
[   70.186504940,7] blocklevel_raw_read: 0x314a200	0x306c2ee8	0x13200
[   70.206092674,7] FLASH: flash subpartition eyecatcher IMCC
[   70.220219562,6] STB: IMA_CATALOG verified
[   70.233916665,7] SLW: Init chip 0x8
[   70.235335577,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.238880075,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.242426979,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.245272651,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.248819587,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.252368600,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.255212713,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.258759644,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.262308532,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.265855425,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.268701347,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.272248342,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.275796841,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.278640491,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.282188293,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.285736918,7] SLW: EC_PPM_SPECIAL_WKUP_HYP read  0x0000000000000000
[   70.288580223,5] SLW: Configuring self-restore for HRMOR
[   70.291426805,7] CPU idle state device tree init
[   70.293552940,5] SLW: Enabling: stop0_lite
[   70.295680896,5] SLW: Enabling: stop0
[   70.297808226,5] SLW: Enabling: stop1_lite
[   70.299937395,5] SLW: Enabling: stop1
[   70.302064889,5] SLW: Enabling: stop2_lite
[   70.304196026,5] SLW: Enabling: stop2
[   70.305622012,7] NVRAM: 'pcie-max-link-speed' not found
[   70.308514601,6] CAPI: Preloading ucode 200d1
[   70.310587790,7] FLASH: Queueing preload of 2/200d1
[   70.313424552,7] FLASH: Queueing preload of 0/0
[   70.313435935,7] LPC-MBOX: Sending BMC interrupt
[   70.313953433,6] BT: seq 0x07 netfn 0x04 cmd 0x30: IPMI MSG done
[   70.320530204,7] FLASH: Queueing preload of 1/0
[   70.324363352,5] VAS: Initialized chip 0
[   70.326546454,5] VAS: Initialized chip 8
[   70.327950838,6] NX RNG[0] pace:2000
[   70.330018665,6] NX0: Crypto at 0x2010000
[   70.332148306,6] NX0: POWER9 nx-crypto not yet supported
[   70.334987225,6] NX0: 842 at 0x2010000
[   70.336411628,5] Found ibm,power9-nx
[   70.338538963,7] NX0: UMAC VAS MMIO BAR, 0x0006019100000000, xcfg 0x20110d4
[   70.342089619,7] NX0: DMA VAS MMIO BAR, 0x0006019100000000, xcfg 0x201105e
[   70.345639486,7] NX0: UMAC SEND WC BAR, 0x0000000054800000, xcfg 0x20110d2
[   70.349189354,7] NX0: Setting UMAC Status Control 0x7000000000000000
[   70.352032632,7] NX0:   DMA 0x00ffc1987f800080
[   70.354166191,7] NX0: Setting UMAC FIFO bar 0x0000000055008280
[   70.357708346,7] NX0: Setting UMAC notify match 0x0fff000003000011
[   70.360556859,7] NX0: Setting UMAC FIFO bar 0x0000000055018280
[   70.363393269,7] NX0: Setting UMAC notify match 0x0fff000003000021
[   70.366938314,7] NX0:   Engine Enable 0x8000000000000067
[   70.369074010,6] NX0: 842 Coprocessor Enabled
[   70.371907441,6] NX0: gzip at 0x2010000
[   70.373333041,7] NX0:   DMA 0x00ffc1987f800080
[   70.376168852,7] NX0:   Engine Enable 0x8000000000000067
[   70.378307171,7] NX0: Setting UMAC FIFO bar 0x0000000055028280
[   70.381848391,7] NX0: Setting UMAC notify match 0x0fff000002000011
[   70.384693846,7] NX0: Setting UMAC FIFO bar 0x0000000055038280
[   70.387532115,7] NX0: Setting UMAC notify match 0x0fff000002000021
[   70.391078681,6] NX0: gzip Coprocessor Enabled
[   70.393217334,6] NX RNG[8] pace:2000
[   70.394631897,6] NX8: Crypto at 0x2010000
[   70.396762524,6] NX8: POWER9 nx-crypto not yet supported
[   70.399600984,6] NX8: 842 at 0x2010000
[   70.401728748,5] Found ibm,power9-nx
[   70.403152608,7] NX8: UMAC VAS MMIO BAR, 0x0006219100000000, xcfg 0x20110d4
[   70.406702212,7] NX8: DMA VAS MMIO BAR, 0x0006219100000000, xcfg 0x201105e
[   70.410252419,7] NX8: UMAC SEND WC BAR, 0x0000200003000000, xcfg 0x20110d2
[   70.413802977,7] NX8: Setting UMAC Status Control 0x7000000000000000
[   70.416628054,7] blocklevel_read: 0x0	0x31c03b20	0x30
[   70.416629523,7] blocklevel_raw_read: 0x0	0x31c03b20	0x30
[   70.416651644,7] FFS: Partition map size: 0x1000
[   70.416652593,7] blocklevel_read: 0x0	0x306e2628	0x1000
[   70.416653901,7] blocklevel_raw_read: 0x0	0x306e2628	0x1000
[   70.418395117,7] FLASH: CAPP partition has ECC
[   70.418395993,7] blocklevel_read: 0x3104000	0x306c1940	0x1000
[   70.418397444,7] blocklevel_read: region has ECC
[   70.418398473,7] blocklevel_raw_read: 0x3104000	0x306e3fe8	0x1200
[   70.419297347,7] FLASH: CAPP partition is signed
[   70.419298195,7] blocklevel_read: 0x3105200	0x306c2940	0x1a000
[   70.419299623,7] blocklevel_read: region has ECC
[   70.419300621,7] blocklevel_raw_read: 0x3105200	0x306e3fe8	0x1d400
[   70.446049356,7] FLASH: flash subpartition eyecatcher CAPP
[   70.446052897,0] STB: CAPP verification FAILED. log=0xffffffffffff8160
[   70.446054261,0] STB: secure mode enforced, aborting.
[   70.446055097,0] Aborting!
CPU 0000 Backtrace:
 S: 0000000031c039c0 R: 00000000300136d8   .backtrace+0x48
 S: 0000000031c03a60 R: 0000000030019ed0   ._abort+0x4c
 S: 0000000031c03ae0 R: 000000003009cf14   .secureboot_enforce+0x3c
 S: 0000000031c03b50 R: 000000003009d334   .secureboot_verify+0xc0
 S: 0000000031c03bf0 R: 000000003002ec6c   .flash_load_resources+0x680
 S: 0000000031c03d40 R: 0000000030018088   .cpu_process_jobs+0xdc
 S: 0000000031c03e00 R: 0000000030014d38   .__secondary_cpu_entry+0x44
 S: 0000000031c03e80 R: 0000000030014d8c   .secondary_cpu_entry+0x34
 S: 0000000031c03f00 R: 0000000030002748   secondary_wait+0x8c
[   70.447972204,6] BT: seq 0x08 netfn 0x0a cmd 0x42: Message sent to host
[   70.497879816,7] NX8:   DMA 0x00ffc1987f800080
[   70.500013673,7] NX8: Setting UMAC FIFO bar 0x0000200003808280
[   70.503554992,7] NX8: Setting UMAC notify match 0x0fff000003000031
[   70.506402421,7] NX8: Setting UMAC FIFO bar 0x0000200003818280
[   70.509240545,7] NX8: Setting UMAC notify match 0x0fff000003000041
[   71.000786474,7] NX8:   Engine Enable 0x8000000000000067
[   71.002919939,6] NX8: 842 Coprocessor Enabled
[   71.005754314,6] NX8: gzip at 0x2010000
[   71.007181634,7] NX8:   DMA 0x00ffc1987f800080
[   71.010014825,7] NX8:   Engine Enable 0x8000000000000067
[   71.012154705,7] NX8: Setting UMAC FIFO bar 0x0000200003828280
[   71.015695972,7] NX8: Setting UMAC notify match 0x0fff000002000031
[   71.018541672,7] NX8: Setting UMAC FIFO bar 0x0000200003838280
[   71.021379595,7] NX8: Setting UMAC notify match 0x0fff000002000041
[   71.024926790,6] NX8: gzip Coprocessor Enabled
[   71.029938937,7] NVRAM: 'pci-eeh-verbose' not found
[   71.031773211,6] PHB4: Verbose EEH enabled
[   71.033902854,7] NVRAM: 'pci-tracing' not found
[   71.036033057,7] NVRAM: 'pci-eeh-mmio' not found
[   71.038870083,7] NVRAM: 'pci-retry-all' not found
[   71.041038795,6] PHB: Chip 0 Found PHB4 PBCQ0 Stack 0 at /xscom@603fc00000000/pbcq@4010c00/stack@0
[   71.045967979,7] PHB[0:0] X[PE]=0x04010c00/0x04010c40 X[PCI]=0x0d010800/0x0d010840 X[ETU]=0x0d010900
[   71.050235612,7] ETU reset: 0
[   71.052172905,7] Version reg: 0x000000a400000002
[   71.054493938,6] PHB: Chip 0 Found PHB4 PBCQ1 Stack 0 at /xscom@603fc00000000/pbcq@4011000/stack@0
[   71.058750538,7] PHB[0:1] X[PE]=0x04011000/0x04011040 X[PCI]=0x0e010800/0x0e010840 X[ETU]=0x0e010900
[   71.064246280,6] PHB: Chip 0 Found PHB4 PBCQ1 Stack 1 at /xscom@603fc00000000/pbcq@4011000/stack@1
[   71.068339826,7] PHB[0:2] X[PE]=0x04011000/0x04011080 X[PCI]=0x0e010800/0x0e010880 X[ETU]=0x0e010940
[   71.073837089,6] PHB: Chip 0 Found PHB4 PBCQ2 Stack 0 at /xscom@603fc00000000/pbcq@4011400/stack@0
[   71.077929824,7] PHB[0:3] X[PE]=0x04011400/0x04011440 X[PCI]=0x0f010800/0x0f010840 X[ETU]=0x0f010900
[   71.083426089,6] PHB: Chip 0 Found PHB4 PBCQ2 Stack 1 at /xscom@603fc00000000/pbcq@4011400/stack@1
[   71.087519212,7] PHB[0:4] X[PE]=0x04011400/0x04011480 X[PCI]=0x0f010800/0x0f010880 X[ETU]=0x0f010940
[   71.093015101,6] PHB: Chip 0 Found PHB4 PBCQ2 Stack 2 at /xscom@603fc00000000/pbcq@4011400/stack@2
[   71.097109192,7] PHB[0:5] X[PE]=0x04011400/0x040114c0 X[PCI]=0x0f010800/0x0f0108c0 X[ETU]=0x0f010980
[   71.102610713,6] PHB: Chip 8 Found PHB4 PBCQ0 Stack 0 at /xscom@623fc00000000/pbcq@4010c00/stack@0
[   71.106707787,7] PHB[8:0] X[PE]=0x04010c00/0x04010c40 X[PCI]=0x0d010800/0x0d010840 X[ETU]=0x0d010900
[   71.112205058,6] PHB: Chip 8 Found PHB4 PBCQ1 Stack 0 at /xscom@623fc00000000/pbcq@4011000/stack@0
[   71.116301498,7] PHB[8:1] X[PE]=0x04011000/0x04011040 X[PCI]=0x0e010800/0x0e010840 X[ETU]=0x0e010900
[   71.121797681,6] PHB: Chip 8 Found PHB4 PBCQ1 Stack 1 at /xscom@623fc00000000/pbcq@4011000/stack@1
[   71.125890813,7] PHB[8:2] X[PE]=0x04011000/0x04011080 X[PCI]=0x0e010800/0x0e010880 X[ETU]=0x0e010940
[   71.131388535,6] PHB: Chip 8 Found PHB4 PBCQ2 Stack 0 at /xscom@623fc00000000/pbcq@4011400/stack@0
[   71.135485160,7] PHB[8:3] X[PE]=0x04011400/0x04011440 X[PCI]=0x0f010800/0x0f010840 X[ETU]=0x0f010900
[   71.141024044,7] PHB#0000:00:00.0 PCI: Registered PHB
[   71.142989929,6] PHB#0000[0:0]: Found /pciex@600c3c0000000 @0x600c3c0000000
[   71.146538679,6] PHB#0000[0:0]:   M32 [0x000600c000000000..0x000600c07fffffff]
[   71.150090117,6] PHB#0000[0:0]:   M64 [0x0006000000000000..0x0006003fffffffff]
[   71.154345736,7] PHB#0000[0:0]: Base location code not found !
[   71.157188099,7] PHB#0000[0:0]: Core revision 0xa40002
[   71.159320976,7] PHB#0000[0:0]: Found 512 max PEs and 4096 IRQs 
[   71.162866717,6] PHB#0000[0:0]: Max link speed: GEN3
[   71.165000455,7] PHB#0000[0:0]: Override lane equalization settings:
[   71.168546373,7] PHB#0000[0:0]:   0x5454545454545454 0x5454545454545454
[   71.172095303,7] PHB#0000[0:0]:   0x5454545454545454 0x5454545454545454
[   71.174940748,7] PHB#0000[0:0]:   0x7777777777777777 0x7777777777777777
[   71.178619664,7] PHB#0000[0:0]: Initializing PHB4...
[   71.181326050,7] PHB#0000[0:0]: Default system config: 0x4410000040400000
[   71.184874899,7] PHB#0000[0:0]: New system config    : 0x4410000030400000
[   71.187720648,7] PHB#0000[0:0]: Initial PHB CRESET is 0xe000000000000000
[   71.191270834,7] PHB#0000[0:0]: Waiting for DLP PG reset to complete...
[   71.194817863,7] PHB#0000[0:0]: Reset state SRC_ID: 0ff8000000000000
[   71.198691675,7] PHB#0000[0:0]: Initialization complete
[   71.200660905,6] BT: seq 0x08 netfn 0x0a cmd 0x42: IPMI MSG done
[   71.204210336,6] BT: seq 0x09 netfn 0x3a cmd 0xf0: Message sent to host
[   71.243410813,6] IPMI: sending chassis control request 0x03
[   71.309793356,6] BT: seq 0x09 netfn 0x3a cmd 0xf0: IPMI MSG done
[   71.312613789,6] BT: seq 0x0b netfn 0x3a cmd 0xf0: Message sent to host
[   71.418187877,6] BT: seq 0x0b netfn 0x3a cmd 0xf0: IPMI MSG done
[   71.420965776,6] BT: seq 0x0c netfn 0x3a cmd 0xf0: Message sent to host
[   72.015248657,6] BT: seq 0x0c netfn 0x3a cmd 0xf0: IPMI MSG done
[   72.017981573,6] BT: seq 0x0d netfn 0x3a cmd 0xf0: Message sent to host
[   72.124259483,6] BT: seq 0x0d netfn 0x3a cmd 0xf0: IPMI MSG done
[   72.126949917,6] BT: seq 0x0e netfn 0x3a cmd 0xf0: Message sent to host
[   72.233227650,6] BT: seq 0x0e netfn 0x3a cmd 0xf0: IPMI MSG done
[   72.235874037,6] BT: seq 0x0f netfn 0x3a cmd 0xf0: Message sent to host
[   72.342149867,6] BT: seq 0x0f netfn 0x3a cmd 0xf0: IPMI MSG done
[   72.344752196,6] BT: seq 0x10 netfn 0x3a cmd 0xf0: Message sent to host
[   72.451030532,6] BT: seq 0x10 netfn 0x3a cmd 0xf0: IPMI MSG done
[   72.453587030,6] BT: seq 0x11 netfn 0x3a cmd 0xf0: Message sent to host
[   73.047864242,6] BT: seq 0x11 netfn 0x3a cmd 0xf0: IPMI MSG done
[   73.050377755,6] BT: seq 0x12 netfn 0x3a cmd 0xf0: Message sent to host
[   73.156654250,6] BT: seq 0x12 netfn 0x3a cmd 0xf0: IPMI MSG done
[   73.159124165,6] BT: seq 0x13 netfn 0x3a cmd 0xf0: Message sent to host
[   73.265400379,6] BT: seq 0x13 netfn 0x3a cmd 0xf0: IPMI MSG done
[   73.267825788,6] BT: seq 0x14 netfn 0x3a cmd 0xf0: Message sent to host
[   73.374102117,6] BT: seq 0x14 netfn 0x3a cmd 0xf0: IPMI MSG done
[   73.376482637,6] BT: seq 0x15 netfn 0x3a cmd 0xf0: Message sent to host
[   73.482760323,6] BT: seq 0x15 netfn 0x3a cmd 0xf0: IPMI MSG done
[   73.485099725,6] BT: seq 0x16 netfn 0x3a cmd 0xf0: Message sent to host
[   74.079376786,6] BT: seq 0x16 netfn 0x3a cmd 0xf0: IPMI MSG done
[   74.081673281,6] BT: seq 0x17 netfn 0x3a cmd 0xf0: Message sent to host
[   74.187949448,6] BT: seq 0x17 netfn 0x3a cmd 0xf0: IPMI MSG done
[   74.190905659,6] BT: seq 0x18 netfn 0x3a cmd 0xf0: Message sent to host
[   74.296478585,6] BT: seq 0x18 netfn 0x3a cmd 0xf0: IPMI MSG done
[   74.299390386,6] BT: seq 0x19 netfn 0x3a cmd 0xf0: Message sent to host
[   74.404962901,6] BT: seq 0x19 netfn 0x3a cmd 0xf0: IPMI MSG done
[   74.407830502,6] BT: seq 0x1a netfn 0x3a cmd 0xf0: Message sent to host
[   75.001403963,6] BT: seq 0x1a netfn 0x3a cmd 0xf0: IPMI MSG done
[   75.004224409,6] BT: seq 0x1b netfn 0x3a cmd 0xf0: Message sent to host
[   75.109799146,6] BT: seq 0x1b netfn 0x3a cmd 0xf0: IPMI MSG done
[   75.112575361,6] BT: seq 0x1c netfn 0x3a cmd 0xf0: Message sent to host
[   75.218853641,6] BT: seq 0x1c netfn 0x3a cmd 0xf0: IPMI MSG done
[   75.221583085,6] BT: seq 0x1d netfn 0x3a cmd 0xf0: Message sent to host
[   75.327871991,6] BT: seq 0x1d netfn 0x3a cmd 0xf0: IPMI MSG done
[   75.330549114,6] BT: seq 0x1e netfn 0x0a cmd 0x44: Message sent to host
[   75.436837860,6] BT: seq 0x1e netfn 0x0a cmd 0x44: IPMI MSG done
[   75.439466772,6] IPMI: SEL: New event logged [ID : 02d]
[   75.442307465,6] BT: seq 0x0a netfn 0x00 cmd 0x02: Message sent to host
[   76.035894135,6] BT: seq 0x0a netfn 0x00 cmd 0x02: IPMI MSG done


--== Welcome to Hostboot  ==--

  2.73076|secure|SecureROM valid - enabling functionality
 13.83996|secure|Booting in non-secure mode.
 14.56856|Ignoring boot flags, incorrect version 0x0
 14.57840|Booting from SBE side 0 on master proc=00050000
 14.73354|ISTEP  6. 5 - host_init_fsi
 14.81271|ISTEP  6. 6 - host_set_ipl_parms

If there are multiple flash resources pre-load requests came, before one CPU job gets abort the other CPU can download the PNOR resource. @cclaudio @stewart-ibm Please have a look at it.

pridhiviraj avatar Feb 08 '18 05:02 pridhiviraj

Primary main thread took additional 6 seconds to abort the boot process, after secondary job gets abort.

pridhiviraj avatar Feb 08 '18 06:02 pridhiviraj

Ouch. We should probably think about a better way to deal with that.

ghost avatar Feb 08 '18 06:02 ghost

Adding cross reference -> https://bugzilla.linux.ibm.com/show_bug.cgi?id=165960

debmc avatar Feb 07 '19 16:02 debmc