Bug #1784
Updated by zecke over 7 years ago
Bug Summary from UX pov: No early media when connecting through voip provider. No audio feedback of call progress on local GSM-GSM calls with (at least) certain Nokia phones. This is a diff of two log segment from osmo-nitb. The call is exactly the same, osc.log was created with osmo-sip-connector and lcr.log with LCR. I note the extra MNCC sent by by LCR: <pre> $ diff -u osc.log lcr.log --- osc.log 2016-07-26 16:17:19.986897563 +0100 +++ lcr.log 2016-07-26 16:17:33.238845393 +0100 @@ -17,6 +17,12 @@ <0001> gsm_04_08.c:1300 new state NULL -> INITIATED <0001> gsm_04_08.c:1935 Subscriber Meteor (68000137793) sends SETUP to 68000135657 <0001> gsm_04_08.c:1361 (bts 0 trx 0 ts 2 ti 8 sub 68000137793) Sending 'MNCC_SETUP_IND' to MNCC. +<0006> gsm_04_08.c:3211 receive message MNCC_CALL_PROC_REQ +<0001> gsm_04_08.c:3415 (bts 0 trx 0 ts 2 ti 08 sub 68000137793) Received 'MNCC_CALL_PROC_REQ' from MNCC in state 1 (INITIATED) +<0001> gsm_04_08.c:1300 new state INITIATED -> MO_CALL_PROC +<0001> gsm_04_08.c:138 (bts 0 trx 0 ts 2 ti 80) Sending 'CALL_PROC' to MS. +<0006> gsm_04_08.c:3211 receive message MNCC_FRAME_RECV +<0001> gsm_04_08.c:1722 queue tch_recv_mncc request (1) <0006> gsm_04_08.c:3211 receive message MNCC_RTP_CREATE <000a> bsc_api.c:383 Sending ChanModify for speech 1 1 <0003> gsm_04_08_utils.c:461 -> CHANNEL MODE MODIFY mode=0x01 @@ -25,13 +31,9 @@ <0004> abis_rsl.c:1866 (bts=0,trx=0,ts=2,ss=0) IPAC_BIND speech_mode=0x10 RTP_PAYLOAD=3 <0003> osmo_msc.c:72 MSC assign complete (do nothing). <0004> abis_rsl.c:1217 (bts=0,trx=0,ts=2,ss=0) CHANNEL MODE MODIFY ACK -<0004> abis_rsl.c:2030 (bts=0,trx=0,ts=2,ss=0) IPAC_CRCX_ACK LOCAL_IP=172.16.0.11 LOCAL_PORT=14286 CON_ID=0 <0006> gsm_04_08.c:3118 (bts=0,trx=0,ts=2,ss=0) sending pending RTP create ind. +<0004> abis_rsl.c:2030 (bts=0,trx=0,ts=2,ss=0) IPAC_CRCX_ACK LOCAL_IP=172.16.0.11 LOCAL_PORT=58708 CON_ID=0 <0006> gsm_04_08.c:3118 (bts=0,trx=0,ts=2,ss=0) sending pending RTP create ind. <0001> gsm_04_08.c:1369 (bts - trx - ts - ti -- sub -) Sending 'MNCC_RTP_CREATE' to MNCC. -<0006> gsm_04_08.c:3211 receive message MNCC_CALL_PROC_REQ -<0001> gsm_04_08.c:3415 (bts 0 trx 0 ts 2 ti 08 sub 68000137793) Received 'MNCC_CALL_PROC_REQ' from MNCC in state 1 (INITIATED) -<0001> gsm_04_08.c:1300 new state INITIATED -> MO_CALL_PROC -<0001> gsm_04_08.c:138 (bts 0 trx 0 ts 2 ti 80) Sending 'CALL_PROC' to MS. <0006> gsm_04_08.c:3211 receive message MNCC_SETUP_REQ <000d> db.c:894 Found Subscriber: ID 72, IMSI 214220001782190, NAME 'DigiMovil', TMSI 4294967295, EXTEN '68000135657', LAC 1, AUTH 1 <0001> transaction.c:71 subscr=0xf578f0, net=0xf11a80 @@ -39,7 +41,7 @@ <0007> paging.c:291 Start paging of subscriber 72 on bts 0. <0007> paging.c:83 Going to send paging commands: imsi: '214220001782190' tmsi: '0xffffffff' <0007> paging.c:83 Going to send paging commands: imsi: '214220001782190' tmsi: '0xffffffff' -<0004> abis_rsl.c:1464 (bts=0,trx=0,ts=3,ss=0) Activating ARFCN(31) SS(0) lctype TCH/F r=PAGING ra=0x2f ta=0 +<0004> abis_rsl.c:1464 (bts=0,trx=0,ts=3,ss=0) Activating ARFCN(31) SS(0) lctype TCH/F r=PAGING ra=0x2e ta=0 <0004> abis_rsl.c:1198 (bts=0,trx=0,ts=3,ss=0) CHANNEL ACTIVATE ACK <0007> paging.c:83 Going to send paging commands: imsi: '214220001782190' tmsi: '0xffffffff' <0000> abis_rsl.c:1652 (bts=0,trx=0,ts=3,ss=0) SAPI=0 ESTABLISH INDICATION @@ -62,7 +64,8 @@ <0001> gsm_04_08.c:1857 starting timer T310 with 180 seconds <0001> gsm_04_08.c:1300 new state CALL_PRESENT -> MO_TERM_CALL_CONF <0001> gsm_04_08.c:1361 (bts 0 trx 0 ts 3 ti 0 sub 68000135657) Sending 'MNCC_CALL_CONF_IND' to MNCC. -<0006> gsm_04_08.c:3211 receive message MNCC_RTP_CREATE +<0006> gsm_04_08.c:3211 receive message MNCC_LCHAN_MODIFY +<0001> gsm_04_08.c:3415 (bts 0 trx 0 ts 3 ti 00 sub 68000135657) Received 'MNCC_LCHAN_MODIFY' from MNCC in state 9 (MO_TERM_CALL_CONF) <000a> bsc_api.c:383 Sending ChanModify for speech 1 1 <0003> gsm_04_08_utils.c:461 -> CHANNEL MODE MODIFY mode=0x01 <0000> abis_rsl.c:1652 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION @@ -70,8 +73,9 @@ <0004> abis_rsl.c:1866 (bts=0,trx=0,ts=3,ss=0) IPAC_BIND speech_mode=0x10 RTP_PAYLOAD=3 <0003> osmo_msc.c:72 MSC assign complete (do nothing). <0004> abis_rsl.c:1217 (bts=0,trx=0,ts=3,ss=0) CHANNEL MODE MODIFY ACK -<0004> abis_rsl.c:2030 (bts=0,trx=0,ts=3,ss=0) IPAC_CRCX_ACK LOCAL_IP=172.16.0.11 LOCAL_PORT=53836 CON_ID=0 <0006> gsm_04_08.c:3118 (bts=0,trx=0,ts=3,ss=0) sending pending RTP create ind. -<0001> gsm_04_08.c:1369 (bts - trx - ts - ti -- sub -) Sending 'MNCC_RTP_CREATE' to MNCC. +<0004> abis_rsl.c:2030 (bts=0,trx=0,ts=3,ss=0) IPAC_CRCX_ACK LOCAL_IP=172.16.0.11 LOCAL_PORT=13340 CON_ID=0 <001b> rtp_proxy.c:542 rtp_socket_create(): success +<001b> rtp_proxy.c:627 rtp_socket_bind(rs=0xf40f00, IP=0.0.0.0): BOUND_IP=0.0.0.0, BOUND_PORT=30000 +<001b> rtp_proxy.c:682 rtp_socket_connect(rs=0xf40f00, ip=172.16.0.11, port=13340) <0000> abis_rsl.c:1652 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION <0000> gsm_04_08.c:3584 Dispatching 04.08 message, pdisc=3 @@ -80,6 +84,13 @@ <0001> gsm_04_08.c:1857 starting timer T301 with 180 seconds <0001> gsm_04_08.c:1300 new state MO_TERM_CALL_CONF -> CALL_RECEIVED <0001> gsm_04_08.c:1361 (bts 0 trx 0 ts 3 ti 0 sub 68000135657) Sending 'MNCC_ALERT_IND' to MNCC. +<0006> gsm_04_08.c:3211 receive message MNCC_LCHAN_MODIFY +<0001> gsm_04_08.c:3415 (bts 0 trx 0 ts 2 ti 08 sub 68000137793) Received 'MNCC_LCHAN_MODIFY' from MNCC in state 3 (MO_CALL_PROC) +<0006> gsm_04_08.c:3211 receive message MNCC_RTP_CONNECT +<0004> abis_rsl.c:1905 (bts=0,trx=0,ts=2,ss=0) IPAC_MDCX IP=172.16.0.1 PORT=16396 RTP_PAYLOAD=3 RTP_PAYLOAD2=0 CONN_ID=0 speech_mode=0x00 +<0004> abis_rsl.c:2040 (bts=0,trx=0,ts=2,ss=0) IPAC_MDCX_ACK LOCAL_IP=172.16.0.11 LOCAL_PORT=58708 CON_ID=0 <0006> gsm_04_08.c:3131 (bts=0,trx=0,ts=2,ss=0) sending pending RTP connect ind. +<0001> gsm_04_08.c:1369 (bts - trx - ts - ti -- sub -) Sending 'MNCC_RTP_CONNECT' to MNCC. +<0000> abis_rsl.c:1652 (bts=0,trx=0,ts=2,ss=0) SAPI=0 DATA INDICATION <0000> gsm_04_08.c:3584 Dispatching 04.08 message, pdisc=3 <0001> gsm_04_08.c:3508 (bts 0 trx 0 ts 2 ti 8 sub 68000137793) Received 'DISCONNECT' from MS in state 3 (MO_CALL_PROC) @@ -116,10 +127,10 @@ <0004> abis_rsl.c:615 (bts=0,trx=0,ts=2,ss=0) DEACTivate SACCH CMD <0000> abis_rsl.c:1652 (bts=0,trx=0,ts=3,ss=0) SAPI=0 RELEASE INDICATION <0004> abis_rsl.c:661 (bts=0,trx=0,ts=3,ss=0) RF Channel Release CMD due error 0 -<0004> abis_rsl.c:2049 (bts=0,trx=0,ts=3,ss=0) IPAC_DLCX_IND CAUSE=0x0f(Normal event, unspecified) +<0004> abis_rsl.c:2049 (bts=0,trx=0,ts=3,ss=0) IPAC_DLCX_IND CAUSE=0x0f(Normal event, unspecified) <001b> rtp_proxy.c:743 rtp_socket_free(rs=0xf40f00) +<0004> abis_rsl.c:717 (bts=0,trx=0,ts=3,ss=0) RF CHANNEL RELEASE ACK <0000> abis_rsl.c:1652 (bts=0,trx=0,ts=2,ss=0) SAPI=0 RELEASE INDICATION <0004> abis_rsl.c:661 (bts=0,trx=0,ts=2,ss=0) RF Channel Release CMD due error 0 <0004> abis_rsl.c:2049 (bts=0,trx=0,ts=2,ss=0) IPAC_DLCX_IND CAUSE=0x0f(Normal event, unspecified) <0004> abis_rsl.c:717 (bts=0,trx=0,ts=2,ss=0) RF CHANNEL RELEASE ACK - </pre>