Bug 2173387
| Summary: | [HWOL]tc(revalidator58)|ERR|failed to parse flower classifier terse options | ||
|---|---|---|---|
| Product: | Red Hat Enterprise Linux Fast Datapath | Reporter: | mhou <mhou> |
| Component: | openvswitch3.1 | Assignee: | Eelco Chaudron <echaudro> |
| Status: | CLOSED CURRENTRELEASE | QA Contact: | mhou <mhou> |
| Severity: | medium | Docs Contact: | |
| Priority: | medium | ||
| Version: | FDP 23.B | CC: | ctrautma, fleitner, jhsiao, kzhang, mleitner, ralongi |
| Target Milestone: | --- | ||
| Target Release: | --- | ||
| Hardware: | x86_64 | ||
| OS: | Linux | ||
| Whiteboard: | |||
| Fixed In Version: | Doc Type: | If docs needed, set a value | |
| Doc Text: | Story Points: | --- | |
| Clone Of: | Environment: | ||
| Last Closed: | 2023-06-24 02:20:17 UTC | Type: | Bug |
| Regression: | --- | Mount Type: | --- |
| Documentation: | --- | CRM: | |
| Verified Versions: | Category: | --- | |
| oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | |
| Cloudforms Team: | --- | Target Upstream Version: | |
| Embargoed: | |||
| Bug Depends On: | |||
| Bug Blocks: | 2172622 | ||
|
Description
mhou
2023-02-26 09:42:38 UTC
Run kernel-rt-5.14.0-283.rt14.283.el9 + openvswitch3.1-3.1.0-2.el9fdp hit very similar issue. https://beaker.engineering.redhat.com/jobs/7602386 ovs log: https://beaker.engineering.redhat.com/recipes/13511072/tasks/157222558/logs/ovs-vswitchd.log :: [ 06:05:05 ] :: [ BEGIN ] :: Running 'grep -C 20 '|ERR|' /var/log/openvswitch/ovs-vswitchd.log' 2023-03-08T11:05:03.301Z|13313|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13314|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13315|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13316|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13317|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13318|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13319|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13320|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13321|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13322|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13323|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.302Z|13324|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.303Z|13325|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.303Z|13326|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.303Z|13327|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.303Z|13328|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.303Z|13329|ofproto_dpif_upcall(revalidator4)|INFO|Unexpected ukey transition from state 3 (last transitioned from thread 0 at ../ofproto/ofproto-dpif-upcall.c:2540) 2023-03-08T11:05:03.303Z|13330|netdev_offload_tc(revalidator4)|DBG|flow get (dev ens1f0np0 prio 2 handle 768 block_id 0) 2023-03-08T11:05:03.304Z|13331|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-08T11:05:03.304Z|13332|netdev_offload_tc(revalidator4)|DBG|flow get (dev ens1f0np0 prio 2 handle 1338 block_id 0) 2023-03-08T11:05:03.304Z|13333|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev ens1f0np0 prio 2 handle 1338): No such file or directory 2023-03-08T11:05:03.304Z|13334|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 2 handle 1338 block_id 0) 2023-03-08T11:05:03.304Z|13335|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev ovsbr1 prio 2 handle 1338): No such file or directory 2023-03-08T11:05:03.304Z|13336|netdev_offload_tc(revalidator4)|DBG|flow get (dev eth1 prio 2 handle 1338 block_id 0) 2023-03-08T11:05:03.304Z|13337|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev eth1 prio 2 handle 1338): No such file or directory 2023-03-08T11:05:03.304Z|13338|netdev_offload_tc(revalidator4)|DBG|flow get (dev ens1f1np1 prio 2 handle 1338 block_id 0) 2023-03-08T11:05:03.304Z|13339|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev ens1f1np1 prio 2 handle 1338): No such file or directory 2023-03-08T11:05:03.304Z|13340|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr0 prio 2 handle 1338 block_id 0) 2023-03-08T11:05:03.304Z|13341|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev ovsbr0 prio 2 handle 1338): No such file or directory 2023-03-08T11:05:03.304Z|13342|netdev_offload_tc(revalidator4)|DBG|flow get (dev eth0 prio 2 handle 1338 block_id 0) 2023-03-08T11:05:03.304Z|13343|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovs-system prio 2 handle 1338 block_id 0) 2023-03-08T11:05:03.304Z|13344|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.304Z|13345|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13346|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13347|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13348|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13349|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13350|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13351|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13352|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13353|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13354|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13355|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13356|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13357|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.305Z|13358|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.306Z|13359|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.306Z|13360|dpif_netlink(revalidator4)|DBG|added flow 2023-03-08T11:05:03.306Z|13361|dpif_netlink(revalidator4)|DBG|added flow Run same test on same environment with kernel-rt-5.14.0-284.1.1.rt14.285.el9_2 can also hit this issue. https://beaker.engineering.redhat.com/jobs/7628881 full of ovs-vswitchd.log: https://beaker-archive.hosts.prod.psi.bos.redhat.com/beaker-logs/2023/03/76288/7628881/13552994/157546339/ovs-vswitchd.log Snippet of ovs-vswitchd.log :: [ 22:22:57 ] :: [ BEGIN ] :: Running 'grep -C 20 '|ERR|' /tmp/ovs_temp_log.log' 2023-03-16T02:22:36.970Z|08658|dpif_netlink(revalidator4)|DBG|added flow 2023-03-16T02:22:36.970Z|08659|dpif_netlink(revalidator4)|DBG|added flow 2023-03-16T02:22:36.970Z|08660|dpif_netlink(revalidator4)|DBG|added flow 2023-03-16T02:22:36.970Z|08661|ofproto_dpif_upcall(revalidator4)|INFO|Unexpected ukey transition from state 3 (last transitioned from thread 0 at ../ofproto/ofproto-dpif-upcall.c:2540) 2023-03-16T02:22:36.971Z|08662|ofproto_dpif_upcall(revalidator4)|INFO|Unexpected ukey transition from state 3 (last transitioned from thread 0 at ../ofproto/ofproto-dpif-upcall.c:2540) 2023-03-16T02:22:36.971Z|08663|ofproto_dpif_upcall(revalidator4)|INFO|Unexpected ukey transition from state 3 (last transitioned from thread 0 at ../ofproto/ofproto-dpif-upcall.c:2540) 2023-03-16T02:22:36.972Z|08664|ofproto_dpif_upcall(revalidator4)|INFO|Unexpected ukey transition from state 3 (last transitioned from thread 0 at ../ofproto/ofproto-dpif-upcall.c:2540) 2023-03-16T02:22:36.988Z|08665|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 22 block_id 0) 2023-03-16T02:22:36.988Z|08666|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.988Z|08667|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 71 block_id 0) 2023-03-16T02:22:36.989Z|08668|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08669|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 81 block_id 0) 2023-03-16T02:22:36.989Z|08670|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08671|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 134 block_id 0) 2023-03-16T02:22:36.989Z|08672|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08673|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 208 block_id 0) 2023-03-16T02:22:36.989Z|08674|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08675|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 224 block_id 0) 2023-03-16T02:22:36.989Z|08676|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08677|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 876 block_id 0) 2023-03-16T02:22:36.989Z|08678|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev ovsbr1 prio 4 handle 876): No such file or directory 2023-03-16T02:22:36.989Z|08679|netdev_offload_tc(revalidator4)|DBG|flow get (dev eth1 prio 4 handle 876 block_id 0) 2023-03-16T02:22:36.989Z|08680|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev eth1 prio 4 handle 876): No such file or directory 2023-03-16T02:22:36.989Z|08681|netdev_offload_tc(revalidator4)|DBG|flow get (dev ens1f1np1 prio 4 handle 876 block_id 0) 2023-03-16T02:22:36.989Z|08682|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev ens1f1np1 prio 4 handle 876): No such file or directory 2023-03-16T02:22:36.989Z|08683|netdev_offload_tc(revalidator4)|DBG|flow get (dev eth0 prio 4 handle 876 block_id 0) 2023-03-16T02:22:36.989Z|08684|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev eth0 prio 4 handle 876): No such file or directory 2023-03-16T02:22:36.989Z|08685|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr0 prio 4 handle 876 block_id 0) 2023-03-16T02:22:36.989Z|08686|netdev_offload_tc(revalidator4)|ERR|flow get failed (dev ovsbr0 prio 4 handle 876): No such file or directory 2023-03-16T02:22:36.989Z|08687|netdev_offload_tc(revalidator4)|DBG|flow get (dev ens1f0np0 prio 4 handle 876 block_id 0) 2023-03-16T02:22:36.989Z|08688|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovs-system prio 4 handle 876 block_id 0) 2023-03-16T02:22:36.989Z|08689|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 252 block_id 0) 2023-03-16T02:22:36.989Z|08690|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08691|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 287 block_id 0) 2023-03-16T02:22:36.989Z|08692|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08693|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 343 block_id 0) 2023-03-16T02:22:36.989Z|08694|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08695|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 353 block_id 0) 2023-03-16T02:22:36.989Z|08696|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.989Z|08697|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 395 block_id 0) 2023-03-16T02:22:36.990Z|08698|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.990Z|08699|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 455 block_id 0) 2023-03-16T02:22:36.990Z|08700|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.990Z|08701|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 4 block_id 0) 2023-03-16T02:22:36.990Z|08702|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.990Z|08703|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 5 block_id 0) 2023-03-16T02:22:36.990Z|08704|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow 2023-03-16T02:22:36.990Z|08705|netdev_offload_tc(revalidator4)|DBG|flow get (dev ovsbr1 prio 4 handle 28 block_id 0) 2023-03-16T02:22:36.990Z|08706|dpif_netlink(revalidator4)|DBG|found flow from netdev, translating to dpif flow This issue still can be reproduced on kernel-5.14.0-325.el9+rt + openvswitch3.1-3.1.0-24.el9fdp beaker job:https://beaker.engineering.redhat.com/jobs/7975038 :: [ 14:55:18 ] :: [ BEGIN ] :: Running 'check_error_message /tmp/ovs_temp_log.log' 2023-06-15T18:55:17.388Z|01588|tc|ERR|failed to parse flower classifier options :: [ 14:55:18 ] :: [ BEGIN ] :: Running 'grep -C 20 '|ERR|' /tmp/ovs_temp_log.log' 2023-06-15T18:55:17.386Z|41416|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.386Z|41417|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.386Z|41418|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41419|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41420|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41421|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41422|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41423|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41424|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41425|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41426|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.387Z|41427|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41428|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41429|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41430|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41431|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41432|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41433|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41434|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41435|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|01588|tc|ERR|failed to parse flower classifier options 2023-06-15T18:55:17.388Z|41436|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41437|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41438|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.388Z|41439|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41440|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41441|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41442|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41443|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41444|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41445|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41446|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41447|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41448|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.389Z|41449|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.390Z|41450|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.390Z|41451|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.390Z|41452|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.390Z|41453|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.391Z|41454|dpif_netlink(revalidator4)|DBG|added flow 2023-06-15T18:55:17.391Z|41455|dpif_netlink(revalidator4)|DBG|added flow :: [ 14:55:19 ] :: [ PASS ] :: Command 'grep -C 20 '|ERR|' /tmp/ovs_temp_log.log' (Expected 0, got 0) Hi @mhou, it looks like 2182942, 2173387, and 2143570 are related. Would it be possible to get access to a setup where I can replicate and further debug the issue? Test on RHEL-9.3.0-20230621.0(kernel-5.14.0-325.el9+rt)+ openvswitch3.1-3.1.0-33.el9fdp and didn't hit this issue. https://beaker.engineering.redhat.com/jobs/8002286 |