[NETVIRT-1027] IfMgr: In updateInterfaceDpidOfPortInfo, port info not found in Operational Store Uuid Created: 20/Nov/17 Updated: 28/Aug/18 Resolved: 28/Aug/18 |
|
| Status: | Resolved |
| Project: | netvirt |
| Component/s: | General |
| Affects Version/s: | Oxygen |
| Fix Version/s: | Oxygen-SR3, Fluorine |
| Type: | Bug | Priority: | Medium |
| Reporter: | Sam Hague | Assignee: | Unassigned |
| Resolution: | Done | Votes: | 0 |
| Labels: | csit:3node | ||
| Remaining Estimate: | Not Specified | ||
| Time Spent: | Not Specified | ||
| Original Estimate: | Not Specified | ||
| Epic Link: | Clustering Stability |
| Description |
2017-11-20 03:54:35,018 | WARN | eChangeHandler-0 | IfMgr | 347 - org.opendaylight.netvirt.ipv6service-impl - 0.4.3.SNAPSHOT | In updateInterfaceDpidOfPortInfo, port info not found in Operational Store Uuid [_value=65a733ca-6d79-402a-8258-ee3e6427a598]. 2017-11-20 03:54:35,076 | WARN | nPool-1-worker-0 | FlowBasedServicesConfigListener | 326 - org.opendaylight.genius.interfacemanager-impl - 0.2.3.SNAPSHOT | bound-service-state is not present for interface:820d676e-b5c2-4dcd-8457-131b6beb629b, service-mode:class org.opendaylight.yang.gen.v1.urn.opendaylight.genius.interfacemanager.servicebinding.rev160406.ServiceModeIngress, service-name:ipv6.820d676e-b5c2-4dcd-8457-131b6beb629b, service-priority:7 2017-11-20 03:54:35,077 | WARN | nPool-1-worker-0 | NeutronvpnManager | 338 - org.opendaylight.netvirt.neutronvpn-impl - 0.4.3.SNAPSHOT | Cannot proceed with deleteVpnInterface for port 820d676e-b5c2-4dcd-8457-131b6beb629b in subnet 8d2ce8a9-5c24-4317-ab42-1fdf22be3085 since port is absent in Neutron config DS 2017-11-20 03:54:35,097 | WARN | nPool-1-worker-2 | FlowBasedServicesConfigListener | 326 - org.opendaylight.genius.interfacemanager-impl - 0.2.3.SNAPSHOT | bound-service-state is not present for interface:820d676e-b5c2-4dcd-8457-131b6beb629b, service-mode:class org.opendaylight.yang.gen.v1.urn.opendaylight.genius.interfacemanager.servicebinding.rev160406.ServiceModeEgress, service-name:acl.ingressacl.820d676e-b5c2-4dcd-8457-131b6beb629b, service-priority:6 2017-11-20 03:54:35,104 | WARN | nPool-1-worker-2 | FlowBasedServicesConfigListener | 326 - org.opendaylight.genius.interfacemanager-impl - 0.2.3.SNAPSHOT | bound-service-state is not present for interface:820d676e-b5c2-4dcd-8457-131b6beb629b, service-mode:class org.opendaylight.yang.gen.v1.urn.opendaylight.genius.interfacemanager.servicebinding.rev160406.ServiceModeIngress, service-name:acl.egressacl.820d676e-b5c2-4dcd-8457-131b6beb629b, service-priority:2 |
| Comments |
| Comment by Faseela K [ 23/Nov/17 ] |
|
This log is coming from netvirt ipv6 module |
| Comment by Sam Hague [ 06/Apr/18 ] |
| Comment by Sam Hague [ 19/Jun/18 ] |
| Comment by Jamo Luhrsen [ 14/Aug/18 ] |
|
SridharG or Karthikeyan any chance you could take a closer look at this? |
| Comment by Karthikeyan Krishnan [ 16/Aug/18 ] |
|
Hi Sam/Jamo, I have analysed the issue observed in build#119 logs and noted the following things LOG.warn("In updateInterfaceDpidOfPortInfo, port info not found in Operational Store {}.", portId); The above warning log message was printing multiple times on following neutron port use cases. 1) DHCP Port Case: ============= At the time of neutron dhcp port creation event(Northbound) received to IPv6 module it is processing and considering as a host port and trying to query IETF interface-state(Operational) DS and it is getting as a null since interface state DS is not keeping the corresponding tap interface. May be later point of the time southbound event will receive for this neutron port. Example log: -------------------- 2018-06-18T05:51:15,552 | INFO | org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.ports.rev150712.ports.attributes.ports.Port_AsyncClusteredDataTreeChangeListenerBase-DataTreeChangeHandler-0 | NeutronPortChangeListener | 365 - org.opendaylight.netvirt.ipv6service-impl - 0.7.0.SNAPSHOT | IPv6: addInterfaceInfo is invoked for a host interface Port{getAllowedAddressPairs=[], getDeviceId=dhcpdfee16d6-aa24-53a5-840f-283ce4bb3117-a4182a31-67fe-424e-9d94-ae268679967d, getDeviceOwner=network:dhcp, getFixedIps=[FixedIps\{getIpAddress=IpAddress{_ipv6Address=Ipv6Address{_value=2003:0:0:ffff::2}}, getSubnetId=Uuid\{_value=af4dc9a2-f2b4-47de-9409-52de0543977d}, augmentations={}}], getMacAddress=MacAddress{_value=fa:16:3e:c3:32:54}, getName=, getNetworkId=Uuid{_value=a4182a31-67fe-424e-9d94-ae268679967d}, getProjectId=37dcd4237b5c4fc4b349bcf01c93929c, getSecurityGroups=[], getStatus=ACTIVE, getTenantId=Uuid{_value=37dcd423-7b5c-4fc4-b349-bcf01c93929c}, getUuid=Uuid{_value=3ca7a2d7-37f7-4c7b-805b-b32859de1e9e}, isAdminStateUp=true, augmentations={interface org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.binding.rev150712.PortBindingExtension=PortBindingExtension{getHostId=releng-19653-119-1-devstack-pike-0.vexxhost.net, getProfile={}, getVifDetails=[], getVifType=unbound, getVnicType=normal}, interface org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.portsecurity.rev150712.PortSecurityExtension=PortSecurityExtension{isPortSecurityEnabled=false}}}, fixedIp: FixedIps{getIpAddress=IpAddress{_ipv6Address=Ipv6Address{_value=2003:0:0:ffff::2}}, getSubnetId=Uuid{_value=af4dc9a2-f2b4-47de-9409-52de0543977d}, augmentations={}}
2018-06-18T05:51:15,683 | WARN | org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.ports.rev150712.ports.attributes.ports.Port_AsyncClusteredDataTreeChangeListenerBase-DataTreeChangeHandler-0 | IfMgr | 365 - org.opendaylight.netvirt.ipv6service-impl - 0.7.0.SNAPSHOT | In updateInterfaceDpidOfPortInfo, port info not found in Operational Store Uuid{_value=3ca7a2d7-37f7-4c7b-805b-b32859de1e9e}.
Conclusion: =========== This openstack neutron q-dhcp created port processing is ignored recently and the corresponding fix [0] got merged into both oxygen and fluorine branch. This issue is not being observed in recent logs #146, #147 [0] https://git.opendaylight.org/gerrit/#/c/73439/
2) Unknown Device Owner Use Case: =============================== I have observed some of neutron config port event is received with unknown device owner and as a result it is considering as a hots interface and it is trying to query the IETF interface state DS and it is getting a result as null and printing a warn log message again for this use case as well. 2018-06-18T05:51:31,047 | INFO | org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.ports.rev150712.ports.attributes.ports.Port_AsyncClusteredDataTreeChangeListenerBase-DataTreeChangeHandler-0 | NeutronPortChangeListener | 365 - org.opendaylight.netvirt.ipv6service-impl - 0.7.0.SNAPSHOT | IPv6: addInterfaceInfo is invoked for a host interface Port{getAllowedAddressPairs=[], getDeviceId=, getDeviceOwner=, getFixedIps=[FixedIps{getIpAddress=IpAddress{_*ipv6Address=Ipv6Address\{_value=2003::4}*}, getSubnetId=Uuid\{_value=fbf848c7-13e0-41d8-95ab-c47eb18a4478}, augmentations={}}], getMacAddress=MacAddress{_value=fa:16:3e:55:10:c6}, getName=, getNetworkId=Uuid{_value=680e4181-6d26-400f-8666-3e850fbd0aa7}, getProjectId=49a13afc7a2b4c45a397770baecfc9b4, getSecurityGroups=[Uuid\{_value=700d97a0-2cd2-419e-8ff3-6709976a1ece}], getStatus=ACTIVE, getTenantId=Uuid{_value=49a13afc-7a2b-4c45-a397-770baecfc9b4}, getUuid=Uuid{_value=6a080411-2c23-46b1-a14f-b9ac742d7890}, isAdminStateUp=true, augmentations={interface org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.binding.rev150712.PortBindingExtension=PortBindingExtension{getHostId=, getProfile={}, getVifDetails=[], getVifType=unbound, getVnicType=normal}, interface org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.portsecurity.rev150712.PortSecurityExtension=PortSecurityExtension{isPortSecurityEnabled=true}}}, fixedIp: FixedIps{getIpAddress=IpAddress{_ipv6Address=Ipv6Address{_value=2003::4}}, getSubnetId=Uuid{_value=fbf848c7-13e0-41d8-95ab-c47eb18a4478}, augmentations={}} 2018-06-18T05:51:31,080 | WARN | org.opendaylight.yang.gen.v1.urn.opendaylight.neutron.ports.rev150712.ports.attributes.ports.Port_AsyncClusteredDataTreeChangeListenerBase-DataTreeChangeHandler-0 | IfMgr | 365 - org.opendaylight.netvirt.ipv6service-impl - 0.7.0.SNAPSHOT | In updateInterfaceDpidOfPortInfo, port info not found in Operational Store Uuid{_value=6a080411-2c23-46b1-a14f-b9ac742d7890}.
Conclusion: --------------- I am not sure about the above config neutron port event for unknown device owner. Please share your inputs if you know at what circumstance we use to receive this kind of config notification. Anyway in recent logs(Builds #146, #147) i am not able to see this issue is occurring.
Thanks, Karthikeyan.
|
| Comment by Sam Hague [ 28/Aug/18 ] |