[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