+ COMMAND=start + OVS_SOCKET=/run/openvswitch/db.sock + OVS_PID=/run/openvswitch/ovs-vswitchd.pid + start + t=0 + '[' '!' -e /run/openvswitch/db.sock ']' + ovs-vsctl --db=unix:/run/openvswitch/db.sock --no-wait show 820ba977-5c58-4d7d-ba85-6ff4a553d7f5 + exec ovsinit -- /usr/sbin/ovs-vswitchd unix:/run/openvswitch/db.sock -vconsole:emer -vconsole:err -vconsole:info --pidfile=/run/openvswitch/ovs-vswitchd.pid --user=openvswitch:openvswitch --mlockall time=2026-08-06T01:29:59.800Z level=INFO msg="no existing process found" binary=ovs-vswitchd time=2026-08-06T01:29:59.800Z level=INFO msg="claimed succession" binary=ovs-vswitchd pod=openvswitch-dwqcr time=2026-08-06T01:29:59.800Z level=INFO msg="starting process" binary=ovs-vswitchd 2026-08-06T01:29:59Z|00001|ovs_numa|INFO|Discovered 16 CPU cores on NUMA node 0 2026-08-06T01:29:59Z|00002|ovs_numa|INFO|Discovered 1 NUMA nodes and 16 CPU cores 2026-08-06T01:29:59Z|00003|reconnect|INFO|unix:/run/openvswitch/db.sock: connecting... 2026-08-06T01:29:59Z|00004|reconnect|INFO|unix:/run/openvswitch/db.sock: connected 2026-08-06T01:29:59Z|00005|dpdk|INFO|DPDK Disabled - Use other_config:dpdk-init to enable 2026-08-06T01:29:59Z|00006|bridge|INFO|ovs-vswitchd (Open vSwitch) 3.3.7 2026-08-06T01:30:10Z|00007|memory|INFO|317436 kB peak resident set size after 10.2 seconds 2026-08-06T01:30:10Z|00008|memory|INFO|idl-cells-Open_vSwitch:17 2026-08-06T01:36:21Z|00009|dpif_netlink|INFO|Datapath dispatch mode: per-cpu 2026-08-06T01:36:21Z|00010|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation 2026-08-06T01:36:21Z|00011|ofproto_dpif|INFO|system@ovs-system: VLAN header stack length probed as 2 2026-08-06T01:36:21Z|00012|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 3 2026-08-06T01:36:21Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports truncate action 2026-08-06T01:36:21Z|00014|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids 2026-08-06T01:36:21Z|00015|ofproto_dpif|INFO|system@ovs-system: Datapath supports clone action 2026-08-06T01:36:21Z|00016|ofproto_dpif|INFO|system@ovs-system: Max sample nesting level probed as 10 2026-08-06T01:36:21Z|00017|ofproto_dpif|INFO|system@ovs-system: Datapath supports eventmask in conntrack action 2026-08-06T01:36:21Z|00018|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_clear action 2026-08-06T01:36:21Z|00019|ofproto_dpif|INFO|system@ovs-system: Max dp_hash algorithm probed to be 0 2026-08-06T01:36:21Z|00020|ofproto_dpif|INFO|system@ovs-system: Datapath supports check_pkt_len action 2026-08-06T01:36:21Z|00021|ofproto_dpif|INFO|system@ovs-system: Datapath supports timeout policy in conntrack action 2026-08-06T01:36:21Z|00022|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zero_snat 2026-08-06T01:36:21Z|00023|ofproto_dpif|INFO|system@ovs-system: Datapath supports add_mpls action 2026-08-06T01:36:21Z|00024|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state 2026-08-06T01:36:21Z|00025|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone 2026-08-06T01:36:21Z|00026|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark 2026-08-06T01:36:21Z|00027|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label 2026-08-06T01:36:21Z|00028|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat 2026-08-06T01:36:21Z|00029|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple 2026-08-06T01:36:21Z|00030|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple6 2026-08-06T01:36:21Z|00031|ofproto_dpif|INFO|system@ovs-system: Datapath does not support IPv6 ND Extensions 2026-08-06T01:36:21Z|00032|ofproto_dpif_upcall|INFO|Overriding n-handler-threads to 16, setting n-revalidator-threads to 5 2026-08-06T01:36:21Z|00033|ofproto_dpif_upcall|INFO|Starting 21 threads 2026-08-06T01:36:21Z|00034|bridge|INFO|bridge br-ex: added interface br-ex on port 65534 2026-08-06T01:36:21Z|00035|bridge|INFO|bridge br-ex: using datapath ID 000052f290434847 2026-08-06T01:36:21Z|00036|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt" 2026-08-06T01:36:22Z|00037|bridge|INFO|bridge br-int: added interface br-int on port 65534 2026-08-06T01:36:22Z|00038|bridge|INFO|bridge br-int: using datapath ID 0000d652a95c0ddc 2026-08-06T01:36:22Z|00039|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt" 2026-08-06T01:36:31Z|00040|memory|INFO|peak resident set size grew 57% in last 381.2 seconds, from 317436 kB to 498312 kB 2026-08-06T01:36:31Z|00041|memory|INFO|handlers:16 idl-cells-Open_vSwitch:186 ofconns:4 ports:2 revalidators:5 rules:26 2026-08-06T01:36:33Z|00042|connmgr|INFO|br-int<->unix#14: 18 flow_mods 10 s ago (17 adds, 1 deletes) 2026-08-06T01:45:05Z|00043|bridge|INFO|bridge br-int: added interface o-hm0 on port 1 2026-08-06T01:45:09Z|00044|bridge|INFO|bridge br-int: added interface tap3b122722-a0 on port 2 2026-08-06T01:45:15Z|00045|connmgr|INFO|br-int<->unix#14: 267 flow_mods in the 3 s starting 10 s ago (266 adds, 1 deletes) 2026-08-06T01:55:45Z|00046|timeval|WARN|Unreasonably long 9030ms poll interval (17ms user, 1ms system) 2026-08-06T01:55:45Z|00047|timeval|WARN|context switches: 0 voluntary, 6 involuntary 2026-08-06T01:55:45Z|00048|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=c91dc63e: 2026-08-06T01:55:45Z|00049|coverage|INFO|bridge_reconfigure 0.0/sec 0.067/sec 0.0694/sec total: 250 2026-08-06T01:55:45Z|00050|coverage|INFO|ofproto_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00051|coverage|INFO|ofproto_recv_openflow 0.0/sec 0.067/sec 0.1075/sec total: 387 2026-08-06T01:55:45Z|00052|coverage|INFO|ofproto_update_port 0.0/sec 0.000/sec 0.0042/sec total: 15 2026-08-06T01:55:45Z|00053|coverage|INFO|rev_reconfigure 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-08-06T01:55:45Z|00054|coverage|INFO|rev_port_toggled 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00055|coverage|INFO|rev_flow_table 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-08-06T01:55:45Z|00056|coverage|INFO|xlate_actions 0.0/sec 0.017/sec 0.0047/sec total: 17 2026-08-06T01:55:45Z|00057|coverage|INFO|cmap_expand 0.0/sec 0.917/sec 0.9822/sec total: 3536 2026-08-06T01:55:45Z|00058|coverage|INFO|cmap_shrink 0.0/sec 0.917/sec 0.9078/sec total: 3268 2026-08-06T01:55:45Z|00059|coverage|INFO|dpif_execute 0.0/sec 0.000/sec 0.0031/sec total: 11 2026-08-06T01:55:45Z|00060|coverage|INFO|dpif_execute_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00061|coverage|INFO|dpif_flow_del 0.0/sec 0.017/sec 0.0089/sec total: 32 2026-08-06T01:55:45Z|00062|coverage|INFO|dpif_flow_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00063|coverage|INFO|dpif_flow_get 0.0/sec 0.000/sec 0.0067/sec total: 24 2026-08-06T01:55:45Z|00064|coverage|INFO|dpif_flow_put 0.0/sec 0.017/sec 0.0092/sec total: 33 2026-08-06T01:55:45Z|00065|coverage|INFO|dpif_flow_put_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00066|coverage|INFO|dpif_port_add 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-08-06T01:55:45Z|00067|coverage|INFO|flow_extract 0.0/sec 0.017/sec 0.0050/sec total: 18 2026-08-06T01:55:45Z|00068|coverage|INFO|miniflow_malloc 0.0/sec 15.717/sec 14.5178/sec total: 52264 2026-08-06T01:55:45Z|00069|coverage|INFO|hindex_pathological 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00070|coverage|INFO|hindex_expand 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-08-06T01:55:45Z|00071|coverage|INFO|hmap_pathological 0.0/sec 0.000/sec 0.0033/sec total: 12 2026-08-06T01:55:45Z|00072|coverage|INFO|hmap_expand 3.0/sec 15.350/sec 7.1328/sec total: 25678 2026-08-06T01:55:45Z|00073|coverage|INFO|netdev_get_stats 0.4/sec 0.733/sec 0.1997/sec total: 719 2026-08-06T01:55:45Z|00074|coverage|INFO|txn_unchanged 0.2/sec 0.350/sec 0.2481/sec total: 893 2026-08-06T01:55:45Z|00075|coverage|INFO|txn_incomplete 0.0/sec 0.017/sec 0.0106/sec total: 38 2026-08-06T01:55:45Z|00076|coverage|INFO|txn_success 0.0/sec 0.017/sec 0.0058/sec total: 21 2026-08-06T01:55:45Z|00077|coverage|INFO|poll_create_node 34.8/sec 150.867/sec 59.6617/sec total: 214782 2026-08-06T01:55:45Z|00078|coverage|INFO|poll_zero_timeout 0.2/sec 0.500/sec 0.2181/sec total: 785 2026-08-06T01:55:45Z|00079|coverage|INFO|rconn_queued 0.0/sec 0.067/sec 0.0275/sec total: 99 2026-08-06T01:55:45Z|00080|coverage|INFO|rconn_sent 0.0/sec 0.067/sec 0.0275/sec total: 99 2026-08-06T01:55:45Z|00081|coverage|INFO|seq_change 233.2/sec 975.517/sec 388.7758/sec total: 1399593 2026-08-06T01:55:45Z|00082|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-08-06T01:55:45Z|00083|coverage|INFO|stream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00084|coverage|INFO|unixctl_received 0.0/sec 0.050/sec 0.0197/sec total: 71 2026-08-06T01:55:45Z|00085|coverage|INFO|unixctl_replied 0.0/sec 0.050/sec 0.0197/sec total: 71 2026-08-06T01:55:45Z|00086|coverage|INFO|util_xalloc 76.6/sec 642.200/sec 435.7803/sec total: 1568809 2026-08-06T01:55:45Z|00087|coverage|INFO|vconn_received 0.0/sec 0.067/sec 0.1086/sec total: 391 2026-08-06T01:55:45Z|00088|coverage|INFO|vconn_sent 0.0/sec 0.067/sec 0.0286/sec total: 103 2026-08-06T01:55:45Z|00089|coverage|INFO|netdev_set_policing 0.0/sec 0.000/sec 0.0033/sec total: 12 2026-08-06T01:55:45Z|00090|coverage|INFO|netdev_set_hwaddr 0.0/sec 0.000/sec 0.0006/sec total: 2 2026-08-06T01:55:45Z|00091|coverage|INFO|netdev_get_ethtool 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-08-06T01:55:45Z|00092|coverage|INFO|netdev_set_ethtool 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-08-06T01:55:45Z|00093|coverage|INFO|netlink_received 2.2/sec 48.633/sec 40.0050/sec total: 144018 2026-08-06T01:55:45Z|00094|coverage|INFO|netlink_recv_jumbo 0.2/sec 7.433/sec 6.5200/sec total: 23472 2026-08-06T01:55:45Z|00095|coverage|INFO|netlink_sent 1.8/sec 45.583/sec 38.3089/sec total: 137912 2026-08-06T01:55:45Z|00096|coverage|INFO|route_table_dump 0.0/sec 0.083/sec 0.0886/sec total: 319 2026-08-06T01:55:45Z|00097|coverage|INFO|nln_changed 0.0/sec 0.217/sec 0.2372/sec total: 854 2026-08-06T01:55:45Z|00098|coverage|INFO|131 events never hit 2026-08-06T01:55:45Z|00001|timeval(revalidator22)|WARN|Unreasonably long 9096ms poll interval (569ms user, 11ms system) 2026-08-06T01:55:45Z|00001|ofproto_dpif_upcall(revalidator18)|WARN|Spent an unreasonably long 10015ms dumping flows 2026-08-06T01:55:45Z|00002|timeval(revalidator22)|WARN|context switches: 0 voluntary, 42 involuntary 2026-08-06T01:55:45Z|00003|coverage(revalidator22)|INFO|Skipping details of duplicate event coverage for hash=c91dc63e