" /> SIP call dropped after being answered - Genesys CTI User Forum

Author Topic: SIP call dropped after being answered  (Read 6020 times)

Offline dnib

  • Jr. Member
  • **
  • Posts: 57
  • Karma: 0
SIP call dropped after being answered
« on: May 16, 2012, 09:46:52 AM »
Advertisement
Hello guys,
I need your help!

A calls was dropped after it was answered by an agent. From what I see in logs the BYE SIP message was sent from a routing point on which a strategy is loaded but I don't understand why.

Caller:  724303368
extension:  501

Here is log section with answer and drop.

[i]@18:15:15.2262 [0] 7.6.000.77 distribute_response: message EventEstablished
AttributeEventSequenceNumber 000000000318cc03
AttributeTimeinuSecs 226206
AttributeTimeinSecs 1337094915 (18:15:15)
AttributeExtensions [42] 00 02 01 00..
'BusinessCall' 1
'WrapUpTime' 2
AttributeCustomerID 'Resources'
AttributeANI '724303368'
AttributeDNIS '16010'
AttributeUserData [683] 00 1F 03 00..
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'IVR_APPID' '4'
'AREA' '1'
'DIG1' '1'
'DIG2' '1'
'SECT' '1'
'SRV' 'FE_MU'
'PegDEF' 1
'PB1' 'Muntenia_TA'
'PB2' 'Bucarest'
'PB3' 'Sector 1'
'RVQID' '01HHF06E0SFKN3OSPJ017B5AES0196QM'
'RTargetTypeSelected' '2'
'RTargetRuleSelected' ''
'RTargetObjectSelected' 'FE_MU'
'RTargetObjSelDBID' '127'
'RTargetAgentSelected' 'LDC036'
'RTargetPlaceSelected' 'Place_501'
'RTenant' 'Resources'
'RStrategyName' 'QUEUE_V05_1'
'RStrategyDBID' '168'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RTargetRequested' 'FE_MU'
'PegAGFE_MU' 1
AttributeCallUUID '01F3K4KDLOFMFF9804000VTAES0BE06C'
AttributeConnID 006c020612d32049
AttributeCallID 503881
AttributeCallType 2
AttributeCallState 0
AttributeThirdPartyQueue '16010'
AttributeOtherQueue '16111'
AttributeThisQueue '16101'
AttributeThisDNRole 2
AttributeAgentID '2036'
AttributeThisDN '501'
AttributeOtherDNRole 1
AttributeOtherDN '724303368'
@18:15:15.2262 [ISCC] Translate: '724303368' -> ''; result 1 ()
18:15:15.226 Int 04544 Interaction message "EventEstablished" generated
18:15:15.226 Trc 04542 EventEstablished sent to [33] (00000047 AIL_Service_2 172.19.192.212:4487)
18:15:15.226 Trc 04542 EventEstablished sent to [37] (00000046 AIL_Service_3 172.19.192.209:2108)
18:15:15.226 Trc 04542 EventEstablished sent to [18] (00000051 OCS_SServer_BKP 172.19.192.208:2811)
18:15:15.226 Trc 04542 EventEstablished sent to [20] (0000003d GAD2_SServer 172.19.192.94:1200)
18:15:15.226 Trc 04542 EventEstablished sent to [16] (0000003a OCS_BKP 172.19.192.94:1051)
18:15:15.226 Trc 04542 EventEstablished sent to [24] (00000036 OCS_PRI 172.19.192.93:1170)
18:15:15.226 Trc 04542 EventEstablished sent to [19] (00000035 GAD1_SServer 172.19.192.93:1164)
18:15:15.226 Trc 04542 EventEstablished sent to [31] (00000014 SIP_ICON2 172.19.192.216:3357)
18:15:15.226 Trc 04542 EventEstablished sent to [30] (00000013 SIP_ICON1 172.19.192.215:4704)
18:15:15.226 Trc 04542 EventEstablished sent to [32] (00000015 CCA_SServer_BKP 172.19.192.214:2197)
18:15:15.226 Trc 04542 EventEstablished sent to [29] (00000012 CCA_SServer_PRI 172.19.192.213:1914)
18:15:15.226 Trc 04542 EventEstablished sent to [28] (00000011 CCP_StatServer_PRI 172.19.192.214:2196)
18:15:15.226 Trc 04542 EventEstablished sent to [25] (0000000e URS_StatServer_PRI 172.19.192.204:3227)
18:15:15.226 Trc 04542 EventEstablished sent to [22] (0000000b CCP_StatServer_BCK 172.19.192.213:1913)
18:15:15.226 Trc 04542 EventEstablished sent to [21] (0000000a OCS_SServer_PRI 172.19.192.207:4807)
18:15:15.226 Trc 04542 EventEstablished sent to [17] (00000006 URS_StatServer_BCK 172.19.192.203:15315)
    -- TellEstablished
    SetActive: party 501.0xc4f2880-0xad1bc78:1, cause Null
    -- Cleanup call leg
  --- Established ---
  +++ Post-event +++
    Type EventEstablished
    Devices: <501/501> <-/724303368> <-/->
    Calls: 503881/006c020612d32049/503881.0xad1bc78/c:2/r:9 0/none
    Parties: D501/501.0xc4f2880-0xad1bc78:1/l:2/r:0/Established,Active,Destination
    X724303368/724303368.0xafda2b8-0xad1bc78: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 ---
18:15:15.226 --- CIFace::Event ---
18:15:15.226 SIPCONN(501): NotifyOnComplete
18:15:15.226 SIPCONN(724303368): state e:3,p:3,s:3,c:0,rc:0,m:1
18:15:15.226 SIPPARTY(724303368): 2724326 verify update of party-connection state C-C
18:15:15.226 SIPCONN(724303368): NotifyOnComplete
18:15:15.226 SIPCONN(501): Connect complete, other device '724303368',(c560b08,c571af0)
18:15:15.226 SIPCONN(724303368): Connect complete, other device '501',(c571af0,c560b08)
18:15:15.226 SIPCONN(724303368): SendAck(14460884)
18:15:15.226 Sending  [13,UDP] 429 bytes to 172.19.192.251:5060 >>>>>
ACK sip:724303368@172.19.192.251:5060 SIP/2.0
From: <sip:16010@172.19.192.200>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3878990
To: <sip:724303368@172.19.192.251>;tag=E6D34738-865
Call-ID: 5DA39CB5-9DD711E1-B485AE88-7853E221@172.19.192.251
CSeq: 6 ACK
Content-Length: 0
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120489
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO



@18:15:15.2267 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventUserEvent
attr_#1005 0
attr_#1004 226
attr_#1003 1337094915
attr_#1002 21167397
attr_#1001 1
attr_#1000 131072
attr_#16000 1
attr_#16500 [536] 31 3D 33 0D..
AttributeUserEvent [16099]
@18:15:15.2267 [BSYNC] Trace: Sent
18:15:15.226 HA:MESSAGE:TYPE[2]: SYNCED
18:15:15.226 SipDialog: event CONNECTED_SEND_ACK, t=14460884, s=7, r=8, m=c571b64
18:15:15.226 SIPCONN(724303368): HandleSipDialogEvent(CONNECTED_SEND_ACK) - filtered
18:15:15.226 SIPTR(13175796): complete
18:15:15.226 SIPTR(13175795): Step 0 - SipTransactionConnectCompleteAnswer(13175796) complete
18:15:15.226 SIPTR(13175795): complete
18:15:15.226 SIPCM: transaction SipScenario(13175795) complete
18:15:15.226 IFACE:REC:FN="recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049"
18:15:15.226 Selected for Dn (): Service REC_StreamManager2 (geo-loc , priority 0, capacity 0 (0% of -2))
18:15:15.226 previous scenario is not cleaned up
18:15:15.226 SIPTR(13175795): Begin step 0 - SipScenario(13175797)
18:15:15.226 SIPTR(13175797): Begin step 0 - SipTransactionReserveMediaChannel(13175799)
18:15:15.226 SipDialog: set monitor c555ed4
18:15:15.226 SIPCONN(REC_StreamManager2): main dialog 0 created
18:15:15.227 MakeInactive(REC_StreamManager2)
18:15:15.227 SIPCONN(REC_StreamManager2): Local contact: '<sip:501@172.19.192.200:5060>'
@18:15:15.2270 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventUserEvent
attr_#1005 0
attr_#1004 227
attr_#1003 1337094915
attr_#1002 21167398
attr_#1001 1
attr_#1000 131072
attr_#16000 1
attr_#16500 [85] 31 3D 31 0D..
AttributeUserEvent [16099]
@18:15:15.2270 [BSYNC] Trace: Sent
18:15:15.227 HA:MESSAGE:TYPE[2]: SYNCED
18:15:15.227 SIPDLG[3879255]: register TRN[14460885]
18:15:15.227 SIPDLG[3879255]: TRN[14460885] flags set to 0x6
18:15:15.227 Sending  [13,UDP] 1054 bytes to 172.19.192.208:9104 >>>>>
INVITE sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049 SIP/2.0
From: <sip:16101@172.19.192.200:5060>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879254
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327121@172.19.192.200
CSeq: 1 INVITE
Content-Length: 251
Content-Type: application/sdp
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120490
Contact: <sip:501@172.19.192.200:5060>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER
Max-Forwards: 70
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: timer


v=0
o=- 1349117581 2 IN IP4 0.0.0.0
s=Polycom IP Phone
c=IN IP4 0.0.0.0
t=0 0
a=sendrecv
m=audio 2226 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000

18:15:15.227 SipDialog: event SEND_INVITE, t=14460885, s=2, r=6, m=c555ed4
18:15:15.227 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(SEND_INVITE) - filtered
18:15:15.227 SIPCONN(REC_StreamManager2): sdp state SDP_STATE_NULL, event SDP_OFFER_SENT
18:15:15.227 SIPCONN(REC_StreamManager2): new sdp state SDP_OFFER_SENT, event SDP_OFFER_SENT
18:15:15.227 -----------------------------------------------------------
18:15:15.227 C[503881]:CF[D[[0]]:SC[13175795]
18:15:15.227 P[2724326]:D[724303368[3944802]]:LID[0]:D2[7140[3944804]]
18:15:15.227 SIPCONN(724303368):  endPoint :CON[2768333]:PEER[2768577]:D[724303368[3944802]]:DLG[3878991 STATE[7]]:D2[16010[3944803]:TD[0[IM[no]]]:LCRC
18:15:15.227 P[2724494]:D[501[3945128]]:LID[0]:D2[724303368[3944802]]
18:15:15.227 SIPCONN(501):  endPoint :CON[2768577]:PEER[2768333]:D[501[3945128]]:DLG[3879248 STATE[7]]:D2[724303368[3944802]:TD[0[IM[no]]]:LCRC
18:15:15.227 HA: BEGIN SYNC: CALL[503881] SYNCTYPE[2]: SECOND CALL[0] SYNCTYPE[-1]
18:15:15.227 HA: END SYNC: CALL[503881] SYNCTYPE[2]: SECOND CALL[0] SYNCTYPE[-1]
@18:15:15.2275 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventUserEvent
attr_#1005 0
attr_#1004 227
attr_#1003 1337094915
attr_#1002 21167399
attr_#1001 1
attr_#1000 131072
attr_#16000 1
attr_#16102 [1077] 32 3A 31 7C..
attr_#16101 [788] 32 3A 31 7C..
attr_#16100 [32] 31 3A 31 7C..
AttributeUserEvent [16001]
@18:15:15.2275 [BSYNC] Trace: Sent
18:15:15.227 HA:MESSAGE:TYPE[0]: SYNCED
18:15:15.227 SIPS:LOGBLOCK:END:SIPDATA:]
@18:15:15.2284 [BSYNC] Trace: Received [14]:
message EventUserEvent
AttributeUserEvent [16002]
attr_#16100 [20] 31 3A 31 7C..
attr_#16000 1
attr_#1000 131072
attr_#1001 1
attr_#1002 5198477
attr_#1003 1337094912
attr_#1004 524
attr_#1005 0
18:15:15.228 SIPTS:<< SYNC(16002) <<
18:15:15.228 HA:MESSAGE:TYPE[1]:RECEIVED
18:15:15.228 HA:MESSAGE:TYPE[1]:PROCESSED
18:15:15.230 SIPS:LOGBLOCK:BEGIN:SIPDATA:[
18:15:15.230 Received [13,UDP] 812 bytes from 172.19.192.208:1187 <<<<<
SIP/2.0 200 OK
From: <sip:16101@172.19.192.200:5060>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879254
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303797
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327121@172.19.192.200
CSeq: 1 INVITE
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120490;received=172.19.192.200
Contact: <sip:172.19.192.208:9104>
Content-Type: application/sdp
Content-Length: 229


v=0
o=Genesys 3050066 3050066 IN IP4 172.19.192.208
s=StreamManager-7.6.006.01
c=IN IP4 172.19.192.208
t=0 0
m=audio 20496 RTP/AVP 0 101
a=sendrecv
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

18:15:15.231 SipDialog: event CALLING_RESOK, t=14460885, s=7, r=5, m=c555ed4
18:15:15.231 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(CALLING_RESOK)
18:15:15.231 SIPCONN(REC_StreamManager2): store remote content
18:15:15.231 SIPCONN(REC_StreamManager2): sdp state SDP_OFFER_SENT, event SDP_RECEIVED
18:15:15.231 SIPCONN(REC_StreamManager2): new sdp state SDP_STATE_NULL, event SDP_RECEIVED
18:15:15.231 SIPCONN(REC_StreamManager2): Reserve: 200
18:15:15.231 SIPCONN(REC_StreamManager2): SendAck(14460885)
18:15:15.231 Sending  [13,UDP] 561 bytes to 172.19.192.208:9104 >>>>>
ACK sip:172.19.192.208:9104 SIP/2.0
From: <sip:16101@172.19.192.200:5060>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879254
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303797
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327121@172.19.192.200
CSeq: 1 ACK
Content-Length: 0
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120491
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO



@18:15:15.2311 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventUserEvent
attr_#1005 0
attr_#1004 231
attr_#1003 1337094915
attr_#1002 21167400
attr_#1001 1
attr_#1000 131072
attr_#16000 1
attr_#16500 [665] 31 3D 33 0D..
AttributeUserEvent [16099]
@18:15:15.2311 [BSYNC] Trace: Sent
18:15:15.231 HA:MESSAGE:TYPE[2]: SYNCED
18:15:15.231 SipDialog: event CONNECTED_SEND_ACK, t=14460885, s=7, r=8, m=c555ed4
18:15:15.231 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(CONNECTED_SEND_ACK) - filtered
18:15:15.231 SIPTR(13175799): complete
18:15:15.231 SIPTR(13175797): Step 0 - SipTransactionReserveMediaChannel(13175799) complete
18:15:15.231 SIPTR(13175797): Begin step 1 - SipTransactionReserveMediaChannel(13175800)
18:15:15.231 SipDialog: set monitor c605a5c
18:15:15.231 SIPCONN(REC_StreamManager2): main dialog 0 created
18:15:15.231 MakeInactive(REC_StreamManager2)
18:15:15.231 SIPCONN(REC_StreamManager2): Local contact: '<sip:724303368@172.19.192.200:5060>'
@18:15:15.2313 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventUserEvent
attr_#1005 0
attr_#1004 231
attr_#1003 1337094915
attr_#1002 21167401
attr_#1001 1
attr_#1000 131072
attr_#16000 1
attr_#16500 [85] 31 3D 31 0D..
AttributeUserEvent [16099]
@18:15:15.2313 [BSYNC] Trace: Sent
18:15:15.231 HA:MESSAGE:TYPE[2]: SYNCED
18:15:15.231 SIPDLG[3879256]: register TRN[14460886]
18:15:15.231 SIPDLG[3879256]: TRN[14460886] flags set to 0x6
18:15:15.231 Sending  [13,UDP] 1029 bytes to 172.19.192.208:9104 >>>>>
INVITE sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049 SIP/2.0
From: sip:724303368@172.19.192.251;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879255
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327122@172.19.192.200
CSeq: 1 INVITE
Content-Length: 223
Content-Type: application/sdp
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120492
Contact: <sip:724303368@172.19.192.200:5060>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER
Max-Forwards: 70
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: timer


v=0
o=CiscoSystemsSIP-GW-UserAgent 1349117584 2 IN IP4 0.0.0.0
s=SIP Call
c=IN IP4 0.0.0.0
t=0 0
m=audio 18330 RTP/AVP 0 101
c=IN IP4 0.0.0.0
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

18:15:15.231 SipDialog: event SEND_INVITE, t=14460886, s=2, r=6, m=c605a5c
18:15:15.231 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(SEND_INVITE) - filtered
18:15:15.231 SIPCONN(REC_StreamManager2): sdp state SDP_STATE_NULL, event SDP_OFFER_SENT
18:15:15.231 SIPCONN(REC_StreamManager2): new sdp state SDP_OFFER_SENT, event SDP_OFFER_SENT
18:15:15.231 SIPS:LOGBLOCK:END:SIPDATA:]
18:15:15.233 SIPS:LOGBLOCK:BEGIN:SIPDATA:[
18:15:15.233 Received [13,UDP] 797 bytes from 172.19.192.208:1187 <<<<<
SIP/2.0 200 OK
From: sip:724303368@172.19.192.251;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879255
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303798
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327122@172.19.192.200
CSeq: 1 INVITE
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120492;received=172.19.192.200
Contact: <sip:172.19.192.208:9104>
Content-Type: application/sdp
Content-Length: 217


v=0
o=Genesys 3050067 3050067 IN IP4 172.19.192.208
s=StreamManager-7.6.006.01
c=IN IP4 172.19.192.208
t=0 0
m=audio 20498 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

18:15:15.233 SipDialog: event CALLING_RESOK, t=14460886, s=7, r=5, m=c605a5c
18:15:15.233 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(CALLING_RESOK)
18:15:15.233 SIPCONN(REC_StreamManager2): store remote content
18:15:15.233 SIPCONN(REC_StreamManager2): sdp state SDP_OFFER_SENT, event SDP_RECEIVED
18:15:15.233 SIPCONN(REC_StreamManager2): new sdp state SDP_STATE_NULL, event SDP_RECEIVED
18:15:15.233 SIPCONN(REC_StreamManager2): Reserve: 200
18:15:15.233 SIPCONN(REC_StreamManager2): SendAck(14460886)
18:15:15.233 Sending  [13,UDP] 558 bytes to 172.19.192.208:9104 >>>>>
ACK sip:172.19.192.208:9104 SIP/2.0
From: sip:724303368@172.19.192.251;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879255
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303798
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327122@172.19.192.200
CSeq: 1 ACK
Content-Length: 0
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120493
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO



@18:15:15.2337 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventUserEvent
attr_#1005 0
attr_#1004 233
attr_#1003 1337094915
attr_#1002 21167402
attr_#1001 1
attr_#1000 131072
attr_#16000 1
attr_#16500 [668] 31 3D 33 0D..
AttributeUserEvent [16099]
@18:15:15.2337 [BSYNC] Trace: Sent
18:15:15.233 HA:MESSAGE:TYPE[2]: SYNCED
18:15:15.233 SipDialog: event CONNECTED_SEND_ACK, t=14460886, s=7, r=8, m=c605a5c
18:15:15.233 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(CONNECTED_SEND_ACK) - filtered
18:15:15.233 SIPTR(13175800): complete
18:15:15.233 SIPTR(13175797): Step 1 - SipTransactionReserveMediaChannel(13175800) complete
18:15:15.233 SIPTR(13175797): Begin step 2 - SipTransactionAttachMediaProxy(13175801)
18:15:15.233 SIPCONN(REC_StreamManager2): set monitor c555e60, a373fd4
18:15:15.233 SIPCONN(REC_StreamManager2): set monitor c6059e8, a373fd4
18:15:15.233 SIPTR(13175801): complete
18:15:15.233 SIPTR(13175797): Step 2 - SipTransactionAttachMediaProxy(13175801) complete
18:15:15.233 SIPTR(13175797): Begin step 3 - SipTransactionConnectPair(13175802)
18:15:15.233 SIPCONN(501): re-invite-connected
18:15:15.233 SIPCONN(REC_StreamManager2): re-invite-connected
18:15:15.233 SIPCONN(REC_StreamManager2): GetOffer
18:15:15.233 SIPCONN(REC_StreamManager2): GetOffer::SendReInvite
18:15:15.233 SIPDLG[3879255]: register TRN[14460887]
18:15:15.233 SIPDLG[3879255]: TRN[14460887] flags set to 0x6
18:15:15.233 Sending  [13,UDP] 692 bytes to 172.19.192.208:9104 >>>>>
INVITE sip:172.19.192.208:9104 SIP/2.0
From: <sip:16101@172.19.192.200:5060>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879254
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303797
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327121@172.19.192.200
CSeq: 2 INVITE
Content-Length: 0
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120494
Contact: <sip:501@172.19.192.200:5060>
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO
Max-Forwards: 70
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: timer



18:15:15.233 SipDialog: event CONNECTED_SEND_REINVITE, t=14460887, s=7, r=6, m=c555ed4
18:15:15.233 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(CONNECTED_SEND_REINVITE) - filtered
18:15:15.233 SIPCONN(REC_StreamManager2): sdp state SDP_STATE_NULL, event SDP_OFFER_REQUESTED
18:15:15.233 SIPCONN(REC_StreamManager2): new sdp state SDP_OFFER_REQUESTED, event SDP_OFFER_REQUESTED
18:15:15.233 SIPS:LOGBLOCK:END:SIPDATA:]
18:15:15.234 SIPS:LOGBLOCK:BEGIN:SIPDATA:[
18:15:15.234 Received [13,UDP] 819 bytes from 172.19.192.208:1187 <<<<<
SIP/2.0 200 OK
From: <sip:16101@172.19.192.200:5060>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879254
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303797
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327121@172.19.192.200
CSeq: 2 INVITE
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120494;received=172.19.192.200
Contact: <sip:172.19.192.208:9104>
Content-Type: application/sdp
Content-Length: 236


v=0
o=Genesys 3050068 3050068 IN IP4 172.19.192.208
s=StreamManager-7.6.006.01
c=IN IP4 172.19.192.208
t=0 0
m=audio 20496 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=x-media-op:conf

18:15:15.234 SipDialog: event CONNECTED_RESOK, t=14460887, s=7, r=5, m=c555ed4
18:15:15.234 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(CONNECTED_RESOK)
18:15:15.234 SIPCONN(REC_StreamManager2): store remote content
18:15:15.234 SIPCONN(REC_StreamManager2): sdp state SDP_OFFER_REQUESTED, event SDP_RECEIVED
18:15:15.234 SIPCONN(REC_StreamManager2): new sdp state SDP_OFFER_RECEIVED, event SDP_RECEIVED
18:15:15.234 SIPCONN(REC_StreamManager2): Connect(1,33)
18:15:15.234 SIPCONN(REC_StreamManager2): NotifyOnOffer
18:15:15.234 SIPCONN(501): SendOffer
18:15:15.234 SIPCONN(501): SendOffer::ReInvite
18:15:15.234 add party '501' state
18:15:15.234 SIPDLG[3879248]: register TRN[14460888]
18:15:15.234 SIPDLG[3879248]: TRN[14460888] flags set to 0x6
18:15:15.234 Sending  [13,UDP] 892 bytes to 172.16.205.201:5060 >>>>>
INVITE sip:501@172.16.205.201 SIP/2.0
From: sip:724303368@172.19.192.251;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879247
To: <sip:16101@172.19.192.200:5060>;tag=72ACF25E-2709D59B
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327114@172.19.192.200
CSeq: 2 INVITE
Content-Length: 233
Content-Type: application/sdp
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120495
Contact: <sip:724303368@172.19.192.200:5060>
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO
X-Genesys-CallUUID: 01F3K4KDLOFMFF9804000VTAES0BE06C
Max-Forwards: 69
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: timer


v=0
o=Genesys 1349117547 2 IN IP4 172.19.192.208
s=StreamManager-7.6.006.01
c=IN IP4 172.19.192.208
t=0 0
m=audio 20496 RTP/AVP 0 101
a=x-media-op:conf
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

......output omitted......


@18:15:15.4153 [0] 7.6.000.77 distribute_event: message EventAttachedDataChanged
AttributeEventSequenceNumber 000000000318cc08
AttributeTimeinuSecs 415396
AttributeTimeinSecs 1337094915 (18:15:15)
AttributeThirdPartyDN '501'
AttributeThisDNRole 2
AttributeThisDN '501'
AttributeCustomerID 'Resources'
AttributeANI '724303368'
AttributeDNIS '16010'
AttributeUserData [770] 00 20 03 00..
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'IVR_APPID' '4'
'AREA' '1'
'DIG1' '1'
'DIG2' '1'
'SECT' '1'
'SRV' 'FE_MU'
'PegDEF' 1
'PB1' 'Muntenia_TA'
'PB2' 'Bucarest'
'PB3' 'Sector 1'
'RVQID' '01HHF06E0SFKN3OSPJ017B5AES0196QM'
'RTargetTypeSelected' '2'
'RTargetRuleSelected' ''
'RTargetObjectSelected' 'FE_MU'
'RTargetObjSelDBID' '127'
'RTargetAgentSelected' 'LDC036'
'RTargetPlaceSelected' 'Place_501'
'RTenant' 'Resources'
'RStrategyName' 'QUEUE_V05_1'
'RStrategyDBID' '168'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RTargetRequested' 'FE_MU'
'PegAGFE_MU' 1
'GSIP_REC_FN' 'recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049'
AttributeCallUUID '01F3K4KDLOFMFF9804000VTAES0BE06C'
AttributeConnID 006c020612d32049
AttributeCallID 503881
AttributeCallType 2

......output omitted......

@18:15:15.4156 [ISCC] Updated call:
i c:006c020612d32049,0be3d798 @ m:0000000000000000,00000000 p:2 i:0007b049 nw:0000000000000000 t:2
  p:0000000000000000,0c39f658 @ c:006c020612d32049,0be3d798 r:1 ------ n:724303368:
  p:0000000000000000,0b5a3a28 @ c:006c020612d32049,0be3d798 r:2 ------ n:501:
@18:15:15.4156 [ISCC] UserData propagation has been started
@18:15:15.4156 [ISCC] UserData propagation has been finished
18:15:15.415 SIPTR(13175804): complete
18:15:15.415 SIPTR(13175797): Step 5 - SipTransactionNotifyOnRecordingStarted(13175804) complete
18:15:15.415 SIPTR(13175797): complete
18:15:15.415 SIPTR(13175795): Step 0 - SipScenario(13175797) complete
18:15:15.415 SIPTR(13175795): complete
18:15:15.415 SIPCM: transaction SipScenario(13175795) complete
18:15:15.415 cannot reserve queue subscription for queue: 16101, dn 501
18:15:15.415 PI: 00 S[CC]D[724303368]C[*D[724303368]]P[REC_StreamManager2]
18:15:15.415 PI: 00 S[CC]D[501]C[*D[501]]MP[C[*D[REC_StreamManager2]]C[*D[REC_StreamManager2]]]P[724303368]
18:15:15.415 CALLSTATE(a:2,d:0,i:0,e:1,r:0,o:0)
18:15:15.415 -----------------------------------------------------------
18:15:15.415 C[503881]:CF[D[[0]]:SC[13175795]
18:15:15.415 P[2724326]:D[724303368[3944802]]:LID[0]:D2[7140[3944804]]
18:15:15.415 SIPCONN(724303368):  endPoint :CON[2768333]:PEER[2768584]:D[724303368[3944802]]:DLG[3878991 STATE[7]]:D2[16010[3944803]:TD[0[IM[no]]]:LCRC
18:15:15.415 P[2724494]:D[501[3945128]]:LID[0]:D2[724303368[3944802]]
18:15:15.415 SIPCONN(501):  endPoint :CON[2768577]:PEER[2768583]:D[501[3945128]]:DLG[3879248 STATE[7]]:D2[724303368[3944802]:TD[0[IM[no]]]:LCRC
18:15:15.415 MP Type SIP_MEDIA_PROXY_TYPE_RECORDER
18:15:15.415 SIPCONN(REC_StreamManager2):  mediaProxyIn :CON[2768583]:PEER[2768577]:D[REC_StreamManager2[3945140]]:DLG[3879255 STATE[7]]:D2[501[3945128]:TD[0[IM[no]]]:LCRC
18:15:15.415 SIPCONN(REC_StreamManager2):  mediaProxyOut :CON[2768584]:PEER[2768333]:D[REC_StreamManager2[3945140]]:DLG[3879256 STATE[7]]:D2[724303368[3944802]:TD[0[IM[no]]]:LCRC
18:15:15.415 HA: BEGIN SYNC: CALL[503881] SYNCTYPE[2]: SECOND CALL[0] SYNCTYPE[-1]
18:15:15.415 HA: END SYNC: CALL[503881] SYNCTYPE[2]: SECOND CALL[0] SYNCTYPE[-1]
@18:15:15.4160 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventUserEvent
attr_#1005 0
attr_#1004 416
attr_#1003 1337094915
attr_#1002 21167420
attr_#1001 1
attr_#1000 131072
attr_#16000 1
attr_#16102 [1738] 32 3A 31 7C..
attr_#16101 [747] 32 3A 31 7C..
attr_#16100 [32] 31 3A 31 7C..
AttributeUserEvent [16001]
@18:15:15.4160 [BSYNC] Trace: Sent
18:15:15.416 HA:MESSAGE:TYPE[0]: SYNCED
18:15:15.416 scenario complete
18:15:15.416 SIPS:LOGBLOCK:END:SIPDATA:]
@18:15:15.4171 [BSYNC] Trace: Received [14]:
message EventUserEvent
AttributeUserEvent [16002]
attr_#16100 [20] 31 3A 31 7C..
attr_#16000 1
attr_#1000 131072
attr_#1001 1
attr_#1002 5198480
attr_#1003 1337094912
attr_#1004 713
attr_#1005 0
18:15:15.417 SIPTS:<< SYNC(16002) <<
18:15:15.417 HA:MESSAGE:TYPE[1]:RECEIVED
18:15:15.417 HA:MESSAGE:TYPE[1]:PROCESSED
18:15:15.574 SIPS:LOGBLOCK:BEGIN:SIPDATA:[
18:15:15.574 Received [13,UDP] 491 bytes from 172.16.205.201:5060 <<<<<
BYE sip:724303368@172.19.192.200:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.205.201;branch=z9hG4bK2cde9a45D627AB00
From: <sip:16101@172.19.192.200:5060>;tag=72ACF25E-2709D59B
To: <sip:724303368@172.19.192.251>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879247
CSeq: 1 BYE
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327114@172.19.192.200
Contact: <sip:501@172.16.205.201>
User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439
Accept-Language: en
Max-Forwards: 70
Content-Length: 0



18:15:15.574 SIPDLG[3879248]: register TRN[14460898]
18:15:15.574 SipDialog: event BYE, t=14460898, s=8, r=5, m=c560b7c
18:15:15.574 SIPCONN(501): HandleSipDialogEvent(BYE)
18:15:15.574 SIPCONN(501): new transaction
18:15:15.574 SIPCONN(501): 1pcc event BYE
18:15:15.574 SIPCONN(501): SendResponse(200,14460898)
18:15:15.574 add party '501' state
18:15:15.574 Sending  [13,UDP] 507 bytes to 172.16.205.201:5060 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.205.201;branch=z9hG4bK2cde9a45D627AB00;received=172.16.205.201
From: <sip:16101@172.19.192.200:5060>;tag=72ACF25E-2709D59B
To: <sip:724303368@172.19.192.251>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879247
CSeq: 1 BYE
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327114@172.19.192.200
Contact: <sip:724303368@172.19.192.200:5060>
X-Genesys-CallUUID: 01F3K4KDLOFMFF9804000VTAES0BE06C
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO
Content-Length: 0



18:15:15.574 SipDialog: event TERMINATING_SEND_BYE_RES, t=14460898, s=9, r=8, m=c560b7c
18:15:15.574 SIPCONN(501): HandleSipDialogEvent(TERMINATING_SEND_BYE_RES) - filtered
18:15:15.574 SIPCONN(REC_StreamManager2): ClrMediaPeer
18:15:15.574 SIPCONN(724303368): ClrMediaPeer
18:15:15.574 SIPCONN(501): CONNCHECK: dialog=9
18:15:15.574 SIPCONN(501): set monitor c560b08, 0
18:15:15.574 SIPCONN(501): state e:1,p:3,s:0,c:8,rc:0,m:0
18:15:15.574 SipDialog: ClearCall(phone=1,state=9)
18:15:15.574 SipDialog::Terminate(state=9)
18:15:15.574 SipDialog: set monitor 0
18:15:15.574 SIPCONN(501): DetachMediaPeer
18:15:15.574 SIPCONN(501): ClrMediaPeer
18:15:15.574 SIPCONN(501): media peer 'REC_StreamManager2' refers to cleared connection
18:15:15.574 SIPCONN(REC_StreamManager2): DetachMediaPeer
18:15:15.574 SIPCONN(501): ClrMediaPeer
18:15:15.574 SIPCONN(REC_StreamManager2): DetachMediaPeer
18:15:15.574 SIPCONN(REC_StreamManager2): state e:1,p:3,s:0,c:8,rc:0,m:1
18:15:15.574 SipDialog: ClearCall(phone=0,state=7)
18:15:15.574 SipDialog::Terminate(state=7)
18:15:15.574 SIPDLG[3879255]: register TRN[14460899]
18:15:15.574 Sending  [13,UDP] 526 bytes to 172.19.192.208:9104 >>>>>
BYE sip:172.19.192.208:9104 SIP/2.0
From: <sip:16101@172.19.192.200:5060>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879254
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303797
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327121@172.19.192.200
CSeq: 3 BYE
Content-Length: 0
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120517
Max-Forwards: 70



18:15:15.574 SipDialog: event SEND_BYE, t=14460899, s=8, r=5, m=c555ed4
18:15:15.574 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(SEND_BYE) - filtered
18:15:15.574 SipDialog: set monitor 0
18:15:15.574 SIPCONN(REC_StreamManager2): DetachMediaPeer
18:15:15.574 SIPCONN(REC_StreamManager2): set monitor c555e60, 0
18:15:15.574 SIPCONN(REC_StreamManager2): state e:1,p:3,s:0,c:8,rc:0,m:1
18:15:15.574 SipDialog: ClearCall(phone=0,state=7)
18:15:15.574 SipDialog::Terminate(state=7)
18:15:15.574 SIPDLG[3879256]: register TRN[14460900]
18:15:15.574 Sending  [13,UDP] 523 bytes to 172.19.192.208:9104 >>>>>
BYE sip:172.19.192.208:9104 SIP/2.0
From: sip:724303368@172.19.192.251;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879255
To: <sip:conf=4f678def000445da@172.19.192.208:9104;record=recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049>;tag=6F9B0F87-1F9E-42AE-BA25-C8F79A9ED21D-1303798
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327122@172.19.192.200
CSeq: 3 BYE
Content-Length: 0
Via: SIP/2.0/UDP 172.19.192.200:5060;branch=z9hG4bK0009687A-8DA7-1F67-BD28-0100007FAA77-15120518
Max-Forwards: 70



18:15:15.575 SipDialog: event SEND_BYE, t=14460900, s=8, r=5, m=c605a5c
18:15:15.575 SIPCONN(REC_StreamManager2): HandleSipDialogEvent(SEND_BYE) - filtered
18:15:15.575 SipDialog: set monitor 0
18:15:15.575 SIPCONN(REC_StreamManager2): DetachMediaPeer
18:15:15.575 SIPCONN(REC_StreamManager2): set monitor c6059e8, 0
18:15:15.575 ClearContext: party 501.0xc4f2880-0xad1bc78:1
18:15:15.575 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventReleased
    Devices: <501/501> <-/724303368> <-/->
    Calls: 503881/006c020612d32049/503881.0xad1bc78/c:2/r:9 0/none
    Parties: D501/501.0xc4f2880-0xad1bc78:1/l:2/r:0/Established,Active,Destination
    X724303368/724303368.0xafda2b8-0xad1bc78:1/l:1/r:0/Established,Origination
    none
    Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
  --- Pre-event ---
  -- AgnEmu: starting ACW for 501 - period=2
@18:15:15.5751 [0] 7.6.000.77 distribute_response: message EventAgentNotReady
AttributeExtensions [72] 00 02 01 00..
'WrapUpTime' 2
'AgentSessionID' '01F3K4KDLOFMFF9804000VTAES0BB04K'
AttributeEventSequenceNumber 000000000318cc09
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 575114
AttributeTimeinSecs 1337094915 (18:15:15)
AttributeThisQueue '15000'
AttributeAgentID '2036'
AttributeAgentWorkMode 3 (AfterCallWork)
AttributeThisDN '501'
@18:15:15.5751 [ISCC] Translate: '' -> ''; result 1 ()
18:15:15.575 Trc 04542 EventAgentNotReady sent to [37] (00000046 AIL_Service_3 172.19.192.209:2108)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [33] (00000047 AIL_Service_2 172.19.192.212:4487)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [18] (00000051 OCS_SServer_BKP 172.19.192.208:2811)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [20] (0000003d GAD2_SServer 172.19.192.94:1200)
masked for 16 (0000003a OCS_BKP)
masked for 24 (00000036 OCS_PRI)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [19] (00000035 GAD1_SServer 172.19.192.93:1164)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [31] (00000014 SIP_ICON2 172.19.192.216:3357)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [30] (00000013 SIP_ICON1 172.19.192.215:4704)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [32] (00000015 CCA_SServer_BKP 172.19.192.214:2197)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [29] (00000012 CCA_SServer_PRI 172.19.192.213:1914)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [28] (00000011 CCP_StatServer_PRI 172.19.192.214:2196)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [25] (0000000e URS_StatServer_PRI 172.19.192.204:3227)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [22] (0000000b CCP_StatServer_BCK 172.19.192.213:1913)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [21] (0000000a OCS_SServer_PRI 172.19.192.207:4807)
18:15:15.575 Trc 04542 EventAgentNotReady sent to [17] (00000006 URS_StatServer_BCK 172.19.192.203:15315)
  -- AgnEmu: synchronising agent state 0x122 for 501
@18:15:15.5753 [BSYNC] Trace: Send to backup (SIP_Server_BCK) [14]:
message EventSwitchInfo
attr_#1005 0
attr_#1004 575
attr_#1003 1337094915
attr_#1002 21167421
attr_#1001 3
attr_#1000 131072
AttributeReason 3
AttributeOtherQueue 2
AttributeThisQueue 0
AttributeThisDN 2
AttributeThisDNRole 0
AttributeThisTrunk 2
AttributeOtherDN 3
AttributeANI 290
attr_#15901 7077953
AttributeAgentStateReasonUnused '15000'
AttributeAgentID '2036'
AttributeCallState 3
AttributeConferenceConnID 0
AttributeTransferConnID 1
AttributeDNIS 4
AttributeNodeID 16
AttributeErrorMessage 64
AttributeProtocolVersion '501'
@18:15:15.5753 [BSYNC] Trace: Sent
  -- AgnEmu: ACW started
  +++ Released +++
    SetReleased: party 501.0xc4f2880-0xad1bc78:1, cause Null
    -- party_info 501.0xb5a3a28 state change: from <Connected> to <Null>
    -- G7 release
@18:15:15.5753 [ISCC] Party removed [ssp view]:
@ c:006c020612d32049,0be3d798 @ m:0000000000000000,00000000,0000000000000000 p:2 i:0007b049 nw:00000000:0000000000000000 t:2
  p:0c39f658 @ c:006c020612d32049,0be3d798 r:1 t:1 s:c n:724303368
- p:0b5a3a28 @ c:006c020612d32049,0be3d798 r:2 t:0 s:0 n:501
@18:15:15.5753 [ISCC] Party removed:
@ c:006c020612d32049,0be3d798 @ m:0000000000000000,00000000 p:2 i:0007b049 nw:0000000000000000 t:2
  p:0000000000000000,0c39f658 @ c:006c020612d32049,0be3d798 r:1 ------ n:724303368:
- p:0000000000000000,0b5a3a28 @ c:006c020612d32049,0be3d798 r:2 ------ n:501:
@18:15:15.5753 [ISCC] Party object is removed: p:0000000000000000,00000000 n:501:
@18:15:15.5753 [0] 7.6.000.77 distribute call/party event: message EventCallPartyDeleted
AttributeEventSequenceNumber 000000000318cc0a
AttributeTimeStamp 4fb273030008c7a5
AttributeConnID 006c020612d32049
AttributeCallUUID '01F3K4KDLOFMFF9804000VTAES0BE06C'
AttributePartyUUID '01F3K4KDLOFMFF9804000VTAES0BE0UF'
18:15:15.575 Int 04544 Interaction message "EventCallPartyDeleted" generated
18:15:15.575 Trc 04542 EventCallPartyDeleted sent to [31] (00000014 SIP_ICON2 172.19.192.216:3357)
18:15:15.575 Int 04544 Interaction message "EventCallPartyDeleted" generated
18:15:15.575 Trc 04542 EventCallPartyDeleted sent to [30] (00000013 SIP_ICON1 172.19.192.215:4704)
@18:15:15.5754 [0] 7.6.000.77 distribute_response: message EventReleased
AttributeEventSequenceNumber 000000000318cc0b
AttributeTimeinuSecs 575442
AttributeTimeinSecs 1337094915 (18:15:15)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeOtherDNRole 1
AttributeOtherDN '724303368'
AttributeCustomerID 'Resources'
AttributeANI '724303368'
AttributeDNIS '16010'
AttributeUserData [770] 00 20 03 00..
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'IVR_APPID' '4'
'AREA' '1'
'DIG1' '1'
'DIG2' '1'
'SECT' '1'
'SRV' 'FE_MU'
'PegDEF' 1
'PB1' 'Muntenia_TA'
'PB2' 'Bucarest'
'PB3' 'Sector 1'
'RVQID' '01HHF06E0SFKN3OSPJ017B5AES0196QM'
'RTargetTypeSelected' '2'
'RTargetRuleSelected' ''
'RTargetObjectSelected' 'FE_MU'
'RTargetObjSelDBID' '127'
'RTargetAgentSelected' 'LDC036'
'RTargetPlaceSelected' 'Place_501'
'RTenant' 'Resources'
'RStrategyName' 'QUEUE_V05_1'
'RStrategyDBID' '168'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RTargetRequested' 'FE_MU'
'PegAGFE_MU' 1
'GSIP_REC_FN' 'recordings/new/call_2012-05-15_15-15-15_724303368_2036_006c020612d32049'
AttributeCallUUID '01F3K4KDLOFMFF9804000VTAES0BE06C'
AttributeConnID 006c020612d32049
AttributeCallID 503881
AttributeCallType 2
AttributeCallState 0
AttributeThirdPartyQueue '16010'
AttributeOtherQueue '16111'
AttributeThisQueue '16101'
AttributeThisDNRole 2
AttributeAgentID '2036'
AttributeThisDN '501'
@18:15:15.5754 [ISCC] Translate: '724303368' -> ''; result 1 ()
@18:15:15.5755 [ISCC] Party object is removed: p:0000000000000000,00000000 n::
18:15:15.575 Int 04544 Interaction message "EventReleased" generated
18:15:15.575 Trc 04542 EventReleased sent to [33] (00000047 AIL_Service_2 172.19.192.212:4487)
18:15:15.575 Trc 04542 EventReleased sent to [37] (00000046 AIL_Service_3 172.19.192.209:2108)
18:15:15.575 Trc 04542 EventReleased sent to [18] (00000051 OCS_SServer_BKP 172.19.192.208:2811)
18:15:15.575 Trc 04542 EventReleased sent to [20] (0000003d GAD2_SServer 172.19.192.94:1200)
18:15:15.575 Trc 04542 EventReleased sent to [16] (0000003a OCS_BKP 172.19.192.94:1051)
18:15:15.575 Trc 04542 EventReleased sent to [24] (00000036 OCS_PRI 172.19.192.93:1170)
18:15:15.575 Trc 04542 EventReleased sent to [19] (00000035 GAD1_SServer 172.19.192.93:1164)
18:15:15.575 Trc 04542 EventReleased sent to [31] (00000014 SIP_ICON2 172.19.192.216:3357)
18:15:15.575 Trc 04542 EventReleased sent to [30] (00000013 SIP_ICON1 172.19.192.215:4704)
18:15:15.575 Trc 04542 EventReleased sent to [32] (00000015 CCA_SServer_BKP 172.19.192.214:2197)
18:15:15.575 Trc 04542 EventReleased sent to [29] (00000012 CCA_SServer_PRI 172.19.192.213:1914)
18:15:15.575 Trc 04542 EventReleased sent to [28] (00000011 CCP_StatServer_PRI 172.19.192.214:2196)
18:15:15.575 Trc 04542 EventReleased sent to [25] (0000000e URS_StatServer_PRI 172.19.192.204:3227)
18:15:15.575 Trc 04542 EventReleased sent to [22] (0000000b CCP_StatServer_BCK 172.19.192.213:1913)
18:15:15.575 Trc 04542 EventReleased sent to [21] (0000000a OCS_SServer_PRI 172.19.192.207:4807)
18:15:15.575 Trc 04542 EventReleased sent to [17] (00000006 URS_StatServer_BCK 172.19.192.203:15315)
    -- TellReleased
@18:15:15.5757 [0] 7.6.000.77 distribute_response: message EventOnHook
AttributeEventSequenceNumber 000000000318cc0c
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 575735
AttributeTimeinSecs 1337094915 (18:15:15)
AttributeThisDN '501'
[/i]




Thank you

Offline René

  • Administrator
  • Hero Member
  • *****
  • Posts: 1832
  • Karma: 62
Re: SIP call dropped after being answered
« Reply #1 on: May 16, 2012, 10:53:39 AM »
Hi,

BYE was received from agent's SIP endpoint so it looks like agent has released the call or something went wrong with agent's SIP endpoint.

[quote]18:15:15.574 [b][color=red]Received [13,UDP] 491 bytes from 172.16.205.201:5060[/color][/b] <<<<<
BYE sip:724303368@172.19.192.200:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.205.201;branch=z9hG4bK2cde9a45D627AB00
From: <sip:16101@172.19.192.200:5060>;tag=72ACF25E-2709D59B
To: <sip:724303368@172.19.192.251>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879247
CSeq: 1 BYE
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327114@172.19.192.200
Contact: <sip:501@172.16.205.201>
User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439
Accept-Language: en
Max-Forwards: 70
Content-Length: 0[/quote]
R.

Offline dnib

  • Jr. Member
  • **
  • Posts: 57
  • Karma: 0
Re: SIP call dropped after being answered
« Reply #2 on: May 16, 2012, 11:20:14 AM »
[quote author=René link=topic=7147.msg30712#msg30712 date=1337165619]
Hi,

BYE was received from agent's SIP endpoint so it looks like agent has released the call or something went wrong with agent's SIP endpoint.

[quote]18:15:15.574 [b][color=red]Received [13,UDP] 491 bytes from 172.16.205.201:5060[/color][/b] <<<<<
BYE sip:724303368@172.19.192.200:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.205.201;branch=z9hG4bK2cde9a45D627AB00
From: <sip:16101@172.19.192.200:5060>;tag=72ACF25E-2709D59B
To: <sip:724303368@172.19.192.251>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879247
CSeq: 1 BYE
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327114@172.19.192.200
Contact: <sip:501@172.16.205.201>
User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439
Accept-Language: en
Max-Forwards: 70
Content-Length: 0[/quote]
R.
[/quote]

Thank you René,
I see that the message was received from the endpoints IP, but what is the meaning of:
[i]From: <sip:16101@172.19.192.200:5060>;[/i] ?

Offline dnib

  • Jr. Member
  • **
  • Posts: 57
  • Karma: 0
Re: SIP call dropped after being answered
« Reply #3 on: May 16, 2012, 12:14:29 PM »
[quote author=dnib link=topic=7147.msg30714#msg30714 date=1337167214]
[quote author=René link=topic=7147.msg30712#msg30712 date=1337165619]
Hi,

BYE was received from agent's SIP endpoint so it looks like agent has released the call or something went wrong with agent's SIP endpoint.

[quote]18:15:15.574 [b][color=red]Received [13,UDP] 491 bytes from 172.16.205.201:5060[/color][/b] <<<<<
BYE sip:724303368@172.19.192.200:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.205.201;branch=z9hG4bK2cde9a45D627AB00
From: <sip:16101@172.19.192.200:5060>;tag=72ACF25E-2709D59B
To: <sip:724303368@172.19.192.251>;tag=00096866-8DA7-1F67-BD28-0100007FAA77-3879247
CSeq: 1 BYE
Call-ID: 0009683E-8DA7-1F67-BD28-0100007FAA77-2327114@172.19.192.200
Contact: <sip:501@172.16.205.201>
User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439
Accept-Language: en
Max-Forwards: 70
Content-Length: 0[/quote]
R.
[/quote]

Thank you René,
I see that the message was received from the endpoints IP, but what is the meaning of:
[i]From: <sip:16101@172.19.192.200:5060>;[/i] ?
[/quote]

After some more searching through GIS logs I found out that this is the way the calls are answered:

[i]        Ani                      724303368
        CallId                    503881
        CallType                  Inbound
        ConnId                    006c020612d32049
        Dnis                      16010
        EventType                AttachedDataChanged
        ThirdPartyDn              501
        ThisDn                    501
        ThisDnRole                Destination
[/i]


So thank you for the help. I don't know how the call was ended voluntarely or not but it's sure the BYE message came from the endoint.