Comment 2 for bug 1871818

I looked at the test results and there was only one failing tests, here is my observing so far:

==============================
Failed 1 tests - output below:
==============================

neutron_tempest_plugin.scenario.test_trunk.TrunkTest.test_trunk_subport_lifecycle[id-bb13fe28-f152-4000-8131-37890a40c79e]
--------------------------------------------------------------------------------------------------------------------------

Captured traceback:
~~~~~~~~~~~~~~~~~~~
    Traceback (most recent call last):
      File "/usr/lib/python2.7/site-packages/neutron_tempest_plugin/scenario/test_trunk.py", line 221, in test_trunk_subport_lifecycle
        self._wait_for_port(port)
      File "/usr/lib/python2.7/site-packages/neutron_tempest_plugin/scenario/test_trunk.py", line 141, in _wait_for_port
        "status {!r}.".format(port['id'], status)))
      File "/usr/lib/python2.7/site-packages/neutron_tempest_plugin/common/utils.py", line 82, in wait_until_true
        raise exception
    RuntimeError: Timed out waiting for port u'2ff1e990-aaba-4067-bbc3-22eea33012c8' to transition to get status 'ACTIVE'.

I checked the server logs and the subport association finished fine:

2020-04-09 01:46:22.617 32 DEBUG networking_ovn.ml2.trunk_driver [req-61a93d21-64b4-4eea-99f8-7ed33ea83754 f41d513e64b6497e82da9c448c1bdc08 670053b5f38746959786934f92c55d1c - default default] Done setting parent c528340a-8159-4cd8-8ba9-2ebd839fb4e9 for subport 2ff1e990-aaba-4067-bbc3-22eea33012c8 _set_binding_profile /usr/lib/python2.7/site-packages/networking_ovn/ml2/trunk_driver.py:93

2020-04-09 01:46:22.619 32 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=0): SetLSwitchPortCommand(lport=2ff1e990-aaba-4067-bbc3-22eea33012c8, if_exists=True, columns={'parent_name': 'c528340a-8159-4cd8-8ba9-2ebd839fb4e9', 'tag': 4}) do_commit /usr/lib/python2.7/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:84

2020-04-09 01:46:22.666 33 DEBUG ovsdbapp.backend.ovs_idl.event [-] Matched UPDATE: LogicalSwitchPortUpdateUpEvent(events=('update',), table='Logical_Switch_Port', conditions=(('up', '=', True),), old_conditions=(('up', '=', False),)) to row=Logical_Switch_Port(parent_name=[u'c528340a-8159-4cd8-8ba9-2ebd839fb4e9'], name=2ff1e990-aaba-4067-bbc3-22eea33012c8, enabled=[True], ha_chassis_group=[], up=[True], port_security=[u'fa:16:3e:6d:f7:9a'], dhcpv6_options=[], tag_request=[], dynamic_addresses=[], external_ids={u'neutron:cidrs': u'', u'neutron:revision_number': u'1', u'neutron:port_name': u'', u'neutron:network_name': u'neutron-62d8faf1-be2c-4829-8973-d133c7c2d33e', u'neutron:project_id': u'670053b5f38746959786934f92c55d1c', u'neutron:security_group_ids': u'215a8850-34f1-40d5-a468-814f4fc08949', u'neutron:device_id': u'', u'neutron:device_owner': u''}, tag=[4], type=, options={u'requested-chassis': u''}, dhcpv4_options=[], addresses=[u'fa:16:3e:6d:f7:9a']) old=Logical_Switch_Port(up=[False]) matches /usr/lib/python2.7/site-packages/ovsdbapp/backend/ovs_idl/event.py:44

2020-04-09 01:46:22.667 33 INFO networking_ovn.ml2.mech_driver [req-828a35a7-bca2-4425-a1be-fb94e87c4945 - - - - -] OVN reports status up for port: 2ff1e990-aaba-4067-bbc3-22eea33012c8

2020-04-09 01:46:22.816 33 DEBUG neutron.db.provisioning_blocks [req-828a35a7-bca2-4425-a1be-fb94e87c4945 - - - - -] Provisioning complete for port 2ff1e990-aaba-4067-bbc3-22eea33012c8 triggered by entity L2. provisioning_complete /usr/lib/python2.7/site-packages/neutron/db/provisioning_blocks.py:140

But then Neutron wasn't able to set the status up because port was not bound:

2020-04-09 01:46:22.894 33 DEBUG neutron.plugins.ml2.plugin [req-828a35a7-bca2-4425-a1be-fb94e87c4945 - - - - -] Port 2ff1e990-aaba-4067-bbc3-22eea33012c8 cannot update to ACTIVE because it is not bound. _port_provisioned /usr/lib/python2.7/site-packages/neutron/plugins/ml2/plugin.py:301