Solved: TCL example its-CISCO issue

jvazquez · ‎02-25-2016

Hello i trying to use example its-CISCO and i getting next error .. any idea ??? general configure information below error

thanks in advence

*Feb 25 19:38:36.547: //187//TCL :/tcl_PutsObjCmd: proc act_Setup

*Feb 25 19:38:36.547:

*Feb 25 19:38:36.547: //187//TCL :/tcl_PutsObjCmd:

proc init_perCallvars

*Feb 25 19:38:36.551:

*Feb 25 19:38:36.551: //187//TCL :/tcl_PutsObjCmd:

ANI sip:30110@172.20.5.22

*Feb 25 19:38:36.551:

*Feb 25 19:38:36.551: //187//TCL :/tcl_PutsObjCmd:

DNIS sip:5555@172.20.5.30:5060

*Feb 25 19:38:36.551:

*Feb 25 19:38:36.551: //-1//AFW_:/AFW_Util_GetUsrContainerData: Container is present, No data

*Feb 25 19:38:36.551: //-1//AFW_:/AFW_Util_CopyLegServiceCodeToCallInfoContainer: Service Code not available on Leg

*Feb 25 19:38:36.551: //-1//AFW_:/AFW_Util_CopyLegRouteCodeToCallInfoContainer: Route Code not available on Leg

*Feb 25 19:38:36.551: //-1//AFW_:/AFW_Util_GetTgCicValue: CIC Not found for tag(56)

*Feb 25 19:38:36.555: //-1//AFW_:/AFW_Util_CopyMLPPDataToCallInfoContainer: Voice MLPP not configured

*Feb 25 19:38:36.559: //187//TCL :/tcl_PutsObjCmd:

Call failed. Play prompt and collect digit

*Feb 25 19:38:36.559:

*Feb 25 19:38:36.563: //187//TCL :/tcl_PutsObjCmd:

proc act_Select

*Feb 25 19:38:36.563:

*Feb 25 19:38:36.563: //187//TCL :/tcl_PutsObjCmd:

proc act_DestBusy

*Feb 25 19:38:36.563:

*Feb 25 19:38:36.567: //187/EC1D68000000/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode

SIP: (187) Group (a= group line) attribute, level 65535 instance 1 not found.

SIP: Attribute mid, level 1 instance 1 not found.

*Feb 25 19:38:36.587: //187/EC1D68000000/SIP/Error/sipSPIProcessAckMedia: Could not modify QoS params for midca

-----

service test its-CISCO.tcl

paramspace english index 1

paramspace english lenguage en

paramspace english location flash:

param aa-pilot 5555

param operator 9998

------

dial peer

dial-peer voice 5555 voip

service test

session protocol sipv2

incoming called-number 5555

dtmf-relay rtp-nte

codec g711ulaw

no vad

---

GW version 151-4.M10

yawming · ‎02-25-2016

Here is the issue:

DNIS sip:5555@172.20.5.30:5060

After certain version of IOS, infortag (dnis [infotag get leg_dnis]) is not returning number only, it returns above.

Solution is to extract the number or use new command.

Pleas see the following post

New Tcl IVR command option for "infotag get leg_dnis" and "infotag get leg_ani"

View solution in original post

yawming · ‎02-25-2016

The sample script is not a ready to run you may need to modify according you the network environment.

There are several versions of that script, can you please attach the script you have ?

Thanks !

jvazquez · ‎02-25-2016

thanks,

this is the scrip that im using

# Script Locked by: khom

# Script Version: 2.0.2.0

# Script Name: its_CISCO

# Script Lock Date: Wed Jul 2 09:22:04 2003

#------------------------------------------------------------------

#

# November 27, 2001, Satish Ananthanarayana (sanantha@cisco.com)

#

# Modification History:

# --------------------

# May 20, 2008, Jasmine Kalaiselvan (jkalaise@cisco.com)

#

# Modified the script to do leg setup and then handovers the call

# to Default IOS app. DDTS for this change: CSCsq35953 CSCsl08148

#

# --------------------------------------------------------

#------------------------------------------------------------------

#

# Description:

# This is a TCL IVR script for the IOS Telephony Service and

# Call Manager (CM) offload scenario. The IVR script plays a

# welcome prompt to the user and prompts the user to enter a

# destination number when the user dials the auto-attendant number

# (aa-pilot configured in the CLI). The script collects the digits that

# the user has entered and hands the call to the enhanced session

# application (named Default). The session application

# returns once there is a disconnect (if the call is established)

# or if a call setup problem occurs.

# The operator support is also included, if the user does not dial

# any number or enters "0" the user will be transfered to an operator

# (if operator number is configured in the CLI). If the user enters

# an invalid number, the user will be prompted again to re-enter

# the number for upto 3 times before disconnecting the call.

#

#-------------------------------------------------------------------

#

proc init { } {

global param1

global selectCnt

global callInfo

global legConnected

global maxExtensionLen

set param1(interruptPrompt) true

set param1(abortKey) *

set param1(terminationKey) #

set selectCnt 0

set legConnected false

}

proc init_ConfigVars { } {

global destination

global aaPilot

global oprtr

global maxExtensionLen

# aa-pilot is the IVR number configured on the gateway to be used by the customer

# operator is the operator number for assisted calling

if [infotag get cfg_avpair_exists aa-pilot] {

set aaPilot [string trim [infotag get cfg_avpair aa-pilot]]

} else {

set aaPilot "NONE"

}

if [infotag get cfg_avpair_exists operator] {

set oprtr [string trim [infotag get cfg_avpair operator]]

} else {

set oprtr "NONE"

}

if [infotag get cfg_avpair_exists max-extension-length] {

set maxExtensionLen [string trim [infotag get cfg_avpair max-extension-length]]

if { $maxExtensionLen < 0 } {

call close

}

} else {

set maxExtensionLen 5

}

proc init_perCallVars { } {

puts "\nproc init_perCallvars"

global ani

global digit_enabled

global fcnt

global retrycnt

global dnis

set fcnt 0

set retrycnt 6

set ani ""

set dnis ""

set digit_enabled "FALSE"

set ani [infotag get leg_ani]

puts "\nANI $ani"

set dnis [infotag get leg_dnis]

puts "\nDNIS $dnis"

}

proc act_Setup { } {

global param1

global selectCnt

global dest

global beep

global callInfo

global dnis

global fcnt

global aaPilot

global oprtr

global busyPrompt

global legConnected

puts "proc act_Setup"

set busyPrompt _dest_unreachable.au

set beep 0

init_perCallVars

infotag set med_language 1

if { ($dnis == "") || ($dnis == $aaPilot) } {

leg setupack leg_incoming

leg proceeding leg_incoming

leg connect leg_incoming

set legConnected true

puts "\nNo DNIS\n"

set param1(dialPlan) true

leg collectdigits leg_incoming param1

media play leg_incoming _welcome.au %s1000 _enter_dest.au

} else {

set fcnt 6

leg setupack leg_incoming

#handoff callappl leg_incoming default "DESTINATION=$dnis"

set callInfo(alertTime) 30

leg setup $dnis callInfo leg_incoming

fsm setstate HANDOFF

}

proc act_GotDest { } {

global dest

global maxExtensionLen

global destExtLen

global callInfo

global oprtr

global busyPrompt

puts "\n proc act_GotDest"

set status [infotag get evt_status]

set callInfo(alertTime) 30

puts "\n STATUS: $status"

puts "\n MAXEXTENSION: $maxExtensionLen"

if { ($status == "cd_004") } {

set dest [infotag get evt_dcdigits]

set destExtLen [string length $dest]

puts "\n DESTLEN: $destExtLen"

set extLength [expr $maxExtensionLen - $destExtLen]

if { $dest == "0" } {

set dest $oprtr

}

puts "\n extLength: $extLength"

#handoff callappl leg_incoming default "DESTINATION=$dest"

if {($maxExtensionLen > 0) && ($extLength >= 0)} {

leg setup $dest callInfo leg_incoming

} else {

set busyPrompt _bacd_invalidoption.au

act_Select

}

} elseif { ($status == "cd_001") || ($status == "cd_002") } {

set dest $oprtr

#handoff callappl leg_incoming default "DESTINATION=$dest"

leg setup $dest callInfo leg_incoming

} else {

if { $status == "cd_006" } {

set busyPrompt _dest_unreachable.au

}

puts "\nCall [infotag get con_all] got event $status collecting destination"

set dest [infotag get evt_dcdigits]

if { $dest == "0" } {

set dest $oprtr

#handoff callappl leg_incoming default "DESTINATION=$dest"

leg setup $dest callInfo leg_incoming

} else {

act_Select

}

proc act_CallSetupDone { } {

global busyPrompt

global legConnected

set status [infotag get evt_status]

if { $status == "ls_000" } {

puts "\n Connection success"

handoff appl leg_all default

act_Cleanup

} else {

if { $legConnected == "false" } {

leg proceeding leg_incoming

leg connect leg_incoming

set legConnected true

}

puts "\n Call failed. Play prompt and collect digit"

if { ($status == "ls_007") } {

set busyPrompt _dest_busy.au

}

act_Select

}

proc act_Select { } {

global destination

global promptFlag2

global destBusy

global param1

global fcnt

global retrycnt

global busyPrompt

puts "\n proc act_Select"

set promptFlag2 0

set param1(interruptPrompt) true

set param1(abortKey) *

set param1(terminationKey) #

set param1(dialPlan) true

set param1(dialPlanTerm) true

leg collectdigits leg_incoming param1

if { $fcnt < $retrycnt } {

media play leg_incoming $busyPrompt %s500 _reenter_dest.au

incr fcnt

fsm setstate GETDEST

} else {

act_DestBusy

}

proc act_DestBusy { } {

puts "\n proc act_DestBusy"

media play leg_incoming _disconnect.au

fsm setstate CALLDISCONNECT

}

proc act_Cleanup { } {

call close

}

requiredversion 2.0

init

init_ConfigVars

#----------------------------------

# State Machine

#----------------------------------

set fsm(any_state,ev_disconnected) "act_Cleanup same_state"

set fsm(any_state,ev_disconnect_done) "act_Cleanup same_state"

set fsm(CALL_INIT,ev_setup_indication) "act_Setup GETDEST"

set fsm(GETDEST,ev_collectdigits_done) "act_GotDest HANDOFF"

#set fsm(HANDOFF,ev_returned) "act_CallSetupDone CONTINUE"

set fsm(HANDOFF,ev_setup_done) "act_CallSetupDone CONTINUE"

set fsm(CALLDISCONNECT,ev_media_done) "act_Cleanup same_state"

fsm define fsm CALL_INIT

# Script Approval Signature: C/775c

Also here is a detail debug..

*Feb 25 20:46:54.475: //-1/76B695000000/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=30110

----- ccCallInfo IE subfields -----

cisco-ani=sip:30110@172.20.5.22

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=sip:5555@172.20.5.30:5060

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

*Feb 25 20:46:54.475: //-1/76B695000000/CCAPI/cc_api_call_setup_ind_common:

Interface=0x682705C8, Call Info(

Calling Number=sip:30110@172.20.5.22,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=sip:5555@172.20.5.30:5060(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,

Incoming Dial-peer=5555, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=207

*Feb 25 20:46:54.475: //-1/76B695000000/CCAPI/ccCheckClipClir:

In: Calling Number=sip:30110@172.20.5.22(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

*Feb 25 20:46:54.475: //-1/76B695000000/CCAPI/ccCheckClipClir:

Out: Calling Number=sip:30110@172.20.5.22(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

*Feb 25 20:46:54.475: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 25 20:46:54.475: :cc_get_feature_vsa malloc success

*Feb 25 20:46:54.475: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 25 20:46:54.475: cc_get_feature_vsa count is 1

*Feb 25 20:46:54.475: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 25 20:46:54.475: :FEATURE_VSA attributes are: feature_name:0,feature_time:1768577616,feature_id:97

*Feb 25 20:46:54.475: //207/76B695000000/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=(TON=Unknown, NPI=Unknown))

*Feb 25 20:46:54.479: //207/76B695000000/CCAPI/cc_process_call_setup_ind:

Event=0x68780750

*Feb 25 20:46:54.479: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 5555

*Feb 25 20:46:54.479: //-1//SERV:/AFW_Service_CCInterface: Received Event 26 for service test modulehandle NULL

*Feb 25 20:46:54.479: //-1//SERV:/AFW_Service_GetExecEnv: Script Name = test

*Feb 25 20:46:54.479: //-1//AFW_:/AFW_Process_New: test

*Feb 25 20:46:54.483: //-1//SERV:/AFW_Service_GetExecEnv: Using Cached ExecEnv

*Feb 25 20:46:54.483: //-1//AFW_:EE6A392D60000:/AFW_ExecEnv_SetRoot: Execenv = 0x6A392D60

*Feb 25 20:46:54.483: //-1//AFW_:/AFW_M_TclModule_NewDup:

*Feb 25 20:46:54.483: //-1//AFW_:HN2D887F84:/AFW_M_TclModule_Free: MOD[TclModule_6A39E3D8_0_763740224] ( )

*Feb 25 20:46:54.483: //-1//AFW_:/AFW_M_FSM_Free:

*Feb 25 20:46:54.483: //-1//AFW_:/AFW_Process_Lock: pProcess(0x69681700)=1

*Feb 25 20:46:54.483: //-1//AFW_:LP:EE6A392D60000:HN2D967E88:/AFW_M_Object_SetExecEnv: ObjCount: 1, CmdPending 0

*Feb 25 20:46:54.483: //-1//AFW_:EE6A392D60000:/AFW_ExecEnv_RestoreDataBackup: Restore DataArea from Script

*Feb 25 20:46:54.487: //-1//AFW_:EE6A392D60000:/AFW_ExecEnv_RestoreDataBackup: Restore TokenTable from Script

*Feb 25 20:46:54.487: //-1//AFW_:EE6A392D60000:/AFW_ExecEnv_Initiate: Execenv = 0x6A392D60

*Feb 25 20:46:54.487: //-1//AFW_:EE6A392D60000:/AFW_ExecEnv_SetCallCorID:

*Feb 25 20:46:54.487: CallCorID is v6

*Feb 25 20:46:54.487: //-1//AFW_:/AFW_Leg_New:

*Feb 25 20:46:54.487: //207/76B695000000/CCAPI/ccCallSetContext:

Context=0x697CA32C

*Feb 25 20:46:54.487: //207//AFW_:/AFW_M_Leg_SetExecEnv:

*Feb 25 20:46:54.487: //-1//AFW_:EE6A392D60000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 1

*Feb 25 20:46:54.487: //-1//AFW_:LP:EE6A392D60000:LG207:/AFW_M_Object_SetExecEnv: ObjCount: 2, CmdPending 1

*Feb 25 20:46:54.487: //207//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener

*Feb 25 20:46:54.487: //207//AFW_:/AFW_M_Leg_GetHandle: Leg handle: LEG_207

*Feb 25 20:46:54.487: //-1//AFW_:EE6A392D60000:/AFW_ExecEnv_AssignCall: Execenv = 0x6A392D60, Leg = 207, Peer_Tag = 5555

*Feb 25 20:46:54.487: //207//AFW_:/AFW_ExecEnv_SetCallCorID:

*Feb 25 20:46:54.487: CallCorID is v6

*Feb 25 20:46:54.487: //207/76B695000000/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 207 with tag 5555 to app "_ManagedAppProcess_test"

*Feb 25 20:46:54.487: //-1//SERV:/AFW_Service_Process_Space:

*Feb 25 20:46:54.487: Process Started

*Feb 25 20:46:54.487: //-1//AFW_:/AFW_Process_Register: ccAppInitialize(name: _ManagedAppProcess_test)

*Feb 25 20:46:54.491: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

*Feb 25 20:46:54.491: //207//AFW_:/AFW_Process_GetCcqEvent: Received

*Feb 25 20:46:54.491: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_SETUP_IND(34)] {

*Feb 25 20:46:54.491: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x6A392D60][test]

*Feb 25 20:46:54.491: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[207][LEG_INIT(0)][Cause(0)]

*Feb 25 20:46:54.491: //-1//AFW_:/AFW_Process_GetCcqEvent: }

*Feb 25 20:46:54.491: //207//SSIN:/AFW_SS_MapEvent:

*Feb 25 20:46:54.491: //-1//SSIN:/AFW_SS_SIP_MapEvent:

*Feb 25 20:46:54.491: //207/76B695000000/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 6C type 0

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/incrementIncomingDialPeerCallStats: Incrementing call-stat to [1] for dial-peer [5555]

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/AFW_Object_WalkListeners:

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/AFW_M_Object_ShowListeners: START

*Feb 25 20:46:54.495: //-1//AFW_:/AFW_M_Object_ShowListeners:

*Feb 25 20:46:54.495: //207//AFW_:/AFW_M_Module_GetHandle: Module handle: TclModule_682ADEDC_0_764837512MOD[TclModule_682ADEDC_0_764837512] (

*Feb 25 20:46:54.495: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[207][LEG_INCINIT(1)][Cause(0)]

*Feb 25 20:46:54.495: //-1//AFW_:/AFW_M_Object_ShowListeners: )

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/AFW_M_Object_ShowListeners: END

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule

*Feb 25 20:46:54.495: //207//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_682ADEDC_0_764837512 ---> TclModule_682ADEDC_0_764837512

*Feb 25 20:46:54.495: //207//AFW_:/AFW_M_TclModule_Action:

*Feb 25 20:46:54.495: //207//AFW_:/AFW_TclModule_DefaultEvHandling:

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/AFW_Leg_CheckIncomingCallBlock:

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/AFW_Leg_SettlementValidateCall: target=, tokenp=0x0

*Feb 25 20:46:54.495: //207/76B695000000/AFW_:/AFW_Leg_IncomingTranslate:

*Feb 25 20:46:54.495: //-1//AFW_:/AFW_Leg_IncomingTranslate: Tag(5555) calling[30110] redirect[] called[5555]

*Feb 25 20:46:54.495: //207//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(CALL_INIT[1],ev_setup_indication[34])---[act_Setup]------

*Feb 25 20:46:54.495: //207//TCL :/tcl_PutsObjCmd: proc act_Setup

*Feb 25 20:46:54.495:

*Feb 25 20:46:54.495: //207//TCL :/tcl_PutsObjCmd:

proc init_perCallvars

*Feb 25 20:46:54.495:

*Feb 25 20:46:54.499: //207//TCL :/tcl_InfotagObjCmd: infotag get leg_ani

*Feb 25 20:46:54.499: //207//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani

*Feb 25 20:46:54.499: //207//AFW_:/vtr_lg_ani: argc 2 argindex 2

*Feb 25 20:46:54.499: //207//TCL :/tcl_PutsObjCmd:

ANI sip:30110@172.20.5.22

*Feb 25 20:46:54.499:

*Feb 25 20:46:54.499: //207//TCL :/tcl_InfotagObjCmd: infotag get leg_dnis

*Feb 25 20:46:54.499: //207//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis

*Feb 25 20:46:54.499: //207//AFW_:/vtr_lg_dnis: argc 2 argindex 2

*Feb 25 20:46:54.499: //207//TCL :/tcl_PutsObjCmd:

DNIS sip:5555@172.20.5.30:5060

*Feb 25 20:46:54.499:

*Feb 25 20:46:54.499: //207//TCL :/tcl_InfotagObjCmd: infotag set med_language 1

*Feb 25 20:46:54.499: //207//TCL :/tcl_InfotagSetObjCmd: infotag set med_language 1

*Feb 25 20:46:54.499: //207//AFW_:/vtw_ms_language: argc 3 argindex 2

*Feb 25 20:46:54.499: //207//TCL :/tcl_LegObjCmd: leg setupack leg_incoming

*Feb 25 20:46:54.499: //207//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming

*Feb 25 20:46:54.499: //207//AFW_:/vtd_lg_incoming: argc 2

*Feb 25 20:46:54.499: //207//AFW_:/vtd_lg_incoming: Legs [207 ]

*Feb 25 20:46:54.499: //207//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1

*Feb 25 20:46:54.499: //207/76B695000000/CCAPI/ccCallSetupAck:

Call Id=207

*Feb 25 20:46:54.503: //207/76B695000000/CCAPI/cc_api_set_transfer_info:

Transfer Number=, Transfer Reason=0x0

*Feb 25 20:46:54.503: //207//TCL :/tcl_LegObjCmd: leg setup sip:5555@172.20.5.30:5060 callInfo leg_incoming

*Feb 25 20:46:54.503: //207//CSPK:/tcl_LegSetupObjCmd: leg setup sip:5555@172.20.5.30:5060 callInfo leg_incoming

*Feb 25 20:46:54.503: //207//AFW_:/vtd_lg_incoming: argc 4

*Feb 25 20:46:54.503: //207//AFW_:/vtd_lg_incoming: Legs [207 ]

*Feb 25 20:46:54.503: //207//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1

*Feb 25 20:46:54.503: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: copied

*Feb 25 20:46:54.503: //-1/xxxxxxxxxxxx/CCAPI/ccGetMemPoolFromContainer:

mempool not found from usrContainer(69491D2C)

*Feb 25 20:46:54.503: //-1/xxxxxxxxxxxx/CCAPI/ccCreateMemPoolInContainer:

Mempool(6A33B728) created in usrContainer(69491D2C)

*Feb 25 20:46:54.503: //-1//AFW_:/AFW_Util_GetUsrContainerData: Container is present, No data

*Feb 25 20:46:54.503: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: Successful in setting 'CC_TAG_SETUP_REQ_DATA' in usrContainer(69491D2C)

*Feb 25 20:46:54.503: ccDumpTdRequestDataGen:

*Feb 25 20:46:54.503: called_urip=NULL

*Feb 25 20:46:54.503: calling_urip=sip:30110@172.20.5.22

*Feb 25 20:46:54.503: url_dump_header_line_avpair:

*Feb 25 20:46:54.503: num_bodies = 0

*Feb 25 20:46:54.503: //-1//AFW_:/AFW_Util_CopyLegServiceCodeToCallInfoContainer: Service Code not available on Leg

*Feb 25 20:46:54.503: //-1//AFW_:/AFW_Util_CopyLegRouteCodeToCallInfoContainer: Route Code not available on Leg

*Feb 25 20:46:54.503: //-1//AFW_:/AFW_Util_GetTgCicValue: CIC Not found for tag(56)

*Feb 25 20:46:54.507: //-1//CSPK:/tclSetControlParams: alertTime(30) = 30

*Feb 25 20:46:54.507: //207//CSPK:/C_CallSetup_Start: ControlInfo = 0x6A3AD338, callInfo = 0x6A3C1658, destination[0]=sip:5555@172.20.5.30:5060

*Feb 25 20:46:54.507: //207//CSPK:/C_CallSetup_Start: configured mode=rotary (1)

*Feb 25 20:46:54.507: //207//CSPK:/C_CallSetup_Start: configured reroutemode=rotary (1)

*Feb 25 20:46:54.507: //-1//Call:/AFW_CallSetup_New:

*Feb 25 20:46:54.507: //-1//AFW_:/AFW_FSM_New:

*Feb 25 20:46:54.507: //-1//AFW_:LP:EE6A392D60000:HN2D967EA0:/AFW_M_Object_SetExecEnv: ObjCount: 3, CmdPending 1

*Feb 25 20:46:54.507: //207//AFW_:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 2

*Feb 25 20:46:54.507: //207//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener

*Feb 25 20:46:54.507: //207//AFW_:/AFW_M_Module_GetHandle: Module handle: CallSetup_698003EC_0_764837536

*Feb 25 20:46:54.507: //207//Call:/AFW_CallSetup_AddDest: sip:5555@172.20.5.30:5060 index 0

*Feb 25 20:46:54.507: //207//Call:/AFW_CallSetup_SetIntWrkLeg:

*Feb 25 20:46:54.507: //207/76B695000000/AFW_:/AFW_Leg_SignalPeerGet: Leg [207]

*Feb 25 20:46:54.507: //-1//AFW_:/C_PackageSession_GetSigPeer:

*Feb 25 20:46:54.507: //207/76B695000000/AFW_:/C_PackageSession_GetSigPeer: No Interworking module on Leg, no signal peer

*Feb 25 20:46:54.511: //207//Call:/AFW_M_CallSetup_Initiate:

*Feb 25 20:46:54.511: //207/76B695000000/AFW_:/AFW_Leg_SetMLPPInfo: Unable to set mlpp info

*Feb 25 20:46:54.511: //-1//AFW_:/AFW_Util_CopyMLPPDataToCallInfoContainer: Voice MLPP not configured

*Feb 25 20:46:54.511: //207//Call:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set

*Feb 25 20:46:54.511: //207//Call:/CallSetupInitiate:

*Feb 25 20:46:54.511: //207//Call:/CS_Placecall:

*Feb 25 20:46:54.511: //207//Call:/setupHuntGroupInfo: hunt group destination has to be E.164 number

*Feb 25 20:46:54.511: //207/76B695000000/AFW_:/AFW_Leg_SetMLPPInfo: Unable to set mlpp info

*Feb 25 20:46:54.511: //-1//AFW_:/AFW_Util_CopyMLPPDataToCallInfoContainer: Voice MLPP not configured

*Feb 25 20:46:54.511: //207//Call:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set

*Feb 25 20:46:54.511: //-1//Dest:/AFW_Destination_New:

*Feb 25 20:46:54.511: //-1//AFW_:/AFW_FSM_New:

*Feb 25 20:46:54.511: //-1//AFW_:LP:EE6A392D60000:HN2D967EA4:/AFW_M_Object_SetExecEnv: ObjCount: 4, CmdPending 2

*Feb 25 20:46:54.511: //207//AFW_:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 3

*Feb 25 20:46:54.511: //207//AFW_:/AFW_Object_AddListener: adding Module CallSetup as listener

*Feb 25 20:46:54.511: //207//AFW_:/AFW_M_Module_GetHandle: Module handle: Destination_698326C4_0_764837540

*Feb 25 20:46:54.511: //207//Dest:/AFW_Destination_AddDest:

*Feb 25 20:46:54.511: //-1//Dest:/AFW_Destination_AddDest: adding destination "sip:5555@172.20.5.30:5060"

*Feb 25 20:46:54.515: //207//Dest:/AFW_Destination_SetIntWrkLeg: LEG[207 ][LEG_INCACKED(2)][Cause(0)]

*Feb 25 20:46:54.515: //207//Dest:/TermInterworking: current state IW_STATE_INIT, Keep IntWrkLeg

*Feb 25 20:46:54.515: //207//Dest:/DestSetIntWrkLeg:

*Feb 25 20:46:54.515: //207/76B695000000/AFW_:/AFW_Object_AddListener: adding Module Destination as listener

*Feb 25 20:46:54.515: //207//Dest:/AFW_M_Destination_Initiate:

*Feb 25 20:46:54.515: //-1//AFW_:/AFW_Util_SaveRawMsg:

*Feb 25 20:46:54.515: //207//Dest:/AFW_M_Destination_Initiate: Outgoing guid : 76B69500.00010000.00000045.160514AC

*Feb 25 20:46:54.515: Incoming_guid : 00000000.00000000.00000000.00000000

*Feb 25 20:46:54.515: //207//Dest:/AFW_M_Destination_Initiate: Outgoing gcid : BCF7DB89.DB3711E5.81958EDD.70D74969

*Feb 25 20:46:54.515: Incoming_gcid : 00000000.00000000.00000000.00000000

*Feb 25 20:46:54.515: //207/76B695000000/AFW_:/AFW_Leg_SetMLPPInfo: Unable to set mlpp info

*Feb 25 20:46:54.515: //-1//AFW_:/AFW_Util_CopyMLPPDataToCallInfoContainer: Voice MLPP not configured

*Feb 25 20:46:54.515: //207//Dest:/DestUpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] Preempt Hunt Not Set

*Feb 25 20:46:54.515: //207//Dest:/DestSetupInitiate:

*Feb 25 20:46:54.515: //207/76B695000000/SSIN:/AFW_SS_SIP_CheckConsultSetup:

*Feb 25 20:46:54.515: //207//Dest:/DestAddRerouteSetupInfo:

*Feb 25 20:46:54.515: //207//Dest:/DestAddTransferSetupInfo:

*Feb 25 20:46:54.515: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: copied sip:5555@172.20.5.30:5060

*Feb 25 20:46:54.515: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: Successful in setting 'CC_TAG_SETUP_REQ_DATA' in usrContainer(69491D2C)

*Feb 25 20:46:54.515: ccDumpTdRequestDataGen:

*Feb 25 20:46:54.515: called_urip=sip:5555@172.20.5.30:5060

*Feb 25 20:46:54.515: calling_urip=sip:30110@172.20.5.22

*Feb 25 20:46:54.515: url_dump_header_line_avpair:

*Feb 25 20:46:54.515: num_bodies = 0

*Feb 25 20:46:54.515: //207//Dest:/DestMatchDialPeer:

*Feb 25 20:46:54.519: //207//Dest:/DestMatchDialPeer: src carrier id:, tgt carrier id:

*Feb 25 20:46:54.519: //207//Dest:/DestSetupInitiate: Did not match any peers

*Feb 25 20:46:54.519: //207//Dest:/DestResetCallInfo:

*Feb 25 20:46:54.519: //207//Dest:/DestComplete: peer #:0 IW State IW_STATE_INIT, OB State OB_STATE_INIT

*Feb 25 20:46:54.519: //-1//Dest:/DestStatusFromDiscCause: mapped "unassigned number (1)"(1) to DEST_INVALID_NUMBER(4)

*Feb 25 20:46:54.519: //-1//AFW_:/AFW_Event_New: Event ID: ev_destination_done

*Feb 25 20:46:54.519: //207//AFW_:/AFW_Module_ReturnArgEv:

*Feb 25 20:46:54.519: //207//AFW_:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[207 ][LEG_INCACKED(2)][Cause(0)]}

*Feb 25 20:46:54.519: //207/76B695000000/AFW_:/AFW_Object_RemoveListener:

*Feb 25 20:46:54.519: //207//AFW_:/AFW_Module_UnListen: NumObjects: 0

*Feb 25 20:46:54.519: //207//Dest:/DestReturn: Destination Returning(ds_004 Status DEST_INVALID_NUMBER)

*Feb 25 20:46:54.519: //207//Call:/CS_Placecall: Call placed to sip:5555@172.20.5.30:5060 index 0

*Feb 25 20:46:54.519: //207//TCL :/tcl_FSMObjCmd: fsm setstate HANDOFF

*Feb 25 20:46:54.519: //207//TCL :/tcl_FSMSetStateObjCmd: setstate setstate HANDOFF

*Feb 25 20:46:54.519: //207//AFW_:/AFW_FSM_Drive: ACTION END: -------------(HANDOFF[4])---------------

*Feb 25 20:46:54.519: //207//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_682ADEDC_0_764837512 ---> TclModule_682ADEDC_0_764837512

*Feb 25 20:46:54.523: //207/76B695000000/AFW_:/AFW_M_Event_Free:

*Feb 25 20:46:54.523: //207//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

*Feb 25 20:46:54.523: //207//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

*Feb 25 20:46:54.523: //207//AFW_:/AFW_Process_GetPriorityQEvent: Received

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_DESTINATION_DONE(221)] {

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0x6A392D60][test]

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[Destination_698326C4_0_764837540] (

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }

*Feb 25 20:46:54.523: //207//Dest:/AFW_M_Destination_EventPreProcess:

*Feb 25 20:46:54.523: //207//AFW_:/AFW_Object_WalkListeners:

*Feb 25 20:46:54.523: //207//AFW_:/AFW_M_Object_ShowListeners: START

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[CallSetup_698003EC_0_764837536] (

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_698326C4_0_764837540] (

*Feb 25 20:46:54.523: //-1//AFW_:/AFW_M_Object_ShowListeners: )

*Feb 25 20:46:54.523: //207//AFW_:/AFW_M_Object_ShowListeners: END

*Feb 25 20:46:54.523: //207//AFW_:/AFW_Object_WalkListeners: Entering Module : CallSetup

*Feb 25 20:46:54.523: //207/76B695000000/AFW_:/AFW_Object_AddListener: adding Module CallSetup as listener

*Feb 25 20:46:54.523: //207//AFW_:/AFW_Object_RemoveListener:

*Feb 25 20:46:54.523: //207//AFW_:/AFW_Module_UnListen: NumObjects: 1

*Feb 25 20:46:54.527: //207//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> CallSetup_698003EC_0_764837536

*Feb 25 20:46:54.527: //207//Call:/AFW_M_CallSetup_Action:

*Feb 25 20:46:54.527: //207//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(S_PLACECALL[2],ev_destination_done[221])---[C_Action]------

*Feb 25 20:46:54.527: //207//Call:/CS_Placecall_DestDone:

*Feb 25 20:46:54.527: //207//AFW_:/AFW_FSM_Drive: ACTION END: -------------(S_DONE[13])---------------

*Feb 25 20:46:54.527: //-1//AFW_:/AFW_Event_New: Event ID: ev_setup_done

*Feb 25 20:46:54.527: //207//AFW_:/AFW_Module_ReturnArgEv:

*Feb 25 20:46:54.527: //207//AFW_:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[207 ][LEG_INCACKED(2)][Cause(0)]}

*Feb 25 20:46:54.527: //207/76B695000000/AFW_:/AFW_Object_RemoveListener:

*Feb 25 20:46:54.527: //207//AFW_:/AFW_Module_UnListen: NumObjects: 0

*Feb 25 20:46:54.527: //207//Call:/CS_Complete: CallSetup Returning(ls_004 Status CS_INVALID_NUMBER)

*Feb 25 20:46:54.527: //207//AFW_:/AFW_ExecEnv_SetModuleScope: CallSetup_698003EC_0_764837536 ---> NULL

*Feb 25 20:46:54.527: //207//AFW_:/AFW_Object_WalkListeners:

*Feb 25 20:46:54.527: //207//AFW_:/AFW_M_Object_ShowListeners: START

*Feb 25 20:46:54.527: //207//AFW_:/AFW_M_Object_ShowListeners: END

*Feb 25 20:46:54.527: //207//AFW_:/AFW_M_Event_Free:

*Feb 25 20:46:54.527: //207//AFW_:/AFW_M_Event_Free: MODULEDONEEVENT for a Module: Destination_698326C4_0_764837540

*Feb 25 20:46:54.527: //207//AFW_:/AFW_Object_RemoveAllListener:

*Feb 25 20:46:54.527: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 3, CmdPending 3

*Feb 25 20:46:54.527: //207//AFW_:/AFW_ExecEnv_DecrNPendingCmd: PendingCmdCount: 2

*Feb 25 20:46:54.527: //-1//AFW_:HN2D967EA4:/AFW_M_Event_Free: ExecEnv objCount: 3

*Feb 25 20:46:54.527: //-1//Dest:HN2D967EA4:/AFW_M_Destination_Free:

*Feb 25 20:46:54.527: //-1//Dest:HN2D967EA4:/DestEmptyPreemptHoldQ:

*Feb 25 20:46:54.527: //-1//AFW_:/AFW_M_FSM_Free:

*Feb 25 20:46:54.531: //207//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 2

*Feb 25 20:46:54.531: //207//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

*Feb 25 20:46:54.531: //207//AFW_:/AFW_Process_GetPriorityQEvent: Received

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_CALLSETUP_DONE(219)] {

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0x6A392D60][test]

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[CallSetup_698003EC_0_764837536] (

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }

*Feb 25 20:46:54.531: //207//Call:/AFW_M_CallSetup_EventPreProcess:

*Feb 25 20:46:54.531: //207//AFW_:/AFW_Object_WalkListeners:

*Feb 25 20:46:54.531: //207//AFW_:/AFW_M_Object_ShowListeners: START

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_682ADEDC_0_764837512] (

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[207][LEG_INCACKED(2)][Cause(0)]

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[CallSetup_698003EC_0_764837536] (

*Feb 25 20:46:54.531: //-1//AFW_:/AFW_M_Object_ShowListeners: )

*Feb 25 20:46:54.531: //207//AFW_:/AFW_M_Object_ShowListeners: END

*Feb 25 20:46:54.531: //207//AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule

*Feb 25 20:46:54.531: //207/76B695000000/AFW_:/AFW_Object_AddListener: adding Module TclModule as listener

*Feb 25 20:46:54.535: //207//AFW_:/AFW_Object_RemoveListener:

*Feb 25 20:46:54.535: //207//AFW_:/AFW_Module_UnListen: NumObjects: 1

*Feb 25 20:46:54.535: //207//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_682ADEDC_0_764837512

*Feb 25 20:46:54.535: //207//AFW_:/AFW_M_TclModule_Action:

*Feb 25 20:46:54.535: //207//AFW_:/AFW_TclModule_DefaultEvHandling:

*Feb 25 20:46:54.535: //207//AFW_:/AFW_TclModule_UpdateSessionMask: Update intwrk-mask for leg [207]'s session

*Feb 25 20:46:54.535: //207//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(HANDOFF[4],ev_setup_done[219])---[act_CallSetupDone]------

*Feb 25 20:46:54.535: //207//TCL :/tcl_InfotagObjCmd: infotag get evt_status

*Feb 25 20:46:54.535: //207//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status

*Feb 25 20:46:54.535: //207//AFW_:/vtr_ev_status: argc 2 argindex 2

*Feb 25 20:46:54.535: //207//TCL :/tcl_PutsObjCmd:

Call failed. Play prompt and collect digit

*Feb 25 20:46:54.535:

*Feb 25 20:46:54.535: //207//TCL :/tcl_PutsObjCmd:

proc act_Select

*Feb 25 20:46:54.535:

*Feb 25 20:46:54.535: //207//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1

*Feb 25 20:46:54.535: //207//Digi:/tcl_LegDigitCollectObjCmd: collectdigits leg_incoming param1

*Feb 25 20:46:54.535: //207//AFW_:/vtd_lg_incoming: argc 3

*Feb 25 20:46:54.535: //207//AFW_:/vtd_lg_incoming: Legs [207 ]

*Feb 25 20:46:54.539: //207//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1

*Feb 25 20:46:54.539: //207//Digi:/C_DigitCollect_Start: Leg=207, Dialplan=True, Diaplanterm=True, DigitReport=False, DigitConsume=False IgnoreInitialKey=False, IgnoreDTMFacceptINFO=False, Abortkey=*, Terminationkey=#, Numpatterns=0, Maxdigits=0, Mindigits=0, Interruptprompt=True, InterTimeout=-1000, Initialtimeout=-1000, Mask=0 Hotword=0

*Feb 25 20:46:54.539: //-1//Digi:/AFW_DigitCollect_New:

*Feb 25 20:46:54.539: //-1//Digi:HN2D967EC0:/AFW_DigitCollect_New: DialPlan=TRUE AbortKey=* TermKey=# NumPatts=0

Enable=FALSE Consume=FALSE InterruptPrompt=TRUE minDigits=0 maxDigits=0 DialPlanTerm=TRUE hotword=0

*Feb 25 20:46:54.539: //-1//AFW_:LP:EE6A392D60000:HN2D967EC0:/AFW_M_Object_SetExecEnv: ObjCount: 4, CmdPending 2

*Feb 25 20:46:54.539: //207//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener

*Feb 25 20:46:54.539: //207//AFW_:/AFW_M_Module_GetHandle: Module handle: DigitCollect_6A37B624_0_764837568

*Feb 25 20:46:54.539: //207/76B695000000/AFW_:/AFW_Object_AddListener: adding Module DigitCollect as listener

*Feb 25 20:46:54.539: //207/76B695000000/Digi:/DigitCollectStart_UpdateStats:

*Feb 25 20:46:54.539: //207//Digi:/DigitCollect_MLPPTuning:

*Feb 25 20:46:54.539: //207/76B695000000/AFW_:/AFW_Leg_PrecedenceDcValid:

*Feb 25 20:46:54.539: //207//Digi:/DigitCollect_MLPPTuning: Precednece Digit Collect not enabled

*Feb 25 20:46:54.539: //207/76B695000000/AFW_:/AFW_Leg_TypeAheadGet: no chars in buffer.

*Feb 25 20:46:54.539: //207/76B695000000/CCAPI/ccSetDigitTimeouts:

Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)

*Feb 25 20:46:54.539: //207/76B695000000/CCAPI/ccSetDigitTimeouts:

Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))

*Feb 25 20:46:54.539: //207/76B695000000/AFW_:/AFW_Leg_DigitReportEnable: Current: 0x0 Input: 0x2

*Feb 25 20:46:54.539: //207/76B695000000/CCAPI/ccCallReportDigits:

(callID=0xCF, digit_event=0x1, enable=TRUE, consume=FALSE)

*Feb 25 20:46:54.539: //207/76B695000000/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=207

*Feb 25 20:46:54.543: //207//TCL :/tcl_PutsObjCmd:

proc act_DestBusy

*Feb 25 20:46:54.543:

*Feb 25 20:46:54.543: //207//PACK:/tcl_MediaObjCmd: media play leg_incoming _disconnect.au

*Feb 25 20:46:54.543: //207//PACK:/tcl_MediaPlayObjCmd: play leg_incoming _disconnect.au

*Feb 25 20:46:54.543: //207//AFW_:/vtd_lg_incoming: argc 3

*Feb 25 20:46:54.543: //207//AFW_:/vtd_lg_incoming: Legs [207 ]

*Feb 25 20:46:54.543: //207//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1

*Feb 25 20:46:54.543: //-1//DPM :DP86:/pc_mc_addToDynamicS: (1) _disconnect.au

*Feb 25 20:46:54.543: //-1//DPM :DP86:/pc_mc_addToDynamicS: Doing : _disconnect.au

*Feb 25 20:46:54.543: //-1//DPM :/mcTokenizerGetNext: savedcharptr=_ endptrptr=_disconnect.au

*Feb 25 20:46:54.543: //-1//DPM :DP86:/pc_mc_addToDynamicS: Token : _disconnect.au status 1

*Feb 25 20:46:54.543: //-1//DPM :DP86:/pc_mc_addToDynamicS: call dp_mcDQfromFileDynamic() to Handle relative file name

*Feb 25 20:46:54.543: //-1//DPM :DP86:/dp_mcDQfromFileDynamic: pLanguage=en FileName=_disconnect.au

*Feb 25 20:46:54.543: //-1//DPM :DP86:/dp_mcDQfromURL: file=flash:en_disconnect.au

*Feb 25 20:46:54.543: //-1//MCM :/mc_createFromFileUrl: Getting a media content: name=en_disconnect.au

url=flash:en_disconnect.au

load fast, fetchtimeout=-1

*Feb 25 20:46:54.543: //-1//MCM :MC14:/mc_getFromUrlName: en_disconnect.au on ram

*Feb 25 20:46:54.543: //-1//MCM :/mc_createFromFileUrl: Found a good mc (0x6A3AF9A8), RefCount(1)

*Feb 25 20:46:54.543: //-1//DPM :DP86:/dp_mcDQfromURL: mc_createFromFileUrl OK

*Feb 25 20:46:54.543: //-1//DPM :/mcTokenizerGetNext: savedcharptr= endptrptr=

*Feb 25 20:46:54.543: //207//PACK:/Media_Play_Start:

*Feb 25 20:46:54.547: //-1//PACK:/Media_Play_Start: pLeg->stream is null, call msw_create()

*Feb 25 20:46:54.547: //207//MSW :/msw_create: cbf=0x62A841C0

*Feb 25 20:46:54.547: //-1//MSM :MS111:/ms_create: Iniz ply_timer

*Feb 25 20:46:54.547: //207//MSW :/msw_synth_open: mediaStream 0x6A333418 created

*Feb 25 20:46:54.547: //207//MSW :/msw_synth_open: rtspStream 0x6848A1EC created,

status=RTSP_STATUS_SUCCESS, session_id=0x6F (111)

*Feb 25 20:46:54.547: //207//MSW :/msw_synth_open: AIS : Creating TTS AIS Backend record

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_create_session:

url=rtsp://tts-en-us/synthesizer, type=SYNTHESIZER, streamcontext=0x68489B54, sess_id=-1

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_create_session:

New SCB creation

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_add_synthesizer_fsm:

adding synthesizer fsm

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_add_connection_fsm:

adding connection fsm

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_add_connection_fsm:

adding connection (fsm) 6984C360

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_add_rtpsetup_fsm:

adding rtpsetup fsm

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_create_svr_session_url:

url=rtsp://tts-en-us/synthesizer

*Feb 25 20:46:54.547: //-1//MRCP:/hash_get:

Table=mrcp_host_stat_table, Key=12

*Feb 25 20:46:54.547: //-1//MSM :MS112:/ms_create: Iniz ply_timer

*Feb 25 20:46:54.547: //207//MSW :/msw_recrd_open:

*Feb 25 20:46:54.547: :msw_recrd_open mediaStream 0x69773964 created

*Feb 25 20:46:54.547: //207//MSW :/msw_recrd_open: rtspStream 0x6848A1F4 created,

status=RTSP_STATUS_SUCCESS, session_id=0x70 (112)

*Feb 25 20:46:54.547: //207//MSW :/msw_recog_open: AIS : Creating ASR AIS Backend record

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_create_session:

url=rtsp://asr-en-us/recognizer, type=RECOGNIZER, streamcontext=0x68489C10, sess_id=57

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_create_session:

Already an SCB is created for this call

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_add_recognizer_fsm:

adding recognizer fsm

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_add_connection_fsm:

adding connection fsm

*Feb 25 20:46:54.547: //207//MRCP:/mrcp_add_connection_fsm:

adding connection (fsm) 6A35AF84

*Feb 25 20:46:54.551: //207//MRCP:/mrcp_add_rtpsetup_fsm:

adding rtpsetup fsm

*Feb 25 20:46:54.551: //207//MRCP:/mrcp_create_svr_session_url:

url=rtsp://asr-en-us/recognizer

*Feb 25 20:46:54.551: //-1//MRCP:/hash_get:

Table=mrcp_host_stat_table, Key=41

*Feb 25 20:46:54.551: //-1//PACK:/Media_Play_Start: msw_create() succeed to create stream [56]

*Feb 25 20:46:54.551: //-1//MSW :/msw_associate_call:

*Feb 25 20:46:54.551: msw_associate_call: callID=0xCF(207),

genericStream=0x68489B50

*Feb 25 20:46:54.551: //207//MRCP:/mrcp_associate_call:

sess_id=57, type=SYNTHESIZER

*Feb 25 20:46:54.551: //207//MRCP:/mrcp_associate_call:

sess_id=57, type=RECOGNIZER

*Feb 25 20:46:54.551: //207//MSW :/msw_synth_get_stream_state: genericStream 0x68489B50 is in state MSW_S_IDLE

*Feb 25 20:46:54.551: //-1//MSW :/msw_synth_start:

*Feb 25 20:46:54.551: msw_synth_start: Enter...

*Feb 25 20:46:54.551: //207//MSW :/msw_synth_start: current_stream_id=1, content 0x0, dynamicS 0x6976D738, current_state=MSW_S_IDLE

*Feb 25 20:46:54.551: //-1//MSW :/msw_synth_start: msw_synth_start: p_mcDynamicS, We've been given a list of URLs to play.

*Feb 25 20:46:54.551: //-1//MSW :/msw_synth_start: p_mcDynamicQ is NOT empty

*Feb 25 20:46:54.551: //207//MSW :/msu_synth_partial_play: Media Stream URL

*Feb 25 20:46:54.551: //207/76B695000000/CCAPI/ccAssociateStream:

Coder=5, DTMF Relay=1, Vad=0,

Record Function=0x0, Event Queue=0x6876B948, Stream Context=6A333418,

Record Context=0x0, Stream Call Id=208, Call Id=207

*Feb 25 20:46:54.551: //207/76B695000000/CCAPI/ccAssociateStream:

Call Entry(Stream Status=2, Digit Enable=FALSE)

*Feb 25 20:46:54.551: //207//TCL :/tcl_FSMObjCmd: fsm setstate CALLDISCONNECT

*Feb 25 20:46:54.551: //207//TCL :/tcl_FSMSetStateObjCmd: setstate setstate CALLDISCONNECT

*Feb 25 20:46:54.551: //207//AFW_:/AFW_FSM_Drive: ACTION END: -------------(CALLDISCONNECT[2])

yawming · ‎02-25-2016

From the log you have, this may relate to the environment you have. Can you please briefly describe what you are trying to achieve ? Maybe we can find another script fit your application better.

jvazquez · ‎02-25-2016

From call manager dial in to GW script ( DN 5555 dia peer trough sip trunk) scripts answer ask for destination number to connect call trough PSTN or another extension on CM and connect them .

-- CM Ext 30110-- sip trunk DN 555 ---> GW--Tranfer to Destination number -- PSTN or another extension on CM

Thanks in advance

yawming · ‎02-25-2016

Here is the issue:

DNIS sip:5555@172.20.5.30:5060

After certain version of IOS, infortag (dnis [infotag get leg_dnis]) is not returning number only, it returns above.

Solution is to extract the number or use new command.

Pleas see the following post

New Tcl IVR command option for "infotag get leg_dnis" and "infotag get leg_ani"

jvazquez · ‎02-25-2016

You are totally right -- i mad call trough FXS or sccp .. and it works since it is no sip and for SIP also made transformation for dest 5555@xxxxxx to 5555 and it works

thanks

what tool do you suggest for recording new audio (prompts) to customize my self

yawming · ‎02-25-2016

Not following recording tool lately but any tool can record .wav or .au file should be fine. I would use G.711 format to be safe.

jvazquez · ‎02-26-2016

How do i modify length destination ??? always keep 5 set dest [infotag get evt_dcdigits] from this event here

*Feb 26 20:23:16.691: //65//TCL :/tcl_PutsObjCmd:

DESTLEN: 5

*Feb 26 20:23:16.691:

yawming · ‎02-26-2016

if [infotag get cfg_avpair_exists max-extension-length] {

set maxExtensionLen [string trim [infotag get cfg_avpair max-extension-length]]

if { $maxExtensionLen < 0 } {

call close

}

} else {

set maxExtensionLen 5

}

so you need to add "param max-extension-length ?" to the max digit length you want in the following

Default is 5

service test its-CISCO.tcl

paramspace english index 1

paramspace english lenguage en

paramspace english location flash:

param aa-pilot 5555

param operator 9998

jvazquez · ‎02-26-2016

I believe thats the extension witch im calling and the one for connect destination is different

global maxExtensionLen -- > this one i could modified to 13 with out problem param max-extension-length

global destExtLen -- this one is what i need to increase

MAXEXTENSION: 13

*Feb 26 22:10:41.615:

*Feb 26 22:10:41.615: //90//TCL :/tcl_InfotagObjCmd: infotag get evt_dcdigits

*Feb 26 22:10:41.615: //90//TCL :/tcl_InfotagGetObjCmd: infotag get evt_dcdigits

*Feb 26 22:10:41.615: //90//AFW_:/vtr_ev_dcdigits: argc 2

*Feb 26 22:10:41.615: //90//AFW_:/vtr_ev_dcdigits: DCDIGITS [33300]

*Feb 26 22:10:41.615: //90//TCL :/tcl_PutsObjCmd:

DESTLEN: 5

*Feb 26 22:10:41.615:

*Feb 26 22:10:41.615: //90//TCL :/tcl_PutsObjCmd:

extLength: 8

*Feb 26 22:10:41.615:

*Feb 26 22:10:41.615: //90//TCL :/tcl_PutsObjCmd:

DIGITS: 33300

so i enter when ask for destination 330038670 and cut it to the first 5 digits

proc act_GotDest { } {

global dest

global maxExtensionLen

global destExtLen

global callInfo

global oprtr

global busyPrompt

global pin

puts "\n proc act_GotDest"

set status [infotag get evt_status]

set callInfo(alertTime) 30

puts "\n STATUS: $status"

puts "\n MAXEXTENSION: $maxExtensionLen"

if { ($status == "cd_004") } {

set dest [infotag get evt_dcdigits]

set destExtLen [string length $dest]

puts "\n DESTLEN: $destExtLen"

set extLength [expr $maxExtensionLen - $destExtLen]

if { $dest == "0" } {

set dest $oprtr

}

puts "\n extLength: $extLength"

puts "\n DIGITS: $dest"

yawming · ‎02-26-2016

you may need to reload the service after adding new param

jvazquez · ‎02-26-2016

did that too but no work,

from my interpretation of code i don see relation between maxExtensionLen and destExtLen

as you mention maxExtensionLen is defined on parameter begin of code and i think is used for extension i am using to dial in to service but destExtLen gets digits from evt_dcdigits witch are digit that i enter when audio prompt is played but only takes first 5 .. any ideas ?

c act_GotDest { } {

global dest

global maxExtensionLen

global destExtLen

global callInfo

global oprtr

global busyPrompt

global pin

puts "\n proc act_GotDest"

set status [infotag get evt_status]

set callInfo(alertTime) 30

puts "\n STATUS: $status"

puts "\n MAXEXTENSION: $maxExtensionLen"

if { ($status == "cd_004") } {

set dest [infotag get evt_dcdigits]

set destExtLen [string length $dest]

yawming · ‎02-26-2016

Can you please attach debug ?

yawming · ‎02-26-2016

Ok,

	set param1(dialPlan) true
	leg collectdigits leg_incoming param1

dialPlan is set to true so check you IOS dial-plan

other way to do is like this:

set param1(interruptPrompt) true

set param1(maxDigits) $maxExtensionLen

set param1(initialDigitTimeout) 5

set param1(terminationKey) #

leg collectdigits leg_incoming param1