Outbound calls having problems when digits are not recognize by remote auto-attendants DTMF issues

[fuhrersk8]

                   Hello guys!

My situation is the classic one: Outbound calls from CUCM 8.5 when connected to other auto-attendants and the user press the digits, the digits are not being recognized by the far end auto-attendant. I know it has to deal with the dtmf-relay configuration on the dial-peers on the voice gateway. I wantt to know how to recognize where the mismatch lies.

Please instruct me how to recognize the mismatch. Attached the debug ccsip all and my voice gateway configuration. I have configured on my dial peers

dtmf-relay h245-alphanumeric but I dont know wich one the service provider has on their side.

Also, I noticed these messages from the debug:

May 18 16:57:42.616: //4443/Info/sipSPIDoDTMFRelayNegotiation: Requested DTMF-RELAY option(s) not found in Preferred DTMF-RELAY option list!

May 18 16:57:42.616: //4443/Info/sipSPIStreamTypeAndDtmfRelay: DTMF Relay mode: Inband Voice

I know this is an easy one for you guys! Thanks in advanced!

[Ayodeji Okanlawon · Accepted solution]

The trace only shows that the Verizon SIP provider is sending rtp-nte with paylod type 101. We dont know what you are sending..

Can you do a test and send the ff debug output:

debug voip rtp session named-events

[fuhrersk8]

Thanks for the reply. Below the debug voip rtp session:

May 18 17:37:15.459: voip_rtp_exchange_context_info
May 18 17:37:15.459: voip_rtp_exchange_context_info gccb not found, context is NULL
May 18 17:37:15.459: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.459: voip_rtp_create_session: callID=4470, dstCallID=4471 laddr=192.168.70.1, lport=32162,raddr=0.0.0.0, rport=0, type=3, sig_tos=3, ip_tos=5
May 18 17:37:15.459: IPIPGW: voip_rtcp_create_session, IP-IP DETECTED, in=1, out=3
May 18 17:37:15.459: voip_rtp_update_local_ssrc srtp: 0 CallID: 4470 dstCallID: 4471 new ssrc: 0x20834601
May 18 17:37:15.459: voip_rtcp_get_cname: cname=0.0.0@192.168.70.1
May 18 17:37:15.459:  voip_rtp_update_xmit_info
May 18 17:37:15.459:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 24F910F4,context 0
May 18 17:37:15.459:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.459: voip_rtp_set_non_rtp_call: Non-RTP call end
May 18 17:37:15.459: IPIPGW: voip_rtp_update_callinfo, IP-IP DETECTED, in=1, out=3
May 18 17:37:15.459: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=3
May 18 17:37:15.459:  voip_rtp_update_xmit_info
May 18 17:37:15.459:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 24F910F4,context 0
May 18 17:37:15.459:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.463: voip_rtcp_start_session:
May 18 17:37:15.463: voip_rtcp_start_session: start session
May 18 17:37:15.467: voip_rtcp_map_mode: entered. rtp_mode = 0x2
May 18 17:37:15.467: voip_rtcp_update_session: xmit fn=0x0,
dstIF=0x0, dstCallID=-1, voip_mode=0x2, rtp_mode=0x2, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.467: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.467: voip_rtp_create_session: callID=4472, dstCallID=-1 laddr=192.168.70.1, lport=23390,raddr=0.0.0.0, rport=0, type=2, sig_tos=0, ip_tos=0
May 18 17:37:15.467: voip_rtp_update_local_ssrc srtp: 0 CallID: 4472 dstCallID: -1 new ssrc: 0x25444601
May 18 17:37:15.467: voip_rtcp_get_cname: cname=0.0.0@192.168.70.1
May 18 17:37:15.467:  voip_rtp_update_xmit_info
May 18 17:37:15.467:  voip_rtp_update_xmit_info, dstvdbptr: 0, dstCallID -1, gccb: 29F8CAA0, xmitFunc 0,context 0
May 18 17:37:15.467:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.467: voip_rtp_create_session_with_call_data vrf_table_id= 0
May 18 17:37:15.467: voip_rtp_create_session_with_call_data: RTP Session creation Success.
May 18 17:37:15.467: voip_rtcp_start_session:
May 18 17:37:15.467: voip_rtcp_start_session: start session
May 18 17:37:15.467: voip_rtp_exchange_context_info
May 18 17:37:15.467: voip_rtp_exchange_context_info gccb not found, context is NULL
May 18 17:37:15.467: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x2, rtp_mode=0x0, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.467: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=2
May 18 17:37:15.467:  voip_rtp_update_xmit_info
May 18 17:37:15.467:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29F8CAA0, xmitFunc 2420C4F4,context 0
May 18 17:37:15.467:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.467: voip_rtp_exchange_context_info
May 18 17:37:15.467:  voip_rtp_update_xmit_info
May 18 17:37:15.467:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29F8CAA0, xmitFunc 2420C4F4,context 0
May 18 17:37:15.467:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.471: voip_rtp_stop_disc_timer
May 18 17:37:15.471: voip_rtcp_remove_ccb
May 18 17:37:15.471: voip_rtcp_map_mode: entered. rtp_mode = 0x2
May 18 17:37:15.471: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x2, rtp_mode=0x2, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.471: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.471: voip_rtp_create_session: callID=4472, dstCallID=4473 laddr=192.168.70.1, lport=23390,raddr=0.0.0.0, rport=0, type=2, sig_tos=0, ip_tos=0
May 18 17:37:15.471: voip_rtp_update_local_ssrc srtp: 0 CallID: 4472 dstCallID: 4473 new ssrc: 0x1DD64601
May 18 17:37:15.471: voip_rtcp_get_cname: cname=0.0.0@192.168.70.1
May 18 17:37:15.471:  voip_rtp_update_xmit_info
May 18 17:37:15.471:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29F8CAA0, xmitFunc 2420C4F4,context 0
May 18 17:37:15.471:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.471: voip_rtp_create_session_with_call_data vrf_table_id= 0
May 18 17:37:15.471: voip_rtp_create_disc_timer: initial_interval: 1200, normal_interval:1200
May 18 17:37:15.471: voip_rtp_create_session_with_call_data: RTP Timer creation Success. (1200)
May 18 17:37:15.471: voip_rtp_create_session_with_call_data: RTP Session creation Success.
May 18 17:37:15.471: voip_rtp_inactive_notification_update: session: 0x2A32C840, new_request_mfactor: 1200,                        rtcp_interval_unjittered: 1,                        inactive_callback: 0x24D6CD94,                        reactive_callback: 0x24D6CF74

May 18 17:37:15.471: voip_rtp_inactive_notification_update: old initial_disc_timer_interval: 1200
May 18 17:37:15.471: voip_rtp_inactive_notification_update: new initial_interval: 1200
May 18 17:37:15.471: voip_rtcp_close_session
May 18 17:37:15.471: voip_rtcp_stop_session:
May 18 17:37:15.471: voip_rtcp_stop_session: stop session
May 18 17:37:15.471: Cleaning up sess=3117D888, sp=23390, dp=0
May 18 17:37:15.471: voip_rtcp_start_session:
May 18 17:37:15.471: voip_rtcp_start_session: start session
May 18 17:37:15.471: voip_rtp_update_cisco_payloads:
May 18 17:37:15.471: voip_rtp_update_cisco_payloads: The updated cisco reserved payload types are:
         cisco_codec_fax_pt = 128, cisco_codec_fax_ack_pt = 128,
         cisco_rtp_dtmf_relay_pt = 128, cisco_fax_relay_pt = 128,
         cisco_cas_pt = 128, cisco_clear_channel_pt = 128,
         cisco_rtp_type_annex_B = 128, cisco_lmr_tone_pt = 630521856,
         cisco_nte_tone_pt = 0
May 18 17:37:15.471: IPIPGW: voip_rtp_update_callinfo, IP-IP DETECTED, in=1, out=3
May 18 17:37:15.471: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=3
May 18 17:37:15.471:  voip_rtp_update_xmit_info
May 18 17:37:15.471:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 24F910F4,context 0
May 18 17:37:15.471:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.475: voip_rtcp_map_mode: entered. rtp_mode = 0x2
May 18 17:37:15.475: voip_rtcp_update_session: xmit fn=0x0,
dstIF=0x0, dstCallID=-1, voip_mode=0x2, rtp_mode=0x2, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.475: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.475: voip_rtp_create_session: callID=4474, dstCallID=-1 laddr=192.168.70.1, lport=20942,raddr=0.0.0.0, rport=0, type=2, sig_tos=0, ip_tos=0
May 18 17:37:15.475: voip_rtp_update_local_ssrc srtp: 0 CallID: 4474 dstCallID: -1 new ssrc: 0x1C314601
May 18 17:37:15.475: voip_rtcp_get_cname: cname=0.0.0@192.168.70.1
May 18 17:37:15.475:  voip_rtp_update_xmit_info
May 18 17:37:15.475:  voip_rtp_update_xmit_info, dstvdbptr: 0, dstCallID -1, gccb: 29A9FD60, xmitFunc 0,context 0
May 18 17:37:15.475:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.475: voip_rtp_create_session_with_call_data vrf_table_id= 0
May 18 17:37:15.475: voip_rtp_create_session_with_call_data: RTP Session creation Success.
May 18 17:37:15.475: voip_rtcp_start_session:
May 18 17:37:15.475: voip_rtcp_start_session: start session
May 18 17:37:15.475: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x2, rtp_mode=0x0, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.475: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=2
May 18 17:37:15.475:  voip_rtp_update_xmit_info
May 18 17:37:15.475:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29A9FD60, xmitFunc 2420C4F4,context 0
May 18 17:37:15.475:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.475: voip_rtp_exchange_context_info
May 18 17:37:15.475:  voip_rtp_update_xmit_info
May 18 17:37:15.475:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29A9FD60, xmitFunc 2420C4F4,context 0
May 18 17:37:15.475:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.479: voip_rtp_stop_disc_timer
May 18 17:37:15.479: voip_rtcp_remove_ccb
May 18 17:37:15.479: voip_rtcp_map_mode: entered. rtp_mode = 0x2
May 18 17:37:15.479: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x2, rtp_mode=0x2, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.479: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.479: voip_rtp_create_session: callID=4474, dstCallID=4473 laddr=192.168.70.1, lport=20942,raddr=0.0.0.0, rport=0, type=2, sig_tos=0, ip_tos=0
May 18 17:37:15.479: voip_rtp_update_local_ssrc srtp: 0 CallID: 4474 dstCallID: 4473 new ssrc: 0x2054601
May 18 17:37:15.479: voip_rtcp_get_cname: cname=0.0.0@192.168.70.1
May 18 17:37:15.479:  voip_rtp_update_xmit_info
May 18 17:37:15.479:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29A9FD60, xmitFunc 2420C4F4,context 0
May 18 17:37:15.479:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.479: voip_rtp_create_session_with_call_data vrf_table_id= 0
May 18 17:37:15.479: voip_rtp_create_disc_timer: initial_interval: 1200, normal_interval:1200
May 18 17:37:15.479: voip_rtp_create_session_with_call_data: RTP Timer creation Success. (1200)
May 18 17:37:15.479: voip_rtp_create_session_with_call_data: RTP Session creation Success.
May 18 17:37:15.479: voip_rtp_inactive_notification_update: session: 0x31230BA0, new_request_mfactor: 1200,                        rtcp_interval_unjittered: 1,                        inactive_callback: 0x24D6CD94,                        reactive_callback: 0x24D6CF74

May 18 17:37:15.479: voip_rtp_inactive_notification_update: old initial_disc_timer_interval: 1200
May 18 17:37:15.479: voip_rtp_inactive_notification_update: new initial_interval: 1200
May 18 17:37:15.479: voip_rtcp_map_mode: entered. rtp_mode = 0x3
May 18 17:37:15.479: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x3, rtp_mode=0x3, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.479: voip_rtp_update_callinfo: RTP address/port changes 0:0->C0A84601:7DA2
May 18 17:37:15.479: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=3
May 18 17:37:15.479:  voip_rtp_update_xmit_info
May 18 17:37:15.479:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29A9FD60, xmitFunc 2420C4F4,context 0
May 18 17:37:15.479:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.479: voip_rtp_stop_disc_timer
May 18 17:37:15.479: voip_rtcp_remove_ccb
May 18 17:37:15.479: voip_rtcp_map_mode: entered. rtp_mode = 0x3
May 18 17:37:15.479: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x3, rtp_mode=0x3, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.479: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.479: voip_rtp_create_session: callID=4474, dstCallID=4473 laddr=192.168.70.1, lport=20942,raddr=192.168.70.1, rport=32162, type=3, sig_tos=0, ip_tos=5
May 18 17:37:15.479: voip_rtp_update_local_ssrc srtp: 0 CallID: 4474 dstCallID: 4473 new ssrc: 0xEF54601
May 18 17:37:15.479: voip_rtcp_get_cname: cname=0.0.0@192.168.70.1
May 18 17:37:15.479:  voip_rtp_update_xmit_info
May 18 17:37:15.479:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29A9FD60, xmitFunc 2420C4F4,context 0
May 18 17:37:15.479:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.479: voip_rtp_create_session_with_call_data vrf_table_id= 0
May 18 17:37:15.479: voip_rtp_create_disc_timer: initial_interval: 1200, normal_interval:1200
May 18 17:37:15.479: voip_rtp_create_session_with_call_data: RTP Timer creation Success. (1200)
May 18 17:37:15.479: voip_rtp_create_session_with_call_data: RTP Session creation Success.
May 18 17:37:15.479: voip_rtp_inactive_notification_update: session: 0x29F82B40, new_request_mfactor: 1200,                        rtcp_interval_unjittered: 1,                        inactive_callback: 0x24D6CD94,                        reactive_callback: 0x24D6CF74

May 18 17:37:15.479: voip_rtp_inactive_notification_update: old initial_disc_timer_interval: 1200
May 18 17:37:15.479: voip_rtp_inactive_notification_update: new initial_interval: 1200
May 18 17:37:15.479: voip_rtcp_close_session
May 18 17:37:15.479: voip_rtcp_stop_session:
May 18 17:37:15.479: voip_rtcp_stop_session: stop session
May 18 17:37:15.479: Cleaning up sess=3117D888, sp=20942, dp=0
May 18 17:37:15.479: voip_rtcp_close_session
May 18 17:37:15.479: voip_rtcp_stop_session:
May 18 17:37:15.479: voip_rtcp_stop_session: stop session
May 18 17:37:15.479: Cleaning up sess=2A31EB9C, sp=20942, dp=32162
May 18 17:37:15.479: voip_rtcp_start_session:
May 18 17:37:15.479: voip_rtcp_start_session: start session
May 18 17:37:15.479: voip_rtp_update_cisco_payloads:
May 18 17:37:15.479: voip_rtp_update_cisco_payloads: The updated cisco reserved payload types are:
         cisco_codec_fax_pt = 128, cisco_codec_fax_ack_pt = 128,
         cisco_rtp_dtmf_relay_pt = 128, cisco_fax_relay_pt = 128,
         cisco_cas_pt = 128, cisco_clear_channel_pt = 128,
         cisco_rtp_type_annex_B = 128, cisco_lmr_tone_pt = 806967400,
         cisco_nte_tone_pt = 806967400
May 18 17:37:15.479: voip_rtp_update_cisco_payloads:
May 18 17:37:15.479: voip_rtp_update_cisco_payloads: The updated cisco reserved payload types are:
         cisco_codec_fax_pt = 128, cisco_codec_fax_ack_pt = 128,
         cisco_rtp_dtmf_relay_pt = 128, cisco_fax_relay_pt = 128,
         cisco_cas_pt = 128, cisco_clear_channel_pt = 128,
         cisco_rtp_type_annex_B = 128, cisco_lmr_tone_pt = 630521856,
         cisco_nte_tone_pt = 806967400
May 18 17:37:15.479: voip_rtp_set_non_rtp_call: Non-RTP call end
May 18 17:37:15.479: IPIPGW: voip_rtp_update_callinfo, IP-IP DETECTED, in=1, out=3
May 18 17:37:15.479: voip_rtp_update_callinfo: RTP address/port changes 0:0->C0A84601:51CE
May 18 17:37:15.479: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=3
May 18 17:37:15.479:  voip_rtp_update_xmit_info
May 18 17:37:15.479:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 24F910F4,context 0
May 18 17:37:15.479:  voip_rtp_update_xmit_info Context is NULL, exit voip_rtp_start_disc_timer: Timer not started 0, 0

May 18 17:37:15.483: voip_rtp_set_non_rtp_call: Non-RTP call end
May 18 17:37:15.483: IPIPGW: voip_rtp_update_callinfo, IP-IP DETECTED, in=1, out=3
May 18 17:37:15.483: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=3
May 18 17:37:15.483:  voip_rtp_update_xmit_info
May 18 17:37:15.483:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 24F910F4,context 0
May 18 17:37:15.483:  voip_rtp_update_xmit_info Context is NULL, exit voip_rtp_start_disc_timer: Timer not started 0, 0

May 18 17:37:15.483: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.483: voip_rtp_create_session: callID=4471, dstCallID=4470 laddr=10.32.116.242, lport=23658,raddr=10.32.1.90, rport=54032, type=3, sig_tos=3, ip_tos=5
May 18 17:37:15.483: IPIPGW: voip_rtcp_create_session, IP-IP DETECTED, in=3, out=1
May 18 17:37:15.483:  voip_rtp_update_xmit_info
May 18 17:37:15.483:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 24F910F4,context 29F8CFA0
May 18 17:37:15.483:  voip_rtp_update_xmit_info Xmit Info node current values xmit_info->dstvdbptr: 30C47F50, xmit_info->dstCallID 4471, xmit_info->xmitFunc 24F910F4, xmit_info->context 29F8CFA0
May 18 17:37:15.483:  voip xmit info count: 1
May 18 17:37:15.483: voip_rtp_update_local_ssrc srtp: 0 CallID: 4471 dstCallID: 4470 new ssrc: 0x2B074F2
May 18 17:37:15.483: voip_rtcp_get_cname: cname=0.0.0@10.32.116.242
May 18 17:37:15.483:  voip_rtp_update_xmit_info
May 18 17:37:15.483:  voip_rtp_update_xmit_info, dstvdbptr: 29742E78, dstCallID 4470, gccb: 29F8CFA0, xmitFunc 24F910F4,context 311E0A40
May 18 17:37:15.483:  voip_rtp_update_xmit_info Xmit Info node current values xmit_info->dstvdbptr: 29742E78, xmit_info->dstCallID 4470, xmit_info->xmitFunc 24F910F4, xmit_info->context 311E0A40
May 18 17:37:15.483:  voip xmit info count: 1
May 18 17:37:15.483: voip_rtp_update_cisco_payloads:
May 18 17:37:15.483: voip_rtp_update_cisco_payloads: The updated cisco reserved payload types are:
         cisco_codec_fax_pt = 96, cisco_codec_fax_ack_pt = 97,
         cisco_rtp_dtmf_relay_pt = 121, cisco_fax_relay_pt = 122,
         cisco_cas_pt = 123, cisco_clear_channel_pt = 125,
         cisco_rtp_type_annex_B = 123, cisco_lmr_tone_pt = 0,
         cisco_nte_tone_pt = 0
May 18 17:37:15.483: voip_rtp_set_non_rtp_call: Non-RTP call end
May 18 17:37:15.483:  voip_rtp_update_xmit_info
May 18 17:37:15.483:  voip_rtp_update_xmit_info, dstvdbptr: 29742E78, dstCallID 4470, gccb: 29F8CFA0, xmitFunc 24F910F4,context 311E0A40
May 18 17:37:15.483:  voip_rtp_update_xmit_info Xmit Info node current values xmit_info->dstvdbptr: 29742E78, xmit_info->dstCallID 4470, xmit_info->xmitFunc 24F910F4, xmit_info->context 311E0A40
May 18 17:37:15.483:  voip xmit info count: 1
May 18 17:37:15.483: voip_rtcp_start_session:
May 18 17:37:15.483: voip_rtcp_start_session: start session
May 18 17:37:15.495: voip_rtcp_map_mode: entered. rtp_mode = 0x3
May 18 17:37:15.495: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x3, rtp_mode=0x3, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.495: voip_rtp_update_callinfo: RTP address/port changes 0:0->C0A83903:6E88
May 18 17:37:15.495: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=3
May 18 17:37:15.495:  voip_rtp_update_xmit_info
May 18 17:37:15.495:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29F8CAA0, xmitFunc 2420C4F4,context 0
May 18 17:37:15.495:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.495: voip_rtp_stop_disc_timer
May 18 17:37:15.495: voip_rtcp_remove_ccb
May 18 17:37:15.495: voip_rtcp_map_mode: entered. rtp_mode = 0x3
May 18 17:37:15.495: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x3, rtp_mode=0x3, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:15.495: voip_rtp_create_session idb tableid is 0,session table id is 0
May 18 17:37:15.495: voip_rtp_create_session: callID=4472, dstCallID=4473 laddr=192.168.70.1, lport=23390,raddr=192.168.57.3, rport=28296, type=3, sig_tos=0, ip_tos=5
May 18 17:37:15.495: voip_rtp_update_local_ssrc srtp: 0 CallID: 4472 dstCallID: 4473 new ssrc: 0xE934601
May 18 17:37:15.495: voip_rtcp_get_cname: cname=0.0.0@192.168.70.1
May 18 17:37:15.495:  voip_rtp_update_xmit_info
May 18 17:37:15.495:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29F8CAA0, xmitFunc 2420C4F4,context 0
May 18 17:37:15.495:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:15.495: voip_rtp_create_session_with_call_data vrf_table_id= 0
May 18 17:37:15.495: voip_rtp_create_disc_timer: initial_interval: 1200, normal_interval:1200
May 18 17:37:15.495: voip_rtp_create_session_with_call_data: RTP Timer creation Success. (1200)
May 18 17:37:15.495: voip_rtp_create_session_with_call_data: RTP Session creation Success.
May 18 17:37:15.495: voip_rtp_inactive_notification_update: session: 0x3117DF20, new_request_mfactor: 1200,                        rtcp_interval_unjittered: 1,                        inactive_callback: 0x24D6CD94,                        reactive_callback: 0x24D6CF74

May 18 17:37:15.495: voip_rtp_inactive_notification_update: old initial_disc_timer_interval: 1200
May 18 17:37:15.495: voip_rtp_inactive_notification_update: new initial_interval: 1200
May 18 17:37:15.495: voip_rtcp_close_session
May 18 17:37:15.495: voip_rtcp_stop_session:
May 18 17:37:15.495: voip_rtcp_stop_session: stop session
May 18 17:37:15.495: Cleaning up sess=3014808C, sp=23390, dp=28296
May 18 17:37:15.499: voip_rtcp_start_session:
May 18 17:37:15.499: voip_rtcp_start_session: start session
May 18 17:37:15.499: voip_rtp_update_cisco_payloads:
May 18 17:37:15.499: voip_rtp_update_cisco_payloads: The updated cisco reserved payload types are:
         cisco_codec_fax_pt = 128, cisco_codec_fax_ack_pt = 128,
         cisco_rtp_dtmf_relay_pt = 128, cisco_fax_relay_pt = 128,
         cisco_cas_pt = 128, cisco_clear_channel_pt = 128,
         cisco_rtp_type_annex_B = 128, cisco_lmr_tone_pt = 806967432,
         cisco_nte_tone_pt = 806967432
May 18 17:37:15.567: voip_rtp_recv_input: removing old SSRC 610553564,  new 409218591
May 18 17:37:15.567: voip_rtp_recv_input: removing old SSRC 610553564,  new 409218591
May 18 17:37:35.477: IPIPGW: voip_rtp_update_callinfo, IP-IP DETECTED, in=1, out=3
May 18 17:37:35.477:  voip_rtp_update_xmit_info
May 18 17:37:35.477:  voip_rtp_update_xmit_info, dstvdbptr: 29742E78, dstCallID 4470, gccb: 29F8CFA0, xmitFunc 24F910F4,context 311E0A40
May 18 17:37:35.477:  voip_rtp_update_xmit_info Xmit Info node current values xmit_info->dstvdbptr: 29742E78, xmit_info->dstCallID 4470, xmit_info->xmitFunc 24F910F4, xmit_info->context 311E0A40
May 18 17:37:35.477:  voip xmit info count: 1
May 18 17:37:35.477: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=1
May 18 17:37:35.477:  voip_rtp_update_xmit_info
May 18 17:37:35.477:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 24F910F4,context 29F8CFA0
May 18 17:37:35.477:  voip_rtp_update_xmit_info Xmit Info node current values xmit_info->dstvdbptr: 30C47F50, xmit_info->dstCallID 4471, xmit_info->xmitFunc 24F910F4, xmit_info->context 29F8CFA0
May 18 17:37:35.477:  voip xmit info count: 1
May 18 17:37:35.477: voip_rtcp_map_mode: entered. rtp_mode = 0x1
May 18 17:37:35.477: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x1, rtp_mode=0x1, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:35.477: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=1
May 18 17:37:35.477:  voip_rtp_update_xmit_info
May 18 17:37:35.477:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29F8CAA0, xmitFunc 2420C4F4,context 0
May 18 17:37:35.477:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:35.477: voip_rtp_inactive_notification_update: session: 0x3117DF20, new_request_mfactor: 1200,                        rtcp_interval_unjittered: 1,                        inactive_callback: 0x24D6CD94,                        reactive_callback: 0x24D6CF74

May 18 17:37:35.477: voip_rtp_inactive_notification_update: old initial_disc_timer_interval: 1200
May 18 17:37:35.477: voip_rtp_inactive_notification_update: new initial_interval: 1200
May 18 17:37:35.477: voip_rtp_update_cisco_payloads:
May 18 17:37:35.477: voip_rtp_update_cisco_payloads: The updated cisco reserved payload types are:
         cisco_codec_fax_pt = 128, cisco_codec_fax_ack_pt = 128,
         cisco_rtp_dtmf_relay_pt = 128, cisco_fax_relay_pt = 128,
         cisco_cas_pt = 128, cisco_clear_channel_pt = 128,
         cisco_rtp_type_annex_B = 128, cisco_lmr_tone_pt = 630521856,
         cisco_nte_tone_pt = 806967432
May 18 17:37:35.477: voip_rtcp_update_session: xmit fn=0x0,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x1, rtp_mode=0x0, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:35.477: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=1
May 18 17:37:35.477:  voip_rtp_update_xmit_info
May 18 17:37:35.477:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29F8CAA0, xmitFunc 0,context 0
May 18 17:37:35.477:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:35.477: voip_rtcp_map_mode: entered. rtp_mode = 0x1
May 18 17:37:35.477: voip_rtcp_update_session: xmit fn=0x2420C4F4,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x1, rtp_mode=0x1, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:35.477: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=1
May 18 17:37:35.477:  voip_rtp_update_xmit_info
May 18 17:37:35.481:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29A9FD60, xmitFunc 2420C4F4,context 0
May 18 17:37:35.481:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:35.481: voip_rtp_inactive_notification_update: session: 0x29F82B40, new_request_mfactor: 1200,                        rtcp_interval_unjittered: 1,                        inactive_callback: 0x24D6CD94,                        reactive_callback: 0x24D6CF74

May 18 17:37:35.481: voip_rtp_inactive_notification_update: old initial_disc_timer_interval: 1200
May 18 17:37:35.481: voip_rtp_inactive_notification_update: new initial_interval: 1200
May 18 17:37:35.481: voip_rtp_stop_disc_timer
May 18 17:37:35.481: IPIPGW: voip_rtp_update_callinfo, IP-IP DETECTED, in=1, out=3
May 18 17:37:35.481:  voip_rtp_update_xmit_info
May 18 17:37:35.481:  voip_rtp_update_xmit_info, dstvdbptr: 29742E78, dstCallID 4470, gccb: 29F8CFA0, xmitFunc 24F910F4,context 311E0A40
May 18 17:37:35.481:  voip_rtp_update_xmit_info Xmit Info node current values xmit_info->dstvdbptr: 29742E78, xmit_info->dstCallID 4470, xmit_info->xmitFunc 24F910F4, xmit_info->context 311E0A40
May 18 17:37:35.481:  voip xmit info count: 1
May 18 17:37:35.481: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=1
May 18 17:37:35.481:  voip_rtp_update_xmit_info
May 18 17:37:35.481:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 0,context 29F8CFA0
May 18 17:37:35.481:  voip_rtp_update_xmit_info xmit_func is NULL
May 18 17:37:35.481: IPIPGW: voip_rtp_update_callinfo, IP-IP DETECTED, in=3, out=1
May 18 17:37:35.481:  voip_rtp_update_xmit_info
May 18 17:37:35.481:  voip_rtp_update_xmit_info, dstvdbptr: 30C47F50, dstCallID 4471, gccb: 311E0A40, xmitFunc 0,context 29F8CFA0
May 18 17:37:35.481:  voip_rtp_update_xmit_info xmit_func is NULL, exit
May 18 17:37:35.481: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=3
May 18 17:37:35.481:  voip_rtp_update_xmit_info
May 18 17:37:35.481:  voip_rtp_update_xmit_info, dstvdbptr: 29742E78, dstCallID 4470, gccb: 29F8CFA0, xmitFunc 0,context 311E0A40
May 18 17:37:35.481:  voip_rtp_update_xmit_info xmit_func is NULL
May 18 17:37:35.481: voip_rtp_stop_disc_timer
May 18 17:37:35.481: voip_rtp_update_cisco_payloads:
May 18 17:37:35.481: voip_rtp_update_cisco_payloads: The updated cisco reserved payload types are:
         cisco_codec_fax_pt = 128, cisco_codec_fax_ack_pt = 128,
         cisco_rtp_dtmf_relay_pt = 128, cisco_fax_relay_pt = 128,
         cisco_cas_pt = 128, cisco_clear_channel_pt = 128,
         cisco_rtp_type_annex_B = 128, cisco_lmr_tone_pt = 630521856,
         cisco_nte_tone_pt = 1329
May 18 17:37:35.481: voip_rtcp_update_session: xmit fn=0x0,
dstIF=0x30C27B58, dstCallID=4473, voip_mode=0x1, rtp_mode=0x0, ssrc_status=0, report_digits=0, tse-payload=0, need_new_ssrc=0
May 18 17:37:35.481: voip_rtp_update_callinfo: first_pak=TRUE, new_ssrc=0, type=1
May 18 17:37:35.481:  voip_rtp_update_xmit_info
May 18 17:37:35.481:  voip_rtp_update_xmit_info, dstvdbptr: 30C27B58, dstCallID 4473, gccb: 29A9FD60, xmitFunc 0,context 0
May 18 17:37:35.481:  voip_rtp_update_xmit_info Context is NULL, exit
May 18 17:37:35.481: voip_rtp_stop_disc_timer
May 18 17:37:35.481: voip_rtcp_remove_ccb
May 18 17:37:35.481: voip_rtp_stop_disc_timer
May 18 17:37:35.481: voip_rtcp_remove_ccb
May 18 17:37:35.481: voip_rtcp_close_session
May 18 17:37:35.481: voip_rtcp_stop_session:
May 18 17:37:35.481: voip_rtcp_stop_session: stop session
May 18 17:37:35.481: Cleaning up sess=30C64A68, sp=23390, dp=28296
May 18 17:37:35.481: voip_rtcp_close_session
May 18 17:37:35.481: voip_rtcp_stop_session:
May 18 17:37:35.481: voip_rtcp_stop_session: stop session
May 18 17:37:35.485: Cleaning up sess=29F29DA4, sp=32162, dp=20942
May 18 17:37:35.485: voip_rtp_stop_disc_timer
May 18 17:37:35.485: voip_rtcp_remove_ccb
May 18 17:37:35.485: voip_rtcp_close_session
May 18 17:37:35.485: voip_rtcp_stop_session:
May 18 17:37:35.485: voip_rtcp_stop_session: stop session
May 18 17:37:35.485: Cleaning up sess=30005244, sp=20942, dp=32162
May 18 17:37:35.489: voip_rtp_stop_disc_timer
May 18 17:37:35.489: voip_rtcp_remove_ccb
May 18 17:37:35.489: voip_rtcp_close_session
May 18 17:37:35.489: voip_rtcp_stop_session:
May 18 17:37:35.489: voip_rtcp_stop_session: stop session
May 18 17:37:35.489: Cleaning up sess=30010E7C, sp=23658, dp=54032

[Ayodeji Okanlawon]

Did you enter any digit when you took this debug? I cant see any digit been entered..

[fuhrersk8]

Thanks for the help. I configured dtmf-relay h245-alphanumeric for outbound peers to CUCM and dtmf-relay rtp-nte fdor outbound peers to Service Provider SIP server and now is working as intended. Thanks again!