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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:39:29 +0000 (0:00:00.014)       0:00:00.014 ********
ok: [instance]

TASK [Setup libvirt] ***********************************************************
Friday 03 October 2025  13:39:30 +0000 (0:00:00.836)       0:00:00.851 ********
skipping: [instance]

TASK [Setup CRC] ***************************************************************
Friday 03 October 2025  13:39:30 +0000 (0:00:00.021)       0:00:00.872 ********
skipping: [instance]

TASK [Start RHOL/CRC] **********************************************************
Friday 03 October 2025  13:39:30 +0000 (0:00:00.020)       0:00:00.892 ********
changed: [instance]

TASK [Inject our own NetworkManager configuration] *****************************
Friday 03 October 2025  13:39:30 +0000 (0:00:00.400)       0:00:01.292 ********
changed: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Friday 03 October 2025  13:39:31 +0000 (0:00:00.570)       0:00:01.863 ********
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Friday 03 October 2025  13:39:31 +0000 (0:00:00.016)       0:00:01.879 ********
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] *****************************************
Friday 03 October 2025  13:39:31 +0000 (0:00:00.038)       0:00:01.918 ********
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Friday 03 October 2025  13:39:31 +0000 (0:00:00.187)       0:00:02.106 ********
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Friday 03 October 2025  13:39:31 +0000 (0:00:00.315)       0:00:02.421 ********
ok: [instance] => (item=ubi-9-appstream)
ok: [instance] => (item=ubi-9-baseos)
ok: [instance] => (item=ubi-9-codeready-builder)

TASK [Deploy repo-setup] *******************************************************
Friday 03 October 2025  13:39:32 +0000 (0:00:00.691)       0:00:03.113 ********

TASK [repo_setup : Ensure directories are present] *****************************
Friday 03 October 2025  13:39:32 +0000 (0:00:00.061)       0:00:03.175 ********
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] ********************
Friday 03 October 2025  13:39:32 +0000 (0:00:00.549)       0:00:03.725 ********
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Friday 03 October 2025  13:39:33 +0000 (0:00:00.797)       0:00:04.522 ********
changed: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Friday 03 October 2025  13:39:35 +0000 (0:00:01.210)       0:00:05.733 ********
changed: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Friday 03 October 2025  13:39:43 +0000 (0:00:08.730)       0:00:14.464 ********
changed: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Friday 03 October 2025  13:39:44 +0000 (0:00:00.757)       0:00:15.222 ********
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Friday 03 October 2025  13:39:44 +0000 (0:00:00.024)       0:00:15.247 ********
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Friday 03 October 2025  13:39:46 +0000 (0:00:01.959)       0:00:17.206 ********
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Friday 03 October 2025  13:39:46 +0000 (0:00:00.048)       0:00:17.255 ********
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Friday 03 October 2025  13:39:46 +0000 (0:00:00.046)       0:00:17.301 ********
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Friday 03 October 2025  13:39:46 +0000 (0:00:00.049)       0:00:17.350 ********
changed: [instance]

TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.653)       0:00:18.004 ********
changed: [instance]

TASK [repo_setup : Dump current-podified hash] *********************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.413)       0:00:18.417 ********
skipping: [instance]

TASK [repo_setup : Slurp current podified hash] ********************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.035)       0:00:18.452 ********
skipping: [instance]

TASK [repo_setup : Update the value of full_hash] ******************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.037)       0:00:18.490 ********
skipping: [instance]

TASK [repo_setup : Export hashes facts for further use] ************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.036)       0:00:18.526 ********
ok: [instance]

TASK [repo_setup : Create download directory] **********************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.046)       0:00:18.573 ********
skipping: [instance]

TASK [repo_setup : Print the URL to request] ***********************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.033)       0:00:18.606 ********
skipping: [instance]

TASK [Download the RPM] ********************************************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.031)       0:00:18.638 ********
skipping: [instance]

TASK [repo_setup : Install RHOS Release tool] **********************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.034)       0:00:18.673 ********
skipping: [instance]

TASK [repo_setup : Get rhos-release tool version] ******************************
Friday 03 October 2025  13:39:47 +0000 (0:00:00.035)       0:00:18.708 ********
skipping: [instance]

TASK [repo_setup : Print rhos-release tool version] ****************************
Friday 03 October 2025  13:39:48 +0000 (0:00:00.031)       0:00:18.739 ********
skipping: [instance]

TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
Friday 03 October 2025  13:39:48 +0000 (0:00:00.032)       0:00:18.772 ********
skipping: [instance]

TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
Friday 03 October 2025  13:39:48 +0000 (0:00:00.031)       0:00:18.804 ********
ok: [instance]

TASK [repo_setup : Use RDO proxy mirrors] **************************************
Friday 03 October 2025  13:39:48 +0000 (0:00:00.259)       0:00:19.063 ********
changed: [instance]

TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
Friday 03 October 2025  13:39:48 +0000 (0:00:00.278)       0:00:19.341 ********
changed: [instance]

TASK [repo_setup : Check for gating.repo file on content provider] *************
Friday 03 October 2025  13:39:48 +0000 (0:00:00.304)       0:00:19.646 ********
skipping: [instance]

TASK [repo_setup : Populate gating repo from content provider ip] **************
Friday 03 October 2025  13:39:48 +0000 (0:00:00.033)       0:00:19.679 ********
skipping: [instance]

TASK [repo_setup : Check for DLRN repo at the destination] *********************
Friday 03 October 2025  13:39:48 +0000 (0:00:00.029)       0:00:19.708 ********
skipping: [instance]

TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
Friday 03 October 2025  13:39:49 +0000 (0:00:00.028)       0:00:19.737 ********
skipping: [instance]

TASK [repo_setup : Check for DLRN component repo] ******************************
Friday 03 October 2025  13:39:49 +0000 (0:00:00.029)       0:00:19.767 ********
skipping: [instance]

TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
Friday 03 October 2025  13:39:49 +0000 (0:00:00.029)       0:00:19.797 ********
skipping: [instance]

TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
Friday 03 October 2025  13:39:49 +0000 (0:00:00.027)       0:00:19.825 ********
skipping: [instance]

TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
Friday 03 October 2025  13:39:49 +0000 (0:00:00.022)       0:00:19.847 ********
skipping: [instance]

TASK [repo_setup : Cleanup existing metadata] **********************************
Friday 03 October 2025  13:39:49 +0000 (0:00:00.036)       0:00:19.883 ********
skipping: [instance]

TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
Friday 03 October 2025  13:39:49 +0000 (0:00:00.026)       0:00:19.909 ********
skipping: [instance]

TASK [test_deps : Look for redhat-release rpm] *********************************
Friday 03 October 2025  13:39:49 +0000 (0:00:00.028)       0:00:19.938 ********
skipping: [instance]

TASK [test_deps : Remove redhat-release] ***************************************
Friday 03 October 2025  13:39:49 +0000 (0:00:00.036)       0:00:19.974 ********
skipping: [instance]

TASK [test_deps : Install centos-stream-release] *******************************
Friday 03 October 2025  13:39:49 +0000 (0:00:00.037)       0:00:20.012 ********
skipping: [instance]

TASK [test_deps : Install additional packages] *********************************
Friday 03 October 2025  13:39:49 +0000 (0:00:00.035)       0:00:20.047 ********
skipping: [instance]

TASK [test_deps : Install selinux python libs] *********************************
Friday 03 October 2025  13:39:49 +0000 (0:00:00.036)       0:00:20.083 ********
ok: [instance]

TASK [test_deps : Install python yaml libs] ************************************
Friday 03 October 2025  13:40:33 +0000 (0:00:44.082)       0:01:04.166 ********
ok: [instance]

TASK [test_deps : Install extra packages] **************************************
Friday 03 October 2025  13:40:34 +0000 (0:00:01.280)       0:01:05.446 ********
skipping: [instance]

TASK [ci_setup : Gather variables for each operating system] *******************
Friday 03 October 2025  13:40:34 +0000 (0:00:00.033)       0:01:05.479 ********
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] *************************************
Friday 03 October 2025  13:40:34 +0000 (0:00:00.047)       0:01:05.527 ********
ok: [instance] =>
  cifmw_ci_setup_packages:
  - bash-completion
  - ca-certificates
  - git-core
  - make
  - tar
  - tmux
  - python3-pip

TASK [ci_setup : Install needed packages] **************************************
Friday 03 October 2025  13:40:34 +0000 (0:00:00.023)       0:01:05.550 ********
changed: [instance]

TASK [ci_setup : Gather version of openshift client] ***************************
Friday 03 October 2025  13:40:41 +0000 (0:00:06.871)       0:01:12.422 ********
ok: [instance]

TASK [ci_setup : Ensure openshift client install path is present] **************
Friday 03 October 2025  13:40:42 +0000 (0:00:00.627)       0:01:13.049 ********
changed: [instance]

TASK [ci_setup : Install openshift client] *************************************
Friday 03 October 2025  13:40:42 +0000 (0:00:00.255)       0:01:13.305 ********
changed: [instance]

TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
Friday 03 October 2025  13:40:48 +0000 (0:00:06.270)       0:01:19.576 ********
ok: [instance]

TASK [ci_setup : Create completion file] ***************************************
Friday 03 October 2025  13:40:48 +0000 (0:00:00.053)       0:01:19.629 ********
changed: [instance]

TASK [ci_setup : Source completion from within .bashrc] ************************
Friday 03 October 2025  13:40:49 +0000 (0:00:00.344)       0:01:19.973 ********
changed: [instance]

TASK [ci_setup : Check rhsm status] ********************************************
Friday 03 October 2025  13:40:49 +0000 (0:00:00.350)       0:01:20.324 ********
skipping: [instance]

TASK [ci_setup : Gather the repos to be enabled] *******************************
Friday 03 October 2025  13:40:49 +0000 (0:00:00.023)       0:01:20.348 ********
skipping: [instance]

TASK [ci_setup : Enabling the required repositories.] **************************
Friday 03 October 2025  13:40:49 +0000 (0:00:00.024)       0:01:20.372 ********
skipping: [instance]

TASK [ci_setup : Get current /etc/redhat-release] ******************************
Friday 03 October 2025  13:40:49 +0000 (0:00:00.024)       0:01:20.396 ********
skipping: [instance]

TASK [ci_setup : Print current /etc/redhat-release] ****************************
Friday 03 October 2025  13:40:49 +0000 (0:00:00.024)       0:01:20.421 ********
skipping: [instance]

TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
Friday 03 October 2025  13:40:49 +0000 (0:00:00.025)       0:01:20.446 ********
skipping: [instance]

TASK [ci_setup : Manage directories] *******************************************
Friday 03 October 2025  13:40:49 +0000 (0:00:00.034)       0:01:20.481 ********
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] ***************************************************
Friday 03 October 2025  13:40:50 +0000 (0:00:01.161)       0:01:21.642 ********
FAILED - RETRYING: [instance]: Check for CRC startup (120 retries left).
FAILED - RETRYING: [instance]: Check for CRC startup (119 retries left).
FAILED - RETRYING: [instance]: Check for CRC startup (118 retries left).
FAILED - RETRYING: [instance]: Check for CRC startup (117 retries left).
changed: [instance]

RUNNING HANDLER [Restart NetworkManager] ***************************************
Friday 03 October 2025  13:44:12 +0000 (0:03:21.262)       0:04:42.905 ********
changed: [instance]

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

Friday 03 October 2025  13:44:13 +0000 (0:00:00.916)       0:04:43.821 ********
===============================================================================
Check for CRC startup ------------------------------------------------- 201.26s
test_deps : Install selinux python libs -------------------------------- 44.08s
repo_setup : Initialize python venv and install requirements ------------ 8.73s
ci_setup : Install needed packages -------------------------------------- 6.87s
ci_setup : Install openshift client ------------------------------------- 6.27s
repo_setup : Run repo-setup --------------------------------------------- 1.96s
test_deps : Install python yaml libs ------------------------------------ 1.28s
repo_setup : Get repo-setup repository ---------------------------------- 1.21s
ci_setup : Manage directories ------------------------------------------- 1.16s
Restart NetworkManager -------------------------------------------------- 0.92s
Gathering Facts --------------------------------------------------------- 0.84s
repo_setup : Make sure git-core package is installed -------------------- 0.80s
repo_setup : Install repo-setup package --------------------------------- 0.76s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
repo_setup : Run repo-setup-get-hash ------------------------------------ 0.65s
ci_setup : Gather version of openshift client --------------------------- 0.63s
Inject our own NetworkManager configuration ----------------------------- 0.57s
repo_setup : Ensure directories are present ----------------------------- 0.55s
repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.41s
Start RHOL/CRC ---------------------------------------------------------- 0.40s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:44:13 +0000 (0:00:00.042)       0:00:00.042 ********
ok: [instance]

TASK [Verify crc creds playbook] ***********************************************
Friday 03 October 2025  13:44:14 +0000 (0:00:01.110)       0:00:01.152 ********

TASK [rhol_crc : Make sure kubeconfig file exists] *****************************
Friday 03 October 2025  13:44:14 +0000 (0:00:00.037)       0:00:01.190 ********
ok: [instance]

TASK [rhol_crc : Warn the user that the kubeconfig files doesn't exist] ********
Friday 03 October 2025  13:44:15 +0000 (0:00:00.362)       0:00:01.552 ********
skipping: [instance]

TASK [rhol_crc : Add crc creds in the user bashrc file] ************************
Friday 03 October 2025  13:44:15 +0000 (0:00:00.033)       0:00:01.586 ********
changed: [instance]

TASK [rhol_crc : Source bashrc and confirm crc login] **************************
Friday 03 October 2025  13:44:15 +0000 (0:00:00.329)       0:00:01.916 ********
ok: [instance]

TASK [rhol_crc : Check bashrc results] *****************************************
Friday 03 October 2025  13:44:16 +0000 (0:00:00.711)       0:00:02.627 ********
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

Friday 03 October 2025  13:44:16 +0000 (0:00:00.060)       0:00:02.688 ********
===============================================================================
Gathering Facts --------------------------------------------------------- 1.11s
rhol_crc : Source bashrc and confirm crc login -------------------------- 0.71s
rhol_crc : Make sure kubeconfig file exists ----------------------------- 0.36s
rhol_crc : Add crc creds in the user bashrc file ------------------------ 0.33s
rhol_crc : Check bashrc results ----------------------------------------- 0.06s
Verify crc creds playbook ----------------------------------------------- 0.04s
rhol_crc : Warn the user that the kubeconfig files doesn't exist -------- 0.03s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:44:17 +0000 (0:00:00.020)       0:00:00.020 ********
ok: [instance]

TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
Friday 03 October 2025  13:44:18 +0000 (0:00:01.009)       0:00:01.030 ********
ok: [instance]

TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
Friday 03 October 2025  13:44:18 +0000 (0:00:00.329)       0:00:01.359 ********
skipping: [instance]

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

Friday 03 October 2025  13:44:18 +0000 (0:00:00.049)       0:00:01.408 ********
===============================================================================
Gathering Facts --------------------------------------------------------- 1.01s
rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.33s
rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 0.05s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:44:38 +0000 (0:00:00.019)       0:00:00.019 ********
ok: [instance]

TASK [Setup libvirt] ***********************************************************
Friday 03 October 2025  13:44:39 +0000 (0:00:00.951)       0:00:00.970 ********
skipping: [instance]

TASK [Setup CRC] ***************************************************************
Friday 03 October 2025  13:44:39 +0000 (0:00:00.023)       0:00:00.994 ********
skipping: [instance]

TASK [Start RHOL/CRC] **********************************************************
Friday 03 October 2025  13:44:39 +0000 (0:00:00.025)       0:00:01.019 ********
skipping: [instance]

TASK [Inject our own NetworkManager configuration] *****************************
Friday 03 October 2025  13:44:39 +0000 (0:00:00.024)       0:00:01.044 ********
ok: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Friday 03 October 2025  13:44:39 +0000 (0:00:00.659)       0:00:01.703 ********
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Friday 03 October 2025  13:44:39 +0000 (0:00:00.022)       0:00:01.726 ********
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] *****************************************
Friday 03 October 2025  13:44:39 +0000 (0:00:00.052)       0:00:01.778 ********
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Friday 03 October 2025  13:44:40 +0000 (0:00:00.227)       0:00:02.006 ********
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Friday 03 October 2025  13:44:40 +0000 (0:00:00.277)       0:00:02.283 ********
ok: [instance] => (item=ubi-9-appstream)
ok: [instance] => (item=ubi-9-baseos)
ok: [instance] => (item=ubi-9-codeready-builder)

TASK [Deploy repo-setup] *******************************************************
Friday 03 October 2025  13:44:41 +0000 (0:00:00.844)       0:00:03.128 ********

TASK [repo_setup : Ensure directories are present] *****************************
Friday 03 October 2025  13:44:41 +0000 (0:00:00.095)       0:00:03.223 ********
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] ********************
Friday 03 October 2025  13:44:42 +0000 (0:00:00.649)       0:00:03.873 ********
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Friday 03 October 2025  13:44:43 +0000 (0:00:01.591)       0:00:05.464 ********
ok: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Friday 03 October 2025  13:44:44 +0000 (0:00:00.648)       0:00:06.113 ********
ok: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Friday 03 October 2025  13:44:45 +0000 (0:00:01.490)       0:00:07.603 ********
ok: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Friday 03 October 2025  13:44:46 +0000 (0:00:00.357)       0:00:07.961 ********
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Friday 03 October 2025  13:44:46 +0000 (0:00:00.026)       0:00:07.988 ********
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Friday 03 October 2025  13:44:47 +0000 (0:00:01.073)       0:00:09.061 ********
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Friday 03 October 2025  13:44:47 +0000 (0:00:00.050)       0:00:09.111 ********
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Friday 03 October 2025  13:44:47 +0000 (0:00:00.045)       0:00:09.157 ********
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Friday 03 October 2025  13:44:47 +0000 (0:00:00.046)       0:00:09.204 ********
changed: [instance]

TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
Friday 03 October 2025  13:44:47 +0000 (0:00:00.593)       0:00:09.798 ********
ok: [instance]

TASK [repo_setup : Dump current-podified hash] *********************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.433)       0:00:10.231 ********
skipping: [instance]

TASK [repo_setup : Slurp current podified hash] ********************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.033)       0:00:10.265 ********
skipping: [instance]

TASK [repo_setup : Update the value of full_hash] ******************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.033)       0:00:10.299 ********
skipping: [instance]

TASK [repo_setup : Export hashes facts for further use] ************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.035)       0:00:10.335 ********
ok: [instance]

TASK [repo_setup : Create download directory] **********************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.048)       0:00:10.384 ********
skipping: [instance]

TASK [repo_setup : Print the URL to request] ***********************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.033)       0:00:10.417 ********
skipping: [instance]

TASK [Download the RPM] ********************************************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.032)       0:00:10.450 ********
skipping: [instance]

TASK [repo_setup : Install RHOS Release tool] **********************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.031)       0:00:10.481 ********
skipping: [instance]

TASK [repo_setup : Get rhos-release tool version] ******************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.032)       0:00:10.514 ********
skipping: [instance]

TASK [repo_setup : Print rhos-release tool version] ****************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.035)       0:00:10.549 ********
skipping: [instance]

TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
Friday 03 October 2025  13:44:48 +0000 (0:00:00.035)       0:00:10.584 ********
skipping: [instance]

TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
Friday 03 October 2025  13:44:48 +0000 (0:00:00.032)       0:00:10.616 ********
ok: [instance]

TASK [repo_setup : Use RDO proxy mirrors] **************************************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.233)       0:00:10.850 ********
changed: [instance]

TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
Friday 03 October 2025  13:44:49 +0000 (0:00:00.290)       0:00:11.140 ********
changed: [instance]

TASK [repo_setup : Check for gating.repo file on content provider] *************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.337)       0:00:11.477 ********
skipping: [instance]

TASK [repo_setup : Populate gating repo from content provider ip] **************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.026)       0:00:11.504 ********
skipping: [instance]

TASK [repo_setup : Check for DLRN repo at the destination] *********************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.027)       0:00:11.532 ********
skipping: [instance]

TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
Friday 03 October 2025  13:44:49 +0000 (0:00:00.028)       0:00:11.560 ********
skipping: [instance]

TASK [repo_setup : Check for DLRN component repo] ******************************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.030)       0:00:11.591 ********
skipping: [instance]

TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
Friday 03 October 2025  13:44:49 +0000 (0:00:00.030)       0:00:11.621 ********
skipping: [instance]

TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
Friday 03 October 2025  13:44:49 +0000 (0:00:00.030)       0:00:11.652 ********
skipping: [instance]

TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
Friday 03 October 2025  13:44:49 +0000 (0:00:00.025)       0:00:11.677 ********
skipping: [instance]

TASK [repo_setup : Cleanup existing metadata] **********************************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.036)       0:00:11.714 ********
skipping: [instance]

TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
Friday 03 October 2025  13:44:49 +0000 (0:00:00.023)       0:00:11.738 ********
skipping: [instance]

TASK [test_deps : Look for redhat-release rpm] *********************************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.029)       0:00:11.767 ********
skipping: [instance]

TASK [test_deps : Remove redhat-release] ***************************************
Friday 03 October 2025  13:44:49 +0000 (0:00:00.040)       0:00:11.807 ********
skipping: [instance]

TASK [test_deps : Install centos-stream-release] *******************************
Friday 03 October 2025  13:44:50 +0000 (0:00:00.036)       0:00:11.844 ********
skipping: [instance]

TASK [test_deps : Install additional packages] *********************************
Friday 03 October 2025  13:44:50 +0000 (0:00:00.038)       0:00:11.882 ********
skipping: [instance]

TASK [test_deps : Install selinux python libs] *********************************
Friday 03 October 2025  13:44:50 +0000 (0:00:00.037)       0:00:11.920 ********
ok: [instance]

TASK [test_deps : Install python yaml libs] ************************************
Friday 03 October 2025  13:45:39 +0000 (0:00:49.380)       0:01:01.300 ********
ok: [instance]

TASK [test_deps : Install extra packages] **************************************
Friday 03 October 2025  13:45:40 +0000 (0:00:01.350)       0:01:02.650 ********
skipping: [instance]

TASK [ci_setup : Gather variables for each operating system] *******************
Friday 03 October 2025  13:45:40 +0000 (0:00:00.040)       0:01:02.691 ********
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] *************************************
Friday 03 October 2025  13:45:40 +0000 (0:00:00.054)       0:01:02.746 ********
ok: [instance] =>
  cifmw_ci_setup_packages:
  - bash-completion
  - ca-certificates
  - git-core
  - make
  - tar
  - tmux
  - python3-pip

TASK [ci_setup : Install needed packages] **************************************
Friday 03 October 2025  13:45:40 +0000 (0:00:00.025)       0:01:02.771 ********
ok: [instance]

TASK [ci_setup : Gather version of openshift client] ***************************
Friday 03 October 2025  13:45:42 +0000 (0:00:01.278)       0:01:04.050 ********
ok: [instance]

TASK [ci_setup : Ensure openshift client install path is present] **************
Friday 03 October 2025  13:45:42 +0000 (0:00:00.284)       0:01:04.334 ********
ok: [instance]

TASK [ci_setup : Install openshift client] *************************************
Friday 03 October 2025  13:45:42 +0000 (0:00:00.251)       0:01:04.586 ********
skipping: [instance]

TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
Friday 03 October 2025  13:45:42 +0000 (0:00:00.066)       0:01:04.652 ********
skipping: [instance]

TASK [ci_setup : Create completion file] ***************************************
Friday 03 October 2025  13:45:42 +0000 (0:00:00.034)       0:01:04.687 ********
ok: [instance]

TASK [ci_setup : Source completion from within .bashrc] ************************
Friday 03 October 2025  13:45:43 +0000 (0:00:00.191)       0:01:04.879 ********
changed: [instance]

TASK [ci_setup : Check rhsm status] ********************************************
Friday 03 October 2025  13:45:43 +0000 (0:00:00.310)       0:01:05.189 ********
skipping: [instance]

TASK [ci_setup : Gather the repos to be enabled] *******************************
Friday 03 October 2025  13:45:43 +0000 (0:00:00.021)       0:01:05.211 ********
skipping: [instance]

TASK [ci_setup : Enabling the required repositories.] **************************
Friday 03 October 2025  13:45:43 +0000 (0:00:00.026)       0:01:05.237 ********
skipping: [instance]

TASK [ci_setup : Get current /etc/redhat-release] ******************************
Friday 03 October 2025  13:45:43 +0000 (0:00:00.024)       0:01:05.262 ********
skipping: [instance]

TASK [ci_setup : Print current /etc/redhat-release] ****************************
Friday 03 October 2025  13:45:43 +0000 (0:00:00.019)       0:01:05.281 ********
skipping: [instance]

TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
Friday 03 October 2025  13:45:43 +0000 (0:00:00.019)       0:01:05.300 ********
skipping: [instance]

TASK [ci_setup : Manage directories] *******************************************
Friday 03 October 2025  13:45:43 +0000 (0:00:00.028)       0:01:05.329 ********
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] ***************************************************
Friday 03 October 2025  13:45:44 +0000 (0:00:01.105)       0:01:06.435 ********
skipping: [instance]

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

Friday 03 October 2025  13:45:44 +0000 (0:00:00.047)       0:01:06.482 ********
===============================================================================
test_deps : Install selinux python libs -------------------------------- 49.38s
repo_setup : Make sure git-core package is installed -------------------- 1.59s
repo_setup : Initialize python venv and install requirements ------------ 1.49s
test_deps : Install python yaml libs ------------------------------------ 1.35s
ci_setup : Install needed packages -------------------------------------- 1.28s
ci_setup : Manage directories ------------------------------------------- 1.11s
repo_setup : Run repo-setup --------------------------------------------- 1.07s
Gathering Facts --------------------------------------------------------- 0.95s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.84s
Inject our own NetworkManager configuration ----------------------------- 0.66s
repo_setup : Ensure directories are present ----------------------------- 0.65s
repo_setup : Get repo-setup repository ---------------------------------- 0.65s
repo_setup : Run repo-setup-get-hash ------------------------------------ 0.59s
repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
repo_setup : Install repo-setup package --------------------------------- 0.36s
repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.34s
ci_setup : Source completion from within .bashrc ------------------------ 0.31s
repo_setup : Use RDO proxy mirrors -------------------------------------- 0.29s
ci_setup : Gather version of openshift client --------------------------- 0.28s
test_deps : Disable ubi host subscription-manager integration ----------- 0.28s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:45:45 +0000 (0:00:00.014)       0:00:00.014 ********
ok: [instance]

TASK [rhol_crc : Create bin directory if it does not exist] ********************
Friday 03 October 2025  13:45:46 +0000 (0:00:00.973)       0:00:00.987 ********
ok: [instance]

TASK [rhol_crc : Create temporary directory for downloading RHOL/CRC] **********
Friday 03 October 2025  13:45:46 +0000 (0:00:00.345)       0:00:01.333 ********
changed: [instance]

TASK [rhol_crc : Download RHOL/CRC with sha256 checksum] ***********************
Friday 03 October 2025  13:45:46 +0000 (0:00:00.292)       0:00:01.625 ********
changed: [instance]

TASK [rhol_crc : Unarchive RHOL/CRC binary] ************************************
Friday 03 October 2025  13:46:01 +0000 (0:00:14.953)       0:00:16.578 ********
changed: [instance]

TASK [rhol_crc : Delete temporary directory for downloading RHOL/CRC] **********
Friday 03 October 2025  13:46:07 +0000 (0:00:05.711)       0:00:22.290 ********
ok: [instance]

TASK [rhol_crc : Get CRC binary version] ***************************************
Friday 03 October 2025  13:46:07 +0000 (0:00:00.190)       0:00:22.480 ********
changed: [instance]

TASK [rhol_crc : Extract versions] *********************************************
Friday 03 October 2025  13:46:08 +0000 (0:00:00.692)       0:00:23.173 ********
ok: [instance]

TASK [rhol_crc : Output found binary location and versions] ********************
Friday 03 October 2025  13:46:08 +0000 (0:00:00.041)       0:00:23.214 ********
ok: [instance] =>
  msg: 'Found crc at /home/zuul/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] *********************************************************
Friday 03 October 2025  13:46:08 +0000 (0:00:00.052)       0:00:23.267 ********
ok: [instance]

TASK [Ensure facts are set and look correct] ***********************************
Friday 03 October 2025  13:46:09 +0000 (0:00:00.712)       0:00:23.979 ********
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

Friday 03 October 2025  13:46:09 +0000 (0:00:00.047)       0:00:24.027 ********
===============================================================================
rhol_crc : Download RHOL/CRC with sha256 checksum ---------------------- 14.95s
rhol_crc : Unarchive RHOL/CRC binary ------------------------------------ 5.71s
Gathering Facts --------------------------------------------------------- 0.97s
Gathering Facts --------------------------------------------------------- 0.71s
rhol_crc : Get CRC binary version --------------------------------------- 0.69s
rhol_crc : Create bin directory if it does not exist -------------------- 0.35s
rhol_crc : Create temporary directory for downloading RHOL/CRC ---------- 0.29s
rhol_crc : Delete temporary directory for downloading RHOL/CRC ---------- 0.19s
rhol_crc : Output found binary location and versions -------------------- 0.05s
Ensure facts are set and look correct ----------------------------------- 0.05s
rhol_crc : Extract versions --------------------------------------------- 0.04s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:46:09 +0000 (0:00:00.014)       0:00:00.014 ********
ok: [instance]

TASK [Cleanup RHOL/CRC after tests] ********************************************
Friday 03 October 2025  13:46:10 +0000 (0:00:00.855)       0:00:00.870 ********
changed: [instance]

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

Friday 03 October 2025  13:46:15 +0000 (0:00:05.331)       0:00:06.202 ********
===============================================================================
Cleanup RHOL/CRC after tests -------------------------------------------- 5.33s
Gathering Facts --------------------------------------------------------- 0.86s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:46:33 +0000 (0:00:00.018)       0:00:00.018 ********
ok: [instance]

TASK [Setup libvirt] ***********************************************************
Friday 03 October 2025  13:46:34 +0000 (0:00:00.864)       0:00:00.883 ********
skipping: [instance]

TASK [Setup CRC] ***************************************************************
Friday 03 October 2025  13:46:34 +0000 (0:00:00.023)       0:00:00.906 ********
skipping: [instance]

TASK [Start RHOL/CRC] **********************************************************
Friday 03 October 2025  13:46:34 +0000 (0:00:00.023)       0:00:00.930 ********
skipping: [instance]

TASK [Inject our own NetworkManager configuration] *****************************
Friday 03 October 2025  13:46:34 +0000 (0:00:00.023)       0:00:00.953 ********
ok: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Friday 03 October 2025  13:46:35 +0000 (0:00:00.611)       0:00:01.565 ********
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Friday 03 October 2025  13:46:35 +0000 (0:00:00.019)       0:00:01.584 ********
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] *****************************************
Friday 03 October 2025  13:46:35 +0000 (0:00:00.045)       0:00:01.630 ********
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Friday 03 October 2025  13:46:35 +0000 (0:00:00.191)       0:00:01.822 ********
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Friday 03 October 2025  13:46:35 +0000 (0:00:00.228)       0:00:02.050 ********
ok: [instance] => (item=ubi-9-appstream)
ok: [instance] => (item=ubi-9-baseos)
ok: [instance] => (item=ubi-9-codeready-builder)

TASK [Deploy repo-setup] *******************************************************
Friday 03 October 2025  13:46:36 +0000 (0:00:00.696)       0:00:02.747 ********

TASK [repo_setup : Ensure directories are present] *****************************
Friday 03 October 2025  13:46:36 +0000 (0:00:00.064)       0:00:02.811 ********
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] ********************
Friday 03 October 2025  13:46:37 +0000 (0:00:00.569)       0:00:03.381 ********
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Friday 03 October 2025  13:46:38 +0000 (0:00:01.384)       0:00:04.766 ********
ok: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Friday 03 October 2025  13:46:39 +0000 (0:00:00.564)       0:00:05.330 ********
ok: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Friday 03 October 2025  13:46:40 +0000 (0:00:01.297)       0:00:06.628 ********
ok: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Friday 03 October 2025  13:46:40 +0000 (0:00:00.287)       0:00:06.915 ********
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Friday 03 October 2025  13:46:40 +0000 (0:00:00.023)       0:00:06.938 ********
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Friday 03 October 2025  13:46:41 +0000 (0:00:00.913)       0:00:07.852 ********
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Friday 03 October 2025  13:46:41 +0000 (0:00:00.040)       0:00:07.893 ********
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Friday 03 October 2025  13:46:41 +0000 (0:00:00.040)       0:00:07.933 ********
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Friday 03 October 2025  13:46:41 +0000 (0:00:00.040)       0:00:07.974 ********
changed: [instance]

TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.604)       0:00:08.579 ********
ok: [instance]

TASK [repo_setup : Dump current-podified hash] *********************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.373)       0:00:08.952 ********
skipping: [instance]

TASK [repo_setup : Slurp current podified hash] ********************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.031)       0:00:08.984 ********
skipping: [instance]

TASK [repo_setup : Update the value of full_hash] ******************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.033)       0:00:09.017 ********
skipping: [instance]

TASK [repo_setup : Export hashes facts for further use] ************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.032)       0:00:09.049 ********
ok: [instance]

TASK [repo_setup : Create download directory] **********************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.041)       0:00:09.091 ********
skipping: [instance]

TASK [repo_setup : Print the URL to request] ***********************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.030)       0:00:09.121 ********
skipping: [instance]

TASK [Download the RPM] ********************************************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.028)       0:00:09.150 ********
skipping: [instance]

TASK [repo_setup : Install RHOS Release tool] **********************************
Friday 03 October 2025  13:46:42 +0000 (0:00:00.030)       0:00:09.181 ********
skipping: [instance]

TASK [repo_setup : Get rhos-release tool version] ******************************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.033)       0:00:09.214 ********
skipping: [instance]

TASK [repo_setup : Print rhos-release tool version] ****************************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.030)       0:00:09.245 ********
skipping: [instance]

TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
Friday 03 October 2025  13:46:43 +0000 (0:00:00.031)       0:00:09.276 ********
skipping: [instance]

TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.028)       0:00:09.304 ********
ok: [instance]

TASK [repo_setup : Use RDO proxy mirrors] **************************************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.190)       0:00:09.494 ********
changed: [instance]

TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
Friday 03 October 2025  13:46:43 +0000 (0:00:00.223)       0:00:09.718 ********
changed: [instance]

TASK [repo_setup : Check for gating.repo file on content provider] *************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.237)       0:00:09.956 ********
skipping: [instance]

TASK [repo_setup : Populate gating repo from content provider ip] **************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.030)       0:00:09.987 ********
skipping: [instance]

TASK [repo_setup : Check for DLRN repo at the destination] *********************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.031)       0:00:10.018 ********
skipping: [instance]

TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
Friday 03 October 2025  13:46:43 +0000 (0:00:00.027)       0:00:10.046 ********
skipping: [instance]

TASK [repo_setup : Check for DLRN component repo] ******************************
Friday 03 October 2025  13:46:43 +0000 (0:00:00.027)       0:00:10.074 ********
skipping: [instance]

TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
Friday 03 October 2025  13:46:43 +0000 (0:00:00.030)       0:00:10.105 ********
skipping: [instance]

TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
Friday 03 October 2025  13:46:43 +0000 (0:00:00.030)       0:00:10.135 ********
skipping: [instance]

TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
Friday 03 October 2025  13:46:43 +0000 (0:00:00.025)       0:00:10.161 ********
skipping: [instance]

TASK [repo_setup : Cleanup existing metadata] **********************************
Friday 03 October 2025  13:46:44 +0000 (0:00:00.034)       0:00:10.196 ********
skipping: [instance]

TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
Friday 03 October 2025  13:46:44 +0000 (0:00:00.027)       0:00:10.223 ********
skipping: [instance]

TASK [test_deps : Look for redhat-release rpm] *********************************
Friday 03 October 2025  13:46:44 +0000 (0:00:00.029)       0:00:10.252 ********
skipping: [instance]

TASK [test_deps : Remove redhat-release] ***************************************
Friday 03 October 2025  13:46:44 +0000 (0:00:00.033)       0:00:10.286 ********
skipping: [instance]

TASK [test_deps : Install centos-stream-release] *******************************
Friday 03 October 2025  13:46:44 +0000 (0:00:00.034)       0:00:10.321 ********
skipping: [instance]

TASK [test_deps : Install additional packages] *********************************
Friday 03 October 2025  13:46:44 +0000 (0:00:00.034)       0:00:10.356 ********
skipping: [instance]

TASK [test_deps : Install selinux python libs] *********************************
Friday 03 October 2025  13:46:44 +0000 (0:00:00.033)       0:00:10.389 ********
ok: [instance]

TASK [test_deps : Install python yaml libs] ************************************
Friday 03 October 2025  13:47:29 +0000 (0:00:45.652)       0:00:56.042 ********
ok: [instance]

TASK [test_deps : Install extra packages] **************************************
Friday 03 October 2025  13:47:31 +0000 (0:00:01.236)       0:00:57.278 ********
skipping: [instance]

TASK [ci_setup : Gather variables for each operating system] *******************
Friday 03 October 2025  13:47:31 +0000 (0:00:00.034)       0:00:57.312 ********
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] *************************************
Friday 03 October 2025  13:47:31 +0000 (0:00:00.046)       0:00:57.359 ********
ok: [instance] =>
  cifmw_ci_setup_packages:
  - bash-completion
  - ca-certificates
  - git-core
  - make
  - tar
  - tmux
  - python3-pip

TASK [ci_setup : Install needed packages] **************************************
Friday 03 October 2025  13:47:31 +0000 (0:00:00.021)       0:00:57.380 ********
ok: [instance]

TASK [ci_setup : Gather version of openshift client] ***************************
Friday 03 October 2025  13:47:32 +0000 (0:00:01.237)       0:00:58.618 ********
ok: [instance]

TASK [ci_setup : Ensure openshift client install path is present] **************
Friday 03 October 2025  13:47:32 +0000 (0:00:00.262)       0:00:58.881 ********
ok: [instance]

TASK [ci_setup : Install openshift client] *************************************
Friday 03 October 2025  13:47:32 +0000 (0:00:00.207)       0:00:59.088 ********
skipping: [instance]

TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
Friday 03 October 2025  13:47:32 +0000 (0:00:00.064)       0:00:59.153 ********
skipping: [instance]

TASK [ci_setup : Create completion file] ***************************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.034)       0:00:59.188 ********
ok: [instance]

TASK [ci_setup : Source completion from within .bashrc] ************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.178)       0:00:59.366 ********
ok: [instance]

TASK [ci_setup : Check rhsm status] ********************************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.284)       0:00:59.651 ********
skipping: [instance]

TASK [ci_setup : Gather the repos to be enabled] *******************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.017)       0:00:59.668 ********
skipping: [instance]

TASK [ci_setup : Enabling the required repositories.] **************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.017)       0:00:59.685 ********
skipping: [instance]

TASK [ci_setup : Get current /etc/redhat-release] ******************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.017)       0:00:59.702 ********
skipping: [instance]

TASK [ci_setup : Print current /etc/redhat-release] ****************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.018)       0:00:59.721 ********
skipping: [instance]

TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
Friday 03 October 2025  13:47:33 +0000 (0:00:00.018)       0:00:59.739 ********
skipping: [instance]

TASK [ci_setup : Manage directories] *******************************************
Friday 03 October 2025  13:47:33 +0000 (0:00:00.023)       0:00:59.763 ********
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] ***************************************************
Friday 03 October 2025  13:47:34 +0000 (0:00:01.001)       0:01:00.765 ********
skipping: [instance]

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

Friday 03 October 2025  13:47:34 +0000 (0:00:00.037)       0:01:00.802 ********
===============================================================================
test_deps : Install selinux python libs -------------------------------- 45.65s
repo_setup : Make sure git-core package is installed -------------------- 1.38s
repo_setup : Initialize python venv and install requirements ------------ 1.30s
ci_setup : Install needed packages -------------------------------------- 1.24s
test_deps : Install python yaml libs ------------------------------------ 1.24s
ci_setup : Manage directories ------------------------------------------- 1.00s
repo_setup : Run repo-setup --------------------------------------------- 0.91s
Gathering Facts --------------------------------------------------------- 0.86s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
Inject our own NetworkManager configuration ----------------------------- 0.61s
repo_setup : Run repo-setup-get-hash ------------------------------------ 0.60s
repo_setup : Ensure directories are present ----------------------------- 0.57s
repo_setup : Get repo-setup repository ---------------------------------- 0.56s
repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.37s
repo_setup : Install repo-setup package --------------------------------- 0.29s
ci_setup : Source completion from within .bashrc ------------------------ 0.28s
ci_setup : Gather version of openshift client --------------------------- 0.26s
repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.24s
test_deps : Disable ubi host subscription-manager integration ----------- 0.23s
repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  13:47:35 +0000 (0:00:00.030)       0:00:00.030 ********
ok: [instance]

TASK [rhol_crc : Ensure directories exist] *************************************
Friday 03 October 2025  13:47:36 +0000 (0:00:00.831)       0:00:00.861 ********
ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
ok: [instance] => (item=/home/zuul/bin)

TASK [manage_secrets : Ensure directory tree exist] ****************************
Friday 03 October 2025  13:47:36 +0000 (0:00:00.665)       0:00:01.527 ********
changed: [instance]

TASK [rhol_crc : Check for binary] *********************************************
Friday 03 October 2025  13:47:36 +0000 (0:00:00.213)       0:00:01.740 ********
ok: [instance]

TASK [rhol_crc : Get versions if binary is available] **************************
Friday 03 October 2025  13:47:37 +0000 (0:00:00.294)       0:00:02.035 ********
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] ***************************************
Friday 03 October 2025  13:47:37 +0000 (0:00:00.038)       0:00:02.073 ********
changed: [instance]

TASK [rhol_crc : Extract versions] *********************************************
Friday 03 October 2025  13:47:50 +0000 (0:00:13.685)       0:00:15.759 ********
ok: [instance]

TASK [rhol_crc : Output found binary location and versions] ********************
Friday 03 October 2025  13:47:50 +0000 (0:00:00.039)       0:00:15.798 ********
ok: [instance] =>
  msg: 'Found crc at /home/zuul/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] **************************************
Friday 03 October 2025  13:47:50 +0000 (0:00:00.027)       0:00:15.825 ********
ok: [instance]

TASK [rhol_crc : Set CRC status] ***********************************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.355)       0:00:16.181 ********
ok: [instance]

TASK [rhol_crc : Fail if crc domain is already defined] ************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.027)       0:00:16.208 ********
skipping: [instance]

TASK [Ensure pull-secret is in place if we need to configure CRC] **************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.029)       0:00:16.238 ********

TASK [manage_secrets : Assert we get needed data] ******************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.045)       0:00:16.283 ********
ok: [instance]

TASK [manage_secrets : Manage secret] ******************************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.024)       0:00:16.307 ********
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] *************************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.025)       0:00:16.333 ********
ok: [instance] =>
  _secret_dest: /home/zuul/pull-secret.json

TASK [manage_secrets : Debug _secret_file] *************************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.025)       0:00:16.358 ********
ok: [instance] =>
  _secret_file: ''

TASK [manage_secrets : Debug _secret_content] **********************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.022)       0:00:16.381 ********
ok: [instance] =>
  _secret_content:
    auths:
      fake:
        auth: Zm9vOmJhcgo=

TASK [manage_secrets : Ensure parameter is an absolute path] *******************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.022)       0:00:16.403 ********
skipping: [instance]

TASK [manage_secrets : Check if pull secret src file exists] *******************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.018)       0:00:16.422 ********
skipping: [instance]

TASK [manage_secrets : Copy file to location] **********************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.018)       0:00:16.441 ********
skipping: [instance]

TASK [manage_secrets : Create file from content] *******************************
Friday 03 October 2025  13:47:51 +0000 (0:00:00.018)       0:00:16.459 ********
changed: [instance]

TASK [rhol_crc : Get wanted RHOL/CRC version binary if needed] *****************
Friday 03 October 2025  13:47:52 +0000 (0:00:00.460)       0:00:16.919 ********
skipping: [instance]

TASK [rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup] *******
Friday 03 October 2025  13:47:52 +0000 (0:00:00.035)       0:00:16.955 ********
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] ****************
Friday 03 October 2025  13:47:52 +0000 (0:00:00.038)       0:00:16.993 ********
changed: [instance]

TASK [rhol_crc : Clean RHOL/CRC if wanted] *************************************
Friday 03 October 2025  13:47:52 +0000 (0:00:00.320)       0:00:17.314 ********
skipping: [instance]

TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
Friday 03 October 2025  13:47:52 +0000 (0:00:00.030)       0:00:17.345 ********
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] ***************************
Friday 03 October 2025  13:47:52 +0000 (0:00:00.039)       0:00:17.384 ********
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] ***********************************************
Friday 03 October 2025  13:47:54 +0000 (0:00:01.961)       0:00:19.345 ********
changed: [instance]

TASK [rhol_crc : Start RHOL/CRC] ***********************************************
Friday 03 October 2025  13:52:06 +0000 (0:04:11.594)       0:04:30.940 ********
fatal: [instance]: FAILED! => changed=true
  cmd: set -o pipefail; /home/zuul/bin/crc start 2>&1 | tee /home/zuul/ci-framework-data/logs/crc-start.log
  delta: '0:22:48.783399'
  end: '2025-10-03 14:14:55.038733'
  msg: non-zero return code
  rc: 1
  start: '2025-10-03 13:52:06.255334'
  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] ***
Friday 03 October 2025  14:14:55 +0000 (0:22:48.999)       0:27:19.939 ********
changed: [instance]

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

Friday 03 October 2025  14:14:55 +0000 (0:00:00.210)       0:27:20.150 ********
===============================================================================
rhol_crc : Start RHOL/CRC -------------------------------------------- 1369.00s
rhol_crc : Setup RHOL/CRC --------------------------------------------- 251.59s
rhol_crc : Get CRC binary version -------------------------------------- 13.69s
rhol_crc : Set RHOL/CRC configuration options --------------------------- 1.96s
Gathering Facts --------------------------------------------------------- 0.83s
rhol_crc : Ensure directories exist ------------------------------------- 0.67s
manage_secrets : Create file from content ------------------------------- 0.46s
rhol_crc : Get CRC VM availability -------------------------------------- 0.36s
rhol_crc : Add passwordless sudo access for CRC commands ---------------- 0.32s
rhol_crc : Check for binary --------------------------------------------- 0.29s
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 : Get versions if binary is available -------------------------- 0.04s
rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup ------- 0.04s
rhol_crc : Get wanted RHOL/CRC version binary if needed ----------------- 0.04s
rhol_crc : Clean RHOL/CRC if wanted ------------------------------------- 0.03s
rhol_crc : Fail if crc domain is already defined ------------------------ 0.03s

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

TASK [Gathering Facts] *********************************************************
Friday 03 October 2025  14:14:55 +0000 (0:00:00.013)       0:00:00.013 ********
ok: [instance]

TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
Friday 03 October 2025  14:14:56 +0000 (0:00:00.906)       0:00:00.920 ********
ok: [instance]

TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
Friday 03 October 2025  14:14:56 +0000 (0:00:00.274)       0:00:01.194 ********
changed: [instance]

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

Friday 03 October 2025  14:15:00 +0000 (0:00:03.772)       0:00:04.967 ********
===============================================================================
rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.77s
Gathering Facts --------------------------------------------------------- 0.91s
rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.27s