2026-06-22T13:40:55.127Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller-vtep.log
2026-06-22T13:40:55.128Z|00002|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:40:55.128Z|00003|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:40:56.129Z|00004|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:40:56.129Z|00005|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:40:56.129Z|00006|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 2 seconds before reconnect
2026-06-22T13:40:58.131Z|00007|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:40:58.131Z|00008|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:40:58.131Z|00009|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 4 seconds before reconnect
2026-06-22T13:41:02.135Z|00010|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:41:02.135Z|00011|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:41:02.135Z|00012|reconnect|INFO|/var/run/openvswitch/db.sock: continuing to reconnect in the background but suppressing further logging
2026-06-22T13:45:06.913Z|00013|fatal_signal|WARN|terminating with signal 15 (Terminated)
2026-06-22T13:45:08.333Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller-vtep.log
2026-06-22T13:45:08.334Z|00002|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:45:08.334Z|00003|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:45:09.335Z|00004|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:45:09.335Z|00005|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:45:09.335Z|00006|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 2 seconds before reconnect
2026-06-22T13:45:11.337Z|00007|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:45:11.337Z|00008|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:45:11.337Z|00009|reconnect|INFO|/var/run/openvswitch/db.sock: waiting 4 seconds before reconnect
2026-06-22T13:45:15.341Z|00010|reconnect|INFO|/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:45:15.341Z|00011|reconnect|INFO|/var/run/openvswitch/db.sock: connection attempt failed (Address family not supported by protocol)
2026-06-22T13:45:15.341Z|00012|reconnect|INFO|/var/run/openvswitch/db.sock: continuing to reconnect in the background but suppressing further logging
2026-06-22T13:51:45.046Z|00013|timeval|WARN|Unreasonably long 1736ms poll interval (1ms user, 1724ms system)
2026-06-22T13:51:45.765Z|00014|timeval|WARN|faults: 17 minor, 174 major
2026-06-22T13:51:45.780Z|00015|timeval|WARN|disk: 44728 reads, 0 writes
2026-06-22T13:51:45.784Z|00016|timeval|WARN|context switches: 177 voluntary, 1811 involuntary
2026-06-22T13:51:45.789Z|00017|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=57c59a00:
2026-06-22T13:51:45.789Z|00018|coverage|INFO|util_xalloc                0.8/sec     0.800/sec        0.1675/sec   total: 603
2026-06-22T13:51:45.789Z|00019|coverage|INFO|stream_open                0.2/sec     0.200/sec        0.0144/sec   total: 52
2026-06-22T13:51:45.789Z|00020|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:51:45.789Z|00021|coverage|INFO|seq_change                 0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:51:45.789Z|00022|coverage|INFO|poll_create_node           0.2/sec     0.200/sec        0.0144/sec   total: 52
2026-06-22T13:51:45.789Z|00023|coverage|INFO|hmap_expand                0.0/sec     0.000/sec        0.0072/sec   total: 26
2026-06-22T13:51:45.789Z|00024|coverage|INFO|108 events never hit
2026-06-22T13:52:23.187Z|00025|timeval|WARN|Unreasonably long 5959ms poll interval (0ms user, 5712ms system)
2026-06-22T13:52:23.217Z|00026|timeval|WARN|faults: 15 minor, 256 major
2026-06-22T13:52:23.226Z|00027|timeval|WARN|disk: 63688 reads, 0 writes
2026-06-22T13:52:23.226Z|00028|timeval|WARN|context switches: 259 voluntary, 5541 involuntary
2026-06-22T13:52:23.226Z|00029|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=b0f87ec2:
2026-06-22T13:52:23.226Z|00030|coverage|INFO|util_xalloc                0.4/sec     1.183/sec        0.1794/sec   total: 646
2026-06-22T13:52:23.226Z|00031|coverage|INFO|long_poll_interval         0.0/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T13:52:23.226Z|00032|coverage|INFO|stream_open                0.2/sec     0.183/sec        0.0156/sec   total: 56
2026-06-22T13:52:23.226Z|00033|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:52:23.226Z|00034|coverage|INFO|seq_change                 0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:52:23.226Z|00035|coverage|INFO|poll_create_node           0.2/sec     0.183/sec        0.0156/sec   total: 56
2026-06-22T13:52:23.226Z|00036|coverage|INFO|hmap_expand                0.0/sec     0.000/sec        0.0072/sec   total: 26
2026-06-22T13:52:23.226Z|00037|coverage|INFO|107 events never hit
2026-06-22T13:52:27.635Z|00038|poll_loop|INFO|wakeup due to 4409-ms timeout at lib/reconnect.c:677 (61% CPU usage)
2026-06-22T13:52:42.605Z|00039|timeval|WARN|Unreasonably long 3948ms poll interval (0ms user, 3856ms system)
2026-06-22T13:52:42.613Z|00040|timeval|WARN|faults: 18 minor, 200 major
2026-06-22T13:52:42.613Z|00041|timeval|WARN|disk: 50496 reads, 0 writes
2026-06-22T13:52:42.613Z|00042|timeval|WARN|context switches: 202 voluntary, 3680 involuntary
2026-06-22T13:52:42.613Z|00043|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=c3b66cf4:
2026-06-22T13:52:42.613Z|00044|coverage|INFO|util_xalloc                0.4/sec     1.683/sec        0.1911/sec   total: 688
2026-06-22T13:52:42.613Z|00045|coverage|INFO|long_poll_interval         0.0/sec     0.033/sec        0.0006/sec   total: 2
2026-06-22T13:52:42.613Z|00046|coverage|INFO|stream_open                0.2/sec     0.167/sec        0.0161/sec   total: 58
2026-06-22T13:52:42.613Z|00047|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:52:42.613Z|00048|coverage|INFO|seq_change                 0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:52:42.613Z|00049|coverage|INFO|poll_create_node           0.2/sec     0.167/sec        0.0161/sec   total: 58
2026-06-22T13:52:42.613Z|00050|coverage|INFO|hmap_expand                0.0/sec     0.000/sec        0.0072/sec   total: 26
2026-06-22T13:52:42.613Z|00051|coverage|INFO|107 events never hit
2026-06-22T13:52:45.561Z|00052|poll_loop|INFO|wakeup due to 2947-ms timeout at lib/reconnect.c:677 (65% CPU usage)
2026-06-22T13:53:21.994Z|00053|timeval|WARN|Unreasonably long 1668ms poll interval (0ms user, 1478ms system)
2026-06-22T13:53:25.759Z|00054|timeval|WARN|faults: 15 minor, 150 major
2026-06-22T13:53:26.607Z|00055|timeval|WARN|disk: 39016 reads, 0 writes
2026-06-22T13:53:30.084Z|00056|timeval|WARN|context switches: 153 voluntary, 1354 involuntary
2026-06-22T13:53:46.921Z|00057|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=63f66820:
2026-06-22T13:53:46.921Z|00058|coverage|INFO|util_xalloc                0.8/sec     1.800/sec        0.2061/sec   total: 742
2026-06-22T13:53:46.921Z|00059|coverage|INFO|long_poll_interval         0.0/sec     0.033/sec        0.0008/sec   total: 3
2026-06-22T13:53:46.921Z|00060|coverage|INFO|stream_open                0.2/sec     0.167/sec        0.0175/sec   total: 63
2026-06-22T13:53:46.921Z|00061|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:53:46.921Z|00062|coverage|INFO|seq_change                 0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:53:46.921Z|00063|coverage|INFO|poll_create_node           0.2/sec     0.167/sec        0.0175/sec   total: 63
2026-06-22T13:53:46.921Z|00064|coverage|INFO|hmap_expand                0.0/sec     0.000/sec        0.0072/sec   total: 26
2026-06-22T13:53:46.921Z|00065|coverage|INFO|107 events never hit
2026-06-22T13:53:46.922Z|00066|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (94% CPU usage)
2026-06-22T13:54:10.049Z|00067|timeval|WARN|Unreasonably long 6915ms poll interval (0ms user, 5487ms system)
2026-06-22T13:54:10.053Z|00068|timeval|WARN|faults: 11 minor, 194 major
2026-06-22T13:54:10.053Z|00069|timeval|WARN|disk: 47952 reads, 0 writes
2026-06-22T13:54:10.053Z|00070|timeval|WARN|context switches: 201 voluntary, 5357 involuntary
2026-06-22T13:54:10.053Z|00071|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=e5a792cb:
2026-06-22T13:54:10.053Z|00072|coverage|INFO|util_xalloc                0.4/sec     0.967/sec        0.2189/sec   total: 788
2026-06-22T13:54:10.053Z|00073|coverage|INFO|long_poll_interval         0.0/sec     0.017/sec        0.0011/sec   total: 4
2026-06-22T13:54:10.053Z|00074|coverage|INFO|stream_open                0.2/sec     0.100/sec        0.0183/sec   total: 66
2026-06-22T13:54:10.053Z|00075|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:54:10.053Z|00076|coverage|INFO|seq_change                 0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:54:10.053Z|00077|coverage|INFO|poll_create_node           0.2/sec     0.100/sec        0.0183/sec   total: 66
2026-06-22T13:54:10.053Z|00078|coverage|INFO|hmap_expand                0.0/sec     0.000/sec        0.0072/sec   total: 26
2026-06-22T13:54:10.053Z|00079|coverage|INFO|107 events never hit
2026-06-22T13:55:57.600Z|00080|timeval|WARN|Unreasonably long 4116ms poll interval (1ms user, 4200ms system)
2026-06-22T13:55:57.601Z|00081|timeval|WARN|faults: 18 minor, 252 major
2026-06-22T13:55:57.601Z|00082|timeval|WARN|disk: 64536 reads, 0 writes
2026-06-22T13:55:57.601Z|00083|timeval|WARN|context switches: 259 voluntary, 4512 involuntary
2026-06-22T13:55:57.602Z|00084|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb
2026-06-22T13:56:04.310Z|00085|poll_loop|INFO|wakeup due to 4211-ms timeout at lib/reconnect.c:677 (53% CPU usage)
2026-06-22T13:56:04.310Z|00086|timeval|WARN|Unreasonably long 2497ms poll interval (0ms user, 2117ms system)
2026-06-22T13:56:04.310Z|00087|timeval|WARN|faults: 16 minor, 80 major
2026-06-22T13:56:04.310Z|00088|timeval|WARN|disk: 19968 reads, 8 writes
2026-06-22T13:56:04.310Z|00089|timeval|WARN|context switches: 85 voluntary, 2230 involuntary
2026-06-22T13:56:04.310Z|00090|coverage|INFO|Skipping details of duplicate event coverage for hash=e5a792cb
2026-06-22T13:56:27.231Z|00091|timeval|WARN|Unreasonably long 6201ms poll interval (0ms user, 5432ms system)
2026-06-22T13:56:27.231Z|00092|timeval|WARN|faults: 10 minor, 203 major
2026-06-22T13:56:27.231Z|00093|timeval|WARN|disk: 49088 reads, 0 writes
2026-06-22T13:56:27.231Z|00094|timeval|WARN|context switches: 209 voluntary, 5778 involuntary