RHEL Engineering is moving the tracking of its product development work on RHEL 6 through RHEL 9 to Red Hat Jira (issues.redhat.com). If you're a Red Hat customer, please continue to file support cases via the Red Hat customer portal. If you're not, please head to the "RHEL project" in Red Hat Jira and file new tickets here. Individual Bugzilla bugs in the statuses "NEW", "ASSIGNED", and "POST" are being migrated throughout September 2023. Bugs of Red Hat partners with an assigned Engineering Partner Manager (EPM) are migrated in late September as per pre-agreed dates. Bugs against components "kernel", "kernel-rt", and "kpatch" are only migrated if still in "NEW" or "ASSIGNED". If you cannot log in to RH Jira, please consult article #7032570. That failing, please send an e-mail to the RH Jira admins at rh-issues@redhat.com to troubleshoot your issue as a user management inquiry. The email creates a ServiceNow ticket with Red Hat. Individual Bugzilla bugs that are migrated will be moved to status "CLOSED", resolution "MIGRATED", and set with "MigratedToJIRA" in "Keywords". The link to the successor Jira issue will be found under "Links", have a little "two-footprint" icon next to it, and direct you to the "RHEL project" in Red Hat Jira (issue links are of type "https://issues.redhat.com/browse/RHEL-XXXX", where "X" is a digit). This same link will be available in a blue banner at the top of the page informing you that that bug has been migrated.
Bug 1867016 - Got no callback for `NM.ActiveConnection.deactivate_connection_async()` when deactivating 100 profiles at once
Summary: Got no callback for `NM.ActiveConnection.deactivate_connection_async()` when ...
Keywords:
Status: CLOSED NOTABUG
Alias: None
Product: Red Hat Enterprise Linux 8
Classification: Red Hat
Component: NetworkManager
Version: 8.3
Hardware: x86_64
OS: Linux
medium
medium
Target Milestone: rc
: 8.4
Assignee: Beniamino Galvani
QA Contact: Desktop QE
URL:
Whiteboard:
Depends On:
Blocks: nmstate-nm
TreeView+ depends on / blocked
 
Reported: 2020-08-07 07:10 UTC by Gris Ge
Modified: 2020-09-01 14:55 UTC (History)
8 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2020-09-01 14:55:43 UTC
Type: Bug
Target Upstream Version:
Embargoed:


Attachments (Terms of Use)
300.yml (54.42 KB, text/plain)
2020-08-07 07:11 UTC, Gris Ge
no flags Details
300_absent.yml (56.77 KB, text/plain)
2020-08-07 07:11 UTC, Gris Ge
no flags Details
System logs with NM trace enabled (16.47 MB, text/plain)
2020-08-07 07:59 UTC, Gris Ge
no flags Details

Description Gris Ge 2020-08-07 07:10:39 UTC
Description of problem:

Got no callback after invoked `deactivate_connection_async()` when deactivating
100 profiles at one batch.


Version-Release number of selected component (if applicable):
NetworkManager-1.26.0-0.2.el8.x86_64
nmstate-0.3.4-9.el8.noarch

How reproducible:
100%

Steps to Reproduce:
1. sudo nmstatectl set 300.yml
2. wait 1 minute for STP to finish.
3. sudo nmstatectl set 300_absent.yml

Actual results:

Nmstate complain about timeout on deactivating a dummy profile.

Expected results:

No failure.

Additional info:

The network layout is: dummy0, 300 vlan over dummy0, 300 bridge over each vlan.

In nmstate, only 100 profile deactivation is sent in one batch and nmstate will
wait till all 100 finished to continue on remaining.

By changing dummy0 to veth or real NIC, no such problem.

Comment 1 Gris Ge 2020-08-07 07:11:14 UTC
Created attachment 1710746 [details]
300.yml

Comment 2 Gris Ge 2020-08-07 07:11:38 UTC
Created attachment 1710747 [details]
300_absent.yml

Comment 3 Gris Ge 2020-08-07 07:21:07 UTC
The script `gen_state.py` in https://bugzilla.redhat.com/show_bug.cgi?id=1866269 could be used to generate yml file for nmstatectl to set.

Comment 4 Gris Ge 2020-08-07 07:59:53 UTC
Created attachment 1710756 [details]
System logs with NM trace enabled

Comment 5 Gris Ge 2020-08-07 08:08:29 UTC
The error for above logs is:

libnmstate.error.NmstateTimeoutError: Action {'Deactivate profile: dummy0.279'} timeout

Comment 6 Beniamino Galvani 2020-08-07 11:39:06 UTC
I have added a debug print in nmstate before calling
client.deactivate_connection_async() and one in the callback. I see
them both in the output, meaning that the callback was
invoked:

  2020-08-07 13:27:23,081 root         DEBUG    #### deactivate_connection_async: dummy0.279
  ...
  2020-08-07 13:27:23,186 root         DEBUG    Async queue(100) full, waiting all existing actions to be finished before registering more async action
  2020-08-07 13:27:25,410 root         DEBUG    #### callback: dummy0.279
  2020-08-07 13:27:25,410 root         DEBUG    Connection is not active on dummy0.279, no need to deactivate

Then nmstate fails with:

  libnmstate.error.NmstateTimeoutError: Action {'Deactivate profile: dummy0.279'} timeout

I think NM is behaving correctly in this case and perhaps
nmstate is forgetting to cancel the timer for dummy0.279?

Comment 7 Gris Ge 2020-09-01 14:55:43 UTC
Confirmed. Not a bug of NM.

Nmstate is ignoring that callback.

Sorry for the troubles.


Note You need to log in before you can comment on or make changes to this bug.