2026-06-22T12:47:31.898Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller-vtep.log 2026-06-22T12:47:31.898Z|00002|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:47:31.899Z|00003|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:47:32.900Z|00004|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:47:32.900Z|00005|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:47:32.900Z|00006|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 2 seconds before reconnect 2026-06-22T12:47:34.901Z|00007|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:47:34.901Z|00008|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:47:34.901Z|00009|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 4 seconds before reconnect 2026-06-22T12:47:38.905Z|00010|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:47:38.905Z|00011|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:47:38.905Z|00012|reconnect|INFO|/var/run/openvswitch/db.sock: continuing to reconnect in the background but suppressing further logging 2026-06-22T12:51:52.048Z|00013|fatal_signal|WARN|terminating with signal 15 (Terminated) 2026-06-22T12:51:53.426Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller-vtep.log 2026-06-22T12:51:53.427Z|00002|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:51:53.427Z|00003|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:51:54.428Z|00004|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:51:54.428Z|00005|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:51:54.428Z|00006|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 2 seconds before reconnect 2026-06-22T12:51:56.430Z|00007|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:51:56.430Z|00008|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:51:56.430Z|00009|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 4 seconds before reconnect 2026-06-22T12:52:00.434Z|00010|reconnect|INFO|/var/run/openvswitch/db.sock: connecting... 2026-06-22T12:52:00.434Z|00011|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol) 2026-06-22T12:52:00.434Z|00012|reconnect|INFO|/var/run/openvswitch/db.sock: continuing to reconnect in the background but suppressing further logging 2026-06-22T12:58:37.590Z|00013|timeval|WARN|Unreasonably long 7563ms poll interval (1ms user, 5600ms system) 2026-06-22T12:58:40.720Z|00014|timeval|WARN|faults: 13 minor, 293 major 2026-06-22T12:58:40.720Z|00015|timeval|WARN|disk: 73616 reads, 0 writes 2026-06-22T12:58:40.720Z|00016|timeval|WARN|context switches: 293 voluntary, 5205 involuntary 2026-06-22T12:58:40.720Z|00017|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=57c59a00: 2026-06-22T12:58:40.720Z|00018|coverage|INFO|util_xalloc 0.4/sec 0.667/sec 0.1675/sec total: 603 2026-06-22T12:58:40.720Z|00019|coverage|INFO|stream_open 0.2/sec 0.167/sec 0.0144/sec total: 52 2026-06-22T12:58:40.720Z|00020|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T12:58:40.720Z|00021|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T12:58:40.720Z|00022|coverage|INFO|poll_create_node 0.2/sec 0.167/sec 0.0144/sec total: 52 2026-06-22T12:58:40.720Z|00023|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T12:58:40.720Z|00024|coverage|INFO|108 events never hit 2026-06-22T12:58:41.487Z|00025|poll_loop|INFO|wakeup due to 766-ms timeout at lib/reconnect.c:677 (72% CPU usage) 2026-06-22T12:59:28.495Z|00026|timeval|WARN|Unreasonably long 3433ms poll interval (0ms user, 2909ms system) 2026-06-22T12:59:28.495Z|00027|timeval|WARN|faults: 7 minor, 89 major 2026-06-22T12:59:28.495Z|00028|timeval|WARN|disk: 20928 reads, 0 writes 2026-06-22T12:59:28.495Z|00029|timeval|WARN|context switches: 87 voluntary, 3068 involuntary 2026-06-22T12:59:28.496Z|00030|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=b0f87ec2: 2026-06-22T12:59:28.496Z|00031|coverage|INFO|util_xalloc 0.4/sec 1.050/sec 0.1828/sec total: 658 2026-06-22T12:59:28.496Z|00032|coverage|INFO|long_poll_interval 0.0/sec 0.017/sec 0.0003/sec total: 1 2026-06-22T12:59:28.496Z|00033|coverage|INFO|stream_open 0.2/sec 0.133/sec 0.0161/sec total: 58 2026-06-22T12:59:28.496Z|00034|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T12:59:28.496Z|00035|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T12:59:28.496Z|00036|coverage|INFO|poll_create_node 0.2/sec 0.133/sec 0.0161/sec total: 58 2026-06-22T12:59:28.496Z|00037|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T12:59:28.496Z|00038|coverage|INFO|107 events never hit 2026-06-22T13:00:01.302Z|00039|timeval|WARN|Unreasonably long 5061ms poll interval (0ms user, 4549ms system) 2026-06-22T13:00:01.305Z|00040|timeval|WARN|faults: 23 minor, 213 major 2026-06-22T13:00:01.305Z|00041|timeval|WARN|disk: 55240 reads, 0 writes 2026-06-22T13:00:01.305Z|00042|timeval|WARN|context switches: 218 voluntary, 4222 involuntary 2026-06-22T13:00:01.305Z|00043|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=c3b66cf4: 2026-06-22T13:00:01.305Z|00044|coverage|INFO|util_xalloc 0.4/sec 1.617/sec 0.1944/sec total: 700 2026-06-22T13:00:01.305Z|00045|coverage|INFO|long_poll_interval 0.0/sec 0.033/sec 0.0006/sec total: 2 2026-06-22T13:00:01.305Z|00046|coverage|INFO|stream_open 0.2/sec 0.150/sec 0.0169/sec total: 61 2026-06-22T13:00:01.305Z|00047|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T13:00:01.305Z|00048|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T13:00:01.305Z|00049|coverage|INFO|poll_create_node 0.2/sec 0.150/sec 0.0169/sec total: 61 2026-06-22T13:00:01.305Z|00050|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T13:00:01.305Z|00051|coverage|INFO|107 events never hit 2026-06-22T13:00:03.586Z|00052|poll_loop|INFO|wakeup due to 2281-ms timeout at lib/reconnect.c:677 (65% CPU usage) 2026-06-22T13:00:54.571Z|00053|timeval|WARN|Unreasonably long 1387ms poll interval (0ms user, 974ms system) 2026-06-22T13:00:54.572Z|00054|timeval|WARN|faults: 5 minor, 40 major 2026-06-22T13:00:54.572Z|00055|timeval|WARN|disk: 9224 reads, 0 writes 2026-06-22T13:00:54.572Z|00056|timeval|WARN|context switches: 40 voluntary, 994 involuntary 2026-06-22T13:00:54.572Z|00057|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=63f66820: 2026-06-22T13:00:54.572Z|00058|coverage|INFO|util_xalloc 0.4/sec 1.733/sec 0.2117/sec total: 762 2026-06-22T13:00:54.572Z|00059|coverage|INFO|long_poll_interval 0.0/sec 0.033/sec 0.0008/sec total: 3 2026-06-22T13:00:54.572Z|00060|coverage|INFO|stream_open 0.2/sec 0.167/sec 0.0189/sec total: 68 2026-06-22T13:00:54.572Z|00061|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T13:00:54.572Z|00062|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T13:00:54.572Z|00063|coverage|INFO|poll_create_node 0.2/sec 0.167/sec 0.0189/sec total: 68 2026-06-22T13:00:54.572Z|00064|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T13:00:54.572Z|00065|coverage|INFO|107 events never hit 2026-06-22T13:01:06.288Z|00066|timeval|WARN|Unreasonably long 2720ms poll interval (0ms user, 2534ms system) 2026-06-22T13:01:17.016Z|00067|timeval|WARN|faults: 15 minor, 178 major 2026-06-22T13:01:17.094Z|00068|timeval|WARN|disk: 45360 reads, 0 writes 2026-06-22T13:01:17.094Z|00069|timeval|WARN|context switches: 186 voluntary, 2063 involuntary 2026-06-22T13:01:17.094Z|00070|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=e5a792cb: 2026-06-22T13:01:17.094Z|00071|coverage|INFO|util_xalloc 3.4/sec 1.667/sec 0.2211/sec total: 796 2026-06-22T13:01:17.094Z|00072|coverage|INFO|long_poll_interval 0.2/sec 0.033/sec 0.0011/sec total: 4 2026-06-22T13:01:17.094Z|00073|coverage|INFO|stream_open 0.2/sec 0.150/sec 0.0192/sec total: 69 2026-06-22T13:01:17.094Z|00074|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T13:01:17.094Z|00075|coverage|INFO|seq_change 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T13:01:17.094Z|00076|coverage|INFO|poll_create_node 0.2/sec 0.150/sec 0.0192/sec total: 69 2026-06-22T13:01:17.094Z|00077|coverage|INFO|hmap_expand 0.0/sec 0.000/sec 0.0072/sec total: 26 2026-06-22T13:01:17.094Z|00078|coverage|INFO|107 events never hit 2026-06-22T13:01:17.094Z|00079|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (95% CPU usage) 2026-06-22T13:01:36.370Z|00080|timeval|WARN|Unreasonably long 2837ms poll interval (0ms user, 2127ms system) 2026-06-22T13:01:36.375Z|00081|timeval|WARN|faults: 10 minor, 124 major 2026-06-22T13:01:36.375Z|00082|timeval|WARN|disk: 29992 reads, 0 writes 2026-06-22T13:01:36.375Z|00083|timeval|WARN|context switches: 123 voluntary, 1914 involuntary 2026-06-22T13:03:31.735Z|00084|timeval|WARN|Unreasonably long 1209ms poll interval (0ms user, 1005ms system) 2026-06-22T13:03:34.168Z|00085|timeval|WARN|faults: 18 minor, 130 major 2026-06-22T13:03:43.096Z|00086|timeval|WARN|disk: 32840 reads, 0 writes 2026-06-22T13:03:43.098Z|00087|timeval|WARN|context switches: 132 voluntary, 805 involuntary 2026-06-22T13:03:43.098Z|00088|coverage|INFO|Dropped 1 log messages in last 126 seconds (most recently, 126 seconds ago) due to excessive rate 2026-06-22T13:03:43.098Z|00089|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb 2026-06-22T13:03:43.098Z|00090|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (96% CPU usage) 2026-06-22T13:03:55.830Z|00091|timeval|WARN|Unreasonably long 3674ms poll interval (0ms user, 3536ms system) 2026-06-22T13:04:01.664Z|00092|timeval|WARN|faults: 16 minor, 179 major 2026-06-22T13:04:01.664Z|00093|timeval|WARN|disk: 46288 reads, 0 writes 2026-06-22T13:04:01.664Z|00094|timeval|WARN|context switches: 181 voluntary, 3224 involuntary 2026-06-22T13:04:01.664Z|00095|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb 2026-06-22T13:04:01.664Z|00096|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (96% CPU usage) 2026-06-22T13:04:12.761Z|00097|timeval|WARN|Unreasonably long 2973ms poll interval (0ms user, 2540ms system) 2026-06-22T13:04:12.762Z|00098|timeval|WARN|faults: 11 minor, 109 major 2026-06-22T13:04:12.762Z|00099|timeval|WARN|disk: 27712 reads, 0 writes 2026-06-22T13:04:12.762Z|00100|timeval|WARN|context switches: 109 voluntary, 2458 involuntary 2026-06-22T13:04:12.762Z|00101|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb 2026-06-22T13:04:21.369Z|00102|timeval|WARN|Unreasonably long 1171ms poll interval (0ms user, 795ms system) 2026-06-22T13:04:21.369Z|00103|timeval|WARN|faults: 9 minor, 40 major 2026-06-22T13:04:21.369Z|00104|timeval|WARN|disk: 10048 reads, 0 writes 2026-06-22T13:04:21.369Z|00105|timeval|WARN|context switches: 40 voluntary, 803 involuntary 2026-06-22T13:04:51.858Z|00106|timeval|WARN|Unreasonably long 3904ms poll interval (0ms user, 3316ms system) 2026-06-22T13:04:51.859Z|00107|timeval|WARN|faults: 18 minor, 151 major 2026-06-22T13:04:51.859Z|00108|timeval|WARN|disk: 39888 reads, 0 writes 2026-06-22T13:04:51.859Z|00109|timeval|WARN|context switches: 152 voluntary, 3204 involuntary 2026-06-22T13:04:51.859Z|00110|coverage|INFO|Dropped 1 log messages in last 30 seconds (most recently, 30 seconds ago) due to excessive rate 2026-06-22T13:04:51.859Z|00111|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb 2026-06-22T13:04:56.091Z|00112|poll_loop|INFO|wakeup due to 3530-ms timeout at lib/reconnect.c:677 (56% CPU usage) 2026-06-22T13:05:02.775Z|00113|timeval|WARN|Unreasonably long 6454ms poll interval (2ms user, 6419ms system) 2026-06-22T13:05:02.776Z|00114|timeval|WARN|faults: 44 minor, 376 major 2026-06-22T13:05:02.776Z|00115|timeval|WARN|disk: 99320 reads, 8 writes 2026-06-22T13:05:02.776Z|00116|timeval|WARN|context switches: 386 voluntary, 6129 involuntary 2026-06-22T13:05:08.296Z|00117|poll_loop|INFO|wakeup due to 4573-ms timeout at lib/reconnect.c:677 (59% CPU usage) 2026-06-22T13:05:26.160Z|00118|timeval|WARN|Unreasonably long 1435ms poll interval (0ms user, 877ms system) 2026-06-22T13:05:26.160Z|00119|timeval|WARN|faults: 5 minor, 32 major 2026-06-22T13:05:26.161Z|00120|timeval|WARN|disk: 6536 reads, 0 writes 2026-06-22T13:05:26.161Z|00121|timeval|WARN|context switches: 32 voluntary, 1076 involuntary 2026-06-22T13:05:52.285Z|00122|timeval|WARN|Unreasonably long 6036ms poll interval (0ms user, 4557ms system) 2026-06-22T13:05:52.538Z|00123|timeval|WARN|faults: 13 minor, 175 major 2026-06-22T13:05:52.538Z|00124|timeval|WARN|disk: 44144 reads, 0 writes 2026-06-22T13:05:52.538Z|00125|timeval|WARN|context switches: 179 voluntary, 4465 involuntary 2026-06-22T13:05:52.538Z|00126|coverage|INFO|Dropped 2 log messages in last 50 seconds (most recently, 27 seconds ago) due to excessive rate 2026-06-22T13:05:52.538Z|00127|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb 2026-06-22T13:05:57.725Z|00128|poll_loop|INFO|wakeup due to 2520-ms timeout at lib/reconnect.c:677 (69% CPU usage) 2026-06-22T13:05:57.726Z|00129|timeval|WARN|Unreasonably long 2668ms poll interval (1ms user, 2345ms system) 2026-06-22T13:05:57.726Z|00130|timeval|WARN|faults: 16 minor, 105 major 2026-06-22T13:05:57.726Z|00131|timeval|WARN|disk: 27800 reads, 8 writes 2026-06-22T13:05:57.726Z|00132|timeval|WARN|context switches: 107 voluntary, 2321 involuntary 2026-06-22T13:06:13.552Z|00133|timeval|WARN|Unreasonably long 4874ms poll interval (0ms user, 4448ms system) 2026-06-22T13:06:21.556Z|00134|timeval|WARN|faults: 19 minor, 235 major 2026-06-22T13:06:21.556Z|00135|timeval|WARN|disk: 60392 reads, 0 writes 2026-06-22T13:06:21.556Z|00136|timeval|WARN|context switches: 241 voluntary, 4074 involuntary 2026-06-22T13:06:21.556Z|00137|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (93% CPU usage)