2026-04-18T22:02:56.254Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovs-vswitchd.log 2026-04-18T22:02:56.265Z|00002|ovs_numa|INFO|Discovered 16 CPU cores on NUMA node 0 2026-04-18T22:02:56.265Z|00003|ovs_numa|INFO|Discovered 1 NUMA nodes and 16 CPU cores 2026-04-18T22:02:56.265Z|00004|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting... 2026-04-18T22:02:56.265Z|00005|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected 2026-04-18T22:02:56.273Z|00006|bridge|INFO|ovs-vswitchd (Open vSwitch) 3.3.4 2026-04-18T22:03:01.342Z|00007|dpif_netlink|INFO|Datapath dispatch mode: per-cpu 2026-04-18T22:03:01.343Z|00008|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation 2026-04-18T22:03:01.343Z|00009|ofproto_dpif|INFO|system@ovs-system: VLAN header stack length probed as 2 2026-04-18T22:03:01.343Z|00010|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 3 2026-04-18T22:03:01.343Z|00011|ofproto_dpif|INFO|system@ovs-system: Datapath supports truncate action 2026-04-18T22:03:01.343Z|00012|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids 2026-04-18T22:03:01.343Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports clone action 2026-04-18T22:03:01.343Z|00014|ofproto_dpif|INFO|system@ovs-system: Max sample nesting level probed as 10 2026-04-18T22:03:01.343Z|00015|ofproto_dpif|INFO|system@ovs-system: Datapath supports eventmask in conntrack action 2026-04-18T22:03:01.344Z|00016|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_clear action 2026-04-18T22:03:01.344Z|00017|ofproto_dpif|INFO|system@ovs-system: Max dp_hash algorithm probed to be 1 2026-04-18T22:03:01.344Z|00018|ofproto_dpif|INFO|system@ovs-system: Datapath supports check_pkt_len action 2026-04-18T22:03:01.344Z|00019|ofproto_dpif|INFO|system@ovs-system: Datapath supports timeout policy in conntrack action 2026-04-18T22:03:01.344Z|00020|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zero_snat 2026-04-18T22:03:01.344Z|00021|ofproto_dpif|INFO|system@ovs-system: Datapath supports add_mpls action 2026-04-18T22:03:01.344Z|00022|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state 2026-04-18T22:03:01.344Z|00023|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone 2026-04-18T22:03:01.344Z|00024|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark 2026-04-18T22:03:01.344Z|00025|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label 2026-04-18T22:03:01.344Z|00026|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat 2026-04-18T22:03:01.344Z|00027|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple 2026-04-18T22:03:01.344Z|00028|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple6 2026-04-18T22:03:01.344Z|00029|ofproto_dpif|INFO|system@ovs-system: Datapath does not support IPv6 ND Extensions 2026-04-18T22:03:01.344Z|00030|ofproto_dpif_upcall|INFO|Overriding n-handler-threads to 16, setting n-revalidator-threads to 5 2026-04-18T22:03:01.344Z|00031|ofproto_dpif_upcall|INFO|Starting 21 threads 2026-04-18T22:03:01.484Z|00032|bridge|INFO|bridge br-int: added interface br-int on port 65534 2026-04-18T22:03:01.485Z|00033|bridge|INFO|bridge br-int: using datapath ID 00007ef7381cf7b4 2026-04-18T22:03:01.485Z|00034|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt" 2026-04-18T22:03:06.497Z|00035|memory|INFO|199152 kB peak resident set size after 10.2 seconds 2026-04-18T22:03:06.497Z|00036|memory|INFO|handlers:16 idl-cells-Open_vSwitch:101 ports:1 revalidators:5 rules:4 2026-04-18T22:14:05.213Z|00037|bridge|INFO|bridge br-int: deleted interface br-int on port 65534 2026-04-18T22:14:06.401Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovs-vswitchd.log 2026-04-18T22:14:06.500Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovs-vswitchd.log 2026-04-18T22:14:06.508Z|00002|ovs_numa|INFO|Discovered 16 CPU cores on NUMA node 0 2026-04-18T22:14:06.508Z|00003|ovs_numa|INFO|Discovered 1 NUMA nodes and 16 CPU cores 2026-04-18T22:14:06.508Z|00004|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting... 2026-04-18T22:14:06.509Z|00005|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected 2026-04-18T22:14:06.517Z|00006|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation 2026-04-18T22:14:06.517Z|00007|ofproto_dpif|INFO|system@ovs-system: VLAN header stack length probed as 2 2026-04-18T22:14:06.517Z|00008|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 3 2026-04-18T22:14:06.517Z|00009|ofproto_dpif|INFO|system@ovs-system: Datapath supports truncate action 2026-04-18T22:14:06.517Z|00010|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids 2026-04-18T22:14:06.517Z|00011|ofproto_dpif|INFO|system@ovs-system: Datapath supports clone action 2026-04-18T22:14:06.517Z|00012|ofproto_dpif|INFO|system@ovs-system: Max sample nesting level probed as 10 2026-04-18T22:14:06.517Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports eventmask in conntrack action 2026-04-18T22:14:06.517Z|00014|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_clear action 2026-04-18T22:14:06.517Z|00015|ofproto_dpif|INFO|system@ovs-system: Max dp_hash algorithm probed to be 1 2026-04-18T22:14:06.517Z|00016|ofproto_dpif|INFO|system@ovs-system: Datapath supports check_pkt_len action 2026-04-18T22:14:06.517Z|00017|ofproto_dpif|INFO|system@ovs-system: Datapath supports timeout policy in conntrack action 2026-04-18T22:14:06.517Z|00018|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zero_snat 2026-04-18T22:14:06.517Z|00019|ofproto_dpif|INFO|system@ovs-system: Datapath supports add_mpls action 2026-04-18T22:14:06.517Z|00020|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state 2026-04-18T22:14:06.517Z|00021|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone 2026-04-18T22:14:06.517Z|00022|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark 2026-04-18T22:14:06.517Z|00023|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label 2026-04-18T22:14:06.517Z|00024|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat 2026-04-18T22:14:06.517Z|00025|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple 2026-04-18T22:14:06.517Z|00026|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple6 2026-04-18T22:14:06.517Z|00027|ofproto_dpif|INFO|system@ovs-system: Datapath does not support IPv6 ND Extensions 2026-04-18T22:14:06.518Z|00028|ofproto_dpif_upcall|INFO|Overriding n-handler-threads to 16, setting n-revalidator-threads to 5 2026-04-18T22:14:06.518Z|00029|ofproto_dpif_upcall|INFO|Starting 21 threads 2026-04-18T22:14:06.637Z|00030|bridge|INFO|bridge br-int: added interface br-int on port 65534 2026-04-18T22:14:06.637Z|00031|bridge|INFO|bridge br-int: using datapath ID 00007ef7381cf7b4 2026-04-18T22:14:06.637Z|00032|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt" 2026-04-18T22:14:06.638Z|00033|bridge|INFO|ovs-vswitchd (Open vSwitch) 3.3.4 2026-04-18T22:14:07.089Z|00034|bridge|INFO|bridge br-ex: added interface br-ex on port 65534 2026-04-18T22:14:07.089Z|00035|bridge|INFO|bridge br-ex: using datapath ID 0000fe39eed71045 2026-04-18T22:14:07.089Z|00036|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt" 2026-04-18T22:14:10.277Z|00037|connmgr|INFO|br-int<->unix#1: 18 flow_mods in the last 0 s (17 adds, 1 deletes) 2026-04-18T22:14:16.515Z|00038|memory|INFO|199664 kB peak resident set size after 10.0 seconds 2026-04-18T22:14:16.515Z|00039|memory|INFO|handlers:16 idl-cells-Open_vSwitch:199 ofconns:4 ports:2 revalidators:5 rules:26 2026-04-18T22:14:20.502Z|00040|connmgr|INFO|br-int<->unix#5: 18 flow_mods 10 s ago (17 adds, 1 deletes) 2026-04-18T22:14:43.807Z|00041|bridge|INFO|bridge br-int: added interface patch-br-int-to-provnet-d61444a1-6221-4575-956e-9119a193e6bd on port 1 2026-04-18T22:14:43.807Z|00042|bridge|INFO|bridge br-ex: added interface patch-provnet-d61444a1-6221-4575-956e-9119a193e6bd-to-br-int on port 1 2026-04-18T22:15:20.502Z|00043|connmgr|INFO|br-int<->unix#5: 554 flow_mods in the 4 s starting 36 s ago (539 adds, 8 deletes, 7 modifications) 2026-04-18T22:19:10.584Z|00044|bridge|INFO|bridge br-int: added interface tap0f59dc93-ff on port 2 2026-04-18T22:19:12.843Z|00045|bridge|INFO|bridge br-int: added interface tap8facfb53-c0 on port 3 2026-04-18T22:19:20.637Z|00046|connmgr|INFO|br-int<->unix#5: 159 flow_mods in the 2 s starting 10 s ago (157 adds, 2 deletes) 2026-04-18T22:20:20.637Z|00047|connmgr|INFO|br-int<->unix#5: 461 flow_mods in the 17 s starting 35 s ago (445 adds, 12 deletes, 4 modifications) 2026-04-18T22:21:18.766Z|00048|bridge|WARN|could not open network device tap1db1e1bb-ca (No such device) 2026-04-18T22:21:20.343Z|00049|bridge|INFO|bridge br-int: added interface tap1db1e1bb-ca on port 4 2026-04-18T22:21:20.749Z|00050|bridge|INFO|bridge br-int: added interface tapecb9a3a8-c0 on port 5 2026-04-18T22:21:30.374Z|00051|connmgr|INFO|br-int<->unix#5: 209 flow_mods in the 4 s starting 10 s ago (195 adds, 10 deletes, 4 modifications) 2026-04-18T22:22:30.374Z|00052|connmgr|INFO|br-int<->unix#5: 12 flow_mods in the 10 s starting 46 s ago (8 adds, 4 deletes) 2026-04-18T22:23:02.514Z|00053|bridge|WARN|could not open network device tapef0e4b86-7f (No such device) 2026-04-18T22:23:04.091Z|00054|bridge|INFO|bridge br-int: added interface tapef0e4b86-7f on port 6 2026-04-18T22:23:04.807Z|00055|bridge|WARN|could not open network device tapc30ab07b-07 (No such device) 2026-04-18T22:23:06.697Z|00056|bridge|INFO|bridge br-int: added interface tapc30ab07b-07 on port 7 2026-04-18T22:23:30.375Z|00057|connmgr|INFO|br-int<->unix#5: 1030 flow_mods in the 30 s starting 45 s ago (756 adds, 274 deletes) 2026-04-18T22:24:30.375Z|00058|connmgr|INFO|br-int<->unix#5: 8 flow_mods 15 s ago (4 adds, 4 deletes) 2026-04-18T22:24:52.774Z|00001|timeval(revalidator18)|WARN|Unreasonably long 1085ms poll interval (636ms user, 158ms system) 2026-04-18T22:24:52.775Z|00002|timeval(revalidator18)|WARN|context switches: 1 voluntary, 264 involuntary 2026-04-18T22:24:52.775Z|00003|coverage(revalidator18)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=3214eedd: 2026-04-18T22:24:52.775Z|00004|coverage(revalidator18)|INFO|nln_changed 0.0/sec 0.000/sec 0.0278/sec total: 100 2026-04-18T22:24:52.775Z|00005|coverage(revalidator18)|INFO|route_table_dump 0.0/sec 0.000/sec 0.0203/sec total: 73 2026-04-18T22:24:52.775Z|00006|coverage(revalidator18)|INFO|netlink_sent 19.6/sec 21.433/sec 3.9872/sec total: 14373 2026-04-18T22:24:52.775Z|00007|coverage(revalidator18)|INFO|netlink_recv_jumbo 3.8/sec 2.550/sec 0.6331/sec total: 2281 2026-04-18T22:24:52.775Z|00008|coverage(revalidator18)|INFO|netlink_received 17.4/sec 22.033/sec 4.5003/sec total: 16223 2026-04-18T22:24:52.775Z|00009|coverage(revalidator18)|INFO|netdev_set_ethtool 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-04-18T22:24:52.775Z|00010|coverage(revalidator18)|INFO|netdev_get_ethtool 0.0/sec 0.000/sec 0.0081/sec total: 29 2026-04-18T22:24:52.775Z|00011|coverage(revalidator18)|INFO|netdev_set_hwaddr 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-18T22:24:52.775Z|00012|coverage(revalidator18)|INFO|netdev_set_policing 0.0/sec 0.000/sec 0.0083/sec total: 30 2026-04-18T22:24:52.775Z|00013|coverage(revalidator18)|INFO|vconn_sent 0.6/sec 0.283/sec 0.0836/sec total: 301 2026-04-18T22:24:52.775Z|00014|coverage(revalidator18)|INFO|vconn_received 4.2/sec 0.867/sec 0.7894/sec total: 2842 2026-04-18T22:24:52.775Z|00015|coverage(revalidator18)|INFO|util_xalloc 1032.2/sec 917.400/sec 132.1103/sec total: 475682 2026-04-18T22:24:52.775Z|00016|coverage(revalidator18)|INFO|stream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-18T22:24:52.775Z|00017|coverage(revalidator18)|INFO|pstream_open 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-04-18T22:24:52.775Z|00018|coverage(revalidator18)|INFO|seq_change 1050.0/sec 1090.200/sec 198.6822/sec total: 715585 2026-04-18T22:24:52.775Z|00019|coverage(revalidator18)|INFO|rconn_sent 0.6/sec 0.283/sec 0.0814/sec total: 293 2026-04-18T22:24:52.775Z|00020|coverage(revalidator18)|INFO|rconn_queued 0.6/sec 0.283/sec 0.0814/sec total: 293 2026-04-18T22:24:52.775Z|00021|coverage(revalidator18)|INFO|poll_zero_timeout 1.2/sec 4.750/sec 0.3792/sec total: 1365 2026-04-18T22:24:52.775Z|00022|coverage(revalidator18)|INFO|poll_create_node 190.4/sec 205.767/sec 35.7758/sec total: 128811 2026-04-18T22:24:52.775Z|00023|coverage(revalidator18)|INFO|txn_success 0.2/sec 0.200/sec 0.0225/sec total: 81 2026-04-18T22:24:52.775Z|00024|coverage(revalidator18)|INFO|txn_incomplete 0.4/sec 0.233/sec 0.0294/sec total: 106 2026-04-18T22:24:52.775Z|00025|coverage(revalidator18)|INFO|txn_unchanged 0.0/sec 0.000/sec 0.0492/sec total: 177 2026-04-18T22:24:52.775Z|00026|coverage(revalidator18)|INFO|netdev_get_stats 4.0/sec 2.167/sec 0.2167/sec total: 780 2026-04-18T22:24:52.775Z|00027|coverage(revalidator18)|INFO|mac_learning_expired 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-18T22:24:52.775Z|00028|coverage(revalidator18)|INFO|mac_learning_learned 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-04-18T22:24:52.775Z|00029|coverage(revalidator18)|INFO|hmap_expand 25.4/sec 21.733/sec 4.2817/sec total: 15414 2026-04-18T22:24:52.775Z|00030|coverage(revalidator18)|INFO|hmap_pathological 0.0/sec 0.000/sec 0.0033/sec total: 12 2026-04-18T22:24:52.775Z|00031|coverage(revalidator18)|INFO|hindex_expand 0.0/sec 0.000/sec 0.0022/sec total: 8 2026-04-18T22:24:52.775Z|00032|coverage(revalidator18)|INFO|hindex_pathological 0.0/sec 0.000/sec 0.0031/sec total: 11 2026-04-18T22:24:52.775Z|00033|coverage(revalidator18)|INFO|miniflow_malloc 7.8/sec 1.167/sec 2.2864/sec total: 8231 2026-04-18T22:24:52.775Z|00034|coverage(revalidator18)|INFO|flow_extract 1.0/sec 4.633/sec 0.2836/sec total: 1021 2026-04-18T22:24:52.775Z|00035|coverage(revalidator18)|INFO|dpif_port_add 0.0/sec 0.000/sec 0.0019/sec total: 7 2026-04-18T22:24:52.775Z|00036|coverage(revalidator18)|INFO|dpif_flow_put_error 0.4/sec 0.483/sec 0.0383/sec total: 138 2026-04-18T22:24:52.775Z|00037|coverage(revalidator18)|INFO|dpif_flow_put 6.6/sec 4.567/sec 0.2831/sec total: 1019 2026-04-18T22:24:52.775Z|00038|coverage(revalidator18)|INFO|dpif_flow_get 0.0/sec 0.000/sec 0.0069/sec total: 25 2026-04-18T22:24:52.775Z|00039|coverage(revalidator18)|INFO|dpif_flow_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-18T22:24:52.775Z|00040|coverage(revalidator18)|INFO|dpif_flow_del 0.6/sec 2.167/sec 0.1753/sec total: 642 2026-04-18T22:24:52.775Z|00041|coverage(revalidator18)|INFO|dpif_execute_with_help 0.0/sec 0.150/sec 0.0111/sec total: 40 2026-04-18T22:24:52.775Z|00042|coverage(revalidator18)|INFO|dpif_execute_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-18T22:24:52.775Z|00043|coverage(revalidator18)|INFO|dpif_execute 1.0/sec 4.767/sec 0.2764/sec total: 995 2026-04-18T22:24:52.775Z|00044|coverage(revalidator18)|INFO|cmap_shrink 0.2/sec 0.750/sec 0.3325/sec total: 1197 2026-04-18T22:24:52.775Z|00045|coverage(revalidator18)|INFO|cmap_expand 0.0/sec 1.083/sec 0.5003/sec total: 1801 2026-04-18T22:24:52.775Z|00046|coverage(revalidator18)|INFO|ccmap_shrink 0.0/sec 0.000/sec 0.0378/sec total: 136 2026-04-18T22:24:52.775Z|00047|coverage(revalidator18)|INFO|ccmap_expand 0.0/sec 0.000/sec 0.0078/sec total: 28 2026-04-18T22:24:52.775Z|00048|coverage(revalidator18)|INFO|xlate_actions 20.8/sec 8.867/sec 0.6561/sec total: 2362 2026-04-18T22:24:52.775Z|00049|coverage(revalidator18)|INFO|upcall_ukey_replace 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-18T22:24:52.775Z|00050|coverage(revalidator18)|INFO|upcall_flow_limit_grew 0.0/sec 0.483/sec 0.0272/sec total: 98 2026-04-18T22:24:52.775Z|00051|coverage(revalidator18)|INFO|handler_duplicate_upcall 0.0/sec 0.433/sec 0.0164/sec total: 59 2026-04-18T22:24:52.775Z|00052|coverage(revalidator18)|INFO|rev_mac_learning 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-04-18T22:24:52.775Z|00053|coverage(revalidator18)|INFO|rev_flow_table 0.2/sec 0.033/sec 0.0114/sec total: 41 2026-04-18T22:24:52.775Z|00054|coverage(revalidator18)|INFO|rev_port_toggled 0.0/sec 0.000/sec 0.0019/sec total: 7 2026-04-18T22:24:52.775Z|00055|coverage(revalidator18)|INFO|rev_reconfigure 0.0/sec 0.000/sec 0.0022/sec total: 8 2026-04-18T22:24:52.775Z|00056|coverage(revalidator18)|INFO|ofproto_update_port 0.0/sec 0.000/sec 0.0119/sec total: 43 2026-04-18T22:24:52.775Z|00057|coverage(revalidator18)|INFO|ofproto_recv_openflow 4.2/sec 0.867/sec 0.7872/sec total: 2834 2026-04-18T22:24:52.775Z|00058|coverage(revalidator18)|INFO|ofproto_packet_out 0.0/sec 0.217/sec 0.0294/sec total: 106 2026-04-18T22:24:52.775Z|00059|coverage(revalidator18)|INFO|ofproto_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-18T22:24:52.775Z|00060|coverage(revalidator18)|INFO|bridge_reconfigure 0.0/sec 0.000/sec 0.0178/sec total: 64 2026-04-18T22:24:52.775Z|00061|coverage(revalidator18)|INFO|114 events never hit 2026-04-18T22:25:30.374Z|00059|connmgr|INFO|br-int<->unix#5: 18 flow_mods 42 s ago (9 adds, 9 deletes)