Thanks pekka. Looks like I will have to get in sofia's code. However, I don't now the very insides of SIP and sofia-sip, so I think it will take quite a long time.
On the other side, if anyone is interested in this, this error is thrown with sofsip-cli too. thanks! Carlos Pekka Pessi writes: > 2008/1/2, [EMAIL PROTECTED] <[EMAIL PROTECTED]>: >> back to the point, I'm having some problems when trying to answer a call >> through wengo. I get a "strange ACK" message when accepting the invite, and >> then a terminated message with an internal error before that. > >> However, it's fine if I am the one calling from sofia-sip to another client >> through wengo. Any idea? > > You have hairy logs and managed to trip to multiple problems... ;) > > It looks to me that Wengo is a rfc 2543 proxy, SER is forking > requests, and the request merging logic in sofia-sip nta fails with > the second fork (the first INVITE shown in your log, and 2nd Via line > containing z9hG4bK261e.c5161123.0). So, the request is processed by > nua in the context of the original dialog, so nua discards the request > because its CSeq has not been increased. > > However, the "overlapping" INVITE seem to corrupt the SOA SDP state > from original INVITE so when you accept the call the nua_respond() > fails. > > It also looks like the stack matches the ACK message with the original > INVITE (one with 2nd Via line containing z9hG4bK261e.c5161123.4), not > with the INVITE shown in the log. The original INVITE transaction is > still pending and it does not process ACK but it is delivered to the > transaction (which just logs "strange ACK"). > > 3 bugs and still counting. ;-/ > > --Pekka > > >> >> Here I strip a little from the output with TPORT_LOG=9 >> >> ------------------------------------------------------------------------ >> Remote SDP: (null) >> Call 6|early >> recv 1118 bytes from udp/[213.91.9.206]:5060 at 15:18:50.843667: >> ------------------------------------------------------------------------ >> INVITE sip:[EMAIL PROTECTED] SIP/2.0 >> Record-Route: <sip:213.91.9.206:5060;lr=on> >> Record-Route: >> <sip:192.168.70.88:5062;proxy=yes;ftag=32721-BF-00a49812-47837c974;lr=on> >> Record-Route: <sip:192.168.70.242:5067;lr=on> >> Allow: UPDATE,REFER >> Call-ID: [EMAIL PROTECTED] >> Contact: <sip:213.91.9.213:5060> >> Content-Type: application/sdp >> CSeq: 10745350 INVITE >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> Max-Forwards: 27 >> To: <sip:[EMAIL PROTECTED];user=phone> >> User-Agent: Cirpack/v4.41a (gw_sip) >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> Via: SIP/2.0/UDP 192.168.70.242:5067;branch=0 >> Via: SIP/2.0/UDP >> 213.91.9.213:5060;received=192.168.70.20;branch=z9hG4bK-14AE-27B95 >> Content-Length: 276 >> >> v=0 >> o=cp10 119927620337 119927620338 IN IP4 80.118.99.22 >> s=SIP Call >> c=IN IP4 80.118.99.22 >> t=0 0 >> m=audio 45168 RTP/AVP 8 0 18 >> b=AS:64 >> a=rtpmap:8 PCMA/8000/1 >> a=rtpmap:0 PCMU/8000/1 >> a=rtpmap:18 G729/8000/1 >> a=fmtp:18 annexb=no >> a=ptime:30 >> a=sendrecv >> a=nortpproxy:yes >> ------------------------------------------------------------------------ >> send 733 bytes to udp/[213.91.9.206]:5060 at 15:18:50.844278: >> ------------------------------------------------------------------------ >> SIP/2.0 500 Overlapping Requests >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> Via: SIP/2.0/UDP 192.168.70.242:5067;branch=0 >> Via: SIP/2.0/UDP >> 213.91.9.213:5060;received=192.168.70.20;branch=z9hG4bK-14AE-27B95 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> Call-ID: [EMAIL PROTECTED] >> CSeq: 10745350 INVITE >> Retry-After: 7 (Overlapping Requests) >> User-Agent: sofia-sip/1.12.7 >> Accept: application/sdp >> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, >> NOTIFY, REFER, UPDATE >> Supported: timer, 100rel >> Content-Length: 0 >> >> ------------------------------------------------------------------------ >> recv 509 bytes from udp/[213.91.9.206]:5060 at 15:18:51.066475: >> ------------------------------------------------------------------------ >> ACK sip:[EMAIL PROTECTED] SIP/2.0 >> Max-Forwards: 10 >> Record-Route: <sip:213.91.9.206:5060;lr=on> >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> Call-ID: [EMAIL PROTECTED] >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> CSeq: 10745350 ACK >> User-Agent: OpenSer (1.1.0-notls (i386/linux)) >> Content-Length: 0 >> >> ------------------------------------------------------------------------ >> nua(0x8059e88): strange ACK from <sip:[EMAIL PROTECTED];user=phone> >> send 733 bytes to udp/[213.91.9.206]:5060 at 15:18:51.345997: >> ------------------------------------------------------------------------ >> SIP/2.0 500 Overlapping Requests >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> Via: SIP/2.0/UDP 192.168.70.242:5067;branch=0 >> Via: SIP/2.0/UDP >> 213.91.9.213:5060;received=192.168.70.20;branch=z9hG4bK-14AE-27B95 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> Call-ID: [EMAIL PROTECTED] >> CSeq: 10745350 INVITE >> Retry-After: 7 (Overlapping Requests) >> User-Agent: sofia-sip/1.12.7 >> Accept: application/sdp >> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, >> NOTIFY, REFER, UPDATE >> Supported: timer, 100rel >> Content-Length: 0 >> >> ------------------------------------------------------------------------ >> recv 509 bytes from udp/[213.91.9.206]:5060 at 15:18:51.551582: >> ------------------------------------------------------------------------ >> ACK sip:[EMAIL PROTECTED] SIP/2.0 >> Max-Forwards: 10 >> Record-Route: <sip:213.91.9.206:5060;lr=on> >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> Call-ID: [EMAIL PROTECTED] >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> CSeq: 10745350 ACK >> User-Agent: OpenSer (1.1.0-notls (i386/linux)) >> Content-Length: 0 >> >> >> ------- and then, later, when I choose to 'answer' ----------- >> >> ------------------------------------------------------------------------ >> send 733 bytes to udp/[213.91.9.206]:5060 at 15:18:54.348987: >> ------------------------------------------------------------------------ >> SIP/2.0 500 Overlapping Requests >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> Via: SIP/2.0/UDP 192.168.70.242:5067;branch=0 >> Via: SIP/2.0/UDP >> 213.91.9.213:5060;received=192.168.70.20;branch=z9hG4bK-14AE-27B95 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> Call-ID: [EMAIL PROTECTED] >> CSeq: 10745350 INVITE >> Retry-After: 7 (Overlapping Requests) >> User-Agent: sofia-sip/1.12.7 >> Accept: application/sdp >> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, >> NOTIFY, REFER, UPDATE >> Supported: timer, 100rel >> Content-Length: 0 >> >> ------------------------------------------------------------------------ >> recv 509 bytes from udp/[213.91.9.206]:5060 at 15:18:54.572938: >> ------------------------------------------------------------------------ >> ACK sip:[EMAIL PROTECTED] SIP/2.0 >> Max-Forwards: 10 >> Record-Route: <sip:213.91.9.206:5060;lr=on> >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> Call-ID: [EMAIL PROTECTED] >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> CSeq: 10745350 ACK >> User-Agent: OpenSer (1.1.0-notls (i386/linux)) >> Content-Length: 0 >> >> ------------------------------------------------------------------------ >> send 733 bytes to udp/[213.91.9.206]:5060 at 15:18:55.301320: >> ------------------------------------------------------------------------ >> SIP/2.0 500 Overlapping Requests >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> Via: SIP/2.0/UDP 192.168.70.242:5067;branch=0 >> Via: SIP/2.0/UDP >> 213.91.9.213:5060;received=192.168.70.20;branch=z9hG4bK-14AE-27B95 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> Call-ID: [EMAIL PROTECTED] >> CSeq: 10745350 INVITE >> Retry-After: 2 (Overlapping Requests) >> User-Agent: sofia-sip/1.12.7 >> Accept: application/sdp >> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, >> NOTIFY, REFER, UPDATE >> Supported: timer, 100rel >> Content-Length: 0 >> >> ------------------------------------------------------------------------ >> send 883 bytes to udp/[213.91.9.206]:5060 at 15:18:55.365311: >> ------------------------------------------------------------------------ >> SIP/2.0 500 Internal Server Error >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.4 >> Via: SIP/2.0/UDP 192.168.70.242:5067;branch=0 >> Via: SIP/2.0/UDP >> 213.91.9.213:5060;received=192.168.70.20;branch=z9hG4bK-14AE-27B95 >> Record-Route: <sip:213.91.9.206:5060;lr=on> >> Record-Route: >> <sip:192.168.70.88:5062;proxy=yes;ftag=32721-BF-00a49812-47837c974;lr=on> >> Record-Route: <sip:192.168.70.242:5067;lr=on> >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> Call-ID: [EMAIL PROTECTED] >> CSeq: 10745350 INVITE >> Contact: <sip:192.168.255.102> >> User-Agent: sofia-sip/1.12.7 >> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, >> NOTIFY, REFER, UPDATE >> Supported: timer, 100rel >> Content-Length: 0 >> >> ------------------------------------------------------------------------ >> ::1199287135 sofia_events.c at app_i_error(): NUA Error: Internal Server >> Error >> Remote SDP: (null) >> Call 10|terminated >> Call terminated >> ::tag_null: 0 >> recv 509 bytes from udp/[213.91.9.206]:5060 at 15:18:55.555716: >> ------------------------------------------------------------------------ >> ACK sip:[EMAIL PROTECTED] SIP/2.0 >> Max-Forwards: 10 >> Record-Route: <sip:213.91.9.206:5060;lr=on> >> Via: SIP/2.0/UDP 213.91.9.206;branch=0 >> Via: SIP/2.0/UDP 192.168.70.88:5062;branch=z9hG4bK261e.c5161123.0 >> From: "cjb2002" >> <sip:[EMAIL PROTECTED];user=phone>;tag=32721-BF-00a49812-47837c974 >> Call-ID: [EMAIL PROTECTED] >> To: <sip:[EMAIL PROTECTED];user=phone>;tag=F19jtD1g3Hmpp >> CSeq: 10745350 ACK >> User-Agent: OpenSer (1.1.0-notls (i386/linux)) >> Content-Length: 0 >> >> >> >> Thanks in advance, >> >> Carlos >> >> ------------------------------------------------------------------------- >> This SF.net email is sponsored by: Microsoft >> Defy all challenges. Microsoft(R) Visual Studio 2005. >> http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/ >> _______________________________________________ >> Sofia-sip-devel mailing list >> Sofia-sip-devel@lists.sourceforge.net >> https://lists.sourceforge.net/lists/listinfo/sofia-sip-devel >> > > > -- > Pekka.Pessi mail at nokia.com ------------------------------------------------------------------------- This SF.net email is sponsored by: Microsoft Defy all challenges. Microsoft(R) Visual Studio 2005. http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/ _______________________________________________ Sofia-sip-devel mailing list Sofia-sip-devel@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/sofia-sip-devel
