[Asterisk-Users] Asterisk started but doesn't register SIP client

Howard Lowndes lannet at lannet.com.au
Fri Dec 10 01:22:05 MST 2004 

On Fri, 2004-12-10 at 01:59, K Wong wrote:
> Sorry for the last resend.  Somehow I got a bounced message, so I
> thought it wasn't delivered.
> 
> Ok, with the sip debug, I see the following, does it mean my NAT
> setting is wrong? (my Sipura is behind a router), or is that my
> sip.conf setting is wrong?  Please help.
> 
I'm seeing a "401 Unauthorised", which would be the problem.  You need
to check out what might be causing that.  Have you got the user agents
listed in sip.conf.


> Andy
> 
> Sip read:
> NOTIFY sip:sip.domain.tld SIP/2.0
> Via: SIP/2.0/UDP 192.168.0.218:5060;branch=z9hG4bK-89e5c71e;rport
> From: Andy Wong <sip:2203 at sip.domain.tld>;tag=e6cf368bf33afa50
> To: <sip:sip.domain.tld>
> Call-ID: 9b62d15b-44979dc0 at 192.168.0.218
> CSeq: 52 NOTIFY
> Max-Forwards: 70
> Event: keep-alive
> User-Agent: Sipura/SPA2000-2.0.9(d)
> Content-Length: 0
> 
> 
> 10 headers, 0 lines
> Transmitting (no NAT):
> SIP/2.0 200 OK
> Via: SIP/2.0/UDP 192.168.0.218:5060;branch=z9hG4bK-89e5c71e
> From: Andy Wong <sip:2203 at sip.domain.tld>;tag=e6cf368bf33afa50
> To: <sip:sip.domain.tld>;tag=as3cd508a7
> Call-ID: 9b62d15b-44979dc0 at 192.168.0.218
> CSeq: 52 NOTIFY
> User-Agent: Asterisk PBX
> Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
> Contact:
> Content-Length: 0
> 
> 
>  to 64.230.132.119:55409
> Destroying call '9b62d15b-44979dc0 at 192.168.0.218'
> sip*CLI>
> 
> Sip read:
> REGISTER sip:sip.domain.tld SIP/2.0
> Via: SIP/2.0/UDP 192.168.0.218:5060;branch=z9hG4bK-dc203171;rport
> From: Andy Wong <sip:2203 at sip.domain.tld>;tag=e6cf368bf33afa50
> To: Andy Wong <sip:2203 at sip.domain.tld>
> Call-ID: 950c71b7-7fa51914 at 192.168.0.218
> CSeq: 32 REGISTER
> Max-Forwards: 70
> Authorization: Digest
> username="2203",realm="asterisk",nonce="2759623e",uri="sip:2203 at sip.domain.tld",algorithm=MD5,respo
> nse="e68901143fa66ff1c58f5809c137b09d"
> Contact: Andy Wong <sip:2203 at 64.230.132.119:55409>;expires=3600
> User-Agent: Sipura/SPA2000-2.0.9(d)
> Content-Length: 0
> Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
> Supported: x-sipura
> 
> 
> 13 headers, 0 lines
> Using latest request as basis request
> Sending to 192.168.0.218 : 5060 (non-NAT)
> Transmitting (NAT):
> SIP/2.0 100 Trying
> Via: SIP/2.0/UDP
> 192.168.0.218:5060;branch=z9hG4bK-dc203171;received=64.230.132.119;rport=55409
> From: Andy Wong <sip:2203 at sip.domain.tld>;tag=e6cf368bf33afa50
> To: Andy Wong <sip:2203 at sip.domain.tld>;tag=as776abf4d
> Call-ID: 950c71b7-7fa51914 at 192.168.0.218
> CSeq: 32 REGISTER
> User-Agent: Asterisk PBX
> Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
> Contact: <sip:2203@###.###.###.###>
> Content-Length: 0
> 
> 
>  to 64.230.132.119:55409
> Transmitting (NAT):
> SIP/2.0 401 Unauthorized
> Via: SIP/2.0/UDP
> 192.168.0.218:5060;branch=z9hG4bK-dc203171;received=64.230.132.119;rport=55409
> From: Andy Wong <sip:2203 at sip.domain.tld>;tag=e6cf368bf33afa50
> To: Andy Wong <sip:2203 at sip.domain.tld>;tag=as776abf4d
> Call-ID: 950c71b7-7fa51914 at 192.168.0.218
> CSeq: 32 REGISTER
> User-Agent: Asterisk PBX
> Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
> Contact: <sip:2203@###.###.###.###>
> WWW-Authenticate: Digest realm="asterisk", nonce="1bfb5889"
> Content-Length: 0
> 
> 
>  to 64.230.132.119:55409
> Scheduling destruction of call '950c71b7-7fa51914 at 192.168.0.218' in 15000 ms
> sip*CLI>
> 
> Sip read:
> REGISTER sip:sip.domain.tld SIP/2.0
> Via: SIP/2.0/UDP 192.168.0.218:5060;branch=z9hG4bK-18384b9a;rport
> From: Andy Wong <sip:2203 at sip.domain.tld>;tag=e6cf368bf33afa50
> To: Andy Wong <sip:2203 at sip.domain.tld>
> Call-ID: 950c71b7-7fa51914 at 192.168.0.218
> CSeq: 33 REGISTER
> Max-Forwards: 70
> Authorization: Digest
> username="2203",realm="asterisk",nonce="1bfb5889",uri="sip:2203 at sip.domain.tld",algorithm=MD5,respo
> nse="640a8dbeccc79d3ab6f6ef52b5033830"
> Contact: Andy Wong <sip:2203 at 64.230.132.119:55409>;expires=3600
> User-Agent: Sipura/SPA2000-2.0.9(d)
> Content-Length: 0
> Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
> Supported: x-sipura
> 
> 
> 13 headers, 0 lines
> Using latest request as basis request
> Sending to 192.168.0.218 : 5060 (NAT)
> Transmitting (NAT):
> SIP/2.0 100 Trying
> Via: SIP/2.0/UDP
> 192.168.0.218:5060;branch=z9hG4bK-18384b9a;received=64.230.132.119;rport=55409
> From: Andy Wong <sip:2203 at sip.domain.tld>;tag=e6cf368bf33afa50
> To: Andy Wong <sip:2203 at sip.domain.tld>;tag=as776abf4d
> Call-ID: 950c71b7-7fa51914 at 192.168.0.218
> CSeq: 33 REGISTER
> User-Agent: Asterisk PBX
> Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
> Contact: <sip:2203@###.###.###.###>
> Content-Length: 0
> 
> 
>  to 64.230.132.119:55409
> 
> 
> On Fri, 10 Dec 2004 01:46:20 -0500, Howard Lowndes <lannet at lannet.com.au> wrote:
> > Try doing a "sip debug" and see what happens to the SIP stream.
> > 
> > 
> > 
> > On Fri, 2004-12-10 at 01:41, K Wong wrote:
> > > Hi:
> > >
> > > We just setup the Asterisk and it seems to start ok.  We checked the
> > > log, and beside the timer warning, there isn't other error message.
> > > However, we tried both SIPURA and XLite, but their registration is not
> > > accepted (timed out and failed).
> > >
> > > Could someone tell me what's wrong?
> > >
> > > [message]
> > > Dec 10 01:33:22 WARNING[2649]: Unable to open IAX timing interface:
> > > Permission denied
> > > Dec 10 01:33:23 WARNING[2649]: Unable to open Asterisk database
> > > Dec 10 01:33:23 WARNING[2649]: Unable to open Asterisk database
> > > Dec 10 01:33:23 WARNING[2649]: Unable to open Asterisk database
> > > Dec 10 01:33:23 WARNING[2649]: Unable to open Asterisk database
> > >
> > >
> > > [debug]
> > > Dec 10 01:33:22 DEBUG[2649]: VM Review Option disabled globally
> > > Dec 10 01:33:22 DEBUG[2649]: VM Operator break disabled globally
> > > Dec 10 01:33:22 DEBUG[2649]: VM CID Info before msg disabled globally
> > > Dec 10 01:33:22 DEBUG[2649]: Send Voicemail msg disabled globally
> > > Dec 10 01:33:22 DEBUG[2649]: ENVELOPE before msg enabled globally
> > > Dec 10 01:33:22 DEBUG[2649]: We are not going to skip to the next msg
> > > after save/delete
> > > Dec 10 01:33:22 DEBUG[2649]:
> > > 216.207.245.47/255.255.255.255/255.255.255.255 appended to acl for
> > > peer
> > > Dec 10 01:33:22 DEBUG[2649]:
> > > 69.73.19.178/255.255.255.255/255.255.255.255 appended to acl for peer
> > > Dec 10 01:33:23 DEBUG[2649]:
> > > 216.207.245.47/255.255.255.255/255.255.255.255 appended to acl for
> > > peer
> > > Dec 10 01:33:23 DEBUG[2649]:
> > > 69.73.19.178/255.255.255.255/255.255.255.255 appended to acl for peer
> > > Dec 10 01:33:23 DEBUG[2649]: Scheduled a timeout # 1
> > > Dec 10 01:33:23 DEBUG[2661]: Stopping retransmission on
> > > '366b24810a0bb91355fbb4ba7d9a27ef at 127.0.0.1' of Request 102: Found
> > > Dec 10 01:33:23 DEBUG[2661]: Setting NAT on RTP to 4
> > > Dec 10 01:33:23 DEBUG[2661]: Stopping retransmission on
> > > '366b24810a0bb91355fbb4ba7d9a27ef at 127.0.0.1' of Request 103: Found
> > > Dec 10 01:33:23 DEBUG[2661]: Registration successful
> > > Dec 10 01:33:23 DEBUG[2661]: Cancelling timeout 1
> > > Dec 10 01:33:23 DEBUG[2661]: Setting NAT on RTP to 4
> > > Dec 10 01:33:23 DEBUG[2649]: cdr_mysql: got hostname of localhost
> > > Dec 10 01:33:23 DEBUG[2649]: cdr_mysql: got port of 3306
> > > Dec 10 01:33:23 DEBUG[2649]: cdr_mysql: got sock file of
> > > /var/lib/mysql/mysql.sock
> > > Dec 10 01:33:23 DEBUG[2649]: cdr_mysql: got user of XXXXXXX
> > > Dec 10 01:33:23 DEBUG[2649]: cdr_mysql: got dbname of XXXXXX
> > > Dec 10 01:33:23 DEBUG[2649]: cdr_mysql: got password of XXXXXX
> > > Dec 10 01:33:23 DEBUG[2649]: Successfully connected to MySQL database.
> > > Dec 10 01:33:39 DEBUG[2661]: Auto destroying call
> > > '950c71b7-7fa51914 at 192.168.0.218'
> > >
> > > [full]
> > > ....................
> > >
> > > Dec 10 01:33:23 DEBUG[2649]: Successfully connected to MySQL database.
> > > Dec 10 01:33:23 VERBOSE[2649]:
> > > [app_callingcard.so]Dec 10 01:33:23 VERBOSE[2649]:
> > >  [app_callingcard.so] => (Calling Card
> > > Application)
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing
> > > '/etc/asterisk/callingcard.conf': Dec 10 01:33:23 VERBOSE[2649]:   ==
> > > Parsing '/etc/asterisk/callingcard.conf': Found
> > > Dec 10 01:33:23 VERBOSE[2649]:
> > > [app_prepaid_auth_cid.so]Dec 10 01:33:23
> > > VERBOSE[2649]:  [app_prepaid_auth_cid.so] =>
> > > ( PrepaidAuthCID(Destination,Tariffplan): Returns the Tariff
> > > rate for th)
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing
> > > '/etc/asterisk/prepaid.conf': Dec 10 01:33:23 VERBOSE[2649]:   ==
> > > Parsing '/etc/asterisk/prepaid.conf': Found
> > > Dec 10 01:33:23 VERBOSE[2649]:
> > > [app_prepaid_rate.so]Dec 10 01:33:23
> > > VERBOSE[2649]:  [app_prepaid_rate.so] => (
> > > PrepaidRate(Destination,Tariffplan): Returns the Tariff rate for the
> > > d)
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing
> > > '/etc/asterisk/prepaid.conf': Dec 10 01:33:23 VERBOSE[2649]:   ==
> > > Parsing '/etc/asterisk/prepaid.conf': Found
> > > Dec 10 01:33:23 VERBOSE[2649]:
> > > [app_prepaid_rateplan.so]Dec 10 01:33:23
> > > VERBOSE[2649]:  [app_prepaid_rateplan.so] =>
> > > ( PrepaidRatePlan(User): Returns the Tariff plan for the
> > > specified User.)
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing
> > > '/etc/asterisk/prepaid.conf': Dec 10 01:33:23 VERBOSE[2649]:   ==
> > > Parsing '/etc/asterisk/prepaid.conf': Found
> > > Dec 10 01:33:23 WARNING[2649]: Unable to open Asterisk database
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing '/etc/asterisk/enum.conf':
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing '/etc/asterisk/enum.conf':
> > > Found
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing
> > > '/etc/asterisk/extconfig.conf': Dec 10 01:33:23 VERBOSE[2649]:   ==
> > > Parsing '/etc/asterisk/extconfig.conf': Found
> > > Dec 10 01:33:23 VERBOSE[2649]: Asterisk Event Logger restarted
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing
> > > '/etc/asterisk/manager.conf': Dec 10 01:33:23 VERBOSE[2649]:   ==
> > > Parsing '/etc/asterisk/manager.conf': Found
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing '/etc/asterisk/enum.conf':
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing '/etc/asterisk/enum.conf':
> > > Found
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing '/etc/asterisk/rtp.conf':
> > > Dec 10 01:33:23 VERBOSE[2649]:   == Parsing '/etc/asterisk/rtp.conf':
> > > Found
> > > Dec 10 01:33:23 VERBOSE[2649]:   == RTP Allocating from port range
> > > 10000 -> 20000
> > > Dec 10 01:33:23 VERBOSE[2649]: Asterisk Ready.
> > > Dec 10 01:33:39 DEBUG[2661]: Auto destroying call
> > > '950c71b7-7fa51914 at 192.168.0.218'
> > > -----------------------------------------------------------------------------------------------------------------
> > > Note that the 192.168.0.218' is our SIPURA
> > >
> > > Thanks.
> > >
> > > Andy
> > > _______________________________________________
> > > Asterisk-Users mailing list
> > > Asterisk-Users at lists.digium.com
> > > http://lists.digium.com/mailman/listinfo/asterisk-users
> > > To UNSUBSCRIBE or update options visit:
> > >    http://lists.digium.com/mailman/listinfo/asterisk-users
> > --
> > Howard.
> > LANNet Computing Associates;
> > Your Linux people <http://www.lannetlinux.com>
> > ------------------------------------------
> > "When you just want a system that works, you choose Linux;
> > when you want a system that just works, you choose Microsoft."
> > ------------------------------------------
> > "Flatter government, not fatter government;
> > Get rid of the Australian states."
> > 
> >
> _______________________________________________
> Asterisk-Users mailing list
> Asterisk-Users at lists.digium.com
> http://lists.digium.com/mailman/listinfo/asterisk-users
> To UNSUBSCRIBE or update options visit:
>    http://lists.digium.com/mailman/listinfo/asterisk-users
-- 
Howard.
LANNet Computing Associates;
Your Linux people <http://www.lannetlinux.com>
------------------------------------------
"When you just want a system that works, you choose Linux;
when you want a system that just works, you choose Microsoft."
------------------------------------------
"Flatter government, not fatter government;
Get rid of the Australian states."

More information about the asterisk-users mailing list