<meta http-equiv="Content-Type" content="text/html; charset=GB18030"><div>Hi Pedrol,</div><div><br></div><div>Sorry for answer late. I was in holiday these days.</div><div>Version info as below:<br></div><div>sudo ./osmo-pcu --version<br>OsmoPCU version 0.8.0<br><br>Copyright (C) 2012 by Ivan Kluchnikov <kluchnikovi@gmail.com> and <br>                      Andreas Eversberg <jolly@eversberg.eu><br>License GNU GPL version 2 or later<br>This is free software: you are free to change and redistribute it.<br>There is NO WARRANTY, to the extent permitted by law.<br></div><div><br></div><div>sudo osmo-bts-trx --version<br>((*))<br>  |<br> / \ OsmoBTS<br>OsmoBTS version 1.2.2<br><br>Copyright (C) 2010, 2011 by Harald Welte, Andreas Eversberg and On-Waves<br>License AGPLv3+: GNU AGPL version 3 or later <http://gnu.org/licenses/agpl-3.0.html><br>This is free software: you are free to change and redistribute it.<br>There is NO WARRANTY, to the extent permitted by law.<br></div><div>pcap file attached.</div><div><br></div><div>BTW:</div><div>I had a test for this issue.If modify gsm48_gmm_sendmsg(, 0, , true) to gsm48_gmm_sendmsg(, 1, , false) in gsm48_tx_gmm_att_ack in the gprs_gmm.c. The attach process can be successful.<br></div><div><div><br></div><div>Thanks a lot.</div><div>Peter<br><br></div><div style="font-size: 12px;font-family: Arial Narrow;padding:2px 0 2px 0;">------------------ 原始邮件 ------------------</div><div style="font-size: 12px;background:#efefef;padding:8px;"><div><b>发件人:</b>                                                                                                                        "Pau Espin Pedrol"                                                                                    <pespin.shar@gmail.com>;</div><div><b>发送时间:</b> 2021年4月29日(星期四) 晚上11:27</div><div><b>收件人:</b> "海鱼"<86731050@qq.com>;<wbr></div><div><b>抄送:</b> "osmocom-net-gprs"<osmocom-net-gprs@lists.osmocom.org>;<wbr></div><div><b>主题:</b> Re: osmo-pcu always "T3193 timeout expired, freeing TBF" when osmo-sgsn send GPRS ATTACH ACCEPT</div></div><div><br></div><div dir="ltr"><div>Hi,</div><div><br></div><div>can you please record and send a pcap file while trying to attach?</div><div>Make sure you are using osmo-bts-trx and osmo-pcu master (please provide output wyou get with "--version").</div><div>Also make sure to enable GSMTAP in both osmo-pcu and osmo-bts-trx.</div><div>Also please enable "gsmtap log" with "logging level set-all info" for osmo-pcu, so that everything end sup in the pcap file.</div><div><br></div><div>Regards,<br></div><div><div><div dir="ltr" class="gmail_signature" data-smartmail="gmail_signature"><div dir="ltr"><div>Pau Espin Pedrol<br></div></div></div></div><br></div></div><br><div class="gmail_quote"><div dir="ltr" class="gmail_attr">Missatge de 海鱼 <<a href="mailto:86731050@qq.com">86731050@qq.com</a>> del dia dc., 28 d’abr. 2021 a les 3:32:<br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div>Hi All,</div><div><br></div><div>Here is the issue when use egprs with osmo-bts-trx,osmo-pcu and osmo-sgsn.</div><div>osmo-pcu debugging info as below:</div><div><000c> gprs_bssgp_pcu.cpp:168 LLC [SGSN -> PCU] = TLLI: 0xaf8ce1ff IMSI: 460000323182742 len: 26<br><0008> tbf.cpp:1068 Allocating DL TBF: MS_CLASS=12/12<br><0008> tbf.cpp:541 TBF(TFI=0 TLLI=0x00000000 DIR=DL STATE=NULL) Setting Control TS 6<br><0008> tbf.cpp:945 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=NULL) Allocated: trx = 0, ul_slots = 40, dl_slots = 40<br><0008> bts.cpp:898 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) TX: START Immediate Assignment Downlink (PCH)<br><0008> tbf_dl.cpp:520 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) set ass. type PACCH [prev CCCH:0, PACCH:0]<br><0008> tbf.cpp:1270 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)<br><0009> tbf.cpp:788 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) Scheduled DL Assignment polling on PACCH (FN=2402222, TS=6)<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402270, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402322, TS=6<br><0008> tbf.cpp:808 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) poll timeout for FN=2402322, TS=6 (curr FN 2402387)<br><0008> tbf.cpp:879 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Timeout for polling PACKET DOWNLINK ACK.<br><0008> tbf.cpp:1184 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Assignment was on PACCH<br><0008> tbf.cpp:1190 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Downlink ACK was received<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402426, TS=6<br><0008> tbf.cpp:989 Allocating UL TBF: MS_CLASS=12/12<br><0008> tbf.cpp:541 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL) Setting Control TS 6<br><0008> tbf.cpp:945 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=NULL) Allocated: trx = 0, ul_slots = 40, dl_slots = 00<br><0008> tbf.cpp:1359 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=ASSIGN) start Packet Uplink Assignment (PACCH)<br><0009> tbf.cpp:782 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled UL Assignment polling on PACCH (FN=2402478, TS=6)<br><0007> gprs_rlcmac_meas.cpp:184 DL Bandwitdh of IMSI=460000323182742 / TLLI=0xaf8ce1ff: 66 KBits/s<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402525, TS=6<br><000c> tbf_ul.cpp:404 LLC [PCU -> SGSN] TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=FLOW) len=97<br><000c> gprs_bssgp_pcu.cpp:168 LLC [SGSN -> PCU] = TLLI: 0xaf8ce1ff IMSI: 460000323182742 len: 9<br><0008> tbf.cpp:808 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) poll timeout for FN=2402525, TS=6 (curr FN 2402586)<br><0008> tbf.cpp:879 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Timeout for polling PACKET DOWNLINK ACK.<br><0008> tbf.cpp:1184 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Assignment was on PACCH<br><0008> tbf.cpp:1190 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Downlink ACK was received<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402625, TS=6<br><0007> gprs_rlcmac_meas.cpp:106 UL RSSI of TLLI=0xaf8ce1ff: -31 dBm<br><0008> tbf.cpp:486 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=FINISHED) free<br><0008> tbf.cpp:989 Allocating UL TBF: MS_CLASS=12/12<br><0008> tbf.cpp:541 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL) Setting Control TS 6<br><0008> tbf.cpp:945 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=NULL) Allocated: trx = 0, ul_slots = 40, dl_slots = 00<br><0008> tbf.cpp:1359 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=ASSIGN) start Packet Uplink Assignment (PACCH)<br><0009> tbf.cpp:782 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled UL Assignment polling on PACCH (FN=2402673, TS=6)<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402720, TS=6<br><000c> tbf_ul.cpp:404 LLC [PCU -> SGSN] TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=FLOW) len=17<br><000c> gprs_bssgp_pcu.cpp:168 LLC [SGSN -> PCU] = TLLI: 0xaf8ce1ff IMSI: 460000323182742 len: 26<br><0007> gprs_rlcmac_meas.cpp:184 DL Bandwitdh of IMSI=460000323182742 / TLLI=0xaf8ce1ff: 86 KBits/s<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402768, TS=6<br><0007> gprs_rlcmac_meas.cpp:106 UL RSSI of TLLI=0xaf8ce1ff: -31 dBm<br><0008> tbf.cpp:486 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=UL STATE=FINISHED) free<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402816, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402863, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402911, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2402959, TS=6<br><0007> gprs_rlcmac_meas.cpp:184 DL Bandwitdh of IMSI=460000323182742 / TLLI=0xaf8ce1ff: 240 KBits/s<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2403006, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2403054, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2403102, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2403149, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FINISHED) Scheduled Ack/Nack polling on FN=2403197, TS=6<br><0008> tbf.cpp:650 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=WAIT RELEASE) T3193 timeout expired, freeing TBF<br><0008> tbf.cpp:486 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=RELEASING) free<br><000c> gprs_bssgp_pcu.cpp:168 LLC [SGSN -> PCU] = TLLI: 0xaf8ce1ff IMSI: 460000323182742 len: 26<br><0008> tbf.cpp:1068 Allocating DL TBF: MS_CLASS=12/12<br><0008> tbf.cpp:541 TBF(TFI=0 TLLI=0x00000000 DIR=DL STATE=NULL) Setting Control TS 6<br><0008> tbf.cpp:945 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=NULL) Allocated: trx = 0, ul_slots = 40, dl_slots = 40<br><0008> bts.cpp:898 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) TX: START Immediate Assignment Downlink (PCH)<br><0008> tbf_dl.cpp:520 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) set ass. type PACCH [prev CCCH:0, PACCH:0]<br><0008> tbf.cpp:1270 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)<br><0009> tbf.cpp:788 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) Scheduled DL Assignment polling on PACCH (FN=2404263, TS=6)<br><0007> gprs_rlcmac_meas.cpp:184 DL Bandwitdh of IMSI=460000323182742 / TLLI=0xaf8ce1ff: 22 KBits/s<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404311, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404358, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404406, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404454, TS=6<br><0008> tbf.cpp:808 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) poll timeout for FN=2404454, TS=6 (curr FN 2404519)<br><0008> tbf.cpp:879 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Timeout for polling PACKET DOWNLINK ACK.<br><0008> tbf.cpp:1184 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Assignment was on PACCH<br><0008> tbf.cpp:1190 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Downlink ACK was received<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404558, TS=6<br><0007> gprs_rlcmac_meas.cpp:184 DL Bandwitdh of IMSI=460000323182742 / TLLI=0xaf8ce1ff: 163 KBits/s<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404605, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404653, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2404701, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FINISHED) Scheduled Ack/Nack polling on FN=2404748, TS=6<br><0008> tbf.cpp:650 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=WAIT RELEASE) T3193 timeout expired, freeing TBF<br><0008> tbf.cpp:486 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=RELEASING) free<br><000c> gprs_bssgp_pcu.cpp:168 LLC [SGSN -> PCU] = TLLI: 0xaf8ce1ff IMSI: 460000323182742 len: 26<br><0008> tbf.cpp:1068 Allocating DL TBF: MS_CLASS=12/12<br><0008> tbf.cpp:541 TBF(TFI=0 TLLI=0x00000000 DIR=DL STATE=NULL) Setting Control TS 6<br><0008> tbf.cpp:945 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=NULL) Allocated: trx = 0, ul_slots = 40, dl_slots = 40<br><0008> bts.cpp:898 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) TX: START Immediate Assignment Downlink (PCH)<br><0008> tbf_dl.cpp:520 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) set ass. type PACCH [prev CCCH:0, PACCH:0]<br><0008> tbf.cpp:1270 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)<br><0009> tbf.cpp:788 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=ASSIGN) Scheduled DL Assignment polling on PACCH (FN=2405537, TS=6)<br><0007> gprs_rlcmac_meas.cpp:184 DL Bandwitdh of IMSI=460000323182742 / TLLI=0xaf8ce1ff: 25 KBits/s<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2405585, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2405632, TS=6<br><0009> tbf_dl.cpp:880 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Scheduled Ack/Nack polling on FN=2405680, TS=6<br><0008> tbf.cpp:808 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) poll timeout for FN=2405680, TS=6 (curr FN 2405741)<br><0008> tbf.cpp:879 TBF(TFI=0 TLLI=0xaf8ce1ff DIR=DL STATE=FLOW) Timeout for polling PACKET DOWNLINK ACK.<br></div><div><br></div><div>osmo-sgsn debugging info:</div><div><0011> gprs_llc_parse.c:78 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0xa3ad04 CMD=UI DATA <br><0002> gprs_gmm.c:1118 MM(---/ffffffff) -> GMM ATTACH REQUEST MI(4018987519) type="GPRS attach" <br><0002> fsm.c:461 GMM(gmm_fsm)[0xaaaaf63e5b50]{Deregistered}: Allocated<br><0002> fsm.c:461 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{Init}: Allocated<br><0002> fsm.c:461 MM_STATE_Gb[0xaaaaf63e5e50]{Idle}: Allocated<br><0002> gprs_sgsn.c:299 MM(/00000000) Allocated with GEA0 cipher.<br><0002> gprs_gmm.c:1100 GMM(gmm_fsm)[0xaaaaf63e5b50]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ<br><0002> gprs_gmm_fsm.c:25 GMM(gmm_fsm)[0xaaaaf63e5b50]{Deregistered}: state_chg to CommonProcedureInitiated<br><0011> gprs_llc.c:1056 LLME(ffffffff/af8ce1ff){UNASSIGNED} LLGM Assign pre (af8ce1ff => cf0ebf06)<br><0011> gprs_llc.c:1102 LLME(af8ce1ff/cf0ebf06){ASSIGNED} LLGM Assign post (af8ce1ff => cf0ebf06)<br><0002> gprs_gmm.c:1271 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{Init}: Received Event E_ATTACH_REQ_RECV<br><0002> gprs_gmm_attach.c:50 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{Init}: state_chg to CheckIdentity<br><0002> gprs_gmm.c:409 MM(/cf0ebf06) <- GPRS IDENTITY REQUEST: mi_type=IMEI<br><0010> gprs_bssgp.c:423 BSSGP TLLI=0xaf8ce1ff Rx UPLINK-UNITDATA<br><0011> gprs_llc_parse.c:78 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0x8c64cd CMD=UI DATA <br><0002> gprs_gb.c:40 MM_STATE_Gb(2945245695)[0xaaaaf63e5e50]{Idle}: Received Event E_MM_PDU_RECEPTION<br><0002> gprs_gmm.c:1040 MM(/cf0ebf06) -> GMM IDENTITY RESPONSE: MI(IMEI)=866089038960590<br><0002> gprs_gmm.c:113 MM(/cf0ebf06) Stopping MM timer 3370 but 0 is running<br><0002> gprs_gmm.c:1084 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{CheckIdentity}: Received Event E_IDEN_RESP_RECV<br><0002> gprs_gmm_attach.c:111 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{CheckIdentity}: state_chg to CheckIdentity<br><0002> gprs_gmm.c:409 MM(/cf0ebf06) <- GPRS IDENTITY REQUEST: mi_type=IMSI<br><0010> gprs_bssgp.c:423 BSSGP TLLI=0xaf8ce1ff Rx UPLINK-UNITDATA<br><0011> gprs_llc_parse.c:78 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0xb71895 CMD=UI DATA <br><0002> gprs_gb.c:40 MM_STATE_Gb(2945245695)[0xaaaaf63e5e50]{Idle}: Received Event E_MM_PDU_RECEPTION<br><0002> gprs_gmm.c:1040 MM(/cf0ebf06) -> GMM IDENTITY RESPONSE: MI(IMSI)=460000323182742<br><0002> gprs_gmm.c:113 MM(/cf0ebf06) Stopping MM timer 3370 but 0 is running<br><0002> gprs_gmm.c:1084 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{CheckIdentity}: Received Event E_IDEN_RESP_RECV<br><0002> gprs_gmm_attach.c:113 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{CheckIdentity}: state_chg to Authenticate<br><0002> gprs_gmm_attach.c:148 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{Authenticate}: state_chg to WaitAttachComplete<br><0002> gprs_gmm.c:289 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (new P-TMSI=0xcf0ebf06)<br><0002> gprs_gmm.c:305 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (ra_id=0x020xf20x50 lac:2f11 rac:2)<br><000e> gprs_sgsn.c:1012 Checking for inactive LLMEs, time = 1652<br><000f> gprs_ns.c:779 NSEI=1234 Timer expired in mode tns-test (30 seconds)<br><000f> gprs_ns.c:712 NSEI=1234 Tx NS ALIVE (NSVCI=1234)<br><000f> gprs_ns.c:751 NSEI=1234 Starting timer in mode tns-alive (3 seconds)<br><000f> gprs_ns.c:725 NSEI=1234 Tx NS ALIVE_ACK (NSVCI=1234)<br><000f> gprs_ns.c:1763 NSEI 1234 (NS-VCI=1234) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]<br><000f> gprs_ns.c:751 NSEI=1234 Starting timer in mode tns-test (30 seconds)<br><0010> gprs_bssgp.c:839 BSSGP BVCI=1234 Rx Flow Control BVC<br><0002> fsm.c:322 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{WaitAttachComplete}: Timeout of T3350<br><0002> gprs_gmm.c:289 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (new P-TMSI=0xcf0ebf06)<br><0002> gprs_gmm.c:305 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (ra_id=0x020xf20x50 lac:2f11 rac:2)<br><0002> fsm.c:322 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{WaitAttachComplete}: Timeout of T3350<br><0002> gprs_gmm.c:289 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (new P-TMSI=0xcf0ebf06)<br><0002> gprs_gmm.c:305 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (ra_id=0x020xf20x50 lac:2f11 rac:2)<br><0010> gprs_bssgp.c:423 BSSGP TLLI=0xaf8ce1ff Rx UPLINK-UNITDATA<br><0011> gprs_llc_parse.c:78 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0x0d0447 CMD=UI DATA <br><0002> gprs_gb.c:40 MM_STATE_Gb(2945245695)[0xaaaaf63e5e50]{Idle}: Received Event E_MM_PDU_RECEPTION<br><0002> gprs_gmm.c:1118 MM(460000323182742/cf0ebf06) -> GMM ATTACH REQUEST MI(4018987519) type="GPRS attach" <br><0002> gprs_gmm.c:1100 GMM(gmm_fsm)[0xaaaaf63e5b50]{CommonProcedureInitiated}: Received Event E_GMM_COMMON_PROC_INIT_REQ<br><0002> gprs_gmm.c:1100 GMM(gmm_fsm)[0xaaaaf63e5b50]{CommonProcedureInitiated}: Event E_GMM_COMMON_PROC_INIT_REQ not permitted<br><0011> gprs_llc.c:1056 LLME(af8ce1ff/cf0ebf06){ASSIGNED} LLGM Assign pre (af8ce1ff => cf0ebf06)<br><0011> gprs_llc.c:1102 LLME(af8ce1ff/cf0ebf06){ASSIGNED} LLGM Assign post (af8ce1ff => cf0ebf06)<br><0002> gprs_gmm.c:1271 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{WaitAttachComplete}: Received Event E_ATTACH_REQ_RECV<br><0002> gprs_gmm_attach.c:360 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{WaitAttachComplete}: state_chg to Init<br><0002> gprs_gmm_attach.c:50 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{Init}: state_chg to CheckIdentity<br><0002> gprs_gmm.c:409 MM(460000323182742/cf0ebf06) <- GPRS IDENTITY REQUEST: mi_type=IMEI<br><0010> gprs_bssgp.c:839 BSSGP BVCI=1234 Rx Flow Control BVC<br><0010> gprs_bssgp.c:423 BSSGP TLLI=0xaf8ce1ff Rx UPLINK-UNITDATA<br><0011> gprs_llc_parse.c:78 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0xea5c4f CMD=UI DATA <br><0002> gprs_gb.c:40 MM_STATE_Gb(2945245695)[0xaaaaf63e5e50]{Idle}: Received Event E_MM_PDU_RECEPTION<br><0002> gprs_gmm.c:1040 MM(460000323182742/cf0ebf06) -> GMM IDENTITY RESPONSE: MI(IMEI)=866089038960590<br><0002> gprs_gmm.c:113 MM(460000323182742/cf0ebf06) Stopping MM timer 3370 but 0 is running<br><0002> gprs_gmm.c:1084 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{CheckIdentity}: Received Event E_IDEN_RESP_RECV<br><0002> gprs_gmm_attach.c:113 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{CheckIdentity}: state_chg to Authenticate<br><0002> gprs_gmm_attach.c:148 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{Authenticate}: state_chg to WaitAttachComplete<br><0002> gprs_gmm.c:289 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (new P-TMSI=0xcf0ebf06)<br><0002> gprs_gmm.c:305 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (ra_id=0x020xf20x50 lac:2f11 rac:2)<br><0002> fsm.c:322 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0xaaaaf63e5c80]{WaitAttachComplete}: Timeout of T3350<br><0002> gprs_gmm.c:289 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (new P-TMSI=0xcf0ebf06)<br><0002> gprs_gmm.c:305 MM(460000323182742/cf0ebf06) <- GPRS ATTACH ACCEPT (ra_id=0x020xf20x50 lac:2f11 rac:2)<br></div><div><br></div><div>Configure information:</div><div>gprs mode egprs<br>  gprs routing area 2<br>  gprs cell bvci 1234<br>  gprs nsei 1234<br>  gprs nsvc 0 nsvci 1234<br>  gprs nsvc 0 local udp port 23001<br>  gprs nsvc 0 remote udp port 23000<br>  。。。</div><div>   timeslot 6<br>    phys_chan_config PDCH<br>    hopping enabled 0<br>   timeslot 7<br>    phys_chan_config PDCH<br>    hopping enabled 0</div><div><br></div><div>pcu<br> flow-control-interval 10<br> cs 2<br> cs max 4<br> cs threshold 10 33<br> cs downgrade-threshold 200<br> cs link-quality-ranges cs1 6 cs2 5 8 cs3 7 13 cs4 12<br> mcs link-quality-ranges mcs1 6 mcs2 5 8 mcs3 7 13 mcs4 12 15 mcs5 14 17 mcs6 16 18 mcs7 17 20 mcs8 19 24 mcs9 23<br> mcs max 9<br> window-size 64 0<br> queue idle-ack-delay 10<br> queue codel<br> alloc-algorithm dynamic<br> alpha 0<br> gamma 0<br> dl-tbf-idle-time 2000<br></div><div><br></div><div>sgsn<br> gtp local-ip 127.0.0.1<br> ggsn 0 remote-ip 127.0.0.6<br> ggsn 0 gtp-version 1<br> auth-policy accept-all<br>ns<br> timer tns-block 3<br> timer tns-block-retries 3<br> timer tns-reset 3<br> timer tns-reset-retries 3<br> timer tns-test 30<br> timer tns-alive 3<br> timer tns-alive-retries 10<br> encapsulation udp local-ip 127.0.0.1<br> encapsulation udp local-port 23000<br> encapsulation framerelay-gre enabled 0<br><br></div><div>Version information:</div><div>OsmoPCU version 0.8.0</div><div>OsmoSGSN version 1.6.2</div><div><br></div><div>I tried to debug this issue but the no any progress for this issue.The root cause is confifure or something else?</div><div>I need help.</div><div>Thanks a lot.</div><div><br></div><div>Br,</div><div>Perter</div><div><br></div></blockquote></div></div>