[SR-Users] Kamailio stability/timing problem w.r.t. registrations?
Sotas Development
sotasdev at gmail.com
Thu Jan 17 14:41:25 CET 2013
Daniel,
> can you add an xlog() at the start of the main route block and log a
message for any
> request received? [...] You can put another xlog before the save()
function to see if
> registration requests are getting there. [...] You can switch to kamailio
flavour modules
> and see if reproduces.
Thanks for the help! We switched to kamailio flavour, added some xlog
messages and managed to reproduce. See below for some logging.
To our surprise, the last message handled was an INVITE. We'll add more
logging to see whether it is handled successfully or gets stuck somewhere.
Those numbers at the start of the log message, are these child IDs? At
first they alternate, but apparently child 1 and 2 stop running early on
(each with an INVITE as last message).
Regards,
Michiel Veldkamp
0(304) WARNING: <core> [socket_info.c:1392]: WARNING: fix_hostname: could
not rev. resolve 192.168.10.1
0(304) INFO: <core> [tcp_main.c:4832]: init_tcp: using epoll_lt as the io
watch method (auto detected)
0(306) INFO: usrloc [hslot.c:53]: locks array size 512
0(306) INFO: <core> [udp_server.c:179]: INFO: udp_init: SO_RCVBUF is
initially 108544
0(306) INFO: <core> [udp_server.c:230]: INFO: udp_init: SO_RCVBUF is
finally 217088
7(315) INFO: ctl [io_listener.c:225]: io_listen_loop: using epoll_lt io
watch method (config)
1(309) ERROR: <script>: request_route start -- method=REGISTER
2(310) ERROR: <script>: request_route start -- method=REGISTER
3(311) ERROR: <script>: request_route start -- method=REGISTER
3(311) ERROR: <script>: route[REGISTRAR] start
2(310) ERROR: <script>: route[REGISTRAR] start
3(311) ERROR: <script>: route[REGISTRAR] saving location...
2(310) ERROR: <script>: route[REGISTRAR] saving location...
1(309) ERROR: <script>: route[REGISTRAR] start
1(309) ERROR: <script>: route[REGISTRAR] saving location...
1(309) ERROR: <script>: route[REGISTRAR] saving location... done
3(311) ERROR: <script>: route[REGISTRAR] saving location... done
2(310) ERROR: <script>: route[REGISTRAR] saving location... done
3(311) ERROR: <script>: request_route start -- method=REGISTER
3(311) ERROR: <script>: route[REGISTRAR] start
3(311) ERROR: <script>: route[REGISTRAR] saving location...
3(311) ERROR: <script>: route[REGISTRAR] saving location... done
2(310) ERROR: <script>: request_route start -- method=REGISTER
1(309) ERROR: <script>: request_route start -- method=REGISTER
1(309) ERROR: <script>: route[REGISTRAR] start
1(309) ERROR: <script>: route[REGISTRAR] saving location...
2(310) ERROR: <script>: route[REGISTRAR] start
2(310) ERROR: <script>: route[REGISTRAR] saving location...
1(309) ERROR: <script>: route[REGISTRAR] saving location... done
2(310) ERROR: <script>: route[REGISTRAR] saving location... done
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: route[REGISTRAR] start
1(309) ERROR: <script>: request_route start -- method=INVITE
1(309) ERROR: <script>: route[REGISTRAR] start
2(310) ERROR: <script>: request_route start -- method=INVITE
2(310) ERROR: <script>: route[REGISTRAR] start
1(309) ERROR: <script>: request_route start -- method=INVITE
1(309) ERROR: <script>: route[REGISTRAR] start
2(310) ERROR: <script>: request_route start -- method=INVITE
2(310) ERROR: <script>: route[REGISTRAR] start
2(310) ERROR: <script>: request_route start -- method=ACK
1(309) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034404;method=INVITE;from_tag=966002447;to_tag=3a8473f2;call_id=
331189810 at 192.168.10.2
;code=200;reason=OK;src_user=Radio1_device;src_domain=testnet;src_ip=192.168.10.2;dst_ouser=Radio1;dst_user=3;dst_domain=192.168.10.2
3(311) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034404;method=INVITE;from_tag=1718943109;to_tag=22a97aa7;call_id=
956233863 at 192.168.10.2
;code=200;reason=OK;src_user=User3;src_domain=testnet;src_ip=192.168.10.2;dst_ouser=IC1;dst_user=1;dst_domain=192.168.10.2
3(311) ERROR: <script>: request_route start -- method=ACK
1(309) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034404;method=INVITE;from_tag=1709043140;to_tag=4aa972a8;call_id=
780056350 at 192.168.10.2
;code=200;reason=OK;src_user=User3;src_domain=testnet;src_ip=192.168.10.2;dst_ouser=IC2;dst_user=2;dst_domain=192.168.10.2
1(309) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034404;method=INVITE;from_tag=1506064927;to_tag=5a73a3fc;call_id=
1129604628 at 192.168.10.2
;code=200;reason=OK;src_user=User3;src_domain=testnet;src_ip=192.168.10.2;dst_ouser=emergency;dst_user=4;dst_domain=192.168.10.2
1(309) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034404;method=INVITE;from_tag=1200029211;to_tag=14c6d7d4;call_id=
1214006622 at 192.168.10.2
;code=200;reason=OK;src_user=User3;src_domain=testnet;src_ip=192.168.10.2;dst_ouser=Radio1;dst_user=3;dst_domain=192.168.10.2
1(309) ERROR: <script>: request_route start -- method=ACK
2(310) ERROR: <script>: request_route start -- method=ACK
3(311) ERROR: <script>: request_route start -- method=ACK
2(310) ERROR: <script>: request_route start -- method=REGISTER
2(310) ERROR: <script>: route[REGISTRAR] start
2(310) ERROR: <script>: route[REGISTRAR] saving location...
1(309) ERROR: <script>: request_route start -- method=REGISTER
1(309) ERROR: <script>: route[REGISTRAR] start
1(309) ERROR: <script>: route[REGISTRAR] saving location...
2(310) ERROR: <script>: route[REGISTRAR] saving location... done
1(309) ERROR: <script>: route[REGISTRAR] saving location... done
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: route[REGISTRAR] start
3(311) ERROR: <script>: request_route start -- method=ACK
1(309) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034419;method=INVITE;from_tag=22866096;to_tag=461b3848;call_id=
119348927 at 192.168.10.1
;code=200;reason=OK;src_user=User1;src_domain=testnet;src_ip=192.168.10.1;dst_ouser=IC1;dst_user=1;dst_domain=192.168.10.2
2(310) ERROR: <script>: request_route start -- method=INVITE
2(310) ERROR: <script>: route[REGISTRAR] start
2(310) ERROR: <script>: request_route start -- method=ACK
3(311) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034419;method=INVITE;from_tag=1086463804;to_tag=1b28a959;call_id=
1666188208 at 192.168.10.1
;code=200;reason=OK;src_user=User1;src_domain=testnet;src_ip=192.168.10.1;dst_ouser=IC2;dst_user=2;dst_domain=192.168.10.2
1(309) ERROR: <script>: request_route start -- method=INVITE
1(309) ERROR: <script>: route[REGISTRAR] start
1(309) ERROR: <script>: request_route start -- method=ACK
2(310) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034419;method=INVITE;from_tag=425688445;to_tag=7c6f5fd8;call_id=
138053674 at 192.168.10.1
;code=200;reason=OK;src_user=User1;src_domain=testnet;src_ip=192.168.10.1;dst_ouser=emergency;dst_user=4;dst_domain=192.168.10.2
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: route[REGISTRAR] start
3(311) ERROR: <script>: request_route start -- method=ACK
2(310) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034419;method=INVITE;from_tag=2111220874;to_tag=6a7be79d;call_id=
1381530996 at 192.168.10.1
;code=200;reason=OK;src_user=User1;src_domain=testnet;src_ip=192.168.10.1;dst_ouser=Radio1;dst_user=3;dst_domain=192.168.10.2
1(309) ERROR: <script>: request_route start -- method=INVITE
1(309) ERROR: <script>: route[REGISTRAR] start
1(309) ERROR: <script>: request_route start -- method=ACK
2(310) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034419;method=INVITE;from_tag=269879616;to_tag=210d7864;call_id=
907816659 at 192.168.10.1
;code=200;reason=OK;src_user=User2;src_domain=testnet;src_ip=192.168.10.1;dst_ouser=IC1;dst_user=1;dst_domain=192.168.10.2
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: route[REGISTRAR] start
1(309) NOTICE: acc [acc.c:275]: ACC: transaction answered:
timestamp=1350034419;method=INVITE;from_tag=1891265955;to_tag=29747a68;call_id=
1205241696 at 192.168.10.1
;code=200;reason=OK;src_user=User2;src_domain=testnet;src_ip=192.168.10.1;dst_ouser=emergency;dst_user=4;dst_domain=192.168.10.2
3(311) ERROR: <script>: request_route start -- method=ACK
2(310) ERROR: <script>: request_route start -- method=INVITE
1(309) ERROR: <script>: request_route start -- method=INVITE
2(310) ERROR: <script>: request_route start -- method=ACK
2(310) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: request_route start -- method=ACK
3(311) ERROR: <script>: request_route start -- method=INVITE
2(310) ERROR: <script>: request_route start -- method=INVITE <-- Last
message from 2(310)
3(311) ERROR: <script>: request_route start -- method=ACK
3(311) ERROR: <script>: request_route start -- method=ACK
3(311) ERROR: <script>: request_route start -- method=ACK
1(309) ERROR: <script>: request_route start -- method=INVITE <-- Last
message from 1(309)
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: request_route start -- method=ACK
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: request_route start -- method=ACK
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: request_route start -- method=ACK
3(311) ERROR: <script>: request_route start -- method=INVITE
3(311) ERROR: <script>: request_route start -- method=ACK
...
3(311) ERROR: <script>: request_route start -- method=REGISTER
3(311) ERROR: <script>: route[REGISTRAR] start
3(311) ERROR: <script>: route[REGISTRAR] saving location...
3(311) ERROR: <script>: route[REGISTRAR] saving location... done
3(311) ERROR: <script>: request_route start -- method=INVITE <-- Last
message
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20130117/75069708/attachment.htm>
More information about the sr-users
mailing list