[cisco-voip] No audio IN TCL IVR Prompts

Tue Nov 17 16:51:52 EST 2009

Dear All,

I'm asking your help to understand how tcl ivr prompts work.

In order to introduce myself to tlc ivr scripting I wanted to explore 
a
classic of this kind: its-CISCO.2.0.2.0.tcl
This script is provided 
by
Cisco, and it is an example of what a gateway could do.

By my side, 
I
tryed this script installing it in my 1751v.
But I couldn't get to 
ear
any prompt.
I read many post of this Mailinglist, and others.
I 
also
read of someone who said that ivr prompts could not be played to 
voip
dial-peers but only to pots.
I read official docs, not carefully, 
I
have to admit it, but I didn't see a page saying this.
By the way the

script I have uploaded on my router, seems to work, except I can not

hear any sound back in my handset.

here I paste the config I placed on

my router; I would appreciate any suggest on why this odd behaviour.

application
service aa tftp://10.10.10.1/its-CISCO.2.0.2.0.tcl

paramspace english language en
  paramspace english index 1

paramspace english location tftp://10.10.10.1/
  param aa-pilot 5000

param operator 104
dial-peer voice 250 voip
service aa
destination-

pattern 5000
incoming called-number 5000
session protocol sipv2

session target ipv4:10.10.13.2
codec g711ulaw

the following a debug

showing what's happen when I call the number.
Nov 17 22:07:11.277 GMT:

//-1//HIFS:/hifs_ifs_cb: hifs ifs file read succeeded. size=7164,

url=tftp://10.10.10.1/its-CISCO.2.0.2.0.tcl
Nov 17 22:07:11.305 GMT: 
//-
1//HIFS:/hifs_free_idata: hifs_free_idata: 0x85354B94
Nov 17 22:07:

11.305 GMT: //-1//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x85354B94

Nov 17 22:07:11.325 GMT: //-1//AFW_:/tcl_RequiredVersionObjCmd: Script

requires version 2.0.
Nov 17 22:07:11.345 GMT: //-1//AFW_:
EE84F68554000:
/Tcl_Link: Linking script aa
Nov 17 22:07:11.381 GMT: //-
1//TCL :
EE84F68554000:/tcl_RequiredVersionObjCmd: Script requires 
version 2.0.
So 2.1 is OK
Nov 17 22:07:11.441 GMT: //-1//TCL :
EE84F68554000:
/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists aa-
pilot
Nov 17 22:
07:11.445 GMT: //-1//TCL :EE84F68554000:
/tcl_InfotagGetObjCmd: infotag
get cfg_avpair_exists aa-pilot
Nov 17 22:
07:11.445 GMT: //-1//AFW_:
EE84F68554000:/vtr_cf_avpair_exists: argc 3 
argindex 2
Nov 17 22:07:
11.445 GMT: //-1//TCL :EE84F68554000:
/tcl_InfotagObjCmd:  infotag get
cfg_avpair_exists operator
Nov 17 22:
07:11.449 GMT: //-1//TCL :
EE84F68554000:/tcl_InfotagGetObjCmd: infotag 
get cfg_avpair_exists
operator
Nov 17 22:07:11.449 GMT: //-1//AFW_:
EE84F68554000:
/vtr_cf_avpair_exists: argc 3 argindex 2
Nov 17 22:07:
11.453 GMT: //-
1//TCL :EE84F68554000:/tcl_FSMObjCmd:  fsm define fsm 
CALL_INIT
Nov 17
22:07:11.457 GMT: //-1//TCL :EE84F68554000:
/tcl_FSMDefineObjCmd: State
Machine: Array fsm: Start State: CALL_INIT

Nov 17 22:07:11.461 GMT: //-
1//TCL :EE84F68554000:
/tcl_FSMDefineObjCmd: FSM Data structure
Nov 17
22:07:11.461 GMT: 
(CALLDISCONNECT(2), ev_media_done(146)--(act_Cleanup)
-->(any_state(0))

Nov 17 22:07:11.461 GMT: (GETDEST(3),
ev_collectdigits_done(190)--
(act_GotDest)-->(HANDOFF(4))
Nov 17 22:07:
11.461 GMT: (any_state(0), 
ev_disconnect_done(18)--(act_Cleanup)-->
(any_state(0))
Nov 17 22:07:
11.461 GMT: (any_state(0), ev_disconnected
(17)--(act_Cleanup)-->
(any_state(0))
Nov 17 22:07:11.461 GMT: (HANDOFF
(4), ev_setup_done(184)
--(act_CallSetupDone)-->(CONTINUE(5))
Nov 17 22:
07:11.461 GMT: 
(CALL_INIT(1), ev_setup_indication(30)--(act_Setup)-->
(GETDEST(3))
Nov 
17 22:07:11.461 GMT: FSM start state CALL_INIT(1)
Nov
17 22:07:11.465 
GMT: //-1//AFW_:EE84F68554000:/Tcl_Link: Script aa
succesfully linked.

Nov 17 22:07:21.337 GMT: //-1//AFW_:EE84F68F4C000:
/Tcl_Link: Linking 
script aa
Nov 17 22:07:21.377 GMT: //-1//TCL :
EE84F68F4C000:
/tcl_RequiredVersionObjCmd: Script requires version 2.0.
So 2.1 is OK

Nov 17 22:07:21.393 GMT: //-1//TCL :EE84F68F4C000:
/tcl_InfotagObjCmd:  
infotag get cfg_avpair_exists aa-pilot
Nov 17 22:
07:21.393 GMT: //-
1//TCL :EE84F68F4C000:/tcl_InfotagGetObjCmd: infotag
get 
cfg_avpair_exists aa-pilot
Nov 17 22:07:21.393 GMT: //-1//AFW_:

EE84F68F4C000:/vtr_cf_avpair_exists: argc 3 argindex 2
Nov 17 22:07:

21.397 GMT: //-1//TCL :EE84F68F4C000:/tcl_InfotagObjCmd:  infotag get

cfg_avpair aa-pilot
Nov 17 22:07:21.397 GMT: //-1//TCL :EE84F68F4C000:

/tcl_InfotagGetObjCmd: infotag get cfg_avpair aa-pilot
Nov 17 22:07:

21.397 GMT: //-1//AFW_:EE84F68F4C000:/vtr_cf_avpair: argc 3 argindex 2

Nov 17 22:07:21.401 GMT: //-1//PACK:EE84F68F4C000:

/tcl_ParamReadObjCmd:  aa-pilot
Nov 17 22:07:21.401 GMT: //-1//TCL :

EE84F68F4C000:/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists

operator
Nov 17 22:07:21.401 GMT: //-1//TCL :EE84F68F4C000:

/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists operator
Nov 17
22:
07:21.405 GMT: //-1//AFW_:EE84F68F4C000:/vtr_cf_avpair_exists: argc
3 
argindex 2
Nov 17 22:07:21.405 GMT: //-1//TCL :EE84F68F4C000:

/tcl_InfotagObjCmd:  infotag get cfg_avpair operator
Nov 17 22:07:

21.405 GMT: //-1//TCL :EE84F68F4C000:/tcl_InfotagGetObjCmd: infotag get

cfg_avpair operator
Nov 17 22:07:21.409 GMT: //-1//AFW_:EE84F68F4C000:

/vtr_cf_avpair: argc 3 argindex 2
Nov 17 22:07:21.409 GMT: //-1//PACK:

EE84F68F4C000:/tcl_ParamReadObjCmd:  operator
Nov 17 22:07:21.413 GMT:

//-1//TCL :EE84F68F4C000:/tcl_FSMObjCmd:  fsm define fsm CALL_INIT
Nov

17 22:07:21.413 GMT: //-1//TCL :EE84F68F4C000:/tcl_FSMDefineObjCmd:

State Machine: Array fsm: Start State: CALL_INIT
Nov 17 22:07:21.417

GMT: //-1//TCL :EE84F68F4C000:/tcl_FSMDefineObjCmd: FSM Data structure

Nov 17 22:07:21.417 GMT: (CALLDISCONNECT(2), ev_media_done(146)--

(act_Cleanup)-->(any_state(0))
Nov 17 22:07:21.417 GMT: (GETDEST(3),

ev_collectdigits_done(190)--(act_GotDest)-->(HANDOFF(4))
Nov 17 22:07:

21.421 GMT: (any_state(0), ev_disconnect_done(18)--(act_Cleanup)-->

(any_state(0))
Nov 17 22:07:21.421 GMT: (any_state(0), ev_disconnected

(17)--(act_Cleanup)-->(any_state(0))
Nov 17 22:07:21.421 GMT: (HANDOFF

(4), ev_setup_done(184)--(act_CallSetupDone)-->(CONTINUE(5))
Nov 17 22:

07:21.421 GMT: (CALL_INIT(1), ev_setup_indication(30)--(act_Setup)-->

(GETDEST(3))
Nov 17 22:07:21.421 GMT: FSM start state CALL_INIT(1)
Nov

17 22:07:21.421 GMT: //-1//AFW_:EE84F68F4C000:/Tcl_Link: Script aa

succesfully linked.
Nov 17 22:07:21.433 GMT: //18680//TCL :

/tcl_PutsObjCmd: proc act_Setup
Nov 17 22:07:21.433 GMT:
Nov 17 22:07:

21.433 GMT: //18680//TCL :/tcl_PutsObjCmd:
proc init_perCallvars
Nov
17 
22:07:21.437 GMT:
Nov 17 22:07:21.437 GMT: //18680//TCL :

/tcl_InfotagObjCmd:  infotag get leg_ani
Nov 17 22:07:21.437 GMT:

//18680//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
Nov 17 22:07:

21.437 GMT: //18680//AFW_:/vtr_lg_ani: argc 2 argindex 2
Nov 17 22:07:

21.441 GMT: //18680//TCL :/tcl_PutsObjCmd:
ANI 105
Nov 17 22:07:21.441

GMT:
Nov 17 22:07:21.441 GMT: //18680//TCL :/tcl_InfotagObjCmd: 

infotag get leg_dnis
Nov 17 22:07:21.441 GMT: //18680//TCL :

/tcl_InfotagGetObjCmd: infotag get leg_dnis
Nov 17 22:07:21.441 GMT:

//18680//AFW_:/vtr_lg_dnis: argc 2 argindex 2
Nov 17 22:07:21.445 GMT:

//18680//TCL :/tcl_PutsObjCmd:
DNIS 5000
Nov 17 22:07:21.445 GMT:
Nov

17 22:07:21.445 GMT: //18680//TCL :/tcl_InfotagObjCmd:  infotag set

med_language 1
Nov 17 22:07:21.445 GMT: //18680//TCL :

/tcl_InfotagSetObjCmd: infotag set med_language 1
Nov 17 22:07:21.445

GMT: //18680//AFW_:/vtw_ms_language: argc 3 argindex 2
Nov 17 22:07:

21.449 GMT: //18680//TCL :/tcl_LegObjCmd:  leg setupack leg_incoming

Nov 17 22:07:21.449 GMT: //18680//TCL :/tcl_LegSetupAckObjCmd: setupack

leg_incoming
Nov 17 22:07:21.449 GMT: //18680//AFW_:/vtd_lg_incoming:

argc 2
Nov 17 22:07:21.449 GMT: //18680//AFW_:/vtd_lg_incoming: Legs

[18680 ]
Nov 17 22:07:21.453 GMT: //18680//Tcl :

/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
Nov 17 22:
07:
21.453 GMT: //18680//TCL :/tcl_LegObjCmd:  leg proceeding 
leg_incoming

Nov 17 22:07:21.453 GMT: //18680//TCL :
/tcl_LegProceedObjCmd:
proceeding leg_incoming
Nov 17 22:07:21.453 GMT: 
//18680//AFW_:
/vtd_lg_incoming: argc 2
Nov 17 22:07:21.453 GMT: 
//18680//AFW_:
/vtd_lg_incoming: Legs [18680 ]
Nov 17 22:07:21.453 GMT: 
//18680//Tcl :
/tcl_parseCallID_vartagObj: VARTAG Translation Leg 
Count=1
Nov 17 22:07:
21.457 GMT: //18680//TCL :/tcl_LegObjCmd:  leg 
connect leg_incoming

Nov 17 22:07:21.457 GMT: //18680//TCL :
/tcl_LegConnectObjCmd: connect
leg_incoming
Nov 17 22:07:21.457 GMT: 
//18680//AFW_:/vtd_lg_incoming:
argc 2
Nov 17 22:07:21.457 GMT: 
//18680//AFW_:/vtd_lg_incoming: Legs
[18680 ]
Nov 17 22:07:21.457 GMT: 
//18680//Tcl :
/tcl_parseCallID_vartagObj: VARTAG Translation Leg 
Count=1
Nov 17 22:07:
21.461 GMT: //18680//TCL :/tcl_PutsObjCmd:
No 
DNIS
Nov 17 22:07:21.461
GMT:
Nov 17 22:07:21.461 GMT: //18680//TCL :
/tcl_LegObjCmd:  leg
collectdigits leg_incoming param1
Nov 17 22:07:
21.461 GMT:
//18680//AFW_:/vtd_lg_incoming: argc 3
Nov 17 22:07:21.461 
GMT:
//18680//AFW_:/vtd_lg_incoming: Legs [18680 ]
Nov 17 22:07:21.461 
GMT:
//18680//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg

Count=1
Nov 17 22:07:21.469 GMT: //18680//PACK:/tcl_MediaObjCmd:  media

play leg_incoming _welcome.wav %s1000 _enter_dest.wav
Nov 17 22:07:

21.469 GMT: //18680//PACK:/tcl_MediaPlayObjCmd:  play leg_incoming

_welcome.wav %s1000 _enter_dest.wav
Nov 17 22:07:21.469 GMT:

//18680//AFW_:/vtd_lg_incoming: argc 5
Nov 17 22:07:21.469 GMT:

//18680//AFW_:/vtd_lg_incoming: Legs [18680 ]
Nov 17 22:07:21.473 GMT:

//18680//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg

Count=1
Nov 17 22:07:21.473 GMT: //-1//DPM :DP139:

/pc_mc_createDynamicS: calloc mcDynamicS_t
Nov 17 22:07:21.473 GMT: //-

1//DPM :DP139:/pc_mc_addToDynamicS: (3) _welcome.wav %s1000 
_enter_dest.
wav
Nov 17 22:07:21.473 GMT: //-1//DPM :DP139:
/pc_mc_addToDynamicS:
Doing : _welcome.wav
Nov 17 22:07:21.477 GMT: //-
1//DPM :
/mcTokenizerGetNext: savedcharptr=_ endptrptr=_welcome.wav
Nov 
17 22:07:
21.477 GMT: //-1//DPM :DP139:/pc_mc_addToDynamicS: Token : 
_welcome.wav
status 1
Nov 17 22:07:21.477 GMT: //-1//DPM :DP139:

/pc_mc_addToDynamicS: call dp_mcDQfromFileDynamic() to Handle relative

file name
Nov 17 22:07:21.477 GMT: //-1//DPM :DP139:

/dp_mcDQfromFileDynamic:  pLanguage=en FileName=_welcome.wav
Nov 17 22:

07:21.477 GMT: //-1//DPM :DP139:/dp_mcDQfromURL:  file=tftp://10.
10.10.1
/en_welcome.wav
Nov 17 22:07:21.477 GMT: //-1//MCM :

/mc_createFromFileUrl: Getting a media content: name=en_welcome.wav

url=tftp://10.10.10.1/en_welcome.wav
    load fast, fetchtimeout=-1
Nov

17 22:07:21.481 GMT: //-1//MCM :MC121:/mc_getFromUrlName: en_welcome.

wav on ram
Nov 17 22:07:21.481 GMT: //-1//MCM :MC121:/mc_waitq_unlink:

elm=8578A820
Nov 17 22:07:21.481 GMT: //-1//MCM :MC121:

/mc_waitq_unlink: prompt_wait=ADB2F prompt_active=0
Nov 17 22:07:21.481

GMT: //-1//MCM :MC121:/mc_waitq_delete: prompt_wait=ADB2F

prompt_active=1168A
Nov 17 22:07:21.481 GMT: //-1//MCM :

/mc_createFromFileUrl: Found a good mc (0x85762AB4), RefCount(1)
Nov 17

22:07:21.481 GMT: //-1//DPM :DP139:/dp_mcDQfromURL:

mc_createFromFileUrl OK
Nov 17 22:07:21.485 GMT: //-1//DPM :LP:DP139:

MC121:/dp_mcDQfromURL:
Nov 17 22:07:21.485 GMT: dp_mcDQfromURL

enqueuing en_welcome.wav OK###
Nov 17 22:07:21.485 GMT: //-1//DPM :

/mcTokenizerGetNext: savedcharptr= endptrptr=
Nov 17 22:07:21.485 GMT:

//-1//DPM :DP139:/pc_mc_addToDynamicS: Doing : %s1000
Nov 17 22:07:

21.485 GMT: //-1//DPM :/mcTokenizerGetNext: savedcharptr=% endptrptr=%

s1000
Nov 17 22:07:21.485 GMT: //-1//DPM :DP139:/pc_mc_addToDynamicS:

Token : %s1000 status 1
Nov 17 22:07:21.485 GMT: //-1//DPM :DP139:

/dp_mcDQfromSilence:  time=1000
Nov 17 22:07:21.485 GMT: //-1//DPM :

/du_create_silenceDynamicObj:  silence=1000
Nov 17 22:07:21.489 GMT: 
//-
1//DPM :/du_create_silenceUrlName: name=silence:1000
Nov 17 22:07:

21.489 GMT: //-1//DPM :LP:DP139:MC142:/du_create_silenceDynamicObj:

OK###
Nov 17 22:07:21.489 GMT: //-1//DPM :/mcTokenizerGetNext:

savedcharptr= endptrptr=
Nov 17 22:07:21.489 GMT: //-1//DPM :DP139:

/pc_mc_addToDynamicS: Doing : _enter_dest.wav
Nov 17 22:07:21.489 GMT:

//-1//DPM :/mcTokenizerGetNext: savedcharptr=_ endptrptr=_enter_dest.

wav
Nov 17 22:07:21.493 GMT: //-1//DPM :DP139:/pc_mc_addToDynamicS:

Token : _enter_dest.wav status 1
Nov 17 22:07:21.493 GMT: //-1//DPM :

DP139:/pc_mc_addToDynamicS: call dp_mcDQfromFileDynamic() to Handle

relative file name
Nov 17 22:07:21.493 GMT: //-1//DPM :DP139:

/dp_mcDQfromFileDynamic:  pLanguage=en FileName=_enter_dest.wav
Nov 17

22:07:21.493 GMT: //-1//DPM :DP139:/dp_mcDQfromURL:  file=tftp://10.

10.10.1/en_enter_dest.wav
Nov 17 22:07:21.493 GMT: //-1//MCM :

/mc_createFromFileUrl: Getting a media content: name=en_enter_dest.wav

    url=tftp://10.10.10.1/en_enter_dest.wav
    load fast,

fetchtimeout=-1
Nov 17 22:07:21.493 GMT: //-1//MCM :MC123:

/mc_getFromUrlName: en_enter_dest.wav on ram
Nov 17 22:07:21.497 GMT:

//-1//MCM :MC123:/mc_waitq_unlink: elm=861FC45C
Nov 17 22:07:21.497

GMT: //-1//MCM :MC123:/mc_waitq_unlink: prompt_wait=A7BFF

prompt_active=1168A
Nov 17 22:07:21.497 GMT: //-1//MCM :MC123:

/mc_waitq_delete: prompt_wait=A7BFF prompt_active=175BA
Nov 17 22:07:

21.497 GMT: //-1//MCM :/mc_createFromFileUrl: Found a good mc

(0x8578BC80), RefCount(1)
Nov 17 22:07:21.497 GMT: //-1//DPM :DP139:

/dp_mcDQfromURL: mc_createFromFileUrl OK
Nov 17 22:07:21.501 GMT: //-

1//DPM :LP:DP139:MC123:/dp_mcDQfromURL:
Nov 17 22:07:21.501 GMT:

dp_mcDQfromURL enqueuing en_enter_dest.wav OK###
Nov 17 22:07:21.501

GMT: //-1//DPM :/mcTokenizerGetNext: savedcharptr= endptrptr=
Nov 17 
22:
07:21.501 GMT: //18680//PACK:/Media_Play_Start: 
Nov 17 22:07:
21.501
GMT: //18680//MSW :/msw_create: cbf=0x819169B0
Nov 17 22:07:
21.501 GMT:
//-1//MSM :MS141:/ms_create: Iniz ply_timer
Nov 17 22:07:
21.501 GMT:
//18680//MSW :/msw_synth_open: mediaStream 0x854487C8 
created
Nov 17 22:
07:21.505 GMT: //18680//MSW :/msw_synth_open: 
rtspStream 0x8486208C
created,
            status=RTSP_STATUS_SUCCESS, 
session_id=0x8D (141)

Nov 17 22:07:21.505 GMT: //18680//MSW :
/msw_synth_open: AIS : Creating
TTS AIS Backend record
Nov 17 22:07:
21.505 GMT: //-1//MSM :MS142:
/ms_create: Iniz ply_timer
Nov 17 22:07:
21.505 GMT: //18680//MSW :
/msw_recrd_open:
Nov 17 22:07:21.505 GMT: :
msw_recrd_open mediaStream
0x86128B20 created
Nov 17 22:07:21.505 GMT: 
//18680//MSW :
/msw_recrd_open: rtspStream 0x84862094 created,

status=RTSP_STATUS_SUCCESS, session_id=0x8E (142)
Nov 17 22:
07:21.509
GMT: //18680//MSW :/msw_recog_open: AIS : Creating ASR AIS 
Backend
record
Nov 17 22:07:21.509 GMT: //-1//MSW :/msw_associate_call:

Nov 17
22:07:21.509 GMT: msw_associate_call: callID=0x48F8(18680),

                     genericStream=0x84861388
Nov 17 22:07:21.509 GMT:

//18680//MSW :/msw_synth_get_stream_state: genericStream 0x84861388 is

in state MSW_S_IDLE
Nov 17 22:07:21.509 GMT: //-1//MSW :

/msw_synth_start:
Nov 17 22:07:21.509 GMT: msw_synth_start: Enter...

Nov 17 22:07:21.513 GMT: //18680//MSW :/msw_synth_start:

current_stream_id=1, content 0x0, dynamicS 0x8576B9D8,

current_state=MSW_S_IDLE
Nov 17 22:07:21.513 GMT: //-1//MSW :

/msw_synth_start: msw_synth_start: p_mcDynamicS, We've been given a

list of URLs to play.
Nov 17 22:07:21.513 GMT: //-1//MSW :

/msw_synth_start:  p_mcDynamicQ is NOT empty
Nov 17 22:07:21.513 GMT:

//18680//MSW :/msu_synth_partial_play: Media Stream URL
Nov 17 22:07:

21.513 GMT: //-1//MCM :MR128:/mc_createDynamicReader: 
Nov 17 22:07:

21.513 GMT: //18680//MSM :/ms_get_packet_size:
Nov 17 22:07:21.513

GMT:  ms_get_packet_size NO Voice Class codec
Nov 17 22:07:21.517 GMT:

//18680//MSM :/ms_associate: packet_size = 160 timestamp increment =

160 packet_duration = 20 Coder = 5 vad = 0 SampleRate = 8000
Nov 17 22:

07:21.517 GMT: //18680//MSM :/ms_associate: >>ccAssociateStream()
Nov

17 22:07:21.537 GMT: //18680//MSM :LP:MS141:/ms_associateDone: 
Nov 17

22:07:21.537 GMT: //18680//MSM :/ms_asDone_buginf: callID=0x48F8,

pVdb=0x84835048,
    disposition=0, playFunc=0x82449BB4,

codec=0x5=g711ulaw, vad=0,
    mediaType=3, streamAssocID=18681
Nov 17

22:07:21.537 GMT: //18680//MSM :/ms_associateDone: Setting initial

seqnum=1932 for the call
Nov 17 22:07:21.537 GMT: //-1//MCM :MR128:

/mc_setup_reader_encaps:  SSRC: 0x84FF9240 SeqNum: 0x78C
Nov 17 22:07:

21.537 GMT: //18680//MSM :/ms_associateDone: First Buf Play at 2w2d of

en_welcome.wav
Nov 17 22:07:21.541 GMT: //18680//MSM :

/ms_associateDone: 2w2d, Tstart(ply: iSndDly 0)
Nov 17 22:07:21.545

GMT: //-1//MCM :MR128:/mc_make_packets_DQ: mc:85762AB4 name:en_welcome.

wav
Nov 17 22:07:21.545 GMT: //-1//MCM :MC121:/mc_make_nohdr_packet: 
mc-
>name en_welcome.wav, bytesLeftInBuf: 71306
Nov 17 22:07:21.545 
GMT: //-
1//MCM :MC121:/mc_make_nohdr_packet: (enough data), 
current_buf:
0x857C04A4 thisMcBytesPlayed: 160
Nov 17 22:07:21.549 GMT: 
//-1//MCM :
MC121:/mc_make_nohdr_packet: mc->name en_welcome.wav, 
bytesLeftInBuf:
71146
Nov 17 22:07:21.549 GMT: //-1//MCM :MC121:
/mc_make_nohdr_packet:
(enough data), current_buf: 0x857C04A4 
thisMcBytesPlayed: 320

                               [....] a bunch 
of those [....]

Nov 17
22:07:21.625 GMT: //-1//MCM :MC121:
/mc_make_nohdr_packet: mc->name
en_welcome.wav, bytesLeftInBuf: 61866

Nov 17 22:07:21.629 GMT: //-
1//MCM :MC121:/mc_make_nohdr_packet: 
(enough data), current_buf:
0x857C04A4 thisMcBytesPlayed: 9600
Nov 17 
22:07:21.629 GMT: //-1//MCM :
MR128:/mc_make_packets_DQ: 
mc_fill_pakList(): status_fillpak 1, /

                      mcr-
>thisMcBytesPlay 9600, mc:85762AB4 name:
en_welcome.wav
Nov 17 22:07:
21.629 GMT: //-1//MCM :MR128:
/mc_make_packets_DQ: count: 60
Nov 17 22:
07:21.629 GMT: //-1//MCM :
MR128:/mc_load_next_mcr_data: mc: 0x85762AB4

Nov 17 22:07:21.629 GMT:
//-1//MCM :MR128:/mc_load_next_mcr_data: 
count: 60
Nov 17 22:07:21.629
GMT: //18680//MSM :
/ms_handle_stream_timer: >>ms_start_play()
Nov 17 22:
07:21.633 GMT: 
//18680//MSM :/ms_start_play: 2w2d mgdTstart(rf)
Nov 17
22:07:21.633 
GMT: //18680//MSM :/ms_start_play: 2w2d mgdTstop(ply)
Nov
17 22:07:
21.845 GMT: //-1//MCM :MR128:/mc_make_packets_DQ: mc:85762AB4
name:
en_welcome.wav
Nov 17 22:07:21.845 GMT: //-1//MCM :MR128:

/mc_make_packets_DQ: mc_fill_pakList(): status_fillpak 1, /

                      mcr->thisMcBytesPlay 9600, mc:85762AB4 name:

en_welcome.wav
Nov 17 22:07:21.845 GMT: //-1//MCM :MR128:

/mc_make_packets_DQ: count: 60
Nov 17 22:07:21.849 GMT: //-1//MCM :

MR128:/mc_load_next_mcr_data: mc: 0x85762AB4
Nov 17 22:07:21.849 GMT:

//-1//MCM :MR128:/mc_load_next_mcr_data: count: 60
Nov 17 22:07:21.849

GMT: //18680//MSM :/ms_need_packets_more: mgdTstart(rf) at 2w2d
Nov 17

22:07:22.049 GMT: //-1//MCM :MR128:/mc_make_packets_DQ: mc:85762AB4

name:en_welcome.wav
Nov 17 22:07:22.049 GMT: //-1//MCM :MR128:

/mc_make_packets_DQ: mc_fill_pakList(): status_fillpak 1, /

                      mcr->thisMcBytesPlay 9600, mc:85762AB4 name:

en_welcome.wav
Nov 17 22:07:22.049 GMT: //-1//MCM :MR128:

/mc_make_packets_DQ: count: 60
Nov 17 22:07:22.049 GMT: //-1//MCM :

MR128:/mc_load_next_mcr_data: mc: 0x85762AB4
Nov 17 22:07:22.049 GMT:

//-1//MCM :MR128:/mc_load_next_mcr_data: count: 60
Nov 17 22:07:22.053

GMT: //18680//MSM :/ms_need_packets_more: mgdTstart(rf) at 2w2d
Nov 17

22:07:22.417 GMT: //-1//MCM :MR128:/mc_make_packets_DQ: mc:85762AB4

name:en_welcome.wav
Nov 17 22:07:22.417 GMT: //-1//MCM :MR128:

/mc_make_packets_DQ: mc_fill_pakList(): status_fillpak 1, /

                               [....] a bunch of those [....]

                      mcr->thisMcBytesPlay 9600, mc:85762AB4 name:

en_welcome.wav
Nov 17 22:07:31.209 GMT: //-1//MCM :MR128:

/mc_make_packets_DQ: count: 60
Nov 17 22:07:31.209 GMT: //-1//MCM :

MR128:/mc_load_next_mcr_data: mc: 0x85762AB4
Nov 17 22:07:31.209 GMT:

//-1//MCM :MR128:/mc_load_next_mcr_data: count: 60
Nov 17 22:07:31.209

GMT: //18680//MSM :/ms_need_packets_more: mgdTstart(rf) at 2w2d
Nov 17

22:07:31.253 GMT: //-1//MSW :/msw_synth_bargein:
Nov 17 22:07:31.257

GMT: msw_synth_bargein: genericStream=0x84861388,

reason=MSW_REASON_DISCONNECTED, current_state=MSW_S_PLAYING
Nov 17 22:

07:31.257 GMT: //-1//MSW :/msw_synth_stop: msw_synth_stop: Enter...
Nov

17 22:07:31.257 GMT: //-1//MSW :/msw_synth_stop:

genericStream=0x84861388,
               mediaStream=0x854487C8,

rtspStream=0x8486208C

reason=MSW_SYNTH_REASON_DISCONNECTED, current_state=MSW_S_PLAYING
Nov

17 22:07:31.257 GMT: //18680//MSW :/msw_synth_stop: msw_synth_stop:

call ms_stop_play()...
Nov 17 22:07:31.257 GMT: //18680//MSM :

/ms_stop_play: mgdTstop at 2w2d (cause MS_STOP_DISCONNECTED)
Nov 17 22:

07:31.257 GMT: //18680//MSM :/ms_stop_play: mgdTstop(rf) at 2w2d
Nov 17

22:07:31.261 GMT: //18680//MSM :/ms_stop_play: Play Stopped at 2w2d
Nov

17 22:07:31.261 GMT: //18680//MSM :/ms_stop_play: calling CBF for

stream_id 1
Nov 17 22:07:31.261 GMT: //18680//MSW :

/msu_synth_ms_play_complete: context=0x8486138C, use_dynamic=1,

dynamicElement=0x860897C4,
            cause=MS_STOP_DISCONNECTED,

stream_id=1, duration=0, rate=0 proto_code=0
Nov 17 22:07:31.261 GMT:

//18680//MSW :/msu_synth_ms_play_complete: Ignoring: cur. stream=2,

resp. stream=1
Nov 17 22:07:31.265 GMT: //-1//MCM :MR128:

/mc_delete_read: dequeue(pakList)
Nov 17 22:07:31.265 GMT:
//18680//MSW 
:/msw_synth_stop: msw_synth_stop: call

reg_invoke_dp_delete_mcDynamicS()
Nov 17 22:07:31.265 GMT: //-1//DPM :

DP139:/dp_delete_mcDynamicS: >dequeue(mcDynamicQ)
Nov 17 22:07:31.269

GMT: //-1//MCM :MC121:/mc_delete: mc=0x85762AB4
Nov 17 22:07:31.269

GMT: //-1//MCM :MC121:/mc_delete: refCount=1
Nov 17 22:07:31.269 GMT:

//-1//MCM :MC121:/mc_check_background_load: mc(0x85762AB4), mc->eof=1

Nov 17 22:07:31.269 GMT: //-1//MCM :MC121:/mc_waitq_add:

prompt_wait=B9289 prompt_active=5F30
Nov 17 22:07:31.269 GMT: //-1//MCM

:MC142:/mc_delete: mc=0x859C371C
Nov 17 22:07:31.269 GMT: //-1//MCM :

MC142:/mc_delete: refCount=1
Nov 17 22:07:31.273 GMT: //-1//MCM :MC142:

/mc_check_background_load: mc(0x859C371C), mc->eof=1
Nov 17 22:07:

31.273 GMT: //-1//MCM :MC142:/mc_remove: mc: 0x859C371C
Nov 17 22:07:

31.273 GMT: //-1//MCM :MC142:/mc_hold_mc: mc_hold_mc: 0x859C371C
Nov 17

22:07:31.273 GMT: //-1//MCM :MC123:/mc_delete: mc=0x8578BC80
Nov 17 22:

07:31.273 GMT: //-1//MCM :MC123:/mc_delete: refCount=1
Nov 17 22:07:

31.277 GMT: //-1//MCM :MC123:/mc_check_background_load: mc(0x8578BC80),

mc->eof=1
Nov 17 22:07:31.277 GMT: //-1//MCM :MC123:/mc_waitq_add:

prompt_wait=BF1B9 prompt_active=0
Nov 17 22:07:31.277 GMT: //-1//DPM :

DP139:/dp_delete_mcDynamicS: >free(p_mcDynamicS)
Nov 17 22:07:31.277

GMT: //-1//MSW :/msw_mediadone_stats: 
Nov 17 22:07:31.285 GMT:

//18680//TCL :/tcl_CallObjCmd:  call close
Nov 17 22:07:31.285 GMT:

//18680//TCL :/tcl_CallCloseObjCmd:  close
Nov 17 22:07:31.305 GMT: %

VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2, ConnectionId

7682C5ED2F411DE8875B021D1E42C3D, SetupTime 22:07:21.321 GMT Tue Nov 17

2009, PeerAddress 105, PeerSubAddress , DisconnectCause 10  ,

DisconnectText normal call clearing (16), ConnectTime 22:07:21.535 GMT

Tue Nov 17 2009, DisconnectTime 22:07:31.305 GMT Tue Nov 17 2009,

CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0,

TransmitBytes 0, ReceivePackets 450, ReceiveBytes 72000
Nov 17 22:07:

31.305 GMT: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:11/17/2009

22:07:21.325,cgn:105,cdn:5000,frs:0,fid:888,fcid:

7682C5ED2F411DE8875B021D1E42C3D,legID:48F8,bguid:

07682C5ED2F411DE8875B021D1E42C3D
Nov 17 22:07:31.313 GMT: //18680//MSW 
:
/msw_destroy: 
Nov 17 22:07:31.313 GMT: //-1//MSW :/msw_stop:

genericStream=0x84861388,
               reason=MSW_REASON_DISCONNECTED

Nov 17 22:07:31.317 GMT: //-1//MSW :/msw_synth_stop: msw_synth_stop:

Enter...
Nov 17 22:07:31.317 GMT: //-1//MSW :/msw_synth_stop:

genericStream=0x84861388,
               mediaStream=0x854487C8,

rtspStream=0x8486208C

reason=MSW_SYNTH_REASON_DISCONNECTED, current_state=MSW_S_IDLE
Nov 17

22:07:31.317 GMT: //18680//MSW :/msw_synth_stop: Stream not currently

active
Nov 17 22:07:31.317 GMT: //-1//MSW :/msw_recrd_stop:

genericStream=0x84861388,
               mediaStream=0x86128B20,

rtspStream=0x84862094

reason=MSW_RECRD_REASON_DISCONNECTED current_state=MSW_S_IDLE
Nov 17 
22:
07:31.317 GMT: //-1//MSW :/msw_recrd_stop: Stream not currently 
active

Nov 17 22:07:31.317 GMT: //-1//MSW :/msw_recog_stop:
Nov 17 22:
07:
31.321 GMT: msw_recog_stop: genericStream=0x84861388,

mrcpStream=0x84862118

reason=MSW_RECOG_REASON_DISCONNECTED, current_state=MSW_S_IDLE
Nov 17

22:07:31.321 GMT: //-1//MSW :/msw_synth_stop: msw_synth_stop: Enter...

Nov 17 22:07:31.321 GMT: //-1//MSW :/msw_synth_stop:

genericStream=0x84861388,
               mediaStream=0x854487C8,

rtspStream=0x8486208C

reason=MSW_SYNTH_REASON_DISCONNECTED, current_state=MSW_S_IDLE
Nov 17

22:07:31.321 GMT: //18680//MSW :/msw_synth_stop: Stream not currently

active
Nov 17 22:07:31.321 GMT: //-1//MSW :/msw_recrd_stop:

genericStream=0x84861388,
               mediaStream=0x86128B20,

rtspStream=0x84862094

reason=MSW_RECRD_REASON_DISCONNECTED current_state=MSW_S_IDLE
Nov 17 
22:
07:31.325 GMT: //-1//MSW :/msw_recrd_stop: Stream not currently 
active

Nov 17 22:07:31.325 GMT: //-1//MSW :/msw_recog_stop:
Nov 17 22:
07:
31.325 GMT: msw_recog_stop: genericStream=0x84861388,

mrcpStream=0x84862118

reason=MSW_RECOG_REASON_DISCONNECTED, current_state=MSW_S_IDLE
Nov 17

22:07:31.341 GMT: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2,

ConnectionId 7682C5ED2F411DE8875B021D1E42C3D, SetupTime 22:07:21.251

GMT Tue Nov 17 2009, PeerAddress 5000, PeerSubAddress , DisconnectCause

10  , DisconnectText normal call clearing (16), ConnectTime 22:07:

21.661 GMT Tue Nov 17 2009, DisconnectTime 22:07:31.341 GMT Tue Nov 17

2009, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 450,

TransmitBytes 72000, ReceivePackets 0, ReceiveBytes 0
Nov 17 22:07:

31.345 GMT: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEAT_VSA=fn:TWC,ft:11/17/2009

22:07:21.237,cgn:105,cdn:5000,frs:0,fid:887,fcid:

7682C5ED2F411DE8875B021D1E42C3D,legID:48F7,bguid:

07682C5ED2F411DE8875B021D1E42C3D

thanks
Alessandro

Trova il tuo Prestito Online

Confronta le offerte di 15 Istituti. Consulenza gratuita. Importo fino a 60.000 €. Durate fino a 120 mesi