[asterisk-users] lots of registrations, sip problem

Tue Oct 17 10:18:38 MST 2006

Hello,

   I've got a problem with connection to my SIP provider. In general,
everything works, but I get lots of these messages:

Oct 17 19:10:06 DEBUG[29707]: chan_sip.c:11148 handle_request: That's
odd...  Got a response on a call we dont know about. Cseq 42710 Cmd
SIP/2.0

        Oct 17 19:10:06 DEBUG[29707]: chan_sip.c:11148 handle_request:
That's odd...  Got a response on a call we dont know about. Cseq 42686
Cmd SIP/2.0

            Oct 17 19:10:06 DEBUG[29707]: chan_sip.c:11148
handle_request: That's odd...  Got a response on a call we dont know
about. Cseq 42686 Cmd SIP/2.0

   That itself would not be a problem, but my provider is complaining
about lots of faulty registrations. I ran ngrep on that traffic. This
is ngrep output after fresh start of asterisk:

U 200.100.100.123:5060 -> 195.195.12.223:5060
  REGISTER sip:sip1.provider.com SIP/2.0..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK6cb7aba7;rport..From:
<sip:4221917293125 at sip1.gtsne
  xtra.sk>;tag=as04cc5746..To:
<sip:4221917293125 at sip1.provider.com>..Call-ID:
0c74fd93144a92174f3a032d4aeca8dc at 200.100.100.123..CSeq: 102 REGIST
  ER..User-Agent: Asterisk PBX..Max-Forwards: 70..Expires:
1200..Contact: <sip:4221917293125 at 200.100.100.123>..Event:
registration..Content-Lengt
  h: 0....

U 195.195.12.223:5060 -> 200.100.100.123:5060
  SIP/2.0 100 Trying..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK6cb7aba7;received=200.100.100.123;rport=5060..From:
<sip:4221917293125 at sip
  1.provider.sk>;tag=as04cc5746..To:
<sip:4221917293125 at sip1.provider.com>..Call-ID:
0c74fd93144a92174f3a032d4aeca8dc at 200.100.100.123..CSeq: 102
   REGISTER..User-Agent: SoftSwitch v1.0..Allow: INVITE, ACK, CANCEL,
OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY..Contact: <sip:4212326
  60145 at 195.195.12.223>..Content-Length: 0....

U 195.195.12.223:5060 -> 200.100.100.123:5060
  SIP/2.0 401 Unauthorized..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK6cb7aba7;received=200.100.100.123;rport=5060..From:
<sip:4212326601
  45 at sip1.provider.com>;tag=as04cc5746..To:
<sip:4221917293125 at sip1.provider.com>;tag=as17802cd7..Call-ID:
0c74fd93144a92174f3a032d4aeca8dc at 21
  7.67.16.43..CSeq: 102 REGISTER..User-Agent: SoftSwitch v1.0..Allow:
INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY..
  Contact: <sip:4221917293125 at 195.195.12.223>..WWW-Authenticate:
Digest realm="provider", nonce="70495064"..Content-Length: 0....

U 200.100.100.123:5060 -> 195.195.12.223:5060
  REGISTER sip:sip1.provider.com SIP/2.0..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK1362d770;rport..From:
<sip:4221917293125 at sip1.gtsne
  xtra.sk>;tag=as51ddb092..To:
<sip:4221917293125 at sip1.provider.com>..Call-ID:
0c74fd93144a92174f3a032d4aeca8dc at 200.100.100.123..CSeq: 103 REGIST
  ER..User-Agent: Asterisk PBX..Max-Forwards: 70..Authorization:
Digest username="4221917293125", realm="provider", algorithm=MD5,
uri="sip:s
  ip1.provider.sk", nonce="70495064",
response="1c6b0193890a8e1c9c6b3a9287d1d30b", opaque=""..Expires:
1200..Contact: <sip:4221917293125 at 217.
  67.16.43>..Event: registration..Content-Length: 0....

U 195.195.12.223:5060 -> 200.100.100.123:5060
  SIP/2.0 100 Trying..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK1362d770;received=200.100.100.123;rport=5060..From:
<sip:4221917293125 at sip
  1.provider.sk>;tag=as51ddb092..To:
<sip:4221917293125 at sip1.provider.com>..Call-ID:
0c74fd93144a92174f3a032d4aeca8dc at 200.100.100.123..CSeq: 103
   REGISTER..User-Agent: SoftSwitch v1.0..Allow: INVITE, ACK, CANCEL,
OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY..Contact: <sip:4212326
  60145 at 195.195.12.223>..Content-Length: 0....

U 195.195.12.223:5060 -> 200.100.100.123:5060
  SIP/2.0 200 OK..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK1362d770;received=200.100.100.123;rport=5060..From:
<sip:4221917293125 at sip1.gt
  sprovider.sk>;tag=as51ddb092..To:
<sip:4221917293125 at sip1.provider.com>;tag=as17802cd7..Call-ID:
0c74fd93144a92174f3a032d4aeca8dc at 200.100.100.123
  ..CSeq: 103 REGISTER..User-Agent: SoftSwitch v1.0..Allow: INVITE,
ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY..Expires: 1
  200..Contact:
<sip:4221917293125 at 200.100.100.123>;expires=1200..Date: Tue, 17 Oct
2006 16:38:19 GMT..Content-Length: 0....

(******)

U 200.100.100.123:5060 -> 195.195.12.223:5060
  REGISTER sip:my-provider-link SIP/2.0..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK102b43da;rport..From:
<sip:4221917293125 at sip1.gt
  sprovider.sk>;tag=as6550d352..To:
<sip:4221917293125 at sip1.provider.com>..Call-ID:
19c054184a0c40ed4a8f5f194d19fe5a at 200.100.100.123..CSeq: 42620 R
  EGISTER..User-Agent: Asterisk PBX..Max-Forwards: 70..Authorization:
Digest username="4221917293125", realm="provider", algorithm=MD5,
uri="
  sip:my-provider-link", nonce="58c9238d",
response="9d6439e3894f6a7e70fd09d683df073e", opaque=""..Expires:
120..Contact: <sip:4212326601
  45 at 200.100.100.123>..Event: registration..Content-Length: 0....

U 195.195.12.223:5060 -> 200.100.100.123:5060
  SIP/2.0 100 Trying..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK102b43da;received=200.100.100.123;rport=5060..From:
<sip:4221917293125 at sip
  1.provider.sk>;tag=as6550d352..To:
<sip:4221917293125 at sip1.provider.com>..Call-ID:
19c054184a0c40ed4a8f5f194d19fe5a at 200.100.100.123..CSeq: 426
  20 REGISTER..User-Agent: SoftSwitch v1.0..Allow: INVITE, ACK,
CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY..Contact: <sip:42123
  2660145 at 195.195.12.223>..Content-Length: 0....

U 195.195.12.223:5060 -> 200.100.100.123:5060
  SIP/2.0 401 Unauthorized..Via: SIP/2.0/UDP
200.100.100.123:5060;branch=z9hG4bK102b43da;received=200.100.100.123;rport=5060..From:
<sip:4212326601
  45 at sip1.provider.com>;tag=as6550d352..To:
<sip:4221917293125 at sip1.provider.com>;tag=as12748c70..Call-ID:
19c054184a0c40ed4a8f5f194d19fe5a at 21
  7.67.16.43..CSeq: 42620 REGISTER..User-Agent: SoftSwitch
v1.0..Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE,
NOTIFY
  ..Contact: <sip:4221917293125 at 195.195.12.223>..WWW-Authenticate:
Digest realm="provider", nonce="311081ba"..Content-Length: 0....

   Until (******) it seems like a standard registration. Everything
after that is repeated several times a second. I don't know why it
tries to register, when it has clearly already done so. Even sip show
registry shows that provider as registered. Please note the difference
in URI, first time it's sip1.provider.com, second time it's
sip:my-provider-link. This is my sip.conf (relevant parts of it):

register => 4221917293125:secret123 at sip1.provider.com/4221917293125

[my-provider-link]
type=friend
username=4221917293125
secret=secret123
host=sip1.provider.com
nat=no
context=provider-in
canreinvite=no
insecure=very
qualify=no

   It seems to me, that it tries to register also this
my-provider-link somehow, but I have no idea how and why. Just for the
record, I have several accounts of this SIP provider in my sip.conf
(about 10). All other providers are working correctly, this one
(except for these excessive registrations) is working too (all of the
accounts). I've been told by my voice provider, that they are also
using Asterisk on their side.

  I've tried upgrading from Asterisk 1.2.10 to Asterisk 1.2.12.1 and
it did not help. Any ideas or help would be greatly appreciated.

       Juraj Bednar.