2026-05-12T19:52:52.523Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovsdb-server.log 2026-05-12T19:52:52.538Z|00002|ovsdb_server|INFO|ovsdb-server (Open vSwitch) 3.3.4 2026-05-12T19:53:02.553Z|00003|memory|INFO|7220 kB peak resident set size after 10.0 seconds 2026-05-12T19:53:02.553Z|00004|memory|INFO|atoms:186 cells:131 json-caches:1 monitors:3 n-weak-refs:0 sessions:2 2026-05-12T19:57:17.670Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovsdb-server.log 2026-05-12T19:57:17.681Z|00002|ovsdb_server|INFO|ovsdb-server (Open vSwitch) 3.3.4 2026-05-12T19:57:27.704Z|00003|memory|INFO|7956 kB peak resident set size after 10.0 seconds 2026-05-12T19:57:27.704Z|00004|memory|INFO|atoms:807 cells:241 json-caches:3 monitors:6 n-weak-refs:0 sessions:4 2026-05-12T20:13:51.544Z|00005|jsonrpc|WARN|tcp:127.0.0.1:52482: send error: Broken pipe 2026-05-12T20:13:51.545Z|00006|jsonrpc|WARN|tcp:127.0.0.1:56076: send error: Broken pipe 2026-05-12T20:13:51.545Z|00007|timeval|WARN|Unreasonably long 9389ms poll interval (0ms user, 7438ms system) 2026-05-12T20:13:51.545Z|00008|timeval|WARN|faults: 205 minor, 837 major 2026-05-12T20:13:51.545Z|00009|timeval|WARN|disk: 200016 reads, 8 writes 2026-05-12T20:13:51.545Z|00010|timeval|WARN|context switches: 870 voluntary, 8509 involuntary 2026-05-12T20:13:51.545Z|00011|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=7f70cdd3: 2026-05-12T20:13:51.545Z|00012|coverage|INFO|util_xalloc 70.2/sec 1580.617/sec 534.4067/sec total: 1923864 2026-05-12T20:13:51.545Z|00013|coverage|INFO|unixctl_replied 0.0/sec 0.000/sec 0.0006/sec total: 2 2026-05-12T20:13:51.545Z|00014|coverage|INFO|unixctl_received 0.0/sec 0.000/sec 0.0006/sec total: 2 2026-05-12T20:13:51.545Z|00015|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-05-12T20:13:51.545Z|00016|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-05-12T20:13:51.545Z|00017|coverage|INFO|poll_zero_timeout 0.2/sec 0.733/sec 0.2358/sec total: 849 2026-05-12T20:13:51.545Z|00018|coverage|INFO|poll_create_node 0.8/sec 27.917/sec 9.3400/sec total: 33624 2026-05-12T20:13:51.545Z|00019|coverage|INFO|lockfile_lock 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-05-12T20:13:51.545Z|00020|coverage|INFO|hmap_expand 1.6/sec 36.250/sec 13.0831/sec total: 47099 2026-05-12T20:13:51.545Z|00021|coverage|INFO|hmap_pathological 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-05-12T20:13:51.545Z|00022|coverage|INFO|113 events never hit 2026-05-12T20:13:51.545Z|00023|poll_loop|INFO|wakeup due to 0-ms timeout at tcp:127.0.0.1:52482 (67% CPU usage) 2026-05-12T20:13:51.545Z|00024|reconnect|WARN|tcp:127.0.0.1:52482: connection dropped (Broken pipe) 2026-05-12T20:13:51.546Z|00025|reconnect|WARN|tcp:127.0.0.1:56076: connection dropped (Broken pipe) 2026-05-12T20:13:52.060Z|00026|poll_loop|INFO|wakeup due to [POLLIN] on fd 24 (127.0.0.1:6640<->) at ../lib/stream-fd.c:274 (67% CPU usage) 2026-05-12T20:13:52.060Z|00027|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (127.0.0.1:6640<->127.0.0.1:58460) at ../lib/stream-fd.c:157 (67% CPU usage) 2026-05-12T20:13:52.061Z|00028|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (127.0.0.1:6640<->127.0.0.1:58460) at ../lib/stream-fd.c:157 (67% CPU usage) 2026-05-12T20:13:52.080Z|00029|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (127.0.0.1:6640<->127.0.0.1:58460) at ../lib/stream-fd.c:157 (67% CPU usage) 2026-05-12T20:13:52.081Z|00030|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (127.0.0.1:6640<->127.0.0.1:58460) at ../lib/stream-fd.c:157 (67% CPU usage) 2026-05-12T20:13:52.317Z|00031|poll_loop|INFO|wakeup due to [POLLIN] on fd 24 (127.0.0.1:6640<->) at ../lib/stream-fd.c:274 (67% CPU usage) 2026-05-12T20:13:52.318Z|00032|poll_loop|INFO|wakeup due to [POLLIN] on fd 27 (127.0.0.1:6640<->127.0.0.1:58468) at ../lib/stream-fd.c:157 (67% CPU usage) 2026-05-12T20:13:52.321Z|00033|poll_loop|INFO|wakeup due to [POLLIN] on fd 27 (127.0.0.1:6640<->127.0.0.1:58468) at ../lib/stream-fd.c:157 (67% CPU usage) 2026-05-12T20:13:52.345Z|00034|poll_loop|INFO|wakeup due to [POLLIN] on fd 27 (127.0.0.1:6640<->127.0.0.1:58468) at ../lib/stream-fd.c:157 (67% CPU usage) 2026-05-12T20:14:00.126Z|00035|timeval|WARN|Unreasonably long 2880ms poll interval (0ms user, 2408ms system) 2026-05-12T20:14:00.126Z|00036|timeval|WARN|faults: 47 minor, 218 major 2026-05-12T20:14:00.126Z|00037|timeval|WARN|disk: 49616 reads, 0 writes 2026-05-12T20:14:00.126Z|00038|timeval|WARN|context switches: 221 voluntary, 3029 involuntary 2026-05-12T20:14:00.126Z|00039|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=e3dad7a2: 2026-05-12T20:14:00.126Z|00040|coverage|INFO|util_xalloc 2634.8/sec 1760.000/sec 538.0661/sec total: 1937113 2026-05-12T20:14:00.126Z|00041|coverage|INFO|unixctl_replied 0.0/sec 0.000/sec 0.0006/sec total: 2 2026-05-12T20:14:00.126Z|00042|coverage|INFO|unixctl_received 0.0/sec 0.000/sec 0.0006/sec total: 2 2026-05-12T20:14:00.126Z|00043|coverage|INFO|long_poll_interval 0.2/sec 0.017/sec 0.0003/sec total: 1 2026-05-12T20:14:00.126Z|00044|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-05-12T20:14:00.126Z|00045|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-05-12T20:14:00.126Z|00046|coverage|INFO|poll_zero_timeout 0.2/sec 0.717/sec 0.2361/sec total: 851 2026-05-12T20:14:00.126Z|00047|coverage|INFO|poll_create_node 21.8/sec 28.383/sec 9.3703/sec total: 33742 2026-05-12T20:14:00.126Z|00048|coverage|INFO|lockfile_lock 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-05-12T20:14:00.126Z|00049|coverage|INFO|hmap_expand 40.4/sec 38.800/sec 13.1392/sec total: 47303 2026-05-12T20:14:00.126Z|00050|coverage|INFO|hmap_pathological 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-05-12T20:14:00.126Z|00051|coverage|INFO|112 events never hit 2026-05-12T20:14:00.126Z|00052|poll_loop|INFO|Dropped 2 log messages in last 8 seconds (most recently, 6 seconds ago) due to excessive rate 2026-05-12T20:14:00.126Z|00053|poll_loop|INFO|wakeup due to 0-ms timeout at ../lib/reconnect.c:677 (72% CPU usage)