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

K Wong kkwong at gmail.com
Thu Dec 9 23:59:57 MST 2004 

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.

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."
> 
>

More information about the asterisk-users mailing list