Hello,
I'm using the head from GIT and I'm getting the following issue, when
using the sbc app with the stock transparent profile configuration
(the only adaptation I made RURI=sip:[email protected]:5090 for
routing) I have the following issue, the BYE is successfully
forwarded from the Calling party to the Called Leg, but the
corresponding 200 OK is not getting forwarded back to Calling party.
(Is SEMs designed to handle BYE end to end or hop by hop?)
I am using the stock UAC / UAS scripts from sipp to perform the call.
I feel like I'm missing something basic regarding configuration
Thank you for your help (debug log at end)
Torrey
 [#b725eb70/11444] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd
msg via UDP vv
--++--
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:5080;branch=z9hG4bK-11448-1-7
From: sipp<sip:[email protected]:5080>;tag=11448SIPpTag001
To:
sut<sip:[email protected]:5060>;tag=54AE15F7-4E79DC38000094C4-B705CB70
Call-ID: [email protected]
CSeq: 2 BYE
Contact: sip:[email protected]:5080
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0
--++--
[#b725eb70/11444] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted
URI port (5060) to int (5060)
[#b725eb70/11444] [parse_via_params, parse_via.cpp:525] DEBUG: parsed
branch: z9hG4bK-11448-1-7
[#b725eb70/11444] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 0
[#b725eb70/11444] [match_request, trans_table.cpp:68] DEBUG: Matching
BYE request
[#b725eb70/11444] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted
URI port (5080) to int (5080)
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:597]
DEBUG: Received new request
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:600]
DEBUG: req.method =<BYE>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:603]
DEBUG: req.r_uri =<sip:[email protected]:5060>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:604]
DEBUG: req.from_uri =<sip:[email protected]:5080>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:605]
DEBUG: req.from =<sipp<sip:[email protected]:5080>>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:606]
DEBUG: req.to =<sut
<sip:[email protected]:5060>;tag=54AE15F7-4E79DC38000094C4-B705CB70>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:607]
DEBUG: req.callid =<[email protected]>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:608]
DEBUG: req.from_tag =<11448SIPpTag001>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:609]
DEBUG: req.to_tag =<54AE15F7-4E79DC38000094C4-B705CB70>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:610]
DEBUG: cseq =<2>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:611]
DEBUG: req.route =<>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:612]
DEBUG: hdrs =<Max-Forwards: 70
Subject: Performance Test
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:613]
DEBUG: body =<>
[#b725eb70/11444] [handle_sip_request, SipCtrlInterface.cpp:619]
DEBUG: ^^ M [[email protected]|54AE15F7-4E79DC38000094C4-B705CB70]
Ru SIP request BYE handled ^^
[#b6e5ab70/11453] [processingCycle, AmSession.cpp:406] DEBUG: vv S
[[email protected]|54AE15F7-4E79DC38000094C4-B705CB70] Connected,
running, 0 UACTransPending vv
[#b6e5ab70/11453] [process, AmSession.cpp:757] DEBUG: AmSession
processing event
[#b6e5ab70/11453] [onRxRequest, AmSipDialog.cpp:151] DEBUG:
AmSipDialog::onRxRequest(req = BYE)
[#b6e5ab70/11453] [onBye, SBC.cpp:1060] DEBUG: onBye()
[#b6e5ab70/11453] [relayEvent, AmB2BSession.cpp:517] DEBUG:
AmB2BSession::relayEvent: to
other_id='0D06234A-4E79DC380000A29A-B6E5AB70'
[#b6d59b70/11454] [processingCycle, AmSession.cpp:406] DEBUG: vv S
[6B094A9F-4E79DC380000A2AC-B6E5AB70|0D06234A-4E79DC380000A29A-B6E5AB70]
Connected, running, 0 UACTransPending vv
[#b6e5ab70/11453] [relayEvent, AmB2BSession.cpp:517] DEBUG:
AmB2BSession::relayEvent: to other_id=''
[#b6d59b70/11454] [onB2BEvent, AmB2BSession.cpp:117] DEBUG:
AmB2BSession::onB2BEvent
[#b6e5ab70/11453] [processingCycle, AmSession.cpp:424] DEBUG: ^^ S
[[email protected]|54AE15F7-4E79DC38000094C4-B705CB70]
Disconnecting, stopped, 0 UACTransPending ^^
[#b6d59b70/11454] [onB2BEvent, AmB2BSession.cpp:237] DEBUG: terminateLeg()
[#b6d59b70/11454] [sendRequest, AmSipDialog.cpp:1548] DEBUG: req.body = ''
[#b6d59b70/11454] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted
URI port (5090) to int (5090)
[#b6d59b70/11454] [set_next_hop, trans_layer.cpp:672] DEBUG:
next_hop:next_port is<192.168.1.34:5090>
[#b6d59b70/11454] [set_destination_ip, trans_layer.cpp:683] DEBUG:
checking whether '192.168.1.34' is IP address...
[#b6d59b70/11454] [set_destination_ip, trans_layer.cpp:725] DEBUG:
set destination to 192.168.1.34:5090
[#b6d59b70/11454] [send_request, trans_layer.cpp:820] DEBUG:
send_request to R-URI<sip:192.168.1.34:5090;transport=UDP>
[#b6d59b70/11454] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted
URI port (5090) to int (5090)
[#b6d59b70/11454] [parse_via_params, parse_via.cpp:525] DEBUG: parsed
branch: z9hG4bKEkfDwad9
[#b6d59b70/11454] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
[#b6d59b70/11454] [send_request, trans_layer.cpp:889] DEBUG: Sending
to 192.168.1.34:5090<BYE sip:192.168.1.34:5090;transport=UDP SIP/2.0
V...>
[#b6d59b70/11454] [send, transport.cpp:98] DEBUG: send msg
--++--
BYE sip:192.168.1.34:5090;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34;branch=z9hG4bKEkfDwad9;rport
From: sipp<sip:[email protected]:5080>;tag=0D06234A-4E79DC380000A29A-B6E5AB70
To: sut<sip:[email protected]:5060>;tag=8479SIPpTag01484
CSeq: 11 BYE
Call-ID: 6B094A9F-4E79DC380000A2AC-B6E5AB70
Content-Length: 0
--++--
[#b6d59b70/11454] [send_request, trans_layer.cpp:901] DEBUG:
update_uac_request tt->_t =(nil)
[#b6d59b70/11454] [update_uac_request, trans_layer.cpp:1420] DEBUG:
update_uac_request(t=0x95f8a68)
[#b6d59b70/11454] [reset_timer, sip_trans.cpp:185] DEBUG: New timer
of type E at time=852 (repeated=0)
[#b6d59b70/11454] [reset_timer, sip_trans.cpp:185] DEBUG: New timer
of type F at time=2427 (repeated=0)
[#b6d59b70/11454] [reset_timer, sip_trans.cpp:185] DEBUG: New timer
of type M at time=1227 (repeated=0)
[#b6d59b70/11454] [processingCycle, AmSession.cpp:424] DEBUG: ^^ S
[6B094A9F-4E79DC380000A2AC-B6E5AB70|0D06234A-4E79DC380000A29A-B6E5AB70]
Disconnecting, stopped, 1 UACTransPending ^^
[#b6f5bb70/11447] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg
via UDP vv
--++--
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.34;branch=z9hG4bKEkfDwad9;rport
From: sipp<sip:[email protected]:5080>;tag=0D06234A-4E79DC380000A29A-B6E5AB70
To: sut<sip:[email protected]:5060>;tag=8479SIPpTag01484
Call-ID: 6B094A9F-4E79DC380000A2AC-B6E5AB70
CSeq: 11 BYE
Contact:<sip:192.168.1.34:5090;transport=UDP>
Content-Length: 0
--++--
[#b6f5bb70/11447] [parse_via_params, parse_via.cpp:525] DEBUG: parsed
branch: z9hG4bKEkfDwad9
[#b6f5bb70/11447] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
[#b6f5bb70/11447] [received_msg, trans_layer.cpp:1206] DEBUG: Reply
matched an existing transaction
[#b6f5bb70/11447] [update_uac_reply, trans_layer.cpp:1249] DEBUG:
update_uac_reply(reply code = 200, trans=0x95f8a68)
[#b6f5bb70/11447] [reset_timer, sip_trans.cpp:139] DEBUG: Clearing
old timer of type E (this=0x95f8b30)
[#b6f5bb70/11447] [reset_timer, sip_trans.cpp:139] DEBUG: Clearing
old timer of type M (this=0x95f8b80)
[#b6f5bb70/11447] [reset_timer, sip_trans.cpp:139] DEBUG: Clearing
old timer of type F (this=0x95f8b58)
[#b6f5bb70/11447] [reset_timer, sip_trans.cpp:185] DEBUG: New timer
of type K at time=1077 (repeated=0)
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:632] DEBUG:
Received reply: 200 OK
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:633] DEBUG:
reply.callid =<6B094A9F-4E79DC380000A2AC-B6E5AB70>
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:634] DEBUG:
reply.from_tag =<0D06234A-4E79DC380000A29A-B6E5AB70>
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:635] DEBUG:
reply.to_tag =<8479SIPpTag01484>
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:636] DEBUG:
reply.contact =<<sip:192.168.1.34:5090;transport=UDP>>
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:637] DEBUG:
reply.to_uri =<sip:192.168.1.34:5090;transport=UDP>
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:638] DEBUG:
cseq =<11>
[#b6f5bb70/11447] [handle_sip_reply, SipCtrlInterface.cpp:644] DEBUG:
^^ M [6B094A9F-4E79DC380000A2AC-B6E5AB70|0D06234A-4E79DC380000A29A-B6E5AB70]
ru SIP reply 200 OK handled ^^
[#b6d59b70/11454] [processingCycle, AmSession.cpp:406] DEBUG: vv S
[6B094A9F-4E79DC380000A2AC-B6E5AB70|0D06234A-4E79DC380000A29A-B6E5AB70]
Disconnecting, stopped, 1 UACTransPending vv
[#b6d59b70/11454] [process, AmSession.cpp:757] DEBUG: AmSession
processing event
[#b6d59b70/11454] [onRxReply, AmSipDialog.cpp:681] DEBUG:
onRxReply(rep = 200 OK): transaction found!
[#b6d59b70/11454] [onRxReply, AmSipDialog.cpp:773] DEBUG:
?Disconnecting?: cseq_method = BYE; code = 200
[#b6d59b70/11454] [onSipReply, SBC.cpp:1409] DEBUG: onSipReply: 200 OK (fwd=0)
[#b6d59b70/11454] [onSipReply, SBC.cpp:1410] DEBUG: onSipReply: content-type =
[#b6d59b70/11454] [onSipReply, AmB2BSession.cpp:447] DEBUG:
onSipReply: BYE -> 200 OK (fwd=false), c-t=
[#b6d59b70/11454] [onSipReply, AmSession.cpp:871] DEBUG: Dialog
status changed Disconnecting -> Disconnected (stopped=true)
[#b6d59b70/11454] [relayEvent, AmB2BSession.cpp:517] DEBUG:
AmB2BSession::relayEvent: to
other_id='54AE15F7-4E79DC38000094C4-B705CB70'
[#b6d59b70/11454] [processingCycle, AmSession.cpp:424] DEBUG: ^^ S
[6B094A9F-4E79DC380000A2AC-B6E5AB70|0D06234A-4E79DC380000A29A-B6E5AB70]
Disconnected, stopped, 0 UACTransPending ^^
[#b6d59b70/11454] [run, AmSession.cpp:309] DEBUG: session event loop
ended, finalizing session
[#b6e5ab70/11453] [processingCycle, AmSession.cpp:406] DEBUG: vv S
[[email protected]|54AE15F7-4E79DC38000094C4-B705CB70]
Disconnecting, stopped, 0 UACTransPending vv
[#b6d59b70/11454] [finalize, AmSession.cpp:485] DEBUG: running
finalize sequence...
[#b6d59b70/11454] [destroy, AmSession.cpp:525] DEBUG: AmSession::destroy()
[#b6d59b70/11454] [stop, AmThread.cpp:153] DEBUG: Thread 3067452272
(3067452272) calling on_stop, give it a chance to clean up.
[#b6d59b70/11454] [on_stop, AmSession.cpp:499] DEBUG: AmSession::stop()
[#b6d59b70/11454] [clearAudio, AmSession.cpp:749] DEBUG: Audio cleared !!!
[#b6d59b70/11454] [stop, AmThread.cpp:168] DEBUG: Thread 3067452272
(3067452272) finished detach.
[#b6d59b70/11454] [finalize, AmSession.cpp:491] DEBUG: session is stopped.
[#b6e5ab70/11453] [onB2BEvent, AmB2BSession.cpp:931] DEBUG:
B2BSipReply: other_id empty (reply code=200; method=BYE;
callid=6B094A9F-4E79DC380000A2AC-B6E5AB70;
from_tag=0D06234A-4E79DC380000A29A-B6E5AB70; to_tag=8479SIPpTag01484;
cseq=11)
[#b6e5ab70/11453] [onB2BEvent, AmB2BSession.cpp:940] DEBUG: 200 reply
received from other leg
[#b6e5ab70/11453] [onB2BEvent, AmB2BSession.cpp:987] DEBUG: reply
from callee: 200 OK
[#b6e5ab70/11453] [onB2BEvent, AmB2BSession.cpp:117] DEBUG:
AmB2BSession::onB2BEvent
[#b6e5ab70/11453] [onB2BEvent, AmB2BSession.cpp:167] DEBUG:
B2BSipReply: 200 OK (fwd=false)
[#b6e5ab70/11453] [onB2BEvent, AmB2BSession.cpp:168] DEBUG:
B2BSipReply: content-type =
[#b6e5ab70/11453] [processingCycle, AmSession.cpp:424] DEBUG: ^^ S
[[email protected]|54AE15F7-4E79DC38000094C4-B705CB70]
Disconnecting, stopped, 0 UACTransPending ^^
[#b6d59b70/11454] [_start, AmThread.cpp:72] DEBUG: Thread 3067452272 is
ending.
[#b715db70/11445] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg
via UDP vv
--++--
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:5080;branch=z9hG4bK-11448-1-7
From: sipp<sip:[email protected]:5080>;tag=11448SIPpTag001
To:
sut<sip:[email protected]:5060>;tag=54AE15F7-4E79DC38000094C4-B705CB70
Call-ID: [email protected]
CSeq: 2 BYE
Contact: sip:[email protected]:5080
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0
--++--
[#b715db70/11445] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted
URI port (5060) to int (5060)
[#b715db70/11445] [parse_via_params, parse_via.cpp:525] DEBUG: parsed
branch: z9hG4bK-11448-1-7
[#b715db70/11445] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 0
[#b715db70/11445] [match_request, trans_table.cpp:68] DEBUG: Matching
BYE request
[#b715db70/11445] [match_request, trans_table.cpp:87] DEBUG: do_3261_match = 1
[#b715db70/11445] [received_msg, trans_layer.cpp:1146] DEBUG: Found
retransmission
