Bug 2169673
| Summary: | [Neutron][OVN] - VLAN-aware instances - sub-ports are taking 15-30 min to be available | |||
|---|---|---|---|---|
| Product: | Red Hat OpenStack | Reporter: | Slawek Kaplonski <skaplons> | |
| Component: | python-networking-ovn | Assignee: | OSP Team <rhos-maint> | |
| Status: | CLOSED CURRENTRELEASE | QA Contact: | Vadim Khitrin <vkhitrin> | |
| Severity: | high | Docs Contact: | ||
| Priority: | high | |||
| Version: | 16.2 (Train) | CC: | apevec, bcafarel, chrisw, dhill, dhruv, ekuris, fesilva, froyo, gthiemon, jlibosva, jraju, lhh, majopela, mariel, mblue, mflusche, ralonsoh, scohen, skaplons, vkhitrin | |
| Target Milestone: | z6 | Keywords: | TestOnly, Triaged | |
| Target Release: | 16.2 (Train on RHEL 8.4) | |||
| Hardware: | x86_64 | |||
| OS: | Linux | |||
| Whiteboard: | ||||
| Fixed In Version: | python-networking-ovn-7.4.2-2.20220409154875.el8ost | Doc Type: | No Doc Update | |
| Doc Text: | Story Points: | --- | ||
| Clone Of: | 2166012 | |||
| : | 2169676 (view as bug list) | Environment: | ||
| Last Closed: | 2023-10-19 10:36:45 UTC | Type: | --- | |
| 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: | 2166012 | |||
| Bug Blocks: | 2169676 | |||
|
Comment 14
Lon Hohberger
2023-08-02 10:37:16 UTC
Verified on compose `RHOS-16.2-RHEL-8-20231005.n.3`, trunk ports are configured quickly after their creation
```
grep -iR '4b0a4d85-9c73-4c9e-a42e-ead2442340e6' /var/log/containers/neutron/ # trunk port ID
--------------------------------------------------------------------------------------------
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.178 16 DEBUG neutron.notifiers.nova [req-d1aedb6c-3503-44f6-876e-7309882eb102 ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] device_id is not set on port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 yet. _can_notify /usr/lib/python3.6/site-packages/neutron/notifiers/nova.py:201
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.270 16 DEBUG neutron.db.db_base_plugin_common [req-d1aedb6c-3503-44f6-876e-7309882eb102 ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] Allocated IP 10.10.10.14 (3915bac2-cdf8-4e4c-be65-24d8de031a02/6ceb5bb1-ad74-43c5-a4aa-e9d3134da4e8/4b0a4d85-9c73-4c9e-a42e-ead2442340e6) _store_ip_allocation /usr/lib/python3.6/site-packages/neutron/db/db_base_plugin_common.py:137
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.442 16 DEBUG networking_ovn.ml2.mech_driver [req-d1aedb6c-3503-44f6-876e-7309882eb102 ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] No provisioning block for port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 since it does not have a host _is_port_provisioning_required /usr/lib/python3.6/site-packages/networking_ovn/ml2/mech_driver.py:607
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.443 16 DEBUG networking_ovn.db.revision [req-d1aedb6c-3503-44f6-876e-7309882eb102 ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] create_initial_revision uuid=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, type=ports, rev=-1 create_initial_revision /usr/lib/python3.6/site-packages/networking_ovn/db/revision.py:59
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.612 16 DEBUG neutron.api.rpc.handlers.resources_rpc [req-d1aedb6c-3503-44f6-876e-7309882eb102 ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - - -] Pushing event updated for resources: {'Port': ['ID=4b0a4d85-9c73-4c9e-a42e-ead2442340e6,revision_number=1']} push /usr/lib/python3.6/site-packages/neutron/api/rpc/handlers/resources_rpc.py:243
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.698 16 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=0): AddLSwitchPortCommand(lport=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, lswitch=neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02, may_exist=True, columns={'addresses': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'external_ids': {'neutron:port_name': 'TEST_PORT', 'neutron:device_id': '', 'neutron:project_id': 'd697d970e6074604bd102d1d9968dfd0', 'neutron:cidrs': '10.10.10.14/24', 'neutron:device_owner': '', 'neutron:network_name': 'neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02', 'neutron:security_group_ids': '2fa725fe-abc5-4a22-893a-c2d8de93bd45', 'neutron:revision_number': '1'}, 'parent_name': [], 'tag': [], 'enabled': True, 'options': {'requested-chassis': '', 'mcast_flood_reports': 'true'}, 'type': '', 'port_security': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'dhcpv4_options': [UUID('244e7f23-1ce1-41c5-8fc3-2fb7a948d77c')], 'dhcpv6_options': []}) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.700 16 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=3): QoSDelCommand(switch=neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02, conditions=[('direction', '=', 'to-lport'), ('priority', '=', 2002), ('match', '=', 'outport == "4b0a4d85-9c73-4c9e-a42e-ead2442340e6"')], if_exists=True) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.701 16 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=4): QoSDelCommand(switch=neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02, conditions=[('direction', '=', 'from-lport'), ('priority', '=', 2002), ('match', '=', 'inport == "4b0a4d85-9c73-4c9e-a42e-ead2442340e6"')], if_exists=True) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:54:32.796 16 INFO networking_ovn.db.revision [req-d1aedb6c-3503-44f6-876e-7309882eb102 ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] Successfully bumped revision number for resource 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 (type: ports) to 1
/var/log/containers/neutron/server.log:2023-10-16 19:56:03.072 15 INFO neutron.wsgi [req-6071bf9e-9663-4835-866d-157075310fe5 ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] 10.46.174.2,10.10.120.155 "GET /v2.0/ports?id=4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 1022 time: 0.1250429
/var/log/containers/neutron/server.log:2023-10-16 19:56:32.083 16 INFO neutron.wsgi [req-b432ab72-6996-43cd-aeea-7a9b176bbecf ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] 10.46.174.2,10.10.120.155 "GET /v2.0/ports?id=4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 1022 time: 0.5176280
/var/log/containers/neutron/server.log:2023-10-16 19:59:05.780 15 DEBUG neutron.api.v2.base [req-3c1125c2-8e9f-4f42-bd41-786001a2ab5a ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] Request body: {'trunk': {'name': 'TEST_TRUNK', 'admin_state_up': True, 'port_id': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'sub_ports': [{'port_id': 'de74fab5-06b1-45c0-bf82-4c30ca53c259', 'segmentation_id': 125, 'segmentation_type': 'vlan'}]}} prepare_request_body /usr/lib/python3.6/site-packages/neutron/api/v2/base.py:719
/var/log/containers/neutron/server.log:2023-10-16 19:59:06.191 15 DEBUG networking_ovn.ml2.trunk_driver [req-3c1125c2-8e9f-4f42-bd41-786001a2ab5a ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] Setting parent 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 for subport de74fab5-06b1-45c0-bf82-4c30ca53c259 _set_binding_profile /usr/lib/python3.6/site-packages/networking_ovn/ml2/trunk_driver.py:72
/var/log/containers/neutron/server.log:2023-10-16 19:59:06.279 15 DEBUG networking_ovn.ml2.trunk_driver [req-3c1125c2-8e9f-4f42-bd41-786001a2ab5a ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] Done setting parent 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 for subport de74fab5-06b1-45c0-bf82-4c30ca53c259 _set_binding_profile /usr/lib/python3.6/site-packages/networking_ovn/ml2/trunk_driver.py:113
/var/log/containers/neutron/server.log:2023-10-16 19:59:06.281 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=1): SetLSwitchPortCommand(lport=de74fab5-06b1-45c0-bf82-4c30ca53c259, external_ids_update={'neutron:device_owner': 'trunk:subport'}, columns={'parent_name': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'tag': 125}, if_exists=True) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:59:36.721 16 INFO neutron.wsgi [req-b82bd497-c27d-45d6-ab5e-690918e00b8a ec5426eda4174a28bff34200f80e4c0f d697d970e6074604bd102d1d9968dfd0 - default default] 10.10.120.128,10.10.120.157 "GET /v2.0/ports/4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 1236 time: 0.0971382
/var/log/containers/neutron/server.log:2023-10-16 19:59:41.667 16 INFO neutron.wsgi [req-ef361114-6b0d-4d79-ab2e-f4407c82dfc4 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] 10.10.120.124,10.10.120.157 "GET /v2.0/ports/4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 1236 time: 0.3395944
/var/log/containers/neutron/server.log:2023-10-16 19:59:42.936 15 DEBUG neutron.notifiers.nova [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Ignoring state change previous_port_status: DOWN current_port_status: DOWN port_id 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 record_port_status_changed /usr/lib/python3.6/site-packages/neutron/notifiers/nova.py:240
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.019 15 DEBUG neutron.db.provisioning_blocks [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Transition to ACTIVE for port object 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 will not be triggered until provisioned by entity L2. add_provisioning_component /usr/lib/python3.6/site-packages/neutron/db/provisioning_blocks.py:76
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.128 15 DEBUG neutron.api.rpc.handlers.resources_rpc [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - - -] Pushing event updated for resources: {'Port': ['ID=4b0a4d85-9c73-4c9e-a42e-ead2442340e6,revision_number=2']} push /usr/lib/python3.6/site-packages/neutron/api/rpc/handlers/resources_rpc.py:243
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.246 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=0): CheckRevisionNumberCommand(name=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, resource={'id': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'name': 'TEST_PORT', 'network_id': '3915bac2-cdf8-4e4c-be65-24d8de031a02', 'tenant_id': 'd697d970e6074604bd102d1d9968dfd0', 'mac_address': 'fa:16:3e:23:cd:bb', 'admin_state_up': True, 'status': 'DOWN', 'device_id': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'device_owner': 'compute:nova', 'fixed_ips': [{'subnet_id': '6ceb5bb1-ad74-43c5-a4aa-e9d3134da4e8', 'ip_address': '10.10.10.14'}], 'allowed_address_pairs': [], 'extra_dhcp_opts': [], 'security_groups': ['2fa725fe-abc5-4a22-893a-c2d8de93bd45'], 'description': '', 'binding:vnic_type': 'normal', 'binding:profile': {}, 'binding:host_id': 'computedpdksriov-1.localdomain', 'binding:vif_type': 'unbound', 'binding:vif_details': {}, 'port_security_enabled': True, 'qos_policy_id': None, 'trunk_details': {'trunk_id': 'dbe0bdf3-0dc2-46b5-8adc-d78ae1c8add0', 'sub_ports': [{'segmentation_id': 125, 'segmentation_type': 'vlan', 'port_id': 'de74fab5-06b1-45c0-bf82-4c30ca53c259', 'mac_address': 'fa:16:3e:bb:9c:92'}]}, 'resource_request': None, 'tags': [], 'created_at': '2023-10-16T19:54:32Z', 'updated_at': '2023-10-16T19:59:42Z', 'revision_number': 2, 'project_id': 'd697d970e6074604bd102d1d9968dfd0', 'network': {'id': '3915bac2-cdf8-4e4c-be65-24d8de031a02', 'name': 'dpdk-mgmt', 'tenant_id': 'd697d970e6074604bd102d1d9968dfd0', 'admin_state_up': True, 'mtu': 8942, 'status': 'ACTIVE', 'subnets': ['6ceb5bb1-ad74-43c5-a4aa-e9d3134da4e8'], 'shared': False, 'availability_zone_hints': [], 'availability_zones': [], 'ipv4_address_scope': None, 'ipv6_address_scope': None, 'router:external': False, 'vlan_transparent': None, 'description': '', 'port_security_enabled': True, 'qos_policy_id': None, 'tags': [], 'created_at': '2023-10-16T16:29:22Z', 'updated_at': '2023-10-16T16:31:29Z', 'revision_number': 2, 'project_id': 'd697d970e6074604bd102d1d9968dfd0', 'provider:network_type': 'geneve', 'provider:physical_network': None, 'provider:segmentation_id': 13667}}, resource_type=ports, if_exists=True) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.247 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=1): SetLSwitchPortCommand(lport=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, external_ids_update=None, columns={'external_ids': {'neutron:port_name': 'TEST_PORT', 'neutron:device_id': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'neutron:project_id': 'd697d970e6074604bd102d1d9968dfd0', 'neutron:cidrs': '10.10.10.14/24', 'neutron:device_owner': 'compute:nova', 'neutron:network_name': 'neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02', 'neutron:security_group_ids': '2fa725fe-abc5-4a22-893a-c2d8de93bd45', 'neutron:revision_number': '2'}, 'parent_name': [], 'tag': [], 'options': {'requested-chassis': 'computedpdksriov-1.localdomain', 'mcast_flood_reports': 'true'}, 'enabled': True, 'port_security': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'dhcpv4_options': [UUID('244e7f23-1ce1-41c5-8fc3-2fb7a948d77c')], 'dhcpv6_options': [], 'type': '', 'addresses': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'ha_chassis_group': []}, if_exists=False) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.248 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=2): PgAddPortCommand(port_group=neutron_pg_drop, lsp=['4b0a4d85-9c73-4c9e-a42e-ead2442340e6'], if_exists=False) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.309 15 INFO networking_ovn.db.revision [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Successfully bumped revision number for resource 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 (type: ports) to 2
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.343 15 DEBUG neutron.plugins.ml2.managers [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Attempting to bind port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 on host computedpdksriov-1.localdomain for vnic_type normal with profile bind_port /usr/lib/python3.6/site-packages/neutron/plugins/ml2/managers.py:795
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.343 15 DEBUG neutron.plugins.ml2.managers [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Attempting to bind port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 by drivers sriovnicswitch,ovn on host computedpdksriov-1.localdomain at level 0 using segments [{'id': '95071630-bcca-41e6-bb43-53b205efed50', 'network_type': 'geneve', 'physical_network': None, 'segmentation_id': 13667, 'network_id': '3915bac2-cdf8-4e4c-be65-24d8de031a02'}] _bind_port_level /usr/lib/python3.6/site-packages/neutron/plugins/ml2/managers.py:822
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.343 15 DEBUG neutron.plugins.ml2.drivers.mech_sriov.mech_driver.mech_driver [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Attempting to bind port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 on network 3915bac2-cdf8-4e4c-be65-24d8de031a02 bind_port /usr/lib/python3.6/site-packages/neutron/plugins/ml2/drivers/mech_sriov/mech_driver/mech_driver.py:116
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.345 15 DEBUG networking_ovn.ml2.mech_driver [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Attempting to bind port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 on host computedpdksriov-1.localdomain for network segment with type geneve, segmentation ID 13667, physical network None bind_port /usr/lib/python3.6/site-packages/networking_ovn/ml2/mech_driver.py:873
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.345 15 DEBUG neutron.plugins.ml2.managers [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Bound port: 4b0a4d85-9c73-4c9e-a42e-ead2442340e6, host: computedpdksriov-1.localdomain, vif_type: vhostuser, vif_details: {"port_filter": false, "vhostuser_mode": "server", "vhostuser_ovs_plug": true, "vhostuser_socket": "/var/lib/vhost_sockets/vhu4b0a4d85-9c"}, binding_levels: [{'bound_driver': 'ovn', 'bound_segment': {'id': '95071630-bcca-41e6-bb43-53b205efed50', 'network_type': 'geneve', 'physical_network': None, 'segmentation_id': 13667, 'network_id': '3915bac2-cdf8-4e4c-be65-24d8de031a02'}}] _bind_port_level /usr/lib/python3.6/site-packages/neutron/plugins/ml2/managers.py:937
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.508 15 DEBUG neutron.plugins.ml2.db [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] For port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6, host computedpdksriov-1.localdomain, cleared binding levels clear_binding_levels /usr/lib/python3.6/site-packages/neutron/plugins/ml2/db.py:111
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.511 15 DEBUG neutron.plugins.ml2.db [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] For port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6, host computedpdksriov-1.localdomain, set binding levels [PortBindingLevel(driver='ovn',host='computedpdksriov-1.localdomain',level=0,port_id=4b0a4d85-9c73-4c9e-a42e-ead2442340e6,segment=NetworkSegment(95071630-bcca-41e6-bb43-53b205efed50),segment_id=95071630-bcca-41e6-bb43-53b205efed50)] set_binding_levels /usr/lib/python3.6/site-packages/neutron/plugins/ml2/db.py:65
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.511 15 DEBUG networking_ovn.ml2.mech_driver [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] No provisioning block for port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 since host unchanged _is_port_provisioning_required /usr/lib/python3.6/site-packages/networking_ovn/ml2/mech_driver.py:612
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.645 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=0): CheckRevisionNumberCommand(name=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, resource={'id': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'name': 'TEST_PORT', 'network_id': '3915bac2-cdf8-4e4c-be65-24d8de031a02', 'tenant_id': 'd697d970e6074604bd102d1d9968dfd0', 'mac_address': 'fa:16:3e:23:cd:bb', 'admin_state_up': True, 'status': 'DOWN', 'device_id': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'device_owner': 'compute:nova', 'fixed_ips': [{'subnet_id': '6ceb5bb1-ad74-43c5-a4aa-e9d3134da4e8', 'ip_address': '10.10.10.14'}], 'allowed_address_pairs': [], 'extra_dhcp_opts': [], 'security_groups': ['2fa725fe-abc5-4a22-893a-c2d8de93bd45'], 'description': '', 'binding:vnic_type': 'normal', 'binding:profile': {}, 'binding:host_id': 'computedpdksriov-1.localdomain', 'binding:vif_type': 'vhostuser', 'binding:vif_details': {'port_filter': False, 'vhostuser_mode': 'server', 'vhostuser_ovs_plug': True, 'vhostuser_socket': '/var/lib/vhost_sockets/vhu4b0a4d85-9c'}, 'port_security_enabled': True, 'qos_policy_id': None, 'trunk_details': {'trunk_id': 'dbe0bdf3-0dc2-46b5-8adc-d78ae1c8add0', 'sub_ports': [{'segmentation_id': 125, 'segmentation_type': 'vlan', 'port_id': 'de74fab5-06b1-45c0-bf82-4c30ca53c259', 'mac_address': 'fa:16:3e:bb:9c:92'}]}, 'resource_request': None, 'tags': [], 'created_at': '2023-10-16T19:54:32Z', 'updated_at': '2023-10-16T19:59:42Z', 'revision_number': 2, 'project_id': 'd697d970e6074604bd102d1d9968dfd0', 'network': {'id': '3915bac2-cdf8-4e4c-be65-24d8de031a02', 'name': 'dpdk-mgmt', 'tenant_id': 'd697d970e6074604bd102d1d9968dfd0', 'admin_state_up': True, 'mtu': 8942, 'status': 'ACTIVE', 'subnets': ['6ceb5bb1-ad74-43c5-a4aa-e9d3134da4e8'], 'shared': False, 'availability_zone_hints': [], 'availability_zones': [], 'ipv4_address_scope': None, 'ipv6_address_scope': None, 'router:external': False, 'vlan_transparent': None, 'description': '', 'port_security_enabled': True, 'qos_policy_id': None, 'tags': [], 'created_at': '2023-10-16T16:29:22Z', 'updated_at': '2023-10-16T16:31:29Z', 'revision_number': 2, 'project_id': 'd697d970e6074604bd102d1d9968dfd0', 'provider:network_type': 'geneve', 'provider:physical_network': None, 'provider:segmentation_id': 13667}}, resource_type=ports, if_exists=True) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.646 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=1): SetLSwitchPortCommand(lport=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, external_ids_update=None, columns={'external_ids': {'neutron:port_name': 'TEST_PORT', 'neutron:device_id': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'neutron:project_id': 'd697d970e6074604bd102d1d9968dfd0', 'neutron:cidrs': '10.10.10.14/24', 'neutron:device_owner': 'compute:nova', 'neutron:network_name': 'neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02', 'neutron:security_group_ids': '2fa725fe-abc5-4a22-893a-c2d8de93bd45', 'neutron:revision_number': '2'}, 'parent_name': [], 'tag': [], 'options': {'requested-chassis': 'computedpdksriov-1.localdomain', 'mcast_flood_reports': 'true'}, 'enabled': True, 'port_security': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'dhcpv4_options': [UUID('244e7f23-1ce1-41c5-8fc3-2fb7a948d77c')], 'dhcpv6_options': [], 'type': '', 'addresses': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'ha_chassis_group': []}, if_exists=False) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.647 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=2): PgAddPortCommand(port_group=neutron_pg_drop, lsp=['4b0a4d85-9c73-4c9e-a42e-ead2442340e6'], if_exists=False) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.679 15 DEBUG neutron.api.rpc.handlers.resources_rpc [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - - -] Pushing event updated for resources: {'Port': ['ID=4b0a4d85-9c73-4c9e-a42e-ead2442340e6,revision_number=3']} push /usr/lib/python3.6/site-packages/neutron/api/rpc/handlers/resources_rpc.py:243
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.699 15 INFO networking_ovn.db.revision [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] Successfully bumped revision number for resource 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 (type: ports) to 2
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.721 15 INFO neutron.wsgi [req-bdc98aba-0ba3-449b-8cbf-59f747151021 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] 10.10.120.124,10.10.120.157 "PUT /v2.0/ports/4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 1446 time: 1.5372753
/var/log/containers/neutron/server.log:2023-10-16 19:59:43.721 15 DEBUG neutron.notifiers.nova [-] Sending events: [{'name': 'network-changed', 'server_uuid': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'tag': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6'}] send_events /usr/lib/python3.6/site-packages/neutron/notifiers/nova.py:279
/var/log/containers/neutron/server.log:2023-10-16 19:59:44.611 15 INFO neutron.notifiers.nova [-] Nova event matching ['req-608dc8d3-1f98-4b0f-82fb-6bbc6a1c5ba2'] response: {'name': 'network-changed', 'server_uuid': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'tag': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'status': 'completed', 'code': 200}
/var/log/containers/neutron/server.log:2023-10-16 19:59:44.883 16 INFO neutron.wsgi [req-9d3c612e-9e37-4c85-8fe5-c820cdc1633a 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] 10.10.120.124,10.10.120.157 "GET /v2.0/floatingips?fixed_ip_address=10.10.10.14&port_id=4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 193 time: 0.0451658
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.476 15 DEBUG ovsdbapp.backend.ovs_idl.event [-] Matched UPDATE: LogicalSwitchPortUpdateUpEvent(events=('update',), table='Logical_Switch_Port', conditions=(('up', '=', True),), old_conditions=(('up', '!=', True),)) to row=Logical_Switch_Port(name=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, up=[True], external_ids={'neutron:cidrs': '10.10.10.14/24', 'neutron:device_id': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'neutron:device_owner': 'compute:nova', 'neutron:network_name': 'neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02', 'neutron:port_name': 'TEST_PORT', 'neutron:project_id': 'd697d970e6074604bd102d1d9968dfd0', 'neutron:revision_number': '2', 'neutron:security_group_ids': '2fa725fe-abc5-4a22-893a-c2d8de93bd45'}, ha_chassis_group=[], options={'mcast_flood_reports': 'true', 'requested-chassis': 'computedpdksriov-1.localdomain'}, parent_name=[], enabled=[True], type=, dhcpv6_options=[], addresses=['fa:16:3e:23:cd:bb 10.10.10.14'], port_security=['fa:16:3e:23:cd:bb 10.10.10.14'], tag_request=[], tag=[], dhcpv4_options=[<ovs.db.idl.Row object at 0x7f9c38c37a20>], dynamic_addresses=[]) old=Logical_Switch_Port(up=[False]) matches /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/event.py:44
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.479 15 INFO networking_ovn.ml2.mech_driver [req-ad287d20-5c6a-45fb-9913-9b7cf94537de - - - - -] OVN reports status up for port: 4b0a4d85-9c73-4c9e-a42e-ead2442340e6
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.501 15 DEBUG neutron.db.provisioning_blocks [req-ad287d20-5c6a-45fb-9913-9b7cf94537de - - - - -] Provisioning for port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 completed by entity L2. provisioning_complete /usr/lib/python3.6/site-packages/neutron/db/provisioning_blocks.py:135
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.507 15 DEBUG neutron.db.provisioning_blocks [req-ad287d20-5c6a-45fb-9913-9b7cf94537de - - - - -] Provisioning complete for port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 triggered by entity L2. provisioning_complete /usr/lib/python3.6/site-packages/neutron/db/provisioning_blocks.py:141
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.606 15 DEBUG neutron.plugins.ml2.plugin [req-ad287d20-5c6a-45fb-9913-9b7cf94537de - - - - -] Current status of the port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 is: DOWN; New status is: ACTIVE _update_individual_port_db_status /usr/lib/python3.6/site-packages/neutron/plugins/ml2/plugin.py:2187
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.693 15 DEBUG neutron.notifiers.nova [-] Sending events: [{'server_uuid': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'name': 'network-vif-plugged', 'status': 'completed', 'tag': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6'}] send_events /usr/lib/python3.6/site-packages/neutron/notifiers/nova.py:279
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.744 15 INFO neutron.notifiers.nova [-] Nova event matching ['req-86ca74cb-084c-485c-a1b1-69fbb51357e9'] response: {'server_uuid': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'name': 'network-vif-plugged', 'status': 'completed', 'tag': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'code': 200}
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.756 15 DEBUG neutron.plugins.ml2.db [req-ad287d20-5c6a-45fb-9913-9b7cf94537de - - - - -] For port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6, host computedpdksriov-1.localdomain, got binding levels [PortBindingLevel(driver='ovn',host='computedpdksriov-1.localdomain',level=0,port_id=4b0a4d85-9c73-4c9e-a42e-ead2442340e6,segment=NetworkSegment(95071630-bcca-41e6-bb43-53b205efed50),segment_id=95071630-bcca-41e6-bb43-53b205efed50)] get_binding_level_objs /usr/lib/python3.6/site-packages/neutron/plugins/ml2/db.py:99
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.756 15 DEBUG networking_ovn.ml2.mech_driver [req-ad287d20-5c6a-45fb-9913-9b7cf94537de - - - - -] No provisioning block for port 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 since it is active _is_port_provisioning_required /usr/lib/python3.6/site-packages/networking_ovn/ml2/mech_driver.py:602
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.918 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=0): CheckRevisionNumberCommand(name=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, resource={'id': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'name': 'TEST_PORT', 'network_id': '3915bac2-cdf8-4e4c-be65-24d8de031a02', 'tenant_id': 'd697d970e6074604bd102d1d9968dfd0', 'mac_address': 'fa:16:3e:23:cd:bb', 'admin_state_up': True, 'status': 'ACTIVE', 'device_id': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'device_owner': 'compute:nova', 'fixed_ips': [{'subnet_id': '6ceb5bb1-ad74-43c5-a4aa-e9d3134da4e8', 'ip_address': '10.10.10.14'}], 'allowed_address_pairs': [], 'extra_dhcp_opts': [], 'security_groups': ['2fa725fe-abc5-4a22-893a-c2d8de93bd45'], 'description': '', 'binding:vnic_type': 'normal', 'binding:profile': {}, 'binding:host_id': 'computedpdksriov-1.localdomain', 'binding:vif_type': 'vhostuser', 'binding:vif_details': {'port_filter': False, 'vhostuser_mode': 'server', 'vhostuser_ovs_plug': True, 'vhostuser_socket': '/var/lib/vhost_sockets/vhu4b0a4d85-9c'}, 'port_security_enabled': True, 'qos_policy_id': None, 'trunk_details': {'trunk_id': 'dbe0bdf3-0dc2-46b5-8adc-d78ae1c8add0', 'sub_ports': [{'segmentation_id': 125, 'segmentation_type': 'vlan', 'port_id': 'de74fab5-06b1-45c0-bf82-4c30ca53c259', 'mac_address': 'fa:16:3e:bb:9c:92'}]}, 'resource_request': None, 'tags': [], 'created_at': '2023-10-16T19:54:32Z', 'updated_at': '2023-10-16T20:00:03Z', 'revision_number': 4, 'project_id': 'd697d970e6074604bd102d1d9968dfd0', 'network': {'id': '3915bac2-cdf8-4e4c-be65-24d8de031a02', 'name': 'dpdk-mgmt', 'tenant_id': 'd697d970e6074604bd102d1d9968dfd0', 'admin_state_up': True, 'mtu': 8942, 'status': 'ACTIVE', 'subnets': ['6ceb5bb1-ad74-43c5-a4aa-e9d3134da4e8'], 'shared': False, 'availability_zone_hints': [], 'availability_zones': [], 'ipv4_address_scope': None, 'ipv6_address_scope': None, 'router:external': False, 'vlan_transparent': None, 'description': '', 'port_security_enabled': True, 'qos_policy_id': None, 'tags': [], 'created_at': '2023-10-16T16:29:22Z', 'updated_at': '2023-10-16T16:31:29Z', 'revision_number': 2, 'project_id': 'd697d970e6074604bd102d1d9968dfd0', 'provider:network_type': 'geneve', 'provider:physical_network': None, 'provider:segmentation_id': 13667}}, resource_type=ports, if_exists=True) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.919 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=1): SetLSwitchPortCommand(lport=4b0a4d85-9c73-4c9e-a42e-ead2442340e6, external_ids_update=None, columns={'external_ids': {'neutron:port_name': 'TEST_PORT', 'neutron:device_id': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'neutron:project_id': 'd697d970e6074604bd102d1d9968dfd0', 'neutron:cidrs': '10.10.10.14/24', 'neutron:device_owner': 'compute:nova', 'neutron:network_name': 'neutron-3915bac2-cdf8-4e4c-be65-24d8de031a02', 'neutron:security_group_ids': '2fa725fe-abc5-4a22-893a-c2d8de93bd45', 'neutron:revision_number': '4'}, 'parent_name': [], 'tag': [], 'options': {'requested-chassis': 'computedpdksriov-1.localdomain', 'mcast_flood_reports': 'true'}, 'enabled': True, 'port_security': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'dhcpv4_options': [UUID('244e7f23-1ce1-41c5-8fc3-2fb7a948d77c')], 'dhcpv6_options': [], 'type': '', 'addresses': ['fa:16:3e:23:cd:bb 10.10.10.14'], 'ha_chassis_group': []}, if_exists=False) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.920 15 DEBUG ovsdbapp.backend.ovs_idl.transaction [-] Running txn n=1 command(idx=2): PgAddPortCommand(port_group=neutron_pg_drop, lsp=['4b0a4d85-9c73-4c9e-a42e-ead2442340e6'], if_exists=False) do_commit /usr/lib/python3.6/site-packages/ovsdbapp/backend/ovs_idl/transaction.py:88
/var/log/containers/neutron/server.log:2023-10-16 20:00:03.984 15 INFO networking_ovn.db.revision [req-ad287d20-5c6a-45fb-9913-9b7cf94537de - - - - -] Successfully bumped revision number for resource 4b0a4d85-9c73-4c9e-a42e-ead2442340e6 (type: ports) to 4
/var/log/containers/neutron/server.log:2023-10-16 20:00:04.119 15 DEBUG neutron.api.rpc.handlers.resources_rpc [req-645e76c9-9f1f-44a2-a75b-1006113220df - - - - -] Pushing event updated for resources: {'Port': ['ID=4b0a4d85-9c73-4c9e-a42e-ead2442340e6,revision_number=4']} push /usr/lib/python3.6/site-packages/neutron/api/rpc/handlers/resources_rpc.py:243
/var/log/containers/neutron/server.log:2023-10-16 20:00:05.744 15 DEBUG neutron.notifiers.nova [-] Sending events: [{'server_uuid': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'name': 'network-vif-plugged', 'status': 'completed', 'tag': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6'}] send_events /usr/lib/python3.6/site-packages/neutron/notifiers/nova.py:279
/var/log/containers/neutron/server.log:2023-10-16 20:00:05.785 15 INFO neutron.notifiers.nova [-] Nova event matching ['req-64e88333-342d-4f8d-9256-faa55ca6b57a'] response: {'server_uuid': '7b8ef459-375d-4044-8a07-18b6f222f4d8', 'name': 'network-vif-plugged', 'status': 'completed', 'tag': '4b0a4d85-9c73-4c9e-a42e-ead2442340e6', 'code': 200}
/var/log/containers/neutron/server.log:2023-10-16 20:03:20.331 16 INFO neutron.wsgi [req-d42179b8-f9eb-4b81-9a0c-437ecd1d79f9 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] 10.10.120.124,10.10.120.157 "GET /v2.0/floatingips?fixed_ip_address=10.10.10.14&port_id=4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 193 time: 0.0692675
/var/log/containers/neutron/server.log:2023-10-16 20:06:19.950 15 INFO neutron.wsgi [req-aaf12f84-9f96-4178-bcbb-8cdbf101eb22 8ee28e1d57d54e22a7df781215ae385a 8d6fb529619041d9831c32b05aff44a4 - default default] 10.10.120.124,10.10.120.157 "GET /v2.0/floatingips?fixed_ip_address=10.10.10.14&port_id=4b0a4d85-9c73-4c9e-a42e-ead2442340e6 HTTP/1.1" status: 200 len: 193 time: 0.0467505
```
|