[Home]

Summary:ASTERISK-08073: Failure getting "online status" on softphone
Reporter:Henrik Woffinden (hwoffinden)Labels:
Date Opened:2006-11-06 14:55:37.000-0600Date Closed:2007-01-09 13:10:28.000-0600
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Channels/chan_sip/Registration
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:( 0) siperror.log
Description:When my Xlite softphone registers then it can no longer get the status of the other contacts in the contacts list. Placing and receiving calls still works fine.

It all worked on 1.2.12.1 but not on 1.2.13

It's bristuffed with bristuff-0.3.0-PRE-1v

****** ADDITIONAL INFORMATION ******

Nov  6 21:45:12 WARNING[4430]: chan_sip.c:1090 __sip_xmit: sip_xmit of 0x88c7730 (len 982) to 192.168.9.9:56018 returned -1: Operation not permitted
Retransmitting #4 (NAT) to 80.62.57.166:20268:
NOTIFY sip:sbo@phone.nitramlexa.com SIP/2.0
Via: SIP/2.0/UDP 87.48.198.141:5060;branch=z9hG4bK66671ae1;rport
From: <sip:6373@phone.woffinden.co.uk>;tag=as79820cb6
To: "Claus Bo Hansen"<sip:sbo@phone.nitramlexa.com>;tag=90244601
Contact: <sip:6373@87.48.198.141>
Call-ID: MjVlMWM0MDM3M2M2YmNjMTBhNTMxMGE2ZDRkZTRjNTQ.
CSeq: 110 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: -
Content-Type: unknown
Subscription-State: active
Content-Length: 0
phone*CLI> sip no

---
Nov  6 21:45:15 WARNING[4430]: chan_sip.c:1090 __sip_xmit: sip_xmit of 0x8896ca0 (len 466) to 80.62.57.166:20268 returned -1: Operation not permitted
Retransmitting #4 (NAT) to 80.62.57.166:20268:
NOTIFY sip:sbo@phone.nitramlexa.com SIP/2.0
Via: SIP/2.0/UDP 87.48.198.141:5060;branch=z9hG4bK0eebd824;rport
From: <sip:6373@phone.woffinden.co.uk>;tag=as79820cb6
To: "Claus Bo Hansen"<sip:sbo@phone.nitramlexa.com>;tag=90244601
Contact: <sip:6373@87.48.198.141>
Call-ID: MjVlMWM0MDM3M2M2YmNjMTBhNTMxMGE2ZDRkZTRjNTQ.
CSeq: 111 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: -
Content-Type: unknown
Subscription-State: active
Content-Length: 0
phone*CLI> sip no

---
Nov  6 21:45:15 WARNING[4430]: chan_sip.c:1090 __sip_xmit: sip_xmit of 0x88970b0 (len 466) to 80.62.57.166:20268 returned -1: Operation not permitted
Retransmitting #4 (NAT) to 80.62.57.166:20268:
NOTIFY sip:sbo@phone.nitramlexa.com SIP/2.0
Via: SIP/2.0/UDP 87.48.198.141:5060;branch=z9hG4bK46f547ab;rport
From: <sip:6373@phone.woffinden.co.uk>;tag=as79820cb6
To: "Claus Bo Hansen"<sip:sbo@phone.nitramlexa.com>;tag=90244601
Contact: <sip:6373@87.48.198.141>
Call-ID: MjVlMWM0MDM3M2M2YmNjMTBhNTMxMGE2ZDRkZTRjNTQ.
CSeq: 112 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: -
Content-Type: unknown
Subscription-State: active
Content-Length: 0
phone*CLI> sip no

---
Nov  6 21:45:15 WARNING[4430]: chan_sip.c:1090 __sip_xmit: sip_xmit of 0x88c4e40 (len 466) to 80.62.57.166:20268 returned -1: Operation not permitted
Retransmitting #4 (NAT) to 192.168.9.9:56018:
NOTIFY sip:hw@phone.woffinden.co.uk SIP/2.0
Via: SIP/2.0/UDP 87.48.198.141:5060;branch=z9hG4bK57a55ab2;rport
From: <sip:8008@phone.woffinden.co.uk>;tag=as5de15c0e
To: "Henrik Woffinden"<sip:hw@phone.woffinden.co.uk>;tag=b8139a43
Contact: <sip:8008@87.48.198.141>
Call-ID: NDNmNGM5YTlmZmZkNmZjOGU0M2M3ZDdlMmQzYTEwMTc.
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: presence
Content-Type: application/pidf+xml
Subscription-State: active
Content-Length: 544

<?xml version="1.0" encoding="ISO-8859-1"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf"
xmlns:pp="urn:ietf:params:xml:ns:pidf:person"
xmlns:es="urn:ietf:params:xml:ns:pidf:rpid:status:rpid-status"
xmlns:ep="urn:ietf:params:xml:ns:pidf:rpid:rpid-person"
entity="sip:hw@phone.woffinden.co.uk">
<pp:person><status>
<ep:activities><ep:away/></ep:activities>
</status></pp:person>
<note>Not online</note>
<tuple id="8008">
<contact priority="1">sip:8008@phone.woffinden.co.uk</contact>
<status><basic>closed</basic></status>
</tuple>
</presence>
Comments:By: Olle Johansson (oej) 2006-11-07 02:16:14.000-0600

Nov 6 21:45:15 WARNING[4430]: chan_sip.c:1090 __sip_xmit: sip_xmit of 0x88c4e40 (len 466) to 80.62.57.166:20268 returned -1: Operation not permitted

Indicates some sort of network problem - we're not allowed to send IP to that IP address and port.
By: Henrik Woffinden (hwoffinden) 2006-11-07 02:58:24.000-0600

What makes me think, is that I've changed nothing in either Asterisk config, softphone setup or firewall rules when upgrading from 1.2.12.1 to 1.2.13.

It all worked in 1.2.12.1.

Since I control the firewalls of all locations in this setup, I know that no rules prevent traffic between theese computers.
By: Olle Johansson (oej) 2006-11-07 06:49:57.000-0600

Ok then  - get me a SIP debug with the SUBSCRIBE and the NOTIFY transactions for 1.2.12 and 1.2.13 so I can compare. Thanks.
By: Joshua C. Colp (jcolp) 2006-11-07 12:50:20.000-0600

Out of curiosity as well... if you revert back to your "working" version does it start working again? If not, then you know something else is up.
By: Henrik Woffinden (hwoffinden) 2006-11-08 13:07:14.000-0600

To my big surprice the error appeared again after downgrading to 1.2.12.1

I also tried to disable all "deny" rules in the firewall while testing, but no change.

I've attached a session log when logging Xlite on to 1.2.13.
Since the error also was on 1.2.12.1 I didn't log that... Do you still want a log from there?

(Your real name is Olle, right? If so then we met in Denmark earlier this year at a meeting with our costumer. I'm the technician in the wheelchair)
By: Joshua C. Colp (jcolp) 2006-11-08 13:16:15.000-0600

Are you able to ping the IP address outside of Asterisk? I did some Google research and it looks like the error is indeed caused by a firewall of some sort on the local machine.
By: Henrik Woffinden (hwoffinden) 2006-11-08 16:07:27.000-0600

Yes. I can ping both from client to Asterisk and reverse.

But..... I haven't changed anything in firewall rules from it worked til it didn't...
By: Serge Vecher (serge-v) 2006-11-14 12:16:08.000-0600

hwoffinden: Olle likes to see SIP debug produced per the following:

1) Prepare test environment (reduce the amount of unrelated traffic on the server);
2) Make sure your logger.conf has the following line:
  console => notice,warning,error,debug
3) restart Asterisk with the following command:
  'asterisk -Tvvvvvdddddgc | tee /tmp/verbosedebug.txt'
4) Enable SIP transaction logging with the following CLI commands:
set debug 4
set verbose 4
sip debug
5) Attach verbosedebug.txt to the issue.
By: Serge Vecher (serge-v) 2006-11-14 12:17:05.000-0600

also, please make sure you are testing with an installation from a clean Asterisk tarball -- we do not support third-party patches such as bri-stuff.
By: Olle Johansson (oej) 2006-12-01 03:55:47.000-0600

hwoffinden: There is an error in the first notify packets that I believe I have fixed (reported in other bug reports). The network problem I assume is outside of Asterisk.

Kul att h?ras igen!

/Olle
By: Serge Vecher (serge-v) 2007-01-09 13:10:27.000-0600

please reopen if you are able to reproduce with 1.2.14 without bristuff patches and have an updated debug available.