chan_mobile dtmf incoming mobile calls problems

Hello Asterisk User,

maybe someone had a similar problem with Asterisk and the chan_mobile.

Asterisk: SVN-trunk-r89454M
Application: Incoming call from Mobile network -> autorization with password -> Dialtone and outgoing call via SIP Provider
Mobile: Sony Ericson K610i / Samsung SGH-F200
BT Adapter: USB dongle V1.2

extension.conf

[incoming_mobile]
exten => s,1,Authenticate(1234)
exten => s,2,Background(pls-wait-connect-call)
exten => s,3,DISA(no-password,phones,“sipgate” <7001>)

For the first tests these entries in the extension.conf should do the job.
The problem is that in case of an incoming call, asterisk does not detect the DTMF tones. No authorization possible. But in case of outgoing calls DTMF is send without any problems. Normal calls are working fine, incoming and outgoing with good speech quality via the “chan_mobile”.

I already tried a normal call from PSTN to my mobile. I pressed keys at the PSTN phone, and at the mobile side DTMF tone was there !!

Does anybody has incoming DTMF tones with chan_mobile working ? I think it would be really helpfull, that we can compare the debug messages.

Incoming call chan_mobile (no DTMF):

[Nov 23 11:20:07] DEBUG[3526] chan_mobile.c: Checking device state for device SGH-F200
[Nov 23 11:20:07] DEBUG[3526] chan_mobile.c: Checking device state for device SGH-F200
[Nov 23 11:20:08] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [OK]
[Nov 23 11:20:08] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 3,0]
[Nov 23 11:20:08] DEBUG[3526] chan_mobile.c: Device SGH-F200 [+CIEV: 3,0]
[Nov 23 11:20:08] DEBUG[3526] chan_mobile.c: accept()ed socket.
[Nov 23 11:20:08] DEBUG[3526] chan_mobile.c: Incoming Audio Connection from device 00:1B:59:14:77:38 MTU is 64
[Nov 23 11:20:08] DEBUG[3526] chan_mobile.c: About to accept() socket.
[Nov 23 11:20:45] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [RING]
[Nov 23 11:20:45] DEBUG[3526] chan_mobile.c: Device SGH-F200 [RING]
[Nov 23 11:20:45] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 4,1]
[Nov 23 11:20:45] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [+CLIP: “”,145,""]
[Nov 23 11:20:45] DEBUG[3526] pbx.c: Launching ‘Authenticate’
[Nov 23 11:20:45] VERBOSE[3526] logger.c: – Executing [s@incoming_mobile:1] e[1;36;40mAuthenticatee[0;37;40m(“e[1;35;40mMobile/SGH-F200-56f6e[0;37;40m”, “e[1;35;40m1234e[0;37;40m”) in new stack
[Nov 23 11:20:45] DEBUG[3526] chan_mobile.c: rfcomm_write() (SGH-F200) [ATA
]
[Nov 23 11:20:45] DEBUG[3526] chan_mobile.c: Checking device state for device SGH-F200
[Nov 23 11:20:45] DEBUG[3526] channel.c: Set channel Mobile/SGH-F200-56f6 to write format gsm
[Nov 23 11:20:45] VERBOSE[3526] logger.c: – <Mobile/SGH-F200-56f6> Playing ‘agent-pass.gsm’ (language ‘en’)
[Nov 23 11:20:46] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [OK]
[Nov 23 11:20:46] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 3,1]
[Nov 23 11:20:46] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 4,0]
[Nov 23 11:20:46] DEBUG[3526] chan_mobile.c: accept()ed socket.
[Nov 23 11:20:46] DEBUG[3526] chan_mobile.c: Incoming Audio Connection from device 00:1B:59:14:77:38 MTU is 64
[Nov 23 11:20:46] DEBUG[3526] chan_mobile.c: About to accept() socket.
[Nov 23 11:20:47] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,4]
[Nov 23 11:20:48] DEBUG[3526] channel.c: Set channel Mobile/SGH-F200-56f6 to write format slin
[Nov 23 11:20:58] DEBUG[3526] channel.c: Set channel Mobile/SGH-F200-56f6 to write format gsm
[Nov 23 11:20:58] VERBOSE[3526] logger.c: – <Mobile/SGH-F200-56f6> Playing ‘auth-incorrect.gsm’ (language ‘en’)


Outgoing call chan_mobile (DTMF OK):

[Nov 23 11:19:49] DEBUG[3526] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 4,0]
[Nov 23 11:19:50] DEBUG[3526] chan_mobile.c: Checking device state for device SGH-F200
[Nov 23 11:19:50] DEBUG[3526] chan_sip.c: Stopping retransmission on ‘0012dadb-f1620008-12445519-2371650d@10.0.1.22’ of Response 102: Match Found
[Nov 23 11:19:50] DEBUG[3526] rtp.c: Ooh, format changed from unknown to ulaw
[Nov 23 11:19:50] DEBUG[3526] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 23 11:19:56] DEBUG[3526] rtp.c: Sending dtmf: 49 (1), at 10.0.1.22
[Nov 23 11:19:56] DEBUG[3526] rtp.c: Sending dtmf: 49 (1), at 10.0.1.22
[Nov 23 11:19:56] DEBUG[3526] chan_mobile.c: Dialed 1
[Nov 23 11:19:56] DEBUG[3526] chan_mobile.c: rfcomm_write() (SGH-F200) [AT+VTS=1
]
[Nov 23 11:19:56] DEBUG[3526] rtp.c: Sending dtmf: 50 (2), at 10.0.1.22
[Nov 23 11:19:57] DEBUG[3526] rtp.c: Sending dtmf: 50 (2), at 10.0.1.22
[Nov 23 11:19:57] DEBUG[3526] chan_mobile.c: Dialed 2
[Nov 23 11:19:57] DEBUG[3526] chan_mobile.c: rfcomm_write() (SGH-F200) [AT+VTS=2