RE: Client registers continuously (about once per second) 03.08.2011 23:30

Forum: Bugs

Thanks for looking into this!

connectioncheck is no on all extensions.

In my softphone, there is a setting for the subscribe interval. This is set to 3600. I changed it to 0, which seems to completely suppress all subscribe messages to the server. Since then, the network load has come down significantly.

And yes, I DID mixup subscribe and register - sorry for that, I'm new to this.

This solves my problem for now. I still think that it's a bug that PBXes answers to subscribe messages with expires: 0. This seems to cause my client to sent subscribe messages over and over again. Not that I'm an expert on any of this ... ;-)

Thanks again

Michael

Client registers continuously (about once per second) 01.08.2011 03:09

Forum: Bugs

I experienced permanent traffic between my softphone (Blink Pro on OSX) and pbxes. Looking at some logs, I see that after each successful registration, the client starts to register again immediately.

I can see the same behavior with a remote ATA (OBI-110) that I administer.

It doesn't affect the ability to make calls, but the traffic is noticeable and unnecessary.

Both clients only show this when connecting to pbxes, not when connecting to e.g. Sipgate.

I noticed that in the registration confirmation, pbxes sends "Expires: 0" back to the client - could that be the reason?

Below is part of the system log of pbxes. You can see a complete cycle for the registration, and after success the new registration attempt started immediately by the client.

Is there something that I need to configure, either on the client(s) or pbxes?

Michael

Aug 1 02:38:42 VERBOSE[57463] logger.c:
<-- SIP read
SUBSCRIBE sip:codex22-100@pbxes.org SIP/2.0
Via: SIP/2.0/UDP 192.168.1.132:51787;rport;branch=z9hG4bKPjpdJWMaQETuwpOd3FmYCWtmKd99xtRlJv
Max-Forwards: 70
From: "Michael Baehr" <sip:codex22-100@pbxes.org>;tag=kwlEGUGpaaQXYCLa2EEwnBX0Pfmfbhuu
To: <sip:codex22-100@pbxes.org>
Contact: <sip:xapbgzhr@192.168.1.132:51787>
Call-ID: 4X30vFIW.O5NljHxzxF-SOByKRAreo
CSeq: 634 SUBSCRIBE
Event: message-summary
Expires: 3600
Accept: application/simple-message-summary
Allow-Events: conference, message-summary, presence, presence.winfo, xcap-diff, refer
User-Agent: Blink Pro 1.1.1 (MacOSX)
Content-Length: 0
Aug 1 02:38:42 VERBOSE[57463] logger.c: --- (14 headers 0 lines)Aug 1 02:38:42 VERBOSE[57463] logger.c: --- (14 headers 0 lines)---
Aug 1 02:38:42 VERBOSE[57463] logger.c: Using latest SUBSCRIBE request as basis request
Aug 1 02:38:42 VERBOSE[57463] logger.c: Found peer 'codex22-100'
Aug 1 02:38:42 VERBOSE[57463] logger.c: Transmitting (NAT)
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.132:51787;branch=z9hG4bKPjpdJWMaQETuwpOd3FmYCWtmKd99xtRlJv;received=88.198.69.250;rport=56968
From: "Michael Baehr" <sip:codex22-100@pbxes.org>;tag=kwlEGUGpaaQXYCLa2EEwnBX0Pfmfbhuu
To: <sip:codex22-100@pbxes.org>;tag=as09854cd4
Call-ID: 4X30vFIW.O5NljHxzxF-SOByKRAreo
CSeq: 634 SUBSCRIBE
User-Agent: PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:codex22-100@88.198.69.250:30405>
WWW-Authenticate: Digest realm="pbxes.org", nonce="07f8aa9219abf33155bef8d3346ea204726e9a36"
Content-Length: 0
---
Aug 1 02:38:42 VERBOSE[57463] logger.c: Scheduling destruction of call '4X30vFIW.O5NljHxzxF-SOByKRAreo' in 15000 ms
Aug 1 02:38:42 VERBOSE[57463] logger.c:
<-- SIP read
SUBSCRIBE sip:codex22-100@pbxes.org SIP/2.0
Via: SIP/2.0/UDP 192.168.1.132:51787;rport;branch=z9hG4bKPjg2667-QGwYn27DEuz-1XdDNBPrZeloZW
Max-Forwards: 70
From: "Michael Baehr" <sip:codex22-100@pbxes.org>;tag=kwlEGUGpaaQXYCLa2EEwnBX0Pfmfbhuu
P-src-ip: 87.158.139.53
To: <sip:codex22-100@pbxes.org>
Contact: <sip:xapbgzhr@192.168.1.132:51787>
Call-ID: 4X30vFIW.O5NljHxzxF-SOByKRAreo
CSeq: 635 SUBSCRIBE
Event: message-summary
Expires: 3600
Accept: application/simple-message-summary
Allow-Events: conference, message-summary, presence, presence.winfo, xcap-diff, refer
User-Agent: Blink Pro 1.1.1 (MacOSX)
Authorization: Digest username="codex22-100", realm="pbxes.org", nonce="07f8aa9219abf33155bef8d3346ea204726e9a36", uri="sip:codex22-100@pbxes.org", response="6ed3b5c43a9026c879374c8fe7b25553"
Content-Length: 0
Aug 1 02:38:42 VERBOSE[57463] logger.c: --- (16 headers 0 lines)Aug 1 02:38:42 VERBOSE[57463] logger.c: --- (16 headers 0 lines)---
Aug 1 02:38:42 VERBOSE[57463] logger.c: Found peer 'codex22-100'
Aug 1 02:38:42 VERBOSE[57463] logger.c: Looking for codex22-100 in from-internal-cont (domain pbxes.org)
Aug 1 02:38:42 VERBOSE[57463] logger.c: Transmitting (NAT)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.132:51787;branch=z9hG4bKPjg2667-QGwYn27DEuz-1XdDNBPrZeloZW;received=88.198.69.250;rport=56968
From: "Michael Baehr" <sip:codex22-100@pbxes.org>;tag=kwlEGUGpaaQXYCLa2EEwnBX0Pfmfbhuu
To: <sip:codex22-100@pbxes.org>;tag=as09854cd4
Call-ID: 4X30vFIW.O5NljHxzxF-SOByKRAreo
CSeq: 635 SUBSCRIBE
User-Agent: PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Expires: 0
Content-Length: 0
---
Aug 1 02:38:42 VERBOSE[57463] logger.c: Destroying call '4X30vFIW.O5NljHxzxF-SOByKRAreo'

==== Here we start again 1 second later! ===

Aug 1 02:38:43 VERBOSE[57463] logger.c:
<-- SIP read
SUBSCRIBE sip:codex22-100@pbxes.org SIP/2.0
Via: SIP/2.0/UDP 192.168.1.132:51787;rport;branch=z9hG4bKPjgqI8GXUa477IJrnR.Pc0hxhXDHD.yfEV
Max-Forwards: 70
From: "Michael Baehr" <sip:codex22-100@pbxes.org>;tag=YgenIhrFFHNldqwUDdQ.VyL3y4N6nzI2
P-src-ip: 87.158.139.53
To: <sip:codex22-100@pbxes.org>
Contact: <sip:xapbgzhr@192.168.1.132:51787>
Call-ID: oYwKZXrtgyCyTNeyVRmY3O2-VRIGb-am
CSeq: 19332 SUBSCRIBE
Event: message-summary
Expires: 3600
Accept: application/simple-message-summary
Allow-Events: conference, message-summary, presence, presence.winfo, xcap-diff, refer
User-Agent: Blink Pro 1.1.1 (MacOSX)
Content-Length: 0
Aug 1 02:38:43 VERBOSE[57463] logger.c: --- (15 headers 0 lines)Aug 1 02:38:43 VERBOSE[57463] logger.c: --- (15 headers 0 lines)---
Aug 1 02:38:43 VERBOSE[57463] logger.c: Using latest SUBSCRIBE request as basis request
Aug 1 02:38:43 VERBOSE[57463] logger.c: Found peer 'codex22-100'
Aug 1 02:38:43 VERBOSE[57463] logger.c: Transmitting (NAT)
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.132:51787;branch=z9hG4bKPjgqI8GXUa477IJrnR.Pc0hxhXDHD.yfEV;received=88.198.69.250;rport=56968
From: "Michael Baehr" <sip:codex22-100@pbxes.org>;tag=YgenIhrFFHNldqwUDdQ.VyL3y4N6nzI2
To: <sip:codex22-100@pbxes.org>;tag=as28b2f15f
Call-ID: oYwKZXrtgyCyTNeyVRmY3O2-VRIGb-am
CSeq: 19332 SUBSCRIBE
User-Agent: PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:codex22-100@88.198.69.250:30405>
WWW-Authenticate: Digest realm="pbxes.org", nonce="163059455cbaba90297a914c5ceb83177d0c1127"
Content-Length: 0