i made the same test with sems 1.5 and it worked fine. after getting 503 from first invite, sems sent out the second invite fine without To tag:
U 2013/09/04 13:10:33.124496 192.98.102.10:5070 -> 192.98.102.10:5090 SIP/2.0 503 Service Unavailable. Via: SIP/2.0/UDP 192.98.102.10:5090;branch=z9hG4bKV45n7a0s;rport=5090. From: sip:[email protected];tag=278B4A48-5227071300078344-86CE0700. To: sip:[email protected];tag=c02670ad1171fe45d9ff9a27d6c2cb82-1b0d. CSeq: 10 INVITE. Call-ID: 1CD375BC-522707130007834E-86CE0700. Server: OpenXg SIP Proxy (4.1.0-dev7 (x86_64/linux)). Content-Length: 0. . # U 2013/09/04 13:10:33.125603 192.98.102.10:5090 -> 192.98.102.10:5070 ACK sip:[email protected] SIP/2.0. Via: SIP/2.0/UDP 192.98.102.10:5090;branch=z9hG4bKV45n7a0s;rport. From: sip:[email protected];tag=278B4A48-5227071300078344-86CE0700. To: sip:[email protected];tag=c02670ad1171fe45d9ff9a27d6c2cb82-1b0d. Call-ID: 1CD375BC-522707130007834E-86CE0700. CSeq: 10 ACK. . # U 2013/09/04 13:10:33.130577 192.98.102.10:5090 -> 192.98.102.10:5070 INVITE sip:[email protected] SIP/2.0. Via: SIP/2.0/UDP 192.98.102.10:5090;branch=z9hG4bKjl7bXaHI;rport. From: "Juha Heinanen" <sip:[email protected]>;tag=579A2F43-522707190001F56E-86CE0700. To: sip:[email protected]. debug with 1.5 is below. there is more and different output in master version, but i have not been able to figure out if there are some real differences that could explain the bug in master version. -- juha Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012SIP/2.0 503 Service Unavailable#015#012Via: SIP/2.0/UDP 192.98.102.10:5090;branch=z9hG4bKV45n7a0s;rport=5090#015#012From: sip:[email protected];tag=278B4A48-5227071300078344-86CE0700#015#012To: sip:[email protected];tag=c02670ad1171fe45d9ff9a27d6c2cb82-1b0d#015#012CSeq: 10 INVITE#015#012Call-ID: 1CD375BC-522707130007834E-86CE0700#015#012Server: OpenXg SIP Proxy (4.1.0-dev7 (x86_64/linux))#015#012Content-Length: 0#015#012#015#012--++-- Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKV45n7a0s Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1 Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [received_msg, trans_layer.cpp:1289] DEBUG: Reply matched an existing transaction Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [update_uac_reply, trans_layer.cpp:1340] DEBUG: update_uac_reply(reply code = 503, trans=0x29dde70) Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type C (this=0x29d6df0) Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [send_non_200_ack, trans_layer.cpp:1721] DEBUG: About to send ACK Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [send, transport.cpp:109] DEBUG: send msg#012--++--#012ACK sip:[email protected] SIP/2.0#015#012Via: SIP/2.0/UDP 192.98.102.10:5090;branch=z9hG4bKV45n7a0s;rport#015#012From: sip:[email protected];tag=278B4A48-5227071300078344-86CE0700#015#012To: sip:[email protected];tag=c02670ad1171fe45d9ff9a27d6c2cb82-1b0d#015#012Call-ID: 1CD375BC-522707130007834E-86CE0700#015#012CSeq: 10 ACK#015#012#015#012--++-- Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type D at time=5301 (repeated=0) Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:606] DEBUG: Received reply: 503 Service Unavailable Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:607] DEBUG: reply.callid = <1CD375BC-522707130007834E-86CE0700> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:608] DEBUG: reply.from_tag = <278B4A48-5227071300078344-86CE0700> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:609] DEBUG: reply.to_tag = <c02670ad1171fe45d9ff9a27d6c2cb82-1b0d> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:610] DEBUG: reply.contact = <> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:611] DEBUG: reply.to_uri = <> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:612] DEBUG: cseq = <10> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:613] DEBUG: reply.route = <> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:614] DEBUG: hdrs = <Server: OpenXg SIP Proxy (4.1.0-dev7 (x86_64/linux))#015#012> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:615] DEBUG: body-ct = <> Sep 4 13:10:33 siika sems[31125]: [#7fa5876a2700] [handle_sip_reply, SipCtrlInterface.cpp:621] DEBUG: ^^ M [1CD375BC-522707130007834E-86CE0700|278B4A48-5227071300078344-86CE0700] ru SIP reply 503 Service Unavailable handled ^^ Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [processingCycle, AmSession.cpp:368] DEBUG: vv S [1CD375BC-522707130007834E-86CE0700|278B4A48-5227071300078344-86CE0700] Proceeding, running, 1 UACTransPending vv Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [process, AmSession.cpp:713] DEBUG: AmSession processing event Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [onRxReply, AmSipDialog.cpp:387] DEBUG: onRxReply(rep = 503 Service Unavailable): transaction found! Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [onSipReply, AmB2BSession.cpp:468] DEBUG: onSipReply: INVITE -> 503 Service Unavailable (fwd=false), c-t= Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [onSipReply, AmSession.cpp:832] DEBUG: Dialog status changed Proceeding -> Disconnected (stopped=false) Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [onSipReply, AmB2BSession.cpp:508] DEBUG: relaying B2B SIP reply 503 Service Unavailable Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [relayEvent, AmB2BSession.cpp:538] DEBUG: AmB2BSession::relayEvent: to other_id='107A43F4-5227070F0002A8EA-877A3700' Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [processingCycle, AmSession.cpp:386] DEBUG: ^^ S [1CD375BC-522707130007834E-86CE0700|278B4A48-5227071300078344-86CE0700] Disconnected, running, 0 UACTransPending ^^ Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [processingCycle, AmSession.cpp:368] DEBUG: vv S [[email protected]|107A43F4-5227070F0002A8EA-877A3700] Connected, running, 0 UACTransPending vv Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [process, Ivr.cpp:759] DEBUG: IvrDialog::process Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [onB2BEvent, AmB2BSession.cpp:949] DEBUG: 503 Service Unavailable reply received from other leg Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [relayEvent, AmB2BSession.cpp:538] DEBUG: AmB2BSession::relayEvent: to other_id='278B4A48-5227071300078344-86CE0700' Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [removeTimer, AmSession.cpp:1263] DEBUG: removing timer 1 Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [removeTimer, AmSession.cpp:1263] DEBUG: removing timer 2 Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [setTimer, AmSession.cpp:1255] DEBUG: setting timer 1 with timeout 6.000000 Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [setTimer, AmSession.cpp:1255] DEBUG: setting timer 2 with timeout 20.000000 Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [IvrDialogBase_b2b_connectCallee, IvrDialogBase.cpp:316] DEBUG: args != Py_None Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [connectCallee, AmB2BSession.cpp:1112] DEBUG: relaying B2B connect event to sip:[email protected] Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [createCalleeSession, Ivr.cpp:815] DEBUG: Created B2BUA callee leg, From: "Juha Heinanen" <sip:[email protected]> Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [processingCycle, AmSession.cpp:368] DEBUG: vv S [1CD375BC-522707130007834E-86CE0700|278B4A48-5227071300078344-86CE0700] Disconnected, running, 0 UACTransPending vv Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [onB2BEvent, AmB2BSession.cpp:133] DEBUG: AmB2BSession::onB2BEvent Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [onB2BEvent, AmB2BSession.cpp:255] DEBUG: terminateLeg() Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [bye, AmSipDialog.cpp:899] DEBUG: bye(): we are not connected (status=Disconnected). do nothing! Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [relayEvent, AmB2BSession.cpp:538] DEBUG: AmB2BSession::relayEvent: to other_id='579A2F43-522707190001F56E-86CE0700' Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [onB2BEvent, AmB2BSession.cpp:133] DEBUG: AmB2BSession::onB2BEvent Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [onB2BEvent, AmB2BSession.cpp:187] DEBUG: B2BSipReply: 503 Service Unavailable (fwd=false) Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [onB2BEvent, AmB2BSession.cpp:189] DEBUG: B2BSipReply: content-type = Sep 4 13:10:33 siika sems[31125]: [#7fa586ce0700] [processingCycle, AmSession.cpp:386] DEBUG: ^^ S [[email protected]|107A43F4-5227070F0002A8EA-877A3700] Connected, running, 0 UACTransPending ^^ Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [processingCycle, AmSession.cpp:386] DEBUG: ^^ S [1CD375BC-522707130007834E-86CE0700|278B4A48-5227071300078344-86CE0700] Disconnected, stopped, 0 UACTransPending ^^ Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [run, AmSession.cpp:271] DEBUG: session event loop ended, finalizing session Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [finalize, AmSession.cpp:447] DEBUG: running finalize sequence... Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [destroy, AmSession.cpp:487] DEBUG: AmSession::destroy() Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [stop, AmThread.cpp:114] DEBUG: Thread 140348915054336 (140348915054336) calling on_stop, give it a chance to clean up. Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [on_stop, AmSession.cpp:461] DEBUG: AmSession::stop() Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [clearAudio, AmSession.cpp:705] DEBUG: Audio cleared !!! Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [stop, AmThread.cpp:129] DEBUG: Thread 140348915054336 (140348915054336) finished detach. Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [finalize, AmSession.cpp:453] DEBUG: session is stopped. Sep 4 13:10:33 siika sems[31125]: [#7fa58739f700] [_start, AmThread.cpp:68] DEBUG: Thread 140348915054336 is ending. Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [_start, AmThread.cpp:65] DEBUG: Thread 140348909033216 is starting. Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [run, AmSession.cpp:260] DEBUG: startup session Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [run, AmSession.cpp:264] DEBUG: running session event loop Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [processingCycle, AmSession.cpp:368] DEBUG: vv S [7FBF8B22-522707190001F57C-86CE0700|579A2F43-522707190001F56E-86CE0700] Disconnected, running, 0 UACTransPending vv Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [setState, AmOfferAnswer.cpp:69] DEBUG: setting SIP dialog O/A status: None->OfferSent Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [set_destination_ip, trans_layer.cpp:717] DEBUG: checking whether '192.98.102.10' is IP address... Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [set_destination_ip, trans_layer.cpp:759] DEBUG: set destination to 192.98.102.10:5070 Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [send_request, trans_layer.cpp:911] DEBUG: send_request to R-URI <sip:[email protected]> Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port () to int (5060) Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKjl7bXaHI Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1 Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [send_request, trans_layer.cpp:981] DEBUG: Sending to 192.98.102.10:5070 <INVITE sip:[email protected] SIP/2.0#015#012Via: SIP/...> Sep 4 13:10:33 siika sems[31125]: [#7fa586de1700] [send, transport.cpp:109] DEBUG: send msg#012--++--#012INVITE sip:[email protected] SIP/2.0#015#012Via: SIP/2.0/UDP 192.98.102.10:5090;branch=z9hG4bKjl7bXaHI;rport#015#012From: "Juha Heinanen" <sip:[email protected]>;tag=579A2F43-522707190001F56E-86CE0700#015#012To: sip:[email protected]#015#012CSeq: 10 INVITE#015#012Call-ID: 7FBF8B22-522707190001F57C-86CE0700#015#012Contact: <sip:192.98.102.10:5090>#015#012Max-Forwards: 16#015#012Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,PRACK,REFER,NOTIFY,SUBSCRIBE,INFO,MESSAGE#015#012Supported: replaces,norefersub,100rel#015#012User-Agent: Twinkle/1.4.2#015#012Supported: 100rel#015#012Content-Type: application/sdp#015#012Content-Length: 316#015#012#015#012v=0#015#012o=twinkle 961613179 157898504 IN IP4 192.98.102.10#015#012s=-#015#012c=IN IP4 192.98.102.10#015#012t=0 0#015#012m=audio 8002 RTP/AVP 97 98 8 0 101#015#012a=rtpmap:97 speex/8000#015#012a=rtpmap:98 speex/16000#015#012a=rtpmap: 8 PCMA/8000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:101 telephone-event/8000#015#012a=fmtp:101 0-15#015#012a=sendrecv#015#012a=ptime:20#015#012a=direction:both#015#012--++-- _______________________________________________ Sems mailing list [email protected] http://lists.iptel.org/mailman/listinfo/sems
