Re: What is the operation of callInfo(rerouteMode) ? - Page 2

paolo bevilacqua · ‎08-06-2015

I cannot have this working as described. I set it "none" in conjunction with "mode rotary", but when calling a number that has call forward, there is no notification to the script, call setup continues toward the CF destination.

That is a SIP call and the message "moved temporarily" is received normally.

Is the element name "rerouteMode" the correct one? On page 3-77 there is an example as:

set callInfo(rotaryRedirectMode) “ROTARY”

That doesn't work either.

paolo bevilacqua · ‎08-10-2015

As I have mentioned in my opening post, I'm using "none".

The script above has "rotary" for the purpose of generating a comparison with NONE.

Here again the logs when using NONE. They are the same as when using rotary".

Status code never indicates call forward.

Aug 10 15:32:46.792: //-1//SERV:/AFW_Service_GetExecEnv: Script Name = test

Aug 10 15:32:46.792: //-1//SERV:/AFW_Service_GetExecEnv: Using Cached ExecEnv

Aug 10 15:32:46.792: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetRoot: Execenv = 0x67B8F67C

Aug 10 15:32:46.792: //-1//AFW_:/AFW_M_TclModule_NewDup:

Aug 10 15:32:46.792: //-1//AFW_:HN1EDD1CD4:/AFW_M_TclModule_Free:

Aug 10 15:32:46.796: //-1//AFW_:HN1EDD1CD4:/AFW_M_Module_GetHandle: Module handle: TclModule_69F322F0_0_517807316MOD[TclModule_69F322F0_0_517807316] ( )

Aug 10 15:32:46.796: //-1//AFW_:/AFW_M_FSM_Free:

Aug 10 15:32:46.796: //-1//AFW_:/AFW_Process_Lock: pProcess(0x690CCFCC)=1

Aug 10 15:32:46.796: //-1//AFW_:LP:EE67B8F67C000:HN1EDF48E4:/AFW_M_Object_SetExecEnv: ObjCount: 1, CmdPending 0

Aug 10 15:32:46.796: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_RestoreDataBackup: Restore DataArea from Script

Aug 10 15:32:46.796: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_RestoreDataBackup: Restore TokenTable from Script

Aug 10 15:32:46.796: //-1//AFW_:/AFW_Event_New: Event ID: ev_session_indication

Aug 10 15:32:46.796: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_Initiate: Execenv = 0x67B8F67C

Aug 10 15:32:46.796: //-1//AFW_:EE67B8F67C000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

Aug 10 15:32:46.796: //-1//AFW_:EE67B8F67C000:/AFW_Process_GetAppQEvent: Received

Aug 10 15:32:46.800: //-1//AFW_:/AFW_Process_GetAppQEvent: Event[APP_EV_SESSION_START_IND(198)] {

Aug 10 15:32:46.800: //-1//AFW_:/AFW_Process_GetAppQEvent: EXECENV[0x67B8F67C][test]

Aug 10 15:32:46.800: //-1//AFW_:/AFW_Process_GetAppQEvent:

Aug 10 15:32:46.800: //-1//AFW_:EE67B8F67C000:/AFW_M_Module_GetHandle: Module handle: TclModule_69F31770_0_517949668MOD[TclModule_69F31770_0_517949668] (

Aug 10 15:32:46.800: //-1//AFW_:/AFW_Process_GetAppQEvent: )

Aug 10 15:32:46.800: //-1//AFW_:/AFW_Process_GetAppQEvent: }

Aug 10 15:32:46.800: //-1//AFW_:EE67B8F67C000:/AFW_M_TclModule_EventPreProcess:

Aug 10 15:32:46.800: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: TclModule_69F31770_0_517949668 ---> TclModule_69F31770_0_517949668

Aug 10 15:32:46.800: //-1//AFW_:EE67B8F67C000:/AFW_M_TclModule_Action:

Aug 10 15:32:46.800: //-1//AFW_:EE67B8F67C000:/AFW_TclModule_DefaultEvHandling:

Aug 10 15:32:46.800: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(I[1],ev_session_indication[198])---[sess]------

Aug 10 15:32:46.800: //-1//TCL :EE67B8F67C000:/tcl_LegObjCmd: leg setup 202 callinfo

Aug 10 15:32:46.800: //-1//CSPK:EE67B8F67C000:/tcl_LegSetupObjCmd: leg setup 202 callinfo

Aug 10 15:32:46.804: //-1//CSPK:EE67B8F67C000:/C_CallSetup_Start: ControlInfo = 0x69FE6480, callInfo = 0x69F9C774, destination[0]=202

Aug 10 15:32:46.804: //-1//CSPK:EE67B8F67C000:/C_CallSetup_Start: configured mode=rotary (1)

Aug 10 15:32:46.804: //-1//CSPK:EE67B8F67C000:/C_CallSetup_Start: configured reroutemode=rotary (1)

Aug 10 15:32:46.804: //-1//Call:/AFW_CallSetup_New:

Aug 10 15:32:46.804: //-1//AFW_:/AFW_FSM_New:

Aug 10 15:32:46.804: //-1//AFW_:LP:EE67B8F67C000:HN1EDF48F0:/AFW_M_Object_SetExecEnv: ObjCount: 2, CmdPending 0

Aug 10 15:32:46.804: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 1

Aug 10 15:32:46.804: //-1//AFW_:EE67B8F67C000:/AFW_Object_AddListener: adding Module TclModule as listener

Aug 10 15:32:46.804: //-1//AFW_:EE67B8F67C000:/AFW_M_Module_GetHandle: Module handle: CallSetup_69139C08_0_517949680

Aug 10 15:32:46.804: //-1//Call:EE67B8F67C000:/AFW_CallSetup_AddDest: 202 index 0

Aug 10 15:32:46.804: //-1//Call:EE67B8F67C000:/AFW_M_CallSetup_Initiate:

Aug 10 15:32:46.804: //-1//Call:EE67B8F67C000:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set

Aug 10 15:32:46.804: //-1//Call:EE67B8F67C000:/CallSetupInitiate:

Aug 10 15:32:46.804: //-1//Call:EE67B8F67C000:/CS_Placecall:

Aug 10 15:32:46.808: //-1//Call:EE67B8F67C000:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set

Aug 10 15:32:46.808: //-1//Dest:/AFW_Destination_New:

Aug 10 15:32:46.808: //-1//AFW_:/AFW_FSM_New:

Aug 10 15:32:46.808: //-1//AFW_:LP:EE67B8F67C000:HN1EDF48F4:/AFW_M_Object_SetExecEnv: ObjCount: 3, CmdPending 1

Aug 10 15:32:46.808: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 2

Aug 10 15:32:46.808: //-1//AFW_:EE67B8F67C000:/AFW_Object_AddListener: adding Module CallSetup as listener

Aug 10 15:32:46.808: //-1//AFW_:EE67B8F67C000:/AFW_M_Module_GetHandle: Module handle: Destination_69151204_0_517949684

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/AFW_Destination_AddDest:

Aug 10 15:32:46.808: //-1//Dest:/AFW_Destination_AddDest: adding destination "202"

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Initiate:

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Initiate: Outgoing guid : 1F25020B.3E9B11E5.87B0C175.851B1C9E

Aug 10 15:32:46.808: Incoming_guid : 00000000.00000000.00000000.00000000

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Initiate: Outgoing gcid : 00000000.00000000.00000000.00000000

Aug 10 15:32:46.808: Incoming_gcid : 00000000.00000000.00000000.00000000

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/DestUpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] Preempt Hunt Not Set

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/DestSetupInitiate:

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/DestAddRerouteSetupInfo:

Aug 10 15:32:46.808: //-1//Dest:EE67B8F67C000:/DestAddTransferSetupInfo:

Aug 10 15:32:46.812: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: copied 202

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestMatchDialPeer:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestMatchDialPeer: src carrier id:, tgt carrier id:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestQueuePeers: Matched peers:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestPeerItemUpdatePreemptInfo:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestPeerItemResetLPCORInfo:

Aug 10 15:32:46.812: //-1//Dest:/DestQueuePeers: 200

Aug 10 15:32:46.812: //-1//Dest:/DestQueuePeers: , Total(1)

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestDialPeerRotary:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestOutboundCallUsingPeer: dest(202), numexpDest(202), numDestDigits(2)

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestOutboundCallUsingPeer: prefix(.55), peer tag(200)

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestUpdatePreemptSetupInfo:

Aug 10 15:32:46.812: //-1//AFW_:/AFW_Util_RemovePreemptInfoFromContainer: Failed to remove mlppSetupInfo from TD container

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestDialPeerFilter:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestDialPeerFilter: Checking peer [200]

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestPeerFilterRouteCode: Route Code not available

Aug 10 15:32:46.812: //-1//Dest:/DestOutboundCallUsingPeer: Outgoing Tag(200) calling[] called[202]

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestCheckTeleUnassBusy:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestHandleLPCOR:

Aug 10 15:32:46.812: //-1//Dest:EE67B8F67C000:/DestSetup:

Aug 10 15:32:46.812: //-1//AFW_:/AFW_Leg_GetTypeDetail: ***ERROR*** pLeg is NULL

Aug 10 15:32:46.812: //-1//AFW_:/AFW_Util_SaveRawMsg:

Aug 10 15:32:46.812: //-1//AFW_:/AFW_Leg_New:

Aug 10 15:32:46.816: //1075/1F25020B87B0/AFW_:/AFW_Leg_NewSetupRequest: ccCallSetupRequest done

Aug 10 15:32:46.816: //1075/1F25020B87B0/AFW_:/AFW_M_Leg_SetExecEnv:

Aug 10 15:32:46.816: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 3

Aug 10 15:32:46.816: //-1/1F25020B87B0/AFW_:LP:EE67B8F67C000:LG1075:/AFW_M_Object_SetExecEnv: ObjCount: 4, CmdPending 3

Aug 10 15:32:46.816: //1075/1F25020B87B0/AFW_:/AFW_Object_AddListener: adding Module Destination as listener

Aug 10 15:32:46.816: //1075/1F25020B87B0/AFW_:/AFW_M_Leg_GetHandle: Leg handle: LEG_1075

Aug 10 15:32:46.816: //1075/1F25020B87B0/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 12C type 3

Aug 10 15:32:46.816: //-1//Dest:EE67B8F67C000:/DestSetup: Destination setup success

Aug 10 15:32:46.816: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Initiate: attempting call to destination 202

Aug 10 15:32:46.816: //-1//Call:EE67B8F67C000:/CS_Placecall: Call placed to 202 index 0

Aug 10 15:32:46.816: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION END: -------------(I[1])---------------

Aug 10 15:32:46.816: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: TclModule_69F31770_0_517949668 ---> TclModule_69F31770_0_517949668

Aug 10 15:32:46.816: //-1//AFW_:EE67B8F67C000:/AFW_M_Event_Free:

Aug 10 15:32:46.816: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 10 15:32:46.816: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 10 15:32:46.816: //-1//AFW_:/AFW_M_Event_Free:

Aug 10 15:32:46.820: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL

Aug 10 15:32:46.824: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 10 15:32:46.828: //1075/1F25020B87B0/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 10 15:32:46.828: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_PROCEEDING(31)] {

Aug 10 15:32:46.828: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x67B8F67C][test]

Aug 10 15:32:46.828: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1075][LEG_OUTINIT(7)][Cause(0)]

Aug 10 15:32:46.828: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 10 15:32:46.828: //1075/1F25020B87B0/SSIN:/AFW_SS_MapEvent:

Aug 10 15:32:46.828: //-1//SSIN:/AFW_SS_SIP_MapEvent:

Aug 10 15:32:46.828: //1075/1F25020B87B0/AFW_:/AFW_M_Leg_EventPreProcess: LEG[1075 ][LEG_OUTINIT(7)][Cause(0)]

Aug 10 15:32:46.828: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners:

Aug 10 15:32:46.828: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:46.828: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69151204_0_517949684] (

Aug 10 15:32:46.828: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1075][LEG_OUTINIT(7)][Cause(0)]

Aug 10 15:32:46.828: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 10 15:32:46.828: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:46.828: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 10 15:32:46.828: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: TclModule_69F31770_0_517949668 ---> Destination_69151204_0_517949684

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Action:

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/AFW_Destination_Action:

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/OB_FSM_Drive:

Aug 10 15:32:46.828: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:46.828: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_proceeding[31])---[C_Action]------

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/OB_Setting_Proceeding:

Aug 10 15:32:46.828: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------

Aug 10 15:32:46.828: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive:

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/DestIsEventNotified:

Aug 10 15:32:46.828: //-1//Dest:EE67B8F67C000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING

Aug 10 15:32:46.828: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> TclModule_69F31770_0_517949668

Aug 10 15:32:46.832: //1075/1F25020B87B0/AFW_:/AFW_M_Event_Free:

Aug 10 15:32:46.832: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 10 15:32:46.832: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:202@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28E1BFA

From: <sip:10.160.3.56>;tag=1EDF4904-252

To: <sip:202@10.160.3.55>

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 0522519051-1050350053-2276508021-2233146526

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1439213566

Contact: <sip:10.160.3.56:5060>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 267

v=0

o=CiscoSystemsSIP-GW-UserAgent 278 8518 IN IP4 10.160.3.56

s=SIP Call

c=IN IP4 10.160.3.56

t=0 0

m=audio 16384 RTP/AVP 0 101 19

c=IN IP4 10.160.3.56

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

Aug 10 15:32:46.844: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28E1BFA

From: <sip:10.160.3.56>;tag=1EDF4904-252

To: <sip:202@10.160.3.55>

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Timestamp: 1439213566

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

Aug 10 15:32:46.844: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 302 Moved Temporarily

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28E1BFA

From: <sip:10.160.3.56>;tag=1EDF4904-252

To: <sip:202@10.160.3.55>;tag=1EAE6A9C-E78

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Timestamp: 1439213566

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Diversion: <sip:202@10.160.3.55>;reason=unconditional;counter=1

Contact: <sip:203@10.160.3.55>

Content-Length: 0

Aug 10 15:32:46.852: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 10 15:32:46.852: //1075/1F25020B87B0/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 10 15:32:46.852: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_FEATURE(38)] {

Aug 10 15:32:46.852: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x67B8F67C][test]

Aug 10 15:32:46.852: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1075][LEG_OUTPROCEED(8)][Cause(0)]

Aug 10 15:32:46.852: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 10 15:32:46.852: //-1//SSIN:/AFW_SS_MapEvent: No mapping required

Aug 10 15:32:46.852: //1075/1F25020B87B0/AFW_:/AFW_M_Leg_EventPreProcess: LEG[1075 ][LEG_OUTPROCEED(8)][Cause(0)]

Aug 10 15:32:46.852: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners:

Aug 10 15:32:46.852: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:46.852: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69151204_0_517949684] (

Aug 10 15:32:46.852: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1075][LEG_OUTPROCEED(8)][Cause(0)]

Aug 10 15:32:46.852: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 10 15:32:46.852: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:46.852: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 10 15:32:46.852: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_69151204_0_517949684

Aug 10 15:32:46.852: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Action:

Aug 10 15:32:46.852: //-1//Dest:EE67B8F67C000:/AFW_Destination_Action:

Aug 10 15:32:46.852: //-1//Dest:EE67B8F67C000:/OB_FSM_Drive:

Aug 10 15:32:46.856: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:46.856: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_feature[38])---[C_Action]------

Aug 10 15:32:46.856: //-1//Dest:EE67B8F67C000:/OB_Ignore: IGNORING EVENT

Aug 10 15:32:46.856: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------

Aug 10 15:32:46.856: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:46.856: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive:

Aug 10 15:32:46.856: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 10 15:32:46.856: //-1//Dest:EE67B8F67C000:/DestIsEventNotified:

Aug 10 15:32:46.856: //-1//Dest:EE67B8F67C000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING

Aug 10 15:32:46.856: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> NULL

Aug 10 15:32:46.856: //1075/1F25020B87B0/AFW_:/AFW_M_Event_Free:

Aug 10 15:32:46.856: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 10 15:32:46.856: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:202@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28E1BFA

From: <sip:10.160.3.56>;tag=1EDF4904-252

To: <sip:202@10.160.3.55>;tag=1EAE6A9C-E78

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

Aug 10 15:32:46.860: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:203@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28F1199

From: <sip:10.160.3.56>;tag=1EDF491C-1CC5

To: <sip:202@10.160.3.55>

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 0522519051-1050350053-2276508021-2233146526

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 102 INVITE

Max-Forwards: 70

Timestamp: 1439213566

Contact: <sip:10.160.3.56:5060>

Diversion: <sip:202@10.160.3.55>;reason=unconditional;counter=1

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 267

v=0

o=CiscoSystemsSIP-GW-UserAgent 278 8518 IN IP4 10.160.3.56

s=SIP Call

c=IN IP4 10.160.3.56

t=0 0

m=audio 16384 RTP/AVP 0 101 19

c=IN IP4 10.160.3.56

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

Aug 10 15:32:46.872: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28F1199

From: <sip:10.160.3.56>;tag=1EDF491C-1CC5

To: <sip:202@10.160.3.55>

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Timestamp: 1439213566

CSeq: 102 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

Aug 10 15:32:46.876: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28F1199

From: <sip:10.160.3.56>;tag=1EDF491C-1CC5

To: <sip:202@10.160.3.55>;tag=1EAE6ABC-1275

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Timestamp: 1439213566

CSeq: 102 INVITE

Require: 100rel

RSeq: 1785

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Remote-Party-ID: "Ext. twozerothree" <sip:203@10.160.3.55>;party=called;screen=no;privacy=off

Contact: <sip:203@10.160.3.55:5060>

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

Aug 10 15:32:46.880: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 10 15:32:46.880: //1075/1F25020B87B0/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 10 15:32:46.880: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_ALERT(16)] {

Aug 10 15:32:46.880: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x67B8F67C][test]

Aug 10 15:32:46.880: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1075][LEG_OUTPROCEED(8)][Cause(0)]

Aug 10 15:32:46.880: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 10 15:32:46.880: //1075/1F25020B87B0/SSIN:/AFW_SS_MapEvent:

Aug 10 15:32:46.880: //-1//SSIN:/AFW_SS_SIP_MapEvent:

Aug 10 15:32:46.880: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners:

Aug 10 15:32:46.880: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:46.880: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69151204_0_517949684] (

Aug 10 15:32:46.880: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1075][LEG_OUTPROCEED(8)][Cause(0)]

Aug 10 15:32:46.880: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 10 15:32:46.880: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:46.880: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 10 15:32:46.880: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_69151204_0_517949684

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Action:

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/AFW_Destination_Action:

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/OB_FSM_Drive:

Aug 10 15:32:46.884: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:46.884: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_alert[16])---[C_Action]------

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/OB_Setting_Alert:

Aug 10 15:32:46.884: //-1//Dest:/OB_Setting_Alert: aData bitmask 0x80000001

Aug 10 15:32:46.884: //-1//Dest:/OB_Setting_Alert: aData.display_info=Ext. twozerothree

Aug 10 15:32:46.884: //1075/1F25020B87B0/AFW_:/AFW_Util_FillDispNotifyData: Current: notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 10 15:32:46.884: //1075/1F25020B87B0/AFW_:/AFW_Util_FillDispNotifyData: Filled : notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 10 15:32:46.884: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------

Aug 10 15:32:46.884: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive:

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/DestIsEventNotified:

Aug 10 15:32:46.884: //-1//Dest:EE67B8F67C000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING

Aug 10 15:32:46.884: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> NULL

Aug 10 15:32:46.884: //1075/1F25020B87B0/AFW_:/AFW_M_Event_Free:

Aug 10 15:32:46.884: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 10 15:32:46.940: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Sent:

PRACK sip:203@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK290FE

From: <sip:10.160.3.56>;tag=1EDF491C-1CC5

To: <sip:202@10.160.3.55>;tag=1EAE6ABC-1275

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

CSeq: 103 PRACK

RAck: 1785 102 INVITE

Allow-Events: telephone-event

Max-Forwards: 70

Content-Length: 0

Aug 10 15:32:46.944: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK290FE

From: <sip:10.160.3.56>;tag=1EDF491C-1CC5

To: <sip:202@10.160.3.55>;tag=1EAE6ABC-1275

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 103 PRACK

Content-Length: 0

Aug 10 15:32:50.355: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28F1199

From: <sip:10.160.3.56>;tag=1EDF491C-1CC5

To: <sip:202@10.160.3.55>;tag=1EAE6ABC-1275

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Timestamp: 1439213566

CSeq: 102 INVITE

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Remote-Party-ID: "Ext. twozerothree" <sip:203@10.160.3.55>;party=called;screen=no;privacy=off

Contact: <sip:203@10.160.3.55:5060>

Supported: replaces

Supported: sdp-anat

Server: Cisco-SIPGateway/IOS-12.x

Supported: timer

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 268

v=0

o=CiscoSystemsSIP-GW-UserAgent 5624 8833 IN IP4 10.160.3.55

s=SIP Call

c=IN IP4 10.160.3.55

t=0 0

m=audio 17530 RTP/AVP 0 19 101

c=IN IP4 10.160.3.55

a=rtpmap:0 PCMU/8000

a=rtpmap:19 CN/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

Aug 10 15:32:50.363: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 10 15:32:50.363: //1075/1F25020B87B0/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 10 15:32:50.363: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_CONNECTED(17)] {

Aug 10 15:32:50.363: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x67B8F67C][test]

Aug 10 15:32:50.363: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1075][LEG_OUTRINGING(9)][Cause(0)]

Aug 10 15:32:50.363: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 10 15:32:50.363: //1075/1F25020B87B0/SSIN:/AFW_SS_MapEvent:

Aug 10 15:32:50.363: //-1//SSIN:/AFW_SS_SIP_MapEvent:

Aug 10 15:32:50.363: //1075/1F25020B87B0/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 12C type 1

Aug 10 15:32:50.363: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners:

Aug 10 15:32:50.363: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:50.363: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69151204_0_517949684] (

Aug 10 15:32:50.363: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1075][LEG_OUTCONNECTED(10)][Cause(0)]

Aug 10 15:32:50.363: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 10 15:32:50.363: //1075/1F25020B87B0/AFW_:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:50.363: //1075/1F25020B87B0/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 10 15:32:50.367: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_69151204_0_517949684

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_Action:

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/AFW_Destination_Action:

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/OB_FSM_Drive:

Aug 10 15:32:50.367: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:50.367: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_connected[17])---[C_Action]------

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/OB_Setting_Connect:

Aug 10 15:32:50.367: //-1//Dest:/OB_Setting_Connect: cData.display_info=Ext. twozerothree

Aug 10 15:32:50.367: //1075/1F25020B87B0/AFW_:/AFW_Util_FillDispNotifyData: Current: notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 10 15:32:50.367: //1075/1F25020B87B0/AFW_:/AFW_Util_FillDispNotifyData: Filled : notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/DestNotifySharedLnState: callid: 1075, peer: 200

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/DestNotifySharedLnState: Shared-Line NOT enabled!

Aug 10 15:32:50.367: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_DONE[8])---------------

Aug 10 15:32:50.367: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> Destination_69151204_0_517949684

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive:

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/DestIsEventNotified:

Aug 10 15:32:50.367: //-1//Dest:EE67B8F67C000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_DONE

Aug 10 15:32:50.367: //-1//AFW_:/AFW_Event_New: Event ID: ev_destination_done

Aug 10 15:32:50.367: //-1//AFW_:EE67B8F67C000:/AFW_Module_ReturnArgEv:

Aug 10 15:32:50.367: //-1//AFW_:EE67B8F67C000:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[1075 ][LEG_OUTCONNECTED(10)][Cause(0)]}

Aug 10 15:32:50.371: //1075/1F25020B87B0/AFW_:/AFW_Object_RemoveListener:

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_Module_UnListen: NumObjects: 0

Aug 10 15:32:50.371: //-1//Dest:EE67B8F67C000:/DestReturn: Destination Returning(ds_000 Status DEST_ACTIVE)

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: Destination_69151204_0_517949684 ---> NULL

Aug 10 15:32:50.371: //1075/1F25020B87B0/AFW_:/AFW_M_Event_Free:

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_Process_GetPriorityQEvent: Received

Aug 10 15:32:50.371: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_DESTINATION_DONE(221)] {

Aug 10 15:32:50.371: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0x67B8F67C][test]

Aug 10 15:32:50.371: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[Destination_69151204_0_517949684] (

Aug 10 15:32:50.371: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )

Aug 10 15:32:50.371: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }

Aug 10 15:32:50.371: //-1//Dest:EE67B8F67C000:/AFW_M_Destination_EventPreProcess:

Aug 10 15:32:50.371: //1075/1F25020B87B0/Dest:/DestUpdateSuccessStats:

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_Object_WalkListeners:

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:50.371: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[CallSetup_69139C08_0_517949680] (

Aug 10 15:32:50.371: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69151204_0_517949684] (

Aug 10 15:32:50.371: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 10 15:32:50.371: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Object_WalkListeners: Entering Module : CallSetup

Aug 10 15:32:50.375: //1075/1F25020B87B0/AFW_:/AFW_Object_AddListener: adding Module CallSetup as listener

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Object_RemoveListener:

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Module_UnListen: NumObjects: 1

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: NULL ---> CallSetup_69139C08_0_517949680

Aug 10 15:32:50.375: //-1//Call:EE67B8F67C000:/AFW_M_CallSetup_Action:

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(S_PLACECALL[2],ev_destination_done[221])---[C_Action]------

Aug 10 15:32:50.375: //-1//Call:EE67B8F67C000:/CS_Placecall_DestDone:

Aug 10 15:32:50.375: //-1//Call:EE67B8F67C000:/CS_DestDone_CapturePreemptStatus: PreemptErrorCode 0; Play prompt none

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION END: -------------(S_DONE[13])---------------

Aug 10 15:32:50.375: //-1//AFW_:/AFW_Event_New: Event ID: ev_setup_done

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Module_ReturnArgEv:

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[1075 ][LEG_OUTCONNECTED(10)][Cause(0)]}

Aug 10 15:32:50.375: //1075/1F25020B87B0/AFW_:/AFW_Object_RemoveListener:

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Module_UnListen: NumObjects: 0

Aug 10 15:32:50.375: //-1//Call:EE67B8F67C000:/CS_Complete: CallSetup Returning(ls_000 Status CS_ACTIVE)

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: CallSetup_69139C08_0_517949680 ---> NULL

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Object_WalkListeners:

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_M_Event_Free:

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_M_Event_Free: MODULEDONEEVENT for a Module: Destination_69151204_0_517949684

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_Object_RemoveAllListener:

Aug 10 15:32:50.375: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 3, CmdPending 3

Aug 10 15:32:50.375: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_DecrNPendingCmd: PendingCmdCount: 2

Aug 10 15:32:50.375: //-1//AFW_:HN1EDF48F4:/AFW_M_Event_Free: ExecEnv objCount: 3

Aug 10 15:32:50.375: //-1//Dest:HN1EDF48F4:/AFW_M_Destination_Free:

Aug 10 15:32:50.379: //-1//Dest:HN1EDF48F4:/DestEmptyPreemptHoldQ:

Aug 10 15:32:50.379: //-1//AFW_:/AFW_M_FSM_Free:

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 2

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_Process_GetPriorityQEvent: Received

Aug 10 15:32:50.379: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_CALLSETUP_DONE(219)] {

Aug 10 15:32:50.379: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0x67B8F67C][test]

Aug 10 15:32:50.379: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[CallSetup_69139C08_0_517949680] (

Aug 10 15:32:50.379: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )

Aug 10 15:32:50.379: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }

Aug 10 15:32:50.379: //-1//Call:EE67B8F67C000:/AFW_M_CallSetup_EventPreProcess:

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_Object_WalkListeners:

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:50.379: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_69F31770_0_517949668] (

Aug 10 15:32:50.379: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[CallSetup_69139C08_0_517949680] (

Aug 10 15:32:50.379: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_Object_WalkListeners: Entering Module : TclModule

Aug 10 15:32:50.379: //1075/1F25020B87B0/AFW_:/AFW_Object_AddListener: adding Module TclModule as listener

Aug 10 15:32:50.379: //-1//AFW_:EE67B8F67C000:/AFW_Object_RemoveListener:

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_Module_UnListen: NumObjects: 1

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_69F31770_0_517949668

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_M_TclModule_Action:

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_TclModule_DefaultEvHandling:

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_TclModule_UpdateSessionMask: Update intwrk-mask for leg [1075]'s session

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(I[1],ev_setup_done[219])---[setupDone]------

Aug 10 15:32:50.383: //-1//TCL :EE67B8F67C000:/tcl_InfotagObjCmd: infotag get evt_status

Aug 10 15:32:50.383: //-1//TCL :EE67B8F67C000:/tcl_InfotagGetObjCmd: infotag get evt_status

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/vtr_ev_status: argc 2 argindex 2

Aug 10 15:32:50.383: //-1//TCL :EE67B8F67C000:/tcl_PutsObjCmd: ls_000

Aug 10 15:32:50.383:

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION END: -------------(I[1])---------------

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_SetModuleScope: TclModule_69F31770_0_517949668 ---> NULL

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_Object_WalkListeners:

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: START

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_M_Object_ShowListeners: END

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_M_Event_Free:

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_M_Event_Free: MODULEDONEEVENT for a Module: CallSetup_69139C08_0_517949680

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_Object_RemoveAllListener:

Aug 10 15:32:50.383: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 2, CmdPending 2

Aug 10 15:32:50.383: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_DecrNPendingCmd: PendingCmdCount: 1

Aug 10 15:32:50.383: //-1//AFW_:HN1EDF48F0:/AFW_M_Event_Free: ExecEnv objCount: 2

Aug 10 15:32:50.383: //-1//Call:HN1EDF48F0:/AFW_M_CallSetup_Free:

Aug 10 15:32:50.383: //-1//AFW_:/AFW_M_FSM_Free:

Aug 10 15:32:50.387: //-1//AFW_:EE67B8F67C000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1

Aug 10 15:32:50.387: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 10 15:32:50.387: //-1//AFW_:/AFW_M_Event_Free:

Aug 10 15:32:50.387: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL

Aug 10 15:32:50.387: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:203@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK291126

From: <sip:10.160.3.56>;tag=1EDF491C-1CC5

To: <sip:202@10.160.3.55>;tag=1EAE6ABC-1275

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Max-Forwards: 70

CSeq: 102 ACK

Allow-Events: telephone-event

Content-Length: 0

Aug 10 15:32:50.387: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 10 15:32:50.387: //-1//AFW_:/AFW_M_Event_Free:

Aug 10 15:32:50.387: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL

Raghavendra G V · ‎08-10-2015

I tried your script with NONE, i did received the status code(ls_026).In your log rerouteMode set as rotary. looks like CallInfo

passing to leg setup is different than the variable.

Your log

Aug 10 15:32:46.800: //-1//AFW_:EE67B8F67C000:/AFW_FSM_Drive: ACTION BEGIN: ------(I[1],ev_session_indication[198])---[sess]------
Aug 10 15:32:46.800: //-1//TCL :EE67B8F67C000:/tcl_LegObjCmd: leg setup 202 callinfo
Aug 10 15:32:46.800: //-1//CSPK:EE67B8F67C000:/tcl_LegSetupObjCmd: leg setup 202 callinfo
Aug 10 15:32:46.804: //-1//CSPK:EE67B8F67C000:/C_CallSetup_Start: ControlInfo = 0x69FE6480, callInfo = 0x69F9C774, destination[0]=202
Aug 10 15:32:46.804: //-1//CSPK:EE67B8F67C000:/C_CallSetup_Start: configured mode=rotary (1)
Aug 10 15:32:46.804: //-1//CSPK:EE67B8F67C000:/C_CallSetup_Start: configured reroutemode=rotary (1)

My log

*Aug 11 04:49:28.116: //-1//AFW_:EE4F02F014000:/AFW_FSM_Drive: ACTION BEGIN: ------(I[1],ev_session_indication[195])---[sess]------
*Aug 11 04:49:28.116: //-1//TCL :EE4F02F014000:/tcl_LegObjCmd: leg setup 300 callInfo
*Aug 11 04:49:28.116: //-1//CSPK:EE4F02F014000:/tcl_LegSetupObjCmd: leg setup 300 callInfo
*Aug 11 04:49:28.116: //-1//CSPK:/tclSetControlParams: rerouteMode(5) = NONE
*Aug 11 04:49:28.116: //-1//CSPK:EE4F02F014000:/C_CallSetup_Start: ControlInfo = 0x4F04D090, callInfo = 0x4F047780, destination[0]=300
*Aug 11 04:49:28.116: //-1//CSPK:EE4F02F014000:/C_CallSetup_Start: configured mode=rotary (1)

Thanks,
Raghavendra

paolo bevilacqua · ‎08-11-2015

Which IOS version are you using? Can you try 15.2(4)M9 ?

Raghavendra G V · ‎08-11-2015

I tried in 151-4.M1.

Thanks,

Raghavendra

paolo bevilacqua · ‎08-11-2015

Can you try 15.1(4)M10 which has ED status ?

Cisco.com does not allow to dowload 15.1(4)M1 with the following message, followed by a long list of bugs-

Cisco engineering has identified at least one serious software issue with the release which you have selected that may affect your use of these software.

Raghavendra G V · ‎08-11-2015

Issue is not with IOS, you need to change the script as mentioned in my above posts.

Thanks,

Raghavendra

paolo bevilacqua · ‎08-11-2015

Issue is not with IOS, you need to change the script as mentioned in my above posts.

Thanks, I have correct a typo in the script. Still router receives ls_000 not ls_026.

Check logs below for bold:

BWS-2801#sh ver

Cisco IOS Software, 2801 Software (C2801-SPSERVICESK9-M), Version 15.1(4)M9, RELEASE SOFTWARE (fc3)

Technical Support: http://www.cisco.com/techsupport

Compiled Fri 12-Sep-14 11:06 by prod_rel_team

ROM: System Bootstrap, Version 12.4(13r)T5, RELEASE SOFTWARE (fc1)

BWS-2801 uptime is 6 days, 23 hours, 36 minutes

System returned to ROM by power-on

System restarted at 15:41:05 CEST Tue Aug 4 2015

System image file is "flash:c2801-spservicesk9-mz.151-4.M9.bin"

Last reload type: Normal Reload

This product contains cryptographic features and is subject to United

States and local country laws governing import, export, transfer and

use. Delivery of Cisco cryptographic products does not imply

third-party authority to import, export, distribute or use encryption.

Importers, exporters, distributors and users are responsible for

compliance with U.S. and local country laws. By using this product you

agree to comply with applicable laws and regulations. If you are unable

to comply with U.S. and local laws, return this product immediately.

A summary of U.S. laws governing Cisco cryptographic products may be found at:

http://www.cisco.com/wwl/export/crypto/tool/stqrg.html

If you require further assistance please contact us by sending email to

export@cisco.com.

Cisco 2801 (revision 7.0) with 241664K/20480K bytes of memory.

Processor board ID FCZ11429476

2 FastEthernet interfaces

2 Voice FXS interfaces

2 DSPs, 16 Voice resources

DRAM configuration is 64 bits wide with parity disabled.

191K bytes of NVRAM.

62720K bytes of ATA CompactFlash (Read/Write)

License Info:

License UDI:

-------------------------------------------------

Device# PID SN

-------------------------------------------------

*0 CISCO2801 FCZ11429476

Configuration register is 0x2102

BWS-2801#sh call application voice test

Script Name : test

URL : ftp://10.160.3.249/test-callinfo.tcl

Type : Service

State: Registered

Life : Configured

Exec Instances: 0

Parameters registered under test namespace:

name type default value description

uid-len I 10 the number of digits in UID

warning-time I 30 the time (in secs) within which a user is warned before the calling time expires (call terminates)

pin-len I 4 the number of digits in PIN

retry-count I 3 the number of attempts to reenter PIN

redirect-number S the telephone number where a call is redirected to

Script Code Begin:

--------------------------------

TCL Script version 2.0 - 2.1

proc sess {} {

set callInfo(rerouteMode) NONE

leg setup 202 callInfo

}

proc setupDone {} { puts [infotag get evt_status] }

set fsm(I,ev_session_indication) {sess same_state}

set fsm(I,ev_setup_done) {setupDone same_state}

fsm define fsm I

--------------------------------

BWS-2801#call application sess start a test

BWS-2801#

Aug 11 15:33:16.847: //-1//SERV:/AFW_Service_GetExecEnv: Script Name = test

Aug 11 15:33:16.847: //-1//SERV:/AFW_Service_GetExecEnv: Using Cached ExecEnv

Aug 11 15:33:16.847: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetRoot: Execenv = 0x69285A18

Aug 11 15:33:16.851: //-1//AFW_:/AFW_M_TclModule_NewDup:

Aug 11 15:33:16.851: //-1//AFW_:HN2405DF1C:/AFW_M_TclModule_Free: MOD[TclModule_69F31D30_0_604121448] ( )

Aug 11 15:33:16.851: //-1//AFW_:/AFW_M_FSM_Free:

Aug 11 15:33:16.851: //-1//AFW_:/AFW_Process_Lock: pProcess(0x690CCB4C)=1

Aug 11 15:33:16.851: //-1//AFW_:LP:EE69285A18000:HN24063974:/AFW_M_Object_SetExecEnv: ObjCount: 1, CmdPending 0

Aug 11 15:33:16.851: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_RestoreDataBackup: Restore DataArea from Script

Aug 11 15:33:16.851: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_RestoreDataBackup: Restore TokenTable from Script

Aug 11 15:33:16.851: //-1//AFW_:/AFW_Event_New: Event ID: ev_session_indication

Aug 11 15:33:16.851: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_Initiate: Execenv = 0x69285A18

Aug 11 15:33:16.851: //-1//AFW_:EE69285A18000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

Aug 11 15:33:16.855: //-1//AFW_:EE69285A18000:/AFW_Process_GetAppQEvent: Received

Aug 11 15:33:16.855: //-1//AFW_:/AFW_Process_GetAppQEvent: Event[APP_EV_SESSION_START_IND(198)] {

Aug 11 15:33:16.855: //-1//AFW_:/AFW_Process_GetAppQEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:16.855: //-1//AFW_:/AFW_Process_GetAppQEvent:

Aug 11 15:33:16.855: //-1//AFW_:EE69285A18000:/AFW_M_Module_GetHandle: Module handle: TclModule_69F32B90_0_604387700MOD[TclModule_69F32B90_0_604387700] (

Aug 11 15:33:16.855: //-1//AFW_:/AFW_Process_GetAppQEvent: )

Aug 11 15:33:16.855: //-1//AFW_:/AFW_Process_GetAppQEvent: }

Aug 11 15:33:16.855: //-1//AFW_:EE69285A18000:/AFW_M_TclModule_EventPreProcess:

Aug 11 15:33:16.855: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32B90_0_604387700 ---> TclModule_69F32B90_0_604387700

Aug 11 15:33:16.855: //-1//AFW_:EE69285A18000:/AFW_M_TclModule_Action:

Aug 11 15:33:16.855: //-1//AFW_:EE69285A18000:/AFW_TclModule_DefaultEvHandling:

Aug 11 15:33:16.855: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION BEGIN: ------(I[1],ev_session_indication[198])---[sess]------

Aug 11 15:33:16.855: //-1//TCL :EE69285A18000:/tcl_LegObjCmd: leg setup 202 callInfo

Aug 11 15:33:16.855: //-1//CSPK:EE69285A18000:/tcl_LegSetupObjCmd: leg setup 202 callInfo

Aug 11 15:33:16.859: //-1//CSPK:/tclSetControlParams: rerouteMode(5) = NONE

Aug 11 15:33:16.859: //-1//CSPK:EE69285A18000:/C_CallSetup_Start: ControlInfo = 0x69EE0C30, callInfo = 0x69F484D4, destination[0]=202

Aug 11 15:33:16.859: //-1//CSPK:EE69285A18000:/C_CallSetup_Start: configured mode=rotary (1)

Aug 11 15:33:16.859: //-1//Call:/AFW_CallSetup_New:

Aug 11 15:33:16.859: //-1//AFW_:/AFW_FSM_New:

Aug 11 15:33:16.859: //-1//AFW_:LP:EE69285A18000:HN2406397C:/AFW_M_Object_SetExecEnv: ObjCount: 2, CmdPending 0

Aug 11 15:33:16.859: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 1

Aug 11 15:33:16.859: //-1//AFW_:EE69285A18000:/AFW_Object_AddListener: adding Module TclModule as listener

Aug 11 15:33:16.859: //-1//AFW_:EE69285A18000:/AFW_M_Module_GetHandle: Module handle: CallSetup_69149850_0_604387708

Aug 11 15:33:16.859: //-1//Call:EE69285A18000:/AFW_CallSetup_AddDest: 202 index 0

Aug 11 15:33:16.859: //-1//Call:EE69285A18000:/AFW_M_CallSetup_Initiate:

Aug 11 15:33:16.859: //-1//AFW_:/AFW_Util_CopyMLPPDataToCallInfoContainer: Voice MLPP not configured

Aug 11 15:33:16.863: //-1//Call:EE69285A18000:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set

Aug 11 15:33:16.863: //-1//Call:EE69285A18000:/CallSetupInitiate:

Aug 11 15:33:16.863: //-1//Call:EE69285A18000:/CS_Placecall:

Aug 11 15:33:16.863: //-1//Call:EE69285A18000:/CS_Placecall: enable reroute_ind intercept for reroutemode NONE

Aug 11 15:33:16.863: //-1//AFW_:/AFW_Util_CopyMLPPDataToCallInfoContainer: Voice MLPP not configured

Aug 11 15:33:16.863: //-1//Call:EE69285A18000:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set

Aug 11 15:33:16.863: //-1//Dest:/AFW_Destination_New:

Aug 11 15:33:16.863: //-1//AFW_:/AFW_FSM_New:

Aug 11 15:33:16.863: //-1//AFW_:LP:EE69285A18000:HN24063980:/AFW_M_Object_SetExecEnv: ObjCount: 3, CmdPending 1

Aug 11 15:33:16.863: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 2

Aug 11 15:33:16.863: //-1//AFW_:EE69285A18000:/AFW_Object_AddListener: adding Module CallSetup as listener

Aug 11 15:33:16.863: //-1//AFW_:EE69285A18000:/AFW_M_Module_GetHandle: Module handle: Destination_6917FADC_0_604387712

Aug 11 15:33:16.863: //-1//Dest:EE69285A18000:/AFW_Destination_AddDest:

Aug 11 15:33:16.863: //-1//Dest:/AFW_Destination_AddDest: adding destination "202"

Aug 11 15:33:16.863: //-1//Dest:EE69285A18000:/AFW_M_Destination_Initiate:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/AFW_M_Destination_Initiate: Outgoing guid : 5B79869D.3F6411E5.89FBC175.851B1C9E

Aug 11 15:33:16.867: Incoming_guid : 00000000.00000000.00000000.00000000

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/AFW_M_Destination_Initiate: Outgoing gcid : 00000000.00000000.00000000.00000000

Aug 11 15:33:16.867: Incoming_gcid : 00000000.00000000.00000000.00000000

Aug 11 15:33:16.867: //-1//AFW_:/AFW_Util_CopyMLPPDataToCallInfoContainer: Voice MLPP not configured

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestUpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] Preempt Hunt Not Set

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestSetupInitiate:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestAddRerouteSetupInfo:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestAddTransferSetupInfo:

Aug 11 15:33:16.867: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: copied 202

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestMatchDialPeer:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestMatchDialPeer: src carrier id:, tgt carrier id:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestQueuePeers: Matched peers:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestPeerItemUpdatePreemptInfo:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestPeerItemResetLPCORInfo:

Aug 11 15:33:16.867: //-1//Dest:/DestQueuePeers: 200

Aug 11 15:33:16.867: //-1//Dest:/DestQueuePeers: , Total(1)

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestDialPeerRotary:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestOutboundCallUsingPeer: dest(202), numexpDest(202), numDestDigits(2)

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestOutboundCallUsingPeer: prefix(.55), peer tag(200)

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestUpdatePreemptSetupInfo:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestDialPeerFilter:

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestDialPeerFilter: Checking peer [200]

Aug 11 15:33:16.867: //-1//Dest:EE69285A18000:/DestPeerFilterRouteCode: Route Code not available in callInfo

Aug 11 15:33:16.867: //-1//Dest:/DestOutboundCallUsingPeer: Outgoing Tag(200) calling[] called[202]

Aug 11 15:33:16.871: //-1//Dest:EE69285A18000:/DestCheckTeleUnassBusy:

Aug 11 15:33:16.871: //-1//Dest:EE69285A18000:/DestHandleLPCOR:

Aug 11 15:33:16.871: //-1//Dest:EE69285A18000:/DestSetup:

Aug 11 15:33:16.871: //-1//AFW_:/AFW_Leg_GetTypeDetail: ***ERROR*** pLeg is NULL

Aug 11 15:33:16.871: //-1//AFW_:/AFW_Util_SaveRawMsg:

Aug 11 15:33:16.871: //-1//AFW_:/AFW_Leg_New:

Aug 11 15:33:16.871: //1419/5B79869D89FB/AFW_:/AFW_Leg_NewSetupRequest: ccCallSetupRequest done

Aug 11 15:33:16.871: //1419/5B79869D89FB/AFW_:/AFW_M_Leg_SetExecEnv:

Aug 11 15:33:16.871: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 3

Aug 11 15:33:16.871: //-1/5B79869D89FB/AFW_:LP:EE69285A18000:LG1419:/AFW_M_Object_SetExecEnv: ObjCount: 4, CmdPending 3

Aug 11 15:33:16.871: //1419/5B79869D89FB/AFW_:/AFW_Object_AddListener: adding Module Destination as listener

Aug 11 15:33:16.871: //1419/5B79869D89FB/AFW_:/AFW_M_Leg_GetHandle: Leg handle: LEG_1419

Aug 11 15:33:16.871: //1419/5B79869D89FB/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 17A type 3

Aug 11 15:33:16.871: //-1//Dest:EE69285A18000:/DestSetup: Destination setup success

Aug 11 15:33:16.871: //-1//Dest:EE69285A18000:/AFW_M_Destination_Initiate: attempting call to destination 202

Aug 11 15:33:16.871: //-1//Call:EE69285A18000:/CS_Placecall: Call placed to 202 index 0

Aug 11 15:33:16.875: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION END: -------------(I[1])---------------

Aug 11 15:33:16.875: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32B90_0_604387700 ---> TclModule_69F32B90_0_604387700

Aug 11 15:33:16.875: //-1//AFW_:EE69285A18000:/AFW_M_Event_Free:

Aug 11 15:33:16.875: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 11 15:33:16.875: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:16.875: //-1//AFW_:/AFW_M_Event_Free:

Aug 11 15:33:16.875: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL

Aug 11 15:33:16.883: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:16.883: //1419/5B79869D89FB/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 11 15:33:16.883: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_PROCEEDING(31)] {

Aug 11 15:33:16.883: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:16.883: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1419][LEG_OUTINIT(7)][Cause(0)]

Aug 11 15:33:16.883: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 11 15:33:16.883: //1419/5B79869D89FB/SSIN:/AFW_SS_MapEvent:

Aug 11 15:33:16.883: //-1//SSIN:/AFW_SS_SIP_MapEvent:

Aug 11 15:33:16.883: //1419/5B79869D89FB/AFW_:/AFW_M_Leg_EventPreProcess: LEG[1419 ][LEG_OUTINIT(7)][Cause(0)]

Aug 11 15:33:16.883: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners:

Aug 11 15:33:16.883: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:16.883: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_6917FADC_0_604387712] (

Aug 11 15:33:16.883: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1419][LEG_OUTINIT(7)][Cause(0)]

Aug 11 15:33:16.883: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 11 15:33:16.883: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:16.883: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 11 15:33:16.883: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32B90_0_604387700 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.883: //-1//Dest:EE69285A18000:/AFW_M_Destination_Action:

Aug 11 15:33:16.883: //-1//Dest:EE69285A18000:/AFW_Destination_Action:

Aug 11 15:33:16.883: //-1//Dest:EE69285A18000:/OB_FSM_Drive:

Aug 11 15:33:16.887: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.887: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_proceeding[31])---[C_Action]------

Aug 11 15:33:16.887: //-1//Dest:EE69285A18000:/OB_Setting_Proceeding:

Aug 11 15:33:16.887: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------

Aug 11 15:33:16.887: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.887: //-1//Dest:EE69285A18000:/IW_FSM_Drive:

Aug 11 15:33:16.887: //-1//Dest:EE69285A18000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 11 15:33:16.887: //-1//Dest:EE69285A18000:/DestIsEventNotified:

Aug 11 15:33:16.887: //-1//Dest:EE69285A18000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING

Aug 11 15:33:16.887: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> TclModule_69F32B90_0_604387700

Aug 11 15:33:16.887: //1419/5B79869D89FB/AFW_:/AFW_M_Event_Free:

Aug 11 15:33:16.887: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 11 15:33:16.887: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:202@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2DF22FE

From: <sip:10.160.3.56>;tag=24063990-1709

To: <sip:202@10.160.3.55>

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 1534690973-1063522789-2314977653-2233146526

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1439299996

Contact: <sip:10.160.3.56:5060>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 268

v=0

o=CiscoSystemsSIP-GW-UserAgent 1332 3154 IN IP4 10.160.3.56

s=SIP Call

c=IN IP4 10.160.3.56

t=0 0

m=audio 17184 RTP/AVP 0 101 19

c=IN IP4 10.160.3.56

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

Aug 11 15:33:16.899: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2DF22FE

From: <sip:10.160.3.56>;tag=24063990-1709

To: <sip:202@10.160.3.55>

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Timestamp: 1439299996

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

Aug 11 15:33:16.899: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 302 Moved Temporarily

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2DF22FE

From: <sip:10.160.3.56>;tag=24063990-1709

To: <sip:202@10.160.3.55>;tag=23D53B80-1BA6

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Timestamp: 1439299996

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Diversion: <sip:202@10.160.3.55>;reason=unconditional;counter=1

Contact: <sip:203@10.160.3.55>

Content-Length: 0

Aug 11 15:33:16.907: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:16.907: //1419/5B79869D89FB/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 11 15:33:16.907: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_FEATURE(38)] {

Aug 11 15:33:16.907: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:16.907: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1419][LEG_OUTPROCEED(8)][Cause(0)]

Aug 11 15:33:16.907: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 11 15:33:16.907: //-1//SSIN:/AFW_SS_MapEvent: No mapping required

Aug 11 15:33:16.907: //1419/5B79869D89FB/AFW_:/AFW_M_Leg_EventPreProcess: LEG[1419 ][LEG_OUTPROCEED(8)][Cause(0)]

Aug 11 15:33:16.907: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners:

Aug 11 15:33:16.907: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:16.907: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_6917FADC_0_604387712] (

Aug 11 15:33:16.907: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1419][LEG_OUTPROCEED(8)][Cause(0)]

Aug 11 15:33:16.911: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 11 15:33:16.911: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:16.911: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 11 15:33:16.911: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/AFW_M_Destination_Action:

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/AFW_Destination_Action:

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/OB_FSM_Drive:

Aug 11 15:33:16.911: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.911: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_feature[38])---[C_Action]------

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/OB_Ignore: IGNORING EVENT

Aug 11 15:33:16.911: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------

Aug 11 15:33:16.911: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/IW_FSM_Drive:

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/DestIsEventNotified:

Aug 11 15:33:16.911: //-1//Dest:EE69285A18000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING

Aug 11 15:33:16.911: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> NULL

Aug 11 15:33:16.911: //1419/5B79869D89FB/AFW_:/AFW_M_Event_Free:

Aug 11 15:33:16.911: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 11 15:33:16.915: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:202@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2DF22FE

From: <sip:10.160.3.56>;tag=24063990-1709

To: <sip:202@10.160.3.55>;tag=23D53B80-1BA6

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

Aug 11 15:33:16.915: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:203@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2E01CA6

From: <sip:10.160.3.56>;tag=240639A8-AAF

To: <sip:202@10.160.3.55>

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 1534690973-1063522789-2314977653-2233146526

User-Agent: Cisco-SIPGateway/IOS-12.x

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 102 INVITE

Max-Forwards: 70

Timestamp: 1439299996

Contact: <sip:10.160.3.56:5060>

Diversion: <sip:202@10.160.3.55>;reason=unconditional;counter=1

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 268

v=0

o=CiscoSystemsSIP-GW-UserAgent 1332 3154 IN IP4 10.160.3.56

s=SIP Call

c=IN IP4 10.160.3.56

t=0 0

m=audio 17184 RTP/AVP 0 101 19

c=IN IP4 10.160.3.56

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

Aug 11 15:33:16.931: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2E01CA6

From: <sip:10.160.3.56>;tag=240639A8-AAF

To: <sip:202@10.160.3.55>

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Timestamp: 1439299996

CSeq: 102 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

Aug 11 15:33:16.931: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2E01CA6

From: <sip:10.160.3.56>;tag=240639A8-AAF

To: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Timestamp: 1439299996

CSeq: 102 INVITE

Require: 100rel

RSeq: 3218

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Remote-Party-ID: "Ext. twozerothree" <sip:203@10.160.3.55>;party=called;screen=no;privacy=off

Contact: <sip:203@10.160.3.55:5060>

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

Aug 11 15:33:16.935: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:16.935: //1419/5B79869D89FB/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 11 15:33:16.935: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_ALERT(16)] {

Aug 11 15:33:16.935: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:16.935: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1419][LEG_OUTPROCEED(8)][Cause(0)]

Aug 11 15:33:16.935: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 11 15:33:16.935: //1419/5B79869D89FB/SSIN:/AFW_SS_MapEvent:

Aug 11 15:33:16.935: //-1//SSIN:/AFW_SS_SIP_MapEvent:

Aug 11 15:33:16.939: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners:

Aug 11 15:33:16.939: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:16.939: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_6917FADC_0_604387712] (

Aug 11 15:33:16.939: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1419][LEG_OUTPROCEED(8)][Cause(0)]

Aug 11 15:33:16.939: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 11 15:33:16.939: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:16.939: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 11 15:33:16.939: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.939: //-1//Dest:EE69285A18000:/AFW_M_Destination_Action:

Aug 11 15:33:16.939: //-1//Dest:EE69285A18000:/AFW_Destination_Action:

Aug 11 15:33:16.939: //-1//Dest:EE69285A18000:/OB_FSM_Drive:

Aug 11 15:33:16.939: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.939: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_alert[16])---[C_Action]------

Aug 11 15:33:16.939: //-1//Dest:EE69285A18000:/OB_Setting_Alert:

Aug 11 15:33:16.939: //-1//Dest:/OB_Setting_Alert: aData bitmask 0x80000001

Aug 11 15:33:16.939: //-1//Dest:/OB_Setting_Alert: aData.display_info=Ext. twozerothree

Aug 11 15:33:16.939: //1419/5B79869D89FB/AFW_:/AFW_Util_FillDispNotifyData: Current: notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 11 15:33:16.939: //1419/5B79869D89FB/AFW_:/AFW_Util_FillDispNotifyData: Filled : notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 11 15:33:16.939: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------

Aug 11 15:33:16.943: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:16.943: //-1//Dest:EE69285A18000:/IW_FSM_Drive:

Aug 11 15:33:16.943: //-1//Dest:EE69285A18000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 11 15:33:16.943: //-1//Dest:EE69285A18000:/DestIsEventNotified:

Aug 11 15:33:16.943: //-1//Dest:EE69285A18000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING

Aug 11 15:33:16.943: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> NULL

Aug 11 15:33:16.943: //1419/5B79869D89FB/AFW_:/AFW_M_Event_Free:

Aug 11 15:33:16.943: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 11 15:33:16.943: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Sent:

PRACK sip:203@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2E12153

From: <sip:10.160.3.56>;tag=240639A8-AAF

To: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

CSeq: 103 PRACK

RAck: 3218 102 INVITE

Allow-Events: telephone-event

Max-Forwards: 70

Content-Length: 0

Aug 11 15:33:16.947: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2E12153

From: <sip:10.160.3.56>;tag=240639A8-AAF

To: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 103 PRACK

Content-Length: 0

Aug 11 15:33:19.383: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2E01CA6

From: <sip:10.160.3.56>;tag=240639A8-AAF

To: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Timestamp: 1439299996

CSeq: 102 INVITE

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Remote-Party-ID: "Ext. twozerothree" <sip:203@10.160.3.55>;party=called;screen=no;privacy=off

Contact: <sip:203@10.160.3.55:5060>

Supported: replaces

Supported: sdp-anat

Server: Cisco-SIPGateway/IOS-12.x

Supported: timer

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 268

v=0

o=CiscoSystemsSIP-GW-UserAgent 3438 6464 IN IP4 10.160.3.55

s=SIP Call

c=IN IP4 10.160.3.55

t=0 0

m=audio 18124 RTP/AVP 0 19 101

c=IN IP4 10.160.3.55

a=rtpmap:0 PCMU/8000

a=rtpmap:19 CN/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

Aug 11 15:33:19.391: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:19.391: //1419/5B79869D89FB/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 11 15:33:19.391: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_CONNECTED(17)] {

Aug 11 15:33:19.391: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:19.391: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1419][LEG_OUTRINGING(9)][Cause(0)]

Aug 11 15:33:19.391: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 11 15:33:19.391: //1419/5B79869D89FB/SSIN:/AFW_SS_MapEvent:

Aug 11 15:33:19.391: //-1//SSIN:/AFW_SS_SIP_MapEvent:

Aug 11 15:33:19.391: //1419/5B79869D89FB/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 17A type 1

Aug 11 15:33:19.391: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners:

Aug 11 15:33:19.395: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:19.395: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_6917FADC_0_604387712] (

Aug 11 15:33:19.395: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1419][LEG_OUTCONNECTED(10)][Cause(0)]

Aug 11 15:33:19.395: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 11 15:33:19.395: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:19.395: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination

Aug 11 15:33:19.395: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_6917FADC_0_604387712

Aug 11 15:33:19.395: //-1//Dest:EE69285A18000:/AFW_M_Destination_Action:

Aug 11 15:33:19.395: //-1//Dest:EE69285A18000:/AFW_Destination_Action:

Aug 11 15:33:19.395: //-1//Dest:EE69285A18000:/OB_FSM_Drive:

Aug 11 15:33:19.395: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:19.395: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_connected[17])---[C_Action]------

Aug 11 15:33:19.395: //-1//Dest:EE69285A18000:/OB_Setting_Connect:

Aug 11 15:33:19.395: //-1//Dest:/OB_Setting_Connect: cData.display_info=Ext. twozerothree

Aug 11 15:33:19.395: //1419/5B79869D89FB/AFW_:/AFW_Util_FillDispNotifyData: Current: notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 11 15:33:19.395: //1419/5B79869D89FB/AFW_:/AFW_Util_FillDispNotifyData: Filled : notifyData.connected_num.num_string =203,notifyData.display_info = Ext. twozerothree,notifyData.connected_num.oct3a = 0

Aug 11 15:33:19.395: //-1//Dest:EE69285A18000:/DestNotifySharedLnState: callid: 1419, peer: 200

Aug 11 15:33:19.395: //-1//Dest:EE69285A18000:/DestNotifySharedLnState: Shared-Line NOT enabled!

Aug 11 15:33:19.395: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_DONE[8])---------------

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> Destination_6917FADC_0_604387712

Aug 11 15:33:19.399: //-1//Dest:EE69285A18000:/IW_FSM_Drive:

Aug 11 15:33:19.399: //-1//Dest:EE69285A18000:/IW_FSM_Drive: No interworking leg, no need to interwork event

Aug 11 15:33:19.399: //-1//Dest:EE69285A18000:/DestIsEventNotified:

Aug 11 15:33:19.399: //-1//Dest:EE69285A18000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_DONE

Aug 11 15:33:19.399: //-1//AFW_:/AFW_Event_New: Event ID: ev_destination_done

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_Module_ReturnArgEv:

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[1419 ][LEG_OUTCONNECTED(10)][Cause(0)]}

Aug 11 15:33:19.399: //1419/5B79869D89FB/AFW_:/AFW_Object_RemoveListener:

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_Module_UnListen: NumObjects: 0

Aug 11 15:33:19.399: //-1//Dest:EE69285A18000:/DestReturn: Destination Returning(ds_000 Status DEST_ACTIVE)

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: Destination_6917FADC_0_604387712 ---> NULL

Aug 11 15:33:19.399: //1419/5B79869D89FB/AFW_:/AFW_M_Event_Free:

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

Aug 11 15:33:19.399: //-1//AFW_:EE69285A18000:/AFW_Process_GetPriorityQEvent: Received

Aug 11 15:33:19.399: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_DESTINATION_DONE(221)] {

Aug 11 15:33:19.399: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:19.399: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[Destination_6917FADC_0_604387712] (

Aug 11 15:33:19.399: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )

Aug 11 15:33:19.403: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }

Aug 11 15:33:19.403: //-1//Dest:EE69285A18000:/AFW_M_Destination_EventPreProcess:

Aug 11 15:33:19.403: //1419/5B79869D89FB/Dest:/DestUpdateSuccessStats:

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_Object_WalkListeners:

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:19.403: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[CallSetup_69149850_0_604387708] (

Aug 11 15:33:19.403: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_6917FADC_0_604387712] (

Aug 11 15:33:19.403: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_Object_WalkListeners: Entering Module : CallSetup

Aug 11 15:33:19.403: //1419/5B79869D89FB/AFW_:/AFW_Object_AddListener: adding Module CallSetup as listener

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_Object_RemoveListener:

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_Module_UnListen: NumObjects: 1

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: NULL ---> CallSetup_69149850_0_604387708

Aug 11 15:33:19.403: //-1//Call:EE69285A18000:/AFW_M_CallSetup_Action:

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION BEGIN: ------(S_PLACECALL[2],ev_destination_done[221])---[C_Action]------

Aug 11 15:33:19.403: //-1//Call:EE69285A18000:/CS_Placecall_DestDone:

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION END: -------------(S_DONE[13])---------------

Aug 11 15:33:19.403: //-1//AFW_:/AFW_Event_New: Event ID: ev_setup_done

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_Module_ReturnArgEv:

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_Module_ReturnArgEv: Return List (remove=TRUE){LEG[1419 ][LEG_OUTCONNECTED(10)][Cause(0)]}

Aug 11 15:33:19.403: //1419/5B79869D89FB/AFW_:/AFW_Object_RemoveListener:

Aug 11 15:33:19.403: //-1//AFW_:EE69285A18000:/AFW_Module_UnListen: NumObjects: 0

Aug 11 15:33:19.403: //-1//Call:EE69285A18000:/CS_Complete: CallSetup Returning(ls_000 Status CS_ACTIVE)

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: CallSetup_69149850_0_604387708 ---> NULL

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_Object_WalkListeners:

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_M_Event_Free:

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_M_Event_Free: MODULEDONEEVENT for a Module: Destination_6917FADC_0_604387712

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_Object_RemoveAllListener:

Aug 11 15:33:19.407: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 3, CmdPending 3

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_DecrNPendingCmd: PendingCmdCount: 2

Aug 11 15:33:19.407: //-1//AFW_:HN24063980:/AFW_M_Event_Free: ExecEnv objCount: 3

Aug 11 15:33:19.407: //-1//Dest:HN24063980:/AFW_M_Destination_Free:

Aug 11 15:33:19.407: //-1//Dest:HN24063980:/DestEmptyPreemptHoldQ:

Aug 11 15:33:19.407: //-1//AFW_:/AFW_M_FSM_Free:

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 2

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_Process_GetPriorityQEvent: Received

Aug 11 15:33:19.407: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_CALLSETUP_DONE(219)] {

Aug 11 15:33:19.407: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:19.407: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[CallSetup_69149850_0_604387708] (

Aug 11 15:33:19.407: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )

Aug 11 15:33:19.407: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }

Aug 11 15:33:19.407: //-1//Call:EE69285A18000:/AFW_M_CallSetup_EventPreProcess:

Aug 11 15:33:19.407: //-1//AFW_:EE69285A18000:/AFW_Object_WalkListeners:

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:19.411: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_69F32B90_0_604387700] (

Aug 11 15:33:19.411: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[CallSetup_69149850_0_604387708] (

Aug 11 15:33:19.411: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_Object_WalkListeners: Entering Module : TclModule

Aug 11 15:33:19.411: //1419/5B79869D89FB/AFW_:/AFW_Object_AddListener: adding Module TclModule as listener

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_Object_RemoveListener:

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_Module_UnListen: NumObjects: 1

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_69F32B90_0_604387700

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_M_TclModule_Action:

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_TclModule_DefaultEvHandling:

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_TclModule_UpdateSessionMask: Update intwrk-mask for leg [1419]'s session

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION BEGIN: ------(I[1],ev_setup_done[219])---[setupDone]------

Aug 11 15:33:19.411: //-1//TCL :EE69285A18000:/tcl_InfotagObjCmd: infotag get evt_status

Aug 11 15:33:19.411: //-1//TCL :EE69285A18000:/tcl_InfotagGetObjCmd: infotag get evt_status

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/vtr_ev_status: argc 2 argindex 2

Aug 11 15:33:19.411: //-1//TCL :EE69285A18000:/tcl_PutsObjCmd: ls_000

Aug 11 15:33:19.411:

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: ACTION END: -------------(I[1])---------------

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32B90_0_604387700 ---> NULL

Aug 11 15:33:19.411: //-1//AFW_:EE69285A18000:/AFW_Object_WalkListeners:

Aug 11 15:33:19.415: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:19.415: //-1//AFW_:EE69285A18000:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:19.415: //-1//AFW_:EE69285A18000:/AFW_M_Event_Free:

Aug 11 15:33:19.415: //-1//AFW_:EE69285A18000:/AFW_M_Event_Free: MODULEDONEEVENT for a Module: CallSetup_69149850_0_604387708

Aug 11 15:33:19.415: //-1//AFW_:EE69285A18000:/AFW_Object_RemoveAllListener:

Aug 11 15:33:19.415: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 2, CmdPending 2

Aug 11 15:33:19.415: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_DecrNPendingCmd: PendingCmdCount: 1

Aug 11 15:33:19.415: //-1//AFW_:HN2406397C:/AFW_M_Event_Free: ExecEnv objCount: 2

Aug 11 15:33:19.415: //-1//Call:HN2406397C:/AFW_M_CallSetup_Free:

Aug 11 15:33:19.415: //-1//AFW_:/AFW_M_FSM_Free:

Aug 11 15:33:19.415: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1

Aug 11 15:33:19.415: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:19.415: //-1//AFW_:/AFW_M_Event_Free:

Aug 11 15:33:19.415: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL

Aug 11 15:33:19.415: //1419/5B79869D89FB/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:203@10.160.3.55:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK2E21420

From: <sip:10.160.3.56>;tag=240639A8-AAF

To: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

Date: Tue, 11 Aug 2015 13:33:16 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

Max-Forwards: 70

CSeq: 102 ACK

Allow-Events: telephone-event

Content-Length: 0

Aug 11 15:33:19.419: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:19.419: //-1//AFW_:/AFW_M_Event_Free:

Aug 11 15:33:19.419: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL

Aug 11 15:33:22.403: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

BYE sip:10.160.3.56:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.55:5060;branch=z9hG4bK40B6F8

From: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

To: <sip:10.160.3.56>;tag=240639A8-AAF

Date: Tue, 11 Aug 2015 13:33:19 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

User-Agent: Cisco-SIPGateway/IOS-12.x

Max-Forwards: 70

Timestamp: 1439300002

CSeq: 101 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=140,OS=22400,PR=0,OR=0,PL=0,JI=0,LA=0,DU=3

Content-Length: 0

Aug 11 15:33:22.407: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event

Aug 11 15:33:22.407: //1419/5B79869D89FB/AFW_:/AFW_Process_GetCcqEvent: Received

Aug 11 15:33:22.407: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_DISCONNECTED(20)] {

Aug 11 15:33:22.407: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285A18][test]

Aug 11 15:33:22.407: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1419][LEG_OUTCONNECTED(10)][Cause(0)]

Aug 11 15:33:22.407: //-1//AFW_:/AFW_Process_GetCcqEvent: }

Aug 11 15:33:22.407: //1419/5B79869D89FB/SSIN:/AFW_SS_MapEvent:

Aug 11 15:33:22.407: //-1//SSIN:/AFW_SS_SIP_MapEvent:

Aug 11 15:33:22.407: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners:

Aug 11 15:33:22.407: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: START

Aug 11 15:33:22.407: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[TclModule_69F32B90_0_604387700] (

Aug 11 15:33:22.407: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1419][LEG_OUTCONNECTED(10)][Cause(16)]

Aug 11 15:33:22.411: //-1//AFW_:/AFW_M_Object_ShowListeners: )

Aug 11 15:33:22.411: //1419/5B79869D89FB/AFW_:/AFW_M_Object_ShowListeners: END

Aug 11 15:33:22.411: //1419/5B79869D89FB/AFW_:/AFW_Object_WalkListeners: Entering Module : TclModule

Aug 11 15:33:22.411: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: NULL ---> TclModule_69F32B90_0_604387700

BWS-2801#

Aug 11 15:33:22.411: //-1//AFW_:EE69285A18000:/AFW_M_TclModule_Action:

Aug 11 15:33:22.411: //-1//AFW_:EE69285A18000:/AFW_TclModule_DefaultEvHandling:

Aug 11 15:33:22.411: //-1//AFW_:EE69285A18000:/AFW_FSM_Drive: FSM no match for (I[1],ev_disconnected[20])

Aug 11 15:33:22.411: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32B90_0_604387700 ---> NULL

Aug 11 15:33:22.411: //1419/5B79869D89FB/AFW_:/AFW_M_Event_Free:

Aug 11 15:33:22.411: //-1//AFW_:EE69285A18000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 1

Aug 11 15:33:22.903: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

BYE sip:10.160.3.56:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.55:5060;branch=z9hG4bK40B6F8

From: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

To: <sip:10.160.3.56>;tag=240639A8-AAF

Date: Tue, 11 Aug 2015 13:33:19 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

User-Agent: Cisco-SIPGateway/IOS-12.x

Max-Forwards: 70

Timestamp: 1439300002

CSeq: 101 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=140,OS=22400,PR=0,OR=0,PL=0,JI=0,LA=0,DU=3

Content-Length: 0

Aug 11 15:33:23.903: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

BYE sip:10.160.3.56:5060 SIP/2.0

Via: SIP/2.0/UDP 10.160.3.55:5060;branch=z9hG4bK40B6F8

From: <sip:202@10.160.3.55>;tag=23D53BA0-26A1

To: <sip:10.160.3.56>;tag=240639A8-AAF

Date: Tue, 11 Aug 2015 13:33:19 GMT

Call-ID: 5B7BF73D-3F6411E5-89FEC175-851B1C9E@10.160.3.56

User-Agent: Cisco-SIPGateway/IOS-12.x

Max-Forwards: 70

Timestamp: 1439300003

CSeq: 101 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=140,OS=22400,PR=0,OR=0,PL=0,JI=0,LA=0,DU=3

Content-Length: 0

Raghavendra G V · ‎08-11-2015

how did you do call forward, is your destination phone is IP phone?.

Thanks,

Raghavendra

paolo bevilacqua · ‎08-11-2015

Yes another IP phone. You can see below.

Can you test with latest version of 15.1(4) ? The one you are using has been deferred.

Aug 10 15:32:46.844: //1075/1F25020B87B0/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 302 Moved Temporarily

Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28E1BFA

From: <sip:10.160.3.56>;tag=1EDF4904-252

To: <sip:202@10.160.3.55>;tag=1EAE6A9C-E78

Date: Mon, 10 Aug 2015 13:32:46 GMT

Call-ID: 1F280ED2-3E9B11E5-87B3C175-851B1C9E@10.160.3.56

Timestamp: 1439213566

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Diversion: <sip:202@10.160.3.55>;reason=unconditional;counter=1

Contact: <sip:203@10.160.3.55>

Content-Length: 0

Raghavendra G V · ‎08-17-2015

Could you please explain about your call flow what you are trying.

Thanks,

Raghavendra

paolo bevilacqua · ‎08-17-2015

As explained before: Script calls a remote CME ephone that has set call forward.

Raghavendra G V · ‎11-11-2015

Raised Bug (CSCuw11988) for this issue.

Thanks,

Raghavendra