PLAY [Prepare] *****************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 04 December 2025  08:05:07 +0000 (0:00:00.022)       0:00:00.022 *****
ok: [instance]

TASK [Create custom basedir] ***************************************************
Thursday 04 December 2025  08:05:08 +0000 (0:00:00.798)       0:00:00.820 *****
changed: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Thursday 04 December 2025  08:05:09 +0000 (0:00:00.297)       0:00:01.118 *****
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Thursday 04 December 2025  08:05:09 +0000 (0:00:00.016)       0:00:01.135 *****
ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)

TASK [test_deps : Detect mirrors file] *****************************************
Thursday 04 December 2025  08:05:09 +0000 (0:00:00.039)       0:00:01.174 *****
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Thursday 04 December 2025  08:05:09 +0000 (0:00:00.248)       0:00:01.423 *****
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Thursday 04 December 2025  08:05:09 +0000 (0:00:00.192)       0:00:01.615 *****
ok: [instance] => (item=ubi-9-appstream)
ok: [instance] => (item=ubi-9-baseos)
ok: [instance] => (item=ubi-9-codeready-builder)

TASK [Deploy repo-setup] *******************************************************
Thursday 04 December 2025  08:05:10 +0000 (0:00:00.615)       0:00:02.230 *****

TASK [repo_setup : Ensure directories are present] *****************************
Thursday 04 December 2025  08:05:10 +0000 (0:00:00.055)       0:00:02.286 *****
changed: [instance] => (item=tmp)
changed: [instance] => (item=artifacts/repositories)
changed: [instance] => (item=venv/repo_setup)

TASK [repo_setup : Make sure git-core package is installed] ********************
Thursday 04 December 2025  08:05:10 +0000 (0:00:00.550)       0:00:02.836 *****
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Thursday 04 December 2025  08:05:11 +0000 (0:00:00.749)       0:00:03.586 *****
changed: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Thursday 04 December 2025  08:05:12 +0000 (0:00:01.061)       0:00:04.647 *****
changed: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Thursday 04 December 2025  08:05:20 +0000 (0:00:07.520)       0:00:12.168 *****
changed: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Thursday 04 December 2025  08:05:20 +0000 (0:00:00.685)       0:00:12.853 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Thursday 04 December 2025  08:05:20 +0000 (0:00:00.020)       0:00:12.874 *****
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Thursday 04 December 2025  08:07:19 +0000 (0:01:58.773)       0:02:11.647 *****
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Thursday 04 December 2025  08:07:19 +0000 (0:00:00.036)       0:02:11.684 *****
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Thursday 04 December 2025  08:07:19 +0000 (0:00:00.035)       0:02:11.720 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Thursday 04 December 2025  08:07:19 +0000 (0:00:00.035)       0:02:11.755 *****
fatal: [instance]: FAILED! => changed=true
  cmd:
  - /opt/basedir/venv/repo-setup/bin/repo-setup-get-hash
  - --dlrn-url
  - https://trunk.rdoproject.org
  - --os-version
  - centos9
  - --release
  - master
  - --tag
  - current-podified
  - --json
  delta: '0:00:17.334881'
  end: '2025-12-04 08:07:37.191108'
  msg: non-zero return code
  rc: 1
  start: '2025-12-04 08:07:19.856227'
  stderr: |-
    Traceback (most recent call last):
      File "/opt/basedir/venv/repo-setup/bin/repo-setup-get-hash", line 10, in <module>
        sys.exit(cli_entrypoint())
      File "/opt/basedir/venv/repo-setup/lib64/python3.9/site-packages/repo_setup/get_hash/__main__.py", line 117, in cli_entrypoint
        main()
      File "/opt/basedir/venv/repo-setup/lib64/python3.9/site-packages/repo_setup/get_hash/__main__.py", line 89, in main
        repo_setup_hash_info = HashInfo(
      File "/opt/basedir/venv/repo-setup/lib64/python3.9/site-packages/repo_setup/get_hash/hash_info.py", line 153, in __init__
        raise HashInvalidDLRNResponse(error_str)
    repo_setup.get_hash.exceptions.HashInvalidDLRNResponse: Invalid response received from the delorean server. Queried URL: https://trunk.rdoproject.org/centos9-master/current-podified/delorean.repo.md5. Response code: -1. Response text: <urlopen error _ssl.c:1130: The handshake operation timed out>. Failed to create HashInfo object.
  stderr_lines: <omitted>
  stdout: '2025-12-04 08:07:37,171 - repo-setup-get-hash - ERROR - Invalid response received from the delorean server. Queried URL: https://trunk.rdoproject.org/centos9-master/current-podified/delorean.repo.md5. Response code: -1. Response text: <urlopen error _ssl.c:1130: The handshake operation timed out>. Failed to create HashInfo object.'
  stdout_lines: <omitted>

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

Thursday 04 December 2025  08:07:37 +0000 (0:00:17.522)       0:02:29.278 *****
===============================================================================
repo_setup : Run repo-setup ------------------------------------------- 118.77s
repo_setup : Run repo-setup-get-hash ----------------------------------- 17.52s
repo_setup : Initialize python venv and install requirements ------------ 7.52s
repo_setup : Get repo-setup repository ---------------------------------- 1.06s
Gathering Facts --------------------------------------------------------- 0.80s
repo_setup : Make sure git-core package is installed -------------------- 0.75s
repo_setup : Install repo-setup package --------------------------------- 0.69s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.62s
repo_setup : Ensure directories are present ----------------------------- 0.55s
Create custom basedir --------------------------------------------------- 0.30s
test_deps : Detect mirrors file ----------------------------------------- 0.25s
test_deps : Disable ubi host subscription-manager integration ----------- 0.19s
Deploy repo-setup ------------------------------------------------------- 0.06s
test_deps : Gather variables for each operating system ------------------ 0.04s
repo_setup : Get component repo ----------------------------------------- 0.04s
repo_setup : Disable component repo in current-podified dlrn repo ------- 0.04s
repo_setup : Rename component repo -------------------------------------- 0.04s
repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider --- 0.02s
test_deps : Gather facts if they don't exist ---------------------------- 0.02s

PLAY [Cleanup] *****************************************************************

TASK [Gathering Facts] *********************************************************
Thursday 04 December 2025  08:07:37 +0000 (0:00:00.014)       0:00:00.014 *****
ok: [instance]

TASK [Ensure we have the destination directory] ********************************
Thursday 04 December 2025  08:07:38 +0000 (0:00:00.797)       0:00:00.811 *****
changed: [instance]

TASK [Copy generated content to proper location] *******************************
Thursday 04 December 2025  08:07:38 +0000 (0:00:00.300)       0:00:01.111 *****
changed: [instance] => (item=/opt/basedir/artifacts)
ok: [instance] => (item=/opt/basedir/logs)
ok: [instance] => (item=/opt/basedir/cifmw_config_drive)
ok: [instance] => (item=/etc/cifmw-dnsmasq.conf)
ok: [instance] => (item=/etc/cifmw-dnsmasq.d)

TASK [libvirt_manager : Get installed packages list] ***************************
Thursday 04 December 2025  08:07:40 +0000 (0:00:01.292)       0:00:02.404 *****
ok: [instance]

TASK [libvirt_manager : Populate service facts] ********************************
Thursday 04 December 2025  08:07:40 +0000 (0:00:00.719)       0:00:03.123 *****
ok: [instance]

TASK [libvirt_manager : Start virtqemud socket service] ************************
Thursday 04 December 2025  08:07:42 +0000 (0:00:01.850)       0:00:04.974 *****
skipping: [instance] => (item=virtqemud.service)
skipping: [instance] => (item=virtqemud.socket)
skipping: [instance]

TASK [libvirt_manager : Set _is_deepscrub internal fact] ***********************
Thursday 04 December 2025  08:07:42 +0000 (0:00:00.066)       0:00:05.041 *****
ok: [instance]

TASK [libvirt_manager : List all of the existing virtual machines] *************
Thursday 04 December 2025  08:07:42 +0000 (0:00:00.058)       0:00:05.100 *****
skipping: [instance]

TASK [libvirt_manager : Get pool configuration] ********************************
Thursday 04 December 2025  08:07:42 +0000 (0:00:00.064)       0:00:05.164 *****
skipping: [instance]

TASK [libvirt_manager : Filter out target environment] *************************
Thursday 04 December 2025  08:07:42 +0000 (0:00:00.070)       0:00:05.235 *****
skipping: [instance]

TASK [libvirt_manager : Expose cleanup list] ***********************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.066)       0:00:05.301 *****
skipping: [instance]

TASK [libvirt_manager : Clean ssh known_hosts] *********************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.066)       0:00:05.367 *****
skipping: [instance]

TASK [libvirt_manager : Destroy machine] ***************************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.067)       0:00:05.435 *****
skipping: [instance]

TASK [libvirt_manager : Undefine machine] **************************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.065)       0:00:05.500 *****
skipping: [instance]

TASK [libvirt_manager : (localhost) Clean ssh jumpers] *************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.063)       0:00:05.564 *****
skipping: [instance]

TASK [libvirt_manager : (instance) Clean ssh jumpers] **************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.104)       0:00:05.668 *****
skipping: [instance]

TASK [libvirt_manager : Get network list] **************************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.062)       0:00:05.730 *****
skipping: [instance]

TASK [libvirt_manager : Filter out target nets] ********************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.061)       0:00:05.791 *****
skipping: [instance]

TASK [libvirt_manager : Expose cleanup list] ***********************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.059)       0:00:05.850 *****
skipping: [instance]

TASK [Remove dnsmasq network bits] *********************************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.063)       0:00:05.914 *****
skipping: [instance]

TASK [libvirt_manager : Destroy networks] **************************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.064)       0:00:05.979 *****
skipping: [instance]

TASK [libvirt_manager : Undefine networks] *************************************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.060)       0:00:06.040 *****
skipping: [instance]

TASK [libvirt_manager : Ensure no trace of networks lays in nmcli] *************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.061)       0:00:06.102 *****
skipping: [instance]

TASK [libvirt_manager : Find dummy interface connection files] *****************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.061)       0:00:06.163 *****
skipping: [instance]

TASK [libvirt_manager : Remove dummy interface connections] ********************
Thursday 04 December 2025  08:07:43 +0000 (0:00:00.059)       0:00:06.222 *****
skipping: [instance]

TASK [libvirt_manager : Clean firewalld libvirt zone] **************************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.061)       0:00:06.284 *****
skipping: [instance]

TASK [libvirt_manager : Ensure firewalld is enabled and started] ***************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.061)       0:00:06.346 *****
skipping: [instance]

TASK [libvirt_manager : Disable masquerade in firewalld default zone] **********
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.058)       0:00:06.405 *****
skipping: [instance]

TASK [libvirt_manager : Disable forward in firewalld libvirt zone] *************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.105)       0:00:06.510 *****
skipping: [instance]

TASK [libvirt_manager : Remove cifmw storage pool] *****************************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.061)       0:00:06.572 *****
skipping: [instance]

TASK [libvirt_manager : Remove overlay images from ocp_volume pools if exists] ***
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.062)       0:00:06.634 *****
skipping: [instance]

TASK [libvirt_manager : Remove ocp_volumes storage pool] ***********************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.043)       0:00:06.678 *****
skipping: [instance]

TASK [libvirt_manager : Refresh all pools after actions] ***********************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.062)       0:00:06.740 *****
skipping: [instance]

TASK [libvirt_manager : Get temporary key status] ******************************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.061)       0:00:06.802 *****
ok: [instance]

TASK [libvirt_manager : Get public key] ****************************************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.294)       0:00:07.096 *****
skipping: [instance]

TASK [libvirt_manager : Remove public key] *************************************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.035)       0:00:07.132 *****
skipping: [instance]

TASK [libvirt_manager : Remove keypair] ****************************************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.034)       0:00:07.167 *****
skipping: [instance] => (item=cifmw_reproducer_key.pub)
skipping: [instance] => (item=cifmw_reproducer_key)
skipping: [instance]

TASK [libvirt_manager : List libvirt logs] *************************************
Thursday 04 December 2025  08:07:44 +0000 (0:00:00.038)       0:00:07.205 *****
[WARNING]: Skipped '/var/log/libvirt/qemu/' path due to this access issue:
'/var/log/libvirt/qemu/' is not a directory
ok: [instance]

TASK [libvirt_manager : Remove detected logs] **********************************
Thursday 04 December 2025  08:07:45 +0000 (0:00:00.347)       0:00:07.553 *****
skipping: [instance]

TASK [libvirt_manager : Remove workload and volumes] ***************************
Thursday 04 December 2025  08:07:45 +0000 (0:00:00.034)       0:00:07.587 *****
ok: [instance] => (item=workload)
ok: [instance] => (item=volumes)
ok: [instance] => (item=artifacts/debug_network_data.yml)
ok: [instance] => (item=artifacts/debug_pub_net_value.txt)
ok: [instance] => (item=artifacts/debug_cifmw_libvirt_manager_layout.yml)
ok: [instance] => (item=artifacts/interfaces-info.yml)
ok: [instance] => (item=artifacts/libvirt-uuids.yml)
ok: [instance] => (item=artifacts/net-map-def-patch.yml)

TASK [libvirt_manager : Deepscrub data] ****************************************
Thursday 04 December 2025  08:07:46 +0000 (0:00:01.424)       0:00:09.012 *****
ok: [instance] => (item=images)
ok: [instance] => (item=artifacts/virtual-nodes.yml)

TASK [podman : Ensure podman is installed] *************************************
Thursday 04 December 2025  08:07:47 +0000 (0:00:00.431)       0:00:09.443 *****
fatal: [instance]: FAILED! => changed=false
  msg: 'Failed to download metadata for repo ''delorean-component-barbican'': Cannot download repomd.xml: Cannot download repodata/repomd.xml: All mirrors were tried'
  rc: 1
  results: []

PLAY RECAP *********************************************************************
instance                   : ok=10   changed=2    unreachable=0    failed=1    skipped=31   rescued=0    ignored=0

Thursday 04 December 2025  08:09:47 +0000 (0:02:00.566)       0:02:10.010 *****
===============================================================================
podman : Ensure podman is installed ----------------------------------- 120.57s
libvirt_manager : Populate service facts -------------------------------- 1.85s
libvirt_manager : Remove workload and volumes --------------------------- 1.42s
Copy generated content to proper location ------------------------------- 1.29s
Gathering Facts --------------------------------------------------------- 0.80s
libvirt_manager : Get installed packages list --------------------------- 0.72s
libvirt_manager : Deepscrub data ---------------------------------------- 0.43s
libvirt_manager : List libvirt logs ------------------------------------- 0.35s
Ensure we have the destination directory -------------------------------- 0.30s
libvirt_manager : Get temporary key status ------------------------------ 0.29s
libvirt_manager : Disable masquerade in firewalld default zone ---------- 0.11s
libvirt_manager : (localhost) Clean ssh jumpers ------------------------- 0.10s
libvirt_manager : Get pool configuration -------------------------------- 0.07s
libvirt_manager : Clean ssh known_hosts --------------------------------- 0.07s
libvirt_manager : Start virtqemud socket service ------------------------ 0.07s
libvirt_manager : Expose cleanup list ----------------------------------- 0.07s
libvirt_manager : Filter out target environment ------------------------- 0.07s
libvirt_manager : Destroy machine --------------------------------------- 0.07s
libvirt_manager : List all of the existing virtual machines ------------- 0.06s
Remove dnsmasq network bits --------------------------------------------- 0.06s