Re: TCL Auto Attendant Problem

Ahmed Awida · ‎07-23-2013

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

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 !

duclan_hp · ‎07-23-2013

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

Ahmed Awida · ‎07-24-2013

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

duclan_hp · ‎07-24-2013

Hi, please get back with the log of:

debug voip application tclcommands

Carlo Poggiarelli · ‎07-24-2013

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"

Ahmed Awida · ‎07-24-2013

Thanks Carlo,already tried,does not work.

Do i need to reload the router ?

Carlo Poggiarelli · ‎07-24-2013

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"

Ahmed Awida · ‎07-24-2013

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)

Ahmed Awida · ‎07-24-2013

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

duclan_hp · ‎07-24-2013

Hi Ahmed

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

Could you share your tcl script here??

Ahmed Awida · ‎07-24-2013

Hi Lan Pham,

thanks again for reply.

I attached the tcl script.Take a look please.

Thanks.

Carlo Poggiarelli · ‎07-24-2013

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"

Ahmed Awida · ‎07-24-2013

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.

Ahmed Awida · ‎07-25-2013

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.

duclan_hp · ‎07-24-2013

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