+ 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 a956324a-b431-4236-9e04-c9a9654048da + 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-29T14:27:16.248Z level=INFO msg="no existing process found" binary=ovs-vswitchd time=2026-07-29T14:27:16.248Z level=INFO msg="claimed succession" binary=ovs-vswitchd pod=openvswitch-tm9fk time=2026-07-29T14:27:16.248Z level=INFO msg="starting process" binary=ovs-vswitchd 2026-07-29T14:27:16Z|00001|ovs_numa|INFO|Discovered 16 CPU cores on NUMA node 0 2026-07-29T14:27:16Z|00002|ovs_numa|INFO|Discovered 1 NUMA nodes and 16 CPU cores 2026-07-29T14:27:16Z|00003|reconnect|INFO|unix:/run/openvswitch/db.sock: connecting... 2026-07-29T14:27:16Z|00004|reconnect|INFO|unix:/run/openvswitch/db.sock: connected 2026-07-29T14:27:16Z|00005|dpdk|INFO|DPDK Disabled - Use other_config:dpdk-init to enable 2026-07-29T14:27:16Z|00006|bridge|INFO|ovs-vswitchd (Open vSwitch) 3.3.7 2026-07-29T14:27:30Z|00007|memory|INFO|317200 kB peak resident set size after 14.1 seconds 2026-07-29T14:27:30Z|00008|memory|INFO|idl-cells-Open_vSwitch:17 2026-07-29T14:42:24Z|00009|dpif_netlink|INFO|Datapath dispatch mode: per-cpu 2026-07-29T14:42:24Z|00010|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation 2026-07-29T14:42:24Z|00011|ofproto_dpif|INFO|system@ovs-system: VLAN header stack length probed as 2 2026-07-29T14:42:24Z|00012|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 3 2026-07-29T14:42:24Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports truncate action 2026-07-29T14:42:24Z|00014|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids 2026-07-29T14:42:24Z|00015|ofproto_dpif|INFO|system@ovs-system: Datapath supports clone action 2026-07-29T14:42:24Z|00016|ofproto_dpif|INFO|system@ovs-system: Max sample nesting level probed as 10 2026-07-29T14:42:24Z|00017|ofproto_dpif|INFO|system@ovs-system: Datapath supports eventmask in conntrack action 2026-07-29T14:42:24Z|00018|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_clear action 2026-07-29T14:42:24Z|00019|ofproto_dpif|INFO|system@ovs-system: Max dp_hash algorithm probed to be 0 2026-07-29T14:42:24Z|00020|ofproto_dpif|INFO|system@ovs-system: Datapath supports check_pkt_len action 2026-07-29T14:42:24Z|00021|ofproto_dpif|INFO|system@ovs-system: Datapath supports timeout policy in conntrack action 2026-07-29T14:42:24Z|00022|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zero_snat 2026-07-29T14:42:24Z|00023|ofproto_dpif|INFO|system@ovs-system: Datapath supports add_mpls action 2026-07-29T14:42:24Z|00024|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state 2026-07-29T14:42:24Z|00025|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone 2026-07-29T14:42:24Z|00026|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark 2026-07-29T14:42:24Z|00027|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label 2026-07-29T14:42:24Z|00028|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat 2026-07-29T14:42:24Z|00029|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple 2026-07-29T14:42:24Z|00030|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple6 2026-07-29T14:42:24Z|00031|ofproto_dpif|INFO|system@ovs-system: Datapath does not support IPv6 ND Extensions 2026-07-29T14:42:24Z|00032|ofproto_dpif_upcall|INFO|Overriding n-handler-threads to 16, setting n-revalidator-threads to 5 2026-07-29T14:42:24Z|00033|ofproto_dpif_upcall|INFO|Starting 21 threads 2026-07-29T14:42:25Z|00034|bridge|INFO|bridge br-ex: added interface br-ex on port 65534 2026-07-29T14:42:25Z|00035|bridge|INFO|bridge br-ex: using datapath ID 00000af9a4f7fb47 2026-07-29T14:42:25Z|00036|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt" 2026-07-29T14:42:35Z|00037|memory|INFO|peak resident set size grew 57% in last 904.6 seconds, from 317200 kB to 498052 kB 2026-07-29T14:42:35Z|00038|memory|INFO|handlers:16 idl-cells-Open_vSwitch:96 ports:1 revalidators:5 rules:5 2026-07-29T14:42:38Z|00039|bridge|INFO|bridge br-int: added interface br-int on port 65534 2026-07-29T14:42:38Z|00040|bridge|INFO|bridge br-int: using datapath ID 00007a6b366ada48 2026-07-29T14:42:38Z|00041|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt" 2026-07-29T14:42:38Z|00042|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting... 2026-07-29T14:42:38Z|00043|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633" 2026-07-29T14:42:39Z|00044|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected 2026-07-29T14:42:39Z|00045|connmgr|INFO|br-ex: Changes to controller "punix:/var/run/openvswitch/br-ex.mgmt" expects re-initialization: Re-initializing now. 2026-07-29T14:42:39Z|00046|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt" 2026-07-29T14:42:39Z|00047|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connecting... 2026-07-29T14:42:39Z|00048|connmgr|INFO|br-ex: added primary controller "tcp:127.0.0.1:6633" 2026-07-29T14:42:40Z|00049|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connected 2026-07-29T14:42:40Z|00050|bridge|INFO|bridge br-int: added interface int-br-ex on port 1 2026-07-29T14:42:40Z|00051|bridge|INFO|bridge br-ex: added interface phy-br-ex on port 1 2026-07-29T14:42:40Z|00052|bridge|INFO|bridge br-tun: added interface br-tun on port 65534 2026-07-29T14:42:40Z|00053|bridge|INFO|bridge br-tun: using datapath ID 0000ae95ca29c643 2026-07-29T14:42:40Z|00054|connmgr|INFO|br-tun: added service controller "punix:/var/run/openvswitch/br-tun.mgmt" 2026-07-29T14:42:40Z|00055|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connecting... 2026-07-29T14:42:40Z|00056|connmgr|INFO|br-tun: added primary controller "tcp:127.0.0.1:6633" 2026-07-29T14:42:41Z|00057|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connected 2026-07-29T14:42:41Z|00058|bridge|INFO|bridge br-int: added interface patch-tun on port 2 2026-07-29T14:42:41Z|00059|bridge|INFO|bridge br-tun: added interface patch-int on port 1 2026-07-29T14:42:49Z|00060|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 11 flow_mods in the 1 s starting 10 s ago (11 adds) 2026-07-29T14:42:50Z|00061|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 2 flow_mods 10 s ago (2 adds) 2026-07-29T14:42:51Z|00062|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 12 flow_mods 10 s ago (12 adds) 2026-07-29T14:43:16Z|00063|bridge|INFO|bridge br-int: added interface tap5cf050ea-c5 on port 3 2026-07-29T14:43:16Z|00001|ofproto_dpif_xlate(handler13)|WARN|dropping VLAN 0 packet received on port tap5cf050ea-c5 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:1d:e9:30,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-29T14:43:16Z|00002|ofproto_dpif_xlate(handler13)|WARN|dropping VLAN 0 packet received on port tap5cf050ea-c5 not configured for trunking VLAN 0 on bridge br-int while processing icmp6,in_port=3,vlan_tci=0x0000,dl_src=fa:16:3e:1d:e9:30,dl_dst=33:33:ff:1d:e9:30,ipv6_src=::,ipv6_dst=ff02::1:ff1d:e930,ipv6_label=0x00000,nw_tos=0,nw_ecn=0,nw_ttl=255,nw_frag=no,icmp_type=135,icmp_code=0,nd_target=fe80::f816:3eff:fe1d:e930,nd_sll=00:00:00:00:00:00,nd_tll=00:00:00:00:00:00 2026-07-29T14:43:49Z|00064|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 13 flow_mods in the 1 s starting 30 s ago (1 adds, 12 deletes) 2026-07-29T14:43:50Z|00065|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 1 flow_mods 31 s ago (1 adds) 2026-07-29T14:48:10Z|00066|bridge|INFO|bridge br-int: added interface tap55416beb-c7 on port 4 2026-07-29T14:48:10Z|00001|ofproto_dpif_xlate(handler3)|WARN|dropping VLAN 0 packet received on port tap55416beb-c7 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:a5:b1:05,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-29T14:48:20Z|00067|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 1 flow_mods 10 s ago (1 adds) 2026-07-29T14:48:20Z|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-29T14:56:24Z|00069|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 12 flow_mods 10 s ago (12 deletes) 2026-07-29T14:56:26Z|00070|bridge|INFO|bridge br-int: added interface o-hm0 on port 5 2026-07-29T14:57:24Z|00071|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 16 flow_mods in the 2 s starting 57 s ago (12 adds, 4 deletes) 2026-07-29T15:10:43Z|00001|timeval(revalidator18)|WARN|Unreasonably long 1804ms poll interval (0ms user, 1802ms system) 2026-07-29T15:10:43Z|00002|timeval(revalidator18)|WARN|context switches: 0 voluntary, 1 involuntary 2026-07-29T15:10:43Z|00003|coverage(revalidator18)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=2daff441: 2026-07-29T15:10:43Z|00004|coverage(revalidator18)|INFO|bridge_reconfigure 0.0/sec 0.050/sec 0.0842/sec total: 303 2026-07-29T15:10:43Z|00005|coverage(revalidator18)|INFO|ofproto_flush 0.0/sec 0.000/sec 0.0019/sec total: 7 2026-07-29T15:10:43Z|00006|coverage(revalidator18)|INFO|ofproto_recv_openflow 1.0/sec 1.200/sec 0.6292/sec total: 2265 2026-07-29T15:10:43Z|00007|coverage(revalidator18)|INFO|ofproto_update_port 0.0/sec 0.000/sec 0.0081/sec total: 29 2026-07-29T15:10:43Z|00008|coverage(revalidator18)|INFO|rev_reconfigure 0.0/sec 0.000/sec 0.0044/sec total: 16 2026-07-29T15:10:43Z|00009|coverage(revalidator18)|INFO|rev_port_toggled 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-07-29T15:10:43Z|00010|coverage(revalidator18)|INFO|rev_flow_table 0.0/sec 0.000/sec 0.0242/sec total: 87 2026-07-29T15:10:43Z|00011|coverage(revalidator18)|INFO|rev_mac_learning 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-07-29T15:10:43Z|00012|coverage(revalidator18)|INFO|xlate_actions 0.0/sec 0.000/sec 0.0256/sec total: 92 2026-07-29T15:10:43Z|00013|coverage(revalidator18)|INFO|ccmap_shrink 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-07-29T15:10:43Z|00014|coverage(revalidator18)|INFO|cmap_expand 0.0/sec 0.917/sec 1.0803/sec total: 3889 2026-07-29T15:10:43Z|00015|coverage(revalidator18)|INFO|cmap_shrink 0.0/sec 0.917/sec 1.0592/sec total: 3813 2026-07-29T15:10:43Z|00016|coverage(revalidator18)|INFO|dpif_execute 0.0/sec 0.000/sec 0.0053/sec total: 19 2026-07-29T15:10:43Z|00017|coverage(revalidator18)|INFO|dpif_execute_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-29T15:10:43Z|00018|coverage(revalidator18)|INFO|dpif_flow_del 0.0/sec 0.000/sec 0.0114/sec total: 41 2026-07-29T15:10:43Z|00019|coverage(revalidator18)|INFO|dpif_flow_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-29T15:10:43Z|00020|coverage(revalidator18)|INFO|dpif_flow_get 0.0/sec 0.000/sec 0.0067/sec total: 24 2026-07-29T15:10:43Z|00021|coverage(revalidator18)|INFO|dpif_flow_put 0.0/sec 0.000/sec 0.0119/sec total: 43 2026-07-29T15:10:43Z|00022|coverage(revalidator18)|INFO|dpif_flow_put_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-29T15:10:43Z|00023|coverage(revalidator18)|INFO|dpif_port_add 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-07-29T15:10:43Z|00024|coverage(revalidator18)|INFO|flow_extract 0.0/sec 0.000/sec 0.0053/sec total: 19 2026-07-29T15:10:43Z|00025|coverage(revalidator18)|INFO|miniflow_malloc 0.8/sec 17.383/sec 17.4286/sec total: 62743 2026-07-29T15:10:43Z|00026|coverage(revalidator18)|INFO|hindex_expand 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-29T15:10:43Z|00027|coverage(revalidator18)|INFO|hmap_pathological 0.0/sec 0.000/sec 0.0031/sec total: 11 2026-07-29T15:10:43Z|00028|coverage(revalidator18)|INFO|hmap_expand 22.2/sec 27.267/sec 16.0883/sec total: 57963 2026-07-29T15:10:43Z|00029|coverage(revalidator18)|INFO|mac_learning_learned 0.0/sec 0.000/sec 0.0006/sec total: 2 2026-07-29T15:10:43Z|00030|coverage(revalidator18)|INFO|mac_learning_expired 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-29T15:10:43Z|00031|coverage(revalidator18)|INFO|netdev_get_stats 2.0/sec 2.000/sec 0.8686/sec total: 3137 2026-07-29T15:10:43Z|00032|coverage(revalidator18)|INFO|txn_unchanged 0.0/sec 0.150/sec 0.2225/sec total: 801 2026-07-29T15:10:43Z|00033|coverage(revalidator18)|INFO|txn_incomplete 0.2/sec 0.200/sec 0.1128/sec total: 407 2026-07-29T15:10:43Z|00034|coverage(revalidator18)|INFO|txn_success 0.2/sec 0.200/sec 0.0997/sec total: 360 2026-07-29T15:10:43Z|00035|coverage(revalidator18)|INFO|txn_try_again 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-07-29T15:10:43Z|00036|coverage(revalidator18)|INFO|poll_create_node 189.0/sec 222.733/sec 115.9406/sec total: 417621 2026-07-29T15:10:43Z|00037|coverage(revalidator18)|INFO|poll_zero_timeout 1.0/sec 0.533/sec 0.3275/sec total: 1180 2026-07-29T15:10:43Z|00038|coverage(revalidator18)|INFO|rconn_queued 1.0/sec 1.200/sec 0.6117/sec total: 2203 2026-07-29T15:10:43Z|00039|coverage(revalidator18)|INFO|rconn_sent 1.0/sec 1.200/sec 0.6117/sec total: 2203 2026-07-29T15:10:43Z|00040|coverage(revalidator18)|INFO|seq_change 1018.2/sec 1113.033/sec 566.7806/sec total: 2041262 2026-07-29T15:10:43Z|00041|coverage(revalidator18)|INFO|pstream_open 0.0/sec 0.000/sec 0.0022/sec total: 8 2026-07-29T15:10:43Z|00042|coverage(revalidator18)|INFO|stream_open 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-07-29T15:10:43Z|00043|coverage(revalidator18)|INFO|unixctl_received 0.0/sec 0.033/sec 0.0239/sec total: 86 2026-07-29T15:10:43Z|00044|coverage(revalidator18)|INFO|unixctl_replied 0.0/sec 0.033/sec 0.0239/sec total: 86 2026-07-29T15:10:43Z|00045|coverage(revalidator18)|INFO|util_xalloc 453.8/sec 873.750/sec 651.0008/sec total: 2344719 2026-07-29T15:10:43Z|00046|coverage(revalidator18)|INFO|vconn_open 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-29T15:10:43Z|00047|coverage(revalidator18)|INFO|vconn_received 1.0/sec 1.200/sec 0.6300/sec total: 2268 2026-07-29T15:10:43Z|00048|coverage(revalidator18)|INFO|vconn_sent 1.0/sec 1.200/sec 0.6125/sec total: 2206 2026-07-29T15:10:43Z|00049|coverage(revalidator18)|INFO|netdev_set_policing 0.0/sec 0.000/sec 0.0036/sec total: 13 2026-07-29T15:10:43Z|00050|coverage(revalidator18)|INFO|netdev_set_hwaddr 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-07-29T15:10:43Z|00051|coverage(revalidator18)|INFO|netlink_received 9.2/sec 51.967/sec 48.8300/sec total: 175802 2026-07-29T15:10:43Z|00052|coverage(revalidator18)|INFO|netlink_recv_jumbo 0.0/sec 7.367/sec 7.5586/sec total: 27211 2026-07-29T15:10:43Z|00053|coverage(revalidator18)|INFO|netlink_sent 7.2/sec 48.900/sec 46.6472/sec total: 167942 2026-07-29T15:10:43Z|00054|coverage(revalidator18)|INFO|route_table_dump 0.0/sec 0.083/sec 0.1019/sec total: 367 2026-07-29T15:10:43Z|00055|coverage(revalidator18)|INFO|nln_changed 0.0/sec 0.217/sec 0.2686/sec total: 967 2026-07-29T15:10:43Z|00056|coverage(revalidator18)|INFO|128 events never hit 2026-07-29T15:10:43Z|00057|poll_loop(revalidator18)|INFO|wakeup due to 489-ms timeout at ofproto/ofproto-dpif-upcall.c:1068 (53% CPU usage) 2026-07-29T15:10:43Z|00058|poll_loop(revalidator18)|INFO|wakeup due to [POLLIN] on fd 46 (FIFO pipe:[1114511]) at lib/ovs-thread.c:379 (53% CPU usage) 2026-07-29T15:10:43Z|00059|poll_loop(revalidator18)|INFO|wakeup due to [POLLIN] on fd 46 (FIFO pipe:[1114511]) at lib/ovs-thread.c:379 (53% CPU usage) 2026-07-29T15:10:44Z|00060|poll_loop(revalidator18)|INFO|wakeup due to 501-ms timeout at ofproto/ofproto-dpif-upcall.c:1068 (53% CPU usage) 2026-07-29T15:10:44Z|00061|poll_loop(revalidator18)|INFO|wakeup due to [POLLIN] on fd 46 (FIFO pipe:[1114511]) at lib/ovs-thread.c:379 (53% CPU usage) 2026-07-29T15:10:44Z|00062|poll_loop(revalidator18)|INFO|wakeup due to 500-ms timeout at ofproto/ofproto-dpif-upcall.c:1068 (53% CPU usage) 2026-07-29T15:10:44Z|00063|poll_loop(revalidator18)|INFO|wakeup due to [POLLIN] on fd 46 (FIFO pipe:[1114511]) at lib/ovs-thread.c:379 (53% CPU usage) 2026-07-29T15:10:45Z|00064|poll_loop(revalidator18)|INFO|wakeup due to 508-ms timeout at ofproto/ofproto-dpif-upcall.c:1068 (53% CPU usage) 2026-07-29T15:10:45Z|00065|poll_loop(revalidator18)|INFO|wakeup due to [POLLIN] on fd 46 (FIFO pipe:[1114511]) at lib/ovs-thread.c:379 (53% CPU usage) 2026-07-29T15:10:45Z|00066|poll_loop(revalidator18)|INFO|wakeup due to 498-ms timeout at ofproto/ofproto-dpif-upcall.c:1068 (53% CPU usage)