Setting admin_state down on port produces no logs

[Roey Dekel]

Tried to check how admin_state down affects HA ports.
Noticed that management data between them stoped and cause them to become master. Although traffic to connected floating IP remain working.
Problem is: no log on OVS agent idicated why it's processing a port update or why it's setting it's VLAN tag to 4095.
(06:39:44 PM) amuller: there should be an INFO level log saying something like: "Setting port admin_state to {True/False}"(06:39:56 PM) amuller: with the port ID of course

Current log:
2015-06-08 10:25:25.782 1055 INFO neutron.plugins.openvswitch.agent.ovs_neutron_agent [req-b5a70070-2c49-47f2-9c77-49ba88851f4b ] Port 'ha-8e0f96c5-78' has lost its vlan tag '1'!
2015-06-08 10:25:25.783 1055 INFO neutron.agent.securitygroups_rpc [req-b5a70070-2c49-47f2-9c77-49ba88851f4b ] Refresh firewall rules
2015-06-08 10:25:26.784 1055 INFO neutron.plugins.openvswitch.agent.ovs_neutron_agent [req-b5a70070-2c49-47f2-9c77-49ba88851f4b ] Port 8e0f96c5-7891-46a4-8420-778454949bd0 updated. Details: {u'profile': {}, u'allowed_address_pairs': [], u'admin_state_up': False, u'network_id': u'6a5116a2-39f7-45bc-a432-3d624765d602', u'segmentation_id': 10, u'device_owner': u'network:router_ha_interface', u'physical_network': None, u'mac_address': u'fa:16:3e:02:cb:47', u'device': u'8e0f96c5-7891-46a4-8420-778454949bd0', u'port_security_enabled': True, u'port_id': u'8e0f96c5-7891-46a4-8420-778454949bd0', u'fixed_ips': [{u'subnet_id': u'f81913ba-328f-4374-96f2-1a7fd44d7fb1', u'ip_address': u'169.254.192.3'}], u'network_type': u'vxlan'}
2015-06-08 10:25:26.940 1055 INFO neutron.plugins.openvswitch.agent.ovs_neutron_agent [req-b5a70070-2c49-47f2-9c77-49ba88851f4b ] Configuration for device 8e0f96c5-7891-46a4-8420-778454949bd0 completed.

[Assaf Muller (amuller)]

Additional info / summary: When setting the admin_state to False on a port, the OVS agents are very confusing as there is no explicit message about the agent reacting to the event because of the admin state change, and no message explaining why its setting the port's vlan to 4095. It seems like an error and not an operation caused by the API.

[Assaf Muller (amuller)]

Ping Srilatha Tangirala. Are you taking care of this?

[Miguel Angel Ajo (mangelajo)]

Would it also make any sense to protect ha router internal ports from admin_down setting?, is there any value on allowing such thing?

[Assaf Muller (amuller)]

Special casing HA ports when setting admin_state is just so ugly I can't bring myself to suggest it.

[Hynek Mlnarik (hmlnarik-s)]

I suggest INFO message should be only logged when port admin state is disabled, not when it is enabled.

There is already an INFO message in OVS neutron agent logged upon change of admin state containing the state - it is in the details of "Port X updated. Details: {...}" message. The confusion in this bug possibly comes from the fact that the admin state is somewhat hidden in the long details map.

Adding extra INFO message only for the case when admin state is disabled would remove the confusion about why port VLAN tag is set dead vlan (4095). Logging extra INFO message upon enabling admin state does not seem useful as there would still be (already existing) message about port update.

[OpenStack Infra (hudson-openstack)]

Fix proposed to branch: master
Review: https://review.openstack.org/266383

[OpenStack Infra (hudson-openstack)]

Reviewed: https://review.openstack.org/266383
Committed: https://git.openstack.org/cgit/openstack/neutron/commit/?id=7ca04daa71910b578303845a363883fa036358f7
Submitter: Jenkins
Branch: master

commit 7ca04daa71910b578303845a363883fa036358f7
Author: Hynek Mlnarik <email address hidden>
Date: Tue Jan 12 09:10:25 2016 +0100

    Log INFO message when setting admin state up flag to False for OVS port

    Disabling admin state can cause confusion in OVS agent logs as to why
    VLAN 4095 is set to a particular port. This commit adds an extra INFO
    log message when admin state is disabled. There is no log emitted
    upon enabling admin state as there is already a detailed log entry
    logged in treat_devices_added_or_updated() function.

    Change-Id: I1301db8fea75af211f66a1914d1f0b706a6d5b36
    Closes-Bug: 1463891

[OpenStack Infra (hudson-openstack)]

Fix proposed to branch: stable/liberty
Review: https://review.openstack.org/266948

[OpenStack Infra (hudson-openstack)]

Fix proposed to branch: stable/kilo
Review: https://review.openstack.org/266957

[Thierry Carrez (ttx)]

This issue was fixed in the openstack/neutron 8.0.0.0b2 development milestone.

[OpenStack Infra (hudson-openstack)]

Reviewed: https://review.openstack.org/266948
Committed: https://git.openstack.org/cgit/openstack/neutron/commit/?id=a370fa302ccbb307eebc039a264af9b5ee16227f
Submitter: Jenkins
Branch: stable/liberty

commit a370fa302ccbb307eebc039a264af9b5ee16227f
Author: Hynek Mlnarik <email address hidden>
Date: Tue Jan 12 09:10:25 2016 +0100

    Log INFO message when setting admin state up flag to False for OVS port

    Disabling admin state can cause confusion in OVS agent logs as to why
    VLAN 4095 is set to a particular port. This commit adds an extra INFO
    log message when admin state is disabled. There is no log emitted
    upon enabling admin state as there is already a detailed log entry
    logged in treat_devices_added_or_updated() function.

    Change-Id: I1301db8fea75af211f66a1914d1f0b706a6d5b36
    Closes-Bug: 1463891
    (cherry picked from commit 7ca04daa71910b578303845a363883fa036358f7)

[Doug Hellmann (doug-hellmann)]

This issue was fixed in the openstack/neutron 7.0.2 release.

[OpenStack Infra (hudson-openstack)]

Reviewed: https://review.openstack.org/266957
Committed: https://git.openstack.org/cgit/openstack/neutron/commit/?id=74bcec35cfacc019872a78a7bb5151f1080878c7
Submitter: Jenkins
Branch: stable/kilo

commit 74bcec35cfacc019872a78a7bb5151f1080878c7
Author: Hynek Mlnarik <email address hidden>
Date: Tue Jan 12 09:10:25 2016 +0100

    Log INFO message when setting admin state up flag to False for OVS port

    Disabling admin state can cause confusion in OVS agent logs as to why
    VLAN 4095 is set to a particular port. This commit adds an extra INFO
    log message when admin state is disabled. There is no log emitted
    upon enabling admin state as there is already a detailed log entry
    logged in treat_devices_added_or_updated() function.

    Change-Id: I1301db8fea75af211f66a1914d1f0b706a6d5b36
    Closes-Bug: 1463891
    (cherry picked from commit 7ca04daa71910b578303845a363883fa036358f7)

[Doug Hellmann (doug-hellmann)]

This issue was fixed in the openstack/neutron 2015.1.4 release.

[OpenStack Infra (hudson-openstack)]

This issue was fixed in the openstack/neutron 2015.1.4 release.