I installed a caching nameserver but it made no difference.
I then switched logging from syslog to stderr and thats much better and
the phone can register fine.
I have attached the debug from when I tried making the call between
lines. If there is nothing usefull there then can you let me know where
I could put some additional loggin in the config file in order to help.
Thanks
Bogdan-Andrei Iancu wrote:
Hi Gareth,
Gareth Blades wrote:
I am having a problem with running opensips in debug level 6. When
opensips is set to this I am finding that it takes a long time to
respond to register requests (over 5 seconds compared to a fraction of
a second) which means that my phone times out when trying to register
so I cannot then debug trying to send calls through.
woow...that is really strange.....never encountered something like
that....do you log to stderror(console) or to syslog ?
Any ideas?
DNS timeouts are a common cause for these sort of pauses. Are there
any specific DNS setup requirements that opensips has that I might
have missing?
see the DNS related params:
http://www.opensips.org/Resources/DocsCoreFcn#toc41
Also, better use DNS cache app between your opensips and the outer world.
So, your missing INVITE (not being sent out) are because of opensips
blocking in some DNS query ?
Regards,
Bogdan
Thanks
Gareth
Bogdan-Andrei Iancu wrote:
Hi Gareth,
On a first look, the script looks ok, but as a general way to
debug/troubleshoot your script, place xlog()'s in your script, in
different points, to see if the script execution gets to that point.
Regarding the 408 - take care your script does not have NAT support,
so it may not work if your client is behind a NAT.
Regards,
Bogdan
Gareth Blades wrote:
Thanks I will need to get back to you on Monday.
The phone on my desk has just started not being able to register.
Its showing registration status 408 which is no response.
I can see the opensips server receiving the the registration and
issuing a 401 unauthorised but I dont think this is getting back
through the firewall.
I will get some static forwarding added to the firewall to avoid
this sort of issue complicaing investigating the other problem.
In the meantime I have attached my config file.
Bogdan-Andrei Iancu wrote:
Hi Gareth,
looking at the logs, it seams that your script processing (for the
INVITE) never get to a t_relay() point - place some xlog() prints
in your script to see where the INVITE processing is going through.
Regards,
Bogdan
Gareth Blades wrote:
Thanks. I have attached a text file as its a bit long to paste in
a message and there are long lines.
Looking through the log these couple of entries look significant.
DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest
realm="vmopensips1.skycomuk.com",
nonce="4d26db6600000001d69ce33126031746bdf4735e3f0f922d"
...
DBG:tm:matching_3261: RFC3261 transaction matching failed
Bogdan-Andrei Iancu wrote:
Hi Gareth,
hard to say without some logs - do you see errors in the opensips
log ? if not, increase the debug level to 6 and post the logs
corresponding the call.
Regards,
Bogdan
Gareth Blades wrote:
Setup:-
Server installed at a hosting facility with its owm public IP
address.
A desk phone in the office with a couple of accounts configured
registered to opensips through a NAT firewall.
The opensips server has been installed and configured using the
guide in the Opensips PACT book together with database support
(no multidomain).
The two lines on the phone are able to register fine and I can
see them as being registered in the database and 'opensipsctrl
ul show' displays what I would expect.
However when I call between the two lines and minitoring via
ngrep I see the initial INVITE go out and then a '407 proxy
authentication required' comes back and the phone sends the
invite again with the authentication but opensips doesnt send
any reply. The phone retransmits the packet a couple of times
and displays call failed.
Any idea what may be wrong?
I know the call audio is unlikely to work but that will be the
next step. I am just trying to get each step working as I go at
the moment.
Thanks
Gareth
_______________________________________________
_______________________________________________
Users mailing list
[email protected]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Jan 12 16:03:17 [20309] DBG:core:parse_msg: SIP Request:
Jan 12 16:03:17 [20309] DBG:core:parse_msg: method: <INVITE>
Jan 12 16:03:17 [20309] DBG:core:parse_msg: uri:
<sip:[email protected]:5060>
Jan 12 16:03:17 [20309] DBG:core:parse_msg: version: <SIP/2.0>
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=2
Jan 12 16:03:17 [20309] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK305b33da50b0bb55a>; state=16
Jan 12 16:03:17 [20309] DBG:core:parse_via: end of header reached, state=5
Jan 12 16:03:17 [20309] DBG:core:parse_headers: via found, flags=2
Jan 12 16:03:17 [20309] DBG:core:parse_headers: this is the first via
Jan 12 16:03:17 [20309] DBG:core:receive_msg: After parse_msg...
Jan 12 16:03:17 [20309] DBG:core:receive_msg: preparing to run routing
scripts...
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=100
Jan 12 16:03:17 [20309] DBG:maxfwd:is_maxfwd_present: value = 70
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=8
Jan 12 16:03:17 [20309] DBG:core:parse_to: end of header reached, state=10
Jan 12 16:03:17 [20309] DBG:core:parse_to: display={"1001"},
ruri={sip:[email protected]:5060}
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: <To> [49];
uri=[sip:[email protected]:5060]
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: to body ["1001"
<sip:[email protected]:5060>
]
Jan 12 16:03:17 [20309] DBG:uri:has_totag: no totag
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=78
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: cseq <CSeq>: <4671> <INVITE>
Jan 12 16:03:17 [20309] DBG:tm:t_lookup_request: start searching: hash=17063,
isACK=0
Jan 12 16:03:17 [20309] DBG:tm:matching_3261: RFC3261 transaction matching
failed
Jan 12 16:03:17 [20309] DBG:tm:t_lookup_request: no transaction found
Jan 12 16:03:17 [20309] DBG:core:parse_to_param: tag=3da87ae9c5
Jan 12 16:03:17 [20309] DBG:core:parse_to: end of header reached, state=29
Jan 12 16:03:17 [20309] DBG:core:parse_to: display={"1000"},
ruri={sip:[email protected]:5060}
Jan 12 16:03:17 [20309] DBG:core:grep_sock_info: checking if host==us: 24==13
&& [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan 12 16:03:17 [20309] DBG:core:grep_sock_info: checking if port 5060 matches
port 5060
Jan 12 16:03:17 [20309] DBG:core:grep_sock_info: checking if host==us: 24==13
&& [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan 12 16:03:17 [20309] DBG:core:grep_sock_info: checking if port 5060 matches
port 5060
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=10000
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: content_length=595
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: found end of header
Jan 12 16:03:17 [20309] DBG:auth:pre_auth: credentials with given realm not
found
Jan 12 16:03:17 [20309] DBG:auth:reserve_nonce_index: second= 21, sec_monit=
-1, index= 2
Jan 12 16:03:17 [20309] DBG:auth:build_auth_hf: nonce index= 2
Jan 12 16:03:17 [20309] DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest
realm="vmopensips1.skycomuk.com",
nonce="4d2dd0e300000002977a8489c7d0972ef195cbc9144a4f03"
'
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=ffffffffffffffff
Jan 12 16:03:17 [20309] DBG:core:check_ip_address: params 212.84.178.71,
212.84.178.71, 0
Jan 12 16:03:17 [20309] DBG:core:destroy_avp_list: destroying list (nil)
Jan 12 16:03:17 [20309] DBG:core:receive_msg: cleaning up
Jan 12 16:03:17 [20309] DBG:core:parse_msg: SIP Request:
Jan 12 16:03:17 [20309] DBG:core:parse_msg: method: <ACK>
Jan 12 16:03:17 [20309] DBG:core:parse_msg: uri:
<sip:[email protected]:5060>
Jan 12 16:03:17 [20309] DBG:core:parse_msg: version: <SIP/2.0>
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=2
Jan 12 16:03:17 [20309] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK305b33da50b0bb55a>; state=16
Jan 12 16:03:17 [20309] DBG:core:parse_via: end of header reached, state=5
Jan 12 16:03:17 [20309] DBG:core:parse_headers: via found, flags=2
Jan 12 16:03:17 [20309] DBG:core:parse_headers: this is the first via
Jan 12 16:03:17 [20309] DBG:core:receive_msg: After parse_msg...
Jan 12 16:03:17 [20309] DBG:core:receive_msg: preparing to run routing
scripts...
Jan 12 16:03:17 [20309] DBG:core:parse_headers: flags=8
Jan 12 16:03:17 [20309] DBG:core:parse_to_param:
tag=b3addd350d7d55a695048985db4db659.0e06
Jan 12 16:03:17 [20309] DBG:core:parse_to: end of header reached, state=29
Jan 12 16:03:17 [20309] DBG:core:parse_to: display={"1001"},
ruri={sip:[email protected]:5060}
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: <To> [91];
uri=[sip:[email protected]:5060]
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: to body ["1001"
<sip:[email protected]:5060>]
Jan 12 16:03:17 [20309] DBG:sl:sl_filter_ACK: local ACK found -> dropping it!
Jan 12 16:03:17 [20309] DBG:core:destroy_avp_list: destroying list (nil)
Jan 12 16:03:17 [20309] DBG:core:receive_msg: cleaning up
_______________________________________________
Users mailing list
[email protected]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
