PLAY [Converge] ****************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:42:58 +0000 (0:00:00.017)       0:00:00.017 ******
ok: [instance]

TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
Thursday 02 October 2025  19:42:59 +0000 (0:00:01.160)       0:00:01.177 ******
changed: [instance]

TASK [Check if new ssh keypair exists] *****************************************
Thursday 02 October 2025  19:42:59 +0000 (0:00:00.418)       0:00:01.596 ******

TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
Thursday 02 October 2025  19:42:59 +0000 (0:00:00.031)       0:00:01.628 ******
ok: [instance]

TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
Thursday 02 October 2025  19:43:00 +0000 (0:00:00.341)       0:00:01.969 ******
ok: [instance]

TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
Thursday 02 October 2025  19:43:00 +0000 (0:00:00.022)       0:00:01.991 ******
skipping: [instance]

TASK [Add the crc host dynamically] ********************************************
Thursday 02 October 2025  19:43:00 +0000 (0:00:00.028)       0:00:02.020 ******
changed: [instance]

TASK [Fetch crc network facts] *************************************************
Thursday 02 October 2025  19:43:00 +0000 (0:00:00.025)       0:00:02.045 ******
ok: [instance -> crc]

TASK [Load shared variables] ***************************************************
Thursday 02 October 2025  19:43:05 +0000 (0:00:04.756)       0:00:06.802 ******
ok: [instance]

TASK [Call ci_multus role] *****************************************************
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.023)       0:00:06.825 ******

TASK [ci_multus : Create manifests directory] **********************************
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.078)       0:00:06.904 ******
changed: [instance]

TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.353)       0:00:07.258 ******
skipping: [instance]

TASK [networking_mapper : Check for Networking Definition file existance] ******
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.040)       0:00:07.298 ******
skipping: [instance]

TASK [networking_mapper : Load the Networking Definition from file] ************
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.032)       0:00:07.331 ******
skipping: [instance]

TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.040)       0:00:07.371 ******
skipping: [instance]

TASK [ci_multus : Gather network layout from OCP host or default networks] *****
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.044)       0:00:07.416 ******
ok: [instance]

TASK [ci_multus : Merge any available multus net info patches] *****************
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.055)       0:00:07.472 ******
ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'macvlan'}}}})
ok: [instance] => (item={'bridge-to-linux-bridge': {'gw_v4': '192.168.122.1', 'network_name': 'bridge-to-linux-bridge', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth1', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge', 'multus_attach': 'linux-bridge'}}}})

TASK [ci_multus : Remove any networks without Multus networking defined] *******
Thursday 02 October 2025  19:43:05 +0000 (0:00:00.231)       0:00:07.703 ******
ok: [instance]

TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
Thursday 02 October 2025  19:43:06 +0000 (0:00:00.098)       0:00:07.802 ******
ok: [instance]

TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
Thursday 02 October 2025  19:43:06 +0000 (0:00:00.072)       0:00:07.874 ******
ok: [instance]

TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
Thursday 02 October 2025  19:43:06 +0000 (0:00:00.109)       0:00:07.984 ******
changed: [instance]

TASK [ci_multus : Create the multus namespace] *********************************
Thursday 02 October 2025  19:43:06 +0000 (0:00:00.619)       0:00:08.604 ******
changed: [instance]

TASK [ci_multus : Apply network attachment definition manifests] ***************
Thursday 02 October 2025  19:43:07 +0000 (0:00:01.102)       0:00:09.706 ******
changed: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=16   changed=6    unreachable=0    failed=0    skipped=5    rescued=0    ignored=0

Thursday 02 October 2025  19:43:08 +0000 (0:00:00.815)       0:00:10.522 ******
===============================================================================
Fetch crc network facts ------------------------------------------------- 4.76s
Gathering Facts --------------------------------------------------------- 1.16s
ci_multus : Create the multus namespace --------------------------------- 1.10s
ci_multus : Apply network attachment definition manifests --------------- 0.82s
ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.62s
Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.42s
ci_multus : Create manifests directory ---------------------------------- 0.35s
recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.34s
ci_multus : Merge any available multus net info patches ----------------- 0.23s
ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.11s
ci_multus : Remove any networks without Multus networking defined ------- 0.10s
Call ci_multus role ----------------------------------------------------- 0.08s
ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.07s
ci_multus : Gather network layout from OCP host or default networks ----- 0.06s
networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
networking_mapper : Load the Networking Definition from file ------------ 0.04s
networking_mapper : Check for Networking Environment Definition file existence --- 0.04s
networking_mapper : Check for Networking Definition file existance ------ 0.03s
Check if new ssh keypair exists ----------------------------------------- 0.03s
recognize_ssh_keypair : Set fact if new keypair does not exists --------- 0.03s

PLAY [Verify] ******************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:43:09 +0000 (0:00:00.017)       0:00:00.017 ******
ok: [instance]

TASK [Load shared variables] ***************************************************
Thursday 02 October 2025  19:43:10 +0000 (0:00:01.121)       0:00:01.138 ******
ok: [instance]

TASK [Fetch files stat results] ************************************************
Thursday 02 October 2025  19:43:10 +0000 (0:00:00.025)       0:00:01.164 ******
ok: [instance]

TASK [Assert that expected file exist] *****************************************
Thursday 02 October 2025  19:43:10 +0000 (0:00:00.378)       0:00:01.543 ******
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Fetch file content] ******************************************************
Thursday 02 October 2025  19:43:10 +0000 (0:00:00.036)       0:00:01.579 ******
ok: [instance]

TASK [Set _ci_multus_nad variable] *********************************************
Thursday 02 October 2025  19:43:11 +0000 (0:00:00.361)       0:00:01.940 ******
ok: [instance]

TASK [Assert expected number of Network Attachment Definitions are created] ****
Thursday 02 October 2025  19:43:11 +0000 (0:00:00.036)       0:00:01.977 ******
ok: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:43:11 +0000 (0:00:00.057)       0:00:02.035 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.12s
Fetch files stat results ------------------------------------------------ 0.38s
Fetch file content ------------------------------------------------------ 0.36s
Assert expected number of Network Attachment Definitions are created ---- 0.06s
Set _ci_multus_nad variable --------------------------------------------- 0.04s
Assert that expected file exist ----------------------------------------- 0.04s
Load shared variables --------------------------------------------------- 0.03s

PLAY [Verify CRC] **************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:43:12 +0000 (0:00:00.019)       0:00:00.019 ******
ok: [instance]

TASK [Include default vars] ****************************************************
Thursday 02 October 2025  19:43:13 +0000 (0:00:01.026)       0:00:01.046 ******
ok: [instance]

TASK [Check if NADs were created] **********************************************
Thursday 02 October 2025  19:43:13 +0000 (0:00:00.042)       0:00:01.088 ******
ok: [instance]

TASK [Store output spec] *******************************************************
Thursday 02 October 2025  19:43:14 +0000 (0:00:00.944)       0:00:02.033 ******
ok: [instance]

TASK [Ensure both lists have the same length] **********************************
Thursday 02 October 2025  19:43:14 +0000 (0:00:00.061)       0:00:02.095 ******
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Compare each corresponding element in the lists] *************************
Thursday 02 October 2025  19:43:14 +0000 (0:00:00.068)       0:00:02.163 ******
ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}', '{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
  ansible_loop_var: item
  item:
  - |-
    {
      "cniVersion": "0.3.1",
      "name": "bridge-to-linux-bridge",
      "type": "bridge",
      "bridge": "bridge-to-linux-bridge",
      "ipam": {
        "type": "whereabouts",
        "range": "192.168.122.0/24",
        "range_start": "192.168.122.30",
        "range_end": "192.168.122.70"
      }
    }
  - |-
    {
      "cniVersion": "0.3.1",
      "name": "bridge-to-linux-bridge",
      "type": "bridge",
      "bridge": "bridge-to-linux-bridge",
      "ipam": {
        "type": "whereabouts",
        "range": "192.168.122.0/24",
        "range_start": "192.168.122.30",
        "range_end": "192.168.122.70"
      }
    }
  msg: All assertions passed
ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
  ansible_loop_var: item
  item:
  - |-
    {
      "cniVersion": "0.3.1",
      "name": "default",
      "type": "bridge",
      "bridge": "eth0",
      "ipam": {
        "type": "whereabouts",
        "range": "192.168.122.0/24",
        "range_start": "192.168.122.30",
        "range_end": "192.168.122.70"
      }
    }
  - |-
    {
      "cniVersion": "0.3.1",
      "name": "default",
      "type": "bridge",
      "bridge": "eth0",
      "ipam": {
        "type": "whereabouts",
        "range": "192.168.122.0/24",
        "range_start": "192.168.122.30",
        "range_end": "192.168.122.70"
      }
    }
  msg: All assertions passed
ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}']) => changed=false
  ansible_loop_var: item
  item:
  - |-
    {
      "cniVersion": "0.3.1",
      "name": "patchnetwork",
      "type": "macvlan",
      "master": "eth2",
      "ipam": {
        "type": "whereabouts",
        "range": "192.168.122.0/24",
        "range_start": "192.168.122.30",
        "range_end": "192.168.122.70"
      }
    }
  - |-
    {
      "cniVersion": "0.3.1",
      "name": "patchnetwork",
      "type": "macvlan",
      "master": "eth2",
      "ipam": {
        "type": "whereabouts",
        "range": "192.168.122.0/24",
        "range_start": "192.168.122.30",
        "range_end": "192.168.122.70"
      }
    }
  msg: All assertions passed

TASK [Create a test pod to attach a network] ***********************************
Thursday 02 October 2025  19:43:14 +0000 (0:00:00.055)       0:00:02.219 ******
[WARNING]: unknown field "api_version"
FAILED - RETRYING: [instance]: Create a test pod to attach a network (30 retries left).
FAILED - RETRYING: [instance]: Create a test pod to attach a network (29 retries left).
FAILED - RETRYING: [instance]: Create a test pod to attach a network (28 retries left).
changed: [instance]

TASK [Assert that test pod has the additional network] *************************
Thursday 02 October 2025  19:44:35 +0000 (0:01:21.261)       0:01:23.481 ******
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Delete test pod] *********************************************************
Thursday 02 October 2025  19:44:35 +0000 (0:00:00.045)       0:01:23.526 ******
changed: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:44:36 +0000 (0:00:00.910)       0:01:24.437 ******
===============================================================================
Create a test pod to attach a network ---------------------------------- 81.26s
Gathering Facts --------------------------------------------------------- 1.03s
Check if NADs were created ---------------------------------------------- 0.94s
Delete test pod --------------------------------------------------------- 0.91s
Ensure both lists have the same length ---------------------------------- 0.07s
Store output spec ------------------------------------------------------- 0.06s
Compare each corresponding element in the lists ------------------------- 0.06s
Assert that test pod has the additional network ------------------------- 0.05s
Include default vars ---------------------------------------------------- 0.04s

PLAY [Clean] *******************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:37 +0000 (0:00:00.018)       0:00:00.018 ******
ok: [instance]

TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
Thursday 02 October 2025  19:44:38 +0000 (0:00:01.087)       0:00:01.105 ******
changed: [instance]

TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
Thursday 02 October 2025  19:44:38 +0000 (0:00:00.345)       0:00:01.450 ******
ok: [instance]

TASK [ci_multus : Cleanup - Delete NADs] ***************************************
Thursday 02 October 2025  19:44:39 +0000 (0:00:00.891)       0:00:02.342 ******
changed: [instance] => (item=bridge-to-linux-bridge)
changed: [instance] => (item=default)
changed: [instance] => (item=patchnetwork)

TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
Thursday 02 October 2025  19:44:42 +0000 (0:00:02.693)       0:00:05.035 ******
changed: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:44:42 +0000 (0:00:00.436)       0:00:05.471 ******
===============================================================================
ci_multus : Cleanup - Delete NADs --------------------------------------- 2.69s
Gathering Facts --------------------------------------------------------- 1.09s
ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.89s
ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.44s
Backup NAD before cleanup so they can be inspected in CI ---------------- 0.35s

PLAY [Verify Clean] ************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:43 +0000 (0:00:00.017)       0:00:00.017 ******
ok: [instance]

TASK [Include default vars] ****************************************************
Thursday 02 October 2025  19:44:44 +0000 (0:00:01.169)       0:00:01.187 ******
ok: [instance]

TASK [Fetch file stat results] *************************************************
Thursday 02 October 2025  19:44:44 +0000 (0:00:00.032)       0:00:01.219 ******
ok: [instance]

TASK [Assert that created file are removed by cleanup] *************************
Thursday 02 October 2025  19:44:44 +0000 (0:00:00.346)       0:00:01.566 ******
ok: [instance] => changed=false
  msg: All assertions passed

PLAY RECAP *********************************************************************
instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:44:44 +0000 (0:00:00.049)       0:00:01.616 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.17s
Fetch file stat results ------------------------------------------------- 0.35s
Assert that created file are removed by cleanup ------------------------- 0.05s
Include default vars ---------------------------------------------------- 0.03s

PLAY [Verify Clean CRC] ********************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:45 +0000 (0:00:00.016)       0:00:00.016 ******
ok: [instance]

TASK [Include default vars] ****************************************************
Thursday 02 October 2025  19:44:46 +0000 (0:00:00.990)       0:00:01.006 ******
ok: [instance]

TASK [Check if NADs were deleted] **********************************************
Thursday 02 October 2025  19:44:46 +0000 (0:00:00.028)       0:00:01.035 ******
ok: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=3    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:44:47 +0000 (0:00:00.922)       0:00:01.957 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 0.99s
Check if NADs were deleted ---------------------------------------------- 0.92s
Include default vars ---------------------------------------------------- 0.03s

PLAY [Converge] ****************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:47 +0000 (0:00:00.016)       0:00:00.016 ******
ok: [instance]

TASK [Load shared variables] ***************************************************
Thursday 02 October 2025  19:44:49 +0000 (0:00:01.065)       0:00:01.081 ******
ok: [instance]

TASK [Call ci_multus role] *****************************************************
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.030)       0:00:01.112 ******

TASK [ci_multus : Create manifests directory] **********************************
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.058)       0:00:01.171 ******
changed: [instance]

TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.368)       0:00:01.540 ******
skipping: [instance]

TASK [networking_mapper : Check for Networking Definition file existance] ******
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.032)       0:00:01.572 ******
skipping: [instance]

TASK [networking_mapper : Load the Networking Definition from file] ************
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.028)       0:00:01.600 ******
skipping: [instance]

TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.029)       0:00:01.630 ******
skipping: [instance]

TASK [ci_multus : Gather network layout from OCP host or default networks] *****
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.043)       0:00:01.673 ******
ok: [instance]

TASK [ci_multus : Merge any available multus net info patches] *****************
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.048)       0:00:01.721 ******
ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}]}}}})

TASK [ci_multus : Remove any networks without Multus networking defined] *******
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.152)       0:00:01.874 ******
ok: [instance]

TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.095)       0:00:01.969 ******
ok: [instance]

TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
Thursday 02 October 2025  19:44:49 +0000 (0:00:00.076)       0:00:02.046 ******
ok: [instance]

TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
Thursday 02 October 2025  19:44:50 +0000 (0:00:00.079)       0:00:02.126 ******
changed: [instance]

TASK [ci_multus : Create the multus namespace] *********************************
Thursday 02 October 2025  19:44:50 +0000 (0:00:00.729)       0:00:02.855 ******
skipping: [instance]

TASK [ci_multus : Apply network attachment definition manifests] ***************
Thursday 02 October 2025  19:44:50 +0000 (0:00:00.022)       0:00:02.877 ******
skipping: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0

Thursday 02 October 2025  19:44:50 +0000 (0:00:00.050)       0:00:02.928 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.07s
ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.73s
ci_multus : Create manifests directory ---------------------------------- 0.37s
ci_multus : Merge any available multus net info patches ----------------- 0.15s
ci_multus : Remove any networks without Multus networking defined ------- 0.10s
ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.08s
ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
Call ci_multus role ----------------------------------------------------- 0.06s
ci_multus : Apply network attachment definition manifests --------------- 0.05s
ci_multus : Gather network layout from OCP host or default networks ----- 0.05s
networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
Load shared variables --------------------------------------------------- 0.03s
networking_mapper : Load the Networking Definition from file ------------ 0.03s
networking_mapper : Check for Networking Definition file existance ------ 0.03s
ci_multus : Create the multus namespace --------------------------------- 0.02s

PLAY [Verify] ******************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:51 +0000 (0:00:00.016)       0:00:00.016 ******
ok: [instance]

TASK [Load shared variables] ***************************************************
Thursday 02 October 2025  19:44:52 +0000 (0:00:01.086)       0:00:01.102 ******
ok: [instance]

TASK [Fetch files stat results] ************************************************
Thursday 02 October 2025  19:44:52 +0000 (0:00:00.025)       0:00:01.128 ******
ok: [instance]

TASK [Assert that expected file exist] *****************************************
Thursday 02 October 2025  19:44:52 +0000 (0:00:00.369)       0:00:01.497 ******
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Fetch file content] ******************************************************
Thursday 02 October 2025  19:44:52 +0000 (0:00:00.023)       0:00:01.521 ******
ok: [instance]

TASK [Set _ci_multus_nad variable] *********************************************
Thursday 02 October 2025  19:44:53 +0000 (0:00:00.359)       0:00:01.880 ******
ok: [instance]

TASK [Assert expected number of Network Attachment Definitions are created] ****
Thursday 02 October 2025  19:44:53 +0000 (0:00:00.044)       0:00:01.925 ******
ok: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:44:53 +0000 (0:00:00.060)       0:00:01.985 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.09s
Fetch files stat results ------------------------------------------------ 0.37s
Fetch file content ------------------------------------------------------ 0.36s
Assert expected number of Network Attachment Definitions are created ---- 0.06s
Set _ci_multus_nad variable --------------------------------------------- 0.04s
Load shared variables --------------------------------------------------- 0.03s
Assert that expected file exist ----------------------------------------- 0.02s

PLAY [Clean] *******************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:54 +0000 (0:00:00.015)       0:00:00.015 ******
ok: [instance]

TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
Thursday 02 October 2025  19:44:55 +0000 (0:00:01.102)       0:00:01.118 ******
changed: [instance]

TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
Thursday 02 October 2025  19:44:55 +0000 (0:00:00.350)       0:00:01.468 ******
skipping: [instance]

TASK [ci_multus : Cleanup - Delete NADs] ***************************************
Thursday 02 October 2025  19:44:55 +0000 (0:00:00.028)       0:00:01.497 ******
skipping: [instance]

TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
Thursday 02 October 2025  19:44:55 +0000 (0:00:00.032)       0:00:01.530 ******
changed: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0

Thursday 02 October 2025  19:44:55 +0000 (0:00:00.364)       0:00:01.894 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.10s
ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.36s
Backup NAD before cleanup so they can be inspected in CI ---------------- 0.35s
ci_multus : Cleanup - Delete NADs --------------------------------------- 0.03s
ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s

PLAY [Verify Clean] ************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:56 +0000 (0:00:00.018)       0:00:00.018 ******
ok: [instance]

TASK [Include default vars] ****************************************************
Thursday 02 October 2025  19:44:57 +0000 (0:00:01.127)       0:00:01.145 ******
ok: [instance]

TASK [Fetch file stat results] *************************************************
Thursday 02 October 2025  19:44:57 +0000 (0:00:00.030)       0:00:01.176 ******
ok: [instance]

TASK [Assert that created file are removed by cleanup] *************************
Thursday 02 October 2025  19:44:58 +0000 (0:00:00.337)       0:00:01.513 ******
ok: [instance] => changed=false
  msg: All assertions passed

PLAY RECAP *********************************************************************
instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:44:58 +0000 (0:00:00.061)       0:00:01.575 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.13s
Fetch file stat results ------------------------------------------------- 0.34s
Assert that created file are removed by cleanup ------------------------- 0.06s
Include default vars ---------------------------------------------------- 0.03s

PLAY [Converge] ****************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:44:58 +0000 (0:00:00.017)       0:00:00.017 ******
ok: [instance]

TASK [Load shared variables] ***************************************************
Thursday 02 October 2025  19:45:00 +0000 (0:00:02.074)       0:00:02.091 ******
ok: [instance]

TASK [Call ci_multus role] *****************************************************
Thursday 02 October 2025  19:45:00 +0000 (0:00:00.026)       0:00:02.118 ******

TASK [ci_multus : Create manifests directory] **********************************
Thursday 02 October 2025  19:45:00 +0000 (0:00:00.059)       0:00:02.177 ******
changed: [instance]

TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.409)       0:00:02.587 ******
skipping: [instance]

TASK [networking_mapper : Check for Networking Definition file existance] ******
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.024)       0:00:02.612 ******
skipping: [instance]

TASK [networking_mapper : Load the Networking Definition from file] ************
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.028)       0:00:02.641 ******
skipping: [instance]

TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.027)       0:00:02.669 ******
skipping: [instance]

TASK [ci_multus : Gather network layout from OCP host or default networks] *****
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.032)       0:00:02.701 ******
ok: [instance]

TASK [ci_multus : Merge any available multus net info patches] *****************
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.038)       0:00:02.739 ******
skipping: [instance] => (item={'default': {'interface_name': 'eth1', 'network_name': 'default', 'gw_v6': 'fdc0:8b54:108a:c949:0000:0000:0000:0001', 'network_v6': 'fdc0:8b54:108a:c949::/64', 'tools': {'multus': {'ipv6_ranges': [{'start': 'fdc0:8b54:108a:c949:0000:0000:0000:001e', 'end': 'fdc0:8b54:108a:c949:0000:0000:0000:0027'}]}}}})
skipping: [instance]

TASK [ci_multus : Remove any networks without Multus networking defined] *******
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.105)       0:00:02.845 ******
ok: [instance]

TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.084)       0:00:02.930 ******
ok: [instance]

TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.071)       0:00:03.001 ******
ok: [instance]

TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
Thursday 02 October 2025  19:45:01 +0000 (0:00:00.074)       0:00:03.076 ******
changed: [instance]

TASK [ci_multus : Create the multus namespace] *********************************
Thursday 02 October 2025  19:45:02 +0000 (0:00:00.761)       0:00:03.837 ******
skipping: [instance]

TASK [ci_multus : Apply network attachment definition manifests] ***************
Thursday 02 October 2025  19:45:02 +0000 (0:00:00.024)       0:00:03.861 ******
skipping: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=8    changed=2    unreachable=0    failed=0    skipped=7    rescued=0    ignored=0

Thursday 02 October 2025  19:45:02 +0000 (0:00:00.056)       0:00:03.918 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 2.07s
ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.76s
ci_multus : Create manifests directory ---------------------------------- 0.41s
ci_multus : Merge any available multus net info patches ----------------- 0.11s
ci_multus : Remove any networks without Multus networking defined ------- 0.09s
ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.07s
ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.07s
Call ci_multus role ----------------------------------------------------- 0.06s
ci_multus : Apply network attachment definition manifests --------------- 0.06s
ci_multus : Gather network layout from OCP host or default networks ----- 0.04s
networking_mapper : Set cifmw_networking_env_definition is present ------ 0.03s
networking_mapper : Check for Networking Definition file existance ------ 0.03s
networking_mapper : Load the Networking Definition from file ------------ 0.03s
Load shared variables --------------------------------------------------- 0.03s
networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
ci_multus : Create the multus namespace --------------------------------- 0.02s

PLAY [Verify] ******************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:45:03 +0000 (0:00:00.017)       0:00:00.017 ******
ok: [instance]

TASK [Load shared variables] ***************************************************
Thursday 02 October 2025  19:45:04 +0000 (0:00:01.057)       0:00:01.075 ******
ok: [instance]

TASK [Fetch files stat results] ************************************************
Thursday 02 October 2025  19:45:04 +0000 (0:00:00.027)       0:00:01.103 ******
ok: [instance]

TASK [Assert that expected file exist] *****************************************
Thursday 02 October 2025  19:45:04 +0000 (0:00:00.357)       0:00:01.460 ******
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Fetch file content] ******************************************************
Thursday 02 October 2025  19:45:04 +0000 (0:00:00.026)       0:00:01.487 ******
ok: [instance]

TASK [Set _ci_multus_nad variable] *********************************************
Thursday 02 October 2025  19:45:05 +0000 (0:00:00.287)       0:00:01.775 ******
ok: [instance]

TASK [Assert expected number of Network Attachment Definitions are created] ****
Thursday 02 October 2025  19:45:05 +0000 (0:00:00.031)       0:00:01.807 ******
ok: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:45:05 +0000 (0:00:00.048)       0:00:01.855 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.06s
Fetch files stat results ------------------------------------------------ 0.36s
Fetch file content ------------------------------------------------------ 0.29s
Assert expected number of Network Attachment Definitions are created ---- 0.05s
Set _ci_multus_nad variable --------------------------------------------- 0.03s
Load shared variables --------------------------------------------------- 0.03s
Assert that expected file exist ----------------------------------------- 0.03s

PLAY [Clean] *******************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:45:05 +0000 (0:00:00.017)       0:00:00.017 ******
ok: [instance]

TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
Thursday 02 October 2025  19:45:06 +0000 (0:00:01.029)       0:00:01.046 ******
changed: [instance]

TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
Thursday 02 October 2025  19:45:07 +0000 (0:00:00.404)       0:00:01.451 ******
skipping: [instance]

TASK [ci_multus : Cleanup - Delete NADs] ***************************************
Thursday 02 October 2025  19:45:07 +0000 (0:00:00.029)       0:00:01.481 ******
skipping: [instance]

TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
Thursday 02 October 2025  19:45:07 +0000 (0:00:00.046)       0:00:01.527 ******
changed: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0

Thursday 02 October 2025  19:45:07 +0000 (0:00:00.404)       0:00:01.932 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.03s
ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.40s
Backup NAD before cleanup so they can be inspected in CI ---------------- 0.40s
ci_multus : Cleanup - Delete NADs --------------------------------------- 0.05s
ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s

PLAY [Verify Clean] ************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 02 October 2025  19:45:08 +0000 (0:00:00.014)       0:00:00.014 ******
ok: [instance]

TASK [Include default vars] ****************************************************
Thursday 02 October 2025  19:45:09 +0000 (0:00:01.165)       0:00:01.180 ******
ok: [instance]

TASK [Fetch file stat results] *************************************************
Thursday 02 October 2025  19:45:09 +0000 (0:00:00.035)       0:00:01.215 ******
ok: [instance]

TASK [Assert that created file are removed by cleanup] *************************
Thursday 02 October 2025  19:45:09 +0000 (0:00:00.387)       0:00:01.603 ******
ok: [instance] => changed=false
  msg: All assertions passed

PLAY RECAP *********************************************************************
instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Thursday 02 October 2025  19:45:09 +0000 (0:00:00.058)       0:00:01.661 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.17s
Fetch file stat results ------------------------------------------------- 0.39s
Assert that created file are removed by cleanup ------------------------- 0.06s
Include default vars ---------------------------------------------------- 0.04s