[OpenSIPS-Users] possible bug with freeswitch module

[Liviu Chircu]

https://www.opensips.org/Documentation/TroubleShooting-OutOfMem

Liviu Chircu
OpenSIPS Developer
http://www.opensips-solutions.com

On 03.03.2019 05:40, junkmail wrote:
> Hello I have run into a problem with what seems to be the freeswitch 
> module and opensips version 2.4.4
>
> I have recently noticed that something will happen and then the CPU on 
> the system goes to 100% usage by opensips.
>
> Around the time of the start of the problem below are the log entries 
> I get from Opensips.  This repeats on for a little bit then stablizes 
> but the CPU stays at 100% after this.
>
> Let me know what other information I can provide.  Thanks.
>
>
> Feb 26 06:32:13 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:13 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1705i.XXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:load_balancer:mi_lb_reload: "lb_reload" MI command received!
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:db_mysql:switch_state_to_disconnected: disconnect event for 
> 0x7faf39244138
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:db_mysql:reset_all_statements: resetting all statements on 
> connection: (0x7faf392440a0) 0x7faf39244138
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:db_mysql:connect_with_retry: re-connected successful for 
> 0x7faf39244138
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_reconnects: failed to connect to FS sock 
> '1705i.XXXXXXXXXX.com:8021'
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:18 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_reconnects: failed to connect to FS sock 
> '1705i.XXXXXXXXXXX.com:8021'
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1703i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1536i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1535i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1241i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1575i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1706i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1574i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1700i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1709i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1697i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1704i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1710i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 979i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1696i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1701i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:23 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:load_balancer:mi_lb_reload: "lb_reload" MI command received!
> Feb 26 06:32:28 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_reconnects: failed to connect to FS sock 
> '1705i.XXXXXXXXXXX.com:8021'
> Feb 26 06:32:33 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_reconnects: failed to connect to FS sock 
> '1703i.XXXXXXXXXXX.com:8021'
> Feb 26 06:32:38 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_reconnects: failed to connect to FS sock 
> '1536i.XXXXXXXXXXX.com:8021'
> Feb 26 06:32:43 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_reconnects: failed to connect to FS sock 
> '1535i.XXXXXXXXXXX.com:8021'
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_reconnects: failed to connect to FS sock 
> '1241i.XXXXXXXXXXX.com:8021'
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: oom
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1575i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1706i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1574i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1700i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1709i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1697i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1704i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1710i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 979i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1696i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1701i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1427i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1702i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1699i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1695i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:load_balancer:mi_lb_reload: "lb_reload" MI command received!
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> ERROR:freeswitch:handle_io: read error 1 on FS sock 
> 1707i.XXXXXXXXXXX.com:8021. Reconnecting...
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1705i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1703i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1535i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5623]: 
> INFO:freeswitch:update_event_subscriptions: subscribed to HEARTBEAT 
> events on FS sock 1241i.XXXXXXXXXXX.com:8021
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:load_balancer:mi_lb_reload: "lb_reload" MI command received!
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:load_balancer:mi_lb_reload: "lb_reload" MI command received!
> Feb 26 06:32:48 ID2079LA /usr/sbin/opensips[5625]: 
> INFO:load_balancer:mi_lb_reload: "lb_reload" MI command received!
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users