Skip to content
This repository has been archived by the owner on Sep 26, 2018. It is now read-only.

Calls not being completed #88

Open
marvdias opened this issue Nov 6, 2017 · 4 comments
Open

Calls not being completed #88

marvdias opened this issue Nov 6, 2017 · 4 comments

Comments

@marvdias
Copy link

marvdias commented Nov 6, 2017

I am currently trying to make 'internal' calls between clients, I already check and both clients have credits, however it does not complete the dial.
OpenBSC log:

<0004> abis_rsl.c:1852 BTS 0 CHAN RQD: reason: call re-establishment (ra=0x4d, neci=0x01, chreq_reason=0x02)
<0000> chan_alloc.c:352 (bts=0,trx=0,ts=3,pchan=TCH/F) Allocating lchan=0 as TCH_F
<0004> abis_rsl.c:1922 (bts=0,trx=0,ts=3,ss=0) Activating ARFCN(65) SS(0) lctype TCH_F r=CALL ra=0x4d ta=0
<0004> abis_rsl.c:595 (bts=0,trx=0,ts=3,pchan=TCH/F) Tx RSL Channel Activate with act_type=INITIAL
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state NONE -> ACTIVATION REQUESTED
<0004> abis_rsl.c:1603 (bts=0,trx=0,ts=3,ss=0) CHANNEL ACTIVATE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state ACTIVATION REQUESTED -> ACTIVE
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 ESTABLISH INDICATION
<0000> gsm_04_08.c:4007 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:1024 <- CM SERVICE REQUEST serv_type=0x01 MI(TMSI)=608646690
<000d> db.c:1307 Sync Equipment IMEI=355320084208080, classmark1=33, classmark2=33 19 82 , classmark3=60 14 04 62 88 10 01 27 e0 00
<0002> gsm_04_08_utils.c:667 -> CM SERVICE ACK
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION
<0003> bsc_api.c:605 GPRS SUSPENSION REQUEST
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:4007 Dispatching 04.08 message, pdisc=3
<0001> gsm_04_08.c:3902 (bts 0 trx 0 ts 3 ti 8 sub 15555581408) Received 'SETUP' from MS in state 0 (NULL)
<0001> gsm_04_08.c:3907 Unknown transaction ID 8, creating new trans.
<0001> transaction.c:71 subscr=0x557d746ddbc0, net=0x557d7453bdb0
<0001> gsm_04_08.c:1631 new state NULL -> INITIATED
<0001> gsm_04_08.c:2310 Subscriber Marcus Dias (15555581408) sends SETUP to 15555579368
<0001> gsm_04_08.c:1693 (bts 0 trx 0 ts 3 ti 8 sub 15555581408) Sending 'MNCC_SETUP_IND' to MNCC.
<0006> gsm_04_08.c:3593 receive message MNCC_RTP_CREATE
<0006> gsm_04_08.c:3424 RTP create: codec=SPEECH_V1, chan_type=TCH_F
<000a> bsc_api.c:415 Sending (bts=0,trx=0,ts=3,ss=0) ChanModify for speech: SPEECH_V1 on channel TCH_F
<0003> gsm_04_08_utils.c:517 -> CHANNEL MODE MODIFY mode=0x01
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION
<0003> gsm_04_08_utils.c:542 CHANNEL MODE MODIFY ACK
<0004> abis_rsl.c:2342 (bts=0,trx=0,ts=3,ss=0) IPAC_BIND speech_mode=0x10 RTP_PAYLOAD=3
<0003> osmo_msc.c:76 MSC assign complete (do nothing).
<0004> abis_rsl.c:1624 (bts=0,trx=0,ts=3,ss=0) CHANNEL MODE MODIFY ACK
<0004> abis_rsl.c:2523 (bts=0,trx=0,ts=3,ss=0) IPAC_CRCX_ACK LOCAL_IP=200.239.93.157 LOCAL_PORT=39406 CON_ID=0 <0006> gsm_04_08.c:3500 (bts=0,trx=0,ts=3,ss=0) sending pending RTP create ind.
<0001> gsm_04_08.c:1701 (bts - trx - ts - ti -- sub -) Sending 'MNCC_RTP_CREATE' to MNCC.

<0006> gsm_04_08.c:3593 receive message MNCC_CALL_PROC_REQ
<0001> gsm_04_08.c:3804 (bts 0 trx 0 ts 3 ti 08 sub 15555581408) Received 'MNCC_CALL_PROC_REQ' from MNCC in state 1 (INITIATED)
<0001> gsm_04_08.c:1631 new state INITIATED -> MO_CALL_PROC
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 3 ti 80) Sending 'CALL_PROC' to MS.
<0006> gsm_04_08.c:3593 receive message MNCC_DISC_REQ
<0001> gsm_04_08.c:3804 (bts 0 trx 0 ts 3 ti 08 sub 15555581408) Received 'MNCC_DISC_REQ' from MNCC in state 3 (MO_CALL_PROC)
<0001> gsm_04_08.c:2234 starting timer T306 with 30 seconds
<0001> gsm_04_08.c:1631 new state MO_CALL_PROC -> DISCONNECT_IND
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 3 ti 80) Sending 'DISCONNECT' to MS.
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:4007 Dispatching 04.08 message, pdisc=3
<0001> gsm_04_08.c:3902 (bts 0 trx 0 ts 3 ti 8 sub 15555581408) Received 'RELEASE' from MS in state 12 (DISCONNECT_IND)
<0001> gsm_04_08.c:1673 stopping pending timer T306
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 3 ti 80) Sending 'RELEASE_COMPL' to MS.
<0001> gsm_04_08.c:1693 (bts 0 trx 0 ts 3 ti 8 sub 15555581408) Sending 'MNCC_REL_IND' to MNCC.
<0001> gsm_04_08.c:1631 new state DISCONNECT_IND -> NULL
<0000> chan_alloc.c:486 (bts=0,trx=0,ts=3,ss=0) starting release sequence
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state ACTIVE -> RELEASE REQUESTED
<0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 0 Type: 2
<0004> abis_rsl.c:777 (bts=0,trx=0,ts=3,ss=0) DEACTivate SACCH CMD
<0004> abis_rsl.c:1445 (bts=0,trx=0,ts=3,ss=0): MEAS RES for inactive channel
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 RELEASE INDICATION
<0004> abis_rsl.c:1731 (bts=0,trx=0,ts=3,ss=0) T3111 expired: releasing RF Channel
<0004> abis_rsl.c:869 (bts=0,trx=0,ts=3,ss=0) RF Channel Release
<0004> abis_rsl.c:2542 (bts=0,trx=0,ts=3,ss=0) IPAC_DLCX_IND
<0004> abis_rsl.c:939 (bts=0,trx=0,ts=3,ss=0) RF CHANNEL RELEASE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state RELEASE REQUESTED -> NONE

freeswitch log:

2017-11-06 18:17:03.428847 [ERR] mod_smpp_gateway.c:297 Unrecognized Command ID: 258
2017-11-06 18:17:03.428847 [ERR] mod_smpp_gateway.c:379 Unknown command_id[258]
2017-11-06 18:24:31.428836 [DEBUG] switch_rtp.c:1459  [  zrtp cache]: 	Storing ZRTP cache to </var/lib/freeswitch/db/zrtp.dat>...
2017-11-06 18:24:31.428836 [DEBUG] switch_rtp.c:1320 Saving ZRTP cache: OK

Any ideas in how to solve this? Thanks

@kheimerl
Copy link
Contributor

kheimerl commented Nov 6, 2017 via email

@marvdias
Copy link
Author

marvdias commented Nov 6, 2017

I tried the following commands on fs_cli

sofia global siptrace on
sofia loglevel all 9
sofia tracelevel debug

When I try to make the calls nothing appears! How do I look for it?

@kheimerl
Copy link
Contributor

kheimerl commented Nov 6, 2017 via email

@marvdias
Copy link
Author

marvdias commented Nov 6, 2017

OpenBSC log while calling to 888:

<0004> abis_rsl.c:1852 BTS 0 CHAN RQD: reason: call re-establishment (ra=0x49, neci=0x01, chreq_reason=0x02)
<0000> chan_alloc.c:352 (bts=0,trx=0,ts=3,pchan=TCH/F) Allocating lchan=0 as TCH_F
<0004> abis_rsl.c:1922 (bts=0,trx=0,ts=3,ss=0) Activating ARFCN(65) SS(0) lctype TCH_F r=CALL ra=0x49 ta=0
<0004> abis_rsl.c:595 (bts=0,trx=0,ts=3,pchan=TCH/F) Tx RSL Channel Activate with act_type=INITIAL
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state NONE -> ACTIVATION REQUESTED
<0004> abis_rsl.c:1603 (bts=0,trx=0,ts=3,ss=0) CHANNEL ACTIVATE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state ACTIVATION REQUESTED -> ACTIVE
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 ESTABLISH INDICATION
<0000> gsm_04_08.c:4007 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:1024 <- CM SERVICE REQUEST serv_type=0x01 MI(TMSI)=966238291
<000d> db.c:1307 Sync Equipment IMEI=355302046072440, classmark1=33, classmark2=33 59 a6 , classmark3=60 14 20 00
<0002> gsm_04_08_utils.c:667 -> CM SERVICE ACK
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:4007 Dispatching 04.08 message, pdisc=3
<0001> gsm_04_08.c:3902 (bts 0 trx 0 ts 3 ti 8 sub 15555579368) Received 'SETUP' from MS in state 0 (NULL)
<0001> gsm_04_08.c:3907 Unknown transaction ID 8, creating new trans.
<0001> transaction.c:71 subscr=0x557d746a9a90, net=0x557d7453bdb0
<0001> gsm_04_08.c:1631 new state NULL -> INITIATED
<0001> gsm_04_08.c:2310 Subscriber 724050047114287 (15555579368) sends SETUP to 888
<0001> gsm_04_08.c:1693 (bts 0 trx 0 ts 3 ti 8 sub 15555579368) Sending 'MNCC_SETUP_IND' to MNCC.
<0006> gsm_04_08.c:3593 receive message MNCC_RTP_CREATE
<0006> gsm_04_08.c:3424 RTP create: codec=SPEECH_V1, chan_type=TCH_F
<000a> bsc_api.c:415 Sending (bts=0,trx=0,ts=3,ss=0) ChanModify for speech: SPEECH_V1 on channel TCH_F
<0003> gsm_04_08_utils.c:517 -> CHANNEL MODE MODIFY mode=0x01
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION
<0003> gsm_04_08_utils.c:542 CHANNEL MODE MODIFY ACK
<0004> abis_rsl.c:2342 (bts=0,trx=0,ts=3,ss=0) IPAC_BIND speech_mode=0x10 RTP_PAYLOAD=3
<0003> osmo_msc.c:76 MSC assign complete (do nothing).
<0004> abis_rsl.c:1624 (bts=0,trx=0,ts=3,ss=0) CHANNEL MODE MODIFY ACK
<0004> abis_rsl.c:2523 (bts=0,trx=0,ts=3,ss=0) IPAC_CRCX_ACK LOCAL_IP=200.239.93.157 LOCAL_PORT=62302 CON_ID=0 <0006> gsm_04_08.c:3500 (bts=0,trx=0,ts=3,ss=0) sending pending RTP create ind.
<0001> gsm_04_08.c:1701 (bts - trx - ts - ti -- sub -) Sending 'MNCC_RTP_CREATE' to MNCC.

<0006> gsm_04_08.c:3593 receive message MNCC_CALL_PROC_REQ
<0001> gsm_04_08.c:3804 (bts 0 trx 0 ts 3 ti 08 sub 15555579368) Received 'MNCC_CALL_PROC_REQ' from MNCC in state 1 (INITIATED)
<0001> gsm_04_08.c:1631 new state INITIATED -> MO_CALL_PROC
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 3 ti 80) Sending 'CALL_PROC' to MS.
<0006> gsm_04_08.c:3593 receive message MNCC_DISC_REQ
<0001> gsm_04_08.c:3804 (bts 0 trx 0 ts 3 ti 08 sub 15555579368) Received 'MNCC_DISC_REQ' from MNCC in state 3 (MO_CALL_PROC)
<0001> gsm_04_08.c:2234 starting timer T306 with 30 seconds
<0001> gsm_04_08.c:1631 new state MO_CALL_PROC -> DISCONNECT_IND
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 3 ti 80) Sending 'DISCONNECT' to MS.
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:4007 Dispatching 04.08 message, pdisc=3
<0001> gsm_04_08.c:3902 (bts 0 trx 0 ts 3 ti 8 sub 15555579368) Received 'RELEASE' from MS in state 12 (DISCONNECT_IND)
<0001> gsm_04_08.c:1673 stopping pending timer T306
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 3 ti 80) Sending 'RELEASE_COMPL' to MS.
<0001> gsm_04_08.c:1693 (bts 0 trx 0 ts 3 ti 8 sub 15555579368) Sending 'MNCC_REL_IND' to MNCC.
<0001> gsm_04_08.c:1631 new state DISCONNECT_IND -> NULL
<0000> chan_alloc.c:486 (bts=0,trx=0,ts=3,ss=0) starting release sequence
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state ACTIVE -> RELEASE REQUESTED
<0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 0 Type: 2
<0004> abis_rsl.c:777 (bts=0,trx=0,ts=3,ss=0) DEACTivate SACCH CMD
<0000> abis_rsl.c:2125 (bts=0,trx=0,ts=3,ss=0) SAPI=0 RELEASE INDICATION
<0004> abis_rsl.c:1731 (bts=0,trx=0,ts=3,ss=0) T3111 expired: releasing RF Channel
<0004> abis_rsl.c:869 (bts=0,trx=0,ts=3,ss=0) RF Channel Release
<0004> abis_rsl.c:2542 (bts=0,trx=0,ts=3,ss=0) IPAC_DLCX_IND
<0004> abis_rsl.c:939 (bts=0,trx=0,ts=3,ss=0) RF CHANNEL RELEASE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=3,ss=0) state RELEASE REQUESTED -> NONE

The call does not work :/

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants