2026-04-20T07:33:50.245Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovs-vswitchd.log 2026-04-20T07:33:50.258Z|00002|ovs_numa|INFO|Discovered 16 CPU cores on NUMA node 0 2026-04-20T07:33:50.258Z|00003|ovs_numa|INFO|Discovered 1 NUMA nodes and 16 CPU cores 2026-04-20T07:33:50.258Z|00004|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting... 2026-04-20T07:33:50.258Z|00005|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected 2026-04-20T07:33:50.266Z|00006|bridge|INFO|ovs-vswitchd (Open vSwitch) 3.3.4 2026-04-20T07:33:57.292Z|00007|dpif_netlink|INFO|Datapath dispatch mode: per-cpu 2026-04-20T07:33:57.293Z|00008|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation 2026-04-20T07:33:57.293Z|00009|ofproto_dpif|INFO|system@ovs-system: VLAN header stack length probed as 2 2026-04-20T07:33:57.293Z|00010|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 3 2026-04-20T07:33:57.293Z|00011|ofproto_dpif|INFO|system@ovs-system: Datapath supports truncate action 2026-04-20T07:33:57.293Z|00012|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids 2026-04-20T07:33:57.293Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports clone action 2026-04-20T07:33:57.294Z|00014|ofproto_dpif|INFO|system@ovs-system: Max sample nesting level probed as 10 2026-04-20T07:33:57.294Z|00015|ofproto_dpif|INFO|system@ovs-system: Datapath supports eventmask in conntrack action 2026-04-20T07:33:57.294Z|00016|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_clear action 2026-04-20T07:33:57.294Z|00017|ofproto_dpif|INFO|system@ovs-system: Max dp_hash algorithm probed to be 1 2026-04-20T07:33:57.294Z|00018|ofproto_dpif|INFO|system@ovs-system: Datapath supports check_pkt_len action 2026-04-20T07:33:57.294Z|00019|ofproto_dpif|INFO|system@ovs-system: Datapath supports timeout policy in conntrack action 2026-04-20T07:33:57.294Z|00020|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zero_snat 2026-04-20T07:33:57.294Z|00021|ofproto_dpif|INFO|system@ovs-system: Datapath supports add_mpls action 2026-04-20T07:33:57.294Z|00022|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state 2026-04-20T07:33:57.294Z|00023|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone 2026-04-20T07:33:57.295Z|00024|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark 2026-04-20T07:33:57.295Z|00025|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label 2026-04-20T07:33:57.295Z|00026|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat 2026-04-20T07:33:57.295Z|00027|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple 2026-04-20T07:33:57.295Z|00028|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple6 2026-04-20T07:33:57.295Z|00029|ofproto_dpif|INFO|system@ovs-system: Datapath does not support IPv6 ND Extensions 2026-04-20T07:33:57.295Z|00030|ofproto_dpif_upcall|INFO|Overriding n-handler-threads to 16, setting n-revalidator-threads to 5 2026-04-20T07:33:57.295Z|00031|ofproto_dpif_upcall|INFO|Starting 21 threads 2026-04-20T07:33:57.526Z|00032|bridge|INFO|bridge br-int: added interface br-int on port 65534 2026-04-20T07:33:57.526Z|00033|bridge|INFO|bridge br-int: using datapath ID 0000b66388a90ab8 2026-04-20T07:33:57.527Z|00034|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt" 2026-04-20T07:34:00.534Z|00035|memory|INFO|199152 kB peak resident set size after 10.3 seconds 2026-04-20T07:34:00.534Z|00036|memory|INFO|handlers:16 idl-cells-Open_vSwitch:101 ports:1 revalidators:5 rules:4 2026-04-20T07:46:41.728Z|00037|bridge|INFO|bridge br-int: deleted interface br-int on port 65534 2026-04-20T07:46:42.950Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovs-vswitchd.log 2026-04-20T07:46:43.093Z|00001|vlog|INFO|opened log file /var/log/openvswitch/ovs-vswitchd.log 2026-04-20T07:46:43.102Z|00002|ovs_numa|INFO|Discovered 16 CPU cores on NUMA node 0 2026-04-20T07:46:43.102Z|00003|ovs_numa|INFO|Discovered 1 NUMA nodes and 16 CPU cores 2026-04-20T07:46:43.102Z|00004|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connecting... 2026-04-20T07:46:43.102Z|00005|reconnect|INFO|unix:/var/run/openvswitch/db.sock: connected 2026-04-20T07:46:43.111Z|00006|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation 2026-04-20T07:46:43.111Z|00007|ofproto_dpif|INFO|system@ovs-system: VLAN header stack length probed as 2 2026-04-20T07:46:43.111Z|00008|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 3 2026-04-20T07:46:43.111Z|00009|ofproto_dpif|INFO|system@ovs-system: Datapath supports truncate action 2026-04-20T07:46:43.111Z|00010|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids 2026-04-20T07:46:43.111Z|00011|ofproto_dpif|INFO|system@ovs-system: Datapath supports clone action 2026-04-20T07:46:43.111Z|00012|ofproto_dpif|INFO|system@ovs-system: Max sample nesting level probed as 10 2026-04-20T07:46:43.111Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports eventmask in conntrack action 2026-04-20T07:46:43.111Z|00014|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_clear action 2026-04-20T07:46:43.111Z|00015|ofproto_dpif|INFO|system@ovs-system: Max dp_hash algorithm probed to be 1 2026-04-20T07:46:43.111Z|00016|ofproto_dpif|INFO|system@ovs-system: Datapath supports check_pkt_len action 2026-04-20T07:46:43.111Z|00017|ofproto_dpif|INFO|system@ovs-system: Datapath supports timeout policy in conntrack action 2026-04-20T07:46:43.111Z|00018|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zero_snat 2026-04-20T07:46:43.111Z|00019|ofproto_dpif|INFO|system@ovs-system: Datapath supports add_mpls action 2026-04-20T07:46:43.111Z|00020|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state 2026-04-20T07:46:43.111Z|00021|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone 2026-04-20T07:46:43.111Z|00022|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark 2026-04-20T07:46:43.111Z|00023|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label 2026-04-20T07:46:43.111Z|00024|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat 2026-04-20T07:46:43.112Z|00025|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple 2026-04-20T07:46:43.112Z|00026|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_orig_tuple6 2026-04-20T07:46:43.112Z|00027|ofproto_dpif|INFO|system@ovs-system: Datapath does not support IPv6 ND Extensions 2026-04-20T07:46:43.112Z|00028|ofproto_dpif_upcall|INFO|Overriding n-handler-threads to 16, setting n-revalidator-threads to 5 2026-04-20T07:46:43.112Z|00029|ofproto_dpif_upcall|INFO|Starting 21 threads 2026-04-20T07:46:43.227Z|00030|bridge|INFO|bridge br-int: added interface br-int on port 65534 2026-04-20T07:46:43.227Z|00031|bridge|INFO|bridge br-int: using datapath ID 0000b66388a90ab8 2026-04-20T07:46:43.227Z|00032|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt" 2026-04-20T07:46:43.228Z|00033|bridge|INFO|ovs-vswitchd (Open vSwitch) 3.3.4 2026-04-20T07:46:43.691Z|00034|bridge|INFO|bridge br-ex: added interface br-ex on port 65534 2026-04-20T07:46:43.691Z|00035|bridge|INFO|bridge br-ex: using datapath ID 0000726ffbe6f74c 2026-04-20T07:46:43.692Z|00036|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt" 2026-04-20T07:46:46.864Z|00037|connmgr|INFO|br-int<->unix#1: 18 flow_mods in the last 0 s (17 adds, 1 deletes) 2026-04-20T07:46:53.106Z|00038|memory|INFO|199664 kB peak resident set size after 10.0 seconds 2026-04-20T07:46:53.107Z|00039|memory|INFO|handlers:16 idl-cells-Open_vSwitch:199 ofconns:4 ports:2 revalidators:5 rules:26 2026-04-20T07:46:57.096Z|00040|connmgr|INFO|br-int<->unix#5: 18 flow_mods 10 s ago (17 adds, 1 deletes) 2026-04-20T07:47:27.456Z|00041|bridge|INFO|bridge br-int: added interface patch-br-int-to-provnet-6b33db63-7540-4621-b9c2-679134e13d63 on port 1 2026-04-20T07:47:27.456Z|00042|bridge|INFO|bridge br-ex: added interface patch-provnet-6b33db63-7540-4621-b9c2-679134e13d63-to-br-int on port 1 2026-04-20T07:47:57.096Z|00043|connmgr|INFO|br-int<->unix#5: 554 flow_mods in the 7 s starting 29 s ago (539 adds, 8 deletes, 7 modifications) 2026-04-20T07:51:35.125Z|00044|bridge|INFO|bridge br-int: added interface tapf6468315-ee on port 2 2026-04-20T07:51:38.952Z|00045|bridge|INFO|bridge br-int: added interface tape4732d43-60 on port 3 2026-04-20T07:51:45.177Z|00046|connmgr|INFO|br-int<->unix#5: 159 flow_mods in the 3 s starting 10 s ago (157 adds, 2 deletes) 2026-04-20T07:52:45.177Z|00047|connmgr|INFO|br-int<->unix#5: 461 flow_mods in the 24 s starting 31 s ago (445 adds, 12 deletes, 4 modifications) 2026-04-20T07:53:39.555Z|00048|bridge|WARN|could not open network device tapc9c79cdc-2c (No such device) 2026-04-20T07:53:41.138Z|00049|bridge|INFO|bridge br-int: added interface tapc9c79cdc-2c on port 4 2026-04-20T07:53:41.606Z|00050|bridge|INFO|bridge br-int: added interface tap06b7e55d-90 on port 5 2026-04-20T07:53:51.177Z|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-20T07:54:51.176Z|00052|connmgr|INFO|br-int<->unix#5: 12 flow_mods 36 s ago (8 adds, 4 deletes) 2026-04-20T07:55:51.177Z|00053|connmgr|INFO|br-int<->unix#5: 16 flow_mods in the 30 s starting 35 s ago (8 adds, 8 deletes) 2026-04-20T07:56:56.064Z|00054|connmgr|INFO|br-int<->unix#5: 8 flow_mods 10 s ago (4 adds, 4 deletes) 2026-04-20T07:57:20.721Z|00055|bridge|WARN|could not open network device tap21b6b9c9-b3 (No such device) 2026-04-20T07:57:22.325Z|00056|bridge|INFO|bridge br-int: added interface tap21b6b9c9-b3 on port 6 2026-04-20T07:57:22.899Z|00057|bridge|WARN|could not open network device tap0ed80125-98 (No such device) 2026-04-20T07:57:24.790Z|00058|bridge|INFO|bridge br-int: added interface tap0ed80125-98 on port 7 2026-04-20T07:57:56.065Z|00059|connmgr|INFO|br-int<->unix#5: 1032 flow_mods in the 17 s starting 47 s ago (757 adds, 275 deletes) 2026-04-20T08:13:54.349Z|00001|timeval(revalidator18)|WARN|Unreasonably long 1455ms poll interval (620ms user, 834ms system) 2026-04-20T08:13:54.349Z|00002|timeval(revalidator18)|WARN|context switches: 0 voluntary, 1 involuntary 2026-04-20T08:13:54.349Z|00003|coverage(revalidator18)|INFO|Event coverage, avg rate over last: 5 seconds, last minute, last hour, hash=2ab1c4e6: 2026-04-20T08:13:54.349Z|00004|coverage(revalidator18)|INFO|nln_changed 0.0/sec 0.000/sec 0.0278/sec total: 100 2026-04-20T08:13:54.349Z|00005|coverage(revalidator18)|INFO|route_table_dump 0.0/sec 0.000/sec 0.0197/sec total: 71 2026-04-20T08:13:54.349Z|00006|coverage(revalidator18)|INFO|netlink_sent 7.8/sec 12.500/sec 8.1408/sec total: 29318 2026-04-20T08:13:54.349Z|00007|coverage(revalidator18)|INFO|netlink_recv_jumbo 1.0/sec 1.000/sec 1.2103/sec total: 4357 2026-04-20T08:13:54.349Z|00008|coverage(revalidator18)|INFO|netlink_received 10.4/sec 15.317/sec 9.3981/sec total: 33847 2026-04-20T08:13:54.349Z|00009|coverage(revalidator18)|INFO|netdev_set_ethtool 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-04-20T08:13:54.349Z|00010|coverage(revalidator18)|INFO|netdev_get_ethtool 0.0/sec 0.000/sec 0.0078/sec total: 28 2026-04-20T08:13:54.349Z|00011|coverage(revalidator18)|INFO|netdev_set_hwaddr 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-20T08:13:54.349Z|00012|coverage(revalidator18)|INFO|netdev_set_policing 0.0/sec 0.000/sec 0.0081/sec total: 29 2026-04-20T08:13:54.349Z|00013|coverage(revalidator18)|INFO|vconn_sent 0.2/sec 0.183/sec 0.1344/sec total: 484 2026-04-20T08:13:54.349Z|00014|coverage(revalidator18)|INFO|vconn_received 0.4/sec 0.283/sec 0.8683/sec total: 3126 2026-04-20T08:13:54.349Z|00015|coverage(revalidator18)|INFO|util_xalloc 581.2/sec 634.317/sec 325.6456/sec total: 1172391 2026-04-20T08:13:54.349Z|00016|coverage(revalidator18)|INFO|stream_open 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-20T08:13:54.349Z|00017|coverage(revalidator18)|INFO|pstream_open 0.0/sec 0.000/sec 0.0014/sec total: 5 2026-04-20T08:13:54.349Z|00018|coverage(revalidator18)|INFO|seq_change 936.2/sec 1049.500/sec 489.0594/sec total: 1760833 2026-04-20T08:13:54.349Z|00019|coverage(revalidator18)|INFO|rconn_sent 0.2/sec 0.183/sec 0.1322/sec total: 476 2026-04-20T08:13:54.349Z|00020|coverage(revalidator18)|INFO|rconn_queued 0.2/sec 0.183/sec 0.1322/sec total: 476 2026-04-20T08:13:54.349Z|00021|coverage(revalidator18)|INFO|poll_zero_timeout 0.6/sec 2.000/sec 1.0639/sec total: 3830 2026-04-20T08:13:54.349Z|00022|coverage(revalidator18)|INFO|poll_create_node 167.4/sec 179.600/sec 88.0806/sec total: 317104 2026-04-20T08:13:54.349Z|00023|coverage(revalidator18)|INFO|txn_success 0.2/sec 0.200/sec 0.0783/sec total: 282 2026-04-20T08:13:54.349Z|00024|coverage(revalidator18)|INFO|txn_incomplete 0.2/sec 0.200/sec 0.0911/sec total: 328 2026-04-20T08:13:54.349Z|00025|coverage(revalidator18)|INFO|txn_unchanged 0.0/sec 0.000/sec 0.0467/sec total: 168 2026-04-20T08:13:54.349Z|00026|coverage(revalidator18)|INFO|netdev_get_stats 2.0/sec 2.000/sec 0.7494/sec total: 2698 2026-04-20T08:13:54.349Z|00027|coverage(revalidator18)|INFO|mac_learning_expired 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-20T08:13:54.349Z|00028|coverage(revalidator18)|INFO|mac_learning_learned 0.0/sec 0.000/sec 0.0008/sec total: 3 2026-04-20T08:13:54.349Z|00029|coverage(revalidator18)|INFO|hmap_expand 19.2/sec 19.533/sec 9.8631/sec total: 35507 2026-04-20T08:13:54.349Z|00030|coverage(revalidator18)|INFO|hmap_pathological 0.0/sec 0.000/sec 0.0036/sec total: 13 2026-04-20T08:13:54.349Z|00031|coverage(revalidator18)|INFO|hindex_expand 0.0/sec 0.000/sec 0.0022/sec total: 8 2026-04-20T08:13:54.349Z|00032|coverage(revalidator18)|INFO|hindex_pathological 0.0/sec 0.000/sec 0.0031/sec total: 11 2026-04-20T08:13:54.349Z|00033|coverage(revalidator18)|INFO|miniflow_malloc 0.0/sec 0.250/sec 2.3381/sec total: 8417 2026-04-20T08:13:54.349Z|00034|coverage(revalidator18)|INFO|flow_extract 0.0/sec 1.300/sec 0.9361/sec total: 3370 2026-04-20T08:13:54.349Z|00035|coverage(revalidator18)|INFO|dpif_port_add 0.0/sec 0.000/sec 0.0019/sec total: 7 2026-04-20T08:13:54.349Z|00036|coverage(revalidator18)|INFO|dpif_flow_put_error 0.0/sec 0.033/sec 0.1144/sec total: 412 2026-04-20T08:13:54.349Z|00037|coverage(revalidator18)|INFO|dpif_flow_put 0.0/sec 1.200/sec 0.7842/sec total: 2823 2026-04-20T08:13:54.349Z|00038|coverage(revalidator18)|INFO|dpif_flow_get 0.0/sec 0.000/sec 0.0069/sec total: 25 2026-04-20T08:13:54.349Z|00039|coverage(revalidator18)|INFO|dpif_flow_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-20T08:13:54.349Z|00040|coverage(revalidator18)|INFO|dpif_flow_del 0.0/sec 1.067/sec 0.6589/sec total: 2375 2026-04-20T08:13:54.349Z|00041|coverage(revalidator18)|INFO|dpif_execute_with_help 0.0/sec 0.050/sec 0.0339/sec total: 122 2026-04-20T08:13:54.349Z|00042|coverage(revalidator18)|INFO|dpif_execute_error 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-20T08:13:54.349Z|00043|coverage(revalidator18)|INFO|dpif_execute 0.4/sec 1.450/sec 0.9875/sec total: 3555 2026-04-20T08:13:54.349Z|00044|coverage(revalidator18)|INFO|cmap_shrink 0.0/sec 0.000/sec 0.3628/sec total: 1306 2026-04-20T08:13:54.349Z|00045|coverage(revalidator18)|INFO|cmap_expand 0.0/sec 0.000/sec 0.5275/sec total: 1899 2026-04-20T08:13:54.349Z|00046|coverage(revalidator18)|INFO|ccmap_shrink 0.0/sec 0.000/sec 0.0378/sec total: 136 2026-04-20T08:13:54.349Z|00047|coverage(revalidator18)|INFO|ccmap_expand 0.0/sec 0.000/sec 0.0078/sec total: 28 2026-04-20T08:13:54.349Z|00048|coverage(revalidator18)|INFO|xlate_actions 0.4/sec 2.050/sec 1.5525/sec total: 5589 2026-04-20T08:13:54.349Z|00049|coverage(revalidator18)|INFO|upcall_ukey_replace 0.0/sec 0.000/sec 0.0019/sec total: 7 2026-04-20T08:13:54.349Z|00050|coverage(revalidator18)|INFO|upcall_flow_limit_grew 0.0/sec 0.000/sec 0.0333/sec total: 120 2026-04-20T08:13:54.349Z|00051|coverage(revalidator18)|INFO|handler_duplicate_upcall 0.0/sec 0.000/sec 0.0989/sec total: 356 2026-04-20T08:13:54.349Z|00052|coverage(revalidator18)|INFO|rev_mac_learning 0.0/sec 0.000/sec 0.0011/sec total: 4 2026-04-20T08:13:54.349Z|00053|coverage(revalidator18)|INFO|rev_flow_table 0.0/sec 0.000/sec 0.0111/sec total: 40 2026-04-20T08:13:54.349Z|00054|coverage(revalidator18)|INFO|rev_port_toggled 0.0/sec 0.000/sec 0.0017/sec total: 6 2026-04-20T08:13:54.349Z|00055|coverage(revalidator18)|INFO|rev_reconfigure 0.0/sec 0.000/sec 0.0022/sec total: 8 2026-04-20T08:13:54.349Z|00056|coverage(revalidator18)|INFO|ofproto_update_port 0.0/sec 0.000/sec 0.0117/sec total: 42 2026-04-20T08:13:54.349Z|00057|coverage(revalidator18)|INFO|ofproto_recv_openflow 0.4/sec 0.283/sec 0.8661/sec total: 3118 2026-04-20T08:13:54.349Z|00058|coverage(revalidator18)|INFO|ofproto_packet_out 0.4/sec 0.200/sec 0.0842/sec total: 303 2026-04-20T08:13:54.349Z|00059|coverage(revalidator18)|INFO|ofproto_flush 0.0/sec 0.000/sec 0.0003/sec total: 1 2026-04-20T08:13:54.349Z|00060|coverage(revalidator18)|INFO|bridge_reconfigure 0.0/sec 0.000/sec 0.0175/sec total: 63 2026-04-20T08:13:54.349Z|00061|coverage(revalidator18)|INFO|114 events never hit 2026-04-20T08:19:53.936Z|00062|ofproto_dpif_upcall(revalidator18)|WARN|Spent an unreasonably long 2863ms dumping flows