+ 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 f1669a80-a841-467b-b0f5-1c143d3c8cae + 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-07-24T05:12:45.113Z level=INFO msg="no existing process found" binary=ovs-vswitchd time=2026-07-24T05:12:45.114Z level=INFO msg="claimed succession" binary=ovs-vswitchd pod=openvswitch-fgjdq time=2026-07-24T05:12:45.114Z level=INFO msg="starting process" binary=ovs-vswitchd 2026-07-24T05:12:45Z|00001|ovs_numa|INFO|Discovered 16 CPU cores on NUMA node 0 2026-07-24T05:12:45Z|00002|ovs_numa|INFO|Discovered 1 NUMA nodes and 16 CPU cores 2026-07-24T05:12:45Z|00003|reconnect|INFO|unix:/run/openvswitch/db.sock: connecting... 2026-07-24T05:12:45Z|00004|reconnect|INFO|unix:/run/openvswitch/db.sock: connected 2026-07-24T05:12:45Z|00005|dpdk|INFO|DPDK Disabled - Use other_config:dpdk-init to enable 2026-07-24T05:12:45Z|00006|bridge|INFO|ovs-vswitchd (Open vSwitch) 3.3.7 2026-07-24T05:13:00Z|00007|memory|INFO|317340 kB peak resident set size after 14.9 seconds 2026-07-24T05:13:00Z|00008|memory|INFO|idl-cells-Open_vSwitch:17 2026-07-24T05:19:56Z|00009|dpif_netlink|INFO|Datapath dispatch mode: per-cpu 2026-07-24T05:19:56Z|00010|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation 2026-07-24T05:19:56Z|00011|ofproto_dpif|INFO|system@ovs-system: VLAN header stack length probed as 2 2026-07-24T05:19:56Z|00012|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 3 2026-07-24T05:19:56Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports truncate action 2026-07-24T05:19:56Z|00014|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids 2026-07-24T05:19:56Z|00015|ofproto_dpif|INFO|system@ovs-system: Datapath supports clone action 2026-07-24T05:19:56Z|00016|ofproto_dpif|INFO|system@ovs-system: Max sample nesting level probed as 10 2026-07-24T05:19:56Z|00017|ofproto_dpif|INFO|system@ovs-system: Datapath supports eventmask in conntrack action 2026-07-24T05:19:56Z|00018|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_clear action 2026-07-24T05:19:56Z|00019|ofproto_dpif|INFO|system@ovs-system: Max dp_hash algorithm probed to be 0 2026-07-24T05:19:56Z|00020|ofproto_dpif|INFO|system@ovs-system: Datapath supports check_pkt_len action 2026-07-24T05:19:56Z|00021|ofproto_dpif|INFO|system@ovs-system: Datapath supports timeout policy in conntrack action 2026-07-24T05:19:56Z|00022|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zero_snat 2026-07-24T05:19:56Z|00023|ofproto_dpif|INFO|system@ovs-system: Datapath supports add_mpls action 2026-07-24T05:19:56Z|00024|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state 2026-07-24T05:19:56Z|00025|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone 2026-07-24T05:19:56Z|00026|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark 2026-07-24T05:19:56Z|00027|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label 2026-07-24T05:19:56Z|00028|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat 2026-07-24T05:19:56Z|00029|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple 2026-07-24T05:19:56Z|00030|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple6 2026-07-24T05:19:56Z|00031|ofproto_dpif|INFO|system@ovs-system: Datapath does not support IPv6 ND Extensions 2026-07-24T05:19:56Z|00032|ofproto_dpif_upcall|INFO|Overriding n-handler-threads to 16, setting n-revalidator-threads to 5 2026-07-24T05:19:56Z|00033|ofproto_dpif_upcall|INFO|Starting 21 threads 2026-07-24T05:19:57Z|00034|bridge|INFO|bridge br-ex: added interface br-ex on port 65534 2026-07-24T05:19:57Z|00035|bridge|INFO|bridge br-ex: using datapath ID 00009686f67e134d 2026-07-24T05:19:57Z|00036|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt" 2026-07-24T05:20:07Z|00037|memory|INFO|peak resident set size grew 57% in last 427.0 seconds, from 317340 kB to 498044 kB 2026-07-24T05:20:07Z|00038|memory|INFO|handlers:16 idl-cells-Open_vSwitch:96 ports:1 revalidators:5 rules:5 2026-07-24T05:20:12Z|00039|bridge|INFO|bridge br-int: added interface br-int on port 65534 2026-07-24T05:20:12Z|00040|bridge|INFO|bridge br-int: using datapath ID 0000ceccdff28647 2026-07-24T05:20:12Z|00041|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt" 2026-07-24T05:20:12Z|00042|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting... 2026-07-24T05:20:12Z|00043|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633" 2026-07-24T05:20:13Z|00044|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected 2026-07-24T05:20:13Z|00045|connmgr|INFO|br-ex: Changes to controller "punix:/var/run/openvswitch/br-ex.mgmt" expects re-initialization: Re-initializing now. 2026-07-24T05:20:13Z|00046|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt" 2026-07-24T05:20:13Z|00047|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connecting... 2026-07-24T05:20:13Z|00048|connmgr|INFO|br-ex: added primary controller "tcp:127.0.0.1:6633" 2026-07-24T05:20:13Z|00049|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connected 2026-07-24T05:20:14Z|00050|bridge|INFO|bridge br-int: added interface int-br-ex on port 1 2026-07-24T05:20:14Z|00051|bridge|INFO|bridge br-ex: added interface phy-br-ex on port 1 2026-07-24T05:20:14Z|00052|bridge|INFO|bridge br-tun: added interface br-tun on port 65534 2026-07-24T05:20:14Z|00053|bridge|INFO|bridge br-tun: using datapath ID 00004e7e0288674d 2026-07-24T05:20:14Z|00054|connmgr|INFO|br-tun: added service controller "punix:/var/run/openvswitch/br-tun.mgmt" 2026-07-24T05:20:14Z|00055|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connecting... 2026-07-24T05:20:14Z|00056|connmgr|INFO|br-tun: added primary controller "tcp:127.0.0.1:6633" 2026-07-24T05:20:14Z|00057|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connected 2026-07-24T05:20:15Z|00058|bridge|INFO|bridge br-int: added interface patch-tun on port 2 2026-07-24T05:20:15Z|00059|bridge|INFO|bridge br-tun: added interface patch-int on port 1 2026-07-24T05:20:23Z|00060|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 10 flow_mods in the 1 s starting 10 s ago (10 adds) 2026-07-24T05:20:24Z|00061|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 2 flow_mods 10 s ago (2 adds) 2026-07-24T05:20:25Z|00062|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 15 flow_mods 10 s ago (15 adds) 2026-07-24T05:20:54Z|00063|bridge|INFO|bridge br-int: added interface tap45dd8816-a6 on port 3 2026-07-24T05:20:54Z|00001|ofproto_dpif_xlate(handler15)|WARN|dropping VLAN 0 packet received on port tap45dd8816-a6 not configured for trunking VLAN 0 on bridge br-int while processing pkt_mark=0xd4,skb_priority=0x7,icmp6,in_port=3,vlan_tci=0x0000,dl_src=fa:16:3e:47:0a:6e,dl_dst=33:33:00:00:00:16,ipv6_src=::,ipv6_dst=ff02::16,ipv6_label=0x00000,nw_tos=0,nw_ecn=0,nw_ttl=1,nw_frag=no,icmp_type=143,icmp_code=0 2026-07-24T05:21:23Z|00064|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 13 flow_mods in the 2 s starting 28 s ago (1 adds, 12 deletes) 2026-07-24T05:21:24Z|00065|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 1 flow_mods 29 s ago (1 adds) 2026-07-24T05:26:02Z|00066|bridge|INFO|bridge br-int: added interface tap4414097d-2e on port 4 2026-07-24T05:26:03Z|00001|ofproto_dpif_xlate(handler1)|WARN|dropping VLAN 0 packet received on port tap4414097d-2e not configured for trunking VLAN 0 on bridge br-int while processing pkt_mark=0x106,skb_priority=0x7,icmp6,in_port=4,vlan_tci=0x0000,dl_src=fa:16:3e:81:11:e9,dl_dst=33:33:00:00:00:16,ipv6_src=::,ipv6_dst=ff02::16,ipv6_label=0x00000,nw_tos=0,nw_ecn=0,nw_ttl=1,nw_frag=no,icmp_type=143,icmp_code=0 2026-07-24T05:26:13Z|00067|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 1 flow_mods 10 s ago (1 adds) 2026-07-24T05:26:13Z|00068|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 12 flow_mods in the 2 s starting 10 s ago (12 deletes) 2026-07-24T05:38:28Z|00069|timeval|WARN|Unreasonably long 2588ms poll interval (1951ms user, 545ms system) 2026-07-24T05:38:28Z|00070|timeval|WARN|context switches: 0 voluntary, 468 involuntary 2026-07-24T05:38:28Z|00071|coverage|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=6c429fd2: 2026-07-24T05:38:28Z|00072|coverage|INFO|bridge_reconfigure 0.0/sec 0.000/sec 0.0711/sec total: 256 2026-07-24T05:38:28Z|00073|coverage|INFO|ofproto_flush 0.0/sec 0.000/sec 0.0019/sec total: 7 2026-07-24T05:38:28Z|00074|coverage|INFO|ofproto_recv_openflow 1.4/sec 1.200/sec 0.4158/sec total: 1497 2026-07-24T05:38:28Z|00075|coverage|INFO|ofproto_update_port 0.0/sec 0.000/sec 0.0067/sec total: 24 2026-07-24T05:38:28Z|00076|coverage|INFO|rev_reconfigure 0.0/sec 0.000/sec 0.0039/sec total: 14 2026-07-24T05:38:28Z|00077|coverage|INFO|rev_port_toggled 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-07-24T05:38:28Z|00078|coverage|INFO|rev_flow_table 0.0/sec 0.000/sec 0.0169/sec total: 61 2026-07-24T05:38:28Z|00079|coverage|INFO|rev_mac_learning 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-07-24T05:38:28Z|00080|coverage|INFO|upcall_flow_limit_reduced 0.0/sec 0.000/sec 0.0003/sec total: 2 2026-07-24T05:38:28Z|00081|coverage|INFO|xlate_actions 0.0/sec 0.000/sec 0.0083/sec total: 30 2026-07-24T05:38:28Z|00082|coverage|INFO|cmap_expand 0.0/sec 0.000/sec 0.8647/sec total: 3113 2026-07-24T05:38:28Z|00083|coverage|INFO|cmap_shrink 0.0/sec 0.000/sec 0.8458/sec total: 3045 2026-07-24T05:38:28Z|00084|coverage|INFO|dpif_execute 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-07-24T05:38:28Z|00085|coverage|INFO|dpif_execute_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-24T05:38:28Z|00086|coverage|INFO|dpif_flow_del 0.0/sec 0.000/sec 0.0078/sec total: 28 2026-07-24T05:38:28Z|00087|coverage|INFO|dpif_flow_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-24T05:38:28Z|00088|coverage|INFO|dpif_flow_get 0.0/sec 0.000/sec 0.0067/sec total: 24 2026-07-24T05:38:28Z|00089|coverage|INFO|dpif_flow_put 0.0/sec 0.000/sec 0.0081/sec total: 29 2026-07-24T05:38:28Z|00090|coverage|INFO|dpif_flow_put_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-24T05:38:28Z|00091|coverage|INFO|dpif_port_add 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-07-24T05:38:28Z|00092|coverage|INFO|flow_extract 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-07-24T05:38:28Z|00093|coverage|INFO|miniflow_malloc 1.2/sec 1.000/sec 13.7603/sec total: 49537 2026-07-24T05:38:28Z|00094|coverage|INFO|hindex_expand 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-24T05:38:28Z|00095|coverage|INFO|hmap_pathological 0.0/sec 0.000/sec 0.0028/sec total: 10 2026-07-24T05:38:28Z|00096|coverage|INFO|hmap_expand 28.8/sec 25.017/sec 10.8811/sec total: 39184 2026-07-24T05:38:28Z|00097|coverage|INFO|mac_learning_expired 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-24T05:38:28Z|00098|coverage|INFO|netdev_get_stats 3.6/sec 1.950/sec 0.5269/sec total: 1897 2026-07-24T05:38:28Z|00099|coverage|INFO|txn_unchanged 0.0/sec 0.000/sec 0.1842/sec total: 663 2026-07-24T05:38:28Z|00100|coverage|INFO|txn_incomplete 0.4/sec 0.217/sec 0.0781/sec total: 281 2026-07-24T05:38:28Z|00101|coverage|INFO|txn_success 0.2/sec 0.200/sec 0.0650/sec total: 235 2026-07-24T05:38:28Z|00102|coverage|INFO|txn_try_again 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-24T05:38:28Z|00103|coverage|INFO|poll_create_node 221.2/sec 212.667/sec 75.6269/sec total: 272347 2026-07-24T05:38:28Z|00104|coverage|INFO|poll_zero_timeout 0.4/sec 0.367/sec 0.1803/sec total: 649 2026-07-24T05:38:28Z|00105|coverage|INFO|rconn_queued 1.6/sec 1.217/sec 0.4050/sec total: 1458 2026-07-24T05:38:28Z|00106|coverage|INFO|rconn_sent 1.6/sec 1.217/sec 0.4050/sec total: 1458 2026-07-24T05:38:28Z|00107|coverage|INFO|seq_change 1061.6/sec 1057.900/sec 376.6411/sec total: 1356754 2026-07-24T05:38:28Z|00108|coverage|INFO|pstream_open 0.0/sec 0.000/sec 0.0022/sec total: 8 2026-07-24T05:38:28Z|00109|coverage|INFO|stream_open 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-07-24T05:38:28Z|00110|coverage|INFO|unixctl_received 0.0/sec 0.033/sec 0.0142/sec total: 51 2026-07-24T05:38:28Z|00111|coverage|INFO|unixctl_replied 0.0/sec 0.033/sec 0.0142/sec total: 51 2026-07-24T05:38:28Z|00112|coverage|INFO|util_xalloc 595.8/sec 488.567/sec 479.3878/sec total: 1726183 2026-07-24T05:38:28Z|00113|coverage|INFO|vconn_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-24T05:38:28Z|00114|coverage|INFO|vconn_received 1.4/sec 1.200/sec 0.4167/sec total: 1500 2026-07-24T05:38:28Z|00115|coverage|INFO|vconn_sent 1.6/sec 1.217/sec 0.4058/sec total: 1461 2026-07-24T05:38:28Z|00116|coverage|INFO|netdev_set_policing 0.0/sec 0.000/sec 0.0025/sec total: 9 2026-07-24T05:38:28Z|00117|coverage|INFO|netdev_set_hwaddr 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-24T05:38:28Z|00118|coverage|INFO|netlink_received 9.8/sec 9.067/sec 38.1725/sec total: 137426 2026-07-24T05:38:28Z|00119|coverage|INFO|netlink_recv_jumbo 0.0/sec 0.000/sec 6.0300/sec total: 21708 2026-07-24T05:38:28Z|00120|coverage|INFO|netlink_sent 8.0/sec 7.083/sec 36.5681/sec total: 131648 2026-07-24T05:38:28Z|00121|coverage|INFO|route_table_dump 0.0/sec 0.000/sec 0.0847/sec total: 305 2026-07-24T05:38:28Z|00122|coverage|INFO|nln_changed 0.0/sec 0.000/sec 0.2231/sec total: 803 2026-07-24T05:38:28Z|00123|coverage|INFO|129 events never hit 2026-07-24T05:38:28Z|00124|poll_loop|INFO|wakeup due to [POLLIN] on fd 19 (FIFO pipe:[639072]) at vswitchd/bridge.c:424 (59% CPU usage) 2026-07-24T05:38:28Z|00125|poll_loop|INFO|wakeup due to [POLLIN] on fd 19 (FIFO pipe:[639072]) at vswitchd/bridge.c:424 (59% CPU usage) 2026-07-24T05:38:28Z|00126|poll_loop|INFO|wakeup due to [POLLIN] on fd 62 (127.0.0.1:44588<->127.0.0.1:6633) at lib/stream-fd.c:157 (59% CPU usage) 2026-07-24T05:38:28Z|00127|poll_loop|INFO|wakeup due to [POLLIN] on fd 63 (127.0.0.1:44596<->127.0.0.1:6633) at lib/stream-fd.c:157 (59% CPU usage) 2026-07-24T05:38:29Z|00128|poll_loop|INFO|wakeup due to [POLLIN] on fd 19 (FIFO pipe:[639072]) at vswitchd/bridge.c:424 (59% CPU usage) 2026-07-24T05:38:29Z|00129|poll_loop|INFO|wakeup due to [POLLIN] on fd 19 (FIFO pipe:[639072]) at vswitchd/bridge.c:424 (59% CPU usage) 2026-07-24T05:38:30Z|00130|poll_loop|INFO|wakeup due to [POLLIN] on fd 19 (FIFO pipe:[639072]) at vswitchd/bridge.c:424 (59% CPU usage) 2026-07-24T05:38:30Z|00131|poll_loop|INFO|wakeup due to [POLLIN] on fd 19 (FIFO pipe:[639072]) at vswitchd/bridge.c:424 (59% CPU usage) 2026-07-24T05:38:30Z|00132|poll_loop|INFO|wakeup due to [POLLIN] on fd 62 (127.0.0.1:44588<->127.0.0.1:6633) at lib/stream-fd.c:157 (59% CPU usage) 2026-07-24T05:38:31Z|00133|poll_loop|INFO|wakeup due to [POLLIN] on fd 19 (FIFO pipe:[639072]) at vswitchd/bridge.c:424 (59% CPU usage)