DTMF issues

[Filipe Henriques]

Hi,

while trying to test revision 592d8fe515aa DTMF tones(inband) are not being detected (or almost never, on this mms-log there were no DTMF detected but sometimes it detects). Attached is the MMS log and the wireshark trace. I am able to check the DTMF reaching the media server (filter rtpevent) on the trace.

Best regards,

Filipe Henriques

2012-11-06 19:12:08,930 INFO  [MGCP] (Thread-1) tx=318818518 Started, message= CRCX mobicents/ivr/$@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

2012-11-06 19:12:08,932 INFO  [RtpConnectionImpl] (Thread-4) FormatsRTPFormats{0 AudioFormat[pcmu,8000,8,mono],8 AudioFormat[pcma,8000,8,mono],3 AudioFormat[gsm,8000,mono],18 AudioFormat[g729,8000,mono],101 AudioFormat[telephone-event,8000,mono],9 AudioFormat[g722,8000,mono],99 AudioFormat[g726-32,8000,mono],4 AudioFormat[g723,8000,mono]}

2012-11-06 19:12:08,935 INFO  [MGCP] (Thread-3) tx=318818518 was executed normaly

2012-11-06 19:12:09,711 INFO  [MGCP] (Thread-1) tx=318818519 Started, message= RQNT mobicents/ivr/3...@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

2012-11-06 19:12:09,723 INFO  [PlayCollect] (Thread-4) (mobicents/ivr/3) Prepare digit collect phase

2012-11-06 19:12:09,723 INFO  [PlayCollect] (Thread-4) (mobicents/ivr/3) Start prompt phase

2012-11-06 19:12:09,724 INFO  [DtmfBuffer] (Thread-4) (detector) Flush, buffer size: 0

2012-11-06 19:12:09,724 INFO  [MGCP] (Thread-4) tx=318818519 was executed normaly

2012-11-06 19:12:09,730 INFO  [JitterBuffer] (Thread-1) Format has been changed: 0 AudioFormat[pcmu,8000,8,mono]

2012-11-06 19:12:11,571 INFO  [AudioPlayerImpl] (Thread-1) (mobicents/ivr/3) End of file reached

2012-11-06 19:12:11,571 INFO  [PlayCollect] (Thread-1) (mobicents/ivr/3) Prompt phase terminated, start collect phase

2012-11-06 19:12:14,631 INFO  [PlayCollect] (Thread-2) (mobicents/ivr/3) Timeout expired waiting for dtmf

2012-11-06 19:12:14,632 INFO  [DtmfBuffer] (Thread-2) (detector) Flush, buffer size: 0

2012-11-06 19:12:14,632 INFO  [DtmfBuffer] (Thread-2) (detector) Flush, buffer size: 0

2012-11-06 19:12:15,730 INFO  [AudioPlayerImpl] (Thread-2) (mobicents/ivr/3) End of file reached

2012-11-06 19:12:15,731 INFO  [PlayCollect] (Thread-2) (mobicents/ivr/3) Prompt phase terminated, start collect phase

2012-11-06 19:12:18,731 INFO  [PlayCollect] (Thread-1) (mobicents/ivr/3) Timeout expired waiting for dtmf

2012-11-06 19:12:18,731 INFO  [DtmfBuffer] (Thread-1) (detector) Flush, buffer size: 0

2012-11-06 19:12:18,732 INFO  [DtmfBuffer] (Thread-1) (detector) Flush, buffer size: 0

2012-11-06 19:12:18,732 INFO  [DtmfBuffer] (Thread-1) (detector) Flush, buffer size: 0

2012-11-06 19:12:19,830 INFO  [AudioPlayerImpl] (Thread-2) (mobicents/ivr/3) End of file reached

2012-11-06 19:12:19,831 INFO  [PlayCollect] (Thread-2) (mobicents/ivr/3) Prompt phase terminated, start collect phase

2012-11-06 19:12:20,210 INFO  [MGCP] (Thread-1) tx=318818520 Started, message= DLCX mobicents/ivr/3...@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

2012-11-06 19:12:20,216 INFO  [MGCP] (Thread-4) tx=318818520 was executed normaly

[Filipe Henriques]

Forgot to mention that I was trying to test the PlayCollect functionality with the command: 

new EventName(AUPackage.AU, MgcpEvent.factory("pc").withParm("dp=[0-9]{5}# fdt=30 cb=true idt=50 na=3 ip="+INSERT+" rp="+TRY_AGAIN) 

[yulian_o]

Hello

Please provide your config file

Also please pay attention that you are using out of band dtmf and not inband as stated

Best regards

Yulian Oifa

[yulian_o]

Btw release 20651c447172 removes duplicate flushbuffer events

Best regards
Yulian Oifa

[Filipe Henriques]

Attached

Best regards,

Filipe Henriques

[yulian_o]

Ok , since everything looks ok lets start digging.
As i see by DLCX response the number of received and sent packets are equal to the number shown by wireshark.
That means that all packets are processed by mms.
What i want to check is if the rtp data channel identifies out of band dtmf packets as rfc 2833 , otherwise they will not be detected.
To do so i need you to change following :
1) Instead of dtmfConverter.write(rtpPacket); in
https://code.google.com/p/mediaserver/source/browse/io/rtp/src/main/java/org/mobicents/media/server/impl/rtp/RTPDataChannel.java#579
do
{
logger.info("dtmf packet detected");

dtmfConverter.write(rtpPacket);

}

2) Before
https://code.google.com/p/mediaserver/source/browse/io/rtp/src/main/java/org/mobicents/media/server/impl/rtp/rfc2833/DtmfConverter.java#85
do logger.info("dtmf payload readed");

3) Instead of return; in
https://code.google.com/p/mediaserver/source/browse/io/rtp/src/main/java/org/mobicents/media/server/impl/rtp/rfc2833/DtmfConverter.java#87
do
{
logger.info("no data in packet");

return;

}

You can set rfc buffer size in config file to 1.This will process dtmf tones faster.
Please post the log so i will be able to check the results.
Best regards
Yulian Oifa

[Filipe Henriques]

MMS log. If you need

2012-11-07 13:59:23,372 INFO  [MGCP] (Thread-1) tx=453642719 Started, message= CRCX mobicents/ivr/$@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

2012-11-07 13:59:23,377 INFO  [RtpConnectionImpl] (Thread-4) FormatsRTPFormats{0 AudioFormat[pcmu,8000,8,mono],8 AudioFormat[pcma,8000,8,mono],3 AudioFormat[gsm,8000,mono],18 AudioFormat[g729,8000,mono],101 AudioFormat[telephone-event,8000,mono],9 AudioFormat[g722,8000,mono],99 AudioFormat[g726-32,8000,mono],4 AudioFormat[g723,8000,mono]}

2012-11-07 13:59:23,379 INFO  [MGCP] (Thread-3) tx=453642719 was executed normaly

2012-11-07 13:59:24,131 INFO  [MGCP] (Thread-1) tx=453642720 Started, message= RQNT mobicents/ivr/2...@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

2012-11-07 13:59:24,131 INFO  [JitterBuffer] (Thread-1) Format has been changed: 0 AudioFormat[pcmu,8000,8,mono]

2012-11-07 13:59:24,138 INFO  [PlayCollect] (Thread-4) (mobicents/ivr/2) Prepare digit collect phase

2012-11-07 13:59:24,138 INFO  [PlayCollect] (Thread-4) (mobicents/ivr/2) Start prompt phase

2012-11-07 13:59:24,141 INFO  [DtmfBuffer] (Thread-4) (detector) Flush, buffer size: 0

2012-11-07 13:59:24,142 INFO  [MGCP] (Thread-4) tx=453642720 was executed normaly

2012-11-07 13:59:25,990 INFO  [AudioPlayerImpl] (Thread-1) (mobicents/ivr/2) End of file reached

2012-11-07 13:59:25,991 INFO  [PlayCollect] (Thread-1) (mobicents/ivr/2) Prompt phase terminated, start collect phase

2012-11-07 13:59:26,251 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,251 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,271 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,271 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,290 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,291 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,310 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,310 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,331 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,331 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,350 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,351 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,370 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:26,370 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:26,390 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,390 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,411 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:26,411 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:26,430 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,431 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,450 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:26,450 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:26,470 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,470 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,491 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:26,491 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:26,870 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,870 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,890 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,890 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,911 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,911 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,930 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,931 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,950 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,950 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,970 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,970 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:26,991 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:26,991 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,010 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,011 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,031 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:27,031 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:27,050 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,050 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,430 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,431 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,450 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,450 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,470 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,470 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,490 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,490 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,510 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,511 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:27,530 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:27,530 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:27,551 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:27,551 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:27,570 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:27,571 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:27,590 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:27,590 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:27,610 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:27,610 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:27,631 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:27,631 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,030 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,031 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,050 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,050 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,070 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,070 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,091 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,091 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,110 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,111 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,130 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,130 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,150 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,150 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,170 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,170 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,191 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,191 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,210 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,211 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,230 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,230 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,250 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,250 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,271 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,271 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,751 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,753 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,753 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,753 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,790 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,791 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,811 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:28,811 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:28,830 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,831 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,864 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,864 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,864 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,864 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,865 INFO  [EventBuffer] (Thread-1) Receive 5 tone

2012-11-07 13:59:28,865 INFO  [PlayCollect] (Thread-1) (mobicents/ivr/2) Tone '5' has been detected

2012-11-07 13:59:28,866 INFO  [PlayCollect] (Thread-1) (mobicents/ivr/2) Tone '5' has been detected: collected

2012-11-07 13:59:28,867 INFO  [DetectorImpl] (Thread-1) (mobicents/ivr/2) (detector) Delivered '5' tone

2012-11-07 13:59:28,891 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,891 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:28,910 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:28,911 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:33,910 INFO  [PlayCollect] (Thread-1) (mobicents/ivr/2) Timeout expired waiting for dtmf

2012-11-07 13:59:33,914 INFO  [DtmfBuffer] (Thread-1) (detector) Flush, buffer size: 0

2012-11-07 13:59:33,915 INFO  [DtmfBuffer] (Thread-1) (detector) Flush, buffer size: 0

2012-11-07 13:59:35,011 INFO  [AudioPlayerImpl] (Thread-1) (mobicents/ivr/2) End of file reached

2012-11-07 13:59:35,011 INFO  [PlayCollect] (Thread-1) (mobicents/ivr/2) Prompt phase terminated, start collect phase

2012-11-07 13:59:35,950 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:35,950 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:35,971 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:35,971 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:35,991 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:35,991 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,010 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:36,011 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,030 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:36,030 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,051 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:36,052 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,070 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:36,071 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,090 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:36,090 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,111 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:36,111 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:36,130 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:36,131 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,150 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:36,151 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:36,170 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:36,170 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:36,191 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:36,191 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:38,010 INFO  [PlayCollect] (Thread-2) (mobicents/ivr/2) Timeout expired waiting for dtmf

2012-11-07 13:59:38,011 INFO  [DtmfBuffer] (Thread-2) (detector) Flush, buffer size: 0

2012-11-07 13:59:38,011 INFO  [DtmfBuffer] (Thread-2) (detector) Flush, buffer size: 0

2012-11-07 13:59:38,012 INFO  [DtmfBuffer] (Thread-2) (detector) Flush, buffer size: 0

2012-11-07 13:59:39,110 INFO  [AudioPlayerImpl] (Thread-2) (mobicents/ivr/2) End of file reached

2012-11-07 13:59:39,111 INFO  [PlayCollect] (Thread-2) (mobicents/ivr/2) Prompt phase terminated, start collect phase

2012-11-07 13:59:39,710 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,711 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,731 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,731 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,751 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,751 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,770 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,771 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,791 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,792 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,810 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,810 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,830 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:39,830 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:39,851 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,851 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,870 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:39,871 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:39,890 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,890 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,911 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:39,911 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:39,930 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:39,931 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:40,750 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,750 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,770 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,770 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,791 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,791 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,810 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,810 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,830 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,830 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,851 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,851 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,870 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,870 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,890 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,890 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:40,910 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:40,911 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:42,090 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:42,091 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:42,110 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:42,111 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:42,111 INFO  [PlayCollect] (Thread-2) (mobicents/ivr/2) Timeout expired waiting for dtmf

2012-11-07 13:59:42,112 INFO  [MGCP] (Thread-2) tx=3 Started, message= NTFY mobicents/ivr/2...@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

2012-11-07 13:59:42,112 INFO  [MGCP] (Thread-3) tx=3 was executed normaly

2012-11-07 13:59:42,130 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:42,131 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:42,150 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:42,150 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:42,171 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:42,171 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:42,190 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:42,190 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:42,210 INFO  [RTPDataChannel] (Thread-1) dtmf payload detected

2012-11-07 13:59:42,210 INFO  [DtmfConverter] (Thread-1) dtmf payload readed

2012-11-07 13:59:42,231 INFO  [RTPDataChannel] (Thread-2) dtmf payload detected

2012-11-07 13:59:42,231 INFO  [DtmfConverter] (Thread-2) dtmf payload readed

2012-11-07 13:59:47,091 INFO  [MGCP] (Thread-2) tx=453642722 Started, message= DLCX mobicents/ivr/2...@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

2012-11-07 13:59:47,099 INFO  [MGCP] (Thread-3) tx=453642722 was executed normaly

2012/11/7 yulian_o <oifa....@gmail.com>

[yulian_o]

Hello
1) Is audio quailty good? as it appears on wireshark the sound is pretty bad for some reason.
2) Please update to latest trunk it will remove flush buffer duplicate messages
3) Please attach wav file played in announcement , new log file and wireshark

Thanks and best regards
Yulian Oifa

[Filipe Henriques]

Hi,

1) It has to do with the wav file. Nothing wrong in there.

2) Done

3) Attached

By the way, PlayCollect is returning rc=100 even if the pattern is not matched and timeouts ocurred on all the attempts. 

Best regards,

Filipe Henriques

2012/11/7 yulian_o <oifa....@gmail.com>

[yulian_o]

Hello
1) Its not about the wav file its about the sound quality i wanted to verify that audio file quaility is similar ( very bad ) as appears on wireshark to do so i need files you requested in prompt and reprompt ( insert and try_again )
2) I have opened an issue 98 for rc=100 i will resolve it today , it happens when mn and mx are not set.

Btw latest release where verified with out of band dtmf by mss team and everything is ok.So its local problem on your side.
As i think the only issue that may arrise is that audio data causes noise for dtmf tones and therefore they are not detected.

As i saw your client is sending rtp/avp , but is adding crypto attribute to it ( which means its rtp/avps ).
Also it sets telephone-events to 0-16 while most of clients are using 0-15.

Which client are you using?
Best regards
Yulian Oifa

[Filipe Henriques]

VoIP phone, snom 821. If it's on my side, I don't understand why it worked on 3.0.0 CR1 and now it's having all this problems.

Best regards,

Filipe Henriques

2012/11/7 yulian_o <oifa....@gmail.com>

Hello

[yulian_o]

Hello
1) Can you revert back to 3.0.0.CR1 and check if it works?
2) Please check http://wiki.snom.com/Settings/user_savp
Whether or not the crypto attribute is included depends on whether RTP encryption is on or off.
try to turn RTP encryption off.

Best regards
Yulian Oifa

[Filipe Henriques]

1) Yes it works on 3.0.0.CR1. I've been using it for development and everything has been working fine. I can do a capture tomorrow and post the logs and trace.

2) I'll test it tomorrow, since right now I don't have access to it. 

Best regards,

Filipe Henriques

2012/11/7 yulian_o <oifa....@gmail.com>

Hello

[Filipe Henriques]

1) That's what I was trying to say. That the audio file quality is similar to the one on the stream. 

Best regards,

Filipe Henriques 

2012/11/7 yulian_o <oifa....@gmail.com>

Hello

[yulian_o]

Hello
There are 2 things i am suspecting
1) Has been working does not always mean working now , simple software upgrade which may be automatic in some sip phones may cause different behaviour ,same with settings changes.
2) Did you set the RFC Buffer Size in configuration file to 1 as i requested? Since its the only change that has something with dtmf.By default its 3 while 3.0.0.CR1 it was always 1 , and there was no option to set it.
3) Because of encryption rtp packets may cause mixing problem ( since from mms point of view garbage is received and not unencrypted data ).

Best regards
Yulian Oifa

[Filipe Henriques]

Hi,

1) Yes but what I did is that after testing the other revisions, I changed to the release 3.0.0.CR1 and everything was still working there.

2) Yes I changed it on the configuration while testing the latest revision.

Best regards,

Filipe Henriques

2012/11/7 yulian_o <oifa....@gmail.com>

Hello

[Filipe Henriques]

I've been checking and I'll try to test the latest revision and change the RTPDataChannel and DtmfConverter classes to the ones before revision cd6f061a87a7. From there (if it works) I can do some digging (log some other stuff) and try to check if the problem is there. I'll provide some update tomorrow.

Best regards,

Filipe Henriques

[yulian_o]

Hello

Please try to use cd6f061a87a7 release as is , since it has been tested against several sip clients and different voip providers for out of band dtmf support.

Also if you will check files you will see that with rfc buffer set to 1 the code is actually the same as before.

Best regards

Yulian Oifa

[Filipe Henriques]

The problem starts exactly at revision cd6f061a87a7 . I've tested the previous revision and everything works fine, when i try this one, that's where the problems start.Then i tried to comment 

https://code.google.com/p/mediaserver/source/browse/io/rtp/src/main/java/org/mobicents/media/server/impl/rtp/rfc2833/DtmfConverter.java#123

//if(frameBuffer.size()==bufferSize)

wakeup();

and everything worked fine. When I try to do the same with the latest revision, everything is working.

Best regards,

Filipe Henriques

[yulian_o]

Hello Fillipe
Can you try to set

if(frameBuffer.size()>=bufferSize)

instead of removing it completely.Also i would like to verify once again that you are settings rfc buffer size to 1 while having problem
Please see the value here :
https://code.google.com/p/mediaserver/source/browse/bootstrap/src/main/config/server-beans.xml#40
Its is set to default 1 in latest release.

Also what about na parameter with latest trunk?
Best regards
Yulian Oifa

[Filipe Henriques]

Hi,

1) Can you try to set if(frameBuffer.size()>=bufferSize)

I was just testing that after I posted and the problem "reappears", yet it appears that it processes more DTMF codes than with just ==

The rfc buffer size is set to 1.

I'll do some logging on frameBuffer.size() and buffersize.

2) Also what about na parameter with latest trunk?

I haven't checked that yet, but I'll look into it.

Best regards,

Filipe Henriques

[yulian_o]

Hello Filippe
This is not logically possible.
If buffer size is set to 1 that means that this if becomes if(frameBuffer.size()>=1) and one raw before that frame is added to buffer.
Therefore it all the time will be true , and should behave equal to the case where this if is removed.

The only possibility is that bufferSize is not 1 and this should come from config file.
Best regards
Yulian  Oifa

[yulian_o]

Oh sorry found the problem
The issue is not in dtmf detector but in channel manager
https://code.google.com/p/mediaserver/source/browse/io/rtp/src/main/java/org/mobicents/media/server/impl/rtp/ChannelsManager.java#104
it returns jitter instead of buffer size therefore leading to value of 50!

Please try to modify as following :
1) Leave if as was intially (== )
2) Chanel channelsmanager to return correct variable

Please let me know about the results
Best regarsd
Yulian Oifa

[Filipe Henriques]

I was just gonna post that now :)

Best regards,

Filipe Henriques

17:42:01,273 INFO  [DtmfConverter] Current size: 45; bufferSize: 50

17:42:01,293 INFO  [DtmfConverter] Current size: 46; bufferSize: 50

17:42:01,313 INFO  [DtmfConverter] Current size: 47; bufferSize: 50

17:42:01,653 INFO  [DtmfConverter] Current size: 48; bufferSize: 50

17:42:01,672 INFO  [DtmfConverter] Current size: 49; bufferSize: 50

17:42:01,693 INFO  [DtmfConverter] Current size: 50; bufferSize: 50

17:42:01,693 INFO  [DtmfConverter] Waking up

17:42:01,713 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,733 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,753 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,754 INFO  [DtmfBuffer] (detector) Buffer size: 1

17:42:01,754 INFO  [DetectorImpl] (mobicents/ivr/1) (detector) Buffered '5' tone

17:42:01,773 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,792 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,813 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,832 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,853 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,872 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:01,893 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:02,053 INFO  [DtmfConverter] Current size: 1; bufferSize: 50

17:42:02,073 INFO  [DtmfConverter] Current size: 2; bufferSize: 50

17:42:02,093 INFO  [DtmfConverter] Current size: 3; bufferSize: 50

17:42:02,113 INFO  [DtmfConverter] Current size: 4; bufferSize: 50

17:42:02,133 INFO  [DtmfConverter] Current size: 5; bufferSize: 50

17:42:02,153 INFO  [DtmfConverter] Current size: 6; bufferSize: 50

17:42:02,173 INFO  [DtmfConverter] Current size: 7; bufferSize: 50

17:42:02,193 INFO  [DtmfConverter] Current size: 8; bufferSize: 50

17:42:02,213 INFO  [DtmfConverter] Current size: 9; bufferSize: 50

17:42:02,232 INFO  [DtmfConverter] Current size: 10; bufferSize: 50

17:42:02,253 INFO  [DtmfConverter] Current size: 11; bufferSize: 50

17:42:02,272 INFO  [DtmfConverter] Current size: 12; bufferSize: 50

17:42:03,793 INFO  [MGCP] tx=463077615 Started, message= DLCX mobicents/ivr/1...@127.0.0.1:2427, call agent = Ubuntu1204S-001.dev.wit/10.39.40.194:2727

[yulian_o]

Hello
Does it works with modified Channels Manager?
Does it works with == or >=?

Best regards
Yulian Oifa

[Filipe Henriques]

Everything is working fine now. 

I'll now check about the na parameter and the return codes and let you know how it went.

Best regards,

Filipe Henriques

Quinta-feira, 8 de Novembro de 2012 17:54:20 UTC, yulian_o escreveu:

[Filipe Henriques]

==

Best regards,

Filipe Henriques

[Filipe Henriques]

Forogt to mention with modified Channels Manager (returning the correct value rfc2833BufferSize).

Best regards,

Filipe Henriques

[Filipe Henriques]

Result codes and digits collected seem fine(at least I'm not getting some errors I spotted before), na needs to be changed, since according to the rfc it should be the number of the attempt when it was correctly inserted. 

naContent=" na=" + (options.getNumberOfAttempts()-numberOfAttempts +1);

Best regards,

Filipe Henriques

[yulian_o]

Hello
Please check against latest release 306a49245dca
both na and buffer size settings.

Best regards
Yulian oifa

[Filipe Henriques]

Just noticed that there is one line that hasn't been changed. I'll give an update tomorrow.

http://code.google.com/p/mediaserver/source/browse/controls/mgcp/src/main/java/org/mobicents/media/server/mgcp/pkg/au/PlayCollect.java#811

Best regards,

Filipe Henriques

2012/11/8 yulian_o <oifa....@gmail.com>

[yulian_o]

Hello There is not required since its part of if(numberOfAttempts==1)
therefore in this raw (options.getNumberOfAttempts()-numberOfAttempts +1)=(options.getNumberOfAttempts()-1 +1)=options.getNumberOfAttempts()

Best regards
Yulian Oifa

[Filipe Henriques]

Hi,

oh ok, didn't notice that. Everything seems to be working fine now, DTMF detection, return codes and na.

Best regards,

Filipe Henriques

2012/11/9 yulian_o <oifa....@gmail.com>

[Rahul Raina]

Hi Yulian,

 

We have been using new media server 3.0.0 but we are stuck at the problem of no dtmf tone detection. Sometimes we do detect dtmf tone but that is very rare. Also we have not done any modification in the server-beans.xml file except for the bind address change. Please suggest some solution.