Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. If you'd prefer to explore, try our test area to get started. And see here for current known issues.

New Member

TCL Auto Attendant Problem

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 !

28 REPLIES
New Member

TCL Auto Attendant Problem

Dear folk,

Let try aa-pilot

application

service autoattend flash:/its-CISCO.2.0.2.0.tcl

  paramspace english language en

  paramspace english index 0

  param aa-pilot 750

  paramspace english location flash:/

  paramspace english prefix en

  param oprtr 100

and under voip dial-peer 750, add:

codec g711ulaw

for tracking: debug voice application script

New Member

Re: TCL Auto Attendant Problem

Dear Lan Pham.

Thanks for your reply ,first i didn't think that the aa-pilot instead of aapilot would make difference cause the param aapilot i copied it as it was written in the script.

But i changed it and also was changing some config's in the dialpeer step by step but i still hear nothing.

Here are the configs and the debug of voice application script:

CUBE#debug voice application script

voip application script debugging is on

CUBE#

Jul 24 07:22:58.162: //4872//TCL :/tcl_PutsObjCmd: proc act_Setup

Jul 24 07:22:58.162:

Jul 24 07:22:58.162: //4872//TCL :/tcl_PutsObjCmd:

proc init_perCallvars

Jul 24 07:22:58.162:

Jul 24 07:22:58.162: //4872//TCL :/tcl_PutsObjCmd:

ANI

sip:8124XXXXXX@10.41.201.250

Jul 24 07:22:58.162:

Jul 24 07:22:58.162: //4872//TCL :/tcl_PutsObjCmd:

DNIS sip:750@fa0/4_public_ip_of cube:5060

Jul 24 07:22:58.162:

Jul 24 07:22:58.190: //4872//TCL :/tcl_PutsObjCmd:

Call failed. Play prompt and collect digit

Jul 24 07:22:58.190:

Jul 24 07:22:58.190: //4872//TCL :/tcl_PutsObjCmd:

proc act_Select

Jul 24 07:22:58.190:

Jul 24 07:22:58.190: //4872//TCL :/tcl_PutsObjCmd:

proc act_DestBusy

CUBE#

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

param oprtr 100

paramspace english prefix en

!

global

service alternate default

!

dial-peer voice 750 voip

service autoattend

destination-pattern 750

session protocol sipv2

session target ipv4:10.41.201.250

incoming called-number 750

dtmf-relay rtp-nte sip-kpml sip-notify h245-signal h245-alphanumeric

codec g711ulaw

no vad

I had a voice class codec and i added to the dial-peer instead of only g711ulaw but didn't make difference.

voice class codec 1

codec preference 1 g711ulaw

codec preference 2 g711alaw

codec preference 3 g729r8

codec preference 4 g729br8

When i use debug ccsip messages it shows 404 not found and i understand this is because 750 is just a pilot number.

Could this be  a problem with my voice files,cause for only one time instead of hearing nothing ,i heared some distorted sound.

Thanks in advance

New Member

Re: TCL Auto Attendant Problem

Hi, please get back with the log of:

debug voip application tclcommands

Re: TCL Auto Attendant Problem

Hi Ahmed.

Try also to reload audio prompt by using audio-promt load flash:filename.au command

HTH

Regards

Carlo

Please rate all helpful posts

"The more you help the more you learn"

Please rate all helpful posts "The more you help the more you learn"
New Member

Re: TCL Auto Attendant Problem

Thanks Carlo,already tried,does not work.

Do i need to reload the router ?

Re: TCL Auto Attendant Problem

Hi Ahmed.

Yes in some case a reload could be useful

I had similar issues in the past with promts and a reload solved the problem

Can you please tell me the IOS version you are using?

Thanks

Regards

Carlo

Please rate all helpful posts

"The more you help the more you learn"

Please rate all helpful posts "The more you help the more you learn"
New Member

Re: TCL Auto Attendant Problem

Hi Paolo,thanks for reply

here is the version:

Cisco IOS Software, C880 Software (C880VOICE-UNIVERSALK9_NPE-M), Version 15.3(1)T, RELEASE SOFTWARE (fc1)

New Member

Re: TCL Auto Attendant Problem

Hi ,thanks for reply.

When i load the autoattend service ,i get connected to the pilot but i hear a destored sound not a sound of human,and when i try to interrupt this sound by dailing internal number the debug dialpeer shows that it collects 2 digits only of 3 digits internal number.

CUBE#show debug

debug voip application script is ON (filter is OFF)

debug voip application tcl commands is ON (filter is OFF)

CUBE#call application voice load autoattend

CUBE#

Jul 24 11:45:15.225: //-1//HIFS:/hifs_ifs_cb: hifs ifs file read succeeded. size=8073, url=flash:/its-CISCO.2.0.2.0.

tcl
Jul 24 11:45:15.225: //-1//HIFS:/hifs_free_idata: hifs_free_idata: 0x8C2E48C0
Jul 24 11:45:15.225: //-1//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x8C2E48C0
Jul 24 11:45:15.225: //-1//AFW_:EE8A18AF7C000:/Tcl_Link: Linking script autoattend
Jul 24 11:45:15.229: //-1//TCL :EE8A18AF7C000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 24 11:45:15.229: //-1//TCL :EE8A18AF7C000:/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists aa-pilot
Jul 24 11:45:15.229: //-1//TCL :EE8A18AF7C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 24 11:45:15.229: //-1//AFW_:EE8A18AF7C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 11:45:15.229: //-1//TCL :EE8A18AF7C000:/tcl_InfotagObjCmd:  infotag get cfg_avpair aa-pilot
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 24 11:45:15.233: //-1//AFW_:EE8A18AF7C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 11:45:15.233: //-1//PACK:EE8A18AF7C000:/tcl_ParamReadObjCmd:  aa-pilot
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists operator
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 24 11:45:15.233: //-1//AFW_:EE8A18AF7C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_InfotagObjCmd:  infotag get cfg_avpair operator
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 24 11:45:15.233: //-1//AFW_:EE8A18AF7C000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 11:45:15.233: //-1//PACK:EE8A18AF7C000:/tcl_ParamReadObjCmd:  operator
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_FSMObjCmd:  fsm define fsm CALL_INIT
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 24 11:45:15.233: //-1//TCL :EE8A18AF7C000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 24 11:45:15.233: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 24 11:45:15.233: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 24 11:45:15.233: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 24 11:45:15.233: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 24 11:45:15.233: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 24 11:45:15.233: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 24 11:45:15.233: FSM start state CALL_INIT(1)
CUBE#
Jul 24 11:45:15.233: //-1//AFW_:EE8A18AF7C000:/Tcl_Link: Script autoattend succesfully linked.
CUBE#
Jul 24 11:46:53.393: //-1//AFW_:EE8A18B064000:/Tcl_Link: Linking script autoattend
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_RequiredVersionObjCmd: Script requires version 2.0. So 2.1 is OK
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists aa-pilot
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists aa-pilot
Jul 24 11:46:53.401: //-1//AFW_:EE8A18B064000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagObjCmd:  infotag get cfg_avpair aa-pilot
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Jul 24 11:46:53.401: //-1//AFW_:EE8A18B064000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 11:46:53.401: //-1//PACK:EE8A18B064000:/tcl_ParamReadObjCmd:  aa-pilot
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists operator
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Jul 24 11:46:53.401: //-1//AFW_:EE8A18B064000:/vtr_cf_avpair_exists: argc 3 argindex 2
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagObjCmd:  infotag get cfg_avpair operator
Jul 24 11:46:53.401: //-1//TCL :EE8A18B064000:/tcl_InfotagGetObjCmd: infotag get cfg_avpair operator
Jul 24 11:46:53.401: //-1//AFW_:EE8A18B064000:/vtr_cf_avpair: argc 3 argindex 2
Jul 24 11:46:53.401: //-1//PACK:EE8A18B064000:/tcl_ParamReadObjCmd:  operator
Jul 24 11:46:53.405: //-1//TCL :EE8A18B064000:/tcl_FSMObjCmd:  fsm define fsm CALL_INIT
Jul 24 11:46:53.405: //-1//TCL :EE8A18B064000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: CALL_INIT
Jul 24 11:46:53.405: //-1//TCL :EE8A18B064000:/tcl_FSMDefineObjCmd: FSM Data structure
Jul 24 11:46:53.405: (CALLDISCONNECT(2), ev_media_done(186)--(act_Cleanup)-->(any_state(0))
Jul 24 11:46:53.405: (GETDEST(3), ev_collectdigits_done(247)--(act_GotDest)-->(HANDOFF(4))
Jul 24 11:46:53.405: (any_state(0), ev_disconnect_done(21)--(act_Cleanup)-->(any_state(0))
Jul 24 11:46:53.405: (any_state(0), ev_disconnected(20)--(act_Cleanup)-->(any_state(0))
Jul 24 11:46:53.405: (HANDOFF(4), ev_setup_done(238)--(act_CallSetupDone)-->(CONTINUE(5))
Jul 24 11:46:53.405: (CALL_INIT(1), ev_setup_indication(35)--(act_Setup)-->(GETDEST(3))
Jul 24 11:46:53.405: FSM start state CALL_INIT(1)
Jul 24 11:46:53.405: //-1//AFW_:EE8A18B064000:/Tcl_Link: Script autoattend succesfully linked.
Jul 24 11:46:53.405: //5269//TCL :/tcl_PutsObjCmd: proc act_Setup
Jul 24 11:46:53.409:
Jul 24 11:46:53.409: //5269//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Jul 24 11:46:53.409:
Jul 24 11:46:53.409: //5269//TCL :/tcl_InfotagObjCmd:  infotag get leg_ani
Jul 24 11:46:53.409: //5269//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Jul 24 11:46:53.409: //5269//AFW_:/vtr_lg_ani: argc 2 argindex 2
Jul 24 11:46:53.409: //5269//TCL :/tcl_PutsObjCmd:
ANI sip:8124XXXXXX@10.41.201.250
Jul 24 11:46:53.409:
Jul 24 11:46:53.409: //5269//TCL :/tcl_InfotagObjCmd:  infotag get leg_dnis
Jul 24 11:46:53.409: //5269//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
Jul 24 11:46:53.409: //5269//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Jul 24 11:46:53.409: //5269//TCL :/tcl_PutsObjCmd:
DNIS sip:750@fa0/4_public_ip_of cube:5060
Jul 24 11:46:53.409:
Jul 24 11:46:53.409: //5269//TCL :/tcl_InfotagObjCmd:  infotag set med_language 1
Jul 24 11:46:53.409: //5269//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1
Jul 24 11:46:53.409: //5269//AFW_:/vtw_ms_language: argc 3 argindex 2
Jul 24 11:46:53.409: //5269//TCL :/tcl_LegObjCmd:  leg setupack leg_incoming
Jul 24 11:46:53.409: //5269//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
Jul 24 11:46:53.409: //5269//AFW_:/vtd_lg_incoming: argc 2
Jul 24 11:46:53.409: //5269//AFW_:/vtd_lg_incoming: Legs [5269 ]
Jul 24 11:46:53.409: //5269//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 11:46:53.409: //5269//TCL :/tcl_LegObjCmd:  leg setup sip:750@fa0/4_public_ip_of cube:5060 callInfo leg_incoming
Jul 24 11:46:53.409: //5269//AFW_:/vtd_lg_incoming: argc 4
Jul 24 11:46:53.409: //5269//AFW_:/vtd_lg_incoming: Legs [5269 ]
Jul 24 11:46:53.409: //5269//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 11:46:53.409: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30
Jul 24 11:46:53.413: //5269//TCL :/tcl_FSMObjCmd:  fsm setstate HANDOFF
Jul 24 11:46:53.413: //5269//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF
Jul 24 11:46:53.413: //5269//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
Jul 24 11:46:53.413: //5269//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
Jul 24 11:46:53.413: //5269//AFW_:/vtr_ev_status: argc 2 argindex 2
Jul 24 11:46:53.413: //5269//TCL :/tcl_LegObjCmd:  leg proceeding leg_incoming
Jul 24 11:46:53.413: //5269//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
Jul 24 11:46:53.413: //5269//AFW_:/vtd_lg_incoming: argc 2
Jul 24 11:46:53.413: //5269//AFW_:/vtd_lg_incoming: Legs [5269 ]
Jul 24 11:46:53.413: //5269//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 11:46:53.413: //5269//TCL :/tcl_LegObjCmd:  leg connect leg_incoming
Jul 24 11:46:53.413: //5269//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
Jul 24 11:46:53.413: //5269//AFW_:/vtd_lg_incoming: argc 2
Jul 24 11:46:53.417: //5269//AFW_:/vtd_lg_incoming: Legs [5269 ]
Jul 24 11:46:53.417: //5269//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 11:46:53.417: //5269//TCL :/tcl_PutsObjCmd:
Call failed. Play prompt and collect digit
Jul 24 11:46:53.417:
Jul 24 11:46:53.417: //5269//TCL :/tcl_PutsObjCmd:
proc act_Select
Jul 24 11:46:53.417:
Jul 24 11:46:53.417: //5269//TCL :/tcl_LegObjCmd:  leg collectdigits leg_incoming param1 pattern
Jul 24 11:46:53.417: //5269//AFW_:/vtd_lg_incoming: argc 4
Jul 24 11:46:53.417: //5269//AFW_:/vtd_lg_incoming: Legs [5269 ]
Jul 24 11:46:53.417: //5269//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 11:46:53.417: //5269//TCL :/tcl_PutsObjCmd:
proc act_DestBusy
Jul 24 11:46:53.417:
Jul 24 11:46:53.417: //5269//PACK:/tcl_MediaObjCmd:  media play leg_incoming _disconnect.au
Jul 24 11:46:53.417: //5269//PACK:/tcl_MediaPlayObjCmd:  play leg_incoming _disconnect.au
Jul 24 11:46:53.417: //5269//AFW_:/vtd_lg_incoming: argc 3
Jul 24 11:46:53.417: //5269//AFW_:/vtd_lg_incoming: Legs [5269 ]
Jul 24 11:46:53.417: //5269//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Jul 24 11:46:53.417: //5269//PACK:/Media_Play_Start: 
Jul 24 11:46:53.417: //5269//TCL :/tcl_FSMObjCmd:  fsm setstate CALLDISCONNECT
Jul 24 11:46:53.417: //5269//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT
CUBE#
Jul 24 11:48:23.517: //5269//TCL :/tcl_CallObjCmd:  call close
Jul 24 11:48:23.517: //5269//TCL :/tcl_CallCloseObjCmd:  close
New Member

Re: TCL Auto Attendant Problem

Hi Ahmed

In fact, I dont see your script play some media like "welcome" or something.

Could you share your tcl script here??

New Member

Re: TCL Auto Attendant Problem

Hi Lan Pham,

thanks again for reply.

I attached the tcl script.Take a look please.

Thanks.

Re: TCL Auto Attendant Problem

Ok Ahmed.

I tryed with the same platform/Ios in my lab.

A reload sloved the issue

Try to reload and let me know

Carlo

Please rate all helpful posts

"The more you help the more you learn"

Please rate all helpful posts "The more you help the more you learn"
New Member

Re: TCL Auto Attendant Problem

ok,i can't make this now cause this is a client's working network.I ll try to do this late at night,and will update you tomorrow.

Thanks Carlo for reply.

New Member

TCL Auto Attendant Problem

Hi Carlo,

the reload didn't resolve the issue.I still hear distorted sound, 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.

New Member

Re: TCL Auto Attendant Problem

Hi Ahmed

I revised one command line on your script:

"media play leg_incoming _welcome.au %s100 _enter_dest.au"

Make sure "en_welcome.au" and "en_enter_dest.au" seat in flash:/

let upload the attachment to CME, reload and test again, if you see:

.....

Jul 24 13:57:17.850: //27//TCL :/tcl_LegObjCmd:  leg collectdigits leg_incoming param1

Jul 24 13:57:17.850: //27//AFW_:/vtd_lg_incoming: argc 3

Jul 24 13:57:17.850: //27//AFW_:/vtd_lg_incoming: Legs [27 ]

Jul 24 13:57:17.850: //27//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1

Jul 24 13:57:17.854: //27//PACK:/tcl_MediaObjCmd:  media play leg_incoming _holidaynotice1.au %s100

_enter_dest.au

Jul 24 13:57:17.854: //27//PACK:/tcl_MediaPlayObjCmd:  play leg_incoming _welcome.au %s100 _enter_dest.au

....

       Means your script is running well.

If you see above but still can not hear anything, codec issue shoulde be accounted

New Member

Re: TCL Auto Attendant Problem

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

New Member

Re: TCL Auto Attendant Problem

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")

New Member

Re: TCL Auto Attendant Problem

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.

New Member

Re: TCL Auto Attendant Problem

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"

New Member

Re: TCL Auto Attendant Problem

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.

New Member

Re: TCL Auto Attendant Problem

Hi Ahmed,

Beside config (As Mr Carlo mentioned), could you include network diagram too?

New Member

Re: TCL Auto Attendant Problem

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.

Re: TCL Auto Attendant Problem

Hi Ahmed.
Can you please share you entire config?
Thanks

Carlo

Sent from Cisco Technical Support iPhone App

Please rate all helpful posts "The more you help the more you learn"
New Member

Re: TCL Auto Attendant Problem

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

New Member

Re: TCL Auto Attendant Problem

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

New Member

Re: TCL Auto Attendant Problem

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

New Member

Re: TCL Auto Attendant Problem

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.

New Member

Re: TCL Auto Attendant Problem

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.

New Member

Re: TCL Auto Attendant Problem

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

1015
Views
6
Helpful
28
Replies