[Spce-user] Call does not stop after hang-up
Andrew Pogrebennyk
apogrebennyk at sipwise.com
Thu Dec 20 11:12:11 EST 2018
Hi Marcos,
indeed, the trace shows that BYE is not leaving sems sbc. Do you see any error in /var/log/ngcp/sems.log?
Could you send me the pcap trace offline (to my email address)? Or just send it as a text, replacing sensitive info like public IPs, if any.
Especially interested what is coming from the callee on outgoing call leg.
Regards,
Andrew
----- Original Message -----
From: "Marcos Pytel" <marcos.pytel at cotesma.com.ar>
To: "Andrew Pogrebennyk" <apogrebennyk at sipwise.com>
Cc: spce-user at lists.sipwise.com
Sent: Thursday, December 20, 2018 4:08:12 PM
Subject: RE: [Spce-user] Call does not stop after hang-up
Thank you Andrew!
The 10.10.10.2 is the IP that is facing to the MediaGateway.
This is the Kamailio-lb.log
--------------------------------------------------------------------------------------
root at sipwise:~# grep 37cc5932-0001-0008-0018 at 10.7.255.250 /var/log/ngcp/kamailio-lb.log
Dec 20 12:03:40 sipwise lb[13641]: NOTICE: <script>: New request on lb - M=INVITE R=sip:420518 at sip00.cotesma.com.ar F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=udp:10.7.255.250:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=10.7.255.253:5060
Dec 20 12:03:40 sipwise lb[13641]: NOTICE: <script>: Relaying request, fs='udp:127.0.0.1:5060' du='sip:127.0.0.1:5062' - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise lb[13658]: NOTICE: <script>: Reply from Inbound - S=100 - Trying M=INVITE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:40 sipwise lb[13658]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.7.255.253:5060' du='10.7.255.250:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise lb[13657]: NOTICE: <script>: Reply from Inbound - S=407 - Proxy Authentication Required M=INVITE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:40 sipwise lb[13657]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.7.255.253:5060' du='10.7.255.250:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise lb[13647]: NOTICE: <script>: New request on lb - M=INVITE R=sip:420518 at sip00.cotesma.com.ar F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=udp:10.7.255.250:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=10.7.255.253:5060
Dec 20 12:03:40 sipwise lb[13647]: NOTICE: <script>: Relaying request, fs='udp:127.0.0.1:5060' du='sip:127.0.0.1:5062' - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise lb[13661]: NOTICE: <script>: Reply from Inbound - S=100 - Trying M=INVITE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:40 sipwise lb[13661]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.7.255.253:5060' du='10.7.255.250:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise lb[13659]: NOTICE: <script>: Reply from Inbound - S=101 - Connecting M=INVITE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:40 sipwise lb[13660]: NOTICE: <script>: New request on lb - M=INVITE R=sip:420518 at 10.10.10.1:5060;transport=udp F=sip:2972413159 at sip00.cotesma.com.ar T=sip:420518 at 10.10.10.1 IP=udp:127.0.0.1:5080 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:40 sipwise lb[13660]: NOTICE: <script>: Relaying request, fs='udp:10.10.10.2:5060' du='10.10.10.1:5060' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='<null>'
Dec 20 12:03:41 sipwise lb[13633]: NOTICE: <script>: Reply from Outbound - S=100 - Trying M=INVITE IP=udp:10.10.10.1:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1' DESTIP=10.10.10.2:5060
Dec 20 12:03:41 sipwise lb[13633]: NOTICE: <script>: Sending reply from outbound, fs='udp:127.0.0.1:5060' du='127.0.0.1:5080' - ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1'
Dec 20 12:03:41 sipwise lb[13634]: NOTICE: <script>: Reply from Outbound - S=183 - Session Progress M=INVITE IP=udp:10.10.10.1:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1' DESTIP=10.10.10.2:5060
Dec 20 12:03:41 sipwise lb[13634]: NOTICE: <script>: NATed reply detected - ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1'
Dec 20 12:03:41 sipwise lb[13634]: NOTICE: <script>: Sending reply from outbound, fs='udp:127.0.0.1:5060' du='127.0.0.1:5080' - ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1'
Dec 20 12:03:41 sipwise lb[13657]: NOTICE: <script>: Reply from Inbound - S=183 - Session Progress M=INVITE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:41 sipwise lb[13657]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.7.255.253:5060' du='10.7.255.250:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:44 sipwise lb[13636]: NOTICE: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:10.10.10.1:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1' DESTIP=10.10.10.2:5060
Dec 20 12:03:44 sipwise lb[13636]: NOTICE: <script>: NATed reply detected - ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1'
Dec 20 12:03:44 sipwise lb[13636]: NOTICE: <script>: Sending reply from outbound, fs='udp:127.0.0.1:5060' du='127.0.0.1:5080' - ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1'
Dec 20 12:03:44 sipwise lb[13660]: NOTICE: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:44 sipwise lb[13660]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.7.255.253:5060' du='10.7.255.250:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:44 sipwise lb[13641]: NOTICE: <script>: New request on lb - M=ACK R=sip:ngcp-lb at 10.7.255.253:5060;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31 F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=udp:10.7.255.250:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=10.7.255.253:5060
Dec 20 12:03:44 sipwise lb[13641]: NOTICE: <script>: Relaying request, fs='udp:127.0.0.1:5060' du='sip:127.0.0.1:5062' - R=sip:127.0.0.1:5080;prxroute=1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:44 sipwise lb[13662]: NOTICE: <script>: New request on lb - M=ACK R=sip:420518 at 10.10.10.1:5060;alias=10.10.10.1~5060~1 F=sip:2972413159 at sip00.cotesma.com.ar T=sip:420518 at 10.10.10.1 IP=udp:127.0.0.1:5080 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:44 sipwise lb[13662]: NOTICE: <script>: Relaying request, fs='udp:10.10.10.2:5060' du='10.10.10.1:5060' - R=sip:420518 at 10.10.10.1:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='<null>'
Dec 20 12:03:50 sipwise lb[13647]: NOTICE: <script>: New request on lb - M=BYE R=sip:ngcp-lb at 10.7.255.253:5060;ngcpct=7369703a3132372e302e302e313a353038303b707278726f7574653d31 F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=udp:10.7.255.250:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=10.7.255.253:5060
Dec 20 12:03:50 sipwise lb[13647]: NOTICE: <script>: Relaying request, fs='udp:127.0.0.1:5060' du='sip:127.0.0.1:5062' - R=sip:127.0.0.1:5080;prxroute=1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:50 sipwise lb[13660]: NOTICE: <script>: Reply from Inbound - S=100 - Trying M=BYE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:50 sipwise lb[13660]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.7.255.253:5060' du='10.7.255.250:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:54 sipwise lb[13657]: NOTICE: <script>: Reply from Inbound - S=100 - Trying M=BYE IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:03:54 sipwise lb[13657]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.7.255.253:5060' du='10.7.255.250:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:56 sipwise lb[13665]: NOTICE: <script>: Inbound timeout - R=sip:127.0.0.1:5080;prxroute=1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:06:54 sipwise lb[13634]: NOTICE: <script>: New request on lb - M=BYE R=sip:ngcp-lb at 10.10.10.2:5060;ngcpct=7369703a3132372e302e302e313a35303830 F=sip:420518 at 10.10.10.1 T=sip:2972413159 at sip00.cotesma.com.ar IP=udp:10.10.10.1:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1' DESTIP=10.10.10.2:5060
Dec 20 12:06:54 sipwise lb[13634]: NOTICE: <script>: Relaying request, fs='udp:127.0.0.1:5060' du='<null>' - R=sip:127.0.0.1:5080 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='Synway/5.3.2.1'
Dec 20 12:06:54 sipwise lb[13662]: NOTICE: <script>: New request on lb - M=BYE R=sip:sip_0234 at 10.7.255.250:5060;alias=10.7.255.250~5060~1 F=sip:420518 at sip00.cotesma.com.ar T=sip:sip_0234 at sip00.cotesma.com.ar IP=udp:127.0.0.1:5062 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1' DESTIP=127.0.0.1:5060
Dec 20 12:06:54 sipwise lb[13662]: NOTICE: <script>: Relaying request, fs='udp:10.7.255.253:5060' du='sip:10.7.255.250:5060' - R=sip:sip_0234 at 10.7.255.250:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1'
Dec 20 12:06:54 sipwise lb[13644]: NOTICE: <script>: Reply from Outbound - S=481 - Call Leg/Transaction Does Not Exist M=BYE IP=udp:10.7.255.250:5060 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=10.7.255.253:5060
Dec 20 12:06:54 sipwise lb[13644]: NOTICE: <script>: NATed reply detected - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:06:54 sipwise lb[13644]: NOTICE: <script>: Sending reply from outbound, fs='udp:127.0.0.1:5060' du='127.0.0.1:5062' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:06:54 sipwise lb[13660]: NOTICE: <script>: Reply from Inbound - S=481 - Call Leg/Transaction Does Not Exist M=BYE IP=udp:127.0.0.1:5080 ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='<null>' DESTIP=127.0.0.1:5060
Dec 20 12:06:54 sipwise lb[13660]: NOTICE: <script>: Sending reply from inbound, fs='udp:10.10.10.2:5060' du='10.10.10.1:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250_b2b-1 UA='<null>'
----------------------------------------------------------
This is the Kamailio-proxy.log
Dec 20 12:03:40 sipwise proxy[6160]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:420518 at sip00.cotesma.com.ar F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=10.7.255.250:5060 (127.0.0.1:5060) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:03:40 sipwise proxy[6160]: NOTICE: <script>: Sending reply S=100 Trying fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6160]: NOTICE: <script>: Authentication failed, no credentials - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' Auth=<null>
Dec 20 12:03:40 sipwise proxy[6160]: NOTICE: <script>: Sending reply S=407 fs='127.0.0.1:5062' du='127.0.0.1:5060' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6158]: NOTICE: <script>: New request on proxy - M=ACK R=sip:420518 at sip00.cotesma.com.ar F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=<null>:<null> (127.0.0.1:5060) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:420518 at sip00.cotesma.com.ar F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=10.7.255.250:5060 (127.0.0.1:5060) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Sending reply S=100 Trying fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Sending reply S=101 Connecting fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Dialog managed, lua_dlg_callid:[37cc5932-0001-0008-0018 at 10.7.255.250] - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: User-Provided CLI 'sip_0234' taken from From-User - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: User-provided CLI 'sip_0234' rejected, using network-provided CLI '542972413159' - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting '542972413159 at sip00.cotesma.com.ar' as initiating user-provided CLI - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting '542972413159 at sip00.cotesma.com.ar' as initiating network-provided CLI - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Callee is not local - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Call to SIP Peering - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Load gws matching calling part 'sip:542972413159 at sip00.cotesma.com.ar' and called user '420518' and called part 'sip:420518 at sip00.cotesma.com.ar' - R=sip:420518 at sip00.cotesma.com.ar ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Rewriting acc caller party '542972413159' to '2972413159' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting acc source-leg for uuid '6f11a127-0395-4b33-a433-9068e5e6a0e4': '6f11a127-0395-4b33-a433-9068e5e6a0e4|sip_0234|sip00.cotesma.com.ar|542972413159|542972413159|16436|233|||0|call|10.7.255.250|1545318220.995565|SMVVSZD01|||||||||||2972413159||||' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting acc destination-leg for uuid '0': '0|||0|420518|0|420518|10.10.10.1|420518|sip00.cotesma.com.ar|1||||||||||||420518|||' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: WARNING: <script>: Failed to load dispatcher entries for lb using did '50', use first lb found in network.yml - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Rewriting first user-provided CLI '542972413159' to '2972413159' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Rewriting first network-provided CLI '542972413159' to '2972413159' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting caller_cli_netprov/caller_domain_netprov '2972413159 at sip00.cotesma.com.ar' for npn - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting caller_cli_userprov/caller_domain_userprov '2972413159 at sip00.cotesma.com.ar' for upn - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting From to '2972413159 <sip:2972413159 at sip00.cotesma.com.ar>' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting P-Called-Party-ID '<sip:420518 at 10.10.10.1>' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Setting 'sip:10.10.10.1:5060' taken from R-URI as next hop after lb for PSTN call - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Appending P-D-URI 'sip:127.0.0.1:5060;received=sip:10.10.10.1:5060%3blr%3btransport%3dudp' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6161]: NOTICE: <script>: Request leaving server, M=INVITE fs='127.0.0.1:5062' du='127.0.0.1:5080' - R=sip:420518 at 10.10.10.1:5060;transport=udp ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:40 sipwise proxy[6152]: NOTICE: <script>: NAT-Reply - S=100 - Connecting M=INVITE IP=10.7.255.250:5060 (127.0.0.1:5080) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:03:41 sipwise proxy[6151]: NOTICE: <script>: NAT-Reply - S=183 - Session Progress M=INVITE IP=10.7.255.250:5060 (127.0.0.1:5080) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1' DESTIP=127.0.0.1:5062
Dec 20 12:03:41 sipwise proxy[6151]: NOTICE: <script>: Sending reply S=183 M=INVITE fs='127.0.0.1:5062' du='127.0.0.1:<null>' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1'
Dec 20 12:03:44 sipwise proxy[6158]: NOTICE: <script>: NAT-Reply - S=200 - OK M=INVITE IP=10.7.255.250:5060 (127.0.0.1:5080) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1' DESTIP=127.0.0.1:5062
Dec 20 12:03:44 sipwise proxy[6158]: NOTICE: <script>: Sending reply S=200 M=INVITE fs='127.0.0.1:5062' du='127.0.0.1:<null>' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1'
Dec 20 12:03:44 sipwise proxy[6158]: NOTICE: <script>: [dialog:start] lua_dlg_callid:[37cc5932-0001-0008-0018 at 10.7.255.250] - R=<null> ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1'
Dec 20 12:03:44 sipwise proxy[6161]: NOTICE: <script>: New request on proxy - M=ACK R=sip:127.0.0.1:5080;prxroute=1 F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=10.7.255.250:5060 (127.0.0.1:5060) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:03:44 sipwise proxy[6161]: NOTICE: <script>: Request relayed to sems via local route - R=sip:127.0.0.1:5080;prxroute=1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:44 sipwise proxy[6161]: NOTICE: <script>: Request leaving server, M=ACK fs='127.0.0.1:5062' du='127.0.0.1:5080' - R=sip:127.0.0.1:5080;prxroute=1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:50 sipwise proxy[6158]: NOTICE: <script>: New request on proxy - M=BYE R=sip:127.0.0.1:5080;prxroute=1 F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=10.7.255.250:5060 (127.0.0.1:5060) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:03:50 sipwise proxy[6158]: NOTICE: <script>: Sending reply S=100 Trying fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:03:54 sipwise proxy[6158]: NOTICE: <script>: New request on proxy - M=BYE R=sip:127.0.0.1:5080;prxroute=1 F=sip:sip_0234 at sip00.cotesma.com.ar T=sip:420518 at sip00.cotesma.com.ar IP=10.7.255.250:5060 (127.0.0.1:5060) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:03:54 sipwise proxy[6158]: NOTICE: <script>: Sending reply S=100 Trying fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:127.0.0.1:5080;prxroute=1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
Dec 20 12:05:17 sipwise proxy[6163]: NOTICE: <script>: [dialog:end] decreased redis counters lua_dlg_callid:[37cc5932-0001-0008-0018 at 10.7.255.250] - R=sip:you at kamailio.org ID=123 UA='<null>'
Dec 20 12:05:17 sipwise proxy[6163]: WARNING: dialog [dlg_handlers.c:1596]: dlg_ontimeout(): timeout for dlg with CallID '37cc5932-0001-0008-0018 at 10.7.255.250' and tags '0-73697030-7cca73c9' '0E2B3889-5C1BAF4C000F3CC6-0B4F4700'
Dec 20 12:06:54 sipwise proxy[6151]: NOTICE: <script>: New request on proxy - M=BYE R=sip:sip_0234 at 10.7.255.250:5060;alias=10.7.255.250~5060~1 F=sip:420518 at sip00.cotesma.com.ar T=sip:2972413159 at sip00.cotesma.com.ar IP=127.0.0.1:5080 (127.0.0.1:5080) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1' DESTIP=127.0.0.1:5062
Dec 20 12:06:54 sipwise proxy[6151]: NOTICE: <script>: Sending reply S=100 Trying fs='127.0.0.1:5062' du='127.0.0.1:5080' - R=sip:sip_0234 at 10.7.255.250:5060;alias=10.7.255.250~5060~1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1'
Dec 20 12:06:54 sipwise proxy[6151]: NOTICE: <script>: MOS 4.4/4.4/4.4 - R=sip:sip_0234 at 10.7.255.250:5060;alias=10.7.255.250~5060~1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1'
Dec 20 12:06:54 sipwise proxy[6151]: NOTICE: <script>: Request leaving server, M=BYE fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:sip_0234 at 10.7.255.250:5060;alias=10.7.255.250~5060~1 ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='Synway/5.3.2.1'
Dec 20 12:06:54 sipwise proxy[6161]: NOTICE: <script>: NAT-Reply - S=481 - Call Leg/Transaction Does Not Exist M=BYE IP=127.0.0.1:5080 (127.0.0.1:5060) ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062
Dec 20 12:06:54 sipwise proxy[6161]: NOTICE: <script>: Sending reply S=481 M=BYE fs='127.0.0.1:5062' du='127.0.0.1:5080' - ID=37cc5932-0001-0008-0018 at 10.7.255.250 UA='<null>'
De: Andrew Pogrebennyk <apogrebennyk at sipwise.com>
Enviado el: jueves 20 de diciembre del 2018 11:11
Para: Marcos Pytel <marcos.pytel at cotesma.com.ar>
CC: spce-user at lists.sipwise.com
Asunto: Re: [Spce-user] Call does not stop after hang-up
Hi,
did I understand you correctly that 10.10.10.2 is a separate Sipwise CE box?
If the message if not forwarded to the other leg usually this means a problem with some Record-Route headers in 200 OK.. but in that case even an ACK would be dropped before.
You should check both kamailio-lb.log and kamailio-proxy.log and try to figure out if the message is dropped because of an error or something else.. many possible options here, but for sure we'd need to have the trace to be able to help you.
Regards
----- Original Message -----
From: "Marcos Pytel" < marcos.pytel at cotesma.com.ar >
To: spce-user at lists.sipwise.com
Sent: Thursday, December 20, 2018 12:50:35 PM
Subject: [Spce-user] Call does not stop after hang-up
Hi!
I’m using mr6.5.1
I have this schema:
10.7.255.250 is the device---127.0.0.1 SIPWISE port5060---127.0.0.1 SIPWISE port5062 + 10.10.10.2 SIPWISE----10.10.10.1 GW VoIP
Device send to SipWise 5060 port, then Sipwise will route to Sipwise 5062 port, which might for the purpose to route to 10.10.10.2 Sipwise, then this message will be finally lead to GW VoIP.
when the device try to hangup, it sends BYE to the sipwise , but somehow 127.0.0.1 Sipwise port 5062 sends back a 100 trying(???) and also I didn’t see a BYE come out from 10.10.10.2 (Tot he GW) . Maybe there might be something wrong when the BYE message transferring from 127.0.0.1 Port 5062 to 10.10.10.2 sipwise?
Thank you!
Marcos.
_______________________________________________
Spce-user mailing list
Spce-user at lists.sipwise.com
https://lists.sipwise.com/listinfo/spce-user
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20181220/62a7318d/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: image001.jpg
Type: image/jpeg
Size: 56769 bytes
Desc: not available
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20181220/62a7318d/attachment-0001.jpg>
More information about the Spce-user
mailing list