I am setting up my first SipX box (version 4.0.2 installed as a CentOS
ISO image). I have a trunk set up with Vitelity, and I have a Linksys
SPA942phone and a Grandstream Handytone 486 ATA that can register and
call each other, as well as receive incoming calls from Vitelity.
My server has a fixed address on my internal subnet, which is NATed to
our external ISP through a M0n0Wall firewall. DNS records from the
SipX DNS advisor have been added to our domain's DNS server, and all
of the SIPX diagnostics tests pass except the DHCP option 120 test
(DHCP is provided by a different linux box on the net). On Vitelity,
I ran their setup wizard and told it I was using a "SBC/other switch"
and that I don't have a static address (my network firewall has a
static public address, but not my SIP server).
When I attempt to call out, I get errors, and I have not been able to
resolve them. On the first attempt after a SIPX restart, the phone
displays:
ITSP timed out
On any subsequent attempts, it displays:
invalid number
I have included log entries below that are related to these first and
second attempts. I would appreciate any guidance you could share to
help resolve this problem.
Jeff
proxy log:
"2009-09-21T17:31:54.059270Z":2:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:pid-12066:B7FD46E0:SipXProxy:
"SipRouter::readConfig SIPX_PROXY_AUTHENTICATE_REALM : ev.ithaca.ny.us"
"2009-09-21T17:31:54.083237Z":
3
:KERNEL:WARNING:choicevoip.ev.ithaca.ny.us:OsTimer
-9:B79CDB90:SipXProxy:"OsTimerTask::insertTimer timer to fire 24569
microseconds in the past, queue length = 0"
"2009-09-21T17:31:54.118141Z":
4
:NAT:ERR:choicevoip.ev.ithaca.ny.us:pid
-12066
:B7FD46E0:SipXProxy:"NatTraversalRules::initializeNatTraversalInfo -
No child Node named 'publicaddress', using host IP: '192.168.240.33'"
"2009-09-21T17:31:54.118340Z":
5
:NAT:ERR:choicevoip.ev.ithaca.ny.us:pid
-12066
:B7FD46E0:SipXProxy:"NatTraversalRules::initializeNatTraversalInfo -
No child Node named 'mediarelayexternaladdress', using public IP
address of '192.168.240.33'"
"2009-09-21T17:31:54.118449Z":
6
:NAT:ERR:choicevoip.ev.ithaca.ny.us:pid
-12066
:B7FD46E0:SipXProxy:"NatTraversalRules::initializeNatTraversalInfo -
No child Node named 'secureXMLRPC'"
"2009-09-21T17:31:54.120550Z":7:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:pid-12066:B7FD46E0:SipXProxy:
"SipRouter::SipRouter Skipping IP address-based domain alias
'192.168.240.33'"
"2009-09-21T17:47:26.637249Z":8:SIP:WARNING:choicevoip.ev.ithaca.ny.us:SipRouter-11:B6F43B90:SipXProxy:
"SipUserAgent::send INVITE request matches existing transaction"
"2009-09-21T17:47:26.716653Z":9:SIP:ERR:choicevoip.ev.ithaca.ny.us:SipUserAgent-2:B6E42B90:SipXProxy:
"SipUserAgent::handleMessage SIP message timeout expired with no
matching transaction"
"2009-09-21T18:51:27.030113Z":10:SIP:WARNING:choicevoip.ev.ithaca.ny.us:SipRouter-11:B6F43B90:SipXProxy:
"SipUserAgent::send INVITE request matches existing transaction"
"2009-09-21T18:51:27.102803Z":11:SIP:ERR:choicevoip.ev.ithaca.ny.us:SipUserAgent-2:B6E42B90:SipXProxy:
"SipUserAgent::handleMessage SIP message timeout expired with no
matching transaction"
Registrar:
"2009-09-21T17:33:07.924858Z":2:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:pid-12253:B7F0A6E0:SipRegistrar:
"SipRegistrar::configurePeers SIP_REGISTRAR_SYNC_WITH not set -
replication disabled"
"2009-09-21T17:33:07.933720Z":3:SIP:WARNING:choicevoip.ev.ithaca.ny.us:SipRegistrar:B7486B90:SipRegistrar:
"LocationDB::load failed to load \"/var/sipxdata/sipdb/location.xml\""
"2009-09-21T17:33:07.934801Z":4:SIP:CRIT:choicevoip.ev.ithaca.ny.us:SipRegistrar:B7486B90:SipRegistrar:
"[150-ISN] SipRedirectorISN::readConfig BASE_DOMAIN parameter missing
or empty"
"2009-09-21T17:33:07.935590Z":5:SIP:CRIT:choicevoip.ev.ithaca.ny.us:SipRegistrar:B7486B90:SipRegistrar:
"[160-ENUM] SipRedirectorENUM::readConfig BASE_DOMAIN parameter
missing or empty"
"2009-09-21T17:33:07.940349Z":6:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:SipRegistrar:B7486B90:SipRegistrar:
"BranchId::setSecret reset identifier key; previously generated branch
ids will not be recognized as local."
"2009-09-21T17:33:07.943948Z":7:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:SipRegistrar:B7486B90:SipRegistrar:
"BranchId::setSecret reset identifier key; previously generated branch
ids will not be recognized as local."
"2009-09-21T17:47:26.617418Z":8:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:SipRedirectServer-13:B6678B90:SipRegistrar:
"ContactList::add(): [140-FALLBACK] SipRedirectorFallback added
contact for 'sip:[email protected]':\n '<sip:[email protected]?route=192.168.240.33%3a5090&expires=60
>;q=0.9' (contact index 0)"
"2009-09-21T17:47:26.618038Z":9:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:SipRedirectServer-13:B6678B90:SipRegistrar:
"ContactList::set(): [999-AUTHROUTER] SipRedirectorAuthRouter modified
contact index 0 for 'sip:[email protected]':\n was: '<sip:[email protected]?route=192.168.240.33%3a5090&expires=60
>;q=0.9'\n now is: '<sip:[email protected]?expires=60&ROUTE=%3Csip%3A192.168.240.33%3A5060%3Blr%3E%2C192.168.240.33%3A5090
>;q=0.9'"
"2009-09-21T18:51:27.007826Z":10:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:SipRedirectServer-13:B6678B90:SipRegistrar:
"ContactList::add(): [140-FALLBACK] SipRedirectorFallback added
contact for 'sip:[email protected]':\n '<sip:[email protected]?route=192.168.240.33%3a5090&expires=60
>;q=0.9' (contact index 0)"
"2009-09-21T18:51:27.008200Z":11:SIP:NOTICE:choicevoip.ev.ithaca.ny.us:SipRedirectServer-13:B6678B90:SipRegistrar:
"ContactList::set(): [999-AUTHROUTER] SipRedirectorAuthRouter modified
contact index 0 for 'sip:[email protected]':\n was: '<sip:[email protected]?route=192.168.240.33%3a5090&expires=60
>;q=0.9'\n now is: '<sip:[email protected]?expires=60&ROUTE=%3Csip%3A192.168.240.33%3A5060%3Blr%3E%2C192.168.240.33%3A5090
>;q=0.9'"
Sipxbridge:
"2009-09-21T19:26:45.254000Z":
763
:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-504:00000000:sipXbridge:"Read SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nSIP/2.0 401 Unauthorized\r\nVia: SIP/
2.0/UDP
192.168.240.33
:
5080
;branch
=
z9hG4bK23c348890a2ad99ab28e54b17fb8ddd1313330;received=66.152.115.226\r
\nFrom: <sip:[email protected]>;tag=7489546044574466764\r\nTo: <sip:[email protected]
>;tag=as42a986da\r\nCall-ID:
[email protected]\r\ncseq: 243 REGISTER\r
\nUser-Agent: Asterisk PBX\r\nAllow:
INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY\r\nSupported:
replaces\r\nWWW-Authenticate: Digest algorithm=MD5,realm=\"asterisk
\",nonce=\"7c3d6ad3\"\r\nContent-Length: 0\r\n\r
\n====================END====================\n"
"2009-09-21T19:26:45.264000Z":
764
:OUTGOING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-504:00000000:sipXbridge:"Sent SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nREGISTER sip:vitelity.net SIP/2.0\r
\nCall-ID: [email protected]\r\ncseq:
244 REGISTER\r\nFrom:
<sip:[email protected]>;tag=7489546044574466764\r\nTo: <sip:[email protected]
>\r\nVia: SIP/2.0/UDP
192.168.240.33
:5080;branch=z9hG4bKb8d2d825af7032dadd9149450c1226bc313330\r\nMax-
Forwards: 70\r\nUser-Agent: sipXecs/4.0.2 sipXecs/sipxbridge (Linux)\r
\nAllow: INVITE,BYE,ACK,CANCEL,OPTIONS\r\nRoute: <sip:64.2.142.15:5060;transport=udp;lr
>\r\nContact: <sip:[email protected]:5080;transport=udp>\r
\nExpires: 600\r\nAuthorization: Digest username=\"evinet\",realm=
\"asterisk\",nonce=\"7c3d6ad3\",uri=\"sip:vitelity.net\",response=
\"45d389a3dfb1f12647c04e28bc0a0c1f\",algorithm=MD5\r\nContent-Length:
0\r\n\r\n--------------------END--------------------\n"
"2009-09-21T19:26:45.333000Z":
765
:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-505:00000000:sipXbridge:"Read SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nSIP/2.0 100 Trying\r\nVia: SIP/2.0/UDP
192.168.240.33
:
5080
;branch
=
z9hG4bKb8d2d825af7032dadd9149450c1226bc313330;received=66.152.115.226\r
\nFrom: <sip:[email protected]>;tag=7489546044574466764\r\nTo: <sip:[email protected]
>\r\nCall-ID: [email protected]\r
\nCSeq: 244 REGISTER\r\nUser-Agent: Asterisk PBX\r\nAllow:
INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY\r\nSupported:
replaces\r\nContact: <sip:[email protected]>\r\nContent-Length: 0\r\n
\r\n====================END====================\n"
"2009-09-21T19:26:45.339000Z":
766
:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-506:00000000:sipXbridge:"Read SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nSIP/2.0 200 OK\r\nVia: SIP/2.0/UDP
192.168.240.33
:
5080
;branch
=
z9hG4bKb8d2d825af7032dadd9149450c1226bc313330;received=66.152.115.226\r
\nFrom: <sip:[email protected]>;tag=7489546044574466764\r\nTo: <sip:[email protected]
>;tag=as42a986da\r\nCall-ID:
[email protected]\r\ncseq: 244 REGISTER\r
\nUser-Agent: Asterisk PBX\r\nAllow:
INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY\r\nSupported:
replaces\r\nExpires: 60\r\nContact: <sip:[email protected]:5080;transport=udp
>;expires=60\r\nDate: Mon, 21 Sep 2009 19:26:41 GMT\r\nContent-
Length: 0\r\n\r\n====================END====================\n"
"2009-09-21T19:27:40.350000Z":
767
:OUTGOING:INFO:choicevoip.ev.ithaca.ny.us:Timer
-0:00000000:sipXbridge:"Sent SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nREGISTER sip:vitelity.net SIP/2.0\r
\nCall-ID: [email protected]\r\ncseq:
245 REGISTER\r\nFrom:
<sip:[email protected]>;tag=456322946691187827\r\nTo: <sip:[email protected]
>\r\nVia: SIP/2.0/UDP
192.168.240.33
:5080;branch=z9hG4bK1da4e9709161febd52f49eacbc53d4a1313330\r\nMax-
Forwards: 70\r\nUser-Agent: sipXecs/4.0.2 sipXecs/sipxbridge (Linux)\r
\nAllow: INVITE,BYE,ACK,CANCEL,OPTIONS\r\nRoute: <sip:64.2.142.15:5060;transport=udp;lr
>\r\nContact: <sip:[email protected]:5080;transport=udp>\r
\nExpires: 600\r\nContent-Length: 0\r\n\r\n--------------------
END--------------------\n"
"2009-09-21T19:27:40.409000Z":
768
:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-507:00000000:sipXbridge:"Read SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nSIP/2.0 100 Trying\r\nVia: SIP/2.0/UDP
192.168.240.33
:
5080
;branch
=
z9hG4bK1da4e9709161febd52f49eacbc53d4a1313330;received=66.152.115.226\r
\nFrom: <sip:[email protected]>;tag=456322946691187827\r\nTo: <sip:[email protected]
>\r\nCall-ID: [email protected]\r
\nCSeq: 245 REGISTER\r\nUser-Agent: Asterisk PBX\r\nAllow:
INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY\r\nSupported:
replaces\r\nContact: <sip:[email protected]>\r\nContent-Length: 0\r\n
\r\n====================END====================\n"
"2009-09-21T19:27:40.415000Z":
769
:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-508:00000000:sipXbridge:"Read SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nSIP/2.0 401 Unauthorized\r\nVia: SIP/
2.0/UDP
192.168.240.33
:
5080
;branch
=
z9hG4bK1da4e9709161febd52f49eacbc53d4a1313330;received=66.152.115.226\r
\nFrom: <sip:[email protected]>;tag=456322946691187827\r\nTo: <sip:[email protected]
>;tag=as50b621e6\r\nCall-ID:
[email protected]\r\ncseq: 245 REGISTER\r
\nUser-Agent: Asterisk PBX\r\nAllow:
INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY\r\nSupported:
replaces\r\nWWW-Authenticate: Digest algorithm=MD5,realm=\"asterisk
\",nonce=\"45244b5e\"\r\nContent-Length: 0\r\n\r
\n====================END====================\n"
"2009-09-21T19:27:40.423000Z":
770
:OUTGOING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-508:00000000:sipXbridge:"Sent SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nREGISTER sip:vitelity.net SIP/2.0\r
\nCall-ID: [email protected]\r\ncseq:
246 REGISTER\r\nFrom:
<sip:[email protected]>;tag=456322946691187827\r\nTo: <sip:[email protected]
>\r\nVia: SIP/2.0/UDP
192.168.240.33
:5080;branch=z9hG4bK8998e2ff63548742214864ad15bfe313313330\r\nMax-
Forwards: 70\r\nUser-Agent: sipXecs/4.0.2 sipXecs/sipxbridge (Linux)\r
\nAllow: INVITE,BYE,ACK,CANCEL,OPTIONS\r\nRoute: <sip:64.2.142.15:5060;transport=udp;lr
>\r\nContact: <sip:[email protected]:5080;transport=udp>\r
\nExpires: 600\r\nAuthorization: Digest username=\"evinet\",realm=
\"asterisk\",nonce=\"45244b5e\",uri=\"sip:vitelity.net\",response=
\"afc63b4c04b79af840aeda5b3cde5354\",algorithm=MD5\r\nContent-Length:
0\r\n\r\n--------------------END--------------------\n"
"2009-09-21T19:27:40.481000Z":
771
:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-509:00000000:sipXbridge:"Read SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nSIP/2.0 100 Trying\r\nVia: SIP/2.0/UDP
192.168.240.33
:
5080
;branch
=
z9hG4bK8998e2ff63548742214864ad15bfe313313330;received=66.152.115.226\r
\nFrom: <sip:[email protected]>;tag=456322946691187827\r\nTo: <sip:[email protected]
>\r\nCall-ID: [email protected]\r
\nCSeq: 246 REGISTER\r\nUser-Agent: Asterisk PBX\r\nAllow:
INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY\r\nSupported:
replaces\r\nContact: <sip:[email protected]>\r\nContent-Length: 0\r\n
\r\n====================END====================\n"
"2009-09-21T19:27:40.491000Z":
772
:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:Thread
-510:00000000:sipXbridge:"Read SIP Message :\n----Remote Host:
64.2.142.15---- Port: 5060----\nSIP/2.0 200 OK\r\nVia: SIP/2.0/UDP
192.168.240.33
:
5080
;branch
=
z9hG4bK8998e2ff63548742214864ad15bfe313313330;received=66.152.115.226\r
\nFrom: <sip:[email protected]>;tag=456322946691187827\r\nTo: <sip:[email protected]
>;tag=as50b621e6\r\nCall-ID:
[email protected]\r\ncseq: 246 REGISTER\r
\nUser-Agent: Asterisk PBX\r\nAllow:
INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY\r\nSupported:
replaces\r\nExpires: 60\r\nContact: <sip:[email protected]:5080;transport=udp
>;expires=60\r\nDate: Mon, 21 Sep 2009 19:27:36 GMT\r\nContent-
Length: 0\r\n\r\n====================END====================\n"
[
_______________________________________________
sipx-users mailing list [email protected]
List Archive: http://list.sipfoundry.org/archive/sipx-users
Unsubscribe: http://list.sipfoundry.org/mailman/listinfo/sipx-users
sipXecs IP PBX -- http://www.sipfoundry.org/
