Up to Discussions in OpenSBC

This Question is Answered

2 "helpful" answers available (1 pts)
10 Replies Last post: Oct 24, 2009 1:23 PM by nate187  
Click to view nate187's profile  
nate187
27 posts since
Sep 29, 2009

Oct 19, 2009 12:36 PM

Unable to find ACK Transaction

The call is up and fine, but after 30 seconds it goes to one way audio.
The pstn side of the call can hear audio ok, but the sip endpoint can't hear anything.

endpoint - opensbc - proxy - pstn

proxy sends INVITE,
opensbc sends TRYING,
opensbc sends OK,
proxy sends ACK,
opensbc says "Unable to find ACK Transaction "
Then the log starts scrolling "RTP Out of order packet, received xxx expected xxx ssrc=981235891"

Attached is the debug of the invite thats causing this. I can get a full debug or tcpdump if needed.
Attachments:
Click to view nate187's profile  
nate187
27 posts since
Sep 29, 2009
1. Oct 19, 2009 2:26 PM in response to: nate187
Re: Unable to find ACK Transaction
I updated to svn, and am still getting the same thing 30 seconds into a call:

2009/10/19 13:15:27.479 INF: [CID=0x018ce7a3] <<< INVITE sip:sip5551231234@10.0.0.50 SIP/2.0 Method(INVITE) SRC: 10.0.0.200:5060:UDP enc=0 bytes=815
2009/10/19 13:15:27.481 INF: [CID=0x018ce7a3] >>> SIP/2.0 100 Trying Method(INVITE) DST: 10.0.0.200:5060:UDP SRC: 10.0.0.50:5060 enc=0 bytes=460
2009/10/19 13:15:27.483 INF: [CID=0x018ce7a3] >>> SIP/2.0 200 OK Method(INVITE) DST: 10.0.0.200:5060:UDP SRC: 10.0.0.50:5060 enc=0 bytes=651
2009/10/19 13:15:27.486 INF: [CID=0x018ce7a3] <<< ACK sip:sip5551231234@10.0.0.50 SIP/2.0 Method(ACK) SRC: 10.0.0.200:5060:UDP enc=0 bytes=617
2009/10/19 13:15:27.486 DBG: [CID=0x018ce7a3] Unable to find ACK Transaction 2ab79d9104608b850eaa63d7fc711368@192.168.69.238-0x0001473bf398d47bb49|z9hG4bK-205f7ea629f6baa2465ea52cacabd230-myswitch.mydomain.com-1|ACK
2009/10/19 13:15:27.486 DBG: [CID=0x018ce7a3] Event: Setting UA Core [Call] to handle event ACK
2009/10/19 13:15:27.486 DTL: [CID=0x018ce7a3] Event: ---> UnknownTransaction - ACK
2009/10/19 13:15:27.486 PWL: [CID=0x00000000] No corresponding 200 ok retransmission for 2ab79d9104608b850eaa63d7fc711368@192.168.69.238-0x0001|178837358|ACK
2009/10/19 13:15:27.507 PWL: [CID=0x00000000] RTP Set remote address from first Data PDU from 10.0.0.129:33578 enc=0
2009/10/19 13:15:27.507 PWL: [CID=0x00000000] RTP_UDP SetRemoteSocketInfo: session=1 data channel, new=10.0.0.129:33578, local=10.0.0.50:30000-30001, remote=10.0.0.20:3000-3001
2009/10/19 13:15:27.523 PWL: [CID=0x00000000] RTP Set remote address from first Data PDU from 10.0.0.20:3000 enc=0
2009/10/19 13:15:27.524 PWL: [CID=0x00000000] RTP_UDP SetRemoteSocketInfo: session=1 data channel, new=10.0.0.20:3000, local=10.0.0.50:30000-30001, remote=10.0.0.129:33578-33579
2009/10/19 13:15:27.531 PWL: [CID=0x00000000] RTP Set remote address from first Data PDU from 10.0.0.129:33578 enc=0
Click to view joegen's profile  
joegen
539 posts since
Apr 28, 2007
2. Oct 20, 2009 5:10 AM in response to: nate187
Re: Unable to find ACK Transaction

The log says the ACK doesnt match a 200 OK retransmission. The only way to prove if OpenSBC is right or wrong in assuming that can only be done if we can see the actual packets. send a level 5 log.
Click to view nate187's profile  
nate187
27 posts since
Sep 29, 2009
3. Oct 20, 2009 9:57 AM in response to: nate187
Re: Unable to find ACK Transaction
Attached is the entire log
Attachments:
Click to view nate187's profile  
nate187
27 posts since
Sep 29, 2009
4. Oct 23, 2009 12:11 PM in response to: nate187
Re: Unable to find ACK Transaction
Just copied from cvs and recompiled opensipstack and opensbc v1.1.6-3

Attached is the log.

2009/10/23 10:55:26 Call Begins
2009/10/23 10:55:56 Call goes to one way audio
Attachments:
Click to view nate187's profile  
nate187
27 posts since
Sep 29, 2009
5. Oct 23, 2009 3:09 PM in response to: nate187
Re: Unable to find ACK Transaction
Also, I'm using OpenSBC in front of a Metaswitch, in case I didn't mention that earlier.

Metaswitch support has looked at some packet captures and debugs and can't see anything wrong in the ACK that the Meta is sending in response to the 200 OK from the SBC.

They would like some help understanding why the OpenSBC is failing to correlate the ACK from your 200 OK to our reINVITE.
Click to view joegen's profile  
joegen
539 posts since
Apr 28, 2007
6. Oct 23, 2009 8:23 PM in response to: nate187
Re: Unable to find ACK Transaction

Tell the metaswitch guys that there is nothing wrong with the ACK. It is properly matched to the transaction. The reason I see for your one way audio is a mixed up on the port opensbc sends for the metaswitch reinvite. OpenSBC is sending port 30000 when in fact it should ahve sent port 30002. I will be investigating this and get back to you. Thanks for the logs.
Click to view joegen's profile  
joegen
539 posts since
Apr 28, 2007
7. Oct 23, 2009 8:37 PM in response to: joegen
Re: Unable to find ACK Transaction
Question: Do you have Local-Refer enabled?
Click to view nate187's profile  
nate187
27 posts since
Sep 29, 2009
8. Oct 23, 2009 10:11 PM in response to: nate187
Re: Unable to find ACK Transaction
Enable-Local-Refer is checked
Click to view joegen's profile  
joegen
539 posts since
Apr 28, 2007
9. Oct 23, 2009 10:26 PM in response to: nate187
Re: Unable to find ACK Transaction
Ok, I have a possible patch for this in CVS. Can you try that and report back with the results? Upgrade both opensipstack and opensbc
Click to view nate187's profile  
nate187
27 posts since
Sep 29, 2009
10. Oct 24, 2009 1:23 PM in response to: nate187
Re: Unable to find ACK Transaction
I made a call in and out, and both times the audio stayed up for over a minute. I'll do some further testing on Monday, but this seems to have fixed the problem. Thanks for the quick patch!

Actions

More Like This