Monday, April 27, 2015

IOS XR spi4 bus unsync logs


When getting the above logs, replace the SPA.

LC/0/7/CPU0:Apr 27 16:39:55.079 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:40:26.136 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:40:57.194 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:41:28.251 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:41:59.308 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:42:30.365 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:43:01.423 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:43:32.480 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:44:03.537 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:44:34.594 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:45:05.652 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:45:36.709 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:46:07.766 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:46:38.823 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:47:09.881 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:47:40.938 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:48:11.995 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:48:43.052 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:49:14.110 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:49:45.167 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:50:16.224 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:50:47.281 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:51:18.339 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:51:49.396 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:52:20.453 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:52:51.510 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:53:22.568 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:53:53.625 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO :









LC/0/7/CPU0:Apr 27 16:53:22.568 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:53:42.041 UTC: ifmgr[197]: %PKT_INFRA-LINK-3-UPDOWN : Interface POS0/7/2/0, changed state to Down
LC/0/7/CPU0:Apr 27 16:53:42.041 UTC: vpa_1p_oc192[390]: %L2-SONET_LOCAL-4-ALARM : SONET0/7/2/0: PLOP
LC/0/7/CPU0:Apr 27 16:53:42.044 UTC: vpa_1p_oc192[390]: %L2-SONET_LOCAL-4-ALARM : SONET0/7/2/0: SLOF
LC/0/7/CPU0:Apr 27 16:53:42.254 UTC: vpa_1p_oc192[390]: %L2-SONET_LOCAL-4-ALARM : SONET0/7/2/0: SLOS
LC/0/7/CPU0:Apr 27 16:53:53.625 UTC: vpa_1p_oc192[390]: %L2-VPA_1P_OC192-6-INFO : SPI4 STAT: bay = 2 : spi4 bus unsync
LC/0/7/CPU0:Apr 27 16:54:00.157 UTC: 6-10ge-wlo-flex[244]: %L2-SPA-5-OIR_REMOVED : SPA removed from bay 2
LC/0/7/CPU0:Apr 27 16:54:01.145 UTC: vpa_1p_oc192[390]: %PLATFORM-XCVR-6-REMOVAL : Transceiver(  :rack:0/slot:7/bay:2/port:0) REMOVED
LC/0/7/CPU0:Apr 27 16:54:01.443 UTC: ifmgr[197]: %PKT_INFRA-LINEPROTO-5-UPDOWN : Line protocol on Interface POS0/7/2/0, changed state to Down
LC/0/7/CPU0:Apr 27 16:54:01.616 UTC: plaspa_server[129]: %L2-PLIM_ASIC-4-HW_WARNING : plaspa instance 1: (plaspa port 0 jacket slot 2) TSI SPI4 Status OOF was reported
RP/0/RP0/CPU0:Apr 27 16:54:03.804 UTC: invmgr[258]: %PLATFORM-INV-6-NODE_STATE_CHANGE : Node: 0/7/2, state: UNPOWERED
LC/0/7/CPU0:Apr 27 16:54:38.621 UTC: 6-10ge-wlo-flex[244]: %L2-SPA-5-STATE_CHANGE : SPA in bay 2 type 1xOC192 POS/RPR HHSPA with XFP Initing
LC/0/7/CPU0:Apr 27 16:54:43.855 UTC: vpa_1p_oc192[390]: %L2-SONET_LOCAL-4-ALARM : SONET0/7/2/0: SLOS
RP/0/RP0/CPU0:Apr 27 16:54:43.870 UTC: invmgr[258]: %PLATFORM-INV-6-NODE_STATE_CHANGE : Node: 0/7/2, state: OK
LC/0/7/CPU0:Apr 27 16:54:46.310 UTC: vpa_1p_oc192[390]: %L2-OPTICS-2-TRANSCEIVER_LOC : Transceiver location is int Sonet 7/2/0
LC/0/7/CPU0:Apr 27 16:54:46.310 UTC: vpa_1p_oc192[390]: %L2-OPTICS-2-ETHER_XCVR_CODE : Ether xcvr code is 0x40000000
LC/0/7/CPU0:Apr 27 16:54:46.310 UTC: vpa_1p_oc192[390]: %L2-OPTICS-2-SONET_XCVR_CODE : Sonet xcvr code is 0x40000000
LC/0/7/CPU0:Apr 27 16:54:46.310 UTC: vpa_1p_oc192[390]: %L2-OPTICS-2-VID : VID is V03
LC/0/7/CPU0:Apr 27 16:54:46.310 UTC: vpa_1p_oc192[390]: %L2-OPTICS-2-PID : PID is XFP-10GLR-OC192SR
LC/0/7/CPU0:Apr 27 16:54:46.310 UTC: vpa_1p_oc192[390]: %L2-OPTICS-2-PN : PN is 10-1989-03
LC/0/7/CPU0:Apr 27 16:54:46.414 UTC: vpa_1p_oc192[390]: %PLATFORM-XCVR-6-ENABLE : Transceiver(  :rack:0/slot:7/bay:2/port:0) ENABLED
LC/0/7/CPU0:Apr 27 16:54:47.061 UTC: ifmgr[197]: %PKT_INFRA-LINK-3-UPDOWN : Interface POS0/7/2/0, changed state to Down
LC/0/7/CPU0:Apr 27 16:54:47.061 UTC: ifmgr[197]: %PKT_INFRA-LINEPROTO-5-UPDOWN : Line protocol on Interface POS0/7/2/0, changed state to Down
LC/0/7/CPU0:Apr 27 16:54:47.271 UTC: ifmgr[197]: %PKT_INFRA-LINEPROTO-5-UPDOWN : Line protocol on Interface POS0/7/2/0, changed state to Down
RP/0/RP0/CPU0:Apr 27 16:54:49.670 UTC: invmgr[258]: %PLATFORM-INV-6-CARD_OIRIN : OIR: Card 0/7/2 inserted
LC/0/7/CPU0:Apr 27 16:54:53.895 UTC: vpa_1p_oc192[390]: %L2-SONET_LOCAL-4-ALARM : SONET0/7/2/0: SLOS cleared
LC/0/7/CPU0:Apr 27 16:54:53.899 UTC: ifmgr[197]: %PKT_INFRA-LINK-3-UPDOWN : Interface POS0/7/2/0, changed state to Up