new install and INVITES not being forwarded

classic Classic list List threaded Threaded
14 messages Options
Reply | Threaded
Open this post in threaded view
|

new install and INVITES not being forwarded

Gareth Blades
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
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Bogdan-Andrei Iancu
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
> [hidden email]
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>


--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami,  USA
www.voice-system.ro


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Gareth Blades
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
>> [hidden email]
>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>
>
>

Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg: SIP Request:
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg:  method:  <INVITE>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg:  uri:     <sip:[hidden email]:5060>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg:  version: <SIP/2.0>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=2
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK1f688db07e3542d3a>; state=16
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_via: end of header reached, state=5
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: via found, flags=2
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: this is the first via
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:receive_msg: After parse_msg...
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:receive_msg: preparing to run routing scripts...
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=100
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:maxfwd:is_maxfwd_present: value = 70  
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=8
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to: end of header reached, state=10
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to: display={"1001"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: <To> [49]; uri=[sip:[hidden email]:5060]  
 ]   7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]:5060>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:uri:has_totag: no totag
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=78
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: cseq <CSeq>: <6436> <INVITE>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:tm:t_lookup_request: start searching: hash=16284, isACK=0
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_msg: SIP Request:
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:tm:t_lookup_request: no transaction found
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_msg:  method:  <INVITE>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to_param: tag=6392ae5f12
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_msg:  uri:     <sip:[hidden email]:5060>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to: end of header reached, state=29
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_msg:  version: <SIP/2.0>
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to: display={"1000"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:09 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: flags=2
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK1f688db07e3542d3a>; state=16
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_via: end of header reached, state=5
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: via found, flags=2
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: this is the first via
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:receive_msg: After parse_msg...
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:receive_msg: preparing to run routing scripts...
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_msg: SIP Request:
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: flags=100
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_msg:  method:  <INVITE>
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:maxfwd:is_maxfwd_present: value = 70  
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_msg:  uri:     <sip:[hidden email]:5060>
Jan  7 09:22:10 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: flags=8
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_msg:  version: <SIP/2.0>
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_to: end of header reached, state=10
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: flags=2
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_to: display={"1001"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20835]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK1f688db07e3542d3a>; state=16
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20836]: DBG:core:get_hdr_field: <To> [49]; uri=[sip:[hidden email]:5060]  
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=10000
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_via: end of header reached, state=5
 ]   7 09:22:11 vmopensips1 /sbin/opensips[20836]: DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]:5060>
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: content_length=595
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: via found, flags=2
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20836]: DBG:uri:has_totag: no totag
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: found end of header
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: this is the first via
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: flags=78
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20835]: DBG:auth:pre_auth: credentials with given realm not found
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20837]: DBG:core:receive_msg: After parse_msg...
Jan  7 09:22:11 vmopensips1 /sbin/opensips[20836]: DBG:core:get_hdr_field: cseq <CSeq>: <6436> <INVITE>
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20835]: DBG:auth:reserve_nonce_index: second= 1, sec_monit= -1,  index= 0
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20837]: DBG:core:receive_msg: preparing to run routing scripts...
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20836]: DBG:tm:t_lookup_request: start searching: hash=16284, isACK=0
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20835]: DBG:auth:build_auth_hf: nonce index= 0
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: flags=100
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20836]: DBG:tm:matching_3261: RFC3261 transaction matching failed
 '   7 09:22:12 vmopensips1 /sbin/opensips[20835]: DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest realm="vmopensips1.skycomuk.com", nonce="4d26db62000000005a481811987f903105543127c6b1e8ff"
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20837]: DBG:maxfwd:is_maxfwd_present: value = 70  
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20836]: DBG:tm:t_lookup_request: no transaction found
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=ffffffffffffffff
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: flags=8
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_to_param: tag=6392ae5f12
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20835]: DBG:core:check_ip_address: params 212.84.170.85, 212.84.170.85, 0
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_msg: SIP Request:
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_to: end of header reached, state=10
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_to: end of header reached, state=29
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20835]: DBG:core:destroy_avp_list: destroying list (nil)
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_msg:  method:  <INVITE>
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_to: display={"1001"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_to: display={"1000"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20835]: DBG:core:receive_msg: cleaning up
Jan  7 09:22:12 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_msg:  uri:     <sip:[hidden email]:5060>
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20837]: DBG:core:get_hdr_field: <To> [49]; uri=[sip:[hidden email]:5060]  
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg: SIP Request:
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_msg:  version: <SIP/2.0>
 ]   7 09:22:13 vmopensips1 /sbin/opensips[20837]: DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]:5060>
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg:  method:  <ACK>
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: flags=2
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20837]: DBG:uri:has_totag: no totag
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg:  uri:     <sip:[hidden email]:5060>
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK1f688db07e3542d3a>; state=16
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: flags=78
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_msg:  version: <SIP/2.0>
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_via: end of header reached, state=5
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20837]: DBG:core:get_hdr_field: cseq <CSeq>: <6436> <INVITE>
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan  7 09:22:13 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=2
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: via found, flags=2
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20837]: DBG:tm:t_lookup_request: start searching: hash=16284, isACK=0
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK1f688db07e3542d3a>; state=16
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: this is the first via
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20837]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_via: end of header reached, state=5
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20838]: DBG:core:receive_msg: After parse_msg...
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20837]: DBG:tm:t_lookup_request: no transaction found
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: via found, flags=2
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20838]: DBG:core:receive_msg: preparing to run routing scripts...
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_to_param: tag=6392ae5f12
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: this is the first via
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: flags=100
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_to: end of header reached, state=29
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20835]: DBG:core:receive_msg: After parse_msg...
Jan  7 09:22:14 vmopensips1 /sbin/opensips[20838]: DBG:maxfwd:is_maxfwd_present: value = 70  
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_to: display={"1000"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20835]: DBG:core:receive_msg: preparing to run routing scripts...
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: flags=8
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20836]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20835]: DBG:sl:sl_filter_ACK: to late to be a local ACK!
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_to: end of header reached, state=10
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: flags=10000
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=100
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_to: display={"1001"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20836]: DBG:core:get_hdr_field: content_length=595
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20835]: DBG:maxfwd:is_maxfwd_present: value = 70  
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20838]: DBG:core:get_hdr_field: <To> [49]; uri=[sip:[hidden email]:5060]  
Jan  7 09:22:15 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20836]: DBG:core:get_hdr_field: found end of header
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=8
 ]   7 09:22:16 vmopensips1 /sbin/opensips[20838]: DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]:5060>
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20836]: DBG:auth:pre_auth: credentials with given realm not found
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to_param: tag=c97b4d1cb1f3d0da549e06a8d482ef63.5f56
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20838]: DBG:uri:has_totag: no totag
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20836]: DBG:auth:reserve_nonce_index: second= 6, sec_monit= -1,  index= 1
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to: end of header reached, state=29
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: flags=78
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20836]: DBG:auth:build_auth_hf: nonce index= 1
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_to: display={"1001"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20838]: DBG:core:get_hdr_field: cseq <CSeq>: <6436> <INVITE>
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
 '   7 09:22:16 vmopensips1 /sbin/opensips[20836]: DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest realm="vmopensips1.skycomuk.com", nonce="4d26db6600000001d69ce33126031746bdf4735e3f0f922d"
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: <To> [91]; uri=[sip:[hidden email]:5060]  
Jan  7 09:22:16 vmopensips1 /sbin/opensips[20838]: DBG:tm:t_lookup_request: start searching: hash=16284, isACK=0
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20836]: DBG:core:parse_headers: flags=ffffffffffffffff
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]:5060>]
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20838]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20836]: DBG:core:check_ip_address: params 212.84.170.85, 212.84.170.85, 0
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20835]: DBG:uri:has_totag: totag found
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20838]: DBG:tm:t_lookup_request: no transaction found
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20836]: DBG:core:destroy_avp_list: destroying list (nil)
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=200
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_to_param: tag=6392ae5f12
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20837]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20836]: DBG:core:receive_msg: cleaning up
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: cseq <CSeq>: <6436> <ACK>
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_to: end of header reached, state=29
Jan  7 09:22:17 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: flags=10000
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: content_length=0
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_to: display={"1000"}, ruri={sip:[hidden email]:5060}
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20837]: DBG:core:get_hdr_field: content_length=595
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20835]: DBG:core:get_hdr_field: found end of header
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20837]: DBG:core:get_hdr_field: found end of header
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20835]: DBG:rr:find_first_route: No Route headers found
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20837]: DBG:auth:pre_auth: credentials with given realm not found
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20835]: DBG:rr:loose_route: There is no Route HF
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20837]: DBG:auth:reserve_nonce_index: second= 8, sec_monit= -1,  index= 2
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20835]: DBG:core:parse_headers: flags=78
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20837]: DBG:auth:build_auth_hf: nonce index= 2
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20835]: DBG:tm:t_lookup_request: start searching: hash=16284, isACK=1
Jan  7 09:22:18 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
 '   7 09:22:19 vmopensips1 /sbin/opensips[20837]: DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest realm="vmopensips1.skycomuk.com", nonce="4d26db6900000002df907eceab9018f0d8b6f41664c24279"
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20835]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20837]: DBG:core:parse_headers: flags=ffffffffffffffff
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20835]: DBG:tm:t_lookup_request: no transaction found
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if host==us: 24==9 &&  [vmopensips1.skycomuk.com] == [127.0.0.1]
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20837]: DBG:core:check_ip_address: params 212.84.170.85, 212.84.170.85, 0
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20835]: DBG:core:destroy_avp_list: destroying list (nil)
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20837]: DBG:core:destroy_avp_list: destroying list (nil)
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20835]: DBG:core:receive_msg: cleaning up
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if host==us: 24==11 &&  [vmopensips1.skycomuk.com] == [10.150.0.33]
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20837]: DBG:core:receive_msg: cleaning up
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan  7 09:22:19 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: flags=10000
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:core:get_hdr_field: content_length=595
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:core:get_hdr_field: found end of header
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:auth:pre_auth: credentials with given realm not found
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:auth:reserve_nonce_index: second= 10, sec_monit= -1,  index= 3
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:auth:build_auth_hf: nonce index= 3
 '   7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest realm="vmopensips1.skycomuk.com", nonce="4d26db6a0000000330a51f25bde49628253cc14e3458c505"
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:core:parse_headers: flags=ffffffffffffffff
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:core:check_ip_address: params 212.84.170.85, 212.84.170.85, 0
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:core:destroy_avp_list: destroying list (nil)
Jan  7 09:22:20 vmopensips1 /sbin/opensips[20838]: DBG:core:receive_msg: cleaning up
_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Bogdan-Andrei Iancu
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
>>> [hidden email]
>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>
>>
>
> ------------------------------------------------------------------------
>
> _______________________________________________
> Users mailing list
> [hidden email]
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users


--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami,  USA
www.voice-system.ro


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Gareth Blades
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
>>>> [hidden email]
>>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>>
>>>
>>>
>>
>> ------------------------------------------------------------------------
>>
>> _______________________________________________
>> Users mailing list
>> [hidden email]
>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>
>

#
# $Id: opensips.cfg 7027 2010-07-15 13:48:29Z razvancrainea $
#
# OpenSIPS basic configuration script
#     by Anca Vamanu <[hidden email]>
#
# Please refer to the Core CookBook at:
#      http://www.opensips.org/index.php?n=Resources.DocsCookbooks
# for a explanation of possible statements, functions and parameters.
#


####### Global Parameters #########

debug=6
log_stderror=no
log_facility=LOG_LOCAL0

fork=yes
children=4

/* uncomment the following lines to enable debugging */
#debug=6
#fork=no
#log_stderror=yes

/* uncomment the next line to disable TCP (default on) */
#disable_tcp=yes

/* uncomment the next line to enable the auto temporary blacklisting of
   not available destinations (default disabled) */
#disable_dns_blacklist=no

/* uncomment the next line to enable IPv6 lookup after IPv4 dns
   lookup failures (default disabled) */
#dns_try_ipv6=yes

/* uncomment the next line to disable the auto discovery of local aliases
   based on revers DNS on IPs (default on) */
#auto_aliases=no
alias=vmopensips1.skycomuk.com
alias=sip.skycomuk.com

/* uncomment the following lines to enable TLS support  (default off) */
#disable_tls = no
#listen = tls:your_IP:5061
#tls_verify_server = 1
#tls_verify_client = 1
#tls_require_client_certificate = 0
#tls_method = TLSv1
#tls_certificate = "//etc/opensips/tls/user/user-cert.pem"
#tls_private_key = "//etc/opensips/tls/user/user-privkey.pem"
#tls_ca_list = "//etc/opensips/tls/user/user-calist.pem"


port=5060

/* uncomment and configure the following line if you want opensips to
   bind on a specific interface/port/proto (default bind on all available) */
#listen=udp:192.168.1.2:5060


####### Modules Section ########

#set module path
mpath="//lib64/opensips/modules/"

/* uncomment next line for MySQL DB support */
loadmodule "db_mysql.so"
loadmodule "signaling.so"
loadmodule "sl.so"
loadmodule "tm.so"
loadmodule "rr.so"
loadmodule "maxfwd.so"
loadmodule "usrloc.so"
loadmodule "registrar.so"
loadmodule "textops.so"
loadmodule "mi_fifo.so"
loadmodule "uri.so"
loadmodule "acc.so"
/* uncomment next lines for MySQL based authentication support
   NOTE: a DB (like db_mysql) module must be also loaded */
loadmodule "auth.so"
loadmodule "auth_db.so"
/* uncomment next line for aliases support
   NOTE: a DB (like db_mysql) module must be also loaded */
#loadmodule "alias_db.so"
/* uncomment next line for multi-domain support
   NOTE: a DB (like db_mysql) module must be also loaded
   NOTE: be sure and enable multi-domain support in all used modules
         (see "multi-module params" section ) */
#loadmodule "domain.so"
/* uncomment the next two lines for presence server support
   NOTE: a DB (like db_mysql) module must be also loaded */
#loadmodule "presence.so"
#loadmodule "presence_xml.so"


# ----------------- setting module-specific parameters ---------------


# ----- mi_fifo params -----
modparam("mi_fifo", "fifo_name", "/tmp/opensips_fifo")


# ----- rr params -----
# add value to ;lr param to cope with most of the UAs
modparam("rr", "enable_full_lr", 1)
# do not append from tag to the RR (no need for this script)
modparam("rr", "append_fromtag", 0)


# ----- registrar params -----
/* uncomment the next line not to allow more than 10 contacts per AOR */
#modparam("registrar", "max_contacts", 10)


# ----- usrloc params -----
#modparam("usrloc", "db_mode",   0)
/* uncomment the following lines if you want to enable DB persistency
   for location entries */
modparam("usrloc", "db_mode",   2)
modparam("usrloc", "db_url", "mysql://opensips:Aegucho8@10.150.0.30/opensips")


# ----- uri params -----
modparam("uri", "use_uri_table", 0)


# ----- acc params -----
/* what sepcial events should be accounted ? */
modparam("acc", "early_media", 1)
modparam("acc", "report_ack", 1)
modparam("acc", "report_cancels", 1)
/* by default ww do not adjust the direct of the sequential requests.
   if you enable this parameter, be sure the enable "append_fromtag"
   in "rr" module */
modparam("acc", "detect_direction", 0)
/* account triggers (flags) */
modparam("acc", "failed_transaction_flag", 3)
modparam("acc", "log_flag", 1)
modparam("acc", "log_missed_flag", 2)
/* uncomment the following lines to enable DB accounting also */
modparam("acc", "db_flag", 1)
modparam("acc", "db_missed_flag", 2)


# ----- auth_db params -----
/* uncomment the following lines if you want to enable the DB based
   authentication */
modparam("auth_db", "calculate_ha1", yes)
modparam("auth_db", "password_column", "password")
modparam("auth_db", "db_url", "mysql://opensips:Aegucho8@10.150.0.30/opensips")
modparam("auth_db", "load_credentials", "")


# ----- alias_db params -----
/* uncomment the following lines if you want to enable the DB based
   aliases */
#modparam("alias_db", "db_url",
# "mysql://opensips:opensipsrw@localhost/opensips")


# ----- domain params -----
/* uncomment the following lines to enable multi-domain detection
   support */
#modparam("domain", "db_url",
# "mysql://opensips:opensipsrw@localhost/opensips")
#modparam("domain", "db_mode", 1)   # Use caching


# ----- multi-module params -----
/* uncomment the following line if you want to enable multi-domain support
   in the modules (dafault off) */
#modparam("auth_db|usrloc|uri", "use_domain", 1)


# ----- presence params -----
/* uncomment the following lines if you want to enable presence */
#modparam("presence|presence_xml", "db_url", "mysql://opensips:Aegucho8@10.150.0.30/opensips")
#modparam("presence_xml", "force_active", 1)
#modparam("presence", "server_address", "sip:192.168.1.2:5060")


####### Routing Logic ########


# main request routing logic

route{

        if (!mf_process_maxfwd_header("10")) {
                sl_send_reply("483","Too Many Hops");
                exit;
        }

        if (has_totag()) {
                # sequential request withing a dialog should
                # take the path determined by record-routing
                if (loose_route()) {
                        if (is_method("BYE")) {
                                setflag(1); # do accounting ...
                                setflag(3); # ... even if the transaction fails
                        } else if (is_method("INVITE")) {
                                # even if in most of the cases is useless, do RR for
                                # re-INVITEs alos, as some buggy clients do change route set
                                # during the dialog.
                                record_route();
                        }
                        # route it out to whatever destination was set by loose_route()
                        # in $du (destination URI).
                        route(1);
                } else {
                        /* uncomment the following lines if you want to enable presence */
                        ##if (is_method("SUBSCRIBE") && $rd == "your.server.ip.address") {
                        ## # in-dialog subscribe requests
                        ## route(2);
                        ## exit;
                        ##}
                        if ( is_method("ACK") ) {
                                if ( t_check_trans() ) {
                                        # non loose-route, but stateful ACK; must be an ACK after
                                        # a 487 or e.g. 404 from upstream server
                                        t_relay();
                                        exit;
                                } else {
                                        # ACK without matching transaction ->
                                        # ignore and discard
                                        exit;
                                }
                        }
                        sl_send_reply("404","Not here");
                }
                exit;
        }

        #initial requests

        # CANCEL processing
        if (is_method("CANCEL"))
        {
                if (t_check_trans())
                        t_relay();
                exit;
        }

        t_check_trans();

        # authenticate if from local subscriber (uncomment to enable auth)
        # authenticate all initial non-REGISTER request that pretend to be
        # generated by local subscriber (domain from FROM URI is local)
        if (!(method=="REGISTER") && from_uri==myself) /*no multidomain version*/
        ##if (!(method=="REGISTER") && is_from_local())  /*multidomain version*/
        {
                if (!proxy_authorize("", "subscriber")) {
                        proxy_challenge("", "0");
                        exit;
                }
                if (!db_check_from()) {
                        sl_send_reply("403","Forbidden auth ID");
                        exit;
                }
       
                consume_credentials();
                # caller authenticated
        }

        # preloaded route checking
        if (loose_route()) {
                xlog("L_ERR",
                "Attempt to route with preloaded Route's [$fu/$tu/$ru/$ci]");
                if (!is_method("ACK"))
                        sl_send_reply("403","Preload Route denied");
                exit;
        }

        # record routing
        if (!is_method("REGISTER|MESSAGE"))
                record_route();

        # account only INVITEs
        if (is_method("INVITE")) {
                setflag(1); # do accounting
        }
        if (!uri==myself)
        ## replace with following line if multi-domain support is used
        ##if (!is_uri_host_local())
        {
                append_hf("P-hint: outbound\r\n");
                # if you have some interdomain connections via TLS
                ##if($rd=="tls_domain1.net") {
                ## t_relay("tls:domain1.net");
                ## exit;
                ##} else if($rd=="tls_domain2.net") {
                ## t_relay("tls:domain2.net");
                ## exit;
                ##}
                route(1);
        }

        # requests for my domain

        ## uncomment this if you want to enable presence server
        ##   and comment the next 'if' block
        ##   NOTE: uncomment also the definition of route[2] from  below
        ##if( is_method("PUBLISH|SUBSCRIBE"))
        ## route(2);

        if (is_method("PUBLISH"))
        {
                sl_send_reply("503", "Service Unavailable");
                exit;
        }
       

        if (is_method("REGISTER"))
        {
                # authenticate the REGISTER requests (uncomment to enable auth)
                if (!www_authorize("", "subscriber"))
                {
                        www_challenge("", "0");
                        exit;
                }
               
                if (!db_check_to())
                {
                        sl_send_reply("403","Forbidden auth ID");
                        exit;
                }

                if (!save("location"))
                        sl_reply_error();

                exit;
        }

        if ($rU==NULL) {
                # request with no Username in RURI
                sl_send_reply("484","Address Incomplete");
                exit;
        }

        # apply DB based aliases (uncomment to enable)
        ##alias_db_lookup("dbaliases");

        # do lookup with method filtering
        if (!lookup("location","m")) {
                switch ($retcode) {
                        case -1:
                        case -3:
                                t_newtran();
                                t_reply("404", "Not Found");
                                exit;
                        case -2:
                                sl_send_reply("405", "Method Not Allowed");
                                exit;
                }
        }

        # when routing via usrloc, log the missed calls also
        setflag(2);

        route(1);
}


route[1] {
        # for INVITEs enable some additional helper routes
        if (is_method("INVITE")) {
                t_on_branch("2");
                t_on_reply("2");
                t_on_failure("1");
        }

        if (!t_relay()) {
                sl_reply_error();
        };
        exit;
}


# Presence route
/* uncomment the whole following route for enabling presence
   NOTE: do not forget to enable the call of this route from the main
     route */
##route[2]
##{
## if (!t_newtran())
## {
## sl_reply_error();
## exit;
## };
##
## if(is_method("PUBLISH"))
## {
## handle_publish();
## }
## else
## if( is_method("SUBSCRIBE"))
## {
## handle_subscribe();
## }
##
## exit;
##}


branch_route[2] {
        xlog("new branch at $ru\n");
}


onreply_route[2] {
        xlog("incoming reply\n");
}


failure_route[1] {
        if (t_was_cancelled()) {
                exit;
        }

        # uncomment the following lines if you want to block client
        # redirect based on 3xx replies.
        ##if (t_check_status("3[0-9][0-9]")) {
        ##t_reply("404","Not found");
        ## exit;
        ##}

        # uncomment the following lines if you want to redirect the failed
        # calls to a different new destination
        ##if (t_check_status("486|408")) {
        ## sethostport("192.168.2.100:5060");
        ## # do not set the missed call flag again
        ## t_relay();
        ##}
}

_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Bogdan-Andrei Iancu
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
>>>>>
>>>>> _______________________________________________


--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami,  USA
www.voice-system.ro


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Gareth Blades
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.

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?

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
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Bogdan-Andrei Iancu
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
> [hidden email]
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>


--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami,  USA
www.voice-system.ro


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Gareth Blades
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
>> [hidden email]
>> 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:[hidden email]: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:[hidden email]:5060}
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: <To> [49]; uri=[sip:[hidden email]:5060]
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]: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:[hidden email]: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:[hidden email]: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:[hidden email]:5060}
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: <To> [91]; uri=[sip:[hidden email]:5060]
Jan 12 16:03:17 [20309] DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]: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
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Bogdan-Andrei Iancu-2
Hi

try to place a log as:

    xlog("before record routing\n");

before the line with :

# record routing


another one like :

    xlog("doing lookup\n");

before:

 #do lookup with method filtering


and one in the beginning of route[1]:
    xlog("sending request $rm out \n");


Check which logs do appear.

Regards,
Bogdan

Gareth Blades wrote:

> 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
>>> [hidden email]
>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>
>>
>
> ------------------------------------------------------------------------
>
> _______________________________________________
> Users mailing list
> [hidden email]
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users


--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami,  USA
OpenSIPS solutions and "know-how"


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Gareth Blades
Thanks. I have done that but the only debug messages I get is the one I
added myself just after the 'route{' line at the very start.

Is the following line from the logs significant? I dont have multidomain
support enabled.
Jan 18 14:55:29 [8644] DBG:auth:pre_auth: credentials with given realm
not found

Bogdan-Andrei Iancu wrote:

> Hi
>
> try to place a log as:
>
>    xlog("before record routing\n");
>
> before the line with :
>
> # record routing
>
>
> another one like :
>
>    xlog("doing lookup\n");
>
> before:
>
> #do lookup with method filtering
>
>
> and one in the beginning of route[1]:
>    xlog("sending request $rm out \n");
>
>
> Check which logs do appear.
>
> Regards,
> Bogdan
>
> Gareth Blades wrote:
>> 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
>>>> [hidden email]
>>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>>
>>>
>>>
>>
>> ------------------------------------------------------------------------
>>
>> _______________________________________________
>> Users mailing list
>> [hidden email]
>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>
>

U 212.84.178.71:5060 -> 212.84.166.33:5060
INVITE sip:[hidden email]:5060 SIP/2.0.
Via: SIP/2.0/UDP 212.84.178.71:5060;branch=z9hG4bK73658aefde39bee35.
Max-Forwards: 70.
From: "1000" <sip:[hidden email]:5060>;tag=da5d758c2a.
To: "1001" <sip:[hidden email]:5060>.
Call-ID: 42aba56e029d925e.
CSeq: 31079 INVITE.
Allow:  INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO.
Allow-Events: talk, hold, conference, LocalModeStatus.
Contact: "1000" <sip:1000@212.84.178.71:5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-1000-8000-00085D23801E>".
Supported: gruu, path, timer, 100rel, replaces.
User-Agent: Aastra 55i/2.5.2.1010.
Content-Type: application/sdp.
Content-Length: 595.
.
v=0.
o=MxSIP 0 0 IN IP4 192.168.2.11.
s=SIP Call.
c=IN IP4 212.84.178.71.
t=0 0.
m=audio 3000 RTP/AVP 0 18 106 107 113 110 111 112 98 97 115 96 9 8 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:18 G729/8000.
a=rtpmap:106 BV16/8000.
a=rtpmap:107 BV32/16000.
a=rtpmap:113 L16/16000.
a=rtpmap:110 PCMU/16000.
a=rtpmap:111 PCMA/16000.
a=rtpmap:112 L16/8000.
a=rtpmap:98 G726-16/8000.
a=rtpmap:97 G726-24/8000.
a=rtpmap:115 G726-32/8000.
a=rtpmap:96 G726-40/8000.
a=rtpmap:9 G722/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.
a=silenceSupp:on - - - -.
a=fmtp:101 0-15.
a=ptime:30.
a=sendrecv.

Jan 18 15:05:30 [9057] DBG:core:parse_msg: SIP Request:
Jan 18 15:05:30 [9057] DBG:core:parse_msg:  method:  <INVITE>
Jan 18 15:05:30 [9057] DBG:core:parse_msg:  uri:     <sip:[hidden email]:5060>
Jan 18 15:05:30 [9057] DBG:core:parse_msg:  version: <SIP/2.0>
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=2
Jan 18 15:05:30 [9057] DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK73658aefde39bee35>; state=16
Jan 18 15:05:30 [9057] DBG:core:parse_via: end of header reached, state=5
Jan 18 15:05:30 [9057] DBG:core:parse_headers: via found, flags=2
Jan 18 15:05:30 [9057] DBG:core:parse_headers: this is the first via
Jan 18 15:05:30 [9057] DBG:core:receive_msg: After parse_msg...
Jan 18 15:05:30 [9057] DBG:core:receive_msg: preparing to run routing scripts...
=========== something happened - start of route
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=100
Jan 18 15:05:30 [9057] DBG:maxfwd:is_maxfwd_present: value = 70
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=8
Jan 18 15:05:30 [9057] DBG:core:parse_to: end of header reached, state=10
Jan 18 15:05:30 [9057] DBG:core:parse_to: display={"1001"}, ruri={sip:[hidden email]:5060}
Jan 18 15:05:30 [9057] DBG:core:get_hdr_field: <To> [49]; uri=[sip:[hidden email]:5060]
Jan 18 15:05:30 [9057] DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]:5060>
]
Jan 18 15:05:30 [9057] DBG:uri:has_totag: no totag
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=78
Jan 18 15:05:30 [9057] DBG:core:get_hdr_field: cseq <CSeq>: <31079> <INVITE>
Jan 18 15:05:30 [9057] DBG:tm:t_lookup_request: start searching: hash=16191, isACK=0
Jan 18 15:05:30 [9057] DBG:tm:matching_3261: RFC3261 transaction matching failed
Jan 18 15:05:30 [9057] DBG:tm:t_lookup_request: no transaction found
Jan 18 15:05:30 [9057] DBG:core:parse_to_param: tag=da5d758c2a
Jan 18 15:05:30 [9057] DBG:core:parse_to: end of header reached, state=29
Jan 18 15:05:30 [9057] DBG:core:parse_to: display={"1000"}, ruri={sip:[hidden email]:5060}
Jan 18 15:05:30 [9057] DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan 18 15:05:30 [9057] DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan 18 15:05:30 [9057] DBG:core:grep_sock_info: checking if host==us: 24==13 &&  [vmopensips1.skycomuk.com] == [212.84.166.33]
Jan 18 15:05:30 [9057] DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=10000
Jan 18 15:05:30 [9057] DBG:core:get_hdr_field: content_length=595
Jan 18 15:05:30 [9057] DBG:core:get_hdr_field: found end of header
Jan 18 15:05:30 [9057] DBG:auth:pre_auth: credentials with given realm not found
Jan 18 15:05:30 [9057] DBG:auth:reserve_nonce_index: second= 20, sec_monit= -1,  index= 1
Jan 18 15:05:30 [9057] DBG:auth:build_auth_hf: nonce index= 1
Jan 18 15:05:30 [9057] DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest realm="vmopensips1.skycomuk.com", nonce="4d35ac5800000001f3bd3700f67bd497cadc69bef83fd0cb"
'
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=ffffffffffffffff
Jan 18 15:05:30 [9057] DBG:core:check_ip_address: params 212.84.178.71, 212.84.178.71, 0
Jan 18 15:05:30 [9057] DBG:core:destroy_avp_list: destroying list (nil)
Jan 18 15:05:30 [9057] DBG:core:receive_msg: cleaning up

U 212.84.166.33:5060 -> 212.84.178.71:5060
SIP/2.0 407 Proxy Authentication Required.
Via: SIP/2.0/UDP 212.84.178.71:5060;branch=z9hG4bK73658aefde39bee35.
From: "1000" <sip:[hidden email]:5060>;tag=da5d758c2a.
To: "1001" <sip:[hidden email]:5060>;tag=b3addd350d7d55a695048985db4db659.c74d.
Call-ID: 42aba56e029d925e.
CSeq: 31079 INVITE.
Proxy-Authenticate: Digest realm="vmopensips1.skycomuk.com", nonce="4d35ac5800000001f3bd3700f67bd497cadc69bef83fd0cb".
Server: OpenSIPS (1.6.4-notls (x86_64/linux)).
Content-Length: 0.
.


U 212.84.178.71:5060 -> 212.84.166.33:5060
ACK sip:[hidden email]:5060 SIP/2.0.
Via: SIP/2.0/UDP 212.84.178.71:5060;branch=z9hG4bK73658aefde39bee35.
Max-Forwards: 70.
From: "1000" <sip:[hidden email]:5060>;tag=da5d758c2a.
To: "1001" <sip:[hidden email]:5060>;tag=b3addd350d7d55a695048985db4db659.c74d.
Call-ID: 42aba56e029d925e.
CSeq: 31079 ACK.
User-Agent: Aastra 55i/2.5.2.1010.
Content-Length: 0.
.

Jan 18 15:05:30 [9057] DBG:core:parse_msg: SIP Request:
Jan 18 15:05:30 [9057] DBG:core:parse_msg:  method:  <ACK>
Jan 18 15:05:30 [9057] DBG:core:parse_msg:  uri:     <sip:[hidden email]:5060>
Jan 18 15:05:30 [9057] DBG:core:parse_msg:  version: <SIP/2.0>
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=2
Jan 18 15:05:30 [9057] DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK73658aefde39bee35>; state=16
Jan 18 15:05:30 [9057] DBG:core:parse_via: end of header reached, state=5
Jan 18 15:05:30 [9057] DBG:core:parse_headers: via found, flags=2
Jan 18 15:05:30 [9057] DBG:core:parse_headers: this is the first via
Jan 18 15:05:30 [9057] DBG:core:receive_msg: After parse_msg...
Jan 18 15:05:30 [9057] DBG:core:receive_msg: preparing to run routing scripts...
Jan 18 15:05:30 [9057] DBG:core:parse_headers: flags=8
Jan 18 15:05:30 [9057] DBG:core:parse_to_param: tag=b3addd350d7d55a695048985db4db659.c74d
Jan 18 15:05:30 [9057] DBG:core:parse_to: end of header reached, state=29
Jan 18 15:05:30 [9057] DBG:core:parse_to: display={"1001"}, ruri={sip:[hidden email]:5060}
Jan 18 15:05:30 [9057] DBG:core:get_hdr_field: <To> [91]; uri=[sip:[hidden email]:5060]
Jan 18 15:05:30 [9057] DBG:core:get_hdr_field: to body ["1001" <sip:[hidden email]:5060>]
Jan 18 15:05:30 [9057] DBG:sl:sl_filter_ACK: local ACK found -> dropping it!
Jan 18 15:05:30 [9057] DBG:core:destroy_avp_list: destroying list (nil)
Jan 18 15:05:30 [9057] DBG:core:receive_msg: cleaning up

U 212.84.178.71:5060 -> 212.84.166.33:5060
INVITE sip:[hidden email]:5060 SIP/2.0.
Via: SIP/2.0/UDP 212.84.178.71:5060;branch=z9hG4bKb95352f6f9a6634fd.
Proxy-Authorization: Digest username="1000",realm="vmopensips1.skycomuk.com",nonce="4d35ac5800000001f3bd3700f67bd497cadc69bef83fd0cb",uri="sip:[hidden email]:5060",response="a426010f6ee341153dad82f48f5cec09".
Max-Forwards: 70.
From: "1000" <sip:[hidden email]:5060>;tag=da5d758c2a.
To: "1001" <sip:[hidden email]:5060>.
Call-ID: 42aba56e029d925e.
CSeq: 31080 INVITE.
Allow:  INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO.
Allow-Events: talk, hold, conference, LocalModeStatus.
Contact: "1000" <sip:1000@212.84.178.71:5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-1000-8000-00085D23801E>".
Supported: gruu, path, timer, 100rel, replaces.
User-Agent: Aastra 55i/2.5.2.1010.
Content-Type: application/sdp.
Content-Length: 528.
.
v=0.
o=MxSIP 0 0 IN IP4 192.168.2.11.
s=SIP Call.
c=IN IP4 212.84.178.71.
t=0 0.
m=audio 3000 RTP/AVP 0 18 106 107 113 110 111 112 98 97 115 96 9 8 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:18 G729/8000.
a=rtpmap:106 BV16/8000.
a=rtpmap:107 BV32/16000.
a=rtpmap:113 L16/16000.
a=rtpmap:110 PCMU/16000.
a=rtpmap:111 PCMA/16000.
a=rtpmap:112 L16/8000.
a=rtpmap:98 G726-16/8000.
a=rtpmap:97 G726-24/8000.
a=rtpmap:115 G726-32/8000.
a=rtpmap:96 G726-40/8000.
a=rtpmap:9 G722/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.


U 212.84.178.71:5060 -> 212.84.166.33:5060
INVITE sip:[hidden email]:5060 SIP/2.0.
Via: SIP/2.0/UDP 212.84.178.71:5060;branch=z9hG4bKb95352f6f9a6634fd.
Proxy-Authorization: Digest username="1000",realm="vmopensips1.skycomuk.com",nonce="4d35ac5800000001f3bd3700f67bd497cadc69bef83fd0cb",uri="sip:[hidden email]:5060",response="a426010f6ee341153dad82f48f5cec09".
Max-Forwards: 70.
From: "1000" <sip:[hidden email]:5060>;tag=da5d758c2a.
To: "1001" <sip:[hidden email]:5060>.
Call-ID: 42aba56e029d925e.
CSeq: 31080 INVITE.
Allow:  INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO.
Allow-Events: talk, hold, conference, LocalModeStatus.
Contact: "1000" <sip:1000@212.84.178.71:5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-1000-8000-00085D23801E>".
Supported: gruu, path, timer, 100rel, replaces.
User-Agent: Aastra 55i/2.5.2.1010.
Content-Type: application/sdp.
Content-Length: 528.
.
v=0.
o=MxSIP 0 0 IN IP4 192.168.2.11.
s=SIP Call.
c=IN IP4 212.84.178.71.
t=0 0.
m=audio 3000 RTP/AVP 0 18 106 107 113 110 111 112 98 97 115 96 9 8 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:18 G729/8000.
a=rtpmap:106 BV16/8000.
a=rtpmap:107 BV32/16000.
a=rtpmap:113 L16/16000.
a=rtpmap:110 PCMU/16000.
a=rtpmap:111 PCMA/16000.
a=rtpmap:112 L16/8000.
a=rtpmap:98 G726-16/8000.
a=rtpmap:97 G726-24/8000.
a=rtpmap:115 G726-32/8000.
a=rtpmap:96 G726-40/8000.
a=rtpmap:9 G722/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.


U 212.84.178.71:5060 -> 212.84.166.33:5060
INVITE sip:[hidden email]:5060 SIP/2.0.
Via: SIP/2.0/UDP 212.84.178.71:5060;branch=z9hG4bKb95352f6f9a6634fd.
Proxy-Authorization: Digest username="1000",realm="vmopensips1.skycomuk.com",nonce="4d35ac5800000001f3bd3700f67bd497cadc69bef83fd0cb",uri="sip:[hidden email]:5060",response="a426010f6ee341153dad82f48f5cec09".
Max-Forwards: 70.
From: "1000" <sip:[hidden email]:5060>;tag=da5d758c2a.
To: "1001" <sip:[hidden email]:5060>.
Call-ID: 42aba56e029d925e.
CSeq: 31080 INVITE.
Allow:  INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO.
Allow-Events: talk, hold, conference, LocalModeStatus.
Contact: "1000" <sip:1000@212.84.178.71:5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-1000-8000-00085D23801E>".
Supported: gruu, path, timer, 100rel, replaces.
User-Agent: Aastra 55i/2.5.2.1010.
Content-Type: application/sdp.
Content-Length: 528.
.
v=0.
o=MxSIP 0 0 IN IP4 192.168.2.11.
s=SIP Call.
c=IN IP4 212.84.178.71.
t=0 0.
m=audio 3000 RTP/AVP 0 18 106 107 113 110 111 112 98 97 115 96 9 8 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:18 G729/8000.
a=rtpmap:106 BV16/8000.
a=rtpmap:107 BV32/16000.
a=rtpmap:113 L16/16000.
a=rtpmap:110 PCMU/16000.
a=rtpmap:111 PCMA/16000.
a=rtpmap:112 L16/8000.
a=rtpmap:98 G726-16/8000.
a=rtpmap:97 G726-24/8000.
a=rtpmap:115 G726-32/8000.
a=rtpmap:96 G726-40/8000.
a=rtpmap:9 G722/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.


U 212.84.178.71:5060 -> 212.84.166.33:5060
INVITE sip:[hidden email]:5060 SIP/2.0.
Via: SIP/2.0/UDP 212.84.178.71:5060;branch=z9hG4bKb95352f6f9a6634fd.
Proxy-Authorization: Digest username="1000",realm="vmopensips1.skycomuk.com",nonce="4d35ac5800000001f3bd3700f67bd497cadc69bef83fd0cb",uri="sip:[hidden email]:5060",response="a426010f6ee341153dad82f48f5cec09".
Max-Forwards: 70.
From: "1000" <sip:[hidden email]:5060>;tag=da5d758c2a.
To: "1001" <sip:[hidden email]:5060>.
Call-ID: 42aba56e029d925e.
CSeq: 31080 INVITE.
Allow:  INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO.
Allow-Events: talk, hold, conference, LocalModeStatus.
Contact: "1000" <sip:1000@212.84.178.71:5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-1000-8000-00085D23801E>".
Supported: gruu, path, timer, 100rel, replaces.
User-Agent: Aastra 55i/2.5.2.1010.
Content-Type: application/sdp.
Content-Length: 528.
.
v=0.
o=MxSIP 0 0 IN IP4 192.168.2.11.
s=SIP Call.
c=IN IP4 212.84.178.71.
t=0 0.
m=audio 3000 RTP/AVP 0 18 106 107 113 110 111 112 98 97 115 96 9 8 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:18 G729/8000.
a=rtpmap:106 BV16/8000.
a=rtpmap:107 BV32/16000.
a=rtpmap:113 L16/16000.
a=rtpmap:110 PCMU/16000.
a=rtpmap:111 PCMA/16000.
a=rtpmap:112 L16/8000.
a=rtpmap:98 G726-16/8000.
a=rtpmap:97 G726-24/8000.
a=rtpmap:115 G726-32/8000.
a=rtpmap:96 G726-40/8000.
a=rtpmap:9 G722/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.

_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Bogdan-Andrei Iancu-2
yes, you have the message for the first Invite (without credentials),
but I see no logs at all for the following INVITEs (with credentials)...:-/

Regards,
Bogdan

Gareth Blades wrote:

> Thanks. I have done that but the only debug messages I get is the one
> I added myself just after the 'route{' line at the very start.
>
> Is the following line from the logs significant? I dont have
> multidomain support enabled.
> Jan 18 14:55:29 [8644] DBG:auth:pre_auth: credentials with given realm
> not found
>
> Bogdan-Andrei Iancu wrote:
>> Hi
>>
>> try to place a log as:
>>
>>    xlog("before record routing\n");
>>
>> before the line with :
>>
>> # record routing
>>
>>
>> another one like :
>>
>>    xlog("doing lookup\n");
>>
>> before:
>>
>> #do lookup with method filtering
>>
>>
>> and one in the beginning of route[1]:
>>    xlog("sending request $rm out \n");
>>
>>
>> Check which logs do appear.
>>
>> Regards,
>> Bogdan
>>
>> Gareth Blades wrote:
>>> 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
>>>>>>>>>>>
>>>>>>>>>>> _______________________________________________
>>>>>>
>>>>>>
>>>>>


--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami,  USA
OpenSIPS solutions and "know-how"


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Brett Nemeroff
In reply to this post by Bogdan-Andrei Iancu
On Wed, Jan 12, 2011 at 9:04 AM, Bogdan-Andrei Iancu <[hidden email]> 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 ?


Bogdan / Gareth,
I've seen this on several installations. Exactly as you describe. If you search the archives, you'll see others have reported this as well. The problem seems to be in the syslog configuration. If you switch your configuration to use asynchronous writes, you'll see performance improve dramatically. In syslog, this is done by placing a minus sign before the filename. For example /var/log/opensips.log becomes -/var/log/opensips. Your distribution / syslogger may vary.

And yes, this is apparent on a system with no load, and SINGLE SIP users. With full logging and no modifications to syslog, I've seen on *many* systems REGISTER timeouts. Took me a while to track it down; suspecting all sorts of other IO issues. 

Bogdan, I'm not sure if this should be expected or if it's an indication of a more serious problem in OpenSIPs. If it's not an indication of a coding problem, then I believe this information should be in the documentation somewhere (if it's not already)?

Thanks,
Brett


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: new install and INVITES not being forwarded

Bogdan-Andrei Iancu-2
Hi Brett,

Brett Nemeroff wrote:

> On Wed, Jan 12, 2011 at 9:04 AM, Bogdan-Andrei Iancu
> <[hidden email] <mailto:[hidden email]>> 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 ?
>
>
> Bogdan / Gareth,
> I've seen this on several installations. Exactly as you describe. If
> you search the archives, you'll see others have reported this as well.
> The problem seems to be in the syslog configuration. If you switch
> your configuration to use asynchronous writes, you'll see performance
> improve dramatically. In syslog, this is done by placing a minus sign
> before the filename. For example /var/log/opensips.log becomes
> -/var/log/opensips. Your distribution / syslogger may vary.
>
> And yes, this is apparent on a system with no load, and SINGLE SIP
> users. With full logging and no modifications to syslog, I've seen on
> *many* systems REGISTER timeouts. Took me a while to track it down;
> suspecting all sorts of other IO issues.
>
> Bogdan, I'm not sure if this should be expected or if it's an
> indication of a more serious problem in OpenSIPs. If it's not an
> indication of a coding problem, then I believe this information should
> be in the documentation somewhere (if it's not already)?

The problem is not opensips related - the syslog is seen as an external
I/O (as DB, DNS) which unfortunately blocks.

And, yes, it may be a good idea to have it somewhere - for example you
can add something to the "Tips and FAQs" section:
                http://www.opensips.org/Resources/DocsTipsFaqs

Regards,
Bogdan


--
Bogdan-Andrei Iancu
OpenSIPS Event - expo, conf, social, bootcamp
2 - 4 February 2011, ITExpo, Miami,  USA
OpenSIPS solutions and "know-how"


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users