Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

  • Congratulations Mike Lewis on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

Dialpeer matching question

Status
Not open for further replies.
Oct 8, 2014
44
CA
Hi Folks,

It's been quite a while since I've worked with Cisco voice gateways and dial peers and I need some help with a project I am working on. Unfortunately, I tend to get confused when matching dial peers so I am asking for some help.

What I am trying to do is get a non-cisco pbx to work with a non-cisco messaging system using my voice gateway AS5300 in the middle (SIP connections to both). The VG is already handling calls from a non-Cisco one (PBX1) and I want to use the same VG and messaging platform for a second PBX (PBX2).

PBX2 has been configured to send calls destined for 5552226245 to the voice gateway where I will attempt to hand off the call to the messaging platform.

Debugs show that the call arrives at the voice gateway but my dialpeer isn't getting matched. I did some tracing (debug dialpeer and debug voice ccapi inout) and noticed the following.

Jul 31 13:03:30 ADT: Inside dpMatchCore:
Jul 31 13:03:30 ADT: destination pattn: vmproduction expanded string: vmproduction
Jul 31 13:03:30 ADT: Inside dpMatchCore:
Jul 31 13:03:30 ADT: destination pattn: 5555008888 expanded string: 5555008888
Jul 31 13:03:30 ADT: Inside dpMatchCore:
Jul 31 13:03:30 ADT: destination pattn: 5555008888 expanded string: 5555008888
Jul 31 13:03:30 ADT: MatchNextPeer: Peer 90 matched <---------not the dialpeer I want to match

Jul 31 13:03:30 ADT: cc_api_call_setup_ind:
Jul 31 13:03:30 ADT: cisco-username=5555008888
Jul 31 13:03:30 ADT: ----- ccCallInfo IE subfields -----
Jul 31 13:03:30 ADT: cisco-ani=5555008888
Jul 31 13:03:30 ADT: cisco-anitype=0
Jul 31 13:03:30 ADT: cisco-aniplan=0
Jul 31 13:03:30 ADT: cisco-anipi=0
Jul 31 13:03:30 ADT: cisco-anisi=0
Jul 31 13:03:30 ADT: dest=vmproduction
Jul 31 13:03:30 ADT: cisco-desttype=0
Jul 31 13:03:30 ADT: cisco-destplan=0
Jul 31 13:03:30 ADT: cisco-rdn=
Jul 31 13:03:30 ADT: cisco-rdntype=0
Jul 31 13:03:30 ADT: cisco-rdnplan=0
Jul 31 13:03:30 ADT: cisco-rdnpi=0
Jul 31 13:03:30 ADT: cisco-rdnsi=0
Jul 31 13:03:30 ADT: cisco-redirectreason=-1

Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=vmproduction,called_oct3=0x80,calling=5555008888,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=90, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x676C3048)

Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/cc_process_call_setup_ind: (event=0x6437E7E8)
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [vmproduction], calling# [5555008888], consultid []
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [vmproduction], calling# [5555008888], consultid []
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 504851 with tag 90 to app "DEFAULT"


These are the dial peers I created for this.

dial-peer voice 6221 voip
destination-pattern 5552226245 <-------- this is the number for the messaging platform for PBX2
voice-class codec 1
session protocol sipv2
session target ipv4:<<messaging system address>>
dtmf-relay rtp-nte
!
dial-peer voice 6222 voip
destination-pattern 555222.... <--------- messaging subscriber number range
voice-class codec 1
session protocol sipv2
session target ipv4:<<PBX2 address>>
dtmf-relay rtp-nte

This is the dialpeer that actually gets matched.

dial-peer voice 90 voip
translation-profile outgoing Outgoing
destination-pattern 1%[2-9][0-9][0-9].......
voice-class codec 1
session protocol sipv2
session target ipv4:<<PBX1 IP address>>
dtmf-relay rtp-nte


Basically, I am not matching my dial peer because I never get a match for "vmproduction" in my destination pattern. ......and I may have my dialpeers configured incorrectly.

Is there a way to match the text vmproduction in the dialpeer?
Is there a way to create a dialpeer match to accept all calls from the far end and hand it over to the messaging system for further processing?

Thanks in advance for your help.
 
Post a bigger debug. Nothing in your debug shows 5552226245. All it shows is the calling number, not the called number.

Also dialpeer 6221 will match that number. I feel like you want it to be 6222 that is doing the matching.


Certifications:
A+
Network+
CCENT
CCNA Voice
TVOICE
CAPPS
 
Hi there. Here's more debug output:

Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x6445334C], magic[FACE0FFF]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x6445334C, tagID=6, dataSize=16, instID=-1,modifier=3
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x65CBE260], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[3]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x65CBE260] instID[-1] into container[0x6445334C]
Jul 31 13:03:30 ADT: Inside dpMatchCore:
Jul 31 13:03:30 ADT: destination pattn: vmproduction expanded string: vmproduction
Jul 31 13:03:30 ADT: Inside dpMatchCore:
Jul 31 13:03:30 ADT: destination pattn: 5555008888 expanded string: 5555008888
Jul 31 13:03:30 ADT: Inside dpMatchCore:
Jul 31 13:03:30 ADT: destination pattn: 5555008888 expanded string: 5555008888
Jul 31 13:03:30 ADT: MatchNextPeer: Peer 90 matched
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x6445334C, tagID=20, dataSize=28, instID=-1,modifier=3
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6C331988], nxtElem[0x0], magic[0xFACE0FFF] tagID[20], dataLen[28], modif[3]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6C331988] instID[-1] into container[0x6445334C]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:
Jul 31 13:03:30 ADT: cc_api_call_setup_ind:
Jul 31 13:03:30 ADT: cisco-username=5555008888
Jul 31 13:03:30 ADT: ----- ccCallInfo IE subfields -----
Jul 31 13:03:30 ADT: cisco-ani=5555008888
Jul 31 13:03:30 ADT: cisco-anitype=0
Jul 31 13:03:30 ADT: cisco-aniplan=0
Jul 31 13:03:30 ADT: cisco-anipi=0
Jul 31 13:03:30 ADT: cisco-anisi=0
Jul 31 13:03:30 ADT: dest=vmproduction
Jul 31 13:03:30 ADT: cisco-desttype=0
Jul 31 13:03:30 ADT: cisco-destplan=0
Jul 31 13:03:30 ADT: cisco-rdn=
Jul 31 13:03:30 ADT: cisco-rdntype=0
Jul 31 13:03:30 ADT: cisco-rdnplan=0
Jul 31 13:03:30 ADT: cisco-rdnpi=0
Jul 31 13:03:30 ADT: cisco-rdnsi=0
Jul 31 13:03:30 ADT: cisco-redirectreason=-1

Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=vmproduction,called_oct3=0x80,calling=5555008888,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=90, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x676C3048)
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind:
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 3
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Jul 31 13:03:30 ADT: ccCheckClipClir: calling number is: "5555008888", calling oct3a is: 0x80
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Jul 31 13:03:30 ADT: Calling Party number is User Provided
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Jul 31 13:03:30 ADT: Leaving ccCheckClipClir
calling number is: "5555008888"
calling oct3 is: 0x0
calling oct3a is: 0x80
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=vmproduction, calling=5555008888, fdest=1 peer_tag=90}, callID=0x676C3048)
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 0
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 1
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE.
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x6C3294E0], numBuckets[11], numEntries[0]
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x6C3294E0] with objects in container[0x6445334C]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6C3294E0], tdObject[0x65CBE260]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[1] for the tag[20]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6C3294E0], tdObject[0x6C331988]
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccTDPvtProfileTableBuildManager:
Jul 31 13:03:30 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6C3294E0], numBuckets[11], numEntries[2]
Jul 31 13:03:30 ADT: Bucket { 1 } ------>0x6C331988[0x0,t-20,l-28,d-0x6C3319A8,m-3,u-8221968,g-FACE0FFF]
Jul 31 13:03:30 ADT:
Jul 31 13:03:30 ADT: Bucket { 5 } ------>0x65CBE260[0x0,t-6,l-16,d-0x65CBE280,m-3,u-8221968,g-FACE0FFF]
Jul 31 13:03:30 ADT:
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x6445334C]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is 162.255.216.18
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 16, voip: 16, mmoip: 0
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7B413, mask=2
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7B413, mask=2 flag=1
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7B413, mask=1 flag=1
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7B413, mask=1
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7B413, mask=1
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/cc_process_call_setup_ind: (event=0x6437E7E8)
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [vmproduction], calling# [5555008888], consultid []
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [vmproduction], calling# [5555008888], consultid []
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 504851 with tag 90 to app "DEFAULT"
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(504851), disp(0)
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(504851), disp(0)
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd:
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallSetContext: (callID=0x7B413, context=0x66E9BD08)
Jul 31 13:03:30 ADT: //504851/85B179B29993/SSAPP:90:-1/ssaCallSetupInd: cid(504851), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1
Jul 31 13:03:30 ADT: //504851/85B179B29993/SSAPP:90:-1/ssaCallSetupInd: src route label=, tgt route label= tg_label_flag 0x0
Jul 31 13:03:30 ADT: //504851/85B179B29993/SSAPP:90:-1/ssaCallSetupInd: finalDest cllng(5555008888), clled(vmproduction) tgt_route_label()tg_label_flag 0x0
Jul 31 13:03:30 ADT: Inside dpMatchPeersMoreArg
Jul 31 13:03:30 ADT: dpMatchPeersMoreArg: Match Dest. pattern; called (vmproduction)
Jul 31 13:03:30 ADT: Inside dpMatchCore:
Jul 31 13:03:30 ADT: destination pattn: vmproduction expanded string: vmproduction
Jul 31 13:03:30 ADT: dpMatchPeersMoreArg: Result=-1 after MATCH_ORIGINATE
Jul 31 13:03:30 ADT: //504851/85B179B29993/SSAPP:90:-1/ssaCallSetupInd: cid(504851), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= -1
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[504851], tagID[24], instID[-1]
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[504851]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[504851], tagID[24], instID[-1], data[0x6439A07C], dataSize[4]
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64B6A8F0], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[4]
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Jul 31 13:03:30 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6C3294E0], tdObject[0x64B6A8F0]
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccTDPvtProfileTableBuildManager:
Jul 31 13:03:30 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6C3294E0], numBuckets[11], numEntries[3]
Jul 31 13:03:30 ADT: Bucket { 0 } ------>0x64B6A8F0[0x0,t-24,l-4,d-0x64B6A910,m-4,u-8221968,g-FACE0FFF]
Jul 31 13:03:30 ADT:
Jul 31 13:03:30 ADT: Bucket { 1 } ------>0x6C331988[0x0,t-20,l-28,d-0x6C3319A8,m-3,u-8221968,g-FACE0FFF]
Jul 31 13:03:30 ADT:
Jul 31 13:03:30 ADT: Bucket { 5 } ------>0x65CBE260[0x0,t-6,l-16,d-0x65CBE280,m-8,u-8221968,g-FACE0FFF]
Jul 31 13:03:30 ADT:
Jul 31 13:03:30 ADT: //504851/85B179B29993/SSAPP:90:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(504851) peer list: (empty)
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallDisconnect: (callID=0x7B413, cause=0x1 tag=0x0)
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallDisconnect: calling accounting start for callID=504851 leg_type=1
Jul 31 13:03:30 ADT: //504851/85B179B29993/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x1
Jul 31 13:03:30 ADT: //504851/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[504851], tagID[24], instID[-1]
 
Again, your called number is no where to be found in that debug.

Certifications:
A+
Network+
CCENT
CCNA Voice
TVOICE
CAPPS
 
So I had a chat with the PBX maintainer and he made a change so that I now see the called number. So the scenario is I am calling 5552226245 to access the mail system. That call should initially hit PBX2, which will route the call via SIP to the VG. From there, the VG needs to send the call to the messaging platform for further processing.

Here is the debug from my call to 5552226245. Sorry for the long debug output but it shows two calls 1) 5555008888 to 5552221212 and 2) 5555008888 to 5552226245. The configs for the dialpeers can be seen at the end of the post.

What I don't understand is 1) why dial-peer 90 is being matched first and 2) why the call is not being handed off.


Debug dialpeer and debug voice ccapi inout results
==================================================

Aug 4 21:00:22 ADT: Inside dpMatchCore:
Aug 4 21:00:22 ADT: destination pattn: expanded string:
Aug 4 21:00:22 ADT: Inside dpMatchCore:
Aug 4 21:00:22 ADT: destination pattn: expanded string:
Aug 4 21:00:22 ADT: Inside dpMatchCore:
Aug 4 21:00:22 ADT: destination pattn: expanded string:
Aug 4 21:00:22 ADT: dpAssociateIncomingPeer_T null source route label
Aug 4 21:00:30 ADT: Inside dpMatchCore:
Aug 4 21:00:30 ADT: destination pattn: expanded string:
Aug 4 21:00:30 ADT: Inside dpMatchCore:
Aug 4 21:00:30 ADT: destination pattn: expanded string:
Aug 4 21:00:30 ADT: Inside dpMatchCore:
Aug 4 21:00:30 ADT: destination pattn: expanded string:
Aug 4 21:00:30 ADT: dpAssociateIncomingPeer_T null source route label
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x65DB0324], magic[FACE0FFF]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65DB0324, tagID=6, dataSize=16, instID=-1,modifier=3
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x67052D88], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[3]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x67052D88] instID[-1] into container[0x65DB0324]
Aug 4 21:00:35 ADT: Inside dpMatchCore:
Aug 4 21:00:35 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 4 21:00:35 ADT: Inside dpMatchCore:
Aug 4 21:00:35 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 4 21:00:35 ADT: Inside dpMatchCore:
Aug 4 21:00:35 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 4 21:00:35 ADT: MatchNextPeer: Peer 90 matched
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65DB0324, tagID=20, dataSize=28, instID=-1,modifier=3
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64432CFC], nxtElem[0x0], magic[0xFACE0FFF] tagID[20], dataLen[28], modif[3]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64432CFC] instID[-1] into container[0x65DB0324]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:
Aug 4 21:00:35 ADT: cc_api_call_setup_ind:
Aug 4 21:00:35 ADT: cisco-username=5555008888
Aug 4 21:00:35 ADT: ----- ccCallInfo IE subfields -----
Aug 4 21:00:35 ADT: cisco-ani=5555008888
Aug 4 21:00:35 ADT: cisco-anitype=0
Aug 4 21:00:35 ADT: cisco-aniplan=0
Aug 4 21:00:35 ADT: cisco-anipi=0
Aug 4 21:00:35 ADT: cisco-anisi=0
Aug 4 21:00:35 ADT: dest=5552226245
Aug 4 21:00:35 ADT: cisco-desttype=0
Aug 4 21:00:35 ADT: cisco-destplan=0
Aug 4 21:00:35 ADT: cisco-rdn=5552221212
Aug 4 21:00:35 ADT: cisco-rdntype=0
Aug 4 21:00:35 ADT: cisco-rdnplan=0
Aug 4 21:00:35 ADT: cisco-rdnpi=0
Aug 4 21:00:35 ADT: cisco-rdnsi=0
Aug 4 21:00:35 ADT: cisco-redirectreason=2

Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245,called_oct3=0x80,calling=5555008888,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=90, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x679579C0)
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind:
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 3
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 4 21:00:35 ADT: ccCheckClipClir: calling number is: "5555008888", calling oct3a is: 0x80
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 4 21:00:35 ADT: Calling Party number is User Provided
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 4 21:00:35 ADT: Leaving ccCheckClipClir
calling number is: "5555008888"
calling oct3 is: 0x0
calling oct3a is: 0x80
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245, calling=5555008888, fdest=1 peer_tag=90}, callID=0x679579C0)
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 1
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 2
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE.
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x64432D68], numBuckets[11], numEntries[0]
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x64432D68] with objects in container[0x65DB0324]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64432D68], tdObject[0x67052D88]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[1] for the tag[20]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64432D68], tdObject[0x64432CFC]
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 4 21:00:35 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64432D68], numBuckets[11], numEntries[2]
Aug 4 21:00:35 ADT: Bucket { 1 } ------>0x64432CFC[0x0,t-20,l-28,d-0x64432D1C,m-3,u-8596187,g-FACE0FFF]
Aug 4 21:00:35 ADT:
Aug 4 21:00:35 ADT: Bucket { 5 } ------>0x67052D88[0x0,t-6,l-16,d-0x67052DA8,m-3,u-8596187,g-FACE0FFF]
Aug 4 21:00:35 ADT:
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x65DB0324]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is << PBX2 IP address >>
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 16, voip: 16, mmoip: 0
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7A, mask=2
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7DD7A, mask=2 flag=1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7DD7A, mask=1 flag=1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7A, mask=1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7A, mask=1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_process_call_setup_ind: (event=0x6437E060)
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [5552226245], calling# [5555008888], consultid []
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [5552226245], calling# [5555008888], consultid []
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 515450 with tag 90 to app "DEFAULT"
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(515450), disp(0)
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(515450), disp(0)
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd:
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallSetContext: (callID=0x7DD7A, context=0x6CDD0808)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaCallSetupInd: cid(515450), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaCallSetupInd: src route label=, tgt route label= tg_label_flag 0x0
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaCallSetupInd: finalDest cllng(5555008888), clled(5552226245) tgt_route_label()tg_label_flag 0x0
Aug 4 21:00:35 ADT: Inside dpMatchPeersMoreArg
Aug 4 21:00:35 ADT: dpMatchPeersMoreArg: Match Dest. pattern; called (5552226245)
Aug 4 21:00:35 ADT: Inside dpMatchCore:
Aug 4 21:00:35 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 4 21:00:35 ADT: MatchNextPeer: Peer 90 matched
Aug 4 21:00:35 ADT: MatchNextPeer: Peer 6221 matched
Aug 4 21:00:35 ADT: MatchNextPeer: Peer 6222 matched
Aug 4 21:00:35 ADT: dpMatchPeersMoreArg: Result=0 after MATCH_ORIGINATE
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaCallSetupInd: cid(515450), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaDebugPeers: ssaSetupPeer cid(515450) peer list: tag(6221) called number (5552226245) tag(6222) called number (5552226245) tag(90) called number (5552226245)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaSetupPeer: dialpeer tags in rotary= 6221 6222 90
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaSetupPeer: cid(515450), destPat(5552226245), matched(10), prefix(), peer(66FFAF60), peer->encapType (2)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaSetupPeer: cid(515450), can't gateway with peer tag(6221))
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaSetupPeer: cid(515450), destPat(5552226245), matched(6), prefix(bm), peer(672E5E3C), peer->encapType (2)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaSetupPeer: cid(515450), can't gateway with peer tag(6222))
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaSetupPeer: cid(515450), destPat(5552226245), matched(4), prefix(), peer(648AA064), peer->encapType (2)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaSetupPeer: cid(515450), can't gateway with peer tag(90))
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[515450], tagID[24], instID[-1]
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[515450]

Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[515450], tagID[24], instID[-1], data[0x64399644], dataSize[4]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6FBAFE14], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[4]
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64432D68], tdObject[0x6FBAFE14]
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 4 21:00:35 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64432D68], numBuckets[11], numEntries[3]
Aug 4 21:00:35 ADT: Bucket { 0 } ------>0x6FBAFE14[0x0,t-24,l-4,d-0x6FBAFE34,m-4,u-8596187,g-FACE0FFF]
Aug 4 21:00:35 ADT:
Aug 4 21:00:35 ADT: Bucket { 1 } ------>0x64432CFC[0x0,t-20,l-28,d-0x64432D1C,m-3,u-8596187,g-FACE0FFF]
Aug 4 21:00:35 ADT:
Aug 4 21:00:35 ADT: Bucket { 5 } ------>0x67052D88[0x0,t-6,l-16,d-0x67052DA8,m-8,u-8596187,g-FACE0FFF]
Aug 4 21:00:35 ADT:
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(515450) peer list: (empty)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallDisconnect: (callID=0x7DD7A, cause=0x3 tag=0x0)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallDisconnect: calling accounting start for callID=515450 leg_type=1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x3
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[515450], tagID[24], instID[-1]
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallReportDigits: (callID=0x7DD7A, enable=0x0)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x63FE443C, callID=0x7DD7A, disp=0)
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(56=CC_EV_CALL_REPORT_DIGITS_DONE), cid(515450), disp(0)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaTraceSct: cid(515450)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_REPORT_DIGITS_DONE)
oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaDebugPeers: ssaReportDigitsDone cid(515450) peer list: (empty)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaReportDigitsDone: callid=515450 Reporting disabled.
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 10
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7A, mask=1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7A, mask=1
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[515450]
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[515450], tagID[7], instID[-1]
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[515450]
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7A, mask=2
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote IP is << PBX2 IP address >>
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is FastEthernet0/0
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of entry: 15, voip: 15, mmoip: 0
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x63FE443C, callID=0x7DD7A, disp=0, tag=0x0)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_delete_call_entry: Decrement call volume counter 2
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_delete_call_entry: current call volume: 1
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_delete_call_entry: entry's incoming TRUE.
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_delete_call_entry: is_incoming is FALSE
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/CCAPI/cc_delete_call_entry: Deleting profileTable[0x64432D68]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x64432D68)
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x6FBAFE14] tagID[24]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64432CFC] tagID[20]
Aug 4 21:00:35 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x67052D88] tagID[6]
Aug 4 21:00:35 ADT: //515450/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(515450), disp(0)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaTraceSct: cid(515450)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)
oldst(SSA_CS_DISCONNECTING)cfid(-1)csize(0)in(1)fDest(1)
Aug 4 21:00:35 ADT: //515450/D4BD06C28A8D/SSAPP:90:-1/ssaDisconnectDone:
Aug 4 21:00:38 ADT: Inside dpMatchCore:
Aug 4 21:00:38 ADT: destination pattn: expanded string:
Aug 4 21:00:38 ADT: Inside dpMatchCore:
Aug 4 21:00:38 ADT: destination pattn: expanded string:
Aug 4 21:00:38 ADT: Inside dpMatchCore:
Aug 4 21:00:38 ADT: destination pattn: expanded string:
Aug 4 21:00:38 ADT: dpAssociateIncomingPeer_T null source route label
Aug 4 21:00:46 ADT: Inside dpMatchCore:
Aug 4 21:00:46 ADT: destination pattn: expanded string:
Aug 4 21:00:46 ADT: Inside dpMatchCore:
Aug 4 21:00:46 ADT: destination pattn: expanded string:
Aug 4 21:00:46 ADT: Inside dpMatchCore:
Aug 4 21:00:46 ADT: destination pattn: expanded string:
Aug 4 21:00:46 ADT: dpAssociateIncomingPeer_T null source route label
Aug 4 21:00:54 ADT: Inside dpMatchCore:
Aug 4 21:00:54 ADT: destination pattn: expanded string:
Aug 4 21:00:54 ADT: Inside dpMatchCore:
Aug 4 21:00:54 ADT: destination pattn: expanded string:
Aug 4 21:00:54 ADT: Inside dpMatchCore:
Aug 4 21:00:54 ADT: destination pattn: expanded string:
Aug 4 21:00:54 ADT: dpAssociateIncomingPeer_T null source route label
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x68C2810C], magic[FACE0FFF]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x68C2810C, tagID=6, dataSize=16, instID=-1,modifier=3
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6F3A1D14], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[3]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6F3A1D14] instID[-1] into container[0x68C2810C]
Aug 4 21:00:58 ADT: Inside dpMatchCore:
Aug 4 21:00:58 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 4 21:00:58 ADT: Inside dpMatchCore:
Aug 4 21:00:58 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 4 21:00:58 ADT: Inside dpMatchCore:
Aug 4 21:00:58 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 4 21:00:58 ADT: MatchNextPeer: Peer 90 matched
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x68C2810C, tagID=20, dataSize=28, instID=-1,modifier=3
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6B6F6508], nxtElem[0x0], magic[0xFACE0FFF] tagID[20], dataLen[28], modif[3]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6B6F6508] instID[-1] into container[0x68C2810C]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:
Aug 4 21:00:58 ADT: cc_api_call_setup_ind:
Aug 4 21:00:58 ADT: cisco-username=5555008888
Aug 4 21:00:58 ADT: ----- ccCallInfo IE subfields -----
Aug 4 21:00:58 ADT: cisco-ani=5555008888
Aug 4 21:00:58 ADT: cisco-anitype=0
Aug 4 21:00:58 ADT: cisco-aniplan=0
Aug 4 21:00:58 ADT: cisco-anipi=0
Aug 4 21:00:58 ADT: cisco-anisi=0
Aug 4 21:00:58 ADT: dest=5552226245
Aug 4 21:00:58 ADT: cisco-desttype=0
Aug 4 21:00:58 ADT: cisco-destplan=0
Aug 4 21:00:58 ADT: cisco-rdn=
Aug 4 21:00:58 ADT: cisco-rdntype=0
Aug 4 21:00:58 ADT: cisco-rdnplan=0
Aug 4 21:00:58 ADT: cisco-rdnpi=0
Aug 4 21:00:58 ADT: cisco-rdnsi=0
Aug 4 21:00:58 ADT: cisco-redirectreason=-1

Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245,called_oct3=0x80,calling=5555008888,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=90, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x692CDE38)
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind:
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 3
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 4 21:00:58 ADT: ccCheckClipClir: calling number is: "5555008888", calling oct3a is: 0x80
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 4 21:00:58 ADT: Calling Party number is User Provided
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 4 21:00:58 ADT: Leaving ccCheckClipClir
calling number is: "5555008888"
calling oct3 is: 0x0
calling oct3a is: 0x80
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245, calling=5555008888, fdest=1 peer_tag=90}, callID=0x692CDE38)
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 1
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 2
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE.
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x6B6F6574], numBuckets[11], numEntries[0]
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x6B6F6574] with objects in container[0x68C2810C]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6B6F6574], tdObject[0x6F3A1D14]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[1] for the tag[20]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6B6F6574], tdObject[0x6B6F6508]
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 4 21:00:58 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6B6F6574], numBuckets[11], numEntries[2]
Aug 4 21:00:58 ADT: Bucket { 1 } ------>0x6B6F6508[0x0,t-20,l-28,d-0x6B6F6528,m-3,u-8596210,g-FACE0FFF]
Aug 4 21:00:58 ADT:
Aug 4 21:00:58 ADT: Bucket { 5 } ------>0x6F3A1D14[0x0,t-6,l-16,d-0x6F3A1D34,m-3,u-8596210,g-FACE0FFF]
Aug 4 21:00:58 ADT:
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x68C2810C]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is << PBX2 IP address >>
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 16, voip: 16, mmoip: 0
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7B, mask=2
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7DD7B, mask=2 flag=1
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7DD7B, mask=1 flag=1
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7B, mask=1
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7B, mask=1
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/cc_process_call_setup_ind: (event=0x6437E060)
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [5552226245], calling# [5555008888], consultid []
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [5552226245], calling# [5555008888], consultid []
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 515451 with tag 90 to app "DEFAULT"
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(515451), disp(0)
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(515451), disp(0)
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd:
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallSetContext: (callID=0x7DD7B, context=0x6BA0E41C)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaCallSetupInd: cid(515451), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaCallSetupInd: src route label=, tgt route label= tg_label_flag 0x0
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaCallSetupInd: finalDest cllng(5555008888), clled(5552226245) tgt_route_label()tg_label_flag 0x0
Aug 4 21:00:58 ADT: Inside dpMatchPeersMoreArg
Aug 4 21:00:58 ADT: dpMatchPeersMoreArg: Match Dest. pattern; called (5552226245)
Aug 4 21:00:58 ADT: Inside dpMatchCore:
Aug 4 21:00:58 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 4 21:00:58 ADT: MatchNextPeer: Peer 90 matched
Aug 4 21:00:58 ADT: MatchNextPeer: Peer 6221 matched
Aug 4 21:00:58 ADT: MatchNextPeer: Peer 6222 matched
Aug 4 21:00:58 ADT: dpMatchPeersMoreArg: Result=0 after MATCH_ORIGINATE
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaCallSetupInd: cid(515451), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaDebugPeers: ssaSetupPeer cid(515451) peer list: tag(6221) called number (5552226245) tag(6222) called number (5552226245) tag(90) called number (5552226245)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaSetupPeer: dialpeer tags in rotary= 6221 6222 90
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaSetupPeer: cid(515451), destPat(5552226245), matched(10), prefix(), peer(66FFAF60), peer->encapType (2)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaSetupPeer: cid(515451), can't gateway with peer tag(6221))
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaSetupPeer: cid(515451), destPat(5552226245), matched(6), prefix(bm), peer(672E5E3C), peer->encapType (2)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaSetupPeer: cid(515451), can't gateway with peer tag(6222))
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaSetupPeer: cid(515451), destPat(5552226245), matched(4), prefix(), peer(648AA064), peer->encapType (2)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaSetupPeer: cid(515451), can't gateway with peer tag(90))

Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[515451], tagID[24], instID[-1]
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[515451]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[515451], tagID[24], instID[-1], data[0x64399644], dataSize[4]
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x65D8265C], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[4]
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 4 21:00:58 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6B6F6574], tdObject[0x65D8265C]
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 4 21:00:58 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6B6F6574], numBuckets[11], numEntries[3]
Aug 4 21:00:58 ADT: Bucket { 0 } ------>0x65D8265C[0x0,t-24,l-4,d-0x65D8267C,m-4,u-8596210,g-FACE0FFF]
Aug 4 21:00:58 ADT:
Aug 4 21:00:58 ADT: Bucket { 1 } ------>0x6B6F6508[0x0,t-20,l-28,d-0x6B6F6528,m-3,u-8596210,g-FACE0FFF]
Aug 4 21:00:58 ADT:
Aug 4 21:00:58 ADT: Bucket { 5 } ------>0x6F3A1D14[0x0,t-6,l-16,d-0x6F3A1D34,m-8,u-8596210,g-FACE0FFF]
Aug 4 21:00:58 ADT:
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(515451) peer list: (empty)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallDisconnect: (callID=0x7DD7B, cause=0x3 tag=0x0)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallDisconnect: calling accounting start for callID=515451 leg_type=1
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x3
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[515451], tagID[24], instID[-1]
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/ccCallReportDigits: (callID=0x7DD7B, enable=0x0)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x63FE443C, callID=0x7DD7B, disp=0)
Aug 4 21:00:58 ADT: //515451/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(56=CC_EV_CALL_REPORT_DIGITS_DONE), cid(515451), disp(0)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaTraceSct: cid(515451)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_REPORT_DIGITS_DONE)
oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaDebugPeers: ssaReportDigitsDone cid(515451) peer list: (empty)
Aug 4 21:00:58 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaReportDigitsDone: callid=515451 Reporting disabled.
Aug 4 21:01:02 ADT: Inside dpMatchCore:
Aug 4 21:01:02 ADT: destination pattn: expanded string:
Aug 4 21:01:02 ADT: Inside dpMatchCore:
Aug 4 21:01:02 ADT: destination pattn: expanded string:
Aug 4 21:01:02 ADT: Inside dpMatchCore:
Aug 4 21:01:02 ADT: destination pattn: expanded string:
Aug 4 21:01:02 ADT: dpAssociateIncomingPeer_T null source route label
Aug 4 21:01:10 ADT: Inside dpMatchCore:
Aug 4 21:01:10 ADT: destination pattn: expanded string:
Aug 4 21:01:10 ADT: Inside dpMatchCore:
Aug 4 21:01:10 ADT: destination pattn: expanded string:
Aug 4 21:01:10 ADT: Inside dpMatchCore:
Aug 4 21:01:10 ADT: destination pattn: expanded string:
Aug 4 21:01:10 ADT: dpAssociateIncomingPeer_T null source route label
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 10
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7B, mask=1
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7B, mask=1
Aug 4 21:01:17 ADT: //515451/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[515451]
Aug 4 21:01:17 ADT: //515451/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[515451], tagID[7], instID[-1]
Aug 4 21:01:17 ADT: //515451/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[515451]
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7DD7B, mask=2
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote IP << PBX2 IP address >>
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is FastEthernet0/0
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of entry: 15, voip: 15, mmoip: 0
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x63FE443C, callID=0x7DD7B, disp=-11, tag=0x0)
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/cc_delete_call_entry: Decrement call volume counter 2
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/cc_delete_call_entry: current call volume: 1
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/cc_delete_call_entry: entry's incoming TRUE.
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/cc_delete_call_entry: is_incoming is FALSE
Aug 4 21:01:17 ADT: //515451/E260E6758A91/CCAPI/cc_delete_call_entry: Deleting profileTable[0x6B6F6574]
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x6B6F6574)
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x65D8265C] tagID[24]
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x6B6F6508] tagID[20]
Aug 4 21:01:17 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x6F3A1D14] tagID[6]
Aug 4 21:01:17 ADT: //515451/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(515451), disp(-11)
Aug 4 21:01:17 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaTraceSct: cid(515451)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)
oldst(SSA_CS_DISCONNECTING)cfid(-1)csize(0)in(1)fDest(1)
Aug 4 21:01:17 ADT: //515451/E260E6758A91/SSAPP:90:-1/ssaDisconnectDone:
Aug 4 21:01:18 ADT: Inside dpMatchCore:
Aug 4 21:01:18 ADT: destination pattn: expanded string:
Aug 4 21:01:18 ADT: Inside dpMatchCore:
Aug 4 21:01:18 ADT: destination pattn: expanded string:
Aug 4 21:01:18 ADT: Inside dpMatchCore:


Gateway Config
==============

voice translation-rule 2
rule 1 /^1800/ /1800/ type any national plan any isdn
rule 2 /^1866/ /1866/ type any national plan any isdn
rule 3 /^1877/ /1877/ type any national plan any isdn
rule 4 /^1888/ /1888/ type any national plan any isdn
rule 5 /^1441/ /1441/ type any national plan any isdn
rule 6 /^1/ /19901/ type any national plan any isdn
rule 7 /^011/ /1991/ type any international plan any isdn
!

voice translation-profile Outgoing2020321
translate called 2


dial-peer voice 90 voip <------------------------gets matched first
translation-profile outgoing OutBound
destination-pattern 1%[2-9][0-9][0-9].......
voice-class codec 1
session protocol sipv2
session target ipv4: << PBX1 IP address >>
dtmf-relay rtp-nte
!
dial-peer voice 6221 voip
destination-pattern 5552226245
voice-class codec 1
session protocol sipv2
session target ipv4: <<PBX2 IP address>>
dtmf-relay rtp-nte
!
dial-peer voice 6222 voip
destination-pattern 555222....
voice-class codec 1
session protocol sipv2
session target ipv4: <<messaging system address>>
dtmf-relay rtp-nte
!


 
Can you make another test call and run a show call active voice brief and paste the call legs?

Certifications:
A+
Network+
CCENT
CCNA Voice
TVOICE
CAPPS
 
Here's the output as requested. I have highlighted the call I made. The other entries are from a call already in progress. Thanks for your help so far.

AS5350#show call active voice brief
<ID>: <start>hs.<index> +<connect> pid:<peer_id> <dir> <addr> <state>
dur hh:mm:ss tx:<packets>/<bytes> rx:<packets>/<bytes>
IP <ip>:<udp> rtt:<time>ms pl:<play>/<gap>ms lost:<lost>/<early>/<late>
delay:<last>/<min>/<max>ms <codec>
MODEMPASS <method> buf:<fills>/<drains> loss <overall%> <multipkt>/<corrected>
last <buf event time>s dur:<Min>/<Max>s
FR <protocol> [int dlci cid] vad:<y/n> dtmf:<y/n> seq:<y/n>
<codec> (payload size)
ATM <protocol> [int vpi/vci cid] vad:<y/n> dtmf:<y/n> seq:<y/n>
<codec> (payload size)
Tele <int> (callID) [channel_id] tx:<tot>/<v>/<fax>ms <codec> noise:<l> acom:<l> i/o:<l>/<l> dBm
MODEMRELAY info:<rcvd>/<sent>/<resent> xid:<rcvd>/<sent> total:<rcvd>/<sent>/<drops>
speeds(bps): local <rx>/<tx> remote <rx>/<tx>
Proxy <ip>:<audio udp>,<video udp>,<tcp0>,<tcp1>,<tcp2>,<tcp3> endpt: <type>/<manf>
bw: <req>/<act> codec: <audio>/<video>
tx: <audio pkts>/<audio bytes>,<video pkts>/<video bytes>,<t120 pkts>/<t120 bytes>
rx: <audio pkts>/<audio bytes>,<video pkts>/<video bytes>,<t120 pkts>/<t120 bytes>


Telephony call-legs: 1
SIP call-legs: 2
H323 call-legs: 0
MGCP call-legs: 0
Multicast call-legs: 0
Total call-legs: 3
1215 : 86576208ms.1 +0 pid:2020321 Answer 5552360686 active
dur 00:29:10 tx:65725/982634 rx:45182/822978
Tele 3/1:D (518139) [3/1.4] tx:1750440/1074000/0ms g729br8 noise:-83 acom:76 i/0:0/-83 dBm

1221 : 86648928ms.1 +16190 pid:91 Originate 1991351296708027 active
dur 00:27:41 tx:45182/822978 rx:65654/965594
IP <<PBX1 IP address>>:19350 rtt:0ms pl:871000/70555ms lost:0/2/0 delay:60/50/160ms g729br8

13CF : 88308828ms.1 +-1 pid:90 Answer 5555008888 connecting
dur 00:00:00 tx:0/0 rx:0/0
IP <<PBX2 IP address>>:23922 rtt:0ms pl:0/0ms lost:0/0/0 delay:0/0/0ms g729r8

Telephony call-legs: 1
SIP call-legs: 2
H323 call-legs: 0
MGCP call-legs: 0
Multicast call-legs: 0
Total call-legs: 3
 
From everything I can see, it matches 90 because its the first possible match. Dial-peers aren't exactly like Route Patterns where it takes the most exact match.

Certifications:
A+
Network+
CCENT
CCNA Voice
TVOICE
CAPPS
 
Thanks for your help. Do you know any way around this so I can have the calls from 5552226245 match dial peer 6221?
 
To test it, make dial peer 6221 to something less than 90.

Certifications:
A+
Network+
CCENT
CCNA Voice
TVOICE
CAPPS
 
So I changed the dial peers from 6221 to 80 and 6222 to 85 and it still looks like dial peer 90 is being matched. Not sure where to go from here. See debugs below.

Call from 5555008888 to 5552226245
==================================


Aug 6 19:53:04 ADT: dpAssociateIncomingPeer_T null source route label
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x66D25088], magic[FACE0FFF]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x66D25088, tagID=6, dataSize=16, instID=-1,modifier=3
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6B00092C], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[3]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6B00092C] instID[-1] into container[0x66D25088]
Aug 6 19:53:08 ADT: Inside dpMatchCore:
Aug 6 19:53:08 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 6 19:53:08 ADT: Inside dpMatchCore:
Aug 6 19:53:08 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 6 19:53:08 ADT: Inside dpMatchCore:
Aug 6 19:53:08 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 6 19:53:08 ADT: MatchNextPeer: Peer 90 matched
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x66D25088, tagID=20, dataSize=28, instID=-1,modifier=3
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x65C20474], nxtElem[0x0], magic[0xFACE0FFF] tagID[20], dataLen[28], modif[3]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x65C20474] instID[-1] into container[0x66D25088]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:
Aug 6 19:53:08 ADT: cc_api_call_setup_ind:
Aug 6 19:53:08 ADT: cisco-username=5555008888
Aug 6 19:53:08 ADT: ----- ccCallInfo IE subfields -----
Aug 6 19:53:08 ADT: cisco-ani=5555008888
Aug 6 19:53:08 ADT: cisco-anitype=0
Aug 6 19:53:08 ADT: cisco-aniplan=0
Aug 6 19:53:08 ADT: cisco-anipi=0
Aug 6 19:53:08 ADT: cisco-anisi=0
Aug 6 19:53:08 ADT: dest=5552226245
Aug 6 19:53:08 ADT: cisco-desttype=0
Aug 6 19:53:08 ADT: cisco-destplan=0
Aug 6 19:53:08 ADT: cisco-rdn=
Aug 6 19:53:08 ADT: cisco-rdntype=0
Aug 6 19:53:08 ADT: cisco-rdnplan=0
Aug 6 19:53:08 ADT: cisco-rdnpi=0
Aug 6 19:53:08 ADT: cisco-rdnsi=0
Aug 6 19:53:08 ADT: cisco-redirectreason=-1

Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245,called_oct3=0x80,calling=5555008888,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=90, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x6FCE03B0)
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind:
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 3
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 19:53:08 ADT: ccCheckClipClir: calling number is: "5555008888", calling oct3a is: 0x80
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 19:53:08 ADT: Calling Party number is User Provided
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 19:53:08 ADT: Leaving ccCheckClipClir
calling number is: "5555008888"
calling oct3 is: 0x0
calling oct3a is: 0x80
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245, calling=5555008888, fdest=1 peer_tag=90}, callID=0x6FCE03B0)
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 1
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 2
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE.
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x671B4DD8], numBuckets[11], numEntries[0]
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x671B4DD8] with objects in container[0x66D25088]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x671B4DD8], tdObject[0x6B00092C]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[1] for the tag[20]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x671B4DD8], tdObject[0x65C20474]
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 6 19:53:08 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x671B4DD8], numBuckets[11], numEntries[2]
Aug 6 19:53:08 ADT: Bucket { 1 } ------>0x65C20474[0x0,t-20,l-28,d-0x65C20494,m-3,u-8764938,g-FACE0FFF]
Aug 6 19:53:08 ADT:
Aug 6 19:53:08 ADT: Bucket { 5 } ------>0x6B00092C[0x0,t-6,l-16,d-0x6B00094C,m-3,u-8764938,g-FACE0FFF]
Aug 6 19:53:08 ADT:
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x66D25088]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is << PBX2 IP address >>
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 16, voip: 16, mmoip: 0
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E0, mask=2
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7F3E0, mask=2 flag=1
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7F3E0, mask=1 flag=1
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E0, mask=1
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E0, mask=1
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/cc_process_call_setup_ind: (event=0x64378D88)
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [5552226245], calling# [5555008888], consultid []
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [5552226245], calling# [5555008888], consultid []
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 521184 with tag 90 to app "DEFAULT"
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(521184), disp(0)
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(521184), disp(0)
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd:
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallSetContext: (callID=0x7F3E0, context=0x671F9750)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaCallSetupInd: cid(521184), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaCallSetupInd: src route label=, tgt route label= tg_label_flag 0x0
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaCallSetupInd: finalDest cllng(5555008888), clled(5552226245) tgt_route_label()tg_label_flag 0x0
Aug 6 19:53:08 ADT: Inside dpMatchPeersMoreArg
Aug 6 19:53:08 ADT: dpMatchPeersMoreArg: Match Dest. pattern; called (5552226245)
Aug 6 19:53:08 ADT: Inside dpMatchCore:
Aug 6 19:53:08 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 6 19:53:08 ADT: MatchNextPeer: Peer 90 matched
Aug 6 19:53:08 ADT: MatchNextPeer: Peer 80 matched
Aug 6 19:53:08 ADT: MatchNextPeer: Peer 85 matched

Aug 6 19:53:08 ADT: dpMatchPeersMoreArg: Result=0 after MATCH_ORIGINATE
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaCallSetupInd: cid(521184), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaDebugPeers: ssaSetupPeer cid(521184) peer list: tag(80) called number (5552226245) tag(85) called number (5552226245) tag(90) called number (5552226245)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaSetupPeer: dialpeer tags in rotary= 80 85 90
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaSetupPeer: cid(521184), destPat(5552226245), matched(10), prefix(), peer(67626230), peer->encapType (2)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaSetupPeer: cid(521184), can't gateway with peer tag(80))
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaSetupPeer: cid(521184), destPat(5552226245), matched(6), prefix(bm), peer(6FC3AEA4), peer->encapType (2)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaSetupPeer: cid(521184), can't gateway with peer tag(85))
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaSetupPeer: cid(521184), destPat(5552226245), matched(4), prefix(), peer(648AA064), peer->encapType (2)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaSetupPeer: cid(521184), can't gateway with peer tag(90))
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521184], tagID[24], instID[-1]
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[521184]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[521184], tagID[24], instID[-1], data[0x64399644], dataSize[4]
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64503894], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[4]
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 6 19:53:08 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x671B4DD8], tdObject[0x64503894]
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 6 19:53:08 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x671B4DD8], numBuckets[11], numEntries[3]
Aug 6 19:53:08 ADT: Bucket { 0 } ------>0x64503894[0x0,t-24,l-4,d-0x645038B4,m-4,u-8764938,g-FACE0FFF]
Aug 6 19:53:08 ADT:
Aug 6 19:53:08 ADT: Bucket { 1 } ------>0x65C20474[0x0,t-20,l-28,d-0x65C20494,m-3,u-8764938,g-FACE0FFF]
Aug 6 19:53:08 ADT:
Aug 6 19:53:08 ADT: Bucket { 5 } ------>0x6B00092C[0x0,t-6,l-16,d-0x6B00094C,m-8,u-8764938,g-FACE0FFF]
Aug 6 19:53:08 ADT:
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(521184) peer list: (empty)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallDisconnect: (callID=0x7F3E0, cause=0x3 tag=0x0)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallDisconnect: calling accounting start for callID=521184 leg_type=1
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x3
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521184], tagID[24], instID[-1]
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/ccCallReportDigits: (callID=0x7F3E0, enable=0x0)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x63FE443C, callID=0x7F3E0, disp=0)
Aug 6 19:53:08 ADT: //521184/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(56=CC_EV_CALL_REPORT_DIGITS_DONE), cid(521184), disp(0)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaTraceSct: cid(521184)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_REPORT_DIGITS_DONE)
oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaDebugPeers: ssaReportDigitsDone cid(521184) peer list: (empty)
Aug 6 19:53:08 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaReportDigitsDone: callid=521184 Reporting disabled.
Aug 6 19:53:12 ADT: Inside dpMatchCore:
Aug 6 19:53:12 ADT: destination pattn: expanded string:
Aug 6 19:53:12 ADT: Inside dpMatchCore:
Aug 6 19:53:12 ADT: destination pattn: expanded string:
Aug 6 19:53:12 ADT: Inside dpMatchCore:
Aug 6 19:53:12 ADT: destination pattn: expanded string:
Aug 6 19:53:12 ADT: dpAssociateIncomingPeer_T null source route label
Aug 6 19:53:20 ADT: Inside dpMatchCore:
Aug 6 19:53:20 ADT: destination pattn: expanded string:
Aug 6 19:53:20 ADT: Inside dpMatchCore:
Aug 6 19:53:20 ADT: destination pattn: expanded string:
Aug 6 19:53:20 ADT: Inside dpMatchCore:
Aug 6 19:53:20 ADT: destination pattn: expanded string:
Aug 6 19:53:20 ADT: dpAssociateIncomingPeer_T null source route label
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 10
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E0, mask=1
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E0, mask=1
Aug 6 19:53:27 ADT: //521184/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[521184]
Aug 6 19:53:27 ADT: //521184/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521184], tagID[7], instID[-1]
Aug 6 19:53:27 ADT: //521184/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[521184]
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E0, mask=2
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote IP is << PBX2 IP address >>
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is FastEthernet0/0
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of entry: 15, voip: 15, mmoip: 0
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x63FE443C, callID=0x7F3E0, disp=-11, tag=0x0)
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/cc_delete_call_entry: Decrement call volume counter 2
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/cc_delete_call_entry: current call volume: 1
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/cc_delete_call_entry: entry's incoming TRUE.
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/cc_delete_call_entry: is_incoming is FALSE
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/CCAPI/cc_delete_call_entry: Deleting profileTable[0x671B4DD8]
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x671B4DD8)
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64503894] tagID[24]
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x65C20474] tagID[20]
Aug 6 19:53:27 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x6B00092C] tagID[6]
Aug 6 19:53:27 ADT: //521184/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(521184), disp(-11)
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaTraceSct: cid(521184)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)
oldst(SSA_CS_DISCONNECTING)cfid(-1)csize(0)in(1)fDest(1)
Aug 6 19:53:27 ADT: //521184/BD81C022BB29/SSAPP:90:-1/ssaDisconnectDone:
Aug 6 19:53:28 ADT: Inside dpMatchCore:
Aug 6 19:53:28 ADT: destination pattn: expanded string:
Aug 6 19:53:28 ADT: Inside dpMatchCore:
Aug 6 19:53:28 ADT: destination pattn: expanded string:
Aug 6 19:53:28 ADT: Inside dpMatchCore:
Aug 6 19:53:28 ADT: destination pattn: expanded string:
Aug 6 19:53:28 ADT: dpAssociateIncomingPeer_T null source route label
Aug 6 19:53:36 ADT: Inside dpMatchCore:
Aug 6 19:53:36 ADT: destination pattn: expanded string:
Aug 6 19:53:36 ADT: Inside dpMatchCore:
Aug 6 19:53:36 ADT: destination pattn: expanded string:
Aug 6 19:53:36 ADT: Inside dpMatchCore:
Aug 6 19:53:36 ADT: destination pattn: expanded string:
Aug 6 19:53:36 ADT: dpAssociateIncomingPeer_T null source route labelun a
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x65C18A24], magic[FACE0FFF]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65C18A24, tagID=6, dataSize=16, instID=-1,modifier=3
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6F05CDE4], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[3]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6F05CDE4] instID[-1] into container[0x65C18A24]
Aug 6 19:53:39 ADT: Inside dpMatchCore:
Aug 6 19:53:39 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 6 19:53:39 ADT: Inside dpMatchCore:
Aug 6 19:53:39 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 6 19:53:39 ADT: Inside dpMatchCore:
Aug 6 19:53:39 ADT: destination pattn: 5555008888 expanded string: 5555008888
Aug 6 19:53:39 ADT: MatchNextPeer: Peer 90 matched
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65C18A24, tagID=20, dataSize=28, instID=-1,modifier=3
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6D7ACDFC], nxtElem[0x0], magic[0xFACE0FFF] tagID[20], dataLen[28], modif[3]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6D7ACDFC] instID[-1] into container[0x65C18A24]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:
Aug 6 19:53:39 ADT: cc_api_call_setup_ind:
Aug 6 19:53:39 ADT: cisco-username=5555008888
Aug 6 19:53:39 ADT: ----- ccCallInfo IE subfields -----
Aug 6 19:53:39 ADT: cisco-ani=5555008888
Aug 6 19:53:39 ADT: cisco-anitype=0
Aug 6 19:53:39 ADT: cisco-aniplan=0
Aug 6 19:53:39 ADT: cisco-anipi=0
Aug 6 19:53:39 ADT: cisco-anisi=0
Aug 6 19:53:39 ADT: dest=5552226245
Aug 6 19:53:39 ADT: cisco-desttype=0
Aug 6 19:53:39 ADT: cisco-destplan=0
Aug 6 19:53:39 ADT: cisco-rdn=
Aug 6 19:53:39 ADT: cisco-rdntype=0
Aug 6 19:53:39 ADT: cisco-rdnplan=0
Aug 6 19:53:39 ADT: cisco-rdnpi=0
Aug 6 19:53:39 ADT: cisco-rdnsi=0
Aug 6 19:53:39 ADT: cisco-redirectreason=-1

Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245,called_oct3=0x80,calling=5555008888,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=90, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x68941E58)
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind:
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 3
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 19:53:39 ADT: ccCheckClipClir: calling number is: "5555008888", calling oct3a is: 0x80
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 19:53:39 ADT: Calling Party number is User Provided
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 19:53:39 ADT: Leaving ccCheckClipClir
calling number is: "5555008888"
calling oct3 is: 0x0
calling oct3a is: 0x80
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245, calling=5555008888, fdest=1 peer_tag=90}, callID=0x68941E58)
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 1
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 2
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE.
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x6D7ACE68], numBuckets[11], numEntries[0]
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x6D7ACE68] with objects in container[0x65C18A24]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6D7ACE68], tdObject[0x6F05CDE4]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[1] for the tag[20]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6D7ACE68], tdObject[0x6D7ACDFC]
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 6 19:53:39 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6D7ACE68], numBuckets[11], numEntries[2]
Aug 6 19:53:39 ADT: Bucket { 1 } ------>0x6D7ACDFC[0x0,t-20,l-28,d-0x6D7ACE1C,m-3,u-8764969,g-FACE0FFF]
Aug 6 19:53:39 ADT:
Aug 6 19:53:39 ADT: Bucket { 5 } ------>0x6F05CDE4[0x0,t-6,l-16,d-0x6F05CE04,m-3,u-8764969,g-FACE0FFF]
Aug 6 19:53:39 ADT:
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x65C18A24]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is << PBX2 IP address >>
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 16, voip: 16, mmoip: 0
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E1, mask=2
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7F3E1, mask=2 flag=1
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7F3E1, mask=1 flag=1
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E1, mask=1
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3E1, mask=1
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/cc_process_call_setup_ind: (event=0x64378D88)
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [5552226245], calling# [5555008888], consultid []
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [5552226245], calling# [5555008888], consultid []
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 521185 with tag 90 to app "DEFAULT"
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(521185), disp(0)
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(521185), disp(0)
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd:
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallSetContext: (callID=0x7F3E1, context=0x65DB1078)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaCallSetupInd: cid(521185), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaCallSetupInd: src route label=, tgt route label= tg_label_flag 0x0
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaCallSetupInd: finalDest cllng(5555008888), clled(5552226245) tgt_route_label()tg_label_flag 0x0
Aug 6 19:53:39 ADT: Inside dpMatchPeersMoreArg
Aug 6 19:53:39 ADT: dpMatchPeersMoreArg: Match Dest. pattern; called (5552226245)
Aug 6 19:53:39 ADT: Inside dpMatchCore:
Aug 6 19:53:39 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 6 19:53:39 ADT: MatchNextPeer: Peer 90 matched
Aug 6 19:53:39 ADT: MatchNextPeer: Peer 80 matched
Aug 6 19:53:39 ADT: MatchNextPeer: Peer 85 matched
Aug 6 19:53:39 ADT: dpMatchPeersMoreArg: Result=0 after MATCH_ORIGINATE
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaCallSetupInd: cid(521185), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaDebugPeers: ssaSetupPeer cid(521185) peer list: tag(80) called number (5552226245) tag(85) called number (5552226245) tag(90) called number (5552226245)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaSetupPeer: dialpeer tags in rotary= 80 85 90
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaSetupPeer: cid(521185), destPat(5552226245), matched(10), prefix(), peer(67626230), peer->encapType (2)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaSetupPeer: cid(521185), can't gateway with peer tag(80))
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaSetupPeer: cid(521185), destPat(5552226245), matched(6), prefix(bm), peer(6FC3AEA4), peer->encapType (2)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaSetupPeer: cid(521185), can't gateway with peer tag(85))
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaSetupPeer: cid(521185), destPat(5552226245), matched(4), prefix(), peer(648AA064), peer->encapType (2)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaSetupPeer: cid(521185), can't gateway with peer tag(90))
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521185], tagID[24], instID[-1]
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[52118ll
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[521185], tagID[24], instID[-1], data[0x64399644], dataSize[4]
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x65BF699C], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[4]
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 6 19:53:39 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6D7ACE68], tdObject[0x65BF699C]
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 6 19:53:39 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6D7ACE68], numBuckets[11], numEntries[3]
Aug 6 19:53:39 ADT: Bucket { 0 } ------>0x65BF699C[0x0,t-24,l-4,d-0x65BF69BC,m-4,u-8764969,g-FACE0FFF]
Aug 6 19:53:39 ADT:
Aug 6 19:53:39 ADT: Bucket { 1 } ------>0x6D7ACDFC[0x0,t-20,l-28,d-0x6D7ACE1C,m-3,u-8764969,g-FACE0FFF]
Aug 6 19:53:39 ADT:
Aug 6 19:53:39 ADT: Bucket { 5 } ------>0x6F05CDE4[0x0,t-6,l-16,d-0x6F05CE04,m-8,u-8764969,g-FACE0FFF]
Aug 6 19:53:39 ADT:
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(521185) peer list: (empty)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallDisconnect: (callID=0x7F3E1, cause=0x3 tag=0x0)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallDisconnect: calling accounting start for callID=521185 leg_type=1
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x3
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521185], tagID[24], instID[-1]
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/ccCallReportDigits: (callID=0x7F3E1, enable=0x0)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x63FE443C, callID=0x7F3E1, disp=0)
Aug 6 19:53:39 ADT: //521185/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(56=CC_EV_CALL_REPORT_DIGITS_DONE), cid(521185), disp(0)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaTraceSct: cid(521185)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_REPORT_DIGITS_DONE)
oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaDebugPeers: ssaReportDigitsDone cid(521185) peer list: (empty)
Aug 6 19:53:39 ADT: //521185/D048F281BB2E/SSAPP:90:-1/ssaReportDigitsDone: callid=521185 Reporting disabled.



Call from 5555008888 to 5552221212
==================================


Aug 6 20:02:21 ADT: Inside dpMatchCore:
Aug 6 20:02:21 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 6 20:02:21 ADT: Inside dpMatchCore:
Aug 6 20:02:21 ADT: destination pattn: 5555000808 expanded string: 5555000808
Aug 6 20:02:21 ADT: Inside dpMatchCore:
Aug 6 20:02:21 ADT: destination pattn: 5555000808 expanded string: 5555000808
Aug 6 20:02:21 ADT: MatchNextPeer: Peer 90 matched
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x66F07C10, tagID=20, dataSize=28, instID=-1,modifier=3
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6E11A228], nxtElem[0x0], magic[0xFACE0FFF] tagID[20], dataLen[28], modif[3]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6E11A228] instID[-1] into container[0x66F07C10]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:
Aug 6 20:02:21 ADT: cc_api_call_setup_ind:
Aug 6 20:02:21 ADT: cisco-username=5555000808
Aug 6 20:02:21 ADT: ----- ccCallInfo IE subfields -----
Aug 6 20:02:21 ADT: cisco-ani=5555000808
Aug 6 20:02:21 ADT: cisco-anitype=0
Aug 6 20:02:21 ADT: cisco-aniplan=0
Aug 6 20:02:21 ADT: cisco-anipi=0
Aug 6 20:02:21 ADT: cisco-anisi=0
Aug 6 20:02:21 ADT: dest=5552226245
Aug 6 20:02:21 ADT: cisco-desttype=0
Aug 6 20:02:21 ADT: cisco-destplan=0
Aug 6 20:02:21 ADT: cisco-rdn=5552221212
Aug 6 20:02:21 ADT: cisco-rdntype=0
Aug 6 20:02:21 ADT: cisco-rdnplan=0
Aug 6 20:02:21 ADT: cisco-rdnpi=0
Aug 6 20:02:21 ADT: cisco-rdnsi=0
Aug 6 20:02:21 ADT: cisco-redirectreason=2

Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245,called_oct3=0x80,calling=5555000808,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=90, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x68311F64)
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind:
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 3
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 20:02:21 ADT: ccCheckClipClir: calling number is: "5555000808", calling oct3a is: 0x80
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 20:02:21 ADT: Calling Party number is User Provided
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
Aug 6 20:02:21 ADT: Leaving ccCheckClipClir
calling number is: "5555000808"
calling oct3 is: 0x0
calling oct3a is: 0x80
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x63FE443C, callInfo={called=5552226245, calling=5555000808, fdest=1 peer_tag=90}, callID=0x68311F64)
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 2
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 3
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE.
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x6E11A294], numBuckets[11], numEntries[0]
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x6E11A294] with objects in container[0x66F07C10]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6E11A294], tdObject[0x65DBE7AC]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[1] for the tag[20]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6E11A294], tdObject[0x6E11A228]
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 6 20:02:21 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6E11A294], numBuckets[11], numEntries[2]
Aug 6 20:02:21 ADT: Bucket { 1 } ------>0x6E11A228[0x0,t-20,l-28,d-0x6E11A248,m-3,u-8765491,g-FACE0FFF]
Aug 6 20:02:21 ADT:
Aug 6 20:02:21 ADT: Bucket { 5 } ------>0x65DBE7AC[0x0,t-6,l-16,d-0x65DBE7CC,m-3,u-8765491,g-FACE0FFF]
Aug 6 20:02:21 ADT:
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x66F07C10]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is << PBX2 IP address >>
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 16, voip: 16, mmoip: 0
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3EE, mask=2
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7F3EE, mask=2 flag=1
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0x7F3EE, mask=1 flag=1
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3EE, mask=1
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3EE, mask=1
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_process_call_setup_ind: (event=0x64378D88)
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [5552226245], calling# [5555000808], consultid []
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [5002226245], calling# [5555000808], consultid []
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 521198 with tag 90 to app "DEFAULT"
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(521198), disp(0)
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(521198), disp(0)
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd:
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallSetContext: (callID=0x7F3EE, context=0x66493174)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaCallSetupInd: cid(521198), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaCallSetupInd: src route label=, tgt route label= tg_label_flag 0x0
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaCallSetupInd: finalDest cllng(5555000808), clled(5552226245) tgt_route_label()tg_label_flag 0x0
Aug 6 20:02:21 ADT: Inside dpMatchPeersMoreArg
Aug 6 20:02:21 ADT: dpMatchPeersMoreArg: Match Dest. pattern; called (5552226245)
Aug 6 20:02:21 ADT: Inside dpMatchCore:
Aug 6 20:02:21 ADT: destination pattn: 5552226245 expanded string: 5552226245
Aug 6 20:02:21 ADT: MatchNextPeer: Peer 90 matched
Aug 6 20:02:21 ADT: MatchNextPeer: Peer 80 matched
Aug 6 20:02:21 ADT: MatchNextPeer: Peer 85 matched
Aug 6 20:02:21 ADT: dpMatchPeersMoreArg: Result=0 after MATCH_ORIGINATE
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaCallSetupInd: cid(521198), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaDebugPeers: ssaSetupPeer cid(521198) peer list: tag(80) called number (5552226245) tag(85) called number (5552226245) tag(90) called number (5552226245)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaSetupPeer: dialpeer tags in rotary= 80 85 90
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaSetupPeer: cid(521198), destPat(5552226245), matched(10), prefix(), peer(67626230), peer->encapType (2)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaSetupPeer: cid(521198), can't gateway with peer tag(80))
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaSetupPeer: cid(521198), destPat(5552226245), matched(6), prefix(bm), peer(6FC3AEA4), peer->encapType (2)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaSetupPeer: cid(521198), can't gateway with peer tag(85))
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaSetupPeer: cid(521198), destPat(5552226245), matched(4), prefix(), peer(648AA064), peer->encapType (2)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaSetupPeer: cid(521198), can't gateway with peer tag(90))
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521198], tagID[24], instID[-1]
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[521198]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[521198], tagID[24], instID[-1], data[0x64399644], dataSize[4]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x65CD20A4], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[4]
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters...
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6E11A294], tdObject[0x65CD20A4]
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccTDPvtProfileTableBuildManager:
Aug 6 20:02:21 ADT: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6E11A294], numBuckets[11], numEntries[3]
Aug 6 20:02:21 ADT: Bucket { 0 } ------>0x65CD20A4[0x0,t-24,l-4,d-0x65CD20C4,m-4,u-8765491,g-FACE0FFF]
Aug 6 20:02:21 ADT:
Aug 6 20:02:21 ADT: Bucket { 1 } ------>0x6E11A228[0x0,t-20,l-28,d-0x6E11A248,m-3,u-8765491,g-FACE0FFF]
Aug 6 20:02:21 ADT:
Aug 6 20:02:21 ADT: Bucket { 5 } ------>0x65DBE7AC[0x0,t-6,l-16,d-0x65DBE7CC,m-8,u-8765491,g-FACE0FFF]
Aug 6 20:02:21 ADT:
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(521198) peer list: (empty)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallDisconnect: (callID=0x7F3EE, cause=0x3 tag=0x0)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallDisconnect: calling accounting start for callID=521198 leg_type=1
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x3
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521198], tagID[24], instID[-1]
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallReportDigits: (callID=0x7F3EE, enable=0x0)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x63FE443C, callID=0x7F3EE, disp=0)
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(56=CC_EV_CALL_REPORT_DIGITS_DONE), cid(521198), disp(0)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaTraceSct: cid(521198)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_REPORT_DIGITS_DONE)
oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaDebugPeers: ssaReportDigitsDone cid(521198) peer list: (empty)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaReportDigitsDone: callid=521198 Reporting disabled.
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 10
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3EE, mask=1
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3EE, mask=1
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[521198]
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[521198], tagID[7], instID[-1]
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[521198]
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0x7F3EE, mask=2
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote IP is << PBX2 IP address >>
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is FastEthernet0/0
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of entry: 15, voip: 15, mmoip: 0
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x63FE443C, callID=0x7F3EE, disp=0, tag=0x0)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_delete_call_entry: Decrement call volume counter 3
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_delete_call_entry: current call volume: 2
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_delete_call_entry: entry's incoming TRUE.
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_delete_call_entry: is_incoming is FALSE
Aug 6 20:02:21 ADT: //521198/07309086BB7F/CCAPI/cc_delete_call_entry: Deleting profileTable[0x6E11A294]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x6E11A294)
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x65CD20A4] tagID[24]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x6E11A228] tagID[20]
Aug 6 20:02:21 ADT: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x65DBE7AC] tagID[6]
Aug 6 20:02:21 ADT: //521198/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(521198), disp(0)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaTraceSct: cid(521198)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)
oldst(SSA_CS_DISCONNECTING)cfid(-1)csize(0)in(1)fDest(1)
Aug 6 20:02:21 ADT: //521198/07309086BB7F/SSAPP:90:-1/ssaDisconnectDone:
 
Try adding the line "preference 1" to your now dial-peer 80.

Certifications:
A+
Network+
CCENT
CCNA Voice
TVOICE
CAPPS
 
Unfortunately the preference statement did not help so I used incoming called number (as follows). Dial-peer 80 is now matched but the call still fails.

dial-peer voice 80 voip
incoming called-number 441222....
voice-class codec 1
session protocol sipv2
session target ipv4:162.255.216.54
dtmf-relay rtp-nte

I now have two dial peers for this; the one above is my inbound dial peer from PBX2 (the PBX that needs to use the existing messaging platform) and the dial peer below is my outbound peer to the messaging platform.

dial-peer voice 80 voip
incoming called-number 441222....
voice-class codec 1
session protocol sipv2
session target ipv4:162.255.216.54
dtmf-relay rtp-nte


Compare this to the working dial peer pair for PBX 1, inbound peer is as follows

dial-peer voice 10002 pots
trunkgroup PRIs
huntstop
preference 2
incoming called-number 44154T
direct-inward-dial

...and the outbound peer below goes to the messaging platform

dial-peer voice 6201 voip
destination-pattern 4415406245
session protocol sipv2
session target dns:iperia.vms.sip.northrock.bm
dtmf-relay rtp-nte
codec g711ulaw

I have attached debugs for the working a call to the messaging platform from PBX1 (ccapi-inout-call-successful.txt) and also from PBX2 (ccapi-inout-call-fail.txt). Even though the dial peers match for PBX2, I can't get the vg to hand off the call to the messaging platform and I can't see why not.

Any thoughts?


 
Probably easier to run a debug ccsip messages to find out why it is failing.

Certifications:
A+
Network+
CCENT
CCNA Voice
TVOICE
CAPPS
 
Status
Not open for further replies.

Part and Inventory Search

Sponsor

Back
Top