Jtapi events error with TServer 7.6 and Cisco Call Manager 6

jumpup · July 1, 2009, 7:48am

Hi all,

When an agent connected to the Cisco CallManager 6 (CCM6) tries to call to another agent logged on CCM6, the call goes through a routing point (in XX) on which a routing strategy is loaded to send the call to a Genesys agent.
the strategy goes fine.
When the call arrives on the agent physical phone, the physical phone rings but the agent desktop does an automatic answer (as if he had received the EventEstablished) but the physical phone is still ringing.

This problem does not occur :

When agents call without routing point
When we call from an outside number

If you have any idea…

Thanks in advance.

Best regards

JumpUp

Rene · July 1, 2009, 4:00pm

Hi,

Could you post here log of TServer (debug level) covering “problematic” call?

R.

jumpup · July 2, 2009, 3:06pm

Hi René,

There is an issue with attach files on the forum, if you can send me your email with private message I can send it directly to you.

For your help:
Caller agent A extension: 299107
Called agent B extension: 299109
Routing point XX: 299109 forward to routing point : 299748 where the strategy is loaded

In addition:
I’de open case on genesys and cisco side and genesys said it is a problem on cisco side…

Thanks for your help.

Best regards

jumpup · July 2, 2009, 3:09pm

In fact following the logs:

16:52:31.398 Int 04543 Interaction message “RequestRouteCall” received from 484 (“UniversalRoutingServer”)
Trying to reserve dn: <299109> agent: <299109> place: <S99_Place_299109> duration: 15000
Reservation request taken from client 0x00000005(UniversalRoutingServer)
@16:52:31.3980 [gctmi] Distributing request RequestUpdateUserData
@16:52:31.3980 [tout] Call [019901b45634d009/40013e4,sOG,tOG,l2] (reqChangeUserData)
@16:52:31.3980 [gctmi] request RequestUpdateUserData removed from reqMgr
@16:52:31.3980 [gctm] Sending queued request RequestUpdateUserData
@16:52:31.3980 [gctmi] Distributing request RequestUpdateUserData
@16:52:31.3980 [tout] Call [019901b45634d009/40013e4,sOG,tOG,l2] (reqChangeUserData)
@16:52:31.3980 [gctmi] request RequestUpdateUserData removed from reqMgr
Agent reservation: have 1 requests with priority [0, 0, 0, 0]
Reservation granted to client 0x00000005
@16:52:31.5070 [0] 7.6.007.01 distribute_event_except_one: message EventAgentReserved
AttributeTimeinuSecs 507000
AttributeTimeinSecs 1246546351 (16:52:31)
AttributeReason [14] 00 01 01 00..
‘RTR’ 110
AttributeThisDN ‘299109’
AttributeAgentID ‘299109’
AttributePlace ‘S99_Place_299109’
AttributeTimeout 15000
AttributeEventSequenceNumber 00000000000001c9
@16:52:31.5070 [ISCC] Translate: ‘’ → ‘’; result 1 ()
16:52:31.507 Trc 04542 EventAgentReserved sent to [368] (0000000b StatServer_CCP 10.110.130.201:3167)
16:52:31.507 Trc 04542 EventAgentReserved sent to [508] (0000000a - SPv7203ST 10.110.153.82:2367)
16:52:31.507 Trc 04542 EventAgentReserved sent to [520] (00000008 StatServer_CCA 10.110.130.203:4224)
16:52:31.507 Trc 04542 EventAgentReserved sent to [392] (00000002 StatServer_GIS 10.110.130.203:4223)
16:52:31.507 Trc 04542 EventAgentReserved sent to [472] (00000004 StatServer_URS 10.110.130.202:4253)
@16:52:31.5070 [gctmi] Distributing request RequestRouteCall
@16:52:31.5070 [tout] Party [019901b45634d009:299748,s800,tRP,rDST,lINT] (reqRouteCall)
@16:52:31.5070 [tout] CcmRouteParty(299748:019901b45634d009) try routing call to 299109, _state:IDLE
@16:52:31.5070 [sm] CcmRouteParty::set_state: IDLE → REDIRECTING
@16:52:31.5070 [tout] Routing call NOW to 299109, _state:REDIRECTING
@16:52:31.5070 [))1] Redirect 67113956 ‘299748’ ‘299109’ 1 2 0 ‘’

@16:52:31.5070 [((1] 1 105 S 9 ‘JSend>> redirect for party 299748:67113956. dest-opts:299109 - mode:1 css:2 cao:0 origCalledParty:’
@16:52:31.5070 [((1] 1 53 S 9 ‘getConnection for CallID:67113956 Party:299748’
@16:52:31.5070 [((1] 1 33 S 9 ‘ConnectionCallID: 67113956’
@16:52:31.5230 [((1] 1 178 S 9 ‘CallEv(0)=> Type=ConnCreatedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_ADDITIONAL_PARTY Addr=299109 ConnReason=REASON_REDIRECT’
@16:52:31.5230 [((1] 1 14 I 32 67113956
@16:52:31.5230 [tout] Collected jtapi[1] tevent(20:CREATE_CALL)( (40013e4:0) (:0).
@16:52:31.5230 [((1] 1 140 S 9 ‘CallEv(5)=> Type=CallActiveEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(true)=META_CALL_STARTING’
@16:52:31.5230 [((1] 1 178 S 9 ‘CallEv(6)=> Type=ConnCreatedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_ADDITIONAL_PARTY Addr=299109 ConnReason=REASON_REDIRECT’
@16:52:31.5230 [((1] 1 14 I 32 67113956
@16:52:31.5230 [tout] Collected jtapi[1] tevent(20:CREATE_CALL)( (40013e4:0) (:0).
@16:52:31.5230 [((1] 1 155 S 9 ‘CallEv(0)=> Type=ConnInprogressEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_ADDITIONAL_PARTY Addr=299109’
@16:52:31.5230 [((1] 1 183 S 9 ‘CallEv(5)=> Type=ConnCreatedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299107 ConnReason=REASON_DIRECTCALL’
@16:52:31.5230 [((1] 1 14 I 32 67113956
@16:52:31.5230 [tout] Collected jtapi[1] tevent(20:CREATE_CALL)( (40013e4:0) (:0).
@16:52:31.5230 [((1] 1 155 S 9 ‘CallEv(6)=> Type=ConnInprogressEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_ADDITIONAL_PARTY Addr=299109’
@16:52:31.5230 [((1] 1 160 S 9 ‘CallEv(0)=> Type=CallCtlConnOfferedEv Cause=CAUSE_REDIRECTED CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_ADDITIONAL_PARTY Addr=299109’
@16:52:31.5230 [((1] 1 156 S 9 ‘CallEv(5)=> Type=ConnConnectedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299107’
@16:52:31.5230 [((1] 1 160 S 9 ‘CallEv(6)=> Type=CallCtlConnOfferedEv Cause=CAUSE_REDIRECTED CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_ADDITIONAL_PARTY Addr=299109’
@16:52:31.5230 [((1] 1 162 S 9 ‘CallEv(5)=> Type=CallCtlConnEstablishedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299107’
@16:52:31.5230 [((1] 1 179 S 9 ‘CallEv(5)=> Type=TermConnCreatedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299107 Term=SEP001DA2F3E526’
@16:52:31.5230 [((1] 1 178 S 9 ‘CallEv(5)=> Type=TermConnActiveEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299107 Term=SEP001DA2F3E526’
@16:52:31.5230 [((1] 1 183 S 9 ‘CallEv(5)=> Type=CallCtlTermConnTalkingEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299107 Term=SEP001DA2F3E526’
@16:52:31.5230 [((1] 1 181 S 9 ‘CallEv(5)=> Type=ConnCreatedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299109 ConnReason=REASON_OUTBOUND’
@16:52:31.5230 [((1] 1 14 I 32 67113956
@16:52:31.5230 [tout] Collected jtapi[1] tevent(20:CREATE_CALL)( (40013e4:0) (:0).
@16:52:31.5230 [((1] 1 157 S 9 ‘CallEv(5)=> Type=ConnInprogressEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299109’
@16:52:31.5230 [((1] 1 158 S 9 ‘CallEv(5)=> Type=CallCtlConnOfferedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299109’
@16:52:31.5230 [((1] 1 24 IS 29 67113956 ‘299109’
@16:52:31.5230 [tout] Collected jtapi[1] tevent(1d:OFFERED)(299109:) (40013e4:0) (:0).
@16:52:31.5230 [((1] 1 183 S 9 ‘CallEv(5)=> Type=ConnCreatedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299748 ConnReason=REASON_FORWARDALL’
@16:52:31.5230 [((1] 1 14 I 32 67113956
@16:52:31.5230 [tout] Collected jtapi[1] tevent(20:CREATE_CALL)( (40013e4:0) (:0).
@16:52:31.5230 [((1] 1 157 S 9 ‘CallEv(5)=> Type=ConnInprogressEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299748’
@16:52:31.5230 [((1] 1 158 S 9 ‘CallEv(5)=> Type=CallCtlConnOfferedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299748’
@16:52:31.5230 [((1] 1 181 S 9 ‘CallEv(5)=> Type=ConnCreatedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299109 ConnReason=REASON_REDIRECT’
@16:52:31.5230 [((1] 1 14 I 32 67113956
@16:52:31.5230 [tout] Collected jtapi[1] tevent(20:CREATE_CALL)( (40013e4:0) (:0).
@16:52:31.5230 [((1] 1 157 S 9 ‘CallEv(5)=> Type=ConnInprogressEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299109’
@16:52:31.5230 [((1] 1 158 S 9 ‘CallEv(5)=> Type=CallCtlConnOfferedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NORMALUNSPECIFIED Cid=67113956 MetaCode(false)=META_CALL_STARTING Addr=299109’
@16:52:31.5230 [((1] 1 24 IS 29 67113956 ‘299109’
@16:52:31.5230 [tout] Collected jtapi[1] tevent(1d:OFFERED)(299109:) (40013e4:0) (:0).
@16:52:31.5540 [((1] 1 144 S 9 ‘CallEv(0)=> Type=ConnAlertingEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5540 [((1] 1 144 S 9 ‘CallEv(5)=> Type=ConnAlertingEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5540 [((1] 1 144 S 9 ‘CallEv(6)=> Type=ConnAlertingEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5540 [((1] 1 149 S 9 ‘CallEv(0)=> Type=CallCtlConnAlertingEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5540 [((1] 1 149 S 9 ‘CallEv(5)=> Type=CallCtlConnAlertingEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5540 [((1] 1 149 S 9 ‘CallEv(6)=> Type=CallCtlConnAlertingEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5540 [((1] 1 175 S 9 ‘CallEv(0)=> Type=TermConnCreatedEvent Cause=Other: 0 CiscoCause=CAUSE_INVALIDIECONTENTS Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 175 S 9 ‘CallEv(5)=> Type=TermConnCreatedEvent Cause=Other: 0 CiscoCause=CAUSE_INVALIDIECONTENTS Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 175 S 9 ‘CallEv(6)=> Type=TermConnCreatedEvent Cause=Other: 0 CiscoCause=CAUSE_INVALIDIECONTENTS Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 169 S 9 ‘CallEv(0)=> Type=TermConnRingingEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 169 S 9 ‘CallEv(5)=> Type=TermConnRingingEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 169 S 9 ‘CallEv(6)=> Type=TermConnRingingEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 173 S 9 ‘CallEv(0)=> Type=CallCtlTermConnRingingEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 173 S 9 ‘CallEv(5)=> Type=CallCtlTermConnRingingEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5540 [((1] 1 81 ISISIS 3 67113956 ‘299109@SEP001F6C7F5233’ 1 ‘299107@SEP001DA2F3E526’ 0 ‘299109’
@16:52:31.5540 [tout] Collected jtapi[1] tevent(3:RINGING)(299109@SEP001F6C7F5233:299107@SEP001DA2F3E526) (40013e4:1) (299109:0).
@16:52:31.5540 [tout] CcmSharedAddressResolver: Resolved 299109@SEP001F6C7F5233 → 299109 [Single in CME]
@16:52:31.5540 [tout] CcmSharedAddressResolver: Resolved 299107@SEP001DA2F3E526 → 299107 [Single in CME]
@16:52:31.5540 [gctmi] TMsg [EventRinging(299109)] distributing to model
@16:52:31.5540 [gctmi] Call [019901b45634d009/40013e4,sOG,tOG,l2] distributing EventRinging
@16:52:31.5540 [gctmi] Call [019901b45634d009/40013e4,sOG,tOG,l2] processRinging
@16:52:31.5540 [tout] Call [019901b45634d009/40013e4,sOG,tOG,l2] (createParty) 299109
@16:52:31.5540 [gctm] Party [019901b45634d009:299109,s0,tDN,rDST,lINT] created.
@16:52:31.5540 [tout] Party [019901b45634d009:299109,s0,tDN,rDST,lINT] (CcmIntParty)
@16:52:31.5540 [tout] Party [019901b45634d009:299109,s0,tDN,rDST,lINT] (CcmAgentParty)
@16:52:31.5540 [gctmi] Party [019901b45634d009:299107,s1000c,tDN,rORG,lINT] processDialing
@16:52:31.5540 [gctmi] Address [299107,tDN,sOFFH] processOffHook
@16:52:31.5540 [gctmi] Party [019901b45634d009:299107,s1000c,tDN,rORG,lINT] EvDialing ignored, in Dialing state
@16:52:31.5540 [gctm] Party [019901b45634d009:299748,s800,tRP,rDST,lINT] rls-party best match, weight 48% to EventRinging
@16:52:31.5540 [gctmi] Party [019901b45634d009:299109,s0,tDN,rDST,lINT] distribute
@16:52:31.5540 [gctmi] Party [019901b45634d009:299109,s0,tDN,rDST,lINT] processRinging
@16:52:31.5540 [gctm] Party [019901b45634d009:299109,s0,tDN,rDST,lINT] Changing state to a
@16:52:31.5540 [ISCC] Party added [ssp view]:
@ c:019901b45634d009,013f52b0 @ m:0000000000000000,00000000,0000000000000000 p:3 i:040013e4 nw:00000000:0000000000000000 t:1
p:011e3bf0 @ c:019901b45634d009,013f52b0 r:1 t:0 s:1000c n:299107
p:013eb598 @ c:019901b45634d009,013f52b0 r:2 t:0 s:800 n:299748
+ p:011e32b0 @ c:019901b45634d009,013f52b0 r:2 t:0 s:a n:299109
@16:52:31.5540 [ISCC] Party added:
@ c:019901b45634d009,013f52b0 @ m:0000000000000000,00000000 p:3 i:040013e4 nw:0000000000000000 t:1
p:0000000000000000,011e3bf0 @ c:019901b45634d009,013f52b0 r:1 ------ n:299107:
p:0000000000000000,013eb598 @ c:019901b45634d009,013f52b0 r:2 ------ n:299748:
+ p:0000000000000000,011e32b0 @ c:019901b45634d009,013f52b0 r:2 ------ n:299109:
@16:52:31.5540 [0] 7.6.007.01 distribute_event: message EventRinging
AttributeEventSequenceNumber 00000000000001ca
AttributeTimeinuSecs 554000
AttributeTimeinSecs 1246546351 (16:52:31)
AttributeOtherDNRole 1
AttributeOtherDN ‘299107’
AttributeThisDNRole 2
AttributeThisDN ‘299109’
AttributeDNIS ‘299109’
AttributeUserData [593] 00 17 00 00..
‘Site’ ‘S99’
‘AGENT_ID’ ‘299109’
‘RVQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘RTargetTypeSelected’ ‘0’
‘RTargetRuleSelected’ ‘’
‘RTargetObjectSelected’ ‘299109’
‘RTargetObjSelDBID’ ‘842’
‘RTargetAgentSelected’ ‘299109’
‘RTargetPlaceSelected’ ‘S99_Place_299109’
‘RTenant’ ‘Resources’
‘RStrategyName’ ‘CCM6_DD_Strategie_Appels_SDA v5’
‘RStrategyDBID’ ‘1048’
‘CBR-actual_volume’ ‘’
‘CBR-Interaction_cost’ ‘’
‘CBR-contract_DBIDs’ ‘’
‘CBR-IT-path_DBIDs’ ‘’
‘RRequestedSkillCombination’ ‘’
‘RRequestedSkills’(list)
‘CustomerSegment’ ‘default’
‘ServiceType’ ‘default’
‘ServiceObjective’ ‘’
‘PegTD’ 1
‘PegDOY’ 2
AttributeCallUUID ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
AttributeConnID 019901b45634d009
AttributeCallID 67113956
AttributeCallType 1
AttributeCallState 0
AttributeAgentID ‘299109’
@16:52:31.5540 [ISCC] Translate: ‘’ → ‘’; result 1 ()
16:52:31.554 Int 04544 Interaction message “EventRinging” generated
16:52:31.554 Trc 04542 EventRinging sent to [368] (0000000b StatServer_CCP 10.110.130.201:3167)
16:52:31.554 Trc 04542 EventRinging sent to [508] (0000000a - SPv7203ST 10.110.153.82:2367)
16:52:31.554 Trc 04542 EventRinging sent to [520] (00000008 StatServer_CCA 10.110.130.203:4224)
16:52:31.554 Trc 04542 EventRinging sent to [392] (00000002 StatServer_GIS 10.110.130.203:4223)
16:52:31.554 Trc 04542 EventRinging sent to [472] (00000004 StatServer_URS 10.110.130.202:4253)
@16:52:31.5540 [((1] 1 173 S 9 ‘CallEv(6)=> Type=CallCtlTermConnRingingEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109 Term=SEP001F6C7F5233’
@16:52:31.5700 [((1] 1 145 S 9 ‘CallEv(0)=> Type=ConnConnectedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5700 [((1] 1 145 S 9 ‘CallEv(5)=> Type=ConnConnectedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5700 [((1] 1 145 S 9 ‘CallEv(6)=> Type=ConnConnectedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5700 [((1] 1 152 S 9 ‘CallEv(0)=> Type=CallCtlConnEstablishedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5700 [((1] 1 59 ISISIS 4 67113956 ‘299109’ 2 ‘299107@SEP001DA2F3E526’ 1 ‘’
@16:52:31.5700 [tout] Collected jtapi[1] tevent(4:ESTABLISHED)(299109:299107@SEP001DA2F3E526) (40013e4:2) (:1).
@16:52:31.5700 [tout] CcmSharedAddressResolver: Resolved 299107@SEP001DA2F3E526 → 299107 [Single in CME]
@16:52:31.5700 [gctmi] TMsg [EventEstablished(299109)] distributing to model
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sOG,tOG,l3] distributing EventEstablished
@16:52:31.5700 [tout] CcmCall(019901b45634d009): processEstablished
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sOG,tOG,l3] processEstablished
@16:52:31.5700 [gctm] Call [019901b45634d009/40013e4,sOG,tOG,l3] Changing state to 5
@16:52:31.5700 [gctm] Call [019901b45634d009/40013e4,sSE,tOG,l3] Setting timer to SE:500mS
@16:52:31.5700 [gctmi] TMsg [EventEstablished(299109)] (re)distributing to model in 501mS
@16:52:31.5700 [((1] 1 152 S 9 ‘CallEv(5)=> Type=CallCtlConnEstablishedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5700 [((1] 1 59 ISISIS 4 67113956 ‘299109’ 2 ‘299107@SEP001DA2F3E526’ 1 ‘’
@16:52:31.5700 [tout] Collected jtapi[1] tevent(4:ESTABLISHED)(299109:299107@SEP001DA2F3E526) (40013e4:2) (:1).
@16:52:31.5700 [tout] CcmSharedAddressResolver: Resolved 299107@SEP001DA2F3E526 → 299107 [Single in CME]
@16:52:31.5700 [gctmi] TMsg [EventEstablished(299109)] distributing to model
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l3] distributing EventEstablished
@16:52:31.5700 [tout] CcmCall(019901b45634d009): processEstablished
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l3] processEstablished
@16:52:31.5700 [gctmi] TMsg [EventEstablished(299109)] (re)distributing to model in 501mS
@16:52:31.5700 [((1] 1 152 S 9 ‘CallEv(6)=> Type=CallCtlConnEstablishedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_PROGRESS Addr=299109’
@16:52:31.5700 [((1] 1 59 ISISIS 4 67113956 ‘299109’ 2 ‘299107@SEP001DA2F3E526’ 1 ‘’
@16:52:31.5700 [tout] Collected jtapi[1] tevent(4:ESTABLISHED)(299109:299107@SEP001DA2F3E526) (40013e4:2) (:1).
@16:52:31.5700 [tout] CcmSharedAddressResolver: Resolved 299107@SEP001DA2F3E526 → 299107 [Single in CME]
@16:52:31.5700 [gctmi] TMsg [EventEstablished(299109)] distributing to model
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l3] distributing EventEstablished
@16:52:31.5700 [tout] CcmCall(019901b45634d009): processEstablished
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l3] processEstablished
@16:52:31.5700 [gctmi] TMsg [EventEstablished(299109)] (re)distributing to model in 501mS
@16:52:31.5700 [((1] 1 154 S 9 ‘CallEv(0)=> Type=ConnDisconnectedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_REMOVING_PARTY Addr=299748’
@16:52:31.5700 [((1] 1 154 S 9 ‘CallEv(5)=> Type=ConnDisconnectedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_REMOVING_PARTY Addr=299748’
@16:52:31.5700 [((1] 1 154 S 9 ‘CallEv(6)=> Type=ConnDisconnectedEvent Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_CALL_REMOVING_PARTY Addr=299748’
@16:52:31.5700 [((1] 1 163 S 9 ‘CallEv(0)=> Type=CallCtlConnDisconnectedEv Cause=CAUSE_REDIRECTED CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_REMOVING_PARTY Addr=299748’
@16:52:31.5700 [((1] 1 35 ISISI 5 67113956 ‘299748’ 210 ‘’ 0
@16:52:31.5700 [tout] Collected jtapi[1] tevent(5:RELEASED)(299748:) (40013e4:d2) (:0).
@16:52:31.5700 [tout] CcmRouteParty::CcmEventReleased with cause d2 OtherAddress: . State: REDIRECTING
@16:52:31.5700 [gctmi] TMsg [EventRouteUsed(299748)] distributing to model
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l3] distributing EventRouteUsed
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l3] processRouteUsed
@16:52:31.5700 [gctmi] Party [019901b45634d009:299748,s800,tRP,rDST,lINT] processRouteUsed
@16:52:31.5700 [gctm] Party [019901b45634d009:299748,s800,tRP,rDST,lINT] Changing state to 0
@16:52:31.5700 [gctmi] request RequestRouteCall removed from reqMgr
@16:52:31.5700 [0] 7.6.007.01 distribute_response: message EventRouteUsed
AttributeEventSequenceNumber 00000000000001cb
AttributeTimeinuSecs 570000
AttributeTimeinSecs 1246546351 (16:52:31)
AttributeThirdPartyDNRole 2
AttributeThirdPartyDN ‘299109’
AttributeOtherDNRole 1
AttributeOtherDN ‘299107’
AttributeThisDNRole 2
AttributeThisDN ‘299748’
AttributeDNIS ‘299109’
AttributeUserData [593] 00 17 00 00..
‘Site’ ‘S99’
‘AGENT_ID’ ‘299109’
‘RVQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘RTargetTypeSelected’ ‘0’
‘RTargetRuleSelected’ ‘’
‘RTargetObjectSelected’ ‘299109’
‘RTargetObjSelDBID’ ‘842’
‘RTargetAgentSelected’ ‘299109’
‘RTargetPlaceSelected’ ‘S99_Place_299109’
‘RTenant’ ‘Resources’
‘RStrategyName’ ‘CCM6_DD_Strategie_Appels_SDA v5’
‘RStrategyDBID’ ‘1048’
‘CBR-actual_volume’ ‘’
‘CBR-Interaction_cost’ ‘’
‘CBR-contract_DBIDs’ ‘’
‘CBR-IT-path_DBIDs’ ‘’
‘RRequestedSkillCombination’ ‘’
‘RRequestedSkills’(list)
‘CustomerSegment’ ‘default’
‘ServiceType’ ‘default’
‘ServiceObjective’ ‘’
‘PegTD’ 1
‘PegDOY’ 2
AttributeCallUUID ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
AttributeConnID 019901b45634d009
AttributeCallID 67113956
AttributeCallType 1
AttributeThisQueue ‘299748’
AttributeCallState 0
AttributeReason [14] 00 01 01 00..
‘RTR’ 110
AttributeReferenceID 2018
@16:52:31.5700 [ISCC] Translate: ‘’ → ‘’; result 1 ()
16:52:31.570 Int 04544 Interaction message “EventRouteUsed” generated
16:52:31.570 Trc 04542 EventRouteUsed sent to [484] (00000005 UniversalRoutingServer 10.110.130.202:4255)
16:52:31.570 Trc 04542 EventRouteUsed sent to [368] (0000000b StatServer_CCP 10.110.130.201:3167)
16:52:31.570 Trc 04542 EventRouteUsed sent to [520] (00000008 StatServer_CCA 10.110.130.203:4224)
16:52:31.570 Trc 04542 EventRouteUsed sent to [392] (00000002 StatServer_GIS 10.110.130.203:4223)
16:52:31.570 Trc 04542 EventRouteUsed sent to [472] (00000004 StatServer_URS 10.110.130.202:4253)
@16:52:31.5700 [gctm] Party [019901b45634d009:299748,s0,tRP,rDST,lINT] deleting.
@16:52:31.5700 [ISCC] Party removed [ssp view]:
@ c:019901b45634d009,013f52b0 @ m:0000000000000000,00000000,0000000000000000 p:3 i:040013e4 nw:00000000:0000000000000000 t:1
p:011e3bf0 @ c:019901b45634d009,013f52b0 r:1 t:0 s:1000c n:299107
- p:013eb598 @ c:019901b45634d009,013f52b0 r:2 t:0 s:0 n:299748
p:011e32b0 @ c:019901b45634d009,013f52b0 r:2 t:0 s:a n:299109
@16:52:31.5700 [ISCC] Party removed:
@ c:019901b45634d009,013f52b0 @ m:0000000000000000,00000000 p:3 i:040013e4 nw:0000000000000000 t:1
p:0000000000000000,011e3bf0 @ c:019901b45634d009,013f52b0 r:1 ------ n:299107:
- p:0000000000000000,013eb598 @ c:019901b45634d009,013f52b0 r:2 ------ n:299748:
p:0000000000000000,011e32b0 @ c:019901b45634d009,013f52b0 r:2 ------ n:299109:
@16:52:31.5700 [ISCC] Party object is removed: p:0000000000000000,00000000 n:299748:
@16:52:31.5700 [sm] SmLegOwner destructor entered.
@16:52:31.5700 [sm] free_dcr_queue…
@16:52:31.5700 [sm] dmx_stop_media_cmd, leg 90
@16:52:31.5700 [sm] gw_stop_media_cmd: l:90, MediaCmdActiveType 0, MediaStopActive 0, MediaCmdSent 0, MediaCmdIssuedType 0
@16:52:31.5700 [sm] SmLeg::Destroy for leg 90, status 2
@16:52:31.5700 [tout] Total 1 legs on SM 1.
@16:52:31.5700 [tout] <<<<<Sending SMEvent, s:412, n:‘1 VoIP_SM_760’, e: DESTROY_LEG(leg=90) ref=23
@16:52:31.5700 [tout] (CcmIntParty deleted)
16:52:31.570 Trc 04541 RequestDistributeEvent received from [484] (00000005 UniversalRoutingServer 10.110.130.202:4255)
message RequestDistributeEvent
AttributeUserEvent EventDiverted
AttributeReferenceID 4294967295
AttributeCustomerID ‘Resources’
AttributeConnID 019901b45634d009
AttributeCallID 67113956
AttributeCallType 1
AttributeThisDN ‘Appels_SDA_S99_Switch’
AttributeThisQueue ‘Appels_SDA_S99_Switch’
AttributeThisDNRole 2
AttributeOtherDN ‘299107’
AttributeOtherDNRole 1
AttributeThirdPartyDN ‘299109’
AttributeThirdPartyDNRole 2
AttributeDNIS ‘299109’
AttributeUserData [593] 00 17 00 00..
‘Site’ ‘S99’
‘AGENT_ID’ ‘299109’
‘RVQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘RTargetTypeSelected’ ‘0’
‘RTargetRuleSelected’ ‘’
‘RTargetObjectSelected’ ‘299109’
‘RTargetObjSelDBID’ ‘842’
‘RTargetAgentSelected’ ‘299109’
‘RTargetPlaceSelected’ ‘S99_Place_299109’
‘RTenant’ ‘Resources’
‘RStrategyName’ ‘CCM6_DD_Strategie_Appels_SDA v5’
‘RStrategyDBID’ ‘1048’
‘CBR-actual_volume’ ‘’
‘CBR-Interaction_cost’ ‘’
‘CBR-contract_DBIDs’ ‘’
‘CBR-IT-path_DBIDs’ ‘’
‘RRequestedSkillCombination’ ‘’
‘RRequestedSkills’(list)
‘CustomerSegment’ ‘default’
‘ServiceType’ ‘default’
‘ServiceObjective’ ‘’
‘PegTD’ 1
‘PegDOY’ 2
AttributeReason [14] 00 01 01 00..
‘RTR’ 110
AttributeExtensions [222] 00 07 00 00..
‘SIGNATURE’ ‘router’
‘NAME’ ‘UniversalRoutingServer’
‘VERSION’ ‘Version: 7.6.001.06’
‘CLUSTER’ ‘UniversalRoutingServer’
‘VQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘Location’ ‘Cisco’
‘CallUUID’ ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
AttributeTimeout 60
AttributeCallUUID ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
16:52:31.570 Int 04543 Interaction message “RequestDistributeEvent” received from 484 (“UniversalRoutingServer”)
@16:52:31.5700 [0] 7.6.007.01 send_to_client: message EventACK
AttributeEventSequenceNumber 00000000000001cc
AttributeTimeinuSecs 570000
AttributeTimeinSecs 1246546351 (16:52:31)
AttributeReferenceID 4294967295
AttributeThisDN ‘Appels_SDA_S99_Switch’
AttributeUserEvent RequestDistributeEvent
16:52:31.570 Trc 04542 EventACK sent to [484] (00000005 UniversalRoutingServer 10.110.130.202:4255)
@16:52:31.5700 [0] 7.6.007.01 distribute_user_event: message EventDiverted
AttributeEventSequenceNumber 00000000000001cd
AttributeTimeinuSecs 570000
AttributeTimeinSecs 1246546351 (16:52:31)
AttributeCallUUID ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
AttributeTimeout 60
AttributeExtensions [222] 00 07 00 00..
‘SIGNATURE’ ‘router’
‘NAME’ ‘UniversalRoutingServer’
‘VERSION’ ‘Version: 7.6.001.06’
‘CLUSTER’ ‘UniversalRoutingServer’
‘VQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘Location’ ‘Cisco’
‘CallUUID’ ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
AttributeReason [14] 00 01 01 00..
‘RTR’ 110
AttributeUserData [593] 00 17 00 00..
‘Site’ ‘S99’
‘AGENT_ID’ ‘299109’
‘RVQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘RTargetTypeSelected’ ‘0’
‘RTargetRuleSelected’ ‘’
‘RTargetObjectSelected’ ‘299109’
‘RTargetObjSelDBID’ ‘842’
‘RTargetAgentSelected’ ‘299109’
‘RTargetPlaceSelected’ ‘S99_Place_299109’
‘RTenant’ ‘Resources’
‘RStrategyName’ ‘CCM6_DD_Strategie_Appels_SDA v5’
‘RStrategyDBID’ ‘1048’
‘CBR-actual_volume’ ‘’
‘CBR-Interaction_cost’ ‘’
‘CBR-contract_DBIDs’ ‘’
‘CBR-IT-path_DBIDs’ ‘’
‘RRequestedSkillCombination’ ‘’
‘RRequestedSkills’(list)
‘CustomerSegment’ ‘default’
‘ServiceType’ ‘default’
‘ServiceObjective’ ‘’
‘PegTD’ 1
‘PegDOY’ 2
AttributeDNIS ‘299109’
AttributeThirdPartyDNRole 2
AttributeThirdPartyDN ‘299109’
AttributeOtherDNRole 1
AttributeOtherDN ‘299107’
AttributeThisDNRole 2
AttributeThisQueue ‘Appels_SDA_S99_Switch’
AttributeThisDN ‘Appels_SDA_S99_Switch’
AttributeCallType 1
AttributeCallID 67113956
AttributeConnID 019901b45634d009
AttributeCustomerID ‘Resources’
AttributeReferenceID 4294967295
AttributeUserEvent EventDiverted
16:52:31.570 Trc 04542 EventDiverted sent to [368] (0000000b StatServer_CCP 10.110.130.201:3167)
16:52:31.570 Trc 04542 EventDiverted sent to [520] (00000008 StatServer_CCA 10.110.130.203:4224)
16:52:31.570 Trc 04542 EventDiverted sent to [392] (00000002 StatServer_GIS 10.110.130.203:4223)
16:52:31.570 Trc 04542 EventDiverted sent to [472] (00000004 StatServer_URS 10.110.130.202:4253)
@16:52:31.5700 [((1] 1 163 S 9 ‘CallEv(5)=> Type=CallCtlConnDisconnectedEv Cause=CAUSE_REDIRECTED CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_REMOVING_PARTY Addr=299748’
@16:52:31.5700 [((1] 1 35 ISISI 5 67113956 ‘299748’ 210 ‘’ 0
@16:52:31.5700 [tout] Collected jtapi[1] tevent(5:RELEASED)(299748:) (40013e4:d2) (:0).
@16:52:31.5700 [gctmi] TMsg [EventReleased(299748)] distributing to model
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l2] distributing EventReleased
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l2] processReleased
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l2] EvMigrated ignored for non-exist party.
@16:52:31.5700 [((1] 1 163 S 9 ‘CallEv(6)=> Type=CallCtlConnDisconnectedEv Cause=CAUSE_REDIRECTED CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(false)=META_CALL_REMOVING_PARTY Addr=299748’
@16:52:31.5700 [((1] 1 35 ISISI 5 67113956 ‘299748’ 210 ‘’ 0
@16:52:31.5700 [tout] Collected jtapi[1] tevent(5:RELEASED)(299748:) (40013e4:d2) (:0).
@16:52:31.5700 [gctmi] TMsg [EventReleased(299748)] distributing to model
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l2] distributing EventReleased
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l2] processReleased
@16:52:31.5700 [gctmi] Call [019901b45634d009/40013e4,sSE,tSE,l2] EvMigrated ignored for non-exist party.
@16:52:31.5700 [((1] 1 131 S 9 ‘CallEv(6)=> Type=CallObservationEndedEv Cause=CAUSE_NORMAL CiscoCause=CAUSE_NOERROR Cid=67113956 MetaCode(true)=META_UNKNOWN’
@16:52:31.5700 [tout] >>>Incoming SMEvent, s:412, l:90, n:‘1 VoIP_SM_760’, e: DESTROY_LEG_ACK(id=1,err=0) ref=23
@16:52:32.0860 [gctm] Call [019901b45634d009/40013e4,sSE,tSE,l2] Single-Est timer expired, transition to Est.
@16:52:32.0860 [gctm] Call [019901b45634d009/40013e4,sSE,tSE,l2] Transition to ESTABLISHED state.
@16:52:32.0860 [gctmi] Party [019901b45634d009:299109,sa,tDN,rDST,lINT] processEstablished
@16:52:32.0860 [gctmi] Address [299109,tDN,sIDLE] processOffHook
@16:52:32.0860 [gctm] Address [299109,tDN,sIDLE] Changing state to 1
@16:52:32.0860 [0] 7.6.007.01 distribute_event: message EventOffHook
AttributeEventSequenceNumber 00000000000001ce
AttributeTimeinuSecs 86000
AttributeTimeinSecs 1246546352 (16:52:32)
AttributeThisDN ‘299109’
@16:52:32.0860 [ISCC] Translate: ‘’ → ‘’; result 1 ()
16:52:32.086 Int 04544 Interaction message “EventOffHook” generated
16:52:32.086 Trc 04542 EventOffHook sent to [368] (0000000b StatServer_CCP 10.110.130.201:3167)
16:52:32.086 Trc 04542 EventOffHook sent to [508] (0000000a - SPv7203ST 10.110.153.82:2367)
16:52:32.086 Trc 04542 EventOffHook sent to [520] (00000008 StatServer_CCA 10.110.130.203:4224)
16:52:32.086 Trc 04542 EventOffHook sent to [392] (00000002 StatServer_GIS 10.110.130.203:4223)
16:52:32.086 Trc 04542 EventOffHook sent to [472] (00000004 StatServer_URS 10.110.130.202:4253)
@16:52:32.0860 [gctm] Party [019901b45634d009:299109,sa,tDN,rDST,lINT] Changing state to c
@16:52:32.0860 [0] 7.6.007.01 distribute_event: message EventEstablished
AttributeEventSequenceNumber 00000000000001cf
AttributeTimeinuSecs 86000
AttributeTimeinSecs 1246546352 (16:52:32)
AttributeOtherDNRole 1
AttributeOtherDN ‘299107’
AttributeThisDNRole 2
AttributeThisDN ‘299109’
AttributeDNIS ‘299109’
AttributeUserData [593] 00 17 00 00..
‘Site’ ‘S99’
‘AGENT_ID’ ‘299109’
‘RVQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘RTargetTypeSelected’ ‘0’
‘RTargetRuleSelected’ ‘’
‘RTargetObjectSelected’ ‘299109’
‘RTargetObjSelDBID’ ‘842’
‘RTargetAgentSelected’ ‘299109’
‘RTargetPlaceSelected’ ‘S99_Place_299109’
‘RTenant’ ‘Resources’
‘RStrategyName’ ‘CCM6_DD_Strategie_Appels_SDA v5’
‘RStrategyDBID’ ‘1048’
‘CBR-actual_volume’ ‘’
‘CBR-Interaction_cost’ ‘’
‘CBR-contract_DBIDs’ ‘’
‘CBR-IT-path_DBIDs’ ‘’
‘RRequestedSkillCombination’ ‘’
‘RRequestedSkills’(list)
‘CustomerSegment’ ‘default’
‘ServiceType’ ‘default’
‘ServiceObjective’ ‘’
‘PegTD’ 1
‘PegDOY’ 2
AttributeCallUUID ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
AttributeConnID 019901b45634d009
AttributeCallID 67113956
AttributeCallType 1
AttributeCallState 0
AttributeAgentID ‘299109’
@16:52:32.0860 [ISCC] Translate: ‘’ → ‘’; result 1 ()
16:52:32.086 Int 04544 Interaction message “EventEstablished” generated
16:52:32.086 Trc 04542 EventEstablished sent to [368] (0000000b StatServer_CCP 10.110.130.201:3167)
16:52:32.086 Trc 04542 EventEstablished sent to [508] (0000000a - SPv7203ST 10.110.153.82:2367)
16:52:32.086 Trc 04542 EventEstablished sent to [520] (00000008 StatServer_CCA 10.110.130.203:4224)
16:52:32.086 Trc 04542 EventEstablished sent to [392] (00000002 StatServer_GIS 10.110.130.203:4223)
16:52:32.086 Trc 04542 EventEstablished sent to [472] (00000004 StatServer_URS 10.110.130.202:4253)
@16:52:32.0860 [gctmi] Party [019901b45634d009:299107,s1000c,tDN,rORG,lINT] processEstablished
@16:52:32.0860 [gctmi] Address [299107,tDN,sOFFH] processOffHook
@16:52:32.0860 [gctm] Party [019901b45634d009:299107,s1000c,tDN,rORG,lINT] Changing state to c
@16:52:32.0860 [0] 7.6.007.01 distribute_event: message EventEstablished
AttributeEventSequenceNumber 00000000000001d0
AttributeTimeinuSecs 86000
AttributeTimeinSecs 1246546352 (16:52:32)
AttributeOtherDNRole 2
AttributeOtherDN ‘299109’
AttributeThisDNRole 1
AttributeThisDN ‘299107’
AttributeDNIS ‘299109’
AttributeUserData [593] 00 17 00 00..
‘Site’ ‘S99’
‘AGENT_ID’ ‘299109’
‘RVQID’ ‘R1SQ18ECTT6TD6RL4KMCR738MG000060’
‘RTargetTypeSelected’ ‘0’
‘RTargetRuleSelected’ ‘’
‘RTargetObjectSelected’ ‘299109’
‘RTargetObjSelDBID’ ‘842’
‘RTargetAgentSelected’ ‘299109’
‘RTargetPlaceSelected’ ‘S99_Place_299109’
‘RTenant’ ‘Resources’
‘RStrategyName’ ‘CCM6_DD_Strategie_Appels_SDA v5’
‘RStrategyDBID’ ‘1048’
‘CBR-actual_volume’ ‘’
‘CBR-Interaction_cost’ ‘’
‘CBR-contract_DBIDs’ ‘’
‘CBR-IT-path_DBIDs’ ‘’
‘RRequestedSkillCombination’ ‘’
‘RRequestedSkills’(list)
‘CustomerSegment’ ‘default’
‘ServiceType’ ‘default’
‘ServiceObjective’ ‘’
‘PegTD’ 1
‘PegDOY’ 2
AttributeCallUUID ‘IU3KTO3V8T4A10MBBESV0JC5HK00001G’
AttributeConnID 019901b45634d009
AttributeCallID 67113956
AttributeCallType 1
AttributeCallState 0
AttributeAgentID ‘299107’
@16:52:32.0860 [ISCC] Translate: ‘’ → ‘’; result 1 ()
16:52:32.086 Int 04544 Interaction message “EventEstablished” generated
16:52:32.086 Trc 04542 EventEstablished sent to [368] (0000000b StatServer_CCP 10.110.130.201:3167)
16:52:32.086 Trc 04542 EventEstablished sent to [532] (00000009 - SPv7203ST 10.110.153.82:2366)
16:52:32.086 Trc 04542 EventEstablished sent to [520] (00000008 StatServer_CCA 10.110.130.203:4224)
16:52:32.086 Trc 04542 EventEstablished sent to [392] (00000002 StatServer_GIS 10.110.130.203:4223)
16:52:32.086 Trc 04542 EventEstablished sent to [472] (00000004 StatServer_URS 10.110.130.202:4253)
@16:52:32.0860 [gctm] Call [019901b45634d009/40013e4,sSE,tSE,l2] Changing state to 6
@16:52:32.0860 [gctm] Call [019901b45634d009/40013e4,sES,tSE,l2] Setting timer to ES:100800000mS
@16:52:32.0860 [gctmi] TMsg [EventEstablished(299109)] re-distributing to model
@16:52:32.0860 [gctmi] Call [019901b45634d009/40013e4,sES,tES,l2] distributing EventEstablished
@16:52:32.0860 [tout] CcmCall(019901b45634d009): processEstablished
@16:52:32.0860 [gctmi] Call [019901b45634d009/40013e4,sES,tES,l2] processEstablished
@16:52:32.0860 [gctmi] Party [019901b45634d009:299109,sc,tDN,rDST,lINT] processEstablished

Rene · July 2, 2009, 6:33pm

Hi,

The log seems to be OK on first sight…

Call arrives to extension 299109 at 16:52:31.5540 (EventRinging)
Agent answers the call using his/her hardphone at 16:52:32.0860 (EventOffHook)
Call is established at 16:52:32.0860 (EventEstablished)

If the hardphone is still ringing after you see EventEstablished in T-Server’s log then something is wrong on Cisco side because Cisco reports off-hook status of the hardphone.

Have you tried to discuss this issue with Cisco support?

R.

bounty · July 3, 2009, 6:21am

Hi,

I think I had a similar problem with Cisco configured to “autoanswer” the call. My softphone was ringing a short moment, but did not took the call.

F.

jumpup · July 3, 2009, 11:01am

Hi René,

Thanks for your reply.

In fact in real (not in logs):

Call arrives to extension 299109 at 16:52:31.5540 (EventRinging)
And that’s all, we didn’t make any addtional action.
But wed receive two more events of offhook and established!!!

Yes I’m currently in discussion with Cisco on this errors but they don’t have any idea on this error…

Thanks for your help.

Best Regards

jumpup · July 3, 2009, 11:04am

Hi bounty,

My problem is not that one, we don’t want to make “autoanswer” but the system make it!

Best regards