TCL Transfer and codecs

Version 1

    Subject: RE: TCL Transfer and codecs
    Replied by: Raghavendra Gutty Veeranagappa on 10-02-2011 06:21:53 AM
    Hi Marko,

    Please send us logs by enabling following debugs

    debug voip app
    debug voip ccapi def

    Thanks,
    Raghavendra
    This document was generated from CDN thread

    Created by: Marko Tisler on 09-02-2011 06:27:14 PM
    Hello,
     
    we are having some problems doing TCL script transfer with g711alaw codec. The scenario is:
     
    - G711 alaw call comes in, triggers the TCL script
    - TCL script creates a new leg to an internal number and transfers the call. The new leg is supposed to be G711 alaw as well (matching dial-peer has only this codec on it).
     
    However, we see that the TCL script insists on using G711 ulaw for this second and therefore doesn't work unless we do alaw to ulaw transcoding. So my question is, is it possible to do this using G711 alaw on both call legs? Anyone tried this?
     
    Best regards,
     
    Marko

    Subject: RE: TCL Transfer and codecs
    Replied by: Yaw-Ming Chen on 09-02-2011 07:02:30 PM
    I don't think Tcl has the control. There is no any parameter for the codec when doing call setup. pretty much IOS controls it.
     
    Can you please share your IOS config ? Or you can send it to developer-support@cisco.com (including Tcl IVR in subject).
     
    Also log of the following debug
    deb voip app tcl
    deb voip app media
    helps
     
    Thanks !

    Subject: RE: TCL Transfer and codecs
    Replied by: Yaw-Ming Chen on 09-02-2011 07:10:35 PM
    Can you please also share your script ?

    Do you include leg_incoming in leg setup ? Any other option for leg setup ?

    Thanks !

    Subject: RE: TCL Transfer and codecs
    Replied by: Marko Tisler on 10-02-2011 03:39:26 AM
    This would be the respective dial-peers:

    Inbound dial-peer:

    dial-peer voice 4746600 voip
    description TEST Script
    huntstop
    service tclscript
    no modem passthrough
    incoming called-number 014746628
    dtmf-relay rtp-nte
    codec g711alaw
    no vad

    Outbound dial-peer:

    dial-peer voice 10 voip
    description To CCM
    huntstop
    preference 1
    destination-pattern 014746[5-6]..
    modem passthrough nse codec g711alaw
    voice-class codec 1
    session protocol sipv2
    session target ipv4:XX.XX.XX.XX
    dtmf-relay rtp-nte
    dtmf-interworking rtp-nte
    fax-relay ecm disable
    no vad

    Both peers are matched correctly and on a call where TCL script is removed from the incoming dial peer, we get alaw codecs on both sides. When we activate the TCL script however,  we get alaw on incoming call leg and ulaw on outgoing call leg. If we replace the "voice-class codec 1" on the outbound dial-peer with "codec g711alaw" the call fails while the script is active, but succeeds as soon as the script is removed form the incoming diall peer.

    Thanks,

    Marko

    Subject: RE: TCL Transfer and codecs
    Replied by: Marko Tisler on 10-02-2011 07:17:29 AM
    Logs (also note g711u and g711a call legs)

    =~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2011.02.10 13:09:55 =~=~=~=~=~=~=~=~=~=~=~=
    ipipgw# sh sccp connections
    sess_id    conn_id      stype mode     codec   sport rport ripaddr

    3          3            xcode sendrecv g711a   17364 2000  193.77.3.143
    3          4            xcode sendrecv g711u   17176 2000  193.77.3.143

    Total number of active session(s) 1, and connection(s) 2

    ipipgw# sh sccp connections
    sess_id    conn_id      stype mode     codec   sport rport ripaddr

    3          3            xcode sendrecv g711a   17364 2000  193.77.3.143
    3          4            xcode sendrecv g711u   17176 2000  193.77.3.143

    Total number of active session(s) 1, and connection(s) 2

    ipipgw# sh sccp connections
    sess_id    conn_id      stype mode     codec   sport rport ripaddr

    3          3            xcode sendrecv g711a   17364 2000  193.77.3.143
    3          4            xcode sendrecv g711u   17176 2000  193.77.3.143

    Total number of active session(s) 1, and connection(s) 2

    ipipgw#3          3            xcode sendrecv g711a   17364 2000  193.77.3.143
                      ^
    % Invalid input detected at '^' marker.

    ipipgw#3          4            xcode sendrecv g711u   17176 2000  193.77.3.143
                      ^
    % Invalid input detected at '^' marker.

    ipipgw#debug voip app
    ipipgw#debug voip application
    Default vxml debugging is on
    Default application debugging is on

    ipipgw#debug voi
    ipipgw#debug voicc      p
    ipipgw#debug voip cca
    ipipgw#debug voip ccapi de
    ipipgw#debug voip ccapi def
    ipipgw#debug voip ccapi default
    voip ccapi default debugging is on
    ipipgw#term mon
    ipipgw#
    Feb 10 13:13:24.822: //210056/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind:
       Call Entry Is Not Found
    Feb 10 13:13:24.822: //-1/FE7D84CC869E/CCAPI/cc_api_display_ie_subfields:
       cc_api_call_setup_ind_common:
       cisco-username=031387265
       ----- ccCallInfo IE subfields -----
       cisco-ani=031387265
       cisco-anitype=0
       cisco-aniplan=0
       cisco-anipi=0
       cisco-anisi=0
       dest=014746628
       cisco-desttype=0
       cisco-destplan=0
       cisco-rdie=FFFFFFFF
       cisco-rdn=
       cisco-lastrdn=
       cisco-rdntype=0
       cisco-rdnplan=0
       cisco-rdnpi=-1
       cisco-rdnsi=-1
       cisco-redirectreason=-1   fwd_final_type =0
       final_redirectNumber =
       hunt_group_timeout =0

    Feb 10 13:13:24.826: //-1/FE7D84CC869E/CCAPI/cc_api_call_setup_ind_common:
       Interface=0x47CE1060, Call Info(
       Calling Number=031387265,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
       Called Number=014746628(TON=Unknown, NPI=Unknown),
       Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
       Incoming Dial-peer=4746600, Progress Indication=NULL(0), Calling IE Present=TRUE,
       Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=210056
    Feb 10 13:13:24.826: //-1/FE7D84CC869E/CCAPI/ccCheckClipClir:
       In: Calling Number=031387265(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
    Feb 10 13:13:24.826: //-1/FE7D84CC869E/CCAPI/ccCheckClipClir:
       Out: Calling Number=031387265(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
    Feb 10 13:13:24.826: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:24.826: :cc_get_feature_vsa malloc success
    Feb 10 13:13:24.826: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:24.826:  cc_get_feature_vsa count is 1
    Feb 10 13:13:24.826: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:24.826: :FEATURE_VSA attributes are: feature_name:0,feature_time:1248157576,feature_id:201106
    Feb 10 13:13:24.826: //210056/FE7D84CC869E/CCAPI/cc_api_call_setup_ind_common:
       Set Up Event Sent;
       Call Info(Calling Number=031387265(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
       Called Number=014746628(TON=Unknown, NPI=Unknown))
    Feb 10 13:13:24.830: //210056/FE7D84CC869E/CCAPI/cc_process_call_setup_ind:
       Event=0x47EC7D38
    Feb 10 13:13:24.830: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
       Try with the demoted called number 014746628
    Feb 10 13:13:24.830: //-1//SERV:/AFW_Service_CCInterface: Received Event 25 for service num2name-http modulehandle NULL
    Feb 10 13:13:24.830: //-1//SERV:/AFW_Service_GetExecEnv: Script Name = num2name-http
    Feb 10 13:13:24.830: //-1//SERV:/AFW_Service_GetExecEnv: Using Cached ExecEnv
    Feb 10 13:13:24.830: //-1//AFW_:EE49A805CC000:/AFW_ExecEnv_SetRoot: Execenv = 0x49A805CC
    Feb 10 13:13:24.830: //-1//AFW_:/AFW_M_TclModule_NewDup: 
    Feb 10 13:13:24.830: //-1//AFW_:HN27E444BC:/AFW_M_TclModule_Free: 
    Feb 10 13:13:24.830: //-1//AFW_:HN27E444BC:/AFW_M_Module_GetHandle: Module handle: TclModule_49A654A8_3_669271228MOD[TclModule_49A654A8_3_669271228] ( )
    Feb 10 13:13:24.830: //-1//AFW_:/AFW_M_FSM_Free: 
    Feb 10 13:13:24.834: //-1//AFW_:/AFW_Process_Lock: pProcess(0x497CC6C4)=1
    Feb 10 13:13:24.834: //-1//AFW_:LP:EE49A805CC000:HN27E515F4:/AFW_M_Object_SetExecEnv: ObjCount: 1, CmdPending 0
    Feb 10 13:13:24.834: //-1//AFW_:EE49A805CC000:/AFW_ExecEnv_RestoreDataBackup: Restore DataArea from Script
    Feb 10 13:13:24.834: //-1//AFW_:EE49A805CC000:/AFW_ExecEnv_RestoreDataBackup: Restore TokenTable from Script
    Feb 10 13:13:24.834: //-1//AFW_:EE49A805CC000:/AFW_ExecEnv_Initiate: Execenv = 0x49A805CC
    Feb 10 13:13:24.834: //-1//AFW_:EE49A805CC000:/AFW_ExecEnv_SetCallCorID:
    Feb 10 13:13:24.834:  CallCorID is ~} L4E `   `SuCU
    Feb 10 13:13:24.834: //-1//AFW_:/AFW_Leg_New: 
    Feb 10 13:13:24.834: //210056/FE7D84CC869E/CCAPI/ccCallSetContext:
       Context=0x49AA360C
    Feb 10 13:13:24.834: //210056//AFW_:/AFW_M_Leg_SetExecEnv: 
    Feb 10 13:13:24.834: //-1//AFW_:EE49A805CC000:/AFW_ExecEnv_IncrPendingCmd:  PendingCmdCount: 1
    Feb 10 13:13:24.834: //-1//AFW_:LP:EE49A805CC000:LG210056:/AFW_M_Object_SetExecEnv: ObjCount: 2, CmdPending 1
    Feb 10 13:13:24.834: //210056//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener
    Feb 10 13:13:24.834: //210056//AFW_:/AFW_M_Leg_GetHandle: Leg handle: LEG_210056
    Feb 10 13:13:24.834: //-1//AFW_:EE49A805CC000:/AFW_ExecEnv_AssignCall: Execenv = 0x49A805CC, Leg = 210056, Peer_Tag = 4746600
    Feb 10 13:13:24.834: //210056//AFW_:/AFW_ExecEnv_SetCallCorID:
    Feb 10 13:13:24.834:  CallCorID is ~} L4E `   `SuCU
    Feb 10 13:13:24.834: //210056/FE7D84CC869E/CCAPI/cc_process_call_setup_ind:
       >>>>CCAPI handed cid 210056 with tag 4746600 to app "_ManagedAppProcess_num2name-http"
    Feb 10 13:13:24.834: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:24.834: //210056//AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:24.834: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CALL_SETUP_IND(31)] {
    Feb 10 13:13:24.838: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:24.838: //-1//AFW_:/AFW_Process_GetCcqEvent:     LEG[210056][LEG_INIT(0)][Cause(0)]
    Feb 10 13:13:24.838: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:24.838: //210056//SSIN:/AFW_SS_MapEvent: 
    Feb 10 13:13:24.838: //-1//SSIN:/AFW_SS_SIP_MapEvent: 
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 91E4 type 0
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/incrementIncomingDialPeerCallStats: Incrementing call-stat for dial-peer [4746600]
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:24.838: //-1//AFW_:/AFW_M_Object_ShowListeners:  
    Feb 10 13:13:24.838: //210056//AFW_:/AFW_M_Module_GetHandle: Module handle: TclModule_49A63AC8_3_669324788MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:24.838: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_INCINIT(1)][Cause(0)]
    Feb 10 13:13:24.838: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:24.838: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:24.838: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:24.838: //210056//AFW_:/AFW_TclModule_DefaultEvHandling: 
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_Leg_CheckIncomingCallBlock: 
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_Leg_SettlementValidateCall: target=, tokenp=0x0
    Feb 10 13:13:24.838: //210056/FE7D84CC869E/AFW_:/AFW_Leg_IncomingTranslate: 
    Feb 10 13:13:24.838: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(INIT[1],ev_setup_indication[31])---[act_Setup]------
    Feb 10 13:13:24.838: //210056//TCL :/tcl_InfotagObjCmd:  infotag get leg_ani
    Feb 10 13:13:24.838: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get leg_ani
    Feb 10 13:13:24.838: //210056//AFW_:/vtr_lg_ani: argc 2 argindex 2
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagObjCmd:  infotag get leg_dnis
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get leg_dnis
    Feb 10 13:13:24.842: //210056//AFW_:/vtr_lg_dnis: argc 2 argindex 2
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists server
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists server
    Feb 10 13:13:24.842: //210056//AFW_:/vtr_cf_avpair_exists: argc 3 argindex 2
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagObjCmd:  infotag get cfg_avpair_exists user
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get cfg_avpair_exists user
    Feb 10 13:13:24.842: //210056//AFW_:/vtr_cf_avpair_exists: argc 3 argindex 2
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagObjCmd:  infotag get cfg_avpair user
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get cfg_avpair user
    Feb 10 13:13:24.842: //210056//AFW_:/vtr_cf_avpair: argc 3 argindex 2
    Feb 10 13:13:24.842: //210056//PACK:/tcl_ParamReadObjCmd:  user
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagObjCmd:  infotag get cfg_avpair password
    Feb 10 13:13:24.842: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get cfg_avpair password
    Feb 10 13:13:24.842: //210056//AFW_:/vtr_cf_avpair: argc 3 argindex 2
    Feb 10 13:13:24.842: //210056//PACK:/tcl_ParamReadObjCmd:  password
    Feb 10 13:13:24.846: //210056//TCL :/tcl_InfotagObjCmd:  infotag get cfg_avpair server
    Feb 10 13:13:24.846: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get cfg_avpair server
    Feb 10 13:13:24.846: //210056//AFW_:/vtr_cf_avpair: argc 3 argindex 2
    Feb 10 13:13:24.846: //210056//PACK:/tcl_ParamReadObjCmd:  server
    Feb 10 13:13:24.846: //210056//TCL :/tcl_InfotagObjCmd:  infotag get cfg_avpair user
    Feb 10 13:13:24.846: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get cfg_avpair user
    Feb 10 13:13:24.846: //210056//AFW_:/vtr_cf_avpair: argc 3 argindex 2
    Feb 10 13:13:24.846: //210056//PACK:/tcl_ParamReadObjCmd:  user
    Feb 10 13:13:24.846: //210056//TCL :/tcl_InfotagObjCmd:  infotag get cfg_avpair password
    Feb 10 13:13:24.846: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get cfg_avpair password
    Feb 10 13:13:24.846: //210056//AFW_:/vtr_cf_avpair: argc 3 argindex 2
    Feb 10 13:13:24.846: //210056//PACK:/tcl_ParamReadObjCmd:  password
    Feb 10 13:13:24.846: //210056//PACK:/tcl_httpiosGeturlObjCmd:  ::httpios::geturl http://193.77.3.112/IPTServices/router.asp?ani=031387265&dnis=014746628&user=test&password=test -headers Authorization {Basic dGVzdDp0ZXN0} -command httpCallback
    Feb 10 13:13:24.846: //210056//PACK:/tcl_httpiosGeturlObjCmd: Current NS: ::
    Feb 10 13:13:24.846: //210056//PACK:/tcl_httpiosGeturlObjCmd: URL: http://193.77.3.112/IPTServices/router.asp?ani=031387265&dnis=014746628&user=test&password=test
    Feb 10 13:13:24.846: //-1//PACK:/httpios_get_custom_headers: No Of Headers: 2
    Feb 10 13:13:24.846: //-1//PACK:/httpios_get_custom_headers: Authorization: Basic dGVzdDp0ZXN0
    Feb 10 13:13:24.846: //-1//AFW_:/AFW_Event_New: Event ID: UnknownEvent
    Feb 10 13:13:24.846: //210056//AFW_:/AFW_ExecEnv_IncrPendingCmd:  PendingCmdCount: 2
    Feb 10 13:13:24.850: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(CALL[3])---------------
    Feb 10 13:13:24.850: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:24.854: //210056/FE7D84CC869E/AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:24.854: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 2
    Feb 10 13:13:24.878: //210056//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
    Feb 10 13:13:24.878: //210056//AFW_:/AFW_ExecEnv_DecrNPendingCmd:  PendingCmdCount: 1
    Feb 10 13:13:24.878: //-1//PACK:/httpios_http_get_cb:
    Feb 10 13:13:24.878: httpios_http_get_cb: status: 200
    Feb 10 13:13:24.882: //210056//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
    Feb 10 13:13:24.882: //210056//AFW_:/AFW_Process_GetAppQEvent: Received
    Feb 10 13:13:24.882: //-1//AFW_:/AFW_Process_GetAppQEvent:   Event[APP_EV_HTTPCB_EVENT(238)] {
    Feb 10 13:13:24.882: //-1//AFW_:/AFW_Process_GetAppQEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:24.882: //-1//AFW_:/AFW_Process_GetAppQEvent:     MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:24.882: //-1//AFW_:/AFW_Process_GetAppQEvent:       LEG[210056][LEG_INCINIT(1)][Cause(0)]
    Feb 10 13:13:24.882: //-1//AFW_:/AFW_Process_GetAppQEvent:     )
    Feb 10 13:13:24.882: //-1//AFW_:/AFW_Process_GetAppQEvent:   }
    Feb 10 13:13:24.882: //210056//AFW_:/AFW_M_TclModule_EventPreProcess: 
    Feb 10 13:13:24.882: //210056//PACK:/tclhttp_handle_httpcb_event: ret_status(0)
    Feb 10 13:13:24.882: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:24.882: //210056//PACK:/tclhttp_handle_httpcb_event: MODULE HANDLE: :: 0x00000000
    Feb 10 13:13:24.886: //210056//PACK:/tcl_httpiosStatusObjCmd:  ::httpios::status ::httpios::httpios_433
    Feb 10 13:13:24.886: //210056//PACK:/tcl_httpiosDataObjCmd:  ::httpios::data ::httpios::httpios_433
    Feb 10 13:13:24.886: //210056//PACK:/tcl_httpiosCleanupObjCmd:  ::httpios::cleanup ::httpios::httpios_433
    Feb 10 13:13:24.890: //210056//TCL :/tcl_LegObjCmd:  leg setup 014746628 callInfo leg_outgoing
    Feb 10 13:13:24.890: //210056//CSPK:/tcl_LegSetupObjCmd: leg setup 014746628 callInfo leg_outgoing
    Feb 10 13:13:24.890: //210056//AFW_:/vtd_lg_outgoing: argc 4
    Feb 10 13:13:24.890: //210056//AFW_:/vtd_lg_outgoing: Legs []
    Feb 10 13:13:24.890: //210056//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=0
    Feb 10 13:13:24.890: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: copied 031387265

    Feb 10 13:13:24.890: //-1//CSPK:/tclSetCallInfoParams: originationNum=031387265
    Feb 10 13:13:24.890: //-1//CSPK:/tclSetCallInfoParams: displayInfo=Marko Tisler
    Feb 10 13:13:24.890: //-1//CSPK:/tclSetControlParams: Notify Signaling Mask=10
    Feb 10 13:13:24.890: //210056//CSPK:/C_CallSetup_Start: ControlInfo = 0x49AB36F8, callInfo = 0x4A194060, destination[0]=014746628
    Feb 10 13:13:24.894: //210056//CSPK:/C_CallSetup_Start: configured mode=rotary (1)
    Feb 10 13:13:24.894: //210056//CSPK:/C_CallSetup_Start: configured reroutemode=rotary (1)
    Feb 10 13:13:24.894: //-1//Call:/AFW_CallSetup_New: 
    Feb 10 13:13:24.894: //-1//AFW_:/AFW_FSM_New: 
    Feb 10 13:13:24.894: //-1//AFW_:LP:EE49A805CC000:HN27E51634:/AFW_M_Object_SetExecEnv: ObjCount: 3, CmdPending 1
    Feb 10 13:13:24.894: //210056//AFW_:/AFW_ExecEnv_IncrPendingCmd:  PendingCmdCount: 2
    Feb 10 13:13:24.894: //210056//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener
    Feb 10 13:13:24.894: //210056//AFW_:/AFW_M_Module_GetHandle: Module handle: CallSetup_4983BFB8_3_669324852
    Feb 10 13:13:24.894: //210056//Call:/AFW_CallSetup_AddDest:  014746628 index 0
    Feb 10 13:13:24.894: //210056//Call:/AFW_M_CallSetup_Initiate: 
    Feb 10 13:13:24.894: //210056//Call:/CS_UpdateMLPPInfo: MLPP info not available and hence not updated
    Feb 10 13:13:24.894: //210056//Call:/CallSetupInitiate: 
    Feb 10 13:13:24.894: //210056//Call:/CS_Placecall: 
    Feb 10 13:13:24.894: //210056//Call:/CS_Placecall: enable IP-IP reroute_ind intercept[800]
    Feb 10 13:13:24.894: //210056//Call:/CS_UpdateMLPPInfo: MLPP info not available and hence not updated
    Feb 10 13:13:24.894: //-1//Dest:/AFW_Destination_New: 
    Feb 10 13:13:24.894: //-1//AFW_:/AFW_FSM_New: 
    Feb 10 13:13:24.894: //-1//AFW_:/AFW_FSM_New: 
    Feb 10 13:13:24.898: //-1//AFW_:LP:EE49A805CC000:HN27E51634:/AFW_M_Object_SetExecEnv: ObjCount: 4, CmdPending 2
    Feb 10 13:13:24.898: //210056//AFW_:/AFW_ExecEnv_IncrPendingCmd:  PendingCmdCount: 3
    Feb 10 13:13:24.898: //210056//AFW_:/AFW_Object_AddListener: adding Module CallSetup as listener
    Feb 10 13:13:24.898: //210056//AFW_:/AFW_M_Module_GetHandle: Module handle: Destination_49876DCC_3_669324852
    Feb 10 13:13:24.898: //210056//Dest:/AFW_Destination_AddDest: 
    Feb 10 13:13:24.898: //-1//Dest:/AFW_Destination_AddDest: adding destination "014746628"
    Feb 10 13:13:24.898: //210056//Dest:/AFW_M_Destination_Initiate: 
    Feb 10 13:13:24.898: //210056//Dest:/AFW_M_Destination_Initiate: Outgoing guid : FE89B9B5.344511E0.86A38B60.D3F543D5
    Feb 10 13:13:24.898:  Incoming_guid : 00000000.00000000.00000000.00000000
    Feb 10 13:13:24.898: //210056//Dest:/AFW_M_Destination_Initiate: Outgoing gcid : 00000000.00000000.00000000.00000000
    Feb 10 13:13:24.898:  Incoming_gcid : 00000000.00000000.00000000.00000000
    Feb 10 13:13:24.898: //210056//Dest:/DestUpdateMLPPInfo: MLPP Information not available and hence not updated
    Feb 10 13:13:24.898: //210056//Dest:/DestSetupInitiate: 
    Feb 10 13:13:24.898: //210056//Dest:/DestAddRerouteSetupInfo: 
    Feb 10 13:13:24.898: //210056//Dest:/DestAddTransferSetupInfo: 
    Feb 10 13:13:24.898: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: copied 014746628

    Feb 10 13:13:24.898: //210056//Dest:/DestMatchDialPeer: 
    Feb 10 13:13:24.898: //210056//Dest:/DestMatchDialPeer: src carrier id:, tgt carrier id:
    Feb 10 13:13:24.898: //210056//Dest:/DestQueuePeers: Matched peers:
    Feb 10 13:13:24.898: //210056//Dest:/DestPeerItemUpdatePreemptInfo: 
    Feb 10 13:13:24.898: //-1//Dest:/DestQueuePeers:  10
    Feb 10 13:13:24.902: //210056//Dest:/DestPeerItemUpdatePreemptInfo: 
    Feb 10 13:13:24.902: //-1//Dest:/DestQueuePeers:  11
    Feb 10 13:13:24.902: //210056//Dest:/DestPeerItemUpdatePreemptInfo: 
    Feb 10 13:13:24.902: //-1//Dest:/DestQueuePeers:  40
    Feb 10 13:13:24.902: //-1//Dest:/DestQueuePeers: , Total(3)
    Feb 10 13:13:24.902: //210056//Dest:/DestDialPeerRotary: 
    Feb 10 13:13:24.902: //210056//Dest:/DestOutboundCallUsingPeer: dest(014746628), numexpDest(014746628), numDestDigits(7)
    Feb 10 13:13:24.902: //210056//Dest:/DestOutboundCallUsingPeer:    prefix(.89), peer tag(10)
    Feb 10 13:13:24.902: //210056//Dest:/DestUpdatePreemptSetupInfo: 
    Feb 10 13:13:24.902: //210056//Dest:/DestCheckTeleUnassBusy: 
    Feb 10 13:13:24.902: //210056//Dest:/DestSetup: 
    Feb 10 13:13:24.902: //-1//AFW_:/AFW_Leg_GetTypeDetail:  ***ERROR*** pLeg is NULL
    Feb 10 13:13:24.902: //-1//AFW_:/AFW_Util_SaveRawMsg: 
    Feb 10 13:13:24.902: //-1//AFW_:/AFW_Leg_New: 
    Feb 10 13:13:24.902: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:
       Destination=, Calling IE Present=TRUE, Mode=0,
       Outgoing Dial-peer=10, Params=0x49AA4964, Progress Indication=NULL(0)
    Feb 10 13:13:24.902: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
       In: Calling Number=031387265(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
    Feb 10 13:13:24.902: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:
       Out: Calling Number=031387265(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
    Feb 10 13:13:24.902: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:
       Destination Pattern=014746[5-6].., Called Number=014746628, Digit Strip=FALSE
    Feb 10 13:13:24.902: //-1/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest:
       Calling Number=031387265(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
       Called Number=014746628(TON=Unknown, NPI=Unknown),
       Redirect Number=, Display Info=Marko Tisler
       Account Number=, Final Destination Flag=FALSE,
       Guid=FE89B9B5-3445-11E0-86A3-8B60D3F543D5, Outgoing Dial-peer=10
    Feb 10 13:13:24.902: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:
       ccCallSetupRequest:
       cisco-username=
       ----- ccCallInfo IE subfields -----
       cisco-ani=031387265
       cisco-anitype=0
       cisco-aniplan=0
       cisco-anipi=0
       cisco-anisi=0
       dest=014746628
       cisco-desttype=0
       cisco-destplan=0
       cisco-rdie=FFFFFFFF
       cisco-rdn=
       cisco-lastrdn=
       cisco-rdntype=0
       cisco-rdnplan=0
       cisco-rdnpi=0
       cisco-rdnsi=0
       cisco-redirectreason=0   fwd_final_type =0
       final_redirectNumber =
       hunt_group_timeout =0

    Feb 10 13:13:24.902: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
       Interface=0x47CE1060, Interface Type=3, Destination=, Mode=0x0,
       Call Params(Calling Number=031387265,(Calling Name=031387265)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
       Called Number=014746628(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
       Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=10, Call Count On=FALSE,
       Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
    Feb 10 13:13:24.906: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:24.906: :cc_get_feature_vsa malloc success
    Feb 10 13:13:24.906: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:24.906:  cc_get_feature_vsa count is 2
    Feb 10 13:13:24.906: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:24.906: :FEATURE_VSA attributes are: feature_name:0,feature_time:1248157352,feature_id:201107
    Feb 10 13:13:24.906: //210057/FE89B9B586A3/CCAPI/ccIFCallSetupRequestPrivate:
       SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
    Feb 10 13:13:24.906: //210057/FE89B9B586A3/AFW_:/AFW_Leg_NewSetupRequest: ccCallSetupRequest done
    Feb 10 13:13:24.906: //210057/FE89B9B586A3/CCAPI/ccCallSetContext:
       Context=0x49AA4914
    Feb 10 13:13:24.906: //210057/FE89B9B586A3/AFW_:/AFW_M_Leg_SetExecEnv: 
    Feb 10 13:13:24.906: //210056//AFW_:/AFW_ExecEnv_IncrPendingCmd:  PendingCmdCount: 4
    Feb 10 13:13:24.906: //-1/FE89B9B586A3/AFW_:LP:EE49A805CC000:LG210057:/AFW_M_Object_SetExecEnv: ObjCount: 5, CmdPending 4
    Feb 10 13:13:24.906: //210057/FE89B9B586A3/AFW_:/AFW_Object_AddListener: adding Module Destination as listener
    Feb 10 13:13:24.906: //210057/FE89B9B586A3/AFW_:/AFW_M_Leg_GetHandle: Leg handle: LEG_210057
    Feb 10 13:13:24.906: //210057/FE89B9B586A3/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 91E4 type 3
    Feb 10 13:13:24.906: //210056//Dest:/DestSetup: Destination setup success
    Feb 10 13:13:24.906: //210056//Dest:/AFW_M_Destination_Initiate: attempting call to destination 014746628
    Feb 10 13:13:24.906: //210056//Call:/CS_Placecall: Call placed to 014746628 index 0
    Feb 10 13:13:24.910: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:24.910: //-1//AFW_:/AFW_M_Httpdata_Free: 
    Feb 10 13:13:24.910: //-1//AFW_:/AFW_M_Httpdata_Free:  Token(::httpios::httpios_433) hdata(0x49A1517C)
    Feb 10 13:13:24.910: //210056//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:24.910: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 4
    Feb 10 13:13:24.910: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:24.910: //-1//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:24.910: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL
    Feb 10 13:13:24.914: //210057/FE89B9B586A3/CCAPI/cc_api_call_proceeding:
       Interface=0x47CE1060, Progress Indication=NULL(0)
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:24.918: //210057/FE89B9B586A3/AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CALL_PROCEEDING(28)] {
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_Process_GetCcqEvent:     LEG[210057][LEG_OUTINIT(7)][Cause(0)]
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:24.918: //210057/FE89B9B586A3/SSIN:/AFW_SS_MapEvent: 
    Feb 10 13:13:24.918: //-1//SSIN:/AFW_SS_SIP_MapEvent: 
    Feb 10 13:13:24.918: //210057/FE89B9B586A3/AFW_:/AFW_M_Leg_EventPreProcess: LEG[210057 ][LEG_OUTINIT(7)][Cause(0)]
    Feb 10 13:13:24.918: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:24.918: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210057][LEG_OUTINIT(7)][Cause(0)]
    Feb 10 13:13:24.918: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:24.918: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:24.918: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination
    Feb 10 13:13:24.918: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:24.918: //210056//Dest:/AFW_M_Destination_Action: 
    Feb 10 13:13:24.918: //210056//Dest:/AFW_Destination_Action: 
    Feb 10 13:13:24.918: //210056//Dest:/OB_FSM_Drive: 
    Feb 10 13:13:24.918: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_proceeding[28])---[C_Action]------
    Feb 10 13:13:24.922: //210056//Dest:/OB_Setting_Proceeding: 
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:24.922: //210056//Dest:/IW_FSM_Drive: 
    Feb 10 13:13:24.922: //210056//Dest:/IW_FSM_Drive: No interworking leg, no need to interwork event
    Feb 10 13:13:24.922: //210056//Dest:/DestIsEventNotified: 
    Feb 10 13:13:24.922: //210056//Dest:/DestComplete: peer #:10 IW State IW_STATE_INIT, OB State OB_STATE_SETTING
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> NULL
    Feb 10 13:13:24.922: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: Event Interceptd : Destination
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:24.922: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[CallSetup_4983BFB8_3_669324852]  (
    Feb 10 13:13:24.922: //-1//AFW_:/AFW_M_Object_ShowListeners:     MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:24.922: //-1//AFW_:/AFW_M_Object_ShowListeners:       LEG[210057][LEG_OUTPROCEED(8)][Cause(0)]
    Feb 10 13:13:24.922: //-1//AFW_:/AFW_M_Object_ShowListeners:     )
    Feb 10 13:13:24.922: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_Object_WalkListeners: Entering Module : CallSetup
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> CallSetup_4983BFB8_3_669324852
    Feb 10 13:13:24.922: //210056//Call:/AFW_M_CallSetup_Action: 
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(S_PLACECALL[2],ev_proceeding[28])---[C_Action]------
    Feb 10 13:13:24.922: //210056//Call:/CS_Placecall_CallSignal: 
    Feb 10 13:13:24.922: //210056//Call:/CS_Placecall_CallSignal:  Event notified to parent
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(S_PLACECALL[2])---------------
    Feb 10 13:13:24.922: //210056//Call:/CS_Complete: Can't complete
    Feb 10 13:13:24.922: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: CallSetup_4983BFB8_3_669324852 ---> NULL
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_Object_WalkListeners: Event Interceptd : CallSetup
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:     MOD[CallSetup_4983BFB8_3_669324852]  (
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:       MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:         LEG[210057][LEG_OUTPROCEED(8)][Cause(0)]
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:       )
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:     )
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_INCINIT(1)][Cause(0)]
    Feb 10 13:13:24.926: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_TclModule_DefaultEvHandling: 
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(CALL[3],ev_proceeding[28])---[act_Proceeding]------
    Feb 10 13:13:24.926: //210056//TCL :/tcl_LegObjCmd:  leg proceeding leg_incoming
    Feb 10 13:13:24.926: //210056//TCL :/tcl_LegProceedObjCmd: proceeding leg_incoming
    Feb 10 13:13:24.926: //210056//AFW_:/vtd_lg_incoming: argc 2
    Feb 10 13:13:24.926: //210056//AFW_:/vtd_lg_incoming: Legs [210056 ]
    Feb 10 13:13:24.926: //210056//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
    Feb 10 13:13:24.926: //210056/FE7D84CC869E/CCAPI/ccCallProceeding:
       Progress Indication=NULL(0)
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(CALL[3])---------------
    Feb 10 13:13:24.926: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:24.930: //210057/FE89B9B586A3/AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:24.930: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 4
    Feb 10 13:13:24.942: //210057/FE89B9B586A3/CCAPI/cc_api_call_alert:
       Interface=0x47CE1060, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
    Feb 10 13:13:24.942: //210057/FE89B9B586A3/CCAPI/cc_api_call_alert:
       Call Entry(Retry Count=0, Responsed=TRUE)
    Feb 10 13:13:24.942: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:24.946: //210057/FE89B9B586A3/AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:24.946: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CALL_ALERT(14)] {
    Feb 10 13:13:24.946: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:24.946: //-1//AFW_:/AFW_Process_GetCcqEvent:     LEG[210057][LEG_OUTPROCEED(8)][Cause(0)]
    Feb 10 13:13:24.946: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:24.946: //210057/FE89B9B586A3/SSIN:/AFW_SS_MapEvent: 
    Feb 10 13:13:24.946: //-1//SSIN:/AFW_SS_SIP_MapEvent: 
    Feb 10 13:13:24.946: //210057/FE89B9B586A3/AFW_:/AFW_M_Leg_EventPreProcess: LEG[210057 ][LEG_OUTPROCEED(8)][Cause(0)]
    Feb 10 13:13:24.946: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:24.946: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:24.946: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:24.946: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210057][LEG_OUTPROCEED(8)][Cause(0)]
    Feb 10 13:13:24.946: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:24.946: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:24.946: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination
    Feb 10 13:13:24.946: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:24.946: //210056//Dest:/AFW_M_Destination_Action: 
    Feb 10 13:13:24.946: //210056//Dest:/AFW_Destination_Action: 
    Feb 10 13:13:24.946: //210056//Dest:/OB_FSM_Drive: 
    Feb 10 13:13:24.946: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:24.946: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_alert[14])---[C_Action]------
    Feb 10 13:13:24.946: //210056//Dest:/OB_Setting_Alert: 
    Feb 10 13:13:24.946: //-1//Dest:/OB_Setting_Alert: aData bitmask 0x1

    Feb 10 13:13:24.950: //-1//Dest:/OB_Setting_Alert: aData.display_info=Marko Tisler

    Feb 10 13:13:24.950: //210057/FE89B9B586A3/AFW_:/AFW_Util_FillDispNotifyData:  Current: notifyData.connected_num.num_string =628,notifyData.display_info = Marko Tisler,notifyData.connected_num.oct3a = 0
    Feb 10 13:13:24.950: //210057/FE89B9B586A3/AFW_:/AFW_Util_FillDispNotifyData:  Filled : notifyData.connected_num.num_string =628,notifyData.display_info = Marko Tisler,notifyData.connected_num.oct3a = 0
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:24.950: //210056//Dest:/IW_FSM_Drive: 
    Feb 10 13:13:24.950: //210056//Dest:/IW_FSM_Drive: No interworking leg, no need to interwork event
    Feb 10 13:13:24.950: //210056//Dest:/DestIsEventNotified: 
    Feb 10 13:13:24.950: //210056//Dest:/DestComplete: peer #:10 IW State IW_STATE_INIT, OB State OB_STATE_SETTING
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> NULL
    Feb 10 13:13:24.950: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: Event Interceptd : Destination
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:24.950: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[CallSetup_4983BFB8_3_669324852]  (
    Feb 10 13:13:24.950: //-1//AFW_:/AFW_M_Object_ShowListeners:     MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:24.950: //-1//AFW_:/AFW_M_Object_ShowListeners:       LEG[210057][LEG_OUTRINGING(9)][Cause(0)]
    Feb 10 13:13:24.950: //-1//AFW_:/AFW_M_Object_ShowListeners:     )
    Feb 10 13:13:24.950: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_Object_WalkListeners: Entering Module : CallSetup
    Feb 10 13:13:24.950: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
    Feb 10 13:13:24.954: //210056/FE7D84CC869E/CCAPI/ccCallAlert:
       Call Entry(Responsed=TRUE, Alert Sent=TRUE)
    Feb 10 13:13:24.958: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(CALL[3])---------------
    Feb 10 13:13:24.958: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:24.958: //210057/FE89B9B586A3/AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:24.958: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 4
    Feb 10 13:13:28.602: //210057/FE89B9B586A3/CCAPI/cc_api_call_connected:
       Interface=0x47CE1060, Data Bitmask=0x1, Progress Indication=NULL(0),
       Connection Handle=0
    Feb 10 13:13:28.602: //210057/FE89B9B586A3/CCAPI/cc_api_call_connected:
       Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
    Feb 10 13:13:28.602: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:28.602: //210057/FE89B9B586A3/AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:28.602: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CALL_CONNECTED(15)] {
    Feb 10 13:13:28.606: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:28.606: //-1//AFW_:/AFW_Process_GetCcqEvent:     LEG[210057][LEG_OUTRINGING(9)][Cause(0)]
    Feb 10 13:13:28.606: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:28.606: //210057/FE89B9B586A3/SSIN:/AFW_SS_MapEvent: 
    Feb 10 13:13:28.606: //-1//SSIN:/AFW_SS_SIP_MapEvent: 
    Feb 10 13:13:28.606: //210057/FE89B9B586A3/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 91E4 type 1
    Feb 10 13:13:28.606: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:28.606: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:28.606: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:28.606: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210057][LEG_OUTCONNECTED(10)][Cause(0)]
    Feb 10 13:13:28.606: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:28.606: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:28.606: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination
    Feb 10 13:13:28.606: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:28.606: //210056//Dest:/AFW_M_Destination_Action: 
    Feb 10 13:13:28.606: //210056//Dest:/AFW_Destination_Action: 
    Feb 10 13:13:28.606: //210056//Dest:/OB_FSM_Drive: 
    Feb 10 13:13:28.606: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:28.606: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_connected[15])---[C_Action]------
    Feb 10 13:13:28.606: //210056//Dest:/OB_Setting_Connect: 
    Feb 10 13:13:28.606: //-1//Dest:/OB_Setting_Connect: cData.display_info=Marko Tisler

    Feb 10 13:13:28.606: //210057/FE89B9B586A3/AFW_:/AFW_Util_FillDispNotifyData:  Current: notifyData.connected_num.num_string =628,notifyData.display_info = Marko Tisler,notifyData.connected_num.oct3a = 0
    Feb 10 13:13:28.610: //210057/FE89B9B586A3/AFW_:/AFW_Util_FillDispNotifyData:  Filled : notifyData.connected_num.num_string =628,notifyData.display_info = Marko Tisler,notifyData.connected_num.oct3a = 0
    Feb 10 13:13:28.610: //210056//Dest:/DestNotifySharedLnState: callid: 210057, peer: 10
    Feb 10 13:13:28.610: //210056//Dest:/DestNotifySharedLnState: Shared-Line NOT enabled!
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_DONE[8])---------------
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> Destination_49876DCC_3_669324852
    Feb 10 13:13:28.610: //210056//Dest:/IW_FSM_Drive: 
    Feb 10 13:13:28.610: //210056//Dest:/IW_FSM_Drive: No interworking leg, no need to interwork event
    Feb 10 13:13:28.610: //210056//Dest:/DestIsEventNotified: 
    Feb 10 13:13:28.610: //210056//Dest:/DestComplete: peer #:10 IW State IW_STATE_INIT, OB State OB_STATE_DONE
    Feb 10 13:13:28.610: //210056//Dest:/DestUpdateMLPPCause: status=0, cause=0
    Feb 10 13:13:28.610: //-1//AFW_:/AFW_Event_New: Event ID: ev_destination_done
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_Module_ReturnArgEv: 
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[210057 ][LEG_OUTCONNECTED(10)][Cause(0)]}
    Feb 10 13:13:28.610: //210057/FE89B9B586A3/AFW_:/AFW_Object_RemoveListener: 
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_Module_UnListen:  NumObjects: 0
    Feb 10 13:13:28.610: //210056//Dest:/DestReturn: Destination Returning(ds_000 Status DEST_ACTIVE)
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: Destination_49876DCC_3_669324852 ---> NULL
    Feb 10 13:13:28.610: //210057/FE89B9B586A3/AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 4
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
    Feb 10 13:13:28.610: //210056//AFW_:/AFW_Process_GetPriorityQEvent: Received
    Feb 10 13:13:28.610: //-1//AFW_:/AFW_Process_GetPriorityQEvent:   Event[APP_EV_DESTINATION_DONE(202)] {
    Feb 10 13:13:28.610: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:28.610: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:28.610: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     )
    Feb 10 13:13:28.614: //-1//AFW_:/AFW_Process_GetPriorityQEvent:   }
    Feb 10 13:13:28.614: //210056//Dest:/AFW_M_Destination_EventPreProcess: 
    Feb 10 13:13:28.614: //210057/FE89B9B586A3/Dest:/DestUpdateSuccessStats: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:28.614: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[CallSetup_4983BFB8_3_669324852]  (
    Feb 10 13:13:28.614: //-1//AFW_:/AFW_M_Object_ShowListeners:     MOD[Destination_49876DCC_3_669324852]  (
    Feb 10 13:13:28.614: //-1//AFW_:/AFW_M_Object_ShowListeners:     )
    Feb 10 13:13:28.614: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Object_WalkListeners: Entering Module : CallSetup
    Feb 10 13:13:28.614: //210057/FE89B9B586A3/AFW_:/AFW_Object_AddListener: adding Module CallSetup as listener
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Object_RemoveListener: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Module_UnListen:  NumObjects: 1
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> CallSetup_4983BFB8_3_669324852
    Feb 10 13:13:28.614: //210056//Call:/AFW_M_CallSetup_Action: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(S_PLACECALL[2],ev_destination_done[202])---[C_Action]------
    Feb 10 13:13:28.614: //210056//Call:/CS_Placecall_DestDone: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(S_DONE[13])---------------
    Feb 10 13:13:28.614: //-1//AFW_:/AFW_Event_New: Event ID: ev_setup_done
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Module_ReturnArgEv: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[210057 ][LEG_OUTCONNECTED(10)][Cause(0)]}
    Feb 10 13:13:28.614: //210057/FE89B9B586A3/AFW_:/AFW_Object_RemoveListener: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Module_UnListen:  NumObjects: 0
    Feb 10 13:13:28.614: //210056//Call:/CS_Complete: CallSetup Returning(ls_000 Status CS_ACTIVE)
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: CallSetup_4983BFB8_3_669324852 ---> NULL
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Object_WalkListeners:
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_M_Event_Free:  MODULEDONEEVENT for a Module: Destination_49876DCC_3_669324852
    Feb 10 13:13:28.614: //210056//AFW_:/AFW_Object_RemoveAllListener: 
    Feb 10 13:13:28.614: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 4, CmdPending 4
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_ExecEnv_DecrNPendingCmd:  PendingCmdCount: 3
    Feb 10 13:13:28.618: //-1//AFW_:HN27E51634:/AFW_M_Event_Free: ExecEnv objCount: 4
    Feb 10 13:13:28.618: //-1//Dest:HN27E51634:/AFW_M_Destination_Free: 
    Feb 10 13:13:28.618: //-1//Dest:HN27E51634:/DestEmptyPreemptHoldQ: 
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_M_FSM_Free: 
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_M_FSM_Free: 
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_Process_GetPriorityQEvent: Received
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_Process_GetPriorityQEvent:   Event[APP_EV_CALLSETUP_DONE(200)] {
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     MOD[CallSetup_4983BFB8_3_669324852]  (
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     )
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_Process_GetPriorityQEvent:   }
    Feb 10 13:13:28.618: //210056//Call:/AFW_M_CallSetup_EventPreProcess: 
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_M_Object_ShowListeners:     MOD[CallSetup_4983BFB8_3_669324852]  (
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_M_Object_ShowListeners:     )
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_INCALERTING(4)][Cause(0)]
    Feb 10 13:13:28.618: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:28.618: //210057/FE89B9B586A3/AFW_:/AFW_Object_AddListener: adding Module TclModule as listener
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_Object_RemoveListener: 
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_Module_UnListen:  NumObjects: 2
    Feb 10 13:13:28.618: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:28.622: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:28.622: //210056//AFW_:/AFW_TclModule_DefaultEvHandling: 
    Feb 10 13:13:28.622: //210056//AFW_:/AFW_TclModule_UpdateSessionMask: Update intwrk-mask for leg [210056]'s session
    Feb 10 13:13:28.622: //210056//AFW_:/AFW_TclModule_UpdateSessionMask: Update intwrk-mask for leg [210057]'s session
    Feb 10 13:13:28.622: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(CALL[3],ev_setup_done[200])---[act_SetupDone]------
    Feb 10 13:13:28.622: //210056//TCL :/tcl_InfotagObjCmd:  infotag get evt_status
    Feb 10 13:13:28.622: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get evt_status
    Feb 10 13:13:28.622: //210056//AFW_:/vtr_ev_status: argc 2 argindex 2
    Feb 10 13:13:28.622: //210056//TCL :/tcl_PutsObjCmd: SetupDone status ls_000
    Feb 10 13:13:28.622: //210056//TCL :/tcl_LegObjCmd:  leg connect leg_incoming
    Feb 10 13:13:28.622: //210056//TCL :/tcl_LegConnectObjCmd: connect leg_incoming
    Feb 10 13:13:28.622: //210056//AFW_:/vtd_lg_incoming: argc 2
    Feb 10 13:13:28.622: //210056//AFW_:/vtd_lg_incoming: Legs [210056 ]
    Feb 10 13:13:28.622: //210056//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
    Feb 10 13:13:28.622: //210056/FE7D84CC869E/AFW_:/AFW_Leg_Connect: prog_ind=0, ccRawMsgInfo_t=0x4A3E902C, cData=0
    Feb 10 13:13:28.622: //210056/FE7D84CC869E/CCAPI/ccCallConnect:
       Progress Indication=NULL(0), Data Bitmask=0x0
    Feb 10 13:13:28.622: //210056/FE7D84CC869E/CCAPI/ccCallConnect:
       Call Entry(Connected=TRUE, Responsed=TRUE)
    Feb 10 13:13:28.622: //210056/FE7D84CC869E/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 91E4 type 1
    Feb 10 13:13:28.622: //210056//TCL :/tcl_ConnectionObjCmd:  connection create leg_incoming leg_outgoing
    Feb 10 13:13:28.622: //210056//TCL :/tcl_ConnectionCreateObjCmd: create leg_incoming leg_outgoing
    Feb 10 13:13:28.622: //210056//AFW_:/vtd_lg_incoming: argc 3
    Feb 10 13:13:28.622: //210056//AFW_:/vtd_lg_incoming: Legs [210056 ]
    Feb 10 13:13:28.622: //210056//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
    Feb 10 13:13:28.622: //210056//AFW_:/vtd_lg_outgoing: argc 3
    Feb 10 13:13:28.622: //210056//AFW_:/vtd_lg_outgoing: Legs [210057 ]
    Feb 10 13:13:28.626: //210056//Tcl :/tcl_parseCallID_vartagObj: VARTAG Translation Leg Count=1
    Feb 10 13:13:28.626: //210056/FE7D84CC869E/AFW_:/AFW_Connection_NewFromConID: 
    Feb 10 13:13:28.626: //210056/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x498BD6C4, callID1=0x33488, callID2=0x33489, tag=0x0)
    Feb 10 13:13:28.626: //210056/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x498BD6C4, callID1=0x33488, gcid=FE7EBDB4-344511E0-86A18B60-D3F543D5, tag=0x0)
    Feb 10 13:13:28.626: //210057/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x498BD6C4, callID2=0x33489, gcid=0-0-0-0, tag=0x0)
    Feb 10 13:13:28.626: //210056/FE7D84CC869E/CCAPI/ccConferenceCreate:
       Conference Id=0x498BD6C4, Call Id1=210056, Call Id2=210057, Tag=0x0
    Feb 10 13:13:28.626: //210056/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
      
    Feb 10 13:13:28.626: ccConferenceCreate: ret1=0, codecMask1=2, bytes1=160, negot1=1, dtmf1=6
                        ret2=0, codecMask2=1, bytes2=160, negot2=1, dtmf2=6,
                        tx_dynamic_pt1=8, rx_dynamic_pt1=8, codec_mode1=0, params_bitmap1 =0
                        tx_dynamic_pt2=0, rx_dynamic_pt2=0, codec_mode2=0, params_bitmap2 =0
    Feb 10 13:13:28.626: //210056/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
      
    Feb 10 13:13:28.626: cc_api_get_xcode_stream : 4534
    Feb 10 13:13:28.626: //210057/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
      
    Feb 10 13:13:28.626: cc_api_get_xcode_stream : 4534
    Feb 10 13:13:28.626: //210056/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
      
    Feb 10 13:13:28.626: cc_api_get_xcode_stream : 4534
    Feb 10 13:13:28.626: //210056/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
      
    Feb 10 13:13:28.626: cc_api_get_xcode_stream : 4534
    Feb 10 13:13:28.626: //210056/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
       Conference Id=0x1B9B9, Source Interface=0x47CE1060, Source Call Id=210056,
       Destination Call Id=210057, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.626: //210057/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
      
    Feb 10 13:13:28.626: cc_api_get_xcode_stream : 4534
    Feb 10 13:13:28.626: //210057/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
       Conference Id=0x1B9B9, Source Interface=0x47CE1060, Source Call Id=210057,
       Destination Call Id=210056, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.626: //210056/FE7D84CC869E/CCAPI/cc_generic_bridge_done:
       Conference Id=0x1B9B9, Source Interface=0x47CE1060, Source Call Id=210057,
       Destination Call Id=210056, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.630: //210056/FE7D84CC869E/CCAPI/ccConferenceCreate:
       Call Entry(Conference Id=0x1B9B9, Destination Call Id=210057)
    Feb 10 13:13:28.630: //210057/FE89B9B586A3/CCAPI/ccConferenceCreate:
       Call Entry(Conference Id=0x1B9B9, Destination Call Id=210056)
    Feb 10 13:13:28.630: //-1//AFW_:CN113081:/AFW_Connection_New: Conference created
    Feb 10 13:13:28.630: //210057/xxxxxxxxxxxx/CCAPI/ccSetCallGCID:
       Update GCID [0-0-0-0] with [FE7EBDB4-344511E0-86A18B60-D3F543D5]
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_ExecEnv_IncrPendingCmd:  PendingCmdCount: 4
    Feb 10 13:13:28.630: //-1//AFW_:LP:EE49A805CC000:CN113081:/AFW_M_Object_SetExecEnv: ObjCount: 5, CmdPending 4
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_Object_AddListener: adding Module TclModule as listener
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_M_Connection_GetHandle: Connection handle: CON_113081
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(CONNECT[4])---------------
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_Object_WalkListeners:
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_M_Event_Free:  MODULEDONEEVENT for a Module: CallSetup_4983BFB8_3_669324852
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_Object_RemoveAllListener: 
    Feb 10 13:13:28.630: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 4, CmdPending 4
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_ExecEnv_DecrNPendingCmd:  PendingCmdCount: 3
    Feb 10 13:13:28.630: //-1//AFW_:HN27E51634:/AFW_M_Event_Free: ExecEnv objCount: 4
    Feb 10 13:13:28.630: //-1//Call:HN27E51634:/AFW_M_CallSetup_Free: 
    Feb 10 13:13:28.630: //-1//AFW_:/AFW_M_FSM_Free: 
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
    Feb 10 13:13:28.630: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:28.630: //210056//AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CONF_CREATE_DONE(36)] {
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_Process_GetCcqEvent:     CON[113081][CONNECTION_CONFING(1)] {
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_Process_GetCcqEvent:       LEG[210056][LEG_INCCONNECTED(5)][Cause(0)],
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_Process_GetCcqEvent:       LEG[210057][LEG_OUTCONNECTED(10)][Cause(0)]
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_Process_GetCcqEvent:     }
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:28.634: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:28.634: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:     CON[113081][CONNECTION_CONFED(2)] {
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:       LEG[210056][LEG_INCCONNECTED(5)][Cause(0)],
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:       LEG[210057][LEG_OUTCONNECTED(10)][Cause(0)]
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:     }
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_INCCONNECTED(5)][Cause(0)]
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210057][LEG_OUTCONNECTED(10)][Cause(0)]
    Feb 10 13:13:28.634: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:28.634: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:28.634: //210056//AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:28.634: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:28.634: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:28.634: //210056//AFW_:/AFW_TclModule_DefaultEvHandling: 
    Feb 10 13:13:28.638: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(CONNECT[4],ev_create_done[36])---[act_Generic]------
    Feb 10 13:13:28.638: //210056//TCL :/tcl_InfotagObjCmd:  infotag get evt_event
    Feb 10 13:13:28.638: //210056//TCL :/tcl_InfotagGetObjCmd: infotag get evt_event
    Feb 10 13:13:28.638: //210056//AFW_:/vtr_ev_event: argc 2
    Feb 10 13:13:28.638: //210056//AFW_:/vtr_ev_event: Event [ev_create_done]
    Feb 10 13:13:28.638: //210056//TCL :/tcl_PutsObjCmd: Event ev_create_done status N/A leg N/A
    Feb 10 13:13:28.638: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(CONNECT[4])---------------
    Feb 10 13:13:28.638: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:28.638: //210056//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:28.638: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
    Feb 10 13:13:28.638: //210056/FE7D84CC869E/CCAPI/cc_process_notify_bridge_done:
       Conference Id=0x1B9B9, Call Id1=210056, Call Id2=210057
    Feb 10 13:13:28.646: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:28.646: //-1//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:28.646: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL
    Feb 10 13:13:28.646: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:28.646: //-1//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:28.646: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
       Interface=0x47DE0D64, Interface Type=9, Destination=0.0.0.0, Mode=0x0,
       Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
       Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
       Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE,
       Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.878: :cc_get_feature_vsa malloc success
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.878:  cc_get_feature_vsa count is 3
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.878: :FEATURE_VSA attributes are: feature_name:0,feature_time:1248157128,feature_id:201108
    Feb 10 13:13:28.878: //210058/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
       SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1
    Feb 10 13:13:28.878: //210058/xxxxxxxxxxxx/CCAPI/ccCallSetContext:
       Context=0x4A1FEA0C
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
       Interface=0x479AF450, Interface Type=25, Destination=, Mode=0x0,
       Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
       Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
       Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE,
       Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.878: :cc_get_feature_vsa malloc success
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.878:  cc_get_feature_vsa count is 4
    Feb 10 13:13:28.878: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.878: :FEATURE_VSA attributes are: feature_name:0,feature_time:1248156904,feature_id:201109
    Feb 10 13:13:28.882: //210059/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
       Interface=0x479AF450, Data Bitmask=0x0, Progress Indication=NULL(0),
       Connection Handle=0
    Feb 10 13:13:28.882: //210059/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
       Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
    Feb 10 13:13:28.882: //210059/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
       SPI Call Setup Request Is Success; Interface Type=25, FlowMode=1
    Feb 10 13:13:28.882: //210059/xxxxxxxxxxxx/CCAPI/ccCallSetContext:
       Context=0x4A200454
    Feb 10 13:13:28.882: //210058/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
       Interface=0x47DE0D64, Data Bitmask=0x0, Progress Indication=NULL(0),
       Connection Handle=0
    Feb 10 13:13:28.882: //210058/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
       Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
    Feb 10 13:13:28.882: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
       Interface=0x47DE0D64, Interface Type=9, Destination=0.0.0.0, Mode=0x0,
       Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
       Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
       Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE,
       Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
    Feb 10 13:13:28.886: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.886: :cc_get_feature_vsa malloc success
    Feb 10 13:13:28.886: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.886:  cc_get_feature_vsa count is 5
    Feb 10 13:13:28.886: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
      
    Feb 10 13:13:28.886: :FEATURE_VSA attributes are: feature_name:0,feature_time:1248156680,feature_id:201110
    Feb 10 13:13:28.886: //210060/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:
       SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1
    Feb 10 13:13:28.886: //210060/xxxxxxxxxxxx/CCAPI/ccCallSetContext:
       Context=0x4A1FCDF4
    Feb 10 13:13:28.886: //210060/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
       Interface=0x47DE0D64, Data Bitmask=0x0, Progress Indication=NULL(0),
       Connection Handle=0
    Feb 10 13:13:28.886: //210060/xxxxxxxxxxxx/CCAPI/cc_api_call_connected:
       Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x4A1DFB50, callID1=0x3348B, callID2=0x3348A, tag=0x0)
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x4A1DFB50, callID1=0x3348B, gcid=0-0-0-0, tag=0x0)
    Feb 10 13:13:28.890: //210058/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x4A1DFB50, callID2=0x3348A, gcid=0-0-0-0, tag=0x0)
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       Conference Id=0x4A1DFB50, Call Id1=210059, Call Id2=210058, Tag=0x0
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
       Conference Id=0x1B9BA, Source Interface=0x479AF450, Source Call Id=210059,
       Destination Call Id=210058, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.890: //210058/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
       Conference Id=0x1B9BA, Source Interface=0x47DE0D64, Source Call Id=210058,
       Destination Call Id=210059, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
       Conference Id=0x1B9BA, Source Interface=0x47DE0D64, Source Call Id=210058,
       Destination Call Id=210059, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       Call Entry(Conference Id=0x1B9BA, Destination Call Id=210058)
    Feb 10 13:13:28.890: //210058/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       Call Entry(Conference Id=0x1B9BA, Destination Call Id=210059)
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x4A1DFB50, callID1=0x3348B, callID2=0x3348C, tag=0x0)
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x4A1DFB50, callID1=0x3348B, gcid=0-0-0-0, tag=0x0)
    Feb 10 13:13:28.890: //210060/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       (confID=0x4A1DFB50, callID2=0x3348C, gcid=0-0-0-0, tag=0x0)
    Feb 10 13:13:28.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       Conference Id=0x4A1DFB50, Call Id1=210059, Call Id2=210060, Tag=0x0
    Feb 10 13:13:28.894: //210060/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
       Conference Id=0x1B9BB, Source Interface=0x47DE0D64, Source Call Id=210060,
       Destination Call Id=210059, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.894: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       Call Entry(Conference Id=0x1B9BB, Destination Call Id=210060)
    Feb 10 13:13:28.894: //210060/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
       Call Entry(Conference Id=0x1B9BB, Destination Call Id=210059)
    Feb 10 13:13:28.894: //210058/xxxxxxxxxxxx/CCAPI/ccCallModify:
       Nominator=0x0, Params=0x4A1DF630, Call Id=210058
    Feb 10 13:13:28.898: //210059/xxxxxxxxxxxx/CCAPI/cc_process_notify_bridge_done:
       Conference Id=0x1B9BA, Call Id1=210059, Call Id2=210058
    Feb 10 13:13:28.898: //210059/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
       Conference Id=0x1B9BB, Source Interface=0x479AF450, Source Call Id=210059,
       Destination Call Id=210060, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.898: //210059/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
       Conference Id=0x1B9BB, Source Interface=0x479AF450, Source Call Id=210059,
       Destination Call Id=210060, Disposition=0x0, Tag=0x0
    Feb 10 13:13:28.898: //210058/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
       Result=0, Interface=0x47DE0D64, Call Id=210058
    Feb 10 13:13:28.902: //210059/xxxxxxxxxxxx/CCAPI/cc_process_notify_bridge_done:
       Conference Id=0x1B9BB, Call Id1=210059, Call Id2=210060
    Feb 10 13:13:28.902: //210060/xxxxxxxxxxxx/CCAPI/ccCallModify:
       Nominator=0x0, Params=0x4A1DF630, Call Id=210060
    Feb 10 13:13:28.902: //210058/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
       Nominator=0x4A1DFB88, Params=0x4A1DFB60, Call Id=210058
    Feb 10 13:13:28.906: //210058/xxxxxxxxxxxx/CCAPI/ccCallModify:
       Nominator=0x18E00, Params=0x4A1DFE50, Call Id=210058
    Feb 10 13:13:28.906: //210060/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
       Result=0, Interface=0x47DE0D64, Call Id=210060
    Feb 10 13:13:28.906: //210058/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
       Result=0, Interface=0x47DE0D64, Call Id=210058
    Feb 10 13:13:28.914: //210060/xxxxxxxxxxxx/CCAPI/ccCallModify:
       Nominator=0x18E00, Params=0x4A1DFE50, Call Id=210060
    Feb 10 13:13:28.914: //210060/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
       Result=0, Interface=0x47DE0D64, Call Id=210060
    Feb 10 13:13:28.918: //210060/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
       Nominator=0x4A1DFB88, Params=0x4A1DFB60, Call Id=210060
    Feb 10 13:13:28.918: //210058/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
       Nominator=0x4A1DFB88, Params=0x4A1DFB60, Call Id=210058
    Feb 10 13:13:28.918: //210060/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
       Nominator=0x4A1DFB88, Params=0x4A1DFB60, Call Id=210060
    Feb 10 13:13:30.678: //210056/FE7D84CC869E/CCAPI/ccGenerateToneInfo:
       Stop Tone On Digit=FALSE, Tone=Null,
       Tone Direction=Sum Network, Params=0x0, Call Id=210056
    Feb 10 13:13:30.682: //210057/FE89B9B586A3/CCAPI/cc_api_call_disconnected:
       Cause Value=16, Interface=0x47CE1060, Call Id=210057
    Feb 10 13:13:30.682: //210057/FE89B9B586A3/CCAPI/cc_api_call_disconnected:
       Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
    Feb 10 13:13:30.682: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:30.682: //210057/FE89B9B586A3/AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:30.682: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CALL_DISCONNECTED(18)] {
    Feb 10 13:13:30.682: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:30.682: //-1//AFW_:/AFW_Process_GetCcqEvent:     LEG[210057][LEG_OUTCONNECTED(10)][Cause(0)]
    Feb 10 13:13:30.682: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:30.682: //210057/FE89B9B586A3/SSIN:/AFW_SS_MapEvent: 
    Feb 10 13:13:30.682: //-1//SSIN:/AFW_SS_SIP_MapEvent: 
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_Util_Disconnect_ManageContainer:
    Feb 10 13:13:30.686: SIP msg container xfer failed between callIDs 210057 & 210056
    Feb 10 13:13:30.686: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:30.686: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:     CON[113081][CONNECTION_CONFED(2)] {
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:       LEG[210056][LEG_INCCONNECTED(5)][Cause(0)],
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:       LEG[210057][LEG_OUTCONNECTED(10)][Cause(16)]
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:     }
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_INCCONNECTED(5)][Cause(0)]
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210057][LEG_OUTCONNECTED(10)][Cause(16)]
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:30.686: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.686: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:30.686: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:30.686: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:30.686: //210056//AFW_:/AFW_TclModule_DefaultEvHandling: 
    Feb 10 13:13:30.686: //210056//AFW_:/AFW_FSM_Drive: ACTION BEGIN: ------(CONNECT[4],ev_disconnected[18])---[act_Cleanup]------
    Feb 10 13:13:30.686: //210056//TCL :/tcl_CallObjCmd:  call close
    Feb 10 13:13:30.686: //210056//TCL :/tcl_CallCloseObjCmd:  close
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_ExecEnv_CallClose:  Exec Env state: 1
    Feb 10 13:13:30.686: //-1//AFW_:/AFW_ExecEnv_CallClose:  Terminating ExecEnv's root module
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_Module_Terminate:  Terminating Module: TclModule_49A63AC8_3_669324788
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_M_TclModule_Terminate:  Module is in the state: ACTIVE
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_TclModule_Cleaner: lastFailureCause 16
    Feb 10 13:13:30.690: //210056/FE7D84CC869E/AFW_:/AFW_Leg_Disconnect: Disconnecting Leg: LEG_210056
    Feb 10 13:13:30.690: //210056/FE7D84CC869E/AFW_:/AFW_Leg_Disconnect: Disconnecting Leg: LEG_210056, Cause 16
    Feb 10 13:13:30.690: //210056/FE7D84CC869E/AFW_:/AFW_Leg_DisconnectContinue: Disconnect time for peer 4746600: 1355423253
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_Connection_Destroy: 
    Feb 10 13:13:30.690: //210056/FE7D84CC869E/CCAPI/ccConferenceDestroy:
       Conference Id=0x1B9B9, Tag=0x0
    Feb 10 13:13:30.690: //210056/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
       Conference Id=0x1B9B9, Source Interface=0x47CE1060, Source Call Id=210056,
       Destination Call Id=210057, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.690: //210057/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
       Conference Id=0x1B9B9, Source Interface=0x47CE1060, Source Call Id=210057,
       Destination Call Id=210056, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.690: //210056/FE7D84CC869E/CCAPI/cc_generic_bridge_done:
       Conference Id=0x1B9B9, Source Interface=0x47CE1060, Source Call Id=210057,
       Destination Call Id=210056, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_TclModule_Cleaner: lastFailureCause 16
    Feb 10 13:13:30.690: //210057/FE89B9B586A3/AFW_:/AFW_Leg_Disconnect: Disconnecting Leg: LEG_210057
    Feb 10 13:13:30.690: //210057/FE89B9B586A3/AFW_:/AFW_Leg_Disconnect: Disconnecting Leg: LEG_210057, Cause 16
    Feb 10 13:13:30.690: //210057/FE89B9B586A3/AFW_:/AFW_Leg_DisconnectContinue: Disconnect time for peer 10: 1355423253
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_Connection_Destroy: 
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_FSM_Drive: ACTION END: -------------(CONNECT[4])---------------
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:30.690: //210057/FE89B9B586A3/AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:30.690: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:30.694: //210056//AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CONF_DESTROY_DONE(37)] {
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Process_GetCcqEvent:     CON[113081][CONNECTION_UNCONFING(3)] {
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Process_GetCcqEvent:       LEG[210056][LEG_INCDISCONNECTING(6)][Cause(16)],
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Process_GetCcqEvent:       LEG[210057][LEG_OUTDISCONNECTING(11)][Cause(16)]
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Process_GetCcqEvent:     }
    Feb 10 13:13:30.694: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:30.694: //210056/FE7D84CC869E/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 91E4 type 2
    Feb 10 13:13:30.694: //210056/FE7D84CC869E/CCAPI/ccCallDisconnect:
       Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
    Feb 10 13:13:30.698: //210056/FE7D84CC869E/CCAPI/ccCallDisconnect:
       Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
    Feb 10 13:13:30.698: //210057/FE89B9B586A3/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 91E4 type 2
    Feb 10 13:13:30.698: //210057/FE89B9B586A3/CCAPI/ccCallDisconnect:
       Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
    Feb 10 13:13:30.698: //210057/FE89B9B586A3/CCAPI/ccCallDisconnect:
       Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.698: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:30.698: //-1//AFW_:/AFW_M_Object_ShowListeners:     CON[113081][CONNECTION_UNCONFED(0)] {,
    Feb 10 13:13:30.698: //-1//AFW_:/AFW_M_Object_ShowListeners:     }
    Feb 10 13:13:30.698: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_INCDISCONNECTING(6)][Cause(16)]
    Feb 10 13:13:30.698: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210057][LEG_OUTDISCONNECTING(11)][Cause(16)]
    Feb 10 13:13:30.698: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_Object_RemoveListener: 
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_Module_UnListen:  NumObjects: 2
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_TclModule_Action: Module is Terminating
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_Object_WalkListeners:
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:30.698: //210056//AFW_:/AFW_M_Event_Free:  CC_EV_CONF_DESTROY_DONE for Connection: CON_113081
    Feb 10 13:13:30.702: //210056//AFW_:/AFW_Object_RemoveAllListener: 
    Feb 10 13:13:30.702: //210056//AFW_:/AFW_ExecEnv_DecrNPendingCmd:  PendingCmdCount: 2
    Feb 10 13:13:30.702: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 3, CmdPending 2
    Feb 10 13:13:30.702: //-1//AFW_:CN113081:/AFW_M_Event_Free: ExecEnv objCount: 3
    Feb 10 13:13:30.702: //-1//AFW_:CN113081:/AFW_M_Connection_Free:
    Feb 10 13:13:30.702: CON[113081 ][CONNECTION_UNCONFED(0)] {,}
    Feb 10 13:13:30.702: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 2
    Feb 10 13:13:30.706: //210057/FE89B9B586A3/CCAPI/cc_api_call_disconnect_done:
       Disposition=0, Interface=0x47CE1060, Tag=0x0, Call Id=210057,
       Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
    Feb 10 13:13:30.706: //210057/FE89B9B586A3/CCAPI/cc_api_call_disconnect_done:
       Call Disconnect Event Sent
    Feb 10 13:13:30.706: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.706: :cc_free_feature_vsa freeing 4A655EA0
    Feb 10 13:13:30.706: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.706:  vsacount in free is 4
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:30.710: //210057/FE89B9B586A3/AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CALL_DISCONNECT_DONE(19)] {
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_Process_GetCcqEvent:     LEG[210057][LEG_OUTDISCONNECTING(11)][Cause(16)]
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:30.710: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
    Feb 10 13:13:30.710: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:30.710: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_INCDISCONNECTING(6)][Cause(16)]
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210057][LEG_DISCONNECTED(12)][Cause(16)]
    Feb 10 13:13:30.710: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:30.710: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.710: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:30.710: //210057/FE89B9B586A3/AFW_:/AFW_Object_RemoveListener: 
    Feb 10 13:13:30.710: //210056//AFW_:/AFW_Module_UnListen:  NumObjects: 1
    Feb 10 13:13:30.710: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:30.714: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:30.714: //210056//AFW_:/AFW_M_TclModule_Action: Module is Terminating
    Feb 10 13:13:30.714: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_Object_WalkListeners:
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_M_Event_Free:  CC_EV_CALL_DISCONNECT_DONE for a Leg: LEG_210057
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_Object_RemoveAllListener: 
    Feb 10 13:13:30.714: //210056//AFW_:/AFW_ExecEnv_DecrNPendingCmd:  PendingCmdCount: 1
    Feb 10 13:13:30.714: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 2, CmdPending 1
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_M_Event_Free: ExecEnv objCount: 2
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/AFW_M_Leg_Free: 
    Feb 10 13:13:30.714: //210057/FE89B9B586A3/AFW_:/decrementDialPeerCallStats: Decrementing call-stat for disconnecting leg, dial-peer [10]
    Feb 10 13:13:30.714: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1
    Feb 10 13:13:30.718: //210056/FE7D84CC869E/CCAPI/cc_api_call_disconnect_done:
       Disposition=0, Interface=0x47CE1060, Tag=0x0, Call Id=210056,
       Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
    Feb 10 13:13:30.718: //210056/FE7D84CC869E/CCAPI/cc_api_call_disconnect_done:
       Call Disconnect Event Sent
    Feb 10 13:13:30.718: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.718: :cc_free_feature_vsa freeing 4A655F80
    Feb 10 13:13:30.718: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.718:  vsacount in free is 3
    Feb 10 13:13:30.722: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:30.722: //210056/FE7D84CC869E/AFW_:/AFW_Process_GetCcqEvent: Received
    Feb 10 13:13:30.722: //-1//AFW_:/AFW_Process_GetCcqEvent:   Event[CC_EV_CALL_DISCONNECT_DONE(19)] {
    Feb 10 13:13:30.722: //-1//AFW_:/AFW_Process_GetCcqEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:30.722: //-1//AFW_:/AFW_Process_GetCcqEvent:     LEG[210056][LEG_INCDISCONNECTING(6)][Cause(16)]
    Feb 10 13:13:30.722: //-1//AFW_:/AFW_Process_GetCcqEvent:   }
    Feb 10 13:13:30.722: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
    Feb 10 13:13:30.722: //210056/FE7D84CC869E/AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:30.722: //210056/FE7D84CC869E/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_M_Object_ShowListeners:   MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_M_Object_ShowListeners:     LEG[210056][LEG_DISCONNECTED(12)][Cause(16)]
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_M_Object_ShowListeners:   )
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_Object_RemoveListener: 
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_Module_UnListen:  NumObjects: 0
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_49A63AC8_3_669324788
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_M_TclModule_Action: 
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_M_TclModule_Action: Module is Terminating
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_Event_New: Event ID: ev_module_done
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_TclModule_ReturnIfDone:  Sending Out APP_EV_TCLMODULE_DONE event
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_Module_ReturnArgEv: 
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_ExecEnv_SetModuleScope: TclModule_49A63AC8_3_669324788 ---> NULL
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_Object_WalkListeners:
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_M_Event_Free:  CC_EV_CALL_DISCONNECT_DONE for a Leg: LEG_210056
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_Object_RemoveAllListener: 
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_ExecEnv_DecrNPendingCmd:  PendingCmdCount: 0
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 1, CmdPending 0
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_M_Event_Free: ExecEnv objCount: 1
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/AFW_M_Leg_Free: 
    Feb 10 13:13:30.726: //210056/FE7D84CC869E/AFW_:/decrementDialPeerCallStats: Decrementing call-stat for disconnecting leg, dial-peer [4746600]
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_ExecEnv_CloseIfDone: Cmd Count: 0
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_ExecEnv_CallClose:  Exec Env state: 3
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
    Feb 10 13:13:30.726: //210056//AFW_:/AFW_Process_GetPriorityQEvent: Received
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_Process_GetPriorityQEvent:   Event[APP_EV_TCLMODULE_DONE(219)] {
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     EXECENV[0x49A805CC][num2name-http]
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     MOD[TclModule_49A63AC8_3_669324788]  (
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_Process_GetPriorityQEvent:     )
    Feb 10 13:13:30.726: //-1//AFW_:/AFW_Process_GetPriorityQEvent:   }
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_M_TclModule_EventPreProcess: 
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_Object_WalkListeners: 
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_M_Object_ShowListeners: START
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_M_Object_ShowListeners: END
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_M_Event_Free:  MODULEDONEEVENT for a Module: TclModule_49A63AC8_3_669324788
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_Object_RemoveAllListener: 
    Feb 10 13:13:30.730: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 0, CmdPending 0
    Feb 10 13:13:30.730: //-1//AFW_:HN27E515F4:/AFW_M_Event_Free: ExecEnv objCount: 0
    Feb 10 13:13:30.730: //-1//SERV:/AFW_Service_ReleaseExecEnv: Script Name = num2name-http cache = true calls = 0
    Feb 10 13:13:30.730: //210056//AFW_:/AFW_ExecEnv_UnSetRoot: Execenv = 0x49A805CC
    Feb 10 13:13:30.730: //-1//AFW_:/AFW_Process_UnLock: pProcess(0x497CC6C4)=0
    Feb 10 13:13:30.730: //-1//SCRI:/cam_sa_stopped: Instance: [] ExecEnv: 0x49A805CC
    Feb 10 13:13:30.730: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
    Feb 10 13:13:30.730: //-1//AFW_:/AFW_M_Event_Free: 
    Feb 10 13:13:30.730: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL
    Feb 10 13:13:30.878: //210058/xxxxxxxxxxxx/CCAPI/ccCallModify:
       Nominator=0x800, Params=0x4A1DFEC8, Call Id=210058
    Feb 10 13:13:30.878: //210058/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
       Result=0, Interface=0x47DE0D64, Call Id=210058
    Feb 10 13:13:30.878: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceDestroy:
       Conference Id=0x1B9BA, Tag=0x0
    Feb 10 13:13:30.878: //210059/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
       Conference Id=0x1B9BA, Source Interface=0x479AF450, Source Call Id=210059,
       Destination Call Id=210058, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.882: //210058/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
       Conference Id=0x1B9BA, Source Interface=0x47DE0D64, Source Call Id=210058,
       Destination Call Id=210059, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.882: //210059/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
       Conference Id=0x1B9BA, Source Interface=0x47DE0D64, Source Call Id=210058,
       Destination Call Id=210059, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.882: //210058/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
       Nominator=0x4A1DFB88, Params=0x4A1DFB60, Call Id=210058
    Feb 10 13:13:30.882: //210058/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
       Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
    Feb 10 13:13:30.882: //210058/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
       Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)
    Feb 10 13:13:30.882: //210060/xxxxxxxxxxxx/CCAPI/ccCallModify:
       Nominator=0x800, Params=0x4A1DFEC8, Call Id=210060
    Feb 10 13:13:30.886: //210058/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
       Disposition=0, Interface=0x47DE0D64, Tag=0x0, Call Id=210058,
       Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)
    Feb 10 13:13:30.886: //210058/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
       Call Disconnect Event Sent
    Feb 10 13:13:30.886: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.886: :cc_free_feature_vsa freeing 4A655DC0
    Feb 10 13:13:30.886: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.886:  vsacount in free is 2
    Feb 10 13:13:30.886: //210060/xxxxxxxxxxxx/CCAPI/cc_api_call_modify_done:
       Result=0, Interface=0x47DE0D64, Call Id=210060
    Feb 10 13:13:30.890: //210059/xxxxxxxxxxxx/CCAPI/ccConferenceDestroy:
       Conference Id=0x1B9BB, Tag=0x0
    Feb 10 13:13:30.890: //210059/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
       Conference Id=0x1B9BB, Source Interface=0x479AF450, Source Call Id=210059,
       Destination Call Id=210060, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.890: //210060/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
       Conference Id=0x1B9BB, Source Interface=0x47DE0D64, Source Call Id=210060,
       Destination Call Id=210059, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.890: //210059/xxxxxxxxxxxx/CCAPI/cc_generic_bridge_done:
       Conference Id=0x1B9BB, Source Interface=0x47DE0D64, Source Call Id=210060,
       Destination Call Id=210059, Disposition=0x0, Tag=0x0
    Feb 10 13:13:30.894: //210060/xxxxxxxxxxxx/CCAPI/ccCallModifyExtended:
       Nominator=0x4A1DFB88, Params=0x4A1DFB60, Call Id=210060
    Feb 10 13:13:30.894: //210060/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
       Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
    Feb 10 13:13:30.894: //210060/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
       Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)
    Feb 10 13:13:30.898: //210060/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
       Disposition=0, Interface=0x47DE0D64, Tag=0x0, Call Id=210060,
       Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)
    Feb 10 13:13:30.898: //210060/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
       Call Disconnect Event Sent
    Feb 10 13:13:30.898: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.898: :cc_free_feature_vsa freeing 4A655C00
    Feb 10 13:13:30.898: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.898:  vsacount in free is 1
    Feb 10 13:13:30.902: //210059/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
       Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
    Feb 10 13:13:30.902: //210059/xxxxxxxxxxxx/CCAPI/ccCallDisconnect:
       Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)
    Feb 10 13:13:30.902: //210059/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
       Disposition=0, Interface=0x479AF450, Tag=0x0, Call Id=210059,
       Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)
    Feb 10 13:13:30.902: //210059/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done:
       Call Disconnect Event Sent
    Feb 10 13:13:30.902: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.902: :cc_free_feature_vsa freeing 4A655CE0
    Feb 10 13:13:30.902: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
      
    Feb 10 13:13:30.902:  vsacount in free is 0
    ipipgw#undeb all
    All possible debugging has been turned off
    ipipgw#

    Subject: RE: TCL Transfer and codecs
    Replied by: Raghavendra Gutty Veeranagappa on 10-02-2011 11:51:38 PM
    Hi Marko,

    thanks for sharing the logs,from the logs found that you are doing leg setup with below command passing leg_outgoing, is there any specific reason for passing leg_outgoing and also after leg setup done you doing connection create leg_incoming leg_outgoing , so please try by removing leg_outgoing to leg setup command

    leg setup 014746628 callInfo leg_outgoing

    Thanks,
    Raghavendra

    Subject: RE: TCL Transfer and codecs
    Replied by: Anusha Kannappan on 15-02-2011 09:54:55 AM
    Hi Marko,

    Why do we have to connect the leg_incoming which is already in the connected state? Could you please share us the script so that it would be easy for us to check the flow of the script and send you the proper solution.

    Thanks,
    Anusha

    Subject: RE: TCL Transfer and codecs
    Replied by: Marko Tisler on 15-02-2011 05:19:32 AM
    This script is running in an IPIPGW and intercepts calls to a certain number. It collects some data, modifies call info and then transfers the call to one of internal extensions.

    "leg setup 014746628 callInfo leg_outgoing" is used to initiate the transfer of incoming call (incoming call leg) to this extension (a new , outgoing  call leg)."connection create" then joins these two call legs together and completes the transfer.
     
    So, the script doesn't function correctly if the "leg setup..." step is removed.
     
    Best regards,

    Marko

    Subject: RE: TCL Transfer and codecs
    Replied by: Raghavendra Gutty Veeranagappa on 15-02-2011 06:00:36 AM
    Hi Marko,

    i think you should use leg_incoming in the leg setup instead of leg_outgoing and also you need to specify mode to callinfo

    for example

    set callInfo(mode) REDIRECT_ROTARY
    set callInfo(rerouteMode) REDIRECT_ROTARY

    leg setup $dnis callInfo leg_incoming

    Thanks,
    Raghavendra

    Subject: RE: TCL Transfer and codecs
    Replied by: Marko Tisler on 15-02-2011 07:54:03 AM
    Ok, getting there. So in this case, we are doing a blind transfer instead of a consult transfer?

    The call is now redirected, but the media is not established:

    Feb 15 13:47:24.929: //215332//Dest:/DestStartMediaNegotiation: MediaNeg not supported for this module
    Feb 15 13:47:24.941: //215332//AFW_:/AFW_FSM_Drive: Tcl_Eval to drive FSM inside Tcl modulespace. code=1 code=ERROR
    Feb 15 13:47:24.941: TCL script failure
            Result:
                             Illegal Operation: Leg not incoming or in wrong state
    Feb 15 13:47:24.941:    TCL script failure errorInfo:
                            Illegal Operation: Leg not incoming or in wrong state
        while executing
    "leg connect leg_incoming"
        (procedure "act_SetupDone" line 16)
        invoked from within
    "act_SetupDone"

    Procedures involved (it fails on SetupDone, when media should be connected):

    proc Setup {} {

    global ani
    global dnis
    global name

    set ani [infotag get leg_ani]
    set dnis [infotag get leg_dnis]


      set callInfo(originationNum) $something
      set callInfo(displayInfo) $something_else
      set callInfo(notifyEvents) "ev_proceeding ev_alert"
      set callInfo(mode) REDIRECT_ROTARY
      set callInfo(rerouteMode) REDIRECT_ROTARY
      leg setup $dnis callInfo leg_incoming
    }

    proc SetupDone {} {

    global servFlag

    set status [infotag get evt_status]
    puts "$status"

    if {$status != "ls_000"} {
      switch $status {
       ls_002 { leg disconnect_prog_ind leg_incoming -c019 }
       ls_007 { leg disconnect_prog_ind leg_incoming -c017 }
       ls_015 { #caller had disconnected }
       default { leg disconnect_prog_ind leg_incoming -c027 }
      }
    } else {
      leg connect leg_incoming
      #connection create leg_incoming leg_outgoing
    }
    }

    Subject: RE: TCL Transfer and codecs
    Replied by: Marko Tisler on 16-02-2011 09:26:25 AM
    Hi,

    my mistake, some leftover code from when before, when this was a consult transfer. Works fine now, that I recoded it as a blind transfer. Thanks for your help!

    Best regards,

    Marko