[Asterisk-Dev] SIP weirdness (beginning in 3/18 CVS)

[Brian Capouch]

Sorry I can't do much more than report this; hopefully it might provide 
some "evidence" if whatever's going on here surfaces for someone else. 
It's perfectly reproducible.

* Narrative Description:

CVS-head fetched early AM 3/18/05.

Asterisk doesn't react to the BYE message coming from my Cisco ATA186 
when I hang it up on an ->inbound IAX call to the server.  Things work 
OK in the other direction, i.e. if I pick up the phone on the Cisco and 
call out, even if I hang up first, things work just fine.

SIP debug below, including error message from phone when I finally tore 
down the call from the remote IAX server.  If I don't do that, the ATA 
sends 4 consecutive BYEs after I go on-hook, and then stops.  When the 
remote end then tears down the call, the server sends the phone a BYE 
which then gets a 481 from the ATA.

I did a similar trace on a different SIP phone/server, and the only 
thing I can see different is that in the "bad" situation, there is no 
"branch" tag on the Via: header.  The tag is there on another server 
that is working fine with an older CVS-HEAD.

Thanks.

* Here's the debug trace, starting at the moment I hung up the ATA after 
(successfully) picking up an incoming call:

pwClient1*CLI>
<-- SIP read from 192.168.1.133:1024:
BYE sip:8772538181 at 192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.133:5060
From: <sip:nwata2 at 192.168.1.133;transport=udp>;tag=672168105
To: "Brian Capouch" <sip:8772538181 at 192.168.1.1>;tag=as77d313d6
Call-ID: 799426b23fa11c4a695932492b16534e at 192.168.1.1
CSeq: 1 BYE
User-Agent: Cisco ATA 186  v3.1.0 atasip (040211A)
Content-Length: 0


--- (8 headers 0 lines)---

pwClient1*CLI>
<-- SIP read from 192.168.1.133:1024:
BYE sip:8772538181 at 192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.133:5060
From: <sip:nwata2 at 192.168.1.133;transport=udp>;tag=672168105
To: "Brian Capouch" <sip:8772538181 at 192.168.1.1>;tag=as77d313d6
Call-ID: 799426b23fa11c4a695932492b16534e at 192.168.1.1
CSeq: 1 BYE
User-Agent: Cisco ATA 186  v3.1.0 atasip (040211A)
Content-Length: 0


--- (8 headers 0 lines)---

pwClient1*CLI>
<-- SIP read from 192.168.1.133:1024:
BYE sip:8772538181 at 192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.133:5060
From: <sip:nwata2 at 192.168.1.133;transport=udp>;tag=672168105
To: "Brian Capouch" <sip:8772538181 at 192.168.1.1>;tag=as77d313d6
Call-ID: 799426b23fa11c4a695932492b16534e at 192.168.1.1
CSeq: 1 BYE
User-Agent: Cisco ATA 186  v3.1.0 atasip (040211A)
Content-Length: 0


--- (8 headers 0 lines)---

pwClient1*CLI>
<-- SIP read from 192.168.1.133:1024:
BYE sip:8772538181 at 192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.133:5060
From: <sip:nwata2 at 192.168.1.133;transport=udp>;tag=672168105
To: "Brian Capouch" <sip:8772538181 at 192.168.1.1>;tag=as77d313d6
Call-ID: 799426b23fa11c4a695932492b16534e at 192.168.1.1
CSeq: 1 BYE
User-Agent: Cisco ATA 186  v3.1.0 atasip (040211A)
Content-Length: 0


--- (8 headers 0 lines)---

pwClient1*CLI>
<-- SIP read from 192.168.1.133:1024:
BYE sip:8772538181 at 192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.133:5060
From: <sip:nwata2 at 192.168.1.133;transport=udp>;tag=672168105
To: "Brian Capouch" <sip:8772538181 at 192.168.1.1>;tag=as77d313d6
Call-ID: 799426b23fa11c4a695932492b16534e at 192.168.1.1
CSeq: 1 BYE
User-Agent: Cisco ATA 186  v3.1.0 atasip (040211A)
Content-Length: 0


--- (8 headers 0 lines)---

set_destination: Parsing <sip:nwata2 at 192.168.1.133:5060;transport=udp> 
for address/port to send to
set_destination: set destination to 192.168.1.133, port 5060
Reliably Transmitting (no NAT) to 192.168.1.133:5060:
BYE sip:nwata2 at 192.168.1.133:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.1:5060;branch=z9hG4bK2cb62318
From: "Brian Capouch" <sip:8772538181 at 192.168.1.1>;tag=as77d313d6
To: <sip:nwata2 at 192.168.1.133:5060;transport=udp>
Contact: <sip:8772538181 at 192.168.1.1>
Call-ID: 799426b23fa11c4a695932492b16534e at 192.168.1.1
CSeq: 103 BYE
User-Agent: Asterisk PBX
Content-Length: 0


---
   == Spawn extension (testcontext, 10101, 1) exited non-zero on 
'IAX2/wrt3 at bc-enc2-2'

pwClient1*CLI>
<-- SIP read from 192.168.1.133:5060:
SIP/2.0 481 Call Leg/Transaction Does Not Exist
Via: SIP/2.0/UDP 192.168.1.1:5060;branch=z9hG4bK2cb62318
From: "Brian Capouch" <sip:8772538181 at 192.168.1.1>;tag=as77d313d6
To: <sip:nwata2 at 192.168.1.133:5060;transport=udp>
Call-ID: 799426b23fa11c4a695932492b16534e at 192.168.1.1
CSeq: 103 BYE
Server: Cisco ATA 186  v3.1.0 atasip (040211A)
Allow: ACK, BYE, CANCEL, INVITE, NOTIFY, OPTIONS, REFER, REGISTER
Content-Length: 0


--- (9 headers 0 lines)---
     -- Got SIP response 481 "Call Leg/Transaction Does Not Exist" back 
from 192.168.1.133
Destroying call '799426b23fa11c4a695932492b16534e at 192.168.1.1'

     -- Executing Hangup("IAX2/wrt3 at bc-enc2-2", "")

   == Spawn extension (testcontext, h, 1) exited non-zero on 
'IAX2/wrt3 at bc-enc2-2'

     -- Hungup 'IAX2/wrt3 at bc-enc2-2'