2025-10-03 13:36:57.988098 | Job console starting... 2025-10-03 13:36:58.226402 | Updating repositories 2025-10-03 13:36:58.958410 | Preparing job workspace 2025-10-03 13:37:06.232058 | Running Ansible setup... 2025-10-03 13:37:09.927673 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 13:37:10.491400 | 2025-10-03 13:37:10.491571 | PLAY [localhost] 2025-10-03 13:37:10.504458 | 2025-10-03 13:37:10.504530 | TASK [Gathering Facts] 2025-10-03 13:37:11.627526 | localhost | ok 2025-10-03 13:37:11.646199 | 2025-10-03 13:37:11.646359 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 13:37:11.984758 | localhost -> localhost | changed 2025-10-03 13:37:11.990366 | 2025-10-03 13:37:11.990460 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 13:37:12.738114 | localhost -> localhost | changed 2025-10-03 13:37:12.746413 | 2025-10-03 13:37:12.746500 | TASK [Setup log path fact] 2025-10-03 13:37:12.763480 | localhost | ok 2025-10-03 13:37:12.774558 | 2025-10-03 13:37:12.774648 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 13:37:12.791816 | localhost | ok 2025-10-03 13:37:12.799571 | 2025-10-03 13:37:12.799651 | TASK [emit-job-header : Print job information] 2025-10-03 13:37:12.827334 | # Job Information 2025-10-03 13:37:12.827471 | Ansible Version: 2.15.12 2025-10-03 13:37:12.827501 | Job: cifmw-molecule-rhol_crc 2025-10-03 13:37:12.827523 | Pipeline: github-check 2025-10-03 13:37:12.827543 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-03 13:37:12.827563 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362 2025-10-03 13:37:12.827583 | Log URL (when completed): https://logserver.rdoproject.org/885/rdoproject.org/885a5445a75149358ed989c1aa35f0bf/ 2025-10-03 13:37:12.827601 | Event ID: f3678040-a05b-11f0-90a2-408975ecafa3 2025-10-03 13:37:12.831314 | 2025-10-03 13:37:12.831384 | LOOP [emit-job-header : Print node information] 2025-10-03 13:37:12.919817 | localhost | ok: 2025-10-03 13:37:12.919996 | localhost | # Node Information 2025-10-03 13:37:12.920031 | localhost | Inventory Hostname: controller 2025-10-03 13:37:12.920057 | localhost | Hostname: np0005469520 2025-10-03 13:37:12.920079 | localhost | Username: zuul 2025-10-03 13:37:12.920100 | localhost | Distro: CentOS 9 2025-10-03 13:37:12.920119 | localhost | Provider: ibm-bm4-nodepool 2025-10-03 13:37:12.920137 | localhost | Region: regionOne 2025-10-03 13:37:12.920155 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm 2025-10-03 13:37:12.920173 | localhost | Product Name: OpenStack Compute 2025-10-03 13:37:12.920190 | localhost | Interface IP: 192.168.26.2 2025-10-03 13:37:12.938365 | 2025-10-03 13:37:12.938450 | PLAY [all] 2025-10-03 13:37:12.945217 | 2025-10-03 13:37:12.945280 | TASK [Gather network facts] 2025-10-03 13:37:13.370154 | controller | ok 2025-10-03 13:37:13.384595 | 2025-10-03 13:37:13.384676 | TASK [include_role : start-zuul-console] 2025-10-03 13:37:13.402002 | controller | ok 2025-10-03 13:37:13.413115 | 2025-10-03 13:37:13.413181 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 13:37:13.775255 | controller | ok 2025-10-03 13:37:13.783511 | 2025-10-03 13:37:13.783580 | TASK [include_role : add-build-sshkey] 2025-10-03 13:37:13.801072 | controller | ok 2025-10-03 13:37:13.814040 | 2025-10-03 13:37:13.814113 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 13:37:14.009788 | controller -> localhost | ok 2025-10-03 13:37:14.016599 | 2025-10-03 13:37:14.016668 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 13:37:14.045242 | controller | ok 2025-10-03 13:37:14.058667 | controller | included: /var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 13:37:14.064427 | 2025-10-03 13:37:14.064497 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 13:37:14.554239 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 13:37:14.554426 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/885a5445a75149358ed989c1aa35f0bf_id_rsa. 2025-10-03 13:37:14.554461 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/885a5445a75149358ed989c1aa35f0bf_id_rsa.pub. 2025-10-03 13:37:14.554487 | controller -> localhost | The key fingerprint is: 2025-10-03 13:37:14.554509 | controller -> localhost | SHA256:/kR8MsmSskJWL9zzjJsWH3Zsqo1kugPH1VvF6+oN+cY zuul-build-sshkey 2025-10-03 13:37:14.554530 | controller -> localhost | The key's randomart image is: 2025-10-03 13:37:14.554551 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 13:37:14.554570 | controller -> localhost | | . | 2025-10-03 13:37:14.554591 | controller -> localhost | | o | 2025-10-03 13:37:14.554611 | controller -> localhost | | . . . . | 2025-10-03 13:37:14.554632 | controller -> localhost | | o o.+... . | 2025-10-03 13:37:14.554652 | controller -> localhost | | o.+.S B+.. | 2025-10-03 13:37:14.554671 | controller -> localhost | | o. o=.B+++.. | 2025-10-03 13:37:14.554690 | controller -> localhost | | .o. =++=oo | 2025-10-03 13:37:14.554709 | controller -> localhost | | ..+.Bo .+E | 2025-10-03 13:37:14.554728 | controller -> localhost | | o+=.o...o | 2025-10-03 13:37:14.554746 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 13:37:14.554790 | controller -> localhost | ok: Runtime: 0:00:00.132562 2025-10-03 13:37:14.562859 | 2025-10-03 13:37:14.562940 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 13:37:14.581247 | controller | ok 2025-10-03 13:37:14.591321 | controller | included: /var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 13:37:14.599945 | 2025-10-03 13:37:14.600026 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 13:37:14.613347 | controller | skipping: Conditional result was False 2025-10-03 13:37:14.619279 | 2025-10-03 13:37:14.619348 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 13:37:15.098572 | controller | changed 2025-10-03 13:37:15.103382 | 2025-10-03 13:37:15.103453 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 13:37:15.356619 | controller | ok 2025-10-03 13:37:15.362144 | 2025-10-03 13:37:15.362214 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 13:37:16.431992 | controller | changed 2025-10-03 13:37:16.437339 | 2025-10-03 13:37:16.437427 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 13:37:17.494491 | controller | changed 2025-10-03 13:37:17.499719 | 2025-10-03 13:37:17.499787 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 13:37:17.513766 | controller | skipping: Conditional result was False 2025-10-03 13:37:17.520044 | 2025-10-03 13:37:17.520111 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 13:37:17.867764 | controller -> localhost | changed 2025-10-03 13:37:17.877793 | 2025-10-03 13:37:17.877866 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 13:37:18.124077 | controller -> localhost | Identity added: /var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/885a5445a75149358ed989c1aa35f0bf_id_rsa (zuul-build-sshkey) 2025-10-03 13:37:18.124273 | controller -> localhost | ok: Runtime: 0:00:00.009214 2025-10-03 13:37:18.130582 | 2025-10-03 13:37:18.130649 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 13:37:18.503793 | controller | ok 2025-10-03 13:37:18.508599 | 2025-10-03 13:37:18.508668 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 13:37:18.535156 | controller | skipping: Conditional result was False 2025-10-03 13:37:18.549537 | 2025-10-03 13:37:18.549629 | TASK [include_role : validate-host] 2025-10-03 13:37:18.568366 | controller | ok 2025-10-03 13:37:18.591594 | 2025-10-03 13:37:18.591685 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 13:37:18.619548 | controller | ok 2025-10-03 13:37:18.624462 | 2025-10-03 13:37:18.624536 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 13:37:18.834772 | controller -> localhost | ok 2025-10-03 13:37:18.841515 | 2025-10-03 13:37:18.841603 | TASK [validate-host : Collect information about the host] 2025-10-03 13:37:19.546366 | controller | ok 2025-10-03 13:37:19.555417 | 2025-10-03 13:37:19.555501 | TASK [validate-host : Sanitize hostname] 2025-10-03 13:37:19.604653 | controller | ok 2025-10-03 13:37:19.609767 | 2025-10-03 13:37:19.609858 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 13:37:20.043957 | controller -> localhost | changed 2025-10-03 13:37:20.049828 | 2025-10-03 13:37:20.049896 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 13:37:20.467457 | controller | ok 2025-10-03 13:37:20.472404 | 2025-10-03 13:37:20.472493 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 13:37:20.861227 | controller -> localhost | changed 2025-10-03 13:37:20.870678 | 2025-10-03 13:37:20.870760 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 13:37:20.894255 | controller | skipping: Conditional result was False 2025-10-03 13:37:20.899519 | 2025-10-03 13:37:20.899586 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 13:37:20.912361 | controller | skipping: Conditional result was False 2025-10-03 13:37:20.917273 | 2025-10-03 13:37:20.917339 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 13:37:21.172870 | controller | ok: "logs" 2025-10-03 13:37:21.173117 | controller | ok: All items complete 2025-10-03 13:37:21.173154 | 2025-10-03 13:37:21.404941 | controller | ok: "artifacts" 2025-10-03 13:37:21.628220 | controller | ok: "docs" 2025-10-03 13:37:21.635499 | 2025-10-03 13:37:21.635583 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 13:37:21.898095 | controller | changed: "logs" 2025-10-03 13:37:22.140746 | controller | changed: "artifacts" 2025-10-03 13:37:22.365617 | controller | changed: "docs" 2025-10-03 13:37:22.396640 | 2025-10-03 13:37:22.396725 | PLAY RECAP 2025-10-03 13:37:22.396772 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 13:37:22.396801 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 13:37:22.396821 | 2025-10-03 13:37:22.494435 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 13:37:22.495209 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 13:37:23.110850 | 2025-10-03 13:37:23.110987 | PLAY [all] 2025-10-03 13:37:23.134686 | 2025-10-03 13:37:23.134790 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 13:37:23.194646 | controller | ok 2025-10-03 13:37:23.199983 | 2025-10-03 13:37:23.200075 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 13:37:23.629318 | controller | changed 2025-10-03 13:37:23.635305 | 2025-10-03 13:37:23.635392 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 13:37:24.871375 | controller | changed 2025-10-03 13:37:24.884238 | 2025-10-03 13:37:24.884381 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-03 13:37:25.359375 | controller | changed: 2025-10-03 13:37:25.359959 | controller | { 2025-10-03 13:37:25.360047 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-03 13:37:25.360402 | controller | } 2025-10-03 13:37:25.650435 | controller | changed: 2025-10-03 13:37:25.650536 | controller | { 2025-10-03 13:37:25.650568 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-03 13:37:25.650591 | controller | } 2025-10-03 13:37:25.931514 | controller | changed: 2025-10-03 13:37:25.931610 | controller | { 2025-10-03 13:37:25.931641 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-03 13:37:25.931664 | controller | } 2025-10-03 13:37:26.206372 | controller | changed: 2025-10-03 13:37:26.206464 | controller | { 2025-10-03 13:37:26.206492 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-03 13:37:26.206513 | controller | } 2025-10-03 13:37:26.482729 | controller | changed: 2025-10-03 13:37:26.482810 | controller | { 2025-10-03 13:37:26.482836 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-03 13:37:26.482856 | controller | } 2025-10-03 13:37:26.760690 | controller | changed: 2025-10-03 13:37:26.760779 | controller | { 2025-10-03 13:37:26.760811 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-03 13:37:26.760834 | controller | } 2025-10-03 13:37:27.036881 | controller | changed: 2025-10-03 13:37:27.037064 | controller | { 2025-10-03 13:37:27.037100 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-03 13:37:27.037123 | controller | } 2025-10-03 13:37:27.319019 | controller | changed: 2025-10-03 13:37:27.319102 | controller | { 2025-10-03 13:37:27.319134 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-03 13:37:27.319157 | controller | } 2025-10-03 13:37:27.602223 | controller | changed: 2025-10-03 13:37:27.602324 | controller | { 2025-10-03 13:37:27.602356 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-03 13:37:27.602379 | controller | } 2025-10-03 13:37:27.902596 | controller | changed: 2025-10-03 13:37:27.902695 | controller | { 2025-10-03 13:37:27.902724 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-03 13:37:27.902747 | controller | } 2025-10-03 13:37:28.159223 | controller | changed: 2025-10-03 13:37:28.159319 | controller | { 2025-10-03 13:37:28.159348 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-03 13:37:28.159371 | controller | } 2025-10-03 13:37:28.439375 | controller | changed: 2025-10-03 13:37:28.439488 | controller | { 2025-10-03 13:37:28.439527 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-03 13:37:28.439557 | controller | } 2025-10-03 13:37:28.725392 | controller | changed: 2025-10-03 13:37:28.725495 | controller | { 2025-10-03 13:37:28.725526 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-03 13:37:28.725550 | controller | } 2025-10-03 13:37:29.034402 | controller | changed: 2025-10-03 13:37:29.034514 | controller | { 2025-10-03 13:37:29.034546 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-03 13:37:29.034569 | controller | } 2025-10-03 13:37:29.320115 | controller | changed: 2025-10-03 13:37:29.320217 | controller | { 2025-10-03 13:37:29.320248 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-03 13:37:29.320272 | controller | } 2025-10-03 13:37:29.606538 | controller | changed: 2025-10-03 13:37:29.606635 | controller | { 2025-10-03 13:37:29.606673 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-03 13:37:29.606704 | controller | } 2025-10-03 13:37:29.895089 | controller | changed: 2025-10-03 13:37:29.895182 | controller | { 2025-10-03 13:37:29.895209 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-03 13:37:29.895231 | controller | } 2025-10-03 13:37:30.183073 | controller | changed: 2025-10-03 13:37:30.183169 | controller | { 2025-10-03 13:37:30.183197 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-03 13:37:30.183229 | controller | } 2025-10-03 13:37:30.469066 | controller | changed: 2025-10-03 13:37:30.469167 | controller | { 2025-10-03 13:37:30.469195 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-03 13:37:30.469216 | controller | } 2025-10-03 13:37:30.755267 | controller | changed: 2025-10-03 13:37:30.755622 | controller | { 2025-10-03 13:37:30.755652 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-03 13:37:30.755675 | controller | } 2025-10-03 13:37:31.038412 | controller | changed: 2025-10-03 13:37:31.038607 | controller | { 2025-10-03 13:37:31.038643 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-03 13:37:31.038667 | controller | } 2025-10-03 13:37:31.314278 | controller | changed: 2025-10-03 13:37:31.314366 | controller | { 2025-10-03 13:37:31.314394 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-03 13:37:31.314414 | controller | } 2025-10-03 13:37:31.600136 | controller | changed: 2025-10-03 13:37:31.600224 | controller | { 2025-10-03 13:37:31.600253 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-03 13:37:31.600276 | controller | } 2025-10-03 13:37:31.889986 | controller | changed: 2025-10-03 13:37:31.890068 | controller | { 2025-10-03 13:37:31.890095 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-03 13:37:31.890115 | controller | } 2025-10-03 13:37:32.178678 | controller | changed: 2025-10-03 13:37:32.180391 | controller | { 2025-10-03 13:37:32.180426 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-03 13:37:32.180450 | controller | } 2025-10-03 13:37:32.483747 | controller | changed: 2025-10-03 13:37:32.483835 | controller | { 2025-10-03 13:37:32.483862 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-03 13:37:32.483884 | controller | } 2025-10-03 13:37:32.518979 | 2025-10-03 13:37:32.519240 | TASK [Set timezone to UTC] 2025-10-03 13:37:33.025349 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-03 13:37:33.032895 | 2025-10-03 13:37:33.034080 | TASK [Create nodepool directory] 2025-10-03 13:37:33.308998 | controller | changed 2025-10-03 13:37:33.318902 | 2025-10-03 13:37:33.319000 | TASK [Create nodepool sub_nodes file] 2025-10-03 13:37:34.253598 | controller | changed 2025-10-03 13:37:34.260280 | 2025-10-03 13:37:34.260354 | TASK [Create nodepool sub_nodes_private file] 2025-10-03 13:37:35.164540 | controller | changed 2025-10-03 13:37:35.170442 | 2025-10-03 13:37:35.170535 | LOOP [Populate nodepool sub_nodes file] 2025-10-03 13:37:35.200557 | 2025-10-03 13:37:35.200705 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-03 13:37:35.230360 | 2025-10-03 13:37:35.230935 | TASK [Create nodepool primary file] 2025-10-03 13:37:35.259491 | controller | skipping: Conditional result was False 2025-10-03 13:37:35.265993 | 2025-10-03 13:37:35.266091 | TASK [Create nodepool node_private for this node] 2025-10-03 13:37:36.296544 | controller | changed 2025-10-03 13:37:36.301986 | 2025-10-03 13:37:36.302050 | LOOP [Copy ssh keys to nodepool directory] 2025-10-03 13:37:36.717094 | controller | ok: Item: id_rsa Runtime: 0:00:00.004363 2025-10-03 13:37:36.717264 | 2025-10-03 13:37:36.957616 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004992 2025-10-03 13:37:36.972127 | 2025-10-03 13:37:36.972403 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-03 13:37:37.981857 | controller | changed 2025-10-03 13:37:37.987681 | 2025-10-03 13:37:37.987753 | TASK [Validate sudoers config after edits] 2025-10-03 13:37:38.276707 | controller | /etc/sudoers: parsed OK 2025-10-03 13:37:38.276777 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-03 13:37:38.276787 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-03 13:37:38.276794 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-03 13:37:38.520886 | controller | ok: Runtime: 0:00:00.006178 2025-10-03 13:37:38.526747 | 2025-10-03 13:37:38.526844 | TASK [Show the environment passed in to job shell scripts] 2025-10-03 13:37:38.805008 | controller | SHELL=/bin/bash 2025-10-03 13:37:38.805089 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-03 13:37:38.805111 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-03 13:37:38.805123 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 13:37:38.805134 | controller | PWD=/home/zuul 2025-10-03 13:37:38.805144 | controller | ZUUL_PIPELINE=github-check 2025-10-03 13:37:38.805154 | controller | LOGNAME=zuul 2025-10-03 13:37:38.805165 | controller | XDG_SESSION_TYPE=tty 2025-10-03 13:37:38.805228 | controller | _=/usr/bin/env 2025-10-03 13:37:38.805237 | controller | MOTD_SHOWN=pam 2025-10-03 13:37:38.805243 | controller | HOME=/home/zuul 2025-10-03 13:37:38.805248 | controller | LANG=en_US.UTF-8 2025-10-03 13:37:38.805253 | controller | SSH_CONNECTION=192.168.26.12 41748 192.168.26.2 22 2025-10-03 13:37:38.805260 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-03 13:37:38.805269 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 13:37:38.805275 | controller | WORKSPACE=/home/zuul/workspace 2025-10-03 13:37:38.805281 | controller | XDG_SESSION_CLASS=user 2025-10-03 13:37:38.805286 | controller | SELINUX_ROLE_REQUESTED= 2025-10-03 13:37:38.805291 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-03 13:37:38.805296 | controller | USER=zuul 2025-10-03 13:37:38.805302 | controller | ZUUL_VOTING=True 2025-10-03 13:37:38.805307 | controller | BUILD_TIMEOUT=5400000 2025-10-03 13:37:38.805312 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-03 13:37:38.805318 | controller | SHLVL=1 2025-10-03 13:37:38.805323 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 13:37:38.805329 | controller | XDG_SESSION_ID=1 2025-10-03 13:37:38.805334 | controller | ZUUL_BRANCH=main 2025-10-03 13:37:38.805340 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-03 13:37:38.805345 | controller | SSH_CLIENT=192.168.26.12 41748 22 2025-10-03 13:37:38.805351 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-03 13:37:38.805356 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-03 13:37:38.805362 | controller | which_declare=declare -f 2025-10-03 13:37:38.805368 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 13:37:38.805373 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-03 13:37:38.805379 | controller | ZUUL_CHANGE=3362 2025-10-03 13:37:38.805384 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-03 13:37:38.805389 | controller | ZUUL_UUID=885a5445a75149358ed989c1aa35f0bf 2025-10-03 13:37:38.805396 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-03 13:37:38.805403 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-03 13:37:38.805408 | controller | } 2025-10-03 13:37:39.060807 | controller | ok: Runtime: 0:00:00.006024 2025-10-03 13:37:39.068482 | 2025-10-03 13:37:39.068582 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-03 13:37:39.082189 | controller | skipping: Conditional result was False 2025-10-03 13:37:39.089093 | 2025-10-03 13:37:39.089195 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-03 13:37:39.649808 | controller | skipping: Conditional result was False 2025-10-03 13:37:39.655885 | 2025-10-03 13:37:39.656145 | TASK [Ensure legacy workspace directory] 2025-10-03 13:37:39.907533 | controller | changed 2025-10-03 13:37:39.927869 | 2025-10-03 13:37:39.928000 | PLAY RECAP 2025-10-03 13:37:39.928051 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 13:37:39.928077 | 2025-10-03 13:37:40.022783 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 13:37:40.023555 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 13:37:40.581117 | 2025-10-03 13:37:40.581233 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-03 13:37:40.611681 | 2025-10-03 13:37:40.611762 | TASK [Create zuul-output directory] 2025-10-03 13:37:41.006169 | controller | changed 2025-10-03 13:37:41.013479 | 2025-10-03 13:37:41.013566 | TASK [Slurp Zuul inventory test] 2025-10-03 13:37:41.314670 | controller -> localhost | ok 2025-10-03 13:37:41.321498 | 2025-10-03 13:37:41.321569 | TASK [Save zuul inventory] 2025-10-03 13:37:42.572418 | controller | changed 2025-10-03 13:37:42.577855 | 2025-10-03 13:37:42.577946 | TASK [Save zuul vars without the change_message] 2025-10-03 13:37:43.649684 | controller | changed 2025-10-03 13:37:43.672421 | 2025-10-03 13:37:43.672536 | PLAY RECAP 2025-10-03 13:37:43.672589 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 13:37:43.672614 | 2025-10-03 13:37:43.814295 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 13:37:43.815190 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 13:37:44.563672 | 2025-10-03 13:37:44.563786 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-03 13:37:44.585503 | 2025-10-03 13:37:44.585592 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-03 13:37:44.614602 | controller | ok 2025-10-03 13:37:44.632194 | 2025-10-03 13:37:44.632280 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 13:37:44.655319 | controller | skipping: Conditional result was False 2025-10-03 13:37:44.661623 | 2025-10-03 13:37:44.661696 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 13:37:45.096676 | controller | ok 2025-10-03 13:37:45.103219 | 2025-10-03 13:37:45.103308 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 13:37:46.095134 | controller | ok 2025-10-03 13:37:46.115112 | 2025-10-03 13:37:46.115222 | TASK [Prepare workspace] 2025-10-03 13:37:46.133520 | controller | ok 2025-10-03 13:37:46.151151 | 2025-10-03 13:37:46.151224 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 13:37:46.529328 | controller | ok 2025-10-03 13:37:46.539734 | 2025-10-03 13:37:46.539835 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 13:37:48.589987 | controller | Output suppressed because no_log was given 2025-10-03 13:37:48.598581 | 2025-10-03 13:37:48.598645 | LOOP [Create zuul-output directory] 2025-10-03 13:37:48.848573 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-03 13:37:49.073080 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-03 13:37:49.083029 | 2025-10-03 13:37:49.083099 | TASK [Install required packages] 2025-10-03 13:38:08.851851 | controller | ok: Nothing to do 2025-10-03 13:38:08.857196 | 2025-10-03 13:38:08.857278 | TASK [Install venv] 2025-10-03 13:39:09.048018 | controller | changed 2025-10-03 13:39:09.074624 | 2025-10-03 13:39:09.074704 | PLAY RECAP 2025-10-03 13:39:09.074748 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 13:39:09.074771 | 2025-10-03 13:39:09.159607 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 13:39:09.160460 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 13:39:09.762836 | 2025-10-03 13:39:09.762962 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-03 13:39:09.784093 | 2025-10-03 13:39:09.784178 | TASK [Gather required facts] 2025-10-03 13:39:10.357940 | controller | ok 2025-10-03 13:39:10.363963 | 2025-10-03 13:39:10.364113 | TASK [Load environment var if instructed to] 2025-10-03 13:39:10.388223 | controller | skipping: Conditional result was False 2025-10-03 13:39:10.393778 | 2025-10-03 13:39:10.393857 | TASK [Run molecule] 2025-10-03 13:39:11.430531 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-03 13:39:11.784650 | controller | INFO Performing prerun with role_name_check=0... 2025-10-03 13:39:28.556715 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.557198 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.557693 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.558153 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.558653 | controller | WARNING Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.559117 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.559586 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.560019 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.560465 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.560892 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.561369 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.561812 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.562277 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.562709 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.563150 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:39:28.573435 | controller | INFO Running add_crc_creds > prepare 2025-10-03 13:39:29.290105 | controller | 2025-10-03 13:39:29.290271 | controller | PLAY [Prepare] ***************************************************************** 2025-10-03 13:39:29.290309 | controller | 2025-10-03 13:39:29.290423 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:39:29.290512 | controller | Friday 03 October 2025 13:39:29 +0000 (0:00:00.014) 0:00:00.014 ******** 2025-10-03 13:39:30.125666 | controller | ok: [instance] 2025-10-03 13:39:30.125715 | controller | 2025-10-03 13:39:30.125835 | controller | TASK [Setup libvirt] *********************************************************** 2025-10-03 13:39:30.125951 | controller | Friday 03 October 2025 13:39:30 +0000 (0:00:00.836) 0:00:00.851 ******** 2025-10-03 13:39:30.146549 | controller | skipping: [instance] 2025-10-03 13:39:30.146646 | controller | 2025-10-03 13:39:30.146770 | controller | TASK [Setup CRC] *************************************************************** 2025-10-03 13:39:30.146897 | controller | Friday 03 October 2025 13:39:30 +0000 (0:00:00.021) 0:00:00.872 ******** 2025-10-03 13:39:30.167352 | controller | skipping: [instance] 2025-10-03 13:39:30.167475 | controller | 2025-10-03 13:39:30.167580 | controller | TASK [Start RHOL/CRC] ********************************************************** 2025-10-03 13:39:30.167682 | controller | Friday 03 October 2025 13:39:30 +0000 (0:00:00.020) 0:00:00.892 ******** 2025-10-03 13:39:30.567480 | controller | changed: [instance] 2025-10-03 13:39:30.567555 | controller | 2025-10-03 13:39:30.567664 | controller | TASK [Inject our own NetworkManager configuration] ***************************** 2025-10-03 13:39:30.567776 | controller | Friday 03 October 2025 13:39:30 +0000 (0:00:00.400) 0:00:01.292 ******** 2025-10-03 13:39:31.138264 | controller | changed: [instance] 2025-10-03 13:39:31.138320 | controller | 2025-10-03 13:39:31.138438 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-03 13:39:31.138538 | controller | Friday 03 October 2025 13:39:31 +0000 (0:00:00.570) 0:00:01.863 ******** 2025-10-03 13:39:31.154252 | controller | skipping: [instance] 2025-10-03 13:39:31.154334 | controller | 2025-10-03 13:39:31.154438 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-03 13:39:31.154543 | controller | Friday 03 October 2025 13:39:31 +0000 (0:00:00.016) 0:00:01.879 ******** 2025-10-03 13:39:31.193388 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-03 13:39:31.193459 | controller | 2025-10-03 13:39:31.193564 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-03 13:39:31.193665 | controller | Friday 03 October 2025 13:39:31 +0000 (0:00:00.038) 0:00:01.918 ******** 2025-10-03 13:39:31.381194 | controller | ok: [instance] 2025-10-03 13:39:31.381307 | controller | 2025-10-03 13:39:31.381450 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-03 13:39:31.381574 | controller | Friday 03 October 2025 13:39:31 +0000 (0:00:00.187) 0:00:02.106 ******** 2025-10-03 13:39:31.696529 | controller | ok: [instance] 2025-10-03 13:39:31.696606 | controller | 2025-10-03 13:39:31.696710 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-03 13:39:31.696816 | controller | Friday 03 October 2025 13:39:31 +0000 (0:00:00.315) 0:00:02.421 ******** 2025-10-03 13:39:32.388297 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-03 13:39:32.388402 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-03 13:39:32.388510 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-03 13:39:32.388603 | controller | 2025-10-03 13:39:32.388704 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-03 13:39:32.388796 | controller | Friday 03 October 2025 13:39:32 +0000 (0:00:00.691) 0:00:03.113 ******** 2025-10-03 13:39:32.450016 | controller | 2025-10-03 13:39:32.450126 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-03 13:39:32.450321 | controller | Friday 03 October 2025 13:39:32 +0000 (0:00:00.061) 0:00:03.175 ******** 2025-10-03 13:39:33.000005 | controller | changed: [instance] => (item=tmp) 2025-10-03 13:39:33.000574 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-03 13:39:33.798134 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-03 13:39:33.798177 | controller | 2025-10-03 13:39:33.798187 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-03 13:39:33.798194 | controller | Friday 03 October 2025 13:39:32 +0000 (0:00:00.549) 0:00:03.725 ******** 2025-10-03 13:39:33.798208 | controller | ok: [instance] 2025-10-03 13:39:35.008800 | controller | 2025-10-03 13:39:35.008833 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-03 13:39:35.008841 | controller | Friday 03 October 2025 13:39:33 +0000 (0:00:00.797) 0:00:04.522 ******** 2025-10-03 13:39:35.008852 | controller | changed: [instance] 2025-10-03 13:39:43.740471 | controller | 2025-10-03 13:39:43.740509 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-03 13:39:43.740522 | controller | Friday 03 October 2025 13:39:35 +0000 (0:00:01.210) 0:00:05.733 ******** 2025-10-03 13:39:43.740533 | controller | changed: [instance] 2025-10-03 13:39:44.497841 | controller | 2025-10-03 13:39:44.497870 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-03 13:39:44.497879 | controller | Friday 03 October 2025 13:39:43 +0000 (0:00:08.730) 0:00:14.464 ******** 2025-10-03 13:39:44.497889 | controller | changed: [instance] 2025-10-03 13:39:44.522671 | controller | 2025-10-03 13:39:44.522705 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-03 13:39:44.522714 | controller | Friday 03 October 2025 13:39:44 +0000 (0:00:00.757) 0:00:15.222 ******** 2025-10-03 13:39:44.522728 | controller | skipping: [instance] 2025-10-03 13:39:46.483092 | controller | 2025-10-03 13:39:46.483152 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-03 13:39:46.483166 | controller | Friday 03 October 2025 13:39:44 +0000 (0:00:00.024) 0:00:15.247 ******** 2025-10-03 13:39:46.483179 | controller | changed: [instance] 2025-10-03 13:39:46.530614 | controller | 2025-10-03 13:39:46.530652 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-03 13:39:46.530661 | controller | Friday 03 October 2025 13:39:46 +0000 (0:00:01.959) 0:00:17.206 ******** 2025-10-03 13:39:46.530673 | controller | skipping: [instance] 2025-10-03 13:39:46.576683 | controller | 2025-10-03 13:39:46.576717 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-03 13:39:46.576725 | controller | Friday 03 October 2025 13:39:46 +0000 (0:00:00.048) 0:00:17.255 ******** 2025-10-03 13:39:46.576736 | controller | skipping: [instance] 2025-10-03 13:39:46.626746 | controller | 2025-10-03 13:39:46.626797 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-03 13:39:46.626806 | controller | Friday 03 October 2025 13:39:46 +0000 (0:00:00.046) 0:00:17.301 ******** 2025-10-03 13:39:46.626818 | controller | skipping: [instance] 2025-10-03 13:39:47.280912 | controller | 2025-10-03 13:39:47.280988 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-03 13:39:47.281003 | controller | Friday 03 October 2025 13:39:46 +0000 (0:00:00.049) 0:00:17.350 ******** 2025-10-03 13:39:47.281039 | controller | changed: [instance] 2025-10-03 13:39:47.693419 | controller | 2025-10-03 13:39:47.693494 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-03 13:39:47.693508 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.653) 0:00:18.004 ******** 2025-10-03 13:39:47.693524 | controller | changed: [instance] 2025-10-03 13:39:47.728627 | controller | 2025-10-03 13:39:47.728681 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-03 13:39:47.728690 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.413) 0:00:18.417 ******** 2025-10-03 13:39:47.728707 | controller | skipping: [instance] 2025-10-03 13:39:47.765695 | controller | 2025-10-03 13:39:47.765740 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-03 13:39:47.765754 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.035) 0:00:18.452 ******** 2025-10-03 13:39:47.765803 | controller | skipping: [instance] 2025-10-03 13:39:47.802070 | controller | 2025-10-03 13:39:47.802106 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-03 13:39:47.802114 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.037) 0:00:18.490 ******** 2025-10-03 13:39:47.802125 | controller | skipping: [instance] 2025-10-03 13:39:47.848193 | controller | 2025-10-03 13:39:47.848231 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-03 13:39:47.848244 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.036) 0:00:18.526 ******** 2025-10-03 13:39:47.848256 | controller | ok: [instance] 2025-10-03 13:39:47.848459 | controller | 2025-10-03 13:39:47.848652 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-03 13:39:47.848838 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.046) 0:00:18.573 ******** 2025-10-03 13:39:47.881889 | controller | skipping: [instance] 2025-10-03 13:39:47.882162 | controller | 2025-10-03 13:39:47.882353 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-03 13:39:47.882556 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.033) 0:00:18.606 ******** 2025-10-03 13:39:47.915165 | controller | skipping: [instance] 2025-10-03 13:39:47.915668 | controller | 2025-10-03 13:39:47.915927 | controller | TASK [Download the RPM] ******************************************************** 2025-10-03 13:39:47.916195 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.031) 0:00:18.638 ******** 2025-10-03 13:39:47.948431 | controller | skipping: [instance] 2025-10-03 13:39:47.948694 | controller | 2025-10-03 13:39:47.948886 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-03 13:39:47.949085 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.034) 0:00:18.673 ******** 2025-10-03 13:39:47.983530 | controller | skipping: [instance] 2025-10-03 13:39:47.983798 | controller | 2025-10-03 13:39:47.984056 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-03 13:39:47.984285 | controller | Friday 03 October 2025 13:39:47 +0000 (0:00:00.035) 0:00:18.708 ******** 2025-10-03 13:39:48.014380 | controller | skipping: [instance] 2025-10-03 13:39:48.014562 | controller | 2025-10-03 13:39:48.014744 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-03 13:39:48.014935 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.031) 0:00:18.739 ******** 2025-10-03 13:39:48.049435 | controller | skipping: [instance] 2025-10-03 13:39:48.049993 | controller | 2025-10-03 13:39:48.050243 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-03 13:39:48.050458 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.032) 0:00:18.772 ******** 2025-10-03 13:39:48.078750 | controller | skipping: [instance] 2025-10-03 13:39:48.078928 | controller | 2025-10-03 13:39:48.079134 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-03 13:39:48.079307 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.031) 0:00:18.804 ******** 2025-10-03 13:39:48.339156 | controller | ok: [instance] 2025-10-03 13:39:48.620026 | controller | 2025-10-03 13:39:48.620087 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-03 13:39:48.620113 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.259) 0:00:19.063 ******** 2025-10-03 13:39:48.620127 | controller | changed: [instance] 2025-10-03 13:39:48.931943 | controller | 2025-10-03 13:39:48.932001 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-03 13:39:48.932012 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.278) 0:00:19.341 ******** 2025-10-03 13:39:48.932024 | controller | changed: [instance] 2025-10-03 13:39:48.954266 | controller | 2025-10-03 13:39:48.954294 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-03 13:39:48.954313 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.304) 0:00:19.646 ******** 2025-10-03 13:39:48.954327 | controller | skipping: [instance] 2025-10-03 13:39:48.954564 | controller | 2025-10-03 13:39:48.954809 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-03 13:39:48.955087 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.033) 0:00:19.679 ******** 2025-10-03 13:39:48.983312 | controller | skipping: [instance] 2025-10-03 13:39:48.983550 | controller | 2025-10-03 13:39:48.983823 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-03 13:39:48.984082 | controller | Friday 03 October 2025 13:39:48 +0000 (0:00:00.029) 0:00:19.708 ******** 2025-10-03 13:39:49.012214 | controller | skipping: [instance] 2025-10-03 13:39:49.012405 | controller | 2025-10-03 13:39:49.012632 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-03 13:39:49.012809 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.028) 0:00:19.737 ******** 2025-10-03 13:39:49.042692 | controller | skipping: [instance] 2025-10-03 13:39:49.042957 | controller | 2025-10-03 13:39:49.043189 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-03 13:39:49.043411 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.029) 0:00:19.767 ******** 2025-10-03 13:39:49.073112 | controller | skipping: [instance] 2025-10-03 13:39:49.100310 | controller | 2025-10-03 13:39:49.100368 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-03 13:39:49.100380 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.029) 0:00:19.797 ******** 2025-10-03 13:39:49.100402 | controller | skipping: [instance] 2025-10-03 13:39:49.122636 | controller | 2025-10-03 13:39:49.122673 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-03 13:39:49.122688 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.027) 0:00:19.825 ******** 2025-10-03 13:39:49.122702 | controller | skipping: [instance] 2025-10-03 13:39:49.158610 | controller | 2025-10-03 13:39:49.158641 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-03 13:39:49.158649 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.022) 0:00:19.847 ******** 2025-10-03 13:39:49.158660 | controller | skipping: [instance] 2025-10-03 13:39:49.185108 | controller | 2025-10-03 13:39:49.185153 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-03 13:39:49.185162 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.036) 0:00:19.883 ******** 2025-10-03 13:39:49.185173 | controller | skipping: [instance] 2025-10-03 13:39:49.213850 | controller | 2025-10-03 13:39:49.213887 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-03 13:39:49.213896 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.026) 0:00:19.909 ******** 2025-10-03 13:39:49.213915 | controller | skipping: [instance] 2025-10-03 13:39:49.250084 | controller | 2025-10-03 13:39:49.250137 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-03 13:39:49.250147 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.028) 0:00:19.938 ******** 2025-10-03 13:39:49.250163 | controller | skipping: [instance] 2025-10-03 13:39:49.288193 | controller | 2025-10-03 13:39:49.288250 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-03 13:39:49.288259 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.036) 0:00:19.974 ******** 2025-10-03 13:39:49.288271 | controller | skipping: [instance] 2025-10-03 13:39:49.322722 | controller | 2025-10-03 13:39:49.322783 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-03 13:39:49.322793 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.037) 0:00:20.012 ******** 2025-10-03 13:39:49.322806 | controller | skipping: [instance] 2025-10-03 13:39:49.323088 | controller | 2025-10-03 13:39:49.323321 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-03 13:39:49.323566 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.035) 0:00:20.047 ******** 2025-10-03 13:39:49.358665 | controller | skipping: [instance] 2025-10-03 13:39:49.358957 | controller | 2025-10-03 13:39:49.359209 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-03 13:39:49.359423 | controller | Friday 03 October 2025 13:39:49 +0000 (0:00:00.036) 0:00:20.083 ******** 2025-10-03 13:40:33.441835 | controller | ok: [instance] 2025-10-03 13:40:33.442005 | controller | 2025-10-03 13:40:33.442018 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-03 13:40:33.442050 | controller | Friday 03 October 2025 13:40:33 +0000 (0:00:44.082) 0:01:04.166 ******** 2025-10-03 13:40:34.721743 | controller | ok: [instance] 2025-10-03 13:40:34.754783 | controller | 2025-10-03 13:40:34.754833 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-03 13:40:34.754842 | controller | Friday 03 October 2025 13:40:34 +0000 (0:00:01.280) 0:01:05.446 ******** 2025-10-03 13:40:34.754852 | controller | skipping: [instance] 2025-10-03 13:40:34.802042 | controller | 2025-10-03 13:40:34.802081 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2025-10-03 13:40:34.802090 | controller | Friday 03 October 2025 13:40:34 +0000 (0:00:00.033) 0:01:05.479 ******** 2025-10-03 13:40:34.802101 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-03 13:40:34.802201 | controller | 2025-10-03 13:40:34.802307 | controller | TASK [ci_setup : List packages to install] ************************************* 2025-10-03 13:40:34.802329 | controller | Friday 03 October 2025 13:40:34 +0000 (0:00:00.047) 0:01:05.527 ******** 2025-10-03 13:40:34.825613 | controller | ok: [instance] => 2025-10-03 13:40:34.825671 | controller | cifmw_ci_setup_packages: 2025-10-03 13:40:34.825680 | controller | - bash-completion 2025-10-03 13:40:34.825688 | controller | - ca-certificates 2025-10-03 13:40:34.825988 | controller | - git-core 2025-10-03 13:40:34.826147 | controller | - make 2025-10-03 13:40:34.826183 | controller | - tar 2025-10-03 13:40:34.826196 | controller | - tmux 2025-10-03 13:40:34.826230 | controller | - python3-pip 2025-10-03 13:40:34.826482 | controller | 2025-10-03 13:40:34.826517 | controller | TASK [ci_setup : Install needed packages] ************************************** 2025-10-03 13:40:41.701073 | controller | Friday 03 October 2025 13:40:34 +0000 (0:00:00.023) 0:01:05.550 ******** 2025-10-03 13:40:41.701147 | controller | changed: [instance] 2025-10-03 13:40:42.324948 | controller | 2025-10-03 13:40:42.325000 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2025-10-03 13:40:42.325011 | controller | Friday 03 October 2025 13:40:41 +0000 (0:00:06.871) 0:01:12.422 ******** 2025-10-03 13:40:42.325039 | controller | ok: [instance] 2025-10-03 13:40:42.325070 | controller | 2025-10-03 13:40:42.325267 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2025-10-03 13:40:42.325320 | controller | Friday 03 October 2025 13:40:42 +0000 (0:00:00.627) 0:01:13.049 ******** 2025-10-03 13:40:42.580736 | controller | changed: [instance] 2025-10-03 13:40:42.580816 | controller | 2025-10-03 13:40:42.580832 | controller | TASK [ci_setup : Install openshift client] ************************************* 2025-10-03 13:40:42.580992 | controller | Friday 03 October 2025 13:40:42 +0000 (0:00:00.255) 0:01:13.305 ******** 2025-10-03 13:40:48.852541 | controller | changed: [instance] 2025-10-03 13:40:48.852613 | controller | 2025-10-03 13:40:48.852735 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2025-10-03 13:40:48.852757 | controller | Friday 03 October 2025 13:40:48 +0000 (0:00:06.270) 0:01:19.576 ******** 2025-10-03 13:40:48.904638 | controller | ok: [instance] 2025-10-03 13:40:48.904752 | controller | 2025-10-03 13:40:48.904969 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-03 13:40:48.904985 | controller | Friday 03 October 2025 13:40:48 +0000 (0:00:00.053) 0:01:19.629 ******** 2025-10-03 13:40:49.249006 | controller | changed: [instance] 2025-10-03 13:40:49.249102 | controller | 2025-10-03 13:40:49.249324 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2025-10-03 13:40:49.249364 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.344) 0:01:19.973 ******** 2025-10-03 13:40:49.599604 | controller | changed: [instance] 2025-10-03 13:40:49.599686 | controller | 2025-10-03 13:40:49.599737 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-03 13:40:49.599920 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.350) 0:01:20.324 ******** 2025-10-03 13:40:49.623779 | controller | skipping: [instance] 2025-10-03 13:40:49.623852 | controller | 2025-10-03 13:40:49.623968 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-03 13:40:49.624128 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.023) 0:01:20.348 ******** 2025-10-03 13:40:49.647667 | controller | skipping: [instance] 2025-10-03 13:40:49.647766 | controller | 2025-10-03 13:40:49.647964 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-03 13:40:49.648084 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.024) 0:01:20.372 ******** 2025-10-03 13:40:49.672085 | controller | skipping: [instance] 2025-10-03 13:40:49.672176 | controller | 2025-10-03 13:40:49.672349 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-03 13:40:49.672461 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.024) 0:01:20.396 ******** 2025-10-03 13:40:49.696558 | controller | skipping: [instance] 2025-10-03 13:40:49.696696 | controller | 2025-10-03 13:40:49.696856 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-03 13:40:49.697018 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.024) 0:01:20.421 ******** 2025-10-03 13:40:49.721721 | controller | skipping: [instance] 2025-10-03 13:40:49.721824 | controller | 2025-10-03 13:40:49.722000 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-03 13:40:49.722184 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.025) 0:01:20.446 ******** 2025-10-03 13:40:49.757012 | controller | skipping: [instance] 2025-10-03 13:40:49.757128 | controller | 2025-10-03 13:40:49.757253 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-03 13:40:49.757375 | controller | Friday 03 October 2025 13:40:49 +0000 (0:00:00.034) 0:01:20.481 ******** 2025-10-03 13:40:50.917951 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-03 13:40:50.918045 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-03 13:40:50.918633 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-03 13:40:50.918682 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-03 13:40:50.918691 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-03 13:40:50.918697 | controller | 2025-10-03 13:40:50.918703 | controller | TASK [Check for CRC startup] *************************************************** 2025-10-03 13:40:50.918711 | controller | Friday 03 October 2025 13:40:50 +0000 (0:00:01.161) 0:01:21.642 ******** 2025-10-03 13:44:12.181945 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (120 retries left). 2025-10-03 13:44:12.182123 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (119 retries left). 2025-10-03 13:44:12.182142 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (118 retries left). 2025-10-03 13:44:12.182153 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (117 retries left). 2025-10-03 13:44:12.182213 | controller | changed: [instance] 2025-10-03 13:44:12.182228 | controller | 2025-10-03 13:44:12.182235 | controller | RUNNING HANDLER [Restart NetworkManager] *************************************** 2025-10-03 13:44:12.182243 | controller | Friday 03 October 2025 13:44:12 +0000 (0:03:21.262) 0:04:42.905 ******** 2025-10-03 13:44:13.097167 | controller | changed: [instance] 2025-10-03 13:44:13.097316 | controller | 2025-10-03 13:44:13.097336 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:44:13.097349 | controller | instance : ok=33 changed=19 unreachable=0 failed=0 skipped=38 rescued=0 ignored=0 2025-10-03 13:44:13.097424 | controller | 2025-10-03 13:44:13.097577 | controller | Friday 03 October 2025 13:44:13 +0000 (0:00:00.916) 0:04:43.821 ******** 2025-10-03 13:44:13.097659 | controller | =============================================================================== 2025-10-03 13:44:13.097769 | controller | Check for CRC startup ------------------------------------------------- 201.26s 2025-10-03 13:44:13.098164 | controller | test_deps : Install selinux python libs -------------------------------- 44.08s 2025-10-03 13:44:13.098282 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.73s 2025-10-03 13:44:13.098293 | controller | ci_setup : Install needed packages -------------------------------------- 6.87s 2025-10-03 13:44:13.098309 | controller | ci_setup : Install openshift client ------------------------------------- 6.27s 2025-10-03 13:44:13.098384 | controller | repo_setup : Run repo-setup --------------------------------------------- 1.96s 2025-10-03 13:44:13.098484 | controller | test_deps : Install python yaml libs ------------------------------------ 1.28s 2025-10-03 13:44:13.098601 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.21s 2025-10-03 13:44:13.098729 | controller | ci_setup : Manage directories ------------------------------------------- 1.16s 2025-10-03 13:44:13.098922 | controller | Restart NetworkManager -------------------------------------------------- 0.92s 2025-10-03 13:44:13.099110 | controller | Gathering Facts --------------------------------------------------------- 0.84s 2025-10-03 13:44:13.099227 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.80s 2025-10-03 13:44:13.099450 | controller | repo_setup : Install repo-setup package --------------------------------- 0.76s 2025-10-03 13:44:13.099566 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s 2025-10-03 13:44:13.099694 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.65s 2025-10-03 13:44:13.099856 | controller | ci_setup : Gather version of openshift client --------------------------- 0.63s 2025-10-03 13:44:13.099985 | controller | Inject our own NetworkManager configuration ----------------------------- 0.57s 2025-10-03 13:44:13.100161 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s 2025-10-03 13:44:13.100309 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.41s 2025-10-03 13:44:13.100452 | controller | Start RHOL/CRC ---------------------------------------------------------- 0.40s 2025-10-03 13:44:13.204173 | controller | INFO Running add_crc_creds > converge 2025-10-03 13:44:13.701613 | controller | 2025-10-03 13:44:14.813888 | controller | PLAY [Converge add_crc_creds] ************************************************** 2025-10-03 13:44:14.813989 | controller | 2025-10-03 13:44:14.813999 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:44:14.814007 | controller | Friday 03 October 2025 13:44:13 +0000 (0:00:00.042) 0:00:00.042 ******** 2025-10-03 13:44:14.814047 | controller | ok: [instance] 2025-10-03 13:44:14.849416 | controller | 2025-10-03 13:44:14.849456 | controller | TASK [Verify crc creds playbook] *********************************************** 2025-10-03 13:44:14.849469 | controller | Friday 03 October 2025 13:44:14 +0000 (0:00:01.110) 0:00:01.152 ******** 2025-10-03 13:44:14.849481 | controller | 2025-10-03 13:44:15.211896 | controller | TASK [rhol_crc : Make sure kubeconfig file exists] ***************************** 2025-10-03 13:44:15.211940 | controller | Friday 03 October 2025 13:44:14 +0000 (0:00:00.037) 0:00:01.190 ******** 2025-10-03 13:44:15.211953 | controller | ok: [instance] 2025-10-03 13:44:15.245425 | controller | 2025-10-03 13:44:15.245467 | controller | TASK [rhol_crc : Warn the user that the kubeconfig files doesn't exist] ******** 2025-10-03 13:44:15.245476 | controller | Friday 03 October 2025 13:44:15 +0000 (0:00:00.362) 0:00:01.552 ******** 2025-10-03 13:44:15.245486 | controller | skipping: [instance] 2025-10-03 13:44:15.575526 | controller | 2025-10-03 13:44:15.575576 | controller | TASK [rhol_crc : Add crc creds in the user bashrc file] ************************ 2025-10-03 13:44:15.575585 | controller | Friday 03 October 2025 13:44:15 +0000 (0:00:00.033) 0:00:01.586 ******** 2025-10-03 13:44:15.575596 | controller | changed: [instance] 2025-10-03 13:44:16.286446 | controller | 2025-10-03 13:44:16.286491 | controller | TASK [rhol_crc : Source bashrc and confirm crc login] ************************** 2025-10-03 13:44:16.286501 | controller | Friday 03 October 2025 13:44:15 +0000 (0:00:00.329) 0:00:01.916 ******** 2025-10-03 13:44:16.286515 | controller | ok: [instance] 2025-10-03 13:44:16.347177 | controller | 2025-10-03 13:44:16.347209 | controller | TASK [rhol_crc : Check bashrc results] ***************************************** 2025-10-03 13:44:16.347226 | controller | Friday 03 October 2025 13:44:16 +0000 (0:00:00.711) 0:00:02.627 ******** 2025-10-03 13:44:16.347238 | controller | ok: [instance] => 2025-10-03 13:44:16.348843 | controller | bashrc_result.stdout: |- 2025-10-03 13:44:16.348872 | controller | /usr/local/bin/crc 2025-10-03 13:44:16.348894 | controller | system:admin 2025-10-03 13:44:16.348901 | controller | 2025-10-03 13:44:16.348906 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:44:16.348912 | controller | instance : ok=5 changed=1 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2025-10-03 13:44:16.348917 | controller | 2025-10-03 13:44:16.348926 | controller | Friday 03 October 2025 13:44:16 +0000 (0:00:00.060) 0:00:02.688 ******** 2025-10-03 13:44:16.438947 | controller | =============================================================================== 2025-10-03 13:44:16.438983 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2025-10-03 13:44:16.438992 | controller | rhol_crc : Source bashrc and confirm crc login -------------------------- 0.71s 2025-10-03 13:44:16.438998 | controller | rhol_crc : Make sure kubeconfig file exists ----------------------------- 0.36s 2025-10-03 13:44:16.439003 | controller | rhol_crc : Add crc creds in the user bashrc file ------------------------ 0.33s 2025-10-03 13:44:16.439008 | controller | rhol_crc : Check bashrc results ----------------------------------------- 0.06s 2025-10-03 13:44:16.439014 | controller | Verify crc creds playbook ----------------------------------------------- 0.04s 2025-10-03 13:44:16.439019 | controller | rhol_crc : Warn the user that the kubeconfig files doesn't exist -------- 0.03s 2025-10-03 13:44:16.439060 | controller | INFO Running add_crc_creds > cleanup 2025-10-03 13:44:17.049511 | controller | 2025-10-03 13:44:18.058696 | controller | PLAY [Prepare] ***************************************************************** 2025-10-03 13:44:18.058739 | controller | 2025-10-03 13:44:18.058753 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:44:18.058760 | controller | Friday 03 October 2025 13:44:17 +0000 (0:00:00.020) 0:00:00.020 ******** 2025-10-03 13:44:18.058770 | controller | ok: [instance] 2025-10-03 13:44:18.391988 | controller | 2025-10-03 13:44:18.392079 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] ********************************* 2025-10-03 13:44:18.392098 | controller | Friday 03 October 2025 13:44:18 +0000 (0:00:01.009) 0:00:01.030 ******** 2025-10-03 13:44:18.392114 | controller | ok: [instance] 2025-10-03 13:44:18.437249 | controller | 2025-10-03 13:44:18.437296 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ******** 2025-10-03 13:44:18.437308 | controller | Friday 03 October 2025 13:44:18 +0000 (0:00:00.329) 0:00:01.359 ******** 2025-10-03 13:44:18.437319 | controller | skipping: [instance] 2025-10-03 13:44:18.497694 | controller | 2025-10-03 13:44:18.497742 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:44:18.497752 | controller | instance : ok=2 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2025-10-03 13:44:18.497758 | controller | 2025-10-03 13:44:18.497769 | controller | Friday 03 October 2025 13:44:18 +0000 (0:00:00.049) 0:00:01.408 ******** 2025-10-03 13:44:18.497775 | controller | =============================================================================== 2025-10-03 13:44:18.497792 | controller | Gathering Facts --------------------------------------------------------- 1.01s 2025-10-03 13:44:18.497797 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.33s 2025-10-03 13:44:18.497803 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 0.05s 2025-10-03 13:44:18.497814 | controller | INFO Performing prerun with role_name_check=0... 2025-10-03 13:44:37.660749 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.661431 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.662050 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.662593 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.663156 | controller | WARNING Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.663680 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.664227 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.664782 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.665311 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.665850 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.666390 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.666951 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.667433 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.667964 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.668459 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:44:37.678855 | controller | INFO Running binary > prepare 2025-10-03 13:44:38.195272 | controller | 2025-10-03 13:44:39.145642 | controller | PLAY [Prepare] ***************************************************************** 2025-10-03 13:44:39.145702 | controller | 2025-10-03 13:44:39.145710 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:44:39.145716 | controller | Friday 03 October 2025 13:44:38 +0000 (0:00:00.019) 0:00:00.019 ******** 2025-10-03 13:44:39.145727 | controller | ok: [instance] 2025-10-03 13:44:39.169540 | controller | 2025-10-03 13:44:39.169579 | controller | TASK [Setup libvirt] *********************************************************** 2025-10-03 13:44:39.169589 | controller | Friday 03 October 2025 13:44:39 +0000 (0:00:00.951) 0:00:00.970 ******** 2025-10-03 13:44:39.169606 | controller | skipping: [instance] 2025-10-03 13:44:39.195205 | controller | 2025-10-03 13:44:39.195239 | controller | TASK [Setup CRC] *************************************************************** 2025-10-03 13:44:39.195247 | controller | Friday 03 October 2025 13:44:39 +0000 (0:00:00.023) 0:00:00.994 ******** 2025-10-03 13:44:39.195257 | controller | skipping: [instance] 2025-10-03 13:44:39.219773 | controller | 2025-10-03 13:44:39.219808 | controller | TASK [Start RHOL/CRC] ********************************************************** 2025-10-03 13:44:39.219817 | controller | Friday 03 October 2025 13:44:39 +0000 (0:00:00.025) 0:00:01.019 ******** 2025-10-03 13:44:39.219827 | controller | skipping: [instance] 2025-10-03 13:44:39.879308 | controller | 2025-10-03 13:44:39.879346 | controller | TASK [Inject our own NetworkManager configuration] ***************************** 2025-10-03 13:44:39.879355 | controller | Friday 03 October 2025 13:44:39 +0000 (0:00:00.024) 0:00:01.044 ******** 2025-10-03 13:44:39.879371 | controller | ok: [instance] 2025-10-03 13:44:39.901420 | controller | 2025-10-03 13:44:39.901447 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-03 13:44:39.901455 | controller | Friday 03 October 2025 13:44:39 +0000 (0:00:00.659) 0:00:01.703 ******** 2025-10-03 13:44:39.901465 | controller | skipping: [instance] 2025-10-03 13:44:39.954495 | controller | 2025-10-03 13:44:39.954529 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-03 13:44:39.954537 | controller | Friday 03 October 2025 13:44:39 +0000 (0:00:00.022) 0:00:01.726 ******** 2025-10-03 13:44:39.954548 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-03 13:44:40.181705 | controller | 2025-10-03 13:44:40.181754 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-03 13:44:40.181764 | controller | Friday 03 October 2025 13:44:39 +0000 (0:00:00.052) 0:00:01.778 ******** 2025-10-03 13:44:40.181780 | controller | ok: [instance] 2025-10-03 13:44:40.459586 | controller | 2025-10-03 13:44:40.459633 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-03 13:44:40.459641 | controller | Friday 03 October 2025 13:44:40 +0000 (0:00:00.227) 0:00:02.006 ******** 2025-10-03 13:44:40.459669 | controller | ok: [instance] 2025-10-03 13:44:41.304553 | controller | 2025-10-03 13:44:41.304599 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-03 13:44:41.304607 | controller | Friday 03 October 2025 13:44:40 +0000 (0:00:00.277) 0:00:02.283 ******** 2025-10-03 13:44:41.304622 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-03 13:44:41.399195 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-03 13:44:41.399265 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-03 13:44:41.399275 | controller | 2025-10-03 13:44:41.399281 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-03 13:44:41.399295 | controller | Friday 03 October 2025 13:44:41 +0000 (0:00:00.844) 0:00:03.128 ******** 2025-10-03 13:44:41.399329 | controller | 2025-10-03 13:44:41.399719 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-03 13:44:42.048697 | controller | Friday 03 October 2025 13:44:41 +0000 (0:00:00.095) 0:00:03.223 ******** 2025-10-03 13:44:42.048738 | controller | ok: [instance] => (item=tmp) 2025-10-03 13:44:43.640801 | controller | ok: [instance] => (item=artifacts/repositories) 2025-10-03 13:44:43.640852 | controller | ok: [instance] => (item=venv/repo_setup) 2025-10-03 13:44:43.640862 | controller | 2025-10-03 13:44:43.640869 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-03 13:44:43.640884 | controller | Friday 03 October 2025 13:44:42 +0000 (0:00:00.649) 0:00:03.873 ******** 2025-10-03 13:44:43.640897 | controller | ok: [instance] 2025-10-03 13:44:44.289157 | controller | 2025-10-03 13:44:44.289204 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-03 13:44:44.289217 | controller | Friday 03 October 2025 13:44:43 +0000 (0:00:01.591) 0:00:05.464 ******** 2025-10-03 13:44:44.289230 | controller | ok: [instance] 2025-10-03 13:44:45.783347 | controller | 2025-10-03 13:44:45.783393 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-03 13:44:45.783410 | controller | Friday 03 October 2025 13:44:44 +0000 (0:00:00.648) 0:00:06.113 ******** 2025-10-03 13:44:45.783425 | controller | ok: [instance] 2025-10-03 13:44:46.137068 | controller | 2025-10-03 13:44:46.137108 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-03 13:44:46.137122 | controller | Friday 03 October 2025 13:44:45 +0000 (0:00:01.490) 0:00:07.603 ******** 2025-10-03 13:44:46.137134 | controller | ok: [instance] 2025-10-03 13:44:46.163488 | controller | 2025-10-03 13:44:46.163524 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-03 13:44:46.163533 | controller | Friday 03 October 2025 13:44:46 +0000 (0:00:00.357) 0:00:07.961 ******** 2025-10-03 13:44:46.163547 | controller | skipping: [instance] 2025-10-03 13:44:47.236841 | controller | 2025-10-03 13:44:47.236884 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-03 13:44:47.236892 | controller | Friday 03 October 2025 13:44:46 +0000 (0:00:00.026) 0:00:07.988 ******** 2025-10-03 13:44:47.236903 | controller | changed: [instance] 2025-10-03 13:44:47.287287 | controller | 2025-10-03 13:44:47.287338 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-03 13:44:47.287347 | controller | Friday 03 October 2025 13:44:47 +0000 (0:00:01.073) 0:00:09.061 ******** 2025-10-03 13:44:47.287375 | controller | skipping: [instance] 2025-10-03 13:44:47.332946 | controller | 2025-10-03 13:44:47.332979 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-03 13:44:47.332988 | controller | Friday 03 October 2025 13:44:47 +0000 (0:00:00.050) 0:00:09.111 ******** 2025-10-03 13:44:47.332999 | controller | skipping: [instance] 2025-10-03 13:44:47.379974 | controller | 2025-10-03 13:44:47.380077 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-03 13:44:47.380094 | controller | Friday 03 October 2025 13:44:47 +0000 (0:00:00.045) 0:00:09.157 ******** 2025-10-03 13:44:47.380107 | controller | skipping: [instance] 2025-10-03 13:44:47.973608 | controller | 2025-10-03 13:44:47.973646 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-03 13:44:47.973654 | controller | Friday 03 October 2025 13:44:47 +0000 (0:00:00.046) 0:00:09.204 ******** 2025-10-03 13:44:47.973665 | controller | changed: [instance] 2025-10-03 13:44:48.407092 | controller | 2025-10-03 13:44:48.407134 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-03 13:44:48.407143 | controller | Friday 03 October 2025 13:44:47 +0000 (0:00:00.593) 0:00:09.798 ******** 2025-10-03 13:44:48.407154 | controller | ok: [instance] 2025-10-03 13:44:48.441384 | controller | 2025-10-03 13:44:48.441422 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-03 13:44:48.441431 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.433) 0:00:10.231 ******** 2025-10-03 13:44:48.441442 | controller | skipping: [instance] 2025-10-03 13:44:48.475564 | controller | 2025-10-03 13:44:48.475633 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-03 13:44:48.475643 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.033) 0:00:10.265 ******** 2025-10-03 13:44:48.475658 | controller | skipping: [instance] 2025-10-03 13:44:48.511387 | controller | 2025-10-03 13:44:48.511433 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-03 13:44:48.511442 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.033) 0:00:10.299 ******** 2025-10-03 13:44:48.511457 | controller | skipping: [instance] 2025-10-03 13:44:48.559195 | controller | 2025-10-03 13:44:48.559250 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-03 13:44:48.559272 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.035) 0:00:10.335 ******** 2025-10-03 13:44:48.559292 | controller | ok: [instance] 2025-10-03 13:44:48.559540 | controller | 2025-10-03 13:44:48.559770 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-03 13:44:48.559946 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.048) 0:00:10.384 ******** 2025-10-03 13:44:48.592445 | controller | skipping: [instance] 2025-10-03 13:44:48.592694 | controller | 2025-10-03 13:44:48.592853 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-03 13:44:48.593053 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.033) 0:00:10.417 ******** 2025-10-03 13:44:48.624989 | controller | skipping: [instance] 2025-10-03 13:44:48.625278 | controller | 2025-10-03 13:44:48.625524 | controller | TASK [Download the RPM] ******************************************************** 2025-10-03 13:44:48.625744 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.032) 0:00:10.450 ******** 2025-10-03 13:44:48.657106 | controller | skipping: [instance] 2025-10-03 13:44:48.689294 | controller | 2025-10-03 13:44:48.689334 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-03 13:44:48.689343 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.031) 0:00:10.481 ******** 2025-10-03 13:44:48.689353 | controller | skipping: [instance] 2025-10-03 13:44:48.724327 | controller | 2025-10-03 13:44:48.724360 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-03 13:44:48.724368 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.032) 0:00:10.514 ******** 2025-10-03 13:44:48.724383 | controller | skipping: [instance] 2025-10-03 13:44:48.759896 | controller | 2025-10-03 13:44:48.759922 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-03 13:44:48.759936 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.035) 0:00:10.549 ******** 2025-10-03 13:44:48.759947 | controller | skipping: [instance] 2025-10-03 13:44:48.791708 | controller | 2025-10-03 13:44:48.791734 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-03 13:44:48.791742 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.035) 0:00:10.584 ******** 2025-10-03 13:44:48.791753 | controller | skipping: [instance] 2025-10-03 13:44:49.025372 | controller | 2025-10-03 13:44:49.025412 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-03 13:44:49.025421 | controller | Friday 03 October 2025 13:44:48 +0000 (0:00:00.032) 0:00:10.616 ******** 2025-10-03 13:44:49.025432 | controller | ok: [instance] 2025-10-03 13:44:49.315815 | controller | 2025-10-03 13:44:49.315858 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-03 13:44:49.315867 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.233) 0:00:10.850 ******** 2025-10-03 13:44:49.315877 | controller | changed: [instance] 2025-10-03 13:44:49.653876 | controller | 2025-10-03 13:44:49.653930 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-03 13:44:49.653939 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.290) 0:00:11.140 ******** 2025-10-03 13:44:49.653950 | controller | changed: [instance] 2025-10-03 13:44:49.679513 | controller | 2025-10-03 13:44:49.679553 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-03 13:44:49.679561 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.337) 0:00:11.477 ******** 2025-10-03 13:44:49.679572 | controller | skipping: [instance] 2025-10-03 13:44:49.707638 | controller | 2025-10-03 13:44:49.707682 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-03 13:44:49.707692 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.026) 0:00:11.504 ******** 2025-10-03 13:44:49.707705 | controller | skipping: [instance] 2025-10-03 13:44:49.735856 | controller | 2025-10-03 13:44:49.735894 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-03 13:44:49.735902 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.027) 0:00:11.532 ******** 2025-10-03 13:44:49.735913 | controller | skipping: [instance] 2025-10-03 13:44:49.767020 | controller | 2025-10-03 13:44:49.767106 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-03 13:44:49.767116 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.028) 0:00:11.560 ******** 2025-10-03 13:44:49.767127 | controller | skipping: [instance] 2025-10-03 13:44:49.797144 | controller | 2025-10-03 13:44:49.797185 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-03 13:44:49.797203 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.030) 0:00:11.591 ******** 2025-10-03 13:44:49.797223 | controller | skipping: [instance] 2025-10-03 13:44:49.827616 | controller | 2025-10-03 13:44:49.827662 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-03 13:44:49.827672 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.030) 0:00:11.621 ******** 2025-10-03 13:44:49.827684 | controller | skipping: [instance] 2025-10-03 13:44:49.852991 | controller | 2025-10-03 13:44:49.853041 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-03 13:44:49.853053 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.030) 0:00:11.652 ******** 2025-10-03 13:44:49.853072 | controller | skipping: [instance] 2025-10-03 13:44:49.890191 | controller | 2025-10-03 13:44:49.890232 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-03 13:44:49.890240 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.025) 0:00:11.677 ******** 2025-10-03 13:44:49.890250 | controller | skipping: [instance] 2025-10-03 13:44:49.913064 | controller | 2025-10-03 13:44:49.913102 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-03 13:44:49.913110 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.036) 0:00:11.714 ******** 2025-10-03 13:44:49.913120 | controller | skipping: [instance] 2025-10-03 13:44:49.913308 | controller | 2025-10-03 13:44:49.913489 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-03 13:44:49.913668 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.023) 0:00:11.738 ******** 2025-10-03 13:44:49.942736 | controller | skipping: [instance] 2025-10-03 13:44:49.942972 | controller | 2025-10-03 13:44:49.943198 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-03 13:44:49.943380 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.029) 0:00:11.767 ******** 2025-10-03 13:44:49.982762 | controller | skipping: [instance] 2025-10-03 13:44:49.982941 | controller | 2025-10-03 13:44:49.983130 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-03 13:44:49.983317 | controller | Friday 03 October 2025 13:44:49 +0000 (0:00:00.040) 0:00:11.807 ******** 2025-10-03 13:44:50.019043 | controller | skipping: [instance] 2025-10-03 13:44:50.019253 | controller | 2025-10-03 13:44:50.019441 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-03 13:44:50.019624 | controller | Friday 03 October 2025 13:44:50 +0000 (0:00:00.036) 0:00:11.844 ******** 2025-10-03 13:44:50.057835 | controller | skipping: [instance] 2025-10-03 13:44:50.058069 | controller | 2025-10-03 13:44:50.058230 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-03 13:44:50.058391 | controller | Friday 03 October 2025 13:44:50 +0000 (0:00:00.038) 0:00:11.882 ******** 2025-10-03 13:44:50.094944 | controller | skipping: [instance] 2025-10-03 13:44:50.095212 | controller | 2025-10-03 13:44:50.095429 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-03 13:44:50.095594 | controller | Friday 03 October 2025 13:44:50 +0000 (0:00:00.037) 0:00:11.920 ******** 2025-10-03 13:45:39.475991 | controller | ok: [instance] 2025-10-03 13:45:40.827137 | controller | 2025-10-03 13:45:40.827176 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-03 13:45:40.827185 | controller | Friday 03 October 2025 13:45:39 +0000 (0:00:49.380) 0:01:01.300 ******** 2025-10-03 13:45:40.827195 | controller | ok: [instance] 2025-10-03 13:45:40.867586 | controller | 2025-10-03 13:45:40.867626 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-03 13:45:40.867634 | controller | Friday 03 October 2025 13:45:40 +0000 (0:00:01.350) 0:01:02.650 ******** 2025-10-03 13:45:40.867645 | controller | skipping: [instance] 2025-10-03 13:45:40.921692 | controller | 2025-10-03 13:45:40.921732 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2025-10-03 13:45:40.921741 | controller | Friday 03 October 2025 13:45:40 +0000 (0:00:00.040) 0:01:02.691 ******** 2025-10-03 13:45:40.921752 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-03 13:45:40.947838 | controller | 2025-10-03 13:45:40.947871 | controller | TASK [ci_setup : List packages to install] ************************************* 2025-10-03 13:45:40.947879 | controller | Friday 03 October 2025 13:45:40 +0000 (0:00:00.054) 0:01:02.746 ******** 2025-10-03 13:45:40.947890 | controller | ok: [instance] => 2025-10-03 13:45:42.225306 | controller | cifmw_ci_setup_packages: 2025-10-03 13:45:42.225337 | controller | - bash-completion 2025-10-03 13:45:42.225344 | controller | - ca-certificates 2025-10-03 13:45:42.225351 | controller | - git-core 2025-10-03 13:45:42.225357 | controller | - make 2025-10-03 13:45:42.225362 | controller | - tar 2025-10-03 13:45:42.225368 | controller | - tmux 2025-10-03 13:45:42.225373 | controller | - python3-pip 2025-10-03 13:45:42.225379 | controller | 2025-10-03 13:45:42.225384 | controller | TASK [ci_setup : Install needed packages] ************************************** 2025-10-03 13:45:42.225394 | controller | Friday 03 October 2025 13:45:40 +0000 (0:00:00.025) 0:01:02.771 ******** 2025-10-03 13:45:42.225403 | controller | ok: [instance] 2025-10-03 13:45:42.509621 | controller | 2025-10-03 13:45:42.509655 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2025-10-03 13:45:42.509663 | controller | Friday 03 October 2025 13:45:42 +0000 (0:00:01.278) 0:01:04.050 ******** 2025-10-03 13:45:42.509673 | controller | ok: [instance] 2025-10-03 13:45:42.761269 | controller | 2025-10-03 13:45:42.761305 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2025-10-03 13:45:42.761314 | controller | Friday 03 October 2025 13:45:42 +0000 (0:00:00.284) 0:01:04.334 ******** 2025-10-03 13:45:42.761324 | controller | ok: [instance] 2025-10-03 13:45:42.827862 | controller | 2025-10-03 13:45:42.827896 | controller | TASK [ci_setup : Install openshift client] ************************************* 2025-10-03 13:45:42.827919 | controller | Friday 03 October 2025 13:45:42 +0000 (0:00:00.251) 0:01:04.586 ******** 2025-10-03 13:45:42.827931 | controller | skipping: [instance] 2025-10-03 13:45:42.862853 | controller | 2025-10-03 13:45:42.862891 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2025-10-03 13:45:42.862901 | controller | Friday 03 October 2025 13:45:42 +0000 (0:00:00.066) 0:01:04.652 ******** 2025-10-03 13:45:42.862913 | controller | skipping: [instance] 2025-10-03 13:45:43.054559 | controller | 2025-10-03 13:45:43.054594 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-03 13:45:43.054602 | controller | Friday 03 October 2025 13:45:42 +0000 (0:00:00.034) 0:01:04.687 ******** 2025-10-03 13:45:43.054612 | controller | ok: [instance] 2025-10-03 13:45:43.364557 | controller | 2025-10-03 13:45:43.364589 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2025-10-03 13:45:43.364597 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.191) 0:01:04.879 ******** 2025-10-03 13:45:43.364607 | controller | changed: [instance] 2025-10-03 13:45:43.385815 | controller | 2025-10-03 13:45:43.385842 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-03 13:45:43.385850 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.310) 0:01:05.189 ******** 2025-10-03 13:45:43.385860 | controller | skipping: [instance] 2025-10-03 13:45:43.412945 | controller | 2025-10-03 13:45:43.412972 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-03 13:45:43.412980 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.021) 0:01:05.211 ******** 2025-10-03 13:45:43.412990 | controller | skipping: [instance] 2025-10-03 13:45:43.437519 | controller | 2025-10-03 13:45:43.437551 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-03 13:45:43.437559 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.026) 0:01:05.237 ******** 2025-10-03 13:45:43.437569 | controller | skipping: [instance] 2025-10-03 13:45:43.456677 | controller | 2025-10-03 13:45:43.456704 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-03 13:45:43.456712 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.024) 0:01:05.262 ******** 2025-10-03 13:45:43.456722 | controller | skipping: [instance] 2025-10-03 13:45:43.475744 | controller | 2025-10-03 13:45:43.475774 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-03 13:45:43.475788 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.019) 0:01:05.281 ******** 2025-10-03 13:45:43.475798 | controller | skipping: [instance] 2025-10-03 13:45:43.504414 | controller | 2025-10-03 13:45:43.504440 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-03 13:45:43.504448 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.019) 0:01:05.300 ******** 2025-10-03 13:45:43.504457 | controller | skipping: [instance] 2025-10-03 13:45:44.611996 | controller | 2025-10-03 13:45:44.612046 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-03 13:45:44.612058 | controller | Friday 03 October 2025 13:45:43 +0000 (0:00:00.028) 0:01:05.329 ******** 2025-10-03 13:45:44.612069 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-03 13:45:44.660657 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-03 13:45:44.660688 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-03 13:45:44.660695 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-03 13:45:44.660701 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-03 13:45:44.660707 | controller | 2025-10-03 13:45:44.660713 | controller | TASK [Check for CRC startup] *************************************************** 2025-10-03 13:45:44.660719 | controller | Friday 03 October 2025 13:45:44 +0000 (0:00:01.105) 0:01:06.435 ******** 2025-10-03 13:45:44.660729 | controller | skipping: [instance] 2025-10-03 13:45:44.760972 | controller | 2025-10-03 13:45:44.761006 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:45:44.761015 | controller | instance : ok=28 changed=5 unreachable=0 failed=0 skipped=42 rescued=0 ignored=0 2025-10-03 13:45:44.761021 | controller | 2025-10-03 13:45:44.761046 | controller | Friday 03 October 2025 13:45:44 +0000 (0:00:00.047) 0:01:06.482 ******** 2025-10-03 13:45:44.761055 | controller | =============================================================================== 2025-10-03 13:45:44.761060 | controller | test_deps : Install selinux python libs -------------------------------- 49.38s 2025-10-03 13:45:44.761066 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.59s 2025-10-03 13:45:44.761071 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.49s 2025-10-03 13:45:44.761076 | controller | test_deps : Install python yaml libs ------------------------------------ 1.35s 2025-10-03 13:45:44.761089 | controller | ci_setup : Install needed packages -------------------------------------- 1.28s 2025-10-03 13:45:44.761095 | controller | ci_setup : Manage directories ------------------------------------------- 1.11s 2025-10-03 13:45:44.761100 | controller | repo_setup : Run repo-setup --------------------------------------------- 1.07s 2025-10-03 13:45:44.761105 | controller | Gathering Facts --------------------------------------------------------- 0.95s 2025-10-03 13:45:44.761110 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.84s 2025-10-03 13:45:44.761115 | controller | Inject our own NetworkManager configuration ----------------------------- 0.66s 2025-10-03 13:45:44.761120 | controller | repo_setup : Ensure directories are present ----------------------------- 0.65s 2025-10-03 13:45:44.761126 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.65s 2025-10-03 13:45:44.761131 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.59s 2025-10-03 13:45:44.761136 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s 2025-10-03 13:45:44.761141 | controller | repo_setup : Install repo-setup package --------------------------------- 0.36s 2025-10-03 13:45:44.761146 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.34s 2025-10-03 13:45:44.761152 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.31s 2025-10-03 13:45:44.761157 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.29s 2025-10-03 13:45:44.761162 | controller | ci_setup : Gather version of openshift client --------------------------- 0.28s 2025-10-03 13:45:44.761176 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.28s 2025-10-03 13:45:44.761186 | controller | INFO Running binary > converge 2025-10-03 13:45:45.203816 | controller | 2025-10-03 13:45:46.176727 | controller | PLAY [Converge binary] ********************************************************* 2025-10-03 13:45:46.176763 | controller | 2025-10-03 13:45:46.176771 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:45:46.176777 | controller | Friday 03 October 2025 13:45:45 +0000 (0:00:00.014) 0:00:00.014 ******** 2025-10-03 13:45:46.176786 | controller | ok: [instance] 2025-10-03 13:45:46.522569 | controller | 2025-10-03 13:45:46.522606 | controller | TASK [rhol_crc : Create bin directory if it does not exist] ******************** 2025-10-03 13:45:46.522615 | controller | Friday 03 October 2025 13:45:46 +0000 (0:00:00.973) 0:00:00.987 ******** 2025-10-03 13:45:46.522625 | controller | ok: [instance] 2025-10-03 13:45:46.814795 | controller | 2025-10-03 13:45:46.814827 | controller | TASK [rhol_crc : Create temporary directory for downloading RHOL/CRC] ********** 2025-10-03 13:45:46.814836 | controller | Friday 03 October 2025 13:45:46 +0000 (0:00:00.345) 0:00:01.333 ******** 2025-10-03 13:45:46.814846 | controller | changed: [instance] 2025-10-03 13:46:01.768350 | controller | 2025-10-03 13:46:01.768395 | controller | TASK [rhol_crc : Download RHOL/CRC with sha256 checksum] *********************** 2025-10-03 13:46:01.768404 | controller | Friday 03 October 2025 13:45:46 +0000 (0:00:00.292) 0:00:01.625 ******** 2025-10-03 13:46:01.768415 | controller | changed: [instance] 2025-10-03 13:46:07.479518 | controller | 2025-10-03 13:46:07.479550 | controller | TASK [rhol_crc : Unarchive RHOL/CRC binary] ************************************ 2025-10-03 13:46:07.479559 | controller | Friday 03 October 2025 13:46:01 +0000 (0:00:14.953) 0:00:16.578 ******** 2025-10-03 13:46:07.479569 | controller | changed: [instance] 2025-10-03 13:46:07.670323 | controller | 2025-10-03 13:46:07.670354 | controller | TASK [rhol_crc : Delete temporary directory for downloading RHOL/CRC] ********** 2025-10-03 13:46:07.670362 | controller | Friday 03 October 2025 13:46:07 +0000 (0:00:05.711) 0:00:22.290 ******** 2025-10-03 13:46:07.670372 | controller | ok: [instance] 2025-10-03 13:46:08.362339 | controller | 2025-10-03 13:46:08.362381 | controller | TASK [rhol_crc : Get CRC binary version] *************************************** 2025-10-03 13:46:08.362390 | controller | Friday 03 October 2025 13:46:07 +0000 (0:00:00.190) 0:00:22.480 ******** 2025-10-03 13:46:08.362402 | controller | changed: [instance] 2025-10-03 13:46:08.362429 | controller | 2025-10-03 13:46:08.362437 | controller | TASK [rhol_crc : Extract versions] ********************************************* 2025-10-03 13:46:08.362501 | controller | Friday 03 October 2025 13:46:08 +0000 (0:00:00.692) 0:00:23.173 ******** 2025-10-03 13:46:08.403977 | controller | ok: [instance] 2025-10-03 13:46:08.456112 | controller | 2025-10-03 13:46:08.456183 | controller | TASK [rhol_crc : Output found binary location and versions] ******************** 2025-10-03 13:46:08.456192 | controller | Friday 03 October 2025 13:46:08 +0000 (0:00:00.041) 0:00:23.214 ******** 2025-10-03 13:46:08.456209 | controller | ok: [instance] => 2025-10-03 13:46:08.456974 | controller | msg: 'Found crc at /home/zuul/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.' 2025-10-03 13:46:08.457010 | controller | 2025-10-03 13:46:09.169074 | controller | PLAY [Verify get_version] ****************************************************** 2025-10-03 13:46:09.169118 | controller | 2025-10-03 13:46:09.169127 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:46:09.169133 | controller | Friday 03 October 2025 13:46:08 +0000 (0:00:00.052) 0:00:23.267 ******** 2025-10-03 13:46:09.169144 | controller | ok: [instance] 2025-10-03 13:46:09.218057 | controller | 2025-10-03 13:46:09.218096 | controller | TASK [Ensure facts are set and look correct] *********************************** 2025-10-03 13:46:09.218104 | controller | Friday 03 October 2025 13:46:09 +0000 (0:00:00.712) 0:00:23.979 ******** 2025-10-03 13:46:09.218116 | controller | ok: [instance] => changed=false 2025-10-03 13:46:09.292898 | controller | msg: All assertions passed 2025-10-03 13:46:09.292939 | controller | 2025-10-03 13:46:09.292952 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:46:09.292966 | controller | instance : ok=11 changed=4 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-03 13:46:09.292976 | controller | 2025-10-03 13:46:09.292983 | controller | Friday 03 October 2025 13:46:09 +0000 (0:00:00.047) 0:00:24.027 ******** 2025-10-03 13:46:09.292988 | controller | =============================================================================== 2025-10-03 13:46:09.292993 | controller | rhol_crc : Download RHOL/CRC with sha256 checksum ---------------------- 14.95s 2025-10-03 13:46:09.292999 | controller | rhol_crc : Unarchive RHOL/CRC binary ------------------------------------ 5.71s 2025-10-03 13:46:09.293004 | controller | Gathering Facts --------------------------------------------------------- 0.97s 2025-10-03 13:46:09.293010 | controller | Gathering Facts --------------------------------------------------------- 0.71s 2025-10-03 13:46:09.293015 | controller | rhol_crc : Get CRC binary version --------------------------------------- 0.69s 2025-10-03 13:46:09.293020 | controller | rhol_crc : Create bin directory if it does not exist -------------------- 0.35s 2025-10-03 13:46:09.293025 | controller | rhol_crc : Create temporary directory for downloading RHOL/CRC ---------- 0.29s 2025-10-03 13:46:09.293059 | controller | rhol_crc : Delete temporary directory for downloading RHOL/CRC ---------- 0.19s 2025-10-03 13:46:09.293065 | controller | rhol_crc : Output found binary location and versions -------------------- 0.05s 2025-10-03 13:46:09.293070 | controller | Ensure facts are set and look correct ----------------------------------- 0.05s 2025-10-03 13:46:09.293076 | controller | rhol_crc : Extract versions --------------------------------------------- 0.04s 2025-10-03 13:46:09.293091 | controller | INFO Running binary > cleanup 2025-10-03 13:46:09.746752 | controller | 2025-10-03 13:46:09.746837 | controller | PLAY [Prepare] ***************************************************************** 2025-10-03 13:46:09.746911 | controller | 2025-10-03 13:46:09.747019 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:46:09.747167 | controller | Friday 03 October 2025 13:46:09 +0000 (0:00:00.014) 0:00:00.014 ******** 2025-10-03 13:46:10.602412 | controller | ok: [instance] 2025-10-03 13:46:10.602465 | controller | 2025-10-03 13:46:10.602756 | controller | TASK [Cleanup RHOL/CRC after tests] ******************************************** 2025-10-03 13:46:15.934177 | controller | Friday 03 October 2025 13:46:10 +0000 (0:00:00.855) 0:00:00.870 ******** 2025-10-03 13:46:15.934219 | controller | changed: [instance] 2025-10-03 13:46:15.934252 | controller | 2025-10-03 13:46:15.934261 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:46:15.934268 | controller | instance : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-03 13:46:15.934275 | controller | 2025-10-03 13:46:15.934284 | controller | Friday 03 October 2025 13:46:15 +0000 (0:00:05.331) 0:00:06.202 ******** 2025-10-03 13:46:15.934605 | controller | =============================================================================== 2025-10-03 13:46:15.983156 | controller | Cleanup RHOL/CRC after tests -------------------------------------------- 5.33s 2025-10-03 13:46:15.983235 | controller | Gathering Facts --------------------------------------------------------- 0.86s 2025-10-03 13:46:15.983256 | controller | INFO Performing prerun with role_name_check=0... 2025-10-03 13:46:33.325532 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.326141 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.326687 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.327224 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.327742 | controller | WARNING Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.328264 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.328756 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.329267 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.329756 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.330286 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.330766 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.331280 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.331972 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.332515 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.333048 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 13:46:33.342179 | controller | INFO Running default > prepare 2025-10-03 13:46:33.838633 | controller | 2025-10-03 13:46:33.838863 | controller | PLAY [Prepare] ***************************************************************** 2025-10-03 13:46:33.839049 | controller | 2025-10-03 13:46:33.839232 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:46:33.839387 | controller | Friday 03 October 2025 13:46:33 +0000 (0:00:00.018) 0:00:00.018 ******** 2025-10-03 13:46:34.703149 | controller | ok: [instance] 2025-10-03 13:46:34.726176 | controller | 2025-10-03 13:46:34.726216 | controller | TASK [Setup libvirt] *********************************************************** 2025-10-03 13:46:34.726226 | controller | Friday 03 October 2025 13:46:34 +0000 (0:00:00.864) 0:00:00.883 ******** 2025-10-03 13:46:34.726240 | controller | skipping: [instance] 2025-10-03 13:46:34.726270 | controller | 2025-10-03 13:46:34.726281 | controller | TASK [Setup CRC] *************************************************************** 2025-10-03 13:46:34.726322 | controller | Friday 03 October 2025 13:46:34 +0000 (0:00:00.023) 0:00:00.906 ******** 2025-10-03 13:46:34.750174 | controller | skipping: [instance] 2025-10-03 13:46:34.750232 | controller | 2025-10-03 13:46:34.750245 | controller | TASK [Start RHOL/CRC] ********************************************************** 2025-10-03 13:46:34.750279 | controller | Friday 03 October 2025 13:46:34 +0000 (0:00:00.023) 0:00:00.930 ******** 2025-10-03 13:46:34.773427 | controller | skipping: [instance] 2025-10-03 13:46:35.384988 | controller | 2025-10-03 13:46:35.385024 | controller | TASK [Inject our own NetworkManager configuration] ***************************** 2025-10-03 13:46:35.385062 | controller | Friday 03 October 2025 13:46:34 +0000 (0:00:00.023) 0:00:00.953 ******** 2025-10-03 13:46:35.385073 | controller | ok: [instance] 2025-10-03 13:46:35.404737 | controller | 2025-10-03 13:46:35.404772 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-03 13:46:35.404780 | controller | Friday 03 October 2025 13:46:35 +0000 (0:00:00.611) 0:00:01.565 ******** 2025-10-03 13:46:35.404790 | controller | skipping: [instance] 2025-10-03 13:46:35.450223 | controller | 2025-10-03 13:46:35.450269 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-03 13:46:35.450279 | controller | Friday 03 October 2025 13:46:35 +0000 (0:00:00.019) 0:00:01.584 ******** 2025-10-03 13:46:35.450291 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-03 13:46:35.450393 | controller | 2025-10-03 13:46:35.450430 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-03 13:46:35.450444 | controller | Friday 03 October 2025 13:46:35 +0000 (0:00:00.045) 0:00:01.630 ******** 2025-10-03 13:46:35.642128 | controller | ok: [instance] 2025-10-03 13:46:35.642177 | controller | 2025-10-03 13:46:35.642185 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-03 13:46:35.642194 | controller | Friday 03 October 2025 13:46:35 +0000 (0:00:00.191) 0:00:01.822 ******** 2025-10-03 13:46:35.870652 | controller | ok: [instance] 2025-10-03 13:46:36.567322 | controller | 2025-10-03 13:46:36.567364 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-03 13:46:36.567372 | controller | Friday 03 October 2025 13:46:35 +0000 (0:00:00.228) 0:00:02.050 ******** 2025-10-03 13:46:36.567382 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-03 13:46:36.567412 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-03 13:46:36.567420 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-03 13:46:36.567800 | controller | 2025-10-03 13:46:36.631692 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-03 13:46:36.631727 | controller | Friday 03 October 2025 13:46:36 +0000 (0:00:00.696) 0:00:02.747 ******** 2025-10-03 13:46:36.631739 | controller | 2025-10-03 13:46:37.201127 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-03 13:46:37.201168 | controller | Friday 03 October 2025 13:46:36 +0000 (0:00:00.064) 0:00:02.811 ******** 2025-10-03 13:46:37.201182 | controller | ok: [instance] => (item=tmp) 2025-10-03 13:46:37.201219 | controller | ok: [instance] => (item=artifacts/repositories) 2025-10-03 13:46:37.201226 | controller | ok: [instance] => (item=venv/repo_setup) 2025-10-03 13:46:37.201232 | controller | 2025-10-03 13:46:37.201239 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-03 13:46:37.201342 | controller | Friday 03 October 2025 13:46:37 +0000 (0:00:00.569) 0:00:03.381 ******** 2025-10-03 13:46:38.585966 | controller | ok: [instance] 2025-10-03 13:46:39.150585 | controller | 2025-10-03 13:46:39.150617 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-03 13:46:39.150625 | controller | Friday 03 October 2025 13:46:38 +0000 (0:00:01.384) 0:00:04.766 ******** 2025-10-03 13:46:39.150635 | controller | ok: [instance] 2025-10-03 13:46:40.448569 | controller | 2025-10-03 13:46:40.448607 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-03 13:46:40.448626 | controller | Friday 03 October 2025 13:46:39 +0000 (0:00:00.564) 0:00:05.330 ******** 2025-10-03 13:46:40.448644 | controller | ok: [instance] 2025-10-03 13:46:40.735738 | controller | 2025-10-03 13:46:40.735771 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-03 13:46:40.735780 | controller | Friday 03 October 2025 13:46:40 +0000 (0:00:01.297) 0:00:06.628 ******** 2025-10-03 13:46:40.735790 | controller | ok: [instance] 2025-10-03 13:46:40.758707 | controller | 2025-10-03 13:46:40.758739 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-03 13:46:40.758747 | controller | Friday 03 October 2025 13:46:40 +0000 (0:00:00.287) 0:00:06.915 ******** 2025-10-03 13:46:40.758757 | controller | skipping: [instance] 2025-10-03 13:46:41.672148 | controller | 2025-10-03 13:46:41.672184 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-03 13:46:41.672207 | controller | Friday 03 October 2025 13:46:40 +0000 (0:00:00.023) 0:00:06.938 ******** 2025-10-03 13:46:41.672219 | controller | changed: [instance] 2025-10-03 13:46:41.712512 | controller | 2025-10-03 13:46:41.712535 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-03 13:46:41.712543 | controller | Friday 03 October 2025 13:46:41 +0000 (0:00:00.913) 0:00:07.852 ******** 2025-10-03 13:46:41.712554 | controller | skipping: [instance] 2025-10-03 13:46:41.712669 | controller | 2025-10-03 13:46:41.712814 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-03 13:46:41.712965 | controller | Friday 03 October 2025 13:46:41 +0000 (0:00:00.040) 0:00:07.893 ******** 2025-10-03 13:46:41.753240 | controller | skipping: [instance] 2025-10-03 13:46:41.753333 | controller | 2025-10-03 13:46:41.753454 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-03 13:46:41.753568 | controller | Friday 03 October 2025 13:46:41 +0000 (0:00:00.040) 0:00:07.933 ******** 2025-10-03 13:46:41.793616 | controller | skipping: [instance] 2025-10-03 13:46:41.793706 | controller | 2025-10-03 13:46:41.793825 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-03 13:46:41.793986 | controller | Friday 03 October 2025 13:46:41 +0000 (0:00:00.040) 0:00:07.974 ******** 2025-10-03 13:46:42.398727 | controller | changed: [instance] 2025-10-03 13:46:42.398858 | controller | 2025-10-03 13:46:42.398983 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-03 13:46:42.399132 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.604) 0:00:08.579 ******** 2025-10-03 13:46:42.772292 | controller | ok: [instance] 2025-10-03 13:46:42.772407 | controller | 2025-10-03 13:46:42.772531 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-03 13:46:42.772649 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.373) 0:00:08.952 ******** 2025-10-03 13:46:42.803739 | controller | skipping: [instance] 2025-10-03 13:46:42.803855 | controller | 2025-10-03 13:46:42.803966 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-03 13:46:42.804097 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.031) 0:00:08.984 ******** 2025-10-03 13:46:42.836789 | controller | skipping: [instance] 2025-10-03 13:46:42.836903 | controller | 2025-10-03 13:46:42.837026 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-03 13:46:42.837175 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.033) 0:00:09.017 ******** 2025-10-03 13:46:42.868959 | controller | skipping: [instance] 2025-10-03 13:46:42.869079 | controller | 2025-10-03 13:46:42.869260 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-03 13:46:42.869894 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.032) 0:00:09.049 ******** 2025-10-03 13:46:42.910882 | controller | ok: [instance] 2025-10-03 13:46:42.910991 | controller | 2025-10-03 13:46:42.911168 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-03 13:46:42.911286 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.041) 0:00:09.091 ******** 2025-10-03 13:46:42.941353 | controller | skipping: [instance] 2025-10-03 13:46:42.941439 | controller | 2025-10-03 13:46:42.941563 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-03 13:46:42.941716 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.030) 0:00:09.121 ******** 2025-10-03 13:46:42.969908 | controller | skipping: [instance] 2025-10-03 13:46:42.970054 | controller | 2025-10-03 13:46:42.970217 | controller | TASK [Download the RPM] ******************************************************** 2025-10-03 13:46:42.970414 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.028) 0:00:09.150 ******** 2025-10-03 13:46:43.000614 | controller | skipping: [instance] 2025-10-03 13:46:43.000761 | controller | 2025-10-03 13:46:43.000877 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-03 13:46:43.000993 | controller | Friday 03 October 2025 13:46:42 +0000 (0:00:00.030) 0:00:09.181 ******** 2025-10-03 13:46:43.033753 | controller | skipping: [instance] 2025-10-03 13:46:43.033840 | controller | 2025-10-03 13:46:43.034000 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-03 13:46:43.034108 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.033) 0:00:09.214 ******** 2025-10-03 13:46:43.064588 | controller | skipping: [instance] 2025-10-03 13:46:43.064761 | controller | 2025-10-03 13:46:43.064920 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-03 13:46:43.065045 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.030) 0:00:09.245 ******** 2025-10-03 13:46:43.095681 | controller | skipping: [instance] 2025-10-03 13:46:43.095793 | controller | 2025-10-03 13:46:43.095925 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-03 13:46:43.096051 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.031) 0:00:09.276 ******** 2025-10-03 13:46:43.123781 | controller | skipping: [instance] 2025-10-03 13:46:43.123886 | controller | 2025-10-03 13:46:43.124018 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-03 13:46:43.124155 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.028) 0:00:09.304 ******** 2025-10-03 13:46:43.315049 | controller | ok: [instance] 2025-10-03 13:46:43.539044 | controller | 2025-10-03 13:46:43.539082 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-03 13:46:43.539090 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.190) 0:00:09.494 ******** 2025-10-03 13:46:43.539101 | controller | changed: [instance] 2025-10-03 13:46:43.776491 | controller | 2025-10-03 13:46:43.776525 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-03 13:46:43.776533 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.223) 0:00:09.718 ******** 2025-10-03 13:46:43.776544 | controller | changed: [instance] 2025-10-03 13:46:43.808066 | controller | 2025-10-03 13:46:43.808108 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-03 13:46:43.808118 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.237) 0:00:09.956 ******** 2025-10-03 13:46:43.808129 | controller | skipping: [instance] 2025-10-03 13:46:43.838741 | controller | 2025-10-03 13:46:43.838777 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-03 13:46:43.838785 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.030) 0:00:09.987 ******** 2025-10-03 13:46:43.838795 | controller | skipping: [instance] 2025-10-03 13:46:43.866538 | controller | 2025-10-03 13:46:43.866569 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-03 13:46:43.866576 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.031) 0:00:10.018 ******** 2025-10-03 13:46:43.866586 | controller | skipping: [instance] 2025-10-03 13:46:43.894686 | controller | 2025-10-03 13:46:43.894722 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-03 13:46:43.894729 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.027) 0:00:10.046 ******** 2025-10-03 13:46:43.894740 | controller | skipping: [instance] 2025-10-03 13:46:43.925025 | controller | 2025-10-03 13:46:43.925092 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-03 13:46:43.925102 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.027) 0:00:10.074 ******** 2025-10-03 13:46:43.925114 | controller | skipping: [instance] 2025-10-03 13:46:43.925324 | controller | 2025-10-03 13:46:43.925509 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-03 13:46:43.925685 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.030) 0:00:10.105 ******** 2025-10-03 13:46:43.955214 | controller | skipping: [instance] 2025-10-03 13:46:43.955446 | controller | 2025-10-03 13:46:43.955624 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-03 13:46:43.955763 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.030) 0:00:10.135 ******** 2025-10-03 13:46:43.980939 | controller | skipping: [instance] 2025-10-03 13:46:43.981173 | controller | 2025-10-03 13:46:43.981390 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-03 13:46:43.981559 | controller | Friday 03 October 2025 13:46:43 +0000 (0:00:00.025) 0:00:10.161 ******** 2025-10-03 13:46:44.015743 | controller | skipping: [instance] 2025-10-03 13:46:44.015914 | controller | 2025-10-03 13:46:44.016090 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-03 13:46:44.016238 | controller | Friday 03 October 2025 13:46:44 +0000 (0:00:00.034) 0:00:10.196 ******** 2025-10-03 13:46:44.042905 | controller | skipping: [instance] 2025-10-03 13:46:44.043135 | controller | 2025-10-03 13:46:44.043333 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-03 13:46:44.043495 | controller | Friday 03 October 2025 13:46:44 +0000 (0:00:00.027) 0:00:10.223 ******** 2025-10-03 13:46:44.072497 | controller | skipping: [instance] 2025-10-03 13:46:44.072668 | controller | 2025-10-03 13:46:44.072818 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-03 13:46:44.072969 | controller | Friday 03 October 2025 13:46:44 +0000 (0:00:00.029) 0:00:10.252 ******** 2025-10-03 13:46:44.106440 | controller | skipping: [instance] 2025-10-03 13:46:44.106647 | controller | 2025-10-03 13:46:44.106804 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-03 13:46:44.106947 | controller | Friday 03 October 2025 13:46:44 +0000 (0:00:00.033) 0:00:10.286 ******** 2025-10-03 13:46:44.141012 | controller | skipping: [instance] 2025-10-03 13:46:44.141270 | controller | 2025-10-03 13:46:44.141453 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-03 13:46:44.141600 | controller | Friday 03 October 2025 13:46:44 +0000 (0:00:00.034) 0:00:10.321 ******** 2025-10-03 13:46:44.175696 | controller | skipping: [instance] 2025-10-03 13:46:44.175893 | controller | 2025-10-03 13:46:44.176072 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-03 13:46:44.176223 | controller | Friday 03 October 2025 13:46:44 +0000 (0:00:00.034) 0:00:10.356 ******** 2025-10-03 13:46:44.209535 | controller | skipping: [instance] 2025-10-03 13:46:44.209709 | controller | 2025-10-03 13:46:44.209873 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-03 13:46:44.210017 | controller | Friday 03 October 2025 13:46:44 +0000 (0:00:00.033) 0:00:10.389 ******** 2025-10-03 13:47:29.861983 | controller | ok: [instance] 2025-10-03 13:47:31.098117 | controller | 2025-10-03 13:47:31.098151 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-03 13:47:31.098159 | controller | Friday 03 October 2025 13:47:29 +0000 (0:00:45.652) 0:00:56.042 ******** 2025-10-03 13:47:31.098168 | controller | ok: [instance] 2025-10-03 13:47:31.132964 | controller | 2025-10-03 13:47:31.133007 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-03 13:47:31.133015 | controller | Friday 03 October 2025 13:47:31 +0000 (0:00:01.236) 0:00:57.278 ******** 2025-10-03 13:47:31.133050 | controller | skipping: [instance] 2025-10-03 13:47:31.178943 | controller | 2025-10-03 13:47:31.178978 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2025-10-03 13:47:31.178986 | controller | Friday 03 October 2025 13:47:31 +0000 (0:00:00.034) 0:00:57.312 ******** 2025-10-03 13:47:31.178996 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-03 13:47:31.200172 | controller | 2025-10-03 13:47:31.200213 | controller | TASK [ci_setup : List packages to install] ************************************* 2025-10-03 13:47:31.200230 | controller | Friday 03 October 2025 13:47:31 +0000 (0:00:00.046) 0:00:57.359 ******** 2025-10-03 13:47:31.200244 | controller | ok: [instance] => 2025-10-03 13:47:31.200279 | controller | cifmw_ci_setup_packages: 2025-10-03 13:47:31.200290 | controller | - bash-completion 2025-10-03 13:47:31.201087 | controller | - ca-certificates 2025-10-03 13:47:31.201131 | controller | - git-core 2025-10-03 13:47:32.437882 | controller | - make 2025-10-03 13:47:32.437912 | controller | - tar 2025-10-03 13:47:32.437920 | controller | - tmux 2025-10-03 13:47:32.437925 | controller | - python3-pip 2025-10-03 13:47:32.437930 | controller | 2025-10-03 13:47:32.437936 | controller | TASK [ci_setup : Install needed packages] ************************************** 2025-10-03 13:47:32.437942 | controller | Friday 03 October 2025 13:47:31 +0000 (0:00:00.021) 0:00:57.380 ******** 2025-10-03 13:47:32.437952 | controller | ok: [instance] 2025-10-03 13:47:32.438125 | controller | 2025-10-03 13:47:32.438274 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2025-10-03 13:47:32.438412 | controller | Friday 03 October 2025 13:47:32 +0000 (0:00:01.237) 0:00:58.618 ******** 2025-10-03 13:47:32.701051 | controller | ok: [instance] 2025-10-03 13:47:32.908131 | controller | 2025-10-03 13:47:32.908163 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2025-10-03 13:47:32.908171 | controller | Friday 03 October 2025 13:47:32 +0000 (0:00:00.262) 0:00:58.881 ******** 2025-10-03 13:47:32.908181 | controller | ok: [instance] 2025-10-03 13:47:32.908205 | controller | 2025-10-03 13:47:32.908213 | controller | TASK [ci_setup : Install openshift client] ************************************* 2025-10-03 13:47:32.908234 | controller | Friday 03 October 2025 13:47:32 +0000 (0:00:00.207) 0:00:59.088 ******** 2025-10-03 13:47:32.973094 | controller | skipping: [instance] 2025-10-03 13:47:33.007882 | controller | 2025-10-03 13:47:33.007920 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2025-10-03 13:47:33.007930 | controller | Friday 03 October 2025 13:47:32 +0000 (0:00:00.064) 0:00:59.153 ******** 2025-10-03 13:47:33.007942 | controller | skipping: [instance] 2025-10-03 13:47:33.186904 | controller | 2025-10-03 13:47:33.186938 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-03 13:47:33.186946 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.034) 0:00:59.188 ******** 2025-10-03 13:47:33.186956 | controller | ok: [instance] 2025-10-03 13:47:33.471066 | controller | 2025-10-03 13:47:33.471100 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2025-10-03 13:47:33.471108 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.178) 0:00:59.366 ******** 2025-10-03 13:47:33.471125 | controller | ok: [instance] 2025-10-03 13:47:33.487977 | controller | 2025-10-03 13:47:33.488004 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-03 13:47:33.488014 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.284) 0:00:59.651 ******** 2025-10-03 13:47:33.488025 | controller | skipping: [instance] 2025-10-03 13:47:33.505172 | controller | 2025-10-03 13:47:33.505212 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-03 13:47:33.505222 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.017) 0:00:59.668 ******** 2025-10-03 13:47:33.505235 | controller | skipping: [instance] 2025-10-03 13:47:33.522559 | controller | 2025-10-03 13:47:33.522594 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-03 13:47:33.522603 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.017) 0:00:59.685 ******** 2025-10-03 13:47:33.522615 | controller | skipping: [instance] 2025-10-03 13:47:33.540991 | controller | 2025-10-03 13:47:33.541024 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-03 13:47:33.541050 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.017) 0:00:59.702 ******** 2025-10-03 13:47:33.541061 | controller | skipping: [instance] 2025-10-03 13:47:33.559207 | controller | 2025-10-03 13:47:33.559244 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-03 13:47:33.559255 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.018) 0:00:59.721 ******** 2025-10-03 13:47:33.559267 | controller | skipping: [instance] 2025-10-03 13:47:33.559308 | controller | 2025-10-03 13:47:33.559322 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-03 13:47:33.559468 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.018) 0:00:59.739 ******** 2025-10-03 13:47:33.582629 | controller | skipping: [instance] 2025-10-03 13:47:33.582822 | controller | 2025-10-03 13:47:33.582994 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-03 13:47:33.583195 | controller | Friday 03 October 2025 13:47:33 +0000 (0:00:00.023) 0:00:59.763 ******** 2025-10-03 13:47:34.585341 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-03 13:47:34.621672 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-03 13:47:34.621695 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-03 13:47:34.621703 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-03 13:47:34.621709 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-03 13:47:34.621715 | controller | 2025-10-03 13:47:34.621721 | controller | TASK [Check for CRC startup] *************************************************** 2025-10-03 13:47:34.621726 | controller | Friday 03 October 2025 13:47:34 +0000 (0:00:01.001) 0:01:00.765 ******** 2025-10-03 13:47:34.621735 | controller | skipping: [instance] 2025-10-03 13:47:34.621758 | controller | 2025-10-03 13:47:34.622174 | controller | PLAY RECAP ********************************************************************* 2025-10-03 13:47:34.622231 | controller | instance : ok=28 changed=4 unreachable=0 failed=0 skipped=42 rescued=0 ignored=0 2025-10-03 13:47:34.622239 | controller | 2025-10-03 13:47:34.622246 | controller | Friday 03 October 2025 13:47:34 +0000 (0:00:00.037) 0:01:00.802 ******** 2025-10-03 13:47:34.622253 | controller | =============================================================================== 2025-10-03 13:47:34.624094 | controller | test_deps : Install selinux python libs -------------------------------- 45.65s 2025-10-03 13:47:34.679885 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.38s 2025-10-03 13:47:34.679918 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.30s 2025-10-03 13:47:34.679925 | controller | ci_setup : Install needed packages -------------------------------------- 1.24s 2025-10-03 13:47:34.679931 | controller | test_deps : Install python yaml libs ------------------------------------ 1.24s 2025-10-03 13:47:34.679936 | controller | ci_setup : Manage directories ------------------------------------------- 1.00s 2025-10-03 13:47:34.679942 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.91s 2025-10-03 13:47:34.679947 | controller | Gathering Facts --------------------------------------------------------- 0.86s 2025-10-03 13:47:34.679952 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s 2025-10-03 13:47:34.679957 | controller | Inject our own NetworkManager configuration ----------------------------- 0.61s 2025-10-03 13:47:34.679962 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.60s 2025-10-03 13:47:34.679973 | controller | repo_setup : Ensure directories are present ----------------------------- 0.57s 2025-10-03 13:47:34.679979 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.56s 2025-10-03 13:47:34.679984 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.37s 2025-10-03 13:47:34.679989 | controller | repo_setup : Install repo-setup package --------------------------------- 0.29s 2025-10-03 13:47:34.679994 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.28s 2025-10-03 13:47:34.679999 | controller | ci_setup : Gather version of openshift client --------------------------- 0.26s 2025-10-03 13:47:34.680004 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.24s 2025-10-03 13:47:34.680009 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.23s 2025-10-03 13:47:34.680015 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s 2025-10-03 13:47:34.685052 | controller | INFO Running default > converge 2025-10-03 13:47:35.180472 | controller | 2025-10-03 13:47:35.180616 | controller | PLAY [Converge default] ******************************************************** 2025-10-03 13:47:35.180722 | controller | 2025-10-03 13:47:35.180837 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 13:47:35.180952 | controller | Friday 03 October 2025 13:47:35 +0000 (0:00:00.030) 0:00:00.030 ******** 2025-10-03 13:47:36.011999 | controller | ok: [instance] 2025-10-03 13:47:36.677880 | controller | 2025-10-03 13:47:36.677911 | controller | TASK [rhol_crc : Ensure directories exist] ************************************* 2025-10-03 13:47:36.677919 | controller | Friday 03 October 2025 13:47:36 +0000 (0:00:00.831) 0:00:00.861 ******** 2025-10-03 13:47:36.677930 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts) 2025-10-03 13:47:36.890827 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-03 13:47:36.890858 | controller | ok: [instance] => (item=/home/zuul/bin) 2025-10-03 13:47:36.890865 | controller | 2025-10-03 13:47:36.890871 | controller | TASK [manage_secrets : Ensure directory tree exist] **************************** 2025-10-03 13:47:36.890877 | controller | Friday 03 October 2025 13:47:36 +0000 (0:00:00.665) 0:00:01.527 ******** 2025-10-03 13:47:36.890887 | controller | changed: [instance] 2025-10-03 13:47:37.185721 | controller | 2025-10-03 13:47:37.185752 | controller | TASK [rhol_crc : Check for binary] ********************************************* 2025-10-03 13:47:37.185760 | controller | Friday 03 October 2025 13:47:36 +0000 (0:00:00.213) 0:00:01.740 ******** 2025-10-03 13:47:37.185770 | controller | ok: [instance] 2025-10-03 13:47:37.224087 | controller | 2025-10-03 13:47:37.224116 | controller | TASK [rhol_crc : Get versions if binary is available] ************************** 2025-10-03 13:47:37.224124 | controller | Friday 03 October 2025 13:47:37 +0000 (0:00:00.294) 0:00:02.035 ******** 2025-10-03 13:47:37.224135 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/get_versions.yml for instance 2025-10-03 13:47:50.909129 | controller | 2025-10-03 13:47:50.909160 | controller | TASK [rhol_crc : Get CRC binary version] *************************************** 2025-10-03 13:47:50.909168 | controller | Friday 03 October 2025 13:47:37 +0000 (0:00:00.038) 0:00:02.073 ******** 2025-10-03 13:47:50.909179 | controller | changed: [instance] 2025-10-03 13:47:50.909367 | controller | 2025-10-03 13:47:50.909403 | controller | TASK [rhol_crc : Extract versions] ********************************************* 2025-10-03 13:47:50.948452 | controller | Friday 03 October 2025 13:47:50 +0000 (0:00:13.685) 0:00:15.759 ******** 2025-10-03 13:47:50.948482 | controller | ok: [instance] 2025-10-03 13:47:50.976189 | controller | 2025-10-03 13:47:50.976227 | controller | TASK [rhol_crc : Output found binary location and versions] ******************** 2025-10-03 13:47:50.976237 | controller | Friday 03 October 2025 13:47:50 +0000 (0:00:00.039) 0:00:15.798 ******** 2025-10-03 13:47:50.976250 | controller | ok: [instance] => 2025-10-03 13:47:50.976279 | controller | msg: 'Found crc at /home/zuul/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.' 2025-10-03 13:47:50.976287 | controller | 2025-10-03 13:47:50.976296 | controller | TASK [rhol_crc : Get CRC VM availability] ************************************** 2025-10-03 13:47:50.976378 | controller | Friday 03 October 2025 13:47:50 +0000 (0:00:00.027) 0:00:15.825 ******** 2025-10-03 13:47:51.331961 | controller | ok: [instance] 2025-10-03 13:47:51.359112 | controller | 2025-10-03 13:47:51.359158 | controller | TASK [rhol_crc : Set CRC status] *********************************************** 2025-10-03 13:47:51.359166 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.355) 0:00:16.181 ******** 2025-10-03 13:47:51.359177 | controller | ok: [instance] 2025-10-03 13:47:51.388194 | controller | 2025-10-03 13:47:51.388233 | controller | TASK [rhol_crc : Fail if crc domain is already defined] ************************ 2025-10-03 13:47:51.388244 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.027) 0:00:16.208 ******** 2025-10-03 13:47:51.388264 | controller | skipping: [instance] 2025-10-03 13:47:51.388297 | controller | 2025-10-03 13:47:51.388307 | controller | TASK [Ensure pull-secret is in place if we need to configure CRC] ************** 2025-10-03 13:47:51.388405 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.029) 0:00:16.238 ******** 2025-10-03 13:47:51.434178 | controller | 2025-10-03 13:47:51.457830 | controller | TASK [manage_secrets : Assert we get needed data] ****************************** 2025-10-03 13:47:51.457863 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.045) 0:00:16.283 ******** 2025-10-03 13:47:51.457877 | controller | ok: [instance] 2025-10-03 13:47:51.483512 | controller | 2025-10-03 13:47:51.483551 | controller | TASK [manage_secrets : Manage secret] ****************************************** 2025-10-03 13:47:51.483561 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.024) 0:00:16.307 ******** 2025-10-03 13:47:51.483574 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/manage_secrets/tasks/_push_secret.yml for instance 2025-10-03 13:47:51.483750 | controller | 2025-10-03 13:47:51.483924 | controller | TASK [manage_secrets : Debug _secret_dest] ************************************* 2025-10-03 13:47:51.484134 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.025) 0:00:16.333 ******** 2025-10-03 13:47:51.508796 | controller | ok: [instance] => 2025-10-03 13:47:51.509004 | controller | _secret_dest: /home/zuul/pull-secret.json 2025-10-03 13:47:51.509214 | controller | 2025-10-03 13:47:51.509357 | controller | TASK [manage_secrets : Debug _secret_file] ************************************* 2025-10-03 13:47:51.509492 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.025) 0:00:16.358 ******** 2025-10-03 13:47:51.531604 | controller | ok: [instance] => 2025-10-03 13:47:51.531767 | controller | _secret_file: '' 2025-10-03 13:47:51.531898 | controller | 2025-10-03 13:47:51.532074 | controller | TASK [manage_secrets : Debug _secret_content] ********************************** 2025-10-03 13:47:51.532228 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.022) 0:00:16.381 ******** 2025-10-03 13:47:51.553703 | controller | ok: [instance] => 2025-10-03 13:47:51.553884 | controller | _secret_content: 2025-10-03 13:47:51.554099 | controller | auths: 2025-10-03 13:47:51.554274 | controller | fake: 2025-10-03 13:47:51.554405 | controller | auth: Zm9vOmJhcgo= 2025-10-03 13:47:51.554526 | controller | 2025-10-03 13:47:51.554659 | controller | TASK [manage_secrets : Ensure parameter is an absolute path] ******************* 2025-10-03 13:47:51.554793 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.022) 0:00:16.403 ******** 2025-10-03 13:47:51.571905 | controller | skipping: [instance] 2025-10-03 13:47:51.572117 | controller | 2025-10-03 13:47:51.572291 | controller | TASK [manage_secrets : Check if pull secret src file exists] ******************* 2025-10-03 13:47:51.572430 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.018) 0:00:16.422 ******** 2025-10-03 13:47:51.590928 | controller | skipping: [instance] 2025-10-03 13:47:51.591164 | controller | 2025-10-03 13:47:51.591354 | controller | TASK [manage_secrets : Copy file to location] ********************************** 2025-10-03 13:47:51.591492 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.018) 0:00:16.441 ******** 2025-10-03 13:47:51.609325 | controller | skipping: [instance] 2025-10-03 13:47:51.609482 | controller | 2025-10-03 13:47:51.609626 | controller | TASK [manage_secrets : Create file from content] ******************************* 2025-10-03 13:47:51.609765 | controller | Friday 03 October 2025 13:47:51 +0000 (0:00:00.018) 0:00:16.459 ******** 2025-10-03 13:47:52.070020 | controller | changed: [instance] 2025-10-03 13:47:52.105590 | controller | 2025-10-03 13:47:52.105621 | controller | TASK [rhol_crc : Get wanted RHOL/CRC version binary if needed] ***************** 2025-10-03 13:47:52.105629 | controller | Friday 03 October 2025 13:47:52 +0000 (0:00:00.460) 0:00:16.919 ******** 2025-10-03 13:47:52.105639 | controller | skipping: [instance] 2025-10-03 13:47:52.144072 | controller | 2025-10-03 13:47:52.144111 | controller | TASK [rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup] ******* 2025-10-03 13:47:52.144121 | controller | Friday 03 October 2025 13:47:52 +0000 (0:00:00.035) 0:00:16.955 ******** 2025-10-03 13:47:52.144133 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/sudoers_grant.yml for instance 2025-10-03 13:47:52.464465 | controller | 2025-10-03 13:47:52.464498 | controller | TASK [rhol_crc : Add passwordless sudo access for CRC commands] **************** 2025-10-03 13:47:52.464506 | controller | Friday 03 October 2025 13:47:52 +0000 (0:00:00.038) 0:00:16.993 ******** 2025-10-03 13:47:52.464516 | controller | changed: [instance] 2025-10-03 13:47:52.495169 | controller | 2025-10-03 13:47:52.495221 | controller | TASK [rhol_crc : Clean RHOL/CRC if wanted] ************************************* 2025-10-03 13:47:52.495231 | controller | Friday 03 October 2025 13:47:52 +0000 (0:00:00.320) 0:00:17.314 ******** 2025-10-03 13:47:52.495245 | controller | skipping: [instance] 2025-10-03 13:47:52.495426 | controller | 2025-10-03 13:47:52.534618 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] *************************** 2025-10-03 13:47:52.534651 | controller | Friday 03 October 2025 13:47:52 +0000 (0:00:00.030) 0:00:17.345 ******** 2025-10-03 13:47:52.534663 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/configuration.yml for instance 2025-10-03 13:47:54.496161 | controller | 2025-10-03 13:47:54.496199 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] *************************** 2025-10-03 13:47:54.496214 | controller | Friday 03 October 2025 13:47:52 +0000 (0:00:00.039) 0:00:17.384 ******** 2025-10-03 13:47:54.496227 | controller | changed: [instance] => (item={'key': 'consent-telemetry', 'value': 'no'}) 2025-10-03 13:47:54.496260 | controller | changed: [instance] => (item={'key': 'disk-size', 'value': 32}) 2025-10-03 13:47:54.496268 | controller | changed: [instance] => (item={'key': 'memory', 'value': 10752}) 2025-10-03 13:47:54.496275 | controller | changed: [instance] => (item={'key': 'cpus', 'value': 4}) 2025-10-03 13:47:54.496282 | controller | changed: [instance] => (item={'key': 'preset', 'value': 'openshift'}) 2025-10-03 13:47:54.496290 | controller | changed: [instance] => (item={'key': 'pull-secret-file', 'value': '/home/zuul/pull-secret.json'}) 2025-10-03 13:47:54.496824 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-unit', 'value': True}) 2025-10-03 13:47:54.496863 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-sockets', 'value': True}) 2025-10-03 13:52:06.090213 | controller | changed: [instance] => (item={'key': 'kubeadmin-password', 'value': 12345678}) 2025-10-03 13:52:06.090443 | controller | 2025-10-03 13:52:06.090458 | controller | TASK [rhol_crc : Setup RHOL/CRC] *********************************************** 2025-10-03 13:52:06.090466 | controller | Friday 03 October 2025 13:47:54 +0000 (0:00:01.961) 0:00:19.345 ******** 2025-10-03 13:52:06.090478 | controller | changed: [instance] 2025-10-03 14:14:55.082684 | controller | 2025-10-03 14:14:55.082738 | controller | TASK [rhol_crc : Start RHOL/CRC] *********************************************** 2025-10-03 14:14:55.082747 | controller | Friday 03 October 2025 13:52:06 +0000 (0:04:11.594) 0:04:30.940 ******** 2025-10-03 14:14:55.082758 | controller | fatal: [instance]: FAILED! => changed=true 2025-10-03 14:14:55.082797 | controller | cmd: set -o pipefail; /home/zuul/bin/crc start 2>&1 | tee /home/zuul/ci-framework-data/logs/crc-start.log 2025-10-03 14:14:55.082805 | controller | delta: '0:22:48.783399' 2025-10-03 14:14:55.082813 | controller | end: '2025-10-03 14:14:55.038733' 2025-10-03 14:14:55.082835 | controller | msg: non-zero return code 2025-10-03 14:14:55.082843 | controller | rc: 1 2025-10-03 14:14:55.083026 | controller | start: '2025-10-03 13:52:06.255334' 2025-10-03 14:14:55.083112 | controller | stderr: '' 2025-10-03 14:14:55.083137 | controller | stderr_lines: 2025-10-03 14:14:55.083882 | controller | stdout: |- 2025-10-03 14:14:55.083926 | controller | 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" 2025-10-03 14:14:55.083936 | controller | level=info msg="Using bundle path /home/zuul/.crc/cache/crc_libvirt_4.16.0_amd64.crcbundle" 2025-10-03 14:14:55.083942 | controller | level=info msg="Checking if running as non-root" 2025-10-03 14:14:55.083948 | controller | level=info msg="Checking if running inside WSL2" 2025-10-03 14:14:55.083954 | controller | level=info msg="Checking if crc-admin-helper executable is cached" 2025-10-03 14:14:55.083959 | controller | level=info msg="Checking if running on a supported CPU architecture" 2025-10-03 14:14:55.083967 | controller | level=info msg="Checking if crc executable symlink exists" 2025-10-03 14:14:55.083988 | controller | level=info msg="Checking minimum RAM requirements" 2025-10-03 14:14:55.084078 | controller | level=info msg="Check if Podman binary exists in: /home/zuul/.crc/bin/oc" 2025-10-03 14:14:55.084225 | controller | level=info msg="Checking if Virtualization is enabled" 2025-10-03 14:14:55.084282 | controller | level=info msg="Checking if KVM is enabled" 2025-10-03 14:14:55.084779 | controller | level=info msg="Checking if libvirt is installed" 2025-10-03 14:14:55.084913 | controller | level=info msg="Checking if user is part of libvirt group" 2025-10-03 14:14:55.084929 | controller | level=info msg="Checking if active user/process is currently part of the libvirt group" 2025-10-03 14:14:55.084936 | controller | level=info msg="Checking if libvirt daemon is running" 2025-10-03 14:14:55.084942 | controller | level=info msg="Checking if a supported libvirt version is installed" 2025-10-03 14:14:55.084947 | controller | level=info msg="Checking if crc-driver-libvirt is installed" 2025-10-03 14:14:55.084955 | controller | level=info msg="Checking crc daemon systemd socket units" 2025-10-03 14:14:55.084978 | controller | level=warning msg="Skipping above check..." 2025-10-03 14:14:55.085277 | controller | level=info msg="Checking if systemd-networkd is running" 2025-10-03 14:14:55.085442 | controller | level=info msg="Checking if NetworkManager is installed" 2025-10-03 14:14:55.085465 | controller | level=info msg="Checking if NetworkManager service is running" 2025-10-03 14:14:55.085477 | controller | level=info msg="Checking if /etc/NetworkManager/conf.d/crc-nm-dnsmasq.conf exists" 2025-10-03 14:14:55.085606 | controller | level=info msg="Checking if /etc/NetworkManager/dnsmasq.d/crc.conf exists" 2025-10-03 14:14:55.085626 | controller | level=info msg="Checking if libvirt 'crc' network is available" 2025-10-03 14:14:55.085650 | controller | level=info msg="Checking if libvirt 'crc' network is active" 2025-10-03 14:14:55.085864 | controller | level=info msg="Loading bundle: crc_libvirt_4.16.0_amd64..." 2025-10-03 14:14:55.085892 | controller | level=info msg="Creating CRC VM for OpenShift 4.16.0..." 2025-10-03 14:14:55.085901 | controller | level=info msg="Generating new SSH key pair..." 2025-10-03 14:14:55.086243 | controller | level=info msg="Generating new password for the kubeadmin user" 2025-10-03 14:14:55.086333 | controller | level=info msg="Starting CRC VM for openshift 4.16.0..." 2025-10-03 14:14:55.086345 | controller | level=info msg="CRC instance is running with IP 192.168.130.11" 2025-10-03 14:14:55.086370 | controller | level=info msg="CRC VM is running" 2025-10-03 14:14:55.086379 | controller | level=info msg="Updating authorized keys..." 2025-10-03 14:14:55.086503 | controller | level=info msg="Resizing /dev/vda4 filesystem" 2025-10-03 14:14:55.086618 | controller | level=info msg="Configuring shared directories" 2025-10-03 14:14:55.086749 | controller | level=info msg="Check internal and public DNS query..." 2025-10-03 14:14:55.086872 | controller | level=info msg="Check DNS query from host..." 2025-10-03 14:14:55.086987 | controller | level=info msg="Verifying validity of the kubelet certificates..." 2025-10-03 14:14:55.087125 | controller | level=info msg="Starting kubelet service" 2025-10-03 14:14:55.087251 | controller | level=info msg="Kubelet client certificate has expired, renewing it... [will take up to 10 minutes]" 2025-10-03 14:14:55.087379 | controller | level=info msg="Kubelet serving certificate has expired, waiting for automatic renewal... [will take up to 5 minutes]" 2025-10-03 14:14:55.087498 | controller | level=info msg="Waiting for kube-apiserver availability... [takes around 2min]" 2025-10-03 14:14:55.087608 | controller | level=info msg="Adding user's pull secret to the cluster..." 2025-10-03 14:14:55.087716 | controller | level=info msg="Updating SSH key to machine config resource..." 2025-10-03 14:14:55.087839 | controller | level=info msg="Waiting until the user's pull secret is written to the instance disk..." 2025-10-03 14:14:55.087956 | controller | Failed to update pull secret on the disk: Temporary error: pull secret not updated to disk (x205) 2025-10-03 14:14:55.088084 | controller | stdout_lines: 2025-10-03 14:14:55.089586 | controller | 2025-10-03 14:14:55.089714 | controller | TASK [rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup] *** 2025-10-03 14:14:55.089847 | controller | Friday 03 October 2025 14:14:55 +0000 (0:22:48.999) 0:27:19.939 ******** 2025-10-03 14:14:55.300396 | controller | changed: [instance] 2025-10-03 14:14:55.300474 | controller | 2025-10-03 14:14:55.300655 | controller | PLAY RECAP ********************************************************************* 2025-10-03 14:14:55.300725 | controller | instance : ok=22 changed=7 unreachable=0 failed=1 skipped=6 rescued=0 ignored=0 2025-10-03 14:14:55.301282 | controller | 2025-10-03 14:14:55.301319 | controller | Friday 03 October 2025 14:14:55 +0000 (0:00:00.210) 0:27:20.150 ******** 2025-10-03 14:14:55.301346 | controller | =============================================================================== 2025-10-03 14:14:55.301355 | controller | rhol_crc : Start RHOL/CRC -------------------------------------------- 1369.00s 2025-10-03 14:14:55.301674 | controller | rhol_crc : Setup RHOL/CRC --------------------------------------------- 251.59s 2025-10-03 14:14:55.301796 | controller | rhol_crc : Get CRC binary version -------------------------------------- 13.69s 2025-10-03 14:14:55.301809 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 1.96s 2025-10-03 14:14:55.301818 | controller | Gathering Facts --------------------------------------------------------- 0.83s 2025-10-03 14:14:55.302841 | controller | rhol_crc : Ensure directories exist ------------------------------------- 0.67s 2025-10-03 14:14:55.303306 | controller | manage_secrets : Create file from content ------------------------------- 0.46s 2025-10-03 14:14:55.303325 | controller | rhol_crc : Get CRC VM availability -------------------------------------- 0.36s 2025-10-03 14:14:55.303332 | controller | rhol_crc : Add passwordless sudo access for CRC commands ---------------- 0.32s 2025-10-03 14:14:55.303337 | controller | rhol_crc : Check for binary --------------------------------------------- 0.29s 2025-10-03 14:14:55.303342 | controller | manage_secrets : Ensure directory tree exist ---------------------------- 0.21s 2025-10-03 14:14:55.303347 | controller | rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup --- 0.21s 2025-10-03 14:14:55.303352 | controller | Ensure pull-secret is in place if we need to configure CRC -------------- 0.05s 2025-10-03 14:14:55.303357 | controller | rhol_crc : Extract versions --------------------------------------------- 0.04s 2025-10-03 14:14:55.303362 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 0.04s 2025-10-03 14:14:55.303373 | controller | rhol_crc : Get versions if binary is available -------------------------- 0.04s 2025-10-03 14:14:55.303381 | controller | rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup ------- 0.04s 2025-10-03 14:14:55.356071 | controller | rhol_crc : Get wanted RHOL/CRC version binary if needed ----------------- 0.04s 2025-10-03 14:14:55.356097 | controller | rhol_crc : Clean RHOL/CRC if wanted ------------------------------------- 0.03s 2025-10-03 14:14:55.356103 | controller | rhol_crc : Fail if crc domain is already defined ------------------------ 0.03s 2025-10-03 14:14:55.356119 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/rhol_crc/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/molecule/default/converge.yml'] 2025-10-03 14:14:55.364279 | controller | WARNING An error occurred during the test sequence action: 'converge'. Cleaning up. 2025-10-03 14:14:55.364306 | controller | INFO Running default > cleanup 2025-10-03 14:14:55.781164 | controller | 2025-10-03 14:14:55.781269 | controller | PLAY [Prepare] ***************************************************************** 2025-10-03 14:14:55.781361 | controller | 2025-10-03 14:14:55.781469 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 14:14:55.781570 | controller | Friday 03 October 2025 14:14:55 +0000 (0:00:00.013) 0:00:00.013 ******** 2025-10-03 14:14:56.687590 | controller | ok: [instance] 2025-10-03 14:14:56.961430 | controller | 2025-10-03 14:14:56.961465 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] ********************************* 2025-10-03 14:14:56.961473 | controller | Friday 03 October 2025 14:14:56 +0000 (0:00:00.906) 0:00:00.920 ******** 2025-10-03 14:14:56.961483 | controller | ok: [instance] 2025-10-03 14:14:56.961499 | controller | 2025-10-03 14:14:56.961513 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ******** 2025-10-03 14:14:56.961528 | controller | Friday 03 October 2025 14:14:56 +0000 (0:00:00.274) 0:00:01.194 ******** 2025-10-03 14:15:00.733411 | controller | changed: [instance] 2025-10-03 14:15:00.733468 | controller | 2025-10-03 14:15:00.733573 | controller | PLAY RECAP ********************************************************************* 2025-10-03 14:15:00.733692 | controller | instance : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-03 14:15:00.733783 | controller | 2025-10-03 14:15:00.733894 | controller | Friday 03 October 2025 14:15:00 +0000 (0:00:03.772) 0:00:04.967 ******** 2025-10-03 14:15:00.733977 | controller | =============================================================================== 2025-10-03 14:15:00.734099 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.77s 2025-10-03 14:15:00.734187 | controller | Gathering Facts --------------------------------------------------------- 0.91s 2025-10-03 14:15:00.734279 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.27s 2025-10-03 14:15:00.785449 | controller | INFO Running default > destroy 2025-10-03 14:15:00.785838 | controller | WARNING Skipping, instances are delegated. 2025-10-03 14:15:00.786199 | controller | INFO Pruning extra files from scenario ephemeral directory 2025-10-03 14:15:00.787619 | controller | INFO Writing /tmp/report.html report. 2025-10-03 14:15:00.878693 | controller | ERROR 2025-10-03 14:15:00.878826 | controller | { 2025-10-03 14:15:00.878860 | controller | "delta": "0:35:50.040195", 2025-10-03 14:15:00.878883 | controller | "end": "2025-10-03 14:15:00.841071", 2025-10-03 14:15:00.878903 | controller | "msg": "non-zero return code", 2025-10-03 14:15:00.878945 | controller | "rc": 1, 2025-10-03 14:15:00.878968 | controller | "start": "2025-10-03 13:39:10.800876" 2025-10-03 14:15:00.878985 | controller | } failure 2025-10-03 14:15:00.930652 | 2025-10-03 14:15:00.930739 | PLAY RECAP 2025-10-03 14:15:00.930781 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-10-03 14:15:00.930802 | 2025-10-03 14:15:00.995698 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 14:15:00.996535 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 14:15:01.485462 | 2025-10-03 14:15:01.485564 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-03 14:15:01.504830 | 2025-10-03 14:15:01.504901 | TASK [Filter out host if needed] 2025-10-03 14:15:01.512768 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-03 14:15:01.516876 | 2025-10-03 14:15:01.516953 | TASK [Ensure file is present] 2025-10-03 14:15:01.839479 | controller | ok 2025-10-03 14:15:01.846377 | 2025-10-03 14:15:01.846448 | TASK [Manage molecule report file] 2025-10-03 14:15:02.372669 | controller | changed 2025-10-03 14:15:02.377400 | 2025-10-03 14:15:02.377461 | TASK [Check if we get ci-framework-data basedir] 2025-10-03 14:15:02.590794 | controller | ok 2025-10-03 14:15:02.595787 | 2025-10-03 14:15:02.595848 | TASK [Create ci-framework-data log directory for zuul] 2025-10-03 14:15:02.939575 | controller | changed 2025-10-03 14:15:02.944578 | 2025-10-03 14:15:02.944639 | TASK [Copy ci-framework interesting files] 2025-10-03 14:15:03.180594 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-03 14:15:03.471240 | controller | changed 2025-10-03 14:15:03.475725 | 2025-10-03 14:15:03.475785 | TASK [Get SELinux listing] 2025-10-03 14:15:04.000231 | controller | changed 2025-10-03 14:15:04.005350 | 2025-10-03 14:15:04.005411 | TASK [Generate log index] 2025-10-03 14:15:04.858353 | controller | changed 2025-10-03 14:15:04.863073 | 2025-10-03 14:15:04.863139 | TASK [Get some env related data] 2025-10-03 14:15:05.535209 | controller | /home/zuul/.local/bin/ansible 2025-10-03 14:15:06.389274 | controller | changed 2025-10-03 14:15:06.394684 | 2025-10-03 14:15:06.394758 | TASK [Generate list of logs to collect in home directory] 2025-10-03 14:15:06.707433 | controller | ok: All paths examined 2025-10-03 14:15:06.737529 | 2025-10-03 14:15:06.737629 | LOOP [Copy logs from home directory] 2025-10-03 14:15:07.121629 | controller | changed: 2025-10-03 14:15:07.121879 | controller | { 2025-10-03 14:15:07.121924 | controller | "atime": 1743544925.4788878, 2025-10-03 14:15:07.122005 | controller | "ctime": 1743545329.1409318, 2025-10-03 14:15:07.122028 | controller | "dev": 64513, 2025-10-03 14:15:07.122046 | controller | "gid": 1000, 2025-10-03 14:15:07.122062 | controller | "gr_name": "zuul", 2025-10-03 14:15:07.122078 | controller | "inode": 4518807, 2025-10-03 14:15:07.122093 | controller | "isblk": false, 2025-10-03 14:15:07.122113 | controller | "ischr": false, 2025-10-03 14:15:07.122128 | controller | "isdir": false, 2025-10-03 14:15:07.122143 | controller | "isfifo": false, 2025-10-03 14:15:07.122157 | controller | "isgid": false, 2025-10-03 14:15:07.122170 | controller | "islnk": false, 2025-10-03 14:15:07.122183 | controller | "isreg": true, 2025-10-03 14:15:07.122196 | controller | "issock": false, 2025-10-03 14:15:07.122209 | controller | "isuid": false, 2025-10-03 14:15:07.122222 | controller | "mode": "0644", 2025-10-03 14:15:07.122235 | controller | "mtime": 1743545329.1409318, 2025-10-03 14:15:07.122248 | controller | "nlink": 1, 2025-10-03 14:15:07.122261 | controller | "path": "/home/zuul/crc-setup.log", 2025-10-03 14:15:07.122284 | controller | "pw_name": "zuul", 2025-10-03 14:15:07.122300 | controller | "rgrp": true, 2025-10-03 14:15:07.122314 | controller | "roth": true, 2025-10-03 14:15:07.122341 | controller | "rusr": true, 2025-10-03 14:15:07.122358 | controller | "size": 4108, 2025-10-03 14:15:07.122372 | controller | "uid": 1000, 2025-10-03 14:15:07.122386 | controller | "wgrp": false, 2025-10-03 14:15:07.122400 | controller | "woth": false, 2025-10-03 14:15:07.122416 | controller | "wusr": true, 2025-10-03 14:15:07.122431 | controller | "xgrp": false, 2025-10-03 14:15:07.122445 | controller | "xoth": false, 2025-10-03 14:15:07.122459 | controller | "xusr": false 2025-10-03 14:15:07.122472 | controller | } 2025-10-03 14:15:07.480574 | controller | changed: 2025-10-03 14:15:07.480672 | controller | { 2025-10-03 14:15:07.480698 | controller | "atime": 1743545331.0429637, 2025-10-03 14:15:07.480717 | controller | "ctime": 1743545842.0111232, 2025-10-03 14:15:07.480734 | controller | "dev": 64513, 2025-10-03 14:15:07.480751 | controller | "gid": 1000, 2025-10-03 14:15:07.480766 | controller | "gr_name": "zuul", 2025-10-03 14:15:07.480781 | controller | "inode": 4194437, 2025-10-03 14:15:07.480796 | controller | "isblk": false, 2025-10-03 14:15:07.480809 | controller | "ischr": false, 2025-10-03 14:15:07.480823 | controller | "isdir": false, 2025-10-03 14:15:07.480837 | controller | "isfifo": false, 2025-10-03 14:15:07.480850 | controller | "isgid": false, 2025-10-03 14:15:07.480864 | controller | "islnk": false, 2025-10-03 14:15:07.480878 | controller | "isreg": true, 2025-10-03 14:15:07.480892 | controller | "issock": false, 2025-10-03 14:15:07.480921 | controller | "isuid": false, 2025-10-03 14:15:07.480945 | controller | "mode": "0644", 2025-10-03 14:15:07.480961 | controller | "mtime": 1743545842.0111232, 2025-10-03 14:15:07.480977 | controller | "nlink": 1, 2025-10-03 14:15:07.480992 | controller | "path": "/home/zuul/crc-start.log", 2025-10-03 14:15:07.481006 | controller | "pw_name": "zuul", 2025-10-03 14:15:07.481020 | controller | "rgrp": true, 2025-10-03 14:15:07.481034 | controller | "roth": true, 2025-10-03 14:15:07.481048 | controller | "rusr": true, 2025-10-03 14:15:07.481062 | controller | "size": 4023, 2025-10-03 14:15:07.481076 | controller | "uid": 1000, 2025-10-03 14:15:07.481089 | controller | "wgrp": false, 2025-10-03 14:15:07.481103 | controller | "woth": false, 2025-10-03 14:15:07.481117 | controller | "wusr": true, 2025-10-03 14:15:07.481131 | controller | "xgrp": false, 2025-10-03 14:15:07.481145 | controller | "xoth": false, 2025-10-03 14:15:07.481158 | controller | "xusr": false 2025-10-03 14:15:07.481172 | controller | } 2025-10-03 14:15:07.837629 | controller | changed: 2025-10-03 14:15:07.837713 | controller | { 2025-10-03 14:15:07.837747 | controller | "atime": 1759498732.844616, 2025-10-03 14:15:07.837772 | controller | "ctime": 1759498748.6218383, 2025-10-03 14:15:07.837793 | controller | "dev": 64513, 2025-10-03 14:15:07.837813 | controller | "gid": 1000, 2025-10-03 14:15:07.837832 | controller | "gr_name": "zuul", 2025-10-03 14:15:07.837851 | controller | "inode": 4340277, 2025-10-03 14:15:07.837869 | controller | "isblk": false, 2025-10-03 14:15:07.837887 | controller | "ischr": false, 2025-10-03 14:15:07.837971 | controller | "isdir": false, 2025-10-03 14:15:07.838005 | controller | "isfifo": false, 2025-10-03 14:15:07.838025 | controller | "isgid": false, 2025-10-03 14:15:07.838042 | controller | "islnk": false, 2025-10-03 14:15:07.838057 | controller | "isreg": true, 2025-10-03 14:15:07.838072 | controller | "issock": false, 2025-10-03 14:15:07.838087 | controller | "isuid": false, 2025-10-03 14:15:07.838101 | controller | "mode": "0644", 2025-10-03 14:15:07.838115 | controller | "mtime": 1759498748.6218383, 2025-10-03 14:15:07.838129 | controller | "nlink": 1, 2025-10-03 14:15:07.838144 | controller | "path": "/home/zuul/ansible.log", 2025-10-03 14:15:07.838160 | controller | "pw_name": "zuul", 2025-10-03 14:15:07.838176 | controller | "rgrp": true, 2025-10-03 14:15:07.838190 | controller | "roth": true, 2025-10-03 14:15:07.838206 | controller | "rusr": true, 2025-10-03 14:15:07.838222 | controller | "size": 6749, 2025-10-03 14:15:07.838235 | controller | "uid": 1000, 2025-10-03 14:15:07.838249 | controller | "wgrp": false, 2025-10-03 14:15:07.838262 | controller | "woth": false, 2025-10-03 14:15:07.838275 | controller | "wusr": true, 2025-10-03 14:15:07.838288 | controller | "xgrp": false, 2025-10-03 14:15:07.838301 | controller | "xoth": false, 2025-10-03 14:15:07.838313 | controller | "xusr": false 2025-10-03 14:15:07.838337 | controller | } 2025-10-03 14:15:07.854250 | 2025-10-03 14:15:07.854316 | TASK [Copy crio stats log file] 2025-10-03 14:15:07.868574 | controller | skipping: Conditional result was False 2025-10-03 14:15:07.874105 | 2025-10-03 14:15:07.874170 | TASK [Get SELinux related data] 2025-10-03 14:15:08.105756 | controller | 2025-10-03 14:15:08.402309 | controller | ERROR 2025-10-03 14:15:08.402508 | controller | { 2025-10-03 14:15:08.402540 | controller | "delta": "0:00:00.008364", 2025-10-03 14:15:08.402561 | controller | "end": "2025-10-03 14:15:08.106638", 2025-10-03 14:15:08.402579 | controller | "msg": "non-zero return code", 2025-10-03 14:15:08.402594 | controller | "rc": 1, 2025-10-03 14:15:08.402609 | controller | "start": "2025-10-03 14:15:08.098274" 2025-10-03 14:15:08.402624 | controller | } 2025-10-03 14:15:08.402645 | controller | ERROR: Ignoring Errors 2025-10-03 14:15:08.407568 | 2025-10-03 14:15:08.407636 | TASK [Create system configuration directory] 2025-10-03 14:15:08.619970 | controller | changed 2025-10-03 14:15:08.625054 | 2025-10-03 14:15:08.625121 | TASK [Get some of the system configurations] 2025-10-03 14:15:09.149713 | controller | changed 2025-10-03 14:15:09.154699 | 2025-10-03 14:15:09.154765 | TASK [Copy generated documentation if available] 2025-10-03 14:15:09.168737 | controller | skipping: Conditional result was False 2025-10-03 14:15:09.174179 | 2025-10-03 14:15:09.174245 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-03 14:15:09.197526 | controller | skipping: Conditional result was False 2025-10-03 14:15:09.202938 | 2025-10-03 14:15:09.203006 | TASK [Compress logs bigger than 2MB] 2025-10-03 14:15:09.729196 | controller | changed 2025-10-03 14:15:09.734522 | 2025-10-03 14:15:09.734586 | TASK [Copy files from workspace on node] 2025-10-03 14:15:09.752880 | controller | ok 2025-10-03 14:15:09.774432 | 2025-10-03 14:15:09.774501 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 14:15:09.787594 | controller | skipping: Conditional result was False 2025-10-03 14:15:09.793038 | 2025-10-03 14:15:09.793109 | TASK [fetch-output : Set log path for single node] 2025-10-03 14:15:09.821782 | controller | ok 2025-10-03 14:15:09.826586 | 2025-10-03 14:15:09.826649 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 14:15:10.017440 | controller -> localhost | ok: "/var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/logs" 2025-10-03 14:15:10.017681 | controller -> localhost | changed: All items complete 2025-10-03 14:15:10.017710 | 2025-10-03 14:15:10.191549 | controller -> localhost | changed: "/var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/artifacts" 2025-10-03 14:15:10.365934 | controller -> localhost | changed: "/var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/docs" 2025-10-03 14:15:10.374205 | 2025-10-03 14:15:10.374282 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 14:15:10.960119 | controller | changed: 2025-10-03 14:15:10.960297 | controller | .d..t...... ./ 2025-10-03 14:15:10.960335 | controller | >f+++++++++ README.html 2025-10-03 14:15:10.960358 | controller | >f+++++++++ ansible-execution.log 2025-10-03 14:15:10.960378 | controller | >f+++++++++ ansible.log 2025-10-03 14:15:10.960394 | controller | >f+++++++++ crc-setup.log 2025-10-03 14:15:10.960409 | controller | >f+++++++++ crc-start.log 2025-10-03 14:15:10.960425 | controller | >f+++++++++ dmesg.log 2025-10-03 14:15:10.960445 | controller | >f+++++++++ installed-pkgs.log 2025-10-03 14:15:10.960462 | controller | >f+++++++++ python.log 2025-10-03 14:15:10.960478 | controller | >f+++++++++ registries.conf 2025-10-03 14:15:10.960492 | controller | >f+++++++++ report.html 2025-10-03 14:15:10.960506 | controller | >f+++++++++ selinux-denials.log 2025-10-03 14:15:10.960520 | controller | >f+++++++++ selinux-listing.log 2025-10-03 14:15:10.960534 | controller | cd+++++++++ ci-framework-data/ 2025-10-03 14:15:10.960548 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-03 14:15:10.960561 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-03 14:15:10.960577 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ 2025-10-03 14:15:10.960592 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/ 2025-10-03 14:15:10.960607 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/ 2025-10-03 14:15:10.960621 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-03 14:15:10.960635 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-03 14:15:10.960649 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-03 14:15:10.960662 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-03 14:15:10.960676 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-03 14:15:10.960691 | controller | >f+++++++++ ci-framework-data/logs/crc-config.log 2025-10-03 14:15:10.960705 | controller | >f+++++++++ ci-framework-data/logs/crc-setup.log 2025-10-03 14:15:10.960718 | controller | >f+++++++++ ci-framework-data/logs/crc-start-add_crc_creds 2025-10-03 14:15:10.960732 | controller | >f+++++++++ ci-framework-data/logs/crc-start.log 2025-10-03 14:15:10.960745 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-03 14:15:10.960759 | controller | cd+++++++++ registries.conf.d/ 2025-10-03 14:15:10.960773 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-03 14:15:10.960787 | controller | cd+++++++++ system-config/ 2025-10-03 14:15:10.960801 | controller | cd+++++++++ system-config/libvirt/ 2025-10-03 14:15:10.960814 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-03 14:15:10.960828 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-03 14:15:10.960841 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf 2025-10-03 14:15:10.960854 | controller | >f+++++++++ system-config/libvirt/network.conf 2025-10-03 14:15:10.960867 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf 2025-10-03 14:15:10.960881 | controller | >f+++++++++ system-config/libvirt/qemu.conf 2025-10-03 14:15:10.960894 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf 2025-10-03 14:15:10.960933 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf 2025-10-03 14:15:10.960959 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf 2025-10-03 14:15:10.960977 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf 2025-10-03 14:15:10.960993 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf 2025-10-03 14:15:10.961013 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf 2025-10-03 14:15:10.961029 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf 2025-10-03 14:15:10.961044 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf 2025-10-03 14:15:10.961058 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf 2025-10-03 14:15:10.961071 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf 2025-10-03 14:15:11.351371 | controller | changed: .d..t...... ./ 2025-10-03 14:15:11.748442 | controller | changed: .d..t...... ./ 2025-10-03 14:15:11.767834 | 2025-10-03 14:15:11.767903 | TASK [Return artifact to Zuul] 2025-10-03 14:15:11.796438 | controller | ok 2025-10-03 14:15:11.814868 | 2025-10-03 14:15:11.814943 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-03 14:15:11.815046 | 2025-10-03 14:15:11.815075 | PLAY RECAP 2025-10-03 14:15:11.815110 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-03 14:15:11.815131 | 2025-10-03 14:15:11.895064 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 14:15:11.895838 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 14:15:12.396952 | 2025-10-03 14:15:12.397064 | PLAY [all] 2025-10-03 14:15:12.415074 | 2025-10-03 14:15:12.415147 | TASK [include_role : fetch-output] 2025-10-03 14:15:12.443809 | controller | ok 2025-10-03 14:15:12.460604 | 2025-10-03 14:15:12.460688 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 14:15:12.504845 | controller | skipping: Conditional result was False 2025-10-03 14:15:12.510383 | 2025-10-03 14:15:12.510460 | TASK [fetch-output : Set log path for single node] 2025-10-03 14:15:12.539005 | controller | ok 2025-10-03 14:15:12.543848 | 2025-10-03 14:15:12.543935 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 14:15:12.863177 | controller -> localhost | ok: "/var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/logs" 2025-10-03 14:15:13.043492 | controller -> localhost | ok: "/var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/artifacts" 2025-10-03 14:15:13.222877 | controller -> localhost | ok: "/var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/docs" 2025-10-03 14:15:13.234359 | 2025-10-03 14:15:13.234477 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 14:15:13.763718 | controller | ok 2025-10-03 14:15:13.763949 | controller | ok: All items complete 2025-10-03 14:15:13.763983 | 2025-10-03 14:15:14.168003 | controller | ok 2025-10-03 14:15:14.549779 | controller | ok 2025-10-03 14:15:14.563717 | 2025-10-03 14:15:14.563801 | TASK [include_role : fetch-output-openshift] 2025-10-03 14:15:14.577389 | controller | skipping: Conditional result was False 2025-10-03 14:15:14.582952 | 2025-10-03 14:15:14.583025 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 14:15:14.928037 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008190 2025-10-03 14:15:15.118195 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009392 2025-10-03 14:15:15.149240 | 2025-10-03 14:15:15.149300 | PLAY [all] 2025-10-03 14:15:15.163057 | 2025-10-03 14:15:15.163127 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 14:15:15.591657 | controller | changed 2025-10-03 14:15:15.613477 | 2025-10-03 14:15:15.613540 | PLAY RECAP 2025-10-03 14:15:15.613591 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 14:15:15.613614 | 2025-10-03 14:15:15.726245 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 14:15:15.726959 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 14:15:16.306834 | 2025-10-03 14:15:16.307059 | PLAY [localhost] 2025-10-03 14:15:16.325744 | 2025-10-03 14:15:16.325821 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 14:15:16.641628 | localhost | changed 2025-10-03 14:15:16.646827 | 2025-10-03 14:15:16.646937 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 14:15:16.677231 | localhost | ok 2025-10-03 14:15:16.686531 | 2025-10-03 14:15:16.686629 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 14:15:17.000092 | localhost | changed 2025-10-03 14:15:17.006867 | 2025-10-03 14:15:17.006985 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 14:15:17.552291 | localhost | changed 2025-10-03 14:15:17.557411 | 2025-10-03 14:15:17.557503 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 14:15:17.910213 | localhost | Identity added: /var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/tmp/ansible.8matl14r (/var/lib/zuul/builds/885a5445a75149358ed989c1aa35f0bf/work/tmp/ansible.8matl14r) 2025-10-03 14:15:17.910400 | localhost | ok: Runtime: 0:00:00.007027 2025-10-03 14:15:17.914529 | 2025-10-03 14:15:17.914601 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 14:15:18.135574 | localhost | ok: Runtime: 0:00:00.006657 2025-10-03 14:15:18.140186 | 2025-10-03 14:15:18.140251 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 14:15:18.188860 | localhost | changed 2025-10-03 14:15:18.192983 | 2025-10-03 14:15:18.193045 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 14:15:18.513527 | localhost | changed 2025-10-03 14:15:18.531530 | 2025-10-03 14:15:18.531586 | PLAY [localhost] 2025-10-03 14:15:18.542053 | 2025-10-03 14:15:18.542116 | TASK [Generate bulk log download script] 2025-10-03 14:15:18.560683 | localhost | ok 2025-10-03 14:15:18.571859 | 2025-10-03 14:15:18.571937 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 14:15:18.599851 | localhost | ok: All assertions passed 2025-10-03 14:15:18.604079 | 2025-10-03 14:15:18.604144 | TASK [local-log-download : Create download script] 2025-10-03 14:15:18.931828 | localhost -> localhost | changed 2025-10-03 14:15:18.940136 | 2025-10-03 14:15:18.940197 | TASK [Register quick-download link] 2025-10-03 14:15:18.957525 | localhost | ok 2025-10-03 14:15:18.994377 | 2025-10-03 14:15:18.994449 | PLAY [logserver.rdoproject.org] 2025-10-03 14:15:19.003499 | 2025-10-03 14:15:19.003557 | TASK [Set zuul-log-path fact] 2025-10-03 14:15:19.020846 | logserver.rdoproject.org | ok 2025-10-03 14:15:19.030070 | 2025-10-03 14:15:19.030133 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 14:15:19.057113 | logserver.rdoproject.org | ok 2025-10-03 14:15:19.062121 | 2025-10-03 14:15:19.062181 | TASK [upload-logs : Create log directories] 2025-10-03 14:15:20.957692 | logserver.rdoproject.org | changed 2025-10-03 14:15:20.960845 | 2025-10-03 14:15:20.960928 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 14:15:21.188839 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004850 2025-10-03 14:15:21.194008 | 2025-10-03 14:15:21.194074 | TASK [upload-logs : Upload logs to log server] 2025-10-03 14:15:22.717010 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 14:15:22.719664 | 2025-10-03 14:15:22.719730 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 14:15:22.758051 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:15:22.766218 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:15:22.777236 | 2025-10-03 14:15:22.777317 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 14:15:22.812001 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:15:22.812216 | 2025-10-03 14:15:22.815110 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 14:15:22.824346 | 2025-10-03 14:15:22.824437 | LOOP [upload-logs : Upload console log and json output]