DTMF tones from a SIP CUBE doens´t arrived to UC

[aherraez77]

Hello:

I have the following issue:

I receive from the SP the calls in a SIP cube and the call is forwarded to the call manager with h.323. So I have a incoming sip dial-peer and a h323 outgoing dial-peer. When a call is for the call handler that do the AA functions, the DTMF tones doens´t work and callers can´t be transfered directly to extensions.

The configuration of the dial-peers is:

dial-peer voice 4 voip
incoming called-number .
voice-class codec 10
dtmf-relay rtp-nte
!
dial-peer voice 5 voip
destination-pattern 9T
signaling forward unconditional
session target ipv4:X.X.X.X
incoming called-number 9T
dtmf-relay h245-alphanumeric
dtmf-interworking standard

The users start listening a "Sorry" and then it starts the personal greeting. So I think that the AA is receiving the dtmf tones empty. The log from the remote port status monitor is:

09:55:52, New Call, CalledId=8000, RedirectingId=8000, AltRedirectingId=, Origin=16, Reason=8, CallGuid=5FF76CA31D974514883C45318EB2F7D7, CallerName=916404643, LastRedirectingId=8000, AltLastRedirectingId=, LastRedirectingReason=8, PortDisplayName=PhoneSystem-1-002,[Origin=Unknown],[Reason=Forward Unconditional]
09:55:53, AttemptForward
09:55:53, State - AttemptForward.cde!Dummy
09:55:53, Event is [NULL]
09:55:53, PHTransfer
09:55:53, State - PHTransfer.cde!LoadInfo
09:55:53, Event is [TrueEvent]
09:55:53, PHGreeting
09:55:53, State - PHGreeting.cde!PlayGreeting
09:55:53, Call answered if needed
09:55:53, Playing greeting for Call Handler: AA_Asentia
09:56:05, No DTMF received
09:56:05, Event is [NULL]
09:56:05, PHTransfer
09:56:05, State - PHTransfer.cde!LoadInfo
09:56:05, Answer Phone if needed
09:56:05, Event is [FalseEvent]
09:56:05, State - PHTransfer.cde!CheckPlayTransferIntro
09:56:05, Event is [TrueEvent]
09:56:05, State - PHTransfer.cde!PlayTransferIntro
09:56:08, Event is [NULL]
09:56:08, State - PHTransfer.cde!XferCall
09:56:09, Event is [HangupEvent]
09:56:09, State - PHTransfer.cde!DoHangUp
09:56:09, Event is [HangupEvent]
09:56:09, Idle

Why is not working the DTMF-tones?

Best Regards.

[Chris Deren]

Change the following dial-peer as following:

dial-peer voice 4 voip
dtmf-relay h245-alphanumeric

[aherraez77]

Hi Chris:

I have chaged the dtmf-relay to h245-alphanumeric but the result is the same. 

Best Regards

[aherraez77]

I have the following output :

#show call active voice
Telephony call-legs: 0
SIP call-legs: 1
H323 call-legs: 1
Call agent controlled call-legs: 0
SCCP call-legs: 0
Multicast call-legs: 0
Total call-legs: 2

GENERIC:
....
tx_DtmfRelay=inband-voice
FastConnect=FALSE

...

VOIP:
ConnectionId[0x5C075F8C 0xAD6011E5 0xA6A3BEB0 0xA6AD6561]
IncomingConnectionId[0x5C075F8C 0xAD6011E5 0xA6A3BEB0 0xA6AD6561]
CallID=71665
GlobalCallId=[0x0 0x0 0x0 0x0]
SessionId=0

RoundTripDelay=0 ms
SelectedQoS=best-effort
tx_DtmfRelay=h245-alphanumeric
FastConnect=TRUE

Here I have the signalling IP address is the call manager and the Media IP address is the Unity Connection

with a debug:  "debug h245 asn1"

capabilityTable
{

{
capabilityTableEntryNumber 1
capability genericControlCapability :
{
capabilityIdentifier standard : { 0 0 8 323 1 3 1 }
}
},
{
capabilityTableEntryNumber 2
capability receiveAudioCapability : g711Ulaw64k : 30
},
{
capabilityTableEntryNumber 3
capability receiveAndTransmitUserInputCapability : dtmf : NULL
},
{
capabilityTableEntryNumber 4
capability receiveAndTransmitUserInputCapability : basicString : NULL
},

value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck :
{
sequenceNumber 1
}

*Dec 29 2015 13:49:59.844 UTC: H245 MSC OUTGOING ENCODE BUFFER::= 20A0
*Dec 29 2015 13:49:59.844 UTC:
*Dec 29 2015 13:50:03.484 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA46E timestamp 0x75A8
*Dec 29 2015 13:50:03.484 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:0A 00 A0
*Dec 29 2015 13:50:03.484 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA46E timestamp 0x75A8
*Dec 29 2015 13:50:03.484 UTC: Pt:101 Evt:2 Pkt:0A 00 A0 <Snd>>>
*Dec 29 2015 13:50:03.484 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA46F timestamp 0x75A8
*Dec 29 2015 13:50:03.484 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:0A 01 40
*Dec 29 2015 13:50:03.484 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA46F timestamp 0x75A8
*Dec 29 2015 13:50:03.484 UTC: Pt:101 Evt:2 Pkt:0A 01 40 <Snd>>>
*Dec 29 2015 13:50:03.500 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA470 timestamp 0x75A8
*Dec 29 2015 13:50:03.500 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:0A 01 E0
*Dec 29 2015 13:50:03.500 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA470 timestamp 0x75A8
*Dec 29 2015 13:50:03.500 UTC: Pt:101 Evt:2 Pkt:0A 01 E0 <Snd>>>
*Dec 29 2015 13:50:03.520 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA471 timestamp 0x75A8
*Dec 29 2015 13:50:03.520 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:0A 02 80
*Dec 29 2015 13:50:03.520 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA471 timestamp 0x75A8
*Dec 29 2015 13:50:03.520 UTC: Pt:101 Evt:2 Pkt:0A 02 80 <Snd>>>
*Dec 29 2015 13:50:03.540 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA472 timestamp 0x75A8
*Dec 29 2015 13:50:03.540 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:0A 03 20
*Dec 29 2015 13:50:03.540 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA472 timestamp 0x75A8
*Dec 29 2015 13:50:03.540 UTC: Pt:101 Evt:2 Pkt:0A 03 20 <Snd>>>
*Dec 29 2015 13:50:03.560 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA473 timestamp 0x75A8
*Dec 29 2015 13:50:03.560 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:0A 03 C0
*Dec 29 2015 13:50:03.560 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA473 timestamp 0x75A8
*Dec 29 2015 13:50:03.560 UTC: Pt:101 Evt:2 Pkt:0A 03 C0 <Snd>>>
*Dec 29 2015 13:50:03.580 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA474 timestamp 0x75A8
*Dec 29 2015 13:50:03.580 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:0A 04 60
*Dec 29 2015 13:50:03.580 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA474 timestamp 0x75A8
*Dec 29 2015 13:50:03.580 UTC: Pt:101 Evt:2 Pkt:0A 04 60 <Snd>>>
*Dec 29 2015 13:50:03.588 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA475 timestamp 0x75A8
*Dec 29 2015 13:50:03.592 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:8A 05 00
*Dec 29 2015 13:50:03.592 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA475 timestamp 0x75A8
*Dec 29 2015 13:50:03.592 UTC: Pt:101 Evt:2 Pkt:8A 05 00 <Snd>>>
*Dec 29 2015 13:50:03.592 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA476 timestamp 0x75A8
*Dec 29 2015 13:50:03.592 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:8A 05 00
*Dec 29 2015 13:50:03.592 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA476 timestamp 0x75A8
*Dec 29 2015 13:50:03.592 UTC: Pt:101 Evt:2 Pkt:8A 05 00 <Snd>>>
*Dec 29 2015 13:50:03.592 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA477 timestamp 0x75A8
*Dec 29 2015 13:50:03.592 UTC: <<<Rcv> Pt:101 Evt:2 Pkt:8A 05 00
*Dec 29 2015 13:50:03.592 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA477 timestamp 0x75A8
*Dec 29 2015 13:50:03.592 UTC: Pt:101 Evt:2 Pkt:8A 05 00 <Snd>>>
*Dec 29 2015 13:50:03.872 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA486 timestamp 0x81B0
*Dec 29 2015 13:50:03.872 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:0A 00 A0
*Dec 29 2015 13:50:03.872 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA486 timestamp 0x81B0
*Dec 29 2015 13:50:03.872 UTC: Pt:101 Evt:9 Pkt:0A 00 A0 <Snd>>>
*Dec 29 2015 13:50:03.872 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA487 timestamp 0x81B0
*Dec 29 2015 13:50:03.872 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:0A 01 40
*Dec 29 2015 13:50:03.872 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA487 timestamp 0x81B0
*Dec 29 2015 13:50:03.872 UTC: Pt:101 Evt:9 Pkt:0A 01 40 <Snd>>>
*Dec 29 2015 13:50:03.884 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA488 timestamp 0x81B0
*Dec 29 2015 13:50:03.884 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:0A 01 E0
*Dec 29 2015 13:50:03.884 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA488 timestamp 0x81B0
*Dec 29 2015 13:50:03.884 UTC: Pt:101 Evt:9 Pkt:0A 01 E0 <Snd>>>
*Dec 29 2015 13:50:03.904 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA489 timestamp 0x81B0
*Dec 29 2015 13:50:03.904 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:0A 02 80
*Dec 29 2015 13:50:03.904 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA489 timestamp 0x81B0
*Dec 29 2015 13:50:03.904 UTC: Pt:101 Evt:9 Pkt:0A 02 80 <Snd>>>
*Dec 29 2015 13:50:03.924 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA48A timestamp 0x81B0
*Dec 29 2015 13:50:03.924 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:0A 03 20
*Dec 29 2015 13:50:03.924 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA48A timestamp 0x81B0
*Dec 29 2015 13:50:03.924 UTC: Pt:101 Evt:9 Pkt:0A 03 20 <Snd>>>
*Dec 29 2015 13:50:03.944 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA48B timestamp 0x81B0
*Dec 29 2015 13:50:03.944 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:0A 03 C0
*Dec 29 2015 13:50:03.944 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA48B timestamp 0x81B0
*Dec 29 2015 13:50:03.944 UTC: Pt:101 Evt:9 Pkt:0A 03 C0 <Snd>>>
*Dec 29 2015 13:50:03.964 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA48C timestamp 0x81B0
*Dec 29 2015 13:50:03.964 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:0A 04 60
*Dec 29 2015 13:50:03.964 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA48C timestamp 0x81B0
*Dec 29 2015 13:50:03.964 UTC: Pt:101 Evt:9 Pkt:0A 04 60 <Snd>>>
*Dec 29 2015 13:50:03.972 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA48D timestamp 0x81B0
*Dec 29 2015 13:50:03.972 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:8A 05 00
*Dec 29 2015 13:50:03.972 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA48D timestamp 0x81B0
*Dec 29 2015 13:50:03.972 UTC: Pt:101 Evt:9 Pkt:8A 05 00 <Snd>>>
*Dec 29 2015 13:50:03.972 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA48E timestamp 0x81B0
*Dec 29 2015 13:50:03.972 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:8A 05 00
*Dec 29 2015 13:50:03.972 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA48E timestamp 0x81B0
*Dec 29 2015 13:50:03.972 UTC: Pt:101 Evt:9 Pkt:8A 05 00 <Snd>>>
*Dec 29 2015 13:50:03.972 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA48F timestamp 0x81B0
*Dec 29 2015 13:50:03.972 UTC: <<<Rcv> Pt:101 Evt:9 Pkt:8A 05 00
*Dec 29 2015 13:50:03.972 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA48F timestamp 0x81B0
*Dec 29 2015 13:50:03.972 UTC: Pt:101 Evt:9 Pkt:8A 05 00 <Snd>>>
*Dec 29 2015 13:50:04.180 UTC: s=VoIP d=DSP payload 0x65 ssrc 0xEF881F4 sequence 0xA49A timestamp 0x8B60
*Dec 29 2015 13:50:04.180 UTC: <<<Rcv> Pt:101 Evt:0 Pkt:0A 00 A0
*Dec 29 2015 13:50:04.180 UTC: s=DSP d=VoIP payload 0x65 ssrc 0xEF881F4 sequence 0xA49A timestamp 0x8B60

Can someone help me?

Best regards.

[Ayodeji Okanlawon]

You might need an on-board MTP/XCODER in this case. So I suggest you configure an on-board xcoder on CUBE and see if that helps

[aherraez77]

Hi Ayodeji:

Can you give some examples for the configuration?. I don´t understad how with a configuration like this can modify the dtmf-relay

dspfarm profile 33 mtp

description ***** Media Termination Point <Cluster Name> *****

no codec g711ulaw

codec g729br8

codec pass-through

maximum sessions software 500

associate application SCCP

no shut

I confirm you that I have a MTP configured in the gw:

MTP Oper State: ACTIVE - Cause Code: NONE
Active Call Manager: 192.168.5.6, Port Number: 2000
TCP Link Status: CONNECTED, Profile Identifier: 3
Reported Max Streams: 1000, Reported Max OOS Streams: 0
Supported Codec: pass-thru, Maximum Packetization Period: N/A
Supported Codec: g711ulaw, Maximum Packetization Period: 30
Supported Codec: rfc2833 dtmf, Maximum Packetization Period: 30
Supported Codec: rfc2833 pass-thru, Maximum Packetization Period: 30
Supported Codec: inband-dtmf to rfc2833 conversion, Maximum Packetization Period: 30
TLS : ENABLEDMTP Oper State: ACTIVE - Cause Code: NONE
Active Call Manager: 192.168.5.6, Port Number: 2000
TCP Link Status: CONNECTED, Profile Identifier: 3
Reported Max Streams: 1000, Reported Max OOS Streams: 0
Supported Codec: pass-thru, Maximum Packetization Period: N/A
Supported Codec: g711ulaw, Maximum Packetization Period: 30
Supported Codec: rfc2833 dtmf, Maximum Packetization Period: 30
Supported Codec: rfc2833 pass-thru, Maximum Packetization Period: 30
Supported Codec: inband-dtmf to rfc2833 conversion, Maximum Packetization Period: 30
TLS : ENABLED

But I think that it is not being used.

Best Regards

[Ayodeji Okanlawon]

Here is an example of how to do this

voice-card X
 dsp services dspfarm

sccp local GigabitEthernet0/0
sccp ccm 9.13.29.30 identifier 1 version 7.0 -- Use CUBE own IP address
sccp
!
sccp ccm group 1
 associate ccm 1 priority 1
 associate profile 1 register XCODE123456

!
dspfarm profile 1 transcode
 codec g711ulaw
 codec g711alaw
 codec g729ar8
 codec g729abr8
 codec gsmfr
 codec g729r8
 codec g729br8
 maximum sessions 10
 associate application SCCP

telephony-service
 max-ephones 1
 max-dn 1
 ip source-address 9.13.29.30 port 2000 --> Use its own IP address
 sdspfarm units 1
 sdspfarm transcode sessions 10
 sdspfarm tag 1 XCODE123456

[aherraez77]

Hi Ayodeji, Happy new year:

this is my configuration but it´s not working:

voice-card 0
dspfarm
dsp services dspfarm
!

sccp local GigabitEthernet0/1
sccp ccm 192.168.5.6 identifier 10 version 7.0
sccp
!
sccp ccm group 1
associate ccm 10 priority 1
associate profile 1 register RFS-TRA
associate profile 2 register RFS-CON
associate profile 3 register RFS-MTP
keepalive retries 5
switchover method immediate
switchback method immediate
switchback interval 5
!
!
!
dspfarm profile 1 transcode
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
codec g729br8
codec pass-through
maximum sessions 3
associate application SCCP
!
dspfarm profile 2 conference
codec g729br8
codec g729r8
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
maximum sessions 1
associate application SCCP
!
dspfarm profile 3 mtp
description recursos_mtp_soft
codec g711ulaw
codec pass-through
maximum sessions hardware 10
maximum sessions software 500
associate application SCCP
!

telephony-service
sdspfarm units 3
sdspfarm transcode sessions 10
sdspfarm tag 1 RFS-TRA
sdspfarm tag 2 RFS-CON
sdspfarm tag 3 RFS-MTP
max-ephones 1
max-dn 1
ip source-address 192.168.5.20 port 2000
max-conferences 8 gain -6
transfer-system full-consult

What debug commands can I use to see what is happening with the dtmf-relay? It has no sense because as I can see the MTP has:

Supported Codec: pass-thru, Maximum Packetization Period: N/A
Supported Codec: g711ulaw, Maximum Packetization Period: 30
Supported Codec: rfc2833 dtmf, Maximum Packetization Period: 30
Supported Codec: rfc2833 pass-thru, Maximum Packetization Period: 30
Supported Codec: inband-dtmf to rfc2833 conversion, Maximum Packetization Period: 30

Best Regards

[Ayodeji Okanlawon]

Hi,

can you try this.

Configure the ff dtmf on your outbound dial-peer to ITSP

dial-peer voice xxx voip
dtmf-relay rtp-nte digit-drop

also ensure you add this to your inbound sip dial-peer

dial-p v 4 voip

dtmf-relay rtp-nte digit-drop

Also check that your on board transcoder is reguistered

sh sccp

[aherraez77]

Hi Ayodeji:

Thanks you for your help.

The board transcoder is registered:

show sccp
SCCP Admin State: UP
Gateway Local Interface: GigabitEthernet0/1
IPv4 Address: X.X.X.X
Port Number: 2000
IP Precedence: 5
User Masked Codec list: None
Call Manager: X.X.X.X Port Number: 2000
Priority: N/A, Version: 7.0, Identifier: 10
Trustpoint: N/A

Transcoding Oper State: ACTIVE - Cause Code: NONE
Active Call Manager: X.X.X.X, Port Number: 2000
TCP Link Status: CONNECTED, Profile Identifier: 1
Reported Max Streams: 6, Reported Max OOS Streams: 0
Supported Codec: pass-thru, Maximum Packetization Period: N/A
Supported Codec: g729br8, Maximum Packetization Period: 60
Supported Codec: g711ulaw, Maximum Packetization Period: 30
Supported Codec: g711alaw, Maximum Packetization Period: 30
Supported Codec: g729ar8, Maximum Packetization Period: 60
Supported Codec: g729abr8, Maximum Packetization Period: 60
Supported Codec: rfc2833 dtmf, Maximum Packetization Period: 30
Supported Codec: rfc2833 pass-thru, Maximum Packetization Period: 30
Supported Codec: inband-dtmf to rfc2833 conversion, Maximum Packetization Period: 30

Conferencing Oper State: ACTIVE - Cause Code: NONE
Active Call Manager: X.X.X.X, Port Number: 2000
TCP Link Status: CONNECTED, Profile Identifier: 2
Reported Max Streams: 8, Reported Max OOS Streams: 0
Supported Codec: g711ulaw, Maximum Packetization Period: 30
Supported Codec: g711alaw, Maximum Packetization Period: 30
Supported Codec: g729ar8, Maximum Packetization Period: 60
Supported Codec: g729abr8, Maximum Packetization Period: 60
Supported Codec: g729r8, Maximum Packetization Period: 60
Supported Codec: g729br8, Maximum Packetization Period: 60
Supported Codec: rfc2833 dtmf, Maximum Packetization Period: 30
Supported Codec: rfc2833 pass-thru, Maximum Packetization Period: 30
Supported Codec: inband-dtmf to rfc2833 conversion, Maximum Packetization Period: 30
TLS : ENABLED

MTP Oper State: ACTIVE - Cause Code: NONE
Active Call Manager: X.X.X.X, Port Number: 2000
TCP Link Status: CONNECTED, Profile Identifier: 3
Reported Max Streams: 1020, Reported Max OOS Streams: 0
Supported Codec: pass-thru, Maximum Packetization Period: N/A
Supported Codec: g711ulaw, Maximum Packetization Period: 30
Supported Codec: rfc2833 dtmf, Maximum Packetization Period: 30
Supported Codec: rfc2833 pass-thru, Maximum Packetization Period: 30
Supported Codec: inband-dtmf to rfc2833 conversion, Maximum Packetization Period: 30
TLS : ENABLED

But after configured the "dtmf-relay rtp-nte digit-drop" in the dial-peers I have a different situation:

Now it works from a handy although it doesn´t work from a PSTN number.

I have this output from the "show call active voice"

SetupTime=1510292700 ms (*00:19:08.963 UTC Fri Jan 8 2016)
Index=1
PeerAddress= The Handy
PeerSubAddress=
PeerId=4
PeerIfIndex=28
LogicalIfIndex=0

CallState=4
CallOrigin=2

tx_DtmfRelay=rtp-nte
FastConnect=FALSE

GENERIC:
SetupTime=1510292710 ms (*00:19:08.967 UTC Fri Jan 8 2016)
Index=1
PeerAddress= xxxxxxx
PeerSubAddress=
PeerId=5
PeerIfIndex=29

CallState=4
CallOrigin=1

tx_DtmfRelay=h245-alphanumeric
FastConnect=TRUE

It matches the dial-peer 4 as the incoming dial-peer and the dial-peer 5 as the outgoing dial-peer.

But with a call from a pstn number I have:

GENERIC:
SetupTime=1510970850 ms (*00:30:27.113 UTC Fri Jan 8 2016)
Index=1
PeerAddress= PSTN number
PeerSubAddress=
PeerId=4
PeerIfIndex=28

CallState=4
CallOrigin=2

tx_DtmfRelay=inband-voice
FastConnect=FALSE

The dtmf-relay is different althoug the incoming dial-peer is the same.

How can be the dtmf-relay different if the calls arrived from the same sip trunk?

Best Regards

[Ayodeji Okanlawon]

So which direction works  now? I don't know what you mean by it works from handy.. 

We need to check which dial peer is matched when you dial from pstn. 

Please do the ff debug when you dial from  pstn

1.debug voip ccapi inout 

2.debug ccsip messages 

[aherraez77]

Hi Ayodeji:

Now what is working is this: If a call from a mobile, after listening the greeting from the Unity Connection I can dial a extension and the UC transfer me to that extension. If I call from a PSTN number it doesn´t work.

Here you are the debug output from a pstn number:

#debug voip ccapi inout
voip ccapi inout debugging is on

*Jan 10 2016 11:07:33.709 UTC: //-1/CD2AFEB9827B/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=960040695
----- ccCallInfo IE subfields -----
cisco-ani=960040695
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=916612250
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*Jan 10 2016 11:07:33.709 UTC: //-1/CD2AFEB9827B/CCAPI/cc_api_call_setup_ind_common:
Interface=0x223911DC, Call Info(
Calling Number=960040695,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916612250(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=4, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=176111
*Jan 10 2016 11:07:33.709 UTC: //-1/CD2AFEB9827B/CCAPI/ccCheckClipClir:
In: Calling Number=960040695(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 10 2016 11:07:33.709 UTC: //-1/CD2AFEB9827B/CCAPI/ccCheckClipClir:
Out: Calling Number=960040695(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 10 2016 11:07:33.709 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:33.709 UTC: :cc_get_feature_vsa malloc success
*Jan 10 2016 11:07:33.709 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:33.709 UTC: cc_get_feature_vsa count is 1
*Jan 10 2016 11:07:33.709 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:33.709 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:1060890968,feature_id:3627
*Jan 10 2016 11:07:33.709 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=960040695(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916612250(TON=Unknown, NPI=Unknown))
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/cc_process_call_setup_ind:
Event=0x2260EDA0
*Jan 10 2016 11:07:33.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 916612250
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallSetContext:
Context=0x3E1409B0
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 176111 with tag 4 to app "_ManagedAppProcess_Default"
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=5, Params=0x3E153930, Progress Indication=NULL(0)
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccCheckClipClir:
In: Calling Number=960040695(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccCheckClipClir:
Out: Calling Number=960040695(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallSetupRequest:
Destination Pattern=9T, Called Number=916612250, Digit Strip=FALSE
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallSetupRequest:
Calling Number=960040695(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916612250(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=960040695
Account Number=960040695, Final Destination Flag=TRUE,
Guid=CD2AFEB9-B6B8-11E5-827B-BEB0A6AD6561, Outgoing Dial-peer=5
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=960040695
----- ccCallInfo IE subfields -----
cisco-ani=960040695
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=916612250
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x3EA4CEC0, Interface Type=1, Destination=, Mode=0x0,
Call Params(Calling Number=960040695,(Calling Name=960040695)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916612250(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=5, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Jan 10 2016 11:07:33.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:33.713 UTC: :cc_get_feature_vsa malloc success
*Jan 10 2016 11:07:33.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:33.713 UTC: cc_get_feature_vsa count is 2
*Jan 10 2016 11:07:33.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:33.713 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:1060883352,feature_id:3628
*Jan 10 2016 11:07:33.713 UTC: //176112/CD2AFEB9827B/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1
*Jan 10 2016 11:07:33.713 UTC: //176112/CD2AFEB9827B/CCAPI/ccCallSetContext:
Context=0x3E1538E0
*Jan 10 2016 11:07:33.713 UTC: //176111/CD2AFEB9827B/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=5
*Jan 10 2016 11:07:33.717 UTC: //176112/CD2AFEB9827B/CCAPI/ccGetMediaClassTag:
media class tag 0
*Jan 10 2016 11:07:33.717 UTC: //176112/CD2AFEB9827B/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
*Jan 10 2016 11:07:33.717 UTC: //176111/CD2AFEB9827B/CCAPI/ccGetMediaClassTag:
media class tag 0
*Jan 10 2016 11:07:33.717 UTC: //176111/CD2AFEB9827B/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
*Jan 10 2016 11:07:33.717 UTC: //176112/CD2AFEB9827B/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
*Jan 10 2016 11:07:33.717 UTC: //176111/CD2AFEB9827B/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
*Jan 10 2016 11:07:34.241 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_set_called_ccm_detected:
CallInfo(called ccm detected=TRUE ccmVersion 3)
*Jan 10 2016 11:07:34.241 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_proceeding:
Interface=0x3EA4CEC0, Progress Indication=NULL(0)
*Jan 10 2016 11:07:34.601 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_set_called_ccm_detected:
CallInfo(called ccm detected=TRUE ccmVersion 3)
*Jan 10 2016 11:07:34.601 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_set_delay_xport:
CallInfo(delay xport=TRUE)
*Jan 10 2016 11:07:34.601 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_alert:
Interface=0x3EA4CEC0, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Jan 10 2016 11:07:34.601 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
*Jan 10 2016 11:07:34.601 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Jan 10 2016 11:07:34.601 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
*Jan 10 2016 11:07:34.601 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=176111
*Jan 10 2016 11:07:34.601 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=176111
*Jan 10 2016 11:07:34.601 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_set_called_ccm_detected:
CallInfo(called ccm detected=TRUE ccmVersion 3)
*Jan 10 2016 11:07:34.601 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_notify:
Data Bitmask=0x5, Interface=0x3EA4CEC0, Call Id=176112
*Jan 10 2016 11:07:34.601 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_get_ssCTreRoutingNotSupported:
CallInfo(ssCTreRoutingNotSupported=FALSE)
*Jan 10 2016 11:07:34.601 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallNotify:
Data Bitmask=0x5, Call Id=176111
*Jan 10 2016 11:07:35.653 UTC: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x3EBC5A20, Interface Type=9, Destination=0.0.0.0, Mode=0x0,
Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Jan 10 2016 11:07:35.653 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.653 UTC: :cc_get_feature_vsa malloc success
*Jan 10 2016 11:07:35.653 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.653 UTC: cc_get_feature_vsa count is 3
*Jan 10 2016 11:07:35.653 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.653 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:1060883128,feature_id:3629
*Jan 10 2016 11:07:35.653 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1
*Jan 10 2016 11:07:35.653 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallSetContext:
Context=0x3DF58514
*Jan 10 2016 11:07:35.653 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
Interface=0x3EBC5A20, Data Bitmask=0x0, Progress Indication=NULL(0),
Connection Handle=0
*Jan 10 2016 11:07:35.653 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
*Jan 10 2016 11:07:35.653 UTC: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x3EBC5A20, Interface Type=9, Destination=0.0.0.0, Mode=0x0,
Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Jan 10 2016 11:07:35.657 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.657 UTC: :cc_get_feature_vsa malloc success
*Jan 10 2016 11:07:35.657 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.657 UTC: cc_get_feature_vsa count is 4
*Jan 10 2016 11:07:35.657 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.657 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:1060888280,feature_id:3630
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallSetContext:
Context=0x3DF5855C
*Jan 10 2016 11:07:35.657 UTC: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x223A7808, Interface Type=28, Destination=, Mode=0x0,
Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Jan 10 2016 11:07:35.657 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.657 UTC: :cc_get_feature_vsa malloc success
*Jan 10 2016 11:07:35.657 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.657 UTC: cc_get_feature_vsa count is 5
*Jan 10 2016 11:07:35.657 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 10 2016 11:07:35.657 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:1060887160,feature_id:3631
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
Interface=0x223A7808, Data Bitmask=0x0, Progress Indication=NULL(0),
Connection Handle=0
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=28, FlowMode=1
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccCallSetContext:
Context=0x3DF58610
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
Interface=0x3EBC5A20, Data Bitmask=0x0, Progress Indication=NULL(0),
Connection Handle=0
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID1=0x2AFF3, gcid=0-0-0-0, tag=0x0)
*Jan 10 2016 11:07:35.657 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID2=0x2AFF1, gcid=0-0-0-0, tag=0x0)
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFF, Call Id1=176115, Call Id2=176113, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x5AA, Source Interface=0x223A7808, Source Call Id=176115,
Destination Call Id=176113, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.657 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:35.657 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.657 UTC: call_info mainst_callID:0x2AFF1, peer_callID:0x2AFF3, confID:0x5AA, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:35.657 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.657 UTC: stream[0]: callid:0x2AFF1, media_passthrough:1
*Jan 10 2016 11:07:35.657 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x5AA, Source Interface=0x3EBC5A20, Source Call Id=176113,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
Conference Id=0x5AA, Source Interface=0x3EBC5A20, Source Call Id=176113,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x5AA, Destination Call Id=176113)
*Jan 10 2016 11:07:35.657 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x5AA, Destination Call Id=176115)
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:

*Jan 10 2016 11:07:35.657 UTC: confID:0x5AA; callEntry1 callID1:0x2AFF3, type:28; callEntry2 callID2:0x2AFF1, type:9

*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID1=0x2AFF3, gcid=0-0-0-0, tag=0x0)
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID2=0x2AFF2, gcid=0-0-0-0, tag=0x0)
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFF, Call Id1=176115, Call Id2=176114, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x5AB, Source Interface=0x223A7808, Source Call Id=176115,
Destination Call Id=176114, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.657 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.657 UTC: call_info mainst_callID:0x2AFF2, peer_callID:0x2AFF3, confID:0x5AB, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.657 UTC: stream[0]: callid:0x2AFF2, media_passthrough:1
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x5AB, Source Interface=0x3EBC5A20, Source Call Id=176114,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
Conference Id=0x5AB, Source Interface=0x3EBC5A20, Source Call Id=176114,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.657 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x5AB, Destination Call Id=176114)
*Jan 10 2016 11:07:35.657 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x5AB, Destination Call Id=176115)
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:

*Jan 10 2016 11:07:35.661 UTC: confID:0x5AB; callEntry1 callID1:0x2AFF3, type:28; callEntry2 callID2:0x2AFF2, type:9

*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallModify:
Nominator=0x0, Params=0x22F77C08, Call Id=176113
*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallModify:
Nominator=0x0, Params=0x22F77C08, Call Id=176114
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallModify:
Nominator=0x418E00, Params=0x22F78520, Call Id=176113
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Destination Interface=0x3EBC5A20, Destination Call Id=176113, Source Call Id=176115,
Caps(Codec=0x1, Fax Rate=0x0, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=160, Signal Type=0)
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=0, Playout Initial=0(ms), Playout Min=0(ms),
Playout Max=0(ms), Fax Nom=0(ms))
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Destination Interface=0x223A7808, Destination Call Id=176115, Source Call Id=176113,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=0, Signal Type=2)
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_caps_ack:
Destination Interface=0x3EBC5A20, Destination Call Id=176113, Source Call Id=176115,
Caps(Codec=g711ulaw(0x1), Fax Rate=Invalid(0x0), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=0, Seq Num Start=0)
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_caps_ack:
Destination Interface=0x223A7808, Destination Call Id=176115, Source Call Id=176113,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=0, Signal Type=2, Seq Num Start=8451)
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Destination Interface=0x3EBC5A20, Destination Call Id=176114, Source Call Id=176115,
Caps(Codec=0x1, Fax Rate=0x0, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=160, Signal Type=0)
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=0, Playout Initial=0(ms), Playout Min=0(ms),
Playout Max=0(ms), Fax Nom=0(ms))
*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Destination Interface=0x223A7808, Destination Call Id=176115, Source Call Id=176114,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=0, Signal Type=2)
*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_caps_ack:
Destination Interface=0x3EBC5A20, Destination Call Id=176114, Source Call Id=176115,
Caps(Codec=g711ulaw(0x1), Fax Rate=Invalid(0x0), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=0, Seq Num Start=0)
*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_caps_ack:
Destination Interface=0x223A7808, Destination Call Id=176115, Source Call Id=176114,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=0, Signal Type=2, Seq Num Start=662)
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x5AA, Call Id1=176115, Call Id2=176113
*Jan 10 2016 11:07:35.661 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x5AB, Call Id1=176115, Call Id2=176114
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
Result=0, Interface=0x3EBC5A20, Call Id=176113
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: call_info mainst_callID:0x2AFF1, peer_callID:0x2AFF3, confID:0x5AA, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: stream[0]: callid:0x2AFF1, media_passthrough:1
*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
Result=0, Interface=0x3EBC5A20, Call Id=176114
*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: call_info mainst_callID:0x2AFF2, peer_callID:0x2AFF3, confID:0x5AB, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: stream[0]: callid:0x2AFF2, media_passthrough:1
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
Result=0, Interface=0x3EBC5A20, Call Id=176113
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: call_info mainst_callID:0x2AFF1, peer_callID:0x2AFF3, confID:0x5AA, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.661 UTC: stream[0]: callid:0x2AFF1, media_passthrough:1
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
Nominator=0x22F781F8, Params=0x22F78208, Call Id=176113
*Jan 10 2016 11:07:35.661 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
Nominator=0x22F781F8, Params=0x22F78208, Call Id=176114
*Jan 10 2016 11:07:35.661 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
Nominator=0x22F781F8, Params=0x22F78208, Call Id=176113
*Jan 10 2016 11:07:35.729 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallModify:
Nominator=0x418E00, Params=0x22F78520, Call Id=176114
*Jan 10 2016 11:07:35.729 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
Result=0, Interface=0x3EBC5A20, Call Id=176114
*Jan 10 2016 11:07:35.729 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.729 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:35.729 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.729 UTC: call_info mainst_callID:0x2AFF2, peer_callID:0x2AFF3, confID:0x5AB, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:35.729 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:35.729 UTC: stream[0]: callid:0x2AFF2, media_passthrough:1
*Jan 10 2016 11:07:35.729 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
Nominator=0x22F781F8, Params=0x22F78208, Call Id=176114
*Jan 10 2016 11:07:35.777 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_event_indication:
Event=183, Call Id=176112
*Jan 10 2016 11:07:35.777 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
*Jan 10 2016 11:07:35.777 UTC: //176112/CD2AFEB9827B/CCAPI/ccIsInfoRingback:
Returning dpRingBack=0
*Jan 10 2016 11:07:35.777 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_connected:
Interface=0x3EA4CEC0, Data Bitmask=0x0, Progress Indication=NULL(0),
Connection Handle=0
*Jan 10 2016 11:07:35.777 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
*Jan 10 2016 11:07:35.777 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID1=0x2AFEF, gcid=CD2AFEB9-B6B811E5-827EBEB0-A6AD6561, tag=0x0)
*Jan 10 2016 11:07:35.777 UTC: //176112/CD2AFEB9827B/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID2=0x2AFF0, gcid=CD2AFEB9-B6B811E5-827EBEB0-A6AD6561, tag=0x0)
*Jan 10 2016 11:07:35.777 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFF, Call Id1=176111, Call Id2=176112, Tag=0x0
*Jan 10 2016 11:07:35.777 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceCreate:

*Jan 10 2016 11:07:35.777 UTC: ccConferenceCreate: ret1=0, codecMask1=1, bytes1=160, negot1=1, dtmf1=0
ret2=0, codecMask2=1, bytes2=160, negot2=1, dtmf2=3,
tx_dynamic_pt1=0, rx_dynamic_pt1=0, codec_mode1=0, params_bitmap1 =0
tx_dynamic_pt2=0, rx_dynamic_pt2=0, codec_mode2=0, params_bitmap2 =0
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/ccGetMediaClassTag:
media class tag 0
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
*Jan 10 2016 11:07:35.781 UTC: //176112/CD2AFEB9827B/CCAPI/ccGetMediaClassTag:
media class tag 0
*Jan 10 2016 11:07:35.781 UTC: //176112/CD2AFEB9827B/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
*Jan 10 2016 11:07:35.781 UTC: //176112/CD2AFEB9827B/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
*Jan 10 2016 11:07:35.781 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
Call Id 176111
*Jan 10 2016 11:07:35.781 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
Failure with registry invocation of cng fax detection being allowed 176111
*Jan 10 2016 11:07:35.781 UTC: //176111/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
xcoder inserted for preferred features w/ mask 0x0
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceCreate:
delay media to slow start case, codec negotation is not done
*Jan 10 2016 11:07:35.781 UTC: //176111/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 10 2016 11:07:35.781 UTC: cc_api_get_xcode_stream : 4982
*Jan 10 2016 11:07:35.781 UTC: //176111/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 10 2016 11:07:35.781 UTC: cc_api_get_xcode_stream : 4982
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_bridge_done:
Conference Id=0x5AC, Source Interface=0x223911DC, Source Call Id=176111,
Destination Call Id=176112, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.781 UTC: //176112/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 10 2016 11:07:35.781 UTC: cc_api_get_xcode_stream : 4982
*Jan 10 2016 11:07:35.781 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_bridge_done:
Conference Id=0x5AC, Source Interface=0x3EA4CEC0, Source Call Id=176112,
Destination Call Id=176111, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/cc_generic_bridge_done:
Conference Id=0x5AC, Source Interface=0x3EA4CEC0, Source Call Id=176112,
Destination Call Id=176111, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x5AC, Destination Call Id=176112)
*Jan 10 2016 11:07:35.781 UTC: //176112/CD2AFEB9827B/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x5AC, Destination Call Id=176111)
*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceCreate:

*Jan 10 2016 11:07:35.781 UTC: confID:0x5AC; callEntry1 callID1:0x2AFEF, type:3; callEntry2 callID2:0x2AFF0, type:1

*Jan 10 2016 11:07:35.781 UTC: //176111/CD2AFEB9827B/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x5AC, Call Id1=176111, Call Id2=176112
*Jan 10 2016 11:07:35.781 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_set_called_ccm_detected:
CallInfo(called ccm detected=TRUE ccmVersion 3)
*Jan 10 2016 11:07:35.781 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_notify:
Data Bitmask=0x5, Interface=0x3EA4CEC0, Call Id=176112
*Jan 10 2016 11:07:35.785 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallConnect:
Progress Indication=NULL(0), Data Bitmask=0x1
*Jan 10 2016 11:07:35.785 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
*Jan 10 2016 11:07:35.785 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=176111
*Jan 10 2016 11:07:35.785 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_get_ssCTreRoutingNotSupported:
CallInfo(ssCTreRoutingNotSupported=FALSE)
*Jan 10 2016 11:07:35.785 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallNotify:
Data Bitmask=0x5, Call Id=176111
*Jan 10 2016 11:07:35.789 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_caps_ind:
Destination Interface=0x223911DC, Destination Call Id=176111, Source Call Id=176112,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x2,
Modem=0x0, Codec Bytes=20, Signal Type=2)
*Jan 10 2016 11:07:35.793 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
*Jan 10 2016 11:07:35.793 UTC: //176112/CD2AFEB9827B/CCAPI/ccGetMediaClassTag:
media class tag 0
*Jan 10 2016 11:07:35.793 UTC: //176112/CD2AFEB9827B/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
*Jan 10 2016 11:07:35.793 UTC: //176111/CD2AFEB9827B/CCAPI/ccGetMediaClassTag:
media class tag 0
*Jan 10 2016 11:07:35.793 UTC: //176111/CD2AFEB9827B/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
*Jan 10 2016 11:07:35.793 UTC: //176112/CD2AFEB9827B/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
*Jan 10 2016 11:07:35.793 UTC: //176111/CD2AFEB9827B/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
*Jan 10 2016 11:07:35.793 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_caps_ack:
Destination Interface=0x3EA4CEC0, Destination Call Id=176112, Source Call Id=176111,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=ON(0x2),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=4264)
*Jan 10 2016 11:07:35.797 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_caps_ack:
Destination Interface=0x223911DC, Destination Call Id=176111, Source Call Id=176112,
Caps(Codec=gsmefr(0x0), Fax Rate=Invalid(0x0), Fax Version:=0, Vad=Invalid(0x0),
Modem=OFF(0x0), Codec Bytes=0, Signal Type=0, Seq Num Start=0)
*Jan 10 2016 11:07:36.189 UTC: //176111/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:36.189 UTC: call_info mainst_callID:0x2AFEF, peer_callID:0x2AFF0, confID:0x5AC, spi_type:4, media_flo_thru:1,num_streams:1, swmtpmsp_present:0

*Jan 10 2016 11:07:36.189 UTC: //176111/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:36.189 UTC: stream[0]: callid:0x2AFEF, media_passthrough:1
*Jan 10 2016 11:07:47.153 UTC: //176112/CD2AFEB9827B/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Sum Network, Params=0x0, Call Id=176112
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x223911DC, Call Id=176111
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceDestroy:
Conference Id=0x5AC, Tag=0x0
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/ccConferenceDestroy:

*Jan 10 2016 11:07:47.153 UTC: confID:0x5AC; callEntry1 callID1:0x2AFEF, type:3; callEntry2 callID2:0x2AFF0, type:1

*Jan 10 2016 11:07:47.153 UTC: //176111/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 10 2016 11:07:47.153 UTC: cc_api_get_xcode_stream : 4982
*Jan 10 2016 11:07:47.153 UTC: //176112/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 10 2016 11:07:47.153 UTC: cc_api_get_xcode_stream : 4982
*Jan 10 2016 11:07:47.153 UTC: //176111/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 10 2016 11:07:47.153 UTC: cc_api_get_xcode_stream : 4982
*Jan 10 2016 11:07:47.153 UTC: //176111/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 10 2016 11:07:47.153 UTC: cc_api_get_xcode_stream : 4982
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x5AC, Source Interface=0x223911DC, Source Call Id=176111,
Destination Call Id=176112, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.153 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x5AC, Source Interface=0x3EA4CEC0, Source Call Id=176112,
Destination Call Id=176111, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/cc_generic_bridge_done:
Conference Id=0x5AC, Source Interface=0x3EA4CEC0, Source Call Id=176112,
Destination Call Id=176111, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
*Jan 10 2016 11:07:47.153 UTC: //176111/CD2AFEB9827B/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Jan 10 2016 11:07:47.153 UTC: //176112/CD2AFEB9827B/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Jan 10 2016 11:07:47.153 UTC: //176112/CD2AFEB9827B/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Jan 10 2016 11:07:47.153 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
*Jan 10 2016 11:07:47.157 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x223911DC, Tag=0x0, Call Id=176111,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Jan 10 2016 11:07:47.157 UTC: //176111/CD2AFEB9827B/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 10 2016 11:07:47.157 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.157 UTC: :cc_free_feature_vsa freeing 3F3BE950
*Jan 10 2016 11:07:47.157 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.157 UTC: vsacount in free is 4
*Jan 10 2016 11:07:47.157 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x3EA4CEC0, Tag=0x0, Call Id=176112,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Jan 10 2016 11:07:47.157 UTC: //176112/CD2AFEB9827B/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 10 2016 11:07:47.157 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.157 UTC: :cc_free_feature_vsa freeing 3F3BCB90
*Jan 10 2016 11:07:47.157 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.157 UTC: vsacount in free is 3
*Jan 10 2016 11:07:47.177 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallModify:
Nominator=0x800, Params=0x22F78570, Call Id=176113
*Jan 10 2016 11:07:47.177 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
Result=0, Interface=0x3EBC5A20, Call Id=176113
*Jan 10 2016 11:07:47.177 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:47.177 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:47.177 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:47.177 UTC: call_info mainst_callID:0x2AFF1, peer_callID:0x2AFF3, confID:0x5AA, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:47.177 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:47.177 UTC: stream[0]: callid:0x2AFF1, media_passthrough:1
*Jan 10 2016 11:07:47.177 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceDestroy:
Conference Id=0x5AA, Tag=0x0
*Jan 10 2016 11:07:47.177 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceDestroy:

*Jan 10 2016 11:07:47.177 UTC: confID:0x5AA; callEntry1 callID1:0x2AFF3, type:28; callEntry2 callID2:0x2AFF1, type:9

*Jan 10 2016 11:07:47.181 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x5AA, Source Interface=0x223A7808, Source Call Id=176115,
Destination Call Id=176113, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.181 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x5AA, Source Interface=0x3EBC5A20, Source Call Id=176113,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.181 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
Conference Id=0x5AA, Source Interface=0x3EBC5A20, Source Call Id=176113,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.181 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
Nominator=0x22F781F8, Params=0x22F78208, Call Id=176113
*Jan 10 2016 11:07:47.181 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Jan 10 2016 11:07:47.181 UTC: //176113/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)
*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallModify:
Nominator=0x800, Params=0x22F78570, Call Id=176114
*Jan 10 2016 11:07:47.181 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x3EBC5A20, Tag=0x0, Call Id=176113,
Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)
*Jan 10 2016 11:07:47.181 UTC: //176113/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 10 2016 11:07:47.181 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.181 UTC: :cc_free_feature_vsa freeing 3F3BCAB0
*Jan 10 2016 11:07:47.181 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.181 UTC: vsacount in free is 2
*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
Result=0, Interface=0x3EBC5A20, Call Id=176114
*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:47.181 UTC: peer_callID:0x2AFF3 is MSP call leg; set call_info.swmtpmsp_present to TRUE

*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:47.181 UTC: call_info mainst_callID:0x2AFF2, peer_callID:0x2AFF3, confID:0x5AB, spi_type:6, media_flo_thru:1,num_streams:1, swmtpmsp_present:1

*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

*Jan 10 2016 11:07:47.181 UTC: stream[0]: callid:0x2AFF2, media_passthrough:1
*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
Nominator=0x22F781F8, Params=0x22F78208, Call Id=176114
*Jan 10 2016 11:07:47.181 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceDestroy:
Conference Id=0x5AB, Tag=0x0
*Jan 10 2016 11:07:47.181 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccConferenceDestroy:

*Jan 10 2016 11:07:47.181 UTC: confID:0x5AB; callEntry1 callID1:0x2AFF3, type:28; callEntry2 callID2:0x2AFF2, type:9

*Jan 10 2016 11:07:47.181 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x5AB, Source Interface=0x223A7808, Source Call Id=176115,
Destination Call Id=176114, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x5AB, Source Interface=0x3EBC5A20, Source Call Id=176114,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.181 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
Conference Id=0x5AB, Source Interface=0x3EBC5A20, Source Call Id=176114,
Destination Call Id=176115, Disposition=0x0, Tag=0x0
*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Jan 10 2016 11:07:47.181 UTC: //176114/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)
*Jan 10 2016 11:07:47.185 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x3EBC5A20, Tag=0x0, Call Id=176114,
Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)
*Jan 10 2016 11:07:47.185 UTC: //176114/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 10 2016 11:07:47.185 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.185 UTC: :cc_free_feature_vsa freeing 3F3BDED0
*Jan 10 2016 11:07:47.185 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.185 UTC: vsacount in free is 1
*Jan 10 2016 11:07:47.185 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Jan 10 2016 11:07:47.185 UTC: //176115/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)
*Jan 10 2016 11:07:47.185 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x223A7808, Tag=0x0, Call Id=176115,
Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)
*Jan 10 2016 11:07:47.185 UTC: //176115/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 10 2016 11:07:47.185 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.185 UTC: :cc_free_feature_vsa freeing 3F3BDA70
*Jan 10 2016 11:07:47.185 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 10 2016 11:07:47.185 UTC: vsacount in free is 0

#debug ccsip messages
*Jan 10 2016 11:12:14.689 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:916612250@192.168.5.20 SIP/2.0
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK27e8046d;rport
Max-Forwards: 70
From: "960040695" <sip:960040695@90.169.25.195>;tag=as4fc09d3c
To: <sip:916612250@192.168.5.20>
Contact: <sip:960040695@90.169.25.195:5060>
Call-ID: 404f5efc37777ef745c26909049d4e7e@90.169.25.195:5060
CSeq: 102 INVITE
User-Agent: FPBX-2.11.0(11.13.0)
Date: Sun, 10 Jan 2016 10:14:04 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 1308

v=0
o=root 994815524 994815524 IN IP4 90.169.25.195
s=Asterisk PBX 11.13.0
c=IN IP4 90.169.25.195
t=0 0
m=audio 10764 RTP/AVP 8 4 3 0 112 5 7 18 110 97 2 9 102 115 116 117 96 100 107 108 10 118 119 101
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=fmtp:4 annexa=no
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:7 LPC/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:110 speex/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:2 G726-32/8000
a=rtpmap:9 G722/8000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:116 G719/48000
a=fmtp:116 bitrate=64000
a=rtpmap:117 speex/16000
a=rtpmap:96 SILK/8000
a=fmtp:96 maxaveragebitrate=10000
a=fmtp:96 usedtx=0
a=fmtp:96 useinbandfec=1
a=rtpmap:100 SILK/12000
a=fmtp:100 maxaveragebitrate=12000
a=fmtp:100 usedtx=0
a=fmtp:100 useinbandfec=1
a=rtpmap:107 SILK/16000
a=fmtp:107 maxaveragebitrate=20000
a=fmtp:107 usedtx=0
a=fmtp:107 useinbandfec=1
a=rtpmap:108 SILK/24000
a=fmtp:108 maxaveragebitrate=30000
a=fmtp:108 usedtx=0
a=fmtp:108 useinbandfec=1
a=rtpmap:10 L16/8000
a=rtpmap:118 L16/16000
a=rtpmap:119 speex/32000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

*Jan 10 2016 11:12:14.701 UTC: //176148/74A5BF6882A8/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK27e8046d;rport
From: "960040695" <sip:960040695@90.169.25.195>;tag=as4fc09d3c
To: <sip:916612250@192.168.5.20>
Date: Sun, 10 Jan 2016 10:12:14 GMT
Call-ID: 404f5efc37777ef745c26909049d4e7e@90.169.25.195:5060
CSeq: 102 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.4.3.M1
Content-Length: 0

*Jan 10 2016 11:12:14.749 UTC: //176148/74A5BF6882A8/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK27e8046d;rport
From: "960040695" <sip:960040695@90.169.25.195>;tag=as4fc09d3c
To: <sip:916612250@192.168.5.20>;tag=66A7E268-1EC4
Date: Sun, 10 Jan 2016 10:12:14 GMT
Call-ID: 404f5efc37777ef745c26909049d4e7e@90.169.25.195:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact: <sip:916612250@192.168.5.20:5060>
Server: Cisco-SIPGateway/IOS-15.4.3.M1
Content-Length: 0

*Jan 10 2016 11:12:15.845 UTC: //176148/74A5BF6882A8/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK27e8046d;rport
From: "960040695" <sip:960040695@90.169.25.195>;tag=as4fc09d3c
To: <sip:916612250@192.168.5.20>;tag=66A7E268-1EC4
Date: Sun, 10 Jan 2016 10:12:14 GMT
Call-ID: 404f5efc37777ef745c26909049d4e7e@90.169.25.195:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact: <sip:916612250@192.168.5.20:5060>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-15.4.3.M1
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 191

v=0
o=CiscoSystemsSIP-GW-UserAgent 7347 1794 IN IP4 192.168.5.20
s=SIP Call
c=IN IP4 192.168.5.20
t=0 0
m=audio 23030 RTP/AVP 0
c=IN IP4 192.168.5.20
a=rtpmap:0 PCMU/8000
a=ptime:20

*Jan 10 2016 11:12:15.861 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:916612250@192.168.5.20:51905 SIP/2.0
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK3f33a87f;rport
Max-Forwards: 70
From: "960040695" <sip:960040695@90.169.25.195>;tag=as4fc09d3c
To: <sip:916612250@192.168.5.20>;tag=66A7E268-1EC4
Contact: <sip:960040695@90.169.25.195:5060>
Call-ID: 404f5efc37777ef745c26909049d4e7e@90.169.25.195:5060
CSeq: 102 ACK
User-Agent: FPBX-2.11.0(11.13.0)
Content-Length: 0

*Jan 10 2016 11:12:20.713 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:2155@192.168.5.20:51905 SIP/2.0
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK1ac004c6;rport
Max-Forwards: 70
From: "Unknown" <sip:Unknown@90.169.25.195>;tag=as675c2e64
To: <sip:2155@192.168.5.20:5060>
Contact: <sip:Unknown@90.169.25.195:5060>
Call-ID: 14900dc74e5564913ace7c5e7bee5a7a@90.169.25.195:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.13.0)
Date: Sun, 10 Jan 2016 10:14:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

*Jan 10 2016 11:12:20.717 UTC: //176154/783CEFF182B2/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK1ac004c6;rport
From: "Unknown" <sip:Unknown@90.169.25.195>;tag=as675c2e64
To: <sip:2155@192.168.5.20:5060>;tag=66A7F9B8-13B4
Date: Sun, 10 Jan 2016 10:12:20 GMT
Call-ID: 14900dc74e5564913ace7c5e7bee5a7a@90.169.25.195:5060
Server: Cisco-SIPGateway/IOS-15.4.3.M1
CSeq: 102 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 371

v=0
o=CiscoSystemsSIP-GW-UserAgent 5717 2613 IN IP4 192.168.5.20
s=SIP Call
c=IN IP4 192.168.5.20
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15
c=IN IP4 192.168.5.20
m=image 0 udptl t38
c=IN IP4 192.168.5.20
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:320
a=T38FaxUdpEC:t38UDPRedundancy

*Jan 10 2016 11:12:26.145 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:916612250@192.168.5.20:51905 SIP/2.0
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK44de4c64;rport
Max-Forwards: 70
From: "960040695" <sip:960040695@90.169.25.195>;tag=as4fc09d3c
To: <sip:916612250@192.168.5.20>;tag=66A7E268-1EC4
Call-ID: 404f5efc37777ef745c26909049d4e7e@90.169.25.195:5060
CSeq: 103 BYE
User-Agent: FPBX-2.11.0(11.13.0)
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

*Jan 10 2016 11:12:26.149 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK44de4c64;rport
From: "960040695" <sip:960040695@90.169.25.195>;tag=as4fc09d3c
To: <sip:916612250@192.168.5.20>;tag=66A7E268-1EC4
Date: Sun, 10 Jan 2016 10:12:26 GMT
Call-ID: 404f5efc37777ef745c26909049d4e7e@90.169.25.195:5060
Server: Cisco-SIPGateway/IOS-15.4.3.M1
CSeq: 103 BYE
Reason: Q.850;cause=16
P-RTP-Stat: PS=515,OS=82400,PR=539,OR=77504,PL=0,JI=0,LA=0,DU=10
Content-Length: 0

[Ayodeji Okanlawon]

The difference between a working call and non working call is in the payload type used for rtp-nte. In the working call your provider sent payload type of 96 and in the non working one, they sent payload type of 101.

I need to see your show run, to know how best to proceed. Send your shoe run please

[aherraez77]

Hi Ayodeji:

The show run:

Building configuration...

Current configuration : 11442 bytes
!
! Last configuration change at 00:18:44 UTC Fri Jan 8 2016 by fibratel
!
version 15.4
service timestamps debug datetime msec localtime show-timezone year
service timestamps log datetime msec
no service password-encryption
!
hostname GW_ASENTIA
!
boot-start-marker
boot-end-marker
!
!
logging buffered 4096
!
no aaa new-model
clock timezone UTC 1 0
clock summer-time CEST recurring last Sun Mar 2:00 last Sun Oct 3:00
network-clock-participate wic 0
network-clock-select 1 BRI0/0/0
!

!
ip domain name yourdomain.com
ip host CiscoUM 192.168.5.7
ip host CiscoUM.ciscolocal.com 192.168.5.7
ip cef
no ipv6 cef
multilink bundle-name authenticated
!
!
!
!
!
isdn switch-type basic-net3
!

!
voice-card 0
dspfarm
dsp services dspfarm
!
!
!
voice service voip
ip address trusted list
ipv4 90.169.25.195
ipv4 213.97.72.7
ipv4 192.168.5.6
ipv4 192.168.5.7
ipv4 192.168.5.8
ipv4 192.168.5.10
ipv4 192.168.5.20
dtmf-interworking rtp-nte
mode border-element
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
supplementary-service h450.12
no supplementary-service sip moved-temporarily
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
h323
h225 connect-passthru
sip
bind control source-interface GigabitEthernet0/1
bind media source-interface GigabitEthernet0/1
no update-callerid
early-offer forced
no silent-discard untrusted
midcall-signaling passthru
privacy-policy passthru
!

voice class codec 10
codec preference 1 g711ulaw
codec preference 2 g729r8
codec preference 3 g711alaw
!
!
voice class sip-profiles 1
request INVITE sip-header SIP-Req-URI modify "; SIP/2.0" ";user=phone SIP/2.0"
request REINVITE sip-header SIP-Req-URI modify "; SIP/2.0" ";user=phone SIP/2.0"
request INVITE sip-header Contact modify ">" ";user=phone>"
request REINVITE sip-header Contact modify ">" ";user=phone>"
request INVITE sip-header To modify ">" ";user=phone>"
request REINVITE sip-header To modify ">" ";user=phone>"
request INVITE sip-header From modify ">" ";user=phone>"
request REINVITE sip-header From modify ">" ";user=phone>"
!
!

hw-module pvdm 0/0
!
hw-module pvdm 0/1

!
redundancy
!
!
!
translation-rule 1
Rule 0 ^08 8
Rule 1 ^09 9
!
!
!
!
!
interface Embedded-Service-Engine0/0
no ip address
shutdown
!
interface GigabitEthernet0/0
description LAN Datos Riofisa
ip address 192.168.1.24 255.255.255.0
duplex auto
speed auto
!
interface GigabitEthernet0/1
description VLAN Voz
ip address 192.168.5.20 255.255.255.0
duplex auto
speed auto
h323-gateway voip interface
h323-gateway voip bind srcaddr 192.168.5.20
!
interface BRI0/0/0
description Track 683700932 683700955
no ip address
isdn switch-type basic-net3
isdn point-to-point-setup
isdn incoming-voice voice
isdn sending-complete
isdn static-tei 0
!
interface BRI0/0/1
no ip address
isdn switch-type basic-net3
isdn point-to-point-setup
isdn incoming-voice voice
isdn sending-complete
!
ip forward-protocol nd
!
ip
!
control-plane
!
!
voice-port 0/0/1
compand-type a-law
cptone ES
!
!
sccp local GigabitEthernet0/1
sccp ccm 192.168.5.6 identifier 10 version 7.0
sccp
!
sccp ccm group 1
associate ccm 10 priority 1
associate profile 1 register RFS-TRA
associate profile 2 register RFS-CON
associate profile 3 register RFS-MTP
keepalive retries 5
switchover method immediate
switchback method immediate
switchback interval 5
!
!
!
dspfarm profile 1 transcode
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
codec g729br8
codec pass-through
maximum sessions 3
associate application SCCP
!
dspfarm profile 2 conference
codec g729br8
codec g729r8
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
maximum sessions 1
associate application SCCP
!
dspfarm profile 3 mtp
description recursos_mtp_soft
codec g711ulaw
codec pass-through
maximum sessions hardware 10
maximum sessions software 500
associate application SCCP
!
dial-peer voice 6001 pots
preference 1
destination-pattern 6........
direct-inward-dial
port 0/0/0
prefix 6
no sip-register
!
dial-peer voice 7001 pots
preference 1
destination-pattern 7........
direct-inward-dial
port 0/0/0
prefix 7
no sip-register
!
dial-peer voice 4 voip
incoming called-number .
voice-class codec 10
dtmf-relay rtp-nte digit-drop
!
dial-peer voice 5 voip
destination-pattern 9T
signaling forward unconditional
session target ipv4:192.168.5.6
voice-class codec 10
dtmf-relay h245-alphanumeric
!
dial-peer voice 9000 voip
destination-pattern 0[89]........
translate-outgoing called 1
session protocol sipv2
session target ipv4:90.169.25.195
session transport udp
voice-class codec 10
voice-class sip options-keepalive up-interval 12 down-interval 65 retry 3
dtmf-relay rtp-nte digit-drop
authentication username 2155 password 7 143F145A3A2E0E0411212B17343B010F5027605A
!
!

sip-ua
credentials username 2155 password 7 10660F48333D3624390D32093200352C71353115 realm sip1.sietevoz.com
authentication username 2155 password 7 032C5D5A302505637B000127013A0D15570C1D27
no remote-party-id
retry invite 2
retry register 10
retry subscribe 10
retry options 10
timers connect 100
registrar ipv4:90.169.25.195 expires 3600
sip-server dns:sip1.sietevoz.com
host-registrar
!
!
!
gatekeeper
shutdown
!
!
telephony-service
sdspfarm units 3
sdspfarm transcode sessions 10
sdspfarm tag 1 RFS-TRA
sdspfarm tag 2 RFS-CON
sdspfarm tag 3 RFS-MTP
max-ephones 1
max-dn 1
ip source-address 192.168.5.20 port 2000
max-conferences 8 gain -6
transfer-system full-consult
!
!

!
end

[Ayodeji Okanlawon]

Please try and add this command.. 

Voice service voip 

Sip

asymmetric payload dtmf

Then test again.