=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2017.11.27 14:52:07 =~=~=~=~=~=~=~=~=~=~=~=
login as: admin
Using keyboard-interactive authentication.
Password: 

R2#terminal monitor
R2#
*Nov 27 12:53:04.686: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.686: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=4, Called Number=4, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.686: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=4
*Nov 27 12:53:04.686: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=4, Expanded String=4, Calling Number=4T
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.686: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=4, saf_enabled=1, saf_dndb_lookup=1, dp_result=-1
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=NO_MATCH(-1)
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=102, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=102
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=102T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=102
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=102T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=40002 Is Matched
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=40002
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@7842 
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=102, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=102
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=102T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=102
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=102T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=40002 Is Matched
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=40002
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@7842 
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/ccSetupReqDataTDFreeHelper:
   data=139BDE50
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/ccFreeTdRequestDataSip:
   sipDatap = 139BDE54
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpAssociateIncomingPeerCore:
   Calling Number=102, Called Number=4, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=102
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=102T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=40002 Is Matched
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=40002
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:04.690: //-1/BE48C93880AE/DPM/dpAssociateIncomingPeerSPI:exit@7793 
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.690: //0/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.690: //0/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.690: //0/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp:
   Tag=40002
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp:
   Result=0 Bitmap=0x0
*Nov 27 12:53:04.690: //88/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_api_update_interface_cac_resource:
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_api_update_interface_cac_resource:
   Hwidb=GigabitEthernet0/2, Bandwidth=80, Call Id=88
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_api_update_interface_cac_resource:
   Total Call Count=0, Voip Call Count=0, MMoip Call Count=0x0, Bandwidth=80
*Nov 27 12:53:04.690: //88/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
*Nov 27 12:53:04.690: //88/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
*Nov 27 12:53:04.690: //-1/BE48C93880AE/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=102
   ----- ccCallInfo IE subfields -----
   cisco-ani=102
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=4
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

*Nov 27 12:53:04.690: //-1/BE48C93880AE/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x238F1F4, Call Info(
   Calling Number=102,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=4(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=40002, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=88
*Nov 27 12:53:04.690: //-1/BE48C93880AE/CCAPI/cc_api_call_setup_ind_common:
   Interface Type=0, Protocol=3
*Nov 27 12:53:04.690: //-1/BE48C93880AE/CCAPI/ccCheckClipClir:
   In: Calling Number=102(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 27 12:53:04.690: //-1/BE48C93880AE/CCAPI/ccCheckClipClir:
   Calling Party Number Is User Provided
*Nov 27 12:53:04.690: //-1/BE48C93880AE/CCAPI/ccCheckClipClir:
   Out: Calling Number=102(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 27 12:53:04.690: //-1/BE48C93880AE/CCAPI/cc_api_call_setup_ind_common:
   After Number Translation Checking:
   Calling Number=102(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=4(TON=Unknown, NPI=Unknown)
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
*Nov 27 12:53:04.690: :cc_get_feature_vsa malloc success
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
*Nov 27 12:53:04.690:  cc_get_feature_vsa count is 1
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
*Nov 27 12:53:04.690: :FEATURE_VSA attributes are: feature_name:0,feature_time:269905880,feature_id:41
*Nov 27 12:53:04.690: //88/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:
   Total Call Count=0, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)
*Nov 27 12:53:04.690: //88/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:
   Total Call Count=1
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_init_call_entry:
   call_init_entry calle is 16616158,callid is 88
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_init_call_entry:
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_insert_guid_pod_entry:
   Incoming=TRUE, Call Id=88
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=102(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=4(TON=Unknown, NPI=Unknown))
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_api_insert_call_active_entry:
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_incr_if_call_volume:
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_incr_if_call_volume:
   Remote IP Address=192.168.1.6, Hwidb=GigabitEthernet0/2
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_incr_if_call_volume:
   Total Call Count=1, Voip Call Count=1, MMoip Call Count=0
*Nov 27 12:53:04.690: //88/BE48C93880AE/CCAPI/cc_process_call_setup_ind:
   Event=0x2A6CE28
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:
   Matching Parameters; Called Number=4, Call Transfer Consult Id=
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Searching Node;
   Called Number=4, Call Transfer Consult Id=
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:
   
*Nov 27 12:53:04.690: Comparing SSOpCode; 0x50 and 0x0
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:
   
*Nov 27 12:53:04.690: Comparing SSOpCode; 0x12 and 0x0
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 4
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:
   
*Nov 27 12:53:04.690: Comparing SSOpCode; 0x50 and 0x0
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:
   
*Nov 27 12:53:04.690: Comparing SSOpCode; 0x12 and 0x0
*Nov 27 12:53:04.690: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:
   No Matching Node
*Nov 27 12:53:04.694: //-1/xxxxxxxxxxxx/CCAPI/ccAppInitializePID:
   Application Process Name=_ManagedAppProcess_Default, Application Process Id=479, Application Handle=0x41F6FEC0
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/ccCallSetContext:
   Context=0x1016773C
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 88 with tag 40002 to app "_ManagedAppProcess_Default"
*Nov 27 12:53:04.694: //-1/xxxxxxxxxxxx/CCAPI/ccAppInitialize:
   Application Name=_ManagedAppProcess_Default, Application Process Id=479, Application Handle=0x10199350
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/ccCallSetupAck:
   Call Id=88
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/cc_api_set_transfer_info:
   Transfer Number=, Transfer Reason=0x0
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/cc_api_set_transfer_info:
   Call Transfer Reset
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=4, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=4
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/dpMatchCore:
   Dial String=4, Expanded String=4, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/MatchNextPeer:
   Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=100
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/dpMatchCore:
   Result=1
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/dpMatchSafModulePlugin:
   dialstring=4, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
*Nov 27 12:53:04.694: //-1/BE48C93880AE/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/ccSetDigitTimeouts:
   Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms)
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/ccSetDigitTimeouts:
   Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/ccCallReportDigits:
   (callID=0x58, digit_event=0x1, enable=TRUE, consume=FALSE)
*Nov 27 12:53:04.694: //88/BE48C93880AE/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=88
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.706: //88/BE48C93880AE/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x238F1F4, callID=0x58, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
*Nov 27 12:53:04.706: //88/BE48C93880AE/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x238F1F4, Call Id=88
*Nov 27 12:53:04.706: //88/BE48C93880AE/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=4, Called Number=102, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_VIA_URI; URI=sip:192.168.1.6:5060
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_REQUEST_URI; URI=sip:4@192.168.1.1:5060
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_TO_URI; URI=sip:102@192.168.1.6
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_FROM_URI; URI=sip:4@192.168.1.1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=102
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=102, Expanded String=102, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=500 Is Matched
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=500
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@7793 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=4, Called Number=4, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_VIA_URI; URI=sip:192.168.1.6:5060
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_TO_URI; URI=sip:102@192.168.1.6
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_FROM_URI; URI=sip:4@192.168.1.1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=4
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=4, Expanded String=4, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=500 Is Matched
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=500
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:04.706: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@7793 
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:05.234: //88/BE48C93880AE/CCAPI/cc_api_call_digit_begin:
*Nov 27 12:53:05.234: //88/BE48C93880AE/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=88, Digit=0, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
*Nov 27 12:53:05.234: //88/BE48C93880AE/CCAPI/cc_api_call_digit_end:
*Nov 27 12:53:05.234: //88/BE48C93880AE/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=88, Digit=0, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
*Nov 27 12:53:05.234: //88/BE48C93880AE/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=4, Called Number=4, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_VIA_URI; URI=sip:192.168.1.6:5060
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_TO_URI; URI=sip:102@192.168.1.6
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_FROM_URI; URI=sip:4@192.168.1.1
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=4
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=4, Expanded String=4, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=500 Is Matched
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=500
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:05.234: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@7793 
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=40, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=40
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/dpMatchCore:
   Dial String=40, Expanded String=40, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/MatchNextPeer:
   Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=100
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/dpMatchCore:
   Result=1
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/dpMatchSafModulePlugin:
   dialstring=40, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
*Nov 27 12:53:05.234: //-1/BE48C93880AE/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:05.790: //88/BE48C93880AE/CCAPI/cc_api_call_digit_begin:
*Nov 27 12:53:05.790: //88/BE48C93880AE/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=88, Digit=2, DigitBeginFlags=0x0,
   Rtp Timestamp=0x0, Rtp Expiration=0x0
*Nov 27 12:53:05.790: //88/BE48C93880AE/CCAPI/cc_api_call_digit_end:
*Nov 27 12:53:05.790: //88/BE48C93880AE/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
   Source Call Id=88, Digit=2, Duration=250,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
*Nov 27 12:53:05.790: //88/BE48C93880AE/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=4, Called Number=4, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_VIA_URI; URI=sip:192.168.1.6:5060
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_TO_URI; URI=sip:102@192.168.1.6
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_FROM_URI; URI=sip:4@192.168.1.1
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=4
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=4, Expanded String=4, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=500 Is Matched
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=500
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:05.790: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@7793 
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=402, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=402
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchCore:
   Dial String=402, Expanded String=402, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=100 Is Matched
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchSafModulePlugin:
   dialstring=402, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0) 
   List of Matched Outgoing Dial-peer(s): 
     1: Dial-peer Tag=100
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCallActiveUpdateNumbers:
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCallReportDigits:
   (callID=0x58, digit_event=0x0, enable=FALSE, consume=FALSE)
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=88
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x238F1F4, callID=0x58, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x238F1F4, Call Id=88
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=402, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=402
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchCore:
   Dial String=402, Expanded String=402, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=100 Is Matched
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchSafModulePlugin:
   dialstring=402, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0) 
   List of Matched Outgoing Dial-peer(s): 
     1: Dial-peer Tag=100
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=402, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=402
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchCore:
   Dial String=402, Expanded String=402, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=100 Is Matched
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchSafModulePlugin:
   dialstring=402, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0) 
   List of Matched Outgoing Dial-peer(s): 
     1: Dial-peer Tag=100
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=402, Called Number=402, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=402
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=402, Expanded String=402, Calling Number=402T
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=100 Is Matched
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=402, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0) 
   List of Matched Outgoing Dial-peer(s): 
     1: Dial-peer Tag=100
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=4, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=4
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=4T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=4
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=4T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@7842 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=402, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=402
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=402T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=402
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=402T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=100 Is Matched
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=100
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@7842 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
   VALIDATING MLPP INFO:- ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
   Unsupported MLPP Service Domain Network 0
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:
   Invalid MLPP Information
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_is_precedence_mlpp_info:
   Precedence not above Routine
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:
   BEFORE COPY - SRC MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:
   DEST  MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:
     AFTER COPY - SRC MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)]   DEST MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:
   BEFORE COPY - SRC MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:
   DEST  MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:
     AFTER COPY - SRC MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)]   DEST MLPP INFO:ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=402, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=402
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=402, Expanded String=402, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=100 Is Matched
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=402, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0) 
   List of Matched Outgoing Dial-peer(s): 
     1: Dial-peer Tag=100
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
   VALIDATING MLPP INFO:- ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
   Unsupported MLPP Service Domain Network 0
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:
   Invalid MLPP Information
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_is_precedence_mlpp_info:
   Precedence not above Routine
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
   VALIDATING MLPP INFO:- ServiceDomain:[none (0)]  DomainIdentifier:[000000]  PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)]  NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] 
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
   Unsupported MLPP Service Domain Network 0
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:
   Invalid MLPP Information
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_is_precedence_mlpp_info:
   Precedence not above Routine
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=402, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=402
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchCore:
   Dial String=402, Expanded String=402, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=100 Is Matched
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchSafModulePlugin:
   dialstring=402, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
*Nov 27 12:53:05.794: //-1/BE48C93880AE/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0) 
   List of Matched Outgoing Dial-peer(s): 
     1: Dial-peer Tag=100
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=100, Params=0x10168D34, Progress Indication=NULL(0)
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_set_post_tagdata:
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_set_post_tagdata:
   CALL_ERROR; Avlist Removal Is Failed
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_set_post_tagdata:
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_set_post_tagdata:
   CALL_ERROR; Avlist Removal Is Failed
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCheckClipClir:
   In: Calling Number=102(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCheckClipClir:
   Calling Party Number Is User Provided
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCheckClipClir:
   Out: Calling Number=102(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCallSetupRequest:
   Destination Pattern=4.., Called Number=402, Digit Strip=FALSE
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccCallSetupRequest:
   Calling Number=102(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=402(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=user22
   Account Number=102, Final Destination Flag=FALSE,
   Guid=BE48C938-D2A8-11E7-80AE-870307D1DDC5, Outgoing Dial-peer=100
*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=102
   ----- ccCallInfo IE subfields -----
   cisco-ani=102
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=402
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

*Nov 27 12:53:05.794: //88/BE48C93880AE/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x238F1F4, Interface Type=3, Destination=, Mode=0x0,
   Call Params(Calling Number=102,(Calling Name=user22)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=402(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE, Outgoing Dial-peer=100, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
*Nov 27 12:53:05.794: :cc_get_feature_vsa malloc success
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
*Nov 27 12:53:05.794:  cc_get_feature_vsa count is 2
*Nov 27 12:53:05.794: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
*Nov 27 12:53:05.794: :FEATURE_VSA attributes are: feature_name:0,feature_time:269905656,feature_id:42
*Nov 27 12:53:05.798: //89/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:
   Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_init_call_entry:
   call_init_entry calle is 166152E8,callid is 89
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_init_call_entry:
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_peer_bind:
   Bind=TRUE, Binder Call Id=88, Bindee Call Id=89
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_insert_guid_pod_entry:
   Incoming=FALSE, Call Id=89
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_set_voice_port_value:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_set_voice_port_value:exit@2203
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_set_post_tagdata:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_set_post_tagdata:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccDirectEventToSPI:
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_api_event_indication:
   Event=101, Call Id=88
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_api_event_indication:
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_check_app_intercept:
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_check_app_intercept:exit@10658
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccDirectEventToSPI:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccCallSetupRequest:exit@2945
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccCallSetContext:
   Context=0x10168CE4
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=100
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_find_feature_vsa:
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccCallGetContext:
   Context=0x10197704, Call Id=88
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccGetMediaClassTag:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccGetMediaClassTag:
   media class tag 0
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccSetMediaclassIp2ipTags:
   media class tags set: NR 0, ASP 0
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccGetMediaClassTag:
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccGetMediaClassTag:
   media class tag 0
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccSetMediaclassIp2ipTags:
   media class tags set: NR 0, ASP 0
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccGet_xc_nr_asp_info:
   media class tags: NR 0, ASP 0
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/ccGet_xc_nr_asp_info:
   media class tags: NR 0, ASP 0
*Nov 27 12:53:05.798: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
   Call Id 88
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_event_indication:
   Event=194, Call Id=89
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_event_indication:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_check_app_intercept:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/ccDirectEventToSPI:
*Nov 27 12:53:05.798: //88/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //89/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //88/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //88/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //89/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //88/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //88/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //89/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //89/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
*Nov 27 12:53:05.798: cc_api_get_xcode_stream : 4982
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_get_xcode_stream:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
   Hwidb=GigabitEthernet0/2, Bandwidth=80, Call Id=89
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
   Total Call Count=1, Voip Call Count=1, MMoip Call Count=0x0, Bandwidth=160
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_call_proceeding:
   Interface=0x238F1F4, Progress Indication=NULL(0)
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_api_insert_call_active_entry:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_incr_if_call_volume:
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_incr_if_call_volume:
   Remote IP Address=192.168.10.2, Hwidb=GigabitEthernet0/0
*Nov 27 12:53:05.798: //89/BE48C93880AE/CCAPI/cc_incr_if_call_volume:
   Total Call Count=1, Voip Call Count=1, MMoip Call Count=0
*Nov 27 12:53:05.798: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=4, Called Number=4, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_VIA_URI; URI=sip:192.168.1.6:5060
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_TO_URI; URI=sip:102@192.168.1.6
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_FROM_URI; URI=sip:4@192.168.1.1
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=4
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=4, Expanded String=4, Calling Number=
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=500 Is Matched
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7211 
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=500
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Nov 27 12:53:05.802: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@7793 
*Nov 27 12:53:14.158: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/cc_api_call_disconnected:
   Cause Value=16, Interface=0x238F1F4, Call Id=88
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=FALSE, Cause Value=16, Retry Count=0)
*Nov 27 12:53:14.158: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:14.158: //-1/xxxxxxxxxxxx/CCAPI/cc_api_request_rt_packet_stats:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
*Nov 27 12:53:14.158: //89/BE48C93880AE/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Nov 27 12:53:14.158: //89/BE48C93880AE/CCAPI/ccCallDisconnect:
   Start Calling Accounting;
   Call Entry(Incoming=FALSE)
*Nov 27 12:53:14.158: //89/BE48C93880AE/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Disconnect Cause=0)
*Nov 27 12:53:14.158: //89/BE48C93880AE/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=FALSE, Cause Value=16)
*Nov 27 12:53:14.158: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:14.158: //-1/xxxxxxxxxxxx/CCAPI/cc_api_request_rt_packet_stats:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/ccCallDisconnect:
   Start Calling Accounting;
   Call Entry(Incoming=TRUE)
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Disconnect Cause=16)
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/ccCallDisconnect:
   Call Entry(Disconnect Cause=16)
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Nov 27 12:53:14.158: //89/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
*Nov 27 12:53:14.158: //89/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
   Hwidb=GigabitEthernet0/2, Bandwidth=-80, Call Id=89
*Nov 27 12:53:14.158: //89/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
   Total Call Count=1, Voip Call Count=1, MMoip Call Count=0x0, Bandwidth=80
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
   Hwidb=GigabitEthernet0/2, Bandwidth=-80, Call Id=88
*Nov 27 12:53:14.158: //88/BE48C93880AE/CCAPI/cc_api_update_interface_cac_resource:
   Total Call Count=1, Voip Call Count=1, MMoip Call Count=0x0, Bandwidth=0
*Nov 27 12:53:14.162: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
*Nov 27 12:53:14.162: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif:
   ExpectFactor=0xA
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_api_insert_call_history:
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/ccGetCallActiveByCallID:
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_decr_if_call_volume:
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_decr_if_call_volume:
   Remote IP Address=192.168.1.6, Hwidb=GigabitEthernet0/2
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_decr_if_call_volume:
   Total Call Count=0, Voip Call Count=0, MMoip Call Count=0
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x238F1F4, Tag=0x0, Call Id=88,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_disconnect_notify:
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/callStackPop:exit@5207
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_delete_guid_pod_entry:
   Incoming=TRUE
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_delete_guid_pod_entry:exit@1264
*Nov 27 12:53:14.162: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
   
*Nov 27 12:53:14.162: :cc_free_feature_vsa freeing 10166FD0
*Nov 27 12:53:14.162: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
   
*Nov 27 12:53:14.162:  vsacount in free is 1
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_delete_call_entry:
   Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_delete_call_entry:
   Total Call Count=0
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/cc_delete_call_entry:
   Deleting profileTable[0x400B47D0]
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/ccCallGetVoipFlag:
   Data Bitmask=0x2, Call Id=88
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/ccCallGetVoipFlag:
   Flag=FALSE
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/ccCallSetVoipFlag:
   Flag=FALSE, Data Bitmask=0x2, Call Id=88
*Nov 27 12:53:14.162: //88/BE48C93880AE/CCAPI/ccCallSetVoipFlag:
   Call Entry(Voip AAA Flags=0x0)
*Nov 27 12:53:14.162: //0/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
*Nov 27 12:53:14.162: //0/xxxxxxxxxxxx/CCAPI/cc_api_call_preempt_done:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found