[Spce-user] Calls hanging up after exactly 32 seconds. - LB says "Internal Server Error"

Joel S. | VOZELIA joel at vozelia.com
Wed Oct 1 14:27:07 EDT 2014


Hi, 

I have a strange situation where calls get hangup after exactly 32 seconds.

This happens when leg-A uses SIP+TLS(tcp) and leg-B uses SIP(tcp), with UDP alone calls work OK.

One problem for sure is "transport=tcp" instead of "transport=tls", but I don't understand why the call works for 30 seconds then it hangs up even if it was setup in TCP instead of TLS.

If I am not mistaken, the ID of the call is D5ABC3E799342103056A864EC2CF153D4E533CE6, then, I have done a grep with that on both kamailio logs and rtp.log


The only thing I can see in the kamailio-proxy.log is a "487 - Request terminated", then, 30s after a "BYE" and in the kamailio-lb.log an "Internal Server Error" is logged. In rtp.log there is an event that says that the call will be delete after 30seconds, but I do not know the reason.


User: vozelia1 is a subscriber connected to SPCE using SIP+TLS (tcp)
User: vozelia2 is a subscriber connected to SPCE using SIP (tcp)

sip.mydomain.com = Domain configured in SPCE
VVV.VVV.VVV.VVV = Private IP of leg-A
WWW.WWW.WWW.WWW = Private IP of leg-B
XXX.XXX.XXX.XXX = Public IP of leg-A
YYY.YYY.YYY.YYY = Public IP of leg-B
ZZZ.ZZZ.ZZZ.ZZZ = Public IP of SPCE



rtp.log:

Oct  1 18:10:32 sip rtpengine[4289]: Got valid command from 127.0.0.1:50798: offer - { "sdp": "v=0#015#012o=- 5779291809 30182 IN IP4 172.138.170.170#015#012s=onngvzk#015#012c=IN IP4 XXX.XXX.XXX.XXX#015#012t=0 0#015#012m=audio 51036 RTP/AVP 102 105 106 3 0 8 9 18 101#015#012a=rtpmap:101 telephone-event/8000#015#012a=rtpmap:102 ILBC/8000#015#012a=rtpmap:105 OPUS/48000/2#015#012a=rtpmap:106 OPUS/48000/2#015#012a=fmtp:101 0-15#015#012a=fmtp:102 mode=30#015#012a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1#015#012a=fmtp:106 maxplaybackrate=16000;useinbandfec=1;usedtx=1#015#012a=fmtp:18 annexb=no#015#012a=ptime:30#015#012a=zrtp-hash:1.10 957766D1826F345C9C42F9D56B10EB83DA2EB6AA4410AE441F5BDFCB4737168E#015#012a=sendrecv#015#012a=direction:active#015#012a=oldmediaip:VVV.VVV.VVV.VVV#015#012", "address-family": "IP4", "ICE": "remove", "flags": [ "trust-address" ], "replace": [ "origin", "session-connection" ], "rtcp-mux": [ "demux" ], "call-id": "D5ABC3E799342103056A864EC2CF153D4E533CE6", "via-branch": "z9hG4bKcd7c.77928236080aa828571c2ae83b42933e.0", "received-from": [ "IP4", "127.0.0.1" ], "from-tag": "7BB940C81E4C1038271A6A1399F81737", "command": "offer" }
Oct  1 18:10:32 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Creating new call
Oct  1 18:10:32 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Returning to SIP proxy: d3:sdp595:v=0#015#012o=- 5779291809 30182 IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012s=onngvzk#015#012c=IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012t=0 0#015#012m=audio 30008 RTP/AVP 102 105 106 3 0 8 9 18 101#015#012a=rtpmap:101 telephone-event/8000#015#012a=rtpmap:102 ILBC/8000#015#012a=rtpmap:105 OPUS/48000/2#015#012a=rtpmap:106 OPUS/48000/2#015#012a=fmtp:101 0-15#015#012a=fmtp:102 mode=30#015#012a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1#015#012a=fmtp:106 maxplaybackrate=16000;useinbandfec=1;usedtx=1#015#012a=fmtp:18 annexb=no#015#012a=ptime:30#015#012a=zrtp-hash:1.10 957766D1826F345C9C42F9D56B10EB83DA2EB6AA4410AE441F5BDFCB4737168E#015#012a=direction:active#015#012a=oldmediaip:VVV.VVV.VVV.VVV#015#012a=sendrecv#015#012a=rtcp:30009#015#0126:result2:oke
Oct  1 18:10:32 sip rtpengine[4289]: Got valid command from 127.0.0.1:50798: offer - { "sdp": "v=0#015#012o=- 5779291809 30182 IN IP4 172.138.170.170#015#012s=onngvzk#015#012c=IN IP4 XXX.XXX.XXX.XXX#015#012t=0 0#015#012m=audio 51036 RTP/AVP 102 105 106 3 0 8 9 18 101#015#012a=rtpmap:101 telephone-event/8000#015#012a=rtpmap:102 ILBC/8000#015#012a=rtpmap:105 OPUS/48000/2#015#012a=rtpmap:106 OPUS/48000/2#015#012a=fmtp:101 0-15#015#012a=fmtp:102 mode=30#015#012a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1#015#012a=fmtp:106 maxplaybackrate=16000;useinbandfec=1;usedtx=1#015#012a=fmtp:18 annexb=no#015#012a=ptime:30#015#012a=zrtp-hash:1.10 957766D1826F345C9C42F9D56B10EB83DA2EB6AA4410AE441F5BDFCB4737168E#015#012a=sendrecv#015#012a=direction:active#015#012a=oldmediaip:VVV.VVV.VVV.VVV#015#012", "address-family": "IP4", "ICE": "remove", "flags": [ "trust-address" ], "replace": [ "origin", "session-connection" ], "rtcp-mux": [ "demux" ], "call-id": "D5ABC3E799342103056A864EC2CF153D4E533CE6", "via-branch": "z9hG4bKcd7c.77928236080aa828571c2ae83b42933e.0", "received-from": [ "IP4", "127.0.0.1" ], "from-tag": "7BB940C81E4C1038271A6A1399F81737", "command": "offer" }
Oct  1 18:10:32 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Returning to SIP proxy: d3:sdp595:v=0#015#012o=- 5779291809 30182 IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012s=onngvzk#015#012c=IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012t=0 0#015#012m=audio 30008 RTP/AVP 102 105 106 3 0 8 9 18 101#015#012a=rtpmap:101 telephone-event/8000#015#012a=rtpmap:102 ILBC/8000#015#012a=rtpmap:105 OPUS/48000/2#015#012a=rtpmap:106 OPUS/48000/2#015#012a=fmtp:101 0-15#015#012a=fmtp:102 mode=30#015#012a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1#015#012a=fmtp:106 maxplaybackrate=16000;useinbandfec=1;usedtx=1#015#012a=fmtp:18 annexb=no#015#012a=ptime:30#015#012a=zrtp-hash:1.10 957766D1826F345C9C42F9D56B10EB83DA2EB6AA4410AE441F5BDFCB4737168E#015#012a=direction:active#015#012a=oldmediaip:VVV.VVV.VVV.VVV#015#012a=sendrecv#015#012a=rtcp:30009#015#0126:result2:oke
Oct  1 18:10:45 sip rtpengine[4289]: Got valid command from 127.0.0.1:60333: answer - { "sdp": "v=0#015#012o=segtec-HP 3621168632 1 IN IP4 WWW.WWW.WWW.WWW#015#012s=sflphone#015#012c=IN IP4 YYY.YYY.YYY.YYY#015#012t=0 0#015#012m=audio 24158 RTP/AVP 3 101#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=sendrecv#015#012a=rtcp:24159 IN IP4 WWW.WWW.WWW.WWW#015#012a=oldmediaip:WWW.WWW.WWW.WWW#015#012a=direction:active#015#012", "ICE": "remove", "flags": [ "trust-address" ], "replace": [ "origin", "session-connection" ], "rtcp-mux": [ "demux" ], "call-id": "D5ABC3E799342103056A864EC2CF153D4E533CE6", "via-branch": "z9hG4bKcd7c.77928236080aa828571c2ae83b42933e.0", "received-from": [ "IP4", "127.0.0.1" ], "from-tag": "7BB940C81E4C1038271A6A1399F81737", "to-tag": "690F21E7-542C277800041CF7-DC171700", "command": "answer" }
Oct  1 18:10:45 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Returning to SIP proxy: d3:sdp273:v=0#015#012o=segtec-HP 3621168632 1 IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012s=sflphone#015#012c=IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012t=0 0#015#012m=audio 30010 RTP/AVP 3 101#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=oldmediaip:WWW.WWW.WWW.WWW#015#012a=direction:active#015#012a=sendrecv#015#012a=rtcp:30011#015#0126:result2:oke
Oct  1 18:10:45 sip rtpengine[4289]: Got valid command from 127.0.0.1:50135: answer - { "sdp": "v=0#015#012o=segtec-HP 3621168632 1 IN IP4 WWW.WWW.WWW.WWW#015#012s=sflphone#015#012c=IN IP4 YYY.YYY.YYY.YYY#015#012t=0 0#015#012m=audio 24158 RTP/AVP 3 101#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=sendrecv#015#012a=rtcp:24159 IN IP4 WWW.WWW.WWW.WWW#015#012a=oldmediaip:WWW.WWW.WWW.WWW#015#012a=direction:active#015#012", "ICE": "remove", "flags": [ "trust-address" ], "replace": [ "origin", "session-connection" ], "rtcp-mux": [ "demux" ], "call-id": "D5ABC3E799342103056A864EC2CF153D4E533CE6", "via-branch": "z9hG4bKcd7c.77928236080aa828571c2ae83b42933e.0", "received-from": [ "IP4", "127.0.0.1" ], "from-tag": "7BB940C81E4C1038271A6A1399F81737", "to-tag": "690F21E7-542C277800041CF7-DC171700", "command": "answer" }
Oct  1 18:10:45 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Returning to SIP proxy: d3:sdp273:v=0#015#012o=segtec-HP 3621168632 1 IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012s=sflphone#015#012c=IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012t=0 0#015#012m=audio 30010 RTP/AVP 3 101#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=oldmediaip:WWW.WWW.WWW.WWW#015#012a=direction:active#015#012a=sendrecv#015#012a=rtcp:30011#015#0126:result2:oke
Oct  1 18:10:46 sip rtpengine[4289]: Got valid command from 127.0.0.1:33528: answer - { "sdp": "v=0#015#012o=segtec-HP 3621168632 1 IN IP4 WWW.WWW.WWW.WWW#015#012s=sflphone#015#012c=IN IP4 YYY.YYY.YYY.YYY#015#012t=0 0#015#012m=audio 24158 RTP/AVP 3 101#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=sendrecv#015#012a=rtcp:24159 IN IP4 WWW.WWW.WWW.WWW#015#012a=oldmediaip:WWW.WWW.WWW.WWW#015#012a=direction:active#015#012", "ICE": "remove", "flags": [ "trust-address" ], "replace": [ "origin", "session-connection" ], "rtcp-mux": [ "demux" ], "call-id": "D5ABC3E799342103056A864EC2CF153D4E533CE6", "via-branch": "z9hG4bKcd7c.77928236080aa828571c2ae83b42933e.0", "received-from": [ "IP4", "127.0.0.1" ], "from-tag": "7BB940C81E4C1038271A6A1399F81737", "to-tag": "690F21E7-542C277800041CF7-DC171700", "command": "answer" }
Oct  1 18:10:46 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Returning to SIP proxy: d3:sdp273:v=0#015#012o=segtec-HP 3621168632 1 IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012s=sflphone#015#012c=IN IP4 ZZZ.ZZZ.ZZZ.ZZZ#015#012t=0 0#015#012m=audio 30010 RTP/AVP 3 101#015#012a=rtpmap:3 GSM/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=oldmediaip:WWW.WWW.WWW.WWW#015#012a=direction:active#015#012a=sendrecv#015#012a=rtcp:30011#015#0126:result2:oke
Oct  1 18:10:50 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30008] Confirmed peer address as YYY.YYY.YYY.YYY:10602
Oct  1 18:10:50 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30010] Confirmed peer address as XXX.XXX.XXX.XXX:1998
Oct  1 18:10:50 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30010] No support for kernel packet forwarding available
Oct  1 18:10:50 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30008] No support for kernel packet forwarding available
Oct  1 18:10:51 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30011] Confirmed peer address as XXX.XXX.XXX.XXX:1222
Oct  1 18:10:53 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30009] Confirmed peer address as YYY.YYY.YYY.YYY:10603
Oct  1 18:10:53 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30009] No support for kernel packet forwarding available
Oct  1 18:10:54 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6 port 30011] No support for kernel packet forwarding available
Oct  1 18:11:17 sip rtpengine[4289]: Got valid command from 127.0.0.1:60333: delete - { "call-id": "D5ABC3E799342103056A864EC2CF153D4E533CE6", "received-from": [ "IP4", "127.0.0.1" ], "from-tag": "690F21E7-542C277800041CF7-DC171700", "to-tag": "7BB940C81E4C1038271A6A1399F81737", "command": "delete" }
Oct  1 18:11:17 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Scheduling deletion of call branch '7BB940C81E4C1038271A6A1399F81737' in 30 seconds
Oct  1 18:11:17 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Returning to SIP proxy: d7:createdi1412179832e11:last signali1412179846e4:tagsd32:7BB940C81E4C1038271A6A1399F81737d3:tag32:7BB940C81E4C1038271A6A1399F817377:createdi1412179832e16:in dialogue with34:690F21E7-542C277800041CF7-DC1717006:mediasld5:indexi1e4:type5:audio8:protocol7:RTP/AVP7:streamsld10:local porti30008e8:endpointd6:family4:IPv47:address12:YYY.YYY.YYY.YYY4:porti10602ee19:advertised endpointd6:family4:IPv47:address12:YYY.YYY.YYY.YYY4:porti24158ee11:last packeti1412179877e5:flagsl3:RTP6:filled9:confirmed10:kernelized17:no kernel supporte5:statsd7:packetsi1598e5:bytesi71910e6:errorsi0eeed10:local porti30009e8:endpointd6:family4:IPv47:address12:YYY.YYY.YYY.YYY4:porti10603ee19:advertised endpointd6:family4:IPv47:address15:WWW.WWW.WWW.WWW4:porti24159ee11:last packeti1412179873e5:flagsl4:RTCP6:filled9:confirmed10:kernelized17:no kernel supporte5:statsd7:packetsi5e5:bytesi300e6:errorsi0eeee5:flagsl11:initializedeeee34:690F21E7-542C277800041CF7-DC171700d3:tag34:690F21E7-542C277800041CF7-DC1717007:createdi1412179832e16:in dialogue with32:7BB940C81E4C1038271A6A1399F817376:mediasld5:indexi1e4:type5:audio8:protocol7:RTP/AVP7:streamsld10:local porti30010e8:endpointd6:family4:IPv47:address12:XXX.XXX.XXX.XXX4:porti1998ee19:advertised endpointd6:family4:IPv47:address12:XXX.XXX.XXX.XXX4:porti51036ee11:last packeti1412179877e5:flagsl3:RTP6:filled9:confirmed10:kernelized17:no kernel supporte5:statsd7:packetsi1619e5:bytesi74718e6:errorsi0eeed10:local porti30011e8:endpointd6:family4:IPv47:address12:XXX.XXX.XXX.XXX4:porti1222ee19:advertised endpointd6:family4:IPv47:address12:XXX.XXX.XXX.XXX4:porti51037ee11:last packeti1412179873e5:flagsl4:RTCP6:filled9:confirmed10:kernelized17:no kernel supporte5:statsd7:packetsi8e5:bytesi596e6:errorsi0eeee5:flagsl11:initializedeeeee6:totalsd3:RTPd7:packetsi3217e5:bytesi146628e6:errorsi0ee4:RTCPd7:packetsi13e5:bytesi896e6:errorsi0eee6:result2:oke
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Call branch '7BB940C81E4C1038271A6A1399F81737' deleted, no more branches remaining
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] Final packet stats:
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] --- Tag '690F21E7-542C277800041CF7-DC171700', created 1:15 ago, in dialogue with '7BB940C81E4C1038271A6A1399F81737'
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] ------ Media #1, port 30010 <>    XXX.XXX.XXX.XXX:1998 , 3113 p, 141935 b, 0 e
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] ------ Media #1, port 30011 <>    XXX.XXX.XXX.XXX:1222  (RTCP), 13 p, 996 b, 0 e
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] --- Tag '7BB940C81E4C1038271A6A1399F81737', created 1:15 ago, in dialogue with '690F21E7-542C277800041CF7-DC171700'
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] ------ Media #1, port 30008 <>    YYY.YYY.YYY.YYY:10602, 1600 p, 72000 b, 0 e
Oct  1 18:11:47 sip rtpengine[4289]: [D5ABC3E799342103056A864EC2CF153D4E533CE6] ------ Media #1, port 30009 <>    YYY.YYY.YYY.YYY:10603 (RTCP), 6 p, 344 b, 0 e


kamailio-proxy.log:

Oct  1 18:10:31 sip proxy[4324]: NOTICE: <script>: New request - M=INVITE R=sips:34991021 at sip.mydomain.com F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5060) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4324]: NOTICE: <script>: Authentication failed, no credentials - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: New request - M=INVITE R=sips:34991021 at sip.mydomain.com F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5060) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: User-Provided CLI 'vozelia1' taken from From-User - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: User-provided CLI 'vozelia1' rejected, using network-provided CLI 'vozelia1' - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting 'vozelia1 at sip.mydomain.com' as initiating user-provided CLI - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting 'vozelia1 at sip.mydomain.com' as initiating network-provided CLI - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Callee was aliased with base '34991021' - R=sips:vozelia2 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Callee is local, uuid='665792c5-32c6-40f5-8cd0-65f8db687a11' - R=sips:vozelia2 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting acc source-leg for uuid 'd0b7532b-dac6-49d8-b0f0-05caaf36dbf1': 'd0b7532b-dac6-49d8-b0f0-05caaf36dbf1|vozelia1|sip.mydomain.com|vozelia1|||2|||0|call|XXX.XXX.XXX.XXX|1412179832.267174' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting acc destination-leg for uuid '665792c5-32c6-40f5-8cd0-65f8db687a11': '0|||2|34991021|665792c5-32c6-40f5-8cd0-65f8db687a11|vozelia2|sip.mydomain.com|34991021|sip.mydomain.com|0' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting caller_cli_userprov/caller_domain_userprov 'vozelia1 at sip.mydomain.com' for upn - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting From to '<sip:vozelia1 at sip.mydomain.com>' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting caller_cli_netprov/caller_domain_netprov 'vozelia1 at sip.mydomain.com' for npn - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting PAI to '<sip:vozelia1 at sip.mydomain.com>' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting P-Called-Party-ID '<sip:vozelia2 at sip.mydomain.com>' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Appending P-D-URI 'sip:lb at 127.0.0.1;lr;received=sip:162.243.35.55:57479%3Btransport%3Dtls;socket=sip:ZZZ.ZZZ.ZZZ.ZZZ:5061' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Request leaving server, D-URI='sip:127.0.0.1:5080' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting caller_cli_userprov/caller_domain_userprov 'vozelia1 at sip.mydomain.com' for upn - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting From to '<sip:vozelia1 at sip.mydomain.com>' - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting caller_cli_netprov/caller_domain_netprov 'vozelia1 at sip.mydomain.com' for npn - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting PAI to '<sip:vozelia1 at sip.mydomain.com>' - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Setting P-Called-Party-ID '<sip:vozelia2 at sip.mydomain.com>' - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Appending P-D-URI 'sip:lb at 127.0.0.1;lr;socket=sip:ZZZ.ZZZ.ZZZ.ZZZ:5060' - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4316]: NOTICE: <script>: Request leaving server, D-URI='sip:127.0.0.1:5080' - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4326]: NOTICE: <script>: NAT-Reply - S=100 - Connecting M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4328]: NOTICE: <script>: NAT-Reply - S=100 - Connecting M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4322]: NOTICE: <script>: NAT-Reply - S=180 - Ringing M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip proxy[4320]: NOTICE: <script>: NAT-Reply - S=180 - Ringing M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip proxy[4318]: NOTICE: <script>: NAT-Reply - S=200 - OK M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip proxy[4326]: NOTICE: <script>: NAT-Reply - S=487 - Request terminated M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip proxy[4328]: NOTICE: <script>: NAT-Reply - S=200 - OK M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:46 sip proxy[4315]: NOTICE: <script>: NAT-Reply - S=200 - OK M=INVITE IP=XXX.XXX.XXX.XXX:1271 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:17 sip proxy[4318]: NOTICE: <script>: New request - M=BYE R=sips:vozelia1 at VVV.VVV.VVV.VVV:4895;alias=XXX.XXX.XXX.XXX~1271~3;rinstance=4C436233;transport=tcp F=sips:34991021 at sip.mydomain.com T=sip:vozelia1 at sip.mydomain.com IP=127.0.0.1:5080 (127.0.0.1:5080) ID=D5ABC3E799342103056A864EC2CF153D4E533CE6


kamailio-lb.log:

Oct  1 18:10:31 sip lb[4196]: NOTICE: <script>: New request - M=INVITE R=sips:34991021 at sip.mydomain.com F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:31 sip lb[4196]: NOTICE: <script>: Relaying request, du='sip:127.0.0.1:5062', fs='udp:127.0.0.1:5060' - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:31 sip lb[4184]: NOTICE: <script>: Reply from Inbound - S=100 - Trying M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:31 sip lb[4184]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4186]: NOTICE: <script>: Reply from Inbound - S=407 - Proxy Authentication Required M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4186]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4196]: NOTICE: <script>: New request - M=ACK R=sips:34991021 at sip.mydomain.com F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4196]: NOTICE: <script>: Relaying request, du='sip:127.0.0.1:5062', fs='udp:127.0.0.1:5060' - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4196]: NOTICE: <script>: New request - M=INVITE R=sips:34991021 at sip.mydomain.com F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4196]: NOTICE: <script>: Relaying request, du='sip:127.0.0.1:5062', fs='udp:127.0.0.1:5060' - R=sips:34991021 at sip.mydomain.com ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4187]: NOTICE: <script>: Reply from Inbound - S=100 - Trying M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4187]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4181]: NOTICE: <script>: Reply from Inbound - S=101 - Connecting M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4185]: NOTICE: <script>: New request - M=INVITE R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 F=sip:vozelia1 at sip.mydomain.com T=sip:vozelia2 at sip.mydomain.com IP=udp:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4182]: NOTICE: <script>: New request - M=INVITE R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp F=sip:vozelia1 at sip.mydomain.com T=sip:vozelia2 at sip.mydomain.com IP=udp:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4185]: NOTICE: <script>: Relaying request, du='<null>', fs='udp:ZZZ.ZZZ.ZZZ.ZZZ:5060' - R=sip:vozelia2 at YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4182]: NOTICE: <script>: Relaying request, du='sip:162.243.35.55:57479;transport=tls', fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4174]: NOTICE: <script>: Reply from Outbound - S=100 - Trying M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4174]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4174]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4175]: NOTICE: <script>: Reply from Outbound - S=180 - Ringing M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4175]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4175]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4188]: NOTICE: <script>: Reply from Inbound - S=180 - Ringing M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4188]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4197]: NOTICE: <script>: Reply from Outbound - S=100 - Trying M=INVITE IP=tls:162.243.35.55:57479 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4197]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4197]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4197]: NOTICE: <script>: Reply from Outbound - S=180 - Ringing M=INVITE IP=tls:162.243.35.55:57479 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4197]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4197]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:32 sip lb[4183]: NOTICE: <script>: Reply from Inbound - S=180 - Ringing M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:32 sip lb[4183]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4180]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4180]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4180]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4185]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4185]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4182]: NOTICE: <script>: New request - M=CANCEL R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp F=sip:vozelia1 at sip.mydomain.com T=sip:vozelia2 at sip.mydomain.com IP=udp:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4182]: NOTICE: <script>: Relaying request, du='sip:162.243.35.55:57479;transport=tls', fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4194]: NOTICE: <script>: Reply from Outbound - S=487 - Request Terminated M=INVITE IP=tls:162.243.35.55:57479 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4194]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4194]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4188]: NOTICE: <script>: New request - M=ACK R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp F=sip:vozelia1 at sip.mydomain.com T=sip:vozelia2 at sip.mydomain.com IP=udp:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4188]: NOTICE: <script>: Relaying request, du='sip:162.243.35.55:57479;transport=tls', fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - R=sips:vozelia2 at 10.0.1.3:57479;rinstance=B2D321C3;transport=tcp ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4194]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=CANCEL IP=tls:162.243.35.55:57479 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4194]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4194]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4183]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4183]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4173]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4173]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4173]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:45 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:45 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:46 sip lb[4185]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:46 sip lb[4185]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:46 sip lb[4176]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:46 sip lb[4176]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:46 sip lb[4176]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:46 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:46 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:48 sip lb[4178]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:48 sip lb[4178]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:48 sip lb[4178]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:50 sip lb[4183]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:50 sip lb[4183]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:50 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:50 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:52 sip lb[4179]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:52 sip lb[4179]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:52 sip lb[4179]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:54 sip lb[4184]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:54 sip lb[4184]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:54 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:54 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:56 sip lb[4180]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:56 sip lb[4180]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:56 sip lb[4180]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:10:58 sip lb[4183]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:58 sip lb[4183]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:58 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:10:58 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:00 sip lb[4175]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:00 sip lb[4175]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:00 sip lb[4175]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:02 sip lb[4187]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:02 sip lb[4187]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:02 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:02 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:04 sip lb[4180]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:04 sip lb[4180]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:04 sip lb[4180]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:06 sip lb[4185]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:06 sip lb[4185]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:06 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:06 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:08 sip lb[4174]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:08 sip lb[4174]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:08 sip lb[4174]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:10 sip lb[4186]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:10 sip lb[4186]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:10 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:10 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:12 sip lb[4175]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:12 sip lb[4175]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:12 sip lb[4175]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:14 sip lb[4185]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:14 sip lb[4185]: NOTICE: <script>: Sending reply, fs='tls:ZZZ.ZZZ.ZZZ.ZZZ:5061' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:14 sip lb[4198]: NOTICE: <script>: New request - M=ACK R=sips:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5061;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31;transport=tcp F=sips:vozelia1 at sip.mydomain.com T=sips:34991021 at sip.mydomain.com IP=tls:XXX.XXX.XXX.XXX:1271 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:14 sip lb[4198]: NOTICE: <script>: Relaying request, du='sips:127.0.0.1:5062;lr=on;ftag=7BB940C81E4C1038271A6A1399F81737;did=8a7.b7d1;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=aHRBPidJVmpoU04WG1RxLjcrZ35qfFZmJh1Pdww-', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6
Oct  1 18:11:16 sip lb[4177]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:16 sip lb[4177]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:16 sip lb[4177]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4184]: NOTICE: <script>: New request - M=ACK R=sip:vozelia2 at WWW.WWW.WWW.WWW:5060;alias=YYY.YYY.YYY.YYY~10095~1 F=sip:vozelia1 at sip.mydomain.com T=sip:vozelia2 at sip.mydomain.com IP=udp:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4183]: NOTICE: <script>: New request - M=BYE R=sip:vozelia2 at WWW.WWW.WWW.WWW:5060;alias=YYY.YYY.YYY.YYY~10095~1 F=sip:vozelia1 at sip.mydomain.com T=sip:vozelia2 at sip.mydomain.com IP=udp:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4184]: NOTICE: <script>: Relaying request, du='sip:YYY.YYY.YYY.YYY:10095', fs='udp:ZZZ.ZZZ.ZZZ.ZZZ:5060' - R=sip:vozelia2 at WWW.WWW.WWW.WWW:5060 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4183]: NOTICE: <script>: Relaying request, du='sip:YYY.YYY.YYY.YYY:10095', fs='udp:ZZZ.ZZZ.ZZZ.ZZZ:5060' - R=sip:vozelia2 at WWW.WWW.WWW.WWW:5060 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4178]: NOTICE: <script>: New request - M=BYE R=sip:ngcp-lb at ZZZ.ZZZ.ZZZ.ZZZ:5060;ngcpct=7369703a3132372e302e302e313a35303830 F=sip:vozelia2 at sip.mydomain.com T=sip:vozelia1 at sip.mydomain.com IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4178]: NOTICE: <script>: Relaying request, du='<null>', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4186]: NOTICE: <script>: Reply from Inbound - S=500 - Server Internal Error M=BYE IP=udp:127.0.0.1:5080 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4186]: NOTICE: <script>: Sending reply, fs='udp:ZZZ.ZZZ.ZZZ.ZZZ:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4179]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=BYE IP=udp:YYY.YYY.YYY.YYY:10095 ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4179]: NOTICE: <script>: NATed reply detected - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1
Oct  1 18:11:17 sip lb[4179]: NOTICE: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=D5ABC3E799342103056A864EC2CF153D4E533CE6_b2b-1


Any help is appreciated.

Thanks in advanced.

Best regards. 
Joel.







More information about the Spce-user mailing list