2026-06-22T10:01:09.116Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller.log
2026-06-22T10:01:09.118Z|00002|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T10:01:09.118Z|00003|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T10:01:09.129Z|00004|main|INFO|OVN internal version is : [24.03.6-20.33.0-76.8]
2026-06-22T10:01:09.129Z|00005|main|INFO|OVS IDL reconnected, force recompute.
2026-06-22T10:01:09.129Z|00006|main|INFO|OVNSB IDL reconnected, force recompute.
2026-06-22T10:05:25.556Z|00007|memory|INFO|8076 kB peak resident set size after 256.4 seconds
2026-06-22T10:05:25.556Z|00008|memory|INFO|idl-cells-Open_vSwitch:101
2026-06-22T10:05:25.557Z|00009|jsonrpc|WARN|unix:/var/run/openvswitch/db.sock: send error: Broken pipe
2026-06-22T10:05:25.558Z|00010|reconnect|WARN|unix:/var/run/openvswitch/db.sock: connection dropped (Broken pipe)
2026-06-22T10:05:26.559Z|00011|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T10:05:26.559Z|00012|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T10:05:26.614Z|00013|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:05:26.614Z|00014|reconnect|INFO|ssl:10.4.3.99:6642: connection attempt failed (Connection refused)
2026-06-22T10:05:27.615Z|00015|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:05:27.615Z|00016|reconnect|INFO|ssl:10.4.3.99:6642: connection attempt failed (Connection refused)
2026-06-22T10:05:27.615Z|00017|reconnect|INFO|ssl:10.4.3.99:6642: waiting 2 seconds before reconnect
2026-06-22T10:05:29.535Z|00001|vlog|INFO|opened log file /var/log/ovn/ovn-controller.log
2026-06-22T10:05:29.537Z|00002|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T10:05:29.537Z|00003|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T10:05:29.538Z|00004|main|INFO|OVN internal version is : [24.03.6-20.33.0-76.8]
2026-06-22T10:05:29.538Z|00005|main|INFO|OVS IDL reconnected, force recompute.
2026-06-22T10:05:29.543Z|00006|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:05:29.543Z|00007|main|INFO|OVNSB IDL reconnected, force recompute.
2026-06-22T10:05:29.547Z|00008|reconnect|INFO|ssl:10.4.3.99:6642: connected
2026-06-22T10:05:29.551Z|00009|features|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T10:05:29.551Z|00010|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T10:05:29.551Z|00011|features|INFO|OVS Feature: ct_zero_snat, state: supported
2026-06-22T10:05:29.551Z|00012|features|INFO|OVS Feature: ct_flush, state: supported
2026-06-22T10:05:29.551Z|00013|features|INFO|OVS Feature: dp_hash_l4_sym_support, state: supported
2026-06-22T10:05:29.551Z|00014|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting...
2026-06-22T10:05:29.551Z|00015|main|INFO|OVS feature set changed, force recompute.
2026-06-22T10:05:29.551Z|00016|ofctrl|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T10:05:29.551Z|00017|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T10:05:29.552Z|00018|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T10:05:29.552Z|00019|main|INFO|OVS OpenFlow connection reconnected,force recompute.
2026-06-22T10:05:29.552Z|00020|rconn|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T10:05:29.552Z|00021|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected
2026-06-22T10:05:29.552Z|00022|main|INFO|OVS feature set changed, force recompute.
2026-06-22T10:05:29.553Z|00001|pinctrl(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T10:05:29.553Z|00002|rconn(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T10:05:29.553Z|00001|statctrl(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting to switch
2026-06-22T10:05:29.553Z|00002|rconn(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connecting...
2026-06-22T10:05:29.554Z|00003|rconn(ovn_statctrl3)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T10:05:29.554Z|00003|rconn(ovn_pinctrl0)|INFO|unix:/var/run/openvswitch/br-int.mgmt: connected
2026-06-22T10:05:29.554Z|00023|features|INFO|OVS DB schema supports 4 flow table prefixes, our IDL supports: 4
2026-06-22T10:05:29.554Z|00024|main|INFO|Setting flow table prefixes: ip_src, ip_dst, ipv6_src, ipv6_dst.
2026-06-22T10:05:43.520Z|00025|memory|INFO|9992 kB peak resident set size after 14.0 seconds
2026-06-22T10:05:43.520Z|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-22T10:05:53.892Z|00027|binding|INFO|Claiming lport cr-lrp-209c3289-e99b-47f0-879e-94eedf1412fd for this chassis.
2026-06-22T10:05:53.892Z|00028|binding|INFO|cr-lrp-209c3289-e99b-47f0-879e-94eedf1412fd: Claiming fa:16:3e:ef:9c:fd 172.24.5.94/24
2026-06-22T10:05:53.895Z|00029|binding|INFO|Setting lport cr-lrp-209c3289-e99b-47f0-879e-94eedf1412fd up in Southbound
2026-06-22T10:06:27.782Z|00030|memory_trim|INFO|Detected inactivity (last active 30001 ms ago): trimming memory
2026-06-22T10:07:25.371Z|00031|memory_trim|INFO|Detected inactivity (last active 30003 ms ago): trimming memory
2026-06-22T10:08:20.262Z|00032|binding|INFO|Claiming lport cr-lrp-c5f770f5-9795-4c03-9c39-126e643d12f0 for this chassis.
2026-06-22T10:08:20.262Z|00033|binding|INFO|cr-lrp-c5f770f5-9795-4c03-9c39-126e643d12f0: Claiming fa:16:3e:b9:e3:e2 172.24.5.58/24 2001:db8::323/64
2026-06-22T10:08:20.267Z|00034|binding|INFO|Setting lport cr-lrp-c5f770f5-9795-4c03-9c39-126e643d12f0 up in Southbound
2026-06-22T10:08:20.395Z|00035|binding|INFO|Claiming lport cr-lrp-1aee378e-ab5b-4e0f-aee1-c1ff2cae7a29 for this chassis.
2026-06-22T10:08:20.395Z|00036|binding|INFO|cr-lrp-1aee378e-ab5b-4e0f-aee1-c1ff2cae7a29: Claiming fa:16:3e:e4:aa:f8 172.24.5.107/24 2001:db8::18/64
2026-06-22T10:08:20.400Z|00037|binding|INFO|Setting lport cr-lrp-1aee378e-ab5b-4e0f-aee1-c1ff2cae7a29 up in Southbound
2026-06-22T10:08:20.468Z|00038|binding|INFO|Claiming lport cr-lrp-df552b0e-8d6f-42af-9fce-64c0f51512a4 for this chassis.
2026-06-22T10:08:20.468Z|00039|binding|INFO|cr-lrp-df552b0e-8d6f-42af-9fce-64c0f51512a4: Claiming fa:16:3e:47:da:32 172.24.5.117/24 2001:db8::12e/64
2026-06-22T10:08:20.472Z|00040|binding|INFO|Setting lport cr-lrp-df552b0e-8d6f-42af-9fce-64c0f51512a4 up in Southbound
2026-06-22T10:08:21.645Z|00041|binding|INFO|Claiming lport cr-lrp-8911c66a-cb18-4022-9d83-73544daa8793 for this chassis.
2026-06-22T10:08:21.645Z|00042|binding|INFO|cr-lrp-8911c66a-cb18-4022-9d83-73544daa8793: Claiming fa:16:3e:5b:b6:04 172.24.5.193/24 2001:db8::26b/64
2026-06-22T10:08:21.654Z|00043|binding|INFO|Setting lport cr-lrp-8911c66a-cb18-4022-9d83-73544daa8793 up in Southbound
2026-06-22T10:08:22.607Z|00044|binding|INFO|Claiming lport cr-lrp-4ba9d063-aa65-4f5d-9e16-78425ee4a792 for this chassis.
2026-06-22T10:08:22.607Z|00045|binding|INFO|cr-lrp-4ba9d063-aa65-4f5d-9e16-78425ee4a792: Claiming fa:16:3e:b5:f9:d6 172.24.5.161/24 2001:db8::2b3/64
2026-06-22T10:08:22.612Z|00046|binding|INFO|Setting lport cr-lrp-4ba9d063-aa65-4f5d-9e16-78425ee4a792 up in Southbound
2026-06-22T10:08:22.862Z|00047|binding|INFO|Claiming lport cr-lrp-501d4daa-7516-443f-9a67-4fcd9487af00 for this chassis.
2026-06-22T10:08:22.862Z|00048|binding|INFO|cr-lrp-501d4daa-7516-443f-9a67-4fcd9487af00: Claiming fa:16:3e:4a:9e:28 172.24.5.79/24 2001:db8::3b1/64
2026-06-22T10:08:22.872Z|00049|binding|INFO|Setting lport cr-lrp-501d4daa-7516-443f-9a67-4fcd9487af00 up in Southbound
2026-06-22T10:08:22.936Z|00050|binding|INFO|Claiming lport cr-lrp-6d6ff689-cb45-4d7a-af4c-029ca2d826c6 for this chassis.
2026-06-22T10:08:22.936Z|00051|binding|INFO|cr-lrp-6d6ff689-cb45-4d7a-af4c-029ca2d826c6: Claiming fa:16:3e:d3:ad:fa 172.24.5.15/24 2001:db8::2d8/64
2026-06-22T10:08:22.942Z|00052|binding|INFO|Setting lport cr-lrp-6d6ff689-cb45-4d7a-af4c-029ca2d826c6 up in Southbound
2026-06-22T10:08:31.286Z|00053|binding|INFO|Claiming lport cr-lrp-d47dbdba-f557-4caa-8d1a-6a282715f757 for this chassis.
2026-06-22T10:08:31.286Z|00054|binding|INFO|cr-lrp-d47dbdba-f557-4caa-8d1a-6a282715f757: Claiming fa:16:3e:d0:aa:70 172.24.5.223/24 2001:db8::118/64
2026-06-22T10:08:31.298Z|00055|binding|INFO|Setting lport cr-lrp-d47dbdba-f557-4caa-8d1a-6a282715f757 up in Southbound
2026-06-22T10:08:31.577Z|00056|memory|INFO|peak resident set size grew 83% in last 168.1 seconds, from 9992 kB to 18312 kB
2026-06-22T10:08:31.577Z|00057|memory|INFO|idl-cells-OVN_Southbound:11972 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:622 lflow-cache-entries-cache-matches:275 lflow-cache-size-KB:2809 local_datapath_usage-KB:4 ofctrl_desired_flow_usage-KB:754 ofctrl_installed_flow_usage-KB:563 ofctrl_sb_flow_ref_usage-KB:276
2026-06-22T10:08:31.578Z|00058|binding|INFO|Claiming lport cr-lrp-64ec4ff7-ae1a-499e-ba41-b44daff0a89a for this chassis.
2026-06-22T10:08:31.578Z|00059|binding|INFO|cr-lrp-64ec4ff7-ae1a-499e-ba41-b44daff0a89a: Claiming fa:16:3e:61:d3:cc 172.24.5.247/24 2001:db8::3ed/64
2026-06-22T10:08:31.593Z|00060|binding|INFO|Setting lport cr-lrp-64ec4ff7-ae1a-499e-ba41-b44daff0a89a up in Southbound
2026-06-22T10:08:34.666Z|00061|binding|INFO|Claiming lport cr-lrp-80d3c59e-bc00-405b-b653-2e0128a3a987 for this chassis.
2026-06-22T10:08:34.666Z|00062|binding|INFO|cr-lrp-80d3c59e-bc00-405b-b653-2e0128a3a987: Claiming fa:16:3e:be:f4:21 172.24.5.112/24 2001:db8::fd/64
2026-06-22T10:08:34.675Z|00063|binding|INFO|Setting lport cr-lrp-80d3c59e-bc00-405b-b653-2e0128a3a987 up in Southbound
2026-06-22T10:08:35.685Z|00064|binding|INFO|Claiming lport cr-lrp-5d13aad7-6f8c-4f78-9234-fe16901922f7 for this chassis.
2026-06-22T10:08:35.685Z|00065|binding|INFO|cr-lrp-5d13aad7-6f8c-4f78-9234-fe16901922f7: Claiming fa:16:3e:9d:e0:e5 172.24.5.156/24 2001:db8::6c/64
2026-06-22T10:08:35.701Z|00066|binding|INFO|Setting lport cr-lrp-5d13aad7-6f8c-4f78-9234-fe16901922f7 up in Southbound
2026-06-22T10:08:37.858Z|00067|binding|INFO|Claiming lport cr-lrp-18220299-b441-40aa-88e4-c59ea8b8c14b for this chassis.
2026-06-22T10:08:37.858Z|00068|binding|INFO|cr-lrp-18220299-b441-40aa-88e4-c59ea8b8c14b: Claiming fa:16:3e:31:ee:06 172.24.5.217/24 2001:db8::219/64
2026-06-22T10:08:37.877Z|00069|binding|INFO|Setting lport cr-lrp-18220299-b441-40aa-88e4-c59ea8b8c14b up in Southbound
2026-06-22T10:08:40.975Z|00070|binding|INFO|Claiming lport cr-lrp-483bc161-b558-48db-b74d-d7465d9d6979 for this chassis.
2026-06-22T10:08:40.975Z|00071|binding|INFO|cr-lrp-483bc161-b558-48db-b74d-d7465d9d6979: Claiming fa:16:3e:01:74:ae 172.24.5.19/24 2001:db8::b4/64
2026-06-22T10:08:40.986Z|00072|binding|INFO|Setting lport cr-lrp-483bc161-b558-48db-b74d-d7465d9d6979 up in Southbound
2026-06-22T10:08:42.631Z|00073|binding|INFO|Claiming lport cr-lrp-12272c61-303e-4349-8fa8-156b15ad4b01 for this chassis.
2026-06-22T10:08:42.631Z|00074|binding|INFO|cr-lrp-12272c61-303e-4349-8fa8-156b15ad4b01: Claiming fa:16:3e:d1:bd:42 172.24.5.160/24 2001:db8::fa/64
2026-06-22T10:08:42.640Z|00075|binding|INFO|Setting lport cr-lrp-12272c61-303e-4349-8fa8-156b15ad4b01 up in Southbound
2026-06-22T10:08:46.395Z|00076|binding|INFO|Claiming lport cr-lrp-b70ef04b-af83-45e2-b3a4-592c6a77731b for this chassis.
2026-06-22T10:08:46.395Z|00077|binding|INFO|cr-lrp-b70ef04b-af83-45e2-b3a4-592c6a77731b: Claiming fa:16:3e:2b:27:09 172.24.5.65/24 2001:db8::c1/64
2026-06-22T10:08:46.413Z|00078|binding|INFO|Setting lport cr-lrp-b70ef04b-af83-45e2-b3a4-592c6a77731b up in Southbound
2026-06-22T10:08:57.874Z|00079|binding|INFO|Claiming lport bca10dff-195a-47f4-8a94-9679aa5efbce for this chassis.
2026-06-22T10:08:57.874Z|00080|binding|INFO|bca10dff-195a-47f4-8a94-9679aa5efbce: Claiming fa:16:3e:a5:34:78 10.1.0.3
2026-06-22T10:08:57.906Z|00081|binding|INFO|Setting lport bca10dff-195a-47f4-8a94-9679aa5efbce ovn-installed in OVS
2026-06-22T10:08:57.906Z|00082|binding|INFO|Setting lport bca10dff-195a-47f4-8a94-9679aa5efbce up in Southbound
2026-06-22T10:08:57.973Z|00083|binding|INFO|Claiming lport 6884d76c-363a-4d8c-9a34-393d9fc5c125 for this chassis.
2026-06-22T10:08:57.973Z|00084|binding|INFO|6884d76c-363a-4d8c-9a34-393d9fc5c125: Claiming fa:16:3e:ae:36:c0 10.1.0.6
2026-06-22T10:08:57.999Z|00085|binding|INFO|Setting lport 6884d76c-363a-4d8c-9a34-393d9fc5c125 ovn-installed in OVS
2026-06-22T10:08:57.999Z|00086|binding|INFO|Setting lport 6884d76c-363a-4d8c-9a34-393d9fc5c125 up in Southbound
2026-06-22T10:08:59.621Z|00087|binding|INFO|Claiming lport ce6938a8-d55d-4380-811d-446e6e203d9c for this chassis.
2026-06-22T10:08:59.621Z|00088|binding|INFO|ce6938a8-d55d-4380-811d-446e6e203d9c: Claiming fa:16:3e:5b:f3:9c 10.1.0.4
2026-06-22T10:08:59.646Z|00089|binding|INFO|Setting lport ce6938a8-d55d-4380-811d-446e6e203d9c ovn-installed in OVS
2026-06-22T10:08:59.646Z|00090|binding|INFO|Setting lport ce6938a8-d55d-4380-811d-446e6e203d9c up in Southbound
2026-06-22T10:09:01.925Z|00091|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:09:05.976Z|00092|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:09:07.087Z|00093|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:09:08.181Z|00094|timeval|WARN|Unreasonably long 1095ms poll interval (16ms user, 779ms system)
2026-06-22T10:09:08.215Z|00095|timeval|WARN|faults: 232 minor, 1233 major
2026-06-22T10:09:08.215Z|00096|timeval|WARN|disk: 281672 reads, 8 writes
2026-06-22T10:09:08.215Z|00097|timeval|WARN|context switches: 1232 voluntary, 165 involuntary
2026-06-22T10:09:08.235Z|00098|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=e55aa99e:
2026-06-22T10:09:08.263Z|00099|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:09:08.278Z|00100|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:09:08.295Z|00101|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:09:08.296Z|00102|coverage|INFO|vconn_sent                82.0/sec   127.500/sec        2.3014/sec   total: 8319
2026-06-22T10:09:08.296Z|00103|coverage|INFO|vconn_received             8.6/sec     5.250/sec        0.0997/sec   total: 369
2026-06-22T10:09:08.296Z|00104|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T10:09:08.296Z|00105|coverage|INFO|util_xalloc              28370.2/sec 39375.950/sec      706.4833/sec   total: 2571414
2026-06-22T10:09:08.296Z|00106|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0019/sec   total: 7
2026-06-22T10:09:08.296Z|00107|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T10:09:08.296Z|00108|coverage|INFO|seq_change                66.4/sec    49.150/sec        1.1061/sec   total: 4076
2026-06-22T10:09:08.296Z|00109|coverage|INFO|rconn_sent                82.0/sec   127.500/sec        2.3003/sec   total: 8315
2026-06-22T10:09:08.296Z|00110|coverage|INFO|rconn_queued              82.0/sec   127.500/sec        2.3003/sec   total: 8315
2026-06-22T10:09:08.296Z|00111|coverage|INFO|poll_zero_timeout          0.2/sec     0.717/sec        0.0283/sec   total: 105
2026-06-22T10:09:08.296Z|00112|coverage|INFO|poll_create_node         195.2/sec   147.017/sec        3.3003/sec   total: 12187
2026-06-22T10:09:08.296Z|00113|coverage|INFO|txn_success                1.8/sec     0.967/sec        0.0197/sec   total: 73
2026-06-22T10:09:08.296Z|00114|coverage|INFO|txn_incomplete             2.4/sec     1.650/sec        0.0347/sec   total: 127
2026-06-22T10:09:08.296Z|00115|coverage|INFO|txn_unchanged             17.6/sec    13.983/sec        0.3194/sec   total: 1184
2026-06-22T10:09:08.296Z|00116|coverage|INFO|hmap_reserve              33.6/sec     3.167/sec        0.0528/sec   total: 272
2026-06-22T10:09:08.296Z|00117|coverage|INFO|hmap_expand              406.2/sec   610.167/sec       11.4100/sec   total: 41526
2026-06-22T10:09:08.296Z|00118|coverage|INFO|hmap_pathological          5.0/sec     6.317/sec        0.1203/sec   total: 433
2026-06-22T10:09:08.296Z|00119|coverage|INFO|miniflow_malloc          730.4/sec  1348.250/sec       23.1669/sec   total: 83452
2026-06-22T10:09:08.296Z|00120|coverage|INFO|physical_run               0.0/sec     1.033/sec        0.0194/sec   total: 70
2026-06-22T10:09:08.296Z|00121|coverage|INFO|lflow_conj_free            1.2/sec     0.100/sec        0.0017/sec   total: 6
2026-06-22T10:09:08.296Z|00122|coverage|INFO|lflow_conj_alloc           1.8/sec     0.150/sec        0.0025/sec   total: 9
2026-06-22T10:09:08.296Z|00123|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:09:08.296Z|00124|coverage|INFO|lflow_cache_delete        35.8/sec    50.767/sec        0.8661/sec   total: 3118
2026-06-22T10:09:08.296Z|00125|coverage|INFO|lflow_cache_miss         125.4/sec   107.150/sec        1.8925/sec   total: 6816
2026-06-22T10:09:08.296Z|00126|coverage|INFO|lflow_cache_hit          317.4/sec   610.900/sec       10.4000/sec   total: 37446
2026-06-22T10:09:08.296Z|00127|coverage|INFO|lflow_cache_add           40.8/sec    69.533/sec        1.2492/sec   total: 4500
2026-06-22T10:09:08.296Z|00128|coverage|INFO|lflow_cache_free_matches  35.2/sec    44.783/sec        0.7656/sec   total: 2756
2026-06-22T10:09:08.296Z|00129|coverage|INFO|lflow_cache_free_expr      0.6/sec     5.983/sec        0.1006/sec   total: 362
2026-06-22T10:09:08.296Z|00130|coverage|INFO|lflow_cache_add_matches   35.8/sec    48.850/sec        0.8758/sec   total: 3153
2026-06-22T10:09:08.296Z|00131|coverage|INFO|lflow_cache_add_expr       5.0/sec    20.683/sec        0.3733/sec   total: 1347
2026-06-22T10:09:08.296Z|00132|coverage|INFO|consider_logical_flow     77.8/sec   170.900/sec        3.0722/sec   total: 11069
2026-06-22T10:09:08.296Z|00133|coverage|INFO|lflow_run                  0.0/sec     0.067/sec        0.0019/sec   total: 7
2026-06-22T10:09:08.296Z|00134|coverage|INFO|116 events never hit
2026-06-22T10:09:10.123Z|00135|timeval|WARN|Unreasonably long 1827ms poll interval (8ms user, 1236ms system)
2026-06-22T10:09:10.123Z|00136|timeval|WARN|faults: 205 minor, 1516 major
2026-06-22T10:09:10.123Z|00137|timeval|WARN|disk: 384040 reads, 0 writes
2026-06-22T10:09:10.123Z|00138|timeval|WARN|context switches: 1536 voluntary, 373 involuntary
2026-06-22T10:09:10.123Z|00139|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=3f27d3a7:
2026-06-22T10:09:10.123Z|00140|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:09:10.123Z|00141|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:09:10.123Z|00142|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:09:10.123Z|00143|coverage|INFO|vconn_sent                82.0/sec   127.500/sec        2.3014/sec   total: 8319
2026-06-22T10:09:10.123Z|00144|coverage|INFO|vconn_received             8.6/sec     5.250/sec        0.0997/sec   total: 372
2026-06-22T10:09:10.123Z|00145|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T10:09:10.123Z|00146|coverage|INFO|util_xalloc              28370.2/sec 39375.950/sec      706.4833/sec   total: 2572477
2026-06-22T10:09:10.123Z|00147|coverage|INFO|long_poll_interval         0.0/sec     0.000/sec        0.0000/sec   total: 1
2026-06-22T10:09:10.123Z|00148|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0019/sec   total: 7
2026-06-22T10:09:10.123Z|00149|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T10:09:10.123Z|00150|coverage|INFO|seq_change                66.4/sec    49.150/sec        1.1061/sec   total: 4086
2026-06-22T10:09:10.123Z|00151|coverage|INFO|rconn_sent                82.0/sec   127.500/sec        2.3003/sec   total: 8315
2026-06-22T10:09:10.123Z|00152|coverage|INFO|rconn_queued              82.0/sec   127.500/sec        2.3003/sec   total: 8315
2026-06-22T10:09:10.123Z|00153|coverage|INFO|poll_zero_timeout          0.2/sec     0.717/sec        0.0283/sec   total: 105
2026-06-22T10:09:10.123Z|00154|coverage|INFO|poll_create_node         195.2/sec   147.017/sec        3.3003/sec   total: 12213
2026-06-22T10:09:10.123Z|00155|coverage|INFO|txn_success                1.8/sec     0.967/sec        0.0197/sec   total: 73
2026-06-22T10:09:10.123Z|00156|coverage|INFO|txn_incomplete             2.4/sec     1.650/sec        0.0347/sec   total: 128
2026-06-22T10:09:10.123Z|00157|coverage|INFO|txn_unchanged             17.6/sec    13.983/sec        0.3194/sec   total: 1185
2026-06-22T10:09:10.123Z|00158|coverage|INFO|hmap_reserve              33.6/sec     3.167/sec        0.0528/sec   total: 277
2026-06-22T10:09:10.123Z|00159|coverage|INFO|hmap_expand              406.2/sec   610.167/sec       11.4100/sec   total: 41546
2026-06-22T10:09:10.123Z|00160|coverage|INFO|hmap_pathological          5.0/sec     6.317/sec        0.1203/sec   total: 433
2026-06-22T10:09:10.123Z|00161|coverage|INFO|miniflow_malloc          730.4/sec  1348.250/sec       23.1669/sec   total: 83452
2026-06-22T10:09:10.123Z|00162|coverage|INFO|physical_run               0.0/sec     1.033/sec        0.0194/sec   total: 70
2026-06-22T10:09:10.123Z|00163|coverage|INFO|lflow_conj_free            1.2/sec     0.100/sec        0.0017/sec   total: 6
2026-06-22T10:09:10.123Z|00164|coverage|INFO|lflow_conj_alloc           1.8/sec     0.150/sec        0.0025/sec   total: 9
2026-06-22T10:09:10.123Z|00165|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:09:10.123Z|00166|coverage|INFO|lflow_cache_delete        35.8/sec    50.767/sec        0.8661/sec   total: 3118
2026-06-22T10:09:10.123Z|00167|coverage|INFO|lflow_cache_miss         125.4/sec   107.150/sec        1.8925/sec   total: 6816
2026-06-22T10:09:10.123Z|00168|coverage|INFO|lflow_cache_hit          317.4/sec   610.900/sec       10.4000/sec   total: 37446
2026-06-22T10:09:10.123Z|00169|coverage|INFO|lflow_cache_add           40.8/sec    69.533/sec        1.2492/sec   total: 4500
2026-06-22T10:09:10.123Z|00170|coverage|INFO|lflow_cache_free_matches  35.2/sec    44.783/sec        0.7656/sec   total: 2756
2026-06-22T10:09:10.123Z|00171|coverage|INFO|lflow_cache_free_expr      0.6/sec     5.983/sec        0.1006/sec   total: 362
2026-06-22T10:09:10.123Z|00172|coverage|INFO|lflow_cache_add_matches   35.8/sec    48.850/sec        0.8758/sec   total: 3153
2026-06-22T10:09:10.123Z|00173|coverage|INFO|lflow_cache_add_expr       5.0/sec    20.683/sec        0.3733/sec   total: 1347
2026-06-22T10:09:10.123Z|00174|coverage|INFO|consider_logical_flow     77.8/sec   170.900/sec        3.0722/sec   total: 11069
2026-06-22T10:09:10.123Z|00175|coverage|INFO|lflow_run                  0.0/sec     0.067/sec        0.0019/sec   total: 7
2026-06-22T10:09:10.123Z|00176|coverage|INFO|115 events never hit
2026-06-22T10:09:10.123Z|00177|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:44696<->10.4.3.99:6642) at lib/stream-ssl.c:842 (51% CPU usage)
2026-06-22T10:09:10.125Z|00178|poll_loop|INFO|wakeup due to [POLLIN] on fd 28 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (51% CPU usage)
2026-06-22T10:09:10.222Z|00179|poll_loop|INFO|wakeup due to [POLLIN] on fd 28 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (51% CPU usage)
2026-06-22T10:09:10.223Z|00180|binding|INFO|Claiming lport 1b1cbc3b-2e57-4cdb-9f60-d7dfc80ac284 for this chassis.
2026-06-22T10:09:10.223Z|00181|binding|INFO|1b1cbc3b-2e57-4cdb-9f60-d7dfc80ac284: Claiming fa:16:3e:d4:38:73 10.1.0.9
2026-06-22T10:09:10.235Z|00182|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:44696<->10.4.3.99:6642) at lib/stream-ssl.c:842 (51% CPU usage)
2026-06-22T10:09:10.253Z|00183|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (51% CPU usage)
2026-06-22T10:09:10.259Z|00184|binding|INFO|Setting lport 1b1cbc3b-2e57-4cdb-9f60-d7dfc80ac284 ovn-installed in OVS
2026-06-22T10:09:10.260Z|00185|binding|INFO|Setting lport 1b1cbc3b-2e57-4cdb-9f60-d7dfc80ac284 up in Southbound
2026-06-22T10:09:10.261Z|00186|poll_loop|INFO|wakeup due to [POLLIN] on fd 28 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (51% CPU usage)
2026-06-22T10:09:10.262Z|00187|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:44696<->10.4.3.99:6642) at lib/stream-ssl.c:842 (51% CPU usage)
2026-06-22T10:09:10.269Z|00188|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:44696<->10.4.3.99:6642) at lib/stream-ssl.c:842 (51% CPU usage)
2026-06-22T10:09:11.516Z|00189|poll_loop|INFO|wakeup due to [POLLIN] on fd 28 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (51% CPU usage)
2026-06-22T10:09:11.576Z|00190|poll_loop|INFO|wakeup due to [POLLIN] on fd 28 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (51% CPU usage)
2026-06-22T10:09:11.586Z|00191|binding|INFO|Releasing lport b30dd86b-dfc2-41a6-887f-f28e20f908de from this chassis (sb_readonly=0)
2026-06-22T10:09:11.794Z|00192|binding|INFO|Claiming lport cr-lrp-0458a51f-3abc-4ae4-a48c-572bea832c48 for this chassis.
2026-06-22T10:09:11.794Z|00193|binding|INFO|cr-lrp-0458a51f-3abc-4ae4-a48c-572bea832c48: Claiming fa:16:3e:7a:db:3f 172.24.5.123/24 2001:db8::87/64
2026-06-22T10:09:11.810Z|00194|binding|INFO|Setting lport cr-lrp-0458a51f-3abc-4ae4-a48c-572bea832c48 up in Southbound
2026-06-22T10:09:15.686Z|00195|binding|INFO|Claiming lport 7e4d3856-2b77-4898-bd68-389b2c77b9b4 for this chassis.
2026-06-22T10:09:15.686Z|00196|binding|INFO|7e4d3856-2b77-4898-bd68-389b2c77b9b4: Claiming fa:16:3e:79:5e:1b 10.1.0.10
2026-06-22T10:09:15.715Z|00197|binding|INFO|Setting lport 7e4d3856-2b77-4898-bd68-389b2c77b9b4 ovn-installed in OVS
2026-06-22T10:09:15.715Z|00198|binding|INFO|Setting lport 7e4d3856-2b77-4898-bd68-389b2c77b9b4 up in Southbound
2026-06-22T10:09:16.043Z|00199|binding|INFO|Releasing lport d0370110-cbe6-47bc-ba1f-dbefd7ffe95a from this chassis (sb_readonly=0)
2026-06-22T10:09:18.559Z|00004|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:a5:34:78 10.1.0.3
2026-06-22T10:09:18.573Z|00005|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:a5:34:78 10.1.0.3
2026-06-22T10:09:18.995Z|00200|binding|INFO|Claiming lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf for this chassis.
2026-06-22T10:09:18.995Z|00201|binding|INFO|0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf: Claiming fa:16:3e:f5:5a:2f 10.1.0.10
2026-06-22T10:09:19.024Z|00202|binding|INFO|Setting lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf ovn-installed in OVS
2026-06-22T10:09:19.024Z|00203|binding|INFO|Setting lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf up in Southbound
2026-06-22T10:09:19.427Z|00204|binding|INFO|Releasing lport c757671b-5efc-457f-ad7e-1daadbe1e614 from this chassis (sb_readonly=0)
2026-06-22T10:09:24.931Z|00205|binding|INFO|Claiming lport cr-lrp-9f918859-cbd4-4862-94b6-2b9ba9e6acf8 for this chassis.
2026-06-22T10:09:24.931Z|00206|binding|INFO|cr-lrp-9f918859-cbd4-4862-94b6-2b9ba9e6acf8: Claiming fa:16:3e:a2:66:e3 172.24.5.226/24 2001:db8::62/64
2026-06-22T10:09:24.952Z|00207|binding|INFO|Setting lport cr-lrp-9f918859-cbd4-4862-94b6-2b9ba9e6acf8 up in Southbound
2026-06-22T10:09:27.952Z|00208|binding|INFO|Releasing lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf from this chassis (sb_readonly=0)
2026-06-22T10:09:27.952Z|00209|binding|INFO|Setting lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf down in Southbound
2026-06-22T10:09:27.954Z|00210|binding|INFO|Removing iface tap0b4dcbd6-fc ovn-installed in OVS
2026-06-22T10:09:28.268Z|00211|binding|INFO|Claiming lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf for this chassis.
2026-06-22T10:09:28.268Z|00212|binding|INFO|0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf: Claiming fa:16:3e:f5:5a:2f 10.1.0.10
2026-06-22T10:09:28.301Z|00213|binding|INFO|Setting lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf ovn-installed in OVS
2026-06-22T10:09:28.301Z|00214|binding|INFO|Setting lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf up in Southbound
2026-06-22T10:09:28.301Z|00215|binding|INFO|Releasing lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf from this chassis (sb_readonly=1)
2026-06-22T10:09:28.304Z|00216|if_status|INFO|Not setting lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf down as sb is readonly
2026-06-22T10:09:28.304Z|00217|binding|INFO|Releasing lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf from this chassis (sb_readonly=0)
2026-06-22T10:09:28.306Z|00218|binding|INFO|Removing iface tap0b4dcbd6-fc ovn-installed in OVS
2026-06-22T10:09:28.306Z|00219|binding|INFO|Setting lport 0b4dcbd6-fca0-4bf9-ba67-dab614bdf9bf down in Southbound
2026-06-22T10:09:28.665Z|00220|binding|INFO|Releasing lport c757671b-5efc-457f-ad7e-1daadbe1e614 from this chassis (sb_readonly=0)
2026-06-22T10:09:31.576Z|00221|binding|INFO|Claiming lport cr-lrp-413453a4-e6bf-49f3-830d-6686346fe88f for this chassis.
2026-06-22T10:09:31.576Z|00222|binding|INFO|cr-lrp-413453a4-e6bf-49f3-830d-6686346fe88f: Claiming fa:16:3e:49:2c:4a 172.24.5.84/24 2001:db8::72/64
2026-06-22T10:09:31.595Z|00223|binding|INFO|Setting lport cr-lrp-413453a4-e6bf-49f3-830d-6686346fe88f up in Southbound
2026-06-22T10:09:34.166Z|00006|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:d4:38:73 10.1.0.9
2026-06-22T10:09:34.182Z|00007|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:d4:38:73 10.1.0.9
2026-06-22T10:09:35.747Z|00008|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:79:5e:1b 10.1.0.10
2026-06-22T10:09:35.761Z|00009|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:79:5e:1b 10.1.0.10
2026-06-22T10:09:37.345Z|00224|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:09:37.345Z|00225|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:09:37.345Z|00226|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:09:37.345Z|00227|binding|INFO|Releasing lport b30dd86b-dfc2-41a6-887f-f28e20f908de from this chassis (sb_readonly=0)
2026-06-22T10:09:37.345Z|00228|binding|INFO|Releasing lport d0370110-cbe6-47bc-ba1f-dbefd7ffe95a from this chassis (sb_readonly=0)
2026-06-22T10:09:39.871Z|00229|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:09:39.871Z|00230|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:09:39.871Z|00231|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:09:39.871Z|00232|binding|INFO|Releasing lport b30dd86b-dfc2-41a6-887f-f28e20f908de from this chassis (sb_readonly=0)
2026-06-22T10:09:39.871Z|00233|binding|INFO|Releasing lport d0370110-cbe6-47bc-ba1f-dbefd7ffe95a from this chassis (sb_readonly=0)
2026-06-22T10:09:40.597Z|00010|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:ae:36:c0 10.1.0.6
2026-06-22T10:09:40.608Z|00011|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:5b:f3:9c 10.1.0.4
2026-06-22T10:09:40.610Z|00012|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:ae:36:c0 10.1.0.6
2026-06-22T10:09:40.622Z|00013|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:5b:f3:9c 10.1.0.4
2026-06-22T10:09:41.755Z|00234|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:09:41.755Z|00235|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:09:41.755Z|00236|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:09:41.755Z|00237|binding|INFO|Releasing lport b30dd86b-dfc2-41a6-887f-f28e20f908de from this chassis (sb_readonly=0)
2026-06-22T10:09:41.755Z|00238|binding|INFO|Releasing lport d0370110-cbe6-47bc-ba1f-dbefd7ffe95a from this chassis (sb_readonly=0)
2026-06-22T10:09:42.969Z|00239|binding|INFO|Claiming lport 255a8340-15f5-41b5-9706-d0edb60a9f56 for this chassis.
2026-06-22T10:09:42.969Z|00240|binding|INFO|255a8340-15f5-41b5-9706-d0edb60a9f56: Claiming fa:16:3e:cd:91:25 10.1.0.4
2026-06-22T10:09:43.000Z|00241|binding|INFO|Setting lport 255a8340-15f5-41b5-9706-d0edb60a9f56 ovn-installed in OVS
2026-06-22T10:09:43.000Z|00242|binding|INFO|Setting lport 255a8340-15f5-41b5-9706-d0edb60a9f56 up in Southbound
2026-06-22T10:09:43.368Z|00243|binding|INFO|Releasing lport a3279be8-56d1-4cb9-a6b4-9ab7baef9f90 from this chassis (sb_readonly=0)
2026-06-22T10:09:49.188Z|00244|binding|INFO|Claiming lport 2590d12b-d33f-4084-b417-b026ffaba638 for this chassis.
2026-06-22T10:09:49.188Z|00245|binding|INFO|2590d12b-d33f-4084-b417-b026ffaba638: Claiming fa:16:3e:9a:f7:68 10.1.0.3
2026-06-22T10:09:49.216Z|00246|binding|INFO|Setting lport 2590d12b-d33f-4084-b417-b026ffaba638 ovn-installed in OVS
2026-06-22T10:09:53.008Z|00247|reconnect|ERR|ssl:10.4.3.99:6642: no response to inactivity probe after 5 seconds, disconnecting
2026-06-22T10:09:53.008Z|00248|reconnect|INFO|ssl:10.4.3.99:6642: connection dropped
2026-06-22T10:09:53.008Z|00249|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T10:09:54.010Z|00250|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:09:55.011Z|00251|reconnect|INFO|ssl:10.4.3.99:6642: connection attempt timed out
2026-06-22T10:09:55.011Z|00252|reconnect|INFO|ssl:10.4.3.99:6642: waiting 2 seconds before reconnect
2026-06-22T10:09:57.012Z|00253|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:09:59.014Z|00254|reconnect|INFO|ssl:10.4.3.99:6642: connection attempt timed out
2026-06-22T10:09:59.014Z|00255|reconnect|INFO|ssl:10.4.3.99:6642: waiting 4 seconds before reconnect
2026-06-22T10:10:03.015Z|00256|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:10:03.020Z|00257|reconnect|INFO|ssl:10.4.3.99:6642: connected
2026-06-22T10:10:03.091Z|00258|binding|INFO|Claiming lport 2590d12b-d33f-4084-b417-b026ffaba638 for this chassis.
2026-06-22T10:10:03.091Z|00259|binding|INFO|2590d12b-d33f-4084-b417-b026ffaba638: Claiming fa:16:3e:9a:f7:68 10.1.0.3
2026-06-22T10:10:03.091Z|00260|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:10:03.091Z|00261|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:10:03.091Z|00262|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:10:03.091Z|00263|binding|INFO|Releasing lport b30dd86b-dfc2-41a6-887f-f28e20f908de from this chassis (sb_readonly=0)
2026-06-22T10:10:03.091Z|00264|binding|INFO|Releasing lport a3279be8-56d1-4cb9-a6b4-9ab7baef9f90 from this chassis (sb_readonly=0)
2026-06-22T10:10:03.091Z|00265|binding|INFO|Releasing lport d0370110-cbe6-47bc-ba1f-dbefd7ffe95a from this chassis (sb_readonly=0)
2026-06-22T10:10:03.140Z|00266|binding|INFO|Setting lport 2590d12b-d33f-4084-b417-b026ffaba638 up in Southbound
2026-06-22T10:10:03.670Z|00267|binding|INFO|Releasing lport ff3fff72-92ab-44d1-91aa-8bef956cc8b2 from this chassis (sb_readonly=0)
2026-06-22T10:10:08.147Z|00268|memory|INFO|peak resident set size grew 54% in last 96.6 seconds, from 18312 kB to 28168 kB
2026-06-22T10:10:08.148Z|00269|memory|INFO|idl-cells-OVN_Southbound:20213 idl-cells-Open_vSwitch:1192 if_status_mgr_ifaces_state_usage-KB:1 if_status_mgr_ifaces_usage-KB:4 lflow-cache-entries-cache-expr:1078 lflow-cache-entries-cache-matches:406 lflow-cache-size-KB:4673 local_datapath_usage-KB:5 ofctrl_desired_flow_usage-KB:1382 ofctrl_installed_flow_usage-KB:1029 ofctrl_sb_flow_ref_usage-KB:502
2026-06-22T10:10:13.160Z|00270|reconnect|ERR|ssl:10.4.3.99:6642: no response to inactivity probe after 5.01 seconds, disconnecting
2026-06-22T10:10:13.160Z|00271|reconnect|INFO|ssl:10.4.3.99:6642: connection dropped
2026-06-22T10:10:13.160Z|00272|reconnect|INFO|ssl:10.4.3.99:6642: continuing to reconnect in the background but suppressing further logging
2026-06-22T10:10:13.161Z|00273|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T10:10:18.519Z|00014|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:cd:91:25 10.1.0.4
2026-06-22T10:10:18.539Z|00015|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:cd:91:25 10.1.0.4
2026-06-22T10:10:19.517Z|00016|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:9a:f7:68 10.1.0.3
2026-06-22T10:10:19.527Z|00017|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:9a:f7:68 10.1.0.3
2026-06-22T10:10:23.064Z|00274|reconnect|INFO|ssl:10.4.3.99:6642: connected
2026-06-22T10:10:33.930Z|00275|memory_trim|INFO|Detected inactivity (last active 30258 ms ago): trimming memory
2026-06-22T10:10:33.932Z|00276|timeval|WARN|Unreasonably long 5844ms poll interval (6ms user, 5002ms system)
2026-06-22T10:10:33.932Z|00277|timeval|WARN|faults: 122 minor, 1525 major
2026-06-22T10:10:33.932Z|00278|timeval|WARN|disk: 397128 reads, 8 writes
2026-06-22T10:10:33.932Z|00279|timeval|WARN|context switches: 1535 voluntary, 4576 involuntary
2026-06-22T10:10:33.932Z|00280|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=49a4c9c1:
2026-06-22T10:10:33.932Z|00281|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:10:33.932Z|00282|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:10:33.932Z|00283|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:10:33.932Z|00284|coverage|INFO|vconn_sent                 0.4/sec    41.733/sec        3.2525/sec   total: 11710
2026-06-22T10:10:33.932Z|00285|coverage|INFO|vconn_received             0.2/sec     3.133/sec        0.1811/sec   total: 653
2026-06-22T10:10:33.932Z|00286|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T10:10:33.932Z|00287|coverage|INFO|util_xalloc              181.0/sec 23060.783/sec     1198.8731/sec   total: 4323063
2026-06-22T10:10:33.932Z|00288|coverage|INFO|long_poll_interval         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:10:33.932Z|00289|coverage|INFO|stream_open                0.2/sec     0.067/sec        0.0031/sec   total: 11
2026-06-22T10:10:33.932Z|00290|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T10:10:33.932Z|00291|coverage|INFO|seq_change                 3.4/sec    26.550/sec        1.7744/sec   total: 6391
2026-06-22T10:10:33.932Z|00292|coverage|INFO|rconn_sent                 0.4/sec    41.733/sec        3.2514/sec   total: 11706
2026-06-22T10:10:33.932Z|00293|coverage|INFO|rconn_queued               0.4/sec    41.733/sec        3.2514/sec   total: 11706
2026-06-22T10:10:33.932Z|00294|coverage|INFO|poll_zero_timeout          0.4/sec     0.383/sec        0.0372/sec   total: 135
2026-06-22T10:10:33.932Z|00295|coverage|INFO|poll_create_node           8.6/sec    76.567/sec        5.2481/sec   total: 18917
2026-06-22T10:10:33.932Z|00296|coverage|INFO|txn_try_again              0.2/sec     0.033/sec        0.0006/sec   total: 2
2026-06-22T10:10:33.932Z|00297|coverage|INFO|txn_success                0.0/sec     0.433/sec        0.0333/sec   total: 120
2026-06-22T10:10:33.932Z|00298|coverage|INFO|txn_incomplete             0.0/sec     0.667/sec        0.0536/sec   total: 193
2026-06-22T10:10:33.932Z|00299|coverage|INFO|txn_unchanged              0.6/sec     6.033/sec        0.4789/sec   total: 1727
2026-06-22T10:10:33.932Z|00300|coverage|INFO|hmap_reserve               2.8/sec    22.733/sec        0.6122/sec   total: 2228
2026-06-22T10:10:33.932Z|00301|coverage|INFO|hmap_expand                3.8/sec   394.900/sec       19.5739/sec   total: 70807
2026-06-22T10:10:33.932Z|00302|coverage|INFO|hmap_pathological          0.0/sec     3.000/sec        0.1772/sec   total: 641
2026-06-22T10:10:33.932Z|00303|coverage|INFO|miniflow_malloc            0.0/sec   670.883/sec       36.8117/sec   total: 132522
2026-06-22T10:10:33.932Z|00304|coverage|INFO|flow_extract               0.2/sec     0.700/sec        0.0128/sec   total: 46
2026-06-22T10:10:33.932Z|00305|coverage|INFO|physical_run               0.0/sec     0.183/sec        0.0239/sec   total: 86
2026-06-22T10:10:33.932Z|00306|coverage|INFO|pinctrl_total_pin_pkts     0.2/sec     0.700/sec        0.0128/sec   total: 46
2026-06-22T10:10:33.932Z|00307|coverage|INFO|pinctrl_notify_main_thread   0.0/sec     0.500/sec        0.0089/sec   total: 32
2026-06-22T10:10:33.932Z|00308|coverage|INFO|lflow_conj_free            0.0/sec     0.100/sec        0.0050/sec   total: 18
2026-06-22T10:10:33.932Z|00309|coverage|INFO|lflow_conj_alloc           0.0/sec     0.483/sec        0.0131/sec   total: 47
2026-06-22T10:10:33.932Z|00310|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0006/sec   total: 3
2026-06-22T10:10:33.932Z|00311|coverage|INFO|lflow_cache_delete         0.0/sec    13.733/sec        1.1533/sec   total: 4152
2026-06-22T10:10:33.932Z|00312|coverage|INFO|lflow_cache_miss           0.0/sec    41.667/sec        2.8111/sec   total: 10120
2026-06-22T10:10:33.932Z|00313|coverage|INFO|lflow_cache_hit            0.0/sec   346.000/sec       17.0447/sec   total: 61361
2026-06-22T10:10:33.932Z|00314|coverage|INFO|lflow_cache_add            0.0/sec    12.017/sec        1.5656/sec   total: 5636
2026-06-22T10:10:33.932Z|00315|coverage|INFO|lflow_cache_free_matches   0.0/sec     9.433/sec        0.9786/sec   total: 3523
2026-06-22T10:10:33.932Z|00316|coverage|INFO|lflow_cache_free_expr      0.0/sec     4.300/sec        0.1747/sec   total: 629
2026-06-22T10:10:33.932Z|00317|coverage|INFO|lflow_cache_add_matches    0.0/sec     9.033/sec        1.0914/sec   total: 3929
2026-06-22T10:10:33.932Z|00318|coverage|INFO|lflow_cache_add_expr       0.0/sec     2.983/sec        0.4742/sec   total: 1707
2026-06-22T10:10:33.932Z|00319|coverage|INFO|consider_logical_flow      0.0/sec   123.717/sec        5.2972/sec   total: 19070
2026-06-22T10:10:33.932Z|00320|coverage|INFO|lflow_run                  0.0/sec     0.067/sec        0.0031/sec   total: 11
2026-06-22T10:10:33.932Z|00321|coverage|INFO|111 events never hit
2026-06-22T10:10:33.932Z|00322|poll_loop|INFO|Dropped 15 log messages in last 82 seconds (most recently, 82 seconds ago) due to excessive rate
2026-06-22T10:10:33.932Z|00323|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (86% CPU usage)
2026-06-22T10:10:34.141Z|00324|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:48222<->10.4.3.99:6642) at lib/stream-ssl.c:842 (86% CPU usage)
2026-06-22T10:10:34.147Z|00325|poll_loop|INFO|wakeup due to 0-ms timeout at lib/inc-proc-eng.c:66 (86% CPU usage)
2026-06-22T10:10:34.150Z|00326|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (86% CPU usage)
2026-06-22T10:10:34.153Z|00327|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (86% CPU usage)
2026-06-22T10:10:34.155Z|00328|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (86% CPU usage)
2026-06-22T10:10:34.195Z|00329|binding|INFO|Releasing lport ff3fff72-92ab-44d1-91aa-8bef956cc8b2 from this chassis (sb_readonly=0)
2026-06-22T10:10:34.195Z|00330|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:10:34.195Z|00331|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:10:34.195Z|00332|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:10:34.195Z|00333|binding|INFO|Releasing lport b30dd86b-dfc2-41a6-887f-f28e20f908de from this chassis (sb_readonly=0)
2026-06-22T10:10:34.195Z|00334|binding|INFO|Releasing lport a3279be8-56d1-4cb9-a6b4-9ab7baef9f90 from this chassis (sb_readonly=0)
2026-06-22T10:10:34.195Z|00335|binding|INFO|Claiming lport 8e7fa2cc-4518-48ba-bb2d-0dfd1abcf5e8 for this chassis.
2026-06-22T10:10:34.195Z|00336|binding|INFO|8e7fa2cc-4518-48ba-bb2d-0dfd1abcf5e8: Claiming fa:16:3e:fe:b7:a2 10.1.0.11
2026-06-22T10:10:34.196Z|00337|binding|INFO|Releasing lport d0370110-cbe6-47bc-ba1f-dbefd7ffe95a from this chassis (sb_readonly=0)
2026-06-22T10:10:34.251Z|00338|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (86% CPU usage)
2026-06-22T10:10:34.251Z|00339|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:48222<->10.4.3.99:6642) at lib/stream-ssl.c:842 (86% CPU usage)
2026-06-22T10:10:34.265Z|00340|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (86% CPU usage)
2026-06-22T10:10:34.266Z|00341|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (86% CPU usage)
2026-06-22T10:10:34.266Z|00342|binding|INFO|Setting lport 8e7fa2cc-4518-48ba-bb2d-0dfd1abcf5e8 ovn-installed in OVS
2026-06-22T10:10:34.266Z|00343|binding|INFO|Setting lport 8e7fa2cc-4518-48ba-bb2d-0dfd1abcf5e8 up in Southbound
2026-06-22T10:10:38.267Z|00344|binding|INFO|Claiming lport cr-lrp-5dfe360d-36fa-455f-8eea-bb8936e93fee for this chassis.
2026-06-22T10:10:38.267Z|00345|binding|INFO|cr-lrp-5dfe360d-36fa-455f-8eea-bb8936e93fee: Claiming fa:16:3e:16:b0:89 172.24.5.138/24 2001:db8::183/64
2026-06-22T10:10:38.291Z|00346|binding|INFO|Setting lport cr-lrp-5dfe360d-36fa-455f-8eea-bb8936e93fee up in Southbound
2026-06-22T10:10:40.178Z|00347|binding|INFO|Releasing lport 1b1cbc3b-2e57-4cdb-9f60-d7dfc80ac284 from this chassis (sb_readonly=0)
2026-06-22T10:10:40.178Z|00348|binding|INFO|Setting lport 1b1cbc3b-2e57-4cdb-9f60-d7dfc80ac284 down in Southbound
2026-06-22T10:10:40.185Z|00349|binding|INFO|Removing iface tap1b1cbc3b-2e ovn-installed in OVS
2026-06-22T10:10:40.429Z|00350|binding|INFO|Releasing lport 255a8340-15f5-41b5-9706-d0edb60a9f56 from this chassis (sb_readonly=1)
2026-06-22T10:10:40.435Z|00351|binding|INFO|Removing iface tap255a8340-15 ovn-installed in OVS
2026-06-22T10:10:40.435Z|00352|if_status|INFO|Not setting lport 255a8340-15f5-41b5-9706-d0edb60a9f56 down as sb is readonly
2026-06-22T10:10:45.020Z|00353|binding|INFO|Releasing lport 7e4d3856-2b77-4898-bd68-389b2c77b9b4 from this chassis (sb_readonly=1)
2026-06-22T10:10:45.032Z|00354|binding|INFO|Removing iface tap7e4d3856-2b ovn-installed in OVS
2026-06-22T10:10:45.146Z|00355|binding|INFO|Releasing lport 6884d76c-363a-4d8c-9a34-393d9fc5c125 from this chassis (sb_readonly=1)
2026-06-22T10:10:45.167Z|00356|binding|INFO|Removing iface tap6884d76c-36 ovn-installed in OVS
2026-06-22T10:10:47.214Z|00357|if_status|INFO|Not updating pb chassis for 6884d76c-363a-4d8c-9a34-393d9fc5c125 now as sb is readonly
2026-06-22T10:10:47.783Z|00358|binding|INFO|Releasing lport 6884d76c-363a-4d8c-9a34-393d9fc5c125 from this chassis (sb_readonly=1)
2026-06-22T10:10:49.327Z|00359|reconnect|ERR|ssl:10.4.3.99:6642: no response to inactivity probe after 5.17 seconds, disconnecting
2026-06-22T10:10:49.333Z|00360|reconnect|INFO|ssl:10.4.3.99:6642: connection dropped
2026-06-22T10:10:49.432Z|00361|main|INFO|OVNSB commit failed, force recompute next time.
2026-06-22T10:10:50.369Z|00362|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:10:51.373Z|00363|reconnect|INFO|ssl:10.4.3.99:6642: connection attempt timed out
2026-06-22T10:10:51.376Z|00364|reconnect|INFO|ssl:10.4.3.99:6642: waiting 2 seconds before reconnect
2026-06-22T10:10:53.421Z|00365|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:10:53.501Z|00018|pinctrl(ovn_pinctrl0)|INFO|DHCPOFFER fa:16:3e:fe:b7:a2 10.1.0.11
2026-06-22T10:10:53.555Z|00019|pinctrl(ovn_pinctrl0)|INFO|DHCPACK fa:16:3e:fe:b7:a2 10.1.0.11
2026-06-22T10:10:55.572Z|00366|reconnect|INFO|ssl:10.4.3.99:6642: connection attempt timed out
2026-06-22T10:10:55.606Z|00367|reconnect|INFO|ssl:10.4.3.99:6642: waiting 4 seconds before reconnect
2026-06-22T10:10:59.560Z|00368|reconnect|INFO|ssl:10.4.3.99:6642: connecting...
2026-06-22T10:10:59.564Z|00369|reconnect|INFO|ssl:10.4.3.99:6642: connected
2026-06-22T10:10:59.618Z|00370|binding|INFO|Releasing lport 255a8340-15f5-41b5-9706-d0edb60a9f56 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.618Z|00371|binding|INFO|Releasing lport ff3fff72-92ab-44d1-91aa-8bef956cc8b2 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.618Z|00372|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:10:59.618Z|00373|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:10:59.618Z|00374|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.618Z|00375|binding|INFO|Releasing lport 6884d76c-363a-4d8c-9a34-393d9fc5c125 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.618Z|00376|binding|INFO|Releasing lport a3279be8-56d1-4cb9-a6b4-9ab7baef9f90 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.619Z|00377|binding|INFO|Releasing lport 7e4d3856-2b77-4898-bd68-389b2c77b9b4 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.619Z|00378|binding|INFO|Releasing lport d0370110-cbe6-47bc-ba1f-dbefd7ffe95a from this chassis (sb_readonly=0)
2026-06-22T10:10:59.651Z|00379|binding|INFO|Setting lport 7e4d3856-2b77-4898-bd68-389b2c77b9b4 down in Southbound
2026-06-22T10:10:59.651Z|00380|binding|INFO|Setting lport 255a8340-15f5-41b5-9706-d0edb60a9f56 down in Southbound
2026-06-22T10:10:59.651Z|00381|binding|INFO|Setting lport 6884d76c-363a-4d8c-9a34-393d9fc5c125 down in Southbound
2026-06-22T10:10:59.691Z|00382|binding|INFO|Releasing lport ff3fff72-92ab-44d1-91aa-8bef956cc8b2 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.691Z|00383|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:10:59.691Z|00384|binding|INFO|Releasing lport 2e142cb9-cba2-47f5-a45c-05b8537d292f from this chassis (sb_readonly=0)
2026-06-22T10:10:59.691Z|00385|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:10:59.691Z|00386|binding|INFO|Releasing lport a3279be8-56d1-4cb9-a6b4-9ab7baef9f90 from this chassis (sb_readonly=0)
2026-06-22T10:11:02.080Z|00387|binding|INFO|Releasing lport ff3fff72-92ab-44d1-91aa-8bef956cc8b2 from this chassis (sb_readonly=0)
2026-06-22T10:11:02.080Z|00388|binding|INFO|Releasing lport 5484e560-a90a-4511-a979-942eaa944c2d from this chassis (sb_readonly=0)
2026-06-22T10:11:02.080Z|00389|binding|INFO|Releasing lport 804259a1-cc1f-4ac1-adaf-c4e4e1eecf67 from this chassis (sb_readonly=0)
2026-06-22T10:11:04.383Z|00390|binding|INFO|Claiming lport 1e646302-d2db-4a60-9571-e6ca402bcacb for this chassis.
2026-06-22T10:11:04.394Z|00391|binding|INFO|1e646302-d2db-4a60-9571-e6ca402bcacb: Claiming fa:16:3e:cc:03:08 10.10.10.224
2026-06-22T10:11:35.410Z|00004|timeval(ovn_statctrl3)|WARN|Unreasonably long 3306ms poll interval (0ms user, 1518ms system)
2026-06-22T10:11:35.411Z|00005|timeval(ovn_statctrl3)|WARN|faults: 11 minor, 72 major
2026-06-22T10:11:35.411Z|00006|timeval(ovn_statctrl3)|WARN|disk: 18952 reads, 0 writes
2026-06-22T10:11:35.411Z|00007|timeval(ovn_statctrl3)|WARN|context switches: 75 voluntary, 1646 involuntary
2026-06-22T10:11:35.411Z|00008|coverage(ovn_statctrl3)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=210fdd08:
2026-06-22T10:11:35.411Z|00009|coverage(ovn_statctrl3)|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.411Z|00010|coverage(ovn_statctrl3)|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:11:35.411Z|00011|coverage(ovn_statctrl3)|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.411Z|00012|coverage(ovn_statctrl3)|INFO|vconn_sent                26.6/sec    25.683/sec        3.6806/sec   total: 13250
2026-06-22T10:11:35.411Z|00013|coverage(ovn_statctrl3)|INFO|vconn_received             1.8/sec     1.683/sec        0.2092/sec   total: 753
2026-06-22T10:11:35.411Z|00014|coverage(ovn_statctrl3)|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T10:11:35.411Z|00015|coverage(ovn_statctrl3)|INFO|util_xalloc              20050.0/sec 19866.783/sec     1529.9861/sec   total: 5507950
2026-06-22T10:11:35.411Z|00016|coverage(ovn_statctrl3)|INFO|long_poll_interval         0.0/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.411Z|00017|coverage(ovn_statctrl3)|INFO|stream_open                0.0/sec     0.050/sec        0.0039/sec   total: 14
2026-06-22T10:11:35.411Z|00392|inc_proc_eng|INFO|node: logical_flow_output, handler for input runtime_data took 30954ms
2026-06-22T10:11:35.411Z|00018|coverage(ovn_statctrl3)|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T10:11:35.411Z|00019|coverage(ovn_statctrl3)|INFO|seq_change                 8.8/sec    13.067/sec        1.9922/sec   total: 7172
2026-06-22T10:11:35.411Z|00020|coverage(ovn_statctrl3)|INFO|rconn_sent                26.6/sec    25.683/sec        3.6794/sec   total: 13246
2026-06-22T10:11:35.411Z|00021|coverage(ovn_statctrl3)|INFO|rconn_queued              26.6/sec    25.683/sec        3.6794/sec   total: 13246
2026-06-22T10:11:35.411Z|00022|coverage(ovn_statctrl3)|INFO|poll_zero_timeout          1.2/sec     0.400/sec        0.0439/sec   total: 158
2026-06-22T10:11:35.411Z|00023|coverage(ovn_statctrl3)|INFO|poll_create_node          28.0/sec    40.100/sec        5.9164/sec   total: 21299
2026-06-22T10:11:35.411Z|00024|coverage(ovn_statctrl3)|INFO|txn_try_again              0.0/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.411Z|00025|coverage(ovn_statctrl3)|INFO|txn_success                1.2/sec     0.367/sec        0.0394/sec   total: 142
2026-06-22T10:11:35.411Z|00026|coverage(ovn_statctrl3)|INFO|txn_incomplete             0.6/sec     1.000/sec        0.0703/sec   total: 253
2026-06-22T10:11:35.411Z|00027|coverage(ovn_statctrl3)|INFO|txn_unchanged              3.6/sec     3.017/sec        0.5292/sec   total: 1905
2026-06-22T10:11:35.411Z|00028|coverage(ovn_statctrl3)|INFO|hmap_reserve               7.6/sec    12.833/sec        0.8261/sec   total: 2974
2026-06-22T10:11:35.411Z|00029|coverage(ovn_statctrl3)|INFO|hmap_expand              381.0/sec   364.767/sec       25.6533/sec   total: 92352
2026-06-22T10:11:35.411Z|00030|coverage(ovn_statctrl3)|INFO|hmap_pathological          2.4/sec     1.517/sec        0.2025/sec   total: 729
2026-06-22T10:11:35.411Z|00031|coverage(ovn_statctrl3)|INFO|miniflow_malloc          540.2/sec   466.750/sec       44.5908/sec   total: 160527
2026-06-22T10:11:35.411Z|00032|coverage(ovn_statctrl3)|INFO|flow_extract               0.0/sec     0.033/sec        0.0133/sec   total: 48
2026-06-22T10:11:35.411Z|00033|coverage(ovn_statctrl3)|INFO|physical_run               0.6/sec     0.083/sec        0.0253/sec   total: 91
2026-06-22T10:11:35.411Z|00034|coverage(ovn_statctrl3)|INFO|pinctrl_total_pin_pkts     0.0/sec     0.033/sec        0.0133/sec   total: 48
2026-06-22T10:11:35.411Z|00035|coverage(ovn_statctrl3)|INFO|pinctrl_notify_main_thread   0.0/sec     0.000/sec        0.0089/sec   total: 32
2026-06-22T10:11:35.411Z|00036|coverage(ovn_statctrl3)|INFO|lflow_conj_free            0.0/sec     0.117/sec        0.0069/sec   total: 25
2026-06-22T10:11:35.411Z|00037|coverage(ovn_statctrl3)|INFO|lflow_conj_alloc           1.2/sec     0.383/sec        0.0194/sec   total: 70
2026-06-22T10:11:35.411Z|00038|coverage(ovn_statctrl3)|INFO|lflow_cache_trim           0.0/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.411Z|00039|coverage(ovn_statctrl3)|INFO|lflow_cache_delete         8.4/sec     7.083/sec        1.2714/sec   total: 4577
2026-06-22T10:11:35.411Z|00040|coverage(ovn_statctrl3)|INFO|lflow_cache_miss          28.2/sec    28.367/sec        3.2839/sec   total: 11822
2026-06-22T10:11:35.411Z|00041|coverage(ovn_statctrl3)|INFO|lflow_cache_hit          319.4/sec   265.917/sec       21.4767/sec   total: 77316
2026-06-22T10:11:35.411Z|00042|coverage(ovn_statctrl3)|INFO|lflow_cache_add            7.0/sec     6.600/sec        1.6756/sec   total: 6032
2026-06-22T10:11:35.411Z|00043|coverage(ovn_statctrl3)|INFO|lflow_cache_free_matches   6.0/sec     5.067/sec        1.0631/sec   total: 3827
2026-06-22T10:11:35.411Z|00044|coverage(ovn_statctrl3)|INFO|lflow_cache_free_expr      3.6/sec     2.017/sec        0.2083/sec   total: 750
2026-06-22T10:11:35.411Z|00045|coverage(ovn_statctrl3)|INFO|lflow_cache_add_matches    6.0/sec     5.083/sec        1.1761/sec   total: 4234
2026-06-22T10:11:35.411Z|00046|coverage(ovn_statctrl3)|INFO|lflow_cache_add_expr       2.2/sec     1.517/sec        0.4994/sec   total: 1798
2026-06-22T10:11:35.411Z|00047|coverage(ovn_statctrl3)|INFO|consider_logical_flow    141.4/sec   113.750/sec        7.1931/sec   total: 25895
2026-06-22T10:11:35.411Z|00048|coverage(ovn_statctrl3)|INFO|lflow_run                  0.6/sec     0.067/sec        0.0042/sec   total: 15
2026-06-22T10:11:35.411Z|00049|coverage(ovn_statctrl3)|INFO|111 events never hit
2026-06-22T10:11:35.416Z|00393|memory_trim|INFO|Detected inactivity (last active 32759 ms ago): trimming memory
2026-06-22T10:11:35.416Z|00394|timeval|WARN|Unreasonably long 31358ms poll interval (25ms user, 26439ms system)
2026-06-22T10:11:35.416Z|00395|timeval|WARN|faults: 1385 minor, 12194 major
2026-06-22T10:11:35.416Z|00396|timeval|WARN|disk: 3014344 reads, 16 writes
2026-06-22T10:11:35.416Z|00397|timeval|WARN|context switches: 12270 voluntary, 18484 involuntary
2026-06-22T10:11:35.417Z|00398|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=9372aace:
2026-06-22T10:11:35.417Z|00399|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.417Z|00400|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:11:35.417Z|00401|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.417Z|00402|coverage|INFO|vconn_sent                26.6/sec    25.683/sec        3.6806/sec   total: 13482
2026-06-22T10:11:35.417Z|00403|coverage|INFO|vconn_received             1.8/sec     1.683/sec        0.2092/sec   total: 753
2026-06-22T10:11:35.417Z|00404|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T10:11:35.417Z|00405|coverage|INFO|util_xalloc              20050.0/sec 19866.783/sec     1529.9861/sec   total: 5521487
2026-06-22T10:11:35.417Z|00406|coverage|INFO|long_poll_interval         0.0/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.417Z|00407|coverage|INFO|stream_open                0.0/sec     0.050/sec        0.0039/sec   total: 14
2026-06-22T10:11:35.417Z|00408|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T10:11:35.417Z|00409|coverage|INFO|seq_change                 8.8/sec    13.067/sec        1.9922/sec   total: 7190
2026-06-22T10:11:35.417Z|00410|coverage|INFO|rconn_sent                26.6/sec    25.683/sec        3.6794/sec   total: 13478
2026-06-22T10:11:35.417Z|00411|coverage|INFO|rconn_queued              26.6/sec    25.683/sec        3.6794/sec   total: 13478
2026-06-22T10:11:35.417Z|00412|coverage|INFO|poll_zero_timeout          1.2/sec     0.400/sec        0.0439/sec   total: 159
2026-06-22T10:11:35.417Z|00413|coverage|INFO|poll_create_node          28.0/sec    40.100/sec        5.9164/sec   total: 21347
2026-06-22T10:11:35.417Z|00414|coverage|INFO|txn_try_again              0.0/sec     0.017/sec        0.0008/sec   total: 3
2026-06-22T10:11:35.417Z|00415|coverage|INFO|txn_success                1.2/sec     0.367/sec        0.0394/sec   total: 142
2026-06-22T10:11:35.417Z|00416|coverage|INFO|txn_incomplete             0.6/sec     1.000/sec        0.0703/sec   total: 254
2026-06-22T10:11:35.417Z|00417|coverage|INFO|txn_unchanged              3.6/sec     3.017/sec        0.5292/sec   total: 1908
2026-06-22T10:11:35.417Z|00418|coverage|INFO|hmap_reserve               7.6/sec    12.833/sec        0.8261/sec   total: 2984
2026-06-22T10:11:35.417Z|00419|coverage|INFO|hmap_expand              381.0/sec   364.767/sec       25.6533/sec   total: 92484
2026-06-22T10:11:35.417Z|00420|coverage|INFO|hmap_pathological          2.4/sec     1.517/sec        0.2025/sec   total: 730
2026-06-22T10:11:35.417Z|00421|coverage|INFO|miniflow_malloc          540.2/sec   466.750/sec       44.5908/sec   total: 161404
2026-06-22T10:11:35.417Z|00422|coverage|INFO|flow_extract               0.0/sec     0.033/sec        0.0133/sec   total: 48
2026-06-22T10:11:35.417Z|00423|coverage|INFO|physical_run               0.6/sec     0.083/sec        0.0253/sec   total: 92
2026-06-22T10:11:35.417Z|00424|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.033/sec        0.0133/sec   total: 48
2026-06-22T10:11:35.417Z|00425|coverage|INFO|pinctrl_notify_main_thread   0.0/sec     0.000/sec        0.0089/sec   total: 32
2026-06-22T10:11:35.417Z|00426|coverage|INFO|lflow_conj_free            0.0/sec     0.117/sec        0.0069/sec   total: 25
2026-06-22T10:11:35.417Z|00427|coverage|INFO|lflow_conj_alloc           1.2/sec     0.383/sec        0.0194/sec   total: 70
2026-06-22T10:11:35.417Z|00428|coverage|INFO|lflow_cache_trim           0.0/sec     0.017/sec        0.0008/sec   total: 4
2026-06-22T10:11:35.417Z|00429|coverage|INFO|lflow_cache_delete         8.4/sec     7.083/sec        1.2714/sec   total: 4577
2026-06-22T10:11:35.417Z|00430|coverage|INFO|lflow_cache_miss          28.2/sec    28.367/sec        3.2839/sec   total: 11849
2026-06-22T10:11:35.417Z|00431|coverage|INFO|lflow_cache_hit          319.4/sec   265.917/sec       21.4767/sec   total: 77424
2026-06-22T10:11:35.417Z|00432|coverage|INFO|lflow_cache_add            7.0/sec     6.600/sec        1.6756/sec   total: 6032
2026-06-22T10:11:35.417Z|00433|coverage|INFO|lflow_cache_free_matches   6.0/sec     5.067/sec        1.0631/sec   total: 3827
2026-06-22T10:11:35.417Z|00434|coverage|INFO|lflow_cache_free_expr      3.6/sec     2.017/sec        0.2083/sec   total: 750
2026-06-22T10:11:35.417Z|00435|coverage|INFO|lflow_cache_add_matches    6.0/sec     5.083/sec        1.1761/sec   total: 4234
2026-06-22T10:11:35.417Z|00436|coverage|INFO|lflow_cache_add_expr       2.2/sec     1.517/sec        0.4994/sec   total: 1798
2026-06-22T10:11:35.417Z|00437|coverage|INFO|consider_logical_flow    141.4/sec   113.750/sec        7.1931/sec   total: 25899
2026-06-22T10:11:35.417Z|00438|coverage|INFO|lflow_run                  0.6/sec     0.067/sec        0.0042/sec   total: 15
2026-06-22T10:11:35.417Z|00439|coverage|INFO|111 events never hit
2026-06-22T10:11:35.417Z|00440|poll_loop|INFO|Dropped 6 log messages in last 62 seconds (most recently, 60 seconds ago) due to excessive rate
2026-06-22T10:11:35.417Z|00441|poll_loop|INFO|wakeup due to [POLLIN] on fd 30 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (79% CPU usage)
2026-06-22T10:11:35.417Z|00442|poll_loop|INFO|wakeup due to [POLLIN] on fd 28 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (79% CPU usage)
2026-06-22T10:11:35.418Z|00443|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (79% CPU usage)
2026-06-22T10:11:35.421Z|00444|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (79% CPU usage)
2026-06-22T10:11:35.425Z|00445|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (79% CPU usage)
2026-06-22T10:11:35.449Z|00446|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:153 (79% CPU usage)
2026-06-22T10:11:35.450Z|00447|poll_loop|INFO|wakeup due to 0-ms timeout at lib/rconn.c:543 (79% CPU usage)
2026-06-22T10:11:35.452Z|00448|poll_loop|INFO|wakeup due to [POLLIN] on fd 32 (<->/var/run/openvswitch/br-int.mgmt) at lib/stream-fd.c:157 (79% CPU usage)
2026-06-22T10:11:35.452Z|00449|binding|INFO|Setting lport 1e646302-d2db-4a60-9571-e6ca402bcacb ovn-installed in OVS
2026-06-22T10:11:35.452Z|00450|binding|INFO|Setting lport 1e646302-d2db-4a60-9571-e6ca402bcacb up in Southbound
2026-06-22T10:11:35.453Z|00451|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (79% CPU usage)
2026-06-22T10:11:35.456Z|00452|poll_loop|INFO|wakeup due to [POLLIN] on fd 28 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (79% CPU usage)
2026-06-22T10:11:35.971Z|00453|binding|INFO|Releasing lport 406f1f37-83d4-41fa-8698-58679f0d2124 from this chassis (sb_readonly=0)
2026-06-22T10:11:36.970Z|00454|binding|INFO|Releasing lport 1e646302-d2db-4a60-9571-e6ca402bcacb from this chassis (sb_readonly=0)
2026-06-22T10:11:36.970Z|00455|binding|INFO|Setting lport 1e646302-d2db-4a60-9571-e6ca402bcacb down in Southbound
2026-06-22T10:11:36.971Z|00456|binding|INFO|Removing iface tap1e646302-d2 ovn-installed in OVS
2026-06-22T10:12:00.802Z|00020|timeval(ovn_pinctrl0)|WARN|Unreasonably long 4190ms poll interval (0ms user, 1787ms system)
2026-06-22T10:12:00.802Z|00021|timeval(ovn_pinctrl0)|WARN|faults: 30 minor, 157 major
2026-06-22T10:12:00.802Z|00022|timeval(ovn_pinctrl0)|WARN|disk: 42256 reads, 0 writes
2026-06-22T10:12:00.802Z|00023|timeval(ovn_pinctrl0)|WARN|context switches: 159 voluntary, 1715 involuntary
2026-06-22T10:12:00.803Z|00457|timeval|WARN|Unreasonably long 18095ms poll interval (5ms user, 14150ms system)
2026-06-22T10:12:00.803Z|00458|timeval|WARN|faults: 41 minor, 633 major
2026-06-22T10:12:00.803Z|00459|timeval|WARN|disk: 161072 reads, 0 writes
2026-06-22T10:12:00.803Z|00460|timeval|WARN|context switches: 653 voluntary, 14259 involuntary
2026-06-22T10:12:00.803Z|00461|poll_loop|INFO|Dropped 25 log messages in last 25 seconds (most recently, 24 seconds ago) due to excessive rate
2026-06-22T10:12:00.803Z|00462|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (79% CPU usage)
2026-06-22T10:12:00.804Z|00463|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (79% CPU usage)
2026-06-22T10:12:00.804Z|00464|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (79% CPU usage)
2026-06-22T10:12:11.167Z|00465|memory_trim|INFO|Detected inactivity (last active 34191 ms ago): trimming memory
2026-06-22T10:12:11.168Z|00466|timeval|WARN|Unreasonably long 4410ms poll interval (0ms user, 3364ms system)
2026-06-22T10:12:11.168Z|00467|timeval|WARN|faults: 21 minor, 137 major
2026-06-22T10:12:11.168Z|00468|timeval|WARN|disk: 33384 reads, 8 writes
2026-06-22T10:12:11.168Z|00469|timeval|WARN|context switches: 140 voluntary, 3558 involuntary
2026-06-22T10:12:11.168Z|00470|coverage|INFO|Dropped 2 log messages in last 10 seconds (most recently, 10 seconds ago) due to excessive rate
2026-06-22T10:12:11.168Z|00471|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=74187613:
2026-06-22T10:12:11.168Z|00472|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:12:11.168Z|00473|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:12:11.168Z|00474|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:12:11.168Z|00475|coverage|INFO|vconn_sent                 2.2/sec    16.900/sec        3.7786/sec   total: 13603
2026-06-22T10:12:11.168Z|00476|coverage|INFO|vconn_received             1.2/sec     1.050/sec        0.2175/sec   total: 783
2026-06-22T10:12:11.168Z|00477|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T10:12:11.168Z|00478|coverage|INFO|util_xalloc              1824.6/sec  9707.067/sec     1552.5344/sec   total: 5592518
2026-06-22T10:12:11.168Z|00479|coverage|INFO|long_poll_interval         0.0/sec     0.017/sec        0.0011/sec   total: 5
2026-06-22T10:12:11.168Z|00480|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0039/sec   total: 14
2026-06-22T10:12:11.168Z|00481|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T10:12:11.168Z|00482|coverage|INFO|seq_change                12.2/sec     8.533/sec        2.0756/sec   total: 7476
2026-06-22T10:12:11.168Z|00483|coverage|INFO|rconn_sent                 2.2/sec    16.900/sec        3.7775/sec   total: 13599
2026-06-22T10:12:11.168Z|00484|coverage|INFO|rconn_queued               2.2/sec    16.900/sec        3.7775/sec   total: 13599
2026-06-22T10:12:11.168Z|00485|coverage|INFO|poll_zero_timeout          0.2/sec     0.283/sec        0.0458/sec   total: 167
2026-06-22T10:12:11.168Z|00486|coverage|INFO|poll_create_node          32.6/sec    25.117/sec        6.1472/sec   total: 22161
2026-06-22T10:12:11.168Z|00487|coverage|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:12:11.168Z|00488|coverage|INFO|txn_success                0.4/sec     0.233/sec        0.0417/sec   total: 150
2026-06-22T10:12:11.168Z|00489|coverage|INFO|txn_incomplete             0.4/sec     0.300/sec        0.0733/sec   total: 264
2026-06-22T10:12:11.168Z|00490|coverage|INFO|txn_unchanged              2.6/sec     2.217/sec        0.5467/sec   total: 1976
2026-06-22T10:12:11.168Z|00491|coverage|INFO|hmap_reserve               6.8/sec     5.883/sec        0.8769/sec   total: 3173
2026-06-22T10:12:11.168Z|00492|coverage|INFO|hmap_expand               31.0/sec   177.967/sec       25.9803/sec   total: 93599
2026-06-22T10:12:11.168Z|00493|coverage|INFO|hmap_pathological          0.0/sec     0.717/sec        0.2033/sec   total: 732
2026-06-22T10:12:11.168Z|00494|coverage|INFO|miniflow_malloc            1.2/sec   251.100/sec       45.0278/sec   total: 162100
2026-06-22T10:12:11.168Z|00495|coverage|INFO|flow_extract               0.0/sec     0.000/sec        0.0133/sec   total: 48
2026-06-22T10:12:11.168Z|00496|coverage|INFO|physical_run               0.0/sec     0.083/sec        0.0258/sec   total: 93
2026-06-22T10:12:11.168Z|00497|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0133/sec   total: 48
2026-06-22T10:12:11.168Z|00498|coverage|INFO|pinctrl_notify_main_thread   0.0/sec     0.000/sec        0.0089/sec   total: 32
2026-06-22T10:12:11.168Z|00499|coverage|INFO|lflow_conj_free            0.0/sec     0.033/sec        0.0075/sec   total: 27
2026-06-22T10:12:11.168Z|00500|coverage|INFO|lflow_conj_alloc           0.0/sec     0.200/sec        0.0200/sec   total: 72
2026-06-22T10:12:11.168Z|00501|coverage|INFO|lflow_cache_trim           0.0/sec     0.017/sec        0.0011/sec   total: 5
2026-06-22T10:12:11.168Z|00502|coverage|INFO|lflow_cache_delete         0.2/sec     3.333/sec        1.2719/sec   total: 4579
2026-06-22T10:12:11.168Z|00503|coverage|INFO|lflow_cache_miss           0.2/sec    13.517/sec        3.3156/sec   total: 11936
2026-06-22T10:12:11.168Z|00504|coverage|INFO|lflow_cache_hit            0.4/sec   135.267/sec       21.5142/sec   total: 77451
2026-06-22T10:12:11.168Z|00505|coverage|INFO|lflow_cache_add            0.0/sec     2.900/sec        1.6786/sec   total: 6043
2026-06-22T10:12:11.168Z|00506|coverage|INFO|lflow_cache_free_matches   0.2/sec     2.183/sec        1.0633/sec   total: 3828
2026-06-22T10:12:11.168Z|00507|coverage|INFO|lflow_cache_free_expr      0.2/sec     1.183/sec        0.2086/sec   total: 751
2026-06-22T10:12:11.168Z|00508|coverage|INFO|lflow_cache_add_matches    0.0/sec     2.133/sec        1.1767/sec   total: 4236
2026-06-22T10:12:11.168Z|00509|coverage|INFO|lflow_cache_add_expr       0.0/sec     0.800/sec        0.5019/sec   total: 1807
2026-06-22T10:12:11.168Z|00510|coverage|INFO|consider_logical_flow      0.4/sec    59.800/sec        7.2122/sec   total: 25964
2026-06-22T10:12:11.168Z|00511|coverage|INFO|lflow_run                  0.0/sec     0.050/sec        0.0042/sec   total: 15
2026-06-22T10:12:11.168Z|00512|coverage|INFO|111 events never hit
2026-06-22T10:12:11.168Z|00513|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (93% CPU usage)
2026-06-22T10:12:11.171Z|00514|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (93% CPU usage)
2026-06-22T10:12:19.529Z|00515|timeval|WARN|Unreasonably long 2342ms poll interval (2ms user, 1324ms system)
2026-06-22T10:12:19.529Z|00516|timeval|WARN|faults: 17 minor, 77 major
2026-06-22T10:12:19.529Z|00517|timeval|WARN|disk: 19592 reads, 0 writes
2026-06-22T10:12:19.529Z|00518|timeval|WARN|context switches: 78 voluntary, 1423 involuntary
2026-06-22T10:12:51.607Z|00519|timeval|WARN|Unreasonably long 9088ms poll interval (3ms user, 7900ms system)
2026-06-22T10:12:51.608Z|00520|timeval|WARN|faults: 168 minor, 1683 major
2026-06-22T10:12:51.608Z|00521|timeval|WARN|disk: 457760 reads, 0 writes
2026-06-22T10:12:51.608Z|00522|timeval|WARN|context switches: 1709 voluntary, 6839 involuntary
2026-06-22T10:12:51.608Z|00523|poll_loop|INFO|Dropped 1 log messages in last 41 seconds (most recently, 41 seconds ago) due to excessive rate
2026-06-22T10:12:51.608Z|00524|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (87% CPU usage)
2026-06-22T10:12:51.611Z|00525|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (87% CPU usage)
2026-06-22T10:12:51.611Z|00526|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (87% CPU usage)
2026-06-22T10:13:29.124Z|00527|memory_trim|INFO|Detected inactivity (last active 51674 ms ago): trimming memory
2026-06-22T10:13:29.125Z|00528|timeval|WARN|Unreasonably long 26578ms poll interval (0ms user, 21196ms system)
2026-06-22T10:13:29.125Z|00529|timeval|WARN|faults: 44 minor, 894 major
2026-06-22T10:13:29.125Z|00530|timeval|WARN|disk: 226104 reads, 8 writes
2026-06-22T10:13:29.125Z|00531|timeval|WARN|context switches: 916 voluntary, 21860 involuntary
2026-06-22T10:13:29.125Z|00532|coverage|INFO|Dropped 2 log messages in last 69 seconds (most recently, 37 seconds ago) due to excessive rate
2026-06-22T10:13:29.125Z|00533|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour,  hash=aa5c8018:
2026-06-22T10:13:29.125Z|00534|coverage|INFO|netlink_sent               0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:13:29.125Z|00535|coverage|INFO|netlink_recv_jumbo         0.0/sec     0.000/sec        0.0006/sec   total: 2
2026-06-22T10:13:29.125Z|00536|coverage|INFO|netlink_received           0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:13:29.125Z|00537|coverage|INFO|vconn_sent                 0.8/sec     0.667/sec        3.7897/sec   total: 13643
2026-06-22T10:13:29.125Z|00538|coverage|INFO|vconn_received             0.8/sec     0.200/sec        0.2208/sec   total: 795
2026-06-22T10:13:29.125Z|00539|coverage|INFO|vconn_open                 0.0/sec     0.000/sec        0.0011/sec   total: 4
2026-06-22T10:13:29.125Z|00540|coverage|INFO|util_xalloc              115.6/sec   799.333/sec     1566.8339/sec   total: 5644366
2026-06-22T10:13:29.125Z|00541|coverage|INFO|long_poll_interval         0.4/sec     0.083/sec        0.0028/sec   total: 10
2026-06-22T10:13:29.125Z|00542|coverage|INFO|stream_open                0.0/sec     0.000/sec        0.0039/sec   total: 14
2026-06-22T10:13:29.125Z|00543|coverage|INFO|pstream_open               0.0/sec     0.000/sec        0.0003/sec   total: 1
2026-06-22T10:13:29.125Z|00544|coverage|INFO|seq_change                 0.6/sec     2.600/sec        2.1286/sec   total: 7706
2026-06-22T10:13:29.125Z|00545|coverage|INFO|rconn_sent                 0.8/sec     0.667/sec        3.7886/sec   total: 13639
2026-06-22T10:13:29.125Z|00546|coverage|INFO|rconn_queued               0.8/sec     0.667/sec        3.7886/sec   total: 13639
2026-06-22T10:13:29.125Z|00547|coverage|INFO|poll_zero_timeout          0.4/sec     0.133/sec        0.0486/sec   total: 177
2026-06-22T10:13:29.125Z|00548|coverage|INFO|poll_create_node           1.6/sec     7.800/sec        6.3031/sec   total: 22800
2026-06-22T10:13:29.125Z|00549|coverage|INFO|txn_try_again              0.0/sec     0.000/sec        0.0008/sec   total: 3
2026-06-22T10:13:29.125Z|00550|coverage|INFO|txn_success                0.0/sec     0.050/sec        0.0425/sec   total: 153
2026-06-22T10:13:29.125Z|00551|coverage|INFO|txn_incomplete             0.0/sec     0.067/sec        0.0744/sec   total: 269
2026-06-22T10:13:29.125Z|00552|coverage|INFO|txn_unchanged              0.6/sec     0.800/sec        0.5622/sec   total: 2031
2026-06-22T10:13:29.125Z|00553|coverage|INFO|hmap_reserve               1.2/sec     1.733/sec        0.9103/sec   total: 3293
2026-06-22T10:13:29.125Z|00554|coverage|INFO|hmap_expand                3.0/sec    10.367/sec       26.1725/sec   total: 94298
2026-06-22T10:13:29.125Z|00555|coverage|INFO|hmap_pathological          0.0/sec     0.200/sec        0.2067/sec   total: 744
2026-06-22T10:13:29.125Z|00556|coverage|INFO|miniflow_malloc            0.0/sec    31.833/sec       45.5583/sec   total: 164010
2026-06-22T10:13:29.125Z|00557|coverage|INFO|flow_extract               0.0/sec     0.000/sec        0.0133/sec   total: 48
2026-06-22T10:13:29.125Z|00558|coverage|INFO|physical_run               0.0/sec     0.000/sec        0.0258/sec   total: 93
2026-06-22T10:13:29.125Z|00559|coverage|INFO|pinctrl_total_pin_pkts     0.0/sec     0.000/sec        0.0133/sec   total: 48
2026-06-22T10:13:29.125Z|00560|coverage|INFO|pinctrl_notify_main_thread   0.0/sec     0.000/sec        0.0089/sec   total: 32
2026-06-22T10:13:29.125Z|00561|coverage|INFO|lflow_conj_free            0.0/sec     0.000/sec        0.0075/sec   total: 27
2026-06-22T10:13:29.125Z|00562|coverage|INFO|lflow_conj_alloc           0.0/sec     0.000/sec        0.0200/sec   total: 72
2026-06-22T10:13:29.125Z|00563|coverage|INFO|lflow_cache_trim           0.0/sec     0.000/sec        0.0014/sec   total: 6
2026-06-22T10:13:29.125Z|00564|coverage|INFO|lflow_cache_delete         0.0/sec     1.333/sec        1.2942/sec   total: 4659
2026-06-22T10:13:29.125Z|00565|coverage|INFO|lflow_cache_miss           0.0/sec     2.750/sec        3.3614/sec   total: 12101
2026-06-22T10:13:29.125Z|00566|coverage|INFO|lflow_cache_hit            0.0/sec    17.600/sec       21.8075/sec   total: 78507
2026-06-22T10:13:29.125Z|00567|coverage|INFO|lflow_cache_add            0.0/sec     1.117/sec        1.6972/sec   total: 6110
2026-06-22T10:13:29.125Z|00568|coverage|INFO|lflow_cache_free_matches   0.0/sec     1.150/sec        1.0825/sec   total: 3897
2026-06-22T10:13:29.125Z|00569|coverage|INFO|lflow_cache_free_expr      0.0/sec     0.183/sec        0.2117/sec   total: 762
2026-06-22T10:13:29.125Z|00570|coverage|INFO|lflow_cache_add_matches    0.0/sec     1.100/sec        1.1950/sec   total: 4302
2026-06-22T10:13:29.125Z|00571|coverage|INFO|lflow_cache_add_expr       0.0/sec     0.017/sec        0.5022/sec   total: 1808
2026-06-22T10:13:29.125Z|00572|coverage|INFO|consider_logical_flow      0.0/sec     1.200/sec        7.2322/sec   total: 26036
2026-06-22T10:13:29.125Z|00573|coverage|INFO|lflow_run                  0.0/sec     0.000/sec        0.0042/sec   total: 15
2026-06-22T10:13:29.125Z|00574|coverage|INFO|111 events never hit
2026-06-22T10:13:29.125Z|00575|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (80% CPU usage)
2026-06-22T10:13:29.125Z|00576|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (80% CPU usage)
2026-06-22T10:13:29.126Z|00577|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (80% CPU usage)
2026-06-22T10:13:29.126Z|00578|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (80% CPU usage)
2026-06-22T10:13:40.876Z|00579|timeval|WARN|Unreasonably long 6730ms poll interval (4ms user, 6131ms system)
2026-06-22T10:13:40.877Z|00580|timeval|WARN|faults: 129 minor, 1179 major
2026-06-22T10:13:40.877Z|00581|timeval|WARN|disk: 318920 reads, 0 writes
2026-06-22T10:13:40.877Z|00582|timeval|WARN|context switches: 1198 voluntary, 5293 involuntary
2026-06-22T10:13:40.877Z|00583|poll_loop|INFO|wakeup due to 0-ms timeout at lib/reconnect.c:677 (91% CPU usage)
2026-06-22T10:13:40.882Z|00584|poll_loop|INFO|wakeup due to [POLLIN] on fd 29 (10.4.3.99:53868<->10.4.3.99:6642) at lib/stream-ssl.c:842 (91% CPU usage)
2026-06-22T10:13:40.883Z|00585|poll_loop|INFO|wakeup due to 0-ms timeout at lib/stream-ssl.c:844 (91% CPU usage)