yahoo-eng-team team mailing list archive

Thread
Date
[Bug 1729229] [NEW] router lost ports when creating VM

To: yahoo-eng-team@xxxxxxxxxxxxxxxxxxx
From: Zhijiang Hu <hu.zhijiang@xxxxxxxxxx>
Date: Wed, 01 Nov 2017 07:53:42 -0000
Reply-to: Bug 1729229 <1729229@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Public bug reported:

In a pike all-in-one env(created by using kolla), every time when I
creating a VM, I see OVS reconnecting with neutron-ovs-agent. after
reconnection, gateway port of virtual route created beforehand is lost.

This is the route's ports before creating VM.
[root@travel daisy]# ip netns exec qrouter-297ae7fb-6c5c-47e0-8c79-08f526b840e6 ifconfig
lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
        inet 127.0.0.1  netmask 255.0.0.0
        inet6 ::1  prefixlen 128  scopeid 0x10<host>
        loop  txqueuelen 1  (Local Loopback)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 0  bytes 0 (0.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

qg-2e8399f4-4c: flags=323<UP,BROADCAST,RUNNING,PROMISC>  mtu 1500
        inet 192.168.1.210  netmask 255.255.255.0  broadcast 192.168.1.255
        inet6 fe80::f816:3eff:fed2:f36e  prefixlen 64  scopeid 0x20<link>
        ether fa:16:3e:d2:f3:6e  txqueuelen 1000  (Ethernet)
        RX packets 223  bytes 13418 (13.1 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 13  bytes 910 (910.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

qr-843934a0-e1: flags=323<UP,BROADCAST,RUNNING,PROMISC>  mtu 1450
        inet 10.0.0.1  netmask 255.255.255.0  broadcast 10.0.0.255
        inet6 fe80::f816:3eff:fe92:ef64  prefixlen 64  scopeid 0x20<link>
        ether fa:16:3e:92:ef:64  txqueuelen 1000  (Ethernet)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 13  bytes 910 (910.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

This is the route's ports after creating VM.
[root@travel daisy]# ip netns exec qrouter-297ae7fb-6c5c-47e0-8c79-08f526b840e6 ifconfig
lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
        inet 127.0.0.1  netmask 255.0.0.0
        inet6 ::1  prefixlen 128  scopeid 0x10<host>
        loop  txqueuelen 1  (Local Loopback)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 0  bytes 0 (0.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0


Related ovs-vswitchd log: (port lost at 2017-11-01T07:18:50 ~ 2017-11-01T07:18:51)

[root@travel kolla-ansible-pike]# cat /var/lib/docker/volumes/kolla_logs/_data/openvswitch/ovs-vswitchd.log
2017-11-01T07:08:08.610Z|00001|vlog|INFO|opened log file /var/log/kolla/openvswitch/ovs-vswitchd.log
2017-11-01T07:08:08.616Z|00002|ovs_numa|INFO|Discovered 12 CPU cores on NUMA node 0
2017-11-01T07:08:08.616Z|00003|ovs_numa|INFO|Discovered 12 CPU cores on NUMA node 1
2017-11-01T07:08:08.616Z|00004|ovs_numa|INFO|Discovered 2 NUMA nodes and 24 CPU cores
2017-11-01T07:08:08.616Z|00005|reconnect|INFO|unix:/run/openvswitch/db.sock: connecting...
2017-11-01T07:08:08.616Z|00006|reconnect|INFO|unix:/run/openvswitch/db.sock: connected
2017-11-01T07:08:08.619Z|00007|dpdk|INFO|DPDK Disabled - Use other_config:dpdk-init to enable
2017-11-01T07:08:08.621Z|00008|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation
2017-11-01T07:08:08.621Z|00009|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 1
2017-11-01T07:08:08.621Z|00010|ofproto_dpif|INFO|system@ovs-system: Datapath does not support truncate action
2017-11-01T07:08:08.621Z|00011|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids
2017-11-01T07:08:08.621Z|00012|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state
2017-11-01T07:08:08.621Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone
2017-11-01T07:08:08.621Z|00014|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark
2017-11-01T07:08:08.621Z|00015|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label
2017-11-01T07:08:08.621Z|00016|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat
2017-11-01T07:08:08.694Z|00001|ofproto_dpif_upcall(handler1)|INFO|received packet on unassociated datapath port 0
2017-11-01T07:08:08.695Z|00017|bridge|INFO|bridge br-ex: added interface p1p1 on port 1
2017-11-01T07:08:08.699Z|00018|bridge|INFO|bridge br-ex: added interface br-ex on port 65534
2017-11-01T07:08:08.700Z|00019|bridge|INFO|bridge br-ex: using datapath ID 0000001b213655fc
2017-11-01T07:08:08.700Z|00020|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt"
2017-11-01T07:08:08.756Z|00021|bridge|INFO|ovs-vswitchd (Open vSwitch) 2.7.2
2017-11-01T07:08:18.757Z|00022|memory|INFO|221336 kB peak resident set size after 10.1 seconds
2017-11-01T07:08:18.757Z|00023|memory|INFO|handlers:17 ports:2 revalidators:7 rules:5 udpif keys:4
2017-11-01T07:10:02.376Z|00024|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports recirculation
2017-11-01T07:10:02.376Z|00025|ofproto_dpif|INFO|netdev@ovs-netdev: MPLS label stack length probed as 3
2017-11-01T07:10:02.376Z|00026|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports truncate action
2017-11-01T07:10:02.376Z|00027|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports unique flow ids
2017-11-01T07:10:02.376Z|00028|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_state
2017-11-01T07:10:02.376Z|00029|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_zone
2017-11-01T07:10:02.376Z|00030|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_mark
2017-11-01T07:10:02.376Z|00031|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_label
2017-11-01T07:10:02.376Z|00032|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath does not support ct_state_nat
2017-11-01T07:10:02.419Z|00033|bridge|INFO|bridge br-int: added interface br-int on port 65534
2017-11-01T07:10:02.419Z|00034|bridge|INFO|bridge br-int: using datapath ID 000072c56eb3c24b
2017-11-01T07:10:02.419Z|00035|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt"
2017-11-01T07:10:02.503Z|00036|connmgr|INFO|br-int: re-added service controller "punix:/var/run/openvswitch/br-int.mgmt"
2017-11-01T07:10:02.507Z|00037|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633"
2017-11-01T07:10:02.507Z|00038|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting...
2017-11-01T07:10:02.545Z|00039|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected
2017-11-01T07:10:02.696Z|00040|bridge|INFO|bridge br-ex: deleted interface p1p1 on port 1
2017-11-01T07:10:02.696Z|00041|bridge|INFO|bridge br-ex: deleted interface br-ex on port 65534
2017-11-01T07:10:02.818Z|00042|bridge|INFO|bridge br-ex: added interface p1p1 on port 1
2017-11-01T07:10:02.819Z|00043|bridge|INFO|bridge br-ex: added interface br-ex on port 65534
2017-11-01T07:10:02.820Z|00044|bridge|INFO|bridge br-ex: using datapath ID 0000001b213655fc
2017-11-01T07:10:02.820Z|00045|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt"
2017-11-01T07:10:02.938Z|00046|connmgr|INFO|br-ex: re-added service controller "punix:/var/run/openvswitch/br-ex.mgmt"
2017-11-01T07:10:02.941Z|00047|connmgr|INFO|br-ex: added primary controller "tcp:127.0.0.1:6633"
2017-11-01T07:10:02.941Z|00048|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connecting...
2017-11-01T07:10:02.980Z|00049|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connected
2017-11-01T07:10:02.991Z|00050|bridge|INFO|bridge br-int: added interface int-br-ex on port 1
2017-11-01T07:10:02.999Z|00051|bridge|INFO|bridge br-ex: added interface phy-br-ex on port 2
2017-11-01T07:10:03.017Z|00052|bridge|INFO|bridge br-tun: added interface br-tun on port 65534
2017-11-01T07:10:03.017Z|00053|bridge|INFO|bridge br-tun: using datapath ID 00004e1bacd0bc45
2017-11-01T07:10:03.017Z|00054|connmgr|INFO|br-tun: added service controller "punix:/var/run/openvswitch/br-tun.mgmt"
2017-11-01T07:10:03.027Z|00055|connmgr|INFO|br-tun: re-added service controller "punix:/var/run/openvswitch/br-tun.mgmt"
2017-11-01T07:10:03.031Z|00056|connmgr|INFO|br-tun: added primary controller "tcp:127.0.0.1:6633"
2017-11-01T07:10:03.031Z|00057|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connecting...
2017-11-01T07:10:03.070Z|00058|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connected
2017-11-01T07:10:03.078Z|00059|bridge|INFO|bridge br-int: added interface patch-tun on port 2
2017-11-01T07:10:03.087Z|00060|bridge|INFO|bridge br-tun: added interface patch-int on port 1
2017-11-01T07:10:08.791Z|00061|memory|INFO|peak resident set size grew 95% in last 110.0 seconds, from 221336 kB to 431100 kB
2017-11-01T07:10:08.791Z|00062|memory|INFO|handlers:17 ofconns:3 ports:8 revalidators:7 rules:31 udpif keys:2
2017-11-01T07:10:12.554Z|00063|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 5 flow_mods 10 s ago (5 adds)
2017-11-01T07:10:12.988Z|00064|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 2 flow_mods 10 s ago (2 adds)
2017-11-01T07:10:13.095Z|00065|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 12 flow_mods 10 s ago (12 adds)
2017-11-01T07:15:05.549Z|00066|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 3
2017-11-01T07:15:07.282Z|00067|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on tap538c1725-9c device failed: No such device
2017-11-01T07:15:07.285Z|00068|netdev_linux|WARN|ethtool command ETHTOOL_GSET on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:07.289Z|00069|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:07.292Z|00070|netdev_linux|WARN|ioctl(SIOCGIFINDEX) on tap538c1725-9c device failed: No such device
2017-11-01T07:15:08.046Z|00071|netdev_linux|WARN|tap538c1725-9c: removing policing failed: No such device
2017-11-01T07:15:08.050Z|00072|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:16.620Z|00073|bridge|INFO|bridge br-int: added interface qr-843934a0-e1 on port 4
2017-11-01T07:15:16.624Z|00074|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:16.826Z|00075|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 1 flow_mods 10 s ago (1 adds)
2017-11-01T07:15:16.831Z|00076|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:16.850Z|00077|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:17.193Z|00078|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:17.999Z|00079|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:18.035Z|00080|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 10 flow_mods in the 9 s starting 10 s ago (10 deletes)
2017-11-01T07:15:18.373Z|00081|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:18.378Z|00082|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on qr-843934a0-e1 device failed: No such device
2017-11-01T07:15:18.380Z|00083|netdev_linux|WARN|ethtool command ETHTOOL_GSET on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:15:18.382Z|00084|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:15:18.384Z|00085|netdev_linux|WARN|ioctl(SIOCGIFINDEX) on qr-843934a0-e1 device failed: No such device
2017-11-01T07:15:32.256Z|00086|bridge|INFO|bridge br-int: added interface qg-2e8399f4-4c on port 5
2017-11-01T07:15:32.256Z|00087|netdev_linux|WARN|qr-843934a0-e1: removing policing failed: No such device
2017-11-01T07:15:32.259Z|00088|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:15:32.260Z|00089|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:32.704Z|00090|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:15:32.707Z|00091|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:32.841Z|00092|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:15:32.844Z|00093|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:15:32.860Z|00094|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:15:33.884Z|00095|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on qg-2e8399f4-4c device failed: No such device
2017-11-01T07:15:42.831Z|00096|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 1 flow_mods 10 s ago (1 adds)
2017-11-01T07:16:18.034Z|00097|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 24 flow_mods in the 17 s starting 59 s ago (1 adds, 23 deletes)
2017-11-01T07:18:50.275Z|00098|netdev_linux|WARN|Dropped 8 log messages in last 198 seconds (most recently, 197 seconds ago) due to excessive rate
2017-11-01T07:18:50.275Z|00099|netdev_linux|WARN|qg-2e8399f4-4c: removing policing failed: No such device
2017-11-01T07:18:50.278Z|00100|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:18:50.281Z|00101|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:18:50.285Z|00102|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
2017-11-01T07:18:50.361Z|00103|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:18:50.364Z|00104|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:18:50.367Z|00105|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
2017-11-01T07:18:50.383Z|00106|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:18:50.386Z|00107|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:18:50.389Z|00108|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
2017-11-01T07:18:50.394Z|00109|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:18:50.397Z|00110|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:18:50.401Z|00111|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
2017-11-01T07:18:50.406Z|00112|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
2017-11-01T07:18:50.409Z|00113|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
2017-11-01T07:18:50.412Z|00114|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
2017-11-01T07:18:50.534Z|00115|bridge|INFO|bridge br-int: deleted interface br-int on port 65534
2017-11-01T07:18:50.534Z|00116|bridge|INFO|bridge br-int: deleted interface qr-843934a0-e1 on port 4
2017-11-01T07:18:50.534Z|00117|bridge|INFO|bridge br-int: deleted interface tap538c1725-9c on port 3
2017-11-01T07:18:50.535Z|00118|bridge|INFO|bridge br-int: deleted interface int-br-ex on port 1
2017-11-01T07:18:50.535Z|00119|bridge|INFO|bridge br-int: deleted interface qg-2e8399f4-4c on port 5
2017-11-01T07:18:50.535Z|00120|bridge|INFO|bridge br-int: deleted interface patch-tun on port 2
2017-11-01T07:18:50.722Z|00121|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation
2017-11-01T07:18:50.722Z|00122|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 1
2017-11-01T07:18:50.722Z|00123|ofproto_dpif|INFO|system@ovs-system: Datapath does not support truncate action
2017-11-01T07:18:50.722Z|00124|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids
2017-11-01T07:18:50.722Z|00125|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state
2017-11-01T07:18:50.722Z|00126|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone
2017-11-01T07:18:50.722Z|00127|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark
2017-11-01T07:18:50.722Z|00128|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label
2017-11-01T07:18:50.722Z|00129|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat
2017-11-01T07:18:50.768Z|00001|ofproto_dpif_upcall(handler339)|INFO|received packet on unassociated datapath port 0
2017-11-01T07:18:50.773Z|00130|bridge|INFO|bridge br-int: added interface br-int on port 65534
2017-11-01T07:18:50.778Z|00131|bridge|INFO|bridge br-int: added interface qr-843934a0-e1 on port 4
2017-11-01T07:18:50.778Z|00132|bridge|INFO|bridge br-int: added interface int-br-ex on port 1
2017-11-01T07:18:50.783Z|00133|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 3
2017-11-01T07:18:50.788Z|00134|bridge|INFO|bridge br-int: added interface qg-2e8399f4-4c on port 5
2017-11-01T07:18:50.788Z|00135|bridge|INFO|bridge br-int: added interface patch-tun on port 2
2017-11-01T07:18:50.788Z|00136|bridge|INFO|bridge br-int: using datapath ID 000072c56eb3c24b
2017-11-01T07:18:50.788Z|00137|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt"
2017-11-01T07:18:50.788Z|00138|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633"
2017-11-01T07:18:50.788Z|00139|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting...
2017-11-01T07:18:50.836Z|00140|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected
2017-11-01T07:18:50.868Z|00141|bridge|INFO|bridge br-int: added interface qvof0ff7a0a-fb on port 6
2017-11-01T07:18:50.990Z|00142|bridge|INFO|bridge br-int: deleted interface qvof0ff7a0a-fb on port 6
2017-11-01T07:18:50.990Z|00143|bridge|INFO|bridge br-int: deleted interface br-int on port 65534
2017-11-01T07:18:50.990Z|00144|bridge|INFO|bridge br-int: deleted interface tap538c1725-9c on port 3
2017-11-01T07:18:50.990Z|00145|bridge|INFO|bridge br-int: deleted interface int-br-ex on port 1
2017-11-01T07:18:50.990Z|00146|bridge|INFO|bridge br-int: deleted interface qr-843934a0-e1 on port 4
2017-11-01T07:18:50.990Z|00147|bridge|INFO|bridge br-int: deleted interface patch-tun on port 2
2017-11-01T07:18:50.990Z|00148|bridge|INFO|bridge br-int: deleted interface qg-2e8399f4-4c on port 5
2017-11-01T07:18:51.200Z|00149|bridge|INFO|bridge br-int: added interface qvof0ff7a0a-fb on port 6
2017-11-01T07:18:51.202Z|00150|bridge|INFO|bridge br-int: added interface br-int on port 65534
2017-11-01T07:18:51.203Z|00151|bridge|INFO|bridge br-int: added interface qr-843934a0-e1 on port 4
2017-11-01T07:18:51.203Z|00152|bridge|INFO|bridge br-int: added interface int-br-ex on port 1
2017-11-01T07:18:51.205Z|00153|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 3
2017-11-01T07:18:51.207Z|00154|bridge|INFO|bridge br-int: added interface qg-2e8399f4-4c on port 5
2017-11-01T07:18:51.207Z|00155|bridge|INFO|bridge br-int: added interface patch-tun on port 2
2017-11-01T07:18:51.207Z|00156|bridge|INFO|bridge br-int: using datapath ID 000072c56eb3c24b
2017-11-01T07:18:51.207Z|00157|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt"
2017-11-01T07:18:51.207Z|00158|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633"
2017-11-01T07:18:51.207Z|00159|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting...
2017-11-01T07:18:51.262Z|00160|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected
2017-11-01T07:18:56.618Z|00161|bridge|INFO|bridge br-int: deleted interface tap538c1725-9c on port 3
2017-11-01T07:18:56.637Z|00162|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 7
2017-11-01T07:18:58.349Z|00163|netdev_linux|WARN|Dropped 24 log messages in last 8 seconds (most recently, 8 seconds ago) due to excessive rate
2017-11-01T07:18:58.349Z|00164|netdev_linux|WARN|tap538c1725-9c: removing policing failed: No such device
2017-11-01T07:18:58.358Z|00165|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on tap538c1725-9c device failed: No such device
2017-11-01T07:19:01.408Z|00166|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 31 flow_mods in the 7 s starting 10 s ago (12 adds, 19 deletes)
2017-11-01T07:19:01.489Z|00167|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 3 flow_mods 10 s ago (3 adds)
2017-11-01T07:19:01.573Z|00168|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 15 flow_mods 10 s ago (15 adds)

** Affects: neutron
     Importance: Undecided
         Status: New

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to neutron.
https://bugs.launchpad.net/bugs/1729229

Title:
  router lost ports when creating VM

Status in neutron:
  New

Bug description:
  In a pike all-in-one env(created by using kolla), every time when I
  creating a VM, I see OVS reconnecting with neutron-ovs-agent. after
  reconnection, gateway port of virtual route created beforehand is
  lost.

  This is the route's ports before creating VM.
  [root@travel daisy]# ip netns exec qrouter-297ae7fb-6c5c-47e0-8c79-08f526b840e6 ifconfig
  lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
          inet 127.0.0.1  netmask 255.0.0.0
          inet6 ::1  prefixlen 128  scopeid 0x10<host>
          loop  txqueuelen 1  (Local Loopback)
          RX packets 0  bytes 0 (0.0 B)
          RX errors 0  dropped 0  overruns 0  frame 0
          TX packets 0  bytes 0 (0.0 B)
          TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

  qg-2e8399f4-4c: flags=323<UP,BROADCAST,RUNNING,PROMISC>  mtu 1500
          inet 192.168.1.210  netmask 255.255.255.0  broadcast 192.168.1.255
          inet6 fe80::f816:3eff:fed2:f36e  prefixlen 64  scopeid 0x20<link>
          ether fa:16:3e:d2:f3:6e  txqueuelen 1000  (Ethernet)
          RX packets 223  bytes 13418 (13.1 KiB)
          RX errors 0  dropped 0  overruns 0  frame 0
          TX packets 13  bytes 910 (910.0 B)
          TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

  qr-843934a0-e1: flags=323<UP,BROADCAST,RUNNING,PROMISC>  mtu 1450
          inet 10.0.0.1  netmask 255.255.255.0  broadcast 10.0.0.255
          inet6 fe80::f816:3eff:fe92:ef64  prefixlen 64  scopeid 0x20<link>
          ether fa:16:3e:92:ef:64  txqueuelen 1000  (Ethernet)
          RX packets 0  bytes 0 (0.0 B)
          RX errors 0  dropped 0  overruns 0  frame 0
          TX packets 13  bytes 910 (910.0 B)
          TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

  This is the route's ports after creating VM.
  [root@travel daisy]# ip netns exec qrouter-297ae7fb-6c5c-47e0-8c79-08f526b840e6 ifconfig
  lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
          inet 127.0.0.1  netmask 255.0.0.0
          inet6 ::1  prefixlen 128  scopeid 0x10<host>
          loop  txqueuelen 1  (Local Loopback)
          RX packets 0  bytes 0 (0.0 B)
          RX errors 0  dropped 0  overruns 0  frame 0
          TX packets 0  bytes 0 (0.0 B)
          TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

  
  Related ovs-vswitchd log: (port lost at 2017-11-01T07:18:50 ~ 2017-11-01T07:18:51)

  [root@travel kolla-ansible-pike]# cat /var/lib/docker/volumes/kolla_logs/_data/openvswitch/ovs-vswitchd.log
  2017-11-01T07:08:08.610Z|00001|vlog|INFO|opened log file /var/log/kolla/openvswitch/ovs-vswitchd.log
  2017-11-01T07:08:08.616Z|00002|ovs_numa|INFO|Discovered 12 CPU cores on NUMA node 0
  2017-11-01T07:08:08.616Z|00003|ovs_numa|INFO|Discovered 12 CPU cores on NUMA node 1
  2017-11-01T07:08:08.616Z|00004|ovs_numa|INFO|Discovered 2 NUMA nodes and 24 CPU cores
  2017-11-01T07:08:08.616Z|00005|reconnect|INFO|unix:/run/openvswitch/db.sock: connecting...
  2017-11-01T07:08:08.616Z|00006|reconnect|INFO|unix:/run/openvswitch/db.sock: connected
  2017-11-01T07:08:08.619Z|00007|dpdk|INFO|DPDK Disabled - Use other_config:dpdk-init to enable
  2017-11-01T07:08:08.621Z|00008|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation
  2017-11-01T07:08:08.621Z|00009|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 1
  2017-11-01T07:08:08.621Z|00010|ofproto_dpif|INFO|system@ovs-system: Datapath does not support truncate action
  2017-11-01T07:08:08.621Z|00011|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids
  2017-11-01T07:08:08.621Z|00012|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state
  2017-11-01T07:08:08.621Z|00013|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone
  2017-11-01T07:08:08.621Z|00014|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark
  2017-11-01T07:08:08.621Z|00015|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label
  2017-11-01T07:08:08.621Z|00016|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat
  2017-11-01T07:08:08.694Z|00001|ofproto_dpif_upcall(handler1)|INFO|received packet on unassociated datapath port 0
  2017-11-01T07:08:08.695Z|00017|bridge|INFO|bridge br-ex: added interface p1p1 on port 1
  2017-11-01T07:08:08.699Z|00018|bridge|INFO|bridge br-ex: added interface br-ex on port 65534
  2017-11-01T07:08:08.700Z|00019|bridge|INFO|bridge br-ex: using datapath ID 0000001b213655fc
  2017-11-01T07:08:08.700Z|00020|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt"
  2017-11-01T07:08:08.756Z|00021|bridge|INFO|ovs-vswitchd (Open vSwitch) 2.7.2
  2017-11-01T07:08:18.757Z|00022|memory|INFO|221336 kB peak resident set size after 10.1 seconds
  2017-11-01T07:08:18.757Z|00023|memory|INFO|handlers:17 ports:2 revalidators:7 rules:5 udpif keys:4
  2017-11-01T07:10:02.376Z|00024|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports recirculation
  2017-11-01T07:10:02.376Z|00025|ofproto_dpif|INFO|netdev@ovs-netdev: MPLS label stack length probed as 3
  2017-11-01T07:10:02.376Z|00026|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports truncate action
  2017-11-01T07:10:02.376Z|00027|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports unique flow ids
  2017-11-01T07:10:02.376Z|00028|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_state
  2017-11-01T07:10:02.376Z|00029|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_zone
  2017-11-01T07:10:02.376Z|00030|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_mark
  2017-11-01T07:10:02.376Z|00031|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath supports ct_label
  2017-11-01T07:10:02.376Z|00032|ofproto_dpif|INFO|netdev@ovs-netdev: Datapath does not support ct_state_nat
  2017-11-01T07:10:02.419Z|00033|bridge|INFO|bridge br-int: added interface br-int on port 65534
  2017-11-01T07:10:02.419Z|00034|bridge|INFO|bridge br-int: using datapath ID 000072c56eb3c24b
  2017-11-01T07:10:02.419Z|00035|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt"
  2017-11-01T07:10:02.503Z|00036|connmgr|INFO|br-int: re-added service controller "punix:/var/run/openvswitch/br-int.mgmt"
  2017-11-01T07:10:02.507Z|00037|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633"
  2017-11-01T07:10:02.507Z|00038|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting...
  2017-11-01T07:10:02.545Z|00039|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected
  2017-11-01T07:10:02.696Z|00040|bridge|INFO|bridge br-ex: deleted interface p1p1 on port 1
  2017-11-01T07:10:02.696Z|00041|bridge|INFO|bridge br-ex: deleted interface br-ex on port 65534
  2017-11-01T07:10:02.818Z|00042|bridge|INFO|bridge br-ex: added interface p1p1 on port 1
  2017-11-01T07:10:02.819Z|00043|bridge|INFO|bridge br-ex: added interface br-ex on port 65534
  2017-11-01T07:10:02.820Z|00044|bridge|INFO|bridge br-ex: using datapath ID 0000001b213655fc
  2017-11-01T07:10:02.820Z|00045|connmgr|INFO|br-ex: added service controller "punix:/var/run/openvswitch/br-ex.mgmt"
  2017-11-01T07:10:02.938Z|00046|connmgr|INFO|br-ex: re-added service controller "punix:/var/run/openvswitch/br-ex.mgmt"
  2017-11-01T07:10:02.941Z|00047|connmgr|INFO|br-ex: added primary controller "tcp:127.0.0.1:6633"
  2017-11-01T07:10:02.941Z|00048|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connecting...
  2017-11-01T07:10:02.980Z|00049|rconn|INFO|br-ex<->tcp:127.0.0.1:6633: connected
  2017-11-01T07:10:02.991Z|00050|bridge|INFO|bridge br-int: added interface int-br-ex on port 1
  2017-11-01T07:10:02.999Z|00051|bridge|INFO|bridge br-ex: added interface phy-br-ex on port 2
  2017-11-01T07:10:03.017Z|00052|bridge|INFO|bridge br-tun: added interface br-tun on port 65534
  2017-11-01T07:10:03.017Z|00053|bridge|INFO|bridge br-tun: using datapath ID 00004e1bacd0bc45
  2017-11-01T07:10:03.017Z|00054|connmgr|INFO|br-tun: added service controller "punix:/var/run/openvswitch/br-tun.mgmt"
  2017-11-01T07:10:03.027Z|00055|connmgr|INFO|br-tun: re-added service controller "punix:/var/run/openvswitch/br-tun.mgmt"
  2017-11-01T07:10:03.031Z|00056|connmgr|INFO|br-tun: added primary controller "tcp:127.0.0.1:6633"
  2017-11-01T07:10:03.031Z|00057|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connecting...
  2017-11-01T07:10:03.070Z|00058|rconn|INFO|br-tun<->tcp:127.0.0.1:6633: connected
  2017-11-01T07:10:03.078Z|00059|bridge|INFO|bridge br-int: added interface patch-tun on port 2
  2017-11-01T07:10:03.087Z|00060|bridge|INFO|bridge br-tun: added interface patch-int on port 1
  2017-11-01T07:10:08.791Z|00061|memory|INFO|peak resident set size grew 95% in last 110.0 seconds, from 221336 kB to 431100 kB
  2017-11-01T07:10:08.791Z|00062|memory|INFO|handlers:17 ofconns:3 ports:8 revalidators:7 rules:31 udpif keys:2
  2017-11-01T07:10:12.554Z|00063|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 5 flow_mods 10 s ago (5 adds)
  2017-11-01T07:10:12.988Z|00064|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 2 flow_mods 10 s ago (2 adds)
  2017-11-01T07:10:13.095Z|00065|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 12 flow_mods 10 s ago (12 adds)
  2017-11-01T07:15:05.549Z|00066|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 3
  2017-11-01T07:15:07.282Z|00067|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on tap538c1725-9c device failed: No such device
  2017-11-01T07:15:07.285Z|00068|netdev_linux|WARN|ethtool command ETHTOOL_GSET on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:07.289Z|00069|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:07.292Z|00070|netdev_linux|WARN|ioctl(SIOCGIFINDEX) on tap538c1725-9c device failed: No such device
  2017-11-01T07:15:08.046Z|00071|netdev_linux|WARN|tap538c1725-9c: removing policing failed: No such device
  2017-11-01T07:15:08.050Z|00072|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:16.620Z|00073|bridge|INFO|bridge br-int: added interface qr-843934a0-e1 on port 4
  2017-11-01T07:15:16.624Z|00074|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:16.826Z|00075|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 1 flow_mods 10 s ago (1 adds)
  2017-11-01T07:15:16.831Z|00076|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:16.850Z|00077|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:17.193Z|00078|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:17.999Z|00079|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:18.035Z|00080|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 10 flow_mods in the 9 s starting 10 s ago (10 deletes)
  2017-11-01T07:15:18.373Z|00081|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:18.378Z|00082|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on qr-843934a0-e1 device failed: No such device
  2017-11-01T07:15:18.380Z|00083|netdev_linux|WARN|ethtool command ETHTOOL_GSET on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:15:18.382Z|00084|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:15:18.384Z|00085|netdev_linux|WARN|ioctl(SIOCGIFINDEX) on qr-843934a0-e1 device failed: No such device
  2017-11-01T07:15:32.256Z|00086|bridge|INFO|bridge br-int: added interface qg-2e8399f4-4c on port 5
  2017-11-01T07:15:32.256Z|00087|netdev_linux|WARN|qr-843934a0-e1: removing policing failed: No such device
  2017-11-01T07:15:32.259Z|00088|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:15:32.260Z|00089|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:32.704Z|00090|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:15:32.707Z|00091|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:32.841Z|00092|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:15:32.844Z|00093|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:15:32.860Z|00094|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:15:33.884Z|00095|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on qg-2e8399f4-4c device failed: No such device
  2017-11-01T07:15:42.831Z|00096|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 1 flow_mods 10 s ago (1 adds)
  2017-11-01T07:16:18.034Z|00097|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 24 flow_mods in the 17 s starting 59 s ago (1 adds, 23 deletes)
  2017-11-01T07:18:50.275Z|00098|netdev_linux|WARN|Dropped 8 log messages in last 198 seconds (most recently, 197 seconds ago) due to excessive rate
  2017-11-01T07:18:50.275Z|00099|netdev_linux|WARN|qg-2e8399f4-4c: removing policing failed: No such device
  2017-11-01T07:18:50.278Z|00100|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:18:50.281Z|00101|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:18:50.285Z|00102|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
  2017-11-01T07:18:50.361Z|00103|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:18:50.364Z|00104|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:18:50.367Z|00105|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
  2017-11-01T07:18:50.383Z|00106|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:18:50.386Z|00107|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:18:50.389Z|00108|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
  2017-11-01T07:18:50.394Z|00109|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:18:50.397Z|00110|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:18:50.401Z|00111|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
  2017-11-01T07:18:50.406Z|00112|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qr-843934a0-e1 failed: No such device
  2017-11-01T07:18:50.409Z|00113|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device tap538c1725-9c failed: No such device
  2017-11-01T07:18:50.412Z|00114|netdev_linux|WARN|ethtool command ETHTOOL_GDRVINFO on network device qg-2e8399f4-4c failed: No such device
  2017-11-01T07:18:50.534Z|00115|bridge|INFO|bridge br-int: deleted interface br-int on port 65534
  2017-11-01T07:18:50.534Z|00116|bridge|INFO|bridge br-int: deleted interface qr-843934a0-e1 on port 4
  2017-11-01T07:18:50.534Z|00117|bridge|INFO|bridge br-int: deleted interface tap538c1725-9c on port 3
  2017-11-01T07:18:50.535Z|00118|bridge|INFO|bridge br-int: deleted interface int-br-ex on port 1
  2017-11-01T07:18:50.535Z|00119|bridge|INFO|bridge br-int: deleted interface qg-2e8399f4-4c on port 5
  2017-11-01T07:18:50.535Z|00120|bridge|INFO|bridge br-int: deleted interface patch-tun on port 2
  2017-11-01T07:18:50.722Z|00121|ofproto_dpif|INFO|system@ovs-system: Datapath supports recirculation
  2017-11-01T07:18:50.722Z|00122|ofproto_dpif|INFO|system@ovs-system: MPLS label stack length probed as 1
  2017-11-01T07:18:50.722Z|00123|ofproto_dpif|INFO|system@ovs-system: Datapath does not support truncate action
  2017-11-01T07:18:50.722Z|00124|ofproto_dpif|INFO|system@ovs-system: Datapath supports unique flow ids
  2017-11-01T07:18:50.722Z|00125|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state
  2017-11-01T07:18:50.722Z|00126|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_zone
  2017-11-01T07:18:50.722Z|00127|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_mark
  2017-11-01T07:18:50.722Z|00128|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_label
  2017-11-01T07:18:50.722Z|00129|ofproto_dpif|INFO|system@ovs-system: Datapath supports ct_state_nat
  2017-11-01T07:18:50.768Z|00001|ofproto_dpif_upcall(handler339)|INFO|received packet on unassociated datapath port 0
  2017-11-01T07:18:50.773Z|00130|bridge|INFO|bridge br-int: added interface br-int on port 65534
  2017-11-01T07:18:50.778Z|00131|bridge|INFO|bridge br-int: added interface qr-843934a0-e1 on port 4
  2017-11-01T07:18:50.778Z|00132|bridge|INFO|bridge br-int: added interface int-br-ex on port 1
  2017-11-01T07:18:50.783Z|00133|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 3
  2017-11-01T07:18:50.788Z|00134|bridge|INFO|bridge br-int: added interface qg-2e8399f4-4c on port 5
  2017-11-01T07:18:50.788Z|00135|bridge|INFO|bridge br-int: added interface patch-tun on port 2
  2017-11-01T07:18:50.788Z|00136|bridge|INFO|bridge br-int: using datapath ID 000072c56eb3c24b
  2017-11-01T07:18:50.788Z|00137|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt"
  2017-11-01T07:18:50.788Z|00138|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633"
  2017-11-01T07:18:50.788Z|00139|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting...
  2017-11-01T07:18:50.836Z|00140|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected
  2017-11-01T07:18:50.868Z|00141|bridge|INFO|bridge br-int: added interface qvof0ff7a0a-fb on port 6
  2017-11-01T07:18:50.990Z|00142|bridge|INFO|bridge br-int: deleted interface qvof0ff7a0a-fb on port 6
  2017-11-01T07:18:50.990Z|00143|bridge|INFO|bridge br-int: deleted interface br-int on port 65534
  2017-11-01T07:18:50.990Z|00144|bridge|INFO|bridge br-int: deleted interface tap538c1725-9c on port 3
  2017-11-01T07:18:50.990Z|00145|bridge|INFO|bridge br-int: deleted interface int-br-ex on port 1
  2017-11-01T07:18:50.990Z|00146|bridge|INFO|bridge br-int: deleted interface qr-843934a0-e1 on port 4
  2017-11-01T07:18:50.990Z|00147|bridge|INFO|bridge br-int: deleted interface patch-tun on port 2
  2017-11-01T07:18:50.990Z|00148|bridge|INFO|bridge br-int: deleted interface qg-2e8399f4-4c on port 5
  2017-11-01T07:18:51.200Z|00149|bridge|INFO|bridge br-int: added interface qvof0ff7a0a-fb on port 6
  2017-11-01T07:18:51.202Z|00150|bridge|INFO|bridge br-int: added interface br-int on port 65534
  2017-11-01T07:18:51.203Z|00151|bridge|INFO|bridge br-int: added interface qr-843934a0-e1 on port 4
  2017-11-01T07:18:51.203Z|00152|bridge|INFO|bridge br-int: added interface int-br-ex on port 1
  2017-11-01T07:18:51.205Z|00153|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 3
  2017-11-01T07:18:51.207Z|00154|bridge|INFO|bridge br-int: added interface qg-2e8399f4-4c on port 5
  2017-11-01T07:18:51.207Z|00155|bridge|INFO|bridge br-int: added interface patch-tun on port 2
  2017-11-01T07:18:51.207Z|00156|bridge|INFO|bridge br-int: using datapath ID 000072c56eb3c24b
  2017-11-01T07:18:51.207Z|00157|connmgr|INFO|br-int: added service controller "punix:/var/run/openvswitch/br-int.mgmt"
  2017-11-01T07:18:51.207Z|00158|connmgr|INFO|br-int: added primary controller "tcp:127.0.0.1:6633"
  2017-11-01T07:18:51.207Z|00159|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connecting...
  2017-11-01T07:18:51.262Z|00160|rconn|INFO|br-int<->tcp:127.0.0.1:6633: connected
  2017-11-01T07:18:56.618Z|00161|bridge|INFO|bridge br-int: deleted interface tap538c1725-9c on port 3
  2017-11-01T07:18:56.637Z|00162|bridge|INFO|bridge br-int: added interface tap538c1725-9c on port 7
  2017-11-01T07:18:58.349Z|00163|netdev_linux|WARN|Dropped 24 log messages in last 8 seconds (most recently, 8 seconds ago) due to excessive rate
  2017-11-01T07:18:58.349Z|00164|netdev_linux|WARN|tap538c1725-9c: removing policing failed: No such device
  2017-11-01T07:18:58.358Z|00165|netdev_linux|INFO|ioctl(SIOCGIFHWADDR) on tap538c1725-9c device failed: No such device
  2017-11-01T07:19:01.408Z|00166|connmgr|INFO|br-int<->tcp:127.0.0.1:6633: 31 flow_mods in the 7 s starting 10 s ago (12 adds, 19 deletes)
  2017-11-01T07:19:01.489Z|00167|connmgr|INFO|br-ex<->tcp:127.0.0.1:6633: 3 flow_mods 10 s ago (3 adds)
  2017-11-01T07:19:01.573Z|00168|connmgr|INFO|br-tun<->tcp:127.0.0.1:6633: 15 flow_mods 10 s ago (15 adds)

To manage notifications about this bug go to:
https://bugs.launchpad.net/neutron/+bug/1729229/+subscriptions
Follow ups

[Bug 1729229] Re: router lost ports when creating VM
From: Zhijiang Hu, 2017-11-03