- Cisco Community
- Technology and Support
- Small Business Support Community
- Voice Systems and Accessories - Small Business
- UC520 Voicemail cut-off after 10 seconds
- Subscribe to RSS Feed
- Mark Topic as New
- Mark Topic as Read
- Float this Topic for Current User
- Bookmark
- Subscribe
- Mute
- Printer Friendly Page
UC520 Voicemail cut-off after 10 seconds
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
08-04-2011 04:29 AM - edited 03-21-2019 04:27 AM
Hi all,
I have an issue with a UC520 cutting off voicemail recording after approximately 10 seconds when using SIP trunk for the incomming call.
We are using the latest software pack (8.2.0) but the issue existed before that. (Was waiting for the update before posting, in case that had resolved my issue).
We tested an incomming call using SIP and we get the issue. When dialing in on an FXO port the voicemail records for longer, with no apparent cut-off.
It *may be* that there is some 'keep-alive' that is missing during the SIP call... but I can't actually track this down.
A normal, not-to-voicemail SIP trunk call does not cut off prematurely, stays active until we finish the call.
P.S. New CCA made the Software Pack upgrade really smooth... never had 100% success before, always having to 'nurse' the UC520 in to accepting an upgrade of the correct language (UK).
Thanks,
Mark
- Labels:
-
Voice Systems
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
08-06-2011 07:02 PM
Hi, Mark:
We need to know who send BYE, CUE or SIP provider.
If you can capture the wireshark SIP log, then it will help us to narrow down the cause.
-David
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
08-07-2011 09:23 AM
Hi David,
Thanks for the reply.
I will attach the log I have been able to make, which should be the SIP trace from the UC520.
(I have done some text substitutions to hide our info)
I am not 100% up to speed with the SIP protocol, so having a little trouble decoding what is going on. Not sure how the call gets transferred from ringing to voicemail... but we know that bit works okay.
As far as I can tell the BYE comes from the SIP provider... but I don't know why... Work fine if not a call to voicemail.
SIP provider is HostComm.co.uk ...
In the following trace, I placed an incomming call from my mobile to the office (UC520) number. The console phone rang (x201) correctly... I waited for it to go to voicemail, and the call cut off after 6 to 10 seconds (correctly recording that partial message).
***Can't see how to attach a file to this message so have pasted it below***
Text Substitutions;
Caller's Number: CALLERS-NUMBER
Dialled (Our) Number: DIALLED-NUMBER
UC520 Public IP: UC520-PUBLIC-IP
UC520 Internal IP: UC520-PRIVATE-IP
--------------------------show logging--------------------------
Syslog logging: enabled (0 messages dropped, 2 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled)
No Active Message Discriminator.
No Inactive Message Discriminator.
Console logging: disabled
Monitor logging: disabled
Buffer logging: level debugging, 2445 messages logged, xml disabled,
filtering disabled
Exception Logging: size (4096 bytes)
Count and timestamp logging messages: disabled
Persistent logging: disabled
No active filter modules.
Trap logging: level informational, 7009 message lines logged
Log Buffer (1000000 bytes):
007005: Aug 7 16:12:16.022: %SYS-5-CONFIG_I: Configured from console by root on vty0 (192.168.144.108)
007006: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
Voice port 0x88A3D9F0 dsl=-1 timeslot=0 does not belong to any trunk group
007007: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=BCD, Called Number=, Voice-Interface=0x88A3D9F0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007008: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=BCD
007009: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007010: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007011: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007012: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007013: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=BCD
007014: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007015: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007016: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20001 Is Matched
007017: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007018: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
007019: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007020: Aug 7 16:12:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@6561
007021: Aug 7 16:12:33.522: //-1/82BFC9FEB7EE/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=BCD
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=0 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
007022: Aug 7 16:12:33.522: //-1/82BFC9FEB7EE/CCAPI/cc_api_call_setup_ind_common:
Interface=0x88A3D9F0, Call Info(
Calling Number=BCD,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
Incoming Dial-peer=20001, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
007023: Aug 7 16:12:33.522: //-1/82BFC9FEB7EE/CCAPI/ccCheckClipClir:
In: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007024: Aug 7 16:12:33.522: //-1/82BFC9FEB7EE/CCAPI/ccCheckClipClir:
Out: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007025: Aug 7 16:12:33.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007026: Aug 7 16:12:33.522: :cc_get_feature_vsa malloc success
007027: Aug 7 16:12:33.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007028: Aug 7 16:12:33.522: cc_get_feature_vsa count is 3
007029: Aug 7 16:12:33.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007030: Aug 7 16:12:33.522: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339296,feature_id:13878
007031: Aug 7 16:12:33.522: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
007032: Aug 7 16:12:33.522: //-1/82BFC9FEB7EE/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46CD54; count=1
007033: Aug 7 16:12:33.522: //-1/82BFC9FEB7EE/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA7CF4
007034: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/cc_process_call_setup_ind:
Event=0x87F2C158
007035: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/ccCallSetContext:
Context=0x860552FC
007036: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 24447 with tag 20001 to app "_ManagedAppProcess_Default"
007037: Aug 7 16:12:33.526: //-1/82BFC9FEB7EE/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46CD54; count=1
007038: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/ccCallSetupAck:
Call Id=24447
007039: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/cc_api_set_transfer_info:
Transfer Number=, Transfer Reason=0x0
007040: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=TRUE, Tone=Dial Tone,
Tone Direction=Network, Params=0x0, Call Id=24447
007041: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/ccSetDigitTimeouts:
Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
007042: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/ccSetDigitTimeouts:
Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
007043: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/ccCallReportDigits:
(callID=0x5F7F, digit_event=0x1, enable=TRUE, consume=FALSE)
007044: Aug 7 16:12:33.526: //24447/82BFC9FEB7EE/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24447
007045: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F7F, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
007046: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24447
007047: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
007048: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24447, Digit=A, DigitBeginFlags=0x0,
Rtp Timestamp=0x50246738, Rtp Expiration=0x0
007049: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24447, Digit=A, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007050: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007051: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24447, Digit=B, DigitBeginFlags=0x0,
Rtp Timestamp=0x5024E438, Rtp Expiration=0x0
007052: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24447, Digit=B, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007053: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007054: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24447, Digit=C, DigitBeginFlags=0x0,
Rtp Timestamp=0x50256138, Rtp Expiration=0x0
007055: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24447, Digit=C, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007056: Aug 7 16:12:33.530: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007057: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
007058: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A
007059: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Dial String=A, Expanded String=A, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007060: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
007061: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Result=1
007062: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
007063: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchSafModulePlugin:
dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
007064: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
007065: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
007066: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=AB
007067: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Dial String=AB, Expanded String=AB, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007068: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
007069: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Result=1
007070: Aug 7 16:12:33.538: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
007071: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchSafModulePlugin:
dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
007072: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
007073: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007074: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007075: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007076: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007077: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007078: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
007079: Aug 7 16:12:33.542: //-1/82BFC9FEB7EE/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007080: Aug 7 16:12:33.542: //24447/82BFC9FEB7EE/CCAPI/ccCallReportDigits:
(callID=0x5F7F, digit_event=0x0, enable=FALSE, consume=FALSE)
007081: Aug 7 16:12:33.542: //24447/82BFC9FEB7EE/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24447
007082: Aug 7 16:12:33.542: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F7F, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
007083: Aug 7 16:12:33.542: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24447
007084: Aug 7 16:12:33.542: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
007085: Aug 7 16:12:33.542: //24447/82BFC9FEB7EE/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
007086: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007087: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007088: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007089: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007090: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007091: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007092: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007093: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007094: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007095: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007096: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007097: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007098: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007099: Aug 7 16:12:33.546: //-1/82BFC9FEB7EE/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007100: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=ABC, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007101: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007102: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=ABCT
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007103: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007104: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007105: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007106: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007107: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007108: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007109: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007110: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007111: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=ABC, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007112: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=ABC
007113: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007114: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007115: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007116: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007117: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=ABC
007118: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007119: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007120: Aug 7 16:12:33.546: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=5 Is Matched
007121: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007122: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007123: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007124: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007125: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007126: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007127: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007128: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007129: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007130: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007131: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007132: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007133: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007134: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007135: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007136: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007137: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
007138: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007139: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46CD54; count=2
007140: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/RXRULE/regxrule_dp_translate: No profile found in peer 5 for outgoing direction
007141: Aug 7 16:12:33.550: //-1/82BFC9FEB7EE/RXRULE/regxrule_dp_translate: calling_number=BCD calling_octet=0x0
called_number=ABC called_octet=0x80
redirect_number= redirect_type=0 redirect_plan=0 redirect_PI=0 redirect_SI=0
007142: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number ABC
007143: Aug 7 16:12:33.550: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007144: Aug 7 16:12:33.550: : updating existing feature vsa
007145: Aug 7 16:12:33.554: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007146: Aug 7 16:12:33.554: feature call basic
007147: Aug 7 16:12:33.554: //24447/82BFC9FEB7EE/CCAPI/ccCallDisconnect:
Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
007148: Aug 7 16:12:33.554: //24447/82BFC9FEB7EE/CCAPI/ccCallDisconnect:
Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
007149: Aug 7 16:12:33.554: //24447/82BFC9FEB7EE/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
007150: Aug 7 16:12:33.566: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88A3D9F0, Tag=0x0, Call Id=24447,
Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
007151: Aug 7 16:12:33.566: //-1/82BFC9FEB7EE/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46CD54; count=2
007152: Aug 7 16:12:33.566: //-1/82BFC9FEB7EE/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA82BC
007153: Aug 7 16:12:33.566: //24447/82BFC9FEB7EE/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
007154: Aug 7 16:12:33.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007155: Aug 7 16:12:33.566: :cc_free_feature_vsa freeing 88DF5B58
007156: Aug 7 16:12:33.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007157: Aug 7 16:12:33.566: vsacount in free is 2
007158: Aug 7 16:12:36.246: %CDP-4-DUPLEX_MISMATCH: duplex mismatch discovered on FastEthernet0/1/8 (not half duplex), with SEP00e075244b3c port 1 (half duplex).
007159: Aug 7 16:12:36.458: //24446/7890FA6DB7E8/CCAPI/cc_api_call_disconnected:
Cause Value=19, Interface=0x88873CA4, Call Id=24446
007160: Aug 7 16:12:36.458: //24446/7890FA6DB7E8/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=19, Retry Count=0)
007161: Aug 7 16:12:36.458: //24445/7890FA6DB7E8/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
007162: Aug 7 16:12:36.458: //24446/7890FA6DB7E8/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
007163: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=201
007164: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=399, Peer Info Type=DIALPEER_INFO_SPEECH
007165: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=399
007166: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=399, Expanded String=399, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007167: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=2000 Is Matched
007168: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007169: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=399, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007170: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2000
007171: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:
type=6 Original Called Number=201, Called Number=201, Calling Number=CALLERS-NUMBER, Calling DN=-1 Calling Id=24445,
Redirect Number=399, Redirect Reason=2
007172: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=CALLERS-NUMBER, Peer Info Type=DIALPEER_INFO_SPEECH
007173: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=CALLERS-NUMBER
007174: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=CALLERS-NUMBER, Expanded String=CALLERS-NUMBER, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007175: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007176: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
007177: Aug 7 16:12:36.458: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=CALLERS-NUMBER, saf_enabled=0, saf_dndb_lookup=1, dp_result=-1
007178: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=NO_MATCH(-1)
007179: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=399, Peer Info Type=DIALPEER_INFO_SPEECH
007180: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=399
007181: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=399, Expanded String=399, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007182: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=2000 Is Matched
007183: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007184: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=399, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007185: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2000
007186: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=201
007187: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007188: Aug 7 16:12:36.462: :cc_get_feature_vsa malloc success
007189: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007190: Aug 7 16:12:36.462: cc_get_feature_vsa count is 3
007191: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007192: Aug 7 16:12:36.462: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339296,feature_id:13879
007193: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007194: Aug 7 16:12:36.462: feature_vsa is 88DF5DF8,featname is 0
007195: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007196: Aug 7 16:12:36.462: :cc_get_feature_vsa malloc success
007197: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007198: Aug 7 16:12:36.462: cc_get_feature_vsa count is 4
007199: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007200: Aug 7 16:12:36.462: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339520,feature_id:13880
007201: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007202: Aug 7 16:12:36.462: : Generating new featurevsa,inserting it
007203: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007204: Aug 7 16:12:36.462: feature call forward featname is 3
007205: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007206: Aug 7 16:12:36.462: feature_vsa is 88DF5D18,featname is 0
007207: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007208: Aug 7 16:12:36.462: :cc_get_feature_vsa malloc success
007209: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007210: Aug 7 16:12:36.462: cc_get_feature_vsa count is 5
007211: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007212: Aug 7 16:12:36.462: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296340192,feature_id:13881
007213: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007214: Aug 7 16:12:36.462: : Generating new featurevsa,inserting it
007215: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007216: Aug 7 16:12:36.462: feature call forward featname is 3
007217: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007218: Aug 7 16:12:36.462: :cc_free_feature_vsa freeing 88DF5B58
007219: Aug 7 16:12:36.462: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007220: Aug 7 16:12:36.462: vsacount in free is 4
007221: Aug 7 16:12:36.462: //24446/7890FA6DB7E8/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=19)
007222: Aug 7 16:12:36.462: //24446/7890FA6DB7E8/CCAPI/ccCallDisconnect:
Cause Value=19, Call Entry(Responsed=TRUE, Cause Value=19)
007223: Aug 7 16:12:36.466: //24446/7890FA6DB7E8/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
007224: Aug 7 16:12:36.470: //24446/7890FA6DB7E8/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88873CA4, Tag=0x0, Call Id=24446,
Call Entry(Disconnect Cause=19, Voice Class Cause Code=0, Retry Count=0)
007225: Aug 7 16:12:36.470: //-1/7890FA6DB7E8/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x0
007226: Aug 7 16:12:36.474: //24446/7890FA6DB7E8/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
007227: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007228: Aug 7 16:12:36.474: :cc_free_feature_vsa freeing 88DF5ED8
007229: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007230: Aug 7 16:12:36.474: vsacount in free is 3
007231: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007232: Aug 7 16:12:36.474: vsacount in free is 2
007233: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007234: Aug 7 16:12:36.474: :cc_get_feature_vsa malloc success
007235: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007236: Aug 7 16:12:36.474: cc_get_feature_vsa count is 3
007237: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007238: Aug 7 16:12:36.474: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339744,feature_id:13882
007239: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007240: Aug 7 16:12:36.474: feature call forward featname is 3
007241: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007242: Aug 7 16:12:36.474: :cc_free_feature_vsa freeing 88DF5D18
007243: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007244: Aug 7 16:12:36.474: vsacount in free is 2
007245: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=399, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007246: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=399
007247: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007248: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007249: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007250: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007251: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=399
007252: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007253: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007254: Aug 7 16:12:36.474: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=2000 Is Matched
007255: Aug 7 16:12:36.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007256: Aug 7 16:12:36.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=2000
007257: Aug 7 16:12:36.478: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007258: Aug 7 16:12:36.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007259: Aug 7 16:12:36.478: //-1/8482D72EB7F2/DPM/dpMatchPeersCore:
Calling Number=, Called Number=399, Peer Info Type=DIALPEER_INFO_SPEECH
007260: Aug 7 16:12:36.478: //-1/8482D72EB7F2/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=399
007261: Aug 7 16:12:36.478: //-1/8482D72EB7F2/DPM/dpMatchCore:
Dial String=399, Expanded String=399, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007262: Aug 7 16:12:36.478: //-1/8482D72EB7F2/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=2000 Is Matched
007263: Aug 7 16:12:36.478: //-1/8482D72EB7F2/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007264: Aug 7 16:12:36.478: //-1/8482D72EB7F2/DPM/dpMatchSafModulePlugin:
dialstring=399, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
007265: Aug 7 16:12:36.478: //-1/8482D72EB7F2/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2000
007266: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46D11C; count=3
007267: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: number=CALLERS-NUMBER type=unknown plan=unknown numbertype=calling
007268: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0
007269: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_match_internal: Error: ruleset for calling number not found
007270: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: No match: number=CALLERS-NUMBER type=unknown plan=unknown
007271: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: number=399 type=unknown plan=unknown numbertype=called
007272: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0
007273: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_match_internal: Error: ruleset for called number not found
007274: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: No match: number=399 type=unknown plan=unknown
007275: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: number= type=unknown plan=unknown numbertype=redirect-target
007276: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0
007277: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_match_internal: Error: ruleset for redirect-target number not found
007278: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: No match: number= type=unknown plan=unknown
007279: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: number=201 type=unknown plan=unknown numbertype=redirect-called
007280: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_match: No match; number=201 rule precedence=1
007281: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_match_internal: No match found
007282: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: No match: number=201 type=unknown plan=unknown
007283: Aug 7 16:12:36.478: //-1/8482D72EB7F2/RXRULE/regxrule_dp_translate: calling_number=CALLERS-NUMBER calling_octet=0x0
called_number=399 called_octet=0x0
redirect_number=201 redirect_type=0 redirect_plan=0 redirect_PI=0 redirect_SI=0
007284: Aug 7 16:12:36.478: //24445/7890FA6DB7E8/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=2000, Params=0x86057A9C, Progress Indication=NULL(0)
007285: Aug 7 16:12:36.478: //24445/7890FA6DB7E8/CCAPI/ccCheckClipClir:
In: Calling Number=CALLERS-NUMBER(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007286: Aug 7 16:12:36.478: //24445/7890FA6DB7E8/CCAPI/ccCheckClipClir:
Out: Calling Number=CALLERS-NUMBER(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007287: Aug 7 16:12:36.478: //24445/7890FA6DB7E8/CCAPI/ccCallSetupRequest:
Destination Pattern=399, Called Number=399, Digit Strip=FALSE
007288: Aug 7 16:12:36.478: //24445/7890FA6DB7E8/CCAPI/ccCallSetupRequest:
Calling Number=CALLERS-NUMBER(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=399(TON=Unknown, NPI=Unknown),
Redirect Number=201, Display Info=CALLERS-NUMBER
Account Number=CALLERS-NUMBER, Final Destination Flag=TRUE,
Guid=8482D72E-C03E-11E0-B7F2-D9FD5E036138, Outgoing Dial-peer=2000
007289: Aug 7 16:12:36.478: //24445/7890FA6DB7E8/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=CALLERS-NUMBER
----- ccCallInfo IE subfields -----
cisco-ani=CALLERS-NUMBER
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=399
cisco-desttype=0
cisco-destplan=0
cisco-rdie=73
cisco-rdn=201
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=2 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
007290: Aug 7 16:12:36.482: //24445/7890FA6DB7E8/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x872E9C18, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=CALLERS-NUMBER,(Calling Name=CALLERS-NUMBER)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=399(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=2000, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
007291: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007292: Aug 7 16:12:36.482: :cc_get_feature_vsa malloc success
007293: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007294: Aug 7 16:12:36.482: cc_get_feature_vsa count is 3
007295: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007296: Aug 7 16:12:36.482: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339744,feature_id:13883
007297: Aug 7 16:12:36.482: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: number=201 type=unknown plan=unknown numbertype=redirect-called
007298: Aug 7 16:12:36.482: //-1/8482D72EB7F2/RXRULE/regxrule_match: No match; number=201 rule precedence=1
007299: Aug 7 16:12:36.482: //-1/8482D72EB7F2/RXRULE/regxrule_profile_match_internal: No match found
007300: Aug 7 16:12:36.482: //-1/8482D72EB7F2/RXRULE/regxrule_profile_translate_internal: No match: number=201 type=unknown plan=unknown
007301: Aug 7 16:12:36.482: //24448/7890FA6DB7E8/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
007302: Aug 7 16:12:36.482: //24448/7890FA6DB7E8/CCAPI/ccCallSetContext:
Context=0x86057A4C
007303: Aug 7 16:12:36.482: //24445/7890FA6DB7E8/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=2000
007304: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007305: Aug 7 16:12:36.482: feature call forward featname is 3
007306: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007307: Aug 7 16:12:36.482: feature_vsa is 88DF5D18,featname is 0
007308: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007309: Aug 7 16:12:36.482: :cc_get_feature_vsa malloc success
007310: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007311: Aug 7 16:12:36.482: cc_get_feature_vsa count is 4
007312: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007313: Aug 7 16:12:36.482: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296340192,feature_id:13884
007314: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007315: Aug 7 16:12:36.482: : Generating new featurevsa,inserting it
007316: Aug 7 16:12:36.482: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007317: Aug 7 16:12:36.482: feature call forward featname is 3
007318: Aug 7 16:12:36.486: //-1/8482D72EB7F2/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46D11C; count=3
007319: Aug 7 16:12:36.486: //-1/8482D72EB7F2/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA7CF4
007320: Aug 7 16:12:36.486: //-1/8482D72EB7F2/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46D11C; count=3
007321: Aug 7 16:12:36.486: //24448/7890FA6DB7E8/CCAPI/cc_api_call_proceeding:
Interface=0x872E9C18, Progress Indication=NULL(0)
007322: Aug 7 16:12:36.490: //24448/8482D72EB7F2/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:399@10.1.10.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.1.10.2:5060;branch=z9hG4bK13361343
From: "CALLERS-NUMBER" <>CALLERS-NUMBER@contact-pro4.co.uk>;tag=188CD62C-13E1
To: <399>
Date: Sun, 07 Aug 2011 15:12:36 GMT
Call-ID: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2223167278-3225293280-3086146045-1577279800
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1312729956
Contact:
Call-Info: <10.1.10.2:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"
Diversion: <>201@contact-pro4.co.uk>;privacy=off;reason=no-answer;counter=1;screen=no
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 68
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 162
v=0
o=CiscoSystemsSIP-GW-UserAgent 9087 2403 IN IP4 10.1.10.2
s=SIP Call
t=0 0
m=audio 18018 RTP/AVP 0
c=IN IP4 10.1.10.2
a=rtpmap:0 PCMU/8000
a=ptime:20
007323: Aug 7 16:12:36.498: //24448/8482D72EB7F2/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.1.10.2:5060;branch=z9hG4bK13361343
To: <399>
From: "CALLERS-NUMBER" <>CALLERS-NUMBER@contact-pro4.co.uk>;tag=188CD62C-13E1
Call-ID: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
CSeq: 101 INVITE
Content-Length: 0
Timestamp: 1312729956
007324: Aug 7 16:12:36.546: //24448/8482D72EB7F2/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.1.10.2:5060;branch=z9hG4bK13361343
To: <399>;tag=ds5f2826c4
From: "CALLERS-NUMBER" <>CALLERS-NUMBER@contact-pro4.co.uk>;tag=188CD62C-13E1
Call-ID: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
CSeq: 101 INVITE
Content-Length: 0
Contact: <399>
Allow: INVITE, BYE, CANCEL, ACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO
Cisco-Gcid: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
007325: Aug 7 16:12:36.550: //24448/7890FA6DB7E8/CCAPI/cc_api_call_alert:
Interface=0x872E9C18, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
007326: Aug 7 16:12:36.550: //24448/7890FA6DB7E8/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
007327: Aug 7 16:12:36.550: //24445/7890FA6DB7E8/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
007328: Aug 7 16:12:36.550: //24445/7890FA6DB7E8/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
007329: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=CALLERS-NUMBER, Peer Info Type=DIALPEER_INFO_SPEECH
007330: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=CALLERS-NUMBER
007331: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=CALLERS-NUMBER, Expanded String=CALLERS-NUMBER, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007332: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007333: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
007334: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007335: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=NO_MATCH(-1)
007336: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=399, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007337: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=399
007338: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007339: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007340: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007341: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007342: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=399
007343: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007344: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007345: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=2000 Is Matched
007346: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007347: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=2000
007348: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007349: Aug 7 16:12:36.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007350: Aug 7 16:12:36.554: //24445/7890FA6DB7E8/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK1d2e.c20225b1.0
From: "CALLERS-NUMBER"
To:
Date: Sun, 07 Aug 2011 15:12:16 GMT
Call-ID: 6ea1da1a539967c857a0bc55605ae591@88.208.249.40
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact: <399>
Record-Route: <88.208.249.40>
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0
007351: Aug 7 16:12:36.598: //24448/8482D72EB7F2/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 10.1.10.2:5060;branch=z9hG4bK13361343
To: <399>;tag=ds5f2826c4
From: "CALLERS-NUMBER" <>CALLERS-NUMBER@contact-pro4.co.uk>;tag=188CD62C-13E1
Call-ID: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
CSeq: 101 INVITE
Content-Length: 126
Contact: <399>
Content-Type: application/sdp
Call-Info: <10.1.10.1:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"
Allow-Events: telephone-event
Allow: INVITE, BYE, CANCEL, ACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO
Cisco-Gcid: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
v=0
o=CUE 13467803 2 IN IP4 10.1.10.1
s=SIP Call
c=IN IP4 10.1.10.1
t=0 0
m=audio 20806 RTP/AVP 0
a=rtpmap:0 PCMU/8000
007352: Aug 7 16:12:36.602: //24448/7890FA6DB7E8/CCAPI/cc_set_post_tagdata:
CALL_ERROR; Avlist Removal Is Failed
007353: Aug 7 16:12:36.602: //24448/7890FA6DB7E8/CCAPI/cc_api_caps_ind:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=24448,
Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,
Modem=0x0, Codec Bytes=160, Signal Type=2)
007354: Aug 7 16:12:36.602: //24448/7890FA6DB7E8/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
007355: Aug 7 16:12:36.602: //24445/7890FA6DB7E8/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=24448, Source Call Id=24445,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=1)
007356: Aug 7 16:12:36.602: //24448/7890FA6DB7E8/CCAPI/cc_api_event_indication:
Event=160, Call Id=24448
007357: Aug 7 16:12:36.602: //24448/7890FA6DB7E8/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
007358: Aug 7 16:12:36.602: //24448/7890FA6DB7E8/CCAPI/cc_api_call_connected:
Interface=0x872E9C18, Data Bitmask=0x1, Progress Indication=NULL(0),
Connection Handle=0
007359: Aug 7 16:12:36.602: //24448/7890FA6DB7E8/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
007360: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/ccConferenceCreate:
(confID=0x88F8D360, callID1=0x5F7D, gcid=78919695-C03E11E0-B7EBD9FD-5E036138, tag=0x0)
007361: Aug 7 16:12:36.606: //24448/7890FA6DB7E8/CCAPI/ccConferenceCreate:
(confID=0x88F8D360, callID2=0x5F80, gcid=78919695-C03E11E0-B7EBD9FD-5E036138, tag=0x0)
007362: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/ccConferenceCreate:
Conference Id=0x88F8D360, Call Id1=24445, Call Id2=24448, Tag=0x0
007363: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/ccConferenceCreate:
007364: Aug 7 16:12:36.606: ccConferenceCreate: ret1=0, codecMask1=1, bytes1=160, negot1=1, dtmf1=6
ret2=0, codecMask2=1, bytes2=160, negot2=1, dtmf2=8,
tx_dynamic_pt1=0, rx_dynamic_pt1=0, codec_mode1=0, params_bitmap1 =0
tx_dynamic_pt2=0, rx_dynamic_pt2=0, codec_mode2=0, params_bitmap2 =0
007365: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/ccConferenceCreate:
delay media to slow start case, codec negotation is not done
007366: Aug 7 16:12:36.606: //24445/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
007367: Aug 7 16:12:36.606: cc_api_get_xcode_stream : 4682
007368: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/cc_api_bridge_done:
Conference Id=0x20, Source Interface=0x872E9C18, Source Call Id=24445,
Destination Call Id=24448, Disposition=0x0, Tag=0x0
007369: Aug 7 16:12:36.606: //24448/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
007370: Aug 7 16:12:36.606: cc_api_get_xcode_stream : 4682
007371: Aug 7 16:12:36.606: //24448/7890FA6DB7E8/CCAPI/cc_api_bridge_done:
Conference Id=0x20, Source Interface=0x872E9C18, Source Call Id=24448,
Destination Call Id=24445, Disposition=0x0, Tag=0x0
007372: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/cc_generic_bridge_done:
Conference Id=0x20, Source Interface=0x872E9C18, Source Call Id=24448,
Destination Call Id=24445, Disposition=0x0, Tag=0x0
007373: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x20, Destination Call Id=24448)
007374: Aug 7 16:12:36.606: //24448/7890FA6DB7E8/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x20, Destination Call Id=24445)
007375: Aug 7 16:12:36.606: //24445/7890FA6DB7E8/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x20, Call Id1=24445, Call Id2=24448
007376: Aug 7 16:12:36.610: //24448/8482D72EB7F2/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:399@10.1.10.1:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.1.10.2:5060;branch=z9hG4bK13371908
From: "CALLERS-NUMBER" <>CALLERS-NUMBER@contact-pro4.co.uk>;tag=188CD62C-13E1
To: <399>;tag=ds5f2826c4
Date: Sun, 07 Aug 2011 15:12:36 GMT
Call-ID: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0
007377: Aug 7 16:12:36.610: //24445/7890FA6DB7E8/CCAPI/ccCallConnect:
Progress Indication=NULL(0), Data Bitmask=0x1
007378: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=CALLERS-NUMBER, Peer Info Type=DIALPEER_INFO_SPEECH
007379: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=CALLERS-NUMBER
007380: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=CALLERS-NUMBER, Expanded String=CALLERS-NUMBER, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007381: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007382: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
007383: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007384: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=NO_MATCH(-1)
007385: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=399, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007386: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=399
007387: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007388: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007389: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007390: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007391: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=399
007392: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007393: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007394: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=2000 Is Matched
007395: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007396: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=2000
007397: Aug 7 16:12:36.610: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007398: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007399: Aug 7 16:12:36.614: //24445/7890FA6DB7E8/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
007400: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=CALLERS-NUMBER, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007401: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=CALLERS-NUMBER
007402: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007403: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=CALLERS-NUMBERT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007404: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007405: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007406: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=CALLERS-NUMBER
007407: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007408: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=CALLERS-NUMBERT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007409: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007410: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007411: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007412: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007413: Aug 7 16:12:36.614: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007414: Aug 7 16:12:36.618: //24445/7890FA6DB7E8/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK1d2e.c20225b1.0
From: "CALLERS-NUMBER"
To:
Date: Sun, 07 Aug 2011 15:12:16 GMT
Call-ID: 6ea1da1a539967c857a0bc55605ae591@88.208.249.40
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact:
Record-Route: <88.208.249.40>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-12.x
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 230
v=0
o=CiscoSystemsSIP-GW-UserAgent 1310 9361 IN IP4 UC520-PRIVATE-IP
s=SIP Call
t=0 0
m=audio 18366 RTP/AVP 0 101
c=IN IP4 UC520-PRIVATE-IP
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
007415: Aug 7 16:12:36.670: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:DIALLED-NUMBER@UC520-PRIVATE-IP:5060 SIP/2.0
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK1d2e.c20225b1.2
Max-Forwards: 69
To:
From: "CALLERS-NUMBER"
Call-ID: 6ea1da1a539967c857a0bc55605ae591@88.208.249.40
Contact:
CSeq: 102 ACK
User-Agent: ContactPro4
Content-Length: 0
007416: Aug 7 16:12:59.690: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:DIALLED-NUMBER@UC520-PRIVATE-IP:5060 SIP/2.0
Record-Route: <88.208.249.40>
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK2d2e.7a9bcae1.0
Max-Forwards: 69
To:
From: "CALLERS-NUMBER"
Call-ID: 6ea1da1a539967c857a0bc55605ae591@88.208.249.40
CSeq: 103 BYE
User-Agent: ContactPro4
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
007417: Aug 7 16:12:59.690: //24448/7890FA6DB7E8/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Sum Network, Params=0x0, Call Id=24448
007418: Aug 7 16:12:59.690: //24445/7890FA6DB7E8/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x872E9C18, Call Id=24445
007419: Aug 7 16:12:59.690: //24445/7890FA6DB7E8/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
007420: Aug 7 16:12:59.690: //24445/7890FA6DB7E8/CCAPI/ccConferenceDestroy:
Conference Id=0x20, Tag=0x0
007421: Aug 7 16:12:59.690: //24445/7890FA6DB7E8/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x20, Source Interface=0x872E9C18, Source Call Id=24445,
Destination Call Id=24448, Disposition=0x0, Tag=0x0
007422: Aug 7 16:12:59.690: //24448/7890FA6DB7E8/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x20, Source Interface=0x872E9C18, Source Call Id=24448,
Destination Call Id=24445, Disposition=0x0, Tag=0x0
007423: Aug 7 16:12:59.690: //24445/7890FA6DB7E8/CCAPI/cc_generic_bridge_done:
Conference Id=0x20, Source Interface=0x872E9C18, Source Call Id=24448,
Destination Call Id=24445, Disposition=0x0, Tag=0x0
007424: Aug 7 16:12:59.694: //24445/7890FA6DB7E8/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
007425: Aug 7 16:12:59.694: //24445/7890FA6DB7E8/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
007426: Aug 7 16:12:59.694: //24448/7890FA6DB7E8/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
007427: Aug 7 16:12:59.694: //24448/7890FA6DB7E8/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
007428: Aug 7 16:12:59.694: //24445/7890FA6DB7E8/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x872E9C18, Tag=0x0, Call Id=24445,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
007429: Aug 7 16:12:59.694: //-1/7890FA6DB7E8/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46D11C; count=3
007430: Aug 7 16:12:59.694: //-1/7890FA6DB7E8/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA7CF4
007431: Aug 7 16:12:59.694: //24445/7890FA6DB7E8/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
007432: Aug 7 16:12:59.694: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007433: Aug 7 16:12:59.694: :cc_free_feature_vsa freeing 88DF5C38
007434: Aug 7 16:12:59.694: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007435: Aug 7 16:12:59.694: vsacount in free is 3
007436: Aug 7 16:12:59.694: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007437: Aug 7 16:12:59.694: vsacount in free is 2
007438: Aug 7 16:12:59.698: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK2d2e.7a9bcae1.0
From: "CALLERS-NUMBER"
To:
Date: Sun, 07 Aug 2011 15:12:59 GMT
Call-ID: 6ea1da1a539967c857a0bc55605ae591@88.208.249.40
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 103 BYE
Reason: Q.850;cause=16
Content-Length: 0
007439: Aug 7 16:12:59.698: //24448/8482D72EB7F2/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:399@10.1.10.1:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.1.10.2:5060;branch=z9hG4bK13386D8
From: "CALLERS-NUMBER" <>CALLERS-NUMBER@contact-pro4.co.uk>;tag=188CD62C-13E1
To: <399>;tag=ds5f2826c4
Date: Sun, 07 Aug 2011 15:12:36 GMT
Call-ID: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Timestamp: 1312729979
CSeq: 102 BYE
Reason: Q.850;cause=16
Content-Length: 0
007440: Aug 7 16:12:59.706: //24448/8482D72EB7F2/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 10.1.10.2:5060;branch=z9hG4bK13386D8
To: <399>;tag=ds5f2826c4
From: "CALLERS-NUMBER" <>CALLERS-NUMBER@contact-pro4.co.uk>;tag=188CD62C-13E1
Call-ID: 84840F7E-C03E11E0-B7F6D9FD-5E036138@contact-pro4.co.uk
CSeq: 102 BYE
Content-Length: 0
007441: Aug 7 16:12:59.706: //24448/7890FA6DB7E8/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x872E9C18, Tag=0x0, Call Id=24448,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
007442: Aug 7 16:12:59.706: //-1/7890FA6DB7E8/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x0
007443: Aug 7 16:12:59.706: //24448/7890FA6DB7E8/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
007444: Aug 7 16:12:59.706: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007445: Aug 7 16:12:59.706: :cc_free_feature_vsa freeing 88DF5ED8
007446: Aug 7 16:12:59.706: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007447: Aug 7 16:12:59.706: vsacount in free is 1
007448: Aug 7 16:12:59.706: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007449: Aug 7 16:12:59.706: vsacount in free is 0
007450: Aug 7 16:13:00.454: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:A800201@10.1.10.2:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.1.10.1:5060;branch=z9hG4bKaDmQfum218LqaclK01N0tQ~~23
Max-Forwards: 70
To:
From: <399>;tag=ds86eb8423
Call-ID: 131272998042724@10.1.10.1
CSeq: 100 INVITE
Content-Length: 126
Contact: <399>
Content-Type: application/sdp
Call-Info: <10.1.10.1:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"
Allow-Events: telephone-event
Allow: INVITE, BYE, CANCEL, ACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO
Cisco-Gcid: A4CDDE0B-0131-1000-4DB7-9A2F8D1F51CC
v=0
o=CUE 13491745 2 IN IP4 10.1.10.1
s=SIP Call
c=IN IP4 10.1.10.1
t=0 0
m=audio 32802 RTP/AVP 0
a=rtpmap:0 PCMU/8000
007451: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=A800201, Called Number=A800201, Peer Info Type=DIALPEER_INFO_SPEECH
007452: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800201
007453: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=A800201T
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007454: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007455: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007456: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=A800201, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
007457: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007458: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=399, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007459: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=399
007460: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007461: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007462: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007463: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007464: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=399
007465: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007466: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007467: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=2000 Is Matched
007468: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007469: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=2000
007470: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007471: Aug 7 16:13:00.458: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007472: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_translate_internal: number=399 type=unknown plan=unknown numbertype=calling
007473: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0
007474: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_match_internal: Error: ruleset for calling number not found
007475: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_translate_internal: No match: number=399 type=unknown plan=unknown
007476: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_translate_internal: number=A800201 type=unknown plan=unknown numbertype=called
007477: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_match: No match; number=A800201 rule precedence=1
007478: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_match: No match; number=A800201 rule precedence=2
007479: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_match_internal: No match found
007480: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_translate_internal: No match: number=A800201 type=unknown plan=unknown
007481: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_translate_internal: number= type=unknown plan=unknown numbertype=redirect-called
007482: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0
007483: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_match_internal: Error: ruleset for redirect-called number not found
007484: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/RXRULE/regxrule_profile_translate_internal: No match: number= type=unknown plan=unknown
007485: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=399, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007486: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=399
007487: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007488: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007489: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007490: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007491: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=399
007492: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007493: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=399T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007494: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=2000 Is Matched
007495: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007496: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=2000
007497: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007498: Aug 7 16:13:00.462: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007499: Aug 7 16:13:00.462: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerCore:
Calling Number=399, Called Number=A800201, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007500: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_VIA_URI; URI=sip:10.1.10.1:5060
007501: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007502: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007503: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Result=-1
007504: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:exit@5982
007505: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_REQUEST_URI; URI=sip:A800201@10.1.10.2:5060;transport=udp
007506: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007507: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007508: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Result=-1
007509: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:exit@5982
007510: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_TO_URI; URI=sip:A800201@10.1.10.2:5060
007511: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007512: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007513: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Result=-1
007514: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:exit@5982
007515: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_FROM_URI; URI=sip:399@10.1.10.1
007516: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007517: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007518: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Result=-1
007519: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:exit@5982
007520: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=A800201
007521: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007522: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007523: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=6 Is Matched
007524: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=1000 Is Matched
007525: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=1005 Is Matched
007526: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchPeertype:exit@5982
007527: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=1005
007528: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007529: Aug 7 16:13:00.466: //-1/92CD49A6B7F7/DPM/dpAssociateIncomingPeerSPI:exit@6561
007530: Aug 7 16:13:00.470: //24449/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
Call Entry Is Not Found
007531: Aug 7 16:13:00.470: //-1/92CD49A6B7F7/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=399
----- ccCallInfo IE subfields -----
cisco-ani=399
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=A800201
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
007532: Aug 7 16:13:00.470: //-1/92CD49A6B7F7/CCAPI/cc_api_call_setup_ind_common:
Interface=0x872E9C18, Call Info(
Calling Number=399,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=A800201(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=1005, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=24449
007533: Aug 7 16:13:00.470: //-1/92CD49A6B7F7/CCAPI/ccCheckClipClir:
In: Calling Number=399(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007534: Aug 7 16:13:00.470: //-1/92CD49A6B7F7/CCAPI/ccCheckClipClir:
Out: Calling Number=399(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007535: Aug 7 16:13:00.470: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007536: Aug 7 16:13:00.470: :cc_get_feature_vsa malloc success
007537: Aug 7 16:13:00.470: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007538: Aug 7 16:13:00.470: cc_get_feature_vsa count is 1
007539: Aug 7 16:13:00.470: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007540: Aug 7 16:13:00.470: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339744,feature_id:13885
007541: Aug 7 16:13:00.470: //24449/92CD49A6B7F7/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=399(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=A800201(TON=Unknown, NPI=Unknown))
007542: Aug 7 16:13:00.470: //-1/92CD49A6B7F7/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46C7D4; count=1
007543: Aug 7 16:13:00.470: //-1/92CD49A6B7F7/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA9DE8
007544: Aug 7 16:13:00.470: //24449/92CD49A6B7F7/CCAPI/cc_process_call_setup_ind:
Event=0x87F2B738
007545: Aug 7 16:13:00.474: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number A800201
007546: Aug 7 16:13:00.474: //24449/92CD49A6B7F7/CCAPI/ccCallSetContext:
Context=0x86058DF4
007547: Aug 7 16:13:00.474: //24449/92CD49A6B7F7/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 24449 with tag 1005 to app "_ManagedAppProcess_Default"
007548: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46C7D4; count=1
007549: Aug 7 16:13:00.474: //24449/92CD49A6B7F7/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
007550: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A800201, Peer Info Type=DIALPEER_INFO_SPEECH
007551: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800201
007552: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007553: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007554: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007555: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchSafModulePlugin:
dialstring=A800201, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007556: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007557: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A800201, Peer Info Type=DIALPEER_INFO_SPEECH
007558: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800201
007559: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007560: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007561: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007562: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchSafModulePlugin:
dialstring=A800201, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007563: Aug 7 16:13:00.474: //-1/92CD49A6B7F7/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007564: Aug 7 16:13:00.474: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=A800201, Called Number=A800201, Peer Info Type=DIALPEER_INFO_SPEECH
007565: Aug 7 16:13:00.474: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800201
007566: Aug 7 16:13:00.474: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=A800201T
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007567: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007568: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007569: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=A800201, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007570: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007571: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=A800201, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007572: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=A800201
007573: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007574: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=A800201T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007575: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007576: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007577: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=A800201
007578: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007579: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=A800201T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007580: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20016 Is Matched
007581: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007582: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007583: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007584: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007585: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=A800201, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007586: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=A800201
007587: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007588: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=A800201T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007589: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007590: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007591: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=A800201
007592: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007593: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=A800201T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007594: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20016 Is Matched
007595: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007596: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007597: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007598: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007599: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A800201, Peer Info Type=DIALPEER_INFO_SPEECH
007600: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800201
007601: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007602: Aug 7 16:13:00.478: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007603: Aug 7 16:13:00.482: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007604: Aug 7 16:13:00.482: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=A800201, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007605: Aug 7 16:13:00.482: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007606: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A800201, Peer Info Type=DIALPEER_INFO_SPEECH
007607: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800201
007608: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007609: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007610: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007611: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/DPM/dpMatchSafModulePlugin:
dialstring=A800201, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
007612: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007613: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46C7D4; count=2
007614: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/RXRULE/regxrule_dp_translate: No profile found in peer 20016 for outgoing direction
007615: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/RXRULE/regxrule_dp_translate: calling_number=399 calling_octet=0x0
called_number=A800201 called_octet=0x0
redirect_number= redirect_type=0 redirect_plan=0 redirect_PI=-1 redirect_SI=-1
007616: Aug 7 16:13:00.482: //24449/92CD49A6B7F7/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=20016, Params=0x8604C9B4, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
007617: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/RXRULE/regxrule_vp_translate: No profile found in voice port or trunk group for outgoing direction
007618: Aug 7 16:13:00.482: //-1/92CD49A6B7F7/RXRULE/regxrule_vp_translate: calling_number=399 calling_octet=0x0
called_number=A800201 called_octet=0x0
redirect_number= redirect_type=0 redirect_plan=0
007619: Aug 7 16:13:00.482: //24449/92CD49A6B7F7/CCAPI/ccCheckClipClir:
In: Calling Number=399(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007620: Aug 7 16:13:00.482: //24449/92CD49A6B7F7/CCAPI/ccCheckClipClir:
Out: Calling Number=399(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007621: Aug 7 16:13:00.482: //24449/92CD49A6B7F7/CCAPI/ccCallSetupRequest:
Destination Pattern=A800..., Called Number=A800201, Digit Strip=TRUE
007622: Aug 7 16:13:00.482: //24449/92CD49A6B7F7/CCAPI/ccCallSetupRequest:
Calling Number=399(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=A800201(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=399, Final Destination Flag=TRUE,
Guid=92CD49A6-C03E-11E0-B7F7-D9FD5E036138, Outgoing Dial-peer=20016
007623: Aug 7 16:13:00.482: //24449/92CD49A6B7F7/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=399
----- ccCallInfo IE subfields -----
cisco-ani=399
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=A800201
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
007624: Aug 7 16:13:00.482: //24449/92CD49A6B7F7/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x8887D9EC, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=399,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=A800201(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20016, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
007625: Aug 7 16:13:00.486: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007626: Aug 7 16:13:00.486: :cc_get_feature_vsa malloc success
007627: Aug 7 16:13:00.486: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007628: Aug 7 16:13:00.486: cc_get_feature_vsa count is 2
007629: Aug 7 16:13:00.486: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007630: Aug 7 16:13:00.486: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296340192,feature_id:13886
007631: Aug 7 16:13:00.486: //24450/92CD49A6B7F7/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
007632: Aug 7 16:13:00.486: //24450/92CD49A6B7F7/CCAPI/ccCallSetContext:
Context=0x8604C964
007633: Aug 7 16:13:00.486: //24449/92CD49A6B7F7/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=20016
007634: Aug 7 16:13:00.486: //24450/92CD49A6B7F7/CCAPI/cc_api_update_call_info:
Interface=0x8887D9EC, Call Id=0x5F82
007635: Aug 7 16:13:00.490: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=399, Peer Info Type=DIALPEER_INFO_SPEECH
007636: Aug 7 16:13:00.490: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=399
007637: Aug 7 16:13:00.490: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=399, Expanded String=399, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007638: Aug 7 16:13:00.490: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=2000 Is Matched
007639: Aug 7 16:13:00.490: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007640: Aug 7 16:13:00.490: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=399, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007641: Aug 7 16:13:00.490: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2000
007642: Aug 7 16:13:00.490: //24450/92CD49A6B7F7/CCAPI/cc_api_call_proceeding:
Interface=0x8887D9EC, Progress Indication=NULL(0)
007643: Aug 7 16:13:00.490: //24450/92CD49A6B7F7/CCAPI/cc_api_call_alert:
Interface=0x8887D9EC, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
007644: Aug 7 16:13:00.494: //24450/92CD49A6B7F7/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
007645: Aug 7 16:13:00.494: //24449/92CD49A6B7F7/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
007646: Aug 7 16:13:00.494: //24449/92CD49A6B7F7/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
007647: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=399, Peer Info Type=DIALPEER_INFO_SPEECH
007648: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=399
007649: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=399, Expanded String=399, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007650: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=2000 Is Matched
007651: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007652: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007653: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2000
007654: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=A800000, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007655: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=A800000
007656: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007657: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=A800000T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007658: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007659: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007660: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=A800000
007661: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007662: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=A800000T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007663: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20016 Is Matched
007664: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007665: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007666: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007667: Aug 7 16:13:00.494: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007668: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A800201, Peer Info Type=DIALPEER_INFO_SPEECH
007669: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800201
007670: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=A800201, Expanded String=A800201, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007671: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007672: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007673: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007674: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007675: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=399, Peer Info Type=DIALPEER_INFO_SPEECH
007676: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=399
007677: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=399, Expanded String=399, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007678: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=2000 Is Matched
007679: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007680: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007681: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2000
007682: Aug 7 16:13:00.498: //24450/92CD49A6B7F7/CCAPI/ccCallFeature:
Feature Type=25, Call Id=24450
007683: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=399, Peer Info Type=DIALPEER_INFO_SPEECH
007684: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=399
007685: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=399, Expanded String=399, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007686: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=2000 Is Matched
007687: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007688: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007689: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2000
007690: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A800000, Peer Info Type=DIALPEER_INFO_SPEECH
007691: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A800000
007692: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=A800000, Expanded String=A800000, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007693: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20016 Is Matched
007694: Aug 7 16:13:00.498: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007695: Aug 7 16:13:00.502: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007696: Aug 7 16:13:00.502: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20016
007697: Aug 7 16:13:00.502: //24449/92CD49A6B7F7/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=24449
007698: Aug 7 16:13:00.502: //24449/92CD49A6B7F7/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.1.10.1:5060;branch=z9hG4bKaDmQfum218LqaclK01N0tQ~~23
From: <399>;tag=ds86eb8423
To:
Date: Sun, 07 Aug 2011 15:13:00 GMT
Call-ID: 131272998042724@10.1.10.1
CSeq: 100 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0
007699: Aug 7 16:13:00.502: //24449/92CD49A6B7F7/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.1.10.1:5060;branch=z9hG4bKaDmQfum218LqaclK01N0tQ~~23
From: <399>;tag=ds86eb8423
To:
Date: Sun, 07 Aug 2011 15:13:00 GMT
Call-ID: 131272998042724@10.1.10.1
CSeq: 100 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact:
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0
007700: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:UC520-PUBLIC-IP:56225 SIP/2.0
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK381a.0.0
From: sip:voipnow@88.208.249.40;tag=7115dd11
To: sip:UC520-PUBLIC-IP:56225
Call-ID: aa9e8761-9b8954d4-601e23@88.208.249.40
CSeq: 1 OPTIONS
Content-Length: 0
007701: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=voipnow, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007702: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=voipnow
007703: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007704: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007705: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007706: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007707: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=voipnow
007708: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007709: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007710: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007711: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007712: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007713: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007714: Aug 7 16:13:02.722: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007715: Aug 7 16:13:02.722: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Calling Number=voipnow, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007716: Aug 7 16:13:02.722: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_VIA_URI; URI=sip:88.208.249.40:5060
007717: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007718: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007719: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Result=-1
007720: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:exit@5982
007721: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_REQUEST_URI; URI=sip:UC520-PUBLIC-IP:56225
007722: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007723: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007724: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Result=-1
007725: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:exit@5982
007726: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_TO_URI; URI=sip:UC520-PUBLIC-IP:56225
007727: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007728: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007729: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Result=-1
007730: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:exit@5982
007731: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_FROM_URI; URI=sip:voipnow@88.208.249.40
007732: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007733: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007734: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Result=-1
007735: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:exit@5982
007736: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=voipnow
007737: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007738: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007739: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Result=-1
007740: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:exit@5982
007741: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=voipnow
007742: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007743: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007744: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchCore:
Result=-1
007745: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchPeertype:exit@5982
007746: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007747: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007748: Aug 7 16:13:02.726: //-1/94275B11B7FC/DPM/dpAssociateIncomingPeerSPI:exit@6561
007749: Aug 7 16:13:02.730: //24451/94275B11B7FC/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK381a.0.0
From: sip:voipnow@88.208.249.40;tag=7115dd11
To: sip:UC520-PUBLIC-IP:56225;tag=188D3CA8-5DC
Date: Sun, 07 Aug 2011 15:13:02 GMT
Call-ID: aa9e8761-9b8954d4-601e23@88.208.249.40
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 178
v=0
o=CiscoSystemsSIP-GW-UserAgent 4332 3833 IN IP4 UC520-PRIVATE-IP
s=SIP Call
c=IN IP4 UC520-PRIVATE-IP
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 UC520-PRIVATE-IP
007750: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
Voice port 0x88A3D9F0 dsl=-1 timeslot=0 does not belong to any trunk group
007751: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=BCD, Called Number=, Voice-Interface=0x88A3D9F0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007752: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=BCD
007753: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007754: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007755: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007756: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007757: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=BCD
007758: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007759: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007760: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20001 Is Matched
007761: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007762: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
007763: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007764: Aug 7 16:13:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@6561
007765: Aug 7 16:13:03.522: //-1/94A16CFEB7FD/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=BCD
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=0 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
007766: Aug 7 16:13:03.522: //-1/94A16CFEB7FD/CCAPI/cc_api_call_setup_ind_common:
Interface=0x88A3D9F0, Call Info(
Calling Number=BCD,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
Incoming Dial-peer=20001, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
007767: Aug 7 16:13:03.522: //-1/94A16CFEB7FD/CCAPI/ccCheckClipClir:
In: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007768: Aug 7 16:13:03.522: //-1/94A16CFEB7FD/CCAPI/ccCheckClipClir:
Out: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007769: Aug 7 16:13:03.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007770: Aug 7 16:13:03.522: :cc_get_feature_vsa malloc success
007771: Aug 7 16:13:03.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007772: Aug 7 16:13:03.522: cc_get_feature_vsa count is 3
007773: Aug 7 16:13:03.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007774: Aug 7 16:13:03.522: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339968,feature_id:13887
007775: Aug 7 16:13:03.522: //24452/94A16CFEB7FD/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
007776: Aug 7 16:13:03.522: //-1/94A16CFEB7FD/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46BFEC; count=1
007777: Aug 7 16:13:03.522: //-1/94A16CFEB7FD/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA78E8
007778: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/cc_process_call_setup_ind:
Event=0x87F2B738
007779: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/ccCallSetContext:
Context=0x86053F54
007780: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 24452 with tag 20001 to app "_ManagedAppProcess_Default"
007781: Aug 7 16:13:03.526: //-1/94A16CFEB7FD/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46BFEC; count=1
007782: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/ccCallSetupAck:
Call Id=24452
007783: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/cc_api_set_transfer_info:
Transfer Number=, Transfer Reason=0x0
007784: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=TRUE, Tone=Dial Tone,
Tone Direction=Network, Params=0x0, Call Id=24452
007785: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/ccSetDigitTimeouts:
Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
007786: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/ccSetDigitTimeouts:
Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
007787: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/ccCallReportDigits:
(callID=0x5F84, digit_event=0x1, enable=TRUE, consume=FALSE)
007788: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24452
007789: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F84, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
007790: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24452
007791: Aug 7 16:13:03.526: //24452/94A16CFEB7FD/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
007792: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24452, Digit=A, DigitBeginFlags=0x0,
Rtp Timestamp=0x5025E608, Rtp Expiration=0x0
007793: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24452, Digit=A, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007794: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007795: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24452, Digit=B, DigitBeginFlags=0x0,
Rtp Timestamp=0x50266308, Rtp Expiration=0x0
007796: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24452, Digit=B, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007797: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007798: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24452, Digit=C, DigitBeginFlags=0x0,
Rtp Timestamp=0x5026E008, Rtp Expiration=0x0
007799: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24452, Digit=C, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007800: Aug 7 16:13:03.530: //24452/94A16CFEB7FD/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007801: Aug 7 16:13:03.534: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
007802: Aug 7 16:13:03.534: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A
007803: Aug 7 16:13:03.534: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Dial String=A, Expanded String=A, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007804: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
007805: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Result=1
007806: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
007807: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchSafModulePlugin:
dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
007808: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
007809: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
007810: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=AB
007811: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Dial String=AB, Expanded String=AB, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007812: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
007813: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Result=1
007814: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
007815: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchSafModulePlugin:
dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
007816: Aug 7 16:13:03.538: //-1/94A16CFEB7FD/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
007817: Aug 7 16:13:03.542: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007818: Aug 7 16:13:03.542: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007819: Aug 7 16:13:03.542: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007820: Aug 7 16:13:03.542: //-1/94A16CFEB7FD/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007821: Aug 7 16:13:03.542: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007822: Aug 7 16:13:03.542: //-1/94A16CFEB7FD/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
007823: Aug 7 16:13:03.542: //-1/94A16CFEB7FD/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007824: Aug 7 16:13:03.542: //24452/94A16CFEB7FD/CCAPI/ccCallReportDigits:
(callID=0x5F84, digit_event=0x0, enable=FALSE, consume=FALSE)
007825: Aug 7 16:13:03.542: //24452/94A16CFEB7FD/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24452
007826: Aug 7 16:13:03.542: //24452/94A16CFEB7FD/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F84, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
007827: Aug 7 16:13:03.542: //24452/94A16CFEB7FD/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24452
007828: Aug 7 16:13:03.542: //24452/94A16CFEB7FD/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
007829: Aug 7 16:13:03.542: //24452/94A16CFEB7FD/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
007830: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007831: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007832: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007833: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007834: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007835: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007836: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007837: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007838: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007839: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007840: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007841: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007842: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007843: Aug 7 16:13:03.546: //-1/94A16CFEB7FD/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007844: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=ABC, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007845: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007846: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=ABCT
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007847: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007848: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007849: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007850: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007851: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007852: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007853: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007854: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007855: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=ABC, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007856: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=ABC
007857: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007858: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007859: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007860: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007861: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=ABC
007862: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007863: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007864: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=5 Is Matched
007865: Aug 7 16:13:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007866: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
007867: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
007868: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
007869: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007870: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007871: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007872: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007873: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007874: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
007875: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007876: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007877: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007878: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007879: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007880: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
007881: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
007882: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
007883: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46BFEC; count=2
007884: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/RXRULE/regxrule_dp_translate: No profile found in peer 5 for outgoing direction
007885: Aug 7 16:13:03.550: //-1/94A16CFEB7FD/RXRULE/regxrule_dp_translate: calling_number=BCD calling_octet=0x0
called_number=ABC called_octet=0x80
redirect_number= redirect_type=0 redirect_plan=0 redirect_PI=0 redirect_SI=0
007886: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number ABC
007887: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007888: Aug 7 16:13:03.550: : updating existing feature vsa
007889: Aug 7 16:13:03.550: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
007890: Aug 7 16:13:03.550: feature call basic
007891: Aug 7 16:13:03.554: //24452/94A16CFEB7FD/CCAPI/ccCallDisconnect:
Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
007892: Aug 7 16:13:03.554: //24452/94A16CFEB7FD/CCAPI/ccCallDisconnect:
Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
007893: Aug 7 16:13:03.554: //24452/94A16CFEB7FD/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
007894: Aug 7 16:13:03.566: //24452/94A16CFEB7FD/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88A3D9F0, Tag=0x0, Call Id=24452,
Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
007895: Aug 7 16:13:03.566: //-1/94A16CFEB7FD/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46BFEC; count=2
007896: Aug 7 16:13:03.566: //-1/94A16CFEB7FD/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA95D0
007897: Aug 7 16:13:03.566: //24452/94A16CFEB7FD/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
007898: Aug 7 16:13:03.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007899: Aug 7 16:13:03.566: :cc_free_feature_vsa freeing 88DF5DF8
007900: Aug 7 16:13:03.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007901: Aug 7 16:13:03.566: vsacount in free is 2
007902: Aug 7 16:13:05.466: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
CANCEL sip:A800201@10.1.10.2:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.1.10.1:5060;branch=z9hG4bKaDmQfum218LqaclK01N0tQ~~23
Max-Forwards: 70
To:
From: <399>;tag=ds86eb8423
Call-ID: 131272998042724@10.1.10.1
CSeq: 100 CANCEL
Content-Length: 0
007903: Aug 7 16:13:05.466: //24449/92CD49A6B7F7/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x872E9C18, Call Id=24449
007904: Aug 7 16:13:05.466: //24449/92CD49A6B7F7/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
007905: Aug 7 16:13:05.466: //24450/92CD49A6B7F7/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
007906: Aug 7 16:13:05.466: //24450/92CD49A6B7F7/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
007907: Aug 7 16:13:05.466: //24450/92CD49A6B7F7/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
007908: Aug 7 16:13:05.470: //24449/92CD49A6B7F7/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
007909: Aug 7 16:13:05.470: //24449/92CD49A6B7F7/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
007910: Aug 7 16:13:05.474: //24450/92CD49A6B7F7/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x8887D9EC, Tag=0x0, Call Id=24450,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
007911: Aug 7 16:13:05.474: //-1/92CD49A6B7F7/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x0
007912: Aug 7 16:13:05.474: //24450/92CD49A6B7F7/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
007913: Aug 7 16:13:05.474: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007914: Aug 7 16:13:05.474: :cc_free_feature_vsa freeing 88DF5ED8
007915: Aug 7 16:13:05.474: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007916: Aug 7 16:13:05.474: vsacount in free is 1
007917: Aug 7 16:13:05.478: //24449/92CD49A6B7F7/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.1.10.1:5060;branch=z9hG4bKaDmQfum218LqaclK01N0tQ~~23
From: <399>;tag=ds86eb8423
To:
Date: Sun, 07 Aug 2011 15:13:05 GMT
Call-ID: 131272998042724@10.1.10.1
CSeq: 100 CANCEL
Content-Length: 0
007918: Aug 7 16:13:05.478: //24449/92CD49A6B7F7/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 487 Request Cancelled
Via: SIP/2.0/UDP 10.1.10.1:5060;branch=z9hG4bKaDmQfum218LqaclK01N0tQ~~23
From: <399>;tag=ds86eb8423
To:
Date: Sun, 07 Aug 2011 15:13:05 GMT
Call-ID: 131272998042724@10.1.10.1
CSeq: 100 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Reason: Q.850;cause=16
Content-Length: 0
007919: Aug 7 16:13:05.518: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:A800201@10.1.10.2:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.1.10.1:5060;branch=z9hG4bKaDmQfum218LqaclK01N0tQ~~23
Max-Forwards: 70
To:
From: <399>;tag=ds86eb8423
Call-ID: 131272998042724@10.1.10.1
CSeq: 100 ACK
Content-Length: 0
007920: Aug 7 16:13:05.522: //24449/92CD49A6B7F7/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x872E9C18, Tag=0x0, Call Id=24449,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
007921: Aug 7 16:13:05.522: //-1/92CD49A6B7F7/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46C7D4; count=2
007922: Aug 7 16:13:05.522: //-1/92CD49A6B7F7/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA8884
007923: Aug 7 16:13:05.522: //24449/92CD49A6B7F7/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
007924: Aug 7 16:13:05.522: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007925: Aug 7 16:13:05.522: :cc_free_feature_vsa freeing 88DF5D18
007926: Aug 7 16:13:05.522: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
007927: Aug 7 16:13:05.522: vsacount in free is 0
007928: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
Voice port 0x88A3D9F0 dsl=-1 timeslot=0 does not belong to any trunk group
007929: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=BCD, Called Number=, Voice-Interface=0x88A3D9F0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
007930: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=BCD
007931: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007932: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007933: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
007934: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007935: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=BCD
007936: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
007937: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
007938: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20001 Is Matched
007939: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
007940: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
007941: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
007942: Aug 7 16:13:33.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@6561
007943: Aug 7 16:13:33.522: //-1/A6830FFEB801/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=BCD
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=0 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
007944: Aug 7 16:13:33.522: //-1/A6830FFEB801/CCAPI/cc_api_call_setup_ind_common:
Interface=0x88A3D9F0, Call Info(
Calling Number=BCD,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
Incoming Dial-peer=20001, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
007945: Aug 7 16:13:33.522: //-1/A6830FFEB801/CCAPI/ccCheckClipClir:
In: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007946: Aug 7 16:13:33.522: //-1/A6830FFEB801/CCAPI/ccCheckClipClir:
Out: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
007947: Aug 7 16:13:33.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007948: Aug 7 16:13:33.522: :cc_get_feature_vsa malloc success
007949: Aug 7 16:13:33.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007950: Aug 7 16:13:33.522: cc_get_feature_vsa count is 1
007951: Aug 7 16:13:33.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
007952: Aug 7 16:13:33.522: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339744,feature_id:13888
007953: Aug 7 16:13:33.522: //24453/A6830FFEB801/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
007954: Aug 7 16:13:33.522: //-1/A6830FFEB801/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46BE8C; count=1
007955: Aug 7 16:13:33.522: //-1/A6830FFEB801/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA875C
007956: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/cc_process_call_setup_ind:
Event=0x87F2C158
007957: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/ccCallSetContext:
Context=0x8605C8EC
007958: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 24453 with tag 20001 to app "_ManagedAppProcess_Default"
007959: Aug 7 16:13:33.526: //-1/A6830FFEB801/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46BE8C; count=1
007960: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/ccCallSetupAck:
Call Id=24453
007961: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/cc_api_set_transfer_info:
Transfer Number=, Transfer Reason=0x0
007962: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=TRUE, Tone=Dial Tone,
Tone Direction=Network, Params=0x0, Call Id=24453
007963: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/ccSetDigitTimeouts:
Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
007964: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/ccSetDigitTimeouts:
Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
007965: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/ccCallReportDigits:
(callID=0x5F85, digit_event=0x1, enable=TRUE, consume=FALSE)
007966: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24453
007967: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F85, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
007968: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24453
007969: Aug 7 16:13:33.526: //24453/A6830FFEB801/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
007970: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24453, Digit=A, DigitBeginFlags=0x0,
Rtp Timestamp=0x502764D8, Rtp Expiration=0x0
007971: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24453, Digit=A, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007972: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007973: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24453, Digit=B, DigitBeginFlags=0x0,
Rtp Timestamp=0x5027E1D8, Rtp Expiration=0x0
007974: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24453, Digit=B, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007975: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007976: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24453, Digit=C, DigitBeginFlags=0x0,
Rtp Timestamp=0x50285ED8, Rtp Expiration=0x0
007977: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24453, Digit=C, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
007978: Aug 7 16:13:33.530: //24453/A6830FFEB801/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
007979: Aug 7 16:13:33.534: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
007980: Aug 7 16:13:33.534: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A
007981: Aug 7 16:13:33.534: //-1/A6830FFEB801/DPM/dpMatchCore:
Dial String=A, Expanded String=A, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007982: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
007983: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchCore:
Result=1
007984: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
007985: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchSafModulePlugin:
dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
007986: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
007987: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
007988: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=AB
007989: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchCore:
Dial String=AB, Expanded String=AB, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007990: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
007991: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchCore:
Result=1
007992: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
007993: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchSafModulePlugin:
dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
007994: Aug 7 16:13:33.538: //-1/A6830FFEB801/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
007995: Aug 7 16:13:33.542: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
007996: Aug 7 16:13:33.542: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
007997: Aug 7 16:13:33.542: //-1/A6830FFEB801/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
007998: Aug 7 16:13:33.542: //-1/A6830FFEB801/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
007999: Aug 7 16:13:33.542: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008000: Aug 7 16:13:33.542: //-1/A6830FFEB801/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
008001: Aug 7 16:13:33.542: //-1/A6830FFEB801/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008002: Aug 7 16:13:33.542: //24453/A6830FFEB801/CCAPI/ccCallReportDigits:
(callID=0x5F85, digit_event=0x0, enable=FALSE, consume=FALSE)
008003: Aug 7 16:13:33.542: //24453/A6830FFEB801/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24453
008004: Aug 7 16:13:33.542: //24453/A6830FFEB801/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F85, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
008005: Aug 7 16:13:33.542: //24453/A6830FFEB801/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24453
008006: Aug 7 16:13:33.542: //24453/A6830FFEB801/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
008007: Aug 7 16:13:33.542: //24453/A6830FFEB801/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
008008: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008009: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008010: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008011: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008012: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008013: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008014: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008015: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008016: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008017: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008018: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008019: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008020: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008021: Aug 7 16:13:33.546: //-1/A6830FFEB801/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008022: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=ABC, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008023: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008024: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=ABCT
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008025: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008026: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008027: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008028: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008029: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
008030: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
008031: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
008032: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
008033: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=ABC, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
008034: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=ABC
008035: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008036: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008037: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
008038: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008039: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=ABC
008040: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008041: Aug 7 16:13:33.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008042: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=5 Is Matched
008043: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008044: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
008045: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
008046: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
008047: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008048: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008049: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008050: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008051: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008052: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008053: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008054: Aug 7 16:13:33.550: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008055: Aug 7 16:13:33.550: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008056: Aug 7 16:13:33.550: //-1/A6830FFEB801/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008057: Aug 7 16:13:33.550: //-1/A6830FFEB801/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008058: Aug 7 16:13:33.550: //-1/A6830FFEB801/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008059: Aug 7 16:13:33.550: //-1/A6830FFEB801/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
008060: Aug 7 16:13:33.550: //-1/A6830FFEB801/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008061: Aug 7 16:13:33.550: //-1/A6830FFEB801/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46BE8C; count=2
008062: Aug 7 16:13:33.550: //-1/A6830FFEB801/RXRULE/regxrule_dp_translate: No profile found in peer 5 for outgoing direction
008063: Aug 7 16:13:33.550: //-1/A6830FFEB801/RXRULE/regxrule_dp_translate: calling_number=BCD calling_octet=0x0
called_number=ABC called_octet=0x80
redirect_number= redirect_type=0 redirect_plan=0 redirect_PI=0 redirect_SI=0
008064: Aug 7 16:13:33.550: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number ABC
008065: Aug 7 16:13:33.554: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
008066: Aug 7 16:13:33.554: : updating existing feature vsa
008067: Aug 7 16:13:33.554: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
008068: Aug 7 16:13:33.554: feature call basic
008069: Aug 7 16:13:33.554: //24453/A6830FFEB801/CCAPI/ccCallDisconnect:
Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
008070: Aug 7 16:13:33.554: //24453/A6830FFEB801/CCAPI/ccCallDisconnect:
Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
008071: Aug 7 16:13:33.554: //24453/A6830FFEB801/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
008072: Aug 7 16:13:33.566: //24453/A6830FFEB801/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88A3D9F0, Tag=0x0, Call Id=24453,
Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
008073: Aug 7 16:13:33.566: //-1/A6830FFEB801/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46BE8C; count=2
008074: Aug 7 16:13:33.566: //-1/A6830FFEB801/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA8194
008075: Aug 7 16:13:33.566: //24453/A6830FFEB801/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
008076: Aug 7 16:13:33.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
008077: Aug 7 16:13:33.566: :cc_free_feature_vsa freeing 88DF5D18
008078: Aug 7 16:13:33.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
008079: Aug 7 16:13:33.566: vsacount in free is 0
008080: Aug 7 16:13:36.246: %CDP-4-DUPLEX_MISMATCH: duplex mismatch discovered on FastEthernet0/1/8 (not half duplex), with SEP00e075244b3c port 1 (half duplex).
008081: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:UC520-PUBLIC-IP:56225 SIP/2.0
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK6b6f.0.0
From: sip:voipnow@88.208.249.40;tag=3715dd11
To: sip:UC520-PUBLIC-IP:56225
Call-ID: aa9e8761-519954d4-831e23@88.208.249.40
CSeq: 1 OPTIONS
Content-Length: 0
008082: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=voipnow, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
008083: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=voipnow
008084: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008085: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008086: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
008087: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008088: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=voipnow
008089: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008090: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008091: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
008092: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008093: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
008094: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
008095: Aug 7 16:13:52.718: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
008096: Aug 7 16:13:52.718: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Calling Number=voipnow, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
008097: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_VIA_URI; URI=sip:88.208.249.40:5060
008098: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008099: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008100: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Result=-1
008101: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:exit@5982
008102: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_REQUEST_URI; URI=sip:UC520-PUBLIC-IP:56225
008103: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008104: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008105: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Result=-1
008106: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:exit@5982
008107: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_TO_URI; URI=sip:UC520-PUBLIC-IP:56225
008108: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008109: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008110: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Result=-1
008111: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:exit@5982
008112: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_FROM_URI; URI=sip:voipnow@88.208.249.40
008113: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008114: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008115: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Result=-1
008116: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:exit@5982
008117: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=voipnow
008118: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008119: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008120: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Result=-1
008121: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:exit@5982
008122: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=voipnow
008123: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008124: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=voipnowT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008125: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchCore:
Result=-1
008126: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchPeertype:exit@5982
008127: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
008128: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
008129: Aug 7 16:13:52.722: //-1/B1F423E9B805/DPM/dpAssociateIncomingPeerSPI:exit@6561
008130: Aug 7 16:13:52.726: //24454/B1F423E9B805/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 88.208.249.40:5060;branch=z9hG4bK6b6f.0.0
From: sip:voipnow@88.208.249.40;tag=3715dd11
To: sip:UC520-PUBLIC-IP:56225;tag=188DFFF4-216C
Date: Sun, 07 Aug 2011 15:13:52 GMT
Call-ID: aa9e8761-519954d4-831e23@88.208.249.40
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 179
v=0
o=CiscoSystemsSIP-GW-UserAgent 27290 7220 IN IP4 UC520-PRIVATE-IP
s=SIP Call
c=IN IP4 UC520-PRIVATE-IP
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 UC520-PRIVATE-IP
008131: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
Voice port 0x88A3D9F0 dsl=-1 timeslot=0 does not belong to any trunk group
008132: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=BCD, Called Number=, Voice-Interface=0x88A3D9F0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
008133: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=BCD
008134: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008135: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008136: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
008137: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008138: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=BCD
008139: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008140: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=BCDT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008141: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20001 Is Matched
008142: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008143: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
008144: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
008145: Aug 7 16:14:03.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@6561
008146: Aug 7 16:14:03.522: //-1/B864B397B806/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=BCD
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=0 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
008147: Aug 7 16:14:03.522: //-1/B864B397B806/CCAPI/cc_api_call_setup_ind_common:
Interface=0x88A3D9F0, Call Info(
Calling Number=BCD,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
Incoming Dial-peer=20001, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
008148: Aug 7 16:14:03.522: //-1/B864B397B806/CCAPI/ccCheckClipClir:
In: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
008149: Aug 7 16:14:03.522: //-1/B864B397B806/CCAPI/ccCheckClipClir:
Out: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
008150: Aug 7 16:14:03.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
008151: Aug 7 16:14:03.522: :cc_get_feature_vsa malloc success
008152: Aug 7 16:14:03.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
008153: Aug 7 16:14:03.522: cc_get_feature_vsa count is 1
008154: Aug 7 16:14:03.522: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
008155: Aug 7 16:14:03.522: :FEATURE_VSA attributes are: feature_name:0,feature_time:2296339744,feature_id:13889
008156: Aug 7 16:14:03.522: //24455/B864B397B806/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
008157: Aug 7 16:14:03.522: //-1/B864B397B806/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46D06C; count=1
008158: Aug 7 16:14:03.522: //-1/B864B397B806/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA8DB8
008159: Aug 7 16:14:03.522: //24455/B864B397B806/CCAPI/cc_process_call_setup_ind:
Event=0x87F2B738
008160: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/ccCallSetContext:
Context=0x8604F0B4
008161: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 24455 with tag 20001 to app "_ManagedAppProcess_Default"
008162: Aug 7 16:14:03.526: //-1/B864B397B806/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46D06C; count=1
008163: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/ccCallSetupAck:
Call Id=24455
008164: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/cc_api_set_transfer_info:
Transfer Number=, Transfer Reason=0x0
008165: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=TRUE, Tone=Dial Tone,
Tone Direction=Network, Params=0x0, Call Id=24455
008166: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/ccSetDigitTimeouts:
Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
008167: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/ccSetDigitTimeouts:
Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
008168: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/ccCallReportDigits:
(callID=0x5F87, digit_event=0x1, enable=TRUE, consume=FALSE)
008169: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24455
008170: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F87, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
008171: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24455
008172: Aug 7 16:14:03.526: //24455/B864B397B806/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
008173: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24455, Digit=A, DigitBeginFlags=0x0,
Rtp Timestamp=0x5028E3A8, Rtp Expiration=0x0
008174: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24455, Digit=A, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
008175: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
008176: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24455, Digit=B, DigitBeginFlags=0x0,
Rtp Timestamp=0x502960A8, Rtp Expiration=0x0
008177: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24455, Digit=B, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
008178: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
008179: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24455, Digit=C, DigitBeginFlags=0x0,
Rtp Timestamp=0x5029DDA8, Rtp Expiration=0x0
008180: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
Source Call Id=24455, Digit=C, Duration=100,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
008181: Aug 7 16:14:03.530: //24455/B864B397B806/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
008182: Aug 7 16:14:03.534: //-1/B864B397B806/DPM/dpMatchPeersCore:
Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
008183: Aug 7 16:14:03.534: //-1/B864B397B806/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=A
008184: Aug 7 16:14:03.534: //-1/B864B397B806/DPM/dpMatchCore:
Dial String=A, Expanded String=A, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008185: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
008186: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchCore:
Result=1
008187: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
008188: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchSafModulePlugin:
dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
008189: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
008190: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchPeersCore:
Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
008191: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=AB
008192: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchCore:
Dial String=AB, Expanded String=AB, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008193: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/MatchNextPeer:
Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=5
008194: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchCore:
Result=1
008195: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
008196: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchSafModulePlugin:
dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
008197: Aug 7 16:14:03.538: //-1/B864B397B806/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
008198: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008199: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008200: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008201: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008202: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008203: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
008204: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008205: Aug 7 16:14:03.542: //24455/B864B397B806/CCAPI/ccCallReportDigits:
(callID=0x5F87, digit_event=0x0, enable=FALSE, consume=FALSE)
008206: Aug 7 16:14:03.542: //24455/B864B397B806/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=24455
008207: Aug 7 16:14:03.542: //24455/B864B397B806/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x88A3D9F0, callID=0x5F87, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
008208: Aug 7 16:14:03.542: //24455/B864B397B806/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x88A3D9F0, Call Id=24455
008209: Aug 7 16:14:03.542: //24455/B864B397B806/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
008210: Aug 7 16:14:03.542: //24455/B864B397B806/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
008211: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008212: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008213: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008214: Aug 7 16:14:03.542: //-1/B864B397B806/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008215: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008216: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008217: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008218: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008219: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008220: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008221: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008222: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008223: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008224: Aug 7 16:14:03.546: //-1/B864B397B806/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008225: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=ABC, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008226: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008227: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=ABCT
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008228: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008229: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008230: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008231: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008232: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
008233: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
008234: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
008235: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
008236: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=ABC, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
008237: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=ABC
008238: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008239: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008240: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
008241: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008242: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=ABC
008243: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
008244: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=ABCT
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
008245: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=5 Is Matched
008246: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5982
008247: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
008248: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
008249: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6610
008250: Aug 7 16:14:03.546: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008251: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008252: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008253: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008254: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008255: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
008256: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008257: Aug 7 16:14:03.550: //-1/B864B397B806/DPM/dpMatchPeersCore:
Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
008258: Aug 7 16:14:03.550: //-1/B864B397B806/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=ABC
008259: Aug 7 16:14:03.550: //-1/B864B397B806/DPM/dpMatchCore:
Dial String=ABC, Expanded String=ABC, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
008260: Aug 7 16:14:03.550: //-1/B864B397B806/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=5 Is Matched
008261: Aug 7 16:14:03.550: //-1/B864B397B806/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
008262: Aug 7 16:14:03.550: //-1/B864B397B806/DPM/dpMatchSafModulePlugin:
dialstring=ABC, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
008263: Aug 7 16:14:03.550: //-1/B864B397B806/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=5
008264: Aug 7 16:14:03.550: //-1/B864B397B806/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8A46D06C; count=2
008265: Aug 7 16:14:03.550: //-1/B864B397B806/RXRULE/regxrule_dp_translate: No profile found in peer 5 for outgoing direction
008266: Aug 7 16:14:03.550: //-1/B864B397B806/RXRULE/regxrule_dp_translate: calling_number=BCD calling_octet=0x0
called_number=ABC called_octet=0x80
redirect_number= redirect_type=0 redirect_plan=0 redirect_PI=0 redirect_SI=0
008267: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number ABC
008268: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
008269: Aug 7 16:14:03.550: : updating existing feature vsa
008270: Aug 7 16:14:03.550: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
008271: Aug 7 16:14:03.550: feature call basic
008272: Aug 7 16:14:03.554: //24455/B864B397B806/CCAPI/ccCallDisconnect:
Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
008273: Aug 7 16:14:03.554: //24455/B864B397B806/CCAPI/ccCallDisconnect:
Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
008274: Aug 7 16:14:03.554: //24455/B864B397B806/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
008275: Aug 7 16:14:03.566: //24455/B864B397B806/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88A3D9F0, Tag=0x0, Call Id=24455,
Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
008276: Aug 7 16:14:03.566: //-1/B864B397B806/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8A46D06C; count=2
008277: Aug 7 16:14:03.566: //-1/B864B397B806/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x88EA875C
008278: Aug 7 16:14:03.566: //24455/B864B397B806/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
008279: Aug 7 16:14:03.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
008280: Aug 7 16:14:03.566: :cc_free_feature_vsa freeing 88DF5D18
008281: Aug 7 16:14:03.566: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
008282: Aug 7 16:14:03.566: vsacount in free is 0
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
08-08-2011 11:22 AM
Hi, Mark:
Yes, the log tells SIP provider send BYE to kill the call.
Why ? It might be due to H450.12 or other issue.
The best bet is to ask SIP provider with this trace.
One thing you can try is to setup CFNA not to Voicemail, but to other internal extension and answered.
Does this scenario work ?
-David
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
08-08-2011 11:22 PM
Hi Mark,
I might be reading the logs Differently to David, but I am seeing Cause Code "17" shortly after it is transferred to the CUE, and then a little way down from that a code 487 "Interworking Unspecified" and there seems to be a repetition of it.
I do not believe it to be the ITSP terminating the call without something being initiated from the UC-520, whilst there is certainly evidence that that ITSP has sent a BYE message, I believe it to be because the UC-520 has had something go wrong.
I am curious, what is the Codec that is being used with the ITSP?
Can you just get a clean "debug ccsip" without the dial-peer debugging turned on, I would like to just focus on the SIP messages, so you will need to debug the call and also the errors, id suggest the following debug if you wouldn't mind but turn is of straight after the test as it can generate some heat on the system with CPU load and memory maxing.
"debug ccsip all"
Capture if possible at least 5 calls, and if you can vary them between being diverted to VM because of routing rules, and if possible try and throw in at least 2 calls that were done by using the Trans to VM function.
Also if you can follow David's suggestion of asking the ITSP for some call logs that would be great, although often more than not they are not very willing to do this.
Cheers,
David.
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
08-12-2011 03:53 AM
Sorry for the delay in getting back to everyone...
Most frustrating, but when I enable debug with debug ccsip all ... the voicemail does not cut off!
Tested messages up to 20+ seconds with no issue.
Turn debugging off... problem reappears. Was logging to the buffer not console if that makes any difference.
Struggling to get a meaningfull log showing the problem!
ITSP probably isn't going to be too keen to give us call logs from their end.
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
01-13-2012 04:39 AM
Again sorry for the delay in following up on this... priorities changed, but now it is important again.
Any ideas as the the best way forwards? As I said before, turning on the debugging all setting actually makes the problem go away. It must be timing related and the debugging slows things enough so it doesn't happen.
Catch22; No debugging captured and the issue happens. Debugging on, to capture the event, and it doesn't occur.
Does any one know which debugging message I should be concentrating on?
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
01-15-2012 04:28 PM
Hi Mark,
Good to see you back here again
Ok so with you telling us that when you turn on debugging the issues doesn't happen, and then when you turn it off it does happen (???) I am going to just have to say you really need to log a case for this, if it is indeed a bug somewhere then it needs to be identified, it just doesn't make sense for that to happen.
Turning debugging on should not effect timing of the SIP trunk in anyway, only the CPU load on the system.
Cheers,
David.
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
03-02-2012 04:04 AM
I am going to reply to my own post as I now have an answer.... I guess I can't give myself a point for that!
Thanks for everyone's input on this, I have now found the issue.
Our SIP provider changed their gateway this month and the problem disapeared. Therefore, though clearly there must have been an issue on the UC520 side (otherwise the strange situation of enabling debugs affecting the result would not be happening), the old gateway must also have had an issue.
Though we have no connection with them other than a customer, I wish to mention that our provider here in the UK is Hostcomm, and they were very helpful in asisting with some additional configuration that was required to authenticate on our side after the gateway change.
If anyone does want to use them, I can give them the template/settings that I know work for us on the UC520.
Thanks again
Mark
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide