错误 #780
终端入网测试，CPE重启第一次后可以正常入网，再次重启，无法正常入网。phy进程有挂死日志打印：TTI间隔突然变大
开始日期:
2022-03-22
计划完成日期:
% 完成:
0%
预期时间:
问题归属:
DRV, FPGA, PHY
发现问题版本:
Rel_2.1.10P
目标解决问题版本:
Rel_2.1.11P
FPGA板卡类型:
115P
CPU类型:
描述
1、基站运行正常，phy、du、cu三个层面状态正常；
2、CPE执行断电重启，重启完毕后可正常接入5G网络，通过终端可正常访问Internet；
3、CPE再次断电重启，重启完毕后，无法接入5G网络，CPE上5Gonline指示灯不亮，观察phy进程，有挂死日志打印；
4、上述现象在同样的操作下，一天内出现两次，初步评估不是偶然现象，请定位。
文件
相关的问题
历史记录
由 吕 国荣 更新于 超过 2 年 之前
- 状态 从 挂起 变更为 进行中
[09-06 13:54:25.730][INFO ][PHY-->DU] MSG_TYPE_PHY_SLOT_IND: CellNum[1], SlotIdx[11749], TTI[587,9], TimeDiff[498.945]us.
[09-06 13:54:25.730][INFO ]dl_config_req, RBNum[273] Layer[2] DLSymb[13] TotalDMRSSym[2] MCS0[23] McsTable[0] tbsize[37905], ModeOrder[6]
[09-06 13:54:25.730][INFO ]ul_config_req, RBSize[4] Layer[1] ULSymb[14] TotalDMRSSym[2] McsTable[0] tbsize[301], ModeOrder[6]
[09-06 13:54:25.730][INFO ] RNTI[17021] SLot[11748]--power bits of received DMRS signal(ant0): 11.461872, RSRP(ant0): -61.301102
[09-06 13:54:25.730][INFO ] Estimated SNR after CE(ant[0], layer[0]): fEstSnr[25.710423]dBm, f_est_snr[372.427887]
[09-06 13:54:25.730][INFO ] SlotAdvIdx[11748]-layer(0): fEstAvgSnr[372.427887] freq_offset[-69.315]Hz TA_est0[0] TAEst[-7]
[09-06 13:54:25.730][INFO ][phy_dl_pdsch_encode_callback]: CellId[0] Slot[11749] SlotAdvIdx[11751] Ctx[3] FEC_DealTime[157.800]us, EncodeAllTime[160.300]us
[09-06 13:54:25.731][INFO ]==== [phy_ul_pusch_decode_callback] CellIdx[0] SfIdx[11748] SlotAdvIdx[11748] iCtx[0] Decode_Time[27.252]us
[09-06 13:54:25.731][INFO ]UL_DECODE_RESULT, SfIdx[11748], CRNTI[17021] TBSize[301], MCS[23], HarqID[6], NDI[1], RVIdx[0], RBs[4], CRC[1]
[09-06 13:54:25.731][FATAL]gnb_fh_thread, TimeLastUs[637] TimeDiff[1] Realslot[11750] TimeOut_Cnt[1] //// ----phy收到的tti 间隔，突然变为 637us。
[09-06 13:54:25.731][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[11749] --> SfIdx[11750]
[09-06 13:54:25.731][INFO ][PHY-->DU] MSG_TYPE_PHY_SLOT_IND: CellNum[1], SlotIdx[11750], TTI[587,10], TimeDiff[650.619]us.
[09-06 13:54:25.731][INFO ]dl_config_req, RBNum[273] Layer[2] DLSymb[13] TotalDMRSSym[2] MCS0[23] McsTable[0] tbsize[37905], ModeOrder[6]
[09-06 13:54:25.732][FATAL]gnb_fh_thread, TimeLastUs[636] TimeDiff[1] Realslot[11751] TimeOut_Cnt[2]
[09-06 13:54:25.732][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[11750] --> SfIdx[11751]
[09-06 13:54:25.732][INFO ][PHY-->DU] MSG_TYPE_PHY_SLOT_IND: CellNum[1], SlotIdx[11751], TTI[587,11], TimeDiff[639.035]us.
[09-06 13:54:25.732][INFO ]dl_config_req, RBNum[273] Layer[2] DLSymb[13] TotalDMRSSym[2] MCS0[23] McsTable[0] tbsize[37905], ModeOrder[6]
[09-06 13:54:25.732][FATAL]gnb_fh_thread, TimeLastUs[638] TimeDiff[1] Realslot[11752] TimeOut_Cnt[3]
[09-06 13:54:25.732][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[11751] --> SfIdx[11752]
[09-06 13:54:25.732][INFO ][PHY-->DU] MSG_TYPE_PHY_SLOT_IND: CellNum[1], SlotIdx[11752], TTI[587,12], TimeDiff[640.869]us.
[09-06 13:54:25.732][INFO ]dl_config_req, RBNum[273] Layer[2] DLSymb[13] TotalDMRSSym[2] MCS0[23] McsTable[0] tbsize[37905], ModeOrder[6]
[09-06 13:54:25.732][ERROR]Previous UL list is not completed CellIdx[0] Subframe[11752] SfIdx[11752] iCtx[0] gMySfn[6340076]
[09-06 13:54:25.732][FATAL]phy_bbupool_reset_cell_state, Reset pBbu->nCurrentSfIdx--->>>[-1]
[09-06 13:54:25.733][FATAL]gnb_fh_thread, TimeLastUs[636] TimeDiff[1] Realslot[0] TimeOut_Cnt[4]
[09-06 13:54:25.733][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[-1] --> SfIdx[0]
[09-06 13:54:25.733][INFO ]BBU Pooling: enter multicell Activate!
[09-06 13:54:25.733][INFO ]BBU Pooling: active result: Q_id = 0,currenSf = 20480, curCellNum = 1, activesfn = 4, CellNumInActSfn = 2
[09-06 13:54:25.733][INFO ]BBU Pooling: multiCell Activate sucessfully!
[09-06 13:54:25.733][FATAL]gnb_fh_thread, TimeLastUs[637] TimeDiff[1] Realslot[1] TimeOut_Cnt[5]
[09-06 13:54:25.733][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[0] --> SfIdx[1]
[09-06 13:54:25.734][FATAL]gnb_fh_thread, TimeLastUs[636] TimeDiff[1] Realslot[2] TimeOut_Cnt[6]
[09-06 13:54:25.734][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[1] --> SfIdx[2]
[09-06 13:54:25.735][FATAL]gnb_fh_thread, TimeLastUs[637] TimeDiff[1] Realslot[3] TimeOut_Cnt[7]
[09-06 13:54:25.735][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[2] --> SfIdx[3]
[09-06 13:54:25.735][FATAL]gnb_fh_thread, TimeLastUs[636] TimeDiff[1] Realslot[4] TimeOut_Cnt[8]
[09-06 13:54:25.735][ERROR]Update SfIdx by CallerFlag[0]: CurrentSfIdx[3] --> SfIdx[4]
[09-06 13:54:25.735][INFO ][PHY-->DU] MSG_TYPE_PHY_SLOT_IND: CellNum[1], SlotIdx[4], TTI[587,13], TimeDiff[3200.553]us.
[09-06 14:01:04.572][ERROR][DRV] FH_Rx, header[587,4,1] SlotIdx[11749] TTI_time[535,9,0] TTIslotIdx[10718]
[09-06 14:01:04.573][WARNG]Time stamp start loop=1051708040 time(us) = 398841953 value=535.9.0.13