[SR-Users] dispatcher: ds_options_callback, Setting the state failed

[Seudin Kasumovic]

Hi,

Test configuration uses OPTION for ping method. In log below host
198.51.100.68 probed first but reply come latter then 192.0.2.4. Seams that
only last probed uri tried for setting state other hosts. Also, DEBUG log
is broken. (kamailio version: kamailio 4.1.0-pre1 (i386/linux)).

Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: dispatcher
[dispatch.c:2420]: ds_check_timer(): probing set #28, URI sip:
198.51.100.68:5060
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: tm [uac.c:243]:
t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:172.16.23.130>
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: tm [uac.c:182]:
dlg2hash(): DEBUG: dlg2hash: 33871
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: tm [uac.c:345]:
t_uac_prepare(): executing event_route[tm:local-request]
Nov  5 12:48:01 localhost kamailio[23729]: INFO: <script>:
1280:tm:local-request OPTIONS sip:198.51.100.68:5060 --ID=
77db7ea0-23729 at 172.16.23.5
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: tm [uac.c:410]:
t_uac_prepare(): apply new updates to sip msg
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: dispatcher
[dispatch.c:2420]: ds_check_timer(): probing set #0, URI sip:192.0.2.4:5090
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: tm [uac.c:243]:
t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:172.16.23.130>
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: tm [uac.c:182]:
dlg2hash(): DEBUG: dlg2hash: 33872
Nov  5 12:48:01 localhost kamailio[23729]: DEBUG: tm [uac.c:345]:
t_uac_prepare(): executing event_route[tm:local-request]
Nov  5 12:48:01 localhost kamailio[23729]: INFO: <script>:
1280:tm:local-request OPTIONS sip:192.0.2.4:5090 --ID=
77db7ea1-23729 at 172.16.23.5
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_lookup.c:1071]:
t_check_msg(): DEBUG: t_check_msg: msg id=54 global id=53 T start=(nil)
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_lookup.c:949]:
t_reply_matching(): DEBUG: t_reply_matching: hash 33871 label 0 branch 0
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_lookup.c:1003]:
t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0xb492a8a8)!
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_lookup.c:1140]:
t_check_msg(): DEBUG: t_check_msg: msg id=54 global id=54 T end=0xb492a8a8
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_reply.c:2205]:
reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0
local=2 is_invite=0)
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_reply.c:1309]:
t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_reply.c:2085]:
local_reply(): DEBUG: local_reply: branch=0, save=0, winner=0
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_reply.c:2122]:
local_reply(): DEBUG: local transaction completed
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_hooks.c:288]:
run_trans_callbacks_internal(): DBG: trans=0xb492a8a8, callback type 1024,
id 0 entered
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: dispatcher
[dispatch.c:2359]: ds_options_callback(): OPTIONS-Request was finished with
code 200 (to 192.0.2.4:5090>
Nov  5 12:48:01 localhost kamailio[23725]: ERROR: dispatcher
[dispatch.c:2372]: ds_options_callback(): Setting the state failed (
192.0.2.4:5090>
Nov  5 12:48:01 localhost kamailio[23725]: DEBUG: tm [t_reply.c:1667]:
cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_lookup.c:1071]:
t_check_msg(): DEBUG: t_check_msg: msg id=50 global id=49 T start=(nil)
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_lookup.c:949]:
t_reply_matching(): DEBUG: t_reply_matching: hash 33872 label 0 branch 0
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_lookup.c:1003]:
t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0xb492c918)!
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_lookup.c:1140]:
t_check_msg(): DEBUG: t_check_msg: msg id=50 global id=50 T end=0xb492c918
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_reply.c:2205]:
reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0
local=2 is_invite=0)
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_reply.c:1309]:
t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_reply.c:2085]:
local_reply(): DEBUG: local_reply: branch=0, save=0, winner=0
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_reply.c:2122]:
local_reply(): DEBUG: local transaction completed
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: tm [t_hooks.c:288]:
run_trans_callbacks_internal(): DBG: trans=0xb492c918, callback type 1024,
id 0 entered
Nov  5 12:48:01 localhost kamailio[23726]: DEBUG: dispatcher
[dispatch.c:2359]: ds_options_callback(): OPTIONS-Request was finished with
code 200 (to sip:192.0.2.4:5090, group 0)


ds_list shows:
SET_NO:: 2
SET:: 28
URI:: sip:198.51.100.68:5060 flags=TX priority=1 attrs=
SET:: 0
URI:: sip:192.0.2.4:5090 flags=AP priority=1 attrs=


-- 
Seudin Kasumovic
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20131105/62fe1e71/attachment.html>