[SR-Users] DRouting, routeid is not triggered

Daniel-Constantin Mierla miconda at gmail.com
Thu Apr 24 18:09:16 CEST 2014


Can you try the patch from commit:

- 
http://git.sip-router.org/cgi-bin/gitweb.cgi/sip-router/?a=commit;h=c3386295d7607a58d37a65b6822bf5f98b3fefa0

If you are using git, do a git pull, then you can pick the commit in 
your branch (if you are not on master):

git cherry-pick -x c3386295d7607a58d37a65b6822bf5f98b3fefa0

Cheers,
Daniel


On 24/04/14 17:16, Daniel-Constantin Mierla wrote:
> Hello,
>
> busy with the release I didn't have time to troubleshoot more yet.
>
> Cheers,
> Daniel
>
> On 24/04/14 17:10, Maciej Bylica wrote:
>> Hello
>>
>> Do you need any other data to verify?
>>
>> Thanks.
>>
>>
>> 2014-04-23 12:11 GMT+02:00 Maciej Bylica <mbsip at gazeta.pl 
>> <mailto:mbsip at gazeta.pl>>:
>>
>>     Hi Daniel,
>>
>>     Here is debug you requested.
>>
>>     DEBUG: <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
>>     DEBUG: <core> [parser/msg_parser.c:625]: parse_msg():  method:
>>      <INVITE>
>>     DEBUG: <core> [parser/msg_parser.c:627]: parse_msg():  uri:    
>>     <sip:43111223344 at 10.10.10.5 <mailto:sip%3A43111223344 at 10.10.10.5>>
>>     DEBUG: <core> [parser/msg_parser.c:629]: parse_msg():  version:
>>     <SIP/2.0>
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 235, <rport> = <n/a>; state=6
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 232, <branch> = <z9hG4bKZZDHrKg1B4Q1c>; state=16
>>     DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of
>>     header reached, state=5
>>     DEBUG: <core> [parser/msg_parser.c:513]: parse_headers():
>>     parse_headers: Via found, flags=2
>>     DEBUG: <core> [parser/msg_parser.c:515]: parse_headers():
>>     parse_headers: this is the first via
>>     DEBUG: <core> [receive.c:152]: receive_msg(): After parse_msg...
>>     DEBUG: <core> [receive.c:193]: receive_msg(): preparing to run
>>     routing scripts...
>>     DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 69
>>     DEBUG: maxfwd [maxfwd.c:161]: process_maxfwd_header(): value 69
>>     decreased to 16
>>     DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec():
>>     end of header reached, state=10
>>     DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG:
>>     get_hdr_field: <To> [32]; uri=[sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>]
>>     DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG:
>>     to body [<sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>>#015#012]
>>     DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field():
>>     get_hdr_field: cseq <CSeq>: <58787375> <INVITE>
>>     DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG:
>>     get_hdr_body : content_length=203
>>     DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found
>>     end of header
>>     DEBUG: <core> [parser/parse_addr_spec.c:176]: parse_to_param():
>>     DEBUG: add_param: tag=1eQFK719e4cyS
>>     DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec():
>>     end of header reached, state=29
>>     DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks
>>     result: 1
>>     DEBUG: siputils [checks.c:103]: has_totag(): no totag
>>     DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=0 T start=0xffffffffffffffff
>>     DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request:
>>     start searching: hash=49678, isACK=0
>>     DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261
>>     transaction matching failed
>>     DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG:
>>     t_lookup_request: no transaction found
>>     DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=1 T end=(nil)
>>     DEBUG: <core> [socket_info.c:583]: grep_sock_info():
>>     grep_sock_info - checking if host==us: 12==9 && [10.10.10.5] ==
>>     [127.0.0.1]
>>     DEBUG: <core> [socket_info.c:587]: grep_sock_info():
>>     grep_sock_info - checking if port 5060 (advertise 0) matches port
>>     5060
>>     DEBUG: <core> [socket_info.c:583]: grep_sock_info():
>>     grep_sock_info - checking if host==us: 12==12 && [10.10.10.5] ==
>>     [10.10.10.5]
>>     DEBUG: <core> [socket_info.c:587]: grep_sock_info():
>>     grep_sock_info - checking if port 5060 (advertise 0) matches port
>>     5060
>>     DEBUG: registrar [lookup.c:158]: lookup(): '43111223344' Not
>>     found in usrloc
>>     DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg
>>     id=1 , global msg id=1 , T on entrance=(nil)
>>     DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request:
>>     start searching: hash=49678, isACK=0
>>     DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261
>>     transaction matching failed
>>     DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG:
>>     t_lookup_request: no transaction found
>>     DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG:
>>     trans=0x7f9ae79e2c10, callback type 1, id 0 entered
>>     DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG:
>>     trans=0x7f9ae79e2c10, callback type 1, id 0 entered
>>     DEBUG: <core> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5
>>     calculated: 60b78f5b572d3477887c1e8305c94b0a
>>     DEBUG: drouting [drouting.c:720]: do_routing(): using dr group 10
>>     DEBUG: drouting [prefix_tree.c:87]: internal_check_rt(): found
>>     rgid 10 (rule list 0x7f9ae79e2a58)
>>     DEBUG: drouting [drouting.c:895]: do_routing(): setting attr []
>>     as for ruri
>>     DEBUG: drouting [drouting.c:912]: do_routing(): setting the gw
>>     [0] as ruri "sip:43111223344 at 10.10.10.9
>>     <mailto:sip%3A43111223344 at 10.10.10.9>"
>>     DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg
>>     id=1 , global msg id=1 , T on entrance=0x7f9ae79e2c10
>>     DEBUG: tm [t_lookup.c:1378]: t_newtran(): DEBUG: t_newtran:
>>     transaction already in process 0x7f9ae79e2c10
>>     DEBUG: tm [t_funcs.c:347]: t_relay_to(): SER: new INVITE
>>     DEBUG: <core> [msg_translator.c:204]: check_via_address():
>>     check_via_address(10.10.5.5, 10.10.5.5, 0)
>>     DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize():
>>     WARNING:vqm_resize: resize(0) called
>>     DEBUG: tm [t_reply.c:728]: _reply_light(): DEBUG: reply sent out.
>>     buf=0x7f9afe08a608: SIP/2.0 100 trying -...,
>>     shmem=0x7f9ae79e5860: SIP/2.0 100 trying -
>>     DEBUG: tm [t_reply.c:738]: _reply_light(): DEBUG: _reply_light:
>>     finished
>>     DEBUG: <script>: new branch [0] to sip:43111223344 at 10.10.10.9
>>     <mailto:sip%3A43111223344 at 10.10.10.9>
>>     DEBUG: <core> [msg_translator.c:204]: check_via_address():
>>     check_via_address(10.10.5.5, 10.10.5.5, 0)
>>     DEBUG: tm [t_funcs.c:394]: t_relay_to(): SER: new transaction fwd'ed
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp
>>     list (nil)
>>     DEBUG: <core> [receive.c:296]: receive_msg(): receive_msg:
>>     cleaning up
>>     DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply
>>      (status):
>>     DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version:
>>     <SIP/2.0>
>>     DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <100>
>>     DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:
>>      <Trying>
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 232, <branch> =
>>     <z9hG4bKe02c.60b78f5b572d3477887c1e8305c94b0a.0>; state=16
>>     DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of
>>     header reached, state=5
>>     DEBUG: <core> [parser/msg_parser.c:513]: parse_headers():
>>     parse_headers: Via found, flags=2
>>     DEBUG: <core> [parser/msg_parser.c:515]: parse_headers():
>>     parse_headers: this is the first via
>>     DEBUG: <core> [receive.c:152]: receive_msg(): After parse_msg...
>>     DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=0 T start=0xffffffffffffffff
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 235, <rport> = <5080>; state=6
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 232, <branch> = <z9hG4bKZZDHrKg1B4Q1c>; state=16
>>     DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of
>>     header reached, state=5
>>     DEBUG: <core> [parser/msg_parser.c:513]: parse_headers():
>>     parse_headers: Via found, flags=62
>>     DEBUG: <core> [parser/msg_parser.c:526]: parse_headers():
>>     parse_headers: this is the second via
>>     DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec():
>>     end of header reached, state=10
>>     DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG:
>>     get_hdr_field: <To> [32]; uri=[sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>]
>>     DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG:
>>     to body [<sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>>#015#012]
>>     DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field():
>>     get_hdr_field: cseq <CSeq>: <58787375> <INVITE>
>>     DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG:
>>     t_reply_matching: hash 49678 label 0 branch 0
>>     DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG:
>>     t_reply_matching: reply matched (T=0x7f9ae79e2c10)!
>>     DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG:
>>     trans=0x7f9ae79e2c10, callback type 2, id 0 entered
>>     DEBUG: acc [acc_logic.c:557]: tmcb_func(): acc callback called
>>     for t(0x7f9ae79e2c10) event type 2, reply code 100
>>     DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=1 T end=0x7f9ae79e2c10
>>     DEBUG: tm [t_reply.c:2206]: reply_received(): DEBUG:
>>     reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)
>>     DEBUG: <script>: incoming reply
>>     DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>>
>>     T_code=100, new_code=100
>>     DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply:
>>     branch=0, save=0, relay=-1 icode=0
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp
>>     list (nil)
>>     DEBUG: <core> [receive.c:296]: receive_msg(): receive_msg:
>>     cleaning up
>>     DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply
>>      (status):
>>     DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version:
>>     <SIP/2.0>
>>     DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <503>
>>     DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:
>>      <Service unavailable>
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 232, <branch> =
>>     <z9hG4bKe02c.60b78f5b572d3477887c1e8305c94b0a.0>; state=16
>>     DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of
>>     header reached, state=5
>>     DEBUG: <core> [parser/msg_parser.c:513]: parse_headers():
>>     parse_headers: Via found, flags=2
>>     DEBUG: <core> [parser/msg_parser.c:515]: parse_headers():
>>     parse_headers: this is the first via
>>     DEBUG: <core> [receive.c:152]: receive_msg(): After parse_msg...
>>     DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=0 T start=0xffffffffffffffff
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 235, <rport> = <5080>; state=6
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 232, <branch> = <z9hG4bKZZDHrKg1B4Q1c>; state=16
>>     DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of
>>     header reached, state=5
>>     DEBUG: <core> [parser/msg_parser.c:513]: parse_headers():
>>     parse_headers: Via found, flags=62
>>     DEBUG: <core> [parser/msg_parser.c:526]: parse_headers():
>>     parse_headers: this is the second via
>>     DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec():
>>     end of header reached, state=10
>>     DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG:
>>     get_hdr_field: <To> [32]; uri=[sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>]
>>     DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG:
>>     to body [<sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>>#015#012]
>>     DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field():
>>     get_hdr_field: cseq <CSeq>: <58787375> <INVITE>
>>     DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG:
>>     t_reply_matching: hash 49678 label 0 branch 0
>>     DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG:
>>     t_reply_matching: reply matched (T=0x7f9ae79e2c10)!
>>     DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG:
>>     trans=0x7f9ae79e2c10, callback type 2, id 0 entered
>>     DEBUG: acc [acc_logic.c:557]: tmcb_func(): acc callback called
>>     for t(0x7f9ae79e2c10) event type 2, reply code 503
>>     DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=1 T end=0x7f9ae79e2c10
>>     DEBUG: tm [t_reply.c:2206]: reply_received(): DEBUG:
>>     reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)
>>     DEBUG: <script>: incoming reply
>>     DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>>
>>     T_code=100, new_code=503
>>     DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=0 global id=0 T start=0x7f9ae79e2c10
>>     DEBUG: tm [t_lookup.c:1144]: t_check_msg(): DEBUG: t_check_msg: T
>>     already found!
>>     DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply:
>>     branch=0, save=0, relay=0 icode=0
>>     DEBUG: <core> [msg_translator.c:204]: check_via_address():
>>     check_via_address(10.10.5.5, 10.10.5.5, 0)
>>     DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG:
>>     trans=0x7f9ae79e2c10, callback type 512, id 0 entered
>>     DEBUG: acc [acc_logic.c:557]: tmcb_func(): acc callback called
>>     for t(0x7f9ae79e2c10) event type 512, reply code 503
>>     DEBUG: <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
>>     DEBUG: <core> [parser/msg_parser.c:625]: parse_msg():  method:  <ACK>
>>     DEBUG: <core> [parser/msg_parser.c:627]: parse_msg():  uri:    
>>     <sip:43111223344 at 10.10.10.5 <mailto:sip%3A43111223344 at 10.10.10.5>>
>>     DEBUG: <core> [parser/msg_parser.c:629]: parse_msg():  version:
>>     <SIP/2.0>
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 235, <rport> = <n/a>; state=6
>>     DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found
>>     param type 232, <branch> = <z9hG4bKZZDHrKg1B4Q1c>; state=16
>>     DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of
>>     header reached, state=5
>>     DEBUG: <core> [parser/msg_parser.c:513]: parse_headers():
>>     parse_headers: Via found, flags=2
>>     DEBUG: <core> [parser/msg_parser.c:515]: parse_headers():
>>     parse_headers: this is the first via
>>     DEBUG: <core> [receive.c:152]: receive_msg(): After parse_msg...
>>     DEBUG: <core> [receive.c:193]: receive_msg(): preparing to run
>>     routing scripts...
>>     DEBUG: sl [sl_funcs.c:412]: sl_filter_ACK(): DEBUG :
>>     sl_filter_ACK: to late to be a local ACK!
>>     DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 69
>>     DEBUG: maxfwd [maxfwd.c:161]: process_maxfwd_header(): value 69
>>     decreased to 16
>>     DEBUG: <core> [parser/parse_addr_spec.c:176]: parse_to_param():
>>     DEBUG: add_param: tag=a6a1c5f60faecf035a1ae5b6e96e979a-3d6b
>>     DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec():
>>     end of header reached, state=29
>>     DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG:
>>     get_hdr_field: <To> [74]; uri=[sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>]
>>     DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG:
>>     to body [<sip:43111223344 at 10.10.10.5
>>     <mailto:sip%3A43111223344 at 10.10.10.5>>]
>>     DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field():
>>     get_hdr_field: cseq <CSeq>: <58787375> <ACK>
>>     DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG:
>>     get_hdr_body : content_length=0
>>     DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found
>>     end of header
>>     DEBUG: <core> [parser/parse_addr_spec.c:176]: parse_to_param():
>>     DEBUG: add_param: tag=1eQFK719e4cyS
>>     DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec():
>>     end of header reached, state=29
>>     DEBUG: tm [t_reply.c:1663]: cleanup_uac_timers(): DEBUG:
>>     cleanup_uac_timers: RETR/FR timers reset
>>     DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks
>>     result: 1
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: siputils [checks.c:106]: has_totag(): totag found
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: rr [loose.c:113]: find_first_route(): No Route headers found
>>     DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp
>>     list (nil)
>>     DEBUG: rr [loose.c:929]: loose_route(): There is no Route HF
>>     DEBUG: <core> [receive.c:296]: receive_msg(): receive_msg:
>>     cleaning up
>>     DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=0 T start=0xffffffffffffffff
>>     DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request:
>>     start searching: hash=49678, isACK=1
>>     DEBUG: tm [t_lookup.c:470]: matching_3261(): DEBUG: RFC3261
>>     transaction matched, tid=ZZDHrKg1B4Q1c
>>     DEBUG: tm [t_lookup.c:726]: t_lookup_request(): DEBUG:
>>     t_lookup_request: transaction found (T=0x7f9ae79e2c10)
>>     DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg:
>>     msg id=1 global id=1 T end=0x7f9ae79e2c10
>>     DEBUG: tm [t_reply.c:1663]: cleanup_uac_timers(): DEBUG:
>>     cleanup_uac_timers: RETR/FR timers reset
>>     DEBUG: <core> [timer.c:595]: timer_add_safe(): timer_add called
>>     on an active timer 0x7f9ae79e2c90 (0x7f9ae778eb18,
>>     0x7f9ae778eb18), flags 201
>>     DEBUG: tm [t_funcs.c:180]: put_on_wait(): tm: put_on_wait:
>>     transaction 0x7f9ae79e2c10 already on wait
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [usr_avp.c:644]: destroy_avp_list():
>>     DEBUG:destroy_avp_list: destroying list (nil)
>>     DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp
>>     list (nil)
>>     DEBUG: <core> [receive.c:296]: receive_msg(): receive_msg:
>>     cleaning up
>>
>>
>>     and call flow:
>>       0.000000  10.10.5.5 -> 10.10.10.5 SIP/SDP 1100 Request: INVITE
>>     sip:43111223344 at 10.10.10.5 <mailto:sip%3A43111223344 at 10.10.10.5>
>>     | , with session description
>>       0.002140 10.10.10.5 -> 10.10.5.5  SIP 377 Status: 100 trying --
>>     your call is important to us |
>>       0.002264 10.10.10.5 -> 10.10.10.9 SIP/SDP 1250 Request: INVITE
>>     sip:43111223344 at 10.10.10.9 <mailto:sip%3A43111223344 at 10.10.10.9>
>>     | , with session description
>>       0.002836 10.10.10.9 -> 10.10.10.5 SIP 442 Status: 100 Trying |
>>       0.370466 10.10.10.9 -> 10.10.10.5 SIP 565 Status: 503 Service
>>     unavailable |
>>       0.370858 10.10.10.5 -> 10.10.10.9 SIP 379 Request: ACK
>>     sip:43111223344 at 10.10.10.9 <mailto:sip%3A43111223344 at 10.10.10.9> |
>>       0.370969 10.10.10.5 -> 10.10.5.5  SIP 400 Status: 500 Service
>>     Unavailable |
>>       0.371441  10.10.5.5 -> 10.10.10.5 SIP 404 Request: ACK
>>     sip:43111223344 at 10.10.10.5 <mailto:sip%3A43111223344 at 10.10.10.5> |
>>
>>     Thanks
>>     Mac
>>
>>
>>     2014-04-22 19:51 GMT+02:00 Daniel-Constantin Mierla
>>     <miconda at gmail.com <mailto:miconda at gmail.com>>:
>>
>>         Hello,
>>
>>         can you set debug=3 in kamailio.cfg and then send the syslog
>>         messages for routing a call matching the drouting rule?
>>
>>         Cheers,
>>         Daniel
>>
>>
>>         On 22/04/14 19:12, Maciej Bylica wrote:
>>>         Hello,
>>>
>>>         I am working on version: kamailio 4.1.2 (x86_64/linux) and
>>>         heaving troubles with drouting module.
>>>         The problem i am facing is that kamailio cannot enter
>>>         routeid that is provided inside dr_rule table.
>>>
>>>         My last try was to use standard script file with placing
>>>         following data before t_relay
>>>                 if (!do_routing("10")) {
>>>         sl_send_reply("403", "No route for You");
>>>                                 exit;
>>>                 }
>>>
>>>         additionaly i have added route definition
>>>         route[1] {
>>>                 xlog("L_INFO","[INFO] Default route --- fn-$fn,
>>>         fu-$fu, fU-$fU, ru-$ru, rU-$rU, sp-$sp, si-$si, tu-$tu,
>>>         tU-$tU");
>>>                 uac_replace_from("sip:$fU at 10.10.10.5
>>>         <mailto:fU at 10.10.10.5>");
>>>
>>>         }
>>>
>>>         My DB contains following data:
>>>         +--------+---------+--------+---------+----------+---------+--------+--------------+
>>>         | ruleid | groupid | prefix | timerec | priority | routeid |
>>>         gwlist | description  |
>>>         +--------+---------+--------+---------+----------+---------+--------+--------------+
>>>         |      1 | 10      | 43     |       |        0 | 1       | 1
>>>          | test rule |
>>>         +--------+---------+--------+---------+----------+---------+--------+--------------+
>>>
>>>         +------+------+---------------+-------+------------+-------+-------------+
>>>         | gwid | type | address       | strip | pri_prefix | attrs |
>>>         description |
>>>         +------+------+---------------+-------+------------+-------+-------------+
>>>         |    1 |   10 | 10.10.10.9 |   0 | NULL       | NULL  |
>>>         FirstGW    |
>>>         +------+------+---------------+-------+------------+-------+-------------+
>>>
>>>         The result is that script is omitting route[1] without any
>>>         purpose (xlog is not shown, $fU is not modified).
>>>
>>>         Could somebody help me to understand whats going on?
>>>
>>>         Thanks in advanced,
>>>         Mac
>>>
>>>
>>>         _______________________________________________
>>>         SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
>>>         sr-users at lists.sip-router.org  <mailto:sr-users at lists.sip-router.org>
>>>         http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>
>>         -- 
>>         Daniel-Constantin Mierla -http://www.asipto.com
>>         http://twitter.com/#!/miconda  <http://twitter.com/#%21/miconda>  -http://www.linkedin.com/in/miconda
>>
>>
>>         _______________________________________________
>>         SIP Express Router (SER) and Kamailio (OpenSER) - sr-users
>>         mailing list
>>         sr-users at lists.sip-router.org
>>         <mailto:sr-users at lists.sip-router.org>
>>         http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>
>>
>>
>
> -- 
> Daniel-Constantin Mierla -http://www.asipto.com
> http://twitter.com/#!/miconda  -http://www.linkedin.com/in/miconda

-- 
Daniel-Constantin Mierla - http://www.asipto.com
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20140424/8e673ee7/attachment.html>


More information about the sr-users mailing list