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.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I believe rerouteMode is correct, you can find below description in guide.
– rerouteMode—Possible values are: none / rotary / redirect / redirect_rotary. If not specified, the
value is same as mode. If both this argument and mode are not specified, the default value is
rotary. This parameter does not allow indexing.
You can also find in the logs as below.
*Aug 7 05:09:11.487: //-1//CSPK:/tclSetControlParams: mode(2) = REDIRECT
*Aug 7 05:09:11.487: //13//CSPK:/C_CallSetup_Start: ControlInfo = 0x7824F12C, callInfo = 0x7824F96C, destination[0]=3009
*Aug 7 05:09:11.487: //13//CSPK:/C_CallSetup_Start: configured reroutemode=rotary (1)
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Problem is, as indicated above, that setting rerouteMode to null has no effect. There is no indication to the script as the manual mentions. At least with IOS 15.14 that I've tried with.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
When you say no indication to script means, script did not get ev_setup_done event ?.
could you please share us the logs.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
You can use below infotag which Returns forwarding request information when a call is being forwarded.
infotag get evt_redirect_info {redirectDest | redirectReason | redirectCount | originalDest}
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
You can use below infotag which Returns forwarding request information when a call is being forwarded.
infotag get evt_redirect_info {redirectDest | redirectReason | redirectCount | originalDest}
Accessing this infotag on ev_setup_done always generate an error, either destination has CF set or not.
Aug 7 13:15:41.660: //768//AFW_:/AFW_FSM_Drive: Tcl_Eval to drive FSM inside Tcl modulespace. code=1 code=ERROR
Aug 7 13:15:41.660: TCL script failure
Result:
Aug 7 13:15:41.660: TCL script failure errorInfo:
while executing
"infotag get evt_redirect_info redirectDest"
As mentioned according to the documentation the script is supposed to receive ev_setup_done with a different status code to indicate destination has call forward. That doesn't happen.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I think you should get the status code as "ls_026" when the call is forwarded. What is the status code you got.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I think you should get the status code as "ls_026" when the call is forwarded. What is the status code you got.
As explained above already, the status is the one related to the final cal destination, for example answered, busy or no answer. Never ls_026.
Note that the destination gateways correctly sends "SIP moved temporarily" and the local router re--invites to the new destination. But the script is never notified.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
What is the status code you got when call is forwarded. Is it possible for you to share the logs.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Again fro my answer above:
As explained above already, the status is the one related to the final cal destination, for example answered, busy or no answer. Never ls_026.
Regarding logs they are nothing particular. Just have a script that calls a number with call forwarding and should see that callInfo(rerouteMode) has no effect.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I don't any issues, you can refer below H450 TCL script for rerouteMode Example.
set callInfo(rerouteMode) NONE
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
As mentioned before, I use SIP not H.323 on which H.450 is based.
With SIP, the script is not notified that the destination has set call transfer.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I did try with SIP only, script got notified with status ls_026 and able to get redirect Info.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
See below log where the script never receives ls_026, yet SIP has sent the redirection info, and the gateway acted on it.
IOS 15.1(4) which is the latest available for ISR routers.
This is the test script It is started with "call application session start xxx zzz"
proc sess {} {
set callInfo(rerouteMode) rotary
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
----------- LOG
Aug 10 14:01:37.039: //-1//SERV:/AFW_Service_GetExecEnv: Script Name = test
Aug 10 14:01:37.039: //-1//AFW_:/AFW_Process_New: test
Aug 10 14:01:37.039: //-1//AFW_:/AFW_ExecEnv_New:
Aug 10 14:01:37.039: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetRoot: Execenv = 0x69285BE8
Aug 10 14:01:37.039: //-1//AFW_:/AFW_TclModule_New:
Aug 10 14:01:37.039: //-1//AFW_:/AFW_Process_Lock: pProcess(0x690CCF4C)=1
Aug 10 14:01:37.043: //-1//AFW_:LP:EE69285BE8000:HN1E8BD0B4:/AFW_M_Object_SetExecEnv: ObjCount: 1, CmdPending 0
Aug 10 14:01:37.043: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetScript: Script Name = test,
Aug 10 14:01:37.043: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetScript: Script URI = ftp://10.0.2.1/test-callinfo.tcl
Aug 10 14:01:37.043: //-1//AFW_:EE69285BE8000:/Tcl_Link: Linking script test
Aug 10 14:01:37.047: //-1//ACPK:EE69285BE8000:/AppCommon_SetSecurity: security = undefined
Aug 10 14:01:37.047: //-1//ACPK:EE69285BE8000:/AppCommon_SetEventLog: event-log = unconfigured
Aug 10 14:01:37.047: //-1//TCL :EE69285BE8000:/tcl_FSMObjCmd: fsm define fsm I
Aug 10 14:01:37.051: //-1//AFW_:/AFW_FSM_New:
Aug 10 14:01:37.051: //-1//TCL :EE69285BE8000:/tcl_FSMDefineObjCmd: State Machine: Array fsm: Start State: I
Aug 10 14:01:37.051: //-1//TCL :EE69285BE8000:/tcl_FSMDefineObjCmd: FSM Data structure
Aug 10 14:01:37.051: (I(1), ev_session_indication(198)--(sess)-->(any_state(0))
Aug 10 14:01:37.051: (I(1), ev_setup_done(219)--(setupDone)-->(any_state(0))
Aug 10 14:01:37.051: FSM start state I(1)
Aug 10 14:01:37.051: //-1//AFW_:EE69285BE8000:/Tcl_Link: Script test succesfully linked.
Aug 10 14:01:37.051: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetScript: Num of packTable entries: 22
Aug 10 14:01:37.051: //-1//AFW_:/AFW_Event_New: Event ID: ev_session_indication
Aug 10 14:01:37.051: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_Initiate: Execenv = 0x69285BE8
Aug 10 14:01:37.051: //-1//SERV:/AFW_Service_Process_Space:
Aug 10 14:01:37.051: Process Started
Aug 10 14:01:37.055: //-1//AFW_:/AFW_Process_Register: ccAppInitialize(name: _ManagedAppProcess_test)
Aug 10 14:01:37.055: //-1//AFW_:EE69285BE8000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
Aug 10 14:01:37.055: //-1//AFW_:EE69285BE8000:/AFW_Process_GetAppQEvent: Received
Aug 10 14:01:37.055: //-1//AFW_:/AFW_Process_GetAppQEvent: Event[APP_EV_SESSION_START_IND(198)] {
Aug 10 14:01:37.055: //-1//AFW_:/AFW_Process_GetAppQEvent: EXECENV[0x69285BE8][test]
Aug 10 14:01:37.055: //-1//AFW_:/AFW_Process_GetAppQEvent:
Aug 10 14:01:37.055: //-1//AFW_:EE69285BE8000:/AFW_M_Module_GetHandle: Module handle: TclModule_69F32740_0_512479412MOD[TclModule_69F32740_0_512479412] (
Aug 10 14:01:37.055: //-1//AFW_:/AFW_Process_GetAppQEvent: )
Aug 10 14:01:37.059: //-1//AFW_:/AFW_Process_GetAppQEvent: }
Aug 10 14:01:37.059: //-1//AFW_:EE69285BE8000:/AFW_M_TclModule_EventPreProcess:
Aug 10 14:01:37.059: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32740_0_512479412 ---> TclModule_69F32740_0_512479412
Aug 10 14:01:37.059: //-1//AFW_:EE69285BE8000:/AFW_M_TclModule_Action:
Aug 10 14:01:37.059: //-1//AFW_:EE69285BE8000:/AFW_TclModule_DefaultEvHandling:
Aug 10 14:01:37.059: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION BEGIN: ------(I[1],ev_session_indication[198])---[sess]------
Aug 10 14:01:37.059: //-1//TCL :EE69285BE8000:/tcl_LegObjCmd: leg setup 202 callinfo
Aug 10 14:01:37.059: //-1//CSPK:EE69285BE8000:/tcl_LegSetupObjCmd: leg setup 202 callinfo
Aug 10 14:01:37.059: //-1//CSPK:EE69285BE8000:/C_CallSetup_Start: ControlInfo = 0x69F1CCEC, callInfo = 0x69F5DCC8, destination[0]=202
Aug 10 14:01:37.059: //-1//CSPK:EE69285BE8000:/C_CallSetup_Start: configured mode=rotary (1)
Aug 10 14:01:37.059: //-1//CSPK:EE69285BE8000:/C_CallSetup_Start: configured reroutemode=rotary (1)
Aug 10 14:01:37.063: //-1//Call:/AFW_CallSetup_New:
Aug 10 14:01:37.063: //-1//AFW_:/AFW_FSM_New:
Aug 10 14:01:37.063: //-1//AFW_:LP:EE69285BE8000:HN1E8BD0CC:/AFW_M_Object_SetExecEnv: ObjCount: 2, CmdPending 0
Aug 10 14:01:37.063: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 1
Aug 10 14:01:37.063: //-1//AFW_:EE69285BE8000:/AFW_Object_AddListener: adding Module TclModule as listener
Aug 10 14:01:37.063: //-1//AFW_:EE69285BE8000:/AFW_M_Module_GetHandle: Module handle: CallSetup_69137538_0_512479436
Aug 10 14:01:37.063: //-1//Call:EE69285BE8000:/AFW_CallSetup_AddDest: 202 index 0
Aug 10 14:01:37.063: //-1//Call:EE69285BE8000:/AFW_M_CallSetup_Initiate:
Aug 10 14:01:37.063: //-1//Call:EE69285BE8000:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set
Aug 10 14:01:37.063: //-1//Call:EE69285BE8000:/CallSetupInitiate:
Aug 10 14:01:37.063: //-1//Call:EE69285BE8000:/CS_Placecall:
Aug 10 14:01:37.063: //-1//Call:EE69285BE8000:/CS_UpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] Preempt Hunt Not Set
Aug 10 14:01:37.063: //-1//Dest:/AFW_Destination_New:
Aug 10 14:01:37.063: //-1//AFW_:/AFW_FSM_New:
Aug 10 14:01:37.063: //-1//AFW_:/AFW_FSM_New:
Aug 10 14:01:37.063: //-1//AFW_:LP:EE69285BE8000:HN1E8BD0CC:/AFW_M_Object_SetExecEnv: ObjCount: 3, CmdPending 1
Aug 10 14:01:37.063: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 2
Aug 10 14:01:37.067: //-1//AFW_:EE69285BE8000:/AFW_Object_AddListener: adding Module CallSetup as listener
Aug 10 14:01:37.067: //-1//AFW_:EE69285BE8000:/AFW_M_Module_GetHandle: Module handle: Destination_69188FD4_0_512479436
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/AFW_Destination_AddDest:
Aug 10 14:01:37.067: //-1//Dest:/AFW_Destination_AddDest: adding destination "202"
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Initiate:
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Initiate: Outgoing guid : 62ECE0A7.3E8E11E5.87A8C175.851B1C9E
Aug 10 14:01:37.067: Incoming_guid : 00000000.00000000.00000000.00000000
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Initiate: Outgoing gcid : 00000000.00000000.00000000.00000000
Aug 10 14:01:37.067: Incoming_gcid : 00000000.00000000.00000000.00000000
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestUpdateMLPPInfo: ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)] Preempt Hunt Not Set
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestSetupInitiate:
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestAddRerouteSetupInfo:
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestAddTransferSetupInfo:
Aug 10 14:01:37.067: //-1//AFW_:/AFW_Util_CopyCallDataToCallInfo: copied 202
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestMatchDialPeer:
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestMatchDialPeer: src carrier id:, tgt carrier id:
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestQueuePeers: Matched peers:
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestPeerItemUpdatePreemptInfo:
Aug 10 14:01:37.067: //-1//Dest:EE69285BE8000:/DestPeerItemResetLPCORInfo:
Aug 10 14:01:37.067: //-1//Dest:/DestQueuePeers: 200
Aug 10 14:01:37.067: //-1//Dest:/DestQueuePeers: , Total(1)
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestDialPeerRotary:
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestOutboundCallUsingPeer: dest(202), numexpDest(202), numDestDigits(2)
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestOutboundCallUsingPeer: prefix(.55), peer tag(200)
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestUpdatePreemptSetupInfo:
Aug 10 14:01:37.071: //-1//AFW_:/AFW_Util_RemovePreemptInfoFromContainer: Failed to remove mlppSetupInfo from TD container
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestDialPeerFilter:
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestDialPeerFilter: Checking peer [200]
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestPeerFilterRouteCode: Route Code not available
Aug 10 14:01:37.071: //-1//Dest:/DestOutboundCallUsingPeer: Outgoing Tag(200) calling[] called[202]
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestCheckTeleUnassBusy:
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestHandleLPCOR:
Aug 10 14:01:37.071: //-1//Dest:EE69285BE8000:/DestSetup:
Aug 10 14:01:37.071: //-1//AFW_:/AFW_Leg_GetTypeDetail: ***ERROR*** pLeg is NULL
Aug 10 14:01:37.071: //-1//AFW_:/AFW_Util_SaveRawMsg:
Aug 10 14:01:37.071: //-1//AFW_:/AFW_Leg_New:
Aug 10 14:01:37.071: //1073/62ECE0A787A8/AFW_:/AFW_Leg_NewSetupRequest: ccCallSetupRequest done
Aug 10 14:01:37.071: //1073/62ECE0A787A8/AFW_:/AFW_M_Leg_SetExecEnv:
Aug 10 14:01:37.075: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_IncrPendingCmd: PendingCmdCount: 3
Aug 10 14:01:37.075: //-1/62ECE0A787A8/AFW_:LP:EE69285BE8000:LG1073:/AFW_M_Object_SetExecEnv: ObjCount: 4, CmdPending 3
Aug 10 14:01:37.075: //1073/62ECE0A787A8/AFW_:/AFW_Object_AddListener: adding Module Destination as listener
Aug 10 14:01:37.075: //1073/62ECE0A787A8/AFW_:/AFW_M_Leg_GetHandle: Leg handle: LEG_1073
Aug 10 14:01:37.075: //1073/62ECE0A787A8/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 12A type 3
Aug 10 14:01:37.075: //-1//Dest:EE69285BE8000:/DestSetup: Destination setup success
Aug 10 14:01:37.075: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Initiate: attempting call to destination 202
Aug 10 14:01:37.075: //-1//Call:EE69285BE8000:/CS_Placecall: Call placed to 202 index 0
Aug 10 14:01:37.075: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION END: -------------(I[1])---------------
Aug 10 14:01:37.075: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32740_0_512479412 ---> TclModule_69F32740_0_512479412
Aug 10 14:01:37.075: //-1//AFW_:EE69285BE8000:/AFW_M_Event_Free:
Aug 10 14:01:37.075: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
Aug 10 14:01:37.075: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
Aug 10 14:01:37.075: //-1//AFW_:/AFW_M_Event_Free:
Aug 10 14:01:37.075: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL
Aug 10 14:01:37.083: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
Aug 10 14:01:37.083: //1073/62ECE0A787A8/AFW_:/AFW_Process_GetCcqEvent: Received
Aug 10 14:01:37.087: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_PROCEEDING(31)] {
Aug 10 14:01:37.087: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285BE8][test]
Aug 10 14:01:37.087: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1073][LEG_OUTINIT(7)][Cause(0)]
Aug 10 14:01:37.087: //-1//AFW_:/AFW_Process_GetCcqEvent: }
Aug 10 14:01:37.087: //1073/62ECE0A787A8/SSIN:/AFW_SS_MapEvent:
Aug 10 14:01:37.087: //-1//SSIN:/AFW_SS_SIP_MapEvent:
Aug 10 14:01:37.087: //1073/62ECE0A787A8/AFW_:/AFW_M_Leg_EventPreProcess: LEG[1073 ][LEG_OUTINIT(7)][Cause(0)]
Aug 10 14:01:37.087: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners:
Aug 10 14:01:37.087: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: START
Aug 10 14:01:37.087: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69188FD4_0_512479436] (
Aug 10 14:01:37.087: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1073][LEG_OUTINIT(7)][Cause(0)]
Aug 10 14:01:37.087: //-1//AFW_:/AFW_M_Object_ShowListeners: )
Aug 10 14:01:37.087: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: END
Aug 10 14:01:37.087: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination
Aug 10 14:01:37.087: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: TclModule_69F32740_0_512479412 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Action:
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/AFW_Destination_Action:
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/OB_FSM_Drive:
Aug 10 14:01:37.087: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.087: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_proceeding[31])---[C_Action]------
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/OB_Setting_Proceeding:
Aug 10 14:01:37.087: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------
Aug 10 14:01:37.087: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/IW_FSM_Drive:
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/IW_FSM_Drive: No interworking leg, no need to interwork event
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/DestIsEventNotified:
Aug 10 14:01:37.087: //-1//Dest:EE69285BE8000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING
Aug 10 14:01:37.087: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> TclModule_69F32740_0_512479412
Aug 10 14:01:37.091: //1073/62ECE0A787A8/AFW_:/AFW_M_Event_Free:
Aug 10 14:01:37.091: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
Aug 10 14:01:37.091: //1073/62ECE0A787A8/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=z9hG4bK28824B4
From: <sip:10.160.3.56>;tag=1E8BD0E0-387
To: <sip:202@10.160.3.55>
Date: Mon, 10 Aug 2015 12:01:37 GMT
Call-ID: 62EF51DF-3E8E11E5-87ABC175-851B1C9E@10.160.3.56
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 1659691175-1049498085-2275983733-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: 1439208097
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 4364 1368 IN IP4 10.160.3.56
s=SIP Call
c=IN IP4 10.160.3.56
t=0 0
m=audio 17608 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 14:01:37.103: //1073/62ECE0A787A8/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28824B4
From: <sip:10.160.3.56>;tag=1E8BD0E0-387
To: <sip:202@10.160.3.55>
Date: Mon, 10 Aug 2015 12:01:37 GMT
Call-ID: 62EF51DF-3E8E11E5-87ABC175-851B1C9E@10.160.3.56
Timestamp: 1439208097
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0
Aug 10 14:01:37.103: //1073/62ECE0A787A8/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 302 Moved Temporarily
Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28824B4
From: <sip:10.160.3.56>;tag=1E8BD0E0-387
To: <sip:202@10.160.3.55>;tag=1E5AF478-22BB
Date: Mon, 10 Aug 2015 12:01:37 GMT
Call-ID: 62EF51DF-3E8E11E5-87ABC175-851B1C9E@10.160.3.56
Timestamp: 1439208097
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 14:01:37.111: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
Aug 10 14:01:37.111: //1073/62ECE0A787A8/AFW_:/AFW_Process_GetCcqEvent: Received
Aug 10 14:01:37.111: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_FEATURE(38)] {
Aug 10 14:01:37.111: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285BE8][test]
Aug 10 14:01:37.111: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1073][LEG_OUTPROCEED(8)][Cause(0)]
Aug 10 14:01:37.111: //-1//AFW_:/AFW_Process_GetCcqEvent: }
Aug 10 14:01:37.111: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
Aug 10 14:01:37.111: //1073/62ECE0A787A8/AFW_:/AFW_M_Leg_EventPreProcess: LEG[1073 ][LEG_OUTPROCEED(8)][Cause(0)]
Aug 10 14:01:37.111: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners:
Aug 10 14:01:37.111: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: START
Aug 10 14:01:37.111: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69188FD4_0_512479436] (
Aug 10 14:01:37.111: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1073][LEG_OUTPROCEED(8)][Cause(0)]
Aug 10 14:01:37.111: //-1//AFW_:/AFW_M_Object_ShowListeners: )
Aug 10 14:01:37.111: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: END
Aug 10 14:01:37.111: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination
Aug 10 14:01:37.111: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.111: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Action:
Aug 10 14:01:37.115: //-1//Dest:EE69285BE8000:/AFW_Destination_Action:
Aug 10 14:01:37.115: //-1//Dest:EE69285BE8000:/OB_FSM_Drive:
Aug 10 14:01:37.115: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.115: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_feature[38])---[C_Action]------
Aug 10 14:01:37.115: //-1//Dest:EE69285BE8000:/OB_Ignore: IGNORING EVENT
Aug 10 14:01:37.115: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_SETTING[3])---------------
Aug 10 14:01:37.115: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.115: //-1//Dest:EE69285BE8000:/IW_FSM_Drive:
Aug 10 14:01:37.115: //-1//Dest:EE69285BE8000:/IW_FSM_Drive: No interworking leg, no need to interwork event
Aug 10 14:01:37.115: //-1//Dest:EE69285BE8000:/DestIsEventNotified:
Aug 10 14:01:37.115: //-1//Dest:EE69285BE8000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_SETTING
Aug 10 14:01:37.115: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> NULL
Aug 10 14:01:37.115: //1073/62ECE0A787A8/AFW_:/AFW_M_Event_Free:
Aug 10 14:01:37.115: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
Aug 10 14:01:37.115: //1073/62ECE0A787A8/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=z9hG4bK28824B4
From: <sip:10.160.3.56>;tag=1E8BD0E0-387
To: <sip:202@10.160.3.55>;tag=1E5AF478-22BB
Date: Mon, 10 Aug 2015 12:01:37 GMT
Call-ID: 62EF51DF-3E8E11E5-87ABC175-851B1C9E@10.160.3.56
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0
Aug 10 14:01:37.119: //1073/62ECE0A787A8/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=z9hG4bK28912FF
From: <sip:10.160.3.56>;tag=1E8BD0F8-21D
To: <sip:202@10.160.3.55>
Date: Mon, 10 Aug 2015 12:01:37 GMT
Call-ID: 62EF51DF-3E8E11E5-87ABC175-851B1C9E@10.160.3.56
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 1659691175-1049498085-2275983733-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: 1439208097
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 4364 1368 IN IP4 10.160.3.56
s=SIP Call
c=IN IP4 10.160.3.56
t=0 0
m=audio 17608 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 14:01:37.131: //1073/62ECE0A787A8/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28912FF
From: <sip:10.160.3.56>;tag=1E8BD0F8-21D
To: <sip:202@10.160.3.55>
Date: Mon, 10 Aug 2015 12:01:37 GMT
Call-ID: 62EF51DF-3E8E11E5-87ABC175-851B1C9E@10.160.3.56
Timestamp: 1439208097
CSeq: 102 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0
Aug 10 14:01:37.131: //1073/62ECE0A787A8/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 10.160.3.56:5060;branch=z9hG4bK28912FF
From: <sip:10.160.3.56>;tag=1E8BD0F8-21D
To: <sip:202@10.160.3.55>;tag=1E5AF494-12AB
Date: Mon, 10 Aug 2015 12:01:37 GMT
Call-ID: 62EF51DF-3E8E11E5-87ABC175-851B1C9E@10.160.3.56
Timestamp: 1439208097
CSeq: 102 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Diversion: <sip:203@10.160.3.55>;reason=unconditional;counter=2
Diversion: <sip:202@10.160.3.55>;reason=unconditional;counter=1
Content-Length: 0
Aug 10 14:01:37.135: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
Aug 10 14:01:37.135: //1073/62ECE0A787A8/AFW_:/AFW_Process_GetCcqEvent: Received
Aug 10 14:01:37.135: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_DISCONNECTED(20)] {
Aug 10 14:01:37.135: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285BE8][test]
Aug 10 14:01:37.135: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1073][LEG_OUTPROCEED(8)][Cause(0)]
Aug 10 14:01:37.135: //-1//AFW_:/AFW_Process_GetCcqEvent: }
Aug 10 14:01:37.135: //1073/62ECE0A787A8/SSIN:/AFW_SS_MapEvent:
Aug 10 14:01:37.135: //-1//SSIN:/AFW_SS_SIP_MapEvent:
Aug 10 14:01:37.135: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners:
Aug 10 14:01:37.135: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: START
Aug 10 14:01:37.135: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69188FD4_0_512479436] (
Aug 10 14:01:37.135: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1073][LEG_OUTPROCEED(8)][Cause(63)]
Aug 10 14:01:37.139: //-1//AFW_:/AFW_M_Object_ShowListeners: )
Aug 10 14:01:37.139: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: END
Aug 10 14:01:37.139: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination
Aug 10 14:01:37.139: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Action:
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/AFW_Destination_Action:
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/OB_FSM_Drive:
Aug 10 14:01:37.139: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.139: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_SETTING[3],ev_disconnected[20])---[C_Action]------
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/OB_Setting_Disconnected:
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/OBDiscContinue:
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/resetAppAlertTimer:
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/Dest_AAA_CheckAccounting:
Aug 10 14:01:37.139: //-1//Dest:EE69285BE8000:/Dest_AAA_CheckAccounting: accounting to be enabled for callID=1073
Aug 10 14:01:37.139: //1073/62ECE0A787A8/AFW_:/AFW_Leg_Disconnect: Disconnecting Leg: LEG_1073
Aug 10 14:01:37.139: //1073/62ECE0A787A8/AFW_:/AFW_Leg_Disconnect: Disconnecting Leg: LEG_1073, Cause 63
Aug 10 14:01:37.139: //1073/62ECE0A787A8/AFW_:/AFW_Leg_UpdateStats: Updating stats for ID 12A type 2
Aug 10 14:01:37.139: //-1//Dest:/DestStatusFromDiscCause: mapped "service or option not available unspecified (63)"(63) to DEST_NUMBER_BLOCKED(18)
Aug 10 14:01:37.143: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_DISCONNECTING[6])---------------
Aug 10 14:01:37.143: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.143: //-1//Dest:EE69285BE8000:/IW_FSM_Drive:
Aug 10 14:01:37.143: //-1//Dest:EE69285BE8000:/IW_FSM_Drive: No interworking leg, no need to interwork event
Aug 10 14:01:37.143: //-1//Dest:EE69285BE8000:/DestIsEventNotified:
Aug 10 14:01:37.143: //-1//Dest:EE69285BE8000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_DISCONNECTING
Aug 10 14:01:37.143: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> NULL
Aug 10 14:01:37.143: //1073/62ECE0A787A8/AFW_:/AFW_M_Event_Free:
Aug 10 14:01:37.143: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 3
Aug 10 14:01:37.147: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
Aug 10 14:01:37.147: //1073/62ECE0A787A8/AFW_:/AFW_Process_GetCcqEvent: Received
Aug 10 14:01:37.147: //-1//AFW_:/AFW_Process_GetCcqEvent: Event[CC_EV_CALL_DISCONNECT_DONE(21)] {
Aug 10 14:01:37.147: //-1//AFW_:/AFW_Process_GetCcqEvent: EXECENV[0x69285BE8][test]
Aug 10 14:01:37.147: //-1//AFW_:/AFW_Process_GetCcqEvent: LEG[1073][LEG_OUTDISCONNECTING(11)][Cause(63)]
Aug 10 14:01:37.147: //-1//AFW_:/AFW_Process_GetCcqEvent: }
Aug 10 14:01:37.147: //-1//SSIN:/AFW_SS_MapEvent: No mapping required
Aug 10 14:01:37.147: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners:
Aug 10 14:01:37.147: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: START
Aug 10 14:01:37.147: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69188FD4_0_512479436] (
Aug 10 14:01:37.147: //-1//AFW_:/AFW_M_Object_ShowListeners: LEG[1073][LEG_DISCONNECTED(12)][Cause(63)]
Aug 10 14:01:37.147: //-1//AFW_:/AFW_M_Object_ShowListeners: )
Aug 10 14:01:37.147: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: END
Aug 10 14:01:37.147: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners: Entering Module : Destination
Aug 10 14:01:37.147: //1073/62ECE0A787A8/AFW_:/AFW_Object_RemoveListener:
Aug 10 14:01:37.147: //-1//AFW_:EE69285BE8000:/AFW_Module_UnListen: NumObjects: 0
Aug 10 14:01:37.147: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: NULL ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.147: //-1//Dest:EE69285BE8000:/AFW_M_Destination_Action:
Aug 10 14:01:37.147: //-1//Dest:EE69285BE8000:/AFW_Destination_Action:
Aug 10 14:01:37.147: //-1//Dest:EE69285BE8000:/OB_FSM_Drive:
Aug 10 14:01:37.147: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.151: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION BEGIN: ------(OB_STATE_DISCONNECTING[6],ev_disconnect_done[21])---[C_Action]------
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/OB_Disconnecting_DiscDone:
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/DestDialPeerRotary:
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/OB_Disconnecting_DiscDone: no more dialpeers or all dialpeers failed
Aug 10 14:01:37.151: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION END: -------------(OB_STATE_DONE[8])---------------
Aug 10 14:01:37.151: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> Destination_69188FD4_0_512479436
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/IW_FSM_Drive:
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/IW_FSM_Drive: No interworking leg, no need to interwork event
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/DestIsEventNotified:
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/DestComplete: peer #:200 IW State IW_STATE_INIT, OB State OB_STATE_DONE
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/DestUpdateMLPPCause: status=18, cause=63, prUserStat=0, prTrunkStat=0
Aug 10 14:01:37.151: //-1//AFW_:/AFW_Event_New: Event ID: ev_destination_done
Aug 10 14:01:37.151: //-1//AFW_:EE69285BE8000:/AFW_Module_ReturnArgEv:
Aug 10 14:01:37.151: //-1//Dest:EE69285BE8000:/DestReturn: Destination Returning(ds_018 Status DEST_NUMBER_BLOCKED)
Aug 10 14:01:37.151: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: Destination_69188FD4_0_512479436 ---> NULL
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_Object_WalkListeners:
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: START
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_M_Object_ShowListeners: END
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_M_Event_Free:
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_M_Event_Free: CC_EV_CALL_DISCONNECT_DONE for a Leg: LEG_1073
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_Object_RemoveAllListener:
Aug 10 14:01:37.151: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_DecrNPendingCmd: PendingCmdCount: 2
Aug 10 14:01:37.151: //-1//AFW_:/AFW_M_Object_UnSetExecEnv: ObjCount: 3, CmdPending 2
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_M_Event_Free: ExecEnv objCount: 3
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_M_Leg_Free:
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/AFW_M_Leg_Free: LEG[1073 ][LEG_DISCONNECTED(12)][Cause(63)]
Aug 10 14:01:37.151: //1073/62ECE0A787A8/AFW_:/decrementDialPeerCallStats: Decrementing call-stat to [0], dial-peer [200]
Aug 10 14:01:37.151: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_CloseIfDone: Cmd Count: 2
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_Event_IsOk: AppEventOk Success - timestamp MATCHED
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_Process_GetPriorityQEvent: Received
Aug 10 14:01:37.155: //-1//AFW_:/AFW_Process_GetPriorityQEvent: Event[APP_EV_DESTINATION_DONE(221)] {
Aug 10 14:01:37.155: //-1//AFW_:/AFW_Process_GetPriorityQEvent: EXECENV[0x69285BE8][test]
Aug 10 14:01:37.155: //-1//AFW_:/AFW_Process_GetPriorityQEvent: MOD[Destination_69188FD4_0_512479436] (
Aug 10 14:01:37.155: //-1//AFW_:/AFW_Process_GetPriorityQEvent: )
Aug 10 14:01:37.155: //-1//AFW_:/AFW_Process_GetPriorityQEvent: }
Aug 10 14:01:37.155: //-1//Dest:EE69285BE8000:/AFW_M_Destination_EventPreProcess:
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_Object_WalkListeners:
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_M_Object_ShowListeners: START
Aug 10 14:01:37.155: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[CallSetup_69137538_0_512479436] (
Aug 10 14:01:37.155: //-1//AFW_:/AFW_M_Object_ShowListeners: MOD[Destination_69188FD4_0_512479436] (
Aug 10 14:01:37.155: //-1//AFW_:/AFW_M_Object_ShowListeners: )
Aug 10 14:01:37.155: //-1//AFW_:/AFW_M_Object_ShowListeners: )
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_M_Object_ShowListeners: END
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_Object_WalkListeners: Entering Module : CallSetup
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_Object_RemoveListener:
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_Module_UnListen: NumObjects: 0
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_ExecEnv_SetModuleScope: NULL ---> CallSetup_69137538_0_512479436
Aug 10 14:01:37.155: //-1//Call:EE69285BE8000:/AFW_M_CallSetup_Action:
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION BEGIN: ------(S_PLACECALL[2],ev_destination_done[221])---[C_Action]------
Aug 10 14:01:37.155: //-1//Call:EE69285BE8000:/CS_Placecall_DestDone:
Aug 10 14:01:37.155: //-1//Call:EE69285BE8000:/CS_DestDone_CapturePreemptStatus: PreemptErrorCode 0; Play prompt none
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_FSM_Drive: ACTION END: -------------(S_DONE[13])---------------
Aug 10 14:01:37.155: //-1//AFW_:/AFW_Event_New: Event ID: ev_setup_done
Aug 10 14:01:37.155: //-1//AFW_:EE69285BE8000:/AFW_Module_ReturnArgEv:
Aug 10 14:01:37.159: //-1//Call:EE69285BE8000:/CS_Complete:
Aug 10 14:01:37.175: //-1//AFW_:/AFW_Event_New: Event ID: ev_any_event
Aug 10 14:01:37.175: //-1//AFW_:/AFW_M_Event_Free:
Aug 10 14:01:37.175: //-1//AFW_:/AFW_M_Event_Free: Null or invalid event context: for event APP_EV_NULL
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
You need to use as below.
proc sess {} {
set callInfo(rerouteMode) NONE
leg setup 202 callInfo
}
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
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_:/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_:/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_:/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_:/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
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
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
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Which IOS version are you using? Can you try 15.2(4)M9 ?
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I tried in 151-4.M1.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
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.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Issue is not with IOS, you need to change the script as mentioned in my above posts.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
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
Copyright (c) 1986-2014 by Cisco Systems, Inc.
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
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_:/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_:/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_:/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_:/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
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
how did you do call forward, is your destination phone is IP phone?.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
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
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Could you please explain about your call flow what you are trying.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
As explained before: Script calls a remote CME ephone that has set call forward.
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Raised Bug (CSCuw11988) for this issue.
Thanks,
Raghavendra
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Comments
0 comments
Please sign in to leave a comment.