[SR-Users] kamailio does not responde if an rtpengine is unreachable

Mon Dec 24 11:33:18 CET 2018

Daniel-Constantin Mierla writes:

> what happens when you send a sip message and have debug=3? Do you see
> any logs printed?

Nothing comes to syslog when register request arrives.  Also kamailio does not respond to ctl command.
Below is sample on what comes to syslog after start.

-- Juha

root at char:/var/www/manager# Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: ERROR: rtpengine [rtpengine.c:2667]: send_rtpp_command(): timeout waiting reply for command "" from RTP proxy <udp:192.26.134.10:6050>
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: ERROR: rtpengine [rtpengine.c:2541]: rtpp_test(): proxy did not respond to ping
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: rtpengine [rtpengine.c:2518]: rtpp_test(): rtpp udp:192.26.134.10:6050 disabled for ever
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: mtree [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f324a610 not found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20315]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy
Dec 24 12:29:41 char /usr/bin/sip-proxy[20315]: INFO: rtpengine [rtpengine.c:2551]: rtpp_test(): rtp proxy <udp:127.0.0.1:6050> found, support for it enabled
Dec 24 12:29:41 char /usr/bin/sip-proxy[20315]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: mtree [mtree_mod.c:344]: child_init(): #3: database connection opened successfully
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: sipdump [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: siptrace [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b19b0 not found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_usage
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f72f32fd570
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_res.c:67]: db_mysql_get_columns(): 1 columns returned from the query
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f72f32ed3b0
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f72f3495140
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f72f3495170)[0]=[table_version]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_res.c:105]: db_mysql_get_columns(): use DB1_INT result type
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:188]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f72f305b780
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f72f305b4c0
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [4]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 1 columns
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f72f32ed3b0
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f72f3495140
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f72f305b4c0
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f72f305b780
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f72f32fd570
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: sl [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: tls [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: websocket [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: tmx [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: tmx [tmx_mod.c:258]: child_init(): rank is (3)
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: sqlops [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f32b0a80 found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b0a80 not found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_cache
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: domain [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: usrloc [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [ulcx-5c20b515-4f5d-] (0 / 19)
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: registrar [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [uloc-5c20b515-4f5d-] (0 / 19)
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: acc [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: auth_db [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: permissions [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: uac_redirect [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [rdir-5c20b515-4f5d-] (0 / 19)
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: nathelper [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: msilo [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: msilo [msilo.c:458]: child_init(): rank #3 / pid <20317>
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: msilo [msilo.c:477]: child_init(): #3 database connection opened successfully
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: htable [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: htable [htable.c:245]: child_init(): rank is (3)
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: kex [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: kex [kex_mod.c:155]: child_init(): rank is (3)
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [srid-5c20b515-4f5d-] (0 / 19)
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: rtimer [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: pua [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: pua [pua.c:312]: child_init(): child 3: Database connection opened successfully
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: local [udp receiver child=2 sock=192.168.43.107:5060]
Dec 24 12:29:41 char /usr/bin/sip-proxy[20317]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 3 rank 3: tcpops [udp receiver child=2 sock=192.168.43.107:5060]
^C
root at char:/var/www/manager# Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: ERROR: rtpengine [rtpengine.c:2667]: send_rtpp_command(): timeout waiting reply for command "" from RTP proxy <udp:192.26.134.10:6050>
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: ERROR: rtpengine [rtpengine.c:2541]: rtpp_test(): proxy did not respond to ping
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: rtpengine [rtpengine.c:2518]: rtpp_test(): rtpp udp:192.26.134.10:6050 disabled for ever
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: mtree [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f324a610 not found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy
Dec 24 12:29:46 char /usr/bin/sip-proxy[20316]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:46 char /usr/bin/sip-proxy[20316]: INFO: rtpengine [rtpengine.c:2551]: rtpp_test(): rtp proxy <udp:127.0.0.1:6050> found, support for it enabled
Dec 24 12:29:46 char /usr/bin/sip-proxy[20316]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: mtree [mtree_mod.c:344]: child_init(): #1: database connection opened successfully
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: sipdump [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: siptrace [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b19b0 not found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_usage
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f72f32fd570
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_res.c:67]: db_mysql_get_columns(): 1 columns returned from the query
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f72f32ed3b0
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f72f3495140
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f72f3495170)[0]=[table_version]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_res.c:105]: db_mysql_get_columns(): use DB1_INT result type
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:188]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f72f305b780
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f72f305b4c0
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [4]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 1 columns
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f72f32ed3b0
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f72f3495140
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f72f305b4c0
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f72f305b780
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f72f32fd570
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: sl [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: tls [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: websocket [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: tmx [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: tmx [tmx_mod.c:258]: child_init(): rank is (1)
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: sqlops [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f32b0a80 found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b0a80 not found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_cache
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: domain [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: usrloc [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [ulcx-5c20b51a-4f5b-] (0 / 19)
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: registrar [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [uloc-5c20b51a-4f5b-] (0 / 19)
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: acc [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: auth_db [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: permissions [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: uac_redirect [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [rdir-5c20b51a-4f5b-] (0 / 19)
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: nathelper [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: msilo [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: msilo [msilo.c:458]: child_init(): rank #1 / pid <20315>
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: msilo [msilo.c:477]: child_init(): #1 database connection opened successfully
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: htable [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: htable [htable.c:245]: child_init(): rank is (1)
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: kex [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: kex [kex_mod.c:155]: child_init(): rank is (1)
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [srid-5c20b51a-4f5b-] (0 / 19)
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: rtimer [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: pua [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: pua [pua.c:312]: child_init(): child 1: Database connection opened successfully
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: local [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 1 rank 1: tcpops [udp receiver child=0 sock=192.168.43.107:5060]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/action.c:1664]: run_child_one_init_route(): attempting to run event_route[core:worker-one-init]
Dec 24 12:29:46 char /usr/bin/sip-proxy[20315]: DEBUG: <core> [core/route.c:129]: route_add(): mapping routing block (0x5643cd4f2080)[core:worker-one-init] to 10
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: ERROR: rtpengine [rtpengine.c:2667]: send_rtpp_command(): timeout waiting reply for command "" from RTP proxy <udp:192.26.134.10:6050>
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: ERROR: rtpengine [rtpengine.c:2541]: rtpp_test(): proxy did not respond to ping
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: rtpengine [rtpengine.c:2518]: rtpp_test(): rtpp udp:192.26.134.10:6050 disabled for ever
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: mtree [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f324a610 not found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy
Dec 24 12:29:51 char /usr/bin/sip-proxy[20318]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: mtree [mtree_mod.c:344]: child_init(): #2: database connection opened successfully
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: sipdump [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: siptrace [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b19b0 not found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_usage
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f72f32fd570
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_res.c:67]: db_mysql_get_columns(): 1 columns returned from the query
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f72f32ed3b0
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f72f3495140
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f72f3495170)[0]=[table_version]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_res.c:105]: db_mysql_get_columns(): use DB1_INT result type
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:188]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f72f305b780
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f72f305b4c0
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [4]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 1 columns
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f72f32ed3b0
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f72f3495140
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f72f305b4c0
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f72f305b780
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f72f32fd570
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: sl [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: tls [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: websocket [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: tmx [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: tmx [tmx_mod.c:258]: child_init(): rank is (2)
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: sqlops [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f32b0a80 found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b0a80 not found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_cache
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: domain [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: usrloc [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [ulcx-5c20b51f-4f5c-] (0 / 19)
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: registrar [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [uloc-5c20b51f-4f5c-] (0 / 19)
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: acc [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: auth_db [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: permissions [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: uac_redirect [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [rdir-5c20b51f-4f5c-] (0 / 19)
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: nathelper [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: msilo [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: msilo [msilo.c:458]: child_init(): rank #2 / pid <20316>
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: msilo [msilo.c:477]: child_init(): #2 database connection opened successfully
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: htable [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: htable [htable.c:245]: child_init(): rank is (2)
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: kex [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: kex [kex_mod.c:155]: child_init(): rank is (2)
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [srid-5c20b51f-4f5c-] (0 / 19)
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: rtimer [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: pua [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: pua [pua.c:312]: child_init(): child 2: Database connection opened successfully
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: local [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20316]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 2 rank 2: tcpops [udp receiver child=1 sock=192.168.43.107:5060]
Dec 24 12:29:51 char /usr/bin/sip-proxy[20318]: INFO: rtpengine [rtpengine.c:2551]: rtpp_test(): rtp proxy <udp:127.0.0.1:6050> found, support for it enabled
Dec 24 12:29:51 char /usr/bin/sip-proxy[20318]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: ERROR: rtpengine [rtpengine.c:2667]: send_rtpp_command(): timeout waiting reply for command "" from RTP proxy <udp:192.26.134.10:6050>
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: ERROR: rtpengine [rtpengine.c:2541]: rtpp_test(): proxy did not respond to ping
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: rtpengine [rtpengine.c:2518]: rtpp_test(): rtpp udp:192.26.134.10:6050 disabled for ever
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: mtree [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f324a610 not found in pool
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy
Dec 24 12:29:56 char /usr/bin/sip-proxy[20321]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:56 char /usr/bin/sip-proxy[20321]: INFO: rtpengine [rtpengine.c:2551]: rtpp_test(): rtp proxy <udp:127.0.0.1:6050> found, support for it enabled
Dec 24 12:29:56 char /usr/bin/sip-proxy[20321]: DEBUG: rtpengine [rtpengine.c:668]: bind_force_send_ip(): force_send_ip_str not specified in .cfg file!
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: mtree [mtree_mod.c:344]: child_init(): #4: database connection opened successfully
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: sipdump [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: siptrace [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b19b0 not found in pool
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_usage
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f72f32fd570
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_res.c:67]: db_mysql_get_columns(): 1 columns returned from the query
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f72f32ed3b0
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f72f3495140
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f72f3495170)[0]=[table_version]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_res.c:105]: db_mysql_get_columns(): use DB1_INT result type
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:188]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f72f305b780
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f72f305b4c0
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [4]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 1 columns
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f72f3495170
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f72f32ed3b0
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f72f3495140
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f72f305b4c0
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f72f305b780
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f72f32fd570
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: sl [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: tls [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: websocket [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: tmx [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: tmx [tmx_mod.c:258]: child_init(): rank is (4)
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:841]: init_mod_child(): idx 4 rank 4: sqlops [udp receiver child=3 sock=192.168.43.107:5060]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f32b0a80 found in pool
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f72f32b0a80 not found in pool
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:107]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/sip_proxy_cache
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:146]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:147]: db_mysql_new_connection(): protocol version is 10
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: db_mysql [km_my_con.c:148]: db_mysql_new_connection(): server version is 10.1.37-MariaDB-0+deb9u1
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [core/sr_module.c:618]: find_mod_export_record(): found export of <db_bind_api> in module db_mysql [/usr/lib/x86_64-linux-gnu/sip-proxy/modules/db_mysql.so]
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
Dec 24 12:29:56 char /usr/bin/sip-proxy[20318]: DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f72f332e090 found in pool