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

TASK [Gathering Facts] *********************************************************
Monday 29 September 2025  19:22:04 +0000 (0:00:00.012)       0:00:00.012 ******
ok: [instance]

TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
Monday 29 September 2025  19:22:05 +0000 (0:00:01.106)       0:00:01.118 ******
changed: [instance]

TASK [Check if new ssh keypair exists] *****************************************
Monday 29 September 2025  19:22:05 +0000 (0:00:00.371)       0:00:01.490 ******

TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
Monday 29 September 2025  19:22:05 +0000 (0:00:00.028)       0:00:01.518 ******
ok: [instance]

TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
Monday 29 September 2025  19:22:06 +0000 (0:00:00.371)       0:00:01.889 ******
ok: [instance]

TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
Monday 29 September 2025  19:22:06 +0000 (0:00:00.023)       0:00:01.913 ******
skipping: [instance]

TASK [Add the crc host dynamically] ********************************************
Monday 29 September 2025  19:22:06 +0000 (0:00:00.026)       0:00:01.939 ******
changed: [instance]

TASK [Fetch crc network facts] *************************************************
Monday 29 September 2025  19:22:06 +0000 (0:00:00.021)       0:00:01.961 ******
ok: [instance -> crc]

TASK [Load shared variables] ***************************************************
Monday 29 September 2025  19:22:10 +0000 (0:00:04.715)       0:00:06.676 ******
ok: [instance]

TASK [Call ci_multus role] *****************************************************
Monday 29 September 2025  19:22:11 +0000 (0:00:00.031)       0:00:06.707 ******

TASK [ci_multus : Create manifests directory] **********************************
Monday 29 September 2025  19:22:11 +0000 (0:00:00.059)       0:00:06.766 ******
changed: [instance]

TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
Monday 29 September 2025  19:22:11 +0000 (0:00:00.362)       0:00:07.129 ******
skipping: [instance]

TASK [networking_mapper : Check for Networking Definition file existance] ******
Monday 29 September 2025  19:22:11 +0000 (0:00:00.027)       0:00:07.157 ******
skipping: [instance]

TASK [networking_mapper : Load the Networking Definition from file] ************
Monday 29 September 2025  19:22:11 +0000 (0:00:00.026)       0:00:07.183 ******
skipping: [instance]

TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
Monday 29 September 2025  19:22:11 +0000 (0:00:00.025)       0:00:07.209 ******
skipping: [instance]

TASK [ci_multus : Gather network layout from OCP host or default networks] *****
Monday 29 September 2025  19:22:11 +0000 (0:00:00.042)       0:00:07.252 ******
ok: [instance]

TASK [ci_multus : Merge any available multus net info patches] *****************
Monday 29 September 2025  19:22:11 +0000 (0:00:00.035)       0:00:07.287 ******
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] *******
Monday 29 September 2025  19:22:11 +0000 (0:00:00.192)       0:00:07.479 ******
ok: [instance]

TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
Monday 29 September 2025  19:22:11 +0000 (0:00:00.091)       0:00:07.571 ******
ok: [instance]

TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
Monday 29 September 2025  19:22:11 +0000 (0:00:00.083)       0:00:07.654 ******
ok: [instance]

TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
Monday 29 September 2025  19:22:12 +0000 (0:00:00.094)       0:00:07.748 ******
changed: [instance]

TASK [ci_multus : Create the multus namespace] *********************************
Monday 29 September 2025  19:22:12 +0000 (0:00:00.742)       0:00:08.491 ******
changed: [instance]

TASK [ci_multus : Apply network attachment definition manifests] ***************
Monday 29 September 2025  19:22:14 +0000 (0:00:01.661)       0:00:10.152 ******
changed: [instance]

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

Monday 29 September 2025  19:22:15 +0000 (0:00:01.013)       0:00:11.166 ******
===============================================================================
Fetch crc network facts ------------------------------------------------- 4.72s
ci_multus : Create the multus namespace --------------------------------- 1.66s
Gathering Facts --------------------------------------------------------- 1.11s
ci_multus : Apply network attachment definition manifests --------------- 1.01s
ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.74s
Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.37s
recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.37s
ci_multus : Create manifests directory ---------------------------------- 0.36s
ci_multus : Merge any available multus net info patches ----------------- 0.19s
ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.09s
ci_multus : Remove any networks without Multus networking defined ------- 0.09s
ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
Call ci_multus role ----------------------------------------------------- 0.06s
networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
ci_multus : Gather network layout from OCP host or default networks ----- 0.04s
Load shared variables --------------------------------------------------- 0.03s
Check if new ssh keypair exists ----------------------------------------- 0.03s
networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
recognize_ssh_keypair : Set fact if new keypair does not exists --------- 0.03s
networking_mapper : Check for Networking Definition file existance ------ 0.03s

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

TASK [Gathering Facts] *********************************************************
Monday 29 September 2025  19:22:16 +0000 (0:00:00.017)       0:00:00.017 ******
ok: [instance]

TASK [Load shared variables] ***************************************************
Monday 29 September 2025  19:22:17 +0000 (0:00:01.048)       0:00:01.066 ******
ok: [instance]

TASK [Fetch files stat results] ************************************************
Monday 29 September 2025  19:22:17 +0000 (0:00:00.040)       0:00:01.106 ******
ok: [instance]

TASK [Assert that expected file exist] *****************************************
Monday 29 September 2025  19:22:17 +0000 (0:00:00.414)       0:00:01.521 ******
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Fetch file content] ******************************************************
Monday 29 September 2025  19:22:17 +0000 (0:00:00.042)       0:00:01.564 ******
ok: [instance]

TASK [Set _ci_multus_nad variable] *********************************************
Monday 29 September 2025  19:22:18 +0000 (0:00:00.370)       0:00:01.934 ******
ok: [instance]

TASK [Assert expected number of Network Attachment Definitions are created] ****
Monday 29 September 2025  19:22:18 +0000 (0:00:00.044)       0:00:01.979 ******
ok: [instance]

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

Monday 29 September 2025  19:22:18 +0000 (0:00:00.078)       0:00:02.057 ******
===============================================================================
Gathering Facts --------------------------------------------------------- 1.05s
Fetch files stat results ------------------------------------------------ 0.41s
Fetch file content ------------------------------------------------------ 0.37s
Assert expected number of Network Attachment Definitions are created ---- 0.08s
Set _ci_multus_nad variable --------------------------------------------- 0.04s
Assert that expected file exist ----------------------------------------- 0.04s
Load shared variables --------------------------------------------------- 0.04s

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

TASK [Gathering Facts] *********************************************************
Monday 29 September 2025  19:22:18 +0000 (0:00:00.015)       0:00:00.015 ******
ok: [instance]

TASK [Include default vars] ****************************************************
Monday 29 September 2025  19:22:20 +0000 (0:00:02.118)       0:00:02.134 ******
ok: [instance]

TASK [Check if NADs were created] **********************************************
Monday 29 September 2025  19:22:20 +0000 (0:00:00.036)       0:00:02.170 ******
ok: [instance]

TASK [Store output spec] *******************************************************
Monday 29 September 2025  19:22:21 +0000 (0:00:00.951)       0:00:03.121 ******
ok: [instance]

TASK [Ensure both lists have the same length] **********************************
Monday 29 September 2025  19:22:21 +0000 (0:00:00.042)       0:00:03.164 ******
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Compare each corresponding element in the lists] *************************
Monday 29 September 2025  19:22:21 +0000 (0:00:00.052)       0:00:03.217 ******
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] ***********************************
Monday 29 September 2025  19:22:22 +0000 (0:00:00.068)       0:00:03.286 ******
fatal: [instance]: FAILED! => changed=false
  msg: 'Failed to create object: b''{"kind":"Status","apiVersion":"v1","metadata":{},"status":"Failure","message":"pods \\"pod-testnad\\" is forbidden: error fetching namespace \\"openstack\\": unable to find annotation openshift.io/sa.scc.uid-range","reason":"Forbidden","details":{"name":"pod-testnad","kind":"pods"},"code":403}\n'''
  reason: Forbidden

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

Monday 29 September 2025  19:22:32 +0000 (0:00:10.887)       0:00:14.173 ******
===============================================================================
Create a test pod to attach a network ---------------------------------- 10.89s
Gathering Facts --------------------------------------------------------- 2.12s
Check if NADs were created ---------------------------------------------- 0.95s
Compare each corresponding element in the lists ------------------------- 0.07s
Ensure both lists have the same length ---------------------------------- 0.05s
Store output spec ------------------------------------------------------- 0.04s
Include default vars ---------------------------------------------------- 0.04s