" /> SIP Event Released - Genesys CTI User Forum

Author Topic: SIP Event Released  (Read 5644 times)

Offline PFCCWA

  • Hero Member
  • *****
  • Posts: 655
  • Karma: -7
SIP Event Released
« on: March 30, 2011, 02:20:38 PM »
Advertisement
Hello,

I would like to determine from this SIP log as to whether this call was disconnected by the external caller or the agent.
From what i have heard it is possible to determine this individually although acknowledge it might not be possible to produce reports by agent.

Within the logs the event prior to release of call is:

[i]@16:33:56.6240 [0] 8.0.300.34 distribute_response: message EventHeld
AttributeEventSequenceNumber 00000000000fe8cc
AttributeTimeinuSecs 624000
AttributeTimeinSecs 1300984436 (16:33:56)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeReason [2] 00 00..
AttributeReferenceID 61291
AttributeOtherDNRole 1
AttributeOtherDN '**********'
AttributeOtherQueue '******'
AttributeThisQueue '4910'
AttributeThisDNRole 2
AttributeAgentID '5052'
AttributeThisDN '5052'
AttributeCustomerID 'Resources'
AttributeANI '**********'
AttributeDNIS '******'
AttributeUserData [479] 00 13 00 00..
'KV_TYPE_ID' '4'
'navbarlabel' '0800*******'
'KV_BRAND' '***'
'KV_SKILL' 'Skill'
'Activity' 'CARD_****'
'KV_ANN_GREETING' '1031'
'KV_ANN_PUBLIC_HOL' '1012'
'KV_ANN_OOH' '1033'
'KV_ANN_ON_HOLD' '1034'
'KV_ANN_FIRE' '1015'
'strCustomerDialledNumber' '0800*******'
'KV_GREET_ABN_GENERIC' '0'
'KV_GREET_ABN_PPC_CARD_****' '0'
'KV_VQ' 'PPC_CARD_****_VQ'
'RVQID' '6OFQE9T8D50M94QS5O71251TD0000C19'
'RVQDBID' '2240'
'PegDOY' 1
'PegAG?:Skill = 10' 1
'PegDOW' 4
AttributeCallUUID '3BVR6AMIB14DP69GMSV6FTKUVS000V5L'
AttributeConnID 015c01e7fde6ec86
AttributeCallID 31925
AttributePropagatedCallType 2
AttributeCallType 2
AttributeCallState 0
16:33:56.624 Int 04544 Interaction message "EventHeld" generated
16:33:56.624 Trc 04542 EventHeld sent to [568] (00000009 gad1 ****)
16:33:56.624 Trc 04542 EventHeld sent to [640] (000002ce Witness Recording ****)
16:33:56.624 Trc 04542 EventHeld sent to [952] (0000028c anonymousTServerClient ****)
16:33:56.624 Trc 04542 EventHeld sent to [328] (00000244 statserver_reporting_backup ****)
16:33:56.624 Trc 04542 EventHeld sent to [1956] (00000243 statserver_reporting ****)
16:33:56.624 Trc 04542 EventHeld sent to [1944] (00000223 Witness Recording 1 ****)
16:33:56.624 Trc 04542 EventHeld sent to [2324] (00000209 ocs_backup ****)
16:33:56.624 Trc 04542 EventHeld sent to [564] (000000aa ocs ****)
16:33:56.624 Trc 04542 EventHeld sent to [540] (000000ab statserver_routing ****)
16:33:56.624 Trc 04542 EventHeld sent to [880] (0000009e statserver_routing_backup ****)
16:33:56.624 Trc 04542 EventHeld sent to [1488] (00000085 wfm_statserver ****)
16:33:56.624 Trc 04542 EventHeld sent to [1164] (00000048 statserver_gad ****)
    -- TellHeld
  --- Held ---
  +++ Post-event +++
    Type EventHeld
    Devices: <5052/5052> <-/**********> <-/->
    Calls: 31925/015c01e7fde6ec86/31925.544c740/c:2/r:6 0/none
    Parties: D5052/5052.584a210-544c740:1/l:2/r:2/Established,Held,Destination
    X**********/**********.5cd88e8-544c740:1/l:1/r:0/Established,Origination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
  --- Post-event ---
16:33:56.624 --- CIFace::Event ---
16:33:56.624:(1) SIPTR(427820): complete
16:33:56.624:(1) SIPTR(427814): Step 5 - SipTransactionChangePartyState(427820) complete
16:33:56.624:(1) SIPTR(427814): complete
16:33:56.624:(1) SIPCM: transaction SipScenario(427814) complete
16:33:56.624:(1) PI: 00 S[CC]D[**********]C[*D[**********]]P[msml_wak]
16:33:56.624:(1) PI: 00 S[HC]D[5052]C[*D[5052]]MS[C[*D[msml_wak]]]P[**********]
16:33:56.624:(1) CALLSTATE(a:2,d:0,i:0,e:1,r:0,o:0)
16:33:56.624:(1) -----------------------------------------------------------
16:33:56.624:(1) C[31925]:CF[D[[0]]:SC[427814]
16:33:56.624:(1) P[81549]:D[**********[132364]]:LID[0]:D2[5052[132384]]
16:33:56.624 SIPCONN(**********):  endPoint :CON[83401]:PEER[83575]:D[**********[132364]]:DLG[101314 STATE[7]]:D2[[0]:TD[0[IM[no]]]:LCRC
16:33:56.624:(1) P[81562]:D[5052[132384]]:LID[0]:D2[**********[132364]]
16:33:56.624 SIPCONN(5052):  endPoint :CON[83413]:PEER[0]:D[5052[132384]]:DLG[101327 STATE[7]]:D2[**********[132364]:TD[0[IM[no]]]:LCRC
16:33:56.624:(1) MS Type SIP_MEDIA_SERVICE_MUSIC
16:33:56.624 SIPCONN(msml_wak):  mediaServiceConnection :CON[83575]:PEER[83401]:D[msml_wak[132622]]:DLG[101503 STATE[7]]:D2[**********[132364]:TD[0[IM[no]]]:LCRC
16:33:56.624:(1) HA: BEGIN SYNC: CALL[31925] SYNCTYPE[2]: SECOND CALL[0] SYNCTYPE[-1]
16:33:56.624:(1) HA: END SYNC: CALL[31925] SYNCTYPE[2]: SECOND CALL[0] SYNCTYPE[-1]
@16:33:56.6240 [BSYNC] Trace: Send to backup (sipserver_backup) [496]:
message EventUserEvent
attr_#1005 0
attr_#1004 624
attr_#1003 1300984436
attr_#1002 649815
attr_#1001 1
attr_#1000 131072
attr_#15999 0
attr_#16000 1
attr_#16102 [1624] 32 3a 31 7c..
attr_#16101 [967] 32 3a 31 7c..
attr_#16100 [70] 31 3a 31 7c..
AttributeUserEvent [16001]
@16:33:56.6240 [BSYNC] Trace: Sent
16:33:56.624:(1) HA:MESSAGE:TYPE[0]: SYNCED
16:33:56.624:(1) call1 31925 idle
16:33:56.624:(1) SIPS:LOGBLOCK:END:SIPDATA:]
@16:33:56.6390 [BSYNC] Trace: Received [496]:
message EventUserEvent
AttributeUserEvent [16002]
attr_#16100 [19] 31 3a 31 7c..
attr_#16000 1
attr_#15999 0
attr_#1000 131072
attr_#1001 1
attr_#1002 122708
attr_#1003 1300984436
attr_#1004 179
attr_#1005 0
16:33:56.639:(1) SIPTS:<< SYNC(16002) <<
16:33:56.639:(1) HA:MESSAGE:TYPE[1]:RECEIVED
16:33:56.639:(1) HA:MESSAGE:TYPE[1]:RECEIVED
16:33:56.639:(1) HA:MESSAGE:TYPE[1]:PROCESSED
16:33:56.811:(1) SIPS:LOGBLOCK:BEGIN:SIPDATA:[
16:33:56.811:(1) Received [628,UDP] 4 bytes from **** <<<<<[/i]

The call ended while this external caller as on hold, and the next 2 events were:

[i]@16:34:41.9670 [0] 8.0.300.34 distribute_event: message EventAgentNotReady
AttributeExtensions [72] 00 02 01 00..
'WrapUpTime' 60
'AgentSessionID' '41BEHSS37D2AR8LGLQAD8EUG6K00734G'
AttributeEventSequenceNumber 00000000000fea44
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 967000
AttributeTimeinSecs 1300984481 (16:34:41)
AttributeAgentWorkMode 3 (AfterCallWork)
AttributeAgentID '5052'
AttributeThisDN '5052'
16:34:41.967 Trc 04542 EventAgentNotReady sent to [640] (000002ce Witness Recording ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [568] (00000009 gad1 ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [952] (0000028c anonymousTServerClient ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [328] (00000244 statserver_reporting_backup ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [1956] (00000243 statserver_reporting ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [1944] (00000223 Witness Recording 1 ****)
masked for 2324 (00000209 ocs_backup)
masked for 564 (000000aa ocs)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [540] (000000ab statserver_routing ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [880] (0000009e statserver_routing_backup ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [1488] (00000085 wfm_statserver ****)
16:34:41.967 Trc 04542 EventAgentNotReady sent to [1164] (00000048 statserver_gad ****)
  -- AgnEmu: synchronising agent state 0x922 for 5052
  -- AgnEmu: synchronising previous state 0x2
  -- AgnEmu: synchronising not-ready workmode 3
@16:34:41.9670 [BSYNC] Trace: Send to backup (sipserver_backup) [496]:
message EventSwitchInfo
attr_#1005 0
attr_#1004 967
attr_#1003 1300984481
attr_#1002 650060
attr_#1001 3
attr_#1000 131072
AttributeReason 3
AttributeOtherDN 3
AttributeOtherQueue 2
AttributeThisQueue 0
AttributeThisDN 60
AttributeThirdPartyDN 0
AttributeThisDNRole 0
AttributeThisTrunk 60
AttributeANI 2338
attr_#15901 22872068
AttributeOtherTrunk 0
AttributeOtherDNRole 22872068
AttributeAgentID '5052'
AttributeCallState 3
AttributeConferenceConnID 0
AttributeTransferConnID 1
AttributeDNIS 4
AttributeNodeID 16
AttributeErrorMessage 65
AttributeProtocolVersion '5052'
@16:34:41.9670 [BSYNC] Trace: Sent
  +++ Released +++
    -- XAction: start 5052.584a210-544c740:1
    SetReleased: party 5052.584a210-544c740:1, cause Null
    -- party_info 5052.5bc0c98 state change: from <Held> to <Null>
    -- G7 release
@16:34:41.9670 [0] 8.0.300.34 distribute_event: message EventReleased
AttributeEventSequenceNumber 00000000000fea45
AttributeTimeinuSecs 967000
AttributeTimeinSecs 1300984481 (16:34:41)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeOtherDNRole 1
AttributeOtherDN '**********'
AttributeOtherQueue '******'
AttributeThisQueue '4910'
AttributeThisDNRole 2
AttributeAgentID '5052'
AttributeThisDN '5052'
AttributeCustomerID 'Resources'
AttributeANI '**********'
AttributeDNIS '******'
AttributeUserData [479] 00 13 00 00..
'KV_TYPE_ID' '4'
'navbarlabel' '0800*******'
'KV_BRAND' '***'
'KV_SKILL' 'Skill'
'Activity' 'CARD_****'
'KV_ANN_GREETING' '1031'
'KV_ANN_PUBLIC_HOL' '1012'
'KV_ANN_OOH' '1033'
'KV_ANN_ON_HOLD' '1034'
'KV_ANN_FIRE' '1015'
'strCustomerDialledNumber' '0800*******'
'KV_GREET_ABN_GENERIC' '0'
'KV_GREET_ABN_PPC_CARD_****' '0'
'KV_VQ' 'PPC_CARD_****_VQ'
'RVQID' '6OFQE9T8D50M94QS5O71251TD0000C19'
'RVQDBID' '2240'
'PegDOY' 1
'PegAG?:Skill = 10' 1
'PegDOW' 4
AttributeCallUUID '3BVR6AMIB14DP69GMSV6FTKUVS000V5L'
AttributeConnID 015c01e7fde6ec86
AttributeCallID 31925
AttributePropagatedCallType 2
AttributeCallType 2
AttributeCallState 0
16:34:41.967 Int 04544 Interaction message "EventReleased" generated
16:34:41.967 Trc 04542 EventReleased sent to [640] (000002ce Witness Recording ****)
16:34:41.967 Trc 04542 EventReleased sent to [568] (00000009 gad1 ****)
16:34:41.967 Trc 04542 EventReleased sent to [952] (0000028c anonymousTServerClient ****)
16:34:41.967 Trc 04542 EventReleased sent to [328] (00000244 statserver_reporting_backup ****)
16:34:41.967 Trc 04542 EventReleased sent to [1956] (00000243 statserver_reporting ****)
16:34:41.967 Trc 04542 EventReleased sent to [1944] (00000223 Witness Recording 1 ****)
16:34:41.967 Trc 04542 EventReleased sent to [2324] (00000209 ocs_backup ****)
16:34:41.967 Trc 04542 EventReleased sent to [564] (000000aa ocs ****)
16:34:41.967 Trc 04542 EventReleased sent to [540] (000000ab statserver_routing ****)
16:34:41.967 Trc 04542 EventReleased sent to [880] (0000009e statserver_routing_backup ****)
16:34:41.967 Trc 04542 EventReleased sent to [1488] (00000085 wfm_statserver ****)
16:34:41.967 Trc 04542 EventReleased sent to [1164] (00000048 statserver_gad ****)
    -- TellReleased
    -- XAction: commit 5052.584a210-544c740:1
@16:34:41.9670 [ISCC] Party removed [ssp view]:
@ c:015c01e7fde6ec86,0585c1c8 @ m:0000000000000000,00000000,0000000000000000 p:2 i:00007cb5 nw:00000000:0000000000000000 t:2
  p:05cac558 @ c:015c01e7fde6ec86,0585c1c8 r:1 t:1 s:0 n:**********
- p:05bc0c98 @ c:015c01e7fde6ec86,0585c1c8 r:2 t:0 s:0 n:5052
@16:34:41.9670 [ISCC] Party removed:
@ c:015c01e7fde6ec86,0585c1c8 @ m:0000000000000000,00000000 p:1 i:00007cb5 nw:0000000000000000 t:2
- p:0000000000000000,05bc0c98 @ c:015c01e7fde6ec86,0585c1c8 r:2 ----h n:5052:
@16:34:41.9670 [ISCC] Party object is removed: p:0000000000000000,00000000 n:5052:
@16:34:41.9670 [0] 8.0.300.34 distribute_event: message EventOnHook
AttributeEventSequenceNumber 00000000000fea46
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 967000
AttributeTimeinSecs 1300984481 (16:34:41)
AttributeThisDN '5052'
16:34:41.967 Int 04544 Interaction message "EventOnHook" generated
16:34:41.967 Trc 04542 EventOnHook sent to [640] (000002ce Witness Recording ****)
16:34:41.967 Trc 04542 EventOnHook sent to [568] (00000009 gad1 ****)
16:34:41.967 Trc 04542 EventOnHook sent to [952] (0000028c anonymousTServerClient ****)
16:34:41.967 Trc 04542 EventOnHook sent to [328] (00000244 statserver_reporting_backup ****)
16:34:41.967 Trc 04542 EventOnHook sent to [1956] (00000243 statserver_reporting ****)
16:34:41.967 Trc 04542 EventOnHook sent to [1944] (00000223 Witness Recording 1 ****)
masked for 2324 (00000209 ocs_backup)
masked for 564 (000000aa ocs)
16:34:41.967 Trc 04542 EventOnHook sent to [540] (000000ab statserver_routing ****)
16:34:41.967 Trc 04542 EventOnHook sent to [880] (0000009e statserver_routing_backup ****)
16:34:41.967 Trc 04542 EventOnHook sent to [1488] (00000085 wfm_statserver ****)
16:34:41.967 Trc 04542 EventOnHook sent to [1164] (00000048 statserver_gad ****)
16:34:41.967:(1) device on hook
  --- Released ---
  +++ Post-event +++
    Type EventReleased
    Devices: <5052/5052> <-/**********> <-/->
    Calls: 31925/015c01e7fde6ec86/31925.544c740/c:0/r:6 0/none
    Parties: D5052/5052.584a210-544c740:1/l:2/r:2/Released,DNull,Destination
    X**********/**********.5cd88e8-544c740:1/l:1/r:0/Released,Origination
    none
    Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
  --- Post-event ---
16:34:41.967 --- CIFace::Event ---
16:34:41.967:(1) -----------------------------------------------------------
16:34:41.967:(1) C[31925]:CF[D[[0]]:SC[0]
16:34:41.967:(1) P[81562]:D[5052[132384]]:LID[0]:D2[**********[132364]]
16:34:41.967:(1) HA: BEGIN SYNC: CALL[31925] SYNCTYPE[4]: SECOND CALL[0] SYNCTYPE[-1]
16:34:41.967:(1) HA: END SYNC: CALL[31925] SYNCTYPE[4]: SECOND CALL[0] SYNCTYPE[-1]
@16:34:41.9670 [BSYNC] Trace: Send to backup (sipserver_backup) [496]:
message EventUserEvent
attr_#1005 0
attr_#1004 967
attr_#1003 1300984481
attr_#1002 650061
attr_#1001 1
attr_#1000 131072
attr_#15999 0
attr_#16000 1
attr_#16100 [19] 31 3a 31 7c..
AttributeUserEvent [16001]
@16:34:41.9670 [BSYNC] Trace: Sent
16:34:41.967:(1) HA:MESSAGE:TYPE[0]: SYNCED
16:34:41.967  -- deleted aTmCall 544c740
16:34:41.967  -- deleted aTmParty 5cd88e8
16:34:41.967  -- RemoveParty **********.5cd88e8-544c740:1
16:34:41.967  -- call leg removed leg_id=1
16:34:41.967  -- deleted party_info_tspp 5cac558
16:34:41.967  -- deleted aTmParty 584a210
16:34:41.967  -- RemoveParty 5052.584a210-544c740:1
16:34:41.967  -- call leg removed leg_id=2
16:34:41.967  -- deleted party_info_tspp 5bc0c98
16:34:41.967  -- TmCallThread deleted: 58cf198
@16:34:41.9670 [0] 8.0.300.34 distribute_event: message EventReleased
AttributeEventSequenceNumber 00000000000fea47
AttributeTimeinuSecs 967000
AttributeTimeinSecs 1300984481 (16:34:41)
AttributeCustomerID 'Resources'
AttributeANI '**********'
AttributeDNIS '******'
AttributeUserData [479] 00 13 00 00..
'KV_TYPE_ID' '4'
'navbarlabel' '0800*******'
'KV_BRAND' '***'
'KV_SKILL' 'CARD_****'
'Activity' 'CARD_****'
'KV_ANN_GREETING' '1031'
'KV_ANN_PUBLIC_HOL' '1012'
'KV_ANN_OOH' '1033'
'KV_ANN_ON_HOLD' '1034'
'KV_ANN_FIRE' '1015'
'strCustomerDialledNumber' '0800*******'
'KV_GREET_ABN_GENERIC' '0'
'KV_GREET_ABN_PPC_CARD_****' '0'
'KV_VQ' 'PPC_CARD_****_VQ'
'RVQID' '6OFQE9T8D50M94QS5O71251TD0000C19'
'RVQDBID' '2240'
'PegDOY' 1
'PegAG?:Skill = 10' 1
'PegDOW' 4
AttributeCallUUID '3BVR6AMIB14DP69GMSV6FTKUVS000V5L'
AttributeConnID 015c01e7fde6ec86
AttributeCallID 31925
AttributePropagatedCallType 2
AttributeCallType 2
AttributeThisDN 'SIP::'
@16:34:41.9670 [ISCC] Party object is removed: p:0000000000000000,00000000 n::
16:34:41.967 Int 04544 Interaction message "EventReleased" generated
16:34:41.967 Trc 04542 EventReleased sent to [328] (00000244 statserver_reporting_backup ****)
16:34:41.967 Trc 04542 EventReleased sent to [1956] (00000243 statserver_reporting ****)
16:34:41.967 Trc 04542 EventReleased sent to [540] (000000ab statserver_routing ****)
16:34:41.967 Trc 04542 EventReleased sent to [1396] (0000009f statserver_reporting_BT ****)
16:34:41.967 Trc 04542 EventReleased sent to [880] (0000009e statserver_routing_backup ****)
16:34:41.967 Trc 04542 EventReleased sent to [1488] (00000085 wfm_statserver ****)
16:34:41.967 Trc 04542 EventReleased sent to [1164] (00000048 statserver_gad ****)
@16:34:41.9670 [ISCC] Call destroyed:
- c:015c01e7fde6ec86,0585c1c8 @ m:0000000000000000,00000000 p:0 i:00007cb5 nw:0000000000000000 t:2
@16:34:41.9670 [ISCC] Call object is removed: c:015c01e7fde6ec86,00000000
16:34:41.967  -- call_info::committed destroyed
16:34:41.967  -- deleted call_info_tspp 015c01e7fde6ec86@585c1c8
@16:34:41.9670 {tscp.call {destructed} {uuid 3BVR6AMIB14DP69GMSV6FTKUVS000V5L} {connection-id 015c01e7fde6ec86} {call-id 31925}}
16:34:41.967  -- deleted: CRequest@3849148 RequestHoldCall-gad1[568]/61291
16:34:41.967:(1) SipDialog: event DESTROY, t=0, s=10, r=4, m=00000000
16:34:41.967:(1) SipDialog[101314]:<< Abort ALL <<
@16:34:41.9670 [BSYNC] Trace: Send to backup (sipserver_backup) [496]:
message EventUserEvent
attr_#1005 0
attr_#1004 967
attr_#1003 1300984481
attr_#1002 650062
attr_#1001 1
attr_#1000 131072
attr_#15999 0
attr_#16000 1
attr_#16500 [16] 31 3d 32 0d..
AttributeUserEvent [16099]
@16:34:41.9670 [BSYNC] Trace: Sent
16:34:41.967:(1) HA:MESSAGE:TYPE[2]: SYNCED
16:34:41.967:(1) SIPS:LOGBLOCK:END:SIPDATA:]
16:34:41.967:(1) SIPS:LOGBLOCK:BEGIN:SIPDATA:[
16:34:41.967:(1) Received [628,UDP] 509 bytes from **** <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.30.202.35:5060;branch=z9hG4bKC29A9FC7-B5F1-409C-9206-73356329C14B-396096
From: sip:**********@pfgroup.provfin.com;tag=3E685F9D-E65A-492A-BC5C-D3E69D9AB4E8-101732
To: <sip:msml_wak@****>;tag=F519C1C7-5CFB-450D-4CAD-A6F9CAF1112B
CSeq: 3 BYE
Call-ID: 865146F3-4253-4F54-8090-8B9FA5D8D082-81788@****
Content-Length: 0
X-Genesys-Orig: 5052
X-Genesys-geo-location: wak
Min-SE: 90
X-Genesys-GVP-Session-ID: 282004E5-7013-48AE-2C83-FDA34ABA3DD2



16:34:41.967:(1) SipDialog: event TERMINATING_BYE_RES, t=392833, s=9, r=4, m=00000000
16:34:41.967:(1) SipDialog: event DESTROY, t=0, s=10, r=4, m=00000000
16:34:41.967:(1) SipDialog[101503]:<< Abort ALL <<
@16:34:41.9670 [BSYNC] Trace: Send to backup (sipserver_backup) [496]:
message EventUserEvent
attr_#1005 0
attr_#1004 967
attr_#1003 1300984481
attr_#1002 650063
attr_#1001 1
attr_#1000 131072
attr_#15999 0
attr_#16000 1
attr_#16500 [16] 31 3d 32 0d..
AttributeUserEvent [16099]
@16:34:41.9670 [BSYNC] Trace: Sent
16:34:41.967:(1) HA:MESSAGE:TYPE[2]: SYNCED
16:34:41.967:(1) SIPS:LOGBLOCK:END:SIPDATA:][/i]

Thanks,
WA

Offline René

  • Administrator
  • Hero Member
  • *****
  • Posts: 1832
  • Karma: 62
Re: SIP Event Released
« Reply #1 on: March 30, 2011, 04:04:28 PM »
Hi Mihai,

The most important part of log is missing... What's happened with the log between 16:33:56.811 and 16:34:41.9670?

Saying it very simple - if you find BYE from media gateway then call was release by remote party. Otherwise it was released by SIP Server because of error, agent's request to release call etc.

R.
« Last Edit: March 31, 2011, 07:09:17 AM by René »

Offline PFCCWA

  • Hero Member
  • *****
  • Posts: 655
  • Karma: -7
Re: SIP Event Released
« Reply #2 on: March 30, 2011, 04:38:24 PM »
Does this extract confirm the call was dropped by external (or remote) party?

[i]16:34:41.967:(1) SIPS:LOGBLOCK:BEGIN:SIPDATA:[
16:34:41.967:(1) Received [628,UDP] 552 bytes from **** <<<<<
BYE sip:**** SIP/2.0
Call-ID: 0e4138d5859e0152344c7bca2700
CSeq: 3 BYE
From: sip:123456789@pfgroup.provfin.com;tag=0e4138d5859e0151344c7bca2700
To: "******" <sip:******@****>;tag=3E685F9D-E65A-492A-BC5C-D3E69D9AB4E8-101543
Via: SIP/2.0/UDP ****;branch=z9hG4bK830303035656534343311a75.0,SIP/2.0/TCP ****;psrrposn=1;received=****;branch=z9hG4bK8050b475959e01ae354c7bca2700
Content-Length: 0
Max-Forwards: 69
User-Agent: Avaya CM/R015x.02.1.016.4
Record-Route: <sip:****;lr>



16:34:41.967:(1) SIPDLG[101314]: register TRN[392831]
16:34:41.967:(1) SipDialog: event BYE, t=392831, s=8, r=5, m=05a2cd84
16:34:41.967 SIPCONN(123456789): HandleSipDialogEvent(BYE)
16:34:41.967 SIPCONN(123456789): new transaction
16:34:41.967 SIPCONN(123456789): 1pcc event BYE
16:34:41.967 SIPCONN(123456789): SendResponse(200,392831)
16:34:41.967:(1) add party '123456789' state
16:34:41.967:(1) Sending  [628,UDP] 780 bytes to **** >>>>>
SIP/2.0 200 OK
Call-ID: 0e4138d5859e0152344c7bca2700
CSeq: 3 BYE
From: sip:123456789@pfgroup.provfin.com;tag=0e4138d5859e0151344c7bca2700
To: "******" <sip:******@****>;tag=3E685F9D-E65A-492A-BC5C-D3E69D9AB4E8-101543
Via: SIP/2.0/UDP
****;branch=z9hG4bK830303035656534343311a75.0;received=****,SIP/2.0/TCP ****;psrrposn=1;received=****;branch=z9hG4bK8050b475959e01ae354c7bca2700
Record-Route: <sip:****;lr>
Contact: <sip:****>
X-Genesys-CallUUID: 3BVR6AMIB14DP69GMSV6FTKUVS000V5L
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, UPDATE, MESSAGE, NOTIFY
X-Genesys-Orig: 5052
X-Genesys-geo-location: wak
X-Genesys-GVP-Session-ID: 282004E5-7013-48AE-2C83-FDA34ABA3DD2
Content-Length: 0



16:34:41.967:(1) SipDialog: event TERMINATING_SEND_BYE_RES, t=392831, s=9, r=8, m=05a2cd84
16:34:41.967 SIPCONN(123456789): HandleSipDialogEvent(TERMINATING_SEND_BYE_RES) - filtered
16:34:41.967 SIPCONN(123456789): ClrMediaPeer
16:34:41.967 SIPCONN(msml_wak): ClrMediaPeer
16:34:41.967 SIPCONN(123456789): CONNCHECK: dialog=9
16:34:41.967 SIPCONN(123456789): set monitor 05a2cd10, 00000000
16:34:41.967 SIPCONN(123456789): state e:1,p:3,s:0,c:8,rc:0,m:0
16:34:41.967:(1) SipDialog: ClearCall(phone=0,state=9)
16:34:41.967:(1) SipDialog::Terminate(state=9,reason=0)
16:34:41.967:(1) SipDialog: set monitor 00000000
16:34:41.967 SIPCONN(123456789): DetachMediaPeer
16:34:41.967:(1) SIPTS: external party released
16:34:41.967 ClearContext: party 123456789.5cd88e8-544c740:1
16:34:41.967 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventReleased
    Devices: <-/123456789> <5052/5052> <-/->
    Calls: 31925/015c01e7fde6ec86/31925.544c740/c:2/r:6 0/none
    Parties: X123456789/123456789.5cd88e8-544c740:1/l:1/r:0/Established,Origination
    D5052/5052.584a210-544c740:1/l:2/r:2/Established,Held,Destination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Released +++
    -- XAction: start 123456789.5cd88e8-544c740:1
    SetReleased: party 123456789.5cd88e8-544c740:1, cause Null
    -- party_info 123456789.5cac558 state change: from <Connected> to <Null>
    -- TellReleased
    -- XAction: commit 123456789.5cd88e8-544c740:1
@16:34:41.9670 [ISCC] Party removed [ssp view]:[/i]

Thanks,WA

Offline René

  • Administrator
  • Hero Member
  • *****
  • Posts: 1832
  • Karma: 62
Re: SIP Event Released
« Reply #3 on: March 31, 2011, 07:12:13 AM »
Hi,

If Avaya is your media gateway than the call was released by Avaya. If possible, you can check Avaya logs and try to find out if release was received from public network or caused by something else.

R.