with some further testing, I've found that this issue doesn't occur when using sems-1.4.2 only with GIT head.
Torrey On 21 September 2011 15:12, Torrey Searle <[email protected]> wrote: > 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 > _______________________________________________ Sems mailing list [email protected] http://lists.iptel.org/mailman/listinfo/sems
