[OpenSIPS-Users] Log of My opensips server
Indiver
nehru.indu at gmail.com
Tue Oct 20 04:54:57 CEST 2009
Hi Bodgan ,
The Intention is when i dial the local number 1000. According to the rules
assigned it should go to Asterisk Server. But i'm getting the 404 error.I'm
pasting the logs of my server.
Oct 20 08:14:19 [4760] DBG:core:parse_msg: SIP Request:
Oct 20 08:14:19 [4760] DBG:core:parse_msg: method: <INVITE>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: uri: <sip:1005 at 192.168.3.36>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: version: <SIP/2.0>
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 235,
<rport> = <n/a>; state=6
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK6416E103AC36018225D01A2D1D91DF8A>; state=16
Oct 20 08:14:19 [4760] DBG:core:parse_via: end of header reached, state=5
Oct 20 08:14:19 [4760] DBG:core:parse_headers: via found, flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_headers: this is the first via
Oct 20 08:14:19 [4760] DBG:core:receive_msg: After parse_msg...
Oct 20 08:14:19 [4760] DBG:core:receive_msg: preparing to run routing
scripts...
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=100
Oct 20 08:14:19 [4760] DBG:core:parse_to: end of header reached, state=10
Oct 20 08:14:19 [4760] DBG:core:parse_to: display={},
ruri={sip:1005 at 192.168.3.36}
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: <To> [25];
uri=[sip:1005 at 192.168.3.36]
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: to body
[<sip:1005 at 192.168.3.36>
]
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: cseq <CSeq>: <27781> <INVITE>
Oct 20 08:14:19 [4760] DBG:maxfwd:is_maxfwd_present: value = 70
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=200
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: content_length=285
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: found end of header
Oct 20 08:14:19 [4760] DBG:rr:find_first_route: No Route headers found
Oct 20 08:14:19 [4760] DBG:rr:loose_route: There is no Route HF
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=78
Oct 20 08:14:19 [4760] DBG:tm:t_lookup_request: start searching: hash=35421,
isACK=0
Oct 20 08:14:19 [4760] DBG:tm:matching_3261: RFC3261 transaction matching
failed
Oct 20 08:14:19 [4760] DBG:tm:t_lookup_request: no transaction found
Oct 20 08:14:19 [4760] DBG:uri:has_totag: no totag
1. ***************** RETURN CODE=2
2. ***************** RETURN CODE=2
3. ***************** RETURN CODE=2Call control: Inside 2 for post paid
Call control: outside switch
Oct 20 08:14:19 [4760] DBG:core:parse_to_param: tag=791668632
Oct 20 08:14:19 [4760] DBG:core:parse_to: end of header reached, state=29
Oct 20 08:14:19 [4760] DBG:core:parse_to: display={1000},
ruri={sip:1000 at 192.168.3.36:5061}
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=10000
Oct 20 08:14:19 [4760] DBG:auth:pre_auth: credentials with given realm not
found
Oct 20 08:14:19 [4760] DBG:auth:reserve_nonce_index: second= 18, sec_monit=
1, index= 3
Oct 20 08:14:19 [4760] DBG:auth:build_auth_hf: nonce index= 3
Oct 20 08:14:19 [4760] DBG:auth:build_auth_hf: 'Proxy-Authenticate: Digest
realm="192.168.3.36",
nonce="4add242100000003d436a2cc8f30da600a834c5131f3a249", qop="auth"
'
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=ffffffffffffffff
Oct 20 08:14:19 [4760] DBG:core:check_via_address: params 192.168.3.36,
192.168.3.36, 0
Oct 20 08:14:19 [4760] DBG:sl:run_sl_callbacks: callback id 0 entered
Oct 20 08:14:19 [4760] DBG:siptrace:trace_sl_onreply_out: trace off...
Oct 20 08:14:19 [4760] DBG:core:destroy_avp_list: destroying list (nil)
Oct 20 08:14:19 [4760] DBG:core:receive_msg: cleaning up
Oct 20 08:14:19 [4760] DBG:core:parse_msg: SIP Request:
Oct 20 08:14:19 [4760] DBG:core:parse_msg: method: <ACK>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: uri: <sip:1005 at 192.168.3.36>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: version: <SIP/2.0>
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 235,
<rport> = <n/a>; state=6
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK6416E103AC36018225D01A2D1D91DF8A>; state=16
Oct 20 08:14:19 [4760] DBG:core:parse_via: end of header reached, state=5
Oct 20 08:14:19 [4760] DBG:core:parse_headers: via found, flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_headers: this is the first via
Oct 20 08:14:19 [4760] DBG:core:receive_msg: After parse_msg...
Oct 20 08:14:19 [4760] DBG:core:receive_msg: preparing to run routing
scripts...
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=8
Oct 20 08:14:19 [4760] DBG:core:parse_to_param:
tag=cff3a8edcbb82ce1a7d1e4e5b02fa2d4.ec4c
Oct 20 08:14:19 [4760] DBG:core:parse_to: end of header reached, state=29
Oct 20 08:14:19 [4760] DBG:core:parse_to: display={},
ruri={sip:1005 at 192.168.3.36}
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: <To> [67];
uri=[sip:1005 at 192.168.3.36]
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: to body
[<sip:1005 at 192.168.3.36>]
Oct 20 08:14:19 [4760] DBG:sl:sl_filter_ACK: local ACK found -> dropping it!
Oct 20 08:14:19 [4760] DBG:core:destroy_avp_list: destroying list (nil)
Oct 20 08:14:19 [4760] DBG:core:receive_msg: cleaning up
Oct 20 08:14:19 [4760] DBG:core:parse_msg: SIP Request:
Oct 20 08:14:19 [4760] DBG:core:parse_msg: method: <INVITE>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: uri: <sip:1005 at 192.168.3.36>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: version: <SIP/2.0>
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 235,
<rport> = <n/a>; state=6
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK77286FE2404A10EDC3FB4BD2CA3BC023>; state=16
Oct 20 08:14:19 [4760] DBG:core:parse_via: end of header reached, state=5
Oct 20 08:14:19 [4760] DBG:core:parse_headers: via found, flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_headers: this is the first via
Oct 20 08:14:19 [4760] DBG:core:receive_msg: After parse_msg...
Oct 20 08:14:19 [4760] DBG:core:receive_msg: preparing to run routing
scripts...
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=100
Oct 20 08:14:19 [4760] DBG:core:parse_to: end of header reached, state=10
Oct 20 08:14:19 [4760] DBG:core:parse_to: display={},
ruri={sip:1005 at 192.168.3.36}
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: <To> [25];
uri=[sip:1005 at 192.168.3.36]
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: to body
[<sip:1005 at 192.168.3.36>
]
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: cseq <CSeq>: <27782> <INVITE>
Oct 20 08:14:19 [4760] DBG:maxfwd:is_maxfwd_present: value = 70
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=200
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: content_length=285
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: found end of header
Oct 20 08:14:19 [4760] DBG:rr:find_first_route: No Route headers found
Oct 20 08:14:19 [4760] DBG:rr:loose_route: There is no Route HF
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=78
Oct 20 08:14:19 [4760] DBG:tm:t_lookup_request: start searching: hash=35418,
isACK=0
Oct 20 08:14:19 [4760] DBG:tm:matching_3261: RFC3261 transaction matching
failed
Oct 20 08:14:19 [4760] DBG:tm:t_lookup_request: no transaction found
Oct 20 08:14:19 [4760] DBG:uri:has_totag: no totag
1. ***************** RETURN CODE=2
2. ***************** RETURN CODE=2
3. ***************** RETURN CODE=2Call control: Inside 2 for post paid
Call control: outside switch
Oct 20 08:14:19 [4760] DBG:core:parse_to_param: tag=791668632
Oct 20 08:14:19 [4760] DBG:core:parse_to: end of header reached, state=29
Oct 20 08:14:19 [4760] DBG:core:parse_to: display={1000},
ruri={sip:1000 at 192.168.3.36:5061}
Oct 20 08:14:19 [4760] DBG:auth:check_nonce: comparing
[4add242100000003d436a2cc8f30da600a834c5131f3a249] and
[4add242100000003d436a2cc8f30da600a834c5131f3a249]
Oct 20 08:14:19 [4760] DBG:db_mysql:has_stmt_ctx: ctx found for subscriber
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_do_prepared_query:
conn=0x8189828 (tail=135892248) MC=0x8199ae0
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_do_prepared_query: set values
for the statement run
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_val2bind: added val (0): len=4;
type=254; is_null=0
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_do_prepared_query: doing
BIND_PARAM in...
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_do_prepared_query: prepared
statement has 1 columns in result
Oct 20 08:14:19 [4760] DBG:core:db_new_result: allocate 28 bytes for result
set at 0x819c3f0
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_get_columns: 1 columns returned
from the query
Oct 20 08:14:19 [4760] DBG:core:db_allocate_columns: allocate 16 bytes for
result columns at 0x819bd10
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x819bd14)[0]=[password]
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_get_columns: use DB_STRING
result type
Oct 20 08:14:19 [4760] DBG:core:db_allocate_rows: allocate 28 bytes for
result rows and values at 0x819c160
Oct 20 08:14:19 [4760] DBG:db_mysql:db_mysql_str2val: converting STRING
[1234]
Oct 20 08:14:19 [4760] DBG:auth_db:get_ha1: HA1 string calculated:
541bcccc25a4bf0e3bb912d4e3e25a03
Oct 20 08:14:19 [4760] DBG:auth:check_response: our result =
'08b854a140725279bc7b526f2532ba9c'
Oct 20 08:14:19 [4760] DBG:auth:check_response: authorization is OK
Oct 20 08:14:19 [4760] DBG:auth:post_auth: nonce index= 3
Oct 20 08:14:19 [4760] DBG:core:db_free_columns: freeing result columns at
0x819bd10
Oct 20 08:14:19 [4760] DBG:core:db_free_rows: freeing 1 rows
Oct 20 08:14:19 [4760] DBG:core:db_free_row: freeing row values at 0x819c168
Oct 20 08:14:19 [4760] DBG:core:db_free_rows: freeing rows at 0x819c160
Oct 20 08:14:19 [4760] DBG:core:db_free_result: freeing result set at
0x819c3f0
Oct 20 08:14:19 [4760] DBG:core:db_free_result: SYNC-DBG - freeing result!
Oct 20 08:14:19 [4760] DBG:uri_db:check_username: Digest username and URI
username match
Oct 20 08:14:19 [4760] DBG:registrar:lookup: '1005' Not found in usrloc
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=ffffffffffffffff
Oct 20 08:14:19 [4760] DBG:registrar:lookup: '1005' Not found in usrloc
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=ffffffffffffffff
Oct 20 08:14:19 [4760] DBG:core:check_via_address: params 192.168.3.36,
192.168.3.36, 0
Oct 20 08:14:19 [4760] DBG:sl:run_sl_callbacks: callback id 0 entered
Oct 20 08:14:19 [4760] DBG:siptrace:trace_sl_onreply_out: trace off...
Oct 20 08:14:19 [4760] DBG:core:destroy_avp_list: destroying list (nil)
Oct 20 08:14:19 [4760] DBG:core:receive_msg: cleaning up
Oct 20 08:14:19 [4760] DBG:core:parse_msg: SIP Request:
Oct 20 08:14:19 [4760] DBG:core:parse_msg: method: <ACK>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: uri: <sip:1005 at 192.168.3.36>
Oct 20 08:14:19 [4760] DBG:core:parse_msg: version: <SIP/2.0>
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 235,
<rport> = <n/a>; state=6
Oct 20 08:14:19 [4760] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK77286FE2404A10EDC3FB4BD2CA3BC023>; state=16
Oct 20 08:14:19 [4760] DBG:core:parse_via: end of header reached, state=5
Oct 20 08:14:19 [4760] DBG:core:parse_headers: via found, flags=2
Oct 20 08:14:19 [4760] DBG:core:parse_headers: this is the first via
Oct 20 08:14:19 [4760] DBG:core:receive_msg: After parse_msg...
Oct 20 08:14:19 [4760] DBG:core:receive_msg: preparing to run routing
scripts...
Oct 20 08:14:19 [4760] DBG:core:parse_headers: flags=8
Oct 20 08:14:19 [4760] DBG:core:parse_to_param:
tag=cff3a8edcbb82ce1a7d1e4e5b02fa2d4.2536
Oct 20 08:14:19 [4760] DBG:core:parse_to: end of header reached, state=29
Oct 20 08:14:19 [4760] DBG:core:parse_to: display={},
ruri={sip:1005 at 192.168.3.36}
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: <To> [67];
uri=[sip:1005 at 192.168.3.36]
Oct 20 08:14:19 [4760] DBG:core:get_hdr_field: to body
[<sip:1005 at 192.168.3.36>]
Oct 20 08:14:19 [4760] DBG:sl:sl_filter_ACK: local ACK found -> dropping it!
Oct 20 08:14:19 [4760] DBG:core:destroy_avp_list: destroying list (nil)
Bogdan-Andrei Iancu wrote:
>
> Hi Indiver,
>
> what exactly is not working?
>
> Can you post the output logs (during INVITE) with debug=6 ? Post also
> the incoming INVITE request.
>
> Regards,
> Bogdan
>
>
> Indiver wrote:
>> Hi Every body,
>>
>> I want to implement prefix based dynamic routing. I followed the
>> following
>> procedure.
>>
>> step 1: Enetered gateways, gateway lists, rules,groups in opensips-cp as
>> follows
>>
>> dr_gateways table:
>> +------+------+----------------+-------+------------+-------+-------------+
>> | gwid | type | address | strip | pri_prefix | attrs | description
>> |
>> +------+------+----------------+-------+------------+-------+-------------+
>> | 1 | 0 | 192.168.3.200 | 0 | 1 | NULL | Gateway 1
>> |
>>
>> dr_groups table:
>> id | username | domain | groupid | description |
>> +----+----------+--------------+---------+-----------------+
>> | 1 | 1004 | 192.168.3.36 | 0 | opensips server |
>>
>> dr_gw_lists
>> id | gwlist | description |
>> +----+--------+----------------+
>> | 1 | 1 | Gateway List 1 |
>>
>> dr_gw_rules
>>
>> ruleid | groupid | prefix | timerec | priority | routeid | gwlist |
>> description |
>> +--------+---------+--------+---------+----------+---------+--------+-------------+
>> | 1 | 0 | 1 | | 1 | 4 | 1 |
>> Rule 1
>> |
>>
>> step 2: My opensips.cfg file is as follows
>>
>> loadmodule "drouting.so"
>>
>> # ----- Dynamic Routing params -----
>> modparam("drouting",
>> "db_url","mysql://opensips:opensipsrw@localhost/opensips")
>>
>> route[4] {
>> # routing to the public network
>> if(!do_routing()){
>> sl_send_reply("404","PSTN Routing not found");
>> exit;
>> }
>>
>> route(1);
>> exit;
>>
>> But still prefix based drouting is not working. Can any one suggest the
>> changes to be made?
>>
>>
>
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>
>
--
View this message in context: http://n2.nabble.com/Dynamic-Routing-Module-tp3848089p3856477.html
Sent from the OpenSIPS - Users mailing list archive at Nabble.com.
More information about the Users
mailing list