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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.016)       0:00:00.016 *****
ok: [instance]

TASK [Setup libvirt] ***********************************************************
Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.813)       0:00:00.829 *****
skipping: [instance]

TASK [Setup CRC] ***************************************************************
Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.022)       0:00:00.852 *****
skipping: [instance]

TASK [Start RHOL/CRC] **********************************************************
Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.024)       0:00:00.876 *****
changed: [instance]

TASK [Inject our own NetworkManager configuration] *****************************
Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.416)       0:00:01.292 *****
changed: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.585)       0:00:01.878 *****
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.018)       0:00:01.897 *****
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] *****************************************
Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.042)       0:00:01.939 *****
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Wednesday 08 October 2025  15:50:07 +0000 (0:00:00.201)       0:00:02.141 *****
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Wednesday 08 October 2025  15:50:07 +0000 (0:00:00.322)       0:00:02.463 *****
ok: [instance] => (item=ubi-9-appstream)
ok: [instance] => (item=ubi-9-baseos)
ok: [instance] => (item=ubi-9-codeready-builder)

TASK [Deploy repo-setup] *******************************************************
Wednesday 08 October 2025  15:50:08 +0000 (0:00:00.725)       0:00:03.189 *****

TASK [repo_setup : Ensure directories are present] *****************************
Wednesday 08 October 2025  15:50:08 +0000 (0:00:00.061)       0:00:03.251 *****
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] ********************
Wednesday 08 October 2025  15:50:08 +0000 (0:00:00.589)       0:00:03.840 *****
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Wednesday 08 October 2025  15:50:09 +0000 (0:00:00.908)       0:00:04.749 *****
changed: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Wednesday 08 October 2025  15:50:11 +0000 (0:00:01.211)       0:00:05.960 *****
changed: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Wednesday 08 October 2025  15:50:20 +0000 (0:00:08.987)       0:00:14.948 *****
changed: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Wednesday 08 October 2025  15:50:20 +0000 (0:00:00.741)       0:00:15.690 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Wednesday 08 October 2025  15:50:20 +0000 (0:00:00.032)       0:00:15.723 *****
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.931)       0:00:16.654 *****
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.044)       0:00:16.699 *****
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.044)       0:00:16.744 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.043)       0:00:16.788 *****
changed: [instance]

TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.617)       0:00:17.405 *****
changed: [instance]

TASK [repo_setup : Dump current-podified hash] *********************************
Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.383)       0:00:17.789 *****
skipping: [instance]

TASK [repo_setup : Slurp current podified hash] ********************************
Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.029)       0:00:17.818 *****
skipping: [instance]

TASK [repo_setup : Update the value of full_hash] ******************************
Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.029)       0:00:17.848 *****
skipping: [instance]

TASK [repo_setup : Export hashes facts for further use] ************************
Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.031)       0:00:17.879 *****
ok: [instance]

TASK [repo_setup : Create download directory] **********************************
Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.041)       0:00:17.920 *****
skipping: [instance]

TASK [repo_setup : Print the URL to request] ***********************************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.036)       0:00:17.957 *****
skipping: [instance]

TASK [Download the RPM] ********************************************************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.036)       0:00:17.993 *****
skipping: [instance]

TASK [repo_setup : Install RHOS Release tool] **********************************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.034)       0:00:18.027 *****
skipping: [instance]

TASK [repo_setup : Get rhos-release tool version] ******************************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.033)       0:00:18.060 *****
skipping: [instance]

TASK [repo_setup : Print rhos-release tool version] ****************************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.033)       0:00:18.093 *****
skipping: [instance]

TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.034)       0:00:18.128 *****
skipping: [instance]

TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.031)       0:00:18.159 *****
ok: [instance]

TASK [repo_setup : Use RDO proxy mirrors] **************************************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.203)       0:00:18.363 *****
changed: [instance]

TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.253)       0:00:18.617 *****
changed: [instance]

TASK [repo_setup : Check for gating.repo file on content provider] *************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.282)       0:00:18.900 *****
skipping: [instance]

TASK [repo_setup : Populate gating repo from content provider ip] **************
Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.025)       0:00:18.926 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN repo at the destination] *********************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:18.952 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:18.979 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN component repo] ******************************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:19.005 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.027)       0:00:19.033 *****
skipping: [instance]

TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.032)       0:00:19.066 *****
skipping: [instance]

TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.024)       0:00:19.091 *****
skipping: [instance]

TASK [repo_setup : Cleanup existing metadata] **********************************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.039)       0:00:19.130 *****
skipping: [instance]

TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:19.157 *****
skipping: [instance]

TASK [test_deps : Look for redhat-release rpm] *********************************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.030)       0:00:19.187 *****
skipping: [instance]

TASK [test_deps : Remove redhat-release] ***************************************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.036)       0:00:19.224 *****
skipping: [instance]

TASK [test_deps : Install centos-stream-release] *******************************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.040)       0:00:19.265 *****
skipping: [instance]

TASK [test_deps : Install additional packages] *********************************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.036)       0:00:19.301 *****
skipping: [instance]

TASK [test_deps : Install selinux python libs] *********************************
Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.037)       0:00:19.339 *****
ok: [instance]

TASK [test_deps : Install python yaml libs] ************************************
Wednesday 08 October 2025  15:51:14 +0000 (0:00:50.147)       0:01:09.486 *****
ok: [instance]

TASK [test_deps : Install extra packages] **************************************
Wednesday 08 October 2025  15:51:15 +0000 (0:00:01.401)       0:01:10.888 *****
skipping: [instance]

TASK [ci_setup : Gather variables for each operating system] *******************
Wednesday 08 October 2025  15:51:15 +0000 (0:00:00.046)       0:01:10.934 *****
ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)

TASK [ci_setup : List packages to install] *************************************
Wednesday 08 October 2025  15:51:16 +0000 (0:00:00.049)       0:01:10.984 *****
ok: [instance] =>
  cifmw_ci_setup_packages:
  - bash-completion
  - ca-certificates
  - git-core
  - make
  - tar
  - tmux
  - python3-pip

TASK [ci_setup : Install needed packages] **************************************
Wednesday 08 October 2025  15:51:16 +0000 (0:00:00.023)       0:01:11.007 *****
changed: [instance]

TASK [ci_setup : Gather version of openshift client] ***************************
Wednesday 08 October 2025  15:51:22 +0000 (0:00:06.636)       0:01:17.644 *****
ok: [instance]

TASK [ci_setup : Ensure openshift client install path is present] **************
Wednesday 08 October 2025  15:51:23 +0000 (0:00:00.574)       0:01:18.218 *****
changed: [instance]

TASK [ci_setup : Install openshift client] *************************************
Wednesday 08 October 2025  15:51:23 +0000 (0:00:00.213)       0:01:18.432 *****
changed: [instance]

TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
Wednesday 08 October 2025  15:51:29 +0000 (0:00:06.175)       0:01:24.607 *****
ok: [instance]

TASK [ci_setup : Create completion file] ***************************************
Wednesday 08 October 2025  15:51:29 +0000 (0:00:00.049)       0:01:24.657 *****
changed: [instance]

TASK [ci_setup : Source completion from within .bashrc] ************************
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.300)       0:01:24.957 *****
changed: [instance]

TASK [ci_setup : Check rhsm status] ********************************************
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.320)       0:01:25.278 *****
skipping: [instance]

TASK [ci_setup : Gather the repos to be enabled] *******************************
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.019)       0:01:25.298 *****
skipping: [instance]

TASK [ci_setup : Enabling the required repositories.] **************************
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.018)       0:01:25.316 *****
skipping: [instance]

TASK [ci_setup : Get current /etc/redhat-release] ******************************
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.019)       0:01:25.335 *****
skipping: [instance]

TASK [ci_setup : Print current /etc/redhat-release] ****************************
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.019)       0:01:25.354 *****
skipping: [instance]

TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.020)       0:01:25.375 *****
skipping: [instance]

TASK [ci_setup : Manage directories] *******************************************
Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.025)       0:01:25.401 *****
changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)

TASK [Check for CRC startup] ***************************************************
Wednesday 08 October 2025  15:51:31 +0000 (0:00:01.177)       0:01:26.578 *****
FAILED - RETRYING: [instance]: Check for CRC startup (120 retries left).
FAILED - RETRYING: [instance]: Check for CRC startup (119 retries left).
changed: [instance]

RUNNING HANDLER [Restart NetworkManager] ***************************************
Wednesday 08 October 2025  15:53:12 +0000 (0:01:40.833)       0:03:07.412 *****
changed: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=33   changed=19   unreachable=0    failed=0    skipped=38   rescued=0    ignored=0

Wednesday 08 October 2025  15:53:13 +0000 (0:00:00.712)       0:03:08.125 *****
===============================================================================
Check for CRC startup ------------------------------------------------- 100.83s
test_deps : Install selinux python libs -------------------------------- 50.15s
repo_setup : Initialize python venv and install requirements ------------ 8.99s
ci_setup : Install needed packages -------------------------------------- 6.64s
ci_setup : Install openshift client ------------------------------------- 6.18s
test_deps : Install python yaml libs ------------------------------------ 1.40s
repo_setup : Get repo-setup repository ---------------------------------- 1.21s
ci_setup : Manage directories ------------------------------------------- 1.18s
repo_setup : Run repo-setup --------------------------------------------- 0.93s
repo_setup : Make sure git-core package is installed -------------------- 0.91s
Gathering Facts --------------------------------------------------------- 0.81s
repo_setup : Install repo-setup package --------------------------------- 0.74s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.73s
Restart NetworkManager -------------------------------------------------- 0.71s
repo_setup : Run repo-setup-get-hash ------------------------------------ 0.62s
repo_setup : Ensure directories are present ----------------------------- 0.59s
Inject our own NetworkManager configuration ----------------------------- 0.59s
ci_setup : Gather version of openshift client --------------------------- 0.57s
Start RHOL/CRC ---------------------------------------------------------- 0.42s
repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.38s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:53:13 +0000 (0:00:00.041)       0:00:00.041 *****
ok: [instance]

TASK [Verify crc creds playbook] ***********************************************
Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.918)       0:00:00.960 *****

TASK [rhol_crc : Make sure kubeconfig file exists] *****************************
Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.028)       0:00:00.989 *****
ok: [instance]

TASK [rhol_crc : Warn the user that the kubeconfig files doesn't exist] ********
Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.302)       0:00:01.291 *****
skipping: [instance]

TASK [rhol_crc : Add crc creds in the user bashrc file] ************************
Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.033)       0:00:01.325 *****
changed: [instance]

TASK [rhol_crc : Source bashrc and confirm crc login] **************************
Wednesday 08 October 2025  15:53:15 +0000 (0:00:00.292)       0:00:01.617 *****
ok: [instance]

TASK [rhol_crc : Check bashrc results] *****************************************
Wednesday 08 October 2025  15:53:15 +0000 (0:00:00.592)       0:00:02.209 *****
ok: [instance] =>
  bashrc_result.stdout: |-
    /usr/local/bin/crc
    system:admin

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

Wednesday 08 October 2025  15:53:15 +0000 (0:00:00.044)       0:00:02.254 *****
===============================================================================
Gathering Facts --------------------------------------------------------- 0.92s
rhol_crc : Source bashrc and confirm crc login -------------------------- 0.59s
rhol_crc : Make sure kubeconfig file exists ----------------------------- 0.30s
rhol_crc : Add crc creds in the user bashrc file ------------------------ 0.29s
rhol_crc : Check bashrc results ----------------------------------------- 0.04s
rhol_crc : Warn the user that the kubeconfig files doesn't exist -------- 0.03s
Verify crc creds playbook ----------------------------------------------- 0.03s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:53:16 +0000 (0:00:00.016)       0:00:00.016 *****
ok: [instance]

TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
Wednesday 08 October 2025  15:53:17 +0000 (0:00:00.898)       0:00:00.914 *****
ok: [instance]

TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
Wednesday 08 October 2025  15:53:17 +0000 (0:00:00.282)       0:00:01.197 *****
changed: [instance]

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

Wednesday 08 October 2025  15:53:21 +0000 (0:00:03.585)       0:00:04.782 *****
===============================================================================
rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.59s
Gathering Facts --------------------------------------------------------- 0.90s
rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.28s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:53:54 +0000 (0:00:00.019)       0:00:00.019 *****
ok: [instance]

TASK [Setup libvirt] ***********************************************************
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.850)       0:00:00.869 *****
skipping: [instance]

TASK [Setup CRC] ***************************************************************
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.023)       0:00:00.892 *****
skipping: [instance]

TASK [Start RHOL/CRC] **********************************************************
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.022)       0:00:00.914 *****
skipping: [instance]

TASK [Inject our own NetworkManager configuration] *****************************
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.021)       0:00:00.936 *****
ok: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.596)       0:00:01.533 *****
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.020)       0:00:01.554 *****
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] *****************************************
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.043)       0:00:01.598 *****
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.215)       0:00:01.813 *****
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Wednesday 08 October 2025  15:53:56 +0000 (0:00:00.215)       0:00:02.028 *****
ok: [instance] => (item=ubi-9-appstream)
ok: [instance] => (item=ubi-9-baseos)
ok: [instance] => (item=ubi-9-codeready-builder)

TASK [Deploy repo-setup] *******************************************************
Wednesday 08 October 2025  15:53:56 +0000 (0:00:00.706)       0:00:02.735 *****

TASK [repo_setup : Ensure directories are present] *****************************
Wednesday 08 October 2025  15:53:56 +0000 (0:00:00.065)       0:00:02.801 *****
ok: [instance] => (item=tmp)
ok: [instance] => (item=artifacts/repositories)
ok: [instance] => (item=venv/repo_setup)

TASK [repo_setup : Make sure git-core package is installed] ********************
Wednesday 08 October 2025  15:53:57 +0000 (0:00:00.565)       0:00:03.367 *****
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Wednesday 08 October 2025  15:53:58 +0000 (0:00:01.378)       0:00:04.746 *****
ok: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Wednesday 08 October 2025  15:53:59 +0000 (0:00:00.595)       0:00:05.341 *****
ok: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Wednesday 08 October 2025  15:54:00 +0000 (0:00:01.314)       0:00:06.656 *****
ok: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Wednesday 08 October 2025  15:54:01 +0000 (0:00:00.304)       0:00:06.960 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Wednesday 08 October 2025  15:54:01 +0000 (0:00:00.025)       0:00:06.985 *****
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.933)       0:00:07.919 *****
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.041)       0:00:07.960 *****
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.041)       0:00:08.001 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.040)       0:00:08.042 *****
changed: [instance]

TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.549)       0:00:08.591 *****
ok: [instance]

TASK [repo_setup : Dump current-podified hash] *********************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.374)       0:00:08.966 *****
skipping: [instance]

TASK [repo_setup : Slurp current podified hash] ********************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.032)       0:00:08.998 *****
skipping: [instance]

TASK [repo_setup : Update the value of full_hash] ******************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.051)       0:00:09.049 *****
skipping: [instance]

TASK [repo_setup : Export hashes facts for further use] ************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.031)       0:00:09.081 *****
ok: [instance]

TASK [repo_setup : Create download directory] **********************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.041)       0:00:09.123 *****
skipping: [instance]

TASK [repo_setup : Print the URL to request] ***********************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.029)       0:00:09.152 *****
skipping: [instance]

TASK [Download the RPM] ********************************************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.028)       0:00:09.181 *****
skipping: [instance]

TASK [repo_setup : Install RHOS Release tool] **********************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.028)       0:00:09.209 *****
skipping: [instance]

TASK [repo_setup : Get rhos-release tool version] ******************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.033)       0:00:09.243 *****
skipping: [instance]

TASK [repo_setup : Print rhos-release tool version] ****************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.029)       0:00:09.273 *****
skipping: [instance]

TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.030)       0:00:09.303 *****
skipping: [instance]

TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.029)       0:00:09.332 *****
ok: [instance]

TASK [repo_setup : Use RDO proxy mirrors] **************************************
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.188)       0:00:09.521 *****
changed: [instance]

TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.218)       0:00:09.739 *****
changed: [instance]

TASK [repo_setup : Check for gating.repo file on content provider] *************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.231)       0:00:09.970 *****
skipping: [instance]

TASK [repo_setup : Populate gating repo from content provider ip] **************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.022)       0:00:09.993 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN repo at the destination] *********************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.024)       0:00:10.017 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.026)       0:00:10.043 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN component repo] ******************************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.025)       0:00:10.068 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.025)       0:00:10.094 *****
skipping: [instance]

TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.025)       0:00:10.119 *****
skipping: [instance]

TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.020)       0:00:10.139 *****
skipping: [instance]

TASK [repo_setup : Cleanup existing metadata] **********************************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.032)       0:00:10.171 *****
skipping: [instance]

TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.020)       0:00:10.192 *****
skipping: [instance]

TASK [test_deps : Look for redhat-release rpm] *********************************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.027)       0:00:10.219 *****
skipping: [instance]

TASK [test_deps : Remove redhat-release] ***************************************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.032)       0:00:10.251 *****
skipping: [instance]

TASK [test_deps : Install centos-stream-release] *******************************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.033)       0:00:10.285 *****
skipping: [instance]

TASK [test_deps : Install additional packages] *********************************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.031)       0:00:10.317 *****
skipping: [instance]

TASK [test_deps : Install selinux python libs] *********************************
Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.032)       0:00:10.350 *****
ok: [instance]

TASK [test_deps : Install python yaml libs] ************************************
Wednesday 08 October 2025  15:54:48 +0000 (0:00:44.233)       0:00:54.584 *****
ok: [instance]

TASK [test_deps : Install extra packages] **************************************
Wednesday 08 October 2025  15:54:50 +0000 (0:00:01.277)       0:00:55.861 *****
skipping: [instance]

TASK [ci_setup : Gather variables for each operating system] *******************
Wednesday 08 October 2025  15:54:50 +0000 (0:00:00.035)       0:00:55.897 *****
ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)

TASK [ci_setup : List packages to install] *************************************
Wednesday 08 October 2025  15:54:50 +0000 (0:00:00.047)       0:00:55.945 *****
ok: [instance] =>
  cifmw_ci_setup_packages:
  - bash-completion
  - ca-certificates
  - git-core
  - make
  - tar
  - tmux
  - python3-pip

TASK [ci_setup : Install needed packages] **************************************
Wednesday 08 October 2025  15:54:50 +0000 (0:00:00.021)       0:00:55.966 *****
ok: [instance]

TASK [ci_setup : Gather version of openshift client] ***************************
Wednesday 08 October 2025  15:54:51 +0000 (0:00:01.265)       0:00:57.231 *****
ok: [instance]

TASK [ci_setup : Ensure openshift client install path is present] **************
Wednesday 08 October 2025  15:54:51 +0000 (0:00:00.269)       0:00:57.501 *****
ok: [instance]

TASK [ci_setup : Install openshift client] *************************************
Wednesday 08 October 2025  15:54:51 +0000 (0:00:00.214)       0:00:57.716 *****
skipping: [instance]

TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
Wednesday 08 October 2025  15:54:51 +0000 (0:00:00.069)       0:00:57.785 *****
skipping: [instance]

TASK [ci_setup : Create completion file] ***************************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.039)       0:00:57.824 *****
ok: [instance]

TASK [ci_setup : Source completion from within .bashrc] ************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.184)       0:00:58.009 *****
changed: [instance]

TASK [ci_setup : Check rhsm status] ********************************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.289)       0:00:58.299 *****
skipping: [instance]

TASK [ci_setup : Gather the repos to be enabled] *******************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.318 *****
skipping: [instance]

TASK [ci_setup : Enabling the required repositories.] **************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.336 *****
skipping: [instance]

TASK [ci_setup : Get current /etc/redhat-release] ******************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.019)       0:00:58.355 *****
skipping: [instance]

TASK [ci_setup : Print current /etc/redhat-release] ****************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.374 *****
skipping: [instance]

TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.392 *****
skipping: [instance]

TASK [ci_setup : Manage directories] *******************************************
Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.024)       0:00:58.417 *****
ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)

TASK [Check for CRC startup] ***************************************************
Wednesday 08 October 2025  15:54:53 +0000 (0:00:01.008)       0:00:59.425 *****
skipping: [instance]

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

Wednesday 08 October 2025  15:54:53 +0000 (0:00:00.038)       0:00:59.463 *****
===============================================================================
test_deps : Install selinux python libs -------------------------------- 44.23s
repo_setup : Make sure git-core package is installed -------------------- 1.38s
repo_setup : Initialize python venv and install requirements ------------ 1.31s
test_deps : Install python yaml libs ------------------------------------ 1.28s
ci_setup : Install needed packages -------------------------------------- 1.27s
ci_setup : Manage directories ------------------------------------------- 1.01s
repo_setup : Run repo-setup --------------------------------------------- 0.93s
Gathering Facts --------------------------------------------------------- 0.85s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.71s
Inject our own NetworkManager configuration ----------------------------- 0.60s
repo_setup : Get repo-setup repository ---------------------------------- 0.60s
repo_setup : Ensure directories are present ----------------------------- 0.57s
repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.37s
repo_setup : Install repo-setup package --------------------------------- 0.30s
ci_setup : Source completion from within .bashrc ------------------------ 0.29s
ci_setup : Gather version of openshift client --------------------------- 0.27s
repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
test_deps : Detect mirrors file ----------------------------------------- 0.22s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:54:54 +0000 (0:00:00.015)       0:00:00.015 *****
ok: [instance]

TASK [rhol_crc : Create bin directory if it does not exist] ********************
Wednesday 08 October 2025  15:54:54 +0000 (0:00:00.847)       0:00:00.863 *****
ok: [instance]

TASK [rhol_crc : Create temporary directory for downloading RHOL/CRC] **********
Wednesday 08 October 2025  15:54:55 +0000 (0:00:00.322)       0:00:01.186 *****
changed: [instance]

TASK [rhol_crc : Download RHOL/CRC with sha256 checksum] ***********************
Wednesday 08 October 2025  15:54:55 +0000 (0:00:00.267)       0:00:01.453 *****
changed: [instance]

TASK [rhol_crc : Unarchive RHOL/CRC binary] ************************************
Wednesday 08 October 2025  15:55:06 +0000 (0:00:10.811)       0:00:12.264 *****
changed: [instance]

TASK [rhol_crc : Delete temporary directory for downloading RHOL/CRC] **********
Wednesday 08 October 2025  15:55:11 +0000 (0:00:05.201)       0:00:17.466 *****
ok: [instance]

TASK [rhol_crc : Get CRC binary version] ***************************************
Wednesday 08 October 2025  15:55:11 +0000 (0:00:00.190)       0:00:17.657 *****
changed: [instance]

TASK [rhol_crc : Extract versions] *********************************************
Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.479)       0:00:18.136 *****
ok: [instance]

TASK [rhol_crc : Output found binary location and versions] ********************
Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.037)       0:00:18.173 *****
ok: [instance] =>
  msg: 'Found crc at /usr/local/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.'

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.047)       0:00:18.221 *****
ok: [instance]

TASK [Ensure facts are set and look correct] ***********************************
Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.645)       0:00:18.866 *****
ok: [instance] => changed=false
  msg: All assertions passed

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

Wednesday 08 October 2025  15:55:13 +0000 (0:00:00.066)       0:00:18.932 *****
===============================================================================
rhol_crc : Download RHOL/CRC with sha256 checksum ---------------------- 10.81s
rhol_crc : Unarchive RHOL/CRC binary ------------------------------------ 5.20s
Gathering Facts --------------------------------------------------------- 0.85s
Gathering Facts --------------------------------------------------------- 0.65s
rhol_crc : Get CRC binary version --------------------------------------- 0.48s
rhol_crc : Create bin directory if it does not exist -------------------- 0.32s
rhol_crc : Create temporary directory for downloading RHOL/CRC ---------- 0.27s
rhol_crc : Delete temporary directory for downloading RHOL/CRC ---------- 0.19s
Ensure facts are set and look correct ----------------------------------- 0.07s
rhol_crc : Output found binary location and versions -------------------- 0.05s
rhol_crc : Extract versions --------------------------------------------- 0.04s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:55:13 +0000 (0:00:00.016)       0:00:00.016 *****
ok: [instance]

TASK [Cleanup RHOL/CRC after tests] ********************************************
Wednesday 08 October 2025  15:55:14 +0000 (0:00:00.842)       0:00:00.858 *****
changed: [instance]

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

Wednesday 08 October 2025  15:55:14 +0000 (0:00:00.465)       0:00:01.323 *****
===============================================================================
Gathering Facts --------------------------------------------------------- 0.84s
Cleanup RHOL/CRC after tests -------------------------------------------- 0.47s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:55:32 +0000 (0:00:00.018)       0:00:00.018 *****
ok: [instance]

TASK [Setup libvirt] ***********************************************************
Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.872)       0:00:00.890 *****
skipping: [instance]

TASK [Setup CRC] ***************************************************************
Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.022)       0:00:00.912 *****
skipping: [instance]

TASK [Start RHOL/CRC] **********************************************************
Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.022)       0:00:00.935 *****
skipping: [instance]

TASK [Inject our own NetworkManager configuration] *****************************
Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.022)       0:00:00.958 *****
ok: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.586)       0:00:01.545 *****
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.020)       0:00:01.566 *****
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] *****************************************
Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.044)       0:00:01.610 *****
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Wednesday 08 October 2025  15:55:34 +0000 (0:00:00.192)       0:00:01.803 *****
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Wednesday 08 October 2025  15:55:34 +0000 (0:00:00.214)       0:00:02.018 *****
ok: [instance] => (item=ubi-9-appstream)
ok: [instance] => (item=ubi-9-baseos)
ok: [instance] => (item=ubi-9-codeready-builder)

TASK [Deploy repo-setup] *******************************************************
Wednesday 08 October 2025  15:55:34 +0000 (0:00:00.721)       0:00:02.740 *****

TASK [repo_setup : Ensure directories are present] *****************************
Wednesday 08 October 2025  15:55:35 +0000 (0:00:00.063)       0:00:02.803 *****
ok: [instance] => (item=tmp)
ok: [instance] => (item=artifacts/repositories)
ok: [instance] => (item=venv/repo_setup)

TASK [repo_setup : Make sure git-core package is installed] ********************
Wednesday 08 October 2025  15:55:35 +0000 (0:00:00.567)       0:00:03.371 *****
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Wednesday 08 October 2025  15:55:37 +0000 (0:00:01.401)       0:00:04.772 *****
ok: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Wednesday 08 October 2025  15:55:37 +0000 (0:00:00.598)       0:00:05.370 *****
ok: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Wednesday 08 October 2025  15:55:38 +0000 (0:00:01.319)       0:00:06.690 *****
ok: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Wednesday 08 October 2025  15:55:39 +0000 (0:00:00.300)       0:00:06.990 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Wednesday 08 October 2025  15:55:39 +0000 (0:00:00.024)       0:00:07.015 *****
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.885)       0:00:07.900 *****
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.040)       0:00:07.941 *****
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.039)       0:00:07.981 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.040)       0:00:08.021 *****
changed: [instance]

TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.570)       0:00:08.592 *****
ok: [instance]

TASK [repo_setup : Dump current-podified hash] *********************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.361)       0:00:08.953 *****
skipping: [instance]

TASK [repo_setup : Slurp current podified hash] ********************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.031)       0:00:08.985 *****
skipping: [instance]

TASK [repo_setup : Update the value of full_hash] ******************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.030)       0:00:09.015 *****
skipping: [instance]

TASK [repo_setup : Export hashes facts for further use] ************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.031)       0:00:09.047 *****
ok: [instance]

TASK [repo_setup : Create download directory] **********************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.042)       0:00:09.089 *****
skipping: [instance]

TASK [repo_setup : Print the URL to request] ***********************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.031)       0:00:09.121 *****
skipping: [instance]

TASK [Download the RPM] ********************************************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.030)       0:00:09.151 *****
skipping: [instance]

TASK [repo_setup : Install RHOS Release tool] **********************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.029)       0:00:09.181 *****
skipping: [instance]

TASK [repo_setup : Get rhos-release tool version] ******************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.028)       0:00:09.210 *****
skipping: [instance]

TASK [repo_setup : Print rhos-release tool version] ****************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.057)       0:00:09.267 *****
skipping: [instance]

TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.032)       0:00:09.300 *****
skipping: [instance]

TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.029)       0:00:09.329 *****
ok: [instance]

TASK [repo_setup : Use RDO proxy mirrors] **************************************
Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.197)       0:00:09.526 *****
changed: [instance]

TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.216)       0:00:09.742 *****
changed: [instance]

TASK [repo_setup : Check for gating.repo file on content provider] *************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.236)       0:00:09.979 *****
skipping: [instance]

TASK [repo_setup : Populate gating repo from content provider ip] **************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.021)       0:00:10.000 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN repo at the destination] *********************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.025 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.051 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN component repo] ******************************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.076 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.023)       0:00:10.100 *****
skipping: [instance]

TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.125 *****
skipping: [instance]

TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.019)       0:00:10.145 *****
skipping: [instance]

TASK [repo_setup : Cleanup existing metadata] **********************************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.029)       0:00:10.175 *****
skipping: [instance]

TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.020)       0:00:10.195 *****
skipping: [instance]

TASK [test_deps : Look for redhat-release rpm] *********************************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.024)       0:00:10.219 *****
skipping: [instance]

TASK [test_deps : Remove redhat-release] ***************************************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.031)       0:00:10.251 *****
skipping: [instance]

TASK [test_deps : Install centos-stream-release] *******************************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.031)       0:00:10.283 *****
skipping: [instance]

TASK [test_deps : Install additional packages] *********************************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.031)       0:00:10.314 *****
skipping: [instance]

TASK [test_deps : Install selinux python libs] *********************************
Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.032)       0:00:10.346 *****
ok: [instance]

TASK [test_deps : Install python yaml libs] ************************************
Wednesday 08 October 2025  15:56:40 +0000 (0:00:58.326)       0:01:08.673 *****
ok: [instance]

TASK [test_deps : Install extra packages] **************************************
Wednesday 08 October 2025  15:56:42 +0000 (0:00:01.226)       0:01:09.900 *****
skipping: [instance]

TASK [ci_setup : Gather variables for each operating system] *******************
Wednesday 08 October 2025  15:56:42 +0000 (0:00:00.035)       0:01:09.936 *****
ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)

TASK [ci_setup : List packages to install] *************************************
Wednesday 08 October 2025  15:56:42 +0000 (0:00:00.047)       0:01:09.983 *****
ok: [instance] =>
  cifmw_ci_setup_packages:
  - bash-completion
  - ca-certificates
  - git-core
  - make
  - tar
  - tmux
  - python3-pip

TASK [ci_setup : Install needed packages] **************************************
Wednesday 08 October 2025  15:56:42 +0000 (0:00:00.022)       0:01:10.006 *****
ok: [instance]

TASK [ci_setup : Gather version of openshift client] ***************************
Wednesday 08 October 2025  15:56:43 +0000 (0:00:01.252)       0:01:11.259 *****
ok: [instance]

TASK [ci_setup : Ensure openshift client install path is present] **************
Wednesday 08 October 2025  15:56:43 +0000 (0:00:00.269)       0:01:11.528 *****
ok: [instance]

TASK [ci_setup : Install openshift client] *************************************
Wednesday 08 October 2025  15:56:43 +0000 (0:00:00.209)       0:01:11.738 *****
skipping: [instance]

TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.066)       0:01:11.804 *****
skipping: [instance]

TASK [ci_setup : Create completion file] ***************************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.036)       0:01:11.841 *****
ok: [instance]

TASK [ci_setup : Source completion from within .bashrc] ************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.182)       0:01:12.024 *****
ok: [instance]

TASK [ci_setup : Check rhsm status] ********************************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.289)       0:01:12.314 *****
skipping: [instance]

TASK [ci_setup : Gather the repos to be enabled] *******************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.018)       0:01:12.332 *****
skipping: [instance]

TASK [ci_setup : Enabling the required repositories.] **************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.018)       0:01:12.351 *****
skipping: [instance]

TASK [ci_setup : Get current /etc/redhat-release] ******************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.019)       0:01:12.371 *****
skipping: [instance]

TASK [ci_setup : Print current /etc/redhat-release] ****************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.019)       0:01:12.390 *****
skipping: [instance]

TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.018)       0:01:12.409 *****
skipping: [instance]

TASK [ci_setup : Manage directories] *******************************************
Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.028)       0:01:12.437 *****
ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)

TASK [Check for CRC startup] ***************************************************
Wednesday 08 October 2025  15:56:45 +0000 (0:00:01.008)       0:01:13.446 *****
skipping: [instance]

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

Wednesday 08 October 2025  15:56:45 +0000 (0:00:00.039)       0:01:13.485 *****
===============================================================================
test_deps : Install selinux python libs -------------------------------- 58.33s
repo_setup : Make sure git-core package is installed -------------------- 1.40s
repo_setup : Initialize python venv and install requirements ------------ 1.32s
ci_setup : Install needed packages -------------------------------------- 1.25s
test_deps : Install python yaml libs ------------------------------------ 1.23s
ci_setup : Manage directories ------------------------------------------- 1.01s
repo_setup : Run repo-setup --------------------------------------------- 0.89s
Gathering Facts --------------------------------------------------------- 0.87s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.72s
repo_setup : Get repo-setup repository ---------------------------------- 0.60s
Inject our own NetworkManager configuration ----------------------------- 0.59s
repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
repo_setup : Ensure directories are present ----------------------------- 0.57s
repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.36s
repo_setup : Install repo-setup package --------------------------------- 0.30s
ci_setup : Source completion from within .bashrc ------------------------ 0.29s
ci_setup : Gather version of openshift client --------------------------- 0.27s
repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.24s
repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
test_deps : Disable ubi host subscription-manager integration ----------- 0.21s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  15:56:46 +0000 (0:00:00.030)       0:00:00.030 *****
ok: [instance]

TASK [rhol_crc : Ensure directories exist] *************************************
Wednesday 08 October 2025  15:56:47 +0000 (0:00:00.838)       0:00:00.868 *****
ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
ok: [instance] => (item=/usr/local/bin)

TASK [manage_secrets : Ensure directory tree exist] ****************************
Wednesday 08 October 2025  15:56:47 +0000 (0:00:00.678)       0:00:01.546 *****
changed: [instance]

TASK [rhol_crc : Check for binary] *********************************************
Wednesday 08 October 2025  15:56:48 +0000 (0:00:00.214)       0:00:01.761 *****
ok: [instance]

TASK [rhol_crc : Get versions if binary is available] **************************
Wednesday 08 October 2025  15:56:48 +0000 (0:00:00.306)       0:00:02.067 *****
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/get_versions.yml for instance

TASK [rhol_crc : Get CRC binary version] ***************************************
Wednesday 08 October 2025  15:56:48 +0000 (0:00:00.038)       0:00:02.106 *****
changed: [instance]

TASK [rhol_crc : Extract versions] *********************************************
Wednesday 08 October 2025  15:56:49 +0000 (0:00:01.400)       0:00:03.506 *****
ok: [instance]

TASK [rhol_crc : Output found binary location and versions] ********************
Wednesday 08 October 2025  15:56:49 +0000 (0:00:00.039)       0:00:03.545 *****
ok: [instance] =>
  msg: 'Found crc at /usr/local/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.'

TASK [rhol_crc : Get CRC VM availability] **************************************
Wednesday 08 October 2025  15:56:49 +0000 (0:00:00.023)       0:00:03.569 *****
ok: [instance]

TASK [rhol_crc : Set CRC status] ***********************************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.382)       0:00:03.952 *****
ok: [instance]

TASK [rhol_crc : Fail if crc domain is already defined] ************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.030)       0:00:03.982 *****
skipping: [instance]

TASK [Ensure pull-secret is in place if we need to configure CRC] **************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.029)       0:00:04.011 *****

TASK [manage_secrets : Assert we get needed data] ******************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.046)       0:00:04.058 *****
ok: [instance]

TASK [manage_secrets : Manage secret] ******************************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.026)       0:00:04.085 *****
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/manage_secrets/tasks/_push_secret.yml for instance

TASK [manage_secrets : Debug _secret_dest] *************************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.031)       0:00:04.116 *****
ok: [instance] =>
  _secret_dest: /home/zuul/pull-secret.json

TASK [manage_secrets : Debug _secret_file] *************************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.027)       0:00:04.143 *****
ok: [instance] =>
  _secret_file: ''

TASK [manage_secrets : Debug _secret_content] **********************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.022)       0:00:04.166 *****
ok: [instance] =>
  _secret_content:
    auths:
      fake:
        auth: Zm9vOmJhcgo=

TASK [manage_secrets : Ensure parameter is an absolute path] *******************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.023)       0:00:04.190 *****
skipping: [instance]

TASK [manage_secrets : Check if pull secret src file exists] *******************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.020)       0:00:04.210 *****
skipping: [instance]

TASK [manage_secrets : Copy file to location] **********************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.022)       0:00:04.232 *****
skipping: [instance]

TASK [manage_secrets : Create file from content] *******************************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.021)       0:00:04.254 *****
changed: [instance]

TASK [rhol_crc : Get wanted RHOL/CRC version binary if needed] *****************
Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.478)       0:00:04.732 *****
skipping: [instance]

TASK [rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup] *******
Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.036)       0:00:04.768 *****
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/sudoers_grant.yml for instance

TASK [rhol_crc : Add passwordless sudo access for CRC commands] ****************
Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.038)       0:00:04.807 *****
changed: [instance]

TASK [rhol_crc : Clean RHOL/CRC if wanted] *************************************
Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.324)       0:00:05.131 *****
skipping: [instance]

TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.031)       0:00:05.162 *****
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/configuration.yml for instance

TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.039)       0:00:05.202 *****
changed: [instance] => (item={'key': 'consent-telemetry', 'value': 'no'})
changed: [instance] => (item={'key': 'disk-size', 'value': 32})
changed: [instance] => (item={'key': 'memory', 'value': 10752})
changed: [instance] => (item={'key': 'cpus', 'value': 4})
changed: [instance] => (item={'key': 'preset', 'value': 'openshift'})
changed: [instance] => (item={'key': 'pull-secret-file', 'value': '/home/zuul/pull-secret.json'})
changed: [instance] => (item={'key': 'skip-check-daemon-systemd-unit', 'value': True})
changed: [instance] => (item={'key': 'skip-check-daemon-systemd-sockets', 'value': True})
changed: [instance] => (item={'key': 'kubeadmin-password', 'value': 12345678})

TASK [rhol_crc : Setup RHOL/CRC] ***********************************************
Wednesday 08 October 2025  15:56:53 +0000 (0:00:02.044)       0:00:07.246 *****
changed: [instance]

TASK [rhol_crc : Start RHOL/CRC] ***********************************************
Wednesday 08 October 2025  16:00:56 +0000 (0:04:02.593)       0:04:09.840 *****
fatal: [instance]: FAILED! => changed=true
  cmd: set -o pipefail; /usr/local/bin/crc start 2>&1 | tee /home/zuul/ci-framework-data/logs/crc-start.log
  delta: '0:23:06.871473'
  end: '2025-10-08 16:24:03.177967'
  msg: non-zero return code
  rc: 1
  start: '2025-10-08 16:00:56.306494'
  stderr: ''
  stderr_lines: <omitted>
  stdout: |-
    level=warning msg="A new version (2.54.0) has been published on https://developers.redhat.com/content-gateway/file/pub/openshift-v4/clients/crc/2.54.0/crc-linux-amd64.tar.xz"
    level=info msg="Using bundle path /home/zuul/.crc/cache/crc_libvirt_4.16.0_amd64.crcbundle"
    level=info msg="Checking if running as non-root"
    level=info msg="Checking if running inside WSL2"
    level=info msg="Checking if crc-admin-helper executable is cached"
    level=info msg="Checking if running on a supported CPU architecture"
    level=info msg="Checking if crc executable symlink exists"
    level=info msg="Checking minimum RAM requirements"
    level=info msg="Check if Podman binary exists in: /home/zuul/.crc/bin/oc"
    level=info msg="Checking if Virtualization is enabled"
    level=info msg="Checking if KVM is enabled"
    level=info msg="Checking if libvirt is installed"
    level=info msg="Checking if user is part of libvirt group"
    level=info msg="Checking if active user/process is currently part of the libvirt group"
    level=info msg="Checking if libvirt daemon is running"
    level=info msg="Checking if a supported libvirt version is installed"
    level=info msg="Checking if crc-driver-libvirt is installed"
    level=info msg="Checking crc daemon systemd socket units"
    level=warning msg="Skipping above check..."
    level=info msg="Checking if systemd-networkd is running"
    level=info msg="Checking if NetworkManager is installed"
    level=info msg="Checking if NetworkManager service is running"
    level=info msg="Checking if /etc/NetworkManager/conf.d/crc-nm-dnsmasq.conf exists"
    level=info msg="Checking if /etc/NetworkManager/dnsmasq.d/crc.conf exists"
    level=info msg="Checking if libvirt 'crc' network is available"
    level=info msg="Checking if libvirt 'crc' network is active"
    level=info msg="Loading bundle: crc_libvirt_4.16.0_amd64..."
    level=info msg="Creating CRC VM for OpenShift 4.16.0..."
    level=info msg="Generating new SSH key pair..."
    level=info msg="Generating new password for the kubeadmin user"
    level=info msg="Starting CRC VM for openshift 4.16.0..."
    level=info msg="CRC instance is running with IP 192.168.130.11"
    level=info msg="CRC VM is running"
    level=info msg="Updating authorized keys..."
    level=info msg="Resizing /dev/vda4 filesystem"
    level=info msg="Configuring shared directories"
    level=info msg="Check internal and public DNS query..."
    level=info msg="Check DNS query from host..."
    level=info msg="Verifying validity of the kubelet certificates..."
    level=info msg="Starting kubelet service"
    level=info msg="Kubelet client certificate has expired, renewing it... [will take up to 10 minutes]"
    level=info msg="Kubelet serving certificate has expired, waiting for automatic renewal... [will take up to 5 minutes]"
    level=info msg="Waiting for kube-apiserver availability... [takes around 2min]"
    level=info msg="Adding user's pull secret to the cluster..."
    level=info msg="Updating SSH key to machine config resource..."
    level=info msg="Waiting until the user's pull secret is written to the instance disk..."
    Failed to update pull secret on the disk: Temporary error: pull secret not updated to disk (x205)
  stdout_lines: <omitted>

TASK [rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup] ***
Wednesday 08 October 2025  16:24:03 +0000 (0:23:07.111)       0:27:16.951 *****
changed: [instance]

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

Wednesday 08 October 2025  16:24:03 +0000 (0:00:00.206)       0:27:17.158 *****
===============================================================================
rhol_crc : Start RHOL/CRC -------------------------------------------- 1387.11s
rhol_crc : Setup RHOL/CRC --------------------------------------------- 242.59s
rhol_crc : Set RHOL/CRC configuration options --------------------------- 2.04s
rhol_crc : Get CRC binary version --------------------------------------- 1.40s
Gathering Facts --------------------------------------------------------- 0.84s
rhol_crc : Ensure directories exist ------------------------------------- 0.68s
manage_secrets : Create file from content ------------------------------- 0.48s
rhol_crc : Get CRC VM availability -------------------------------------- 0.38s
rhol_crc : Add passwordless sudo access for CRC commands ---------------- 0.32s
rhol_crc : Check for binary --------------------------------------------- 0.31s
manage_secrets : Ensure directory tree exist ---------------------------- 0.21s
rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup --- 0.21s
Ensure pull-secret is in place if we need to configure CRC -------------- 0.05s
rhol_crc : Extract versions --------------------------------------------- 0.04s
rhol_crc : Set RHOL/CRC configuration options --------------------------- 0.04s
rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup ------- 0.04s
rhol_crc : Get versions if binary is available -------------------------- 0.04s
rhol_crc : Get wanted RHOL/CRC version binary if needed ----------------- 0.04s
rhol_crc : Clean RHOL/CRC if wanted ------------------------------------- 0.03s
manage_secrets : Manage secret ------------------------------------------ 0.03s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  16:24:03 +0000 (0:00:00.016)       0:00:00.016 *****
ok: [instance]

TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
Wednesday 08 October 2025  16:24:04 +0000 (0:00:00.877)       0:00:00.893 *****
ok: [instance]

TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
Wednesday 08 October 2025  16:24:05 +0000 (0:00:00.288)       0:00:01.182 *****
changed: [instance]

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

Wednesday 08 October 2025  16:24:08 +0000 (0:00:03.548)       0:00:04.730 *****
===============================================================================
rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.55s
Gathering Facts --------------------------------------------------------- 0.88s
rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.29s