2026-06-22T10:01:10.953Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-northd.log 2026-06-22T10:01:10.954Z|00002|ovn_northd|INFO|OVN internal version is : [24.03.6-20.33.0-76.8] 2026-06-22T10:01:10.954Z|00003|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T10:01:10.954Z|00004|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connected 2026-06-22T10:01:10.954Z|00005|ovn_northd|INFO|OVN NB IDL reconnected, force recompute. 2026-06-22T10:01:10.954Z|00006|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T10:01:10.954Z|00007|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connected 2026-06-22T10:01:10.954Z|00008|ovn_northd|INFO|OVN SB IDL reconnected, force recompute. 2026-06-22T10:01:10.954Z|00009|ovn_northd|INFO|ovn-northd lock acquired. This ovn-northd instance is now active. 2026-06-22T10:01:10.960Z|00010|memory_trim|INFO|Detected inactivity (last active 0 ms ago): trimming memory 2026-06-22T10:01:40.991Z|00011|memory|INFO|7340 kB peak resident set size after 30.0 seconds 2026-06-22T10:01:40.991Z|00012|memory|INFO|idl-cells-OVN_Northbound:12 idl-cells-OVN_Southbound:185 2026-06-22T10:01:40.991Z|00013|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory 2026-06-22T10:05:27.530Z|00014|ovn_parallel_hmap|INFO|Setting thread count to 0 2026-06-22T10:05:27.936Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-northd.log 2026-06-22T10:05:27.937Z|00002|ovn_northd|INFO|OVN internal version is : [24.03.6-20.33.0-76.8] 2026-06-22T10:05:27.937Z|00003|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T10:05:27.937Z|00004|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connection attempt failed (No such file or directory) 2026-06-22T10:05:27.937Z|00005|ovn_northd|INFO|OVN NB IDL reconnected, force recompute. 2026-06-22T10:05:27.937Z|00006|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T10:05:27.937Z|00007|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connection attempt failed (No such file or directory) 2026-06-22T10:05:27.937Z|00008|ovn_northd|INFO|OVN SB IDL reconnected, force recompute. 2026-06-22T10:05:28.938Z|00009|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T10:05:28.938Z|00010|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connected 2026-06-22T10:05:28.938Z|00011|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T10:05:28.938Z|00012|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connected 2026-06-22T10:05:28.939Z|00013|ovn_northd|INFO|ovn-northd lock acquired. This ovn-northd instance is now active. 2026-06-22T10:05:38.293Z|00014|memory|INFO|7316 kB peak resident set size after 10.4 seconds 2026-06-22T10:05:38.293Z|00015|memory|INFO|idl-cells-OVN_Northbound:26 idl-cells-OVN_Southbound:218 2026-06-22T10:06:31.560Z|00016|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory 2026-06-22T10:07:26.184Z|00017|memory_trim|INFO|Detected inactivity (last active 30025 ms ago): trimming memory 2026-06-22T10:08:06.080Z|00018|memory_trim|INFO|Detected inactivity (last active 30024 ms ago): trimming memory 2026-06-22T10:08:36.528Z|00019|memory|INFO|peak resident set size grew 63% in last 178.2 seconds, from 7316 kB to 11924 kB 2026-06-22T10:08:36.529Z|00020|memory|INFO|idl-cells-OVN_Northbound:2584 idl-cells-OVN_Southbound:15138 2026-06-22T10:11:35.409Z|00021|memory_trim|INFO|Detected inactivity (last active 32240 ms ago): trimming memory 2026-06-22T10:12:11.163Z|00022|memory_trim|INFO|Detected inactivity (last active 34187 ms ago): trimming memory 2026-06-22T10:12:11.163Z|00023|timeval|WARN|Unreasonably long 3121ms poll interval (17ms user, 2878ms system) 2026-06-22T10:12:11.163Z|00024|timeval|WARN|faults: 18 minor, 100 major 2026-06-22T10:12:11.163Z|00025|timeval|WARN|disk: 24024 reads, 8 writes 2026-06-22T10:12:11.163Z|00026|timeval|WARN|context switches: 105 voluntary, 3270 involuntary 2026-06-22T10:12:11.163Z|00027|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=da0b649c: 2026-06-22T10:12:11.163Z|00028|coverage|INFO|util_xalloc 1959.0/sec 12370.083/sec 3394.9486/sec total: 12222868 2026-06-22T10:12:11.163Z|00029|coverage|INFO|stream_open 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-06-22T10:12:11.163Z|00030|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T10:12:11.163Z|00031|coverage|INFO|seq_change 1.8/sec 7.817/sec 3.1758/sec total: 11436 2026-06-22T10:12:11.163Z|00032|coverage|INFO|poll_create_node 4.4/sec 18.000/sec 7.0983/sec total: 25566 2026-06-22T10:12:11.163Z|00033|coverage|INFO|txn_success 1.0/sec 0.600/sec 0.1925/sec total: 695 2026-06-22T10:12:11.163Z|00034|coverage|INFO|txn_incomplete 1.2/sec 0.700/sec 0.2383/sec total: 859 2026-06-22T10:12:11.163Z|00035|coverage|INFO|txn_unchanged 0.8/sec 1.700/sec 0.5064/sec total: 1828 2026-06-22T10:12:11.163Z|00036|coverage|INFO|hmap_reserve 4.4/sec 30.750/sec 7.3031/sec total: 26293 2026-06-22T10:12:11.163Z|00037|coverage|INFO|hmap_expand 37.4/sec 245.617/sec 68.2689/sec total: 245808 2026-06-22T10:12:11.163Z|00038|coverage|INFO|hmap_pathological 1.4/sec 5.417/sec 0.9625/sec total: 3467 2026-06-22T10:12:11.163Z|00039|coverage|INFO|northd_run 0.4/sec 0.417/sec 0.1481/sec total: 533 2026-06-22T10:12:11.163Z|00040|coverage|INFO|103 events never hit 2026-06-22T10:13:29.116Z|00041|ovn_northd|INFO|OVN_Northbound IDL run: 2 iterations in 6829 ms 2026-06-22T10:13:29.118Z|00001|timeval(stopwatch0)|WARN|Unreasonably long 11006ms poll interval (0ms user, 3785ms system) 2026-06-22T10:13:29.118Z|00002|timeval(stopwatch0)|WARN|faults: 14 minor, 121 major 2026-06-22T10:13:29.118Z|00003|timeval(stopwatch0)|WARN|disk: 28576 reads, 0 writes 2026-06-22T10:13:29.118Z|00004|timeval(stopwatch0)|WARN|context switches: 123 voluntary, 4188 involuntary 2026-06-22T10:13:29.119Z|00005|coverage(stopwatch0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=bd0e16dc: 2026-06-22T10:13:29.119Z|00006|coverage(stopwatch0)|INFO|util_xalloc 521.4/sec 2692.267/sec 3447.2231/sec total: 12410003 2026-06-22T10:13:29.119Z|00007|coverage(stopwatch0)|INFO|long_poll_interval 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T10:13:29.119Z|00008|coverage(stopwatch0)|INFO|stream_open 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-06-22T10:13:29.119Z|00009|coverage(stopwatch0)|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T10:13:29.119Z|00010|coverage(stopwatch0)|INFO|seq_change 0.6/sec 2.450/sec 3.2231/sec total: 11603 2026-06-22T10:13:29.119Z|00011|coverage(stopwatch0)|INFO|poll_create_node 1.6/sec 5.633/sec 7.2072/sec total: 25946 2026-06-22T10:13:29.119Z|00012|coverage(stopwatch0)|INFO|txn_success 0.0/sec 0.200/sec 0.1964/sec total: 707 2026-06-22T10:13:29.119Z|00013|coverage(stopwatch0)|INFO|txn_incomplete 0.2/sec 0.250/sec 0.2431/sec total: 875 2026-06-22T10:13:29.119Z|00014|coverage(stopwatch0)|INFO|txn_unchanged 0.2/sec 0.483/sec 0.5161/sec total: 1858 2026-06-22T10:13:29.119Z|00015|coverage(stopwatch0)|INFO|hmap_reserve 2.8/sec 6.867/sec 7.4369/sec total: 26773 2026-06-22T10:13:29.119Z|00016|coverage(stopwatch0)|INFO|hmap_expand 12.0/sec 55.900/sec 69.3528/sec total: 249670 2026-06-22T10:13:29.119Z|00017|coverage(stopwatch0)|INFO|hmap_pathological 1.6/sec 0.867/sec 0.9800/sec total: 3528 2026-06-22T10:13:29.119Z|00018|coverage(stopwatch0)|INFO|northd_run 0.2/sec 0.100/sec 0.1500/sec total: 540 2026-06-22T10:13:29.119Z|00019|coverage(stopwatch0)|INFO|102 events never hit 2026-06-22T10:13:29.120Z|00042|memory_trim|INFO|Detected inactivity (last active 51675 ms ago): trimming memory 2026-06-22T10:13:29.120Z|00043|timeval|WARN|Unreasonably long 16132ms poll interval (12ms user, 6987ms system) 2026-06-22T10:13:29.120Z|00044|timeval|WARN|faults: 34 minor, 266 major 2026-06-22T10:13:29.120Z|00045|timeval|WARN|disk: 65368 reads, 16 writes 2026-06-22T10:13:29.120Z|00046|timeval|WARN|context switches: 274 voluntary, 8049 involuntary 2026-06-22T10:13:29.120Z|00047|coverage|INFO|Skipping details of duplicate event coverage for hash=bd0e16dc 2026-06-22T10:13:59.137Z|00048|memory_trim|INFO|Detected inactivity (last active 30009 ms ago): trimming memory 2026-06-22T10:14:55.458Z|00049|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory 2026-06-22T10:18:59.248Z|00050|memory_trim|INFO|Detected inactivity (last active 30028 ms ago): trimming memory 2026-06-22T10:19:55.468Z|00051|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory