<div dir="ltr">Hi everyone,<div><br> I'm dealing with, at least, one opensips crash a day. For some reason, when Opensips try to restore a dead connection in the DBs connections pool, it can't find it and then it shuts down with the following message:</div><div><br>CRITICAL:core:db_store_async_con: #012>>> DB con 0x7fb857f4d178 not found#012It seems you have hit a programming bug.#012Please help us make OpenSIPS better by reporting it at <a href="https://github.com/OpenSIPS/opensips/issues">https://github.com/OpenSIPS/opensips/issues</a></div><div><br>BTW I'm using Opensips 2.4.5 with a cluster of MariaDB DBs in the same network. I couldn´t relate it with a DB issue, the databases are working fine. <br><br>Could anyone give me any clues about this?<br><br></div><div>Thanks in advance,<br><div><div><br></div><div><br></div><div>10:29:34 Opensips[7213]: DBG:avpops:ops_async_dbquery: query [SELECT (COUNT(*) > 0), user_type FROM app.user_app where client_phone = 'XXXXXXXXXX' AND status = 'ON']<br>10:29:34 Opensips[7213]: DBG:db_virtual:db_virtual_async_raw_query: f call handle size = 2<br>10:29:34 Opensips[7213]: DBG:db_virtual:try_reconnect: try reconnect<br>10:29:34 Opensips[7213]: DBG:db_virtual:db_virtual_async_raw_query: flags1 = 3<br>10:29:34 Opensips[7213]: DBG:core:db_init_async: >>    1/10 transfers: (6 - 0x7fb857f4d178)<br>10:29:34 Opensips[7213]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected<br>10:29:34 Opensips[7213]: DBG:tm:t_handle_async: placing async job into reactor<br>10:29:34 Opensips[7213]: DBG:tm:io_watch_add: [UDP_worker] io_watch_add op (6 on 12) (0x55b578c3dfa0, 6, 16, 0x7fb8181d8dd0,1), fd_no=4/1024<br>10:29:34 Opensips[7213]: DBG:core:destroy_avp_list: destroying list (nil)<br>10:29:34 Opensips[7213]: DBG:core:receive_msg: cleaning up<br>10:29:34 Opensips[7213]: DBG:tm:t_resume_async: resuming on fd 6, transaction 0x7fb8181d7300<br>10:29:34 Opensips[7213]: DBG:db_mysql:db_mysql_async_resume: mysql_read_query_result: 2013, HY000 - "Lost connection to MySQL server during query"<br>10:29:34 Opensips[7213]: ERROR:db_mysql:db_mysql_async_resume: error [2013, HY000]: Lost connection to MySQL server during query<br>10:29:34 Opensips[7213]: DBG:core:db_store_async_con: >> restore conn 0x7fb857f4d178<br>10:29:34 Opensips[7213]: DBG:core:pool_remove: removing connection from the pool<br>10:29:34 Opensips[7213]: DBG:db_virtual:db_virtual_async_resume: f call handle size = 2<br>10:29:34 Opensips[7213]: DBG:db_virtual:try_reconnect: try reconnect<br>10:29:34 Opensips[7213]: DBG:core:db_do_init: connection 0x7fb857f4aeb0 not found in pool<br>10:29:34 Opensips[7213]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://<a href="http://xxxx:xxxx@10.200.33.21:3306/app">xxxx:xxxx@10.200.33.21:3306/app</a><br>10:29:34 Opensips[7213]: DBG:db_mysql:db_mysql_connect: connection type is 10.200.33.25 via TCP/IP<br>10:29:34 Opensips[7213]: DBG:db_mysql:db_mysql_connect: protocol version is 10<br>10:29:34 Opensips[7213]: DBG:db_mysql:db_mysql_connect: server version is 5.5.5-10.1.34-MariaDB-0ubuntu0.18.04.1<br>10:29:34 Opensips[7213]: DBG:core:db_do_init: connection 0x7fb857f4aeb0 inserted in pool as 0x7fb857f4a638<br>10:29:34 Opensips[7213]: DBG:db_virtual:db_virtual_async_resume: flags1 = 3<br>10:29:34 Opensips[7213]: DBG:core:db_init_async: >>    1/10 transfers: (44 - 0x7fb857f4ad60)<br>10:29:34 Opensips[7213]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected<br>10:29:34 Opensips[7213]: DBG:tm:io_watch_del: [UDP_worker] io_watch_del op on index -1 6 (0x55b578c3dfa0, 6, -1, 0x10,0x1) fd_no=5 called<br>10:29:34 Opensips[7213]: ERROR:tm:io_watch_del: [UDP_worker] removing fd from epoll (6 from 12) list failed: No such file or directory [2]<br>10:29:34 Opensips[7213]: DBG:tm:io_watch_add: [UDP_worker] io_watch_add op (44 on 12) (0x55b578c3dfa0, 44, 16, 0x7fb8181d8dd0,1), fd_no=4/1024<br>10:29:34 Opensips[7213]: DBG:tm:clean_msg_clone: removing hdr->parsed 7<br>10:29:34 Opensips[7213]: DBG:tm:t_resume_async: resuming on fd 44, transaction 0x7fb8181d7300<br>10:29:34 Opensips[7213]: DBG:db_mysql:db_mysql_async_resume: mysql_read_query_result: 2013, HY000 - "Lost connection to MySQL server during query"<br>10:29:34 Opensips[7213]: ERROR:db_mysql:db_mysql_async_resume: error [2013, HY000]: Lost connection to MySQL server during query<br>10:29:34 Opensips[7213]: DBG:core:db_store_async_con: >> restore conn 0x7fb857f4d178<br><b><font color="#ff0000">10:29:34 Opensips[7213]: CRITICAL:core:db_store_async_con: #012>>> DB con 0x7fb857f4d178 not found#012It seems you have hit a programming bug.#012Please help us make OpenSIPS better by reporting it at <a href="https://github.com/OpenSIPS/opensips/issues">https://github.com/OpenSIPS/opensips/issues</a></font></b><br>10:29:35 Opensips[7206]: DBG:uac_registrant:timer_check: checking ... [1581935375] on htable[0]<br>10:29:36 Opensips[7209]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 90000 us delay in execution<br>10:29:36 Opensips[7209]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 90000 us delay in execution<br>10:29:36 Opensips[7205]: WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for 73696360 ms (now 73696450 ms), it may overlap..<br>10:29:40 Opensips[7214]: DBG:db_virtual:reconnect_timer: reconnect with timer<br>10:29:40 Opensips[7200]: DBG:core:handle_sigs: OpenSIPS exit status = 134<br>10:29:40 Opensips[7200]: INFO:core:handle_sigs: child process 7213 exited by a signal 6<br>10:29:40 Opensips[7200]: INFO:core:handle_sigs: core was generated<br>10:29:40 Opensips[7200]: INFO:core:handle_sigs: terminating due to SIGCHLD<br>10:29:40 Opensips[7200]: DBG:core:shutdown_opensips: Asking process 6 [SIP receiver udp:<a href="http://10.200.33.25:5060">10.200.33.25:5060</a> ] to terminate<br>10:29:40 Opensips[7205]: INFO:core:sig_usr: signal 15 received<br>10:29:40 Opensips[7201]: INFO:core:sig_usr: signal 15 received<br>10:29:40 Opensips[7203]: INFO:core:sig_usr: signal 15 received<br>10:29:40 Opensips[7202]: INFO:core:sig_usr: signal 15 received<br>10:29:40 Opensips[7204]: INFO:core:sig_usr: signal 15 received<br>10:29:40 Opensips[7200]: DBG:core:shutdown_opensips: Asking process 7 [SIP receiver udp:<a href="http://10.200.33.25:5060">10.200.33.25:5060</a> ] to terminate<br></div></div></div></div>