2026-06-22T13:40:52.855Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller.log
2026-06-22T13:40:52.857Z|00002|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:40:52.857Z|00003|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T13:40:52.875Z|00004|main|INFO|OVN internal version is : [24.03.6-20.33.0-76.8]
2026-06-22T13:40:52.875Z|00005|main|INFO|OVS IDL reconnected, force recompute.
2026-06-22T13:40:52.875Z|00006|main|INFO|OVNSB IDL reconnected, force recompute.
2026-06-22T13:45:07.243Z|00007|memory|INFO|8076 kB peak resident set size after 254.4 seconds
2026-06-22T13:45:07.243Z|00008|memory|INFO|idl-cells-Open_vSwitch:101
2026-06-22T13:45:07.244Z|00009|jsonrpc|WARN|unix:/var/run/openvswitch/db.sock: send error: Broken pipe
2026-06-22T13:45:07.245Z|00010|reconnect|WARN|unix:/var/run/openvswitch/db.sock: connection dropped (Broken pipe)
2026-06-22T13:45:08.246Z|00011|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:45:08.246Z|00012|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T13:45:08.392Z|00013|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:45:08.392Z|00014|reconnect|INFO|ssl:10.4.3.218:6642: connection attempt failed (Connection refused)
2026-06-22T13:45:09.393Z|00015|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:45:09.393Z|00016|reconnect|INFO|ssl:10.4.3.218:6642: connection attempt failed (Connection refused)
2026-06-22T13:45:09.393Z|00017|reconnect|INFO|ssl:10.4.3.218:6642: waiting 2 seconds before reconnect
2026-06-22T13:45:11.364Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller.log
2026-06-22T13:45:11.365Z|00002|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:45:11.365Z|00003|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T13:45:11.367Z|00004|main|INFO|OVN internal version is : [24.03.6-20.33.0-76.8]
2026-06-22T13:45:11.367Z|00005|main|INFO|OVS IDL reconnected, force recompute.
2026-06-22T13:45:11.371Z|00006|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:45:11.371Z|00007|main|INFO|OVNSB IDL reconnected, force recompute.
2026-06-22T13:45:11.375Z|00008|reconnect|INFO|ssl:10.4.3.218:6642: connected
2026-06-22T13:45:11.379Z|00009|features|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T13:45:11.379Z|00010|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T13:45:11.380Z|00011|features|INFO|OVS Feature: ct_zero_snat, state: supported
2026-06-22T13:45:11.380Z|00012|features|INFO|OVS Feature: ct_flush, state: supported
2026-06-22T13:45:11.380Z|00013|features|INFO|OVS Feature: dp_hash_l4_sym_support, state: supported
2026-06-22T13:45:11.380Z|00014|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T13:45:11.380Z|00015|main|INFO|OVS feature set changed, force recompute.
2026-06-22T13:45:11.380Z|00016|ofctrl|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T13:45:11.380Z|00017|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T13:45:11.380Z|00018|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T13:45:11.380Z|00019|main|INFO|OVS OpenFlow connection reconnected,force recompute.
2026-06-22T13:45:11.380Z|00020|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T13:45:11.380Z|00021|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T13:45:11.380Z|00022|main|INFO|OVS feature set changed, force recompute.
2026-06-22T13:45:11.381Z|00001|pinctrl(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T13:45:11.381Z|00002|rconn(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T13:45:11.381Z|00001|statctrl(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T13:45:11.381Z|00002|rconn(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T13:45:11.381Z|00003|rconn(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T13:45:11.381Z|00003|rconn(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T13:45:11.382Z|00023|features|INFO|OVS DB schema supports 4 flow table prefixes, our IDL supports: 4
2026-06-22T13:45:11.382Z|00024|main|INFO|Setting flow table prefixes: ip_src, ip_dst, ipv6_src, ipv6_dst.
2026-06-22T13:45:25.745Z|00025|memory|INFO|9996 kB peak resident set size after 14.4 seconds
2026-06-22T13:45:25.745Z|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-22T13:45:36.720Z|00027|binding|INFO|Claiming lport cr-lrp-b37ecb44-5f74-46bd-870e-6f94e70b60cf for this chassis.
2026-06-22T13:45:36.720Z|00028|binding|INFO|cr-lrp-b37ecb44-5f74-46bd-870e-6f94e70b60cf: Claiming fa:16:3e:99:74:c5 172.24.5.141/24
2026-06-22T13:45:36.723Z|00029|binding|INFO|Setting lport cr-lrp-b37ecb44-5f74-46bd-870e-6f94e70b60cf up in Southbound
2026-06-22T13:46:10.845Z|00030|memory_trim|INFO|Detected inactivity (last active 30003 ms ago): trimming memory
2026-06-22T13:47:10.660Z|00031|memory_trim|INFO|Detected inactivity (last active 30005 ms ago): trimming memory
2026-06-22T13:48:09.914Z|00032|binding|INFO|Claiming lport cr-lrp-cfe62423-1bac-4b7d-adaf-d004d43f90cb for this chassis.
2026-06-22T13:48:09.914Z|00033|binding|INFO|cr-lrp-cfe62423-1bac-4b7d-adaf-d004d43f90cb: Claiming fa:16:3e:61:86:e7 172.24.5.209/24 2001:db8::1b8/64
2026-06-22T13:48:09.919Z|00034|binding|INFO|Setting lport cr-lrp-cfe62423-1bac-4b7d-adaf-d004d43f90cb up in Southbound
2026-06-22T13:48:10.107Z|00035|binding|INFO|Claiming lport cr-lrp-cbea2723-a2da-4041-8a32-1943b9380cbb for this chassis.
2026-06-22T13:48:10.107Z|00036|binding|INFO|cr-lrp-cbea2723-a2da-4041-8a32-1943b9380cbb: Claiming fa:16:3e:7b:89:35 172.24.5.205/24 2001:db8::3d4/64
2026-06-22T13:48:10.116Z|00037|binding|INFO|Setting lport cr-lrp-cbea2723-a2da-4041-8a32-1943b9380cbb up in Southbound
2026-06-22T13:48:10.334Z|00038|binding|INFO|Claiming lport cr-lrp-c05935e7-0672-4697-8643-2f81e2da032a for this chassis.
2026-06-22T13:48:10.334Z|00039|binding|INFO|cr-lrp-c05935e7-0672-4697-8643-2f81e2da032a: Claiming fa:16:3e:57:26:eb 172.24.5.61/24 2001:db8::48/64
2026-06-22T13:48:10.344Z|00040|binding|INFO|Setting lport cr-lrp-c05935e7-0672-4697-8643-2f81e2da032a up in Southbound
2026-06-22T13:48:12.148Z|00041|binding|INFO|Claiming lport cr-lrp-28b269c5-f4dd-4631-a05f-b32338330741 for this chassis.
2026-06-22T13:48:12.148Z|00042|binding|INFO|cr-lrp-28b269c5-f4dd-4631-a05f-b32338330741: Claiming fa:16:3e:16:b1:7b 172.24.5.125/24 2001:db8::155/64
2026-06-22T13:48:12.157Z|00043|binding|INFO|Setting lport cr-lrp-28b269c5-f4dd-4631-a05f-b32338330741 up in Southbound
2026-06-22T13:48:12.446Z|00044|binding|INFO|Claiming lport cr-lrp-02b95cb8-df69-44c3-a768-f720ae24673f for this chassis.
2026-06-22T13:48:12.446Z|00045|binding|INFO|cr-lrp-02b95cb8-df69-44c3-a768-f720ae24673f: Claiming fa:16:3e:b2:15:ba 172.24.5.120/24 2001:db8::178/64
2026-06-22T13:48:12.459Z|00046|binding|INFO|Setting lport cr-lrp-02b95cb8-df69-44c3-a768-f720ae24673f up in Southbound
2026-06-22T13:48:12.948Z|00047|binding|INFO|Claiming lport cr-lrp-f4d815ab-7a14-4813-b128-f409e39d97f9 for this chassis.
2026-06-22T13:48:12.948Z|00048|binding|INFO|cr-lrp-f4d815ab-7a14-4813-b128-f409e39d97f9: Claiming fa:16:3e:97:76:54 172.24.5.137/24 2001:db8::bd/64
2026-06-22T13:48:12.958Z|00049|binding|INFO|Setting lport cr-lrp-f4d815ab-7a14-4813-b128-f409e39d97f9 up in Southbound
2026-06-22T13:48:14.720Z|00050|binding|INFO|Claiming lport cr-lrp-ccb71b1f-b4af-436b-9196-61cd5c95e9ad for this chassis.
2026-06-22T13:48:14.720Z|00051|binding|INFO|cr-lrp-ccb71b1f-b4af-436b-9196-61cd5c95e9ad: Claiming fa:16:3e:26:55:94 172.24.5.127/24 2001:db8::335/64
2026-06-22T13:48:14.728Z|00052|binding|INFO|Setting lport cr-lrp-ccb71b1f-b4af-436b-9196-61cd5c95e9ad up in Southbound
2026-06-22T13:48:15.282Z|00053|binding|INFO|Claiming lport cr-lrp-7ac2d630-5b95-4e28-964c-7745c5d7469f for this chassis.
2026-06-22T13:48:15.282Z|00054|binding|INFO|cr-lrp-7ac2d630-5b95-4e28-964c-7745c5d7469f: Claiming fa:16:3e:52:99:15 172.24.5.92/24 2001:db8::1ed/64
2026-06-22T13:48:15.289Z|00055|binding|INFO|Setting lport cr-lrp-7ac2d630-5b95-4e28-964c-7745c5d7469f up in Southbound
2026-06-22T13:48:21.500Z|00056|memory|INFO|peak resident set size grew 90% in last 175.8 seconds, from 9996 kB to 18956 kB
2026-06-22T13:48:21.500Z|00057|memory|INFO|idl-cells-OVN_Southbound:12510 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:660 lflow-cache-entries-cache-matches:282 lflow-cache-size-KB:2956 local_datapath_usage-KB:4 ofctrl_desired_flow_usage-KB:799 ofctrl_installed_flow_usage-KB:596 ofctrl_sb_flow_ref_usage-KB:293
2026-06-22T13:48:22.588Z|00058|binding|INFO|Claiming lport cr-lrp-1a2ecf3f-5d19-47de-8a04-4ab3f3816c55 for this chassis.
2026-06-22T13:48:22.588Z|00059|binding|INFO|cr-lrp-1a2ecf3f-5d19-47de-8a04-4ab3f3816c55: Claiming fa:16:3e:3b:c3:f6 172.24.5.24/24 2001:db8::1f4/64
2026-06-22T13:48:22.599Z|00060|binding|INFO|Setting lport cr-lrp-1a2ecf3f-5d19-47de-8a04-4ab3f3816c55 up in Southbound
2026-06-22T13:48:23.781Z|00061|binding|INFO|Claiming lport cr-lrp-5bd9c6cf-c3f0-4b74-9ec7-d3f91bd52721 for this chassis.
2026-06-22T13:48:23.781Z|00062|binding|INFO|cr-lrp-5bd9c6cf-c3f0-4b74-9ec7-d3f91bd52721: Claiming fa:16:3e:7e:a6:99 172.24.5.14/24 2001:db8::a7/64
2026-06-22T13:48:23.794Z|00063|binding|INFO|Setting lport cr-lrp-5bd9c6cf-c3f0-4b74-9ec7-d3f91bd52721 up in Southbound
2026-06-22T13:48:25.740Z|00064|binding|INFO|Claiming lport cr-lrp-a535da93-a331-4941-8fdc-5176d238e7fb for this chassis.
2026-06-22T13:48:25.740Z|00065|binding|INFO|cr-lrp-a535da93-a331-4941-8fdc-5176d238e7fb: Claiming fa:16:3e:ff:c7:c1 172.24.5.82/24 2001:db8::3dd/64
2026-06-22T13:48:25.754Z|00066|binding|INFO|Setting lport cr-lrp-a535da93-a331-4941-8fdc-5176d238e7fb up in Southbound
2026-06-22T13:48:27.417Z|00067|binding|INFO|Claiming lport cr-lrp-cbe5c103-b6f0-4328-b832-e6a600968bba for this chassis.
2026-06-22T13:48:27.417Z|00068|binding|INFO|cr-lrp-cbe5c103-b6f0-4328-b832-e6a600968bba: Claiming fa:16:3e:e1:90:be 172.24.5.170/24 2001:db8::299/64
2026-06-22T13:48:27.430Z|00069|binding|INFO|Setting lport cr-lrp-cbe5c103-b6f0-4328-b832-e6a600968bba up in Southbound
2026-06-22T13:48:27.762Z|00070|binding|INFO|Claiming lport cr-lrp-6690800b-7490-4889-86b0-37a19ae05fd3 for this chassis.
2026-06-22T13:48:27.762Z|00071|binding|INFO|cr-lrp-6690800b-7490-4889-86b0-37a19ae05fd3: Claiming fa:16:3e:7b:6e:1d 172.24.5.94/24 2001:db8::1ca/64
2026-06-22T13:48:27.773Z|00072|binding|INFO|Setting lport cr-lrp-6690800b-7490-4889-86b0-37a19ae05fd3 up in Southbound
2026-06-22T13:48:34.792Z|00073|binding|INFO|Claiming lport cr-lrp-07f906d0-96d4-4615-b1b3-429456be4e33 for this chassis.
2026-06-22T13:48:34.792Z|00074|binding|INFO|cr-lrp-07f906d0-96d4-4615-b1b3-429456be4e33: Claiming fa:16:3e:e8:c1:c7 172.24.5.186/24 2001:db8::261/64
2026-06-22T13:48:34.810Z|00075|binding|INFO|Setting lport cr-lrp-07f906d0-96d4-4615-b1b3-429456be4e33 up in Southbound
2026-06-22T13:48:35.506Z|00076|binding|INFO|Claiming lport cr-lrp-9fa9ca46-1b32-4eeb-9524-f3225f959727 for this chassis.
2026-06-22T13:48:35.506Z|00077|binding|INFO|cr-lrp-9fa9ca46-1b32-4eeb-9524-f3225f959727: Claiming fa:16:3e:ee:93:6f 172.24.5.241/24 2001:db8::5b/64
2026-06-22T13:48:35.525Z|00078|binding|INFO|Setting lport cr-lrp-9fa9ca46-1b32-4eeb-9524-f3225f959727 up in Southbound
2026-06-22T13:48:39.500Z|00079|binding|INFO|Claiming lport cr-lrp-c6c56fef-8536-4f73-ad85-d5101ea02d49 for this chassis.
2026-06-22T13:48:39.500Z|00080|binding|INFO|cr-lrp-c6c56fef-8536-4f73-ad85-d5101ea02d49: Claiming fa:16:3e:47:c3:80 172.24.5.4/24 2001:db8::2bd/64
2026-06-22T13:48:39.513Z|00081|binding|INFO|Setting lport cr-lrp-c6c56fef-8536-4f73-ad85-d5101ea02d49 up in Southbound
2026-06-22T13:48:47.946Z|00082|binding|INFO|Claiming lport f7fb714b-ba82-4187-96d3-4ab2b0073283 for this chassis.
2026-06-22T13:48:47.946Z|00083|binding|INFO|f7fb714b-ba82-4187-96d3-4ab2b0073283: Claiming fa:16:3e:d6:cf:b1 10.1.0.4
2026-06-22T13:48:47.971Z|00084|binding|INFO|Setting lport f7fb714b-ba82-4187-96d3-4ab2b0073283 ovn-installed in OVS
2026-06-22T13:48:47.971Z|00085|binding|INFO|Setting lport f7fb714b-ba82-4187-96d3-4ab2b0073283 up in Southbound
2026-06-22T13:48:48.707Z|00086|binding|INFO|Claiming lport e3c1fce6-75b6-4f14-b31e-0787f013be1d for this chassis.
2026-06-22T13:48:48.707Z|00087|binding|INFO|e3c1fce6-75b6-4f14-b31e-0787f013be1d: Claiming fa:16:3e:fc:8b:d4 10.1.0.7
2026-06-22T13:48:48.724Z|00088|binding|INFO|Setting lport e3c1fce6-75b6-4f14-b31e-0787f013be1d ovn-installed in OVS
2026-06-22T13:48:48.724Z|00089|binding|INFO|Setting lport e3c1fce6-75b6-4f14-b31e-0787f013be1d up in Southbound
2026-06-22T13:48:52.110Z|00004|timeval(ovn_pinctrl0)|WARN|Unreasonably long 1147ms poll interval (0ms user, 318ms system)
2026-06-22T13:48:52.111Z|00005|timeval(ovn_pinctrl0)|WARN|faults: 13 minor, 66 major
2026-06-22T13:48:52.111Z|00006|timeval(ovn_pinctrl0)|WARN|disk: 15240 reads, 0 writes
2026-06-22T13:48:52.111Z|00007|timeval(ovn_pinctrl0)|WARN|context switches: 66 voluntary, 368 involuntary
2026-06-22T13:48:52.111Z|00008|coverage(ovn_pinctrl0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=266f8d6c:
2026-06-22T13:48:52.111Z|00009|coverage(ovn_pinctrl0)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:48:52.111Z|00010|coverage(ovn_pinctrl0)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:48:52.111Z|00011|coverage(ovn_pinctrl0)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:48:52.111Z|00012|coverage(ovn_pinctrl0)|INFO|vconn_sent               200.0/sec   118.017/sec        2.1433/sec   total: 8036
2026-06-22T13:48:52.111Z|00013|coverage(ovn_pinctrl0)|INFO|vconn_received             6.0/sec     4.583/sec        0.0886/sec   total: 353
2026-06-22T13:48:52.111Z|00014|coverage(ovn_pinctrl0)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:48:52.111Z|00015|coverage(ovn_pinctrl0)|INFO|util_xalloc              102356.6/sec 35284.150/sec      637.9919/sec   total: 2380804
2026-06-22T13:48:52.111Z|00016|coverage(ovn_pinctrl0)|INFO|stream_open                0.0/sec     0.000/sec        0.0019/sec   total: 7
2026-06-22T13:48:52.111Z|00017|coverage(ovn_pinctrl0)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:48:52.111Z|00018|coverage(ovn_pinctrl0)|INFO|seq_change                58.8/sec    65.417/sec        1.3389/sec   total: 5007
2026-06-22T13:48:52.111Z|00019|coverage(ovn_pinctrl0)|INFO|rconn_sent               200.0/sec   118.017/sec        2.1422/sec   total: 8032
2026-06-22T13:48:52.111Z|00020|coverage(ovn_pinctrl0)|INFO|rconn_queued             200.0/sec   118.017/sec        2.1422/sec   total: 8032
2026-06-22T13:48:52.111Z|00021|coverage(ovn_pinctrl0)|INFO|poll_zero_timeout          0.6/sec     0.733/sec        0.0278/sec   total: 100
2026-06-22T13:48:52.111Z|00022|coverage(ovn_pinctrl0)|INFO|poll_create_node         176.4/sec   173.383/sec        3.6578/sec   total: 13730
2026-06-22T13:48:52.111Z|00023|coverage(ovn_pinctrl0)|INFO|txn_success                0.6/sec     0.867/sec        0.0181/sec   total: 71
2026-06-22T13:48:52.111Z|00024|coverage(ovn_pinctrl0)|INFO|txn_incomplete             0.8/sec     1.550/sec        0.0325/sec   total: 123
2026-06-22T13:48:52.111Z|00025|coverage(ovn_pinctrl0)|INFO|txn_unchanged             18.0/sec    12.050/sec        0.2856/sec   total: 1080
2026-06-22T13:48:52.111Z|00026|coverage(ovn_pinctrl0)|INFO|hmap_reserve               6.8/sec     0.567/sec        0.0094/sec   total: 92
2026-06-22T13:48:52.111Z|00027|coverage(ovn_pinctrl0)|INFO|hmap_expand              1772.2/sec   512.950/sec        9.7842/sec   total: 36452
2026-06-22T13:48:52.111Z|00028|coverage(ovn_pinctrl0)|INFO|hmap_pathological         19.8/sec     9.667/sec        0.1761/sec   total: 648
2026-06-22T13:48:52.111Z|00029|coverage(ovn_pinctrl0)|INFO|miniflow_malloc          3952.2/sec  1305.183/sec       22.4492/sec   total: 82064
2026-06-22T13:48:52.111Z|00030|coverage(ovn_pinctrl0)|INFO|physical_run               0.8/sec     1.100/sec        0.0206/sec   total: 74
2026-06-22T13:48:52.111Z|00031|coverage(ovn_pinctrl0)|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0000/sec   total: 4
2026-06-22T13:48:52.111Z|00032|coverage(ovn_pinctrl0)|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0000/sec   total: 6
2026-06-22T13:48:52.111Z|00033|coverage(ovn_pinctrl0)|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:48:52.111Z|00034|coverage(ovn_pinctrl0)|INFO|lflow_cache_delete        94.4/sec    49.883/sec        0.8514/sec   total: 3175
2026-06-22T13:48:52.111Z|00035|coverage(ovn_pinctrl0)|INFO|lflow_cache_miss         207.2/sec   101.150/sec        1.7925/sec   total: 6726
2026-06-22T13:48:52.111Z|00036|coverage(ovn_pinctrl0)|INFO|lflow_cache_hit          2233.2/sec   560.200/sec        9.5550/sec   total: 34750
2026-06-22T13:48:52.111Z|00037|coverage(ovn_pinctrl0)|INFO|lflow_cache_add           66.4/sec    70.717/sec        1.2689/sec   total: 4649
2026-06-22T13:48:52.111Z|00038|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_matches  60.2/sec    44.917/sec        0.7678/sec   total: 2830
2026-06-22T13:48:52.111Z|00039|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_expr     34.2/sec     4.967/sec        0.0836/sec   total: 345
2026-06-22T13:48:52.111Z|00040|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_matches   54.6/sec    49.417/sec        0.8853/sec   total: 3248
2026-06-22T13:48:52.111Z|00041|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_expr      11.8/sec    21.300/sec        0.3836/sec   total: 1401
2026-06-22T13:48:52.111Z|00042|coverage(ovn_pinctrl0)|INFO|consider_logical_flow    722.2/sec   129.083/sec        2.3753/sec   total: 8745
2026-06-22T13:48:52.111Z|00043|coverage(ovn_pinctrl0)|INFO|lflow_run                  0.4/sec     0.033/sec        0.0014/sec   total: 5
2026-06-22T13:48:52.111Z|00044|coverage(ovn_pinctrl0)|INFO|116 events never hit
2026-06-22T13:48:53.413Z|00090|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:48:53.665Z|00091|binding|INFO|Releasing lport f7fb714b-ba82-4187-96d3-4ab2b0073283 from this chassis (sb_readonly=0)
2026-06-22T13:48:53.665Z|00092|binding|INFO|Setting lport f7fb714b-ba82-4187-96d3-4ab2b0073283 down in Southbound
2026-06-22T13:48:54.142Z|00093|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:48:58.148Z|00094|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:48:58.976Z|00095|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:00.911Z|00096|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:01.901Z|00097|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:03.296Z|00098|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:05.105Z|00099|binding|INFO|Claiming lport cr-lrp-ae2ee8dd-86c1-49bb-a063-006ee6f3c854 for this chassis.
2026-06-22T13:49:05.105Z|00100|binding|INFO|cr-lrp-ae2ee8dd-86c1-49bb-a063-006ee6f3c854: Claiming fa:16:3e:c5:36:ab 172.24.5.107/24 2001:db8::f3/64
2026-06-22T13:49:05.121Z|00101|binding|INFO|Setting lport cr-lrp-ae2ee8dd-86c1-49bb-a063-006ee6f3c854 up in Southbound
2026-06-22T13:49:05.957Z|00102|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:06.578Z|00103|binding|INFO|Claiming lport cr-lrp-5379648c-bbe5-454a-addd-bc7504bb0bc6 for this chassis.
2026-06-22T13:49:06.578Z|00104|binding|INFO|cr-lrp-5379648c-bbe5-454a-addd-bc7504bb0bc6: Claiming fa:16:3e:30:07:f5 172.24.5.131/24 2001:db8::25c/64
2026-06-22T13:49:06.592Z|00105|binding|INFO|Setting lport cr-lrp-5379648c-bbe5-454a-addd-bc7504bb0bc6 up in Southbound
2026-06-22T13:49:08.676Z|00106|binding|INFO|Claiming lport cr-lrp-818234cf-a6d6-4d66-9f47-2f3881a707e3 for this chassis.
2026-06-22T13:49:08.676Z|00107|binding|INFO|cr-lrp-818234cf-a6d6-4d66-9f47-2f3881a707e3: Claiming fa:16:3e:1b:53:91 172.24.5.176/24 2001:db8::107/64
2026-06-22T13:49:08.685Z|00108|binding|INFO|Setting lport cr-lrp-818234cf-a6d6-4d66-9f47-2f3881a707e3 up in Southbound
2026-06-22T13:49:10.374Z|00109|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:14.277Z|00110|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:17.491Z|00045|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:fc:8b:d4 10.1.0.7
2026-06-22T13:49:17.503Z|00046|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:fc:8b:d4 10.1.0.7
2026-06-22T13:49:29.692Z|00111|reconnect|ERR|ssl:10.4.3.218:6642: no response to inactivity probe after 5.01 seconds, disconnecting
2026-06-22T13:49:29.692Z|00112|reconnect|INFO|ssl:10.4.3.218:6642: connection dropped
2026-06-22T13:49:29.693Z|00113|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T13:49:30.693Z|00114|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:49:31.694Z|00115|reconnect|INFO|ssl:10.4.3.218:6642: connection attempt timed out
2026-06-22T13:49:31.694Z|00116|reconnect|INFO|ssl:10.4.3.218:6642: waiting 2 seconds before reconnect
2026-06-22T13:49:33.696Z|00117|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:49:35.698Z|00118|reconnect|INFO|ssl:10.4.3.218:6642: connection attempt timed out
2026-06-22T13:49:35.698Z|00119|reconnect|INFO|ssl:10.4.3.218:6642: waiting 4 seconds before reconnect
2026-06-22T13:49:39.701Z|00120|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:49:39.705Z|00121|reconnect|INFO|ssl:10.4.3.218:6642: connected
2026-06-22T13:49:39.762Z|00122|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:49:41.145Z|00123|binding|INFO|Claiming lport cr-lrp-74ff8429-c41b-4281-a5fc-b82721b93b7d for this chassis.
2026-06-22T13:49:41.145Z|00124|binding|INFO|cr-lrp-74ff8429-c41b-4281-a5fc-b82721b93b7d: Claiming fa:16:3e:71:31:5e 172.24.5.178/24 2001:db8::2b5/64
2026-06-22T13:49:41.154Z|00125|binding|INFO|Setting lport cr-lrp-74ff8429-c41b-4281-a5fc-b82721b93b7d up in Southbound
2026-06-22T13:49:44.599Z|00126|binding|INFO|Claiming lport cr-lrp-cd6bf9a4-65fb-414c-a147-56018491bbf5 for this chassis.
2026-06-22T13:49:44.599Z|00127|binding|INFO|cr-lrp-cd6bf9a4-65fb-414c-a147-56018491bbf5: Claiming fa:16:3e:50:fa:8b 172.24.5.8/24 2001:db8::308/64
2026-06-22T13:49:44.610Z|00128|binding|INFO|Setting lport cr-lrp-cd6bf9a4-65fb-414c-a147-56018491bbf5 up in Southbound
2026-06-22T13:49:49.154Z|00129|binding|INFO|Claiming lport 2844197d-0677-4e97-acae-45551d324d97 for this chassis.
2026-06-22T13:49:49.154Z|00130|binding|INFO|2844197d-0677-4e97-acae-45551d324d97: Claiming fa:16:3e:89:5f:36 10.1.0.4
2026-06-22T13:49:49.181Z|00131|binding|INFO|Setting lport 2844197d-0677-4e97-acae-45551d324d97 ovn-installed in OVS
2026-06-22T13:49:49.181Z|00132|binding|INFO|Setting lport 2844197d-0677-4e97-acae-45551d324d97 up in Southbound
2026-06-22T13:49:49.478Z|00133|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:49:55.941Z|00134|binding|INFO|Claiming lport cr-lrp-519d6122-827a-46d7-9254-f26ea079f34d for this chassis.
2026-06-22T13:49:55.941Z|00135|binding|INFO|cr-lrp-519d6122-827a-46d7-9254-f26ea079f34d: Claiming fa:16:3e:32:1e:c5 172.24.5.198/24 2001:db8::219/64
2026-06-22T13:49:55.950Z|00136|binding|INFO|Setting lport cr-lrp-519d6122-827a-46d7-9254-f26ea079f34d up in Southbound
2026-06-22T13:49:58.287Z|00137|binding|INFO|Claiming lport cr-lrp-93925422-ded1-444d-9d6b-a8f1bc78711e for this chassis.
2026-06-22T13:49:58.287Z|00138|binding|INFO|cr-lrp-93925422-ded1-444d-9d6b-a8f1bc78711e: Claiming fa:16:3e:9a:b7:6c 172.24.5.195/24 2001:db8::1b/64
2026-06-22T13:49:58.297Z|00139|binding|INFO|Setting lport cr-lrp-93925422-ded1-444d-9d6b-a8f1bc78711e up in Southbound
2026-06-22T13:49:59.665Z|00140|binding|INFO|Claiming lport a88054b5-ecb4-46e9-8161-8d4633f308a7 for this chassis.
2026-06-22T13:49:59.665Z|00141|binding|INFO|a88054b5-ecb4-46e9-8161-8d4633f308a7: Claiming fa:16:3e:31:60:0e 10.1.0.7
2026-06-22T13:49:59.695Z|00142|binding|INFO|Setting lport a88054b5-ecb4-46e9-8161-8d4633f308a7 ovn-installed in OVS
2026-06-22T13:49:59.695Z|00143|binding|INFO|Setting lport a88054b5-ecb4-46e9-8161-8d4633f308a7 up in Southbound
2026-06-22T13:50:00.289Z|00144|binding|INFO|Releasing lport e99f244d-c0ac-4fc5-8661-38f077585ad6 from this chassis (sb_readonly=0)
2026-06-22T13:50:10.873Z|00047|timeval(ovn_pinctrl0)|WARN|Unreasonably long 4433ms poll interval (0ms user, 183ms system)
2026-06-22T13:50:11.017Z|00145|timeval|WARN|Unreasonably long 5712ms poll interval (30ms user, 4051ms system)
2026-06-22T13:50:10.964Z|00048|timeval(ovn_pinctrl0)|WARN|faults: 28 minor, 193 major
2026-06-22T13:50:11.025Z|00146|timeval|WARN|faults: 540 minor, 4426 major
2026-06-22T13:50:11.080Z|00049|timeval(ovn_pinctrl0)|WARN|disk: 44664 reads, 0 writes
2026-06-22T13:50:11.132Z|00050|timeval(ovn_pinctrl0)|WARN|context switches: 199 voluntary, 103 involuntary
2026-06-22T13:50:11.138Z|00147|timeval|WARN|disk: 1125160 reads, 0 writes
2026-06-22T13:50:11.180Z|00148|timeval|WARN|context switches: 4502 voluntary, 1038 involuntary
2026-06-22T13:50:11.202Z|00051|coverage(ovn_pinctrl0)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=ca396246:
2026-06-22T13:50:11.259Z|00052|coverage(ovn_pinctrl0)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:50:11.283Z|00149|coverage|INFO|Skipping details of duplicate event coverage for hash=ca396246
2026-06-22T13:50:11.283Z|00053|coverage(ovn_pinctrl0)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:50:11.327Z|00150|poll_loop|INFO|wakeup due to 6-ms timeout at lib/reconnect.c:677 (52% CPU usage)
2026-06-22T13:50:11.337Z|00054|coverage(ovn_pinctrl0)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:50:11.357Z|00055|coverage(ovn_pinctrl0)|INFO|vconn_sent                 4.8/sec    71.017/sec        4.0619/sec   total: 14624
2026-06-22T13:50:11.374Z|00056|coverage(ovn_pinctrl0)|INFO|vconn_received             2.4/sec     3.233/sec        0.1761/sec   total: 634
2026-06-22T13:50:11.387Z|00057|coverage(ovn_pinctrl0)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:50:11.416Z|00058|coverage(ovn_pinctrl0)|INFO|util_xalloc              5817.8/sec 26157.850/sec     1353.1194/sec   total: 4874158
2026-06-22T13:50:11.445Z|00059|coverage(ovn_pinctrl0)|INFO|long_poll_interval         0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:50:11.515Z|00060|coverage(ovn_pinctrl0)|INFO|stream_open                0.0/sec     0.050/sec        0.0028/sec   total: 10
2026-06-22T13:50:11.622Z|00061|coverage(ovn_pinctrl0)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:50:11.656Z|00062|coverage(ovn_pinctrl0)|INFO|seq_change                 8.2/sec    30.517/sec        2.1022/sec   total: 7572
2026-06-22T13:50:11.680Z|00063|coverage(ovn_pinctrl0)|INFO|rconn_sent                 4.8/sec    71.017/sec        4.0608/sec   total: 14620
2026-06-22T13:50:11.763Z|00064|coverage(ovn_pinctrl0)|INFO|rconn_queued               4.8/sec    71.017/sec        4.0608/sec   total: 14620
2026-06-22T13:50:11.795Z|00065|coverage(ovn_pinctrl0)|INFO|poll_zero_timeout          0.2/sec     0.467/sec        0.0383/sec   total: 139
2026-06-22T13:50:11.831Z|00066|coverage(ovn_pinctrl0)|INFO|poll_create_node          34.8/sec    90.767/sec        5.9272/sec   total: 21366
2026-06-22T13:50:11.868Z|00067|coverage(ovn_pinctrl0)|INFO|txn_try_again              0.0/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T13:50:11.928Z|00068|coverage(ovn_pinctrl0)|INFO|txn_success                0.8/sec     0.567/sec        0.0319/sec   total: 115
2026-06-22T13:50:11.971Z|00069|coverage(ovn_pinctrl0)|INFO|txn_incomplete             0.8/sec     0.783/sec        0.0508/sec   total: 183
2026-06-22T13:50:12.029Z|00070|coverage(ovn_pinctrl0)|INFO|txn_unchanged              5.2/sec     8.467/sec        0.5008/sec   total: 1809
2026-06-22T13:50:12.086Z|00071|coverage(ovn_pinctrl0)|INFO|hmap_reserve               9.0/sec     7.200/sec        0.1814/sec   total: 664
2026-06-22T13:50:12.137Z|00072|coverage(ovn_pinctrl0)|INFO|hmap_expand               97.6/sec   426.650/sec       21.8817/sec   total: 78838
2026-06-22T13:50:12.216Z|00073|coverage(ovn_pinctrl0)|INFO|hmap_pathological          1.2/sec     5.400/sec        0.3150/sec   total: 1135
2026-06-22T13:50:12.281Z|00074|coverage(ovn_pinctrl0)|INFO|miniflow_malloc           35.8/sec   847.700/sec       45.5142/sec   total: 163851
2026-06-22T13:50:12.293Z|00075|coverage(ovn_pinctrl0)|INFO|flow_extract               0.0/sec     0.033/sec        0.0006/sec   total: 2
2026-06-22T13:50:12.345Z|00076|coverage(ovn_pinctrl0)|INFO|physical_run               0.0/sec     0.500/sec        0.0303/sec   total: 109
2026-06-22T13:50:12.412Z|00077|coverage(ovn_pinctrl0)|INFO|pinctrl_total_pin_pkts     0.0/sec     0.033/sec        0.0006/sec   total: 2
2026-06-22T13:50:12.513Z|00078|coverage(ovn_pinctrl0)|INFO|lflow_conj_free            0.4/sec     0.067/sec        0.0025/sec   total: 9
2026-06-22T13:50:12.597Z|00079|coverage(ovn_pinctrl0)|INFO|lflow_conj_alloc           0.4/sec     0.183/sec        0.0064/sec   total: 23
2026-06-22T13:50:12.698Z|00080|coverage(ovn_pinctrl0)|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:50:12.732Z|00081|coverage(ovn_pinctrl0)|INFO|lflow_cache_delete         0.0/sec    28.350/sec        1.5986/sec   total: 5755
2026-06-22T13:50:12.769Z|00082|coverage(ovn_pinctrl0)|INFO|lflow_cache_miss          11.8/sec    60.083/sec        3.3533/sec   total: 12072
2026-06-22T13:50:12.831Z|00083|coverage(ovn_pinctrl0)|INFO|lflow_cache_hit            2.4/sec   411.683/sec       21.6603/sec   total: 77977
2026-06-22T13:50:12.890Z|00084|coverage(ovn_pinctrl0)|INFO|lflow_cache_add            0.6/sec    33.433/sec        1.9689/sec   total: 7088
2026-06-22T13:50:12.989Z|00085|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_matches   0.0/sec    23.317/sec        1.3067/sec   total: 4704
2026-06-22T13:50:13.074Z|00086|coverage(ovn_pinctrl0)|INFO|lflow_cache_free_expr      0.0/sec     5.033/sec        0.2919/sec   total: 1051
2026-06-22T13:50:13.098Z|00087|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_matches    0.2/sec    24.217/sec        1.4153/sec   total: 5095
2026-06-22T13:50:13.121Z|00088|coverage(ovn_pinctrl0)|INFO|lflow_cache_add_expr       0.4/sec     9.217/sec        0.5536/sec   total: 1993
2026-06-22T13:50:13.233Z|00089|coverage(ovn_pinctrl0)|INFO|consider_logical_flow      7.8/sec   125.317/sec        6.4797/sec   total: 23327
2026-06-22T13:50:13.390Z|00090|coverage(ovn_pinctrl0)|INFO|lflow_run                  0.0/sec     0.083/sec        0.0042/sec   total: 15
2026-06-22T13:50:13.528Z|00091|coverage(ovn_pinctrl0)|INFO|112 events never hit
2026-06-22T13:50:19.176Z|00092|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:89:5f:36 10.1.0.4
2026-06-22T13:50:19.580Z|00151|timeval|WARN|Unreasonably long 8270ms poll interval (32ms user, 6200ms system)
2026-06-22T13:50:19.661Z|00152|timeval|WARN|faults: 826 minor, 6191 major
2026-06-22T13:50:19.796Z|00153|timeval|WARN|disk: 1568016 reads, 8 writes
2026-06-22T13:50:19.837Z|00154|timeval|WARN|context switches: 6335 voluntary, 1683 involuntary
2026-06-22T13:50:19.943Z|00093|timeval(ovn_pinctrl0)|WARN|Unreasonably long 6276ms poll interval (0ms user, 641ms system)
2026-06-22T13:50:20.046Z|00094|timeval(ovn_pinctrl0)|WARN|faults: 98 minor, 597 major
2026-06-22T13:50:20.060Z|00155|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=8e963d35:
2026-06-22T13:50:20.060Z|00095|timeval(ovn_pinctrl0)|WARN|disk: 147256 reads, 8 writes
2026-06-22T13:50:20.095Z|00096|timeval(ovn_pinctrl0)|WARN|context switches: 614 voluntary, 242 involuntary
2026-06-22T13:50:20.093Z|00156|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:50:20.177Z|00157|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:50:20.234Z|00158|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:50:20.364Z|00159|coverage|INFO|vconn_sent                 0.8/sec    55.867/sec        4.0631/sec   total: 14631
2026-06-22T13:50:20.410Z|00097|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:89:5f:36 10.1.0.4
2026-06-22T13:50:20.461Z|00160|coverage|INFO|vconn_received             0.6/sec     2.533/sec        0.1769/sec   total: 639
2026-06-22T13:50:20.595Z|00161|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:50:20.794Z|00162|coverage|INFO|util_xalloc              593.6/sec 19586.050/sec     1353.9439/sec   total: 4875161
2026-06-22T13:50:20.960Z|00163|coverage|INFO|long_poll_interval         0.0/sec     0.000/sec        0.0003/sec   total: 3
2026-06-22T13:50:21.103Z|00164|coverage|INFO|stream_open                0.0/sec     0.050/sec        0.0028/sec   total: 10
2026-06-22T13:50:21.191Z|00165|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:50:21.445Z|00166|coverage|INFO|seq_change                 1.8/sec    24.500/sec        2.1047/sec   total: 7584
2026-06-22T13:50:21.555Z|00167|coverage|INFO|rconn_sent                 0.8/sec    55.867/sec        4.0619/sec   total: 14627
2026-06-22T13:50:21.720Z|00168|coverage|INFO|rconn_queued               0.8/sec    55.867/sec        4.0619/sec   total: 14627
2026-06-22T13:50:22.047Z|00169|coverage|INFO|poll_zero_timeout          0.2/sec     0.417/sec        0.0386/sec   total: 139
2026-06-22T13:50:22.225Z|00170|coverage|INFO|poll_create_node           8.4/sec    73.033/sec        5.9389/sec   total: 21402
2026-06-22T13:50:22.435Z|00171|coverage|INFO|txn_try_again              0.0/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T13:50:22.618Z|00172|coverage|INFO|txn_success                0.0/sec     0.433/sec        0.0319/sec   total: 115
2026-06-22T13:50:22.726Z|00173|coverage|INFO|txn_incomplete             0.0/sec     0.600/sec        0.0508/sec   total: 183
2026-06-22T13:50:22.921Z|00174|coverage|INFO|txn_unchanged              1.2/sec     6.750/sec        0.5025/sec   total: 1811
2026-06-22T13:50:23.128Z|00175|coverage|INFO|hmap_reserve               2.2/sec     6.383/sec        0.1844/sec   total: 668
2026-06-22T13:50:23.396Z|00176|coverage|INFO|hmap_expand               12.8/sec   316.033/sec       21.8994/sec   total: 78856
2026-06-22T13:50:23.569Z|00177|coverage|INFO|hmap_pathological          0.2/sec     4.050/sec        0.3153/sec   total: 1135
2026-06-22T13:50:23.747Z|00178|coverage|INFO|miniflow_malloc            0.0/sec   615.450/sec       45.5142/sec   total: 163851
2026-06-22T13:50:23.905Z|00179|coverage|INFO|flow_extract               0.0/sec     0.033/sec        0.0006/sec   total: 3
2026-06-22T13:50:24.070Z|00180|coverage|INFO|physical_run               0.0/sec     0.433/sec        0.0303/sec   total: 109
2026-06-22T13:50:24.191Z|00181|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.033/sec        0.0006/sec   total: 3
2026-06-22T13:50:24.438Z|00182|coverage|INFO|lflow_conj_free            0.0/sec     0.067/sec        0.0025/sec   total: 9
2026-06-22T13:50:24.645Z|00183|coverage|INFO|lflow_conj_alloc           0.0/sec     0.150/sec        0.0064/sec   total: 23
2026-06-22T13:50:24.882Z|00184|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:50:25.087Z|00185|coverage|INFO|lflow_cache_delete         0.0/sec    19.983/sec        1.5986/sec   total: 5755
2026-06-22T13:50:25.307Z|00186|coverage|INFO|lflow_cache_miss           0.0/sec    45.250/sec        3.3533/sec   total: 12072
2026-06-22T13:50:25.671Z|00187|coverage|INFO|lflow_cache_hit            0.0/sec   277.817/sec       21.6603/sec   total: 77977
2026-06-22T13:50:26.048Z|00188|coverage|INFO|lflow_cache_add            0.0/sec    26.267/sec        1.9689/sec   total: 7088
2026-06-22T13:50:26.264Z|00189|coverage|INFO|lflow_cache_free_matches   0.0/sec    17.333/sec        1.3067/sec   total: 4704
2026-06-22T13:50:26.544Z|00190|coverage|INFO|lflow_cache_free_expr      0.0/sec     2.650/sec        0.2919/sec   total: 1051
2026-06-22T13:50:26.745Z|00191|coverage|INFO|lflow_cache_add_matches    0.0/sec    18.383/sec        1.4153/sec   total: 5095
2026-06-22T13:50:27.157Z|00192|coverage|INFO|lflow_cache_add_expr       0.0/sec     7.883/sec        0.5536/sec   total: 1993
2026-06-22T13:50:27.467Z|00193|coverage|INFO|consider_logical_flow      0.0/sec    83.767/sec        6.4797/sec   total: 23327
2026-06-22T13:50:27.905Z|00194|coverage|INFO|lflow_run                  0.0/sec     0.050/sec        0.0042/sec   total: 15
2026-06-22T13:50:28.646Z|00195|coverage|INFO|112 events never hit
2026-06-22T13:50:30.330Z|00196|poll_loop|INFO|wakeup due to 96-ms timeout at lib/reconnect.c:677 (80% CPU usage)
2026-06-22T13:50:44.154Z|00197|memory_trim|INFO|Detected inactivity (last active 43864 ms ago): trimming memory
2026-06-22T13:50:44.155Z|00198|timeval|WARN|Unreasonably long 14276ms poll interval (1ms user, 13195ms system)
2026-06-22T13:50:44.155Z|00199|timeval|WARN|faults: 278 minor, 3021 major
2026-06-22T13:50:44.155Z|00200|timeval|WARN|disk: 824904 reads, 16 writes
2026-06-22T13:50:44.155Z|00201|timeval|WARN|context switches: 3082 voluntary, 10655 involuntary
2026-06-22T13:50:44.155Z|00202|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (94% CPU usage)
2026-06-22T13:50:44.156Z|00203|reconnect|ERR|ssl:10.4.3.218:6642: no response to inactivity probe after 32.6 seconds, disconnecting
2026-06-22T13:50:44.156Z|00204|reconnect|INFO|ssl:10.4.3.218:6642: connection dropped
2026-06-22T13:50:44.156Z|00205|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T13:50:44.156Z|00206|poll_loop|INFO|wakeup due to 0-ms timeout at lib/inc-proc-eng.c:66 (94% CPU usage)
2026-06-22T13:50:44.156Z|00207|poll_loop|INFO|wakeup due to 0-ms timeout at lib/inc-proc-eng.c:66 (94% CPU usage)
2026-06-22T13:50:45.158Z|00208|poll_loop|INFO|wakeup due to 1002-ms timeout at lib/reconnect.c:677 (94% CPU usage)
2026-06-22T13:50:45.158Z|00209|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:50:45.161Z|00210|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:36300<->10.4.3.218:6642) at lib/stream-ssl.c:830 (94% CPU usage)
2026-06-22T13:50:45.163Z|00211|reconnect|INFO|ssl:10.4.3.218:6642: connected
2026-06-22T13:50:45.164Z|00212|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:36300<->10.4.3.218:6642) at lib/stream-ssl.c:842 (94% CPU usage)
2026-06-22T13:50:45.165Z|00213|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:36300<->10.4.3.218:6642) at lib/stream-ssl.c:842 (94% CPU usage)
2026-06-22T13:50:45.166Z|00214|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:36300<->10.4.3.218:6642) at lib/stream-ssl.c:842 (94% CPU usage)
2026-06-22T13:50:45.185Z|00215|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:36300<->10.4.3.218:6642) at lib/stream-ssl.c:842 (94% CPU usage)
2026-06-22T13:50:45.187Z|00216|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (94% CPU usage)
2026-06-22T13:50:45.189Z|00217|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (94% CPU usage)
2026-06-22T13:50:45.191Z|00218|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (94% CPU usage)
2026-06-22T13:50:45.210Z|00219|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:50:45.211Z|00220|binding|INFO|Releasing lport e99f244d-c0ac-4fc5-8661-38f077585ad6 from this chassis (sb_readonly=0)
2026-06-22T13:50:45.211Z|00221|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:50:45.244Z|00222|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (94% CPU usage)
2026-06-22T13:50:46.645Z|00223|binding|INFO|Claiming lport 3ae9b523-4c17-4553-96c4-d6319bf16a75 for this chassis.
2026-06-22T13:50:46.645Z|00224|binding|INFO|3ae9b523-4c17-4553-96c4-d6319bf16a75: Claiming fa:16:3e:46:2b:a4 10.1.0.7
2026-06-22T13:50:46.666Z|00225|binding|INFO|Setting lport 3ae9b523-4c17-4553-96c4-d6319bf16a75 ovn-installed in OVS
2026-06-22T13:50:46.666Z|00226|binding|INFO|Setting lport 3ae9b523-4c17-4553-96c4-d6319bf16a75 up in Southbound
2026-06-22T13:50:47.269Z|00227|binding|INFO|Releasing lport c895190b-480e-4155-bef7-a961af0e599a from this chassis (sb_readonly=0)
2026-06-22T13:50:53.853Z|00228|timeval|WARN|Unreasonably long 1739ms poll interval (8ms user, 1293ms system)
2026-06-22T13:50:53.878Z|00229|timeval|WARN|faults: 274 minor, 1915 major
2026-06-22T13:50:53.910Z|00230|timeval|WARN|disk: 457048 reads, 0 writes
2026-06-22T13:50:53.932Z|00231|timeval|WARN|context switches: 1924 voluntary, 216 involuntary
2026-06-22T13:50:53.958Z|00232|coverage|INFO|Dropped 2 log messages in last 34 seconds (most recently, 10 seconds ago) due to excessive rate
2026-06-22T13:50:53.964Z|00233|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=a60529e2:
2026-06-22T13:50:53.964Z|00234|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:50:53.964Z|00235|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:50:53.964Z|00236|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:50:53.964Z|00237|coverage|INFO|vconn_sent                 1.2/sec    29.683/sec        4.0658/sec   total: 14929
2026-06-22T13:50:53.964Z|00238|coverage|INFO|vconn_received             0.8/sec     1.783/sec        0.1786/sec   total: 661
2026-06-22T13:50:53.964Z|00239|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:50:53.964Z|00240|coverage|INFO|util_xalloc              53303.6/sec 16099.067/sec     1428.2497/sec   total: 5210997
2026-06-22T13:50:53.964Z|00241|coverage|INFO|long_poll_interval         0.6/sec     0.083/sec        0.0017/sec   total: 6
2026-06-22T13:50:53.964Z|00242|coverage|INFO|stream_open                0.2/sec     0.017/sec        0.0031/sec   total: 11
2026-06-22T13:50:53.964Z|00243|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:50:53.964Z|00244|coverage|INFO|seq_change                35.6/sec    17.683/sec        2.1575/sec   total: 7796
2026-06-22T13:50:53.964Z|00245|coverage|INFO|rconn_sent                 1.2/sec    29.683/sec        4.0647/sec   total: 14925
2026-06-22T13:50:53.964Z|00246|coverage|INFO|rconn_queued               1.2/sec    29.683/sec        4.0647/sec   total: 14925
2026-06-22T13:50:53.964Z|00247|coverage|INFO|poll_zero_timeout          1.4/sec     0.333/sec        0.0406/sec   total: 149
2026-06-22T13:50:53.964Z|00248|coverage|INFO|poll_create_node          90.0/sec    51.800/sec        6.0728/sec   total: 22058
2026-06-22T13:50:53.964Z|00249|coverage|INFO|txn_try_again              0.2/sec     0.017/sec        0.0006/sec   total: 2
2026-06-22T13:50:53.964Z|00250|coverage|INFO|txn_success                0.0/sec     0.317/sec        0.0319/sec   total: 122
2026-06-22T13:50:53.964Z|00251|coverage|INFO|txn_incomplete             0.4/sec     0.467/sec        0.0514/sec   total: 192
2026-06-22T13:50:53.964Z|00252|coverage|INFO|txn_unchanged              3.8/sec     4.433/sec        0.5083/sec   total: 1871
2026-06-22T13:50:53.964Z|00253|coverage|INFO|hmap_reserve              13.6/sec     6.100/sec        0.2044/sec   total: 832
2026-06-22T13:50:53.964Z|00254|coverage|INFO|hmap_shrink                0.2/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T13:50:53.964Z|00255|coverage|INFO|hmap_expand              1038.2/sec   269.550/sec       23.3464/sec   total: 85145
2026-06-22T13:50:53.964Z|00256|coverage|INFO|hmap_pathological          5.2/sec     3.017/sec        0.3225/sec   total: 1178
2026-06-22T13:50:53.964Z|00257|coverage|INFO|miniflow_malloc          1024.6/sec   422.750/sec       46.9372/sec   total: 170509
2026-06-22T13:50:53.964Z|00258|coverage|INFO|flow_extract               0.2/sec     0.033/sec        0.0011/sec   total: 4
2026-06-22T13:50:53.964Z|00259|coverage|INFO|physical_run               0.2/sec     0.250/sec        0.0306/sec   total: 112
2026-06-22T13:50:53.964Z|00260|coverage|INFO|pinctrl_total_pin_pkts     0.2/sec     0.033/sec        0.0011/sec   total: 4
2026-06-22T13:50:53.964Z|00261|coverage|INFO|lflow_conj_free            0.0/sec     0.067/sec        0.0025/sec   total: 11
2026-06-22T13:50:53.964Z|00262|coverage|INFO|lflow_conj_alloc           0.6/sec     0.167/sec        0.0072/sec   total: 29
2026-06-22T13:50:53.964Z|00263|coverage|INFO|lflow_cache_trim           0.2/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T13:50:53.964Z|00264|coverage|INFO|lflow_cache_delete         0.0/sec    10.100/sec        1.5986/sec   total: 5766
2026-06-22T13:50:53.964Z|00265|coverage|INFO|lflow_cache_miss          30.6/sec    28.183/sec        3.3958/sec   total: 12382
2026-06-22T13:50:53.964Z|00266|coverage|INFO|lflow_cache_hit          627.0/sec   198.150/sec       22.5311/sec   total: 81267
2026-06-22T13:50:53.964Z|00267|coverage|INFO|lflow_cache_add            0.0/sec    15.533/sec        1.9689/sec   total: 7150
2026-06-22T13:50:53.964Z|00268|coverage|INFO|lflow_cache_free_matches   0.0/sec     9.667/sec        1.3067/sec   total: 4709
2026-06-22T13:50:53.964Z|00269|coverage|INFO|lflow_cache_free_expr      0.0/sec     0.433/sec        0.2919/sec   total: 1057
2026-06-22T13:50:53.964Z|00270|coverage|INFO|lflow_cache_add_matches    0.0/sec    10.650/sec        1.4153/sec   total: 5108
2026-06-22T13:50:53.964Z|00271|coverage|INFO|lflow_cache_add_expr       0.0/sec     4.883/sec        0.5536/sec   total: 2042
2026-06-22T13:50:53.964Z|00272|coverage|INFO|consider_logical_flow    282.6/sec    59.700/sec        6.8722/sec   total: 24854
2026-06-22T13:50:53.964Z|00273|coverage|INFO|lflow_run                  0.2/sec     0.033/sec        0.0044/sec   total: 16
2026-06-22T13:50:53.964Z|00274|coverage|INFO|111 events never hit
2026-06-22T13:50:56.384Z|00275|timeval|WARN|Unreasonably long 2415ms poll interval (7ms user, 1860ms system)
2026-06-22T13:50:56.396Z|00276|timeval|WARN|faults: 309 minor, 2283 major
2026-06-22T13:50:56.431Z|00277|timeval|WARN|disk: 558232 reads, 0 writes
2026-06-22T13:50:56.444Z|00278|timeval|WARN|context switches: 2298 voluntary, 383 involuntary
2026-06-22T13:51:06.667Z|00279|timeval|WARN|Unreasonably long 7590ms poll interval (8ms user, 6059ms system)
2026-06-22T13:51:06.691Z|00280|timeval|WARN|faults: 694 minor, 5886 major
2026-06-22T13:51:06.789Z|00281|timeval|WARN|disk: 1514400 reads, 0 writes
2026-06-22T13:51:06.923Z|00282|timeval|WARN|context switches: 5973 voluntary, 1674 involuntary
2026-06-22T13:51:07.056Z|00283|poll_loop|INFO|Dropped 21 log messages in last 22 seconds (most recently, 20 seconds ago) due to excessive rate
2026-06-22T13:51:07.106Z|00284|poll_loop|INFO|wakeup due to 9-ms timeout at lib/reconnect.c:677 (80% CPU usage)
2026-06-22T13:51:07.468Z|00285|reconnect|ERR|ssl:10.4.3.218:6642: no response to inactivity probe after 13.3 seconds, disconnecting
2026-06-22T13:51:07.496Z|00286|reconnect|INFO|ssl:10.4.3.218:6642: connection dropped
2026-06-22T13:51:07.513Z|00098|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:31:60:0e 10.1.0.7
2026-06-22T13:51:07.772Z|00099|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:31:60:0e 10.1.0.7
2026-06-22T13:51:16.623Z|00100|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:46:2b:a4 10.1.0.7
2026-06-22T13:51:16.913Z|00287|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T13:51:17.195Z|00101|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:46:2b:a4 10.1.0.7
2026-06-22T13:51:17.293Z|00288|timeval|WARN|Unreasonably long 10166ms poll interval (17ms user, 6900ms system)
2026-06-22T13:51:17.458Z|00289|timeval|WARN|faults: 805 minor, 7036 major
2026-06-22T13:51:17.590Z|00290|timeval|WARN|disk: 1793480 reads, 40 writes
2026-06-22T13:51:17.590Z|00291|timeval|WARN|context switches: 7129 voluntary, 2015 involuntary
2026-06-22T13:51:17.590Z|00292|poll_loop|INFO|wakeup due to [POLLIN] on fd 30 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (68% CPU usage)
2026-06-22T13:51:17.676Z|00102|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:46:2b:a4 10.1.0.7
2026-06-22T13:51:17.974Z|00103|timeval(ovn_pinctrl0)|WARN|Unreasonably long 6658ms poll interval (1ms user, 979ms system)
2026-06-22T13:51:18.046Z|00104|timeval(ovn_pinctrl0)|WARN|faults: 163 minor, 942 major
2026-06-22T13:51:18.177Z|00105|timeval(ovn_pinctrl0)|WARN|disk: 235344 reads, 24 writes
2026-06-22T13:51:18.236Z|00106|timeval(ovn_pinctrl0)|WARN|context switches: 972 voluntary, 460 involuntary
2026-06-22T13:51:18.236Z|00293|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:51:19.614Z|00294|memory_trim|INFO|Detected inactivity (last active 32339 ms ago): trimming memory
2026-06-22T13:51:19.773Z|00295|timeval|WARN|Unreasonably long 2121ms poll interval (4ms user, 1378ms system)
2026-06-22T13:51:19.838Z|00296|timeval|WARN|faults: 164 minor, 1362 major
2026-06-22T13:51:19.996Z|00297|timeval|WARN|disk: 339512 reads, 8 writes
2026-06-22T13:51:20.154Z|00298|timeval|WARN|context switches: 1380 voluntary, 404 involuntary
2026-06-22T13:51:20.284Z|00299|poll_loop|INFO|wakeup due to 24-ms timeout at lib/inc-proc-eng.c:66 (68% CPU usage)
2026-06-22T13:51:20.720Z|00300|reconnect|INFO|ssl:10.4.3.218:6642: connection attempt timed out
2026-06-22T13:51:20.839Z|00301|reconnect|INFO|ssl:10.4.3.218:6642: waiting 2 seconds before reconnect
2026-06-22T13:51:22.335Z|00302|timeval|WARN|Unreasonably long 2024ms poll interval (4ms user, 1396ms system)
2026-06-22T13:51:22.354Z|00303|timeval|WARN|faults: 145 minor, 1477 major
2026-06-22T13:51:22.479Z|00304|timeval|WARN|disk: 383272 reads, 24 writes
2026-06-22T13:51:22.552Z|00305|timeval|WARN|context switches: 1494 voluntary, 393 involuntary
2026-06-22T13:51:22.733Z|00306|poll_loop|INFO|wakeup due to 58-ms timeout at lib/reconnect.c:677 (66% CPU usage)
2026-06-22T13:51:23.163Z|00307|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:51:24.589Z|00308|timeval|WARN|Unreasonably long 1872ms poll interval (4ms user, 1264ms system)
2026-06-22T13:51:24.712Z|00309|timeval|WARN|faults: 165 minor, 1335 major
2026-06-22T13:51:24.804Z|00310|timeval|WARN|disk: 348296 reads, 16 writes
2026-06-22T13:51:24.869Z|00311|timeval|WARN|context switches: 1352 voluntary, 284 involuntary
2026-06-22T13:51:25.233Z|00312|poll_loop|INFO|wakeup due to 219-ms timeout at lib/reconnect.c:677 (66% CPU usage)
2026-06-22T13:51:25.645Z|00313|reconnect|INFO|ssl:10.4.3.218:6642: connection attempt timed out
2026-06-22T13:51:25.763Z|00314|reconnect|INFO|ssl:10.4.3.218:6642: waiting 4 seconds before reconnect
2026-06-22T13:51:27.031Z|00315|timeval|WARN|Unreasonably long 1825ms poll interval (2ms user, 1143ms system)
2026-06-22T13:51:27.129Z|00316|timeval|WARN|faults: 141 minor, 1302 major
2026-06-22T13:51:27.159Z|00317|timeval|WARN|disk: 339488 reads, 24 writes
2026-06-22T13:51:27.194Z|00318|timeval|WARN|context switches: 1307 voluntary, 283 involuntary
2026-06-22T13:51:33.426Z|00319|reconnect|INFO|ssl:10.4.3.218:6642: connecting...
2026-06-22T13:51:45.790Z|00320|timeval|WARN|Unreasonably long 16070ms poll interval (2ms user, 15228ms system)
2026-06-22T13:51:45.791Z|00321|timeval|WARN|faults: 208 minor, 2453 major
2026-06-22T13:51:45.791Z|00322|timeval|WARN|disk: 664952 reads, 8 writes
2026-06-22T13:51:45.791Z|00323|timeval|WARN|context switches: 2509 voluntary, 12810 involuntary
2026-06-22T13:51:45.791Z|00324|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (95% CPU usage)
2026-06-22T13:51:45.791Z|00325|reconnect|INFO|ssl:10.4.3.218:6642: connection attempt timed out
2026-06-22T13:51:45.791Z|00326|reconnect|INFO|ssl:10.4.3.218:6642: continuing to reconnect in the background but suppressing further logging
2026-06-22T13:51:47.295Z|00327|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (95% CPU usage)
2026-06-22T13:51:59.341Z|00328|timeval|WARN|Unreasonably long 4523ms poll interval (1ms user, 4131ms system)
2026-06-22T13:51:59.341Z|00329|timeval|WARN|faults: 22 minor, 154 major
2026-06-22T13:51:59.341Z|00330|timeval|WARN|disk: 36576 reads, 0 writes
2026-06-22T13:51:59.341Z|00331|timeval|WARN|context switches: 156 voluntary, 3778 involuntary
2026-06-22T13:51:59.341Z|00332|coverage|INFO|Dropped 9 log messages in last 63 seconds (most recently, 14 seconds ago) due to excessive rate
2026-06-22T13:51:59.341Z|00333|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=cbae0e11:
2026-06-22T13:51:59.341Z|00334|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:51:59.341Z|00335|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:51:59.341Z|00336|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:51:59.341Z|00337|coverage|INFO|vconn_sent                 0.2/sec     5.217/sec        4.1500/sec   total: 14940
2026-06-22T13:51:59.341Z|00338|coverage|INFO|vconn_received             0.2/sec     0.633/sec        0.1875/sec   total: 675
2026-06-22T13:51:59.341Z|00339|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:51:59.341Z|00340|coverage|INFO|util_xalloc               80.6/sec  5679.017/sec     1448.8119/sec   total: 5215973
2026-06-22T13:51:59.341Z|00341|coverage|INFO|long_poll_interval         0.4/sec     0.250/sec        0.0044/sec   total: 16
2026-06-22T13:51:59.341Z|00342|coverage|INFO|stream_open                0.2/sec     0.067/sec        0.0039/sec   total: 15
2026-06-22T13:51:59.341Z|00343|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:51:59.341Z|00344|coverage|INFO|seq_change                 1.0/sec     4.700/sec        2.1853/sec   total: 7868
2026-06-22T13:51:59.341Z|00345|coverage|INFO|rconn_sent                 0.2/sec     5.217/sec        4.1489/sec   total: 14936
2026-06-22T13:51:59.341Z|00346|coverage|INFO|rconn_queued               0.2/sec     5.217/sec        4.1489/sec   total: 14936
2026-06-22T13:51:59.341Z|00347|coverage|INFO|poll_zero_timeout          0.2/sec     0.233/sec        0.0425/sec   total: 153
2026-06-22T13:51:59.341Z|00348|coverage|INFO|poll_create_node           3.8/sec    14.483/sec        6.1869/sec   total: 22281
2026-06-22T13:51:59.341Z|00349|coverage|INFO|txn_try_again              0.0/sec     0.033/sec        0.0008/sec   total: 3
2026-06-22T13:51:59.341Z|00350|coverage|INFO|txn_success                0.0/sec     0.117/sec        0.0339/sec   total: 122
2026-06-22T13:51:59.341Z|00351|coverage|INFO|txn_incomplete             0.0/sec     0.150/sec        0.0533/sec   total: 192
2026-06-22T13:51:59.341Z|00352|coverage|INFO|txn_unchanged              0.4/sec     1.233/sec        0.5231/sec   total: 1884
2026-06-22T13:51:59.341Z|00353|coverage|INFO|hmap_reserve               1.2/sec     3.467/sec        0.2422/sec   total: 876
2026-06-22T13:51:59.341Z|00354|coverage|INFO|hmap_shrink                0.0/sec     0.017/sec        0.0003/sec   total: 1
2026-06-22T13:51:59.341Z|00355|coverage|INFO|hmap_expand                1.8/sec   106.467/sec       23.6772/sec   total: 85244
2026-06-22T13:51:59.341Z|00356|coverage|INFO|hmap_pathological          0.0/sec     0.717/sec        0.3272/sec   total: 1178
2026-06-22T13:51:59.341Z|00357|coverage|INFO|miniflow_malloc            0.0/sec   110.967/sec       47.3636/sec   total: 170509
2026-06-22T13:51:59.341Z|00358|coverage|INFO|flow_extract               0.0/sec     0.117/sec        0.0025/sec   total: 9
2026-06-22T13:51:59.341Z|00359|coverage|INFO|physical_run               0.0/sec     0.050/sec        0.0311/sec   total: 112
2026-06-22T13:51:59.341Z|00360|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.117/sec        0.0025/sec   total: 9
2026-06-22T13:51:59.341Z|00361|coverage|INFO|lflow_conj_free            0.0/sec     0.033/sec        0.0031/sec   total: 11
2026-06-22T13:51:59.341Z|00362|coverage|INFO|lflow_conj_alloc           0.0/sec     0.100/sec        0.0081/sec   total: 29
2026-06-22T13:51:59.341Z|00363|coverage|INFO|lflow_cache_trim           0.0/sec     0.033/sec        0.0011/sec   total: 4
2026-06-22T13:51:59.341Z|00364|coverage|INFO|lflow_cache_delete         0.0/sec     0.183/sec        1.6017/sec   total: 5766
2026-06-22T13:51:59.341Z|00365|coverage|INFO|lflow_cache_miss           0.0/sec     5.167/sec        3.4394/sec   total: 12382
2026-06-22T13:51:59.341Z|00366|coverage|INFO|lflow_cache_hit            0.0/sec    54.833/sec       22.5742/sec   total: 81267
2026-06-22T13:51:59.341Z|00367|coverage|INFO|lflow_cache_add            0.0/sec     1.033/sec        1.9861/sec   total: 7150
2026-06-22T13:51:59.341Z|00368|coverage|INFO|lflow_cache_free_matches   0.0/sec     0.083/sec        1.3081/sec   total: 4709
2026-06-22T13:51:59.341Z|00369|coverage|INFO|lflow_cache_free_expr      0.0/sec     0.100/sec        0.2936/sec   total: 1057
2026-06-22T13:51:59.341Z|00370|coverage|INFO|lflow_cache_add_matches    0.0/sec     0.217/sec        1.4189/sec   total: 5108
2026-06-22T13:51:59.341Z|00371|coverage|INFO|lflow_cache_add_expr       0.0/sec     0.817/sec        0.5672/sec   total: 2042
2026-06-22T13:51:59.341Z|00372|coverage|INFO|consider_logical_flow      0.0/sec    25.450/sec        6.9039/sec   total: 24854
2026-06-22T13:51:59.341Z|00373|coverage|INFO|lflow_run                  0.0/sec     0.017/sec        0.0044/sec   total: 16
2026-06-22T13:51:59.341Z|00374|coverage|INFO|111 events never hit
2026-06-22T13:51:59.640Z|00375|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:830 (100% CPU usage)
2026-06-22T13:51:59.642Z|00376|reconnect|INFO|ssl:10.4.3.218:6642: connected
2026-06-22T13:51:59.646Z|00377|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (100% CPU usage)
2026-06-22T13:51:59.646Z|00378|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (100% CPU usage)
2026-06-22T13:51:59.646Z|00379|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (100% CPU usage)
2026-06-22T13:51:59.648Z|00380|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (100% CPU usage)
2026-06-22T13:51:59.671Z|00381|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (100% CPU usage)
2026-06-22T13:51:59.693Z|00382|binding|INFO|Releasing lport c895190b-480e-4155-bef7-a961af0e599a from this chassis (sb_readonly=0)
2026-06-22T13:51:59.694Z|00383|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:51:59.694Z|00384|binding|INFO|Releasing lport e99f244d-c0ac-4fc5-8661-38f077585ad6 from this chassis (sb_readonly=0)
2026-06-22T13:51:59.694Z|00385|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:52:23.227Z|00004|timeval(ovn_statctrl3)|WARN|Unreasonably long 11641ms poll interval (1ms user, 6546ms system)
2026-06-22T13:52:23.227Z|00005|timeval(ovn_statctrl3)|WARN|faults: 29 minor, 302 major
2026-06-22T13:52:23.227Z|00006|timeval(ovn_statctrl3)|WARN|disk: 75328 reads, 0 writes
2026-06-22T13:52:23.227Z|00007|timeval(ovn_statctrl3)|WARN|context switches: 311 voluntary, 6962 involuntary
2026-06-22T13:52:23.227Z|00008|poll_loop(ovn_statctrl3)|INFO|Dropped 12 log messages in last 23 seconds (most recently, 23 seconds ago) due to excessive rate
2026-06-22T13:52:23.227Z|00009|poll_loop(ovn_statctrl3)|INFO|wakeup due to [POLLIN] on fd 36 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (63% CPU usage)
2026-06-22T13:52:23.233Z|00386|timeval|WARN|Unreasonably long 14899ms poll interval (3ms user, 9572ms system)
2026-06-22T13:52:23.233Z|00387|timeval|WARN|faults: 35 minor, 482 major
2026-06-22T13:52:23.234Z|00388|timeval|WARN|disk: 117416 reads, 0 writes
2026-06-22T13:52:23.234Z|00389|timeval|WARN|context switches: 490 voluntary, 10417 involuntary
2026-06-22T13:52:23.234Z|00390|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (65% CPU usage)
2026-06-22T13:52:23.235Z|00391|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (65% CPU usage)
2026-06-22T13:52:26.187Z|00392|poll_loop|INFO|Dropped 1 log messages in last 3 seconds (most recently, 3 seconds ago) due to excessive rate
2026-06-22T13:52:26.187Z|00393|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (65% CPU usage)
2026-06-22T13:52:26.798Z|00394|binding|INFO|Releasing lport c895190b-480e-4155-bef7-a961af0e599a from this chassis (sb_readonly=0)
2026-06-22T13:52:26.798Z|00395|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:52:26.798Z|00396|binding|INFO|Releasing lport e99f244d-c0ac-4fc5-8661-38f077585ad6 from this chassis (sb_readonly=0)
2026-06-22T13:52:26.798Z|00397|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:52:42.622Z|00398|timeval|WARN|Unreasonably long 10763ms poll interval (3ms user, 9197ms system)
2026-06-22T13:52:42.623Z|00399|timeval|WARN|faults: 200 minor, 1841 major
2026-06-22T13:52:42.623Z|00400|timeval|WARN|disk: 461200 reads, 0 writes
2026-06-22T13:52:42.623Z|00401|timeval|WARN|context switches: 1856 voluntary, 8097 involuntary
2026-06-22T13:52:42.623Z|00402|poll_loop|INFO|Dropped 2 log messages in last 17 seconds (most recently, 17 seconds ago) due to excessive rate
2026-06-22T13:52:42.623Z|00403|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (85% CPU usage)
2026-06-22T13:52:42.633Z|00404|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (85% CPU usage)
2026-06-22T13:52:42.636Z|00405|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (85% CPU usage)
2026-06-22T13:52:54.493Z|00406|binding|INFO|Releasing lport c895190b-480e-4155-bef7-a961af0e599a from this chassis (sb_readonly=0)
2026-06-22T13:52:54.493Z|00407|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:52:54.493Z|00408|binding|INFO|Releasing lport e99f244d-c0ac-4fc5-8661-38f077585ad6 from this chassis (sb_readonly=0)
2026-06-22T13:52:54.493Z|00409|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:52:55.102Z|00410|binding|INFO|Releasing lport c895190b-480e-4155-bef7-a961af0e599a from this chassis (sb_readonly=0)
2026-06-22T13:52:55.102Z|00411|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:52:55.102Z|00412|binding|INFO|Releasing lport e99f244d-c0ac-4fc5-8661-38f077585ad6 from this chassis (sb_readonly=0)
2026-06-22T13:52:55.102Z|00413|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:52:59.833Z|00414|binding|INFO|Releasing lport c895190b-480e-4155-bef7-a961af0e599a from this chassis (sb_readonly=0)
2026-06-22T13:52:59.833Z|00415|binding|INFO|Releasing lport f836d09d-53e8-4ad8-bd8f-394e830e8043 from this chassis (sb_readonly=0)
2026-06-22T13:52:59.833Z|00416|binding|INFO|Releasing lport e99f244d-c0ac-4fc5-8661-38f077585ad6 from this chassis (sb_readonly=0)
2026-06-22T13:52:59.833Z|00417|binding|INFO|Releasing lport ef96aeaf-bdcb-435d-9a93-afc5aa997c49 from this chassis (sb_readonly=0)
2026-06-22T13:53:07.166Z|00418|inc_proc_eng|INFO|node: logical_flow_output, handler for input SB_logical_flow took 985ms
2026-06-22T13:53:07.168Z|00419|timeval|WARN|Unreasonably long 1052ms poll interval (9ms user, 892ms system)
2026-06-22T13:53:07.168Z|00420|timeval|WARN|faults: 87 minor, 267 major
2026-06-22T13:53:07.168Z|00421|timeval|WARN|disk: 58352 reads, 8 writes
2026-06-22T13:53:07.168Z|00422|timeval|WARN|context switches: 267 voluntary, 874 involuntary
2026-06-22T13:53:07.168Z|00423|coverage|INFO|Dropped 3 log messages in last 44 seconds (most recently, 24 seconds ago) due to excessive rate
2026-06-22T13:53:07.169Z|00424|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=c96a028d:
2026-06-22T13:53:07.169Z|00425|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:53:07.169Z|00426|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:53:07.169Z|00427|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:53:07.169Z|00428|coverage|INFO|vconn_sent                96.8/sec    24.267/sec        4.5539/sec   total: 16518
2026-06-22T13:53:07.169Z|00429|coverage|INFO|vconn_received             2.4/sec     0.717/sec        0.1992/sec   total: 723
2026-06-22T13:53:07.169Z|00430|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:53:07.169Z|00431|coverage|INFO|util_xalloc              49591.0/sec 11773.217/sec     1644.8083/sec   total: 6059787
2026-06-22T13:53:07.169Z|00432|coverage|INFO|long_poll_interval         0.0/sec     0.100/sec        0.0053/sec   total: 19
2026-06-22T13:53:07.169Z|00433|coverage|INFO|stream_open                0.0/sec     0.033/sec        0.0042/sec   total: 15
2026-06-22T13:53:07.169Z|00434|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:53:07.169Z|00435|coverage|INFO|seq_change                16.4/sec     7.433/sec        2.3064/sec   total: 8318
2026-06-22T13:53:07.169Z|00436|coverage|INFO|rconn_sent                96.8/sec    24.267/sec        4.5528/sec   total: 16514
2026-06-22T13:53:07.169Z|00437|coverage|INFO|rconn_queued              96.8/sec    24.267/sec        4.5528/sec   total: 16514
2026-06-22T13:53:07.169Z|00438|coverage|INFO|poll_zero_timeout          0.4/sec     0.217/sec        0.0458/sec   total: 166
2026-06-22T13:53:07.169Z|00439|coverage|INFO|poll_create_node          58.4/sec    22.867/sec        6.5575/sec   total: 23691
2026-06-22T13:53:07.169Z|00440|coverage|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:53:07.169Z|00441|coverage|INFO|txn_success                0.8/sec     0.117/sec        0.0358/sec   total: 130
2026-06-22T13:53:07.169Z|00442|coverage|INFO|txn_incomplete             0.6/sec     0.150/sec        0.0558/sec   total: 202
2026-06-22T13:53:07.169Z|00443|coverage|INFO|txn_unchanged              8.2/sec     2.317/sec        0.5608/sec   total: 2036
2026-06-22T13:53:07.169Z|00444|coverage|INFO|hmap_reserve              17.6/sec     5.400/sec        0.3289/sec   total: 1220
2026-06-22T13:53:07.169Z|00445|coverage|INFO|hmap_shrink                0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:53:07.169Z|00446|coverage|INFO|hmap_expand              954.0/sec   223.750/sec       27.4014/sec   total: 100961
2026-06-22T13:53:07.169Z|00447|coverage|INFO|hmap_pathological          4.6/sec     1.417/sec        0.3508/sec   total: 1292
2026-06-22T13:53:07.169Z|00448|coverage|INFO|miniflow_malloc          1765.4/sec   336.750/sec       52.9761/sec   total: 197463
2026-06-22T13:53:07.169Z|00449|coverage|INFO|flow_extract               0.0/sec     0.000/sec        0.0025/sec   total: 9
2026-06-22T13:53:07.169Z|00450|coverage|INFO|physical_run               0.4/sec     0.067/sec        0.0322/sec   total: 117
2026-06-22T13:53:07.169Z|00451|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0025/sec   total: 9
2026-06-22T13:53:07.169Z|00452|coverage|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0031/sec   total: 11
2026-06-22T13:53:07.169Z|00453|coverage|INFO|lflow_conj_alloc           1.6/sec     0.267/sec        0.0125/sec   total: 49
2026-06-22T13:53:07.169Z|00454|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:53:07.169Z|00455|coverage|INFO|lflow_cache_delete        20.6/sec     6.600/sec        1.7117/sec   total: 6322
2026-06-22T13:53:07.169Z|00456|coverage|INFO|lflow_cache_miss          67.4/sec    16.033/sec        3.7067/sec   total: 13747
2026-06-22T13:53:07.169Z|00457|coverage|INFO|lflow_cache_hit          1002.4/sec   196.917/sec       25.8561/sec   total: 96944
2026-06-22T13:53:07.169Z|00458|coverage|INFO|lflow_cache_add            0.6/sec     1.733/sec        2.0150/sec   total: 7388
2026-06-22T13:53:07.169Z|00459|coverage|INFO|lflow_cache_free_matches   4.6/sec     2.183/sec        1.3444/sec   total: 4977
2026-06-22T13:53:07.169Z|00460|coverage|INFO|lflow_cache_free_expr     16.0/sec     4.417/sec        0.3672/sec   total: 1345
2026-06-22T13:53:07.169Z|00461|coverage|INFO|lflow_cache_add_matches    0.0/sec     1.317/sec        1.4408/sec   total: 5319
2026-06-22T13:53:07.169Z|00462|coverage|INFO|lflow_cache_add_expr       0.6/sec     0.417/sec        0.5742/sec   total: 2069
2026-06-22T13:53:07.169Z|00463|coverage|INFO|consider_logical_flow    435.8/sec    80.633/sec        8.2478/sec   total: 30859
2026-06-22T13:53:07.169Z|00464|coverage|INFO|lflow_run                  0.4/sec     0.067/sec        0.0056/sec   total: 21
2026-06-22T13:53:07.169Z|00465|coverage|INFO|111 events never hit
2026-06-22T13:53:12.348Z|00466|timeval|WARN|Unreasonably long 1196ms poll interval (4ms user, 932ms system)
2026-06-22T13:53:12.372Z|00467|timeval|WARN|faults: 197 minor, 1284 major
2026-06-22T13:53:12.386Z|00468|timeval|WARN|disk: 304536 reads, 0 writes
2026-06-22T13:53:12.397Z|00469|timeval|WARN|context switches: 1285 voluntary, 143 involuntary
2026-06-22T13:53:39.484Z|00010|timeval(ovn_statctrl3)|WARN|Unreasonably long 14697ms poll interval (1ms user, 9537ms system)
2026-06-22T13:53:46.919Z|00011|timeval(ovn_statctrl3)|WARN|faults: 45 minor, 658 major
2026-06-22T13:53:46.919Z|00012|timeval(ovn_statctrl3)|WARN|disk: 169816 reads, 0 writes
2026-06-22T13:53:46.919Z|00013|timeval(ovn_statctrl3)|WARN|context switches: 672 voluntary, 9276 involuntary
2026-06-22T13:53:46.919Z|00014|poll_loop(ovn_statctrl3)|INFO|Dropped 10 log messages in last 62 seconds (most recently, 61 seconds ago) due to excessive rate
2026-06-22T13:53:46.919Z|00015|poll_loop(ovn_statctrl3)|INFO|wakeup due to [POLLIN] on fd 36 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (56% CPU usage)
2026-06-22T13:53:46.921Z|00470|memory_trim|INFO|Detected inactivity (last active 39755 ms ago): trimming memory
2026-06-22T13:53:46.922Z|00471|timeval|WARN|Unreasonably long 34507ms poll interval (4ms user, 22644ms system)
2026-06-22T13:53:46.922Z|00472|timeval|WARN|faults: 298 minor, 3127 major
2026-06-22T13:53:46.922Z|00473|timeval|WARN|disk: 813240 reads, 0 writes
2026-06-22T13:53:46.922Z|00474|timeval|WARN|context switches: 3191 voluntary, 21514 involuntary
2026-06-22T13:53:46.922Z|00475|poll_loop|INFO|wakeup due to [POLLIN] on fd 30 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (66% CPU usage)
2026-06-22T13:53:46.922Z|00476|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (66% CPU usage)
2026-06-22T13:53:46.926Z|00477|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (66% CPU usage)
2026-06-22T13:54:10.064Z|00478|timeval|WARN|Unreasonably long 10754ms poll interval (2ms user, 10313ms system)
2026-06-22T13:54:10.064Z|00479|timeval|WARN|faults: 48 minor, 665 major
2026-06-22T13:54:10.064Z|00480|timeval|WARN|disk: 171288 reads, 0 writes
2026-06-22T13:54:10.064Z|00481|timeval|WARN|context switches: 679 voluntary, 10469 involuntary
2026-06-22T13:54:10.064Z|00482|coverage|INFO|Dropped 3 log messages in last 57 seconds (most recently, 23 seconds ago) due to excessive rate
2026-06-22T13:54:10.064Z|00483|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=7dbb62a8:
2026-06-22T13:54:10.064Z|00484|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:54:10.064Z|00485|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:54:10.064Z|00486|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:54:10.064Z|00487|coverage|INFO|vconn_sent                 0.2/sec    10.233/sec        4.5900/sec   total: 16525
2026-06-22T13:54:10.064Z|00488|coverage|INFO|vconn_received             0.2/sec     0.450/sec        0.2033/sec   total: 733
2026-06-22T13:54:10.064Z|00489|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:54:10.064Z|00490|coverage|INFO|util_xalloc              138.6/sec  6502.583/sec     1684.3083/sec   total: 6066202
2026-06-22T13:54:10.064Z|00491|coverage|INFO|long_poll_interval         0.2/sec     0.083/sec        0.0067/sec   total: 24
2026-06-22T13:54:10.064Z|00492|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0042/sec   total: 15
2026-06-22T13:54:10.064Z|00493|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:54:10.064Z|00494|coverage|INFO|seq_change                 1.2/sec     2.433/sec        2.3242/sec   total: 8371
2026-06-22T13:54:10.064Z|00495|coverage|INFO|rconn_sent                 0.2/sec    10.233/sec        4.5889/sec   total: 16521
2026-06-22T13:54:10.064Z|00496|coverage|INFO|rconn_queued               0.2/sec    10.233/sec        4.5889/sec   total: 16521
2026-06-22T13:54:10.064Z|00497|coverage|INFO|poll_zero_timeout          0.4/sec     0.100/sec        0.0469/sec   total: 171
2026-06-22T13:54:10.064Z|00498|coverage|INFO|poll_create_node           3.0/sec     8.517/sec        6.6183/sec   total: 23857
2026-06-22T13:54:10.064Z|00499|coverage|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:54:10.064Z|00500|coverage|INFO|txn_success                0.0/sec     0.083/sec        0.0361/sec   total: 130
2026-06-22T13:54:10.064Z|00501|coverage|INFO|txn_incomplete             0.0/sec     0.067/sec        0.0561/sec   total: 202
2026-06-22T13:54:10.064Z|00502|coverage|INFO|txn_unchanged              0.4/sec     1.133/sec        0.5683/sec   total: 2054
2026-06-22T13:54:10.064Z|00503|coverage|INFO|hmap_reserve               0.8/sec     2.400/sec        0.3444/sec   total: 1256
2026-06-22T13:54:10.064Z|00504|coverage|INFO|hmap_shrink                0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:54:10.064Z|00505|coverage|INFO|hmap_expand                3.6/sec   119.550/sec       28.0689/sec   total: 101118
2026-06-22T13:54:10.064Z|00506|coverage|INFO|hmap_pathological          0.0/sec     0.867/sec        0.3589/sec   total: 1292
2026-06-22T13:54:10.064Z|00507|coverage|INFO|miniflow_malloc            0.0/sec   259.600/sec       54.8508/sec   total: 197463
2026-06-22T13:54:10.064Z|00508|coverage|INFO|flow_extract               0.0/sec     0.000/sec        0.0025/sec   total: 9
2026-06-22T13:54:10.064Z|00509|coverage|INFO|physical_run               0.0/sec     0.050/sec        0.0325/sec   total: 117
2026-06-22T13:54:10.064Z|00510|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0025/sec   total: 9
2026-06-22T13:54:10.064Z|00511|coverage|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0031/sec   total: 11
2026-06-22T13:54:10.064Z|00512|coverage|INFO|lflow_conj_alloc           0.0/sec     0.200/sec        0.0136/sec   total: 49
2026-06-22T13:54:10.064Z|00513|coverage|INFO|lflow_cache_trim           0.0/sec     0.017/sec        0.0014/sec   total: 5
2026-06-22T13:54:10.064Z|00514|coverage|INFO|lflow_cache_delete         0.0/sec     4.383/sec        1.7561/sec   total: 6322
2026-06-22T13:54:10.064Z|00515|coverage|INFO|lflow_cache_miss           0.0/sec    12.333/sec        3.8186/sec   total: 13747
2026-06-22T13:54:10.064Z|00516|coverage|INFO|lflow_cache_hit            0.0/sec   147.900/sec       26.9289/sec   total: 96944
2026-06-22T13:54:10.064Z|00517|coverage|INFO|lflow_cache_add            0.0/sec     2.283/sec        2.0522/sec   total: 7388
2026-06-22T13:54:10.064Z|00518|coverage|INFO|lflow_cache_free_matches   0.0/sec     2.667/sec        1.3825/sec   total: 4977
2026-06-22T13:54:10.064Z|00519|coverage|INFO|lflow_cache_free_expr      0.0/sec     1.717/sec        0.3736/sec   total: 1345
2026-06-22T13:54:10.064Z|00520|coverage|INFO|lflow_cache_add_matches    0.0/sec     2.200/sec        1.4775/sec   total: 5319
2026-06-22T13:54:10.064Z|00521|coverage|INFO|lflow_cache_add_expr       0.0/sec     0.083/sec        0.5747/sec   total: 2069
2026-06-22T13:54:10.064Z|00522|coverage|INFO|consider_logical_flow      0.0/sec    55.767/sec        8.5719/sec   total: 30859
2026-06-22T13:54:10.064Z|00523|coverage|INFO|lflow_run                  0.0/sec     0.050/sec        0.0058/sec   total: 21
2026-06-22T13:54:10.064Z|00524|coverage|INFO|111 events never hit
2026-06-22T13:54:10.064Z|00525|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (97% CPU usage)
2026-06-22T13:54:10.069Z|00526|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (97% CPU usage)
2026-06-22T13:54:10.071Z|00527|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (97% CPU usage)
2026-06-22T13:55:48.123Z|00528|timeval|WARN|Unreasonably long 2262ms poll interval (0ms user, 1753ms system)
2026-06-22T13:55:48.123Z|00529|timeval|WARN|faults: 17 minor, 134 major
2026-06-22T13:55:48.123Z|00530|timeval|WARN|disk: 34640 reads, 0 writes
2026-06-22T13:55:48.123Z|00531|timeval|WARN|context switches: 137 voluntary, 2017 involuntary
2026-06-22T13:55:48.123Z|00532|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=51aaed6b:
2026-06-22T13:55:48.123Z|00533|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:55:48.123Z|00534|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T13:55:48.123Z|00535|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:55:48.123Z|00536|coverage|INFO|vconn_sent                 0.2/sec     0.117/sec        4.5931/sec   total: 16536
2026-06-22T13:55:48.123Z|00537|coverage|INFO|vconn_received             0.2/sec     0.117/sec        0.2056/sec   total: 741
2026-06-22T13:55:48.123Z|00538|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T13:55:48.123Z|00539|coverage|INFO|util_xalloc              540.0/sec   575.367/sec     1699.3292/sec   total: 6120385
2026-06-22T13:55:48.123Z|00540|coverage|INFO|long_poll_interval         0.0/sec     0.000/sec        0.0069/sec   total: 25
2026-06-22T13:55:48.123Z|00541|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0042/sec   total: 15
2026-06-22T13:55:48.123Z|00542|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:55:48.123Z|00543|coverage|INFO|seq_change                 1.0/sec     4.567/sec        2.4503/sec   total: 8852
2026-06-22T13:55:48.123Z|00544|coverage|INFO|rconn_sent                 0.2/sec     0.117/sec        4.5919/sec   total: 16532
2026-06-22T13:55:48.123Z|00545|coverage|INFO|rconn_queued               0.2/sec     0.117/sec        4.5919/sec   total: 16532
2026-06-22T13:55:48.123Z|00546|coverage|INFO|poll_zero_timeout          0.4/sec     0.400/sec        0.0575/sec   total: 209
2026-06-22T13:55:48.123Z|00547|coverage|INFO|poll_create_node           7.0/sec    14.067/sec        6.9981/sec   total: 25278
2026-06-22T13:55:48.123Z|00548|coverage|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T13:55:48.123Z|00549|coverage|INFO|txn_success                0.0/sec     0.000/sec        0.0361/sec   total: 130
2026-06-22T13:55:48.123Z|00550|coverage|INFO|txn_incomplete             0.0/sec     0.000/sec        0.0561/sec   total: 202
2026-06-22T13:55:48.123Z|00551|coverage|INFO|txn_unchanged              1.6/sec     1.667/sec        0.6117/sec   total: 2210
2026-06-22T13:55:48.123Z|00552|coverage|INFO|hmap_reserve               3.2/sec     3.333/sec        0.4311/sec   total: 1568
2026-06-22T13:55:48.123Z|00553|coverage|INFO|hmap_shrink                0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T13:55:48.123Z|00554|coverage|INFO|hmap_expand               14.0/sec    14.567/sec       28.4478/sec   total: 102482
2026-06-22T13:55:48.123Z|00555|coverage|INFO|hmap_pathological          0.0/sec     0.000/sec        0.3589/sec   total: 1292
2026-06-22T13:55:48.123Z|00556|coverage|INFO|miniflow_malloc            0.0/sec     0.000/sec       54.8508/sec   total: 197463
2026-06-22T13:55:48.123Z|00557|coverage|INFO|flow_extract               0.0/sec     0.000/sec        0.0025/sec   total: 9
2026-06-22T13:55:48.123Z|00558|coverage|INFO|physical_run               0.0/sec     0.000/sec        0.0325/sec   total: 117
2026-06-22T13:55:48.123Z|00559|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0025/sec   total: 9
2026-06-22T13:55:48.123Z|00560|coverage|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0031/sec   total: 11
2026-06-22T13:55:48.123Z|00561|coverage|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0136/sec   total: 49
2026-06-22T13:55:48.123Z|00562|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0014/sec   total: 5
2026-06-22T13:55:48.123Z|00563|coverage|INFO|lflow_cache_delete         0.0/sec     0.000/sec        1.7561/sec   total: 6322
2026-06-22T13:55:48.123Z|00564|coverage|INFO|lflow_cache_miss           0.0/sec     0.000/sec        3.8186/sec   total: 13747
2026-06-22T13:55:48.123Z|00565|coverage|INFO|lflow_cache_hit            0.0/sec     0.000/sec       26.9289/sec   total: 96944
2026-06-22T13:55:48.123Z|00566|coverage|INFO|lflow_cache_add            0.0/sec     0.000/sec        2.0522/sec   total: 7388
2026-06-22T13:55:48.123Z|00567|coverage|INFO|lflow_cache_free_matches   0.0/sec     0.000/sec        1.3825/sec   total: 4977
2026-06-22T13:55:48.123Z|00568|coverage|INFO|lflow_cache_free_expr      0.0/sec     0.000/sec        0.3736/sec   total: 1345
2026-06-22T13:55:48.123Z|00569|coverage|INFO|lflow_cache_add_matches    0.0/sec     0.000/sec        1.4775/sec   total: 5319
2026-06-22T13:55:48.123Z|00570|coverage|INFO|lflow_cache_add_expr       0.0/sec     0.000/sec        0.5747/sec   total: 2069
2026-06-22T13:55:48.123Z|00571|coverage|INFO|consider_logical_flow      0.0/sec     0.000/sec        8.5719/sec   total: 30859
2026-06-22T13:55:48.123Z|00572|coverage|INFO|lflow_run                  0.0/sec     0.000/sec        0.0058/sec   total: 21
2026-06-22T13:55:48.123Z|00573|coverage|INFO|111 events never hit
2026-06-22T13:55:57.613Z|00574|timeval|WARN|Unreasonably long 3553ms poll interval (2ms user, 3310ms system)
2026-06-22T13:55:57.614Z|00575|timeval|WARN|faults: 28 minor, 166 major
2026-06-22T13:55:57.614Z|00576|timeval|WARN|disk: 41544 reads, 0 writes
2026-06-22T13:55:57.614Z|00577|timeval|WARN|context switches: 168 voluntary, 3402 involuntary
2026-06-22T13:55:57.614Z|00578|coverage|INFO|Skipping details of duplicate event coverage for hash=51aaed6b
2026-06-22T13:55:57.614Z|00579|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (103% CPU usage)
2026-06-22T13:55:57.617Z|00580|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (103% CPU usage)
2026-06-22T13:55:57.624Z|00581|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (103% CPU usage)
2026-06-22T13:56:27.237Z|00582|timeval|WARN|Unreasonably long 10881ms poll interval (0ms user, 9471ms system)
2026-06-22T13:56:27.237Z|00583|timeval|WARN|faults: 24 minor, 310 major
2026-06-22T13:56:27.237Z|00584|timeval|WARN|disk: 75584 reads, 0 writes
2026-06-22T13:56:27.237Z|00585|timeval|WARN|context switches: 315 voluntary, 10134 involuntary
2026-06-22T13:56:27.237Z|00586|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (95% CPU usage)
2026-06-22T13:56:27.238Z|00587|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.218:43658<->10.4.3.218:6642) at lib/stream-ssl.c:842 (95% CPU usage)
2026-06-22T13:56:27.238Z|00588|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (95% CPU usage)