[Freeswitch-users] FreeSWITCH not answering some REGISTERs

Sat Mar 18 22:04:18 MSK 2017

Mike,

with sofia loglevel all 9, I can see that sometimes (frequency hard to guess), during 4-5 secondes, FS writes those messages in the log for each REGISTER or OPTIONS request:

nta.c:3012 agent_recv_request() nta: OPTIONS (102) going to existing OPTIONS transaction
nta.c:3012 agent_recv_request() nta: REGISTER (116849) going to existing REGISTER transaction

It’s like it considers it’s already dealing with a such request (which it never replied to).

A typical regular flow of logs for a REGISTER which was responded to is:

nta.c:3085 agent_recv_request() nta: REGISTER (108692) going to a default leg
nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
nua_stack.c:899 nh_create() nua: nh_create: entering
nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:280 soa_clone() soa_clone(static::0x7fda40001930, 0x7fda40001130, 0x7fda40039330) called
soa.c:403 soa_set_params() soa_set_params(static::0x7fda40207f40, ...) called
nua_stack.c:271 nua_stack_event() nua(0x7fda40039330): event i_register 100 Trying
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:879 nua_respond() nua: nua_respond: entering
nua_stack.c:529 nua_signal() nua(0x7fda40039330): sent signal r_respond
nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
nua_stack.c:529 nua_signal() nua(0x7fda40039330): sent signal r_destroy
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
nua_stack.c:573 nua_stack_signal() nua(0x7fda40039330): recv signal r_respond 200 OK

When it fails to respond, it is:

nta.c:3085 agent_recv_request() nta: REGISTER (108693) going to a default leg
nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
nua_stack.c:899 nh_create() nua: nh_create: entering
nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:280 soa_clone() soa_clone(static::0x7fda40001930, 0x7fda40001130, 0x7fda402dc590) called
soa.c:403 soa_set_params() soa_set_params(static::0x7fda4014f880, ...) called
nua_stack.c:271 nua_stack_event() nua(0x7fda402dc590): event i_register 100 Trying
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering

It’s difficult to know which lines are related in the log, but when FS does not answer the 200 OK, I am definitely missing a line like:

nua_stack.c:529 nua_signal() nua(0x7fda40039330): sent signal r_respond

David Ponzone  Direction Technique
email: david.ponzone at ipeva.fr <mailto:david.ponzone at ipeva.fr>
tel:      01 74 03 18 97
gsm:   06 66 98 76 34

Service Client IPeva
tel:      0811 46 26 26
www.ipeva.fr <blocked::http://www.ipeva.fr/>  -   www.ipeva-studio.com <blocked::http://www.ipeva-studio.com/>

Ce message et toutes les pièces jointes sont confidentiels et établis à l'intention exclusive de ses destinataires. Toute utilisation ou diffusion non autorisée est interdite. Tout message électronique est susceptible d'altération. IPeva décline toute responsabilité au titre de ce message s'il a été altéré, déformé ou falsifié. Si vous n'êtes pas destinataire de ce message, merci de le détruire immédiatement et d'avertir l'expéditeur.

> Le 17 mars 2017 à 23:08, Michael Jerris <mike at jerris.com> a écrit :
> 
> you can try sofia loglevel all 9
> 
> see if that tells you anything when we get one we don't respond to...  also get latest 1.6 and see if that helps 
> On Fri, Mar 17, 2017 at 5:19 PM David Villasmil <david.villasmil.work at gmail.com <mailto:david.villasmil.work at gmail.com>> wrote:
> How's the memory use?
> On Fri, Mar 17, 2017 at 10:09 PM David Ponzone <david.ponzone at ipeva.fr <mailto:david.ponzone at ipeva.fr>> wrote:
> All,
> 
> I noticed recently that sometimes FS (1.6.6) does not honor REGISTER or OPTIONS requests coming from some customers.
> Those customers register every 30 seconds, and FS does not answer to around 20-30 REGISTERs each day.
> I am trying to understand that, as the server is far from loaded (max 100 concurrent calls on a Dell R610).
> 
> Can anyone recommend some debugging steps to try to narrow this down ?
> Any known issue of this sort in 1.6.6 ?
> 
> Thank you
> 
> David Ponzone  Direction Technique
> email: david.ponzone at ipeva.fr <mailto:david.ponzone at ipeva.fr>
> tel:      01 74 03 18 97
> gsm:   06 66 98 76 34
> 
> Service Client IPeva
> tel:      0811 46 26 26
> www.ipeva.fr <>  -   www.ipeva-studio.com <>
> 
> Ce message et toutes les pièces jointes sont confidentiels et établis à l'intention exclusive de ses destinataires. Toute utilisation ou diffusion non autorisée est interdite. Tout message électronique est susceptible d'altération. IPeva décline toute responsabilité au titre de ce message s'il a été altéré, déformé ou falsifié. Si vous n'êtes pas destinataire de ce message, merci de le détruire immédiatement et d'avertir l'expéditeur.
> 
> 
> 
> 
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org <mailto:consulting at freeswitch.org>
> http://www.freeswitchsolutions.com <http://www.freeswitchsolutions.com/>
> 
> Official FreeSWITCH Sites
> http://www.freeswitch.org <http://www.freeswitch.org/>
> http://confluence.freeswitch.org <http://confluence.freeswitch.org/>
> http://www.cluecon.com <http://www.cluecon.com/>
> 
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org <mailto:FreeSWITCH-users at lists.freeswitch.org>
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users <http://lists.freeswitch.org/mailman/listinfo/freeswitch-users>
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users <http://lists.freeswitch.org/mailman/options/freeswitch-users>
> http://www.freeswitch.org <http://www.freeswitch.org/>_________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org <mailto:consulting at freeswitch.org>
> http://www.freeswitchsolutions.com <http://www.freeswitchsolutions.com/>
> 
> Official FreeSWITCH Sites
> http://www.freeswitch.org <http://www.freeswitch.org/>
> http://confluence.freeswitch.org <http://confluence.freeswitch.org/>
> http://www.cluecon.com <http://www.cluecon.com/>
> 
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org <mailto:FreeSWITCH-users at lists.freeswitch.org>
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users <http://lists.freeswitch.org/mailman/listinfo/freeswitch-users>
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users <http://lists.freeswitch.org/mailman/options/freeswitch-users>
> http://www.freeswitch.org <http://www.freeswitch.org/>_________________________________________________________________________
> Professional FreeSWITCH Consulting Services: 
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
> 
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://confluence.freeswitch.org
> http://www.cluecon.com
> 
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20170318/e18974c8/attachment-0001.html 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 3745 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20170318/e18974c8/attachment-0001.bin 