2026-06-22T14:19:01.205Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller-vtep.log 2026-06-22T14:19:01.206Z|00002|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:19:01.206Z|00003|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:19:02.207Z|00004|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:19:02.207Z|00005|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:19:02.207Z|00006|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 2 seconds before reconnect 2026-06-22T14:19:04.209Z|00007|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:19:04.209Z|00008|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:19:04.209Z|00009|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 4 seconds before reconnect 2026-06-22T14:19:08.213Z|00010|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:19:08.213Z|00011|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:19:08.213Z|00012|reconnect|INFO|/var/run/openvswitch/db.sock: continuing to reconnect in the background but suppressing further logging 2026-06-22T14:23:27.428Z|00013|fatal_signal|WARN|terminating with signal 15 (Terminated) 2026-06-22T14:23:28.775Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller-vtep.log 2026-06-22T14:23:28.776Z|00002|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:23:28.776Z|00003|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:23:29.776Z|00004|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:23:29.776Z|00005|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:23:29.776Z|00006|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 2 seconds before reconnect 2026-06-22T14:23:31.779Z|00007|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:23:31.779Z|00008|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:23:31.779Z|00009|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 4 seconds before reconnect 2026-06-22T14:23:35.783Z|00010|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T14:23:35.783Z|00011|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T14:23:35.783Z|00012|reconnect|INFO|/var/run/openvswitch/db.sock: continuing to reconnect in the background but suppressing further logging 2026-06-22T14:29:06.822Z|00013|timeval|WARN|Unreasonably long 1668ms poll interval (0ms user, 1413ms system) 2026-06-22T14:29:11.936Z|00014|timeval|WARN|faults: 17 minor, 154 major 2026-06-22T14:29:16.130Z|00015|timeval|WARN|disk: 41040 reads, 0 writes 2026-06-22T14:29:22.989Z|00016|timeval|WARN|context switches: 163 voluntary, 1405 involuntary 2026-06-22T14:29:25.533Z|00017|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=57c59a00: 2026-06-22T14:29:25.533Z|00018|coverage|INFO|util_xalloc 0.8/sec 0.800/sec 0.1597/sec total: 575 2026-06-22T14:29:25.533Z|00019|coverage|INFO|stream_open 0.2/sec 0.200/sec 0.0125/sec total: 45 2026-06-22T14:29:25.533Z|00020|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:29:25.533Z|00021|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T14:29:25.533Z|00022|coverage|INFO|poll_create_node 0.2/sec 0.200/sec 0.0125/sec total: 45 2026-06-22T14:29:25.533Z|00023|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T14:29:25.533Z|00024|coverage|INFO|108 events never hit 2026-06-22T14:29:25.533Z|00025|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (96% CPU usage) 2026-06-22T14:30:15.421Z|00026|timeval|WARN|Unreasonably long 1085ms poll interval (0ms user, 768ms system) 2026-06-22T14:30:15.424Z|00027|timeval|WARN|faults: 13 minor, 59 major 2026-06-22T14:30:15.424Z|00028|timeval|WARN|disk: 15144 reads, 0 writes 2026-06-22T14:30:15.424Z|00029|timeval|WARN|context switches: 61 voluntary, 742 involuntary 2026-06-22T14:30:15.424Z|00030|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=b0f87ec2: 2026-06-22T14:30:15.424Z|00031|coverage|INFO|util_xalloc 0.8/sec 1.183/sec 0.1761/sec total: 634 2026-06-22T14:30:15.424Z|00032|coverage|INFO|long_poll_interval 0.0/sec 0.017/sec 0.0003/sec total: 1 2026-06-22T14:30:15.424Z|00033|coverage|INFO|stream_open 0.2/sec 0.167/sec 0.0144/sec total: 52 2026-06-22T14:30:15.424Z|00034|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:30:15.424Z|00035|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T14:30:15.424Z|00036|coverage|INFO|poll_create_node 0.2/sec 0.167/sec 0.0144/sec total: 52 2026-06-22T14:30:15.424Z|00037|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T14:30:15.424Z|00038|coverage|INFO|107 events never hit 2026-06-22T14:30:34.362Z|00039|timeval|WARN|Unreasonably long 1585ms poll interval (0ms user, 1504ms system) 2026-06-22T14:30:39.454Z|00040|timeval|WARN|faults: 19 minor, 167 major 2026-06-22T14:30:39.491Z|00041|timeval|WARN|disk: 42672 reads, 0 writes 2026-06-22T14:30:39.491Z|00042|timeval|WARN|context switches: 171 voluntary, 1271 involuntary 2026-06-22T14:30:39.491Z|00043|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=c3b66cf4: 2026-06-22T14:30:39.491Z|00044|coverage|INFO|util_xalloc 0.8/sec 1.683/sec 0.1867/sec total: 672 2026-06-22T14:30:39.491Z|00045|coverage|INFO|long_poll_interval 0.0/sec 0.033/sec 0.0006/sec total: 2 2026-06-22T14:30:39.491Z|00046|coverage|INFO|stream_open 0.2/sec 0.167/sec 0.0150/sec total: 54 2026-06-22T14:30:39.491Z|00047|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:30:39.491Z|00048|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T14:30:39.491Z|00049|coverage|INFO|poll_create_node 0.2/sec 0.167/sec 0.0150/sec total: 54 2026-06-22T14:30:39.491Z|00050|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T14:30:39.491Z|00051|coverage|INFO|107 events never hit 2026-06-22T14:30:40.819Z|00052|poll_loop|INFO|wakeup due to 1328-ms timeout at lib/reconnect.c:677 (79% CPU usage) 2026-06-22T14:30:57.372Z|00053|timeval|WARN|Unreasonably long 7889ms poll interval (1ms user, 7618ms system) 2026-06-22T14:30:57.375Z|00054|timeval|WARN|faults: 16 minor, 304 major 2026-06-22T14:30:57.375Z|00055|timeval|WARN|disk: 74456 reads, 0 writes 2026-06-22T14:30:57.375Z|00056|timeval|WARN|context switches: 309 voluntary, 7298 involuntary 2026-06-22T14:30:57.375Z|00057|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=63f66820: 2026-06-22T14:30:57.375Z|00058|coverage|INFO|util_xalloc 0.4/sec 1.733/sec 0.1983/sec total: 714 2026-06-22T14:30:57.375Z|00059|coverage|INFO|long_poll_interval 0.0/sec 0.033/sec 0.0008/sec total: 3 2026-06-22T14:30:57.375Z|00060|coverage|INFO|stream_open 0.2/sec 0.167/sec 0.0156/sec total: 56 2026-06-22T14:30:57.375Z|00061|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:30:57.375Z|00062|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T14:30:57.375Z|00063|coverage|INFO|poll_create_node 0.2/sec 0.167/sec 0.0156/sec total: 56 2026-06-22T14:30:57.375Z|00064|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T14:30:57.375Z|00065|coverage|INFO|107 events never hit 2026-06-22T14:31:01.132Z|00066|poll_loop|INFO|wakeup due to 3756-ms timeout at lib/reconnect.c:677 (64% CPU usage) 2026-06-22T14:31:11.097Z|00067|timeval|WARN|Unreasonably long 1436ms poll interval (1ms user, 1193ms system) 2026-06-22T14:31:14.620Z|00068|timeval|WARN|faults: 24 minor, 144 major 2026-06-22T14:31:20.951Z|00069|timeval|WARN|disk: 39016 reads, 0 writes 2026-06-22T14:31:25.641Z|00070|timeval|WARN|context switches: 147 voluntary, 981 involuntary 2026-06-22T14:31:29.735Z|00071|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=e5a792cb: 2026-06-22T14:31:29.735Z|00072|coverage|INFO|util_xalloc 4.2/sec 2.300/sec 0.2100/sec total: 756 2026-06-22T14:31:29.735Z|00073|coverage|INFO|long_poll_interval 0.2/sec 0.050/sec 0.0011/sec total: 4 2026-06-22T14:31:29.735Z|00074|coverage|INFO|stream_open 0.2/sec 0.167/sec 0.0161/sec total: 58 2026-06-22T14:31:29.735Z|00075|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:31:29.735Z|00076|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T14:31:29.735Z|00077|coverage|INFO|poll_create_node 0.2/sec 0.167/sec 0.0161/sec total: 58 2026-06-22T14:31:29.735Z|00078|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T14:31:29.735Z|00079|coverage|INFO|107 events never hit 2026-06-22T14:31:29.735Z|00080|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (97% CPU usage) 2026-06-22T14:32:11.033Z|00081|timeval|WARN|Unreasonably long 7181ms poll interval (1ms user, 6799ms system) 2026-06-22T14:32:19.601Z|00082|timeval|WARN|faults: 15 minor, 311 major 2026-06-22T14:32:19.607Z|00083|timeval|WARN|disk: 76200 reads, 0 writes 2026-06-22T14:32:19.609Z|00084|timeval|WARN|context switches: 314 voluntary, 6722 involuntary 2026-06-22T14:32:19.609Z|00085|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (94% CPU usage) 2026-06-22T14:32:35.692Z|00086|timeval|WARN|Unreasonably long 4640ms poll interval (0ms user, 4720ms system) 2026-06-22T14:32:35.723Z|00087|timeval|WARN|faults: 20 minor, 230 major 2026-06-22T14:32:35.731Z|00088|timeval|WARN|disk: 59112 reads, 0 writes 2026-06-22T14:32:35.731Z|00089|timeval|WARN|context switches: 235 voluntary, 4226 involuntary 2026-06-22T14:32:35.731Z|00090|coverage|INFO|Dropped 1 log messages in last 16 seconds (most recently, 16 seconds ago) due to excessive rate 2026-06-22T14:32:35.731Z|00091|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb 2026-06-22T14:32:37.516Z|00092|poll_loop|INFO|wakeup due to 1783-ms timeout at lib/reconnect.c:677 (79% CPU usage) 2026-06-22T14:33:12.310Z|00093|timeval|WARN|Unreasonably long 7035ms poll interval (1ms user, 7147ms system) 2026-06-22T14:33:13.486Z|00094|timeval|WARN|faults: 23 minor, 326 major 2026-06-22T14:33:13.487Z|00095|timeval|WARN|disk: 84872 reads, 0 writes 2026-06-22T14:33:13.487Z|00096|timeval|WARN|context switches: 341 voluntary, 6591 involuntary 2026-06-22T14:33:14.872Z|00097|poll_loop|INFO|wakeup due to 1385-ms timeout at lib/reconnect.c:677 (85% CPU usage) 2026-06-22T14:33:39.023Z|00098|timeval|WARN|Unreasonably long 3197ms poll interval (0ms user, 3087ms system) 2026-06-22T14:33:39.053Z|00099|timeval|WARN|faults: 21 minor, 200 major 2026-06-22T14:33:39.053Z|00100|timeval|WARN|disk: 51048 reads, 0 writes 2026-06-22T14:33:39.053Z|00101|timeval|WARN|context switches: 203 voluntary, 2965 involuntary 2026-06-22T14:33:39.053Z|00102|coverage|INFO|Dropped 1 log messages in last 25 seconds (most recently, 25 seconds ago) due to excessive rate 2026-06-22T14:33:39.053Z|00103|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb 2026-06-22T14:33:39.958Z|00104|poll_loop|INFO|wakeup due to 904-ms timeout at lib/reconnect.c:677 (81% CPU usage) 2026-06-22T14:33:49.593Z|00105|timeval|WARN|Unreasonably long 1304ms poll interval (0ms user, 920ms system) 2026-06-22T14:33:49.594Z|00106|timeval|WARN|faults: 7 minor, 45 major 2026-06-22T14:33:49.594Z|00107|timeval|WARN|disk: 10920 reads, 0 writes 2026-06-22T14:33:49.594Z|00108|timeval|WARN|context switches: 45 voluntary, 1008 involuntary 2026-06-22T14:34:51.889Z|00109|timeval|WARN|Unreasonably long 4187ms poll interval (1ms user, 3884ms system) 2026-06-22T14:34:51.890Z|00110|timeval|WARN|faults: 12 minor, 155 major 2026-06-22T14:34:51.890Z|00111|timeval|WARN|disk: 39136 reads, 0 writes 2026-06-22T14:34:51.890Z|00112|timeval|WARN|context switches: 160 voluntary, 3585 involuntary 2026-06-22T14:34:51.890Z|00113|coverage|INFO|Dropped 1 log messages in last 62 seconds (most recently, 62 seconds ago) due to excessive rate 2026-06-22T14:34:51.890Z|00114|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb