On 7/15/22 20:33, Ilya Maximets wrote: > On 7/15/22 20:24, Stokes, Ian wrote: >>> Looks like new MTU tests are unstable. >>> Ian, Michael, could you, please, check? >>> >>> Best regards, Ilya Maximets. >> >> Hey Ilya, >> >> Let me take a look at this, it passed our internal CI so not sure why it's >> failed externally, worst case I can revert this as it was really just a nice >> to have, if it's holding up the branching would you prefer this? > > No, it's not holding up branching. Just wanted to highlight the > issue. Would be great to fix it in a near future, but it's not > critical for right now. Thanks.
I think, the tests are lacking the 'Clean up the testpmd now' part at least. If testpmd is still running while we're tearing down OVS, there is a fair chance that some packets will be received while the OpenFlow port is already removed. That migth explain some of the failures. Not sure if that what happened in this particular case thouhg. Also, looksing at other failure reports I see some weird things like: Unreasonably long 1140ms poll interval (408ms user, 263ms system) 408 + 263 != 1140. The main thread got preempted for half a second in this case. Are you running something else on the same cores on that system? Or maybe some processes are not getting stopped properly after the test? > > Best regards, Ilya Maximets. > >> >> Thanks >> Ian >>> >>> On 7/15/22 18:28, ovs_jenk...@intel.com wrote: >>>> Test-Label: intel-ovs-compilation >>>> Test-Status: fail >>>> http://patchwork.ozlabs.org/api/patches/1656877/ >>>> >>>> AVX-512_compilation: failed >>>> DPLCS Test: success >>>> DPIF Test: success >>>> MFEX Test: fail >>>> Errors in DPCLS test: >>>> None >>>> >>>> Errors in DPIF test: >>>> None >>>> >>>> Errors in MFEX test: >>>>> 2022-07-15T16:27:27.850Z|00046|coverage|INFO|stream_open >>> 0.0/sec 0.000/sec 0.0000/sec total: 1 >>>>> 2022-07-15T16:27:27.850Z|00047|coverage|INFO|util_xalloc >>> 0.0/sec 0.000/sec 0.0000/sec total: 8198 >>>>> 2022-07-15T16:27:27.850Z|00048|coverage|INFO|netlink_received >>> 0.0/sec 0.000/sec 0.0000/sec total: 29 >>>>> 2022-07-15T16:27:27.850Z|00049|coverage|INFO|netlink_recv_jumbo >>> 0.0/sec 0.000/sec 0.0000/sec total: 3 >>>>> 2022-07-15T16:27:27.850Z|00050|coverage|INFO|netlink_sent >>> 0.0/sec 0.000/sec 0.0000/sec total: 27 >>>>> 2022-07-15T16:27:27.850Z|00051|coverage|INFO|134 events never >>> hit >>>>> 2022-07-15T16:27:27.851Z|00052|bridge|INFO|ovs-vswitchd (Open >>> vSwitch) 3.0.90 >>>>> 2022-07-15T16:27:27.957Z|00053|pmd_perf|INFO|DPDK provided TSC >>> frequency: 2400000 KHz >>>>> 2022-07-15T16:27:27.958Z|00054|dpif_netdev_extract|INFO|Default >>> MFEX Extract implementation is autovalidator. >>>>> 2022-07-15T16:27:27.969Z|00055|dpif_netdev_impl|INFO|Default >>> DPIF implementation is dpif_scalar. >>>>> 2022-07-15T16:27:27.982Z|00056|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports recirculation >>>>> 2022-07-15T16:27:27.982Z|00057|ofproto_dpif|INFO|netdev@ovs- >>> netdev: VLAN header stack length probed as 1 >>>>> 2022-07-15T16:27:27.982Z|00058|ofproto_dpif|INFO|netdev@ovs- >>> netdev: MPLS label stack length probed as 3 >>>>> 2022-07-15T16:27:27.982Z|00059|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports truncate action >>>>> 2022-07-15T16:27:27.982Z|00060|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports unique flow ids >>>>> 2022-07-15T16:27:27.982Z|00061|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports clone action >>>>> 2022-07-15T16:27:27.982Z|00062|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Max sample nesting level probed as 10 >>>>> 2022-07-15T16:27:27.982Z|00063|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports eventmask in conntrack action >>>>> 2022-07-15T16:27:27.982Z|00064|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_clear action >>>>> 2022-07-15T16:27:27.982Z|00065|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Max dp_hash algorithm probed to be 1 >>>>> 2022-07-15T16:27:27.982Z|00066|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports check_pkt_len action >>>>> 2022-07-15T16:27:27.982Z|00067|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports timeout policy in conntrack action >>>>> 2022-07-15T16:27:27.982Z|00068|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_zero_snat >>>>> 2022-07-15T16:27:27.983Z|00069|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports add_mpls action >>>>> 2022-07-15T16:27:27.983Z|00070|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_state >>>>> 2022-07-15T16:27:27.983Z|00071|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_zone >>>>> 2022-07-15T16:27:27.983Z|00072|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_mark >>>>> 2022-07-15T16:27:27.983Z|00073|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_label >>>>> 2022-07-15T16:27:27.983Z|00074|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_state_nat >>>>> 2022-07-15T16:27:27.983Z|00075|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_orig_tuple >>>>> 2022-07-15T16:27:27.983Z|00076|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports ct_orig_tuple6 >>>>> 2022-07-15T16:27:27.983Z|00077|ofproto_dpif|INFO|netdev@ovs- >>> netdev: Datapath supports IPv6 ND Extensions >>>>> 2022-07-15T16:27:27.983Z|00078|ofproto_dpif_upcall|INFO|Setting >>> n-handler-threads to 71, setting n-revalidator-threads to 25 >>>>> 2022-07-15T16:27:27.983Z|00079|ofproto_dpif_upcall|INFO|Starting >>> 96 threads >>>>> 2022-07-15T16:27:28.031Z|00080|dpif_netdev|INFO|pmd-rxq-affinity >>> isolates PMD core >>>>> 2022-07-15T16:27:28.031Z|00081|dpif_netdev|INFO|PMD auto load >>> balance interval set to 1 mins >>>>> 2022-07-15T16:27:28.031Z|00082|dpif_netdev|INFO|PMD auto load >>> balance improvement threshold set to 25% >>>>> 2022-07-15T16:27:28.031Z|00083|dpif_netdev|INFO|PMD auto load >>> balance load threshold set to 95% >>>>> 2022-07-15T16:27:28.031Z|00084|dpif_netdev|INFO|PMD auto load >>> balance is disabled. >>>>> 2022-07-15T16:27:28.038Z|00085|bridge|INFO|bridge br10: added >>> interface br10 on port 65534 >>>>> 2022-07-15T16:27:28.039Z|00086|bridge|INFO|bridge br10: using >>> datapath ID 0000f2468109f34d >>>>> 2022-07-15T16:27:28.039Z|00087|connmgr|INFO|br10: added service >>> controller "punix:/root/ovs-dev/tests/system-dpdk- >>> testsuite.dir/17/br10.mgmt" >>>>> 2022-07-15T16:27:28.077Z|00088|dpif_netdev|INFO|PMD thread on >>> numa_id: 0, core id: 21 created. >>>>> 2022-07-15T16:27:28.087Z|00001|dpdk(pmd-c21/id:101)|INFO|PMD >>> thread uses DPDK lcore 1. >>>>> 2022-07-15T16:27:28.088Z|00089|dpif_netdev|INFO|PMD thread on >>> numa_id: 1, core id: 88 created. >>>>> 2022-07-15T16:27:28.088Z|00090|dpif_netdev|INFO|There are 1 pmd >>> threads on numa node 1 >>>>> 2022-07-15T16:27:28.088Z|00091|dpif_netdev|INFO|There are 1 pmd >>> threads on numa node 0 >>>>> 2022-07-15T16:27:28.088Z|00092|dpdk|INFO|VHOST_CONFIG: Linear >>> buffers requested without external buffers, disabling host segmentation >>> offloading support >>>>> 2022-07-15T16:27:28.088Z|00001|dpdk(pmd-c88/id:102)|INFO|PMD >>> thread uses DPDK lcore 2. >>>>> 2022-07-15T16:27:28.088Z|00093|dpdk|INFO|VHOST_CONFIG: vhost- >>> user client: socket created, fd: 103 >>>>> 2022-07-15T16:27:28.088Z|00094|netdev_dpdk|INFO|vHost User >>> device 'dpdkvhostuserclient0' created in 'client' mode, using client socket >>> '/root/ovs-dev/tests/system-dpdk-testsuite.dir/17/dpdkvhostclient0' >>>>> 2022-07-15T16:27:28.089Z|00095|dpdk|WARN|VHOST_CONFIG: >>> failed to connect to /root/ovs-dev/tests/system-dpdk- >>> testsuite.dir/17/dpdkvhostclient0: No such file or directory >>>>> 2022-07-15T16:27:28.089Z|00096|dpdk|INFO|VHOST_CONFIG: >>> /root/ovs-dev/tests/system-dpdk-testsuite.dir/17/dpdkvhostclient0: >>> reconnecting... >>>>> 2022-07-15T16:27:28.089Z|00097|dpif_netdev|INFO|Performing pmd >>> to rx queue assignment using cycles algorithm. >>>>> 2022-07-15T16:27:28.089Z|00098|dpif_netdev|INFO|Core 21 on >>> numa node 0 assigned port 'dpdkvhostuserclient0' rx queue 0 (measured >>> processing cycles 0). >>>>> 2022-07-15T16:27:28.094Z|00099|bridge|INFO|bridge br10: added >>> interface dpdkvhostuserclient0 on port 1 >>>>> 2022-07-15T16:27:28.118Z|00100|dpif_netdev|INFO|Performing pmd >>> to rx queue assignment using cycles algorithm. >>>>> 2022-07-15T16:27:28.118Z|00101|dpif_netdev|INFO|Core 21 on >>> numa node 0 assigned port 'dpdkvhostuserclient0' rx queue 0 (measured >>> processing cycles 0). >>>>> 2022-07-15T16:27:31.089Z|00001|dpdk|INFO|VHOST_CONFIG: >>> /root/ovs-dev/tests/system-dpdk-testsuite.dir/17/dpdkvhostclient0: >>> connected >>>>> 2022-07-15T16:27:31.089Z|00002|dpdk|INFO|VHOST_CONFIG: new >>> device, handle is 0, path is /root/ovs-dev/tests/system-dpdk- >>> testsuite.dir/17/dpdkvhostclient0 >>>>> 2022-07-15T16:27:31.964Z|00001|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_GET_FEATURES >>>>> 2022-07-15T16:27:31.964Z|00002|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_GET_PROTOCOL_FEATURES >>>>> 2022-07-15T16:27:31.964Z|00003|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_PROTOCOL_FEATURES >>>>> 2022-07-15T16:27:31.964Z|00004|dpdk|INFO|VHOST_CONFIG: >>> negotiated Vhost-user protocol features: 0x10009 >>>>> 2022-07-15T16:27:31.964Z|00005|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_CALL >>>>> 2022-07-15T16:27:31.964Z|00006|dpdk|INFO|VHOST_CONFIG: vring >>> call idx:0 file:110 >>>>> 2022-07-15T16:27:31.964Z|00007|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_CALL >>>>> 2022-07-15T16:27:31.964Z|00008|dpdk|INFO|VHOST_CONFIG: vring >>> call idx:1 file:111 >>>>> 2022-07-15T16:27:31.965Z|00009|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_FEATURES >>>>> 2022-07-15T16:27:31.965Z|00010|dpdk|INFO|VHOST_CONFIG: >>> negotiated Virtio features: 0x910008000 >>>>> 2022-07-15T16:27:31.965Z|00011|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_STATUS >>>>> 2022-07-15T16:27:31.965Z|00012|dpdk|INFO|VHOST_CONFIG: New >>> device status(0x0000000b): >>>>> -RESET: 0 >>>>> -ACKNOWLEDGE: 1 >>>>> -DRIVER: 1 >>>>> -FEATURES_OK: 1 >>>>> -DRIVER_OK: 0 >>>>> -DEVICE_NEED_RESET: 0 >>>>> -FAILED: 0 >>>>> 2022-07-15T16:27:31.965Z|00013|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_GET_STATUS >>>>> 2022-07-15T16:27:31.965Z|00014|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_MEM_TABLE >>>>> 2022-07-15T16:27:31.965Z|00015|dpdk|INFO|VHOST_CONFIG: guest >>> memory region size: 0x100000000 >>>>> guest physical addr: 0x140000000 >>>>> guest virtual addr: 0x140000000 >>>>> host virtual addr: 0x7fc380000000 >>>>> mmap addr : 0x7fc380000000 >>>>> mmap size : 0x100000000 >>>>> mmap align: 0x40000000 >>>>> mmap off : 0x0 >>>>> 2022-07-15T16:27:31.965Z|00016|dpdk|INFO|VHOST_CONFIG: guest >>> memory region size: 0x100000000 >>>>> guest physical addr: 0x11c0000000 >>>>> guest virtual addr: 0x11c0000000 >>>>> host virtual addr: 0x7fc280000000 >>>>> mmap addr : 0x7fc280000000 >>>>> mmap size : 0x100000000 >>>>> mmap align: 0x40000000 >>>>> mmap off : 0x0 >>>>> 2022-07-15T16:27:31.965Z|00017|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_NUM >>>>> 2022-07-15T16:27:31.965Z|00018|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_BASE >>>>> 2022-07-15T16:27:31.965Z|00019|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_ADDR >>>>> 2022-07-15T16:27:31.965Z|00020|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_KICK >>>>> 2022-07-15T16:27:31.965Z|00021|dpdk|INFO|VHOST_CONFIG: vring >>> kick idx:0 file:114 >>>>> 2022-07-15T16:27:31.965Z|00022|netdev_dpdk|INFO|State of queue >>> 0 ( tx_qid 0 ) of vhost device '/root/ovs-dev/tests/system-dpdk- >>> testsuite.dir/17/dpdkvhostclient0' changed to 'enabled' >>>>> 2022-07-15T16:27:31.965Z|00023|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_NUM >>>>> 2022-07-15T16:27:31.965Z|00024|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_BASE >>>>> 2022-07-15T16:27:31.965Z|00025|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_ADDR >>>>> 2022-07-15T16:27:31.965Z|00026|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_KICK >>>>> 2022-07-15T16:27:31.965Z|00027|dpdk|INFO|VHOST_CONFIG: vring >>> kick idx:1 file:115 >>>>> 2022-07-15T16:27:31.965Z|00028|netdev_dpdk|INFO|State of queue >>> 1 ( rx_qid 0 ) of vhost device '/root/ovs-dev/tests/system-dpdk- >>> testsuite.dir/17/dpdkvhostclient0' changed to 'enabled' >>>>> 2022-07-15T16:27:31.965Z|00029|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_ENABLE >>>>> 2022-07-15T16:27:31.965Z|00030|dpdk|INFO|VHOST_CONFIG: set >>> queue enable: 1 to qp idx: 0 >>>>> 2022-07-15T16:27:31.965Z|00031|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_VRING_ENABLE >>>>> 2022-07-15T16:27:31.965Z|00032|dpdk|INFO|VHOST_CONFIG: set >>> queue enable: 1 to qp idx: 1 >>>>> 2022-07-15T16:27:31.965Z|00033|dpdk|INFO|VHOST_CONFIG: read >>> message VHOST_USER_SET_STATUS >>>>> 2022-07-15T16:27:31.965Z|00034|dpdk|INFO|VHOST_CONFIG: New >>> device status(0x0000000f): >>>>> -RESET: 0 >>>>> -ACKNOWLEDGE: 1 >>>>> -DRIVER: 1 >>>>> -FEATURES_OK: 1 >>>>> -DRIVER_OK: 1 >>>>> -DEVICE_NEED_RESET: 0 >>>>> -FAILED: 0 >>>>> 2022-07-15T16:27:31.965Z|00035|dpdk|INFO|VHOST_CONFIG: virtio >>> is now ready for processing. >>>>> 2022-07-15T16:27:31.965Z|00036|netdev_dpdk|INFO|vHost Device >>> '/root/ovs-dev/tests/system-dpdk-testsuite.dir/17/dpdkvhostclient0' has >>> been added on numa node 0 >>>>> 2022-07-15T16:27:33.023Z|00102|dpif_netdev|INFO|Performing pmd >>> to rx queue assignment using cycles algorithm. >>>>> 2022-07-15T16:27:33.023Z|00103|dpif_netdev|INFO|Core 21 on >>> numa node 0 assigned port 'dpdkvhostuserclient0' rx queue 0 (measured >>> processing cycles 0). >>>>> 2022-07-15T16:27:33.024Z|00104|unixctl|DBG|received request >>> dpctl/show[], id=0 >>>>> 2022-07-15T16:27:33.024Z|00105|unixctl|DBG|replying with success, >>> id=0: "netdev@ovs-netdev: >>>>> lookups: hit:0 missed:2 lost:0 >>>>> flows: 2 >>>>> port 0: ovs-netdev (tap) >>>>> port 1: br10 (tap) >>>>> port 2: dpdkvhostuserclient0 (dpdkvhostuserclient: >>> configured_rx_queues=1, configured_tx_queues=1, mtu=9000, >>> requested_rx_queues=1, requested_tx_queues=1) >>>>> " >>>>> 2022-07-15T16:27:33.025Z|00106|timeval|WARN|Unreasonably long >>> 1059ms poll interval (158ms user, 901ms system) >>>>> 2022-07-15T16:27:33.025Z|00107|timeval|WARN|faults: 8 minor, 0 >>> major >>>>> 2022-07-15T16:27:33.025Z|00108|timeval|WARN|context switches: 9 >>> voluntary, 1 involuntary >>>>> 2022-07-15T16:27:33.025Z|00109|coverage|INFO|Event coverage, avg >>> rate over last: 5 seconds, last minute, last hour, hash=da40191b: >>>>> 2022-07-15T16:27:33.025Z|00110|coverage|INFO|bridge_reconfigure >>> 1.2/sec 0.100/sec 0.0017/sec total: 6 >>>>> 2022-07-15T16:27:33.025Z|00111|coverage|INFO|ofproto_flush >>> 0.2/sec 0.017/sec 0.0003/sec total: 1 >>>>> 2022-07- >>> 15T16:27:33.025Z|00112|coverage|INFO|ofproto_update_port >>> 0.8/sec 0.067/sec 0.0011/sec total: 5 >>>>> 2022-07-15T16:27:33.025Z|00113|coverage|INFO|rev_reconfigure >>> 0.8/sec 0.067/sec 0.0011/sec total: 4 >>>>> 2022-07-15T16:27:33.025Z|00114|coverage|INFO|rev_flow_table >>> 0.2/sec 0.017/sec 0.0003/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00115|coverage|INFO|xlate_actions >>> 0.0/sec 0.000/sec 0.0000/sec total: 2 >>>>> 2022-07-15T16:27:33.025Z|00116|coverage|INFO|ccmap_shrink >>> 4.8/sec 0.400/sec 0.0067/sec total: 24 >>>>> 2022-07-15T16:27:33.025Z|00117|coverage|INFO|cmap_expand >>> 12.6/sec 1.050/sec 0.0175/sec total: 75 >>>>> 2022-07-15T16:27:33.025Z|00118|coverage|INFO|cmap_shrink >>> 8.8/sec 0.733/sec 0.0122/sec total: 50 >>>>> 2022-07-15T16:27:33.025Z|00119|coverage|INFO|dpif_port_add >>> 0.4/sec 0.033/sec 0.0006/sec total: 2 >>>>> 2022-07-15T16:27:33.025Z|00120|coverage|INFO|dpif_flow_flush >>> 0.2/sec 0.017/sec 0.0003/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00121|coverage|INFO|dpif_flow_get >>> 4.8/sec 0.400/sec 0.0067/sec total: 24 >>>>> 2022-07-15T16:27:33.025Z|00122|coverage|INFO|dpif_flow_put >>> 5.0/sec 0.417/sec 0.0069/sec total: 25 >>>>> 2022-07-15T16:27:33.025Z|00123|coverage|INFO|dpif_flow_del >>> 4.8/sec 0.400/sec 0.0067/sec total: 24 >>>>> 2022-07-15T16:27:33.025Z|00124|coverage|INFO|dpif_execute >>> 1.2/sec 0.100/sec 0.0017/sec total: 6 >>>>> 2022-07-15T16:27:33.025Z|00125|coverage|INFO|flow_extract >>> 0.8/sec 0.067/sec 0.0011/sec total: 4 >>>>> 2022-07-15T16:27:33.025Z|00126|coverage|INFO|miniflow_malloc >>> 8.0/sec 0.667/sec 0.0111/sec total: 48 >>>>> 2022-07-15T16:27:33.025Z|00127|coverage|INFO|hmap_pathological >>> 1.2/sec 0.100/sec 0.0017/sec total: 6 >>>>> 2022-07-15T16:27:33.025Z|00128|coverage|INFO|hmap_expand >>> 170.0/sec 14.167/sec 0.2361/sec total: 880 >>>>> 2022-07-15T16:27:33.025Z|00129|coverage|INFO|hmap_shrink >>> 1.0/sec 0.083/sec 0.0014/sec total: 9 >>>>> 2022-07- >>> 15T16:27:33.025Z|00130|coverage|INFO|mac_learning_learned >>> 0.0/sec 0.000/sec 0.0000/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00131|coverage|INFO|netdev_received >>> 0.0/sec 0.000/sec 0.0000/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00132|coverage|INFO|netdev_sent >>> 0.0/sec 0.000/sec 0.0000/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00133|coverage|INFO|netdev_get_stats >>> 0.4/sec 0.033/sec 0.0006/sec total: 4 >>>>> 2022-07-15T16:27:33.025Z|00134|coverage|INFO|txn_unchanged >>> 1.4/sec 0.117/sec 0.0019/sec total: 7 >>>>> 2022-07-15T16:27:33.025Z|00135|coverage|INFO|txn_incomplete >>> 1.6/sec 0.133/sec 0.0022/sec total: 10 >>>>> 2022-07-15T16:27:33.025Z|00136|coverage|INFO|txn_success >>> 0.8/sec 0.067/sec 0.0011/sec total: 4 >>>>> 2022-07-15T16:27:33.025Z|00137|coverage|INFO|poll_create_node >>> 529.2/sec 44.100/sec 0.7350/sec total: 2989 >>>>> 2022-07-15T16:27:33.025Z|00138|coverage|INFO|poll_zero_timeout >>> 7.4/sec 0.617/sec 0.0103/sec total: 38 >>>>> 2022-07-15T16:27:33.025Z|00139|coverage|INFO|seq_change >>> 6388.6/sec 532.383/sec 8.8731/sec total: 40663 >>>>> 2022-07-15T16:27:33.025Z|00140|coverage|INFO|pstream_open >>> 0.6/sec 0.050/sec 0.0008/sec total: 3 >>>>> 2022-07-15T16:27:33.025Z|00141|coverage|INFO|stream_open >>> 0.2/sec 0.017/sec 0.0003/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00142|coverage|INFO|unixctl_received >>> 0.0/sec 0.000/sec 0.0000/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00143|coverage|INFO|unixctl_replied >>> 0.0/sec 0.000/sec 0.0000/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00144|coverage|INFO|util_xalloc >>> 3957.2/sec 329.767/sec 5.4961/sec total: 21524 >>>>> 2022-07-15T16:27:33.025Z|00145|coverage|INFO|netdev_set_policing >>> 0.4/sec 0.033/sec 0.0006/sec total: 2 >>>>> 2022-07-15T16:27:33.025Z|00146|coverage|INFO|netdev_set_hwaddr >>> 0.2/sec 0.017/sec 0.0003/sec total: 1 >>>>> 2022-07-15T16:27:33.025Z|00147|coverage|INFO|netdev_get_ethtool >>> 0.6/sec 0.050/sec 0.0008/sec total: 3 >>>>> 2022-07-15T16:27:33.025Z|00148|coverage|INFO|netlink_received >>> 26.2/sec 2.183/sec 0.0364/sec total: 165 >>>>> 2022-07-15T16:27:33.025Z|00149|coverage|INFO|netlink_recv_jumbo >>> 7.4/sec 0.617/sec 0.0103/sec total: 43 >>>>> 2022-07-15T16:27:33.025Z|00150|coverage|INFO|netlink_sent >>> 22.0/sec 1.833/sec 0.0306/sec total: 136 >>>>> 2022-07-15T16:27:33.025Z|00151|coverage|INFO|nln_changed >>> 1.4/sec 0.117/sec 0.0019/sec total: 9 >>>>> 2022-07-15T16:27:33.025Z|00152|coverage|INFO|107 events never >>> hit >>>>> 2022-07-15T16:27:33.145Z|00153|dpif_netdev|INFO|Performing pmd >>> to rx queue assignment using cycles algorithm. >>>>> 2022-07-15T16:27:33.145Z|00154|dpif_netdev|INFO|Core 21 on >>> numa node 0 assigned port 'dpdkvhostuserclient0' rx queue 0 (measured >>> processing cycles 0). >>>>> 2022-07-15T16:27:33.156Z|00155|unixctl|DBG|received request >>> dpctl/show[], id=0 >>>>> 2022-07-15T16:27:33.156Z|00156|unixctl|DBG|replying with success, >>> id=0: "netdev@ovs-netdev: >>>>> lookups: hit:0 missed:2 lost:0 >>>>> flows: 2 >>>>> port 0: ovs-netdev (tap) >>>>> port 1: br10 (tap) >>>>> port 2: dpdkvhostuserclient0 (dpdkvhostuserclient: >>> configured_rx_queues=1, configured_tx_queues=1, mtu=2000, >>> requested_rx_queues=1, requested_tx_queues=1) >>>>> " >>>>> 2022-07-15T16:27:33.180Z|00157|bridge|INFO|bridge br10: deleted >>> interface dpdkvhostuserclient0 on port 1 >>>>> 2022-07-15T16:27:33.181Z|00158|dpif_netdev|INFO|PMD thread on >>> numa_id: 1, core id: 88 destroyed. >>>>> 2022-07-15T16:27:33.188Z|00002|ofproto_dpif_xlate(pmd- >>> c21/id:101)|WARN|received packet on unknown port 1 on bridge br10 >>> while processing >>> icmp6,in_port=1,vlan_tci=0x0000,dl_src=3e:6f:83:d8:3c:98,dl_dst=33:33: >>> 00:00:00:02,ipv6_src=fe80::3c6f:83ff:fed8:3c98,ipv6_dst=ff02::2,ipv6_lab >>> el=0x00000,nw_tos=0,nw_ecn=0,nw_ttl=255,icmp_type=133,icmp_code= >>> 0 >>>>> 2022-07-15T16:27:33.197Z|00002|dpdk(pmd-c88/id:102)|INFO|PMD >>> thread released DPDK lcore 2. >>>>> 2022-07-15T16:27:33.202Z|00159|dpif_netdev|INFO|PMD thread on >>> numa_id: 0, core id: 21 destroyed. >>>>> 2022-07-15T16:27:33.212Z|00003|dpdk(pmd-c21/id:101)|INFO|PMD >>> thread released DPDK lcore 1. >>>>> 2022-07-15T16:27:33.220Z|00160|dpdk|INFO|VHOST_CONFIG: free >>> connfd = 103 for device '/root/ovs-dev/tests/system-dpdk- >>> testsuite.dir/17/dpdkvhostclient0' >>>>> 2022-07-15T16:27:33.220Z|00161|netdev_dpdk|INFO|vHost Device >>> '/root/ovs-dev/tests/system-dpdk-testsuite.dir/17/dpdkvhostclient0' not >>> found >>>> 17. system-dpdk.at:684: 17. OVS-DPDK - MTU decrease vport port >>> (system-dpdk.at:684): FAILED (system-dpdk.at:728) >> > _______________________________________________ dev mailing list d...@openvswitch.org https://mail.openvswitch.org/mailman/listinfo/ovs-dev
