2026-06-22T09:16:51.558Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller.log
2026-06-22T09:16:51.560Z|00002|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T09:16:51.560Z|00003|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T09:16:51.562Z|00004|main|INFO|OVN internal version is : [24.03.6-20.33.0-76.8]
2026-06-22T09:16:51.562Z|00005|main|INFO|OVS IDL reconnected, force recompute.
2026-06-22T09:16:51.562Z|00006|main|INFO|OVNSB IDL reconnected, force recompute.
2026-06-22T09:21:04.386Z|00007|memory|INFO|8076 kB peak resident set size after 252.8 seconds
2026-06-22T09:21:04.386Z|00008|memory|INFO|idl-cells-Open_vSwitch:101
2026-06-22T09:21:04.387Z|00009|jsonrpc|WARN|unix:/var/run/openvswitch/db.sock: send error: Broken pipe
2026-06-22T09:21:04.388Z|00010|reconnect|WARN|unix:/var/run/openvswitch/db.sock: connection dropped (Broken pipe)
2026-06-22T09:21:05.388Z|00011|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T09:21:05.388Z|00012|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T09:21:05.452Z|00013|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:21:05.452Z|00014|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt failed (Connection refused)
2026-06-22T09:21:06.452Z|00015|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:21:06.452Z|00016|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt failed (Connection refused)
2026-06-22T09:21:06.452Z|00017|reconnect|INFO|ssl:10.4.3.11:6642: waiting 2 seconds before reconnect
2026-06-22T09:21:08.363Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller.log
2026-06-22T09:21:08.364Z|00002|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T09:21:08.364Z|00003|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T09:21:08.366Z|00004|main|INFO|OVN internal version is : [24.03.6-20.33.0-76.8]
2026-06-22T09:21:08.366Z|00005|main|INFO|OVS IDL reconnected, force recompute.
2026-06-22T09:21:08.370Z|00006|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:21:08.370Z|00007|main|INFO|OVNSB IDL reconnected, force recompute.
2026-06-22T09:21:08.375Z|00008|reconnect|INFO|ssl:10.4.3.11:6642: connected
2026-06-22T09:21:08.378Z|00009|features|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T09:21:08.378Z|00010|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T09:21:08.378Z|00011|features|INFO|OVS Feature: ct_zero_snat, state: supported
2026-06-22T09:21:08.378Z|00012|features|INFO|OVS Feature: ct_flush, state: supported
2026-06-22T09:21:08.378Z|00013|features|INFO|OVS Feature: dp_hash_l4_sym_support, state: supported
2026-06-22T09:21:08.378Z|00014|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T09:21:08.378Z|00015|main|INFO|OVS feature set changed, force recompute.
2026-06-22T09:21:08.378Z|00016|ofctrl|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T09:21:08.379Z|00017|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T09:21:08.379Z|00018|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T09:21:08.379Z|00019|main|INFO|OVS OpenFlow connection reconnected,force recompute.
2026-06-22T09:21:08.379Z|00020|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T09:21:08.379Z|00021|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T09:21:08.379Z|00022|main|INFO|OVS feature set changed, force recompute.
2026-06-22T09:21:08.380Z|00001|pinctrl(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T09:21:08.380Z|00002|rconn(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T09:21:08.380Z|00001|statctrl(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T09:21:08.380Z|00002|rconn(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T09:21:08.380Z|00003|rconn(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T09:21:08.380Z|00003|rconn(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T09:21:08.380Z|00023|features|INFO|OVS DB schema supports 4 flow table prefixes, our IDL supports: 4
2026-06-22T09:21:08.381Z|00024|main|INFO|Setting flow table prefixes: ip_src, ip_dst, ipv6_src, ipv6_dst.
2026-06-22T09:21:22.210Z|00025|memory|INFO|9996 kB peak resident set size after 13.8 seconds
2026-06-22T09:21:22.211Z|00026|memory|INFO|idl-cells-OVN_Southbound:226 idl-cells-Open_vSwitch:191 ofctrl_desired_flow_usage-KB:4 ofctrl_installed_flow_usage-KB:3 ofctrl_sb_flow_ref_usage-KB:2
2026-06-22T09:21:32.233Z|00027|binding|INFO|Claiming lport cr-lrp-ce85aafa-60e4-4390-ae86-413e82219bc8 for this chassis.
2026-06-22T09:21:32.233Z|00028|binding|INFO|cr-lrp-ce85aafa-60e4-4390-ae86-413e82219bc8: Claiming fa:16:3e:c3:2e:c6 172.24.5.89/24
2026-06-22T09:21:32.235Z|00029|binding|INFO|Setting lport cr-lrp-ce85aafa-60e4-4390-ae86-413e82219bc8 up in Southbound
2026-06-22T09:22:05.900Z|00030|memory_trim|INFO|Detected inactivity (last active 30001 ms ago): trimming memory
2026-06-22T09:23:05.051Z|00031|memory_trim|INFO|Detected inactivity (last active 30005 ms ago): trimming memory
2026-06-22T09:23:53.096Z|00032|binding|INFO|Claiming lport cr-lrp-01918012-b931-45b6-8cce-a8ed953ff20d for this chassis.
2026-06-22T09:23:53.096Z|00033|binding|INFO|cr-lrp-01918012-b931-45b6-8cce-a8ed953ff20d: Claiming fa:16:3e:c6:e7:a6 172.24.5.112/24 2001:db8::130/64
2026-06-22T09:23:53.100Z|00034|binding|INFO|Setting lport cr-lrp-01918012-b931-45b6-8cce-a8ed953ff20d up in Southbound
2026-06-22T09:23:53.153Z|00035|binding|INFO|Claiming lport cr-lrp-059abdd5-4591-42d8-87f2-492c351bb984 for this chassis.
2026-06-22T09:23:53.153Z|00036|binding|INFO|cr-lrp-059abdd5-4591-42d8-87f2-492c351bb984: Claiming fa:16:3e:4a:d7:a7 172.24.5.59/24 2001:db8::144/64
2026-06-22T09:23:53.160Z|00037|binding|INFO|Setting lport cr-lrp-059abdd5-4591-42d8-87f2-492c351bb984 up in Southbound
2026-06-22T09:23:53.893Z|00038|binding|INFO|Claiming lport cr-lrp-71db959a-3123-4ead-b1f2-97773681602a for this chassis.
2026-06-22T09:23:53.893Z|00039|binding|INFO|cr-lrp-71db959a-3123-4ead-b1f2-97773681602a: Claiming fa:16:3e:04:15:ed 172.24.5.48/24 2001:db8::f8/64
2026-06-22T09:23:53.900Z|00040|binding|INFO|Setting lport cr-lrp-71db959a-3123-4ead-b1f2-97773681602a up in Southbound
2026-06-22T09:23:54.587Z|00041|binding|INFO|Claiming lport cr-lrp-411fd008-cd56-42cd-81a1-3ec45b8bf525 for this chassis.
2026-06-22T09:23:54.587Z|00042|binding|INFO|cr-lrp-411fd008-cd56-42cd-81a1-3ec45b8bf525: Claiming fa:16:3e:c0:95:ca 172.24.5.208/24 2001:db8::154/64
2026-06-22T09:23:54.596Z|00043|binding|INFO|Setting lport cr-lrp-411fd008-cd56-42cd-81a1-3ec45b8bf525 up in Southbound
2026-06-22T09:23:55.392Z|00044|binding|INFO|Claiming lport cr-lrp-9af1f523-cba4-49e6-9458-92f64336dbb4 for this chassis.
2026-06-22T09:23:55.392Z|00045|binding|INFO|cr-lrp-9af1f523-cba4-49e6-9458-92f64336dbb4: Claiming fa:16:3e:61:9d:11 172.24.5.125/24 2001:db8::be/64
2026-06-22T09:23:55.400Z|00046|binding|INFO|Setting lport cr-lrp-9af1f523-cba4-49e6-9458-92f64336dbb4 up in Southbound
2026-06-22T09:23:55.956Z|00047|binding|INFO|Claiming lport cr-lrp-cc2a8605-9031-4774-9ab4-b5fc16924404 for this chassis.
2026-06-22T09:23:55.956Z|00048|binding|INFO|cr-lrp-cc2a8605-9031-4774-9ab4-b5fc16924404: Claiming fa:16:3e:db:4b:1f 172.24.5.109/24 2001:db8::3d/64
2026-06-22T09:23:55.963Z|00049|binding|INFO|Setting lport cr-lrp-cc2a8605-9031-4774-9ab4-b5fc16924404 up in Southbound
2026-06-22T09:23:56.763Z|00050|binding|INFO|Claiming lport cr-lrp-2017ed62-b3f3-4171-ad38-cc40789326ab for this chassis.
2026-06-22T09:23:56.763Z|00051|binding|INFO|cr-lrp-2017ed62-b3f3-4171-ad38-cc40789326ab: Claiming fa:16:3e:62:20:82 172.24.5.34/24 2001:db8::230/64
2026-06-22T09:23:56.772Z|00052|binding|INFO|Setting lport cr-lrp-2017ed62-b3f3-4171-ad38-cc40789326ab up in Southbound
2026-06-22T09:24:02.650Z|00053|memory|INFO|peak resident set size grew 83% in last 160.4 seconds, from 9996 kB to 18316 kB
2026-06-22T09:24:02.650Z|00054|memory|INFO|idl-cells-OVN_Southbound:11381 idl-cells-Open_vSwitch:305 if_status_mgr_ifaces_state_usage-KB:1 if_status_mgr_ifaces_usage-KB:2 lflow-cache-entries-cache-expr:590 lflow-cache-entries-cache-matches:266 lflow-cache-size-KB:2646 local_datapath_usage-KB:3 ofctrl_desired_flow_usage-KB:716 ofctrl_installed_flow_usage-KB:534 ofctrl_sb_flow_ref_usage-KB:263
2026-06-22T09:24:04.140Z|00055|binding|INFO|Claiming lport cr-lrp-32031d28-9b31-4bc4-b279-b5eb3c7969cb for this chassis.
2026-06-22T09:24:04.140Z|00056|binding|INFO|cr-lrp-32031d28-9b31-4bc4-b279-b5eb3c7969cb: Claiming fa:16:3e:af:f6:de 172.24.5.238/24 2001:db8::8a/64
2026-06-22T09:24:04.149Z|00057|binding|INFO|Setting lport cr-lrp-32031d28-9b31-4bc4-b279-b5eb3c7969cb up in Southbound
2026-06-22T09:24:04.259Z|00058|binding|INFO|Claiming lport cr-lrp-1efff847-6ed8-4658-80df-d381d4dc45ac for this chassis.
2026-06-22T09:24:04.259Z|00059|binding|INFO|cr-lrp-1efff847-6ed8-4658-80df-d381d4dc45ac: Claiming fa:16:3e:80:b0:70 172.24.5.132/24 2001:db8::2fc/64
2026-06-22T09:24:04.266Z|00060|binding|INFO|Setting lport cr-lrp-1efff847-6ed8-4658-80df-d381d4dc45ac up in Southbound
2026-06-22T09:24:05.088Z|00061|binding|INFO|Claiming lport cr-lrp-9adec5f5-1731-4237-a65e-6d1b0e114705 for this chassis.
2026-06-22T09:24:05.088Z|00062|binding|INFO|cr-lrp-9adec5f5-1731-4237-a65e-6d1b0e114705: Claiming fa:16:3e:f0:5d:39 172.24.5.7/24 2001:db8::108/64
2026-06-22T09:24:05.100Z|00063|binding|INFO|Setting lport cr-lrp-9adec5f5-1731-4237-a65e-6d1b0e114705 up in Southbound
2026-06-22T09:24:06.510Z|00064|binding|INFO|Claiming lport cr-lrp-6f5788f5-5958-47ad-98ff-c1eff483dcf8 for this chassis.
2026-06-22T09:24:06.510Z|00065|binding|INFO|cr-lrp-6f5788f5-5958-47ad-98ff-c1eff483dcf8: Claiming fa:16:3e:8d:d7:f2 172.24.5.61/24 2001:db8::22e/64
2026-06-22T09:24:06.520Z|00066|binding|INFO|Setting lport cr-lrp-6f5788f5-5958-47ad-98ff-c1eff483dcf8 up in Southbound
2026-06-22T09:24:09.295Z|00067|binding|INFO|Claiming lport cr-lrp-f8eb182f-f38b-4755-b6f3-f0483d5bdeae for this chassis.
2026-06-22T09:24:09.295Z|00068|binding|INFO|cr-lrp-f8eb182f-f38b-4755-b6f3-f0483d5bdeae: Claiming fa:16:3e:b1:07:bc 172.24.5.215/24 2001:db8::1b6/64
2026-06-22T09:24:09.305Z|00069|binding|INFO|Setting lport cr-lrp-f8eb182f-f38b-4755-b6f3-f0483d5bdeae up in Southbound
2026-06-22T09:24:13.303Z|00070|binding|INFO|Claiming lport cr-lrp-8027a8e1-8dfd-48b2-8a8b-2145d7e03162 for this chassis.
2026-06-22T09:24:13.303Z|00071|binding|INFO|cr-lrp-8027a8e1-8dfd-48b2-8a8b-2145d7e03162: Claiming fa:16:3e:27:9a:4d 172.24.5.201/24 2001:db8::131/64
2026-06-22T09:24:13.323Z|00072|binding|INFO|Setting lport cr-lrp-8027a8e1-8dfd-48b2-8a8b-2145d7e03162 up in Southbound
2026-06-22T09:24:15.868Z|00073|binding|INFO|Claiming lport cr-lrp-deb94d52-4b36-46fa-9284-455fcbd9a1d9 for this chassis.
2026-06-22T09:24:15.868Z|00074|binding|INFO|cr-lrp-deb94d52-4b36-46fa-9284-455fcbd9a1d9: Claiming fa:16:3e:8d:6c:8e 172.24.5.56/24 2001:db8::ee/64
2026-06-22T09:24:15.879Z|00075|binding|INFO|Setting lport cr-lrp-deb94d52-4b36-46fa-9284-455fcbd9a1d9 up in Southbound
2026-06-22T09:24:28.777Z|00076|binding|INFO|Claiming lport 6d2373cc-14b8-4f9f-95af-b11a79d5c2c6 for this chassis.
2026-06-22T09:24:28.778Z|00077|binding|INFO|6d2373cc-14b8-4f9f-95af-b11a79d5c2c6: Claiming fa:16:3e:af:2f:89 10.1.0.13
2026-06-22T09:24:28.925Z|00078|binding|INFO|Setting lport 6d2373cc-14b8-4f9f-95af-b11a79d5c2c6 ovn-installed in OVS
2026-06-22T09:24:29.265Z|00079|binding|INFO|Setting lport 6d2373cc-14b8-4f9f-95af-b11a79d5c2c6 up in Southbound
2026-06-22T09:24:29.267Z|00080|if_status|INFO|Not updating pb chassis for d3a9c2b0-f338-4df7-a8a3-72b75d2b20e3 now as sb is readonly
2026-06-22T09:24:29.269Z|00081|binding|INFO|Claiming lport d3a9c2b0-f338-4df7-a8a3-72b75d2b20e3 for this chassis.
2026-06-22T09:24:29.269Z|00082|binding|INFO|d3a9c2b0-f338-4df7-a8a3-72b75d2b20e3: Claiming fa:16:3e:1c:46:fa 10.1.0.10
2026-06-22T09:24:29.287Z|00083|binding|INFO|Setting lport d3a9c2b0-f338-4df7-a8a3-72b75d2b20e3 up in Southbound
2026-06-22T09:24:29.288Z|00084|binding|INFO|Setting lport d3a9c2b0-f338-4df7-a8a3-72b75d2b20e3 ovn-installed in OVS
2026-06-22T09:24:38.163Z|00085|binding|INFO|Releasing lport bc050ca9-f697-4577-a7c9-bb23b72999f1 from this chassis (sb_readonly=0)
2026-06-22T09:24:38.797Z|00086|binding|INFO|Releasing lport 8abc9308-a110-4d0e-a6c8-6cf98205bd30 from this chassis (sb_readonly=0)
2026-06-22T09:24:43.947Z|00087|binding|INFO|Claiming lport 2c32f753-d4d7-414b-812b-bbd1510ba030 for this chassis.
2026-06-22T09:24:43.947Z|00088|binding|INFO|2c32f753-d4d7-414b-812b-bbd1510ba030: Claiming fa:16:3e:86:4a:60 10.1.0.11
2026-06-22T09:24:43.968Z|00089|binding|INFO|Setting lport 2c32f753-d4d7-414b-812b-bbd1510ba030 ovn-installed in OVS
2026-06-22T09:24:43.968Z|00090|binding|INFO|Setting lport 2c32f753-d4d7-414b-812b-bbd1510ba030 up in Southbound
2026-06-22T09:24:44.335Z|00091|binding|INFO|Claiming lport cr-lrp-20139baf-a186-4d74-bd94-7a99a6c3409b for this chassis.
2026-06-22T09:24:44.335Z|00092|binding|INFO|cr-lrp-20139baf-a186-4d74-bd94-7a99a6c3409b: Claiming fa:16:3e:a2:f5:be 172.24.5.86/24 2001:db8::249/64
2026-06-22T09:24:44.350Z|00093|binding|INFO|Setting lport cr-lrp-20139baf-a186-4d74-bd94-7a99a6c3409b up in Southbound
2026-06-22T09:24:44.422Z|00094|binding|INFO|Releasing lport b49a68f0-69b9-4d5d-9fbc-eacd03b79a9f from this chassis (sb_readonly=0)
2026-06-22T09:24:54.416Z|00004|timeval(ovn_pinctrl0)|WARN|Unreasonably long 1386ms poll interval (0ms user, 1292ms system)
2026-06-22T09:25:00.683Z|00005|timeval(ovn_pinctrl0)|WARN|faults: 41 minor, 402 major
2026-06-22T09:25:00.683Z|00006|timeval(ovn_pinctrl0)|WARN|disk: 105104 reads, 0 writes
2026-06-22T09:25:00.683Z|00007|timeval(ovn_pinctrl0)|WARN|context switches: 405 voluntary, 1086 involuntary
2026-06-22T09:25:00.684Z|00008|coverage(ovn_pinctrl0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=53eb2020:
2026-06-22T09:25:00.684Z|00009|coverage(ovn_pinctrl0)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:25:00.684Z|00010|coverage(ovn_pinctrl0)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:25:00.684Z|00011|coverage(ovn_pinctrl0)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:25:00.684Z|00012|coverage(ovn_pinctrl0)|INFO|vconn_sent                69.6/sec   124.567/sec        2.2525/sec   total: 8109
2026-06-22T09:25:00.684Z|00013|coverage(ovn_pinctrl0)|INFO|vconn_received             5.6/sec     5.333/sec        0.1011/sec   total: 364
2026-06-22T09:25:00.684Z|00014|coverage(ovn_pinctrl0)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:25:00.684Z|00015|coverage(ovn_pinctrl0)|INFO|util_xalloc              25793.4/sec 38062.867/sec      683.9200/sec   total: 2462112
2026-06-22T09:25:00.684Z|00016|coverage(ovn_pinctrl0)|INFO|stream_open                0.0/sec     0.000/sec        0.0019/sec   total: 7
2026-06-22T09:25:00.684Z|00017|coverage(ovn_pinctrl0)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:25:00.684Z|00018|coverage(ovn_pinctrl0)|INFO|seq_change                35.2/sec    54.483/sec        1.1669/sec   total: 4201
2026-06-22T09:25:00.684Z|00019|coverage(ovn_pinctrl0)|INFO|rconn_sent                69.6/sec   124.567/sec        2.2514/sec   total: 8105
2026-06-22T09:25:00.684Z|00020|coverage(ovn_pinctrl0)|INFO|rconn_queued              69.6/sec   124.567/sec        2.2514/sec   total: 8105
2026-06-22T09:25:00.684Z|00021|coverage(ovn_pinctrl0)|INFO|poll_zero_timeout          0.4/sec     0.767/sec        0.0281/sec   total: 101
2026-06-22T09:25:00.684Z|00022|coverage(ovn_pinctrl0)|INFO|poll_create_node         113.6/sec   157.717/sec        3.4081/sec   total: 12269
2026-06-22T09:25:00.684Z|00023|coverage(ovn_pinctrl0)|INFO|txn_success                1.0/sec     1.017/sec        0.0206/sec   total: 74
2026-06-22T09:25:00.684Z|00024|coverage(ovn_pinctrl0)|INFO|txn_incomplete             1.2/sec     1.683/sec        0.0350/sec   total: 126
2026-06-22T09:25:00.684Z|00025|coverage(ovn_pinctrl0)|INFO|txn_unchanged             12.0/sec    13.817/sec        0.3119/sec   total: 1123
2026-06-22T09:25:00.684Z|00026|coverage(ovn_pinctrl0)|INFO|hmap_reserve              18.2/sec     4.433/sec        0.0739/sec   total: 266
2026-06-22T09:25:00.684Z|00027|coverage(ovn_pinctrl0)|INFO|hmap_expand              358.4/sec   582.483/sec       10.9283/sec   total: 39342
2026-06-22T09:25:00.684Z|00028|coverage(ovn_pinctrl0)|INFO|hmap_pathological          7.2/sec    10.167/sec        0.1836/sec   total: 661
2026-06-22T09:25:00.684Z|00029|coverage(ovn_pinctrl0)|INFO|miniflow_malloc          804.6/sec  1310.667/sec       22.5406/sec   total: 81146
2026-06-22T09:25:00.684Z|00030|coverage(ovn_pinctrl0)|INFO|physical_run               0.2/sec     0.983/sec        0.0186/sec   total: 67
2026-06-22T09:25:00.684Z|00031|coverage(ovn_pinctrl0)|INFO|lflow_conj_free            0.4/sec     0.117/sec        0.0019/sec   total: 7
2026-06-22T09:25:00.684Z|00032|coverage(ovn_pinctrl0)|INFO|lflow_conj_alloc           0.6/sec     0.167/sec        0.0028/sec   total: 10
2026-06-22T09:25:00.684Z|00033|coverage(ovn_pinctrl0)|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:25:00.684Z|00034|coverage(ovn_pinctrl0)|INFO|lflow_cache_delete        38.4/sec    52.700/sec        0.8983/sec   total: 3234
2026-06-22T09:25:00.684Z|00035|coverage(ovn_pinctrl0)|INFO|lflow_cache_miss          87.0/sec   108.917/sec        1.9219/sec   total: 6919
2026-06-22T09:25:00.684Z|00036|coverage(ovn_pinctrl0)|INFO|lflow_cache_hit          402.0/sec   596.067/sec       10.1528/sec   total: 36550
2026-06-22T09:25:00.684Z|00037|coverage(ovn_pinctrl0)|INFO|lflow_cache_add           46.8/sec    70.600/sec        1.2669/sec   total: 4561
2026-06-22T09:25:00.684Z|00038|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_matches  37.0/sec    46.733/sec        0.7981/sec   total: 2873
2026-06-22T09:25:00.684Z|00039|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_expr      1.4/sec     5.967/sec        0.1003/sec   total: 361
2026-06-22T09:25:00.684Z|00040|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_matches   39.0/sec    50.683/sec        0.9064/sec   total: 3263
2026-06-22T09:25:00.684Z|00041|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_expr       7.8/sec    19.917/sec        0.3606/sec   total: 1298
2026-06-22T09:25:00.684Z|00042|coverage(ovn_pinctrl0)|INFO|consider_logical_flow     59.8/sec   162.683/sec        2.9353/sec   total: 10567
2026-06-22T09:25:00.684Z|00043|coverage(ovn_pinctrl0)|INFO|lflow_run                  0.0/sec     0.067/sec        0.0019/sec   total: 7
2026-06-22T09:25:00.684Z|00044|coverage(ovn_pinctrl0)|INFO|116 events never hit
2026-06-22T09:25:00.685Z|00045|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:af:2f:89 10.1.0.13
2026-06-22T09:25:00.687Z|00095|timeval|WARN|Unreasonably long 7281ms poll interval (4ms user, 2427ms system)
2026-06-22T09:25:00.687Z|00096|timeval|WARN|faults: 19 minor, 111 major
2026-06-22T09:25:00.687Z|00097|timeval|WARN|disk: 25272 reads, 0 writes
2026-06-22T09:25:00.687Z|00098|timeval|WARN|context switches: 115 voluntary, 3320 involuntary
2026-06-22T09:25:00.687Z|00099|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=42e77d0f:
2026-06-22T09:25:00.687Z|00100|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:25:00.687Z|00101|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:25:00.687Z|00102|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:25:00.687Z|00103|coverage|INFO|vconn_sent                 0.2/sec   124.583/sec        2.2531/sec   total: 8111
2026-06-22T09:25:00.687Z|00104|coverage|INFO|vconn_received             0.2/sec     5.333/sec        0.1014/sec   total: 371
2026-06-22T09:25:00.687Z|00105|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:25:00.687Z|00106|coverage|INFO|util_xalloc               10.6/sec 37381.650/sec      683.9492/sec   total: 2469908
2026-06-22T09:25:00.687Z|00107|coverage|INFO|long_poll_interval         0.2/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T09:25:00.687Z|00108|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0019/sec   total: 7
2026-06-22T09:25:00.687Z|00109|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:25:00.687Z|00110|coverage|INFO|seq_change                 0.2/sec    52.217/sec        1.1672/sec   total: 4224
2026-06-22T09:25:00.687Z|00111|coverage|INFO|rconn_sent                 0.2/sec   124.583/sec        2.2519/sec   total: 8107
2026-06-22T09:25:00.687Z|00112|coverage|INFO|rconn_queued               0.2/sec   124.583/sec        2.2519/sec   total: 8107
2026-06-22T09:25:00.687Z|00113|coverage|INFO|poll_zero_timeout          0.0/sec     0.667/sec        0.0281/sec   total: 102
2026-06-22T09:25:00.687Z|00114|coverage|INFO|poll_create_node           0.4/sec   151.033/sec        3.4092/sec   total: 12347
2026-06-22T09:25:00.687Z|00115|coverage|INFO|txn_success                0.0/sec     1.017/sec        0.0206/sec   total: 75
2026-06-22T09:25:00.687Z|00116|coverage|INFO|txn_incomplete             0.0/sec     1.683/sec        0.0350/sec   total: 127
2026-06-22T09:25:00.687Z|00117|coverage|INFO|txn_unchanged              0.0/sec    13.083/sec        0.3119/sec   total: 1132
2026-06-22T09:25:00.687Z|00118|coverage|INFO|hmap_reserve               0.0/sec     4.433/sec        0.0739/sec   total: 281
2026-06-22T09:25:00.687Z|00119|coverage|INFO|hmap_expand                0.0/sec   573.367/sec       10.9283/sec   total: 39467
2026-06-22T09:25:00.687Z|00120|coverage|INFO|hmap_pathological          0.0/sec     9.250/sec        0.1836/sec   total: 665
2026-06-22T09:25:00.687Z|00121|coverage|INFO|miniflow_malloc            0.0/sec  1290.583/sec       22.5406/sec   total: 81159
2026-06-22T09:25:00.687Z|00122|coverage|INFO|flow_extract               0.2/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T09:25:00.687Z|00123|coverage|INFO|physical_run               0.0/sec     0.983/sec        0.0186/sec   total: 67
2026-06-22T09:25:00.687Z|00124|coverage|INFO|pinctrl_total_pin_pkts     0.2/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T09:25:00.687Z|00125|coverage|INFO|lflow_conj_free            0.0/sec     0.117/sec        0.0019/sec   total: 7
2026-06-22T09:25:00.687Z|00126|coverage|INFO|lflow_conj_alloc           0.0/sec     0.167/sec        0.0028/sec   total: 10
2026-06-22T09:25:00.687Z|00127|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:25:00.687Z|00128|coverage|INFO|lflow_cache_delete         0.0/sec    47.117/sec        0.8983/sec   total: 3236
2026-06-22T09:25:00.687Z|00129|coverage|INFO|lflow_cache_miss           0.0/sec   102.083/sec        1.9219/sec   total: 6921
2026-06-22T09:25:00.687Z|00130|coverage|INFO|lflow_cache_hit            0.0/sec   584.983/sec       10.1528/sec   total: 36563
2026-06-22T09:25:00.687Z|00131|coverage|INFO|lflow_cache_add            0.0/sec    65.017/sec        1.2669/sec   total: 4563
2026-06-22T09:25:00.687Z|00132|coverage|INFO|lflow_cache_free_matches   0.0/sec    41.233/sec        0.7981/sec   total: 2874
2026-06-22T09:25:00.687Z|00133|coverage|INFO|lflow_cache_free_expr      0.0/sec     5.883/sec        0.1003/sec   total: 362
2026-06-22T09:25:00.687Z|00134|coverage|INFO|lflow_cache_add_matches    0.0/sec    45.183/sec        0.9064/sec   total: 3264
2026-06-22T09:25:00.687Z|00135|coverage|INFO|lflow_cache_add_expr       0.0/sec    19.833/sec        0.3606/sec   total: 1299
2026-06-22T09:25:00.687Z|00136|coverage|INFO|consider_logical_flow      0.0/sec   156.683/sec        2.9353/sec   total: 10569
2026-06-22T09:25:00.687Z|00137|coverage|INFO|lflow_run                  0.0/sec     0.067/sec        0.0019/sec   total: 7
2026-06-22T09:25:00.687Z|00138|coverage|INFO|113 events never hit
2026-06-22T09:25:00.687Z|00139|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (71% CPU usage)
2026-06-22T09:25:00.700Z|00046|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:af:2f:89 10.1.0.13
2026-06-22T09:25:00.829Z|00047|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:1c:46:fa 10.1.0.10
2026-06-22T09:25:00.844Z|00048|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:1c:46:fa 10.1.0.10
2026-06-22T09:25:01.267Z|00140|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:39318<->10.4.3.11:6642) at lib/stream-ssl.c:842 (71% CPU usage)
2026-06-22T09:25:01.269Z|00141|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (71% CPU usage)
2026-06-22T09:25:01.685Z|00142|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (71% CPU usage)
2026-06-22T09:25:01.686Z|00143|binding|INFO|Releasing lport 2c32f753-d4d7-414b-812b-bbd1510ba030 from this chassis (sb_readonly=0)
2026-06-22T09:25:01.686Z|00144|binding|INFO|Setting lport 2c32f753-d4d7-414b-812b-bbd1510ba030 down in Southbound
2026-06-22T09:25:01.689Z|00145|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:39318<->10.4.3.11:6642) at lib/stream-ssl.c:842 (71% CPU usage)
2026-06-22T09:25:01.698Z|00146|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:39318<->10.4.3.11:6642) at lib/stream-ssl.c:842 (71% CPU usage)
2026-06-22T09:25:01.703Z|00147|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (71% CPU usage)
2026-06-22T09:25:01.704Z|00148|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (71% CPU usage)
2026-06-22T09:25:01.705Z|00149|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (71% CPU usage)
2026-06-22T09:25:01.708Z|00150|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (71% CPU usage)
2026-06-22T09:25:07.446Z|00151|binding|INFO|Releasing lport d3a9c2b0-f338-4df7-a8a3-72b75d2b20e3 from this chassis (sb_readonly=0)
2026-06-22T09:25:07.447Z|00152|binding|INFO|Setting lport d3a9c2b0-f338-4df7-a8a3-72b75d2b20e3 down in Southbound
2026-06-22T09:25:07.474Z|00153|binding|INFO|Removing iface tapd3a9c2b0-f3 ovn-installed in OVS
2026-06-22T09:25:10.145Z|00154|binding|INFO|Releasing lport 6d2373cc-14b8-4f9f-95af-b11a79d5c2c6 from this chassis (sb_readonly=0)
2026-06-22T09:25:10.145Z|00155|binding|INFO|Setting lport 6d2373cc-14b8-4f9f-95af-b11a79d5c2c6 down in Southbound
2026-06-22T09:25:10.147Z|00156|binding|INFO|Removing iface tap6d2373cc-14 ovn-installed in OVS
2026-06-22T09:25:17.521Z|00157|reconnect|ERR|ssl:10.4.3.11:6642: no response to inactivity probe after 5.01 seconds, disconnecting
2026-06-22T09:25:17.521Z|00158|reconnect|INFO|ssl:10.4.3.11:6642: connection dropped
2026-06-22T09:25:17.522Z|00159|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T09:25:18.526Z|00160|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:25:19.532Z|00161|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:25:19.534Z|00162|reconnect|INFO|ssl:10.4.3.11:6642: waiting 2 seconds before reconnect
2026-06-22T09:25:21.539Z|00163|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:25:23.545Z|00164|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:25:23.546Z|00165|reconnect|INFO|ssl:10.4.3.11:6642: waiting 4 seconds before reconnect
2026-06-22T09:25:27.573Z|00166|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:25:31.582Z|00167|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:25:31.582Z|00168|reconnect|INFO|ssl:10.4.3.11:6642: continuing to reconnect in the background but suppressing further logging
2026-06-22T09:25:35.926Z|00169|memory_trim|INFO|Detected inactivity (last active 30018 ms ago): trimming memory
2026-06-22T09:25:50.941Z|00170|timeval|WARN|Unreasonably long 9104ms poll interval (2ms user, 8278ms system)
2026-06-22T09:25:50.942Z|00171|timeval|WARN|faults: 35 minor, 448 major
2026-06-22T09:25:50.942Z|00172|timeval|WARN|disk: 113392 reads, 0 writes
2026-06-22T09:25:50.942Z|00173|timeval|WARN|context switches: 455 voluntary, 8964 involuntary
2026-06-22T09:25:50.942Z|00174|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=280ce193:
2026-06-22T09:25:50.942Z|00175|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:25:50.942Z|00176|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:25:50.942Z|00177|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:25:50.942Z|00178|coverage|INFO|vconn_sent                 0.0/sec    20.633/sec        2.4622/sec   total: 8864
2026-06-22T09:25:50.942Z|00179|coverage|INFO|vconn_received             0.0/sec     1.817/sec        0.1194/sec   total: 430
2026-06-22T09:25:50.942Z|00180|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:25:50.942Z|00181|coverage|INFO|util_xalloc               19.0/sec  5685.267/sec      731.1800/sec   total: 2632383
2026-06-22T09:25:50.942Z|00182|coverage|INFO|long_poll_interval         0.0/sec     0.033/sec        0.0006/sec   total: 2
2026-06-22T09:25:50.942Z|00183|coverage|INFO|stream_open                0.0/sec     0.050/sec        0.0028/sec   total: 11
2026-06-22T09:25:50.942Z|00184|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:25:50.942Z|00185|coverage|INFO|seq_change                 0.4/sec    13.100/sec        1.2867/sec   total: 4633
2026-06-22T09:25:50.942Z|00186|coverage|INFO|rconn_sent                 0.0/sec    20.633/sec        2.4611/sec   total: 8860
2026-06-22T09:25:50.942Z|00187|coverage|INFO|rconn_queued               0.0/sec    20.633/sec        2.4611/sec   total: 8860
2026-06-22T09:25:50.942Z|00188|coverage|INFO|poll_zero_timeout          0.0/sec     0.250/sec        0.0306/sec   total: 110
2026-06-22T09:25:50.942Z|00189|coverage|INFO|poll_create_node           1.6/sec    40.217/sec        3.7758/sec   total: 13601
2026-06-22T09:25:50.942Z|00190|coverage|INFO|txn_try_again              0.0/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T09:25:50.942Z|00191|coverage|INFO|txn_success                0.0/sec     0.333/sec        0.0242/sec   total: 87
2026-06-22T09:25:50.942Z|00192|coverage|INFO|txn_incomplete             0.0/sec     0.483/sec        0.0408/sec   total: 147
2026-06-22T09:25:50.942Z|00193|coverage|INFO|txn_unchanged              0.4/sec     3.783/sec        0.3444/sec   total: 1241
2026-06-22T09:25:50.942Z|00194|coverage|INFO|hmap_reserve               0.0/sec     4.633/sec        0.1089/sec   total: 392
2026-06-22T09:25:50.942Z|00195|coverage|INFO|hmap_shrink                0.2/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T09:25:50.942Z|00196|coverage|INFO|hmap_expand                0.8/sec    82.717/sec       11.6103/sec   total: 41801
2026-06-22T09:25:50.942Z|00197|coverage|INFO|hmap_pathological          0.0/sec     1.917/sec        0.2017/sec   total: 726
2026-06-22T09:25:50.942Z|00198|coverage|INFO|miniflow_malloc            0.0/sec   151.833/sec       23.7831/sec   total: 85619
2026-06-22T09:25:50.942Z|00199|coverage|INFO|flow_extract               0.0/sec     0.067/sec        0.0011/sec   total: 4
2026-06-22T09:25:50.942Z|00200|coverage|INFO|physical_run               0.0/sec     0.067/sec        0.0194/sec   total: 70
2026-06-22T09:25:50.942Z|00201|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.067/sec        0.0011/sec   total: 4
2026-06-22T09:25:50.942Z|00202|coverage|INFO|lflow_conj_free            0.0/sec     0.083/sec        0.0028/sec   total: 10
2026-06-22T09:25:50.942Z|00203|coverage|INFO|lflow_conj_alloc           0.0/sec     0.050/sec        0.0028/sec   total: 10
2026-06-22T09:25:50.942Z|00204|coverage|INFO|lflow_cache_trim           0.2/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T09:25:50.942Z|00205|coverage|INFO|lflow_cache_delete         0.0/sec     9.617/sec        0.9897/sec   total: 3563
2026-06-22T09:25:50.942Z|00206|coverage|INFO|lflow_cache_miss           0.0/sec    19.367/sec        2.0928/sec   total: 7534
2026-06-22T09:25:50.942Z|00207|coverage|INFO|lflow_cache_hit            0.0/sec    67.883/sec       10.6422/sec   total: 38312
2026-06-22T09:25:50.942Z|00208|coverage|INFO|lflow_cache_add            0.0/sec    10.050/sec        1.3508/sec   total: 4863
2026-06-22T09:25:50.942Z|00209|coverage|INFO|lflow_cache_free_matches   0.0/sec     8.200/sec        0.8681/sec   total: 3125
2026-06-22T09:25:50.942Z|00210|coverage|INFO|lflow_cache_free_expr      0.0/sec     1.417/sec        0.1217/sec   total: 438
2026-06-22T09:25:50.942Z|00211|coverage|INFO|lflow_cache_add_matches    0.0/sec     8.333/sec        0.9753/sec   total: 3511
2026-06-22T09:25:50.942Z|00212|coverage|INFO|lflow_cache_add_expr       0.0/sec     1.717/sec        0.3756/sec   total: 1352
2026-06-22T09:25:50.942Z|00213|coverage|INFO|consider_logical_flow      0.0/sec    12.900/sec        3.0428/sec   total: 10954
2026-06-22T09:25:50.942Z|00214|coverage|INFO|lflow_run                  0.0/sec     0.000/sec        0.0019/sec   total: 7
2026-06-22T09:25:50.942Z|00215|coverage|INFO|111 events never hit
2026-06-22T09:25:51.375Z|00216|poll_loop|INFO|Dropped 11 log messages in last 50 seconds (most recently, 48 seconds ago) due to excessive rate
2026-06-22T09:25:51.375Z|00217|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:51860<->10.4.3.11:6642) at lib/stream-ssl.c:830 (89% CPU usage)
2026-06-22T09:25:51.377Z|00218|reconnect|INFO|ssl:10.4.3.11:6642: connected
2026-06-22T09:25:51.382Z|00219|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:51860<->10.4.3.11:6642) at lib/stream-ssl.c:842 (89% CPU usage)
2026-06-22T09:25:51.382Z|00220|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:51860<->10.4.3.11:6642) at lib/stream-ssl.c:842 (89% CPU usage)
2026-06-22T09:25:51.383Z|00221|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:51860<->10.4.3.11:6642) at lib/stream-ssl.c:842 (89% CPU usage)
2026-06-22T09:25:51.383Z|00222|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:51860<->10.4.3.11:6642) at lib/stream-ssl.c:842 (89% CPU usage)
2026-06-22T09:25:51.437Z|00223|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:51860<->10.4.3.11:6642) at lib/stream-ssl.c:842 (89% CPU usage)
2026-06-22T09:25:51.439Z|00224|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (89% CPU usage)
2026-06-22T09:25:51.441Z|00225|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (89% CPU usage)
2026-06-22T09:25:51.455Z|00226|binding|INFO|Releasing lport bc050ca9-f697-4577-a7c9-bb23b72999f1 from this chassis (sb_readonly=0)
2026-06-22T09:25:51.455Z|00227|binding|INFO|Releasing lport 6d2373cc-14b8-4f9f-95af-b11a79d5c2c6 from this chassis (sb_readonly=0)
2026-06-22T09:25:51.455Z|00228|if_status|WARN|Trying to release unknown interface 6d2373cc-14b8-4f9f-95af-b11a79d5c2c6
2026-06-22T09:26:13.911Z|00229|reconnect|ERR|ssl:10.4.3.11:6642: no response to inactivity probe after 5.01 seconds, disconnecting
2026-06-22T09:26:13.912Z|00230|reconnect|INFO|ssl:10.4.3.11:6642: connection dropped
2026-06-22T09:26:13.912Z|00231|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T09:26:14.921Z|00232|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:26:15.931Z|00233|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:26:15.931Z|00234|reconnect|INFO|ssl:10.4.3.11:6642: waiting 2 seconds before reconnect
2026-06-22T09:26:17.938Z|00235|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:26:19.946Z|00236|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:26:19.946Z|00237|reconnect|INFO|ssl:10.4.3.11:6642: waiting 4 seconds before reconnect
2026-06-22T09:26:23.954Z|00238|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:26:27.966Z|00239|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:26:27.967Z|00240|reconnect|INFO|ssl:10.4.3.11:6642: continuing to reconnect in the background but suppressing further logging
2026-06-22T09:26:33.914Z|00241|memory_trim|INFO|Detected inactivity (last active 30013 ms ago): trimming memory
2026-06-22T09:26:52.283Z|00242|reconnect|INFO|ssl:10.4.3.11:6642: connected
2026-06-22T09:27:14.201Z|00049|timeval(ovn_pinctrl0)|WARN|Unreasonably long 1444ms poll interval (0ms user, 66ms system)
2026-06-22T09:27:14.235Z|00050|timeval(ovn_pinctrl0)|WARN|faults: 25 minor, 103 major
2026-06-22T09:27:14.283Z|00051|timeval(ovn_pinctrl0)|WARN|disk: 23560 reads, 0 writes
2026-06-22T09:27:14.396Z|00243|timeval|WARN|Unreasonably long 5956ms poll interval (11ms user, 4541ms system)
2026-06-22T09:27:14.409Z|00052|timeval(ovn_pinctrl0)|WARN|context switches: 107 voluntary, 12 involuntary
2026-06-22T09:27:14.471Z|00244|timeval|WARN|faults: 609 minor, 4869 major
2026-06-22T09:27:14.514Z|00245|timeval|WARN|disk: 1231400 reads, 0 writes
2026-06-22T09:27:14.565Z|00246|timeval|WARN|context switches: 4928 voluntary, 894 involuntary
2026-06-22T09:27:14.570Z|00053|coverage(ovn_pinctrl0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=3fedf1e1:
2026-06-22T09:27:14.571Z|00247|coverage|INFO|Skipping details of duplicate event coverage for hash=3fedf1e1
2026-06-22T09:27:14.585Z|00054|coverage(ovn_pinctrl0)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:27:14.603Z|00248|poll_loop|INFO|Dropped 20 log messages in last 83 seconds (most recently, 81 seconds ago) due to excessive rate
2026-06-22T09:27:14.609Z|00055|coverage(ovn_pinctrl0)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:27:14.609Z|00249|poll_loop|INFO|wakeup due to 1-ms timeout at lib/reconnect.c:677 (76% CPU usage)
2026-06-22T09:27:14.610Z|00056|coverage(ovn_pinctrl0)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:27:14.704Z|00057|coverage(ovn_pinctrl0)|INFO|vconn_sent                20.4/sec    10.683/sec        2.7594/sec   total: 9939
2026-06-22T09:27:14.833Z|00058|coverage(ovn_pinctrl0)|INFO|vconn_received             1.2/sec     0.500/sec        0.1294/sec   total: 471
2026-06-22T09:27:14.886Z|00059|coverage(ovn_pinctrl0)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:27:14.970Z|00060|coverage(ovn_pinctrl0)|INFO|util_xalloc              1469.8/sec  5396.983/sec      892.7581/sec   total: 3216365
2026-06-22T09:27:15.021Z|00061|coverage(ovn_pinctrl0)|INFO|long_poll_interval         0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:27:15.107Z|00062|coverage(ovn_pinctrl0)|INFO|stream_open                0.0/sec     0.083/sec        0.0044/sec   total: 16
2026-06-22T09:27:15.166Z|00063|coverage(ovn_pinctrl0)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:27:15.282Z|00064|coverage(ovn_pinctrl0)|INFO|seq_change                 4.6/sec     8.383/sec        1.4683/sec   total: 5299
2026-06-22T09:27:15.300Z|00065|coverage(ovn_pinctrl0)|INFO|rconn_sent                20.4/sec    10.683/sec        2.7583/sec   total: 9935
2026-06-22T09:27:15.341Z|00066|coverage(ovn_pinctrl0)|INFO|rconn_queued              20.4/sec    10.683/sec        2.7583/sec   total: 9935
2026-06-22T09:27:15.346Z|00067|coverage(ovn_pinctrl0)|INFO|poll_zero_timeout          0.2/sec     0.200/sec        0.0356/sec   total: 130
2026-06-22T09:27:15.384Z|00068|coverage(ovn_pinctrl0)|INFO|poll_create_node          14.0/sec    22.633/sec        4.2869/sec   total: 15488
2026-06-22T09:27:15.431Z|00069|coverage(ovn_pinctrl0)|INFO|txn_try_again              0.0/sec     0.017/sec        0.0006/sec   total: 2
2026-06-22T09:27:15.506Z|00070|coverage(ovn_pinctrl0)|INFO|txn_success                0.0/sec     0.067/sec        0.0264/sec   total: 95
2026-06-22T09:27:15.558Z|00071|coverage(ovn_pinctrl0)|INFO|txn_incomplete             0.0/sec     0.083/sec        0.0439/sec   total: 158
2026-06-22T09:27:15.656Z|00072|coverage(ovn_pinctrl0)|INFO|txn_unchanged              1.6/sec     1.533/sec        0.3819/sec   total: 1383
2026-06-22T09:27:15.700Z|00073|coverage(ovn_pinctrl0)|INFO|hmap_reserve               0.0/sec     0.000/sec        0.1089/sec   total: 392
2026-06-22T09:27:15.765Z|00074|coverage(ovn_pinctrl0)|INFO|hmap_shrink                0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:27:15.848Z|00075|coverage(ovn_pinctrl0)|INFO|hmap_expand               22.4/sec    98.017/sec       14.6472/sec   total: 52792
2026-06-22T09:27:15.870Z|00076|coverage(ovn_pinctrl0)|INFO|hmap_pathological          1.2/sec     0.767/sec        0.2192/sec   total: 789
2026-06-22T09:27:15.948Z|00077|coverage(ovn_pinctrl0)|INFO|miniflow_malloc           20.6/sec   127.200/sec       27.1053/sec   total: 97579
2026-06-22T09:27:16.008Z|00078|coverage(ovn_pinctrl0)|INFO|flow_extract               0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:27:16.048Z|00079|coverage(ovn_pinctrl0)|INFO|physical_run               0.0/sec     0.067/sec        0.0208/sec   total: 75
2026-06-22T09:27:16.111Z|00080|coverage(ovn_pinctrl0)|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:27:16.116Z|00081|coverage(ovn_pinctrl0)|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:27:16.221Z|00082|coverage(ovn_pinctrl0)|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:27:16.403Z|00083|coverage(ovn_pinctrl0)|INFO|lflow_cache_trim           0.0/sec     0.017/sec        0.0011/sec   total: 4
2026-06-22T09:27:16.468Z|00084|coverage(ovn_pinctrl0)|INFO|lflow_cache_delete        12.8/sec     3.833/sec        1.0564/sec   total: 3803
2026-06-22T09:27:16.498Z|00085|coverage(ovn_pinctrl0)|INFO|lflow_cache_miss          18.6/sec     8.317/sec        2.2631/sec   total: 8147
2026-06-22T09:27:16.552Z|00086|coverage(ovn_pinctrl0)|INFO|lflow_cache_hit           28.2/sec    66.033/sec       12.5067/sec   total: 45024
2026-06-22T09:27:16.625Z|00087|coverage(ovn_pinctrl0)|INFO|lflow_cache_add            1.6/sec     2.883/sec        1.3994/sec   total: 5038
2026-06-22T09:27:16.658Z|00088|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_matches  11.0/sec     3.083/sec        0.9206/sec   total: 3314
2026-06-22T09:27:16.688Z|00089|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_expr      1.8/sec     0.750/sec        0.1358/sec   total: 489
2026-06-22T09:27:16.712Z|00090|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_matches    1.4/sec     2.267/sec        1.0133/sec   total: 3648
2026-06-22T09:27:16.757Z|00091|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_expr       0.2/sec     0.617/sec        0.3861/sec   total: 1390
2026-06-22T09:27:16.824Z|00092|coverage(ovn_pinctrl0)|INFO|consider_logical_flow      1.6/sec    24.517/sec        3.8164/sec   total: 13739
2026-06-22T09:27:16.857Z|00093|coverage(ovn_pinctrl0)|INFO|lflow_run                  0.0/sec     0.017/sec        0.0025/sec   total: 9
2026-06-22T09:27:16.957Z|00094|coverage(ovn_pinctrl0)|INFO|111 events never hit
2026-06-22T09:27:20.167Z|00250|timeval|WARN|Unreasonably long 5585ms poll interval (11ms user, 4041ms system)
2026-06-22T09:27:20.196Z|00251|timeval|WARN|faults: 695 minor, 4442 major
2026-06-22T09:27:20.222Z|00252|timeval|WARN|disk: 1112056 reads, 0 writes
2026-06-22T09:27:20.249Z|00253|timeval|WARN|context switches: 4531 voluntary, 1090 involuntary
2026-06-22T09:27:20.273Z|00254|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:42770<->10.4.3.11:6642) at lib/stream-ssl.c:842 (73% CPU usage)
2026-06-22T09:27:52.053Z|00255|memory_trim|INFO|Detected inactivity (last active 53343 ms ago): trimming memory
2026-06-22T09:27:52.053Z|00256|timeval|WARN|Unreasonably long 31784ms poll interval (7ms user, 28109ms system)
2026-06-22T09:27:52.053Z|00257|timeval|WARN|faults: 655 minor, 6397 major
2026-06-22T09:27:52.053Z|00258|timeval|WARN|disk: 1662184 reads, 8 writes
2026-06-22T09:27:52.053Z|00259|timeval|WARN|context switches: 6507 voluntary, 23976 involuntary
2026-06-22T09:27:52.053Z|00260|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (88% CPU usage)
2026-06-22T09:27:52.055Z|00261|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (88% CPU usage)
2026-06-22T09:27:52.055Z|00262|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:42770<->10.4.3.11:6642) at lib/stream-ssl.c:842 (88% CPU usage)
2026-06-22T09:27:52.055Z|00263|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (88% CPU usage)
2026-06-22T09:27:52.504Z|00264|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:42770<->10.4.3.11:6642) at lib/stream-ssl.c:842 (88% CPU usage)
2026-06-22T09:27:52.509Z|00265|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (88% CPU usage)
2026-06-22T09:27:52.509Z|00266|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (88% CPU usage)
2026-06-22T09:27:54.483Z|00267|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:42770<->10.4.3.11:6642) at lib/stream-ssl.c:842 (88% CPU usage)
2026-06-22T09:27:54.622Z|00268|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:42770<->10.4.3.11:6642) at lib/stream-ssl.c:842 (88% CPU usage)
2026-06-22T09:27:54.819Z|00269|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:42770<->10.4.3.11:6642) at lib/stream-ssl.c:842 (88% CPU usage)
2026-06-22T09:28:34.563Z|00270|memory_trim|INFO|Detected inactivity (last active 30196 ms ago): trimming memory
2026-06-22T09:28:34.878Z|00271|reconnect|ERR|ssl:10.4.3.11:6642: no response to inactivity probe after 5.17 seconds, disconnecting
2026-06-22T09:28:34.879Z|00272|reconnect|INFO|ssl:10.4.3.11:6642: connection dropped
2026-06-22T09:28:34.901Z|00273|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T09:28:35.908Z|00274|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:28:36.926Z|00275|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:28:36.930Z|00276|reconnect|INFO|ssl:10.4.3.11:6642: waiting 2 seconds before reconnect
2026-06-22T09:28:38.941Z|00277|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:28:40.973Z|00278|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:28:40.974Z|00279|reconnect|INFO|ssl:10.4.3.11:6642: waiting 4 seconds before reconnect
2026-06-22T09:28:44.978Z|00280|reconnect|INFO|ssl:10.4.3.11:6642: connecting...
2026-06-22T09:28:48.999Z|00281|reconnect|INFO|ssl:10.4.3.11:6642: connection attempt timed out
2026-06-22T09:28:49.003Z|00282|reconnect|INFO|ssl:10.4.3.11:6642: continuing to reconnect in the background but suppressing further logging
2026-06-22T09:28:57.018Z|00283|reconnect|INFO|ssl:10.4.3.11:6642: connected
2026-06-22T09:29:05.002Z|00004|timeval(ovn_statctrl3)|WARN|Unreasonably long 1718ms poll interval (0ms user, 401ms system)
2026-06-22T09:29:05.003Z|00005|timeval(ovn_statctrl3)|WARN|faults: 7 minor, 35 major
2026-06-22T09:29:05.003Z|00006|timeval(ovn_statctrl3)|WARN|disk: 8048 reads, 0 writes
2026-06-22T09:29:05.003Z|00007|timeval(ovn_statctrl3)|WARN|context switches: 36 voluntary, 417 involuntary
2026-06-22T09:29:05.003Z|00008|coverage(ovn_statctrl3)|INFO|Dropped 2 log messages in last 105 seconds (most recently, 73 seconds ago) due to excessive rate
2026-06-22T09:29:05.003Z|00009|coverage(ovn_statctrl3)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=3ae89cfb:
2026-06-22T09:29:05.003Z|00010|coverage(ovn_statctrl3)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:29:05.003Z|00011|coverage(ovn_statctrl3)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:29:05.003Z|00012|coverage(ovn_statctrl3)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:29:05.003Z|00013|coverage(ovn_statctrl3)|INFO|vconn_sent                 0.6/sec     5.133/sec        2.8464/sec   total: 10247
2026-06-22T09:29:05.003Z|00014|coverage(ovn_statctrl3)|INFO|vconn_received             0.6/sec     0.383/sec        0.1372/sec   total: 494
2026-06-22T09:29:05.003Z|00015|coverage(ovn_statctrl3)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:29:05.003Z|00016|coverage(ovn_statctrl3)|INFO|util_xalloc               32.8/sec  1577.367/sec      919.9131/sec   total: 3311959
2026-06-22T09:29:05.003Z|00017|coverage(ovn_statctrl3)|INFO|long_poll_interval         0.0/sec     0.050/sec        0.0019/sec   total: 7
2026-06-22T09:29:05.003Z|00018|coverage(ovn_statctrl3)|INFO|stream_open                0.2/sec     0.067/sec        0.0056/sec   total: 20
2026-06-22T09:29:05.003Z|00019|coverage(ovn_statctrl3)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:29:05.003Z|00020|coverage(ovn_statctrl3)|INFO|seq_change                 0.8/sec     5.983/sec        1.5758/sec   total: 5741
2026-06-22T09:29:05.003Z|00021|coverage(ovn_statctrl3)|INFO|rconn_sent                 0.6/sec     5.133/sec        2.8453/sec   total: 10243
2026-06-22T09:29:05.003Z|00022|coverage(ovn_statctrl3)|INFO|rconn_queued               0.6/sec     5.133/sec        2.8453/sec   total: 10243
2026-06-22T09:29:05.003Z|00023|coverage(ovn_statctrl3)|INFO|poll_zero_timeout          0.0/sec     0.283/sec        0.0408/sec   total: 147
2026-06-22T09:29:05.003Z|00024|coverage(ovn_statctrl3)|INFO|poll_create_node           4.0/sec    17.733/sec        4.6092/sec   total: 16729
2026-06-22T09:29:05.003Z|00025|coverage(ovn_statctrl3)|INFO|txn_try_again              0.0/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T09:29:05.003Z|00026|coverage(ovn_statctrl3)|INFO|txn_success                0.0/sec     0.033/sec        0.0269/sec   total: 97
2026-06-22T09:29:05.003Z|00027|coverage(ovn_statctrl3)|INFO|txn_incomplete             0.0/sec     0.050/sec        0.0447/sec   total: 161
2026-06-22T09:29:05.003Z|00028|coverage(ovn_statctrl3)|INFO|txn_unchanged              0.2/sec     1.750/sec        0.4139/sec   total: 1490
2026-06-22T09:29:05.003Z|00029|coverage(ovn_statctrl3)|INFO|hmap_reserve               0.0/sec     0.000/sec        0.1089/sec   total: 392
2026-06-22T09:29:05.003Z|00030|coverage(ovn_statctrl3)|INFO|hmap_shrink                0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:29:05.003Z|00031|coverage(ovn_statctrl3)|INFO|hmap_expand                0.8/sec    24.400/sec       15.0756/sec   total: 54272
2026-06-22T09:29:05.003Z|00032|coverage(ovn_statctrl3)|INFO|hmap_pathological          0.0/sec     0.450/sec        0.2267/sec   total: 816
2026-06-22T09:29:05.003Z|00033|coverage(ovn_statctrl3)|INFO|miniflow_malloc            0.0/sec    54.850/sec       28.0194/sec   total: 100870
2026-06-22T09:29:05.003Z|00034|coverage(ovn_statctrl3)|INFO|flow_extract               0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:29:05.003Z|00035|coverage(ovn_statctrl3)|INFO|physical_run               0.0/sec     0.000/sec        0.0208/sec   total: 75
2026-06-22T09:29:05.003Z|00036|coverage(ovn_statctrl3)|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:29:05.003Z|00037|coverage(ovn_statctrl3)|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:29:05.003Z|00038|coverage(ovn_statctrl3)|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:29:05.003Z|00039|coverage(ovn_statctrl3)|INFO|lflow_cache_trim           0.0/sec     0.033/sec        0.0017/sec   total: 6
2026-06-22T09:29:05.003Z|00040|coverage(ovn_statctrl3)|INFO|lflow_cache_delete         0.0/sec     5.217/sec        1.1433/sec   total: 4116
2026-06-22T09:29:05.003Z|00041|coverage(ovn_statctrl3)|INFO|lflow_cache_miss           0.0/sec     7.833/sec        2.3936/sec   total: 8617
2026-06-22T09:29:05.003Z|00042|coverage(ovn_statctrl3)|INFO|lflow_cache_hit            0.0/sec    30.900/sec       13.0217/sec   total: 46878
2026-06-22T09:29:05.003Z|00043|coverage(ovn_statctrl3)|INFO|lflow_cache_add            0.0/sec     2.783/sec        1.4458/sec   total: 5205
2026-06-22T09:29:05.003Z|00044|coverage(ovn_statctrl3)|INFO|lflow_cache_free_matches   0.0/sec     2.783/sec        0.9669/sec   total: 3481
2026-06-22T09:29:05.003Z|00045|coverage(ovn_statctrl3)|INFO|lflow_cache_free_expr      0.0/sec     2.433/sec        0.1764/sec   total: 635
2026-06-22T09:29:05.003Z|00046|coverage(ovn_statctrl3)|INFO|lflow_cache_add_matches    0.0/sec     2.567/sec        1.0561/sec   total: 3802
2026-06-22T09:29:05.003Z|00047|coverage(ovn_statctrl3)|INFO|lflow_cache_add_expr       0.0/sec     0.217/sec        0.3897/sec   total: 1403
2026-06-22T09:29:05.003Z|00048|coverage(ovn_statctrl3)|INFO|consider_logical_flow      0.0/sec     2.950/sec        3.8656/sec   total: 13916
2026-06-22T09:29:05.003Z|00049|coverage(ovn_statctrl3)|INFO|lflow_run                  0.0/sec     0.000/sec        0.0025/sec   total: 9
2026-06-22T09:29:05.003Z|00050|coverage(ovn_statctrl3)|INFO|111 events never hit
2026-06-22T09:29:24.088Z|00095|timeval(ovn_pinctrl0)|WARN|Unreasonably long 9547ms poll interval (0ms user, 3160ms system)
2026-06-22T09:29:24.089Z|00096|timeval(ovn_pinctrl0)|WARN|faults: 17 minor, 220 major
2026-06-22T09:29:24.089Z|00097|timeval(ovn_pinctrl0)|WARN|disk: 53880 reads, 0 writes
2026-06-22T09:29:24.089Z|00098|timeval(ovn_pinctrl0)|WARN|context switches: 222 voluntary, 3531 involuntary
2026-06-22T09:29:24.089Z|00099|coverage(ovn_pinctrl0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=20cc227b:
2026-06-22T09:29:24.089Z|00100|coverage(ovn_pinctrl0)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:29:24.089Z|00101|coverage(ovn_pinctrl0)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:29:24.089Z|00102|coverage(ovn_pinctrl0)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:29:24.089Z|00103|coverage(ovn_pinctrl0)|INFO|vconn_sent                77.2/sec    21.100/sec        3.1675/sec   total: 11403
2026-06-22T09:29:24.089Z|00104|coverage(ovn_pinctrl0)|INFO|vconn_received             0.6/sec     0.350/sec        0.1392/sec   total: 501
2026-06-22T09:29:24.089Z|00105|coverage(ovn_pinctrl0)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:29:24.089Z|00106|coverage(ovn_pinctrl0)|INFO|util_xalloc              24200.4/sec  6580.317/sec     1020.7469/sec   total: 3674689
2026-06-22T09:29:24.089Z|00107|coverage(ovn_pinctrl0)|INFO|long_poll_interval         0.0/sec     0.000/sec        0.0019/sec   total: 7
2026-06-22T09:29:24.089Z|00108|coverage(ovn_pinctrl0)|INFO|stream_open                0.0/sec     0.067/sec        0.0056/sec   total: 20
2026-06-22T09:29:24.089Z|00109|coverage(ovn_pinctrl0)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:29:24.089Z|00110|coverage(ovn_pinctrl0)|INFO|seq_change                10.0/sec     6.367/sec        1.6164/sec   total: 5819
2026-06-22T09:29:24.089Z|00111|coverage(ovn_pinctrl0)|INFO|rconn_sent                77.2/sec    21.100/sec        3.1664/sec   total: 11399
2026-06-22T09:29:24.089Z|00112|coverage(ovn_pinctrl0)|INFO|rconn_queued              77.2/sec    21.100/sec        3.1664/sec   total: 11399
2026-06-22T09:29:24.089Z|00113|coverage(ovn_pinctrl0)|INFO|poll_zero_timeout          0.8/sec     0.333/sec        0.0431/sec   total: 155
2026-06-22T09:29:24.089Z|00114|coverage(ovn_pinctrl0)|INFO|poll_create_node          30.2/sec    19.283/sec        4.7350/sec   total: 17046
2026-06-22T09:29:24.089Z|00115|coverage(ovn_pinctrl0)|INFO|txn_try_again              0.0/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T09:29:24.089Z|00116|coverage(ovn_pinctrl0)|INFO|txn_success                0.2/sec     0.050/sec        0.0278/sec   total: 100
2026-06-22T09:29:24.089Z|00117|coverage(ovn_pinctrl0)|INFO|txn_incomplete             0.4/sec     0.067/sec        0.0458/sec   total: 165
2026-06-22T09:29:24.089Z|00118|coverage(ovn_pinctrl0)|INFO|txn_unchanged              3.0/sec     1.850/sec        0.4258/sec   total: 1533
2026-06-22T09:29:24.089Z|00119|coverage(ovn_pinctrl0)|INFO|hmap_reserve               0.0/sec     0.000/sec        0.1089/sec   total: 392
2026-06-22T09:29:24.089Z|00120|coverage(ovn_pinctrl0)|INFO|hmap_shrink                0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:29:24.089Z|00121|coverage(ovn_pinctrl0)|INFO|hmap_expand              481.8/sec   131.583/sec       17.0831/sec   total: 61499
2026-06-22T09:29:24.089Z|00122|coverage(ovn_pinctrl0)|INFO|hmap_pathological          1.6/sec     0.450/sec        0.2333/sec   total: 840
2026-06-22T09:29:24.089Z|00123|coverage(ovn_pinctrl0)|INFO|miniflow_malloc          573.0/sec   144.350/sec       30.4064/sec   total: 109463
2026-06-22T09:29:24.089Z|00124|coverage(ovn_pinctrl0)|INFO|flow_extract               0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:29:24.089Z|00125|coverage(ovn_pinctrl0)|INFO|physical_run               0.2/sec     0.050/sec        0.0217/sec   total: 78
2026-06-22T09:29:24.089Z|00126|coverage(ovn_pinctrl0)|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:29:24.089Z|00127|coverage(ovn_pinctrl0)|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:29:24.089Z|00128|coverage(ovn_pinctrl0)|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:29:24.089Z|00129|coverage(ovn_pinctrl0)|INFO|lflow_cache_trim           0.0/sec     0.017/sec        0.0017/sec   total: 6
2026-06-22T09:29:24.089Z|00130|coverage(ovn_pinctrl0)|INFO|lflow_cache_delete        10.0/sec     3.483/sec        1.1844/sec   total: 4264
2026-06-22T09:29:24.089Z|00131|coverage(ovn_pinctrl0)|INFO|lflow_cache_miss          15.4/sec     5.033/sec        2.4567/sec   total: 8844
2026-06-22T09:29:24.089Z|00132|coverage(ovn_pinctrl0)|INFO|lflow_cache_hit          374.2/sec    94.833/sec       14.5797/sec   total: 52487
2026-06-22T09:29:24.089Z|00133|coverage(ovn_pinctrl0)|INFO|lflow_cache_add            0.8/sec     0.367/sec        1.4481/sec   total: 5213
2026-06-22T09:29:24.089Z|00134|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_matches   3.0/sec     0.867/sec        0.9783/sec   total: 3522
2026-06-22T09:29:24.089Z|00135|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_expr      7.4/sec     2.617/sec        0.2061/sec   total: 742
2026-06-22T09:29:24.089Z|00136|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_matches    0.2/sec     0.250/sec        1.0564/sec   total: 3803
2026-06-22T09:29:24.089Z|00137|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_expr       0.6/sec     0.117/sec        0.3917/sec   total: 1410
2026-06-22T09:29:24.089Z|00138|coverage(ovn_pinctrl0)|INFO|consider_logical_flow    177.4/sec    44.550/sec        4.6042/sec   total: 16575
2026-06-22T09:29:24.089Z|00139|coverage(ovn_pinctrl0)|INFO|lflow_run                  0.2/sec     0.050/sec        0.0033/sec   total: 12
2026-06-22T09:29:24.089Z|00140|coverage(ovn_pinctrl0)|INFO|111 events never hit
2026-06-22T09:29:24.098Z|00284|timeval|WARN|Unreasonably long 14413ms poll interval (1ms user, 8628ms system)
2026-06-22T09:29:24.098Z|00285|timeval|WARN|faults: 59 minor, 745 major
2026-06-22T09:29:24.098Z|00286|timeval|WARN|disk: 192304 reads, 0 writes
2026-06-22T09:29:24.098Z|00287|timeval|WARN|context switches: 752 voluntary, 8796 involuntary
2026-06-22T09:29:24.098Z|00288|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (60% CPU usage)
2026-06-22T09:29:24.101Z|00289|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (60% CPU usage)
2026-06-22T09:29:24.109Z|00290|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (60% CPU usage)
2026-06-22T09:29:26.629Z|00291|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (60% CPU usage)
2026-06-22T09:29:26.631Z|00292|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (60% CPU usage)
2026-06-22T09:29:27.049Z|00293|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (60% CPU usage)
2026-06-22T09:29:39.945Z|00294|inc_proc_eng|INFO|node: logical_flow_output, handler for input SB_logical_flow took 12593ms
2026-06-22T09:29:39.950Z|00295|timeval|WARN|Unreasonably long 12905ms poll interval (10ms user, 11669ms system)
2026-06-22T09:29:39.950Z|00296|timeval|WARN|faults: 208 minor, 2001 major
2026-06-22T09:29:39.950Z|00297|timeval|WARN|disk: 513848 reads, 24 writes
2026-06-22T09:29:39.950Z|00298|timeval|WARN|context switches: 2030 voluntary, 10352 involuntary
2026-06-22T09:29:39.951Z|00299|poll_loop|INFO|wakeup due to 1-ms timeout at lib/reconnect.c:677 (73% CPU usage)
2026-06-22T09:29:39.952Z|00300|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (73% CPU usage)
2026-06-22T09:29:39.954Z|00301|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (73% CPU usage)
2026-06-22T09:29:39.954Z|00302|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (73% CPU usage)
2026-06-22T09:30:03.586Z|00051|timeval(ovn_statctrl3)|WARN|Unreasonably long 2085ms poll interval (0ms user, 783ms system)
2026-06-22T09:30:03.586Z|00052|timeval(ovn_statctrl3)|WARN|faults: 17 minor, 50 major
2026-06-22T09:30:03.586Z|00053|timeval(ovn_statctrl3)|WARN|disk: 12056 reads, 0 writes
2026-06-22T09:30:03.586Z|00054|timeval(ovn_statctrl3)|WARN|context switches: 51 voluntary, 876 involuntary
2026-06-22T09:30:03.586Z|00055|coverage(ovn_statctrl3)|INFO|Dropped 2 log messages in last 40 seconds (most recently, 24 seconds ago) due to excessive rate
2026-06-22T09:30:03.586Z|00056|coverage(ovn_statctrl3)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=d1522b26:
2026-06-22T09:30:03.586Z|00057|coverage(ovn_statctrl3)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:30:03.586Z|00058|coverage(ovn_statctrl3)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:30:03.586Z|00059|coverage(ovn_statctrl3)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:30:03.586Z|00060|coverage(ovn_statctrl3)|INFO|vconn_sent                 4.8/sec    21.117/sec        3.1975/sec   total: 11511
2026-06-22T09:30:03.586Z|00061|coverage(ovn_statctrl3)|INFO|vconn_received             1.8/sec     0.483/sec        0.1444/sec   total: 520
2026-06-22T09:30:03.586Z|00062|coverage(ovn_statctrl3)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:30:03.586Z|00063|coverage(ovn_statctrl3)|INFO|util_xalloc              1145.0/sec  6905.267/sec     1034.8367/sec   total: 3725464
2026-06-22T09:30:03.586Z|00064|coverage(ovn_statctrl3)|INFO|long_poll_interval         0.2/sec     0.050/sec        0.0028/sec   total: 10
2026-06-22T09:30:03.586Z|00065|coverage(ovn_statctrl3)|INFO|stream_open                0.0/sec     0.033/sec        0.0056/sec   total: 20
2026-06-22T09:30:03.586Z|00066|coverage(ovn_statctrl3)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:30:03.586Z|00067|coverage(ovn_statctrl3)|INFO|seq_change                 1.8/sec     5.517/sec        1.6633/sec   total: 6001
2026-06-22T09:30:03.586Z|00068|coverage(ovn_statctrl3)|INFO|rconn_sent                 4.8/sec    21.117/sec        3.1964/sec   total: 11507
2026-06-22T09:30:03.586Z|00069|coverage(ovn_statctrl3)|INFO|rconn_queued               4.8/sec    21.117/sec        3.1964/sec   total: 11507
2026-06-22T09:30:03.586Z|00070|coverage(ovn_statctrl3)|INFO|poll_zero_timeout          0.2/sec     0.250/sec        0.0450/sec   total: 162
2026-06-22T09:30:03.586Z|00071|coverage(ovn_statctrl3)|INFO|poll_create_node          12.8/sec    17.200/sec        4.8792/sec   total: 17591
2026-06-22T09:30:03.586Z|00072|coverage(ovn_statctrl3)|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:30:03.586Z|00073|coverage(ovn_statctrl3)|INFO|txn_success                0.0/sec     0.067/sec        0.0281/sec   total: 101
2026-06-22T09:30:03.586Z|00074|coverage(ovn_statctrl3)|INFO|txn_incomplete             0.0/sec     0.083/sec        0.0461/sec   total: 166
2026-06-22T09:30:03.586Z|00075|coverage(ovn_statctrl3)|INFO|txn_unchanged              2.8/sec     1.767/sec        0.4422/sec   total: 1592
2026-06-22T09:30:03.586Z|00076|coverage(ovn_statctrl3)|INFO|hmap_reserve               0.0/sec     0.000/sec        0.1089/sec   total: 392
2026-06-22T09:30:03.586Z|00077|coverage(ovn_statctrl3)|INFO|hmap_shrink                0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:30:03.586Z|00078|coverage(ovn_statctrl3)|INFO|hmap_expand               33.2/sec   134.817/sec       17.3181/sec   total: 62345
2026-06-22T09:30:03.587Z|00079|coverage(ovn_statctrl3)|INFO|hmap_pathological          0.0/sec     0.783/sec        0.2397/sec   total: 863
2026-06-22T09:30:03.587Z|00080|coverage(ovn_statctrl3)|INFO|miniflow_malloc            3.6/sec   176.150/sec       30.9553/sec   total: 111439
2026-06-22T09:30:03.587Z|00081|coverage(ovn_statctrl3)|INFO|flow_extract               0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:30:03.587Z|00082|coverage(ovn_statctrl3)|INFO|physical_run               0.0/sec     0.050/sec        0.0217/sec   total: 78
2026-06-22T09:30:03.587Z|00083|coverage(ovn_statctrl3)|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:30:03.587Z|00084|coverage(ovn_statctrl3)|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:30:03.587Z|00085|coverage(ovn_statctrl3)|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:30:03.587Z|00086|coverage(ovn_statctrl3)|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0017/sec   total: 6
2026-06-22T09:30:03.587Z|00087|coverage(ovn_statctrl3)|INFO|lflow_cache_delete         1.8/sec     5.433/sec        1.2339/sec   total: 4442
2026-06-22T09:30:03.587Z|00088|coverage(ovn_statctrl3)|INFO|lflow_cache_miss           3.6/sec     8.550/sec        2.5361/sec   total: 9130
2026-06-22T09:30:03.587Z|00089|coverage(ovn_statctrl3)|INFO|lflow_cache_hit            1.8/sec   112.050/sec       14.8892/sec   total: 53601
2026-06-22T09:30:03.587Z|00090|coverage(ovn_statctrl3)|INFO|lflow_cache_add            1.8/sec     2.667/sec        1.4903/sec   total: 5365
2026-06-22T09:30:03.587Z|00091|coverage(ovn_statctrl3)|INFO|lflow_cache_free_matches   0.0/sec     2.983/sec        1.0167/sec   total: 3660
2026-06-22T09:30:03.587Z|00092|coverage(ovn_statctrl3)|INFO|lflow_cache_free_expr      1.8/sec     2.450/sec        0.2172/sec   total: 782
2026-06-22T09:30:03.587Z|00093|coverage(ovn_statctrl3)|INFO|lflow_cache_add_matches    0.0/sec     2.217/sec        1.0931/sec   total: 3935
2026-06-22T09:30:03.587Z|00094|coverage(ovn_statctrl3)|INFO|lflow_cache_add_expr       1.8/sec     0.450/sec        0.3972/sec   total: 1430
2026-06-22T09:30:03.587Z|00095|coverage(ovn_statctrl3)|INFO|consider_logical_flow      1.8/sec    47.017/sec        4.6492/sec   total: 16737
2026-06-22T09:30:03.587Z|00096|coverage(ovn_statctrl3)|INFO|lflow_run                  0.0/sec     0.050/sec        0.0033/sec   total: 12
2026-06-22T09:30:03.587Z|00097|coverage(ovn_statctrl3)|INFO|111 events never hit
2026-06-22T09:30:03.587Z|00303|timeval|WARN|Unreasonably long 3225ms poll interval (2ms user, 2295ms system)
2026-06-22T09:30:03.587Z|00304|timeval|WARN|faults: 23 minor, 155 major
2026-06-22T09:30:03.587Z|00305|timeval|WARN|disk: 39896 reads, 0 writes
2026-06-22T09:30:03.587Z|00306|timeval|WARN|context switches: 155 voluntary, 2321 involuntary
2026-06-22T09:30:54.921Z|00307|memory_trim|INFO|Detected inactivity (last active 31118 ms ago): trimming memory
2026-06-22T09:30:54.921Z|00308|timeval|WARN|Unreasonably long 30879ms poll interval (6ms user, 28301ms system)
2026-06-22T09:30:54.921Z|00309|timeval|WARN|faults: 421 minor, 5052 major
2026-06-22T09:30:54.921Z|00310|timeval|WARN|disk: 1328440 reads, 8 writes
2026-06-22T09:30:54.921Z|00311|timeval|WARN|context switches: 5156 voluntary, 24274 involuntary
2026-06-22T09:30:54.921Z|00312|poll_loop|INFO|wakeup due to [POLLIN] on fd 26 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (87% CPU usage)
2026-06-22T09:30:54.922Z|00313|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (87% CPU usage)
2026-06-22T09:30:54.923Z|00314|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (87% CPU usage)
2026-06-22T09:30:54.923Z|00315|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (87% CPU usage)
2026-06-22T09:31:01.242Z|00316|inc_proc_eng|INFO|node: logical_flow_output, recompute (failed handler for input port_groups) took 1107ms
2026-06-22T09:31:01.245Z|00317|timeval|WARN|Unreasonably long 1166ms poll interval (22ms user, 944ms system)
2026-06-22T09:31:01.245Z|00318|timeval|WARN|faults: 182 minor, 654 major
2026-06-22T09:31:01.245Z|00319|timeval|WARN|disk: 141816 reads, 8 writes
2026-06-22T09:31:01.245Z|00320|timeval|WARN|context switches: 655 voluntary, 851 involuntary
2026-06-22T09:31:01.245Z|00321|coverage|INFO|Dropped 2 log messages in last 57 seconds (most recently, 6 seconds ago) due to excessive rate
2026-06-22T09:31:01.245Z|00322|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=d9d371d1:
2026-06-22T09:31:01.245Z|00323|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:31:01.245Z|00324|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:31:01.245Z|00325|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:31:01.245Z|00326|coverage|INFO|vconn_sent                55.8/sec    12.717/sec        3.3969/sec   total: 12353
2026-06-22T09:31:01.245Z|00327|coverage|INFO|vconn_received             1.6/sec     0.533/sec        0.1500/sec   total: 543
2026-06-22T09:31:01.245Z|00328|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:31:01.245Z|00329|coverage|INFO|util_xalloc              1586.2/sec  2126.167/sec     1067.2292/sec   total: 3903125
2026-06-22T09:31:01.245Z|00330|coverage|INFO|long_poll_interval         0.4/sec     0.083/sec        0.0039/sec   total: 14
2026-06-22T09:31:01.245Z|00331|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0056/sec   total: 20
2026-06-22T09:31:01.245Z|00332|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:31:01.245Z|00333|coverage|INFO|seq_change                 6.2/sec     4.383/sec        1.7189/sec   total: 6201
2026-06-22T09:31:01.245Z|00334|coverage|INFO|rconn_sent                55.8/sec    12.717/sec        3.3958/sec   total: 12349
2026-06-22T09:31:01.245Z|00335|coverage|INFO|rconn_queued               8.2/sec    12.717/sec        3.3958/sec   total: 12349
2026-06-22T09:31:01.245Z|00336|coverage|INFO|poll_zero_timeout          0.6/sec     0.200/sec        0.0475/sec   total: 172
2026-06-22T09:31:01.245Z|00337|coverage|INFO|poll_create_node          23.2/sec    13.650/sec        5.0492/sec   total: 18215
2026-06-22T09:31:01.245Z|00338|coverage|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:31:01.245Z|00339|coverage|INFO|txn_success                0.2/sec     0.050/sec        0.0289/sec   total: 104
2026-06-22T09:31:01.245Z|00340|coverage|INFO|txn_incomplete             0.2/sec     0.067/sec        0.0472/sec   total: 170
2026-06-22T09:31:01.245Z|00341|coverage|INFO|txn_unchanged              3.0/sec     1.533/sec        0.4606/sec   total: 1662
2026-06-22T09:31:01.245Z|00342|coverage|INFO|hmap_reserve               0.0/sec     0.000/sec        0.1089/sec   total: 392
2026-06-22T09:31:01.245Z|00343|coverage|INFO|hmap_shrink                0.2/sec     0.017/sec        0.0006/sec   total: 2
2026-06-22T09:31:01.245Z|00344|coverage|INFO|hmap_expand               33.4/sec    42.317/sec       17.9350/sec   total: 65785
2026-06-22T09:31:01.245Z|00345|coverage|INFO|hmap_pathological          0.0/sec     0.333/sec        0.2453/sec   total: 888
2026-06-22T09:31:01.245Z|00346|coverage|INFO|miniflow_malloc           27.0/sec    56.917/sec       31.8939/sec   total: 116719
2026-06-22T09:31:01.245Z|00347|coverage|INFO|flow_extract               0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:31:01.245Z|00348|coverage|INFO|physical_run               0.0/sec     0.017/sec        0.0219/sec   total: 80
2026-06-22T09:31:01.245Z|00349|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:31:01.245Z|00350|coverage|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:31:01.245Z|00351|coverage|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:31:01.245Z|00352|coverage|INFO|lflow_cache_trim           0.2/sec     0.017/sec        0.0019/sec   total: 7
2026-06-22T09:31:01.245Z|00353|coverage|INFO|lflow_cache_delete         5.2/sec     3.950/sec        1.2947/sec   total: 4687
2026-06-22T09:31:01.245Z|00354|coverage|INFO|lflow_cache_miss           6.2/sec     5.333/sec        2.6150/sec   total: 9446
2026-06-22T09:31:01.245Z|00355|coverage|INFO|lflow_cache_hit            5.2/sec    34.067/sec       15.4519/sec   total: 56864
2026-06-22T09:31:01.245Z|00356|coverage|INFO|lflow_cache_add            0.2/sec     1.667/sec        1.5131/sec   total: 5447
2026-06-22T09:31:01.245Z|00357|coverage|INFO|lflow_cache_free_matches   1.4/sec     1.650/sec        1.0442/sec   total: 3764
2026-06-22T09:31:01.245Z|00358|coverage|INFO|lflow_cache_free_expr      3.8/sec     2.300/sec        0.2506/sec   total: 923
2026-06-22T09:31:01.245Z|00359|coverage|INFO|lflow_cache_add_matches    0.0/sec     1.233/sec        1.1136/sec   total: 4009
2026-06-22T09:31:01.245Z|00360|coverage|INFO|lflow_cache_add_expr       0.2/sec     0.433/sec        0.3994/sec   total: 1438
2026-06-22T09:31:01.245Z|00361|coverage|INFO|consider_logical_flow      0.2/sec    13.300/sec        4.8658/sec   total: 18156
2026-06-22T09:31:01.245Z|00362|coverage|INFO|lflow_run                  0.0/sec     0.017/sec        0.0036/sec   total: 14
2026-06-22T09:31:01.245Z|00363|coverage|INFO|111 events never hit
2026-06-22T09:31:29.525Z|00364|timeval|WARN|Unreasonably long 23145ms poll interval (1ms user, 19839ms system)
2026-06-22T09:31:29.528Z|00365|timeval|WARN|faults: 103 minor, 1383 major
2026-06-22T09:31:29.528Z|00366|timeval|WARN|disk: 364680 reads, 0 writes
2026-06-22T09:31:29.528Z|00367|timeval|WARN|context switches: 1408 voluntary, 19982 involuntary
2026-06-22T09:31:29.529Z|00368|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (85% CPU usage)
2026-06-22T09:31:29.534Z|00369|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (85% CPU usage)
2026-06-22T09:31:29.535Z|00370|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (85% CPU usage)
2026-06-22T09:31:30.128Z|00371|poll_loop|INFO|wakeup due to 593-ms timeout at lib/memory-trim.c:98 (85% CPU usage)
2026-06-22T09:31:30.129Z|00372|memory_trim|INFO|Detected inactivity (last active 30001 ms ago): trimming memory
2026-06-22T09:31:51.061Z|00373|timeval|WARN|Unreasonably long 12962ms poll interval (3ms user, 11067ms system)
2026-06-22T09:31:51.062Z|00374|timeval|WARN|faults: 271 minor, 2232 major
2026-06-22T09:31:51.062Z|00375|timeval|WARN|disk: 564824 reads, 0 writes
2026-06-22T09:31:51.062Z|00376|timeval|WARN|context switches: 2256 voluntary, 10090 involuntary
2026-06-22T09:31:51.062Z|00377|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (85% CPU usage)
2026-06-22T09:31:51.064Z|00378|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (85% CPU usage)
2026-06-22T09:31:51.065Z|00379|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (85% CPU usage)
2026-06-22T09:32:07.088Z|00141|timeval(ovn_pinctrl0)|WARN|Unreasonably long 7720ms poll interval (1ms user, 4504ms system)
2026-06-22T09:32:07.089Z|00142|timeval(ovn_pinctrl0)|WARN|faults: 28 minor, 316 major
2026-06-22T09:32:07.089Z|00143|timeval(ovn_pinctrl0)|WARN|disk: 80648 reads, 0 writes
2026-06-22T09:32:07.089Z|00144|timeval(ovn_pinctrl0)|WARN|context switches: 319 voluntary, 4781 involuntary
2026-06-22T09:32:07.089Z|00145|coverage(ovn_pinctrl0)|INFO|Dropped 2 log messages in last 37 seconds (most recently, 16 seconds ago) due to excessive rate
2026-06-22T09:32:07.089Z|00146|coverage(ovn_pinctrl0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=b7df4cbe:
2026-06-22T09:32:07.089Z|00147|coverage(ovn_pinctrl0)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:32:07.089Z|00148|coverage(ovn_pinctrl0)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T09:32:07.089Z|00149|coverage(ovn_pinctrl0)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:32:07.089Z|00150|coverage(ovn_pinctrl0)|INFO|vconn_sent                 0.2/sec     7.383/sec        3.4425/sec   total: 12393
2026-06-22T09:32:07.089Z|00151|coverage(ovn_pinctrl0)|INFO|vconn_received             0.2/sec     0.317/sec        0.1531/sec   total: 551
2026-06-22T09:32:07.089Z|00152|coverage(ovn_pinctrl0)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:32:07.089Z|00153|coverage(ovn_pinctrl0)|INFO|util_xalloc                1.2/sec  1348.150/sec     1087.4953/sec   total: 3914983
2026-06-22T09:32:07.089Z|00154|coverage(ovn_pinctrl0)|INFO|long_poll_interval         0.0/sec     0.083/sec        0.0047/sec   total: 17
2026-06-22T09:32:07.089Z|00155|coverage(ovn_pinctrl0)|INFO|stream_open                0.0/sec     0.000/sec        0.0056/sec   total: 20
2026-06-22T09:32:07.089Z|00156|coverage(ovn_pinctrl0)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T09:32:07.089Z|00157|coverage(ovn_pinctrl0)|INFO|seq_change                 0.2/sec     2.633/sec        1.7542/sec   total: 6315
2026-06-22T09:32:07.089Z|00158|coverage(ovn_pinctrl0)|INFO|rconn_sent                 0.2/sec     7.383/sec        3.4414/sec   total: 12389
2026-06-22T09:32:07.089Z|00159|coverage(ovn_pinctrl0)|INFO|rconn_queued               0.2/sec     3.417/sec        3.4414/sec   total: 12389
2026-06-22T09:32:07.089Z|00160|coverage(ovn_pinctrl0)|INFO|poll_zero_timeout          0.0/sec     0.133/sec        0.0489/sec   total: 176
2026-06-22T09:32:07.089Z|00161|coverage(ovn_pinctrl0)|INFO|poll_create_node           0.4/sec     8.167/sec        5.1531/sec   total: 18551
2026-06-22T09:32:07.089Z|00162|coverage(ovn_pinctrl0)|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T09:32:07.089Z|00163|coverage(ovn_pinctrl0)|INFO|txn_success                0.0/sec     0.067/sec        0.0297/sec   total: 107
2026-06-22T09:32:07.089Z|00164|coverage(ovn_pinctrl0)|INFO|txn_incomplete             0.0/sec     0.067/sec        0.0481/sec   total: 173
2026-06-22T09:32:07.089Z|00165|coverage(ovn_pinctrl0)|INFO|txn_unchanged              0.0/sec     0.833/sec        0.4703/sec   total: 1693
2026-06-22T09:32:07.089Z|00166|coverage(ovn_pinctrl0)|INFO|hmap_reserve               0.0/sec     0.000/sec        0.1089/sec   total: 392
2026-06-22T09:32:07.089Z|00167|coverage(ovn_pinctrl0)|INFO|hmap_shrink                0.0/sec     0.017/sec        0.0006/sec   total: 2
2026-06-22T09:32:07.089Z|00168|coverage(ovn_pinctrl0)|INFO|hmap_expand                0.0/sec    27.817/sec       18.3522/sec   total: 66068
2026-06-22T09:32:07.089Z|00169|coverage(ovn_pinctrl0)|INFO|hmap_pathological          0.0/sec     0.083/sec        0.2467/sec   total: 888
2026-06-22T09:32:07.089Z|00170|coverage(ovn_pinctrl0)|INFO|miniflow_malloc            0.0/sec    35.917/sec       32.4550/sec   total: 116838
2026-06-22T09:32:07.089Z|00171|coverage(ovn_pinctrl0)|INFO|flow_extract               0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:32:07.089Z|00172|coverage(ovn_pinctrl0)|INFO|physical_run               0.0/sec     0.017/sec        0.0222/sec   total: 80
2026-06-22T09:32:07.089Z|00173|coverage(ovn_pinctrl0)|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T09:32:07.089Z|00174|coverage(ovn_pinctrl0)|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:32:07.089Z|00175|coverage(ovn_pinctrl0)|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0028/sec   total: 10
2026-06-22T09:32:07.089Z|00176|coverage(ovn_pinctrl0)|INFO|lflow_cache_trim           0.0/sec     0.033/sec        0.0022/sec   total: 8
2026-06-22T09:32:07.089Z|00177|coverage(ovn_pinctrl0)|INFO|lflow_cache_delete         0.0/sec     1.133/sec        1.3064/sec   total: 4703
2026-06-22T09:32:07.089Z|00178|coverage(ovn_pinctrl0)|INFO|lflow_cache_miss           0.0/sec     1.333/sec        2.6286/sec   total: 9463
2026-06-22T09:32:07.089Z|00179|coverage(ovn_pinctrl0)|INFO|lflow_cache_hit            0.0/sec    21.317/sec       15.8000/sec   total: 56880
2026-06-22T09:32:07.089Z|00180|coverage(ovn_pinctrl0)|INFO|lflow_cache_add            0.0/sec     0.033/sec        1.5133/sec   total: 5448
2026-06-22T09:32:07.089Z|00181|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_matches   0.0/sec     0.267/sec        1.0467/sec   total: 3768
2026-06-22T09:32:07.089Z|00182|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_expr      0.0/sec     0.867/sec        0.2597/sec   total: 935
2026-06-22T09:32:07.089Z|00183|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_matches    0.0/sec     0.000/sec        1.1136/sec   total: 4009
2026-06-22T09:32:07.089Z|00184|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_expr       0.0/sec     0.033/sec        0.3997/sec   total: 1439
2026-06-22T09:32:07.089Z|00185|coverage(ovn_pinctrl0)|INFO|consider_logical_flow      0.0/sec    10.683/sec        5.0436/sec   total: 18157
2026-06-22T09:32:07.089Z|00186|coverage(ovn_pinctrl0)|INFO|lflow_run                  0.0/sec     0.017/sec        0.0039/sec   total: 14
2026-06-22T09:32:07.089Z|00187|coverage(ovn_pinctrl0)|INFO|111 events never hit
2026-06-22T09:32:07.095Z|00380|timeval|WARN|Unreasonably long 6602ms poll interval (1ms user, 3777ms system)
2026-06-22T09:32:07.095Z|00381|timeval|WARN|faults: 26 minor, 228 major
2026-06-22T09:32:07.095Z|00382|timeval|WARN|disk: 56152 reads, 0 writes
2026-06-22T09:32:07.095Z|00383|timeval|WARN|context switches: 236 voluntary, 4424 involuntary
2026-06-22T09:32:07.095Z|00384|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (59% CPU usage)
2026-06-22T09:32:07.096Z|00385|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (59% CPU usage)
2026-06-22T09:32:07.096Z|00386|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (59% CPU usage)
2026-06-22T09:32:26.567Z|00387|memory_trim|INFO|Detected inactivity (last active 31319 ms ago): trimming memory
2026-06-22T09:32:26.567Z|00388|timeval|WARN|Unreasonably long 2391ms poll interval (0ms user, 1794ms system)
2026-06-22T09:32:26.567Z|00389|timeval|WARN|faults: 16 minor, 138 major
2026-06-22T09:32:26.567Z|00390|timeval|WARN|disk: 34576 reads, 8 writes
2026-06-22T09:32:26.567Z|00391|timeval|WARN|context switches: 141 voluntary, 1893 involuntary
2026-06-22T09:32:34.526Z|00392|timeval|WARN|Unreasonably long 3819ms poll interval (0ms user, 2595ms system)
2026-06-22T09:32:34.527Z|00393|timeval|WARN|faults: 14 minor, 141 major
2026-06-22T09:32:34.527Z|00394|timeval|WARN|disk: 34360 reads, 0 writes
2026-06-22T09:32:34.527Z|00395|timeval|WARN|context switches: 143 voluntary, 2923 involuntary
2026-06-22T09:32:34.527Z|00396|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (78% CPU usage)
2026-06-22T09:32:34.529Z|00397|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.11:52382<->10.4.3.11:6642) at lib/stream-ssl.c:842 (78% CPU usage)
2026-06-22T09:32:34.529Z|00398|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (78% CPU usage)