2026-06-22T12:47:31.481Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-northd.log 2026-06-22T12:47:31.482Z|00002|ovn_northd|INFO|OVN internal version is : [24.03.6-20.33.0-76.8] 2026-06-22T12:47:31.482Z|00003|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T12:47:31.482Z|00004|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connected 2026-06-22T12:47:31.482Z|00005|ovn_northd|INFO|OVN NB IDL reconnected, force recompute. 2026-06-22T12:47:31.482Z|00006|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T12:47:31.482Z|00007|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connected 2026-06-22T12:47:31.482Z|00008|ovn_northd|INFO|OVN SB IDL reconnected, force recompute. 2026-06-22T12:47:31.483Z|00009|ovn_northd|INFO|ovn-northd lock acquired. This ovn-northd instance is now active. 2026-06-22T12:47:31.486Z|00010|memory_trim|INFO|Detected inactivity (last active 0 ms ago): trimming memory 2026-06-22T12:48:01.518Z|00011|memory|INFO|7444 kB peak resident set size after 30.0 seconds 2026-06-22T12:48:01.518Z|00012|memory|INFO|idl-cells-OVN_Northbound:12 idl-cells-OVN_Southbound:185 2026-06-22T12:48:01.518Z|00013|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory 2026-06-22T12:51:54.374Z|00014|ovn_parallel_hmap|INFO|Setting thread count to 0 2026-06-22T12:51:54.777Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-northd.log 2026-06-22T12:51:54.779Z|00002|ovn_northd|INFO|OVN internal version is : [24.03.6-20.33.0-76.8] 2026-06-22T12:51:54.779Z|00003|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connecting... 2026-06-22T12:51:54.779Z|00004|reconnect|INFO|unix:/var/run/ovn/ovnnb_db.sock: connected 2026-06-22T12:51:54.779Z|00005|ovn_northd|INFO|OVN NB IDL reconnected, force recompute. 2026-06-22T12:51:54.779Z|00006|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T12:51:54.779Z|00007|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connection attempt failed (No such file or directory) 2026-06-22T12:51:54.779Z|00008|ovn_northd|INFO|OVN SB IDL reconnected, force recompute. 2026-06-22T12:51:55.780Z|00009|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connecting... 2026-06-22T12:51:55.780Z|00010|reconnect|INFO|unix:/var/run/ovn/ovnsb_db.sock: connected 2026-06-22T12:51:55.781Z|00011|ovn_northd|INFO|ovn-northd lock acquired. This ovn-northd instance is now active. 2026-06-22T12:52:05.387Z|00012|memory|INFO|7316 kB peak resident set size after 10.6 seconds 2026-06-22T12:52:05.387Z|00013|memory|INFO|idl-cells-OVN_Northbound:26 idl-cells-OVN_Southbound:218 2026-06-22T12:53:00.126Z|00014|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory 2026-06-22T12:53:58.864Z|00015|memory_trim|INFO|Detected inactivity (last active 30026 ms ago): trimming memory 2026-06-22T12:54:41.985Z|00016|memory_trim|INFO|Detected inactivity (last active 30017 ms ago): trimming memory 2026-06-22T12:55:16.925Z|00017|memory|INFO|peak resident set size grew 63% in last 191.5 seconds, from 7316 kB to 11924 kB 2026-06-22T12:55:16.925Z|00018|memory|INFO|idl-cells-OVN_Northbound:2449 idl-cells-OVN_Southbound:15193 2026-06-22T12:55:47.359Z|00019|inc_proc_eng|INFO|node: northd, recompute (failed handler for input NB_logical_switch) took 7642ms 2026-06-22T12:55:47.365Z|00020|timeval|WARN|Unreasonably long 7934ms poll interval (62ms user, 5757ms system) 2026-06-22T12:55:47.365Z|00021|timeval|WARN|faults: 654 minor, 4732 major 2026-06-22T12:55:47.365Z|00022|timeval|WARN|disk: 1152568 reads, 8 writes 2026-06-22T12:55:47.365Z|00023|timeval|WARN|context switches: 4780 voluntary, 2969 involuntary 2026-06-22T12:55:47.365Z|00024|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=6caead25: 2026-06-22T12:55:47.365Z|00025|coverage|INFO|util_xalloc 28411.4/sec 117413.550/sec 2028.2464/sec total: 7327837 2026-06-22T12:55:47.365Z|00026|coverage|INFO|stream_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T12:55:47.365Z|00027|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T12:55:47.365Z|00028|coverage|INFO|seq_change 22.6/sec 109.617/sec 2.0481/sec total: 7374 2026-06-22T12:55:47.365Z|00029|coverage|INFO|poll_create_node 50.8/sec 242.717/sec 4.5622/sec total: 16428 2026-06-22T12:55:47.365Z|00030|coverage|INFO|txn_success 1.4/sec 6.250/sec 0.1214/sec total: 437 2026-06-22T12:55:47.365Z|00031|coverage|INFO|txn_incomplete 1.4/sec 7.717/sec 0.1506/sec total: 543 2026-06-22T12:55:47.365Z|00032|coverage|INFO|txn_unchanged 4.2/sec 15.817/sec 0.3147/sec total: 1134 2026-06-22T12:55:47.365Z|00033|coverage|INFO|hmap_reserve 74.2/sec 255.617/sec 4.3339/sec total: 15674 2026-06-22T12:55:47.365Z|00034|coverage|INFO|hmap_expand 573.2/sec 2424.617/sec 42.4478/sec total: 153325 2026-06-22T12:55:47.365Z|00035|coverage|INFO|hmap_pathological 6.2/sec 23.600/sec 0.4042/sec total: 1460 2026-06-22T12:55:47.365Z|00036|coverage|INFO|northd_run 1.0/sec 5.700/sec 0.1094/sec total: 395 2026-06-22T12:55:47.365Z|00037|coverage|INFO|103 events never hit 2026-06-22T12:55:47.815Z|00038|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (54% CPU usage) 2026-06-22T12:55:47.840Z|00039|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (54% CPU usage) 2026-06-22T12:55:47.868Z|00040|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (54% CPU usage) 2026-06-22T12:55:47.870Z|00041|poll_loop|INFO|wakeup due to [POLLIN] on fd 17 (<->/var/run/ovn/ovnnb_db.sock) at lib/stream-fd.c:157 (54% CPU usage) 2026-06-22T12:55:47.872Z|00042|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (54% CPU usage) 2026-06-22T12:55:50.151Z|00043|poll_loop|INFO|wakeup due to [POLLIN] on fd 17 (<->/var/run/ovn/ovnnb_db.sock) at lib/stream-fd.c:157 (54% CPU usage) 2026-06-22T12:55:50.164Z|00044|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (54% CPU usage) 2026-06-22T12:58:22.666Z|00045|ovn_northd|INFO|OVN_Northbound IDL run: 2 iterations in 1189 ms 2026-06-22T12:58:23.589Z|00001|timeval(stopwatch0)|WARN|Unreasonably long 1632ms poll interval (0ms user, 1289ms system) 2026-06-22T12:58:39.565Z|00002|timeval(stopwatch0)|WARN|faults: 32 minor, 117 major 2026-06-22T12:58:40.447Z|00046|ovn_northd|INFO|OVN_Southbound IDL run: 2 iterations in 5994 ms 2026-06-22T12:58:40.718Z|00003|timeval(stopwatch0)|WARN|disk: 34304 reads, 0 writes 2026-06-22T12:58:40.719Z|00004|timeval(stopwatch0)|WARN|context switches: 122 voluntary, 993 involuntary 2026-06-22T12:58:40.719Z|00005|coverage(stopwatch0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=bd0e16dc: 2026-06-22T12:58:40.719Z|00006|coverage(stopwatch0)|INFO|util_xalloc 17533.2/sec 12028.833/sec 3699.6028/sec total: 13318570 2026-06-22T12:58:40.719Z|00007|coverage(stopwatch0)|INFO|long_poll_interval 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T12:58:40.719Z|00008|coverage(stopwatch0)|INFO|stream_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T12:58:40.719Z|00009|coverage(stopwatch0)|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T12:58:40.719Z|00010|coverage(stopwatch0)|INFO|seq_change 11.4/sec 9.150/sec 3.6858/sec total: 13269 2026-06-22T12:58:40.719Z|00011|coverage(stopwatch0)|INFO|poll_create_node 25.6/sec 20.533/sec 8.1778/sec total: 29440 2026-06-22T12:58:40.719Z|00012|coverage(stopwatch0)|INFO|txn_success 0.8/sec 0.567/sec 0.2047/sec total: 737 2026-06-22T12:58:40.719Z|00013|coverage(stopwatch0)|INFO|txn_incomplete 1.4/sec 0.683/sec 0.2528/sec total: 910 2026-06-22T12:58:40.719Z|00014|coverage(stopwatch0)|INFO|txn_unchanged 2.6/sec 1.550/sec 0.5525/sec total: 1989 2026-06-22T12:58:40.719Z|00015|coverage(stopwatch0)|INFO|hmap_reserve 47.0/sec 30.850/sec 8.1958/sec total: 29505 2026-06-22T12:58:40.720Z|00016|coverage(stopwatch0)|INFO|hmap_expand 351.6/sec 240.417/sec 75.1822/sec total: 270656 2026-06-22T12:58:40.720Z|00017|coverage(stopwatch0)|INFO|hmap_pathological 7.0/sec 4.383/sec 0.8628/sec total: 3106 2026-06-22T12:58:40.720Z|00018|coverage(stopwatch0)|INFO|northd_run 1.2/sec 0.450/sec 0.1767/sec total: 636 2026-06-22T12:58:40.720Z|00019|coverage(stopwatch0)|INFO|102 events never hit 2026-06-22T12:58:40.720Z|00047|memory_trim|INFO|Detected inactivity (last active 51090 ms ago): trimming memory 2026-06-22T12:58:40.721Z|00048|timeval|WARN|Unreasonably long 20847ms poll interval (88ms user, 10844ms system) 2026-06-22T12:58:40.721Z|00049|timeval|WARN|faults: 98 minor, 795 major 2026-06-22T12:58:40.721Z|00050|timeval|WARN|disk: 200096 reads, 8 writes 2026-06-22T12:58:40.721Z|00051|timeval|WARN|context switches: 825 voluntary, 10324 involuntary 2026-06-22T12:58:40.721Z|00052|coverage|INFO|Skipping details of duplicate event coverage for hash=bd0e16dc 2026-06-22T12:58:40.721Z|00020|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:40.731Z|00053|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (53% CPU usage) 2026-06-22T12:58:40.731Z|00021|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:40.733Z|00022|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:40.733Z|00023|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:40.734Z|00024|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:41.054Z|00054|poll_loop|INFO|wakeup due to [POLLIN] on fd 17 (<->/var/run/ovn/ovnnb_db.sock) at lib/stream-fd.c:157 (53% CPU usage) 2026-06-22T12:58:41.056Z|00025|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:41.056Z|00026|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:41.059Z|00027|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:41.060Z|00028|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:41.060Z|00029|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T12:58:41.060Z|00030|poll_loop(stopwatch0)|INFO|wakeup due to [POLLIN] on fd 11 (FIFO pipe:[174186]) at lib/stopwatch.c:456 (53% CPU usage) 2026-06-22T13:00:01.305Z|00055|ovn_northd|INFO|OVN_Northbound IDL run: 2 iterations in 5894 ms 2026-06-22T13:00:01.305Z|00031|timeval(stopwatch0)|WARN|Unreasonably long 8373ms poll interval (0ms user, 3689ms system) 2026-06-22T13:00:01.308Z|00032|timeval(stopwatch0)|WARN|faults: 8 minor, 166 major 2026-06-22T13:00:01.309Z|00033|timeval(stopwatch0)|WARN|disk: 41304 reads, 0 writes 2026-06-22T13:00:01.309Z|00034|timeval(stopwatch0)|WARN|context switches: 170 voluntary, 3873 involuntary 2026-06-22T13:00:01.311Z|00035|coverage(stopwatch0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=36227582: 2026-06-22T13:00:01.311Z|00036|coverage(stopwatch0)|INFO|util_xalloc 796.0/sec 5556.617/sec 3797.5969/sec total: 13671349 2026-06-22T13:00:01.311Z|00037|coverage(stopwatch0)|INFO|long_poll_interval 0.0/sec 0.017/sec 0.0008/sec total: 3 2026-06-22T13:00:01.311Z|00038|coverage(stopwatch0)|INFO|stream_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T13:00:01.311Z|00039|coverage(stopwatch0)|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T13:00:01.311Z|00040|coverage(stopwatch0)|INFO|seq_change 1.4/sec 4.433/sec 3.7658/sec total: 13557 2026-06-22T13:00:01.311Z|00041|coverage(stopwatch0)|INFO|poll_create_node 2.2/sec 10.017/sec 8.3589/sec total: 30092 2026-06-22T13:00:01.311Z|00042|coverage(stopwatch0)|INFO|txn_success 0.2/sec 0.367/sec 0.2111/sec total: 760 2026-06-22T13:00:01.311Z|00043|coverage(stopwatch0)|INFO|txn_incomplete 0.2/sec 0.450/sec 0.2606/sec total: 938 2026-06-22T13:00:01.311Z|00044|coverage(stopwatch0)|INFO|txn_unchanged 0.6/sec 0.733/sec 0.5658/sec total: 2037 2026-06-22T13:00:01.311Z|00045|coverage(stopwatch0)|INFO|hmap_reserve 2.8/sec 14.567/sec 8.4492/sec total: 30417 2026-06-22T13:00:01.311Z|00046|coverage(stopwatch0)|INFO|hmap_expand 20.0/sec 114.367/sec 77.1803/sec total: 277849 2026-06-22T13:00:01.311Z|00047|coverage(stopwatch0)|INFO|hmap_pathological 0.6/sec 2.100/sec 0.9003/sec total: 3241 2026-06-22T13:00:01.311Z|00048|coverage(stopwatch0)|INFO|northd_run 0.2/sec 0.217/sec 0.1803/sec total: 649 2026-06-22T13:00:01.311Z|00049|coverage(stopwatch0)|INFO|102 events never hit 2026-06-22T13:00:01.312Z|00056|memory_trim|INFO|Detected inactivity (last active 42935 ms ago): trimming memory 2026-06-22T13:00:01.314Z|00057|timeval|WARN|Unreasonably long 10567ms poll interval (0ms user, 7135ms system) 2026-06-22T13:00:01.314Z|00058|timeval|WARN|faults: 31 minor, 322 major 2026-06-22T13:00:01.314Z|00059|timeval|WARN|disk: 80568 reads, 8 writes 2026-06-22T13:00:01.314Z|00060|timeval|WARN|context switches: 338 voluntary, 6958 involuntary 2026-06-22T13:00:01.314Z|00061|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=df869984: 2026-06-22T13:00:01.314Z|00062|coverage|INFO|util_xalloc 796.0/sec 5556.617/sec 3797.5969/sec total: 13678035 2026-06-22T13:00:01.314Z|00063|coverage|INFO|long_poll_interval 0.0/sec 0.017/sec 0.0008/sec total: 4 2026-06-22T13:00:01.314Z|00064|coverage|INFO|stream_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-06-22T13:00:01.314Z|00065|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-06-22T13:00:01.314Z|00066|coverage|INFO|seq_change 1.4/sec 4.433/sec 3.7658/sec total: 13560 2026-06-22T13:00:01.314Z|00067|coverage|INFO|poll_create_node 2.2/sec 10.017/sec 8.3589/sec total: 30102 2026-06-22T13:00:01.314Z|00068|coverage|INFO|txn_success 0.2/sec 0.367/sec 0.2111/sec total: 761 2026-06-22T13:00:01.314Z|00069|coverage|INFO|txn_incomplete 0.2/sec 0.450/sec 0.2606/sec total: 938 2026-06-22T13:00:01.314Z|00070|coverage|INFO|txn_unchanged 0.6/sec 0.733/sec 0.5658/sec total: 2041 2026-06-22T13:00:01.314Z|00071|coverage|INFO|hmap_reserve 2.8/sec 14.567/sec 8.4492/sec total: 30417 2026-06-22T13:00:01.314Z|00072|coverage|INFO|hmap_expand 20.0/sec 114.367/sec 77.1803/sec total: 277996 2026-06-22T13:00:01.314Z|00073|coverage|INFO|hmap_pathological 0.6/sec 2.100/sec 0.9003/sec total: 3241 2026-06-22T13:00:01.314Z|00074|coverage|INFO|northd_run 0.2/sec 0.217/sec 0.1803/sec total: 649 2026-06-22T13:00:01.314Z|00075|coverage|INFO|102 events never hit 2026-06-22T13:00:01.319Z|00076|poll_loop|INFO|Dropped 30 log messages in last 80 seconds (most recently, 77 seconds ago) due to excessive rate 2026-06-22T13:00:01.319Z|00077|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (69% CPU usage) 2026-06-22T13:00:34.510Z|00078|memory_trim|INFO|Detected inactivity (last active 30011 ms ago): trimming memory 2026-06-22T13:01:22.433Z|00079|inc_proc_eng|INFO|node: northd, recompute (failed handler for input NB_logical_router) took 1647ms 2026-06-22T13:01:22.439Z|00080|timeval|WARN|Unreasonably long 1843ms poll interval (5ms user, 1661ms system) 2026-06-22T13:01:22.439Z|00081|timeval|WARN|faults: 88 minor, 448 major 2026-06-22T13:01:22.439Z|00082|timeval|WARN|disk: 108312 reads, 8 writes 2026-06-22T13:01:22.439Z|00083|timeval|WARN|context switches: 456 voluntary, 1395 involuntary 2026-06-22T13:01:22.439Z|00084|coverage|INFO|Skipping details of duplicate event coverage for hash=df869984 2026-06-22T13:01:36.376Z|00085|timeval|WARN|Unreasonably long 2721ms poll interval (2ms user, 2168ms system) 2026-06-22T13:01:36.376Z|00086|timeval|WARN|faults: 33 minor, 119 major 2026-06-22T13:01:36.376Z|00087|timeval|WARN|disk: 31984 reads, 0 writes 2026-06-22T13:01:36.376Z|00088|timeval|WARN|context switches: 130 voluntary, 1901 involuntary 2026-06-22T13:01:36.376Z|00089|coverage|INFO|Skipping details of duplicate event coverage for hash=df869984 2026-06-22T13:02:09.610Z|00090|inc_proc_eng|INFO|node: northd, recompute (failed handler for input NB_logical_switch) took 4019ms 2026-06-22T13:02:09.617Z|00091|timeval|WARN|Unreasonably long 4143ms poll interval (20ms user, 3410ms system) 2026-06-22T13:02:09.617Z|00092|timeval|WARN|faults: 117 minor, 936 major 2026-06-22T13:02:09.617Z|00093|timeval|WARN|disk: 226896 reads, 8 writes 2026-06-22T13:02:09.617Z|00094|timeval|WARN|context switches: 954 voluntary, 2648 involuntary 2026-06-22T13:02:09.617Z|00095|coverage|INFO|Skipping details of duplicate event coverage for hash=df869984 2026-06-22T13:02:09.619Z|00096|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (57% CPU usage) 2026-06-22T13:02:12.377Z|00097|poll_loop|INFO|wakeup due to [POLLIN] on fd 17 (<->/var/run/ovn/ovnnb_db.sock) at lib/stream-fd.c:157 (57% CPU usage) 2026-06-22T13:02:12.388Z|00098|poll_loop|INFO|wakeup due to [POLLIN] on fd 18 (<->/var/run/ovn/ovnsb_db.sock) at lib/stream-fd.c:157 (57% CPU usage) 2026-06-22T13:02:42.883Z|00099|memory_trim|INFO|Detected inactivity (last active 30024 ms ago): trimming memory 2026-06-22T13:04:31.762Z|00100|memory_trim|INFO|Detected inactivity (last active 30032 ms ago): trimming memory 2026-06-22T13:05:52.539Z|00050|timeval(stopwatch0)|WARN|Unreasonably long 2123ms poll interval (0ms user, 699ms system) 2026-06-22T13:05:52.540Z|00051|timeval(stopwatch0)|WARN|faults: 5 minor, 36 major 2026-06-22T13:05:52.540Z|00052|timeval(stopwatch0)|WARN|disk: 8960 reads, 0 writes 2026-06-22T13:05:52.540Z|00053|timeval(stopwatch0)|WARN|context switches: 38 voluntary, 805 involuntary 2026-06-22T13:05:52.540Z|00054|coverage(stopwatch0)|INFO|Skipping details of duplicate event coverage for hash=df869984 2026-06-22T13:05:52.543Z|00101|memory_trim|INFO|Detected inactivity (last active 39225 ms ago): trimming memory 2026-06-22T13:05:52.543Z|00102|timeval|WARN|Unreasonably long 5969ms poll interval (0ms user, 3079ms system) 2026-06-22T13:05:52.543Z|00103|timeval|WARN|faults: 16 minor, 149 major 2026-06-22T13:05:52.543Z|00104|timeval|WARN|disk: 37000 reads, 0 writes 2026-06-22T13:05:52.543Z|00105|timeval|WARN|context switches: 154 voluntary, 3138 involuntary 2026-06-22T13:05:52.543Z|00106|coverage|INFO|Skipping details of duplicate event coverage for hash=df869984 2026-06-22T13:06:21.558Z|00107|timeval|WARN|Unreasonably long 1064ms poll interval (0ms user, 696ms system) 2026-06-22T13:06:21.558Z|00108|timeval|WARN|faults: 12 minor, 35 major 2026-06-22T13:06:21.558Z|00109|timeval|WARN|disk: 8864 reads, 0 writes 2026-06-22T13:06:21.558Z|00110|timeval|WARN|context switches: 37 voluntary, 739 involuntary 2026-06-22T13:06:21.559Z|00111|coverage|INFO|Skipping details of duplicate event coverage for hash=df869984 2026-06-22T13:06:54.106Z|00112|memory_trim|INFO|Detected inactivity (last active 30034 ms ago): trimming memory 2026-06-22T13:08:04.163Z|00113|memory_trim|INFO|Detected inactivity (last active 30025 ms ago): trimming memory 2026-06-22T13:10:04.289Z|00114|memory_trim|INFO|Detected inactivity (last active 30030 ms ago): trimming memory