Actions
Bug #2960
openOsmoSGSN confirms PDP CTX ACT to MS/Gb despite missing conditional IEs on GTP
Status:
New
Priority:
Normal
Assignee:
-
Category:
-
Target version:
-
Start date:
02/17/2018
Due date:
% Done:
0%
Spec Reference:
Description
When the
GTP Create PDP Context Response
is positive but missing some conditional IE (e.g. GTPIE_CHARGING_ID), we end up in a weird state where we
- Confirm PDP CTX activation to the MS, i.e. Gb/Um side thinks the PDP context is active
- Continue to re-transmit CreatePDP Context Requests to the GGSN
- Eventually those attempts time out
- we then send a PDP CTX Activate Reject after we already confirmed it before
Sat Feb 17 22:15:20 2018 DLGLOBAL <001d> telnet_interface.c:104 telnet at 127.0.0.1 4245 Sat Feb 17 22:15:20 2018 DLCTRL <0024> control_if.c:854 CTRL at 127.0.0.1 4251 Sat Feb 17 22:15:20 2018 DLGTP <0025> gtp.c:757 GTP: gtp_newgsn() started at 127.0.0.1 Sat Feb 17 22:15:20 2018 DGPRS <000f> sgsn_libgtp.c:839 Created GTP on 127.0.0.1 Sat Feb 17 22:15:20 2018 DGPRS <000f> sgsn_main.c:478 libGTP v1.1.0 initialized Sat Feb 17 22:15:20 2018 DLGSUP <0027> gsup_client.c:76 GSUP connecting to 127.0.0.1:4222 Sat Feb 17 22:15:20 2018 DNS <0010> gprs_ns.c:1628 Listening for nsip packets on 127.0.0.1:23000 Sat Feb 17 22:15:20 2018 DNS <0010> gprs_ns.c:1641 NS UDP socket at 127.0.0.1:23000 Sat Feb 17 22:15:30 2018 DLGSUP <0027> gsup_client.c:76 GSUP connecting to 127.0.0.1:4222 Sat Feb 17 22:15:34 2018 DBSSGP <0011> gprs_bssgp.c:289 Cell 262-42-13135-0 CI 20960 on BVCI 196 Sat Feb 17 22:15:40 2018 DLGSUP <0027> gsup_client.c:76 GSUP connecting to 127.0.0.1:4222 Sat Feb 17 22:15:40 2018 DLINP <001f> input/ipa.c:129 connection done. Sat Feb 17 22:15:40 2018 DLINP <001f> input/ipaccess.c:707 received ID get Sat Feb 17 22:15:40 2018 DLLC <0012> gprs_llc.c:526 LLC RX: unknown TLLI 0xd8c18acb, creating LLME on the fly Sat Feb 17 22:15:40 2018 DLLC <0012> gprs_llc_parse.c:81 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x24bf94 CMD=UI DATA Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:1271 MM(---/ffffffff) -> GMM ATTACH REQUEST MI(262420000000017) type="GPRS attach" Sat Feb 17 22:15:40 2018 DMM <0002> gprs_sgsn.c:237 MM(/00000000) Allocated with GEA0 cipher. Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:556 MM(262420000000017/fd9e8165) <- GPRS IDENTITY REQUEST: mi_type=IMEI Sat Feb 17 22:15:40 2018 DLLC <0012> gprs_llc_parse.c:81 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x612fca CMD=UI DATA Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:1194 MM(262420000000017/fd9e8165) -> GMM IDENTITY RESPONSE: MI(IMEI)=499990000000017 Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:160 MM(262420000000017/fd9e8165) Requesting authorization Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:185 MM(262420000000017/fd9e8165) Requesting authentication tuples Sat Feb 17 22:15:40 2018 DMM <0002> gprs_subscriber.c:894 MM(262420000000017/fd9e8165) Requesting subscriber authentication info Sat Feb 17 22:15:40 2018 DMM <0002> gprs_sgsn.c:726 MM(262420000000017/fd9e8165) Subscriber data update Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:219 MM(262420000000017/fd9e8165) Updating authorization (unknown -> authenticate) Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:248 MM(262420000000017/fd9e8165) Got authorization update: state unknown -> authenticate Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:591 MM(262420000000017/fd9e8165) <- GPRS AUTH AND CIPHERING REQ (rand = 2c 6b 09 d8 c4 fd f6 42 b0 82 a8 99 dc 4b cc e5 ) Sat Feb 17 22:15:40 2018 DLLC <0012> gprs_llc_parse.c:81 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xe62da8 CMD=UI DATA Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:731 MM(262420000000017/fd9e8165) -> GPRS AUTH AND CIPH RESPONSE Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:778 MM(262420000000017/fd9e8165) checking auth: received GSM SRES = c2 82 3f 50 Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:160 MM(262420000000017/fd9e8165) Requesting authorization Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:196 MM(262420000000017/fd9e8165) Missing information, requesting subscriber data Sat Feb 17 22:15:40 2018 DMM <0002> gprs_subscriber.c:869 MM(262420000000017/fd9e8165) Requesting subscriber data update Sat Feb 17 22:15:40 2018 DMM <0002> gprs_sgsn.c:726 MM(262420000000017/fd9e8165) Subscriber data update Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:219 MM(262420000000017/fd9e8165) Updating authorization (authenticate -> accepted) Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:248 MM(262420000000017/fd9e8165) Got authorization update: state authenticate -> accepted Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:1118 MM(262420000000017/fd9e8165) Authorized, continuing procedure, IMSI=262420000000017 Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:437 MM(262420000000017/fd9e8165) <- GPRS ATTACH ACCEPT (new P-TMSI=0xfd9e8165) Sat Feb 17 22:15:40 2018 DMM <0002> gprs_sgsn.c:726 MM(262420000000017/fd9e8165) Subscriber data update Sat Feb 17 22:15:40 2018 DMM <0002> sgsn_auth.c:219 MM(262420000000017/fd9e8165) Updating authorization (accepted -> accepted) Sat Feb 17 22:15:40 2018 DLLC <0012> gprs_llc_parse.c:81 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xffae8e CMD=UI DATA Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:1997 MM(262420000000017/fd9e8165) -> ATTACH COMPLETE Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:165 MM(262420000000017/fd9e8165) Changing MM state from MM IDLE to MM READY Sat Feb 17 22:15:40 2018 DLLC <0012> gprs_llc_parse.c:81 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x540e4a CMD=UI DATA Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:2445 MM(262420000000017/fd9e8165) -> ACTIVATE PDP CONTEXT REQ: SAPI=3 NSAPI=5 IETF IPv4 Sat Feb 17 22:15:40 2018 DMM <0002> gprs_sgsn.c:876 MM(262420000000017/fd9e8165) Found GGSN 0 for APN '' (requested '') Sat Feb 17 22:15:40 2018 DMM <0002> gprs_gmm.c:2332 MM(262420000000017/fd9e8165) Using GGSN 0 Sat Feb 17 22:15:40 2018 DGPRS <000f> sgsn_libgtp.c:176 PDP(262420000000017/0) Create PDP Context Sat Feb 17 22:15:40 2018 DLGTP <0025> gtp.c:1795 Packet from 127.0.0.2:2123, length: 49 content: 32 11 00 29 00 00 00 01 b0 00 00 00 01 80 08 00 10 00 02 00 00 11 00 01 00 00 14 05 85 00 04 7f 00 00 01 85 00 04 7f 00 00 01 87 00 04 00 0b 92 1f : Missing conditional information field Sat Feb 17 22:15:40 2018 DGPRS <000f> sgsn_libgtp.c:619 libgtp EOF (type=16, pdp=0x7ff712ffc2e0, cbp=0x55df997cee50) Sat Feb 17 22:15:40 2018 DGPRS <000f> sgsn_libgtp.c:400 Create PDP ctx req timed out Sat Feb 17 22:15:40 2018 DLLC <0012> gprs_llc.c:342 Sending XID type NULL (8 bytes) request to MS... Sat Feb 17 22:15:40 2018 DLINP <001f> input/ipa.c:67 connection closed with server Sat Feb 17 22:15:50 2018 DLGSUP <0027> gsup_client.c:76 GSUP connecting to 127.0.0.1:4222 Sat Feb 17 22:16:00 2018 DLGSUP <0027> gsup_client.c:76 GSUP connecting to 127.0.0.1:4222 Sat Feb 17 22:16:08 2018 DGPRS <000f> sgsn_libgtp.c:619 libgtp EOF (type=16, pdp=(nil), cbp=0x55df997cee50) Sat Feb 17 22:16:08 2018 DGPRS <000f> sgsn_libgtp.c:400 Create PDP ctx req timed out Sat Feb 17 22:16:08 2018 DMM <0002> gprs_gmm.c:2258 MM(262420000000017/fd9e8165) <- ACTIVATE PDP CONTEXT REJ(cause=38)
Files
Related issues
Actions