topic Re: TCL DTMF With 3rd party SIP in Call Control

TCL DTMF With 3rd party SIP

tjohannessen — Tue, 03 Mar 2020 10:57:01 GMT

Hi,

We have an VoiceGateway with a small TCL script playing a prompt and forwarding caller based on pressed nummber.

It works with H323 from CUCM, but the customer want to move to a SIP trunk from a Meta voice switch.

We are having trouble getting digit collection to work, getting DC_UNSUPPORTED in the debug. We have tried rtp nte which many examples use, but according to TCL IVR 2.0 SIP trunk with DMF requires Cisco-rtp. Is this still true, which will mean that 3rd party SIP with TCL and DTMF on IOS woll not work?

Here is the script

# initialize the parameters that configured from IOS CLI
proc init_configs { } {
global welcomePrompt
global thanksPrompt

if [infotag get cfg_avpair_exists welcome-prompt] {
set welcomePrompt [string trim [infotag get cfg_avpair welcome-prompt]]
} else {
set welcomePrompt flash:en_bacd_welcome.au
}

if [infotag get cfg_avpair_exists thanks-prompt] {
set thanksPrompt [string trim [infotag get cfg_avpair thanks-prompt]]
} else {
set thanksPrompt flash:en_bacd_welcome.au
}

}
#procedure to play audio file on incoming dial peer and get selection
proc act_GetDest { } {
global dest
global welcomePrompt
global thanksPrompt
leg setupack leg_incoming
leg proceeding leg_incoming
leg connect leg_incoming

media play leg_incoming $welcomePrompt
set param1(maxDigits) 1
set param1(initialDigitTimeout) 10
leg collectdigits leg_incoming param1

}
#check selection and set destination
proc act_Media { } {
global dest
global thanksPrompt
set status [infotag get evt_status]
set dest [infotag get evt_dcdigits]
media play leg_incoming $thanksPrompt
if {$dest == "1"} {
set dest "99999994"
} elseif {$dest == "2"} {
set dest "12123789805"
}
#else {
# fsm setstate GETDEST
# act_GetDest
# }
}

proc act_Setup { } {
global dest

# if { $status == "cd_004" } {

puts ">>> Tcl: proc act_Setup <<<"
puts ">>> TCL:Final Dest: $dest <<<"
handoff appl leg_incoming default "DESTINATION=$dest"
act_Cleanup
# } else {
# puts "\nCall [infotag get con_all] got event $status while placing an outgoing call"
# call close
# }

}

# disconnect the call
proc act_Cleanup { } {
puts ">>> Tcl: act_Cleanup <<<"
call close
}
init_configs
set fsm(any_state,ev_disconnected) "act_Cleanup same_state"
set fsm(CALL_INIT,ev_setup_indication) "act_GetDest GETDEST"
set fsm(GETDEST,ev_collectdigits_done) "act_Media,PLAYPROMPT"
set fsm(PLAYPROMPT,ev_media_done) "act_Setup PLACECALL"
set fsm(PLACECALL,ev_disconnected) "act_Cleanup CALLDISCONNECT"
set fsm(CALLDISCONNECT,ev_disconnected) "act_Cleanup same_state"
set fsm(CALLDISCONNECT,ev_media_done) "act_Cleanup same_state"
set fsm(CALLDISCONNECT,ev_disconnect_done) "act_Cleanup same_state"

fsm define fsm CALL_INIT

br,

Trond

Re: TCL DTMF With 3rd party SIP

yawming — Tue, 03 Mar 2020 18:28:53 GMT

In most cases, it should work but still need to check on which Voice Gateway? IOS version and IOS configuration, codec, dtmf-relay , dtmf-interworking ?...etc.

Wonder if you can attach ios config and debug log so we can look more detail.

Re: TCL DTMF With 3rd party SIP

tjohannessen — Wed, 04 Mar 2020 09:01:52 GMT

Thanks for a quick response.

It is a C3900e with 15.5(3)M6

Attaching snippet of trace from debuc ccsip mess, media and voice appl all.

Here we try with rtp-nte. I see negotiiated is inband-voice. This is rtp-nte? I begin to wonder. I have seen some using a force rtp-nte...

I dont have the whole config with me here, but i think this is correct an relevant:

application
service select_service3 flash:select_service6.tcl
paramspace english language en
paramspace english index 0
param welcome-prompt flash:welcome.au
param thanks-prompt flash:welcome.au
paramspace english location flash:

voice service voip
ip address trusted list
ipv4 0.0.0.0 0.0.0.0
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
modem passthrough nse codec g711ulaw
h323
call preserve

dial-peer voice 99999997 voip
service select_service3
incoming called-number 99999997
session protocol sipv2
no voice-class sip early-offer forced
dtmf-relay rtp-nte
codec g711ulaw

It is not possible to attach a txt file for som reasone. Sorry for pasting it directly:

Trace:

Received:
INVITE sip:99999997@85.19.204.26:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 161.30.115.10:5060;branch=z9hG4bK+dee9ed31e4d0da44250f362743c3136d1+sip+4+a70f7494
From: <sip:870773904607@10.202.61.4:5060>;tag=161.30.115.10+4+b7cf762d+bb77c49d;isup-oli=00
To: <sip:99999997@85.19.204.26>
CSeq: 771035089 INVITE
Expires: 180
Content-Length: 136
Supported: resource-priority,siprec, 100rel
Contact: <sip:73eb3f8af0ecb5895bf5c79472782523@161.30.115.10:5060>;isup-oli=00
Content-Type: application/sdp
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Call-ID: 0gQAAC8WAAACBAAALxYAABxSbthdbAwcnn3qw1nxUoNkAHSzwf2A91rthdbYcqVj@161.30.115.10
Organization: Metaswitch Networks
Allow: INVITE, ACK, CANCEL, BYE, REGISTER, OPTIONS, PRACK, UPDATE, SUBSCRIBE, NOTIFY, REFER, INFO, PUBLISH
Max-Forwards: 67
P-Asserted-Identity: <sip:870773904607@10.202.61.4:5060>

v=0
o=- 136937161475830 136937161475830 IN IP4 161.30.115.20
s=-
c=IN IP4 161.30.115.20
t=0 0
m=audio 32458 RTP/AVP 0
a=ptime:20

194266: Feb 19 15:01:40.309 UTC: //-1/91677C37A52E/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: STUN Usage is not enabled
194267: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 85.19.204.26
194268: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIDoPtimeNegotiation: Offered ptime:20, Negotiated ptime:20 Negotiated codec bytes: 160 for codec g711ulaw
194269: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec : g711ulaw, bytes :160
Preferred DTMF relay : rtp-nte
Preferred NTE payload : 101
Early Media : No
Delayed Media : No
Bridge Done : No
New Media : No
DSP DNLD Reqd : No

194270: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 85.19.204.26
194271: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIDisplayStreamInfo:
Stream type : voice-only
Media line : 1
State : STREAM_ADDING (2)
Stream address type : 1
Callid : 438017
Peer Callid : -1
RTP/SRTP Negotiated : 8
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : inband-voice
Negotiated NTE payload : 0 (tx), 0 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : [85.19.204.26]:0
Media Dest Addr/Port : [161.30.115.20]:32458

194272: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice-only
Media line : 1
State : STREAM_ADDING (2)
Stream address type : 1
Callid : 438017
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : inband-voice
Negotiated NTE payload : 0 (tx), 0 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : [85.19.204.26]:0
Media Dest Addr/Port : [161.30.115.20]:32458

194273: Feb 19 15:01:40.309 UTC: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 16654 for stream 1
194274: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIDisplayStreamInfo:
Stream type : voice-only
Media line : 1
State : STREAM_ADDING (2)
Stream address type : 1
Callid : 438017
Peer Callid : -1
RTP/SRTP Negotiated : 8
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : inband-voice
Negotiated NTE payload : 0 (tx), 0 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : [85.19.204.26]:16654
Media Dest Addr/Port : [161.30.115.20]:32458

194275: Feb 19 15:01:40.309 UTC: //-1//SERV:/AFW_Service_CCInterface: Received Event 27 for service select_service2 modulehandle NULL
194276: Feb 19 15:01:40.309 UTC: //-1//SERV:/AFW_Service_GetExecEnv: Script Name = select_service2
194277: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0xAD443EC, Type: ExecEnv, RefCount: 1
194278: Feb 19 15:01:40.309 UTC: //-1//SERV:/AFW_Service_GetExecEnv: Using Cached ExecEnv
194279: Feb 19 15:01:40.309 UTC: //-1//AFW_:EE0AD443EC000:/AFW_ExecEnv_SetRoot: Execenv = 0xAD443EC
194280: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: TclModule(Size=360)
194281: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_M_TclModule_NewDup:
194282: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_DataArray_New:
194283: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataArray(Size=80)
194284: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: FSM(Size=104)
194285: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2CEAAB34, Type: TclModule, RefCount: 0
194286: Feb 19 15:01:40.309 UTC: //-1//AFW_:HN3ED774F6:/AFW_M_TclModule_Free:
194287: Feb 19 15:01:40.309 UTC: //-1//AFW_:HN3ED774F6:/AFW_M_Module_GetHandle: Module handle: TclModule_0x2CEAAB34_0_1054307574MOD[TclModule_0x2CEAAB34_0_1054307574] ( )
194288: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x279506CC, Type: FSM, RefCount: 0
194289: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_M_FSM_Free:
194290: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2CDA7008, Type: DataArray, RefCount: 0
194291: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Process_Lock: pProcess(0x29BAF1BC)=1
194292: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x29BAF1BC, Type: Process, RefCount: 2
194293: Feb 19 15:01:40.309 UTC: //-1//AFW_:LP:EE0AD443EC000:HN3ED8C36A:/AFW_M_Object_SetExecEnv: ObjCount: 1, CmdPending 0
194294: Feb 19 15:01:40.309 UTC: //-1//AFW_:EE0AD443EC000:/AFW_ExecEnv_RestoreDataBackup: Restore DataArea from Script
194295: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataArray(Size=80)
194296: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_M_DataArray_NewDup: param_name: Curent_Language, param_namespace: media
194297: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataString(Size=20)
194298: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_DataArray_ElementSetNameSpace: Adding param space media, name Curent_Language, entryKey Curent_Language.media, type DataString
194299: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_DataArray_ElementSet: Adding param: Curent_Language.media, type: DataString
194300: Feb 19 15:01:40.309 UTC: //-1//AFW_:EE0AD443EC000:/AFW_ExecEnv_RestoreDataBackup: Restore TokenTable from Script
194301: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataArray(Size=80)
194302: Feb 19 15:01:40.309 UTC: //-1//AFW_:EE0AD443EC000:/AFW_ExecEnv_Initiate: Execenv = 0xAD443EC
194303: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x25EAF134, Type: TclModule, RefCount: 2
194304: Feb 19 15:01:40.309 UTC: //-1//AFW_:EE0AD443EC000:/AFW_ExecEnv_SetCallCorID:
194305: Feb 19 15:01:40.309 UTC: CallCorID is ^Qg|7R_^Qj%.yfu^SMo
194306: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Leg_New:
194307: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Leg(Size=5520)
194308: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_M_Leg_SetExecEnv:
194309: Feb 19 15:01:40.309 UTC: //-1//AFW_:EE0AD443EC000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 1
194310: Feb 19 15:01:40.309 UTC: //-1//AFW_:LP:EE0AD443EC000:LG438017:/AFW_M_Object_SetExecEnv: ObjCount: 2, CmdPending 1
194311: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener
194312: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_M_Leg_GetHandle: Leg handle: LEG_438017
194313: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_DataArray_ElementSet: Adding param: LEG_438017, type: Leg
194314: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x29C18320, Type: Leg, RefCount: 2
194315: Feb 19 15:01:40.309 UTC: //-1//AFW_:EE0AD443EC000:/AFW_ExecEnv_AssignCall: Execenv = 0xAD443EC, Leg = 438017, Peer_Tag = 99999997
194316: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_ExecEnv_SetCallCorID:
194317: Feb 19 15:01:40.309 UTC: CallCorID is ^Qg|7R_^Qj%.yfu^SMo
194318: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event(0)
194319: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Event(Size=2852)
194320: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_DataList_New:
194321: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194322: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_Process_GetCcqEvent: Received
194323: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_SETUP_IND(37)] {
194324: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0xAD443EC][select_service2]
194325: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[438017][LEG_INIT(0)][Cause(0)]
194326: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: }
194327: Feb 19 15:01:40.309 UTC: //438017//SSIN:/AFW_SS_MapEvent:
194328: Feb 19 15:01:40.309 UTC: //-1//SSIN:/AFW_SS_SIP_MapEvent:
194329: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID EAFD type 0
194330: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/incrementIncomingDialPeerCallStats: Incrementing call-stat to [1] for dial-peer [99999997]
194331: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_WalkListeners:
194332: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Object_ShowListeners: START
194333: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners:
194334: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_M_Module_GetHandle: Module handle: TclModule_0x25EAF134_0_1054393194MOD[TclModule_0x25EAF134_0_1054393194] (
194335: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438017][LEG_INCINIT(1)][Cause(0)]
194336: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194337: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Object_ShowListeners: END
194338: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
194339: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_0x25EAF134_0_1054393194 ---> TclModule_0x25EAF134_0_1054393194
194340: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_M_TclModule_Action:
194341: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_TclModule_DefaultEvHandling:
194342: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_CheckIncomingCallBlock:
194343: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_SettlementValidateCall: target=, tokenp=0x0
194344: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_IncomingTranslate:
194345: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Leg_IncomingTranslate: Tag(99999997) calling[870773904607] redirect[] called[99999997]
194346: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(CALL_INIT[1],ev_setup_indication[37])---[act_preSetup]------
194347: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_PutsObjCmd: Call has come!

194348: Feb 19 15:01:40.309 UTC:
194349: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_LegObjCmd: leg setupack leg_incoming
194350: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_LegSetupAckObjCmd: setupack leg_incoming
194351: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: argc 2
194352: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: Legs [438017 ]
194353: Feb 19 15:01:40.309 UTC: //438017//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
194354: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_LegObjCmd: leg proceeding leg_incoming
194355: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
194356: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: argc 2
194357: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: Legs [438017 ]
194358: Feb 19 15:01:40.309 UTC: //438017//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
194359: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_LegObjCmd: leg connect leg_incoming
194360: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
194361: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: argc 2
194362: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: Legs [438017 ]
194363: Feb 19 15:01:40.309 UTC: //438017//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
194364: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_Connect: prog_ind=0, ccRawMsgInfo_t=0xAF871EC, cData=0
194365: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID EAFD type 1
194366: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_TimerObjCmd: timer start leg_timer 1 leg_incoming
194367: Feb 19 15:01:40.309 UTC: //438017//TCL :/tcl_TimerStartObjCmd: timer start leg_timer 1 leg_incoming
194368: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: argc 4
194369: Feb 19 15:01:40.309 UTC: //438017//AFW_:/vtd_lg_incoming: Legs [438017 ]
194370: Feb 19 15:01:40.309 UTC: //438017//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
194371: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_FSM_Drive: ACTION END: -------------(WAITFORTIMER[6])---------------
194372: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_0x25EAF134_0_1054393194 ---> TclModule_0x25EAF134_0_1054393194
194373: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x29C30B74, Type: Event, RefCount: 0
194374: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Event_Free:
194375: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2CBF5E4C, Type: DataList, RefCount: 0
194376: Feb 19 15:01:40.309 UTC: //-1//AFW_:/AFW_M_DataList_Free:
194377: Feb 19 15:01:40.309 UTC: //438017//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1
194378: Feb 19 15:01:40.309 UTC: //438017/91677C37A52E/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 161.30.115.10:5060;branch=z9hG4bK+dee9ed31e4d0da44250f362743c3136d1+sip+4+a70f7494
From: <sip:870773904607@10.202.61.4:5060>;tag=161.30.115.10+4+b7cf762d+bb77c49d;isup-oli=00
To: <sip:99999997@85.19.204.26>
Date: Wed, 19 Feb 2020 15:01:40 GMT
Call-ID: 0gQAAC8WAAACBAAALxYAABxSbthdbAwcnn3qw1nxUoNkAHSzwf2A91rthdbYcqVj@161.30.115.10
CSeq: 771035089 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.5.3.M6
Content-Length: 0

194379: Feb 19 15:01:40.311 UTC: //438017/91677C37A52E/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 161.30.115.10:5060;branch=z9hG4bK+dee9ed31e4d0da44250f362743c3136d1+sip+4+a70f7494
From: <sip:870773904607@10.202.61.4:5060>;tag=161.30.115.10+4+b7cf762d+bb77c49d;isup-oli=00
To: <sip:99999997@85.19.204.26>;tag=3ED8C36A-1F96
Date: Wed, 19 Feb 2020 15:01:40 GMT
Call-ID: 0gQAAC8WAAACBAAALxYAABxSbthdbAwcnn3qw1nxUoNkAHSzwf2A91rthdbYcqVj@161.30.115.10
CSeq: 771035089 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact: <sip:99999997@85.19.204.26:5060>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-15.5.3.M6
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 191

v=0
o=CiscoSystemsSIP-GW-UserAgent 3373 7414 IN IP4 85.19.204.26
s=SIP Call
c=IN IP4 85.19.204.26
t=0 0
m=audio 16654 RTP/AVP 0
c=IN IP4 85.19.204.26
a=rtpmap:0 PCMU/8000
a=ptime:20

194380: Feb 19 15:01:40.321 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc:0x2CA9B2E0 name:en_enter_card_num.au
194381: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 5911
194382: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 9760
194383: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 5751
194384: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 9920
194385: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 5591
194386: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 10080
194387: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 5431
194388: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 10240
194389: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 5271
194390: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 10400
194391: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 5111
194392: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 10560
194393: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 4951
194394: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 10720
194395: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 4791
194396: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 10880
194397: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 4631
194398: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 11040
194399: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 4471
194400: Feb 19 15:01:40.321 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 11200
194401: Feb 19 15:01:40.321 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc_fill_pakList(): status_fillpak 1, /
mcr->thisMcBytesPlay 11200, mc:0x2CA9B2E0 name:en_enter_card_num.au
194402: Feb 19 15:01:40.321 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: count: 60
194403: Feb 19 15:01:40.321 UTC: //-1//MCM :MR181585:/mc_load_next_mcr_data: mc: 0x2CA9B2E0
194404: Feb 19 15:01:40.321 UTC: //-1//MCM :MR181585:/mc_load_next_mcr_data: count: 60
194405: Feb 19 15:01:40.321 UTC: //438011//MSM :/ms_need_packets_more: mgdTstart(rf) at 1w5d
194406: Feb 19 15:01:40.323 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194407: Feb 19 15:01:40.343 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194408: Feb 19 15:01:40.363 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194409: Feb 19 15:01:40.383 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194410: Feb 19 15:01:40.403 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194411: Feb 19 15:01:40.423 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194412: Feb 19 15:01:40.443 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194413: Feb 19 15:01:40.445 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:99999997@85.19.204.26:5060 SIP/2.0
Via: SIP/2.0/UDP 161.30.115.10:5060;branch=z9hG4bK+8d2cde08c4d30f65211f4f7129b549cc1+sip+4+a70f7495
Call-ID: 0gQAAC8WAAACBAAALxYAABxSbthdbAwcnn3qw1nxUoNkAHSzwf2A91rthdbYcqVj@161.30.115.10
From: <sip:870773904607@10.202.61.4:5060>;tag=161.30.115.10+4+b7cf762d+bb77c49d;isup-oli=00
To: <sip:99999997@85.19.204.26>;tag=3ED8C36A-1F96
CSeq: 771035089 ACK
Contact: <sip:73eb3f8af0ecb5895bf5c79472782523@161.30.115.10:5060>;isup-oli=00
Content-Length: 0
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Max-Forwards: 69
Organization: Metaswitch Networks

194414: Feb 19 15:01:40.463 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194415: Feb 19 15:01:40.483 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194416: Feb 19 15:01:40.503 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194417: Feb 19 15:01:40.521 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc:0x2CA9B2E0 name:en_enter_card_num.au
194418: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 4311
194419: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 11360
194420: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 4151
194421: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 11520
194422: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 3991
194423: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 11680
194424: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 3831
194425: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 11840
194426: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 3671
194427: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 12000
194428: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 3511
194429: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 12160
194430: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 3351
194431: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 12320
194432: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 3191
194433: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 12480
194434: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 3031
194435: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 12640
194436: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 2871
194437: Feb 19 15:01:40.521 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 12800
194438: Feb 19 15:01:40.521 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc_fill_pakList(): status_fillpak 1, /
mcr->thisMcBytesPlay 12800, mc:0x2CA9B2E0 name:en_enter_card_num.au
194439: Feb 19 15:01:40.521 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: count: 60
194440: Feb 19 15:01:40.521 UTC: //-1//MCM :MR181585:/mc_load_next_mcr_data: mc: 0x2CA9B2E0
194441: Feb 19 15:01:40.521 UTC: //-1//MCM :MR181585:/mc_load_next_mcr_data: count: 60
194442: Feb 19 15:01:40.521 UTC: //438011//MSM :/ms_need_packets_more: mgdTstart(rf) at 1w5d
194443: Feb 19 15:01:40.523 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194444: Feb 19 15:01:40.543 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194445: Feb 19 15:01:40.563 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194446: Feb 19 15:01:40.583 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194447: Feb 19 15:01:40.603 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194448: Feb 19 15:01:40.623 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1280 lastFill: 1440, numBytes: 160
194449: Feb 19 15:01:40.645 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 176
194450: Feb 19 15:01:40.665 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194451: Feb 19 15:01:40.685 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194452: Feb 19 15:01:40.705 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194453: Feb 19 15:01:40.721 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc:0x2CA9B2E0 name:en_enter_card_num.au
194454: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 2711
194455: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 12960
194456: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 2551
194457: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 13120
194458: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 2391
194459: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 13280
194460: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 2231
194461: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 13440
194462: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 2071
194463: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 13600
194464: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 1911
194465: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 13760
194466: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 1751
194467: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 13920
194468: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 1591
194469: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 14080
194470: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 1431
194471: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 14240
194472: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 1271
194473: Feb 19 15:01:40.721 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 14400
194474: Feb 19 15:01:40.721 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc_fill_pakList(): status_fillpak 1, /
mcr->thisMcBytesPlay 14400, mc:0x2CA9B2E0 name:en_enter_card_num.au
194475: Feb 19 15:01:40.721 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: count: 60
194476: Feb 19 15:01:40.721 UTC: //-1//MCM :MR181585:/mc_load_next_mcr_data: mc: 0x2CA9B2E0
194477: Feb 19 15:01:40.721 UTC: //-1//MCM :MR181585:/mc_load_next_mcr_data: count: 60
194478: Feb 19 15:01:40.721 UTC: //438011//MSM :/ms_need_packets_more: mgdTstart(rf) at 1w5d
194479: Feb 19 15:01:40.725 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194480: Feb 19 15:01:40.745 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194481: Feb 19 15:01:40.765 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194482: Feb 19 15:01:40.785 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194483: Feb 19 15:01:40.805 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194484: Feb 19 15:01:40.825 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194485: Feb 19 15:01:40.845 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1264 lastFill: 1424, numBytes: 160
194486: Feb 19 15:01:40.867 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 176
194487: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event(0)
194488: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Event(Size=2852)
194489: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_DataList_New:
194490: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194491: Feb 19 15:01:40.877 UTC: //438008/802C12B2DA7F/AFW_:/AFW_Process_GetCcqEvent: Received
194492: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_DIGIT_BEGIN(21)] {
194493: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x27A36924][calling_card]
194494: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[438008][LEG_INCCONNECTED(5)][Cause(0)]
194495: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: }
194496: Feb 19 15:01:40.877 UTC: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
194497: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x29C2DEE4, Type: Event, RefCount: 0
194498: Feb 19 15:01:40.877 UTC: //438008/802C12B2DA7F/AFW_:/AFW_M_Event_Free:
194499: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2CC6B5F8, Type: DataList, RefCount: 0
194500: Feb 19 15:01:40.877 UTC: //-1//AFW_:/AFW_M_DataList_Free:
194501: Feb 19 15:01:40.877 UTC: //438008//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1
194502: Feb 19 15:01:40.887 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194503: Feb 19 15:01:40.907 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194504: Feb 19 15:01:40.921 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc:0x2CA9B2E0 name:en_enter_card_num.au
194505: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 1111
194506: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 14560
194507: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 951
194508: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 14720
194509: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 791
194510: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 14880
194511: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 631
194512: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 15040
194513: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 471
194514: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 15200
194515: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 311
194516: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: (enough data), current_buf: 0x298A2D38 thisMcBytesPlayed: 15360
194517: Feb 19 15:01:40.921 UTC: //-1//MCM :MC7:/mc_make_nohdr_packet: mc->name en_enter_card_num.au, bytesLeftInBuf: 151
194518: Feb 19 15:01:40.921 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: mc_fill_pakList(): status_fillpak 0, /
mcr->thisMcBytesPlay 15360, mc:0x2CA9B2E0 name:en_enter_card_num.au
194519: Feb 19 15:01:40.921 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: count: 56
194520: Feb 19 15:01:40.921 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: No more prompts to play:
194521: Feb 19 15:01:40.921 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: count: 56
194522: Feb 19 15:01:40.921 UTC: //438011//MSM :/ms_need_packets_more: mgdTstart(rf) at 1w5d
194523: Feb 19 15:01:40.927 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194524: Feb 19 15:01:40.947 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194525: Feb 19 15:01:40.967 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194526: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event(0)
194527: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Event(Size=2852)
194528: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_DataList_New:
194529: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194530: Feb 19 15:01:40.977 UTC: //438008/802C12B2DA7F/AFW_:/AFW_Process_GetCcqEvent: Received
194531: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_DIGIT_END(20)] {
194532: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x27A36924][calling_card]
194533: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[438008][LEG_INCCONNECTED(5)][Cause(0)]
194534: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: }
194535: Feb 19 15:01:40.977 UTC: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
194536: Feb 19 15:01:40.977 UTC: //438008/802C12B2DA7F/AFW_:/AFW_M_Leg_EventPreProcess: Passing, not enabled.---
194537: Feb 19 15:01:40.977 UTC: //-1//MSW :/msw_recrd_stop: genericStream=0x261509B8,
mediaStream=0x261280EC, rtspStream=0xAC2D7B4
reason=MSW_RECRD_REASON_INTERRUPTED current_state=MSW_S_IDLE
194538: Feb 19 15:01:40.977 UTC: //-1//MSW :/msw_recrd_stop: Stream not currently active
194539: Feb 19 15:01:40.977 UTC: //438008/802C12B2DA7F/AFW_:/AFW_Object_WalkListeners:
194540: Feb 19 15:01:40.977 UTC: //438008/802C12B2DA7F/AFW_:/AFW_M_Object_ShowListeners: START
194541: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[DigitCollect_0x2CAA93D0_0_1054382808] (
194542: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438008][LEG_INCCONNECTED(5)][Cause(0)]
194543: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194544: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_0x2CEAA2C4_0_1054379466] (
194545: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[DigitCollect_0x2CAA93D0_0_1054382808] (
194546: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438008][LEG_INCCONNECTED(5)][Cause(0)]
194547: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194548: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438008][LEG_INCCONNECTED(5)][Cause(0)]
194549: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194550: Feb 19 15:01:40.977 UTC: //438008/802C12B2DA7F/AFW_:/AFW_M_Object_ShowListeners: END
194551: Feb 19 15:01:40.977 UTC: //438008/802C12B2DA7F/AFW_:/AFW_Object_WalkListeners: Entering Module : DigitCollect
194552: Feb 19 15:01:40.977 UTC: //438008//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> DigitCollect_0x2CAA93D0_0_1054382808
194553: Feb 19 15:01:40.977 UTC: //438008//Digi:/AFW_M_DigitCollect_Action:
194554: Feb 19 15:01:40.977 UTC: //438008//Digi:/act_DCRunning_Digit: : pLeg 438008 Digit 5 Tone Mode 0
194555: Feb 19 15:01:40.977 UTC: //438008//Digi:/DigitCollect_TreatDigit:
194556: Feb 19 15:01:40.977 UTC: //438008//AFW_:/AFW_ExecEnv_SetModuleScope: DigitCollect_0x2CAA93D0_0_1054382808 ---> NULL
194557: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x29C31698, Type: Event, RefCount: 0
194558: Feb 19 15:01:40.977 UTC: //438008/802C12B2DA7F/AFW_:/AFW_M_Event_Free:
194559: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2603DC44, Type: DataList, RefCount: 0
194560: Feb 19 15:01:40.977 UTC: //-1//AFW_:/AFW_M_DataList_Free:
194561: Feb 19 15:01:40.977 UTC: //438008//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1
194562: Feb 19 15:01:40.987 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194563: Feb 19 15:01:41.007 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194564: Feb 19 15:01:41.027 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1248 lastFill: 1408, numBytes: 160
194565: Feb 19 15:01:41.049 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 176
194566: Feb 19 15:01:41.069 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194567: Feb 19 15:01:41.089 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194568: Feb 19 15:01:41.109 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194569: Feb 19 15:01:41.121 UTC: //-1//MCM :MR181585:/mc_make_packets_DQ: No more on reader
194570: Feb 19 15:01:41.121 UTC: //438011//MSM :/ms_need_packets_more: mgdTstart(rf) at 1w5d
194571: Feb 19 15:01:41.129 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194572: Feb 19 15:01:41.149 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194573: Feb 19 15:01:41.169 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194574: Feb 19 15:01:41.189 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194575: Feb 19 15:01:41.209 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194576: Feb 19 15:01:41.229 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194577: Feb 19 15:01:41.249 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194578: Feb 19 15:01:41.269 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194579: Feb 19 15:01:41.289 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194580: Feb 19 15:01:41.309 UTC: //438011//MSM :/ms_play_part: sent: 160, numInBuf: 1232 lastFill: 1392, numBytes: 160
194581: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event(0)
194582: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Event(Size=2852)
194583: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_New:
194584: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194585: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x29C33804, Type: Event, RefCount: 0
194586: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Event_Free:
194587: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_IsType: 0: NULL object
194588: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL
194589: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2603DBF4, Type: DataList, RefCount: 0
194590: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_DataList_Free:
194591: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Event_New: Event ID: ev_leg_timer(1)
194592: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Event(Size=2852)
194593: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_New:
194594: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194595: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Process_GetTimerEvent: Received
194596: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetTimerEvent: Event[APP_EV_LEG_TIMER(1)] {
194597: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetTimerEvent: EXECENV[0xAD443EC][select_service2]
194598: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetTimerEvent: LEG[438017][LEG_INCCONNECTED(5)][Cause(0)]
194599: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetTimerEvent: }
194600: Feb 19 15:01:41.309 UTC: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
194601: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Leg_EventPreProcess: LEG[438017 ][LEG_INCCONNECTED(5)][Cause(0)]
194602: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_WalkListeners:
194603: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Object_ShowListeners: START
194604: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_0x25EAF134_0_1054393194] (
194605: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438017][LEG_INCCONNECTED(5)][Cause(0)]
194606: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194607: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Object_ShowListeners: END
194608: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
194609: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_0x25EAF134_0_1054393194
194610: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_M_TclModule_Action:
194611: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_TclModule_DefaultEvHandling:
194612: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(WAITFORTIMER[6],ev_leg_timer[1])---[act_GetDest]------
194613: Feb 19 15:01:41.309 UTC: //438017//PACK:/tcl_MediaObjCmd: media play leg_incoming flash:welcome.au
194614: Feb 19 15:01:41.309 UTC: //438017//PACK:/tcl_MediaPlayObjCmd: play leg_incoming flash:welcome.au
194615: Feb 19 15:01:41.309 UTC: //438017//AFW_:/vtd_lg_incoming: argc 3
194616: Feb 19 15:01:41.309 UTC: //438017//AFW_:/vtd_lg_incoming: Legs [438017 ]
194617: Feb 19 15:01:41.309 UTC: //438017//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
194618: Feb 19 15:01:41.309 UTC: //-1//DPM :DP181587:/pc_mc_createDynamicS: calloc mcDynamicS_t
194619: Feb 19 15:01:41.309 UTC: //-1//DPM :DP181587:/pc_mc_addToDynamicS: (1) flash:welcome.au
194620: Feb 19 15:01:41.309 UTC: //-1//PACK:/Media_Cur_Language_Prefix:
194621: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetFirst: Elem = 0x25FCAAF8, with Instance = 0x227832E4
194622: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetNext: Elem = 0xAA76A04, with Instance = 0x2CB79DFC, Prev = 0x25FCAAF8
194623: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetNext: Elem = 0x2CC71740, with Instance = 0xAD76770, Prev = 0xAA76A04
194624: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetNext: Elem = 0x2CB4F800, with Instance = 0x22815D04, Prev = 0x2CC71740
194625: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataString_Get:
194626: Feb 19 15:01:41.309 UTC: //-1//DPM :DP181587:/pc_mc_addToDynamicS: Doing : flash:welcome.au
194627: Feb 19 15:01:41.309 UTC: //-1//DPM :/mcTokenizerGetNext: savedcharptr=f endptrptr=flash:welcome.au
194628: Feb 19 15:01:41.309 UTC: //-1//DPM :DP181587:/pc_mc_addToDynamicS: Token : flash:welcome.au status 1
194629: Feb 19 15:01:41.309 UTC: //-1//DPM :DP181587:/pc_mc_addToDynamicS: du_IsNameURL() return TRUE
194630: Feb 19 15:01:41.309 UTC: //-1//DPM :DP181587:/dp_mcDQfromURL: file=flash:welcome.au
194631: Feb 19 15:01:41.309 UTC: //-1//MCM :/mc_createFromFileUrl: Getting a media content: name=welcome.au
url=flash:welcome.au
load fast, fetchtimeout=-1
194632: Feb 19 15:01:41.309 UTC: //-1//MCM :MC34:/mc_getFromUrlName: welcome.au on ram
194633: Feb 19 15:01:41.309 UTC: //-1//MCM :MC34:/mc_waitq_unlink: elm=0x25F59B64
194634: Feb 19 15:01:41.309 UTC: //-1//MCM :MC34:/mc_waitq_unlink: prompt_wait=A91DC prompt_active=3C97

194635: Feb 19 15:01:41.309 UTC: //-1//MCM :MC34:/mc_waitq_delete: prompt_wait=A91DC prompt_active=13CA0
194636: Feb 19 15:01:41.309 UTC: //-1//MCM :/mc_createFromFileUrl: Found a good mc (0x2271BDB4), RefCount(1)
194637: Feb 19 15:01:41.309 UTC: //-1//DPM :DP181587:/dp_mcDQfromURL: mc_createFromFileUrl OK
194638: Feb 19 15:01:41.309 UTC: //-1//DPM :LP:DP181587:MC34:/dp_mcDQfromURL:
194639: Feb 19 15:01:41.309 UTC: dp_mcDQfromURL enqueuing welcome.au OK###
194640: Feb 19 15:01:41.309 UTC: //-1//DPM :/mcTokenizerGetNext: savedcharptr=^@ endptrptr=
194641: Feb 19 15:01:41.309 UTC: //438017//PACK:/Media_Play_Start:
194642: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/PACK:/Media_Play_StartStats:
194643: Feb 19 15:01:41.309 UTC: //-1//PACK:/Media_Play_Start: pLeg->stream is null, call msw_create()
194644: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_create: cbf=0x65930F0
194645: Feb 19 15:01:41.309 UTC: //-1//MSM :MS119041:/ms_create: Iniz ply_timer
194646: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_synth_open: mediaStream 0x2CEDC18C created
194647: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_synth_open: rtspStream 0xAADD110 created,
status=RTSP_STATUS_SUCCESS, session_id=0x1D101 (119041)
194648: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_synth_open: AIS : Creating TTS AIS Backend record
194649: Feb 19 15:01:41.309 UTC: //-1//MSM :MS119042:/ms_create: Iniz ply_timer
194650: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_recrd_open:
194651: Feb 19 15:01:41.309 UTC: :msw_recrd_open mediaStream 0x258745B0 created
194652: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_recrd_open: rtspStream 0xAADD118 created,
status=RTSP_STATUS_SUCCESS, session_id=0x1D102 (119042)
194653: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_recog_open: AIS : Creating ASR AIS Backend record
194654: Feb 19 15:01:41.309 UTC: //-1//PACK:/Media_Play_Start: msw_create() succeed to create stream [59521]
194655: Feb 19 15:01:41.309 UTC: //-1//MSW :/msw_associate_call:
194656: Feb 19 15:01:41.309 UTC: msw_associate_call: callID=0x6AF01(438017),
genericStream=0x27A2DA68
194657: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_synth_get_stream_state: genericStream 0x27A2DA68 is in state MSW_S_IDLE
194658: Feb 19 15:01:41.309 UTC: //-1//MSW :/msw_synth_start:
194659: Feb 19 15:01:41.309 UTC: msw_synth_start: Enter...
194660: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_New:
194661: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194662: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetWrapper: Looking for list element 0x25EAF134
194663: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_Enqueue: Trying to add element to a list
194664: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetWrapper: Looking for list element 0x25EAF134
194665: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_Enqueue: Adding element:
194666: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x25EAF134, Type: TclModule, RefCount: 3
194667: Feb 19 15:01:41.309 UTC: //438017//MSW :/msw_synth_start: current_stream_id=1, content 0x0, dynamicS 0xAA9BF3C, current_state=MSW_S_IDLE
194668: Feb 19 15:01:41.309 UTC: //-1//MSW :/msw_synth_start: msw_synth_start: p_mcDynamicS, We've been given a list of URLs to play.
194669: Feb 19 15:01:41.309 UTC: //-1//MSW :/msw_synth_start: p_mcDynamicQ is NOT empty
194670: Feb 19 15:01:41.309 UTC: //438017//MSW :/msu_synth_partial_play: Media Stream URL
194671: Feb 19 15:01:41.309 UTC: //-1//MCM :MR181586:/mc_createDynamicReader:
194672: Feb 19 15:01:41.309 UTC: //438017//MSM :/ms_get_packet_size:
194673: Feb 19 15:01:41.309 UTC: ms_get_packet_size NO Voice Class codec
194674: Feb 19 15:01:41.309 UTC: //438017//MSM :/ms_associate: packet_size = 160 timestamp increment = 160 packet_duration = 20 Coder = 5 vad = 0 SampleRate = 8000
194675: Feb 19 15:01:41.309 UTC: //438017//MSM :/ms_associate: >>ccAssociateStream()
194676: Feb 19 15:01:41.309 UTC: //438017//TCL :/tcl_LegObjCmd: leg collectdigits leg_incoming param1
194677: Feb 19 15:01:41.309 UTC: //438017//Digi:/tcl_LegDigitCollectObjCmd: collectdigits leg_incoming param1
194678: Feb 19 15:01:41.309 UTC: //438017//AFW_:/vtd_lg_incoming: argc 3
194679: Feb 19 15:01:41.309 UTC: //438017//AFW_:/vtd_lg_incoming: Legs [438017 ]
194680: Feb 19 15:01:41.309 UTC: //438017//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
194681: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_ExecEnv_CallProc: DigitCollect::Start
194682: Feb 19 15:01:41.309 UTC: //438017//Digi:/C_DigitCollect_Start: Leg=438017, Dialplan=False, Diaplanterm=False, DigitReport=False, DigitConsume=False IgnoreInitialKey=False, IgnoreDTMFacceptINFO=False, Abortkey=^@, Terminationkey=^@, Numpatterns=0, Maxdigits=1, Mindigits=0, Interruptprompt=False, InterTimeout=-1000, Initialtimeout=10000, Mask=0 Hotword=0
194683: Feb 19 15:01:41.309 UTC: //-1//Digi:/AFW_DigitCollect_New:
194684: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DigitCollect(Size=644)
194685: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataArray_New:
194686: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataArray(Size=80)
194687: Feb 19 15:01:41.309 UTC: //-1//Digi:HN3ED8C752:/AFW_DigitCollect_New: DialPlan=FALSE AbortKey=^@ TermKey=^@ NumPatts=0
Enable=FALSE Consume=FALSE InterruptPrompt=FALSE minDigits=0 maxDigits=1 DialPlanTerm=FALSE hotword=0
194688: Feb 19 15:01:41.309 UTC: //-1//AFW_:LP:EE0AD443EC000:HN3ED8C752:/AFW_M_Object_SetExecEnv: ObjCount: 3, CmdPending 1
194689: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener
194690: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_M_Module_GetHandle: Module handle: DigitCollect_0x2CAA873C_0_1054394194
194691: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataArray_ElementSet: Adding param: DigitCollect_0x2CAA873C_0_1054394194, type: DigitCollect
194692: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x2CAA873C, Type: DigitCollect, RefCount: 2
194693: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_AddListener: adding Module DigitCollect as listener
194694: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataArray_ElementSet: Adding param: LEG_438017, type: Leg
194695: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x29C18320, Type: Leg, RefCount: 3
194696: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/Digi:/DigitCollectStart_UpdateStats:
194697: Feb 19 15:01:41.309 UTC: //438017//Digi:/DigitCollect_MLPPTuning:
194698: Feb 19 15:01:41.309 UTC: //438017//Digi:/DigitCollect_MLPPTuning: Precednece Digit Collect not enabled
194699: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_TypeAheadGet: no chars in buffer.
194700: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Leg_DigitReportEnable: Current: 0x0 Input: 0x2
194701: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_ADDING
194702: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice-only (callid 438017) to the VOIP RTP library
194703: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 85.19.204.26
194704: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
194705: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIUpdateRtcpSession: setting do_rtcp= TRUE for non forked leg 1
194706: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
laddr = 85.19.204.26, lport = 16654, raddr = 161.30.115.20, rport=32458, do_rtcp=TRUE
src_callid = 438017, dest_callid = -1, stream type = voice-only, stream direction = SENDRECV
media_ip_addr = 161.30.115.20, vrf tableid = 0 media_addr_type = 1 negotiated_bandwidth (kbps) = 0 srtp_services = 0 nat_flag = 0
194707: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one
194708: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPICreateRtpSession: Stun packet reporting for ICE disabled in sipSPICreateRtpSession
194709: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPICreateRtpSession: Pin hole for firewall traversal is disabled
194710: Feb 19 15:01:41.309 UTC: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: Voice quality monitoring is not enabled for this RTP session due to sdp passthru enabled
194711: Feb 19 15:01:41.309 UTC: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: VQM: gccb=0x0, gccb->callId=0, ccb->ccCallID=438017
194712: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/SIP/Media/sipSPIGetNewLocalMediaDirection:
New Remote Media Direction = SENDRECV
Present Local Media Direction = SENDRECV
New Local Media Direction = SENDRECV
retVal = 0

194713: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataString_New:
194714: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataString(Size=20)
194715: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataArray_ElementSetNameSpace: Adding param space Session, name LastCmdHandle, entryKey LastCmdHandle.Session, type DataString
194716: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataArray_ElementSet: Adding param: LastCmdHandle.Session, type: DataString
194717: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x2602732C, Type: DataString, RefCount: 2
194718: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2602732C, Type: DataString, RefCount: 1
194719: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_FSM_Drive: ACTION END: -------------(GETDEST[3])---------------
194720: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_0x25EAF134_0_1054393194 ---> NULL
194721: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x29C34328, Type: Event, RefCount: 0
194722: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Event_Free:
194723: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2603DC6C, Type: DataList, RefCount: 0
194724: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_DataList_Free:
194725: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1
194726: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event(0)
194727: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Event(Size=2852)
194728: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_New:
194729: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194730: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Process_GetCcqEvent: Received
194731: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_REPORT_DIGITS_DONE(71)] {
194732: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0xAD443EC][select_service2]
194733: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[438017][LEG_INCCONNECTED(5)][Cause(0)]
194734: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetCcqEvent: }
194735: Feb 19 15:01:41.309 UTC: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
194736: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_WalkListeners:
194737: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Object_ShowListeners: START
194738: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[DigitCollect_0x2CAA873C_0_1054394194] (
194739: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438017][LEG_INCCONNECTED(5)][Cause(0)]
194740: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194741: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_0x25EAF134_0_1054393194] (
194742: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[DigitCollect_0x2CAA873C_0_1054394194] (
194743: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438017][LEG_INCCONNECTED(5)][Cause(0)]
194744: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194745: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438017][LEG_INCCONNECTED(5)][Cause(0)]
194746: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194747: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Object_ShowListeners: END
194748: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_WalkListeners: Entering Module : DigitCollect
194749: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> DigitCollect_0x2CAA873C_0_1054394194
194750: Feb 19 15:01:41.309 UTC: //438017//Digi:/AFW_M_DigitCollect_Action:
194751: Feb 19 15:01:41.309 UTC: //438017//Digi:/act_DCRunning_RDone: id=438017 error. Enable failed.
194752: Feb 19 15:01:41.309 UTC: //-1//Digi:/DigitCollect_Complete: DC_UNSUPPORTED. Digits=
194753: Feb 19 15:01:41.309 UTC: //438017//Digi:/DigitCollect_MLPPPostAdjust: Non MLPP call
194754: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Event_New: Event ID: ev_collectdigits_done(266)
194755: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: Event(Size=2852)
194756: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_New:
194757: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194758: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_Module_ReturnArgEv:
194759: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_New:
194760: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Class_Allocate: Malloc Data Space: DataList(Size=40)
194761: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_Enqueue: Trying to add element to a list
194762: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetWrapper: Looking for list element 0x29C18320
194763: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_Enqueue: Adding element:
194764: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_IncrRefCount: Object: 0x29C18320, Type: Leg, RefCount: 4
194765: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataList_GetFirst: Elem = 0xAC38E6C, with Instance = 0x29C18320
194766: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[438017 ][LEG_INCCONNECTED(5)][Cause(0)]}
194767: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_RemoveListener:
194768: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_DataArray_ElementDelete: param name LEG_438017
194769: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x29C18320, Type: Leg, RefCount: 3
194770: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_Module_UnListen: NumObjects: 0
194771: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_ExecEnv_SetModuleScope: DigitCollect_0x2CAA873C_0_1054394194 ---> NULL
194772: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x29C32CE0, Type: Event, RefCount: 0
194773: Feb 19 15:01:41.309 UTC: //438017/91677C37A52E/AFW_:/AFW_M_Event_Free:
194774: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x26071754, Type: DataList, RefCount: 0
194775: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_M_DataList_Free:
194776: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1
194777: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
194778: Feb 19 15:01:41.309 UTC: //438017//AFW_:/AFW_Process_GetPriorityQEvent: Received
194779: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_DIGITCOLLECT_DONE(266)] {
194780: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0xAD443EC][select_service2]
194781: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[DigitCollect_0x2CAA873C_0_1054394194] (
194782: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )
194783: Feb 19 15:01:41.309 UTC: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }
194784: Feb 19 15:01:41.309 UTC: //438017//Digi:/AFW_M_DigitCollect_EventPreProcess:
194785: Feb 19 15:01:41.311 UTC: //438017//Digi:/DigitCollectEnd_UpdateStats:
194786: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_Object_WalkListeners:
194787: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_M_Object_ShowListeners: START
194788: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_0x25EAF134_0_1054393194] (
194789: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[DigitCollect_0x2CAA873C_0_1054394194] (
194790: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194791: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[438017][LEG_INCCONNECTED(5)][Cause(0)]
194792: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_M_Object_ShowListeners: )
194793: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_M_Object_ShowListeners: END
194794: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
194795: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_DataList_GetFirst: Elem = 0xAC38E6C, with Instance = 0x29C18320
194796: Feb 19 15:01:41.311 UTC: //438017/91677C37A52E/AFW_:/AFW_Object_AddListener: adding Module TclModule as listener
194797: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_Object_RemoveListener:
194798: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_DataArray_ElementDelete: param name DigitCollect_0x2CAA873C_0_1054394194
194799: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_Instance_DecrRefCount: Object: 0x2CAA873C, Type: DigitCollect, RefCount: 1
194800: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_Module_UnListen: NumObjects: 1
194801: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_0x25EAF134_0_1054393194
194802: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_M_TclModule_Action:
194803: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_TclModule_DefaultEvHandling:
194804: Feb 19 15:01:41.311 UTC: //438017//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(GETDEST[3],ev_collectdigits_done[266])---[act_Media]------
194805: Feb 19 15:01:41.311 UTC: //438017//TCL :/tcl_InfotagObjCmd: infotag get evt_status
194806: Feb 19 15:01:41.311 UTC: //438017//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
194807: Feb 19 15:01:41.311 UTC: //438017//AFW_:/vtr_ev_status: argc 2 argindex 2
194808: Feb 19 15:01:41.311 UTC: //438017//TCL :/tcl_InfotagObjCmd: infotag get evt_dcdigits
194809: Feb 19 15:01:41.311 UTC: //438017//TCL :/tcl_InfotagGetObjCmd: infotag get evt_dcdigits
194810: Feb 19 15:01:41.311 UTC: //438017//AFW_:/vtr_ev_dcdigits: argc 2
194811: Feb 19 15:01:41.311 UTC: //438017//AFW_:/vtr_ev_dcdigits: DCDIGITS []
194812: Feb 19 15:01:41.311 UTC: //438017//PACK:/tcl_MediaObjCmd: media play leg_incoming flash:en_bacd_welcome.au
194813: Feb 19 15:01:41.311 UTC: //438017//PACK:/tcl_MediaPlayObjCmd: play leg_incoming flash:en_bacd_welcome.au
194814: Feb 19 15:01:41.311 UTC: //438017//AFW_:/vtd_lg_incoming: argc 3
194815: Feb 19 15:01:41.311 UTC: //438017//AFW_:/vtd_lg_incoming: Legs [438017 ]
194816: Feb 19 15:01:41.311 UTC: //438017//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
194817: Feb 19 15:01:41.311 UTC: //-1//DPM :DP181588:/pc_mc_createDynamicS: calloc mcDynamicS_t
194818: Feb 19 15:01:41.311 UTC: //-1//DPM :DP181588:/pc_mc_addToDynamicS: (1) flash:en_bacd_welcome.au
194819: Feb 19 15:01:41.311 UTC: //-1//PACK:/Media_Cur_Language_Prefix:
194820: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_DataList_GetFirst: Elem = 0x25FCAAF8, with Instance = 0x227832E4
194821: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_DataList_GetNext: Elem = 0xAA76A04, with Instance = 0x2CB79DFC, Prev = 0x25FCAAF8
194822: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_DataList_GetNext: Elem = 0x2CC71740, with Instance = 0xAD76770, Prev = 0xAA76A04
194823: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_DataList_GetNext: Elem = 0x2CB4F800, with Instance = 0x22815D04, Prev = 0x2CC71740
194824: Feb 19 15:01:41.311 UTC: //-1//AFW_:/AFW_DataString_Get:
194825: Feb 19 15:01:41.311 UTC: //-1//DPM :DP181588:/pc_mc_addToDynamicS: Doing : flash:en_bacd_welcome.au
194826: Feb 19 15:01:41.311 UTC: //-1//DPM :/mcTokenizerGetNext: savedcharptr=f endptrptr=flash:en_bacd_welcome.au
194827: Feb 19 15:01:41.311 UTC: //-1//DPM :DP181588:/pc_mc_addToDynamicS: Token : flash:en_bacd_welcome.au status 1
194828: Feb 19 15:01:41.311 UTC: //-1//DPM :DP181588:/pc_mc_addToDynamicS: du_IsNameURL() return TRUE
194829: Feb 19 15:01:41.311 UTC: //-1//DPM :DP181588:/dp_mcDQfromURL: file=flash:en_bacd_welcome.au
194830: Feb 19 15:01:41.311 UTC: //-1//MCM :/mc_createFromFileUrl: Getting a media content: name=en_bacd_welcome.au
url=flash:en_bacd_welcome.au
load fast, fetchtimeout=-1
194831: Feb 19 15:01:41.311 UTC: //-1//MCM :/mc_createFromFileUrl: No reusable mc found
194832: Feb 19 15:01:41.311 UTC: //-1//MCM :MC4984:/mc_createFromFileUrl: Starting hifs background load for(0x2D109E10) flash:en_bacd_welcome.au
194833: Feb 19 15:01:41.311 UTC: //-1//MCM :MC4984:/mc_load_bg: mc:0x2D109E10 name:en_bacd_welcome.au, streaming_mode 0, max_size 262140
194834: Feb 19 15:01:41.311 UTC: //-1//MCM :MC4984:/mc_load_bg: loader conntext: 0x2D109EB0, 0x0
194835: Feb 19 15:01:41.311 UTC: //-1//MCM :MC4984:/mc_lock:
194836: Feb 19 15:01:41.311 UTC: mc_lock():: mc=0x2D109E10, url=flash:en_bacd_welcome.au, refCount=1
194837: Feb 19 15:01:41.311 UTC: //-1//MCM :/mc_createFromFileUrl: Returning mc (0x2D109E10), RefCount(2)

Re: TCL DTMF With 3rd party SIP

tjohannessen — Wed, 04 Mar 2020 11:33:04 GMT

IOS config here.

Testing with service select_service3 on dialpeer 99999997 here with Cisco-rtp

Re: TCL DTMF With 3rd party SIP

yawming — Wed, 04 Mar 2020 19:38:28 GMT

To be honest, it's been a while since last time worked on voice.

Do you know the plan is to use in or out band dtmf for SIP ?

I saw "

Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : inband-voice

If trying to use out of band I think need to config dtmf-relay sip-notify.

dtmf-relay rtp-nte is still good, have you tried already ?

Anyway I have SME who are current working on this to take a look.

Thanks,

Yawming

Re: TCL DTMF With 3rd party SIP

tjohannessen — Wed, 04 Mar 2020 22:15:22 GMT

We'll use whatever signalling that will work 🙂

Yes we tried rtp-nte. That what was configured when taking that log which ends up with inband-voice. Is this an inconsistency, or is rtp-nte==inband voice

Thanks!

Trond

Re: TCL DTMF With 3rd party SIP

yawming — Thu, 05 Mar 2020 00:15:40 GMT

try - dtmf-relay sip-notify ?

Re: TCL DTMF With 3rd party SIP

tjohannessen — Thu, 05 Mar 2020 07:09:07 GMT

We'll try that, as well as force rtp-nte, and report back.

Re: TCL DTMF With 3rd party SIP

tjohannessen — Thu, 12 Mar 2020 17:03:08 GMT

Hi Yawming,

At last we got to test some more.

The dial-peer command voice-class sip dtmf-relay force rtp-nte

did the trick. Forcing the Meta switch to accept the rtp-nte proposal.

Thanks for assisting on this case!

best regards,

Trond