[ovs-discuss] OVS Errors

Ben Pfaff blp at ovn.org
Tue May 2 15:07:28 UTC 2017


Usually because OVS thinks it might be using excessive CPU time.  Unless
you see real problems, you can ignore them.

On Tue, May 02, 2017 at 11:19:39AM +0800, Sun Paul wrote:
> hello
> 
> anyone can help?
> 
> On Mon, Apr 10, 2017 at 11:50 AM, Sun Paul <paulrbk at gmail.com> wrote:
> > is there any reason why those info come out?
> >
> > On Sat, Apr 8, 2017 at 6:08 AM, Ben Pfaff <blp at ovn.org> wrote:
> >> None of those are errors.
> >>
> >> On Fri, Apr 07, 2017 at 09:26:22AM +0800, Sun Paul wrote:
> >>> Hi
> >>>
> >>> we used OVS to create bridge interface and passing SCTP traffics on
> >>> it, after running a period of time, we found below error, any idea?
> >>>
> >>>
> >>> Apr  6 01:30:04 app011 ovs-vswitchd[11300]:
> >>> 2017-04-06T01:30:03Z|00392|coverage(revalidator10)|INFO|netlink_recv_jumbo
> >>>         0.8/sec     0.800/sec        0.7911/sec   total: 196782
> >>> Apr  6 01:30:04 app011 ovs-vswitchd[11300]:
> >>> 2017-04-06T01:30:03Z|00393|coverage(revalidator10)|INFO|netlink_sent
> >>>             8.2/sec     7.600/sec        8.7511/sec   total: 1832605
> >>> Apr  6 01:30:04 app011 ovs-vswitchd[11300]:
> >>> 2017-04-06T01:30:03Z|00394|coverage(revalidator10)|INFO|nln_changed
> >>>             0.0/sec     0.000/sec        0.0006/sec   total: 58
> >>> Apr  6 01:30:04 app011 ovs-vswitchd[11300]:
> >>> 2017-04-06T01:30:03Z|00395|coverage(revalidator10)|INFO|56 events
> >>> never hit
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00351|coverage(revalidator10)|INFO|ofproto_flush
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 4
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00352|coverage(revalidator10)|INFO|ofproto_update_port
> >>> 0.0/sec     0.000/sec        0.0006/sec   total: 44
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00353|coverage(revalidator10)|INFO|rev_reconfigure
> >>> 0.0/sec     0.000/sec        0.0006/sec   total: 17
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00354|coverage(revalidator10)|INFO|rev_port_toggled
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 5
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00355|coverage(revalidator10)|INFO|rev_flow_table
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 1
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00356|coverage(revalidator10)|INFO|rev_mac_learning
> >>> 0.0/sec     0.017/sec        0.0008/sec   total: 311
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00357|coverage(revalidator10)|INFO|dumped_new_flow
> >>> 0.0/sec     0.317/sec        0.7211/sec   total: 80247
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00358|coverage(revalidator10)|INFO|handler_duplicate_upcall
> >>> 0.0/sec     0.000/sec        0.0008/sec   total: 3330
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00358|coverage(revalidator10)|INFO|handler_duplicate_upcall
> >>> 0.0/sec     0.000/sec        0.0008/sec   total: 3330
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00359|coverage(revalidator10)|INFO|upcall_ukey_replace
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 13
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00360|coverage(revalidator10)|INFO|xlate_actions
> >>> 1.2/sec     0.650/sec        1.0614/sec   total: 114587
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00361|coverage(revalidator10)|INFO|cmap_expand
> >>> 0.0/sec     0.000/sec        0.0039/sec   total: 456
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00362|coverage(revalidator10)|INFO|cmap_shrink
> >>> 0.0/sec     0.000/sec        0.0039/sec   total: 420
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00363|coverage(revalidator10)|INFO|dpif_port_add
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 8
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00364|coverage(revalidator10)|INFO|dpif_flow_flush
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 5
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00365|coverage(revalidator10)|INFO|dpif_flow_get
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 8
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00366|coverage(revalidator10)|INFO|dpif_flow_put
> >>> 0.8/sec     0.350/sec        0.7631/sec   total: 81900
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00367|coverage(revalidator10)|INFO|dpif_flow_del
> >>> 0.0/sec     0.433/sec        0.7233/sec   total: 80282
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00368|coverage(revalidator10)|INFO|dpif_execute
> >>> 0.8/sec     0.350/sec        0.7639/sec   total: 85224
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00369|coverage(revalidator10)|INFO|flow_extract
> >>> 0.8/sec     0.350/sec        0.7639/sec   total: 85224
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00370|coverage(revalidator10)|INFO|miniflow_malloc
> >>> 0.0/sec     0.000/sec        0.0106/sec   total: 393
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00371|coverage(revalidator10)|INFO|hmap_pathological
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 1
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00372|coverage(revalidator10)|INFO|hmap_expand
> >>> 19.2/sec    19.900/sec       19.0261/sec   total: 4699364
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00373|coverage(revalidator10)|INFO|mac_learning_learned
> >>> 0.0/sec     0.000/sec        0.0003/sec   total: 272
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00374|coverage(revalidator10)|INFO|mac_learning_expired
> >>> 0.0/sec     0.033/sec        0.0008/sec   total: 257
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00375|coverage(revalidator10)|INFO|netdev_get_stats
> >>> 1.6/sec     1.600/sec        1.5822/sec   total: 391152
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00376|coverage(revalidator10)|INFO|txn_unchanged
> >>> 0.0/sec     0.000/sec        0.0017/sec   total: 56
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00377|coverage(revalidator10)|INFO|txn_incomplete
> >>> 0.2/sec     0.200/sec        0.1983/sec   total: 49053
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00359|coverage(revalidator10)|INFO|upcall_ukey_replace
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 13
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00360|coverage(revalidator10)|INFO|xlate_actions
> >>> 1.2/sec     0.650/sec        1.0614/sec   total: 114587
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00361|coverage(revalidator10)|INFO|cmap_expand
> >>> 0.0/sec     0.000/sec        0.0039/sec   total: 456
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00362|coverage(revalidator10)|INFO|cmap_shrink
> >>> 0.0/sec     0.000/sec        0.0039/sec   total: 420
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00363|coverage(revalidator10)|INFO|dpif_port_add
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 8
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00364|coverage(revalidator10)|INFO|dpif_flow_flush
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 5
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00365|coverage(revalidator10)|INFO|dpif_flow_get
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 8
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00366|coverage(revalidator10)|INFO|dpif_flow_put
> >>> 0.8/sec     0.350/sec        0.7631/sec   total: 81900
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00367|coverage(revalidator10)|INFO|dpif_flow_del
> >>> 0.0/sec     0.433/sec        0.7233/sec   total: 80282
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00368|coverage(revalidator10)|INFO|dpif_execute
> >>> 0.8/sec     0.350/sec        0.7639/sec   total: 85224
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00369|coverage(revalidator10)|INFO|flow_extract
> >>> 0.8/sec     0.350/sec        0.7639/sec   total: 85224
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00370|coverage(revalidator10)|INFO|miniflow_malloc
> >>> 0.0/sec     0.000/sec        0.0106/sec   total: 393
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00371|coverage(revalidator10)|INFO|hmap_pathological
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 1
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00372|coverage(revalidator10)|INFO|hmap_expand
> >>> 19.2/sec    19.900/sec       19.0261/sec   total: 4699364
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00373|coverage(revalidator10)|INFO|mac_learning_learned
> >>> 0.0/sec     0.000/sec        0.0003/sec   total: 272
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00374|coverage(revalidator10)|INFO|mac_learning_expired
> >>> 0.0/sec     0.033/sec        0.0008/sec   total: 257
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00375|coverage(revalidator10)|INFO|netdev_get_stats
> >>> 1.6/sec     1.600/sec        1.5822/sec   total: 391152
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00376|coverage(revalidator10)|INFO|txn_unchanged
> >>> 0.0/sec     0.000/sec        0.0017/sec   total: 56
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00377|coverage(revalidator10)|INFO|txn_incomplete
> >>> 0.2/sec     0.200/sec        0.1983/sec   total: 49053
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00378|coverage(revalidator10)|INFO|txn_success
> >>> 0.2/sec     0.200/sec        0.1978/sec   total: 48899
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00378|coverage(revalidator10)|INFO|txn_success
> >>> 0.2/sec     0.200/sec        0.1978/sec   total: 48899
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00379|coverage(revalidator10)|INFO|poll_create_node
> >>> 87.6/sec    87.217/sec       87.6019/sec   total: 21150097
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00380|coverage(revalidator10)|INFO|poll_zero_timeout
> >>> 0.8/sec     0.367/sec        0.7114/sec   total: 79687
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00381|coverage(revalidator10)|INFO|seq_change
> >>> 1043.6/sec  1034.517/sec     1030.8686/sec   total: 254212856
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00382|coverage(revalidator10)|INFO|pstream_open
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 9
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00383|coverage(revalidator10)|INFO|stream_open
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 1
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00379|coverage(revalidator10)|INFO|poll_create_node
> >>> 87.6/sec    87.217/sec       87.6019/sec   total: 21150097
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00380|coverage(revalidator10)|INFO|poll_zero_timeout
> >>> 0.8/sec     0.367/sec        0.7114/sec   total: 79687
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00381|coverage(revalidator10)|INFO|seq_change
> >>> 1043.6/sec  1034.517/sec     1030.8686/sec   total: 254212856
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00382|coverage(revalidator10)|INFO|pstream_open
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 9
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00383|coverage(revalidator10)|INFO|stream_open
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 1
> >>> Apr  6 01:30:04 app011 ovs-vswitchd:
> >>> ovs|00384|coverage(revalidator10)|INFO|util_xalloc
> >>> 556.0/sec   553.283/sec      552.9711/sec   total: 134310009
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00384|coverage(revalidator10)|INFO|util_xalloc
> >>> 556.0/sec   553.283/sec      552.9711/sec   total: 134310009
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00385|coverage(revalidator10)|INFO|netdev_set_policing
> >>> 0.0/sec     0.000/sec        0.0006/sec   total: 31
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00386|coverage(revalidator10)|INFO|netdev_get_ifindex
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 8
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00387|coverage(revalidator10)|INFO|netdev_get_hwaddr
> >>> 0.0/sec     0.000/sec        0.0011/sec   total: 56
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00388|coverage(revalidator10)|INFO|netdev_set_hwaddr
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 4
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00385|coverage(revalidator10)|INFO|netdev_set_policing
> >>> 0.0/sec     0.000/sec        0.0006/sec   total: 31
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00386|coverage(revalidator10)|INFO|netdev_get_ifindex
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 8
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00387|coverage(revalidator10)|INFO|netdev_get_hwaddr
> >>> 0.0/sec     0.000/sec        0.0011/sec   total: 56
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00388|coverage(revalidator10)|INFO|netdev_set_hwaddr
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 4
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00389|coverage(revalidator10)|INFO|netdev_get_ethtool
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 16
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00390|coverage(revalidator10)|INFO|netdev_set_ethtool
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 4
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00391|coverage(revalidator10)|INFO|netlink_received
> >>> 9.4/sec     9.233/sec       10.0036/sec   total: 2239974
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00392|coverage(revalidator10)|INFO|netlink_recv_jumbo
> >>> 0.8/sec     0.800/sec        0.7911/sec   total: 196782
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00393|coverage(revalidator10)|INFO|netlink_sent
> >>> 8.2/sec     7.600/sec        8.7511/sec   total: 1832605
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00394|coverage(revalidator10)|INFO|nln_changed
> >>> 0.0/sec     0.000/sec        0.0006/sec   total: 58
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00395|coverage(revalidator10)|INFO|56 events never hit
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00389|coverage(revalidator10)|INFO|netdev_get_ethtool
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 16
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00390|coverage(revalidator10)|INFO|netdev_set_ethtool
> >>> 0.0/sec     0.000/sec        0.0000/sec   total: 4
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00391|coverage(revalidator10)|INFO|netlink_received
> >>> 9.4/sec     9.233/sec       10.0036/sec   total: 2239974
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00392|coverage(revalidator10)|INFO|netlink_recv_jumbo
> >>> 0.8/sec     0.800/sec        0.7911/sec   total: 196782
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00393|coverage(revalidator10)|INFO|netlink_sent
> >>> 8.2/sec     7.600/sec        8.7511/sec   total: 1832605
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00394|coverage(revalidator10)|INFO|nln_changed
> >>> 0.0/sec     0.000/sec        0.0006/sec   total: 58
> >>> Apr  6 01:30:05 app011 ovs-vswitchd:
> >>> ovs|00395|coverage(revalidator10)|INFO|56 events never hit
> >>> _______________________________________________
> >>> discuss mailing list
> >>> discuss at openvswitch.org
> >>> https://mail.openvswitch.org/mailman/listinfo/ovs-discuss


More information about the discuss mailing list