2026-06-22T14:19:00.762Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-northd.log 2026-06-22T14:19:00.763Z|00002|ovn_northd|INFO|OVN internal version is : [24.03.6-20.33.0-76.8] 2026-06-22T14:19:00.763Z|00003|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T14:19:00.763Z|00004|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connected 2026-06-22T14:19:00.763Z|00005|ovn_northd|INFO|OVN NB IDL reconnected, force recompute. 2026-06-22T14:19:00.763Z|00006|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T14:19:00.763Z|00007|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connected 2026-06-22T14:19:00.763Z|00008|ovn_northd|INFO|OVN SB IDL reconnected, force recompute. 2026-06-22T14:19:00.763Z|00009|ovn_northd|INFO|ovn-northd lock acquired. This ovn-northd instance is now active. 2026-06-22T14:19:00.767Z|00010|memory_trim|INFO|Detected inactivity (last active 0 ms ago): trimming memory 2026-06-22T14:19:30.798Z|00011|memory|INFO|7476 kB peak resident set size after 30.0 seconds 2026-06-22T14:19:30.798Z|00012|memory|INFO|idl-cells-OVN_Northbound:12 idl-cells-OVN_Southbound:185 2026-06-22T14:19:30.798Z|00013|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory 2026-06-22T14:23:29.785Z|00014|ovn_parallel_hmap|INFO|Setting thread count to 0 2026-06-22T14:23:30.174Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-northd.log 2026-06-22T14:23:30.175Z|00002|ovn_northd|INFO|OVN internal version is : [24.03.6-20.33.0-76.8] 2026-06-22T14:23:30.175Z|00003|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T14:23:30.175Z|00004|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connection attempt failed (No such file or directory) 2026-06-22T14:23:30.175Z|00005|ovn_northd|INFO|OVN NB IDL reconnected, force recompute. 2026-06-22T14:23:30.175Z|00006|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T14:23:30.175Z|00007|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connection attempt failed (No such file or directory) 2026-06-22T14:23:30.175Z|00008|ovn_northd|INFO|OVN SB IDL reconnected, force recompute. 2026-06-22T14:23:31.176Z|00009|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T14:23:31.176Z|00010|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connected 2026-06-22T14:23:31.176Z|00011|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T14:23:31.176Z|00012|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connected 2026-06-22T14:23:31.177Z|00013|ovn_northd|INFO|ovn-northd lock acquired. This ovn-northd instance is now active. 2026-06-22T14:23:40.784Z|00014|memory|INFO|7316 kB peak resident set size after 10.6 seconds 2026-06-22T14:23:40.784Z|00015|memory|INFO|idl-cells-OVN_Northbound:26 idl-cells-OVN_Southbound:218 2026-06-22T14:24:36.527Z|00016|memory_trim|INFO|Detected inactivity (last active 30010 ms ago): trimming memory 2026-06-22T14:25:30.953Z|00017|memory_trim|INFO|Detected inactivity (last active 30025 ms ago): trimming memory 2026-06-22T14:26:12.472Z|00018|memory_trim|INFO|Detected inactivity (last active 30021 ms ago): trimming memory 2026-06-22T14:26:32.605Z|00019|memory|INFO|peak resident set size grew 51% in last 171.8 seconds, from 7316 kB to 11028 kB 2026-06-22T14:26:32.605Z|00020|memory|INFO|idl-cells-OVN_Northbound:2229 idl-cells-OVN_Southbound:12990 2026-06-22T14:27:33.900Z|00021|timeval|WARN|Unreasonably long 1461ms poll interval (21ms user, 1008ms system) 2026-06-22T14:27:34.051Z|00022|timeval|WARN|faults: 128 minor, 880 major 2026-06-22T14:27:34.213Z|00023|timeval|WARN|disk: 220840 reads, 0 writes 2026-06-22T14:27:34.412Z|00024|timeval|WARN|context switches: 893 voluntary, 230 involuntary 2026-06-22T14:27:34.437Z|00025|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=6caead25: 2026-06-22T14:27:34.493Z|00026|coverage|INFO|util_xalloc 9020.2/sec 107390.283/sec 2247.3431/sec total: 8091694 2026-06-22T14:27:34.593Z|00027|coverage|INFO|stream_open 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-06-22T14:27:34.757Z|00028|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:27:34.808Z|00029|coverage|INFO|seq_change 5.8/sec 85.900/sec 2.2347/sec total: 8048 2026-06-22T14:27:34.892Z|00030|coverage|INFO|poll_create_node 13.2/sec 190.267/sec 4.9667/sec total: 17890 2026-06-22T14:27:35.011Z|00031|coverage|INFO|txn_success 0.4/sec 4.817/sec 0.1311/sec total: 473 2026-06-22T14:27:35.108Z|00032|coverage|INFO|txn_incomplete 0.4/sec 6.167/sec 0.1658/sec total: 599 2026-06-22T14:27:35.155Z|00033|coverage|INFO|txn_unchanged 1.2/sec 12.300/sec 0.3294/sec total: 1188 2026-06-22T14:27:35.282Z|00034|coverage|INFO|hmap_reserve 17.4/sec 239.650/sec 4.9358/sec total: 17769 2026-06-22T14:27:35.332Z|00035|coverage|INFO|hmap_expand 134.2/sec 2184.383/sec 47.1625/sec total: 169838 2026-06-22T14:27:35.363Z|00036|coverage|INFO|hmap_pathological 3.0/sec 32.783/sec 0.6175/sec total: 2223 2026-06-22T14:27:35.411Z|00037|coverage|INFO|northd_run 0.2/sec 3.967/sec 0.1156/sec total: 416 2026-06-22T14:27:35.432Z|00038|coverage|INFO|103 events never hit 2026-06-22T14:29:25.532Z|00039|ovn_northd|INFO|OVN_Northbound IDL run: 2 iterations in 5276 ms 2026-06-22T14:29:25.539Z|00040|memory_trim|INFO|Detected inactivity (last active 37941 ms ago): trimming memory 2026-06-22T14:29:25.541Z|00001|timeval(stopwatch0)|WARN|Unreasonably long 4810ms poll interval (0ms user, 2180ms system) 2026-06-22T14:29:25.541Z|00002|timeval(stopwatch0)|WARN|faults: 9 minor, 97 major 2026-06-22T14:29:25.541Z|00003|timeval(stopwatch0)|WARN|disk: 22256 reads, 0 writes 2026-06-22T14:29:25.541Z|00004|timeval(stopwatch0)|WARN|context switches: 100 voluntary, 2544 involuntary 2026-06-22T14:29:25.552Z|00005|coverage(stopwatch0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=bd0e16dc: 2026-06-22T14:29:25.556Z|00041|timeval|WARN|Unreasonably long 6408ms poll interval (12ms user, 3871ms system) 2026-06-22T14:29:25.556Z|00042|timeval|WARN|faults: 34 minor, 231 major 2026-06-22T14:29:25.556Z|00006|coverage(stopwatch0)|INFO|util_xalloc 5400.0/sec 23940.800/sec 3153.4372/sec total: 11352374 2026-06-22T14:29:25.556Z|00007|coverage(stopwatch0)|INFO|long_poll_interval 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:29:25.556Z|00008|coverage(stopwatch0)|INFO|stream_open 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-06-22T14:29:25.556Z|00009|coverage(stopwatch0)|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T14:29:25.556Z|00010|coverage(stopwatch0)|INFO|seq_change 4.0/sec 23.283/sec 3.0947/sec total: 11141 2026-06-22T14:29:25.556Z|00011|coverage(stopwatch0)|INFO|poll_create_node 7.6/sec 52.233/sec 6.9133/sec total: 24888 2026-06-22T14:29:25.556Z|00012|coverage(stopwatch0)|INFO|txn_success 1.0/sec 1.550/sec 0.1917/sec total: 690 2026-06-22T14:29:25.556Z|00013|coverage(stopwatch0)|INFO|txn_incomplete 0.4/sec 1.833/sec 0.2336/sec total: 841 2026-06-22T14:29:25.556Z|00014|coverage(stopwatch0)|INFO|txn_unchanged 1.6/sec 3.833/sec 0.4883/sec total: 1758 2026-06-22T14:29:25.556Z|00015|coverage(stopwatch0)|INFO|hmap_reserve 15.0/sec 52.433/sec 6.9633/sec total: 25068 2026-06-22T14:29:25.556Z|00016|coverage(stopwatch0)|INFO|hmap_expand 110.2/sec 445.017/sec 64.2994/sec total: 231478 2026-06-22T14:29:25.556Z|00043|timeval|WARN|disk: 54488 reads, 16 writes 2026-06-22T14:29:25.556Z|00044|timeval|WARN|context switches: 245 voluntary, 3834 involuntary 2026-06-22T14:29:25.556Z|00017|coverage(stopwatch0)|INFO|hmap_pathological 2.8/sec 9.250/sec 0.9506/sec total: 3422 2026-06-22T14:29:25.556Z|00018|coverage(stopwatch0)|INFO|northd_run 0.2/sec 0.733/sec 0.1419/sec total: 511 2026-06-22T14:29:25.556Z|00019|coverage(stopwatch0)|INFO|102 events never hit 2026-06-22T14:29:25.556Z|00045|coverage|INFO|Skipping details of duplicate event coverage for hash=bd0e16dc 2026-06-22T14:29:25.556Z|00020|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.106Z|00021|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.107Z|00022|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.110Z|00023|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.110Z|00024|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.110Z|00025|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.110Z|00026|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.113Z|00027|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.113Z|00028|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:29:27.116Z|00029|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[170271]) at lib/stopwatch.c:456 (51% CPU usage) 2026-06-22T14:32:20.782Z|00046|memory_trim|INFO|Detected inactivity (last active 30008 ms ago): trimming memory 2026-06-22T14:33:13.488Z|00047|memory_trim|INFO|Detected inactivity (last active 30261 ms ago): trimming memory 2026-06-22T14:34:09.088Z|00048|memory_trim|INFO|Detected inactivity (last active 30027 ms ago): trimming memory 2026-06-22T14:35:32.043Z|00049|memory_trim|INFO|Detected inactivity (last active 30011 ms ago): trimming memory 2026-06-22T14:36:54.827Z|00050|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory 2026-06-22T14:38:12.377Z|00051|memory_trim|INFO|Detected inactivity (last active 30015 ms ago): trimming memory 2026-06-22T14:39:09.112Z|00052|memory_trim|INFO|Detected inactivity (last active 30023 ms ago): trimming memory