[SR-Users] kamailio does not responde if an rtpengine is unreachable
Daniel-Constantin Mierla
miconda at gmail.com
Mon Dec 24 12:14:15 CET 2018
Can you see how many kamailio processes are running (w.g., with ps)? Are
there expected number of there?
If yes, take the PID of few of them and attach with gdb, then grab the
back trace in order to see what they do.
Cheers,
Daniel
On 24.12.18 11:33, Juha Heinanen wrote:
> 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
>
--
Daniel-Constantin Mierla -- www.asipto.com
www.twitter.com/miconda -- www.linkedin.com/in/miconda
Kamailio World Conference - May 6-8, 2019 -- www.kamailioworld.com
Kamailio Advanced Training - Mar 4-6, 2019 in Berlin; Mar 25-27, 2019, in Washington, DC, USA -- www.asipto.com
More information about the sr-users
mailing list