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

[Seudin Kasumovic]

Hi,

see comments inline.

On Wed, Nov 6, 2013 at 12:35 PM, Daniel-Constantin Mierla <miconda at gmail.com
> wrote:

>  Hello,
>
> you message is not clear for me ... see some comments inline.
>
>
> On 11/5/13 2:22 PM, Seudin Kasumovic wrote:
>
>  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.
>
> Are you saying that only the state for the last probed uri is updated?
>

Yes.


>
>
>
>   Also, DEBUG log is broken. (kamailio version: kamailio 4.1.0-pre1
> (i386/linux)).
>
>
> What is broken with it? More details will help seeing the issue, dry
> messages don't help that much.
>

Log is produced with debug=3 for tm and dispatcher module, I tried with
debug=5 but not get more verbose then posted logs.

Debug output of these lines are not complete when update failed (cut off
after '>'):

DEBUG: dispatcher [dispatch.c:2359]: ds_options_callback(): OPTIONS-Request
was finished with code 200 (to 192.0.2.4:5090>
ERROR: dispatcher [dispatch.c:2372]: ds_options_callback(): Setting the
state failed (192.0.2.4:5090>

Regards,
Seudin


> Cheers,
> Daniel
>
>
>  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
>
>
> _______________________________________________
> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing listsr-users at lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>
>
> --
> Daniel-Constantin Mierla - http://www.asipto.comhttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
> Kamailio Advanced Trainings - Berlin, Nov 25-28
>   - more details about Kamailio trainings at http://www.asipto.com -
>
>
> _______________________________________________
> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
> sr-users at lists.sip-router.org
> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>
>


-- 
MSC Seudin Kasumovic
Tuzla, Bosnia
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20131106/f48b7dd9/attachment.html>