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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  13:56:02 +0000 (0:00:00.013)       0:00:00.013 *****
ok: [instance]

TASK [Create custom basedir] ***************************************************
Wednesday 08 October 2025  13:56:02 +0000 (0:00:00.820)       0:00:00.833 *****
changed: [instance]

TASK [test_deps : Gather facts if they don't exist] ****************************
Wednesday 08 October 2025  13:56:03 +0000 (0:00:00.313)       0:00:01.146 *****
skipping: [instance]

TASK [test_deps : Gather variables for each operating system] ******************
Wednesday 08 October 2025  13:56:03 +0000 (0:00:00.017)       0:00:01.164 *****
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  13:56:03 +0000 (0:00:00.041)       0:00:01.205 *****
ok: [instance]

TASK [test_deps : Disable ubi host subscription-manager integration] ***********
Wednesday 08 October 2025  13:56:03 +0000 (0:00:00.281)       0:00:01.486 *****
ok: [instance]

TASK [test_deps : Disable ubi 9 repos] *****************************************
Wednesday 08 October 2025  13:56:03 +0000 (0:00:00.206)       0:00:01.693 *****
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  13:56:04 +0000 (0:00:00.700)       0:00:02.394 *****

TASK [repo_setup : Ensure directories are present] *****************************
Wednesday 08 October 2025  13:56:04 +0000 (0:00:00.058)       0:00:02.453 *****
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  13:56:05 +0000 (0:00:00.554)       0:00:03.008 *****
ok: [instance]

TASK [repo_setup : Get repo-setup repository] **********************************
Wednesday 08 October 2025  13:56:05 +0000 (0:00:00.807)       0:00:03.815 *****
changed: [instance]

TASK [repo_setup : Initialize python venv and install requirements] ************
Wednesday 08 October 2025  13:56:07 +0000 (0:00:01.196)       0:00:05.012 *****
changed: [instance]

TASK [repo_setup : Install repo-setup package] *********************************
Wednesday 08 October 2025  13:56:14 +0000 (0:00:07.900)       0:00:12.913 *****
changed: [instance]

TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
Wednesday 08 October 2025  13:56:15 +0000 (0:00:00.720)       0:00:13.633 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup] *********************************************
Wednesday 08 October 2025  13:56:15 +0000 (0:00:00.022)       0:00:13.656 *****
changed: [instance]

TASK [repo_setup : Get component repo] *****************************************
Wednesday 08 October 2025  13:56:16 +0000 (0:00:00.859)       0:00:14.516 *****
skipping: [instance]

TASK [repo_setup : Rename component repo] **************************************
Wednesday 08 October 2025  13:56:16 +0000 (0:00:00.035)       0:00:14.551 *****
skipping: [instance]

TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
Wednesday 08 October 2025  13:56:16 +0000 (0:00:00.035)       0:00:14.587 *****
skipping: [instance]

TASK [repo_setup : Run repo-setup-get-hash] ************************************
Wednesday 08 October 2025  13:56:16 +0000 (0:00:00.051)       0:00:14.639 *****
changed: [instance]

TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:01.615)       0:00:16.255 *****
changed: [instance]

TASK [repo_setup : Dump current-podified hash] *********************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.445)       0:00:16.701 *****
skipping: [instance]

TASK [repo_setup : Slurp current podified hash] ********************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.031)       0:00:16.732 *****
skipping: [instance]

TASK [repo_setup : Update the value of full_hash] ******************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.031)       0:00:16.763 *****
skipping: [instance]

TASK [repo_setup : Export hashes facts for further use] ************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.030)       0:00:16.794 *****
ok: [instance]

TASK [repo_setup : Create download directory] **********************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.039)       0:00:16.833 *****
skipping: [instance]

TASK [repo_setup : Print the URL to request] ***********************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.027)       0:00:16.861 *****
skipping: [instance]

TASK [Download the RPM] ********************************************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.028)       0:00:16.889 *****
skipping: [instance]

TASK [repo_setup : Install RHOS Release tool] **********************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.028)       0:00:16.917 *****
skipping: [instance]

TASK [repo_setup : Get rhos-release tool version] ******************************
Wednesday 08 October 2025  13:56:18 +0000 (0:00:00.028)       0:00:16.946 *****
skipping: [instance]

TASK [repo_setup : Print rhos-release tool version] ****************************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.029)       0:00:16.976 *****
skipping: [instance]

TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.032)       0:00:17.008 *****
skipping: [instance]

TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.029)       0:00:17.037 *****
ok: [instance]

TASK [repo_setup : Use RDO proxy mirrors] **************************************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.198)       0:00:17.236 *****
changed: [instance]

TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.223)       0:00:17.459 *****
changed: [instance]

TASK [repo_setup : Check for gating.repo file on content provider] *************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.234)       0:00:17.693 *****
skipping: [instance]

TASK [repo_setup : Populate gating repo from content provider ip] **************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.020)       0:00:17.714 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN repo at the destination] *********************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.022)       0:00:17.736 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.022)       0:00:17.759 *****
skipping: [instance]

TASK [repo_setup : Check for DLRN component repo] ******************************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.023)       0:00:17.782 *****
skipping: [instance]

TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.022)       0:00:17.805 *****
skipping: [instance]

TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.023)       0:00:17.829 *****
skipping: [instance]

TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.017)       0:00:17.846 *****
skipping: [instance]

TASK [repo_setup : Cleanup existing metadata] **********************************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.030)       0:00:17.876 *****
skipping: [instance]

TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.016)       0:00:17.893 *****
skipping: [instance]

TASK [test_deps : Look for redhat-release rpm] *********************************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.021)       0:00:17.915 *****
skipping: [instance]

TASK [test_deps : Remove redhat-release] ***************************************
Wednesday 08 October 2025  13:56:19 +0000 (0:00:00.029)       0:00:17.944 *****
skipping: [instance]

TASK [test_deps : Install centos-stream-release] *******************************
Wednesday 08 October 2025  13:56:20 +0000 (0:00:00.030)       0:00:17.974 *****
skipping: [instance]

TASK [test_deps : Install additional packages] *********************************
Wednesday 08 October 2025  13:56:20 +0000 (0:00:00.030)       0:00:18.004 *****
skipping: [instance]

TASK [test_deps : Install selinux python libs] *********************************
Wednesday 08 October 2025  13:56:20 +0000 (0:00:00.029)       0:00:18.034 *****
ok: [instance]

TASK [test_deps : Install python yaml libs] ************************************
Wednesday 08 October 2025  13:57:13 +0000 (0:00:53.360)       0:01:11.394 *****
ok: [instance]

TASK [test_deps : Install extra packages] **************************************
Wednesday 08 October 2025  13:57:14 +0000 (0:00:01.178)       0:01:12.573 *****
skipping: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0

Wednesday 08 October 2025  13:57:14 +0000 (0:00:00.046)       0:01:12.619 *****
===============================================================================
test_deps : Install selinux python libs -------------------------------- 53.36s
repo_setup : Initialize python venv and install requirements ------------ 7.90s
repo_setup : Run repo-setup-get-hash ------------------------------------ 1.62s
repo_setup : Get repo-setup repository ---------------------------------- 1.20s
test_deps : Install python yaml libs ------------------------------------ 1.18s
repo_setup : Run repo-setup --------------------------------------------- 0.86s
Gathering Facts --------------------------------------------------------- 0.82s
repo_setup : Make sure git-core package is installed -------------------- 0.81s
repo_setup : Install repo-setup package --------------------------------- 0.72s
test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
repo_setup : Ensure directories are present ----------------------------- 0.55s
repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s
Create custom basedir --------------------------------------------------- 0.31s
test_deps : Detect mirrors file ----------------------------------------- 0.28s
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 : Disable ubi host subscription-manager integration ----------- 0.21s
repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.20s
Deploy repo-setup ------------------------------------------------------- 0.06s
repo_setup : Disable component repo in current-podified dlrn repo ------- 0.05s

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

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

TASK [Crate SSH keypair] *******************************************************
Wednesday 08 October 2025  13:57:15 +0000 (0:00:00.795)       0:00:00.809 *****
changed: [instance]

TASK [Add SSH jumper entries] **************************************************
Wednesday 08 October 2025  13:57:16 +0000 (0:00:00.360)       0:00:01.169 *****

TASK [ssh_jumper : Create ssh-jumper entries] **********************************
Wednesday 08 October 2025  13:57:16 +0000 (0:00:00.022)       0:00:01.192 *****
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ssh_jumper/tasks/manage_ssh_jumper_entry.yml for instance => (item={'ssh_dir': '/opt/basedir/ssh', 'hostname': '192.168.250.10', 'proxy_host': 'proxy.example.com', 'proxy_user': 'cloud-user', 'patterns': ['test', 'test.node']})
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ssh_jumper/tasks/manage_ssh_jumper_entry.yml for instance => (item={'hostname': 'minimal.example.com', 'ssh_dir': '/opt/basedir/ssh'})
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ssh_jumper/tasks/manage_ssh_jumper_entry.yml for instance => (item={'target': 'instance', 'ssh_dir': '/opt/basedir/ssh', 'hostname': '192.168.250.11', 'identity_file': '/opt/basedir/ssh/id_test', 'patterns': ['test', 'test.node']})

TASK [ssh_jumper : Make sure ~/.ssh/cifmw_ssh_config.d directory exists] *******
Wednesday 08 October 2025  13:57:16 +0000 (0:00:00.032)       0:00:01.224 *****
changed: [instance]

TASK [ssh_jumper : Include ~/.ssh/cifmw_ssh_config.d/inventory_hostname/*.conf] ***
Wednesday 08 October 2025  13:57:16 +0000 (0:00:00.297)       0:00:01.522 *****
changed: [instance]

TASK [ssh_jumper : Inject ssh jumpers for 192.168.250.10] **********************
Wednesday 08 October 2025  13:57:16 +0000 (0:00:00.293)       0:00:01.815 *****
changed: [instance]

TASK [ssh_jumper : Make sure ~/.ssh/cifmw_ssh_config.d directory exists] *******
Wednesday 08 October 2025  13:57:17 +0000 (0:00:00.558)       0:00:02.374 *****
ok: [instance]

TASK [ssh_jumper : Include ~/.ssh/cifmw_ssh_config.d/inventory_hostname/*.conf] ***
Wednesday 08 October 2025  13:57:17 +0000 (0:00:00.194)       0:00:02.568 *****
ok: [instance]

TASK [ssh_jumper : Inject ssh jumpers for minimal.example.com] *****************
Wednesday 08 October 2025  13:57:17 +0000 (0:00:00.193)       0:00:02.762 *****
changed: [instance]

TASK [ssh_jumper : Make sure ~/.ssh/cifmw_ssh_config.d directory exists] *******
Wednesday 08 October 2025  13:57:18 +0000 (0:00:00.358)       0:00:03.120 *****
ok: [instance]

TASK [ssh_jumper : Include ~/.ssh/cifmw_ssh_config.d/inventory_hostname/*.conf] ***
Wednesday 08 October 2025  13:57:18 +0000 (0:00:00.209)       0:00:03.330 *****
ok: [instance]

TASK [ssh_jumper : Inject ssh jumpers for 192.168.250.11] **********************
Wednesday 08 October 2025  13:57:18 +0000 (0:00:00.227)       0:00:03.558 *****
changed: [instance]

TASK [Slurp ssh/config] ********************************************************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.392)       0:00:03.950 *****
ok: [instance]

TASK [Slurp ssh/cifmw_ssh_config.d/minimal.example.com.conf] *******************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.287)       0:00:04.238 *****
ok: [instance]

TASK [Slurp ssh/cifmw_ssh_config.d/192.168.250.10.conf] ************************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.170)       0:00:04.408 *****
ok: [instance]

TASK [Slurp ssh/cifmw_ssh_config.d/192.168.250.11.conf] ************************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.170)       0:00:04.579 *****
ok: [instance]

TASK [Assert Include in ssh_config] ********************************************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.170)       0:00:04.749 *****
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Assert SSH host - minimal config] ****************************************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.029)       0:00:04.779 *****
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Assert cifmw-192.168.250.10.conf] ****************************************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.029)       0:00:04.808 *****
ok: [instance] => changed=false
  msg: All assertions passed

TASK [Assert cifmw-192.168.250.11.conf] ****************************************
Wednesday 08 October 2025  13:57:19 +0000 (0:00:00.030)       0:00:04.838 *****
ok: [instance] => changed=false
  msg: All assertions passed

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

Wednesday 08 October 2025  13:57:20 +0000 (0:00:00.058)       0:00:04.897 *****
===============================================================================
Gathering Facts --------------------------------------------------------- 0.80s
ssh_jumper : Inject ssh jumpers for 192.168.250.10 ---------------------- 0.56s
ssh_jumper : Inject ssh jumpers for 192.168.250.11 ---------------------- 0.39s
Crate SSH keypair ------------------------------------------------------- 0.36s
ssh_jumper : Inject ssh jumpers for minimal.example.com ----------------- 0.36s
ssh_jumper : Make sure ~/.ssh/cifmw_ssh_config.d directory exists ------- 0.30s
ssh_jumper : Include ~/.ssh/cifmw_ssh_config.d/inventory_hostname/*.conf --- 0.29s
Slurp ssh/config -------------------------------------------------------- 0.29s
ssh_jumper : Include ~/.ssh/cifmw_ssh_config.d/inventory_hostname/*.conf --- 0.23s
ssh_jumper : Make sure ~/.ssh/cifmw_ssh_config.d directory exists ------- 0.21s
ssh_jumper : Make sure ~/.ssh/cifmw_ssh_config.d directory exists ------- 0.19s
ssh_jumper : Include ~/.ssh/cifmw_ssh_config.d/inventory_hostname/*.conf --- 0.19s
Slurp ssh/cifmw_ssh_config.d/192.168.250.11.conf ------------------------ 0.17s
Slurp ssh/cifmw_ssh_config.d/192.168.250.10.conf ------------------------ 0.17s
Slurp ssh/cifmw_ssh_config.d/minimal.example.com.conf ------------------- 0.17s
Assert cifmw-192.168.250.11.conf ---------------------------------------- 0.06s
ssh_jumper : Create ssh-jumper entries ---------------------------------- 0.03s
Assert cifmw-192.168.250.10.conf ---------------------------------------- 0.03s
Assert SSH host - minimal config ---------------------------------------- 0.03s
Assert Include in ssh_config -------------------------------------------- 0.03s

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

TASK [Gathering Facts] *********************************************************
Wednesday 08 October 2025  13:57:20 +0000 (0:00:00.034)       0:00:00.034 *****
ok: [instance]

TASK [Cleanup SSH jumper hosts] ************************************************
Wednesday 08 October 2025  13:57:21 +0000 (0:00:00.788)       0:00:00.823 *****

TASK [ssh_jumper : Remove Include cifmw_ssh_config.d] **************************
Wednesday 08 October 2025  13:57:21 +0000 (0:00:00.022)       0:00:00.846 *****
changed: [instance]

TASK [ssh_jumper : Remove cifmw_ssh_config.d directory] ************************
Wednesday 08 October 2025  13:57:21 +0000 (0:00:00.292)       0:00:01.138 *****
changed: [instance]

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

Wednesday 08 October 2025  13:57:21 +0000 (0:00:00.305)       0:00:01.443 *****
===============================================================================
Gathering Facts --------------------------------------------------------- 0.79s
ssh_jumper : Remove cifmw_ssh_config.d directory ------------------------ 0.31s
ssh_jumper : Remove Include cifmw_ssh_config.d -------------------------- 0.29s
Cleanup SSH jumper hosts ------------------------------------------------ 0.02s