yahoo-eng-team team mailing list archive

[Wei Hui <1881556@xxxxxxxxxxxxxxxxxx>]

Public bug reported:

kolla upgrade neutron

rpc_loop 1:
Physical bridge br-ex was just re-created, 
set bridges_recreated=True
set need_clean_stale_flow=True
process_network_ports argument provisioning_needed is True but throw exception, not call provision_local_vlan and flag need_clean_stale_flow keep True

rpc_loop 2:
set bridges_recreated=False
process_network_ports argument provisioning_needed is False, and not call provision_local_vlan
Cleaning stale flows and set need_clean_stale_flow = False
flow lost 


fllowing is detailed log:

 457:2020-05-29 11:12:37.070 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Agent rpc_loop - iteration:0 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
1912:2020-05-29 11:13:04.994 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Processing port: a31f01a5-371a-4b44-8374-d87272d322ce treat_devices_added_or_updated /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:1692
1914:2020-05-29 11:13:04.995 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Assigning 43 as local vlan for net-id=c514bab4-139d-4eb0-8da2-3ab89938cbb2
11362:2020-05-29 11:17:20.688 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Cleaning stale br-ex flows
11393:2020-05-29 11:17:21.831 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:0 completed. Processed ports statistics: {'regular': {'updated': 0, 'added': 120, 'removed': 0}}. Elapsed:284.761 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003

11396:2020-05-29 11:17:21.832 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:1 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
11399:2020-05-29 11:17:21.840 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Physical bridge br-ex was just re-created.
12771:2020-05-29 11:19:15.353 7 ERROR neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Error while processing VIF ports: MessagingTimeout: Timed out waiting for a reply to message ID df2deeed7f614e67abd42b088e0258e0
12811:2020-05-29 11:19:15.358 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:1 completed. Processed ports statistics: {'regular': {'updated': 0, 'added': 0, 'removed': 0}}. Elapsed:113.526 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003


12813:2020-05-29 11:19:15.359 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:2 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
14372:2020-05-29 11:21:03.018 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Processing port: a31f01a5-371a-4b44-8374-d87272d322ce treat_devices_added_or_updated /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:1692
20013:2020-05-29 11:23:46.786 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Cleaning stale br-ex flows
20037:2020-05-29 11:23:47.709 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:2 completed. Processed ports statistics: {'regular': {'updated': 58, 'added': 120, 'removed': 0}}. Elapsed:272.350 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003

20039:2020-05-29 11:23:47.710 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:3 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
20307:2020-05-29 11:23:49.963 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Processing port: a31f01a5-371a-4b44-8374-d87272d322ce treat_devices_added_or_updated /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:1692
21831:2020-05-29 11:24:27.000 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:3 completed. Processed ports statistics: {'regular': {'updated': 44, 'added': 44, 'removed': 0}}. Elapsed:39.290 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003

** 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/1881556

Title:
  [ovs-agent]exception cause stale flow cleaned but new flow not
  installed

Status in neutron:
  New

Bug description:
  kolla upgrade neutron

  rpc_loop 1:
  Physical bridge br-ex was just re-created, 
  set bridges_recreated=True
  set need_clean_stale_flow=True
  process_network_ports argument provisioning_needed is True but throw exception, not call provision_local_vlan and flag need_clean_stale_flow keep True

  rpc_loop 2:
  set bridges_recreated=False
  process_network_ports argument provisioning_needed is False, and not call provision_local_vlan
  Cleaning stale flows and set need_clean_stale_flow = False
  flow lost 

  
  fllowing is detailed log:

   457:2020-05-29 11:12:37.070 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Agent rpc_loop - iteration:0 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
  1912:2020-05-29 11:13:04.994 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Processing port: a31f01a5-371a-4b44-8374-d87272d322ce treat_devices_added_or_updated /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:1692
  1914:2020-05-29 11:13:04.995 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Assigning 43 as local vlan for net-id=c514bab4-139d-4eb0-8da2-3ab89938cbb2
  11362:2020-05-29 11:17:20.688 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Cleaning stale br-ex flows
  11393:2020-05-29 11:17:21.831 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:0 completed. Processed ports statistics: {'regular': {'updated': 0, 'added': 120, 'removed': 0}}. Elapsed:284.761 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003

  11396:2020-05-29 11:17:21.832 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:1 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
  11399:2020-05-29 11:17:21.840 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Physical bridge br-ex was just re-created.
  12771:2020-05-29 11:19:15.353 7 ERROR neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Error while processing VIF ports: MessagingTimeout: Timed out waiting for a reply to message ID df2deeed7f614e67abd42b088e0258e0
  12811:2020-05-29 11:19:15.358 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:1 completed. Processed ports statistics: {'regular': {'updated': 0, 'added': 0, 'removed': 0}}. Elapsed:113.526 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003

  
  12813:2020-05-29 11:19:15.359 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:2 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
  14372:2020-05-29 11:21:03.018 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Processing port: a31f01a5-371a-4b44-8374-d87272d322ce treat_devices_added_or_updated /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:1692
  20013:2020-05-29 11:23:46.786 7 INFO neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Cleaning stale br-ex flows
  20037:2020-05-29 11:23:47.709 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:2 completed. Processed ports statistics: {'regular': {'updated': 58, 'added': 120, 'removed': 0}}. Elapsed:272.350 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003

  20039:2020-05-29 11:23:47.710 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:3 started rpc_loop /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2214
  20307:2020-05-29 11:23:49.963 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -] Processing port: a31f01a5-371a-4b44-8374-d87272d322ce treat_devices_added_or_updated /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:1692
  21831:2020-05-29 11:24:27.000 7 DEBUG neutron.plugins.ml2.drivers.openvswitch.agent.ovs_neutron_agent [req-cc051aec-d6e6-4278-8944-13835ee1c47b - - - - -]Agent rpc_loop - iteration:3 completed. Processed ports statistics: {'regular': {'updated': 44, 'added': 44, 'removed': 0}}. Elapsed:39.290 loop_count_and_wait /var/lib/kolla/venv/lib/python2.7/site-packages/neutron/plugins/ml2/drivers/openvswitch/agent/ovs_neutron_agent.py:2003

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