07-23-2013 05:00 AM - edited 03-16-2019 06:30 PM
Hi Guys,
I have here in this case a business edition 3000 with a sip trunk to CUBE.I had to configuire BACD to meet the requirements of the customer which BE3000 does not support.
The trunk is configured on BE to send the call to CUBE IP address if some one dials 750 which is the pilot number of Auto Attened script.
when i try to dial 750 i hear nothing.As i understand service should be invoked on the inbound dial-peer,i configured destination pattern with the same aa pilot and session target with the ip address of FA0/4 (public ip address of cube) ,tried a loopback address and default gateway ip address of BE 3000 which is an ip address also in CUBE ,but i never hear anything.In my understanding the outbound dial-peer shoildn't be the reason cause I care about the inbound only in the auto attendant case !
Here are sample configurations with debugs of voip dialpeer and ccsip messages :
CUBE(config)#do show run | s application|dial-peer voice 750 voip
application
service autoattend flash:/its-CISCO.2.0.2.0.tcl
paramspace english language en
paramspace english index 0
param aaPilot 750
paramspace english location flash:/
paramspace english prefix en
param oprtr 100
!
global
service alternate default
!
dial-peer voice 750 voip
service autoattend
destination-pattern 750
session target ipv4:10.41.201.250
incoming called-number 750
CUBE#show call application sessions
Session ID 3E
App: autoattend
Type: Service
Url: flash:/its-CISCO.2.0.2.0.tcl
Session ID 3F
App: autoattend
Type: Service
Url: flash:/its-CISCO.2.0.2.0.tcl
CUBE(config)#do terminal monitor
CUBE(config)#exit
CUBE#
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=750, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124XXXXXX, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 23 10:50:18.939: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 23 10:50:18.943: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124XXXXXX, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 23 10:50:18.943: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 23 10:50:18.943: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 23 10:50:18.943: //-1/A89C56800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124XXXXXX, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 23 10:50:18.943: //-1/A89C56800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 23 10:50:18.943: //-1/A89C56800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 23 10:50:18.947: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124XXXXXX, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 23 10:50:18.947: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 23 10:50:18.947: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 23 10:50:18.951: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 23 10:50:18.951: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 23 10:50:18.951: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 23 10:50:18.951: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Jul 23 10:50:18.951: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 23 10:50:18.951: //-1/A89C56800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 23 10:50:18.951: //-1/A89C56800000/DPM/dpMatchPeersCore:
CUBE#Match Rule=DP_MATCH_DEST_URI; URI=sip:750@fa0/4:5060
Jul 23 10:50:18.951: //-1/A89C56800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 23 10:50:18.951: //-1/A89C56800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 23 10:50:18.955: //-1/A89C56800000/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 23 10:50:18.955: //-1/A89C56800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
CUBE(config)#do debug ccsip messages
SIP Call messages tracing is enabled
Jul 23 11:02:45.407: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:750@fa0/4ip:5060 SIP/2.0
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b5a7c0e11a7
From: <sip:8124XXXXXX@10.41.201.250>;tag=3994346~77bba026-5b79-4e2a-a90d-bffd1d2afd15-26930118
To: <sip:750@fa0/4ip>
Date: Tue, 23 Jul 2013 11:02:45 GMT
Call-ID: 65db7e00-1ee162d5-198be-fac9290a@10.41.201.250
Supported: timer,resource-priority,replaces
Min-SE: 1800
User-Agent: Cisco-CUCM8.6
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
CSeq: 101 INVITE
Expires: 180
Allow-Events: presence, kpml
Supported: X-cisco-srtp-fallback
Supported: Geolocation
Call-Info: <sip:10.41.201.250:5060>;method="NOTIFY;Event=telephone-event;Duration=500"
Cisco-Guid: 1708883456-0000065536-0000000114-4207487242
Session-Expires: 1800
P-Asserted-Identity: <sip:8124XXXXXX@10.41.201.250>
Remote-Party-ID: <sip:8124XXXXXX@10.41.201.250>;party=calling;screen=yes;privacy=off
Contact: <sip:8124XXXXXX@10.41.201.250:5060;transport=tcp>
Max-Forwards: 69
Content-Length: 0
Jul 23 11:02:45.423: //3600/65DB7E000000/SIP/Msg/ccsipDisplayMsg:
Sent:
CUBE(config)#SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b5a7c0e11a7
From: <sip:8124XXXXXX@10.41.201.250>;tag=3994346~77bba026-5b79-4e2a-a90d-bffd1d2afd15-26930118
To: <sip:750@fa0/4ip>
Date: Tue, 23 Jul 2013 11:02:45 GMT
Call-ID: 65db7e00-1ee162d5-198be-fac9290a@10.41.201.250
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.3.1.T
Content-Length: 0
CUBE(config)#
CUBE(config)#
Jul 23 11:03:05.747: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:fa0/4ip:5060 SIP/2.0
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b64c63c9c0
From: <sip:10.41.201.250>;tag=218591423
To: <sip:fa0/4ip>
Date: Tue, 23 Jul 2013 11:03:05 GMT
Call-ID: 71c74000-1ee162e9-198c1-fac9290a@10.41.201.250
User-Agent: Cisco-CUCM8.6
CSeq: 101 OPTIONS
Contact: <sip:10.41.201.250:5060;transport=tcp>
Max-Forwards: 0
Content-Length: 0
Jul 23 11:03:05.751: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b64c63c9c0
From: <sip:10.41.201.250>;tag=218591423
To: <sip:fa0/4ip>;tag=26CB3BA4-1974
Date: Tue, 23 Jul 2013 11:03:05 GMT
Call-ID: 71c74000-1ee162e9-198c1-fac9290a@10.41.201.250
Server: Cisco-SIPGateway/IOS-15.3.1.T
CSeq: 101 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-priorit
CUBE(config)#y,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 380
v=0
o=CiscoSystemsSIP-GW-UserAgent 9576 629 IN IP4 fa0/4ip
s=SIP Call
c=IN IP4 fa0/4ip
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 fa0/4ip
m=image 0 udptl t38
c=IN IP4 fa0/4ip
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:320
a=T38FaxUdpEC:t38UDPRedundancy
CUBE(config)#
Jul 23 11:03:56.187: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
CANCEL sip:750@fa0/4ip:5060 SIP/2.0
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b5a7c0e11a7
From: <sip:8124XXXXXX@10.41.201.250>;tag=3994346~77bba026-5b79-4e2a-a90d-bffd1d2afd15-26930118
To: <sip:750@fa0/4ip>
Date: Tue, 23 Jul 2013 11:02:45 GMT
Call-ID: 65db7e00-1ee162d5-198be-fac9290a@10.41.201.250
CSeq: 101 CANCEL
Max-Forwards: 70
Content-Length: 0
Jul 23 11:03:56.191: //3600/65DB7E000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b5a7c0e11a7
From: <sip:8124XXXXXX@10.41.201.250>;tag=3994346~77bba026-5b79-4e2a-a90d-bffd1d2afd15-26930118
To: <sip:750@fa0/4ip>
Date: Tue, 23 Jul 2013 11:03:56 GMT
Call-ID: 65db7e00-1ee162d5-198be-fac9290a@10.41.201.250
CSeq: 101 CANCEL
Content-Length: 0
Jul 23 11:03:56.191: //3600/65DB7E000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 487 Request Cancelled
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b5a7c0e11a7
From: <sip:8124XXXXXX@10.41.201.250>;tag=3994346~77bba026-5b79-4e2a-a90d-bffd1d2afd15-26930118
To: <sip:750@\fa0/4ip>;tag=26CC00AC-667
Date: Tue, 23 Jul 2013 11:03:56 GMT
Call-ID: 65db7e00-1ee162d5-198be-fac9290a@10.41.201.250
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.3.1.T
Reason: Q.850;cause=16
Content-Length: 0
Jul 23 11:03:56.195: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:750@fa0/4ip:5060 SIP/2.0
Via: SIP/2.0/TCP 10.41.201.250:5060;branch=z9hG4bK40b5a7c0e11a7
From: <sip:8124XXXXXX@10.41.201.250>;tag=3994346~77bba026-5b79-4e2a-a90d-bffd1d2afd15-26930118
To: <sip:750@fa0/4ip>;tag=26CC00AC-667
Date: Tue, 23 Jul 2013 11:02:45 GMT
Call-ID: 65db7e00-1ee162d5-198be-fac9290a@10.41.201.250
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence, kpml
Content-Length: 0
Thanks in advance !
07-25-2013 02:44 AM
Hi Lan,thanks for your effort and time.
I uploaded the script that you have edited and even used different audio files but i still receiving a distorted sound that doesn't mean anything.I tried to use different codecs but the only one that's gives me at least distorted sound is g711ulaw.
Here is the debug output:
CUBE#
Jul 24 19:55:31.903: //-1//HIFS:/hifs_ifs_cb: hifs ifs file read succeeded. size=8065, url=flash:/its-CISCO.2.0.2.0.tcl
Jul 24 19:55:31.903: //-1//HIFS:/hifs_free_idata: hifs_free_idata: 0x8BD30620
Jul 24 19:55:31.903: //-1//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x8BD30620
Jul 24 19:55:31.903: //-1//AFW_:EE89AA093C000:/Tcl_Link: Linking script autoattend
Jul 24 19:55:31.907: //-1//TCL :EE89AA093C000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 24 19:55:31.911: //-1//AFW_:EE89AA093C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 24 19:55:31.911: //-1//AFW_:EE89AA093C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 19:55:31.911: //-1//PACK:EE89AA093C000:/tcl_ParamReadObjCmd: aa-pilot
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 24 19:55:31.911: //-1//AFW_:EE89AA093C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 24 19:55:31.911: //-1//AFW_:EE89AA093C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 19:55:31.911: //-1//PACK:EE89AA093C000:/tcl_ParamReadObjCmd: operator
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 24 19:55:31.911: //-1//TCL :EE89AA093C000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 24 19:55:31.915: //-1//TCL :EE89AA093C000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 24 19:55:31.915: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 24 19:55:31.915: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 24 19:55:31.915: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 24 19:55:31.915: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 24 19:55:31.915: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 24 19:55:31.915: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 24 19:55:31.915: FSM start state CALL_INIT(1)
CUBE#
Jul 24 19:55:31.915: //-1//AFW_:EE89AA093C000:/Tcl_Link: Script autoattend succesfully linked.
CUBE#
CUBE#
CUBE#
CUBE#
CUBE#
CUBE#
CUBE#
Jul 24 19:55:45.971: //-1//AFW_:EE89AA0A24000:/Tcl_Link: Linking script autoattend
Jul 24 19:55:45.975: //-1//TCL :EE89AA0A24000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 24 19:55:45.975: //-1//TCL :EE89AA0A24000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 24 19:55:45.975: //-1//TCL :EE89AA0A24000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 24 19:55:45.975: //-1//AFW_:EE89AA0A24000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 19:55:45.975: //-1//TCL :EE89AA0A24000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 24 19:55:45.979: //-1//AFW_:EE89AA0A24000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 19:55:45.979: //-1//PACK:EE89AA0A24000:/tcl_ParamReadObjCmd: aa-pilot
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 24 19:55:45.979: //-1//AFW_:EE89AA0A24000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 24 19:55:45.979: //-1//AFW_:EE89AA0A24000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 19:55:45.979: //-1//PACK:EE89AA0A24000:/tcl_ParamReadObjCmd: operator
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 24 19:55:45.979: //-1//TCL :EE89AA0A24000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 24 19:55:45.979: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 24 19:55:45.979: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 24 19:55:45.979: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 24 19:55:45.979: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 24 19:55:45.979: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 24 19:55:45.979: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 24 19:55:45.979: FSM start state CALL_INIT(1)
Jul 24 19:55:45.979: //-1//AFW_:EE89AA0A24000:/Tcl_Link: Script autoattend succesfully linked.
Jul 24 19:55:45.983: //8//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 24 19:55:45.983:
Jul 24 19:55:45.983: //8//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 24 19:55:45.983:
Jul 24 19:55:45.983: //8//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 24 19:55:45.983: //8//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 24 19:55:45.983: //8//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 24 19:55:45.983: //8//TCL :/tcl_PutsObjCmd:
ANI sip:8124XXXXXX@10.41.201.250
Jul 24 19:55:45.983:
Jul 24 19:55:45.983: //8//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 24 19:55:45.983: //8//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 24 19:55:45.983: //8//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 24 19:55:45.983: //8//TCL :/tcl_PutsObjCmd:
DNIS sip:750@fa0/4_public_ip_of cube:5060
Jul 24 19:55:45.983:
Jul 24 19:55:45.983: //8//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 24 19:55:45.983: //8//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 24 19:55:45.983: //8//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 24 19:55:45.987: //8//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 24 19:55:45.987: //8//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 24 19:55:45.987: //8//AFW_:/vtd_lg_incoming: argc 2
Jul 24 19:55:45.987: //8//AFW_:/vtd_lg_incoming: Legs [8 ]
Jul 24 19:55:45.987: //8//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 19:55:45.987: //8//TCL :/tcl_LegObjCmd: leg setup sip:750@fa0/4_public_ip_of cube:5060 callInfo leg_incoming
Jul 24 19:55:45.987: //8//AFW_:/vtd_lg_incoming: argc 4
Jul 24 19:55:45.987: //8//AFW_:/vtd_lg_incoming: Legs [8 ]
Jul 24 19:55:45.987: //8//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 19:55:45.987: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 24 19:55:45.991: //8//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 24 19:55:45.991: //8//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
Jul 24 19:55:46.011: //8//TCL :/tcl_InfotagObjCmd: infotag get evt_status
Jul 24 19:55:46.011: //8//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Jul 24 19:55:46.011: //8//AFW_:/vtr_ev_status: argc 2 argindex 2
Jul 24 19:55:46.011: //8//TCL :/tcl_LegObjCmd: leg proceeding leg_incoming
Jul 24 19:55:46.011: //8//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Jul 24 19:55:46.011: //8//AFW_:/vtd_lg_incoming: argc 2
Jul 24 19:55:46.011: //8//AFW_:/vtd_lg_incoming: Legs [8 ]
Jul 24 19:55:46.011: //8//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 19:55:46.011: //8//TCL :/tcl_LegObjCmd: leg connect leg_incoming
Jul 24 19:55:46.011: //8//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
Jul 24 19:55:46.011: //8//AFW_:/vtd_lg_incoming: argc 2
Jul 24 19:55:46.011: //8//AFW_:/vtd_lg_incoming: Legs [8 ]
Jul 24 19:55:46.011: //8//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 19:55:46.011: //8//TCL :/tcl_PutsObjCmd:
Call failed. Play prompt and collect digit
Jul 24 19:55:46.011:
Jul 24 19:55:46.011: //8//TCL :/tcl_PutsObjCmd:
proc act_Select
Jul 24 19:55:46.011:
Jul 24 19:55:46.011: //8//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1 pattern
Jul 24 19:55:46.011: //8//AFW_:/vtd_lg_incoming: argc 4
Jul 24 19:55:46.011: //8//AFW_:/vtd_lg_incoming: Legs [8 ]
Jul 24 19:55:46.011: //8//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 19:55:46.015: //8//TCL :/tcl_PutsObjCmd:
proc act_DestBusy
Jul 24 19:55:46.015:
CUBE#
Jul 24 19:55:46.015: //8//PACK:/tcl_MediaObjCmd: media play leg_incoming _disconnect.au
Jul 24 19:55:46.015: //8//PACK:/tcl_MediaPlayObjCmd: play leg_incoming _disconnect.au
Jul 24 19:55:46.015: //8//AFW_:/vtd_lg_incoming: argc 3
Jul 24 19:55:46.015: //8//AFW_:/vtd_lg_incoming: Legs [8 ]
Jul 24 19:55:46.015: //8//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 19:55:46.015: //8//PACK:/Media_Play_Start:
Jul 24 19:55:46.015: //8//TCL :/tcl_FSMObjCmd: fsm setstate CALLDISCONNECT
Jul 24 19:55:46.015: //8//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT
Also once i hang up the script disconnect by itself and the phone displays enter number and waiting for more digits.I have to reload the script to hear the distorted sound again and see on the phone's screen (connected )
Thanks in advance
07-25-2013 05:29 AM
Hi Ahmed,
Based on your output, infact , your script has never played "_welcome.au" for you to hear, the call failed immediately as the aa catched the incoming call
"Call failed. Play prompt and collect digit"
Let try the attached script with same parameter. then show me the output of debug
Besides, please let me know what debug command you used to have such above ouput?? (except "debug voice application script")
07-25-2013 07:13 AM
Thank you so much for your help.
unfortunately it didn't work.This is a CUBE (cisco unified border element) may be that is the reason !
here is the version:
Cisco IOS Software, C880 Software (C880VOICE-UNIVERSALK9_NPE-M), Version 15.3(1)T, RELEASE SOFTWARE (fc1)
I am using three commands:
call application voice load autoattend
debug voip application script
debug voip application tcl commands
OUTPUT:
CUBE#
Jul 25 14:00:55.767: //-1//AFW_:EE89AC4FFC000:/Tcl_Link: Linking script autoattend
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 25 14:00:55.775: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 25 14:00:55.775: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair: argc 3 argindex 2
Jul 25 14:00:55.775: //-1//PACK:EE89AC4FFC000:/tcl_ParamReadObjCmd: aa-pilot
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 25 14:00:55.775: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 25 14:00:55.775: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 25 14:00:55.775: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair: argc 3 argindex 2
Jul 25 14:00:55.775: //-1//PACK:EE89AC4FFC000:/tcl_ParamReadObjCmd: operator
Jul 25 14:00:55.779: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists max-extension-length
Jul 25 14:00:55.779: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists max-extension-length
Jul 25 14:00:55.779: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 25 14:00:55.779: //-1//TCL :EE89AC4FFC000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 25 14:00:55.779: //-1//TCL :EE89AC4FFC000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 25 14:00:55.779: //-1//TCL :EE89AC4FFC000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 25 14:00:55.779: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 25 14:00:55.779: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 25 14:00:55.779: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 25 14:00:55.779: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 25 14:00:55.779: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 25 14:00:55.779: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 25 14:00:55.779: FSM start state CALL_INIT(1)
Jul 25 14:00:55.779: //-1//AFW_:EE89AC4FFC000:/Tcl_Link: Script autoattend succesfully linked.
Jul 25 14:00:55.783: //1109//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 25 14:00:55.783:
Jul 25 14:00:55.783: //1109//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 25 14:00:55.783:
Jul 25 14:00:55.783: //1109//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 25 14:00:55.783: //1109//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 25 14:00:55.783: //1109//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 25 14:00:55.783: //1109//TCL :/tcl_PutsObjCmd:
ANI sip:8124XXXXXX@10.41.201.250
Jul 25 14:00:55.783:
Jul 25 14:00:55.783: //1109//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 25 14:00:55.783: //1109//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 25 14:00:55.783: //1109//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 25 14:00:55.783: //1109//TCL :/tcl_PutsObjCmd:
DNIS sip:750@fa0/4_public_ip_of cube:5060
Jul 25 14:00:55.783:
Jul 25 14:00:55.783: //1109//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 25 14:00:55.783: //1109//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 25 14:00:55.783: //1109//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 25 14:00:55.783: //1109//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 25 14:00:55.783: //1109//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 25 14:00:55.783: //1109//AFW_:/vtd_lg_incoming: argc 2
Jul 25 14:00:55.783: //1109//AFW_:/vtd_lg_incoming: Legs [1109 ]
Jul 25 14:00:55.783: //1109//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 14:00:55.783: //1109//TCL :/tcl_LegObjCmd: leg setup sip:750@fa0/4_public_ip_of cube:5060 callInfo leg_incoming
Jul 25 14:00:55.783: //1109//AFW_:/vtd_lg_incoming: argc 4
Jul 25 14:00:55.783: //1109//AFW_:/vtd_lg_incoming: Legs [1109 ]
Jul 25 14:00:55.783: //1109//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 14:00:55.787: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 25 14:00:55.787: //1109//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 25 14:00:55.787: //1109//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
Jul 25 14:00:55.811: //1109//TCL :/tcl_InfotagObjCmd: infotag get evt_status
Jul 25 14:00:55.811: //1109//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Jul 25 14:00:55.811: //1109//AFW_:/vtr_ev_status: argc 2 argindex 2
Jul 25 14:00:55.811: //1109//TCL :/tcl_LegObjCmd: leg proceeding leg_incoming
Jul 25 14:00:55.811: //1109//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Jul 25 14:00:55.811: //1109//AFW_:/vtd_lg_incoming: argc 2
Jul 25 14:00:55.811: //1109//AFW_:/vtd_lg_incoming: Legs [1109 ]
Jul 25 14:00:55.811: //1109//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 14:00:55.811: //1109//TCL :/tcl_LegObjCmd: leg connect leg_incoming
Jul 25 14:00:55.811: //1109//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
Jul 25 14:00:55.811: //1109//AFW_:/vtd_lg_incoming: argc 2
Jul 25 14:00:55.811: //1109//AFW_:/vtd_lg_incoming: Legs [1109 ]
Jul 25 14:00:55.811: //1109//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 14:00:55.811: //1109//TCL :/tcl_PutsObjCmd:
Call failed. Play prompt and collect digit
Jul 25 14:00:55.811:
Jul 25 14:00:55.811: //1109//TCL :/tcl_PutsObjCmd:
proc act_Select
Jul 25 14:00:55.811:
Jul 25 14:00:55.811: //1109//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1
Jul 25 14:00:55.811: //1109//AFW_:/vtd_lg_incoming: argc 3
Jul 25 14:00:55.811: //1109//AFW_:/vtd_lg_incoming: Legs [1109 ]
Jul 25 14:00:55.811: //1109//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 14:00:55.811: //1109//TCL :/tcl_PutsObjCmd:
proc act_DestBusy
Jul 25 14:00:55.811:
Jul 25 14:00:55.815: //1109//PACK:/tcl_MediaObjCmd: media play leg_incoming _disconnect.au
Jul 25 14:00:55.815: //1109//PACK:/tcl_MediaPlayObjCmd: play leg_incoming _disconnect.au
Jul 25 14:00:55.815: //1109//AFW_:/vtd_lg_incoming: argc 3
CUBE#
Jul 25 14:00:55.815: //1109//AFW_:/vtd_lg_incoming: Legs [1109 ]
Jul 25 14:00:55.815: //1109//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 14:00:55.815: //1109//PACK:/Media_Play_Start:
Jul 25 14:00:55.815: //1109//TCL :/tcl_FSMObjCmd: fsm setstate CALLDISCONNECT
Jul 25 14:00:55.815: //1109//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT
Jul 25 14:01:14.719: //1109//TCL :/tcl_CallObjCmd: call close
Jul 25 14:01:14.719: //1109//TCL :/tcl_CallCloseObjCmd: close
Thanks for help.
07-25-2013 08:34 AM
Hi Ahmed,
The weird thing here is the script behaves as if it received an invalid DNIS information very soon and resulted in call fail.
I customized the script that allow to re-enter the extension number after the 1st attempt to put ext fail, and the expected audio is "... the number you called is unreachable.." and prompt you to input ext.
Otherwise, please take the output of "debug voice dial-peer inout"
07-25-2013 10:29 AM
Hi Lan,nothing the same destorted sound just this time to stop this sound i started to dial internal number 101,this time the script tokk from me the three digits not 2 digits like before but the phone shows connected on the screen while i waited about 5 minutes but didn't hear the i connected to 101.
Here are the same debugs like before plus debug voip dialpeer inout:
ul 25 17:09:38.318: //-1//AFW_:EE89AC46EC000:/Tcl_Link: Linking script autoattend
Jul 25 17:09:38.322: //-1//TCL :EE89AC46EC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 25 17:09:38.322: //-1//TCL :EE89AC46EC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 25 17:09:38.322: //-1//AFW_:EE89AC46EC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 25 17:09:38.322: //-1//TCL :EE89AC46EC000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 25 17:09:38.326: //-1//AFW_:EE89AC46EC000:/vtr_cf_avpair: argc 3 argindex 2
Jul 25 17:09:38.326: //-1//PACK:EE89AC46EC000:/tcl_ParamReadObjCmd: aa-pilot
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 25 17:09:38.326: //-1//AFW_:EE89AC46EC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 25 17:09:38.326: //-1//AFW_:EE89AC46EC000:/vtr_cf_avpair: argc 3 argindex 2
Jul 25 17:09:38.326: //-1//PACK:EE89AC46EC000:/tcl_ParamReadObjCmd: operator
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists max-extension-length
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists max-extension-length
Jul 25 17:09:38.326: //-1//AFW_:EE89AC46EC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 25 17:09:38.326: //-1//TCL :EE89AC46EC000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 25 17:09:38.326: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 25 17:09:38.326: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 25 17:09:38.326: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 25 17:09:38.326: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 25 17:09:38.326: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 25 17:09:38.326: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 25 17:09:38.326: FSM start state CALL_INIT(1)
Jul 25 17:09:38.326: //-1//AFW_:EE89AC46EC000:/Tcl_Link: Script autoattend succesfully linked.
Jul 25 17:09:38.330: //1303//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 25 17:09:38.330:
Jul 25 17:09:38.330: //1303//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 25 17:09:38.330:
Jul 25 17:09:38.330: //1303//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 25 17:09:38.330: //1303//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 25 17:09:38.330: //1303//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 25 17:09:38.330: //1303//TCL :/tcl_PutsObjCmd:
ANI sip:8124XXXXXX@10.41.201.250
Jul 25 17:09:38.330:
Jul 25 17:09:38.330: //1303//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 25 17:09:38.330: //1303//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 25 17:09:38.330: //1303//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 25 17:09:38.330: //1303//TCL :/tcl_PutsObjCmd:
DNIS sip:750@fa0/4_public_ip_of cube::5060
Jul 25 17:09:38.330:
Jul 25 17:09:38.334: //1303//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 25 17:09:38.334: //1303//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 25 17:09:38.334: //1303//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 25 17:09:38.334: //1303//TCL :/tcl_PutsObjCmd: act_Setup
Jul 25 17:09:38.334:
Jul 25 17:09:38.334: //1303//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 25 17:09:38.334: //1303//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 25 17:09:38.334: //1303//AFW_:/vtd_lg_incoming: argc 2
Jul 25 17:09:38.334: //1303//AFW_:/vtd_lg_incoming: Legs [1303 ]
Jul 25 17:09:38.334: //1303//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 17:09:38.334: //1303//TCL :/tcl_LegObjCmd: leg setup sip:750@fa0/4_public_ip_of cube::5060 callInfo leg_incoming
Jul 25 17:09:38.334: //1303//AFW_:/vtd_lg_incoming: argc 4
Jul 25 17:09:38.334: //1303//AFW_:/vtd_lg_incoming: Legs [1303 ]
Jul 25 17:09:38.334: //1303//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 17:09:38.334: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 25 17:09:38.338: //1303//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 25 17:09:38.338: //1303//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
Jul 25 17:09:38.358: //1303//TCL :/tcl_InfotagObjCmd: infotag get evt_status
Jul 25 17:09:38.358: //1303//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Jul 25 17:09:38.358: //1303//AFW_:/vtr_ev_status: argc 2 argindex 2
Jul 25 17:09:38.358: //1303//TCL :/tcl_LegObjCmd: leg proceeding leg_incoming
Jul 25 17:09:38.358: //1303//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Jul 25 17:09:38.358: //1303//AFW_:/vtd_lg_incoming: argc 2
Jul 25 17:09:38.358: //1303//AFW_:/vtd_lg_incoming: Legs [1303 ]
Jul 25 17:09:38.358: //1303//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 17:09:38.358: //1303//TCL :/tcl_LegObjCmd: leg connect leg_incoming
Jul 25 17:09:38.358: //1303//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
Jul 25 17:09:38.362: //1303//AFW_:/vtd_lg_incoming: argc 2
Jul 25 17:09:38.362: //1303//AFW_:/vtd_lg_incoming: Legs [1303 ]
Jul 25 17:09:38.362: //1303//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 17:09:38.362: //1303//TCL :/tcl_PutsObjCmd:
Call failed. Play prompt and collect digit
Jul 25 17:09:38.362:
Jul 25 17:09:38.362: //1303//TCL :/tcl_PutsObjCmd: End user busy or absent
Jul 25 17:09:38.362:
Jul 25 17:09:38.362: //1303//TCL :/tcl_PutsObjCmd:
proc act_Select
Jul 25 17:09:38.362:
Jul 25 17:09:38.362: //1303//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1
Jul 25 17:09:38.362: //1303//AFW_:/vtd_lg_incoming: argc 3
Jul 25 17:09:38.362: //1303//AFW_:/vtd_lg_incoming: Legs [1303 ]
Jul 25 17:09:38.362: //1303//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 17:09:38.362: //1303//TCL :/tcl_PutsObjCmd: Over ReTry
Jul 25 17:09:38.362:
Jul 25 17:09:38.362: //1303//TCL :/tcl_PutsObjCmd:
proc act_DestBusy
Jul 25 17:09:38.362:
Jul 25 17:09:38.362: //1303//PACK:/tcl_MediaObjCmd: media play leg_incoming _disconnect.au
Jul 25 17:09:38.362: //1303//PACK:/tcl_MediaPlayObjCmd: play
CUBE# leg_incoming _disconnect.au
Jul 25 17:09:38.362: //1303//AFW_:/vtd_lg_incoming: argc 3
Jul 25 17:09:38.362: //1303//AFW_:/vtd_lg_incoming: Legs [1303 ]
Jul 25 17:09:38.362: //1303//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 25 17:09:38.362: //1303//PACK:/Media_Play_Start:
Jul 25 17:09:38.366: //1303//TCL :/tcl_FSMObjCmd: fsm setstate CALLDISCONNECT
Jul 25 17:09:38.366: //1303//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT
CUBE#debug voip dialpeer inout
voip dialpeer inout debugging is on
Jul 25 17:10:04.502: //-1/FB743A800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=1, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 25 17:10:04.502: //-1/FB743A800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=1
Jul 25 17:10:04.502: //-1/FB743A800000/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
Jul 25 17:10:04.502: //-1/FB743A800000/DPM/dpMatchSafModulePlugin:
dialstring=1, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
Jul 25 17:10:04.502: //-1/FB743A800000/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
Jul 25 17:10:04.814: //-1/FB743A800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=10, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 25 17:10:04.814: //-1/FB743A800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=10
Jul 25 17:10:04.814: //-1/FB743A800000/DPM/dpMatchPeersCore:
Result=Partial Matches(1) after DP_MATCH_DEST
Jul 25 17:10:04.814: //-1/FB743A800000/DPM/dpMatchSafModulePlugin:
dialstring=10, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
Jul 25 17:10:04.814: //-1/FB743A800000/DPM/dpMatchPeersMoreArg:
Result=MORE_DIGITS_NEEDED(1)
Jul 25 17:10:05.218: //-1/FB743A800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=101, Peer Info Type=DIALPEER_INFO_SPEECH
CUBE#
Jul 25 17:10:05.218: //-1/FB743A800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=101
Jul 25 17:10:05.218: //-1/FB743A800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 25 17:10:05.218: //-1/FB743A800000/DPM/dpMatchSafModulePlugin:
dialstring=101, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
Jul 25 17:10:05.218: //-1/FB743A800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=2
CUBE#
Jul 25 17:10:15.946: //-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
Jul 25 17:10:15.946: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:10:15.946: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 25 17:10:15.950: //-1/E8E0AA7B853E/DPM/dpAssociateIncomingPeerCore:
CUBE#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
Jul 25 17:10:15.950: //-1/E8E0AA7B853E/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:10:15.950: //-1/E8E0AA7B853E/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
CUBE#
Jul 25 17:11:16.018: //-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
Jul 25 17:11:16.018: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:11:16.018: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 25 17:11:16.018: //-1/0CAEEE80853F/DPM/dpAssociateIncomingPeerCore:
CUBE#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
Jul 25 17:11:16.018: //-1/0CAEEE80853F/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:11:16.018: //-1/0CAEEE80853F/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
CUBE#
Jul 25 17:12:16.266: //-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
Jul 25 17:12:16.266: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:12:16.266: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 25 17:12:16.266: //-1/30980B8B8540/DPM/dpAssociateIncomingPeerCore:
CUBE#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
Jul 25 17:12:16.266: //-1/30980B8B8540/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:12:16.266: //-1/30980B8B8540/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
CUBE#
Jul 25 17:13:17.698: //-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
Jul 25 17:13:17.698: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:13:17.698: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 25 17:13:17.698: //-1/5535D1D78541/DPM/dpAssociateIncomingPeerCore:
CUBE#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
Jul 25 17:13:17.698: //-1/5535D1D78541/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:13:17.698: //-1/5535D1D78541/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 25 17:14:19.414: //-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
Jul 25 17:14:19.414: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:14:19.414: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 25 17:14:19.414: //-1/79FE530C8542/DPM/dpAssociateIncomingPeerCore:
CUBE#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
Jul 25 17:14:19.414: //-1/79FE530C8542/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 25 17:14:19.414: //-1/79FE530C8542/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
CUBE#
Jul 25 17:14:43.766: //1303//TCL :/tcl_CallObjCmd: call close
Jul 25 17:14:43.766: //1303//TCL :/tcl_CallCloseObjCmd: close
Thank you so much.
07-25-2013 08:33 PM
Hi Ahmed,
Beside config (As Mr Carlo mentioned), could you include network diagram too?
07-25-2013 11:48 PM
Hi Lan,
don't have the digram now but this client has simple design.
2 access switches ,1 distribution switch,business edition 3000 which has e1 and a sip trunk to the cisco unified border element.
07-25-2013 11:22 AM
Hi Ahmed.
Can you please share you entire config?
Thanks
Carlo
Sent from Cisco Technical Support iPhone App
07-25-2013 11:46 PM
Hi Carlo,here is the configuration :
CUBE#show run
Building configuration...
Current configuration : 9465 bytes
!
! Last configuration change at 21:08:55 SPB Thu Jul 25 2013 by admin
version 15.3
no service pad
service timestamps debug datetime msec
service timestamps log datetime msec
service password-encryption
!
hostname CUBE
!
boot-start-marker
boot-end-marker
!
!
logging buffered 51200 warnings
logging persistent filesize 100000
enable secret 4 UNqF18mxCS01YvOWxkp0dshs5kkLsiSJHfcktf9/Sqw
!
no aaa new-model
clock timezone SPB 4 0
!
!
!
ip dhcp excluded-address 10.41.201.245 10.41.201.254
!
ip dhcp pool VOICE
network 10.41.201.0 255.255.255.0
option 150 ip 10.41.201.250
default-router 10.41.201.252
dns-server 8.8.8.8 8.8.4.4
lease 3
!
!
!
no ip domain lookup
ip domain name sgsvostok.ru
ip inspect max-incomplete high 50000
ip inspect max-incomplete low 45000
ip inspect one-minute low 4500
ip inspect one-minute high 5000
ip inspect udp idle-time 25
ip inspect dns-timeout 7
ip inspect tcp idle-time 21600
ip inspect tcp finwait-time 7
ip inspect tcp synwait-time 15
ip inspect tcp block-non-session
ip inspect tcp max-incomplete host 150 block-time 3
ip inspect name IPFW tcp alert on audit-trail off router-traffic
ip inspect name IPFW udp alert on audit-trail off router-traffic
ip inspect name IPFW icmp router-traffic
ip inspect name IPFW http
ip inspect name IPFW ftp
ip inspect name IPFW ssh
ip inspect name IPFW dns
ip inspect name IPFW pop3
ip inspect name IPFW imap
ip inspect name IPFW telnet
ip inspect name IPFW microsoft-ds
ip inspect name IPFW netbios-dgm
ip inspect name IPFW netbios-ns
ip inspect name IPFW netbios-ssn
ip inspect name IPFW router
ip inspect name IPFW fragment maximum 10000 timeout 1
ip inspect name IPFW icabrowser
ip inspect name IPFW citrix
ip inspect name IPFW citriximaclient
ip inspect name IPFW ntp
ip inspect name IPFW pptp
ip inspect name IPFW https
ip inspect name IPFW dhcp-failover
ip inspect name IPFW smtp
ip inspect name IPFW sip
ip inspect name IPFW h323
ip cef
no ipv6 cef
ipv6 multicast rpf use-bgp
!
!
!
!
!
multilink bundle-name authenticated
vpdn enable
!
vpdn-group 1
! Default PPTP VPDN group
accept-dialin
protocol pptp
virtual-template 1
!
!
!
!
voice service voip
address-hiding
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
supplementary-service h450.12
redirect ip2ip
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
h323
emptycapability
sip
bind control source-interface FastEthernet4
bind media source-interface FastEthernet4
header-passing
registrar server
early-offer forced
midcall-signaling passthru
!
voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g711alaw
codec preference 3 g729r8
codec preference 4 g729br8
!
!
!
!
!
!
application
service autoattend flash:/Customized-Script.tcl
paramspace english index 0
paramspace english language en
paramspace english location flash:/
param aa-pilot 750
paramspace english prefix en
param operator 100
!
global
service alternate default
!
!
!
crypto pki trustpoint TP-self-signed-884837505
enrollment selfsigned
subject-name cn=IOS-Self-Signed-Certificate-884837505
revocation-check none
rsakeypair TP-self-signed-884837505
!
!
crypto pki certificate chain TP-self-signed-884837505
certificate self-signed 01
30820229 30820192 A0030201 02020101 300D0609 2A864886 F70D0101 05050030
3......................................
quit
license udi pid C881-CUBE-..................
!
!
username admin privilege 15 secret .......................
username vpnx password 7 ............................
!
!
csdb tcp synwait-time 30
csdb tcp idle-time 3600
csdb tcp finwait-time 5
csdb tcp reassembly max-memory 1024
csdb tcp reassembly max-queue-length 16
csdb udp idle-time 30
csdb icmp idle-time 10
csdb session max-session 65535
!
!
!
!
!
interface Loopback10
ip address 10.41.203.252 255.255.255.0
!
interface FastEthernet0
switchport mode trunk
no ip address
!
interface FastEthernet1
switchport access vlan 2
no ip address
!
interface FastEthernet2
switchport access vlan 2
no ip address
!
interface FastEthernet3
switchport access vlan 2
no ip address
!
interface FastEthernet4
ip address 62.y.y.y 255.255.255.192
ip access-group INCOMING in
no ip unreachables
ip nat outside
ip inspect IPFW in
ip inspect IPFW out
ip virtual-reassembly in
duplex auto
speed auto
no cdp enable
!
interface Virtual-Template1
ip unnumbered Vlan2
ip nat inside
ip virtual-reassembly in
peer default ip address pool PPTP-Pool
no keepalive
ppp authentication ms-chap ms-chap-v2
!
interface Vlan1
ip address 10.41.5.18 255.255.255.0
!
interface Vlan2
ip address 10.41.201.252 255.255.255.0
ip nat inside
ip virtual-reassembly in
!
ip local pool PPTP-Pool 172.16.231.2 172.16.231.50
ip forward-protocol nd
ip http server
ip http access-class 23
ip http authentication local
ip http secure-server
ip http timeout-policy idle 60 life 86400 requests 10000
!
!
ip nat inside source list NAT interface FastEthernet4 overload
ip nat inside source static tcp 10.41.201.250 443 62.y.y.y 443 extendable
ip nat inside source static tcp 10.41.201.250 443 62.y.y.y 10443 extendable
ip route 0.0.0.0 0.0.0.0 62.X.X.X
ip route 10.0.0.0 255.0.0.0 10.41.201.247
ip route 172.16.0.0 255.240.0.0 10.41.201.247
ip route 192.168.0.0 255.255.0.0 10.41.201.247
!
ip access-list extended INCOMING
permit icmp any any
permit tcp any any eq 22
permit tcp any any eq 1723
permit gre any any
deny ip any any log
ip access-list extended NAT
permit ip 10.41.201.0 0.0.0.255 any
permit ip 172.16.231.0 0.0.0.255 any
!
logging trap debugging
!
!
control-plane
!
!
!
mgcp behavior rsip-range tgcp-only
mgcp behavior comedia-role none
mgcp behavior comedia-check-media-src disable
mgcp behavior comedia-sdp-force disable
!
mgcp profile default
!
!
dial-peer voice 1 voip
incoming called-number .T
!
dial-peer voice 2 voip
destination-pattern [123456]..
session protocol sipv2
session target ipv4:10.41.201.250
voice-class codec 1
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
!
dial-peer voice 3 voip
description SIP CITY
destination-pattern 812.......
session protocol sipv2
session target ipv4:62.X.X.X
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
dial-peer voice 4 voip
description SIP CITY 7
destination-pattern .......
session protocol sipv2
session target ipv4:62.X.X.X
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
dial-peer voice 5 voip
description LONG DISTANCE
destination-pattern ..........
session protocol sipv2
session target ipv4:62.X.X.X
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
dial-peer voice 6 voip
description SIP Mobile
destination-pattern 9.........
session protocol sipv2
session target ipv4:62.X.X.X
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
dial-peer voice 7 voip
description SIP INTERNATIONAL
destination-pattern 810T
session protocol sipv2
session target ipv4:62.X.X.X
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
dial-peer voice 8 voip
description EMERGENCY
destination-pattern 0.
session protocol sipv2
session target ipv4:62.X.X.X
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
dial-peer voice 9 voip
description TIME
destination-pattern 060
session protocol sipv2
session target ipv4:62.X.X.X (Sip provider)
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
dial-peer voice 750 voip
service autoattend
destination-pattern 750
session protocol sipv2
session target ipv4:10.41.201.250 ( ip address of bussiness edition 3000 )
incoming called-number 750
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
!
!
sip-ua
sip-server ipv4:62.X.X.X
!
!
line con 0
password 7
login
no modem enable
line aux 0
line vty 0 4
privilege level 15
password 7
logging synchronous
login local
transport input telnet ssh
!
ntp master 3
!
end
07-26-2013 06:10 AM
I am sad to say that you have made totally wrong configuration.
Let check:
dial-peer voice 750 voip
service autoattend
destination-pattern 750
session protocol sipv2
session target ipv4:10.41.201.250 ( ip address of bussiness edition 3000 )
incoming called-number 750
dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
I suppose AA TCL and audio file have been stored in CUBE right??
why your dial-peer point to another ip add other than CUBE??
Please "google" for guide to set up AA TCL
07-27-2013 04:04 AM
Hi Lan,you misread my main post ,first the inbound dial peer is the important not the session target here. what i wrote in main post :
(
when i try to dial 750 i hear nothing.As i understand service should be invoked on the inbound dial-peer,i configured destination pattern with the same aa pilot and session target with the ip address of FA0/4 (public ip address of cube) ,tried a loopback address and default gateway ip address of BE 3000 which is an ip address also in CUBE ,but i never hear anything.In my understa
And to prove to you, here are all the kind of configurations:
Just notice also to hear the distorted sound and to see that i am connected word on phone' s screen,i have to reaload the script.Otherwise i see that the script is envolved but i see the wored enter number like the phone is waiting more digits.
CUBE(config)#do dir flash:
Directory of flash:/
1 -rw- 45064548 Mar 1 1984 04:01:00 +04:00 c880voice-universalk9_npe-mz.153-1.T.bin
2 -rw- 3071 Apr 13 2013 00:47:54 +04:00 cpconfig-8xx.cfg
3 -rw- 720 May 25 2013 22:00:58 +04:00 vlan.dat
4 drw- 0 Apr 13 2013 00:48:10 +04:00 ccpexp
240 -rw- 2464 Apr 13 2013 00:49:56 +04:00 home.shtml
241 drw- 0 May 27 2013 10:09:14 +04:00 syslog
246 -rw- 75650 Jul 25 2013 21:03:38 +04:00 en_dest_busy.au
247 -rw- 37952 Jul 25 2013 21:03:54 +04:00 en_dest_unreachable.au
248 -rw- 83291 Jul 25 2013 21:04:38 +04:00 en_disconnect.au
249 -rw- 63055 Jul 25 2013 21:04:56 +04:00 en_enter_dest.au
250 -rw- 496521 Jul 25 2013 21:05:42 +04:00 en_music_on_hold.au
251 -rw- 63055 Jul 25 2013 21:06:24 +04:00 en_reenter_dest.au
252 -rw- 71629 Jul 25 2013 21:06:56 +04:00 en_welcome.au
253 -rw- 8073 Jul 25 2013 12:51:18 +04:00 its-CISCO.2.0.2.0.tcl
254 -rw- 8185 Jul 25 2013 17:55:20 +04:00 its-CISCO.2.0.3.0.tcl
255 -rw- 7996 Jul 25 2013 21:03:18 +04:00 Customized-Script.tc
USING LOOPBACK OF CUBE: Hear nothing
CUBE(config)#do show run | s application|dial-peer voice 750
application
service autoattend flash:/its-CISCO.2.0.2.0.tcl
paramspace english index 0
paramspace english language en
paramspace english location flash:/
param aa-pilot 750
paramspace english prefix en
param operator 100
!
global
service alternate default
!
dial-peer voice 750 voip
service autoattend
destination-pattern 750
session target ipv4:10.41.203.252 loopback address on cube
incoming called-number 750
dtmf-relay h245-signal h245-alphanumeric
codec g711ulaw
CUBE(config)#do call application voice load autoattend
CUBE(config)#
Jul 27 10:02:01.940: //-1//HIFS:/hifs_ifs_cb: hifs ifs file read succeeded. size=8073, url=flash:/its-CISCO.2.0.2.0.tcl
Jul 27 10:02:01.940: //-1//HIFS:/hifs_free_idata: hifs_free_idata: 0x8BD63470
Jul 27 10:02:01.940: //-1//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x8BD63470
Jul 27 10:02:01.940: //-1//AFW_:EE89AC4A8C000:/Tcl_Link: Linking script autoattend
Jul 27 10:02:01.944: //-1//TCL :EE89AC4A8C000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:02:01.944: //-1//TCL :EE89AC4A8C000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:02:01.944: //-1//TCL :EE89AC4A8C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:02:01.944: //-1//AFW_:EE89AC4A8C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:02:01.944: //-1//TCL :EE89AC4A8C000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:02:01.944: //-1//TCL :EE89AC4A8C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:02:01.948: //-1//AFW_:EE89AC4A8C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:02:01.948: //-1//PACK:EE89AC4A8C000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:02:01.948: //-1//TCL :EE89AC4A8C000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:02:01.948: //-1//TCL :EE89AC4A8C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:02:01.948: //-1//AFW_:EE89AC4A8C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:02:01.948: //-1//TCL :EE89AC4A8C000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:02:01.948: //-1//TCL :EE89AC4A8C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:02:01.948: //-1//AFW_:EE89AC4A8C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:02:01.948: //-1//PACK:EE89AC4A8C000:/tcl_ParamReadObjCmd: operator
Jul 27 10:02:01.948: //-1//TCL :EE89AC4A8C000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:02:01.948: //-1//TCL :EE89AC4A8C000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:02:01.948: //-1//TCL :EE89AC4A8C000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:02:01.948: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:01.948: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:02:01.948: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:01.948: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:01.948: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:02:01.948: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:02:01.948: FSM start state CALL_INIT(1)
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
Jul 27 10:02:01.948: //-1//AFW_:EE89AC4A8C000:/Tcl_Link: Script autoattend succesfully linked.
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=750, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:02:14.408: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:02:14.412: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.412: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:02:14.412: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:02:14.412: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.412: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:02:14.412: //-1/9B43DC800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:02:14.416: //-1//AFW_:EE89AC4B74000:/Tcl_Link: Linking script autoattend
Jul 27 10:02:14.420: //-1//TCL :EE89AC4B74000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:02:14.424: //-1//AFW_:EE89AC4B74000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:02:14.424: //-1//AFW_:EE89AC4B74000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:02:14.424: //-1//PACK:EE89AC4B74000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:02:14.424: //-1//AFW_:EE89AC4B74000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:02:14.424: //-1//AFW_:EE89AC4B74000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:02:14.424: //-1//PACK:EE89AC4B74000:/tcl_ParamReadObjCmd: operator
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:02:14.424: //-1//TCL :EE89AC4B74000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:02:14.428: //-1//TCL :EE89AC4B74000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:02:14.428: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:14.428: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:02:14.428: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:14.428: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:14.428: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:02:14.428: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:02:14.428: FSM start state CALL_INIT(1)
Jul 27 10:02:14.428: //-1//AFW_:EE89AC4B74000:/Tcl_Link: Script autoattend succesfully linked.
Jul 27 10:02:14.428: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.428: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:02:14.428: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:02:14.428: //3723//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 27 10:02:14.428:
Jul 27 10:02:14.432: //3723//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 27 10:02:14.432:
Jul 27 10:02:14.432: //3723//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 27 10:02:14.432: //3723//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 27 10:02:14.432: //3723//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 27 10:02:14.432: //3723//TCL :/tcl_PutsObjCmd:
ANI sip:812XXXXXXX@10.41.201.250
Jul 27 10:02:14.432:
Jul 27 10:02:14.432: //3723//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 27 10:02:14.432: //3723//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 27 10:02:14.432: //3723//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 27 10:02:14.432: //3723//TCL :/tcl_PutsObjCmd:
DNIS sip:750@62.X.X.X:5060
Jul 27 10:02:14.432:
Jul 27 10:02:14.432: //3723//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 27 10:02:14.432: //3723//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 27 10:02:14.432: //3723//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 27 10:02:14.432: //3723//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 27 10:02:14.432: //3723//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 27 10:02:14.432: //3723//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:02:14.432: //3723//AFW_:/vtd_lg_incoming: Legs [3723 ]
Jul 27 10:02:14.432: //3723//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:02:14.432: //3723//TCL :/tcl_LegObjCmd: leg setup sip:750@62.X.X.X:5060 callInfo leg_incoming
Jul 27 10:02:14.432: //3723//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:02:14.432: //3723//AFW_:/vtd_lg_incoming: Legs [3723 ]
Jul 27 10:02:14.432: //3723//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:02:14.432: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 27 10:02:14.432: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.436: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:02:14.436: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:02:14.436: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Jul 27 10:02:14.436: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:02:14.436: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.436: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST_URI; URI=sip:750@62.X.X.X:5060
Jul 27 10:02:14.436: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:02:14.436: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:02:14.436: //-1/9B43DC800000/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:02:14.436: //-1/9B43DC800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:02:14.436: //3723//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 27 10:02:14.436: //3723//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
Jul 27 10:02:14.444: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.444: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:02:14.444: //-1/9B43DC800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:02:14.444: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.444: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:02:14.444: //-1/9B43DC800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:02:14.448: //-1//AFW_:EE89AC4D44000:/Tcl_Link: Linking script autoattend
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:02:14.452: //-1//AFW_:EE89AC4D44000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:02:14.452: //-1//AFW_:EE89AC4D44000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:02:14.452: //-1//PACK:EE89AC4D44000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:02:14.452: //-1//AFW_:EE89AC4D44000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:02:14.452: //-1//TCL :EE89AC4D44000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:02:14.456: //-1//AFW_:EE89AC4D44000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:02:14.456: //-1//PACK:EE89AC4D44000:/tcl_ParamReadObjCmd: operator
Jul 27 10:02:14.456: //-1//TCL :EE89AC4D44000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:02:14.456: //-1//TCL :EE89AC4D44000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:02:14.456: //-1//TCL :EE89AC4D44000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:02:14.456: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:14.456: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:02:14.456: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:14.456: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:02:14.456: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:02:14.456: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:02:14.456: FSM start state CALL_INIT(1)
Jul 27 10:02:14.456: //-1//AFW_:EE89AC4D44000:/Tcl_Link: Script autoattend succesfully linked.
Jul 27 10:02:14.456: //3725//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 27 10:02:14.456:
Jul 27 10:02:14.456: //3725//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 27 10:02:14.456:
Jul 27 10:02:14.456: //3725//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 27 10:02:14.456: //3725//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 27 10:02:14.456: //3725//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 27 10:02:14.460: //3725//TCL :/tcl_PutsObjCmd:
ANI 8124990466
Jul 27 10:02:14.460:
Jul 27 10:02:14.460: //3725//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 27 10:02:14.460: //3725//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 27 10:02:14.460: //3725//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 27 10:02:14.460: //3725//TCL :/tcl_PutsObjCmd:
DNIS sip:750@62.X.X.X5060
Jul 27 10:02:14.460:
Jul 27 10:02:14.460: //3725//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 27 10:02:14.460: //3725//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 27 10:02:14.460: //3725//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 27 10:02:14.460: //3725//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 27 10:02:14.460: //3725//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 27 10:02:14.460: //3725//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:02:14.460: //3725//AFW_:/vtd_lg_incoming: Legs [3725 ]
Jul 27 10:02:14.460: //3725//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:02:14.460: //3725//TCL :/tcl_LegObjCmd: leg setup sip:750@62.X.X.X:5060 callInfo leg_incoming
Jul 27 10:02:14.460: //3725//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:02:14.460: //3725//AFW_:/vtd_lg_incoming: Legs [3725 ]
Jul 27 10:02:14.460: //3725//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:02:14.460: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 27 10:02:14.460: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.460: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:02:14.460: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:02:14.460: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Jul 27 10:02:14.460: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:02:14.464: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.464: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST_URI; URI=sip:750@62.X.X.X:5060
Jul 27 10:02:14.464: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:02:14.464: //-1/9B43DC800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:02:14.464: //-1/9B43DC800000/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:02:14.464: //-1/9B43DC800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:02:14.464: //3725//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 27 10:02:14.464: //3725//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
Jul 27 10:02:14.472: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.472: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:02:14.472: //-1/9B43DC800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:02:14.472: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:02:14.472: //-1/9B43DC800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:02:14.472: //-1/9B43DC800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
CUBE(config)#
Jul 27 10:02:14.472: %CALL_CONTROL-6-CALL_LOOP: The incoming call has a global identifier already present in the list of currently handled calls. It is being refused.
CUBE(config)#
Jul 27 10:02:21.528: //-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
Jul 27 10:02:21.528: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 27 10:02:21.528: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 27 10:02:21.528: //-1/768EDCDD8EB7/DPM/dpAssociateIncomingPeerCore:
CUBE(config)#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
Jul 27 10:02:21.528: //-1/768EDCDD8EB7/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 27 10:02:21.528: //-1/768EDCDD8EB7/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
CUBE(config)#
Jul 27 10:02:29.472: //3725//TCL :/tcl_InfotagObjCmd: infotag get evt_status
Jul 27 10:02:29.472: //3725//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Jul 27 10:02:29.472: //3725//AFW_:/vtr_ev_status: argc 2 argindex 2
Jul 27 10:02:29.472: //3725//TCL :/tcl_LegObjCmd: leg proceeding leg_incoming
Jul 27 10:02:29.472: //3725//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Jul 27 10:02:29.472: //3725//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:02:29.472: //3725//AFW_:/vtd_lg_incoming: Legs [3725 ]
Jul 27 10:02:29.472: //3725//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:02:29.472: //3725//TCL :/tcl_LegObjCmd: leg connect leg_incoming
Jul 27 10:02:29.472: //3725//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
Jul 27 10:02:29.472: //3725//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:02:29.472: //3725//AFW_:/vtd_lg_incoming: Legs [3725 ]
Jul 27 10:02:29.472: //3725//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:02:29.472: //3725//TCL :/tcl_PutsObjCmd:
Call failed. Play prompt and collect digit
Jul 27 10:02:29.472:
Jul 27 10:02:29.472: //3725//TCL :/tcl_PutsObjCmd:
proc act_Select
Jul 27 10:02:29.472:
Jul 27 10:02:29.472: //3725//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1 pattern
Jul 27 10:02:29.472: //3725//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:02:29.472: //3725//AFW_:/vtd_lg_incoming: Legs [3725 ]
Jul 27 10:02:29.472: //3725//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:02:29.476: //3725//TCL :/tcl_PutsObjCmd:
proc act_DestBusy
Jul 27 10:02:29.476:
Jul 27 10:02:29.476: //3725//PACK:/tcl_MediaObjCmd: media play leg_incoming _disconnect.au
Jul 27 10:02:29.476: //3725//PACK:/tcl_MediaPlayObjCmd: play leg_incoming _disconnect.au
Jul 27 10:02:29.476: //3725//AFW_:/vtd_lg_incoming: argc 3
Jul 27 10:02:29.476: //3725//AFW_:/vtd_lg_incoming: Legs [3725 ]
Jul 27 10:02:29.476: //3725//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
CUBE(config)#
Jul 27 10:02:29.476: //3725//PACK:/Media_Play_Start:
Jul 27 10:02:29.476: //3725//TCL :/tcl_FSMObjCmd: fsm setstate CALLDISCONNECT
Jul 27 10:02:29.476: //3725//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT
CUBE(config)#
Jul 27 10:02:34.988: //3723//TCL :/tcl_CallObjCmd: call close
Jul 27 10:02:34.988: //3723//TCL :/tcl_CallCloseObjCmd: close
Jul 27 10:02:34.996: //3725//TCL :/tcl_CallObjCmd: call close
Jul 27 10:02:34.996: //3725//TCL :/tcl_CallCloseObjCmd: close
***********************************************************************************************************************************************************************************
application
service autoattend flash:/its-CISCO.2.0.2.0.tcl
paramspace english index 0
paramspace english language en
paramspace english location flash:/
param aa-pilot 750
paramspace english prefix en
param operator 100
!
global
service alternate default
!
dial-peer voice 750 voip
service autoattend
destination-pattern 750
session target ipv4:62.X.X.X (FA0/4 Public ip address on cube :62.X.X.X)
incoming called-number 750
dtmf-relay h245-signal h245-alphanumeric
codec g711ulaw
Using The FA0/4 Public ip address on cube :62.X.X.X
CUBE#call application voice load autoattend
CUBE#
Jul 27 10:08:03.572: //-1//HIFS:/hifs_ifs_cb: hifs ifs file read succeeded. size=8073, url=flash:/its-CISCO.2.0.2.0.tcl
Jul 27 10:08:03.572: //-1//HIFS:/hifs_free_idata: hifs_free_idata: 0x8BD62034
Jul 27 10:08:03.572: //-1//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x8BD62034
Jul 27 10:08:03.572: //-1//AFW_:EE89AC4E2C000:/Tcl_Link: Linking script autoattend
Jul 27 10:08:03.576: //-1//TCL :EE89AC4E2C000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:08:03.580: //-1//AFW_:EE89AC4E2C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:08:03.580: //-1//AFW_:EE89AC4E2C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:08:03.580: //-1//PACK:EE89AC4E2C000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:08:03.580: //-1//AFW_:EE89AC4E2C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:08:03.580: //-1//AFW_:EE89AC4E2C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:08:03.580: //-1//PACK:EE89AC4E2C000:/tcl_ParamReadObjCmd: operator
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:08:03.580: //-1//TCL :EE89AC4E2C000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:08:03.584: //-1//TCL :EE89AC4E2C000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:08:03.584: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:03.584: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:08:03.584: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:03.584: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:03.584: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:08:03.584: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:08:03.584: FSM start state CALL_INIT(1)
CUBE#
Jul 27 10:08:03.584: //-1//AFW_:EE89AC4E2C000:/Tcl_Link: Script autoattend succesfully linked.
CUBE#
CUBE#
CUBE#
Jul 27 10:08:16.424: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=750, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:16.424: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:08:16.424: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:08:16.424: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:08:16.424: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:08:16.428: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:16.428: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:08:16.428: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:08:16.428: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:16.428: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:08:16.428: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:08:16.428: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:16.428: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:08:16.428: //-1/7308AD800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:08:16.436: //-1//AFW_:EE89AC4F14000:/Tcl_Link: Linking script autoattend
Jul 27 10:08:16.440: //-1//TCL :EE89AC4F14000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:08:16.440: //-1//TCL :EE89AC4F14000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:08:16.440: //-1//TCL :EE89AC4F14000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:08:16.440: //-1//AFW_:EE89AC4F14000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:08:16.440: //-1//TCL :EE89AC4F14000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:08:16.440: //-1//TCL :EE89AC4F14000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:08:16.440: //-1//AFW_:EE89AC4F14000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:08:16.440: //-1//PACK:EE89AC4F14000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:08:16.440: //-1//TCL :EE89AC4F14000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:08:16.440: //-1//TCL :EE89AC4F14000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:08:16.440: //-1//AFW_:EE89AC4F14000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:08:16.444: //-1//TCL :EE89AC4F14000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:08:16.444: //-1//TCL :EE89AC4F14000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:08:16.444: //-1//AFW_:EE89AC4F14000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:08:16.444: //-1//PACK:EE89AC4F14000:/tcl_ParamReadObjCmd: operator
Jul 27 10:08:16.444: //-1//TCL :EE89AC4F14000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:08:16.444: //-1//TCL :EE89AC4F14000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:08:16.444: //-1//TCL :EE89AC4F14000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:08:16.444: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:16.444: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:08:16.444: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:16.444: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:16.444: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:08:16.444: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:08:16.444: FSM start state CALL_INIT(1)
Jul 27 10:08:16.444: //-1//AFW_:EE89AC4F14000:/Tcl_Link: Script autoattend succesfully linked.
Jul 27 10:08:16.444: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:16.448: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:08:16.448: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:08:16.448: //3736//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 27 10:08:16.448:
Jul 27 10:08:16.448: //3736//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 27 10:08:16.448:
Jul 27 10:08:16.448: //3736//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 27 10:08:16.448: //3736//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 27 10:08:16.448: //3736//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 27 10:08:16.448: //3736//TCL :/tcl_PutsObjCmd:
ANI sip:812XXXXXXX@10.41.201.250
Jul 27 10:08:16.448:
Jul 27 10:08:16.448: //3736//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 27 10:08:16.448: //3736//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 27 10:08:16.448: //3736//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 27 10:08:16.448: //3736//TCL :/tcl_PutsObjCmd:
DNIS sip:750@62.X.X.X:5060
Jul 27 10:08:16.448:
Jul 27 10:08:16.448: //3736//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 27 10:08:16.448: //3736//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 27 10:08:16.448: //3736//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 27 10:08:16.448: //3736//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 27 10:08:16.448: //3736//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 27 10:08:16.448: //3736//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:08:16.448: //3736//AFW_:/vtd_lg_incoming: Legs [3736 ]
Jul 27 10:08:16.448: //3736//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:08:16.448: //3736//TCL :/tcl_LegObjCmd: leg setup sip:750@62.X.X.X:5060 callInfo leg_incoming
Jul 27 10:08:16.448: //3736//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:08:16.448: //3736//AFW_:/vtd_lg_incoming: Legs [3736 ]
Jul 27 10:08:16.448: //3736//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:08:16.452: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 27 10:08:16.452: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:16.452: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:08:16.452: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:08:16.452: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Jul 27 10:08:16.452: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:08:16.452: //-1/7308AD800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:16.452: //-1/7308AD800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST_URI; URI=sip:750@62.X.X.X5060
Jul 27 10:08:16.452: //-1/7308AD800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:08:16.452: //-1/7308AD800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:08:16.452: //-1/7308AD800000/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:08:16.452: //-1/7308AD800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:08:16.456: //3736//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 27 10:08:16.456: //3736//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
CUBE#
Jul 27 10:08:18.460: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:18.460: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:08:18.460: //-1/7308AD800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:08:18.460: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:18.460: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:08:18.460: //-1/7308AD800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:08:18.464: //-1//AFW_:EE89AC4FFC000:/Tcl_Link: Linking script autoattend
Jul 27 10:08:18.468: //-1//TCL :EE89AC4FFC000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:08:18.468: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:08:18.468: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:08:18.468: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:08:18.468: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:08:18.468: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:08:18.468: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:08:18.468: //-1//PACK:EE89AC4FFC000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:08:18.468: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:08:18.468: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:08:18.472: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:08:18.472: //-1//TCL :EE89AC4FFC000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:08:18.472: //-1//TCL :EE89AC4FFC000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:08:18.472: //-1//AFW_:EE89AC4FFC000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:08:18.472: //-1//PACK:EE89AC4FFC000:/tcl_ParamReadObjCmd: operator
Jul 27 10:08:18.472: //-1//TCL :EE89AC4FFC000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:08:18.472: //-1//TCL :EE89AC4FFC000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:08:18.472: //-1//TCL :EE89AC4FFC000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:08:18.472: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:18.472: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:08:18.472: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:18.472: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:08:18.472: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:08:18.472: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:08:18.472: FSM start state CALL_INIT(1)
Jul 27 10:08:18.472: //-1//AFW_:EE89AC4FFC000:/Tcl_Link: Script autoattend succesfully linked.
Jul 27 10:08:18.472: //3738//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 27 10:08:18.476:
Jul 27 10:08:18.476: //3738//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 27 10:08:18.476:
Jul 27 10:08:18.476: //3738//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 27 10:08:18.476: //3738//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 27 10:08:18.476: //3738//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 27 10:08:18.476: //3738//TCL :/tcl_PutsObjCmd:
ANI 8124990466
Jul 27 10:08:18.476:
Jul 27 10:08:18.476: //3738//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 27 10:08:18.476: //3738//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 27 10:08:18.476: //3738//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 27 10:08:18.476: //3738//TCL :/tcl_PutsObjCmd:
DNIS sip:750@62.X.X.X5060
Jul 27 10:08:18.476:
Jul 27 10:08:18.476: //3738//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 27 10:08:18.476: //3738//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 27 10:08:18.476: //3738//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 27 10:08:18.476: //3738//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 27 10:08:18.476: //3738//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 27 10:08:18.476: //3738//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:08:18.476: //3738//AFW_:/vtd_lg_incoming: Legs [3738 ]
Jul 27 10:08:18.476: //3738//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:08:18.476: //3738//TCL :/tcl_LegObjCmd: leg setup sip:750@62.X.X.X:5060 callInfo leg_incoming
Jul 27 10:08:18.476: //3738//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:08:18.476: //3738//AFW_:/vtd_lg_incoming: Legs [3738 ]
Jul 27 10:08:18.476: //3738//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:08:18.476: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 27 10:08:18.476: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:18.476: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:08:18.480: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:08:18.480: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Jul 27 10:08:18.480: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:08:18.480: //-1/7308AD800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:18.480: //-1/7308AD800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST_URI; URI=sip:750@62.X.X.X:5060
Jul 27 10:08:18.480: //-1/7308AD800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:08:18.480: //-1/7308AD800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:08:18.480: //-1/7308AD800000/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:08:18.480: //-1/7308AD800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:08:18.480: //3738//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 27 10:08:18.480: //3738//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
CUBE#
Jul 27 10:08:20.488: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:20.488: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:08:20.488: //-1/7308AD800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:08:20.488: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:08:20.488: //-1/7308AD800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:08:20.488: //-1/7308AD800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
CUBE#
Jul 27 10:08:20.488: %CALL_CONTROL-6-CALL_LOOP: The incoming call has a global identifier already present in the list of currently handled calls. It is being refused.
CUBE#
Jul 27 10:08:27.440: //-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
Jul 27 10:08:27.440: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 27 10:08:27.440: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 27 10:08:27.440: //-1/50A899738EC4/DPM/dpAssociateIncomingPeerCore:
CUBE#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
Jul 27 10:08:27.440: //-1/50A899738EC4/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 27 10:08:27.440: //-1/50A899738EC4/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
CUBE#
Jul 27 10:08:35.488: //3738//TCL :/tcl_InfotagObjCmd: infotag get evt_status
Jul 27 10:08:35.488: //3738//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Jul 27 10:08:35.488: //3738//AFW_:/vtr_ev_status: argc 2 argindex 2
Jul 27 10:08:35.488: //3738//TCL :/tcl_LegObjCmd: leg proceeding leg_incoming
Jul 27 10:08:35.488: //3738//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Jul 27 10:08:35.488: //3738//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:08:35.488: //3738//AFW_:/vtd_lg_incoming: Legs [3738 ]
Jul 27 10:08:35.488: //3738//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:08:35.488: //3738//TCL :/tcl_LegObjCmd: leg connect leg_incoming
Jul 27 10:08:35.488: //3738//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
Jul 27 10:08:35.488: //3738//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:08:35.488: //3738//AFW_:/vtd_lg_incoming: Legs [3738 ]
Jul 27 10:08:35.488: //3738//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:08:35.488: //3738//TCL :/tcl_PutsObjCmd:
Call failed. Play prompt and collect digit
Jul 27 10:08:35.488:
Jul 27 10:08:35.488: //3738//TCL :/tcl_PutsObjCmd:
proc act_Select
Jul 27 10:08:35.488:
Jul 27 10:08:35.488: //3738//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1 pattern
Jul 27 10:08:35.488: //3738//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:08:35.488: //3738//AFW_:/vtd_lg_incoming: Legs [3738 ]
Jul 27 10:08:35.488: //3738//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:08:35.492: //3738//TCL :/tcl_PutsObjCmd:
proc act_DestBusy
Jul 27 10:08:35.492:
Jul 27 10:08:35.492: //3738//PACK:/tcl_MediaObjCmd: media play leg_incoming _disconnect.au
Jul 27 10:08:35.492: //3738//PACK:/tcl_MediaPlayObjCmd: play leg_incoming _disconnect.au
Jul 27 10:08:35.492: //3738//AFW_:/vtd_lg_incoming: argc 3
Jul 27 10:08:35.492: //3738//AFW_:/vtd_lg_incoming: Legs [3738 ]
Jul 27 10:08:35.492: //3738//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
CUBE#
Jul 27 10:08:35.492: //3738//PACK:/Media_Play_Start:
Jul 27 10:08:35.492: //3738//TCL :/tcl_FSMObjCmd: fsm setstate CALLDISCONNECT
Jul 27 10:08:35.492: //3738//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT
CUBE#
Jul 27 10:08:52.608: //3736//TCL :/tcl_CallObjCmd: call close
Jul 27 10:08:52.608: //3736//TCL :/tcl_CallCloseObjCmd: close
Jul 27 10:08:52.616: //3738//TCL :/tcl_CallObjCmd: call close
Jul 27 10:08:52.616: //3738//TCL :/tcl_CallCloseObjCmd: close
*****************************************************************************************************************************************************************************
Using the same dial-peer but as sip dialpeer instead of h322 ( hear distorted sound again )
application
service autoattend flash:/its-CISCO.2.0.2.0.tcl
paramspace english language en
paramspace english index 0
paramspace english location flash:/
paramspace english prefix en
param aa-pilot 750
param operator 100
!
global
service alternate default
!
dial-peer voice 750 voip
service autoattend
destination-pattern 750
session protocol sipv2
session target ipv4:62.X.X.X (FA0/4 Public ip address on cube :62.X.X.X)
incoming called-number 750
dtmf-relay sip-kpml sip-notify h245-signal h245-alphanumeric
codec g711ulaw
CUBE(config)#
Jul 27 10:23:42.304: //-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
Jul 27 10:23:42.304: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 27 10:23:42.304: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Jul 27 10:23:42.304: //-1/71F5C2478ED3/DPM/dpAssociateIncomingPeerCore:
CUBE(config)#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
Jul 27 10:23:42.308: //-1/71F5C2478ED3/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 27 10:23:42.308: //-1/71F5C2478ED3/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
CUBE(config)#do call application voice load auto
CUBE(config)#do call application voice load autoattend
CUBE(config)#
Jul 27 10:24:04.904: //-1//HIFS:/hifs_ifs_cb: hifs ifs file read succeeded. size=8073, url=flash:/its-CISCO.2.0.2.0.tcl
Jul 27 10:24:04.904: //-1//HIFS:/hifs_free_idata: hifs_free_idata: 0x8BD63D1C
Jul 27 10:24:04.904: //-1//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x8BD63D1C
Jul 27 10:24:04.904: //-1//AFW_:EE89AC50E4000:/Tcl_Link: Linking script autoattend
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:24:04.912: //-1//AFW_:EE89AC50E4000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:24:04.912: //-1//AFW_:EE89AC50E4000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:24:04.912: //-1//PACK:EE89AC50E4000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:24:04.912: //-1//AFW_:EE89AC50E4000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:24:04.912: //-1//TCL :EE89AC50E4000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:24:04.912: //-1//AFW_:EE89AC50E4000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:24:04.912: //-1//PACK:EE89AC50E4000:/tcl_ParamReadObjCmd: operator
Jul 27 10:24:04.916: //-1//TCL :EE89AC50E4000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:24:04.916: //-1//TCL :EE89AC50E4000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:24:04.916: //-1//TCL :EE89AC50E4000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:24:04.916: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:24:04.916: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:24:04.916: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:24:04.916: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:24:04.916: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:24:04.916: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:24:04.916: FSM start state CALL_INIT(1)
CUBE(config)#
CUBE(config)#
CUBE(config)#
CUBE(config)#
Jul 27 10:24:04.916: //-1//AFW_:EE89AC50E4000:/Tcl_Link: Script autoattend succesfully linked.
CUBE(config)#
CUBE(config)#
CUBE(config)#
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=750, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:24:13.500: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:24:13.504: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:13.504: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:24:13.504: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:24:13.504: //-1/AD734A000000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:13.504: //-1/AD734A000000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:24:13.504: //-1/AD734A000000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:24:13.508: //-1//AFW_:EE89AC4604000:/Tcl_Link: Linking script autoattend
Jul 27 10:24:13.512: //-1//TCL :EE89AC4604000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 27 10:24:13.516: //-1//AFW_:EE89AC4604000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 27 10:24:13.516: //-1//AFW_:EE89AC4604000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:24:13.516: //-1//PACK:EE89AC4604000:/tcl_ParamReadObjCmd: aa-pilot
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 27 10:24:13.516: //-1//AFW_:EE89AC4604000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagObjCmd: infotag get cfg_avpair operator
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 27 10:24:13.516: //-1//AFW_:EE89AC4604000:/vtr_cf_avpair: argc 3 argindex 2
Jul 27 10:24:13.516: //-1//PACK:EE89AC4604000:/tcl_ParamReadObjCmd: operator
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_FSMObjCmd: fsm define fsm CALL_INIT
Jul 27 10:24:13.516: //-1//TCL :EE89AC4604000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 27 10:24:13.520: //-1//TCL :EE89AC4604000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 27 10:24:13.520: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 27 10:24:13.520: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 27 10:24:13.520: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 27 10:24:13.520: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 27 10:24:13.520: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 27 10:24:13.520: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 27 10:24:13.520: FSM start state CALL_INIT(1)
Jul 27 10:24:13.520: //-1//AFW_:EE89AC4604000:/Tcl_Link: Script autoattend succesfully linked.
Jul 27 10:24:13.520: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:13.520: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:24:13.520: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:24:13.520: //3759//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 27 10:24:13.520:
Jul 27 10:24:13.520: //3759//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 27 10:24:13.520:
Jul 27 10:24:13.520: //3759//TCL :/tcl_InfotagObjCmd: infotag get leg_ani
Jul 27 10:24:13.524: //3759//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 27 10:24:13.524: //3759//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 27 10:24:13.524: //3759//TCL :/tcl_PutsObjCmd:
ANI sip:812XXXXXXX@10.41.201.250
Jul 27 10:24:13.524:
Jul 27 10:24:13.524: //3759//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis
Jul 27 10:24:13.524: //3759//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 27 10:24:13.524: //3759//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 27 10:24:13.524: //3759//TCL :/tcl_PutsObjCmd:
DNIS sip:750@62.X.X.X :5060
Jul 27 10:24:13.524:
Jul 27 10:24:13.524: //3759//TCL :/tcl_InfotagObjCmd: infotag set med_language 1
Jul 27 10:24:13.524: //3759//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 27 10:24:13.524: //3759//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 27 10:24:13.524: //3759//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
Jul 27 10:24:13.524: //3759//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 27 10:24:13.524: //3759//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:24:13.524: //3759//AFW_:/vtd_lg_incoming: Legs [3759 ]
Jul 27 10:24:13.524: //3759//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:24:13.524: //3759//TCL :/tcl_LegObjCmd: leg setup sip:750@62.X.X.X :5060 callInfo leg_incoming
Jul 27 10:24:13.524: //3759//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:24:13.524: //3759//AFW_:/vtd_lg_incoming: Legs [3759 ]
Jul 27 10:24:13.524: //3759//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:24:13.524: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 27 10:24:13.524: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:13.524: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:24:13.524: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:24:13.524: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Jul 27 10:24:13.524: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:24:13.528: //-1/AD734A000000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:13.528: //-1/AD734A000000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST_URI; URI=sip:750@62.X.X.X :5060
Jul 27 10:24:13.528: //-1/AD734A000000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:24:13.528: //-1/AD734A000000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:24:13.528: //-1/AD734A000000/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:24:13.528: //-1/AD734A000000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:24:13.528: //3759//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF
Jul 27 10:24:13.528: //3759//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
CUBE(config)#
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=750, Called Number=750, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=750
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=750, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=750
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
Jul 27 10:24:14.040: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:24:14.044: //-1/AD734A000000/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=750, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:14.044: //-1/AD734A000000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=750
Jul 27 10:24:14.044: //-1/AD734A000000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Jul 27 10:24:14.048: %CALL_CONTROL-6-CALL_LOOP: The incoming call has a global identifier already present in the list of currently handled calls. It is being refused.
Jul 27 10:24:14.052: //3759//TCL :/tcl_InfotagObjCmd: infotag get evt_status
Jul 27 10:24:14.052: //3759//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Jul 27 10:24:14.052: //3759//AFW_:/vtr_ev_status: argc 2 argindex 2
Jul 27 10:24:14.052: //3759//TCL :/tcl_LegObjCmd: leg proceeding leg_incoming
Jul 27 10:24:14.052: //3759//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Jul 27 10:24:14.052: //3759//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:24:14.056: //3759//AFW_:/vtd_lg_incoming: Legs [3759 ]
Jul 27 10:24:14.056: //3759//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:24:14.056: //3759//TCL :/tcl_LegObjCmd: leg connect leg_incoming
Jul 27 10:24:14.056: //3759//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
Jul 27 10:24:14.056: //3759//AFW_:/vtd_lg_incoming: argc 2
Jul 27 10:24:14.056: //3759//AFW_:/vtd_lg_incoming: Legs [3759 ]
Jul 27 10:24:14.056: //3759//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:24:14.056: //3759//TCL :/tcl_PutsObjCmd:
Call failed. Play prompt and collect digit
Jul 27 10:24:14.056:
Jul 27 10:24:14.056: //3759//TCL :/tcl_PutsObjCmd:
proc act_Select
Jul 27 10:24:14.056:
Jul 27 10:24:14.056: //3759//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1 pattern
Jul 27 10:24:14.056: //3759//AFW_:/vtd_lg_incoming: argc 4
Jul 27 10:24:14.056: //3759//AFW_:/vtd_lg_incoming: Legs [3759 ]
Jul 27 10:24:14.056: //3759//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:24:14.056: //3759//TCL :/tcl_PutsObjCmd:
proc act_DestBusy
Jul 27 10:24:14.056:
Jul 27 10:24:14.056: //3759//PACK:/tcl_MediaObjCmd: media play leg_incoming _disconnect.au
Jul 27 10:24:14.056: //3759//PACK:/tcl_MediaPlayObjCmd: play leg_incoming _disconnect.au
Jul 27 10:24:14.056: //3759//AFW_:/vtd_lg_incoming: argc 3
Jul 27 10:24:14.056: //3759//AFW_:/vtd_lg_incoming: Legs [3759 ]
Jul 27 10:24:14.056: //3759//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 27 10:24:14.056: //3759//PACK:/Media_Play_Start:
Jul 27 10:24:14.060: //3759//TCL :/tcl_FSMObjCmd: fsm setstate CALLDISCONNECT
Jul 27 10:24:14.060: //3759//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT
Jul 27 10:24:14.060: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=8124990466, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 27 10:24:14.060: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=3
CUBE(config)#
Jul 27 10:24:14.060: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
CUBE(config)#
Jul 27 10:24:33.172: //3759//TCL :/tcl_CallObjCmd: call close
Jul 27 10:24:33.172: //3759//TCL :/tcl_CallCloseObjCmd: close
07-27-2013 06:10 AM
Hi Ahmed,
I really try to support but your existing configuration dial-plan has issue, let notice the output of your debug:
%CALL_CONTROL-6-CALL_LOOP: The incoming call has a global identifier already present in the list of currently handled calls. It is being refused.
"Call failed. Play prompt and collect digit"
The AA was invoked did not mean the configuration is OK, the incorrect dialing plan resulted in mis-function of AA.
07-27-2013 01:06 PM
Hi Lan,first thank you so much for your support and time,second in my understanding and searches
%CALL_CONTROL-6-CALL_LOOP: The incoming call has a global identifier already present in the list of currently handled calls. It is being refused.
this message should be seen when you are using the same source and session target but this message shouldn't be seen if you add the incoming called number ,which i already have.I ll keep troubleshooting and update the post as soon as i find something.
Thanks man.
07-28-2013 06:30 AM
Hi Admed,
Below is the topology I used to use to setup AA, (I apply it to your scenario):
(I did not see any E1 trunking in your configuration of CUBE)
BE 3000 ---------(LAN-Ethernet)---------- (CUBE) --------Sip Trunking to outside
For test, let use H323 between BE 3000 and CUBE instead of sip .
dont forget to bind H323 with interfaces of BE3000 and CUBE, ex:
"h323-gateway voip interface
h323-gateway voip bind srcaddr"
on BE 3000 side, make the dial-peer point to CUBE AA, on CUBE side make dial-peer:
dial-peer voice 750 voip
description AA service
service autoattend
session target ipv4:(ipv4 of CUBE itself that you associated with H323)
incoming called-number 750
codec g711ulaw
dtmf-relay h245-alphanumeric h245-signal
no vad
(Dont forget to enable H323 on BE3000 interface that you use to connect to AA voip)
On CUBE, (optionally) you also can use "ip address trusted list" to allow voip communication from BE3000 under "voice service voip"
I usually make equipvalent setting as above and did not encounter any issue with AA
Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: