Hello experts,
I am facing a issue where in when i dial the Trunk group , I get the fast busy tone.
I checked the SIP logs in and SIP logs I get Event Destination busy. and in Resource Manager logs I see
Cannot locate logical resource matching Physical AOR sip:192.168.1.3:5060
However I have already created the LRG i am not sure why LRG is not seen.
SIP log:
08:02:57.4290 [0] 8.1.102.53 distribute_event: message EventDialing
AttributeEventSequenceNumber 000000000000004c
AttributeTimeinuSecs 429000
AttributeTimeinSecs 1482912177 (08:02:57)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 0
AttributeOtherDNRole 2
AttributeOtherDN '2200'
AttributePartyUUID 'OSMESV2Q9P5V140BRJURTD6AEG000004'
AttributeThisDNRole 1
AttributeThisDN '9001'
AttributeANI '9001'
AttributeDNIS '2200'
AttributeCallUUID 'OTNRESGK2H1HT7GQ1GEICJ025G000001'
AttributeConnID 00750295d10b1001
AttributeCallID 16777217
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 0
08:02:57.430 Int 04544 Interaction message "EventDialing" generated
08:02:57.430 Trc 04542 EventDialing sent to [640] (00000005 ORS_1 192.168.1.2:49524)
08:02:57.430 Trc 04542 EventDialing sent to [500] (00000003 StatServer_1 192.168.1.2:49517)
+++ Post-event +++
Type EventOriginated
Devices: <9001/9001> <2200/2200> <-/->
Calls: 16777217/00750295d10b1001/16777217.3148e70/c:1/r:0 0/00750295d10b1001/16777217.3148e70/c:1/r:0
Parties: D9001/9001.3149430-3148e70:1/l:1/r:0/Dialing,Active,Origination
D2200/2200.314a1d0-3148e70: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 ---
08:02:57.430 --- CIFace::Event ---
08:02:57.430: SIPTR(3): complete
08:02:57.430: SIPTR(0): Step 2 - SipTransactionChangePartyState(3) complete
08:02:57.430: SIPTR(0): Begin step 3 - SipTransactionGetOffer(4)
08:02:57.432: SIPTR(4): complete
08:02:57.432: SIPTR(0): Step 3 - SipTransactionGetOffer(4) complete
08:02:57.432: SIPTR(0): Begin step 4 - SipTransactionConnectNewParty(5)
Session value of the SDP is [3957275403]
Version values of the SDP is [1]
08:02:57.434 SIPCONN(2200): re-invite-null
08:02:57.434: SipDialog: set monitor 0000000003149a58
08:02:57.434 SIPCONN(2200): main dialog 0 created, flags 0x21f
08:02:57.463 SIPCONN(2200): Local contact: '<sip:9001@192.168.1.3:5060>'
08:02:57.471: add party info '2200' state 0.
08:02:57.472: SIPDLG[7]: register TRN[7]
08:02:57.472: SIPDLG[7]: TRN[7] flags set to 0x6
08:02:57.472: Sending [0,UDP] 1018 bytes to 192.168.1.3:5090 >>>>>
INVITE sip:2200@192.168.1.3:5090 SIP/2.0
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-5
To: <sip:2200@192.168.1.3:5060>
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3
CSeq: 1 INVITE
Content-Length: 327
Content-Type: application/sdp
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-1
Contact: <sip:9001@192.168.1.3:5060>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
User-Agent: X-Lite release 4.9.6 stamp 82158
Max-Forwards: 69
X-Genesys-CallUUID: OTNRESGK2H1HT7GQ1GEICJ025G000001
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: uui,100rel,timer
v=0
o=- 1482912187 1 IN IP4 192.168.1.52
s=X-Lite release 4.9.6 stamp 82158
c=IN IP4 192.168.1.52
t=0 0
m=audio 55540 RTP/AVP 9 8 120 0 84 101
a=sendrecv
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:84 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
08:02:57.472: SipDialog: event SEND_INVITE, t=7, s=2, r=7, m=0000000003149a58
08:02:57.472: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:57.472 SIPCONN(2200): HandleSipDialogEvent(SEND_INVITE) - filtered
08:02:57.472 SIPCONN(2200): sdp state SDP_STATE_NULL, event SDP_EVENT_OFFER_SENT
08:02:57.472 SIPCONN(2200): new sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_OFFER_SENT
08:02:57.472: $-NET:SIP::0:264377
08:02:57.483: Sending [0,UDP] 305 bytes to 192.168.1.52:54158 >>>>>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.52:54158;branch=z9hG4bK-524287-1---4bd140261561f901;rport;received=192.168.1.52
To: <sip:2200@192.168.1.3:5060>
From: <sip:9001@192.168.1.3:5060>;tag=20e6c156
Call-ID: 82158MGUxODRiYjc4ODI2M2NkZjAwNjE2MTRkZWJhNmRjOWE
CSeq: 1 INVITE
Content-Length: 0
08:02:57.563: $+NET:SIP::0:0
08:02:57.563: SIPTR: Received [0,UDP] 315 bytes from 192.168.1.3:5090 <<<<<
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-1
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-5
To: <sip:2200@192.168.1.3:5060>
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3
Content-Length: 0
08:02:57.563: SipDialog: event CALLING_RESPROV, t=7, s=2, r=5, m=0000000003149a58
08:02:57.563: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:57.563 SIPCONN(2200): HandleSipDialogEvent(CALLING_RESPROV)
08:02:57.563 SIPCONN(2200): Capabilities 61013f
08:02:57.563 SIPCONN(2200): reliable=0
08:02:57.564 SIPCONN(2200): store remote content
08:02:57.564 SIPCONN(2200): store remote content - trying ignored
08:02:57.564 SIPCONN(2200): Trying Timer for 32000 mlsec started...
08:02:57.564: $-NET:SIP::0:894
08:02:57.624: Assocaited transport for the device [13] changed from [:0:0] to [192.168.1.52:54158:1]
08:02:57.639: $+NET:SIP::0:0
08:02:57.639: SIPTR: Received [0,UDP] 880 bytes from 192.168.1.52:54158 <<<<<
INVITE sip:2200@192.168.1.3:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.52:54158;branch=z9hG4bK-524287-1---4bd140261561f901;rport
Max-Forwards: 70
Contact: <sip:9001@192.168.1.52:54158>
To: <sip:2200@192.168.1.3:5060>
From: <sip:9001@192.168.1.3:5060>;tag=20e6c156
Call-ID: 82158MGUxODRiYjc4ODI2M2NkZjAwNjE2MTRkZWJhNmRjOWE
CSeq: 1 INVITE
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: X-Lite release 4.9.6 stamp 82158
Content-Length: 334
v=0
o=- 13127385779580515 1 IN IP4 192.168.1.52
s=X-Lite release 4.9.6 stamp 82158
c=IN IP4 192.168.1.52
t=0 0
m=audio 55540 RTP/AVP 9 8 120 0 84 101
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:84 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
08:02:57.639: Sending [0,UDP] 305 bytes to 192.168.1.52:54158 >>>>>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.52:54158;branch=z9hG4bK-524287-1---4bd140261561f901;rport;received=192.168.1.52
To: <sip:2200@192.168.1.3:5060>
From: <sip:9001@192.168.1.3:5060>;tag=20e6c156
Call-ID: 82158MGUxODRiYjc4ODI2M2NkZjAwNjE2MTRkZWJhNmRjOWE
CSeq: 1 INVITE
Content-Length: 0
08:02:57.639: $-NET:SIP::0:122
08:02:57.798: $+NET:SIP::0:0
08:02:57.798: SIPTR: Received [0,UDP] 811 bytes from 192.168.1.3:5090 <<<<<
SIP/2.0 500 Server Internal Error
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-1
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-5
To: <sip:2200@192.168.1.3:5060>;tag=AF97B611-38BF-43CD-DE87-D56D11588C8D
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3
Contact: sip:Genesys@192.168.1.3:5070
Content-Length: 0
Record-Route: <sip:0000000015B36FF0@192.168.1.3:5090;lr;gvp.rm.datanodes=1;idtag=00000001>
User-Agent: X-Lite release 4.9.6 stamp 82158
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=1BA3C618-84FF-4101-3AA0-27BCC1B95198;1;0;;;;Environment;TestIVRProfile;;0;default_IVRProfile
X-Genesys-GVP-Session-ID: 1BA3C618-84FF-4101-3AA0-27BCC1B95198;gvp.rm.datanodes=1;gvp.rm.tenant-id=1_TestIVRProfile
08:02:57.798: Sending [0,UDP] 436 bytes to 192.168.1.3:5090 >>>>>
ACK sip:2200@192.168.1.3:5090 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-1
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-5
To: <sip:2200@192.168.1.3:5060>;tag=AF97B611-38BF-43CD-DE87-D56D11588C8D
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3
CSeq: 1 ACK
Max-Forwards: 69
User-Agent: X-Lite release 4.9.6 stamp 82158
Content-Length: 0
08:02:57.800: SipDialog: event CALLING_RESREJECT, t=7, s=9, r=5, m=0000000003149a58
08:02:57.800: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:57.800 SIPCONN(2200): HandleSipDialogEvent(CALLING_RESREJECT)
08:02:57.800 SIPCONN(2200): Trying Timer deleted...
08:02:57.800 SIPCONN(2200): ConvertResponse: 500
08:02:57.800 SIPCONN(2200): ConvertResponse: no conversion configured for 500
08:02:57.800 SIPCONN(2200): state e:4,p:0,s:6,c:8,rc:500,m:1
08:02:57.800 SIPCONN(9001): CheckUpdateTransferStatus: no original dialog
08:02:57.832 SIPPARTY(2200): 16777218 verify update of party-connection state N-F
08:02:57.832 SIPPARTY(2200): 16777218 update party-connection state N-F
08:02:57.845 +++ CIFace::Event +++
+++ Pre-event +++
Type EventDestinationBusy
Devices: <9001/9001> <2200/2200> <-/->
Calls: 16777217/00750295d10b1001/16777217.3148e70/c:1/r:0 0/none
Parties: D9001/9001.3149430-3148e70:1/l:1/r:0/Dialing,Active,Origination
D2200/2200.314a1d0-3148e70:1/l:2/r:0/Null,Destination
none
Cause: Blocked/35, Info: 0
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
--- Pre-event ---
+++ DestBusy +++
SetFailed: party 9001.3149430-3148e70:1, cause Blocked
@08:02:57.8460 [0] 8.1.102.53 distribute_event: message EventDestinationBusy
AttributeEventSequenceNumber 000000000000004d
AttributeTimeinuSecs 846000
AttributeTimeinSecs 1482912177 (08:02:57)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 0
AttributeOtherDNRole 2
AttributeOtherDN '2200'
AttributePartyUUID 'OSMESV2Q9P5V140BRJURTD6AEG000004'
AttributeThisDNRole 1
AttributeThisDN '9001'
AttributeANI '9001'
AttributeDNIS '2200'
AttributeCallUUID 'OTNRESGK2H1HT7GQ1GEICJ025G000001'
AttributeConnID 00750295d10b1001
AttributeCallID 16777217
AttributePropagatedCallType 1
AttributeCallType 1
AttributeCallState 5
08:02:57.846 Int 04544 Interaction message "EventDestinationBusy" generated
08:02:57.847 Trc 04542 EventDestinationBusy sent to [640] (00000005 ORS_1 192.168.1.2:49524)
08:02:57.848 Trc 04542 EventDestinationBusy sent to [500] (00000003 StatServer_1 192.168.1.2:49517)
--- DestBusy ---
+++ Post-event +++
Type EventDestinationBusy
Devices: <9001/9001> <2200/2200> <-/->
Calls: 16777217/00750295d10b1001/16777217.3148e70/c:1/r:0 0/none
Parties: D9001/9001.3149430-3148e70:1/l:1/r:0/Failed,Active,Origination
D2200/2200.314a1d0-3148e70:1/l:2/r:0/Blocked,Destination
none
Cause: Blocked/35, Info: 0
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
--- Post-event ---
08:02:57.849 --- CIFace::Event ---
08:02:57.929: check call postponed until end of operation
08:02:57.932 SIPCONN(2200): terminate dialog
08:02:57.932: SipDialog: set monitor 0000000000000000
08:02:57.932: SipDialog::Terminate(state=9,reason=0)
08:02:57.932 SIPCONN(2200): state e:4,p:6,s:6,c:8,rc:500,m:1
08:02:57.932 SIPCONN(9001): CheckUpdateTransferStatus: no original dialog
08:02:57.932 SIPPARTY(2200): 16777218 verify update of party-connection state F-F
08:02:57.933: SIPTR(5): failed
08:02:57.933: SIPTR(0): Step 4 - SipTransactionConnectNewParty(5) failed
08:02:57.933: SIPTR(0): failed
08:02:57.933: SIPCM: transaction SipScenario failed
08:02:57.933: PI: 00 S[IN]D[9001]C[*D[9001]]P[2200]
08:02:57.933: PI: 01 S[FN]D[2200]P[9001], busy scheduled
08:02:57.933: party cannot be recovered
08:02:57.934: CALLSTATE(a:2,d:0,i:0,e:0,r:0,o:0)
08:02:57.934: apply fast busy tone for device '2200'
08:02:57.943: Selected for Dn (geo-loc[]:partitionId[SipServerDefaultPartition]:cpdCapability[]): Service VOIP_DN (geo-loc[], priority[0], capacity 0 (0% of 0))
08:02:57.962: previous scenario is not cleaned up
08:02:57.962: SIPTR(0): Begin step 0 - SipTransactionConnectMediaService(6)
08:02:57.962 SIPCONN(VOIP_DN): set monitor 0000000003162390, 00000000019d9dc0
08:02:57.962 SIPCONN(2200): DetachMediaPeer
08:02:57.962 SIPCONN(9001): re-invite-called-initiated
08:02:57.962 SIPCONN(VOIP_DN): re-invite-null
08:02:57.962 SIPCONN(9001): GetOffer
08:02:57.962 SIPCONN(9001): GetOffer::ReturnOffer
08:02:57.962 SIPCONN(9001): NotifyOnOffer
08:02:57.962 SIPCONN(VOIP_DN): SendOffer
08:02:57.962 SIPCONN(VOIP_DN): re-invite-null
08:02:57.962 SIPCONN(VOIP_DN): SendOffer::Invite
08:02:57.962: SipDialog: set monitor 0000000003162448
08:02:57.962 SIPCONN(VOIP_DN): main dialog 0 created, flags 0x21f
Session value of the SDP is [3957275403]
Version values of the SDP is [1]
08:02:57.963 SIPCONN(VOIP_DN): Local contact: '<sip:9001@192.168.1.3:5060>'
08:02:57.963: SIPDLG[8]: register TRN[8]
08:02:57.963: SIPDLG[8]: TRN[8] flags set to 0x6
08:02:57.963: Sending [0,UDP] 1002 bytes to 192.168.1.3:5090 >>>>>
INVITE sip:msml@192.168.1.3:5090;tenant-dbid=1;media-service=media SIP/2.0
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-6
To: <sip:VOIP_DN@192.168.1.3:5060>
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3
CSeq: 1 INVITE
Content-Length: 327
Content-Type: application/sdp
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-2
Contact: <sip:9001@192.168.1.3:5060>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
Max-Forwards: 69
X-Genesys-CallUUID: OTNRESGK2H1HT7GQ1GEICJ025G000001
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: uui,timer
v=0
o=- 1482912192 1 IN IP4 192.168.1.52
s=X-Lite release 4.9.6 stamp 82158
c=IN IP4 192.168.1.52
t=0 0
m=audio 55540 RTP/AVP 9 8 120 0 84 101
a=sendrecv
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:84 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
08:02:57.963: SipDialog: event SEND_INVITE, t=8, s=2, r=6, m=0000000003162448
08:02:57.963: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:57.963 SIPCONN(VOIP_DN): HandleSipDialogEvent(SEND_INVITE) - filtered
08:02:57.963 SIPCONN(VOIP_DN): sdp state SDP_STATE_NULL, event SDP_EVENT_OFFER_SENT
08:02:57.963 SIPCONN(VOIP_DN): new sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_OFFER_SENT
08:02:57.963: SipDialog: event DESTROY, t=0, s=10, r=4, m=0000000000000000
08:02:57.963: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-1@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:57.963: DIALOG_TRANSPORT_ASSOCIATION[7] NOT FOUND
08:02:57.963: SipDialog[7]:<< Abort ALL <<
08:02:57.963: $-NET:SIP::0:165723
08:02:57.965: $+NET:SIP::0:0
08:02:57.965: SIPTR: Received [0,UDP] 318 bytes from 192.168.1.3:5090 <<<<<
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-2
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-6
To: <sip:VOIP_DN@192.168.1.3:5060>
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3
Content-Length: 0
08:02:57.965: SipDialog: event CALLING_RESPROV, t=8, s=2, r=5, m=0000000003162448
08:02:57.965: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:57.965 SIPCONN(VOIP_DN): HandleSipDialogEvent(CALLING_RESPROV)
08:02:57.965 SIPCONN(VOIP_DN): Capabilities 60013f
08:02:57.965 SIPCONN(VOIP_DN): reliable=0
08:02:57.965 SIPCONN(VOIP_DN): store remote content
08:02:57.965 SIPCONN(VOIP_DN): store remote content - trying ignored
08:02:57.965 SIPCONN(VOIP_DN): Trying Timer for 32000 mlsec started...
08:02:57.966 SIPCONN(VOIP_DN): Connect(2,1)
08:02:57.966: $-NET:SIP::0:514
08:02:58.210: $+NET:SIP::0:0
08:02:58.210: SIPTR: Received [0,UDP] 1090 bytes from 192.168.1.3:5090 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-2
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-6
To: <sip:VOIP_DN@192.168.1.3:5060>;tag=6675E895-D1AB-494B-BDAB-D74D0D3FFA82
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3
Contact: sip:Genesys@192.168.1.3:5070
Content-Length: 247
Content-Type: application/sdp
Record-Route: <sip:0000000015B36FF0@192.168.1.3:5090;lr;gvp.rm.datanodes=1;idtag=00000002>
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=547CEA1C-97FA-40AF-6B9B-FF1EB82D9F7F;1;0;;;;Environment;default_IVRProfile;;0;default_IVRProfile
X-Genesys-GVP-Session-ID: 547CEA1C-97FA-40AF-6B9B-FF1EB82D9F7F;gvp.rm.datanodes=1;gvp.rm.tenant-id=1_default_IVRProfile
Session-Expires: 1800;refresher=uac
Require: timer
v=0
o=- 268532156 1 IN IP4 192.168.1.3
s=phone-call
c=IN IP4 192.168.1.3
t=0 0
m=audio 20002 RTP/AVP 9 8 0 101
a=rtpmap:9 g722/8000
a=rtpmap:8 pcma/8000
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
08:02:58.210: SipDialog: event CALLING_RESOK, t=8, s=7, r=5, m=0000000003162448
08:02:58.210: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:58.210 SIPCONN(VOIP_DN): HandleSipDialogEvent(CALLING_RESOK)
08:02:58.210 SIPCONN(VOIP_DN): Trying Timer deleted...
08:02:58.210 SIPCONN(VOIP_DN): store remote content
08:02:58.210 SIPCONN(VOIP_DN): sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_SDP
08:02:58.210 SIPCONN(VOIP_DN): new sdp state SDP_STATE_NULL, event SDP_EVENT_SDP
08:02:58.210 SIPCONN(VOIP_DN): Connect(2,4)
08:02:58.210 SIPCONN(VOIP_DN): NotifyResponseOnAnswer
08:02:58.210 SIPCONN(9001): SendAnswer
Session value of the SDP is [268532156]
Version values of the SDP is [1]
08:02:58.210 SIPCONN(9001): SendAnswer::Response
08:02:58.210 SIPCONN(9001): SendResponse(200,6)
08:02:58.210: add party info '9001' state 1.
08:02:58.210: Sending [0,UDP] 1109 bytes to 192.168.1.52:54158 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.52:54158;branch=z9hG4bK-524287-1---4bd140261561f901;rport;received=192.168.1.52
To: <sip:2200@192.168.1.3:5060>;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-4
From: <sip:9001@192.168.1.3:5060>;tag=20e6c156
Call-ID: 82158MGUxODRiYjc4ODI2M2NkZjAwNjE2MTRkZWJhNmRjOWE
CSeq: 1 INVITE
Contact: <sip:192.168.1.3:5060>
X-Genesys-CallUUID: OTNRESGK2H1HT7GQ1GEICJ025G000001
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, MESSAGE, NOTIFY, OPTIONS
X-Genesys-GVP-Session-Data: callsession=547CEA1C-97FA-40AF-6B9B-FF1EB82D9F7F;1;0;;;;Environment;default_IVRProfile;;0;default_IVRProfile
X-Genesys-GVP-Session-ID: 547CEA1C-97FA-40AF-6B9B-FF1EB82D9F7F;gvp.rm.datanodes=1;gvp.rm.tenant-id=1_default_IVRProfile
Session-Expires: 1800;refresher=uas
Supported: uui,timer
Content-Type: application/sdp
Content-Length: 248
v=0
o=- 1482912179 1 IN IP4 192.168.1.3
s=phone-call
c=IN IP4 192.168.1.3
t=0 0
m=audio 20002 RTP/AVP 9 8 0 101
a=sendrecv
a=rtpmap:9 g722/8000
a=rtpmap:8 pcma/8000
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
08:02:58.210: SipDialog: event CALLED_RESOK, t=6, s=7, r=5, m=000000000313e188
08:02:58.210 SIPCONN(9001): HandleSipDialogEvent(CALLED_RESOK) - filtered
08:02:58.210 SIPCONN(9001): TRCLR(6)
08:02:58.210 SIPCONN(9001): sdp state SDP_STATE_OFFER_RECEIVED, event SDP_EVENT_ANSWER_SENT
08:02:58.210 SIPCONN(9001): new sdp state SDP_STATE_NULL, event SDP_EVENT_ANSWER_SENT
08:02:58.210 SIPCONN(VOIP_DN): state e:3,p:0,s:3,c:0,rc:0,m:1
08:02:58.210 SIPCONN(9001): CheckUpdateTransferStatus: no original dialog
08:02:58.210 SIPCONN(VOIP_DN): NotifyOnComplete
08:02:58.210: $-NET:SIP::0:651
08:02:58.647: $+NET:SIP::0:0
08:02:58.647: SIPTR: Received [0,UDP] 441 bytes from 192.168.1.52:54158 <<<<<
ACK sip:192.168.1.3:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.52:54158;branch=z9hG4bK-524287-1---dfaa7e00737d0952;rport
Max-Forwards: 70
Contact: <sip:9001@192.168.1.52:54158>
To: <sip:2200@192.168.1.3:5060>;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-4
From: <sip:9001@192.168.1.3:5060>;tag=20e6c156
Call-ID: 82158MGUxODRiYjc4ODI2M2NkZjAwNjE2MTRkZWJhNmRjOWE
CSeq: 1 ACK
User-Agent: X-Lite release 4.9.6 stamp 82158
Content-Length: 0
08:02:58.648: SipDialog: event CONNECTED_ACK, t=6, s=7, r=5, m=000000000313e188
08:02:58.648 SIPCONN(9001): HandleSipDialogEvent(CONNECTED_ACK)
08:02:58.648 SIPCONN(9001): new transaction
08:02:58.648 SIPCONN(9001): store remote content
08:02:58.648 SIPCONN(9001): skip SDP pocessing [0/SDP_STATE_NULL]
08:02:58.648 SIPCONN(9001): Connect(4,26)
08:02:58.648 SIPCONN(9001): TRCLR(0)
08:02:58.648 SIPCONN(9001): NotifyOnComplete
08:02:58.648 SIPCONN(9001): Connect complete, other device 'VOIP_DN',(000000000313e0d0,0000000003162390)
08:02:58.648 SIPCONN(VOIP_DN): Connect complete, other device '9001',(0000000003162390,000000000313e0d0)
08:02:58.648 SIPCONN(VOIP_DN): SendAck(

08:02:58.648: Sending [0,UDP] 606 bytes to 192.168.1.3:5090 >>>>>
ACK sip:Genesys@192.168.1.3:5070 SIP/2.0
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-6
To: <sip:VOIP_DN@192.168.1.3:5060>;tag=6675E895-D1AB-494B-BDAB-D74D0D3FFA82
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3
CSeq: 1 ACK
Content-Length: 0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-3
Route: <sip:0000000015B36FF0@192.168.1.3:5090;lr;gvp.rm.datanodes=1;idtag=00000002>
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, MESSAGE, NOTIFY, OPTIONS
Max-Forwards: 69
User-Agent: X-Lite release 4.9.6 stamp 82158
08:02:58.648: SipDialog: event CONNECTED_SEND_ACK, t=8, s=7, r=5, m=0000000003162448
08:02:58.648: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:58.648 SIPCONN(VOIP_DN): HandleSipDialogEvent(CONNECTED_SEND_ACK) - filtered
08:02:58.649: SIPTR(6): complete
08:02:58.649: SIPTR(0): Step 0 - SipTransactionConnectMediaService(6) complete
08:02:58.649: SIPTR(0): Begin step 1 - SipTransactionProcessMsml(7)
08:02:58.651 SIPCONN(VOIP_DN): CtiRequest(3)
08:02:58.651: SIPDLG[8]: register TRN[9]
08:02:58.651: Sending [0,UDP] 865 bytes to 192.168.1.3:5090 >>>>>
INFO sip:Genesys@192.168.1.3:5070 SIP/2.0
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-6
To: <sip:VOIP_DN@192.168.1.3:5060>;tag=6675E895-D1AB-494B-BDAB-D74D0D3FFA82
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3
CSeq: 2 INFO
Content-Length: 294
Content-Type: application/vnd.radisys.msml+xml
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-4
Contact: <sip:9001@192.168.1.3:5060>
Max-Forwards: 69
Route: <sip:0000000015B36FF0@192.168.1.3:5090;lr;gvp.rm.datanodes=1;idtag=00000002>
<?xml version="1.0" encoding="UTF-8"?>
<msml version="1.1">
<dialogstart target="conn:6675E895-D1AB-494B-BDAB-D74D0D3FFA82" name="music" type="application/moml+xml">
<play barge="false" iterate="forever" gvp:precheck="true">
<video uri="music/atb_5sec"/>
</play>
</dialogstart>
</msml>
08:02:58.657: SipDialog: event CONNECTED_SEND_REQUEST, t=9, s=7, r=6, m=0000000003162448
08:02:58.657: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:58.657 SIPCONN(VOIP_DN): HandleSipDialogEvent(CONNECTED_SEND_REQUEST) - filtered
08:02:58.657: $-NET:SIP::0:12935
08:02:58.879: $+NET:SIP::0:0
08:02:58.879: SIPTR: Received [0,UDP] 842 bytes from 192.168.1.3:5090 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-4
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-6
To: <sip:VOIP_DN@192.168.1.3:5060>;tag=6675E895-D1AB-494B-BDAB-D74D0D3FFA82
CSeq: 2 INFO
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3
Content-Length: 168
Content-Type: application/vnd.radisys.msml+xml
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=547CEA1C-97FA-40AF-6B9B-FF1EB82D9F7F;1;0;;;;Environment;default_IVRProfile;;0;default_IVRProfile
X-Genesys-GVP-Session-ID: 547CEA1C-97FA-40AF-6B9B-FF1EB82D9F7F;gvp.rm.datanodes=1;gvp.rm.tenant-id=1_default_IVRProfile
<?xml version="1.0" encoding="UTF-8"?>
<msml version="1.1">
<result response="200"/>
<dialogid>conn:6675E895-D1AB-494B-BDAB-D74D0D3FFA82/dialog:music</dialogid>
</msml>
08:02:58.879: SipDialog: event CONNECTED_RESOK, t=9, s=7, r=5, m=0000000003162448
08:02:58.879: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:02:58.879 SIPCONN(VOIP_DN): HandleSipDialogEvent(CONNECTED_RESOK)
08:02:58.879 SIPCONN(VOIP_DN): store remote content
08:02:58.879 SIPCONN(VOIP_DN): CtiRequest(3,33)
08:02:58.880 SIPCONN(VOIP_DN): CtiRequestProcessInfoResponse
08:02:58.905 SIPCONN(VOIP_DN): INFO transaction cleared
08:02:58.935: SIPTR(7): complete
08:02:58.935: SIPTR(0): Step 1 - SipTransactionProcessMsml(7) complete
08:02:58.935: SIPTR(0): Begin step 2 - SipTransactionTimeout(

08:02:58.935: $-NET:SIP::0:56496
08:03:03.935: SIPTR(

: complete
08:03:03.935: SIPTR(0): Step 2 - SipTransactionTimeout(

complete
08:03:03.935: SIPTR(0): Begin step 3 - SipTransactionRejectCall(9)
08:03:03.935 SIPCONN(9001): re-invite-connected
08:03:03.935 SIPCONN(9001): terminate dialog
08:03:03.935: SipDialog: set monitor 0000000000000000
08:03:03.935: SipDialog::Terminate(state=7,reason=500)
08:03:03.935: SIPDLG[6]: register TRN[10]
08:03:03.935: Sending [0,UDP] 416 bytes to 192.168.1.52:54158 >>>>>
BYE sip:9001@192.168.1.52:54158 SIP/2.0
From: <sip:2200@192.168.1.3:5060>;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-4
To: <sip:9001@192.168.1.3:5060>;tag=20e6c156
Call-ID: 82158MGUxODRiYjc4ODI2M2NkZjAwNjE2MTRkZWJhNmRjOWE
CSeq: 1 BYE
Content-Length: 0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-5
Reason: SIP;cause=500;text="Server Internal Error"
Max-Forwards: 70
08:03:03.935: SipDialog: event SEND_BYE, t=10, s=8, r=6, m=0000000000000000
08:03:03.935: SIPTR(9): complete
08:03:03.935: SIPTR(0): Step 3 - SipTransactionRejectCall(9) complete
08:03:03.935: SIPTR(0): Begin step 4 - SipTransactionClearParty(10)
08:03:03.935: SD: none
08:03:03.950 SIPCONN(2200): CONNCHECK: dialog=0
08:03:03.950 SIPCONN(2200): CONNCHECK: dialog=0
08:03:03.956 SIPCONN(2200): set monitor 00000000031499a0, 0000000000000000
08:03:03.956 SIPCONN(2200): state e:1,p:6,s:0,c:0,rc:0,m:0
08:03:03.956 SIPCONN(2200): DetachMediaPeer
08:03:03.956 SIPCONN(VOIP_DN): set monitor 0000000003162390, 0000000000000000
08:03:03.956 SIPCONN(VOIP_DN): state e:1,p:3,s:0,c:0,rc:0,m:0
08:03:03.956 SIPCONN(9001): CheckUpdateTransferStatus: no original dialog
08:03:03.956: SipDialog: ClearCall(phone=0,state=7)
08:03:03.956: SipDialog::Terminate(state=7,reason=0)
08:03:03.956: SIPDLG[8]: register TRN[11]
08:03:03.956: Sending [0,UDP] 481 bytes to 192.168.1.3:5090 >>>>>
BYE sip:Genesys@192.168.1.3:5070 SIP/2.0
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-6
To: <sip:VOIP_DN@192.168.1.3:5060>;tag=6675E895-D1AB-494B-BDAB-D74D0D3FFA82
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3
CSeq: 3 BYE
Content-Length: 0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-6
Max-Forwards: 69
Route: <sip:0000000015B36FF0@192.168.1.3:5090;lr;gvp.rm.datanodes=1;idtag=00000002>
08:03:03.956: SipDialog: event SEND_BYE, t=11, s=8, r=5, m=0000000003162448
08:03:03.956: CID:CUUID>2D4D7F5F-0615-4B50-8241-59CD1D53834C-2@192.168.1.3:OTNRESGK2H1HT7GQ1GEICJ025G000001:
08:03:03.956 SIPCONN(VOIP_DN): HandleSipDialogEvent(SEND_BYE) - filtered
08:03:03.956: SipDialog: set monitor 0000000000000000
08:03:03.956 SIPCONN(VOIP_DN): DetachMediaPeer
08:03:03.956 SIPCONN(VOIP_DN): ClrMediaPeer
08:03:03.956 SIPCONN(VOIP_DN): media peer '9001' refers to cleared connection
08:03:03.956 -- setting cntrlDN=2200 (for 1 parties)
08:03:03.956 -- setting cntrlDN=2200 (for 1 parties)
08:03:03.961 ClearContext: party 2200.314a1d0-3148e70:1
08:03:03.961 +++ CIFace::Event +++
+++ Pre-event +++
Type EventReleased
Devices: <2200/2200> <9001/9001> <-/->
Calls: 16777217/00750295d10b1001/16777217.3148e70/c:1/r:0 0/none
Parties: D2200/2200.314a1d0-3148e70:1/l:2/r:0/Blocked,Destination
D9001/9001.3149430-3148e70:1/l:1/r:0/Failed,Active,Origination
none
Cause: Busy/3, Info: 0
Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
--- Pre-event ---
+++ Released +++
-- XAction: start 2200.314a1d0-3148e70:1
SetReleased: party 2200.314a1d0-3148e70:1, cause Busy
-- TellReleased
-- XAction: commit 2200.314a1d0-3148e70:1
08:03:03.961: device on hook
--- Released ---
+++ Post-event +++
Type EventReleased
Devices: <2200/2200> <9001/9001> <-/->
Calls: 16777217/00750295d10b1001/16777217.3148e70/c:1/r:0 0/none
Parties: D2200/2200.314a1d0-3148e70:1/l:2/r:0/Released,Destination
D9001/9001.3149430-3148e70:1/l:1/r:0/Failed,Active,Origination
none
Cause: Busy/3, Info: 0
Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=1
--- Post-event ---
08:03:03.961 --- CIFace::Event ---
08:03:03.961 -- deleted aTmParty 314a1d0
08:03:03.961 -- RemoveParty 2200.314a1d0-3148e70:1
08:03:03.961 -- call leg removed leg_id=2
08:03:03.961 -- deleted party_info_tspp 244eb70
08:03:03.968: check call postponed until end of operation
08:03:03.968: SIPTR(10): complete
08:03:03.968: SIPTR(0): Step 4 - SipTransactionClearParty(10) complete
08:03:03.968: SIPTR(0): Begin step 5 - SipTransactionClearParty(11)
08:03:03.968: SD: none
08:03:03.968 SIPCONN(9001): CONNCHECK: dialog=0
08:03:03.968 SIPCONN(9001): CONNCHECK: dialog=0
08:03:03.968 SIPCONN(9001): set monitor 000000000313e0d0, 0000000000000000
08:03:03.968 SIPCONN(9001): state e:1,p:1,s:0,c:0,rc:0,m:0
08:03:03.968 SIPCONN(VOIP_DN): CheckUpdateTransferStatus: no original dialog
08:03:03.968 SIPCONN(9001): DetachMediaPeer
08:03:03.968 ClearContext: party 9001.3149430-3148e70:1
08:03:03.968 +++ CIFace::Event +++
+++ Pre-event +++
Type EventReleased
RM logs:
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 08500000 VGSIPProxyCore.cxx:282 VGSIPProxyCore::NotifyTransactionRequest processing method 1
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 CCPSIPProxyCoreProcessor.cxx:156 Request-URI parsed: 2200 192.168.1.3 5090
2016-12-28T14:25:41.946 Trc 20119 INFO 00000000-00000000 2836 09400506 New call : sip:2200@192.168.1.3:5090
2016-12-28T14:25:41.946 Trc 20002 NOTE 00000000-00000000 2836 0940040C New call: Session-ID: | Call-ID:2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3 | Request-URI:sip:2200@192.168.1.3:5090
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 GVPRMLocationService.cxx:78 Cannot locate logical resource matching Physical AOR sip:192.168.1.3:5060
2016-12-28T14:25:41.946 Trc 20002 NOTE 00000000-00000000 2836 0940040D CallSessionImpl(768EE136-445C-42D6-63B7-06AF797670E5) CSPtr<000000001EB2EF90> DMPtr<000000000976CBB0>
2016-12-28T14:25:41.946 Trc 20002 NOTE 00000000-00000000 2836 0940040C New call session: Session-ID:768EE136-445C-42D6-63B7-06AF797670E5 | Call-ID:2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 CCPSIPProxyCoreProcessor.cxx:1697 Cluster Information: primary=1 secondary=0
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 PolicyModule.cxx:208 768EE136-445C-42D6-63B7-06AF797670E5 New Call Session Request-URI : sip:2200@192.168.1.3:5090
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 PolicyModule.cxx:274 768EE136-445C-42D6-63B7-06AF797670E5 Handling call using call tenant Environment
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 PolicyModule.cxx:1671 getGVPTenantId returned GVPTenantID: IncomingApp:
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 PolicyModule.cxx:954 Incoming App from header is: bIsDbid: 0
2016-12-28 14:25:41.946 DBUG 00000000-00000000 2836 09400901 PolicyModule.cxx:1036 Calling getApplicationForDNIS tenant: Environment DNIS: 2200
2016-12-28T14:25:41.947 Trc 20002 NOTE 00000000-00000000 2836 0940040D Application corresponding to DNIS(2200) found in current tenant object Environment App: TestIVRProfile
2016-12-28T14:25:41.947 Trc 20002 NOTE 00000000-00000000 2836 0940040D IVR Profile <TestIVRProfile> is selected for the Tenant <Environment> for the call <2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3>
2016-12-28 14:25:41.947 DBUG 00000000-00000000 2836 09400901 PolicyDataStore.cxx:507 Returning Default Application default_IVRProfile
2016-12-28T14:25:41.947 Trc 20002 NOTE 00000000-00000000 2836 0940040D Recording IVR profile <default_IVRProfile> is selected for the Tenant <Environment> for the call <2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3>
2016-12-28 14:25:41.947 DBUG 00000000-00000000 2836 09400901 PolicyDataStoreObjects.cxx:1416 Alternate VoiceXML URL is not set for Application [TestIVRProfile]
2016-12-28 14:25:41.947 DBUG 00000000-00000000 2836 09400901 PolicyDataStoreObjects.cxx:1421 VoiceXML Script URL is not set for Application [TestIVRProfile]
2016-12-28T14:25:41.948 Trc 20002 NOTE 00000000-00000000 2836 0940040D CallImpl CSPtr<000000001EB2EF90> CPtr<0000000011BF0C80> DMPtr<000000000976CBB0>
2016-12-28T14:25:41.948 Trc 20002 NOTE 00000000-00000000 2836 0940040C Session-ID:768EE136-445C-42D6-63B7-06AF797670E5 | Call-ID:2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3 | SERVICE TYPE: voicexml
2016-12-28 14:25:41.948 DBUG 00000000-00000000 2836 09400901 GVPRMLocationService.cxx:104 Calling RequestResource: voicexml UseSameGateway: always TenantDBID: 1
2016-12-28 14:25:41.948 DBUG 00000000-00000000 2836 09400901 CCPSIPProxyCoreProcessor.cxx:1521 added header X-Genesys-GVP-Session-Data:callsession=768EE136-445C-42D6-63B7-06AF797670E5;1;0;;;;Environment;TestIVRProfile;;0;default_IVRProfile
2016-12-28T14:25:41.948 Trc 20002 NOTE 00000000-00000000 2836 0940040C Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3 | 1 target sets selected with 0 SIP response
2016-12-28 14:25:41.948 DBUG 00000000-00000000 2836 08500000 VGSIPProxyCore.cxx:1543 VGSIPProxyCore::StartForking - Added first target [sip:dialog@192.168.1.3:5070;gvp-tenant-id=[Environment].TestIVRProfile;gvp.appmodule=VXML-NG;record-ivr-profile=[Environment].default_IVRProfile;trunkport=2200;voicexml=http://192.168.1.3:8088/testivr/src-gen/Main.vxml] to pProxyTransactionSet[000000001EB4EE80]
2016-12-28 14:25:41.948 DBUG 00000000-00000000 2836 09400901 CCPSIPProxyCore.cxx:974 Proxy received response code: 0. GetRetryOption
2016-12-28 14:25:41.948 DBUG 00000000-00000000 2836 08500000 VGSIPTransportMgr.cxx:3637 VGSIPTransportMgr::ResolveDNS for 192.168.1.3:5070
2016-12-28 14:25:41.948 DBUG 00000000-00000000 2836 08500000 VGSIPTransportMgr.cxx:3752 VGSIPTransportMgr::ResolveDNS nResult 2; CallID<2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3>
2016-12-28 14:25:41.949 DBUG 00000000-00000000 2836 08500000 VGSIPTransportTCP.cxx:62 VGSIPTransportTCP::TCPConnection::Initialize SIPTransportTCP_Port<5090> TPtr<00000000077CDA90> CPtr<000000001DDE7BC0> CAddr<192.168.1.3:5070>
2016-12-28 14:25:41.950 DBUG 00000000-00000000 2836 08500000 VGSIPTransportMgr.cxx:3637 VGSIPTransportMgr::ResolveDNS for 192.168.1.3:5060
2016-12-28 14:25:41.950 DBUG 00000000-00000000 2836 08500000 VGSIPTransportMgr.cxx:3752 VGSIPTransportMgr::ResolveDNS nResult 2; CallID<2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3>
2016-12-28 14:25:41.950 DBUG 00000000-00000000 2836 09400901 CCPSIPMessageInterceptor.h:579 RM - SIP Message sent to [192.168.1.3:5060] (317): SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-16
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-15
To: <sip:2200@192.168.1.3:5060>
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3
Content-Length: 0
2016-12-28 14:25:41.995 DBUG 00000000-00000000 2624 09400901 CCPSIPMessageInterceptor.h:579 RM - SIP Message sent to [192.168.1.3:5070] (1759): INVITE sip:dialog@192.168.1.3:5070;gvp-tenant-id=[Environment].TestIVRProfile;gvp.appmodule=VXML-NG;record-ivr-profile=[Environment].default_IVRProfile;trunkport=2200;voicexml=http://192.168.1.3:8088/testivr/src-gen/Main.vxml SIP/2.0
Via: SIP/2.0/TCP 192.168.1.3:5090;branch=z9hG4bK000000001E985C2063cb65abcdef09
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-16
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-15
To: <sip:2200@192.168.1.3:5060>
Max-Forwards: 68
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3
Contact: <sip:9001@192.168.1.3:5060>
Content-Length: 327
Content-Type: application/sdp
Record-Route: <sip:0000000015B36FF0@192.168.1.3:5090;transport=TCP;lr;gvp.rm.datanodes=1;idtag=00000006>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
User-Agent: X-Lite release 4.9.6 stamp 82158
X-Genesys-CallUUID: OTNRESGK2H1HT7GQ1GEICJ025G000004
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: uui,100rel,timer
X-Genesys-GVP-Session-ID: 768EE136-445C-42D6-63B7-06AF797670E5;gvp.rm.datanodes=1;gvp.rm.tenant-id=1_TestIVRProfile
X-Genesys-GVP-CS-Username: default
X-Genesys-GVP-CS-Password: password
X-Genesys-RM-Application-dbid: 103
X-Genesys-GVP-Session-Data: callsession=768EE136-445C-42D6-63B7-06AF797670E5;1;0;;;;Environment;TestIVRProfile;;0;default_IVRProfile
v=0
o=- 1482912251 1 IN IP4 192.168.1.53
s=X-Lite release 4.9.6 stamp 82158
c=IN IP4 192.168.1.53
t=0 0
m=audio 63458 RTP/AVP 9 8 120 0 84 101
a=sendrecv
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:84 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2016-12-28 14:25:41.996 DBUG 00000000-00000000 2836 09400901 CCPSIPMessageInterceptor.h:579 RM - SIP Message received from [192.168.1.3:5070] (397): SIP/2.0 100 Trying
Via: SIP/2.0/TCP 192.168.1.3:5090;branch=z9hG4bK000000001E985C2063cb65abcdef09
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-16
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-15
To: <sip:2200@192.168.1.3:5060>
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3
Content-Length: 0
2016-12-28 14:25:42.008 DBUG 00000000-00000000 2836 09400901 CCPSIPMessageInterceptor.h:579 RM - SIP Message received from [192.168.1.3:5070] (905): SIP/2.0 503 Service Unavailable
Via: SIP/2.0/TCP 192.168.1.3:5090;branch=z9hG4bK000000001E985C2063cb65abcdef09
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ED03076-1788-45F1-8FAD-4D0C45FA92E9-16
From: sip:9001@192.168.1.3:5060;tag=84F6DEC0-281E-4D6E-A949-F898B252DD59-15
To: <sip:2200@192.168.1.3:5060>;tag=6D5DB928-41CF-4C62-E09E-87F498756BB3
CSeq: 1 INVITE
Call-ID: 2D4D7F5F-0615-4B50-8241-59CD1D53834C-6@192.168.1.3
Contact: sip:Genesys@192.168.1.3:5070
Content-Length: 0
Record-Route: <sip:0000000015B36FF0@192.168.1.3:5090;transport=TCP;lr;gvp.rm.datanodes=1;idtag=00000006>
User-Agent: X-Lite release 4.9.6 stamp 82158
Min-SE: 90
X-Genesys-GVP-Session-Data: callsession=768EE136-445C-42D6-63B7-06AF797670E5;1;0;;;;Environment;TestIVRProfile;;0;default_IVRProfile
X-Genesys-GVP-Session-ID: 768EE136-445C-42D6-63B7-06AF797670E5;gvp.rm.datanodes=1;gvp.rm.tenant-id=1_TestIVRProfile
=============
Any suggestions what have been missing. I have followed the GVP and SIP guide to configure the same.