Genesys CTI User Forum

Genesys CTI User Forum => Genesys CTI Technical Discussion => Topic started by: ajayku14 on February 09, 2015, 07:26:34 AM

Title: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 09, 2015, 07:26:34 AM

HI,

We are using Sip Architecture. We are dialling a tollfree number and the call lands on the GVPIVR, from the IVR the call is transferring to the routing point. After landing on the routing point using strategy I Trouted the call to the destination number and the destination number is avaya hard phone number. When we dial the TFN number the call is landing on the avaya phone number(Hard Phone) and can able to answer it manually. Using the same avaya number we can also login in soft phone, but this time when call lands on the soft phone the agent not able to answer it(But able to answer on the same time using Avaya Hard phone). AlsoAttached data is coming to the soft phone. Expect answering from the soft phone everything is working as expected.

Please give some suggestions how to work this.

The environment is as follows.
SipServer : 8.1
Destination number(Avaya Hard Phone) is created as Extension in CME.

If required any information please let me know.

Thanks
Ajay.

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: Kubig on February 09, 2015, 10:02:17 AM

Show us the settings of Extension object within Genesys configuration - main focus on option "sip-cti-control".

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 09, 2015, 12:46:20 PM

HI Kubig,

The Extension options are as below.

Tserver(section)
-------------------------
contact=sip:extensionnumber(6023)@hardphoneIP(165.xx.xx.yy);transport=tcp;avaya-sc-enabled
refer-enabled=false
sip-cti-control=talk,hold,dtmf

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: cavagnaro on February 09, 2015, 12:47:38 PM

When you use TRoute you lose integration, are you sure of that routing method??
Example,
If was using KVPs on Genesys will not arrive to Avaya extension

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 09, 2015, 01:07:13 PM

HI Cav,

I am not sure about losing the integration when Troute the call, But the attached data is coming to the Avaya Extensions and the testing team can able to see the data from avaya desktop softphone. 
The issue is they are not able to answer the phone from desktop softphone when the call hits on the extension(Avaya Desktop phone).

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: Kubig on February 09, 2015, 01:19:24 PM

And the logs says what?

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 09, 2015, 02:22:06 PM

Hi Kubig,

When we answer the call using Hard Phone we are not getting any errors but when the team tries to answer the call using soft phone at that time error is coming in sip server logs.From the soft phone we can able to release the call also.

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: cavagnaro on February 09, 2015, 02:47:03 PM

But what does logs say?
You endpoint support 3PCC?

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 10, 2015, 06:58:51 AM

HI Cav,

From softphone the error logs are showing as below.

================================================
UUSP.TelephonyModule.GenesysSoftException: A Universal SoftPhone error has occurred.
Please attempt this action again.
Dev. Info: An unrecognized error has occurred. | Code: 50 ---> UUSP.TelephonyModule.GenesysSoftException: Genesys TSERVER Server OnEventError error:
: TERR_UNKNOWN_ERROR | unknown | 50
  --- End of inner exception stack trace ---
  at UUSP.TelephonyModule.GenesysExceptionHandler.CheckForEventError(String server, String methodName, IMessage response, Type expectedType, Boolean fatalError, String additionalMessage) in C:\Work\USP\Nov 14\UUSP.TelephonyModule\GenesysExceptionsEx.cs:line 237
  at UUSP.TelephonyModule.GenesysExceptionHandler.CheckForEventError(String server, String methodName, IMessage response, Type expectedType, Boolean fatalError) in C:\Work\USP\Nov 14\UUSP.TelephonyModule\GenesysExceptionsEx.cs:line 252
  at UUSP.TelephonyModule.GenesysTelephonyModule.OnEventError(IMessage message) in C:\Work\USP\Nov 14\UUSP.TelephonyModule\GenesysTelephonyModule.cs:line 646
Customer loaded
=====================

From sipserver the error logs are as below.
============================
@19:27:49.8420 [0] 8.1.101.01 send_to_client: message EventError
(Unspecified error)
AttributeEventSequenceNumber 0000000000012a75
AttributeTimeinuSecs 842000
AttributeTimeinSecs 1423490269 (19:27:49)
AttributeErrorCode 50
AttributeErrorMessage 'unknown'
AttributeThisDN '6023'
AttributeConnID 006f025cd046d078
AttributeReferenceID 9
AttributeClientID 49
19:27:49.842 Int 04545 Interaction message "EventError" sent to 508 ("iws_test1")
19:27:49.842 Trc 04542 EventError sent to [508] (00000031 iws_test1 165.x.x.x:57929)
  -- deleted: CRequest@33a8a20 RequestAnswerCall-iws_test1[508]/9
19:27:49.842 --- CIFace::Request ---
19:27:49.842: $-TLIB:CTI:Unknown:0:346

19:27:49.848: $+NET:SIP::0:0
19:27:49.848: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
19:27:49.848: SIPTR: Received [648,TCP] 376 bytes from 165.X.X.X:5060 <<<<<
SIP/2.0 489 Bad Event
From: <sip:6015@162.X.X.X>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2333
To: <sip:11000@162.X.X.X:5060>;tag=-6eb8df2b38b357947166af3a_T6023165.X.X.X
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-204@162.X.X.X
CSeq: 3 NOTIFY
Via: SIP/2.0/TCP 162.X.X.X:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4269
Content-Length:    0



19:27:49.848: SipDialog: event EARLY_RES, t=82356, s=2, r=5, m=0000000001c48018
19:27:49.848 SIPCONN(6023): HandleSipDialogEvent(EARLY_RES)
19:27:49.848 SIPCONN(6023): concurrent transaction: c:82341, n:82356
19:27:49.848 SIPCONN(6023): event ignored
19:27:49.848: SIPS:LOGBLOCK:END:SIPMSG:]
19:27:49.848: $-NET:SIP::0:44

If required any information please let me know.


Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: Kubig on February 10, 2015, 08:55:34 AM

We need to see log messages before the EventError is generated (logically ;-)). You have attached just EventError and few rows after that, nothing else.

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 10, 2015, 01:08:24 PM

HI Kubig/Cav,

Please find the sip server log and the connection ID was 006f025cd046d077.
Please go through the logs and provide me suggestion.


========================================================
19:18:53.677: $-NET:SIP::0:186

19:18:55.896: $+NET:SIP::0:0
19:18:55.896: SIPTR: Received [0,UDP] 883 bytes from 165.42.84.135:5060 <<<<<
INVITE sip:18005559979@162.56.2.18 SIP/2.0
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK89bcf55a8
Max-Forwards: 70
Content-Length: 266
To: 18005559979 <sip:18005559979@162.56.2.18>
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632297 INVITE
Supported: timer
Allow: NOTIFY
Allow: REFER
Allow: OPTIONS
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: BYE
Content-Type: application/sdp
Contact: 6015 <sip:6015@165.42.84.135:5060>
Supported: replaces
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26

v=0
o=MxSIP 0 1359736395 IN IP4 165.42.84.135
s=SIP Call
c=IN IP4 165.42.84.135
t=0 0
m=audio 34008 RTP/AVP 0 8 18 2 127
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:127 telephone-event/8000
a=ptime:20

19:18:55.896: CallMatcher: no call match attributes found
19:18:55.896: ResolveCallInfo: set flag DIAL_PLAN_PROCESSING
19:18:55.896: SIPTR(1181): Begin step 0 - SipTransactionCreateCall(1182)
19:18:55.896 SIPCONN(6015): Create dialog
19:18:55.896: SipDialog: set monitor 0000000001c478c8
19:18:55.896 SIPCONN(6015): refer dialog 00000000034c8350 initiated
19:18:55.896 SIPCONN(6015): change transaction 0 -> 0
19:18:55.896: SipDialog: set monitor 0000000001c478c8
19:18:55.896 SIPCONN(6015): ClrMediaPeer
19:18:55.896: SIPDLG[2244]: register TRN[82193]
19:18:55.896: SIPDLG[2244]: TRN[82193] flags set to 0x1
19:18:55.896: SipDialog: event INVITE, t=82193, s=1, r=5, m=0000000001c478c8
19:18:55.896 SIPCONN(6015): HandleSipDialogEvent(INVITE)
19:18:55.896 SIPCONN(6015): Capabilities 200100
19:18:55.896 SIPCONN(6015): new transaction
19:18:55.896 SIPCONN(6015): store remote content
19:18:55.897 SIPCONN(6015): verify sdp
19:18:55.897 SIPCONN(6015): sdp state SDP_STATE_NULL, event SDP_EVENT_SDP
19:18:55.897 SIPCONN(6015): new sdp state SDP_STATE_OFFER_RECEIVED, event SDP_EVENT_SDP
19:18:55.897 SIPCONN(6015): state e:10,p:0,s:1,c:9,rc:0,m:0
19:18:55.897: SIPTR(1182): complete
19:18:55.897: SIPTR(1181): Step 0 - SipTransactionCreateCall(1182) complete
19:18:55.897: SIPTR(1181): Begin step 1 - SipTransactionResolveCallInfoByDialPlan(1183)
19:18:55.897: DialPlan: No dialplan defined for 6015.
19:18:55.897: DialPlan: No rule applied, using original destination '18005559979'.
19:18:55.897: DialPlan: clear flag DIAL_PLAN_PROCESSING
19:18:55.897: ProcessDialPlanResult: Connecting to DEVICE(931,18005559979).
19:18:55.897: Create regular call
@19:18:55.8970 {tscp.call {constructed} {uuid ROLVMNRH4T5DH7SG8IQ67GPNIG0000J3} {connection-id 006f025cd046d077} {call-id 122}}
19:18:55.897  -- created call_info_tspp 006f025cd046d077@3352c40
@19:18:55.8970 Call [006f025cd046d077]: urid 0001006f
19:18:55.897  -- created aTmCall 31aa0b0/3352c40
19:18:55.897  -- TmCallThread created: 31356c0
19:18:55.897 SetANI for 31aa0b0:1: 6015
19:18:55.897 SetDNIS for 31aa0b0:1: 18005559979
19:18:55.897: SIPCALL(122): add party '6015'
19:18:55.897  -- created party_info_tspp 24d15d0
19:18:55.897  -- created aTmParty 33b46f0
19:18:55.897  SetRole: Origination for 6015.33b46f0-31aa0b0:1
19:18:55.897  -- AddParty to 31aa0b0: 6015.33b46f0-31aa0b0:1
19:18:55.897  -- new TSCP call leg 1
19:18:55.897  -- call leg created leg_id=1
19:18:55.897 CreateParty new internal: 6015.33b46f0-31aa0b0:1
19:18:55.897: Call 122 dn 6015 SetPartyId 289
19:18:55.897 SIPCONN(6015): set monitor 0000000001c47810, 000000000332afc8
19:18:55.897 SIPCONN(18005559979): set monitor 0000000001c47f60, 000000000332bc98
19:18:55.897: SIPCALL(122): add party '18005559979'
19:18:55.897  -- created party_info_tspp 24d17d0
19:18:55.897  -- created aTmParty 33b4fb0
19:18:55.897  SetRole: Destination for 18005559979.33b4fb0-31aa0b0:1
19:18:55.897  -- AddParty to 31aa0b0: 18005559979.33b4fb0-31aa0b0:1
19:18:55.897  -- new TSCP call leg 2
19:18:55.897  -- call leg created leg_id=2
19:18:55.897 CreateParty new internal: 18005559979.33b4fb0-31aa0b0:1
19:18:55.897: Call 122 dn 18005559979 SetPartyId 290
19:18:55.897: SIPTR(1183): complete
19:18:55.897: SIPTR(1181): Step 1 - SipTransactionResolveCallInfoByDialPlan(1183) complete
19:18:55.897: SIPTR(1181): Begin step 2 - SipTransactionChangePartyState(1184)
19:18:55.897 ClearContext: party 6015.33b46f0-31aa0b0:1
19:18:55.897 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventOriginated
    Devices: <6015/6015> <18005559979/18005559979> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:0/r:0 0/none
    Parties: D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Null,DNull,Origination
    D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Null,Destination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Dialing +++
19:18:55.897: internal call originator not found
19:18:55.897: SIPS-IID:4F2ULGO89D60F9C3LGPO3GC55400003Q:006f025cd046d077:4F2ULGO89D60F9C3LGPO3GC55400003Q:TLIBEVT
    +++ CIFace::Event +++
      +++ Pre-event +++
        Type EventInitiated
        Devices: <6015/6015> <18005559979/18005559979> <-/->
        Calls: 122/006f025cd046d077/122.31aa0b0/c:0/r:0 0/none
        Parties: D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Null,DNull,Origination
        D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Null,Destination
        none
        Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
      --- Pre-event ---
      +++ NewCall +++
        -- call is consultation
@19:18:55.8970 [0] 8.1.101.01 distribute_event: message EventOffHook
AttributeEventSequenceNumber 00000000000129c5
AttributeTimeinuSecs 897000
AttributeTimeinSecs 1423489735 (19:18:55)
AttributeThisDN '6015'
19:18:55.897 Int 04544 Interaction message "EventOffHook" generated
19:18:55.897 Trc 04542 EventOffHook sent to [516] (00000003 STATSRV 162.56.2.16:58054)
19:18:55.897: device off hook
        SetActive: party 6015.33b46f0-31aa0b0:1, cause Null
        SetOriginating: party 6015.33b46f0-31aa0b0:1, cause NewCall
        -- party_info 6015.24d15d0 state change: from <Null> to <Initiated>
        -- AgnEmu: business type configured as private for extension
        -- AgnEmu: private call BusinessType[1/1:0]
        -- aTmCall::SetBusinessType():006f025cd046d077 set to BusinessType[1/1:0]
        -- aTmParty::SetBusinessType():6015 set to BusinessType[1/1:0]
        -- G7 init
        -- call_info::committed created
@19:18:55.8970 [ISCC] Call object is created: c:006f025cd046d077,0000000003352c40
@19:18:55.8970 [ISCC] Call created:
+ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:0 i:0000007a nw:0000000000000000 t:1
@19:18:55.8970 [ISCC] Party added [ssp view]:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000,0000000000000000 p:2 i:0000007a nw:00000000:0000000000000000 t:1
+ p:00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 t:0 s:1 n:6015
  p:00000000024d17d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:0 n:18005559979
@19:18:55.8970 [ISCC] Party object is created: p:0000000000000000,00000000024d15d0 n:6015:
@19:18:55.8970 [ISCC] Party added:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:1 i:0000007a nw:0000000000000000 t:1
+ p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
      --- NewCall ---
      +++ Post-event +++
        Type EventInitiated
        Devices: <6015/6015> <18005559979/18005559979> <-/->
        Calls: 122/006f025cd046d077/122.31aa0b0/c:1/r:0 0/006f025cd046d077/122.31aa0b0/c:1/r:0
        Parties: D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Originating,Active,Origination
        D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Null,Destination
        none
        Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
      --- Post-event ---
    --- CIFace::Event ---
    CreateParty: already exists: 18005559979.33b4fb0-31aa0b0:1
    SetDialling: party 6015.33b46f0-31aa0b0:1, cause Null
    -- party_info 6015.24d15d0 state change: from <Initiated> to <Connected,Dialing>
    -- G7 dialing
@19:18:55.8970 [0] 8.1.101.01 distribute_event: message EventDialing
AttributeEventSequenceNumber 00000000000129c6
AttributeTimeinuSecs 897000
AttributeTimeinSecs 1423489735 (19:18:55)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 0
AttributeOtherDNRole 2
AttributeOtherDN '18005559979'
AttributeThisDNRole 1
AttributeThisDN '6015'
AttributeDNIS '18005559979'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
19:18:55.897 Int 04544 Interaction message "EventDialing" generated
19:18:55.897 Trc 04542 EventDialing sent to [516] (00000003 STATSRV 162.56.2.16:58054)
  --- Dialing ---
  +++ Post-event +++
    Type EventOriginated
    Devices: <6015/6015> <18005559979/18005559979> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:1/r:0 0/006f025cd046d077/122.31aa0b0/c:1/r:0
    Parties: D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Dialing,Active,Origination
    D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Null,Destination
    none
    Call type: Internal/1
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:18:55.897 --- CIFace::Event ---
19:18:55.897: SIPTR(1184): complete
19:18:55.897: SIPTR(1181): Step 2 - SipTransactionChangePartyState(1184) complete
19:18:55.897: SIPTR(1181): Begin step 3 - SipTransactionGetOffer(1185)
19:18:55.897: SIPTR(1185): complete
19:18:55.897: SIPTR(1181): Step 3 - SipTransactionGetOffer(1185) complete
19:18:55.897: SIPTR(1181): Begin step 4 - SipTransactionConnectNewParty(1186)
Session value of the SDP is [0]
Version values of the SDP is [1359736395]
19:18:55.897 SIPCONN(18005559979): re-invite-null
19:18:55.897: SipDialog: set monitor 0000000001c48018
19:18:55.897 SIPCONN(18005559979): main dialog 0 created
19:18:55.897 SIPCONN(18005559979): Local contact: '<sip:6015@162.56.2.18:5060>'
19:18:55.897: add party info '18005559979' state 0.
19:18:55.898: SIPDLG[2245]: register TRN[82194]
19:18:55.898: SIPDLG[2245]: TRN[82194] flags set to 0x6
19:18:55.898: Sending  [0,UDP] 975 bytes to 162.56.2.19:5070 >>>>>
INVITE sip:18005559979@162.56.2.19:5070 SIP/2.0
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
CSeq: 1 INVITE
Content-Length: 239
Content-Type: application/sdp
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4240
Contact: <sip:6015@162.56.2.18:5060>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Max-Forwards: 69
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: uui,100rel,timer

v=0
o=MxSIP 1423143004 1 IN IP4 165.42.84.135
s=SIP Call
c=IN IP4 165.42.84.135
t=0 0
m=audio 34008 RTP/AVP 0 8 18 127
a=ptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:127 telephone-event/8000

19:18:55.898: SipDialog: event SEND_INVITE, t=82194, s=2, r=7, m=0000000001c48018
19:18:55.898 SIPCONN(18005559979): HandleSipDialogEvent(SEND_INVITE) - filtered
19:18:55.898 SIPCONN(18005559979): sdp state SDP_STATE_NULL, event SDP_EVENT_OFFER_SENT
19:18:55.898 SIPCONN(18005559979): new sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_OFFER_SENT
19:18:55.898: $-NET:SIP::0:1639

19:18:55.908: $+NET:SIP::0:0
19:18:55.908: SIPTR: Received [0,UDP] 325 bytes from 162.56.2.19:5070 <<<<<
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4240
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>
CSeq: 1 INVITE
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Content-Length: 0


19:18:55.908: SipDialog: event CALLING_RESPROV, t=82194, s=2, r=5, m=0000000001c48018
19:18:55.908 SIPCONN(18005559979): HandleSipDialogEvent(CALLING_RESPROV)
19:18:55.908 SIPCONN(18005559979): Capabilities 61013f
19:18:55.908 SIPCONN(18005559979): reliable=0
19:18:55.908 SIPCONN(18005559979): store remote content
19:18:55.908 SIPCONN(18005559979): store remote content - trying ignored
19:18:55.908 SIPCONN(18005559979): Trying Timer for 32000 mlsec started...
19:18:55.908: $-NET:SIP::0:40

19:18:55.944: $+NET:SIP::0:0
19:18:55.944: SIPTR: Received [0,UDP] 798 bytes from 162.56.2.19:5070 <<<<<
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4240
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
CSeq: 1 INVITE
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Contact: sip:Genesys@162.56.2.19:5090
Content-Length: 0
Record-Route: <sip:000000000E216460@162.56.2.19:5070;lr;gvp.rm.datanodes=1;idtag=000000E4>
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=A137F940-255D-40A1-6B9F-BD84EA125B88;1;0;;;;Resources;BoAIVR;
X-Genesys-GVP-Session-ID: A137F940-255D-40A1-6B9F-BD84EA125B88;gvp.rm.datanodes=1;gvp.rm.tenant-id=101_BoAIVR


19:18:55.944: SipDialog: event CALLING_RESPROV, t=82194, s=2, r=5, m=0000000001c48018
19:18:55.944 SIPCONN(18005559979): HandleSipDialogEvent(CALLING_RESPROV)
19:18:55.944 SIPCONN(18005559979): Trying Timer deleted...
19:18:55.944 SIPCONN(18005559979): reliable=0
19:18:55.944 SIPCONN(18005559979): store remote content
19:18:55.944 SIPCONN(18005559979): skip SDP pocessing [0/SDP_STATE_OFFER_SENT]
19:18:55.944 SIPCONN(6015): 18x response, state 1,1,0,0,1
19:18:55.944 SIPCONN(6015): SendResponse(180,82193)
19:18:55.944: add party info '6015' state 1.
19:18:55.944: Sending  [0,UDP] 761 bytes to 165.42.84.135:5060 >>>>>
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK89bcf55a8;received=165.42.84.135
To: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632297 INVITE
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Allow: INVITE, ACK, PRACK, CANCEL, BYE, UPDATE
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
X-Genesys-GVP-Session-Data: callsession=A137F940-255D-40A1-6B9F-BD84EA125B88;1;0;;;;Resources;BoAIVR;
X-Genesys-GVP-Session-ID: A137F940-255D-40A1-6B9F-BD84EA125B88;gvp.rm.datanodes=1;gvp.rm.tenant-id=101_BoAIVR
Supported: uui
Content-Length: 0


19:18:55.944: SipDialog: event CALLED_RESPROV, t=82193, s=1, r=6, m=0000000001c478c8
19:18:55.944 SIPCONN(6015): HandleSipDialogEvent(CALLED_RESPROV) - filtered
19:18:55.944 SIPCONN(18005559979): state e:2,p:0,s:2,c:9,rc:0,m:1
19:18:55.944 SIPPARTY(18005559979): 290 verify update of party-connection state N-A
19:18:55.944 SIPPARTY(18005559979): 290 update party-connection state N-A
19:18:55.944 ClearContext: party 18005559979.33b4fb0-31aa0b0:1
19:18:55.944 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventRinging
    Devices: <18005559979/18005559979> <6015/6015> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:1/r:0 0/none
    Parties: D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Null,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Dialing,Active,Origination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Ringing +++
    SetDelivered: party 18005559979.33b4fb0-31aa0b0:1, cause Null
    -- TellDelivered
    -- party_info 18005559979.24d17d0 state change: from <Null> to <Alerting>
    -- aTmParty::SetBusinessType():18005559979 set to BusinessType[1/1:0]
@19:18:55.9440 [ISCC] Party added [ssp view]:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000,0000000000000000 p:2 i:0000007a nw:00000000:0000000000000000 t:1
  p:00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 t:0 s:1000c n:6015
+ p:00000000024d17d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:a n:18005559979
@19:18:55.9440 [ISCC] Party object is created: p:0000000000000000,00000000024d17d0 n:18005559979:
@19:18:55.9440 [ISCC] Party added:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
+ p:0000000000000000,00000000024d17d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:18005559979:
@19:18:55.9440 [0] 8.1.101.01 distribute_event: message EventRinging
AttributeEventSequenceNumber 00000000000129c7
AttributeTimeinuSecs 944000
AttributeTimeinSecs 1423489735 (19:18:55)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 0
AttributeThisDNRole 2
AttributeThisDN '18005559979'
AttributeDNIS '18005559979'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
AttributeOtherDNRole 1
AttributeOtherDN '6015'
19:18:55.944 Int 04544 Interaction message "EventRinging" generated
    -- Cleanup call leg
  --- Ringing ---
  +++ Post-event +++
    Type EventRinging
    Devices: <18005559979/18005559979> <6015/6015> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:0 0/none
    Parties: D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Alerting,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Dialing,Active,Origination
    none
    Call type: Internal/1
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:18:55.944 --- CIFace::Event ---
19:18:55.944: SIPTR(1186): complete
19:18:55.944: SIPTR(1181): Step 4 - SipTransactionConnectNewParty(1186) complete
19:18:55.944: SIPTR(1181): complete
19:18:55.944: SIPCM: transaction SipScenario(1181) complete
19:18:55.944: PI: 00 S[IN]D[6015]C[*D[6015]]P[18005559979]
19:18:55.944: PI: 00 S[AN]D[18005559979]C[*D[18005559979]]P[6015]
19:18:55.944: CALLSTATE(a:2,d:0,i:0,e:0,r:0,o:0)
19:18:55.944: call1 122 idle
19:18:55.944: $-NET:SIP::0:582

19:18:56.006: $+NET:SIP::0:0
19:18:56.006: SIPTR: Received [0,UDP] 1140 bytes from 162.56.2.19:5070 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4240
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
CSeq: 1 INVITE
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Contact: sip:Genesys@162.56.2.19:5090
Content-Length: 261
Content-Type: application/sdp
Record-Route: <sip:000000000E216460@162.56.2.19:5070;lr;gvp.rm.datanodes=1;idtag=000000E4>
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=A137F940-255D-40A1-6B9F-BD84EA125B88;1;0;;;;Resources;BoAIVR;
X-Genesys-GVP-Session-ID: A137F940-255D-40A1-6B9F-BD84EA125B88;gvp.rm.datanodes=1;gvp.rm.tenant-id=101_BoAIVR
Session-Expires: 1800;refresher=uac
Require: timer

v=0
o=- 269319028 1 IN IP4 162.56.2.19
s=phone-call
c=IN IP4 162.56.2.19
t=0 0
m=audio 20456 RTP/AVP 0 8 18 127
a=rtpmap:0 pcmu/8000
a=ptime:20
a=rtpmap:8 pcma/8000
a=rtpmap:18 g729/8000
a=rtpmap:127 telephone-event/8000
a=fmtp:127 0-15
a=sendrecv

19:18:56.006: SipDialog: event CALLING_RESOK, t=82194, s=7, r=5, m=0000000001c48018
19:18:56.006 SIPCONN(18005559979): HandleSipDialogEvent(CALLING_RESOK)
19:18:56.006 SIPCONN(18005559979): store remote content
19:18:56.006 SIPCONN(18005559979): sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_SDP
19:18:56.006 SIPCONN(18005559979): new sdp state SDP_STATE_NULL, event SDP_EVENT_SDP
19:18:56.006 SIPCONN(18005559979): 1pcc event CALLING_RESOK
19:18:56.006 SIPCONN(18005559979): SetChargingState: true
19:18:56.006: SIPTR(1187): Begin step 0 - SipTransactionConnectCompleteAnswer(1188)
19:18:56.006 SIPCONN(18005559979): re-invite-connected-accepted
19:18:56.006 SIPCONN(6015): re-invite-called-initiated
19:18:56.006 SIPCONN(18005559979): GetAnswer
19:18:56.006 SIPCONN(18005559979): NotifyResponseOnAnswer
19:18:56.006 SIPCONN(6015): SendAnswer
Session value of the SDP is [269319028]
Version values of the SDP is [1]
19:18:56.006 SIPCONN(6015): SendAnswer::Response
19:18:56.006 SIPCONN(6015): SendResponse(200,82193)
19:18:56.006: add party info '6015' state 1.
19:18:56.006: Sending  [0,UDP] 1178 bytes to 165.42.84.135:5060 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK89bcf55a8;received=165.42.84.135
To: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632297 INVITE
Contact: <sip:162.56.2.18:5060>
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, UPDATE, MESSAGE, NOTIFY, OPTIONS
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
X-Genesys-GVP-Session-Data: callsession=A137F940-255D-40A1-6B9F-BD84EA125B88;1;0;;;;Resources;BoAIVR;
X-Genesys-GVP-Session-ID: A137F940-255D-40A1-6B9F-BD84EA125B88;gvp.rm.datanodes=1;gvp.rm.tenant-id=101_BoAIVR
Session-Expires: 1800;refresher=uas
Min-SE: 90
Supported: uui,timer
Content-Type: application/sdp
Content-Length: 262

v=0
o=- 1423142996 1 IN IP4 162.56.2.19
s=phone-call
c=IN IP4 162.56.2.19
t=0 0
m=audio 20456 RTP/AVP 0 8 18 127
a=ptime:20
a=sendrecv
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:18 g729/8000
a=rtpmap:127 telephone-event/8000
a=fmtp:127 0-15

19:18:56.006: SipDialog: event CALLED_RESOK, t=82193, s=7, r=5, m=0000000001c478c8
19:18:56.006 SIPCONN(6015): HandleSipDialogEvent(CALLED_RESOK) - filtered
19:18:56.006 SIPCONN(6015): TRCLR(82193)
19:18:56.006 SIPCONN(6015): sdp state SDP_STATE_OFFER_RECEIVED, event SDP_EVENT_ANSWER_SENT
19:18:56.006 SIPCONN(6015): new sdp state SDP_STATE_NULL, event SDP_EVENT_ANSWER_SENT
19:18:56.006 SIPCONN(18005559979): state e:3,p:2,s:3,c:0,rc:0,m:1
19:18:56.006 SIPCONN(18005559979): SetChargingState: true
19:18:56.006 SIPPARTY(18005559979): 290 verify update of party-connection state A-C
19:18:56.006 ClearContext: party 18005559979.33b4fb0-31aa0b0:1
19:18:56.006 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventEstablished
    Devices: <18005559979/18005559979> <6015/6015> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:0 0/none
    Parties: D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Alerting,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Dialing,Active,Origination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Established +++
19:18:56.006: device off hook
    SetEstablished: party 18005559979.33b4fb0-31aa0b0:1, cause Null
    -- party_info 18005559979.24d17d0 state change: from <Alerting> to <Connected>
    +++ TAgentEmulator::party_established +++
    --- TAgentEmulator::party_established ---
@19:18:56.0070 [0] 8.1.101.01 distribute_event: message EventEstablished
AttributeEventSequenceNumber 00000000000129c8
AttributeTimeinuSecs 7000
AttributeTimeinSecs 1423489736 (19:18:56)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 0
AttributeOtherDNRole 1
AttributeOtherDN '6015'
AttributeThisDNRole 2
AttributeThisDN '18005559979'
AttributeDNIS '18005559979'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
19:18:56.007 Int 04544 Interaction message "EventEstablished" generated
    -- TellEstablished
    SetActive: party 18005559979.33b4fb0-31aa0b0:1, cause Null
    -- Cleanup call leg
  --- Established ---
  +++ Post-event +++
    Type EventEstablished
    Devices: <18005559979/18005559979> <6015/6015> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:0 0/none
    Parties: D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Established,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Dialing,Active,Origination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:18:56.007 --- CIFace::Event ---
19:18:56.007 SIPCONN(18005559979): NotifyOnComplete
19:18:56.007: $-NET:SIP::0:757

19:18:56.102: $+NET:SIP::0:0
19:18:56.102: SIPTR: Received [0,UDP] 479 bytes from 165.42.84.135:5060 <<<<<
ACK sip:162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK040921c1c
Max-Forwards: 70
Content-Length: 0
To: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632297 ACK
Contact: 6015 <sip:6015@165.42.84.135:5060>
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26


19:18:56.102: SipDialog: event CONNECTED_ACK, t=82193, s=7, r=5, m=0000000001c478c8
19:18:56.102 SIPCONN(6015): HandleSipDialogEvent(CONNECTED_ACK)
19:18:56.102 SIPCONN(6015): new transaction
19:18:56.102 SIPCONN(6015): store remote content
19:18:56.102 SIPCONN(6015): skip SDP pocessing [0/SDP_STATE_NULL]
19:18:56.102 SIPCONN(6015): Connect(4,26)
19:18:56.102 SIPCONN(6015): TRCLR(0)
19:18:56.102 SIPCONN(6015): state e:3,p:1,s:3,c:0,rc:0,m:1
19:18:56.102 SIPCONN(6015): SetChargingState: true
19:18:56.102 SIPPARTY(6015): 289 verify update of party-connection state I-C
19:18:56.102 SIPPARTY(6015): other device is updated to the 18005559979
19:18:56.102 ClearContext: party 6015.33b46f0-31aa0b0:1
19:18:56.102 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventEstablished
    Devices: <6015/6015> <18005559979/18005559979> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:0 0/none
    Parties: D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Dialing,Active,Origination
    D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Established,Destination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Established +++
19:18:56.102: device off hook
    SetEstablished: party 6015.33b46f0-31aa0b0:1, cause Null
    -- party_info 6015.24d15d0 state change: from <Connected,Dialing> to <Connected>
    +++ TAgentEmulator::party_established +++
    --- TAgentEmulator::party_established ---
    -- G7 connected
@19:18:56.1020 [0] 8.1.101.01 distribute_event: message EventEstablished
AttributeEventSequenceNumber 00000000000129c9
AttributeTimeinuSecs 102000
AttributeTimeinSecs 1423489736 (19:18:56)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 0
AttributeOtherDNRole 2
AttributeOtherDN '18005559979'
AttributeThisDNRole 1
AttributeThisDN '6015'
AttributeDNIS '18005559979'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
19:18:56.102 Int 04544 Interaction message "EventEstablished" generated
19:18:56.102 Trc 04542 EventEstablished sent to [516] (00000003 STATSRV 162.56.2.16:58054)
    -- TellEstablished
    -- Cleanup call leg
  --- Established ---
  +++ Post-event +++
    Type EventEstablished
    Devices: <6015/6015> <18005559979/18005559979> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:0 0/none
    Parties: D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Established,Active,Origination
    D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Established,Destination
    none
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:18:56.102 --- CIFace::Event ---
19:18:56.102 SIPCONN(6015): NotifyOnComplete
19:18:56.102 SIPCONN(18005559979): Connect complete, other device '6015',(0000000001c47f60,0000000001c47810)
19:18:56.102 SIPCONN(18005559979): SendAck(82194)
19:18:56.102: Sending  [0,UDP] 649 bytes to 162.56.2.19:5070 >>>>>
ACK sip:Genesys@162.56.2.19:5090 SIP/2.0
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
CSeq: 1 ACK
Content-Length: 0
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4241
Route: <sip:000000000E216460@162.56.2.19:5070;lr;gvp.rm.datanodes=1;idtag=000000E4>
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, UPDATE, MESSAGE, NOTIFY, OPTIONS
Max-Forwards: 69
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26


19:18:56.102: SipDialog: event CONNECTED_SEND_ACK, t=82194, s=7, r=5, m=0000000001c48018
19:18:56.102 SIPCONN(18005559979): HandleSipDialogEvent(CONNECTED_SEND_ACK) - filtered
19:18:56.102 SIPCONN(6015): Connect complete, other device '18005559979',(0000000001c47810,0000000001c47f60)
19:18:56.102: SIPTR(1188): complete
19:18:56.102: SIPTR(1187): Step 0 - SipTransactionConnectCompleteAnswer(1188) complete
19:18:56.102: SIPTR(1187): complete
19:18:56.102: SIPCM: transaction SipScenario(1187) complete
19:18:56.102: PI: 00 S[CI]D[6015]C[*D[6015]]P[18005559979]
19:18:56.102: PI: 00 S[CA]D[18005559979]C[*D[18005559979]]P[6015]
19:18:56.102: CALLSTATE(a:2,d:0,i:0,e:0,r:0,o:0)
19:18:56.102: call1 122 idle
19:18:56.102: $-NET:SIP::0:569

19:18:58.680: $+NET:SIP::0:0
19:18:58.680: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDE530d8baca
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93883 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

Resources/99/100
@19:18:58.6800 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129ca
AttributeTimeinuSecs 680000
AttributeTimeinSecs 1423489738 (19:18:58)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 99
'total-ports' 100
AttributeThisDN 'Resources'
19:18:58.680: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDE530d8baca;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93883 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:18:58.680: $-NET:SIP::0:191

19:19:03.688: $+NET:SIP::0:0
19:19:03.688: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B08C5C0d8bacf
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93884 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui


19:19:03.688: $-NET:SIP::0:191

19:19:08.700: $+NET:SIP::0:0
19:19:08.700: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDB5C0d8bad4
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93885 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

Resources/99/100
@19:19:08.7000 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129cc
AttributeTimeinuSecs 700000
AttributeTimeinSecs 1423489748 (19:19:08)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 99
'total-ports' 100
AttributeThisDN 'Resources'
19:19:08.700: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDB5C0d8bad4;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93885 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:19:08.700: $-NET:SIP::0:201


19:19:13.708: $-NET:SIP::0:192

-AP[3]-<-508 @19:19:14.9980
-Ap[3]->-508 @19:19:14.9980
19:19:18.709: $+NET:SIP::0:0
19:19:18.709: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0BFC50d8bade
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93887 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

Resources/99/100

19:19:18.709: $-NET:SIP::0:196

19:19:23.718: $+NET:SIP::0:0
19:19:23.718: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDE740d8bae3
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93888 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui


19:19:23.718: $-NET:SIP::0:197

19:19:28.726: $+NET:SIP::0:0
19:19:28.726: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDD090d8bae8
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93889 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

Resources/99/100
@19:19:28.7260 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129d0
AttributeTimeinuSecs 726000
AttributeTimeinSecs 1423489768 (19:19:28)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 99
'total-ports' 100
AttributeThisDN 'Resources'
19:19:28.726: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDD090d8bae8;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93889 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


Resources/99/100
@19:19:33.7340 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129d1
AttributeTimeinuSecs 734000
AttributeTimeinSecs 1423489773 (19:19:33)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 99
'total-ports' 100
AttributeThisDN 'Resources'
19:19:33.734: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK0000000016B0DC70d8baed;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93890 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:19:33.734: $-NET:SIP::0:192

19:19:38.743: $+NET:SIP::0:0
19:19:38.743: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK0000000016B0D850d8baf2
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93891 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

19:19:43.751: $-NET:SIP::0:192

-AP[4]-<-508 @19:19:45.0430
-Ap[4]->-508 @19:19:45.0440
19:19:48.758: $+NET:SIP::0:0
19:19:48.758: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0DA110d8bafc
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93893 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui


19:19:48.758: $-NET:SIP::0:195

19:19:53.767: $+NET:SIP::0:0
19:19:53.767: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0D96C0d8bb01
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93894 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 10, 2015, 01:13:02 PM

HI Kubig,

I am not able to copy the data in single thread that's why I am copying in multiple threads.

19:19:53.767: $-NET:SIP::0:197

19:19:58.775: $+NET:SIP::0:0
19:19:58.775: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B068ED0d8bb06
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93895 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui


19:20:08.792: $-NET:SIP::0:204

19:20:13.800: $-NET:SIP::0:192

-AP[5]-<-508 @19:20:15.0900
-Ap[5]->-508 @19:20:15.0900
19:20:18.809: $+NET:SIP::0:0
19:20:18.809: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDB9E0d8bb1a
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93899 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

Resources/99/100
@19:20:18.8090 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129de
AttributeTimeinuSecs 809000
AttributeTimeinSecs 1423489818 (19:20:18)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 99
'total-ports' 100
AttributeThisDN 'Resources'
19:20:18.809: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDB9E0d8bb1a;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93899 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:20:18.809: $-NET:SIP::0:188

19:20:23.809: $+NET:SIP::0:0
19:20:23.809: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDD4B0d8bb1f
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93900 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui


19:20:24.688: SIPS:LOGBLOCK:END:SIPMSG:]
19:20:24.688: $-NET:SIP::0:412

CGCL2Connector[664]: Connection with 162.56.2.19(162.56.2.19) established
19:20:28.586: CGSIPListener[0]: Accepted new connection on socket 664
19:20:28.586: TRANSPORT[0x32] [664,TCP] 162.56.2.19:57326 handled connect
19:20:28.636: $+NET:SIP::0:0
19:20:28.636: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
19:20:28.636: SIPTR: Received [664,TCP] 2744 bytes from 162.56.2.19:57326 <<<<<
INFO sip:6015@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/TCP 162.56.2.19:5070;branch=z9hG4bK000000001B2C52E0d8bb24abcdef09
Via: SIP/2.0/TCP 162.56.2.19:5090;branch=z9hG4bK0000000007AC12A0d8bb24
From: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
To: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
Max-Forwards: 69
CSeq: 1 INFO
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Content-Length: 1892
Content-Type: application/x-www-form-urlencoded;charset=utf-8
X-Genesys-GVP-Session-ID: A137F940-255D-40A1-6B9F-BD84EA125B88;gvp.rm.datanodes=1;gvp.rm.tenant-id=101_BoAIVR
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=A137F940-255D-40A1-6B9F-BD84EA125B88;1;0;;;;Resources;BoAIVR;
Supported: timer, uui


19:20:28.636: SIPDLG[2245]: register TRN[82218]
19:20:28.636: SipDialog: event CONNECTED_REQUEST, t=82218, s=7, r=5, m=0000000001c48018
19:20:28.636 SIPCONN(18005559979): HandleSipDialogEvent(CONNECTED_REQUEST)
19:20:28.636 SIPCONN(18005559979): new transaction
19:20:28.636 SIPCONN(18005559979): 1pcc event CONNECTED_REQUEST
19:20:28.636 SIPCONN(18005559979): Handle INFO
@19:20:28.6360 Call [006f025cd046d077]: urid 0002006f
@19:20:28.6360 [0] 8.1.101.01 distribute_event: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129e0
AttributeTimeinuSecs 636000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeThirdPartyDN '18005559979'
AttributeThisDNRole 1
AttributeThisDN '6015'
AttributeDNIS '18005559979'
AttributeUserData [2504] 00 8f 00 00..

AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.637 Int 04544 Interaction message "EventAttachedDataChanged" generated
19:20:28.637 Trc 04542 EventAttachedDataChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.6370 [0] 8.1.101.01 distribute_event: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129e1
AttributeTimeinuSecs 637000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeThirdPartyDN '18005559979'
AttributeThisDNRole 2
AttributeThisDN '18005559979'
AttributeDNIS '18005559979'
AttributeUserData [2504] 00 8f 00 00..

AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.637 Int 04544 Interaction message "EventAttachedDataChanged" generated
@19:20:28.6370 [ISCC] Updated call:
i c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
  p:0000000000000000,00000000024d17d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:18005559979:
@19:20:28.6370 [ISCC] UserData propagation has been started
@19:20:28.6370 [ISCC] UserData propagation has been finished
19:20:28.637  -- call_info::committed changed
19:20:28.637 SIPCONN(18005559979): SendResponse(200,82218)
19:20:28.637: add party info '18005559979' state 3.
19:20:28.637: Sending  [664,TCP] 637 bytes to 162.56.2.19:57326 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/TCP 162.56.2.19:5070;branch=z9hG4bK000000001B2C52E0d8bb24abcdef09;received=162.56.2.19
Via: SIP/2.0/TCP 162.56.2.19:5090;branch=z9hG4bK0000000007AC12A0d8bb24
From: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
To: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
CSeq: 1 INFO
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Contact: <sip:6015@162.56.2.18:5060>
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, UPDATE, MESSAGE, NOTIFY, OPTIONS
Supported: uui
Content-Length: 0


19:20:28.637: SipDialog: event CONNECTED_SEND_RESOK, t=82218, s=7, r=8, m=0000000001c48018
19:20:28.637 SIPCONN(18005559979): HandleSipDialogEvent(CONNECTED_SEND_RESOK) - filtered
19:20:28.637: SIPS:LOGBLOCK:END:SIPMSG:]
19:20:28.637: $-NET:SIP::0:1349

19:20:28.668: $+NET:SIP::0:0
19:20:28.668: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
19:20:28.668: SIPTR: Received [664,TCP] 2047 bytes from 162.56.2.19:57326 <<<<<
REFER sip:6015@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/TCP 162.56.2.19:5070;branch=z9hG4bK000000001B2C6960d8bb24abcdef09
Via: SIP/2.0/TCP 162.56.2.19:5090;branch=z9hG4bK0000000007AC0850d8bb24
From: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
To: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
Max-Forwards: 69
CSeq: 2 REFER
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Contact: <sip:Genesys@162.56.2.19:5090>
Content-Length: 0
Refer-To: <sip:11000@162.56.2.18;user=phone>
Referred-By: <sip:18005559979@162.56.2.18:5060>
X-Genesys-GVP-Session-ID: A137F940-255D-40A1-6B9F-BD84EA125B88;gvp.rm.datanodes=1;gvp.rm.tenant-id=101_BoAIVR
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=A137F940-255D-40A1-6B9F-BD84EA125B88;1;0;;;;Resources;BoAIVR;
Supported: timer, uui


19:20:28.668: SIPDLG[2245]: register TRN[82219]
19:20:28.668: SipDialog: event CONNECTED_REQUEST, t=82219, s=7, r=5, m=0000000001c48018
19:20:28.668 SIPCONN(18005559979): HandleSipDialogEvent(CONNECTED_REQUEST)
19:20:28.668 SIPCONN(18005559979): new transaction
19:20:28.668 SIPCONN(18005559979): 1pcc event CONNECTED_REQUEST
19:20:28.669 SIPCONN(18005559979): SipConnection::CheckForStarcodeFeature, cheking of '<sip:11000@162.56.2.18;user=phone>'
19:20:28.669: TRNMNGR: internal domain 162.56.2.18
19:20:28.669 SIPCONN(18005559979): REFER - execute sst.
19:20:28.669 SIPCONN(18005559979): SendResponse(202,82219)
19:20:28.669: add party info '18005559979' state 3.
19:20:28.669: Sending  [664,TCP] 628 bytes to 162.56.2.19:57326 >>>>>
SIP/2.0 202 Accepted
Via: SIP/2.0/TCP 162.56.2.19:5070;branch=z9hG4bK000000001B2C6960d8bb24abcdef09;received=162.56.2.19
Via: SIP/2.0/TCP 162.56.2.19:5090;branch=z9hG4bK0000000007AC0850d8bb24
From: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
To: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
CSeq: 2 REFER
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Contact: <sip:6015@162.56.2.18:5060>
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, UPDATE, MESSAGE, NOTIFY, OPTIONS
Content-Length: 0


19:20:28.669: SipDialog: event CONNECTED_SEND_RESOK, t=82219, s=7, r=8, m=0000000001c48018
19:20:28.669 SIPCONN(18005559979): HandleSipDialogEvent(CONNECTED_SEND_RESOK) - filtered
19:20:28.669: ResolveCallInfo: set flag DIAL_PLAN_PROCESSING
19:20:28.669: SIPTR(1189): Begin step 0 - SipTransactionResolveCallInfoByDialPlan(1190)
19:20:28.669: DialPlan:Begin: DEVICE[18005559979] using AOR 18005559979 instead
19:20:28.669: DialPlan: No dialplan defined for 18005559979.
19:20:28.669: DialPlan: No rule applied, using original destination '11000'.
19:20:28.669: DialPlan: clear flag DIAL_PLAN_PROCESSING
19:20:28.669: ProcessDialPlanResult: Connecting to DEVICE(935,11000).
19:20:28.669 SIPCONN(6015): re-invite-connected
19:20:28.669: transfer refer
19:20:28.669: SIPCALL(122): add party '11000'
19:20:28.669  -- created party_info_tspp 24d06d0
19:20:28.669  -- created aTmParty 33b51e0
19:20:28.669  SetRole: Destination for 11000.33b51e0-31aa0b0:1
19:20:28.669  -- AddParty to 31aa0b0: 11000.33b51e0-31aa0b0:1
19:20:28.669  -- new TSCP call leg 3
19:20:28.669  -- call leg created leg_id=3
19:20:28.669 CreateParty new internal: 11000.33b51e0-31aa0b0:1
19:20:28.669: Call 122 dn 11000 SetPartyId 291
19:20:28.669: SIPTR(1190): complete
19:20:28.669: SIPTR(1189): Step 0 - SipTransactionResolveCallInfoByDialPlan(1190) complete
19:20:28.669: SIPTR(1189): Begin step 1 - SipTransactionSingleStepTransfer(1191)
19:20:28.669: refer 11000[lc] to 6015[lc] : 1=>1=>1
19:20:28.669: add party info '6015' state 3.
19:20:28.669: SIPDLG[2244]: register TRN[82220]
19:20:28.669: Sending  [0,UDP] 649 bytes to 165.42.84.135:5060 >>>>>
REFER sip:6015@165.42.84.135:5060 SIP/2.0
From: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
To: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 1 REFER
Content-Length: 0
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4242
Contact: <sip:162.56.2.18:5060>
Refer-To: <sip:11000@162.56.2.18:5060;genesysid=7HN03HKD3H02B1O98JSVOMINS8000090>
Referred-By: <sip:11000@162.56.2.18:5060;genesysid=7HN03HKD3H02B1O98JSVOMINS8000090>
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Max-Forwards: 68


19:20:28.669: SipDialog: event CONNECTED_SEND_REQUEST, t=82220, s=7, r=5, m=0000000001c478c8
19:20:28.669 SIPCONN(6015): HandleSipDialogEvent(CONNECTED_SEND_REQUEST) - filtered
19:20:28.669: SIPS:LOGBLOCK:END:SIPMSG:]
19:20:28.669: $-NET:SIP::0:1240

19:20:28.761: $+NET:SIP::0:0
19:20:28.761: SIPTR: Received [0,UDP] 494 bytes from 165.42.84.135:5060 <<<<<
SIP/2.0 202 Accepted
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 1 REFER
From: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
To: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4242
Content-Length: 0
Supported: replaces
Contact: 6015 <sip:6015@165.42.84.135:5060>
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26


19:20:28.761: SipDialog: event CONNECTED_RESOK, t=82220, s=7, r=5, m=0000000001c478c8
19:20:28.761 SIPCONN(6015): HandleSipDialogEvent(CONNECTED_RESOK)
19:20:28.761 SIPCONN(6015): store remote content
19:20:28.761 SIPCONN(6015): refer dialog confirmed
19:20:28.761 SIPCONN(6015): TRCLR(82220)
19:20:28.761: $-NET:SIP::0:71

19:20:28.787: connection matched by Referred-by or request uri
19:20:28.787: $+NET:SIP::0:0
19:20:28.787: SIPTR: Received [0,UDP] 853 bytes from 165.42.84.135:5060 <<<<<
INVITE sip:11000@162.56.2.18 SIP/2.0
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK72940ad76
Max-Forwards: 70
Content-Length: 266
To: 11000 <sip:11000@162.56.2.18>
From: 6015 <sip:6015@162.56.2.18>;tag=8f56692959c93f2
Call-ID: 8c2f69e993ce7a90b96f23ead577ab5b@165.42.84.135
CSeq: 1002849195 INVITE
Supported: timer
Referred-By: <sip:11000@162.56.2.18:5060;genesysid=7HN03HKD3H02B1O98JSVOMINS8000090>
Content-Type: application/sdp
Contact: 6015 <sip:6015@165.42.84.135:5060>
Supported: replaces
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26

v=0
o=MxSIP 0 1129055985 IN IP4 165.42.84.135
s=SIP Call
c=IN IP4 165.42.84.135
t=0 0
m=audio 34008 RTP/AVP 0 8 18 2 127
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:127 telephone-event/8000
a=ptime:20

19:20:28.787: connection matched by Referred-by
19:20:28.787 SIPCONN(6015): Create dialog
19:20:28.787: SipDialog: set monitor 0000000001c478c8
19:20:28.787 SIPCONN(6015): refer dialog 00000000034c9660 initiated
19:20:28.787 SIPCONN(6015): SetOriginalDialog: 00000000034c8350
19:20:28.787: SipDialog: set monitor 0000000000000000
19:20:28.787 SIPCONN(6015): change transaction 0 -> 0
19:20:28.787: SipDialog: set monitor 0000000001c478c8
19:20:28.787 SIPCONN(6015): ClrMediaPeer
19:20:28.787: SIPDLG[2250]: register TRN[82221]
19:20:28.787: SIPDLG[2250]: TRN[82221] flags set to 0x1
19:20:28.787: SipDialog: event INVITE, t=82221, s=1, r=5, m=0000000001c478c8
19:20:28.787 SIPCONN(6015): HandleSipDialogEvent(INVITE)
19:20:28.787 SIPCONN(6015): new transaction
19:20:28.787 SIPCONN(6015): store remote content
19:20:28.787 SIPCONN(6015): verify sdp
19:20:28.787 SIPCONN(6015): sdp state SDP_STATE_NULL, event SDP_EVENT_SDP
19:20:28.787 SIPCONN(6015): new sdp state SDP_STATE_OFFER_RECEIVED, event SDP_EVENT_SDP
19:20:28.787: SIPTR(1191): complete
19:20:28.787: SIPTR(1189): Step 1 - SipTransactionSingleStepTransfer(1191) complete
19:20:28.787: SIPTR(1189): Begin step 2 - SipTransactionTransferCall(1192)
19:20:28.787 SIPCONN(6015): ClrMediaPeer
19:20:28.787 SIPCONN(6015): release original dialog
19:20:28.787 SIPCONN(6015): SetOriginalDialog: 0000000000000000
19:20:28.787 ClearContext: party 18005559979.33b4fb0-31aa0b0:1
19:20:28.787 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventDiverted
    Devices: <18005559979/18005559979> <11000/11000> <6015/6015>
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:0 0/none
    Parties: D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Established,Destination
    D11000/11000.33b51e0-31aa0b0:1/l:3/r:0/Null,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Established,Active,Origination
    Cause: SingleStepTransfer/52, Info: 0
    Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Diverted +++
    DoDivert: from 18005559979.33b4fb0-31aa0b0:1 to 11000.33b51e0-31aa0b0:1 cause SingleStepTransfer
    !! re-arrange group
    -- RemoveParty 11000.33b51e0-31aa0b0:1
    -- call leg removed leg_id=3
    -- AddParty to 31aa0b0: 11000.33b51e0-31aa0b0:1 after 18005559979.33b4fb0-31aa0b0:1
@19:20:28.7870 [0] 8.1.101.01 distribute_event: message EventReleased
AttributeEventSequenceNumber 00000000000129e2
AttributeTimeinuSecs 787000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeExtensions [73] 00 02 00 00..
'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
'BusinessCall' 0
AttributeCause 11
AttributeOtherDNRole 1
AttributeOtherDN '6015'
AttributeThirdPartyDNRole 2
AttributeThirdPartyDN '11000'
AttributeThisDNRole 2
AttributeThisDN '18005559979'
AttributeDNIS '18005559979'
AttributeUserData [2554] 00 90 00 00..

'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 1
19:20:28.788 Int 04544 Interaction message "EventReleased" generated
    inherit_queues: from 24d17d0 to 24d06d0
    -- party_info 18005559979.24d17d0 state change: from <Connected> to <Null>
@19:20:28.7880 [ISCC] Party removed [ssp view]:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000,0000000000000000 p:3 i:0000007a nw:00000000:0000000000000000 t:1
  p:00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 t:0 s:c n:6015
- p:00000000024d17d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:0 n:18005559979
  p:00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:0 n:11000
@19:20:28.7880 [ISCC] Party removed:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
- p:0000000000000000,00000000024d17d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:18005559979:
@19:20:28.7880 [ISCC] Party object is removed: p:0000000000000000,0000000000000000 n:18005559979:
19:20:28.788: device on hook
  --- Diverted ---
  +++ Post-event +++
    Type EventDiverted
    Devices: <18005559979/18005559979> <11000/11000> <6015/6015>
    Calls: 122/006f025cd046d077/122.31aa0b0/c:1/r:0 0/none
    Parties: D18005559979/18005559979.33b4fb0-31aa0b0:1/l:2/r:0/Released,Destination
    D11000/11000.33b51e0-31aa0b0:1/l:2/r:0/Null,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Established,Active,Origination
    Cause: SingleStepTransfer/52, Info: 0
    Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:20:28.788 --- CIFace::Event ---
19:20:28.788  SetRole: TransferredBy for 18005559979.33b4fb0-31aa0b0:1
19:20:28.788: check call postponed until end of operation
19:20:28.788 ClearContext: party 11000.33b51e0-31aa0b0:1
19:20:28.788 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventRouteRequest
    Devices: <11000/11000> <-/-> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:1/r:0 0/none
    Parties: D11000/11000.33b51e0-31aa0b0:1/l:2/r:0/Null,Destination
    none
    none
    Cause: SingleStepTransfer/52, Info: 0
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
  --- Pre-event ---
  +++ Ringing +++
    -- guessed previous party: 18005559979.33b4fb0-31aa0b0:1
    inherit_queues: from 24d17d0 to 24d06d0
    -- inherit skipped
    -- 3rd pi: 33b4fb0
    SetDelivered: party 11000.33b51e0-31aa0b0:1, cause SingleStepTransfer
    -- TellQueued
    -- party_info 11000.24d06d0 state change: from <Null> to <Queued>
    -- aTmParty::SetBusinessType():11000 set to BusinessType[1/1:0]
    -- AgnEmu: business type taken from distribution device 11000
    -- AgnEmu: business call BusinessType[2/4:0]
    -- aTmCall::SetBusinessType():006f025cd046d077 set to BusinessType[2/4:1]
    -- aTmParty::SetBusinessType():11000 set to BusinessType[2/4:1]
    -- call_info::committed changed
@19:20:28.7880 [ISCC] Party added [ssp view]:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000,0000000000000000 p:3 i:0000007a nw:00000000:0000000000000000 t:1
  p:00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 t:0 s:c n:6015
  p:00000000024d17d0 @ c:006f025cd046d077,0000000003352c40 r:7 t:0 s:0 n:18005559979
+ p:00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:9 n:11000
@19:20:28.7880 [ISCC] Party object is created: p:0000000000000000,00000000024d06d0 n:11000:
@19:20:28.7880 [ISCC] Party added:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
+ p:0000000000000000,00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:11000:
    push_queue: 11000 for 11000
@19:20:28.7880 [0] 8.1.101.01 distribute_event: message EventPartyChanged
AttributeEventSequenceNumber 00000000000129e3
AttributeTimeinuSecs 788000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeExtensions [73] 00 02 00 00..
'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
'BusinessCall' 0
AttributeThirdPartyDNRole 7
AttributeThirdPartyDN '18005559979'
AttributeThisDNRole 1
AttributeThisDN '6015'
AttributeDNIS '18005559979'
AttributeUserData [2554] 00 90 00 00..
'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 1
AttributeOtherDNRole 2
AttributeOtherDN '11000'
AttributePreviousConnID 006f025cd046d077
19:20:28.788 Int 04544 Interaction message "EventPartyChanged" generated
19:20:28.788 Trc 04542 EventPartyChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.7880 [0] 8.1.101.01 distribute_event: message EventQueued
AttributeEventSequenceNumber 00000000000129e4
AttributeTimeinuSecs 788000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeExtensions [73] 00 02 00 00..
'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
'BusinessCall' 1
AttributeThisQueue '11000'
AttributeThisDNRole 2
AttributeThisDN '11000'
AttributeDNIS '18005559979'
AttributeUserData [2554] 00 90 00 00..
'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 1
AttributeOtherDNRole 1
AttributeOtherDN '6015'
AttributeThirdPartyDNRole 7
AttributeThirdPartyDN '18005559979'
19:20:28.788 Int 04544 Interaction message "EventQueued" generated
19:20:28.788 Trc 04542 EventQueued sent to [628] (00000004 URSSRV 162.56.2.18:61109)
19:20:28.788 Trc 04542 EventQueued sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.7880 [0] 8.1.101.01 distribute_event: message EventRouteRequest
AttributeEventSequenceNumber 00000000000129e5
AttributeTimeinuSecs 788000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeExtensions [73] 00 02 00 00..
'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
'BusinessCall' 1
AttributeOtherDNRole 1
AttributeOtherDN '6015'
AttributeThisQueue '11000'
AttributeThisDNRole 2
AttributeThisDN '11000'
AttributeDNIS '18005559979'
AttributeUserData [2554] 00 90 00 00..
'Referred-By' '<sip:18005559979@162.56.2.18:5060>'
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
19:20:28.789 Int 04544 Interaction message "EventRouteRequest" generated
19:20:28.789 Trc 04542 EventRouteRequest sent to [628] (00000004 URSSRV 162.56.2.18:61109)
19:20:28.789 Trc 04542 EventRouteRequest sent to [516] (00000003 STATSRV 162.56.2.16:58054)
    -- Cleanup call leg
    -- deleted aTmParty 33b4fb0
    -- RemoveParty 18005559979.33b4fb0-31aa0b0:1
    -- deleted party_info_tspp 24d17d0
  --- Ringing ---
  +++ Post-event +++
    Type EventRouteRequest
    Devices: <11000/11000> <-/-> <18005559979/18005559979>
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:0 0/none
    Parties: D11000/11000.33b51e0-31aa0b0:1/l:2/r:0/Queued,RtRequest,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Established,Active,Origination
    none
    Cause: SingleStepTransfer/52, Info: 0
    Call type: Internal/1
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
  --- Post-event ---
19:20:28.789 --- CIFace::Event ---
19:20:28.789 SIPCONN(6015): SendResponse(180,82221)
19:20:28.789: add party info '6015' state 3.
19:20:28.789: Sending  [0,UDP] 462 bytes to 165.42.84.135:5060 >>>>>
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK72940ad76;received=165.42.84.135
To: 11000 <sip:11000@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2323
From: 6015 <sip:6015@162.56.2.18>;tag=8f56692959c93f2
Call-ID: 8c2f69e993ce7a90b96f23ead577ab5b@165.42.84.135
CSeq: 1002849195 INVITE
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Allow: INVITE, ACK, PRACK, CANCEL, BYE, UPDATE
Supported: uui
Content-Length: 0


19:20:28.789: SipDialog: event CALLED_RESPROV, t=82221, s=1, r=11, m=0000000001c478c8
19:20:28.789 SIPCONN(6015): HandleSipDialogEvent(CALLED_RESPROV) - filtered
19:20:28.789: SIPTR(1192): complete
19:20:28.789: SIPTR(1189): Step 2 - SipTransactionTransferCall(1192) complete
19:20:28.789: SIPTR(1189): Begin step 3 - SipTransactionExecuteCtiRequest(1193)
19:20:28.789 SIPCONN(18005559979): CtiRequest(2)
19:20:28.789: SIPDLG[2245]: register TRN[82222]
19:20:28.789: Sending  [0,UDP] 644 bytes to 162.56.2.19:5070 >>>>>
NOTIFY sip:Genesys@162.56.2.19:5090 SIP/2.0
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
CSeq: 2 NOTIFY
Content-Length: 16
Content-Type: message/sipfrag
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4243
Contact: <sip:6015@162.56.2.18:5060>
Event: refer
Subscription-State: terminated;reason=noresource
Max-Forwards: 69
Route: <sip:000000000E216460@162.56.2.19:5070;lr;gvp.rm.datanodes=1;idtag=000000E4>

SIP/2.0 200 OK

19:20:28.789: SipDialog: event CONNECTED_SEND_REQUEST, t=82222, s=7, r=6, m=0000000001c48018
19:20:28.789 SIPCONN(18005559979): HandleSipDialogEvent(CONNECTED_SEND_REQUEST) - filtered
19:20:28.789: $-NET:SIP::0:2145

19:20:28.813: $+NET:SIP::0:0
19:20:28.813: SIPTR: Received [0,UDP] 662 bytes from 162.56.2.19:5070 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4243
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
CSeq: 2 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Content-Length: 0
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Min-SE: 90

19:20:28.813: SipDialog: event CONNECTED_RESOK, t=82222, s=7, r=5, m=0000000001c48018
19:20:28.813 SIPCONN(18005559979): HandleSipDialogEvent(CONNECTED_RESOK)
19:20:28.813 SIPCONN(18005559979): store remote content
19:20:28.813 SIPCONN(18005559979): CtiRequest(1,33)
19:20:28.813 SIPCONN(18005559979): CtiRequestComplete
19:20:28.813 SIPCONN(18005559979): TRCLR(82222), current 82222
19:20:28.813: SIPTR(1193): complete
19:20:28.813: SIPTR(1189): Step 3 - SipTransactionExecuteCtiRequest(1193) complete
19:20:28.813: SIPTR(1189): Begin step 4 - SipTransactionClearParty(1194)
19:20:28.813: SD: none
19:20:28.813 SIPCONN(18005559979): set monitor 0000000001c47f60, 0000000000000000
19:20:28.813 SIPCONN(18005559979): state e:1,p:3,s:0,c:3,rc:0,m:0
19:20:28.813: SipDialog: ClearCall(phone=0,state=7)
19:20:28.813: SipDialog::Terminate(state=7,reason=0)
19:20:28.813: SIPDLG[2245]: register TRN[82223]
19:20:28.813: Sending  [0,UDP] 488 bytes to 162.56.2.19:5070 >>>>>
BYE sip:Genesys@162.56.2.19:5090 SIP/2.0
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
CSeq: 3 BYE
Content-Length: 0
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4244
Max-Forwards: 69
Route: <sip:000000000E216460@162.56.2.19:5070;lr;gvp.rm.datanodes=1;idtag=000000E4>


19:20:28.813: SipDialog: event SEND_BYE, t=82223, s=8, r=8, m=0000000001c48018
19:20:28.813 SIPCONN(18005559979): HandleSipDialogEvent(SEND_BYE) - filtered
19:20:28.813: SipDialog: set monitor 0000000000000000
19:20:28.813 SIPCONN(18005559979): DetachMediaPeer
19:20:28.813: SIPTR(1194): complete
19:20:28.813: SIPTR(1189): Step 4 - SipTransactionClearParty(1194) complete
19:20:28.813: SIPTR(1189): complete
19:20:28.813: SIPCM: transaction SipScenario(1189) complete
19:20:28.813: PI: 00 S[CI]D[6015]C[*D[6015]]P[-]
19:20:28.813: PI: 01 S[NC]D[18005559979]E[L-]MO[!] - should be deleted
19:20:28.813: check linked party
19:20:28.813: SIPCM: delete party 18005559979:290
19:20:28.813: SIPCALL(122): delete party '18005559979'
19:20:28.813: GetPartyInfo party with partyId 290 not found
19:20:28.813: PI: 00 S[QN]D[11000]E[-]
19:20:28.813: CALLSTATE(a:1,d:1,i:0,e:0,r:0,o:0)
19:20:28.813: call1 122 idle
19:20:28.813: $-NET:SIP::0:398

19:20:28.813: $+NET:SIP::0:0
19:20:28.813: SIPTR: Received [0,UDP] 784 bytes from 162.56.2.19:5070 <<<<<
BYE sip:6015@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5070;branch=z9hG4bK000000001B2C63C0d8bb24abcdef09
Via: SIP/2.0/TCP 162.56.2.19:5090;branch=z9hG4bK0000000007AC16C0d8bb24
From: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
To: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
Max-Forwards: 69
CSeq: 3 BYE
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Content-Length: 0
gvp.rm.tenant-id=101_BoAIVR
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Min-SE: 90
Supported: timer, uui


19:20:28.813: Sending  [0,UDP] 474 bytes to 162.56.2.19:5070 >>>>>
SIP/2.0 481 Call Leg/Transaction Does Not Exist
Via: SIP/2.0/UDP 162.56.2.19:5070;branch=z9hG4bK000000001B2C63C0d8bb24abcdef09;received=162.56.2.19
Via: SIP/2.0/TCP 162.56.2.19:5090;branch=z9hG4bK0000000007AC16C0d8bb24
From: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
To: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
CSeq: 3 BYE
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Content-Length: 0


19:20:28.813: $-NET:SIP::0:69

19:20:28.821: $+NET:SIP::0:0
19:20:28.821: SIPTR: Received [0,UDP] 637 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000000288F240d8bb24
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93901 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 16
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/99/100
Supported: timer, uui

Resources/99/100

19:20:28.821: $-NET:SIP::0:147

19:20:28.822 Trc 04541 RequestUpdateUserData received from [628] (00000004 URSSRV 162.56.2.18:61109)
message RequestUpdateUserData
AttributeThisDN '11000'
AttributeConnID 006f025cd046d077
AttributeUserData [1767] 00 7b 00 00..

AttributeReferenceID 868
19:20:28.822 Int 04543 Interaction message "RequestUpdateUserData" received from 628 ("URSSRV")
@19:20:28.8220 Call [006f025cd046d077]: urid 0003006f
@19:20:28.8220 [0] 8.1.101.01 distribute_event: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129e7
AttributeTimeinuSecs 822000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeThirdPartyDN '11000'
AttributeThisDNRole 1
AttributeThisDN '6015'
AttributeDNIS '18005559979'
AttributeUserData [2784] 00 a8 00 00..

AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.822 Int 04544 Interaction message "EventAttachedDataChanged" generated
19:20:28.822 Trc 04542 EventAttachedDataChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.8220 [0] 8.1.101.01 distribute_response: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129e8
AttributeTimeinuSecs 822000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeReferenceID 868
AttributeThirdPartyDN '11000'
AttributeThisDNRole 2
AttributeThisDN '11000'
AttributeDNIS '18005559979'
AttributeUserData [2784] 00 a8 00 00..

AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.822 Int 04544 Interaction message "EventAttachedDataChanged" generated
19:20:28.822 Trc 04542 EventAttachedDataChanged sent to [628] (00000004 URSSRV 162.56.2.18:61109)
19:20:28.822 Trc 04542 EventAttachedDataChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.8220 [ISCC] Updated call:
i c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
  p:0000000000000000,00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:11000:
@19:20:28.8220 [ISCC] UserData propagation has been started
@19:20:28.8220 [ISCC] UserData propagation has been finished
19:20:28.822  -- call_info::committed changed
19:20:28.822  -- created: CRequest@33a7980 RequestUpdateUserData-URSSRV[628]/868
19:20:28.822: $+TLIB:CTI:Unknown:0:19201717
19:20:28.822  -- deleted: CRequest@33a7980 RequestUpdateUserData-URSSRV[628]/868
19:20:28.822: $-TLIB:CTI:Unknown:0:10

19:20:28.822 Trc 04541 RequestUpdateUserData received from [628] (00000004 URSSRV 162.56.2.18:61109)
message RequestUpdateUserData
AttributeThisDN '11000'
AttributeConnID 006f025cd046d077
AttributeUserData [441] 00 1c 00 00..

AttributeReferenceID 869
19:20:28.822 Int 04543 Interaction message "RequestUpdateUserData" received from 628 ("URSSRV")
@19:20:28.8220 Call [006f025cd046d077]: urid 0004006f
@19:20:28.8220 [0] 8.1.101.01 distribute_event: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129e9
AttributeTimeinuSecs 822000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeThirdPartyDN '11000'
AttributeThisDNRole 1
AttributeThisDN '6015'
AttributeDNIS '18005559979'
AttributeUserData [2872] 00 ae 00 00..

AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.823 Int 04544 Interaction message "EventAttachedDataChanged" generated
19:20:28.823 Trc 04542 EventAttachedDataChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.8230 [0] 8.1.101.01 distribute_response: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129ea
AttributeTimeinuSecs 823000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeReferenceID 869
AttributeThirdPartyDN '11000'
AttributeThisDNRole 2
AttributeThisDN '11000'
AttributeDNIS '18005559979'
AttributeUserData [2872] 00 ae 00 00..

AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.823 Int 04544 Interaction message "EventAttachedDataChanged" generated
19:20:28.823 Trc 04542 EventAttachedDataChanged sent to [628] (00000004 URSSRV 162.56.2.18:61109)
19:20:28.823 Trc 04542 EventAttachedDataChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.8230 [ISCC] Updated call:
i c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
  p:0000000000000000,00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:11000:
@19:20:28.8230 [ISCC] UserData propagation has been started
@19:20:28.8230 [ISCC] UserData propagation has been finished
19:20:28.823  -- call_info::committed changed
19:20:28.823  -- created: CRequest@33a7980 RequestUpdateUserData-URSSRV[628]/869
19:20:28.823: $+TLIB:CTI:Unknown:0:632
19:20:28.823  -- deleted: CRequest@33a7980 RequestUpdateUserData-URSSRV[628]/869
19:20:28.823: $-TLIB:CTI:Unknown:0:7

19:20:28.823 Trc 04541 RequestUpdateUserData received from [628] (00000004 URSSRV 162.56.2.18:61109)
message RequestUpdateUserData
AttributeThisDN '11000'
AttributeConnID 006f025cd046d077
AttributeUserData [462] 00 14 00 00..

AttributeReferenceID 870
19:20:28.823 Int 04543 Interaction message "RequestUpdateUserData" received from 628 ("URSSRV")
@19:20:28.8230 Call [006f025cd046d077]: urid 0005006f
@19:20:28.8230 [0] 8.1.101.01 distribute_event: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129eb
AttributeTimeinuSecs 823000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeThirdPartyDN '11000'
AttributeThisDNRole 1
AttributeThisDN '6015'
AttributeDNIS '18005559979'
AttributeUserData [3332] 00 c2 00 00..
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RStrategyName' 'Test_Strat'
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.823 Int 04544 Interaction message "EventAttachedDataChanged" generated
19:20:28.823 Trc 04542 EventAttachedDataChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.8230 [0] 8.1.101.01 distribute_response: message EventAttachedDataChanged
AttributeEventSequenceNumber 00000000000129ec
AttributeTimeinuSecs 823000
AttributeTimeinSecs 1423489828 (19:20:28)
AttributeReferenceID 870
AttributeThirdPartyDN '11000'
AttributeThisDNRole 2
AttributeThisDN '11000'
AttributeDNIS '18005559979'
AttributeUserData [3332] 00 c2 00 00..
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RStrategyName' 'Test_Strat'
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
19:20:28.823 Int 04544 Interaction message "EventAttachedDataChanged" generated
19:20:28.823 Trc 04542 EventAttachedDataChanged sent to [628] (00000004 URSSRV 162.56.2.18:61109)
19:20:28.823 Trc 04542 EventAttachedDataChanged sent to [516] (00000003 STATSRV 162.56.2.16:58054)
@19:20:28.8230 [ISCC] Updated call:
i c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
  p:0000000000000000,00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:11000:
@19:20:28.8230 [ISCC] UserData propagation has been started
@19:20:28.8240 [ISCC] UserData propagation has been finished
19:20:28.824  -- call_info::committed changed
19:20:28.824  -- created: CRequest@33a7980 RequestUpdateUserData-URSSRV[628]/870
19:20:28.824: $+TLIB:CTI:Unknown:0:597
19:20:28.824  -- deleted: CRequest@33a7980 RequestUpdateUserData-URSSRV[628]/870
19:20:28.824: $-TLIB:CTI:Unknown:0:7

19:20:28.824 Trc 04541 RequestRouteCall received from [628] (00000004 URSSRV 162.56.2.18:61109)
message RequestRouteCall
AttributeThisDN '11000'
AttributeConnID 006f025cd046d077
AttributeOtherDN '6023'
AttributeExtensions [47] 00 02 00 00..
'CUSTOMER_ID' 'Resources'
'SWITCH' 'SIPSwitch'
AttributeRouteType 1 (RouteTypeDefault)
AttributeReason [14] 00 01 01 00..
'RTR' 114
AttributeReferenceID 871
19:20:28.824 Int 04543 Interaction message "RequestRouteCall" received from 628 ("URSSRV")
@19:20:28.8240 [ISCC] destination_location substituted by local
@19:20:28.8240 [ISCC] destination_location substituted by local
19:20:28.824  -- created: CRequest@33a7980 RequestRouteCall-URSSRV[628]/871
19:20:28.824: $+TLIB:CTI:Unknown:0:47
19:20:28.824 +++ CIFace::Request +++
  -- new invoke
  -- thisCall by party
  Parsed: RequestRouteCall
  From: URSSRV[628]/871
  Numbers: +<11000> +<6023>
  Calls: 31aa0b0:1 none
  Parties: 11000.33b51e0-31aa0b0:1
          none
  Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
  -----
  -- validate
  -- state check: ok
  CIFace: Sent CRequest@33a7980 RequestRouteCall-URSSRV[628]/871
  -- aTmCall 006f025cd046d077 SetCause: Distributed to 6023
  FinishRequest CRequest@33a7980 RequestRouteCall-URSSRV[628]/871
  IFace stats: q=0 s=0
  -- complete
  -- NAData ClRq removed
  TNAEmulator::NotifyBackup()
19:20:28.824: concurrent federation request context
19:20:28.824 SIPCONN(6015): re-invite-called-initiated
19:20:28.824: transfer re-INVITE
19:20:28.824 SIPCONN(6023): set monitor 0000000001c486b0, 000000000332dde8
19:20:28.824: SIPCALL(122): add party '6023'
  -- created party_info_tspp 24d05d0
  -- created aTmParty 33b4fb0
  SetRole: Destination for 6023.33b4fb0-31aa0b0:1
  -- AddParty to 31aa0b0: 6023.33b4fb0-31aa0b0:1 after 11000.33b51e0-31aa0b0:1
  CreateParty new internal: 6023.33b4fb0-31aa0b0:1
19:20:28.824: Call 122 dn 6023 SetPartyId 292
19:20:28.824: SIPTR(1195): Begin step 0 - SipTransactionTransferCall(1196)
Session value of the SDP is [0]
Version values of the SDP is [1129055985]
19:20:28.824 SIPCONN(6023): re-invite-null
19:20:28.824: SipDialog: set monitor 0000000001c48768
19:20:28.824 SIPCONN(6023): main dialog 0 created
19:20:28.824 SIPCONN(6023): Local contact: '<sip:6015@162.56.2.18:5060;transport=tcp>'
19:20:28.824: add party info '6023' state 0.
19:20:28.824: SIPDLG[2251]: register TRN[82226]
19:20:28.824: SIPDLG[2251]: TRN[82226] flags set to 0x6
19:20:28.824: Sending  [648,TCP] 1031 bytes to 165.42.77.15:5060 >>>>>
INVITE sip:6023@165.42.77.15;transport=tcp;avaya-sc-enabled SIP/2.0
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2325
To: <sip:11000@162.56.2.18:5060>
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-201@162.56.2.18
CSeq: 1 INVITE
Content-Length: 239
Content-Type: application/sdp
Via: SIP/2.0/TCP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4245
Contact: <sip:6015@162.56.2.18:5060;transport=tcp>
X-Genesys-CallInfo: routed
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26
Max-Forwards: 69
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: uui,100rel,timer

v=0
o=MxSIP 1423143019 1 IN IP4 165.42.84.135
s=SIP Call
c=IN IP4 165.42.84.135
t=0 0
m=audio 34008 RTP/AVP 0 8 18 127
a=ptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:127 telephone-event/8000



Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: Kubig on February 10, 2015, 01:16:03 PM

Your logs does not contain any error.

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 10, 2015, 01:18:07 PM

HI Kubig,

The order of sequence of logs is based on the time starting from 1)at 10:08:24 PM
2)10:13:02 PM and the last one is this thread.

19:20:28.824: SipDialog: event SEND_INVITE, t=82226, s=2, r=7, m=0000000001c48768
19:20:28.824 SIPCONN(6023): HandleSipDialogEvent(SEND_INVITE) - filtered
19:20:28.824 SIPCONN(6023): sdp state SDP_STATE_NULL, event SDP_EVENT_OFFER_SENT
19:20:28.824 SIPCONN(6023): new sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_OFFER_SENT
19:20:28.824 --- CIFace::Request ---
19:20:28.824: $-TLIB:CTI:Unknown:0:712

19:20:28.829: $+NET:SIP::0:0
19:20:28.829: SIPTR: Received [0,UDP] 591 bytes from 165.42.84.135:5060 <<<<<
NOTIFY sip:162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK90f80af6c
Max-Forwards: 70
Content-Length: 21
To: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632298 NOTIFY
Supported: timer
Content-Type: message/sipfrag
Event: refer
Contact: 6015 <sip:6015@165.42.84.135:5060>
Supported: replaces
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26

SIP/2.0 180 Ringing

19:20:28.829: SIPDLG[2244]: register TRN[82227]
19:20:28.829: SipDialog: event CONNECTED_REQUEST, t=82227, s=7, r=4, m=0000000000000000
19:20:28.829: Sending  [0,UDP] 359 bytes to 165.42.84.135:5060 >>>>>
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK90f80af6c;received=165.42.84.135
To: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632298 NOTIFY
Content-Length: 0


19:20:28.829: SipDialog: event CONNECTED_SEND_RESREJECT, t=82227, s=7, r=6, m=0000000000000000
19:20:28.829: $-NET:SIP::0:91

19:20:28.835: $+NET:SIP::0:0
19:20:28.835: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
19:20:28.835: SIPTR: Received [648,TCP] 377 bytes from 165.42.77.15:5060 <<<<<
SIP/2.0 100 Trying
From: <sip:6015@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2325
To: <sip:11000@162.56.2.18:5060>
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-201@162.56.2.18
CSeq: 1 INVITE
Via: SIP/2.0/TCP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4245
User-Agent: Avaya one-X Deskphone 6.2.2.17 (40235)
Content-Length:    0


19:20:28.835: SipDialog: event CALLING_RESPROV, t=82226, s=2, r=5, m=0000000001c48768
19:20:28.835 SIPCONN(6023): HandleSipDialogEvent(CALLING_RESPROV)
19:20:28.835 SIPCONN(6023): Capabilities 61013f
19:20:28.835 SIPCONN(6023): reliable=0
19:20:28.835 SIPCONN(6023): store remote content
19:20:28.835 SIPCONN(6023): store remote content - trying ignored
19:20:28.835 SIPCONN(6023): Trying Timer for 32000 mlsec started...
19:20:28.835: SIPS:LOGBLOCK:END:SIPMSG:]
19:20:28.835: $-NET:SIP::0:44

19:20:28.840: $+NET:SIP::0:0
19:20:28.840: SIPTR: Received [0,UDP] 359 bytes from 162.56.2.19:5070 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4244
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2317
To: <sip:18005559979@162.56.2.18:5060>;tag=F04E45C5-5F16-4250-3195-D41554BBC37F
CSeq: 3 BYE
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-200@162.56.2.18
Content-Length: 0


19:20:28.840: SipDialog: event TERMINATING_BYE_RES, t=82223, s=9, r=4, m=0000000000000000
19:20:28.840: SipDialog: event DESTROY, t=0, s=10, r=4, m=0000000000000000
19:20:28.840: DIALOG_TRANSPORT_ASSOCIATION[2245] NOT FOUND
19:20:28.840: SipDialog[2245]:<< Abort ALL <<
19:20:28.840: $-NET:SIP::0:68

19:20:29.037: $+NET:SIP::0:0
19:20:29.037: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
19:20:29.037: SIPTR: Received [648,TCP] 582 bytes from 165.42.77.15:5060 <<<<<
SIP/2.0 180 Ringing
From: <sip:6015@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2325
To: <sip:11000@162.56.2.18:5060>;tag=-47447c1538b356057166be6b_T6023165.42.77.15
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-201@162.56.2.18
CSeq: 1 INVITE
Via: SIP/2.0/TCP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4245
Allow: INVITE,ACK,BYE,CANCEL,SUBSCRIBE,NOTIFY,MESSAGE,REFER,INFO,PRACK,PUBLISH,UPDATE
User-Agent: Avaya one-X Deskphone 6.2.2.17 (40235)
Contact: <sip:6023@165.42.77.15;transport=tcp>
Accept-Language: en
Content-Length:    0


19:20:29.037: SipDialog: event CALLING_RESPROV, t=82226, s=2, r=5, m=0000000001c48768
19:20:29.037 SIPCONN(6023): HandleSipDialogEvent(CALLING_RESPROV)
19:20:29.037 SIPCONN(6023): Trying Timer deleted...
19:20:29.037 SIPCONN(6023): Capabilities 6101ff
19:20:29.037 SIPCONN(6023): reliable=0
19:20:29.037 SIPCONN(6023): store remote content
19:20:29.037 SIPCONN(6023): skip SDP pocessing [0/SDP_STATE_OFFER_SENT]
19:20:29.037: routing dialog '291' terminated
19:20:29.037  -- thisCall by party
19:20:29.037 SetContext: for party 11000.33b51e0-31aa0b0:1
19:20:29.037 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventRouteUsed
    Devices: <11000/11000> <6023/6023> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:1 0/none
    Parties: D11000/11000.33b51e0-31aa0b0:1/l:2/r:1/Queued,RtRequest,Destination
    D6023/6023.33b4fb0-31aa0b0:1/l:2/r:0/Null,DNull,Destination
    none
    Cause: Distributed/38, Info: 0
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Diverted +++
    SetRouteEnd: party 11000.33b51e0-31aa0b0:1, cause Distributed
@19:20:29.0370 [0] 8.1.101.01 distribute_response: message EventRouteUsed
AttributeEventSequenceNumber 00000000000129ed
AttributeTimeinuSecs 37000
AttributeTimeinSecs 1423489829 (19:20:29)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeReason [14] 00 01 01 00..
'RTR' 114
AttributeReferenceID 871
AttributeThirdPartyDNRole 2
AttributeThirdPartyDN '6023'
AttributeOtherDNRole 1
AttributeOtherDN '6015'
AttributeThisQueue '11000'
AttributeThisDNRole 2
AttributeThisDN '11000'
AttributeDNIS '18005559979'
AttributeUserData [3332] 00 c2 00 00..
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RStrategyName' 'Test_Strat'
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
19:20:29.037 Int 04544 Interaction message "EventRouteUsed" generated
19:20:29.037 Trc 04542 EventRouteUsed sent to [628] (00000004 URSSRV 162.56.2.18:61109)
19:20:29.037 Trc 04542 EventRouteUsed sent to [516] (00000003 STATSRV 162.56.2.16:58054)
    -- Cleanup call leg
  --- Diverted ---
  +++ Post-event +++
    Type EventRouteUsed
    Devices: <11000/11000> <6023/6023> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:1 0/none
    Parties: D11000/11000.33b51e0-31aa0b0:1/l:2/r:1/Queued,Destination
    D6023/6023.33b4fb0-31aa0b0:1/l:2/r:0/Null,DNull,Destination
    none
    Cause: Distributed/38, Info: 0
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:20:29.037 --- CIFace::Event ---
19:20:29.037  -- thisCall by party
19:20:29.037  -- thisCall by party
19:20:29.037 ClearContext: party 11000.33b51e0-31aa0b0:1
19:20:29.037 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventDiverted
    Devices: <11000/11000> <6023/6023> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:1 0/none
    Parties: D11000/11000.33b51e0-31aa0b0:1/l:2/r:1/Queued,Destination
    D6023/6023.33b4fb0-31aa0b0:1/l:2/r:0/Null,DNull,Destination
    none
    Cause: Distributed/38, Info: 0
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Diverted +++
    DoDivert: from 11000.33b51e0-31aa0b0:1 to 6023.33b4fb0-31aa0b0:1 cause Distributed
@19:20:29.0370 [0] 8.1.101.01 distribute_event: message EventDiverted
AttributeEventSequenceNumber 00000000000129ee
AttributeTimeinuSecs 37000
AttributeTimeinSecs 1423489829 (19:20:29)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeOtherDNRole 1
AttributeOtherDN '6015'
AttributeThirdPartyDNRole 2
AttributeThirdPartyDN '6023'
AttributeThisQueue '11000'
AttributeThisDNRole 2
AttributeThisDN '11000'
AttributeDNIS '18005559979'
AttributeUserData [3332] 00 c2 00 00..
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RStrategyName' 'Test_Strat'
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
19:20:29.037 Int 04544 Interaction message "EventDiverted" generated
19:20:29.037 Trc 04542 EventDiverted sent to [628] (00000004 URSSRV 162.56.2.18:61109)
19:20:29.037 Trc 04542 EventDiverted sent to [516] (00000003 STATSRV 162.56.2.16:58054)
    inherit_queues: from 24d06d0 to 24d05d0
    -- party_info 11000.24d06d0 state change: from <Queued> to <Null>
@19:20:29.0380 [ISCC] Party removed [ssp view]:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000,0000000000000000 p:3 i:0000007a nw:00000000:0000000000000000 t:1
  p:00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 t:0 s:c n:6015
- p:00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:0 n:11000
  p:00000000024d05d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:0 n:6023
@19:20:29.0380 [ISCC] Party removed:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
- p:0000000000000000,00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:11000:
@19:20:29.0380 [ISCC] Party object is removed: p:0000000000000000,0000000000000000 n:11000:
  --- Diverted ---
  +++ Post-event +++
    Type EventDiverted
    Devices: <11000/11000> <6023/6023> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:1/r:1 0/none
    Parties: D11000/11000.33b51e0-31aa0b0:1/l:2/r:1/Released,Destination
    D6023/6023.33b4fb0-31aa0b0:1/l:2/r:0/Null,DNull,Destination
    none
    Cause: Distributed/38, Info: 0
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:20:29.038 --- CIFace::Event ---
19:20:29.038  -- thisCall by party
19:20:29.038: check call postponed until end of operation
19:20:29.038 SIPCONN(6015): 18x response, state 1,1,0,0,1
19:20:29.038 SIPCONN(6015): SendResponse(180,82221)
19:20:29.038: add party info '6015' state 3.
19:20:29.038: Sending  [0,UDP] 535 bytes to 165.42.84.135:5060 >>>>>
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK72940ad76;received=165.42.84.135
To: 11000 <sip:11000@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2323
From: 6015 <sip:6015@162.56.2.18>;tag=8f56692959c93f2
Call-ID: 8c2f69e993ce7a90b96f23ead577ab5b@165.42.84.135
CSeq: 1002849195 INVITE
X-Genesys-CallUUID: 4F2ULGO89D60F9C3LGPO3GC55400003Q
Allow: INVITE, ACK, PRACK, CANCEL, BYE, UPDATE
User-Agent: Avaya one-X Deskphone 6.2.2.17 (40235)
Accept-Language: en
Supported: uui
Content-Length: 0


19:20:29.038: SipDialog: event CALLED_RESPROV, t=82221, s=1, r=6, m=0000000001c478c8
19:20:29.038 SIPCONN(6015): HandleSipDialogEvent(CALLED_RESPROV) - filtered
19:20:29.038 SIPCONN(6023): state e:2,p:0,s:2,c:17,rc:0,m:1
19:20:29.038 SIPPARTY(6023): 292 verify update of party-connection state N-A
19:20:29.038 SIPPARTY(6023): 292 update party-connection state N-A
19:20:29.038  -- thisCall by party
19:20:29.038 ClearContext: party 6023.33b4fb0-31aa0b0:1
19:20:29.038 +++ CIFace::Event +++
  +++ Pre-event +++
    Type EventRinging
    Devices: <6023/6023> <6015/6015> <-/->
    Calls: 122/006f025cd046d077/122.31aa0b0/c:1/r:1 0/none
    Parties: D6023/6023.33b4fb0-31aa0b0:1/l:2/r:0/Null,DNull,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Established,Active,Origination
    none
    Cause: Distributed/38, Info: 0
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Pre-event ---
  +++ Ringing +++
    -- guessed previous party: 11000.33b51e0-31aa0b0:1
    inherit_queues: from 24d06d0 to 24d05d0
    -- inherit skipped
    -- 3rd pi: D11000/11000.33b51e0-31aa0b0:1/l:2/r:1/Released,Destination
    SetDelivered: party 6023.33b4fb0-31aa0b0:1, cause Distributed
    -- TellDelivered
    -- party_info 6023.24d05d0 state change: from <Null> to <Alerting>
    -- aTmParty::SetBusinessType():6023 set to BusinessType[2/4:1]
    -- G7 ringing
    -- call delivered
    -- call_info::committed changed
@19:20:29.0380 [ISCC] Party added [ssp view]:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000,0000000000000000 p:3 i:0000007a nw:00000000:0000000000000000 t:1
  p:00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 t:0 s:c n:6015
  p:00000000024d06d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:0 n:11000
+ p:00000000024d05d0 @ c:006f025cd046d077,0000000003352c40 r:2 t:0 s:a n:6023
@19:20:29.0380 [ISCC] Party object is created: p:0000000000000000,00000000024d05d0 n:6023:
@19:20:29.0380 [ISCC] Party added:
@ c:006f025cd046d077,0000000003352c40 @ m:0000000000000000,0000000000000000 p:2 i:0000007a nw:0000000000000000 t:1
  p:0000000000000000,00000000024d15d0 @ c:006f025cd046d077,0000000003352c40 r:1 ----- n:6015:
+ p:0000000000000000,00000000024d05d0 @ c:006f025cd046d077,0000000003352c40 r:2 ----- n:6023:
@19:20:29.0380 [0] 8.1.101.01 distribute_event: message EventRinging
AttributeEventSequenceNumber 00000000000129ef
AttributeTimeinuSecs 38000
AttributeTimeinSecs 1423489829 (19:20:29)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeThisQueue '11000'
AttributeThisDNRole 2
AttributeAgentID '11000'
AttributeThisDN '6023'
AttributeDNIS '18005559979'
AttributeUserData [3332] 00 c2 00 00..
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RStrategyName' 'Test_Strat'
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
AttributeCallUUID '4F2ULGO89D60F9C3LGPO3GC55400003Q'
AttributeConnID 006f025cd046d077
AttributeCallID 122
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
AttributeOtherDNRole 1
AttributeOtherDN '6015'
19:20:29.038 Int 04544 Interaction message "EventRinging" generated
19:20:29.038 Trc 04542 EventRinging sent to [508] (00000031 iws_boa1 165.43.156.107:57929)
19:20:29.038 Trc 04542 EventRinging sent to [516] (00000003 STATSRV 162.56.2.16:58054)
    -- Cleanup call leg
    -- deleted aTmParty 33b51e0
    -- RemoveParty 11000.33b51e0-31aa0b0:1
    -- deleted party_info_tspp 24d06d0
  --- Ringing ---
  +++ Post-event +++
    Type EventRinging
    Devices: <6023/6023> <6015/6015> <11000/11000>
    Calls: 122/006f025cd046d077/122.31aa0b0/c:2/r:1 0/none
    Parties: D6023/6023.33b4fb0-31aa0b0:1/l:2/r:0/Alerting,DNull,Destination
    D6015/6015.33b46f0-31aa0b0:1/l:1/r:0/Established,Active,Origination
    none
    Cause: Distributed/38, Info: 0
    Call type: Internal/1
    Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
  --- Post-event ---
19:20:29.038 --- CIFace::Event ---
19:20:29.038: SIPTR(1196): complete
19:20:29.038: SIPTR(1195): Step 0 - SipTransactionTransferCall(1196) complete
19:20:29.038: SIPTR(1195): complete
19:20:29.038: SIPCM: transaction SipScenario(1195) complete
19:20:29.038: PI: 00 S[CI]D[6015]C[*D[6015]]P[6023]
19:20:29.038: PI: 01 S[NQ]D[11000]E[-] - should be deleted
19:20:29.038: check linked party
19:20:29.038: SIPCM: delete party 11000:291
19:20:29.038: SIPCALL(122): delete party '11000'
19:20:29.038: GetPartyInfo party with partyId 291 not found
19:20:29.038: PI: 00 S[AN]D[6023]C[*D[6023]]P[6015]
19:20:29.038: CALLSTATE(a:2,d:0,i:0,e:0,r:0,o:0)
19:20:29.038: call1 122 idle
19:20:29.038: SIPS:LOGBLOCK:END:SIPMSG:]
19:20:29.038: $-NET:SIP::0:1675

19:20:29.079: $+NET:SIP::0:0
19:20:29.079: SIPTR: Received [0,UDP] 591 bytes from 165.42.84.135:5060 <<<<<
NOTIFY sip:162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK9ba19499b
Max-Forwards: 70
Content-Length: 21
To: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632299 NOTIFY
Supported: timer
Content-Type: message/sipfrag
Event: refer
Contact: 6015 <sip:6015@165.42.84.135:5060>
Supported: replaces
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26

SIP/2.0 180 Ringing

19:20:29.079: SIPDLG[2244]: register TRN[82228]
19:20:29.079: SipDialog: event CONNECTED_REQUEST, t=82228, s=7, r=4, m=0000000000000000
19:20:29.079: Sending  [0,UDP] 359 bytes to 165.42.84.135:5060 >>>>>
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 165.42.84.135:5060;branch=z9hG4bK9ba19499b;received=165.42.84.135
To: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
From: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 567632299 NOTIFY
Content-Length: 0


19:20:29.079: SipDialog: event CONNECTED_SEND_RESREJECT, t=82228, s=7, r=6, m=0000000000000000
19:20:29.079: $-NET:SIP::0:112

19:20:33.829: $+NET:SIP::0:0
19:20:33.829: SIPTR: Received [0,UDP] 639 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDCA60d8bb29
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93902 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 17
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/100/100
Supported: timer, uui

Resources/100/100
@19:20:33.8290 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129f0
AttributeTimeinuSecs 829000
AttributeTimeinSecs 1423489833 (19:20:33)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 100
'total-ports' 100
AttributeThisDN 'Resources'
19:20:33.829: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001AFDCA60d8bb29;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93902 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:20:33.829: $-NET:SIP::0:195

19:20:38.838: $+NET:SIP::0:0
19:20:38.838: SIPTR: Received [0,UDP] 639 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK0000000016B0DA60d8bb2e
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93903 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 17
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/100/100
Supported: timer, uui

Resources/100/100
@19:20:38.8380 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129f1
AttributeTimeinuSecs 838000
AttributeTimeinSecs 1423489838 (19:20:38)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 100
'total-ports' 100
AttributeThisDN 'Resources'
19:20:38.838: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK0000000016B0DA60d8bb2e;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93903 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:20:38.838: $-NET:SIP::0:191

19:20:43.846: $+NET:SIP::0:0
19:20:43.846: SIPTR: Received [0,UDP] 639 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK0000000016B0D850d8bb33
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93904 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 17
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/100/100
Supported: timer, uui

Resources/100/100
@19:20:43.8460 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129f2
AttributeTimeinuSecs 846000
AttributeTimeinSecs 1423489843 (19:20:43)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 100
'total-ports' 100
AttributeThisDN 'Resources'
19:20:43.846: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK0000000016B0D850d8bb33;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93904 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:20:43.846: $-NET:SIP::0:189

19:20:48.855: $+NET:SIP::0:0
19:20:48.855: SIPTR: Received [0,UDP] 639 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0AB490d8bb38
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93905 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 17
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/100/100
Supported: timer, uui

Resources/100/100
@19:20:48.8550 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129f3
AttributeTimeinuSecs 855000
AttributeTimeinSecs 1423489848 (19:20:48)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 100
'total-ports' 100
AttributeThisDN 'Resources'
19:20:48.855: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0AB490d8bb38;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93905 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:20:48.855: $-NET:SIP::0:194

19:20:53.862: $+NET:SIP::0:0
19:20:53.862: SIPTR: Received [0,UDP] 639 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0AAA40d8bb3d
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93906 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 17
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/100/100
Supported: timer, uui

Resources/100/100
@19:20:53.8620 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129f4
AttributeTimeinuSecs 862000
AttributeTimeinSecs 1423489853 (19:20:53)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 100
'total-ports' 100
AttributeThisDN 'Resources'
19:20:53.862: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0AAA40d8bb3d;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93906 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:20:53.862: $-NET:SIP::0:187

19:20:54.323 Trc 04541 RequestAnswerCall received from [508] (00000031 iws_boa1 165.43.156.107:57929)
message RequestAnswerCall
AttributeReferenceID 3
AttributeConnID 006f025cd046d077
AttributeThisDN '6023'
19:20:54.323 Int 04543 Interaction message "RequestAnswerCall" received from 508 ("iws_boa1")
19:20:54.323  -- created: CRequest@33a7e40 RequestAnswerCall-iws_boa1[508]/3
19:20:54.323: $+TLIB:CTI:Unknown:0:25497739
19:20:54.323 +++ CIFace::Request +++
  -- new invoke
  -- thisCall by party
  Parsed: RequestAnswerCall
  From: iws_boa1[508]/3
  Numbers: +<6023> -<none>
  Calls: 31aa0b0:1 none
  Parties: 6023.33b4fb0-31aa0b0:1
          none
  Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
  -----
  -- validate
  -- state check: ok
  CIFace: Sent CRequest@33a7e40 RequestAnswerCall-iws_boa1[508]/3
  TNAEmulator::NotifyBackup()
  -- NAData ClRq added
  FinishRequest CRequest@33a7e40 RequestAnswerCall-iws_boa1[508]/3
  IFace stats: q=0 s=0
  -- complete
  -- NAData ClRq removed
  TNAEmulator::NotifyBackup()
19:20:54.323: SIPDLG[2251]: register TRN[82234]
19:20:54.323: Sending  [648,TCP] 510 bytes to 165.42.77.15:5060 >>>>>
NOTIFY sip:6023@165.42.77.15;transport=tcp SIP/2.0
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2325
To: <sip:11000@162.56.2.18:5060>;tag=-47447c1538b356057166be6b_T6023165.42.77.15
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-201@162.56.2.18
CSeq: 2 NOTIFY
Content-Length: 0
Via: SIP/2.0/TCP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4246
Contact: <sip:6015@162.56.2.18:5060;transport=tcp>
Event: talk
Subscription-State: active
Max-Forwards: 69


19:20:54.323: SipDialog: event SEND_EARLY_REQ, t=82234, s=2, r=5, m=0000000001c48768
19:20:54.323 SIPCONN(6023): HandleSipDialogEvent(SEND_EARLY_REQ) - filtered
19:20:54.323 --- CIFace::Request ---
19:20:54.323: $-TLIB:CTI:Unknown:0:293

19:20:54.337: $+NET:SIP::0:0
19:20:54.337: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
19:20:54.337: SIPTR: Received [648,TCP] 376 bytes from 165.42.77.15:5060 <<<<<
SIP/2.0 489 Bad Event
From: <sip:6015@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2325
To: <sip:11000@162.56.2.18:5060>;tag=-47447c1538b356057166be6b_T6023165.42.77.15
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-201@162.56.2.18
CSeq: 2 NOTIFY
Via: SIP/2.0/TCP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4246
Content-Length:    0


19:20:54.337: SipDialog: event EARLY_RES, t=82234, s=2, r=5, m=0000000001c48768
19:20:54.337 SIPCONN(6023): HandleSipDialogEvent(EARLY_RES)
19:20:54.337 SIPCONN(6023): concurrent transaction: c:82226, n:82234
19:20:54.337 SIPCONN(6023): event ignored
19:20:54.337: SIPS:LOGBLOCK:END:SIPMSG:]
19:20:54.337: $-NET:SIP::0:43

19:20:58.863: $+NET:SIP::0:0
19:20:58.863: SIPTR: Received [0,UDP] 639 bytes from 162.56.2.19:5074 <<<<<
NOTIFY sip:Resources@162.56.2.18:5060 SIP/2.0
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0D9CF0d8bb42
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
Max-Forwards: 70
CSeq: 93907 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Contact: <sip:GVP@162.56.2.19:5074>
Content-Length: 17
Content-Type: application/x-genesys-gvp-tenant-ports
Subscription-State: active
Event: x-genesys-gvp-tenant-ports
X-Genesys-gvp-tenant-ports: Resources/100/100
Supported: timer, uui

Resources/100/100
@19:20:58.8630 [0] 8.1.101.01 distribute_event: message EventResourceInfo
AttributeEventSequenceNumber 00000000000129f5
AttributeTimeinuSecs 863000
AttributeTimeinSecs 1423489858 (19:20:58)
AttributeExtensions [46] 00 02 01 00..
'available-ports' 100
'total-ports' 100
AttributeThisDN 'Resources'
19:20:58.863: Sending  [0,UDP] 369 bytes to 162.56.2.19:5074 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.56.2.19:5074;branch=z9hG4bK000000001B0D9CF0d8bb42;received=162.56.2.19
From: sip:Resources@162.56.2.19:5070;tag=0348A787-4C37-42D4-D69C-88E46CFFF91D
To: sip:Resources@162.56.2.18:5060;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-1
CSeq: 93907 NOTIFY
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-1@162.56.2.18
Content-Length: 0


19:20:58.863: $-NET:SIP::0:183

-AP[6]-<-508 @19:20:59.0830
-Ap[6]->-508 @19:20:59.0830
19:20:59.724 Trc 04541 RequestAnswerCall received from [508] (00000031 iws_boa1 165.43.156.107:57929)
message RequestAnswerCall
AttributeReferenceID 4
AttributeConnID 006f025cd046d077
AttributeThisDN '6023'
19:20:59.724 Int 04543 Interaction message "RequestAnswerCall" received from 508 ("iws_boa1")
19:20:59.724  -- created: CRequest@33a8300 RequestAnswerCall-iws_boa1[508]/4
19:20:59.724: $+TLIB:CTI:Unknown:0:5400033
19:20:59.724 +++ CIFace::Request +++
  -- new invoke
  -- thisCall by party
  Parsed: RequestAnswerCall
  From: iws_boa1[508]/4
  Numbers: +<6023> -<none>
  Calls: 31aa0b0:1 none
  Parties: 6023.33b4fb0-31aa0b0:1
          none
  Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
  -----
  -- validate
  -- state check: ok
  CIFace: Sent CRequest@33a8300 RequestAnswerCall-iws_boa1[508]/4
  TNAEmulator::NotifyBackup()
  -- NAData ClRq added
  FinishRequest CRequest@33a8300 RequestAnswerCall-iws_boa1[508]/4
  IFace stats: q=0 s=0
  -- complete
  -- NAData ClRq removed
  TNAEmulator::NotifyBackup()
19:20:59.724: SIPDLG[2251]: register TRN[82236]
19:20:59.724: Sending  [648,TCP] 510 bytes to 165.42.77.15:5060 >>>>>
NOTIFY sip:6023@165.42.77.15;transport=tcp SIP/2.0
From: sip:6015@162.56.2.18;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2325
To: <sip:11000@162.56.2.18:5060>;tag=-47447c1538b356057166be6b_T6023165.42.77.15
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-201@162.56.2.18
CSeq: 3 NOTIFY
Content-Length: 0
Via: SIP/2.0/TCP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4247
Contact: <sip:6015@162.56.2.18:5060;transport=tcp>
Event: talk
Subscription-State: active
Max-Forwards: 69
19:20:59.724: SipDialog: event SEND_EARLY_REQ, t=82236, s=2, r=5, m=0000000001c48768
19:20:59.724 SIPCONN(6023): HandleSipDialogEvent(SEND_EARLY_REQ) - filtered
  -- thisCall by party
19:20:59.724 Trc 36002 Request rejected: error code 50(unknown)
@19:20:59.7240 [0] 8.1.101.01 send_to_client: message EventError
(Unspecified error)
AttributeEventSequenceNumber 00000000000129f6
AttributeTimeinuSecs 724000
AttributeTimeinSecs 1423489859 (19:20:59)
AttributeErrorCode 50
AttributeErrorMessage 'unknown'
AttributeThisDN '6023'
AttributeConnID 006f025cd046d077
AttributeReferenceID 3
AttributeClientID 49
19:20:59.724 Int 04545 Interaction message "EventError" sent to 508 ("iws_boa1")
19:20:59.724 Trc 04542 EventError sent to [508] (00000031 iws_boa1 165.43.156.107:57929)
  -- deleted: CRequest@33a7e40 RequestAnswerCall-iws_boa1[508]/3
19:20:59.724 --- CIFace::Request ---
19:20:59.724: $-TLIB:CTI:Unknown:0:381

19:20:59.730: $+NET:SIP::0:0
19:20:59.730: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
19:20:59.730: SIPTR: Received [648,TCP] 376 bytes from 165.42.77.15:5060 <<<<<
SIP/2.0 489 Bad Event
From: <sip:6015@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2325
To: <sip:11000@162.56.2.18:5060>;tag=-47447c1538b356057166be6b_T6023165.42.77.15
Call-ID: DBA6A485-0C6F-4B06-9E51-FE06784CD1DF-201@162.56.2.18
CSeq: 3 NOTIFY
Via: SIP/2.0/TCP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4247
Content-Length:    0


19:20:59.730: SipDialog: event EARLY_RES, t=82236, s=2, r=5, m=0000000001c48768
19:20:59.730 SIPCONN(6023): HandleSipDialogEvent(EARLY_RES)
19:20:59.730 SIPCONN(6023): concurrent transaction: c:82226, n:82236
19:20:59.730 SIPCONN(6023): event ignored
19:20:59.730: SIPS:LOGBLOCK:END:SIPMSG:]
19:20:59.730: $-NET:SIP::0:45

19:21:00.692: TRANSPORT[0x32] [664,TCP] 162.56.2.19:57326 handled exception
19:21:00.692: Transport: Remove instance '39'
19:21:03.787: SipDialog: ClearCall(phone=0,state=7)
19:21:03.787: SipDialog::Terminate(state=7,reason=0)
19:21:03.787: SIPDLG[2244]: register TRN[82237]
19:21:03.787: Sending  [0,UDP] 389 bytes to 165.42.84.135:5060 >>>>>
BYE sip:6015@165.42.84.135:5060 SIP/2.0
From: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
To: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 2 BYE
Content-Length: 0
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4248
Max-Forwards: 68


19:21:03.787: SipDialog: event SEND_BYE, t=82237, s=8, r=4, m=0000000000000000
19:21:03.819: $+NET:SIP::0:0
19:21:03.819: SIPTR: Received [0,UDP] 441 bytes from 165.42.84.135:5060 <<<<<
SIP/2.0 200 OK
Call-ID: 999766dbe1d3a54ece22bd5c0ebe5c58@165.42.84.135
CSeq: 2 BYE
From: 18005559979 <sip:18005559979@162.56.2.18>;tag=042D05A8-4745-4C34-B5B7-0EDB505255BC-2316
To: 6015 <sip:6015@162.56.2.18>;tag=cb8a59fa2ddce76
Via: SIP/2.0/UDP 162.56.2.18:5060;branch=z9hG4bK700E7DC4-3205-48FB-99CC-228786351D3E-4248
Content-Length: 0
Supported: replaces
User-Agent: Avaya SIP R2.2 Endpoint Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26


19:21:03.820: SipDialog: event TERMINATING_BYE_RES, t=82237, s=9, r=4, m=0000000000000000
19:21:03.820: SipDialog: event DESTROY, t=0, s=10, r=4, m=0000000000000000
19:21:03.820: DIALOG_TRANSPORT_ASSOCIATION[2244] NOT FOUND
19:21:03.820: SipDialog[2244]:<< Abort ALL <<
19:21:03.820: $-NET:SIP::0:82

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: Kubig on February 10, 2015, 01:31:10 PM

What IP address have got your IWS SipEndpoint?

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 10, 2015, 01:48:30 PM

HI Kubig,

The softphone is not genesys IWS. The softphone(Desktop application) is customized and the desktop IPaddress for the softphone is 165.43.156.107. The hardphone IP is 165.42.77.15 and the hardphone is configured to register with sip server.

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: cavagnaro on February 10, 2015, 02:23:04 PM

I ask again, does your endpoint support 3PCC?

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 10, 2015, 02:35:14 PM

HI Cav,

I asked the testing team the same but they told me that they have no idea.

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 10, 2015, 02:51:25 PM

Hi Cav,

What will happen if the soft phone does not support 3pcc, does the team able to answer the call from softphone or not?

Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: cavagnaro on February 10, 2015, 03:19:15 PM

Will not. That would mean you can work only on 1PCC. Clarify that doubt before proceeding further. Would be a waste of time if not.

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: blakshmikanth on February 11, 2015, 02:40:09 PM

Ajay,

It seems to me your testing and architecture is incorrect. Feel free to correct me if I am wrong.

From the logs, it looks that you are logging into SIP Server (not Avaya PBX) using Softphone. For Avaya integration, you should have the following architecture

Avaya SES/SM  ==>  SIP Server ==> Routing Point ==> GVP
    ||
    ||
Avaya CM == > Avaya T-Server ==> Agent (Hardphone or Softphone)

Is your implementation like above? If not, it will not work.

Regards,
Lucky

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: ajayku14 on February 13, 2015, 01:06:21 PM

HI All,

Related to this issue I found some observation at extension level.

When I configured the extension(6023) of Tserver section in the contact option, if I mention transport=tcp
the call is landing at both soft phone as well as hard phone and not able to answer using soft phone. If I mention transport=udp, In this scenario the call is ringing on the soft phone and can able to answer it but at that time call is not landing on the hard phone.

The options are as follows.

contact=sip:6023@165.42.77.15;transport=tcp;avaya-sc-enabled=========> not able to answer using soft phone.Call lands on both soft phone as well as hard phone.

contact=sip:6023@165.42.77.15;transport=udp;=========> Able to answer using soft phone but the call is not landing on the hard phone.

If I want to answer using hard phone and to land the call on the hard phone what changes I need to do.


Thanks
Ajay

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: cavagnaro on February 13, 2015, 01:25:03 PM

You need to talk to Avaya guys...obviously you have them as medium. Which Rtp protocol do they support?
You have a previous questions still unanswered, 2 of them now from us. If you keep throwing hopes for a magic answer without answering ours too then no much to do.

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: Tambo on September 29, 2016, 10:49:59 AM

you guys really are desperate for sales , it really can't be up to much if this is what you need to revert to !!

Title: Re: Not Able to Answer using SoftPhone from the AgentDesktop
Post by: livinginnj on October 01, 2016, 09:13:40 PM

Check if auto-answer is enabled from switch. If enabled , you need to disable auto-answer from IWS. Hope this helps