2025-10-08 12:14:34.210470 | Job console starting...
2025-10-08 12:14:34.219470 | Updating repositories
2025-10-08 12:14:34.250404 | Preparing job workspace
2025-10-08 12:14:40.502605 | Running Ansible setup...
2025-10-08 12:14:46.816354 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:14:47.463942 | 
2025-10-08 12:14:47.464071 | PLAY [localhost]
2025-10-08 12:14:47.474438 | 
2025-10-08 12:14:47.474536 | TASK [Gathering Facts]
2025-10-08 12:14:48.388352 | localhost | ok
2025-10-08 12:14:48.426450 | 
2025-10-08 12:14:48.426630 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 12:14:48.830606 | localhost -> localhost | changed
2025-10-08 12:14:48.838004 | 
2025-10-08 12:14:48.838119 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 12:14:49.697263 | localhost -> localhost | changed
2025-10-08 12:14:49.706315 | 
2025-10-08 12:14:49.706386 | TASK [Setup log path fact]
2025-10-08 12:14:49.724566 | localhost | ok
2025-10-08 12:14:49.746981 | 
2025-10-08 12:14:49.747089 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:14:49.786616 | localhost | ok
2025-10-08 12:14:49.794714 | 
2025-10-08 12:14:49.794783 | TASK [emit-job-header : Print job information]
2025-10-08 12:14:49.832952 | # Job Information
2025-10-08 12:14:49.833080 | Ansible Version: 2.15.12
2025-10-08 12:14:49.833107 | Job: cifmw-molecule-ci_nmstate
2025-10-08 12:14:49.833127 | Pipeline: github-check
2025-10-08 12:14:49.833145 | Executor: ze04.softwarefactory-project.io
2025-10-08 12:14:49.833164 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-08 12:14:49.833183 | Log URL (when completed): https://logserver.rdoproject.org/bed/rdoproject.org/bed59b8630754d13bc7467c34886fba8/
2025-10-08 12:14:49.833201 | Event ID: 809f8c00-a43f-11f0-9dc5-9cf6e8dba5cd
2025-10-08 12:14:49.836946 | 
2025-10-08 12:14:49.837008 | LOOP [emit-job-header : Print node information]
2025-10-08 12:14:49.951706 | localhost | ok:
2025-10-08 12:14:49.952060 | localhost | # Node Information
2025-10-08 12:14:49.952096 | localhost | Inventory Hostname: controller
2025-10-08 12:14:49.952120 | localhost | Hostname: np0005476056
2025-10-08 12:14:49.952140 | localhost | Username: zuul
2025-10-08 12:14:49.952162 | localhost | Distro: CentOS 9
2025-10-08 12:14:49.952180 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-08 12:14:49.952198 | localhost | Region: RegionOne
2025-10-08 12:14:49.952216 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-10-08 12:14:49.952234 | localhost | Product Name: OpenStack Nova
2025-10-08 12:14:49.952251 | localhost | Interface IP: 38.129.56.76
2025-10-08 12:14:49.981633 | 
2025-10-08 12:14:49.981792 | PLAY [all]
2025-10-08 12:14:49.988646 | 
2025-10-08 12:14:49.988737 | TASK [Gather network facts]
2025-10-08 12:14:50.548013 | controller | ok
2025-10-08 12:14:50.586431 | 
2025-10-08 12:14:50.586581 | TASK [include_role : start-zuul-console]
2025-10-08 12:14:50.608556 | controller | ok
2025-10-08 12:14:50.626825 | 
2025-10-08 12:14:50.626948 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 12:14:51.122758 | controller | ok
2025-10-08 12:14:51.143623 | 
2025-10-08 12:14:51.143857 | TASK [include_role : add-build-sshkey]
2025-10-08 12:14:51.165418 | controller | ok
2025-10-08 12:14:51.179245 | 
2025-10-08 12:14:51.179349 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 12:14:51.457929 | controller -> localhost | ok
2025-10-08 12:14:51.469722 | 
2025-10-08 12:14:51.469897 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 12:14:51.510124 | controller | ok
2025-10-08 12:14:51.537499 | controller | included: /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 12:14:51.546088 | 
2025-10-08 12:14:51.546189 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 12:14:52.069572 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 12:14:52.069980 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/bed59b8630754d13bc7467c34886fba8_id_rsa.
2025-10-08 12:14:52.070052 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/bed59b8630754d13bc7467c34886fba8_id_rsa.pub.
2025-10-08 12:14:52.070103 | controller -> localhost | The key fingerprint is:
2025-10-08 12:14:52.070149 | controller -> localhost | SHA256:9Pn7JWDt1kegUK088uZxf0eL3We3GRwbJpfZbV0hMNc zuul-build-sshkey
2025-10-08 12:14:52.070195 | controller -> localhost | The key's randomart image is:
2025-10-08 12:14:52.070239 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 12:14:52.070281 | controller -> localhost | |           oo+.. |
2025-10-08 12:14:52.070324 | controller -> localhost | |           .o oE.|
2025-10-08 12:14:52.070369 | controller -> localhost | |        . .. .. .|
2025-10-08 12:14:52.070411 | controller -> localhost | |       . ..o+o .B|
2025-10-08 12:14:52.070451 | controller -> localhost | |        S oo+o.B*|
2025-10-08 12:14:52.070492 | controller -> localhost | |           o+o*+=|
2025-10-08 12:14:52.070532 | controller -> localhost | |           o.o***|
2025-10-08 12:14:52.070572 | controller -> localhost | |            .+ =%|
2025-10-08 12:14:52.070613 | controller -> localhost | |            ...+*|
2025-10-08 12:14:52.070653 | controller -> localhost | +----[SHA256]-----+
2025-10-08 12:14:52.070784 | controller -> localhost | ok: Runtime: 0:00:00.090058
2025-10-08 12:14:52.084932 | 
2025-10-08 12:14:52.085096 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 12:14:52.121383 | controller | ok
2025-10-08 12:14:52.142222 | controller | included: /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 12:14:52.154275 | 
2025-10-08 12:14:52.154388 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 12:14:52.179603 | controller | skipping: Conditional result was False
2025-10-08 12:14:52.187090 | 
2025-10-08 12:14:52.187178 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 12:14:52.873596 | controller | changed
2025-10-08 12:14:52.887090 | 
2025-10-08 12:14:52.887259 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 12:14:53.262357 | controller | ok
2025-10-08 12:14:53.267655 | 
2025-10-08 12:14:53.267767 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 12:14:54.735089 | controller | changed
2025-10-08 12:14:54.747432 | 
2025-10-08 12:14:54.747561 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 12:14:56.303319 | controller | changed
2025-10-08 12:14:56.315667 | 
2025-10-08 12:14:56.315826 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 12:14:56.345808 | controller | skipping: Conditional result was False
2025-10-08 12:14:56.359913 | 
2025-10-08 12:14:56.360047 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 12:14:56.776395 | controller -> localhost | changed
2025-10-08 12:14:56.788277 | 
2025-10-08 12:14:56.788351 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 12:14:57.129159 | controller -> localhost | Identity added: /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/bed59b8630754d13bc7467c34886fba8_id_rsa (zuul-build-sshkey)
2025-10-08 12:14:57.129575 | controller -> localhost | ok: Runtime: 0:00:00.014329
2025-10-08 12:14:57.143137 | 
2025-10-08 12:14:57.143417 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 12:14:57.623940 | controller | ok
2025-10-08 12:14:57.634976 | 
2025-10-08 12:14:57.635111 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 12:14:57.672656 | controller | skipping: Conditional result was False
2025-10-08 12:14:57.693086 | 
2025-10-08 12:14:57.693220 | TASK [include_role : validate-host]
2025-10-08 12:14:57.728089 | controller | ok
2025-10-08 12:14:57.782973 | 
2025-10-08 12:14:57.783101 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 12:14:57.823353 | controller | ok
2025-10-08 12:14:57.829569 | 
2025-10-08 12:14:57.829651 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 12:14:58.129790 | controller -> localhost | ok
2025-10-08 12:14:58.137796 | 
2025-10-08 12:14:58.137880 | TASK [validate-host : Collect information about the host]
2025-10-08 12:14:58.997500 | controller | ok
2025-10-08 12:14:59.020160 | 
2025-10-08 12:14:59.020331 | TASK [validate-host : Sanitize hostname]
2025-10-08 12:14:59.085255 | controller | ok
2025-10-08 12:14:59.092222 | 
2025-10-08 12:14:59.092309 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 12:14:59.557145 | controller -> localhost | changed
2025-10-08 12:14:59.569101 | 
2025-10-08 12:14:59.569240 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 12:15:00.037462 | controller | ok
2025-10-08 12:15:00.049002 | 
2025-10-08 12:15:00.049159 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 12:15:00.569408 | controller -> localhost | changed
2025-10-08 12:15:00.597608 | 
2025-10-08 12:15:00.597836 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 12:15:00.623721 | controller | skipping: Conditional result was False
2025-10-08 12:15:00.630312 | 
2025-10-08 12:15:00.630396 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 12:15:00.646055 | controller | skipping: Conditional result was False
2025-10-08 12:15:00.654268 | 
2025-10-08 12:15:00.654363 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 12:15:01.319252 | controller | ok: "logs"
2025-10-08 12:15:01.319542 | controller | ok: All items complete
2025-10-08 12:15:01.319578 | 
2025-10-08 12:15:01.614028 | controller | ok: "artifacts"
2025-10-08 12:15:01.905170 | controller | ok: "docs"
2025-10-08 12:15:01.924091 | 
2025-10-08 12:15:01.924307 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 12:15:02.269313 | controller | changed: "logs"
2025-10-08 12:15:02.593861 | controller | changed: "artifacts"
2025-10-08 12:15:02.913181 | controller | changed: "docs"
2025-10-08 12:15:02.945738 | 
2025-10-08 12:15:02.945857 | PLAY RECAP
2025-10-08 12:15:02.945916 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:15:02.945954 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:15:02.945980 | 
2025-10-08 12:15:03.050186 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:15:03.050978 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:15:03.640884 | 
2025-10-08 12:15:03.641002 | PLAY [all]
2025-10-08 12:15:03.663289 | 
2025-10-08 12:15:03.663428 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:15:03.723440 | controller | ok
2025-10-08 12:15:03.728749 | 
2025-10-08 12:15:03.728830 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:15:04.230101 | controller | changed
2025-10-08 12:15:04.237264 | 
2025-10-08 12:15:04.237357 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:15:05.976009 | controller | changed
2025-10-08 12:15:05.999608 | 
2025-10-08 12:15:05.999780 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 12:15:06.586446 | controller | changed:
2025-10-08 12:15:06.586745 | controller | {
2025-10-08 12:15:06.586804 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 12:15:06.586849 | controller | }
2025-10-08 12:15:06.958360 | controller | changed:
2025-10-08 12:15:06.958473 | controller | {
2025-10-08 12:15:06.958504 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 12:15:06.958526 | controller | }
2025-10-08 12:15:07.328822 | controller | changed:
2025-10-08 12:15:07.328920 | controller | {
2025-10-08 12:15:07.328949 | 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-08 12:15:07.328971 | controller | }
2025-10-08 12:15:07.689709 | controller | changed:
2025-10-08 12:15:07.689894 | controller | {
2025-10-08 12:15:07.689949 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 12:15:07.689991 | controller | }
2025-10-08 12:15:08.084791 | controller | changed:
2025-10-08 12:15:08.085039 | controller | {
2025-10-08 12:15:08.085098 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 12:15:08.085139 | controller | }
2025-10-08 12:15:08.422883 | controller | changed:
2025-10-08 12:15:08.422976 | controller | {
2025-10-08 12:15:08.423008 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 12:15:08.423033 | controller | }
2025-10-08 12:15:08.784644 | controller | changed:
2025-10-08 12:15:08.784838 | controller | {
2025-10-08 12:15:08.784893 | 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-08 12:15:08.784936 | controller | }
2025-10-08 12:15:09.248500 | controller | changed:
2025-10-08 12:15:09.248643 | controller | {
2025-10-08 12:15:09.248730 | 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-08 12:15:09.248778 | controller | }
2025-10-08 12:15:09.616813 | controller | changed:
2025-10-08 12:15:09.616928 | controller | {
2025-10-08 12:15:09.616962 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 12:15:09.617008 | controller | }
2025-10-08 12:15:09.987154 | controller | changed:
2025-10-08 12:15:09.987418 | controller | {
2025-10-08 12:15:09.987478 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 12:15:09.987522 | controller | }
2025-10-08 12:15:10.387129 | controller | changed:
2025-10-08 12:15:10.387239 | controller | {
2025-10-08 12:15:10.387275 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 12:15:10.387306 | controller | }
2025-10-08 12:15:10.758057 | controller | changed:
2025-10-08 12:15:10.758164 | controller | {
2025-10-08 12:15:10.758200 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 12:15:10.758231 | controller | }
2025-10-08 12:15:11.131898 | controller | changed:
2025-10-08 12:15:11.132016 | controller | {
2025-10-08 12:15:11.132057 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 12:15:11.132097 | controller | }
2025-10-08 12:15:11.503216 | controller | changed:
2025-10-08 12:15:11.503300 | controller | {
2025-10-08 12:15:11.503325 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 12:15:11.503344 | controller | }
2025-10-08 12:15:11.893314 | controller | changed:
2025-10-08 12:15:11.893544 | controller | {
2025-10-08 12:15:11.893605 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 12:15:11.893648 | controller | }
2025-10-08 12:15:12.265815 | controller | changed:
2025-10-08 12:15:12.265913 | controller | {
2025-10-08 12:15:12.265937 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 12:15:12.265956 | controller | }
2025-10-08 12:15:12.649944 | controller | changed:
2025-10-08 12:15:12.650097 | controller | {
2025-10-08 12:15:12.650297 | 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-08 12:15:12.650360 | controller | }
2025-10-08 12:15:13.010943 | controller | changed:
2025-10-08 12:15:13.011089 | controller | {
2025-10-08 12:15:13.011127 | 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-08 12:15:13.011159 | controller | }
2025-10-08 12:15:13.421051 | controller | changed:
2025-10-08 12:15:13.421137 | controller | {
2025-10-08 12:15:13.421164 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 12:15:13.421183 | controller | }
2025-10-08 12:15:13.794996 | controller | changed:
2025-10-08 12:15:13.795162 | controller | {
2025-10-08 12:15:13.795196 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 12:15:13.795221 | controller | }
2025-10-08 12:15:14.201504 | controller | changed:
2025-10-08 12:15:14.201595 | controller | {
2025-10-08 12:15:14.201618 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 12:15:14.201637 | controller | }
2025-10-08 12:15:14.619306 | controller | changed:
2025-10-08 12:15:14.619395 | controller | {
2025-10-08 12:15:14.619419 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 12:15:14.619437 | controller | }
2025-10-08 12:15:15.047195 | controller | changed:
2025-10-08 12:15:15.047340 | controller | {
2025-10-08 12:15:15.047390 | 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-08 12:15:15.047431 | controller | }
2025-10-08 12:15:15.428927 | controller | changed:
2025-10-08 12:15:15.429096 | controller | {
2025-10-08 12:15:15.429134 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 12:15:15.429161 | controller | }
2025-10-08 12:15:15.852730 | controller | changed:
2025-10-08 12:15:15.852878 | controller | {
2025-10-08 12:15:15.852945 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 12:15:15.853005 | controller | }
2025-10-08 12:15:16.278348 | controller | changed:
2025-10-08 12:15:16.278444 | controller | {
2025-10-08 12:15:16.278467 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 12:15:16.278485 | controller | }
2025-10-08 12:15:16.301861 | 
2025-10-08 12:15:16.301997 | TASK [Set timezone to UTC]
2025-10-08 12:15:17.057529 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 12:15:17.066325 | 
2025-10-08 12:15:17.066429 | TASK [Create nodepool directory]
2025-10-08 12:15:17.465581 | controller | changed
2025-10-08 12:15:17.478763 | 
2025-10-08 12:15:17.479034 | TASK [Create nodepool sub_nodes file]
2025-10-08 12:15:18.909108 | controller | changed
2025-10-08 12:15:18.914370 | 
2025-10-08 12:15:18.914438 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 12:15:20.388823 | controller | changed
2025-10-08 12:15:20.401543 | 
2025-10-08 12:15:20.401734 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 12:15:20.442984 | 
2025-10-08 12:15:20.443349 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 12:15:20.484839 | 
2025-10-08 12:15:20.485120 | TASK [Create nodepool primary file]
2025-10-08 12:15:20.512335 | controller | skipping: Conditional result was False
2025-10-08 12:15:20.525906 | 
2025-10-08 12:15:20.526078 | TASK [Create nodepool node_private for this node]
2025-10-08 12:15:21.913272 | controller | changed
2025-10-08 12:15:21.926256 | 
2025-10-08 12:15:21.926388 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 12:15:22.440556 | controller | ok: Item: id_rsa Runtime: 0:00:00.010487
2025-10-08 12:15:22.440751 | 
2025-10-08 12:15:22.729875 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007146
2025-10-08 12:15:22.744761 | 
2025-10-08 12:15:22.744876 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 12:15:24.187132 | controller | changed
2025-10-08 12:15:24.193911 | 
2025-10-08 12:15:24.194021 | TASK [Validate sudoers config after edits]
2025-10-08 12:15:24.609531 | controller | /etc/sudoers: parsed OK
2025-10-08 12:15:24.609674 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 12:15:24.609684 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 12:15:24.609690 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 12:15:24.726615 | controller | ok: Runtime: 0:00:00.009582
2025-10-08 12:15:24.732511 | 
2025-10-08 12:15:24.732577 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 12:15:25.074216 | controller | SHELL=/bin/bash
2025-10-08 12:15:25.074302 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 12:15:25.074322 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 12:15:25.074336 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-08 12:15:25.074351 | controller | PWD=/home/zuul
2025-10-08 12:15:25.074488 | controller | ZUUL_PIPELINE=github-check
2025-10-08 12:15:25.074507 | controller | LOGNAME=zuul
2025-10-08 12:15:25.074521 | controller | XDG_SESSION_TYPE=tty
2025-10-08 12:15:25.074536 | controller | _=/usr/bin/env
2025-10-08 12:15:25.074551 | controller | MOTD_SHOWN=pam
2025-10-08 12:15:25.074566 | controller | HOME=/home/zuul
2025-10-08 12:15:25.074580 | controller | LANG=en_US.UTF-8
2025-10-08 12:15:25.074594 | controller | SSH_CONNECTION=38.102.83.114 43284 38.129.56.76 22
2025-10-08 12:15:25.074609 | 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-08 12:15:25.074631 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-08 12:15:25.074646 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 12:15:25.074661 | controller | XDG_SESSION_CLASS=user
2025-10-08 12:15:25.074675 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 12:15:25.074690 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 12:15:25.074703 | controller | USER=zuul
2025-10-08 12:15:25.074715 | controller | ZUUL_VOTING=True
2025-10-08 12:15:25.074728 | controller | BUILD_TIMEOUT=1800000
2025-10-08 12:15:25.074741 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 12:15:25.074754 | controller | SHLVL=1
2025-10-08 12:15:25.074767 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-08 12:15:25.074780 | controller | XDG_SESSION_ID=1
2025-10-08 12:15:25.074793 | controller | ZUUL_BRANCH=main
2025-10-08 12:15:25.074806 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 12:15:25.074819 | controller | SSH_CLIENT=38.102.83.114 43284 22
2025-10-08 12:15:25.074883 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 12:15:25.074904 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 12:15:25.074918 | controller | which_declare=declare -f
2025-10-08 12:15:25.074932 | 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-08 12:15:25.074945 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 12:15:25.074958 | controller | ZUUL_CHANGE=3362
2025-10-08 12:15:25.074971 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 12:15:25.074984 | controller | ZUUL_UUID=bed59b8630754d13bc7467c34886fba8
2025-10-08 12:15:25.074997 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 12:15:25.075010 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 12:15:25.075024 | controller | }
2025-10-08 12:15:25.265485 | controller | ok: Runtime: 0:00:00.009749
2025-10-08 12:15:25.271502 | 
2025-10-08 12:15:25.271567 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 12:15:25.285484 | controller | skipping: Conditional result was False
2025-10-08 12:15:25.291699 | 
2025-10-08 12:15:25.291781 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 12:15:25.848840 | controller | skipping: Conditional result was False
2025-10-08 12:15:25.864288 | 
2025-10-08 12:15:25.864450 | TASK [Ensure legacy workspace directory]
2025-10-08 12:15:26.398639 | controller | changed
2025-10-08 12:15:26.450272 | 
2025-10-08 12:15:26.450432 | PLAY RECAP
2025-10-08 12:15:26.450521 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:15:26.450566 | 
2025-10-08 12:15:26.566669 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:15:26.567467 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-08 12:15:27.127078 | 
2025-10-08 12:15:27.127196 | PLAY [all,!compute]
2025-10-08 12:15:27.157598 | 
2025-10-08 12:15:27.157744 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-08 12:15:27.686909 | controller | skipping: Conditional result was False
2025-10-08 12:15:27.699533 | 
2025-10-08 12:15:27.699761 | TASK [Install podman]
2025-10-08 12:15:27.767338 | controller | skipping: Conditional result was False
2025-10-08 12:15:27.776398 | 
2025-10-08 12:15:27.776494 | TASK [Set var name for quay login zuul secret]
2025-10-08 12:15:27.811395 | controller | skipping: Conditional result was False
2025-10-08 12:15:27.819462 | 
2025-10-08 12:15:27.819564 | TASK [Print the username]
2025-10-08 12:15:28.359874 | controller | skipping: Conditional result was False
2025-10-08 12:15:28.366009 | 
2025-10-08 12:15:28.366094 | TASK [Fail when user or password is not set]
2025-10-08 12:15:28.421176 | controller | skipping: Conditional result was False
2025-10-08 12:15:28.429591 | 
2025-10-08 12:15:28.429744 | TASK [Set vars for quay login]
2025-10-08 12:15:28.508257 | 
2025-10-08 12:15:28.508618 | LOOP [Perform container registry login(s) with podman]
2025-10-08 12:15:28.560250 | 
2025-10-08 12:15:28.560406 | LOOP [Perform container registry login(s) with buildah]
2025-10-08 12:15:28.639640 | 
2025-10-08 12:15:28.639807 | TASK [Set Insecure registry for content provider]
2025-10-08 12:15:28.673688 | controller | skipping: Conditional result was False
2025-10-08 12:15:28.698803 | 
2025-10-08 12:15:28.698903 | PLAY RECAP
2025-10-08 12:15:28.698946 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-10-08 12:15:28.698967 | 
2025-10-08 12:15:28.793721 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-08 12:15:28.794504 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-08 12:15:29.432218 | 
2025-10-08 12:15:29.432323 | PLAY [Prefer ipv4 over ipv6]
2025-10-08 12:15:29.478428 | 
2025-10-08 12:15:29.478564 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-10-08 12:15:29.535843 | controller | skipping: Conditional result was False
2025-10-08 12:15:29.556145 | 
2025-10-08 12:15:29.556211 | PLAY [controller]
2025-10-08 12:15:29.570266 | 
2025-10-08 12:15:29.570351 | TASK [Fail when openshift_pull_sec not provided]
2025-10-08 12:15:29.584195 | controller | skipping: Conditional result was False
2025-10-08 12:15:29.590949 | 
2025-10-08 12:15:29.591008 | TASK [Clone repos in the job workspace]
2025-10-08 12:15:29.613583 | controller | ok
2025-10-08 12:15:29.629715 | 
2025-10-08 12:15:29.629806 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 12:15:30.208287 | controller | ok
2025-10-08 12:15:30.224446 | 
2025-10-08 12:15:30.224625 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 12:15:34.381346 | controller | Output suppressed because no_log was given
2025-10-08 12:15:34.400368 | 
2025-10-08 12:15:34.400492 | TASK [Create pull-secret.txt file]
2025-10-08 12:15:36.059519 | controller | Output suppressed because no_log was given
2025-10-08 12:15:36.066187 | 
2025-10-08 12:15:36.066266 | TASK [Apply CRC features and print image build date]
2025-10-08 12:15:36.092282 | controller | ok
2025-10-08 12:15:36.102803 | controller | included: /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-10-08 12:15:36.118190 | 
2025-10-08 12:15:36.118264 | TASK [Set additional parameters for crc - telemetry]
2025-10-08 12:15:36.680067 | controller | skipping: Conditional result was False
2025-10-08 12:15:36.692405 | 
2025-10-08 12:15:36.692539 | TASK [Set additional parameters for crc - monitoring]
2025-10-08 12:15:37.242612 | controller | skipping: Conditional result was False
2025-10-08 12:15:37.261842 | 
2025-10-08 12:15:37.262026 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-10-08 12:15:37.827749 | controller | skipping: Conditional result was False
2025-10-08 12:15:37.841437 | 
2025-10-08 12:15:37.841612 | TASK [Set additional parameters for crc - enable microshift preset]
2025-10-08 12:15:38.395189 | controller | skipping: Conditional result was False
2025-10-08 12:15:38.408438 | 
2025-10-08 12:15:38.408607 | TASK [Set additional parameters for crc - enable okd preset]
2025-10-08 12:15:38.955710 | controller | skipping: Conditional result was False
2025-10-08 12:15:38.961900 | 
2025-10-08 12:15:38.961989 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-10-08 12:15:39.493150 | controller | skipping: Conditional result was False
2025-10-08 12:15:39.499441 | 
2025-10-08 12:15:39.499509 | TASK [Execute crc start command]
2025-10-08 12:19:27.463423 | controller | ok: Runtime: 0:03:47.392953
2025-10-08 12:19:27.476386 | 
2025-10-08 12:19:27.476515 | TASK [Verify that OpenShift is up and running]
2025-10-08 12:19:27.506510 | controller | ok
2025-10-08 12:19:27.525936 | controller | included: /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-10-08 12:19:27.539624 | 
2025-10-08 12:19:27.539780 | TASK [Create script to login and verify services]
2025-10-08 12:19:29.085941 | controller | changed
2025-10-08 12:19:29.097875 | 
2025-10-08 12:19:29.098001 | TASK [Ensure that user is logged]
2025-10-08 12:19:29.524567 | controller | Checking login to the cluster 1...
2025-10-08 12:19:32.530757 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:19:32.676373 | controller |
2025-10-08 12:19:32.676451 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:19:32.680880 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:19:37.683560 | controller | Checking login to the cluster 2...
2025-10-08 12:19:37.818283 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:19:37.838265 | controller |
2025-10-08 12:19:37.838357 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:19:37.843512 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:19:42.845895 | controller | Checking login to the cluster 3...
2025-10-08 12:19:42.957635 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:19:42.974109 | controller |
2025-10-08 12:19:42.974191 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:19:42.978945 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:19:47.982261 | controller | Checking login to the cluster 4...
2025-10-08 12:19:48.124102 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:19:48.143319 | controller |
2025-10-08 12:19:48.143382 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:19:48.148795 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:19:53.153605 | controller | Checking login to the cluster 5...
2025-10-08 12:19:53.282345 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:19:53.307395 | controller |
2025-10-08 12:19:53.307481 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:19:53.314444 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:19:58.321795 | controller | Checking login to the cluster 6...
2025-10-08 12:19:58.499560 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:19:58.537298 | controller |
2025-10-08 12:19:58.537385 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:19:58.542649 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:03.546866 | controller | Checking login to the cluster 7...
2025-10-08 12:20:03.691673 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:03.733727 | controller |
2025-10-08 12:20:03.733802 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:20:03.740858 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:08.743579 | controller | Checking login to the cluster 8...
2025-10-08 12:20:08.916963 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:09.007256 | controller |
2025-10-08 12:20:09.007371 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:20:09.012468 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:14.014903 | controller | Checking login to the cluster 9...
2025-10-08 12:20:14.186252 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:14.233619 | controller |
2025-10-08 12:20:14.233709 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:20:14.238779 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:19.241421 | controller | Checking login to the cluster 10...
2025-10-08 12:20:19.434498 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:19.748211 | controller |
2025-10-08 12:20:19.748287 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 12:20:19.754946 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:24.757552 | controller | Checking login to the cluster 11...
2025-10-08 12:20:25.228921 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:26.009001 | controller |
2025-10-08 12:20:26.009092 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 12:20:26.014474 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:31.019167 | controller | Checking login to the cluster 12...
2025-10-08 12:20:31.225129 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:31.376570 | controller |
2025-10-08 12:20:31.376656 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 12:20:31.382282 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:36.386522 | controller | Checking login to the cluster 13...
2025-10-08 12:20:36.539387 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:36.662655 | controller |
2025-10-08 12:20:36.662728 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 12:20:36.667747 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:41.670302 | controller | Checking login to the cluster 14...
2025-10-08 12:20:41.905477 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:42.068473 | controller |
2025-10-08 12:20:42.068572 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 12:20:42.075444 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:47.078577 | controller | Checking login to the cluster 15...
2025-10-08 12:20:48.010228 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:48.735956 | controller |
2025-10-08 12:20:48.736041 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 12:20:48.741165 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:20:53.744381 | controller | Checking login to the cluster 16...
2025-10-08 12:20:54.597240 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:20:56.435820 | controller |
2025-10-08 12:20:56.435960 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 12:20:56.442864 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:01.445736 | controller | Checking login to the cluster 17...
2025-10-08 12:21:01.606846 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:01.668385 | controller |
2025-10-08 12:21:01.668487 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:01.677118 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:06.681275 | controller | Checking login to the cluster 18...
2025-10-08 12:21:06.878537 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:06.904135 | controller |
2025-10-08 12:21:06.904235 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:06.910415 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:11.915151 | controller | Checking login to the cluster 19...
2025-10-08 12:21:12.078732 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:12.117003 | controller |
2025-10-08 12:21:12.117105 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:12.122635 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:17.125690 | controller | Checking login to the cluster 20...
2025-10-08 12:21:17.276521 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:17.314808 | controller |
2025-10-08 12:21:17.314914 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:17.320670 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:22.323017 | controller | Checking login to the cluster 21...
2025-10-08 12:21:22.709442 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:22.864425 | controller |
2025-10-08 12:21:22.864540 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:22.869139 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:27.872473 | controller | Checking login to the cluster 22...
2025-10-08 12:21:28.030515 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:28.064133 | controller |
2025-10-08 12:21:28.064244 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:28.070213 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:33.073615 | controller | Checking login to the cluster 23...
2025-10-08 12:21:33.209747 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:33.254466 | controller |
2025-10-08 12:21:33.254604 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:33.266404 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:38.270466 | controller | Checking login to the cluster 24...
2025-10-08 12:21:38.399377 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:38.427664 | controller |
2025-10-08 12:21:38.427756 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:38.432852 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:43.434877 | controller | Checking login to the cluster 25...
2025-10-08 12:21:43.569497 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:43.591322 | controller |
2025-10-08 12:21:43.591397 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:43.596183 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:48.599574 | controller | Checking login to the cluster 26...
2025-10-08 12:21:48.738418 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:48.757913 | controller |
2025-10-08 12:21:48.758012 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:48.765876 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:53.768451 | controller | Checking login to the cluster 27...
2025-10-08 12:21:53.944574 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:53.983391 | controller |
2025-10-08 12:21:53.983482 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:53.989086 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:21:58.991605 | controller | Checking login to the cluster 28...
2025-10-08 12:21:59.134643 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:21:59.164660 | controller |
2025-10-08 12:21:59.164739 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:21:59.169323 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:22:04.173181 | controller | Checking login to the cluster 29...
2025-10-08 12:22:04.295861 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:22:04.321092 | controller |
2025-10-08 12:22:04.321284 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:22:04.328683 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:22:09.331180 | controller | Checking login to the cluster 30...
2025-10-08 12:22:09.452601 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:22:09.472800 | controller |
2025-10-08 12:22:09.472925 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:22:09.478623 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:22:14.480793 | controller | Checking login to the cluster 31...
2025-10-08 12:22:14.604997 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:22:14.625601 | controller |
2025-10-08 12:22:14.625697 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:22:14.632282 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:22:19.634307 | controller | Checking login to the cluster 32...
2025-10-08 12:22:19.785291 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:22:19.818937 | controller |
2025-10-08 12:22:19.819011 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:22:19.824928 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:22:24.826964 | controller | Checking login to the cluster 33...
2025-10-08 12:22:24.939006 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:22:24.960268 | controller |
2025-10-08 12:22:24.960353 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 12:22:24.966400 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 12:22:29.968214 | controller | Checking login to the cluster 34...
2025-10-08 12:22:30.099937 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 12:22:34.174313 | controller |
2025-10-08 12:22:34.174405 | controller | Login successful.
2025-10-08 12:22:34.519362 | controller |
2025-10-08 12:22:34.519449 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-10-08 12:22:34.549664 | controller |
2025-10-08 12:22:34.549722 | controller | Using project "default".
2025-10-08 12:22:34.877439 | controller | changed
2025-10-08 12:22:34.891590 | 
2025-10-08 12:22:34.891767 | TASK [Delete all openshift-marketplace pods]
2025-10-08 12:22:38.166294 | controller | pod "certified-operators-d4s82" deleted
2025-10-08 12:22:38.196124 | controller | pod "certified-operators-f8szs" deleted
2025-10-08 12:22:38.237590 | controller | pod "community-operators-5zsqg" deleted
2025-10-08 12:22:38.301176 | controller | pod "community-operators-8xtnz" deleted
2025-10-08 12:22:38.347696 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2025-10-08 12:22:39.480079 | controller | pod "redhat-marketplace-ps6xk" deleted
2025-10-08 12:22:40.295846 | controller | pod "redhat-marketplace-xl58b" deleted
2025-10-08 12:22:42.219380 | controller | pod "redhat-operators-h5ns9" deleted
2025-10-08 12:22:43.179299 | controller | pod "redhat-operators-tqxl9" deleted
2025-10-08 12:22:49.963279 | controller | ok
2025-10-08 12:22:49.975882 | 
2025-10-08 12:22:49.976021 | TASK [Copy script ensure_services_up.sh]
2025-10-08 12:22:50.043740 | controller | skipping: Conditional result was False
2025-10-08 12:22:50.057764 | 
2025-10-08 12:22:50.057850 | TASK [Ensure all pods are up and running]
2025-10-08 12:22:50.596919 | controller | skipping: Conditional result was False
2025-10-08 12:22:50.610024 | 
2025-10-08 12:22:50.610162 | TASK [Check if there is a image build date file]
2025-10-08 12:22:51.016405 | controller | ok
2025-10-08 12:22:51.028605 | 
2025-10-08 12:22:51.028770 | TASK [Print image date if available]
2025-10-08 12:22:51.673640 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2025-10-08 12:22:52.083763 | controller | changed
2025-10-08 12:22:52.091875 | 
2025-10-08 12:22:52.091957 | TASK [Fix machineconfigpool]
2025-10-08 12:22:52.188009 | controller | skipping: Conditional result was False
2025-10-08 12:22:52.196447 | 
2025-10-08 12:22:52.196533 | TASK [Show available nodes]
2025-10-08 12:22:53.666858 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-10-08 12:22:53.672373 | controller | crc    Ready    control-plane,master,worker   227d   v1.31.5
2025-10-08 12:22:53.773650 | controller | ok: Runtime: 0:00:00.992010
2025-10-08 12:22:53.780814 | 
2025-10-08 12:22:53.780902 | TASK [Configure insecure registry inside crc vm]
2025-10-08 12:22:53.816100 | controller | skipping: Conditional result was False
2025-10-08 12:22:53.824736 | 
2025-10-08 12:22:53.824848 | TASK [Catch CRC IP]
2025-10-08 12:22:54.339011 | controller | 192.168.130.11
2025-10-08 12:22:54.385713 | controller | changed
2025-10-08 12:22:54.397826 | 
2025-10-08 12:22:54.397955 | TASK [Check if id_ed25519 key exists]
2025-10-08 12:22:54.826415 | controller | ok
2025-10-08 12:22:54.832366 | 
2025-10-08 12:22:54.832431 | TASK [Set fact if new keypair exists]
2025-10-08 12:22:54.872890 | controller | ok
2025-10-08 12:22:54.877565 | 
2025-10-08 12:22:54.877624 | TASK [Run crc]
2025-10-08 12:22:57.196948 | controller |
2025-10-08 12:23:07.508620 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-10-08 12:23:07.508715 | controller |
2025-10-08 12:23:07.508724 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:23:07.508737 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-10-08 12:23:07.558463 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-10-08 12:23:07.558510 | controller | interpreter could change the meaning of that path. See
2025-10-08 12:23:07.558517 | controller | https://docs.ansible.com/ansible-
2025-10-08 12:23:07.558524 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-10-08 12:23:07.558535 | controller | ok: [192.168.130.11]
2025-10-08 12:23:09.647753 | controller |
2025-10-08 12:23:09.647826 | controller | TASK [Copy pull-secret] ********************************************************
2025-10-08 12:23:09.647877 | controller | changed: [192.168.130.11]
2025-10-08 12:23:11.174753 | controller |
2025-10-08 12:23:11.174813 | controller | TASK [Add crio config] *********************************************************
2025-10-08 12:23:11.174843 | controller | changed: [192.168.130.11]
2025-10-08 12:23:15.780378 | controller |
2025-10-08 12:23:15.780455 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-10-08 12:23:15.780487 | controller | changed: [192.168.130.11]
2025-10-08 12:23:15.834151 | controller |
2025-10-08 12:23:15.834226 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:23:15.834238 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 12:23:15.834246 | controller |
2025-10-08 12:23:15.945410 | controller | changed
2025-10-08 12:23:15.960037 | 
2025-10-08 12:23:15.960172 | TASK [Set slower etcd profile]
2025-10-08 12:23:16.031147 | controller | ok
2025-10-08 12:23:16.077208 | 
2025-10-08 12:23:16.077315 | TASK [prepare-crc-cloud : Set slower profile]
2025-10-08 12:23:16.685794 | controller | etcd.operator.openshift.io/cluster patched
2025-10-08 12:23:17.116979 | controller | ok
2025-10-08 12:23:17.131309 | 
2025-10-08 12:23:17.131451 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-10-08 12:23:47.186089 | controller | ok
2025-10-08 12:23:47.197097 | 
2025-10-08 12:23:47.197229 | TASK [prepare-crc-cloud : Get pod phase status]
2025-10-08 12:29:15.436839 | controller | ok
2025-10-08 12:29:15.461557 | 
2025-10-08 12:29:15.461657 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-10-08 12:29:15.505335 | controller | ok
2025-10-08 12:29:15.515543 | controller | included: /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-10-08 12:29:15.529499 | 
2025-10-08 12:29:15.529588 | TASK [Set facts for disk limitation]
2025-10-08 12:29:15.564564 | controller | ok
2025-10-08 12:29:15.572440 | 
2025-10-08 12:29:15.572527 | TASK [Print limits for the disk]
2025-10-08 12:29:15.604789 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-10-08 12:29:15.613594 | 
2025-10-08 12:29:15.613705 | TASK [Set limit to crc VM to the vda disk]
2025-10-08 12:29:16.034771 | controller |
2025-10-08 12:29:16.182530 | controller | changed
2025-10-08 12:29:16.195810 | 
2025-10-08 12:29:16.195948 | TASK [Pause machineconfigpool]
2025-10-08 12:29:16.258975 | controller | ok
2025-10-08 12:29:16.304553 | 
2025-10-08 12:29:16.304655 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-10-08 12:29:16.920037 | controller | ok
2025-10-08 12:29:17.408902 | controller | ok
2025-10-08 12:29:17.426670 | 
2025-10-08 12:29:17.426911 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-10-08 12:29:17.980655 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-10-08 12:29:17.987732 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      227d
2025-10-08 12:29:17.987905 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      227d
2025-10-08 12:29:18.479604 | controller | ok
2025-10-08 12:29:18.534201 | 
2025-10-08 12:29:18.534300 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-10-08 12:29:18.534518 | 
2025-10-08 12:29:18.534563 | PLAY RECAP
2025-10-08 12:29:18.534613 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-10-08 12:29:18.534641 | 
2025-10-08 12:29:18.658403 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-08 12:29:18.660035 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:29:19.226048 | 
2025-10-08 12:29:19.226193 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 12:29:19.255001 | 
2025-10-08 12:29:19.255076 | TASK [Create zuul-output directory]
2025-10-08 12:29:19.818340 | controller | changed
2025-10-08 12:29:19.829322 | 
2025-10-08 12:29:19.829421 | TASK [Slurp Zuul inventory test]
2025-10-08 12:29:20.159521 | controller -> localhost | ok
2025-10-08 12:29:20.174766 | 
2025-10-08 12:29:20.174910 | TASK [Save zuul inventory]
2025-10-08 12:29:21.854524 | controller | changed
2025-10-08 12:29:21.867292 | 
2025-10-08 12:29:21.867437 | TASK [Save zuul vars without the change_message]
2025-10-08 12:29:23.400491 | controller | changed
2025-10-08 12:29:23.428858 | 
2025-10-08 12:29:23.428964 | PLAY RECAP
2025-10-08 12:29:23.429019 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:29:23.429047 | 
2025-10-08 12:29:23.531966 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:29:23.532847 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:29:24.247428 | 
2025-10-08 12:29:24.247534 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 12:29:24.271160 | 
2025-10-08 12:29:24.271273 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 12:29:24.300661 | controller | ok
2025-10-08 12:29:24.319468 | 
2025-10-08 12:29:24.319558 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:29:24.343506 | controller | skipping: Conditional result was False
2025-10-08 12:29:24.349674 | 
2025-10-08 12:29:24.349758 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:29:24.869900 | controller | ok
2025-10-08 12:29:24.879286 | 
2025-10-08 12:29:24.879363 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:29:26.130128 | controller | ok
2025-10-08 12:29:26.154295 | 
2025-10-08 12:29:26.154435 | TASK [Prepare workspace]
2025-10-08 12:29:26.178594 | controller | ok
2025-10-08 12:29:26.203706 | 
2025-10-08 12:29:26.203801 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 12:29:26.730995 | controller | ok
2025-10-08 12:29:26.740536 | 
2025-10-08 12:29:26.740626 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 12:29:27.931797 | controller | Output suppressed because no_log was given
2025-10-08 12:29:27.946868 | 
2025-10-08 12:29:27.946957 | LOOP [Create zuul-output directory]
2025-10-08 12:29:28.297472 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 12:29:28.616526 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 12:29:28.634483 | 
2025-10-08 12:29:28.634601 | TASK [Install required packages]
2025-10-08 12:29:31.144603 | controller | ok: Nothing to do
2025-10-08 12:29:31.150977 | 
2025-10-08 12:29:31.151040 | TASK [Install venv]
2025-10-08 12:30:38.368027 | controller | changed
2025-10-08 12:30:38.417338 | 
2025-10-08 12:30:38.417443 | PLAY RECAP
2025-10-08 12:30:38.417501 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:30:38.417530 | 
2025-10-08 12:30:38.559746 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:30:38.560891 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:30:39.165720 | 
2025-10-08 12:30:39.165829 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 12:30:39.187513 | 
2025-10-08 12:30:39.187602 | TASK [Gather required facts]
2025-10-08 12:30:39.886658 | controller | ok
2025-10-08 12:30:39.902975 | 
2025-10-08 12:30:39.903150 | TASK [Load environment var if instructed to]
2025-10-08 12:30:39.940325 | controller | skipping: Conditional result was False
2025-10-08 12:30:39.956300 | 
2025-10-08 12:30:39.956510 | TASK [Run molecule]
2025-10-08 12:30:41.313921 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 12:30:41.395694 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 12:30:59.146760 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.147232 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.147720 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.148212 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.148705 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+c1adc95e was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0+c1adc95e (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.149193 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.149665 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.150138 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.150602 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.151085 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.151544 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.151999 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.152449 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.152939 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.153379 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:30:59.163725 | controller | INFO     Running default > prepare
2025-10-08 12:30:59.972504 | controller |
2025-10-08 12:30:59.972572 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 12:30:59.972643 | controller |
2025-10-08 12:30:59.972762 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:30:59.972914 | controller | Wednesday 08 October 2025  12:30:59 +0000 (0:00:00.015)       0:00:00.015 *****
2025-10-08 12:31:01.088431 | controller | ok: [instance]
2025-10-08 12:31:01.088509 | controller |
2025-10-08 12:31:01.088712 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 12:31:01.088893 | controller | Wednesday 08 October 2025  12:31:01 +0000 (0:00:01.116)       0:00:01.131 *****
2025-10-08 12:31:01.112222 | controller | skipping: [instance]
2025-10-08 12:31:01.112385 | controller |
2025-10-08 12:31:01.112612 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 12:31:01.112897 | controller | Wednesday 08 October 2025  12:31:01 +0000 (0:00:00.023)       0:00:01.155 *****
2025-10-08 12:31:01.170406 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 12:31:01.170487 | controller |
2025-10-08 12:31:01.170698 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 12:31:01.170715 | controller | Wednesday 08 October 2025  12:31:01 +0000 (0:00:00.057)       0:00:01.213 *****
2025-10-08 12:31:01.532741 | controller | ok: [instance]
2025-10-08 12:31:01.532792 | controller |
2025-10-08 12:31:01.532905 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 12:31:01.533003 | controller | Wednesday 08 October 2025  12:31:01 +0000 (0:00:00.362)       0:00:01.575 *****
2025-10-08 12:31:01.978213 | controller | ok: [instance]
2025-10-08 12:31:01.978273 | controller |
2025-10-08 12:31:01.978383 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 12:31:01.978479 | controller | Wednesday 08 October 2025  12:31:01 +0000 (0:00:00.444)       0:00:02.020 *****
2025-10-08 12:31:02.899388 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 12:31:02.979122 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 12:31:02.979198 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 12:31:02.979206 | controller |
2025-10-08 12:31:02.979212 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 12:31:02.979219 | controller | Wednesday 08 October 2025  12:31:02 +0000 (0:00:00.912)       0:00:02.933 *****
2025-10-08 12:31:02.979236 | controller |
2025-10-08 12:31:02.979326 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 12:31:02.979335 | controller | Wednesday 08 October 2025  12:31:02 +0000 (0:00:00.088)       0:00:03.022 *****
2025-10-08 12:31:03.649307 | controller | changed: [instance] => (item=tmp)
2025-10-08 12:31:03.649393 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 12:31:03.649573 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 12:31:03.649729 | controller |
2025-10-08 12:31:03.649901 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 12:31:03.650061 | controller | Wednesday 08 October 2025  12:31:03 +0000 (0:00:00.670)       0:00:03.692 *****
2025-10-08 12:31:04.915234 | controller | ok: [instance]
2025-10-08 12:31:04.915691 | controller |
2025-10-08 12:31:06.030951 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 12:31:06.030995 | controller | Wednesday 08 October 2025  12:31:04 +0000 (0:00:01.265)       0:00:04.958 *****
2025-10-08 12:31:06.031009 | controller | changed: [instance]
2025-10-08 12:31:06.031040 | controller |
2025-10-08 12:31:06.031057 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 12:31:06.031084 | controller | Wednesday 08 October 2025  12:31:06 +0000 (0:00:01.115)       0:00:06.073 *****
2025-10-08 12:31:14.647067 | controller | changed: [instance]
2025-10-08 12:31:15.519900 | controller |
2025-10-08 12:31:15.519972 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 12:31:15.519983 | controller | Wednesday 08 October 2025  12:31:14 +0000 (0:00:08.616)       0:00:14.689 *****
2025-10-08 12:31:15.519997 | controller | changed: [instance]
2025-10-08 12:31:15.545783 | controller |
2025-10-08 12:31:15.545863 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 12:31:15.545879 | controller | Wednesday 08 October 2025  12:31:15 +0000 (0:00:00.872)       0:00:15.562 *****
2025-10-08 12:31:15.545894 | controller | skipping: [instance]
2025-10-08 12:31:15.546010 | controller |
2025-10-08 12:31:15.546069 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 12:31:16.319866 | controller | Wednesday 08 October 2025  12:31:15 +0000 (0:00:00.026)       0:00:15.589 *****
2025-10-08 12:31:16.319958 | controller | changed: [instance]
2025-10-08 12:31:16.362643 | controller |
2025-10-08 12:31:16.362683 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 12:31:16.362691 | controller | Wednesday 08 October 2025  12:31:16 +0000 (0:00:00.773)       0:00:16.363 *****
2025-10-08 12:31:16.362702 | controller | skipping: [instance]
2025-10-08 12:31:16.408589 | controller |
2025-10-08 12:31:16.408633 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 12:31:16.408641 | controller | Wednesday 08 October 2025  12:31:16 +0000 (0:00:00.042)       0:00:16.405 *****
2025-10-08 12:31:16.408652 | controller | skipping: [instance]
2025-10-08 12:31:16.408673 | controller |
2025-10-08 12:31:16.409713 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 12:31:16.410161 | controller | Wednesday 08 October 2025  12:31:16 +0000 (0:00:00.045)       0:00:16.451 *****
2025-10-08 12:31:16.461015 | controller | skipping: [instance]
2025-10-08 12:31:16.461175 | controller |
2025-10-08 12:31:16.461187 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 12:31:16.461196 | controller | Wednesday 08 October 2025  12:31:16 +0000 (0:00:00.052)       0:00:16.503 *****
2025-10-08 12:31:17.017367 | controller | changed: [instance]
2025-10-08 12:31:17.017424 | controller |
2025-10-08 12:31:17.017586 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 12:31:17.017692 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.557)       0:00:17.060 *****
2025-10-08 12:31:17.593635 | controller | changed: [instance]
2025-10-08 12:31:17.593701 | controller |
2025-10-08 12:31:17.593773 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 12:31:17.593789 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.576)       0:00:17.636 *****
2025-10-08 12:31:17.632462 | controller | skipping: [instance]
2025-10-08 12:31:17.632520 | controller |
2025-10-08 12:31:17.632632 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 12:31:17.632755 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.038)       0:00:17.675 *****
2025-10-08 12:31:17.670082 | controller | skipping: [instance]
2025-10-08 12:31:17.670149 | controller |
2025-10-08 12:31:17.670188 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 12:31:17.670297 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.037)       0:00:17.713 *****
2025-10-08 12:31:17.708694 | controller | skipping: [instance]
2025-10-08 12:31:17.708754 | controller |
2025-10-08 12:31:17.708852 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 12:31:17.708974 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.038)       0:00:17.751 *****
2025-10-08 12:31:17.760282 | controller | ok: [instance]
2025-10-08 12:31:17.760365 | controller |
2025-10-08 12:31:17.760395 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 12:31:17.760602 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.051)       0:00:17.803 *****
2025-10-08 12:31:17.798517 | controller | skipping: [instance]
2025-10-08 12:31:17.798583 | controller |
2025-10-08 12:31:17.798705 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 12:31:17.798784 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.038)       0:00:17.841 *****
2025-10-08 12:31:17.837670 | controller | skipping: [instance]
2025-10-08 12:31:17.837733 | controller |
2025-10-08 12:31:17.837879 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 12:31:17.837988 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.039)       0:00:17.880 *****
2025-10-08 12:31:17.872619 | controller | skipping: [instance]
2025-10-08 12:31:17.872739 | controller |
2025-10-08 12:31:17.872915 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 12:31:17.873071 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.035)       0:00:17.916 *****
2025-10-08 12:31:17.910306 | controller | skipping: [instance]
2025-10-08 12:31:17.910532 | controller |
2025-10-08 12:31:17.910669 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 12:31:17.910680 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.037)       0:00:17.953 *****
2025-10-08 12:31:17.944914 | controller | skipping: [instance]
2025-10-08 12:31:17.945016 | controller |
2025-10-08 12:31:17.945129 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 12:31:17.945271 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.034)       0:00:17.988 *****
2025-10-08 12:31:17.999650 | controller | skipping: [instance]
2025-10-08 12:31:17.999726 | controller |
2025-10-08 12:31:17.999889 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 12:31:18.000038 | controller | Wednesday 08 October 2025  12:31:17 +0000 (0:00:00.054)       0:00:18.042 *****
2025-10-08 12:31:18.042698 | controller | skipping: [instance]
2025-10-08 12:31:18.042974 | controller |
2025-10-08 12:31:18.043106 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 12:31:18.043118 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.038)       0:00:18.081 *****
2025-10-08 12:31:18.271448 | controller | ok: [instance]
2025-10-08 12:31:18.271525 | controller |
2025-10-08 12:31:18.271798 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 12:31:18.272079 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.233)       0:00:18.314 *****
2025-10-08 12:31:18.558229 | controller | changed: [instance]
2025-10-08 12:31:18.558342 | controller |
2025-10-08 12:31:18.558495 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 12:31:18.558655 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.286)       0:00:18.601 *****
2025-10-08 12:31:18.875887 | controller | changed: [instance]
2025-10-08 12:31:18.875950 | controller |
2025-10-08 12:31:18.876262 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 12:31:18.903707 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.317)       0:00:18.919 *****
2025-10-08 12:31:18.903752 | controller | skipping: [instance]
2025-10-08 12:31:18.930857 | controller |
2025-10-08 12:31:18.930896 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 12:31:18.930905 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.027)       0:00:18.946 *****
2025-10-08 12:31:18.930916 | controller | skipping: [instance]
2025-10-08 12:31:18.959590 | controller |
2025-10-08 12:31:18.959637 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 12:31:18.959646 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.027)       0:00:18.974 *****
2025-10-08 12:31:18.959667 | controller | skipping: [instance]
2025-10-08 12:31:18.986155 | controller |
2025-10-08 12:31:18.986191 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:31:18.986199 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.028)       0:00:19.002 *****
2025-10-08 12:31:18.986211 | controller | skipping: [instance]
2025-10-08 12:31:19.011398 | controller |
2025-10-08 12:31:19.011448 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 12:31:19.011456 | controller | Wednesday 08 October 2025  12:31:18 +0000 (0:00:00.026)       0:00:19.029 *****
2025-10-08 12:31:19.011470 | controller | skipping: [instance]
2025-10-08 12:31:19.043129 | controller |
2025-10-08 12:31:19.043168 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 12:31:19.043192 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.025)       0:00:19.054 *****
2025-10-08 12:31:19.043213 | controller | skipping: [instance]
2025-10-08 12:31:19.064120 | controller |
2025-10-08 12:31:19.064165 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 12:31:19.064173 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.031)       0:00:19.085 *****
2025-10-08 12:31:19.064185 | controller | skipping: [instance]
2025-10-08 12:31:19.064205 | controller |
2025-10-08 12:31:19.064433 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 12:31:19.100539 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.021)       0:00:19.107 *****
2025-10-08 12:31:19.100587 | controller | skipping: [instance]
2025-10-08 12:31:19.120694 | controller |
2025-10-08 12:31:19.120735 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 12:31:19.120743 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.035)       0:00:19.143 *****
2025-10-08 12:31:19.120755 | controller | skipping: [instance]
2025-10-08 12:31:19.120896 | controller |
2025-10-08 12:31:19.120914 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 12:31:19.120924 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.020)       0:00:19.164 *****
2025-10-08 12:31:19.154581 | controller | skipping: [instance]
2025-10-08 12:31:19.154670 | controller |
2025-10-08 12:31:19.154801 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 12:31:19.154856 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.033)       0:00:19.197 *****
2025-10-08 12:31:19.195333 | controller | skipping: [instance]
2025-10-08 12:31:19.195419 | controller |
2025-10-08 12:31:19.195544 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 12:31:19.195686 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.040)       0:00:19.238 *****
2025-10-08 12:31:19.230255 | controller | skipping: [instance]
2025-10-08 12:31:19.230319 | controller |
2025-10-08 12:31:19.230333 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 12:31:19.230813 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.034)       0:00:19.273 *****
2025-10-08 12:31:19.270257 | controller | skipping: [instance]
2025-10-08 12:31:19.270304 | controller |
2025-10-08 12:31:19.270403 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 12:31:19.270613 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.039)       0:00:19.313 *****
2025-10-08 12:31:19.313689 | controller | skipping: [instance]
2025-10-08 12:31:19.313945 | controller |
2025-10-08 12:31:19.313968 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 12:31:19.313982 | controller | Wednesday 08 October 2025  12:31:19 +0000 (0:00:00.043)       0:00:19.356 *****
2025-10-08 12:32:03.772032 | controller | ok: [instance]
2025-10-08 12:32:03.772093 | controller |
2025-10-08 12:32:03.772346 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 12:32:03.772558 | controller | Wednesday 08 October 2025  12:32:03 +0000 (0:00:44.457)       0:01:03.814 *****
2025-10-08 12:32:05.477929 | controller | ok: [instance]
2025-10-08 12:32:05.478031 | controller |
2025-10-08 12:32:05.478060 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 12:32:05.478083 | controller | Wednesday 08 October 2025  12:32:05 +0000 (0:00:01.706)       0:01:05.521 *****
2025-10-08 12:32:05.548427 | controller | skipping: [instance]
2025-10-08 12:32:05.548549 | controller |
2025-10-08 12:32:05.548603 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:32:05.548799 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-08 12:32:05.549249 | controller |
2025-10-08 12:32:05.549356 | controller | Wednesday 08 October 2025  12:32:05 +0000 (0:00:00.070)       0:01:05.591 *****
2025-10-08 12:32:05.549375 | controller | ===============================================================================
2025-10-08 12:32:05.549395 | controller | test_deps : Install selinux python libs -------------------------------- 44.46s
2025-10-08 12:32:05.549516 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.62s
2025-10-08 12:32:05.549678 | controller | test_deps : Install python yaml libs ------------------------------------ 1.71s
2025-10-08 12:32:05.549809 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.27s
2025-10-08 12:32:05.550005 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2025-10-08 12:32:05.550145 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.12s
2025-10-08 12:32:05.550269 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.91s
2025-10-08 12:32:05.550417 | controller | repo_setup : Install repo-setup package --------------------------------- 0.87s
2025-10-08 12:32:05.550756 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s
2025-10-08 12:32:05.550851 | controller | repo_setup : Ensure directories are present ----------------------------- 0.67s
2025-10-08 12:32:05.550879 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.58s
2025-10-08 12:32:05.551022 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-10-08 12:32:05.551172 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.44s
2025-10-08 12:32:05.551313 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.36s
2025-10-08 12:32:05.551496 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.32s
2025-10-08 12:32:05.551799 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.29s
2025-10-08 12:32:05.551918 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.23s
2025-10-08 12:32:05.551950 | controller | Deploy repo-setup ------------------------------------------------------- 0.09s
2025-10-08 12:32:05.552051 | controller | test_deps : Install extra packages -------------------------------------- 0.07s
2025-10-08 12:32:05.552174 | controller | test_deps : Gather variables for each operating system ------------------ 0.06s
2025-10-08 12:32:05.645908 | controller | INFO     Running default > converge
2025-10-08 12:32:06.205482 | controller |
2025-10-08 12:32:06.205695 | controller | PLAY [Add crc to the inventory] ************************************************
2025-10-08 12:32:06.205848 | controller |
2025-10-08 12:32:06.206072 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:32:06.206231 | controller | Wednesday 08 October 2025  12:32:06 +0000 (0:00:00.015)       0:00:00.015 *****
2025-10-08 12:32:07.326214 | controller | ok: [instance]
2025-10-08 12:32:07.326273 | controller |
2025-10-08 12:32:07.326367 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-10-08 12:32:07.326467 | controller | Wednesday 08 October 2025  12:32:07 +0000 (0:00:01.120)       0:00:01.135 *****
2025-10-08 12:32:07.703457 | controller | changed: [instance]
2025-10-08 12:32:07.703531 | controller |
2025-10-08 12:32:07.703716 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-10-08 12:32:07.733299 | controller | Wednesday 08 October 2025  12:32:07 +0000 (0:00:00.377)       0:00:01.513 *****
2025-10-08 12:32:07.733344 | controller |
2025-10-08 12:32:07.733446 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-10-08 12:32:07.733630 | controller | Wednesday 08 October 2025  12:32:07 +0000 (0:00:00.029)       0:00:01.543 *****
2025-10-08 12:32:08.117427 | controller | ok: [instance]
2025-10-08 12:32:08.117465 | controller |
2025-10-08 12:32:08.117558 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-10-08 12:32:08.117669 | controller | Wednesday 08 October 2025  12:32:08 +0000 (0:00:00.384)       0:00:01.927 *****
2025-10-08 12:32:08.140375 | controller | ok: [instance]
2025-10-08 12:32:08.140414 | controller |
2025-10-08 12:32:08.140505 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-10-08 12:32:08.140606 | controller | Wednesday 08 October 2025  12:32:08 +0000 (0:00:00.023)       0:00:01.950 *****
2025-10-08 12:32:08.165736 | controller | skipping: [instance]
2025-10-08 12:32:08.165807 | controller |
2025-10-08 12:32:08.165992 | controller | TASK [Add the crc host dynamically] ********************************************
2025-10-08 12:32:08.166154 | controller | Wednesday 08 October 2025  12:32:08 +0000 (0:00:00.025)       0:00:01.975 *****
2025-10-08 12:32:08.227063 | controller | changed: [instance]
2025-10-08 12:32:08.227128 | controller |
2025-10-08 12:32:08.227337 | controller | TASK [Add a dummy ethernet interface to target the crc NMstate] ****************
2025-10-08 12:32:08.227497 | controller | Wednesday 08 October 2025  12:32:08 +0000 (0:00:00.060)       0:00:02.035 *****
2025-10-08 12:32:08.740873 | controller | changed: [instance]
2025-10-08 12:32:08.741526 | controller |
2025-10-08 12:32:13.311049 | controller | PLAY [Get CRC facts] ***********************************************************
2025-10-08 12:32:13.311100 | controller |
2025-10-08 12:32:13.311107 | controller | TASK [Fetch crc network facts] *************************************************
2025-10-08 12:32:13.311114 | controller | Wednesday 08 October 2025  12:32:08 +0000 (0:00:00.514)       0:00:02.550 *****
2025-10-08 12:32:13.311127 | controller | ok: [crc]
2025-10-08 12:32:13.311173 | controller |
2025-10-08 12:32:13.311189 | controller | PLAY [Converge] ****************************************************************
2025-10-08 12:32:13.311339 | controller |
2025-10-08 12:32:13.311490 | controller | TASK [Set common molecule test facts] ******************************************
2025-10-08 12:32:13.311636 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:04.570)       0:00:07.120 *****
2025-10-08 12:32:13.356783 | controller | ok: [instance]
2025-10-08 12:32:13.356868 | controller |
2025-10-08 12:32:13.357144 | controller | TASK [Prepare the Networking Env Definition for ci_nmstate role] ***************
2025-10-08 12:32:13.408657 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.045)       0:00:07.166 *****
2025-10-08 12:32:13.408680 | controller |
2025-10-08 12:32:13.504735 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ******
2025-10-08 12:32:13.504782 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.052)       0:00:07.218 *****
2025-10-08 12:32:13.504796 | controller | skipping: [instance]
2025-10-08 12:32:13.504987 | controller |
2025-10-08 12:32:13.505018 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-08 12:32:13.610046 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.095)       0:00:07.313 *****
2025-10-08 12:32:13.610116 | controller | skipping: [instance]
2025-10-08 12:32:13.610272 | controller |
2025-10-08 12:32:13.610294 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************
2025-10-08 12:32:13.610424 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.105)       0:00:07.419 *****
2025-10-08 12:32:13.666937 | controller | skipping: [instance]
2025-10-08 12:32:13.667043 | controller |
2025-10-08 12:32:13.667187 | controller | TASK [networking_mapper : Load the interfaces info from file] ******************
2025-10-08 12:32:13.667500 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.057)       0:00:07.476 *****
2025-10-08 12:32:13.724040 | controller | skipping: [instance]
2025-10-08 12:32:13.724083 | controller |
2025-10-08 12:32:13.724187 | controller | TASK [networking_mapper : Ensure local fact is empty] **************************
2025-10-08 12:32:13.724318 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.057)       0:00:07.533 *****
2025-10-08 12:32:13.749202 | controller | ok: [instance]
2025-10-08 12:32:13.749280 | controller |
2025-10-08 12:32:13.749961 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] ***
2025-10-08 12:32:13.893264 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.025)       0:00:07.559 *****
2025-10-08 12:32:13.893330 | controller | ok: [instance] => (item={'networks': {'default': {'network': '38.129.56.0/24', 'gateway': '38.129.56.1', 'dns': ['1.1.1.1', '1.0.0.1'], 'mtu': '1450'}, 'testing-untagged': {'network': '172.17.20.0/24', 'dns': ['192.168.130.11', '192.168.130.1'], 'mtu': '1500'}, 'testing-vlan': {'network': '172.17.21.0/24', 'vlan': 21}}, 'instances': {'crc': {'networks': {'testing-untagged': {'ip': '172.17.20.5'}, 'testing-vlan': {'ip': '172.17.21.5', 'trunk-parent': 'testing-untagged'}}}, 'instance': {'networks': {'default': {'ip': '38.129.56.76'}}}}})
2025-10-08 12:32:13.893500 | controller |
2025-10-08 12:32:13.893517 | controller | TASK [networking_mapper : Gather facts if requested] ***************************
2025-10-08 12:32:13.893635 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.143)       0:00:07.702 *****
2025-10-08 12:32:13.924228 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/networking_mapper/tasks/_gather_facts.yml for instance
2025-10-08 12:32:13.924370 | controller |
2025-10-08 12:32:13.924603 | controller | TASK [networking_mapper : Gather the facts] ************************************
2025-10-08 12:32:13.924816 | controller | Wednesday 08 October 2025  12:32:13 +0000 (0:00:00.031)       0:00:07.734 *****
2025-10-08 12:32:14.336354 | controller | skipping: [instance] => (item=crc)
2025-10-08 12:32:14.336521 | controller | skipping: [instance] => (item=instance)
2025-10-08 12:32:14.336847 | controller | skipping: [instance]
2025-10-08 12:32:14.337149 | controller |
2025-10-08 12:32:14.337441 | controller | TASK [networking_mapper : Save instances refreshed facts for troubleshooting purposes] ***
2025-10-08 12:32:14.337750 | controller | Wednesday 08 October 2025  12:32:14 +0000 (0:00:00.411)       0:00:08.145 *****
2025-10-08 12:32:15.088155 | controller | changed: [instance]
2025-10-08 12:32:15.088245 | controller |
2025-10-08 12:32:15.088325 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] *****************
2025-10-08 12:32:15.088478 | controller | Wednesday 08 October 2025  12:32:15 +0000 (0:00:00.752)       0:00:08.898 *****
2025-10-08 12:32:15.529930 | controller | changed: [instance]
2025-10-08 12:32:15.530269 | controller |
2025-10-08 12:32:16.039134 | controller | TASK [networking_mapper : Write the Networking Definition to file] *************
2025-10-08 12:32:16.039170 | controller | Wednesday 08 October 2025  12:32:15 +0000 (0:00:00.441)       0:00:09.340 *****
2025-10-08 12:32:16.039182 | controller | changed: [instance]
2025-10-08 12:32:16.039488 | controller |
2025-10-08 12:32:16.039527 | controller | TASK [networking_mapper : Call the networking mapper] **************************
2025-10-08 12:32:16.039554 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.508)       0:00:09.848 *****
2025-10-08 12:32:16.143405 | controller | ok: [instance]
2025-10-08 12:32:16.143462 | controller |
2025-10-08 12:32:16.143473 | controller | TASK [networking_mapper : Set networking mapper facts] *************************
2025-10-08 12:32:16.143546 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.104)       0:00:09.952 *****
2025-10-08 12:32:16.174532 | controller | ok: [instance]
2025-10-08 12:32:16.174641 | controller |
2025-10-08 12:32:16.174660 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] ***
2025-10-08 12:32:16.174678 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.031)       0:00:09.984 *****
2025-10-08 12:32:16.664603 | controller | changed: [instance]
2025-10-08 12:32:16.664681 | controller |
2025-10-08 12:32:16.664813 | controller | TASK [Call the role under test] ************************************************
2025-10-08 12:32:16.664999 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.490)       0:00:10.474 *****
2025-10-08 12:32:16.720142 | controller |
2025-10-08 12:32:16.720218 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-08 12:32:16.720374 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.055)       0:00:10.530 *****
2025-10-08 12:32:16.749356 | controller | skipping: [instance]
2025-10-08 12:32:16.749716 | controller |
2025-10-08 12:32:16.749852 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-08 12:32:16.749968 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.029)       0:00:10.559 *****
2025-10-08 12:32:16.780399 | controller | skipping: [instance]
2025-10-08 12:32:16.780483 | controller |
2025-10-08 12:32:16.780688 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-08 12:32:16.780864 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.030)       0:00:10.589 *****
2025-10-08 12:32:16.809438 | controller | skipping: [instance]
2025-10-08 12:32:16.809580 | controller |
2025-10-08 12:32:16.809792 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-08 12:32:16.810029 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.029)       0:00:10.619 *****
2025-10-08 12:32:16.845434 | controller | skipping: [instance]
2025-10-08 12:32:16.845513 | controller |
2025-10-08 12:32:16.845637 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] **********************
2025-10-08 12:32:16.845766 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.036)       0:00:10.655 *****
2025-10-08 12:32:16.891628 | controller | ok: [instance]
2025-10-08 12:32:16.891713 | controller |
2025-10-08 12:32:16.891880 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] ***
2025-10-08 12:32:16.892021 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.045)       0:00:10.701 *****
2025-10-08 12:32:16.966902 | controller | ok: [instance] => (item=crc)
2025-10-08 12:32:16.966980 | controller | ok: [instance] => (item=instance)
2025-10-08 12:32:16.967114 | controller |
2025-10-08 12:32:16.967132 | controller | TASK [ci_nmstate : Get k8s nodes] **********************************************
2025-10-08 12:32:16.967251 | controller | Wednesday 08 October 2025  12:32:16 +0000 (0:00:00.075)       0:00:10.776 *****
2025-10-08 12:32:17.959592 | controller | ok: [instance]
2025-10-08 12:32:17.960014 | controller |
2025-10-08 12:32:18.067811 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ********
2025-10-08 12:32:18.067876 | controller | Wednesday 08 October 2025  12:32:17 +0000 (0:00:00.992)       0:00:11.769 *****
2025-10-08 12:32:18.067890 | controller | ok: [instance]
2025-10-08 12:32:18.157640 | controller |
2025-10-08 12:32:18.157674 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] *************************
2025-10-08 12:32:18.157682 | controller | Wednesday 08 October 2025  12:32:18 +0000 (0:00:00.102)       0:00:11.871 *****
2025-10-08 12:32:18.157693 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_install.yml for instance
2025-10-08 12:32:18.421868 | controller |
2025-10-08 12:32:18.421911 | controller | TASK [ci_nmstate : Create the target dir to dump NMstate manifests] ************
2025-10-08 12:32:18.421921 | controller | Wednesday 08 October 2025  12:32:18 +0000 (0:00:00.095)       0:00:11.967 *****
2025-10-08 12:32:18.421935 | controller | changed: [instance]
2025-10-08 12:32:19.410588 | controller |
2025-10-08 12:32:19.410628 | controller | TASK [ci_nmstate : Create the nmstate namespace] *******************************
2025-10-08 12:32:19.410646 | controller | Wednesday 08 October 2025  12:32:18 +0000 (0:00:00.259)       0:00:12.227 *****
2025-10-08 12:32:19.410659 | controller | changed: [instance]
2025-10-08 12:32:19.510983 | controller |
2025-10-08 12:32:19.511031 | controller | TASK [ci_nmstate : Generate the OperatorGroup final CR] ************************
2025-10-08 12:32:19.511039 | controller | Wednesday 08 October 2025  12:32:19 +0000 (0:00:00.992)       0:00:13.220 *****
2025-10-08 12:32:19.511060 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1', 'kind': 'OperatorGroup', 'metadata': {'annotations': {'olm.providedAPIs': 'NMState.v1.nmstate.io'}, 'name': 'openshift-nmstate', 'namespace': 'openshift-nmstate'}, 'spec': {'targetNamespaces': ['openshift-nmstate']}})
2025-10-08 12:32:19.588533 | controller |
2025-10-08 12:32:19.588579 | controller | TASK [ci_nmstate : Generate the Subscription final CR] *************************
2025-10-08 12:32:19.588594 | controller | Wednesday 08 October 2025  12:32:19 +0000 (0:00:00.096)       0:00:13.316 *****
2025-10-08 12:32:19.588615 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1alpha1', 'kind': 'Subscription', 'metadata': {'labels': {'operators.coreos.com/kubernetes-nmstate-operator.openshift-nmstate': ''}, 'name': 'kubernetes-nmstate-operator', 'namespace': 'openshift-nmstate'}, 'spec': {'channel': 'stable', 'installPlanApproval': 'Automatic', 'name': 'kubernetes-nmstate-operator', 'source': 'redhat-operators', 'sourceNamespace': 'openshift-marketplace'}})
2025-10-08 12:32:20.439883 | controller |
2025-10-08 12:32:20.439919 | controller | TASK [ci_nmstate : Save k8s nmstate OLM manifests as artifacts] ****************
2025-10-08 12:32:20.439927 | controller | Wednesday 08 October 2025  12:32:19 +0000 (0:00:00.081)       0:00:13.398 *****
2025-10-08 12:32:20.439952 | controller | changed: [instance] => (item=openshift-nmstate)
2025-10-08 12:32:22.055509 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-10-08 12:32:22.055548 | controller |
2025-10-08 12:32:22.055557 | controller | TASK [ci_nmstate : Create the OLM subscription resources] **********************
2025-10-08 12:32:22.055566 | controller | Wednesday 08 October 2025  12:32:20 +0000 (0:00:00.851)       0:00:14.249 *****
2025-10-08 12:32:22.055589 | controller | changed: [instance] => (item=openshift-nmstate)
2025-10-08 12:32:53.852043 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-10-08 12:32:53.852079 | controller |
2025-10-08 12:32:53.852087 | controller | TASK [ci_nmstate : Wait for nmstate operator to be installed] ******************
2025-10-08 12:32:53.852094 | controller | Wednesday 08 October 2025  12:32:22 +0000 (0:00:01.615)       0:00:15.864 *****
2025-10-08 12:32:53.852104 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (12 retries left).
2025-10-08 12:32:54.305597 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (11 retries left).
2025-10-08 12:32:54.305631 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (10 retries left).
2025-10-08 12:32:54.305639 | controller | ok: [instance]
2025-10-08 12:32:54.305645 | controller |
2025-10-08 12:32:54.305652 | controller | TASK [ci_nmstate : Save nmstate operator config as an artifact] ****************
2025-10-08 12:32:54.305658 | controller | Wednesday 08 October 2025  12:32:53 +0000 (0:00:31.796)       0:00:47.661 *****
2025-10-08 12:32:54.305668 | controller | changed: [instance]
2025-10-08 12:32:55.108195 | controller |
2025-10-08 12:32:55.108231 | controller | TASK [ci_nmstate : Trigger nmstate operator to install nmstate] ****************
2025-10-08 12:32:55.108240 | controller | Wednesday 08 October 2025  12:32:54 +0000 (0:00:00.449)       0:00:48.110 *****
2025-10-08 12:32:55.108250 | controller | changed: [instance]
2025-10-08 12:33:26.712879 | controller |
2025-10-08 12:33:26.712938 | controller | TASK [ci_nmstate : Wait for nsmtate Deployments to be ready] *******************
2025-10-08 12:33:26.712946 | controller | Wednesday 08 October 2025  12:32:55 +0000 (0:00:00.806)       0:00:48.917 *****
2025-10-08 12:33:26.712958 | controller | ok: [instance]
2025-10-08 12:33:27.774338 | controller |
2025-10-08 12:33:27.774397 | controller | TASK [ci_nmstate : Wait for nsmtate DaemonSet to be ready] *********************
2025-10-08 12:33:27.774407 | controller | Wednesday 08 October 2025  12:33:26 +0000 (0:00:31.604)       0:01:20.522 *****
2025-10-08 12:33:27.774422 | controller | ok: [instance]
2025-10-08 12:33:27.774450 | controller |
2025-10-08 12:33:27.774499 | controller | TASK [ci_nmstate : Provision nncps] ********************************************
2025-10-08 12:33:27.774513 | controller | Wednesday 08 October 2025  12:33:27 +0000 (0:00:01.062)       0:01:21.584 *****
2025-10-08 12:33:27.819477 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_provision_node.yml for instance => (item=crc)
2025-10-08 12:33:27.819803 | controller |
2025-10-08 12:33:27.914929 | controller | TASK [ci_nmstate : Prepare the k8s nmstate nncp to apply] **********************
2025-10-08 12:33:27.914987 | controller | Wednesday 08 October 2025  12:33:27 +0000 (0:00:00.044)       0:01:21.629 *****
2025-10-08 12:33:27.915001 | controller | ok: [instance]
2025-10-08 12:33:27.915199 | controller |
2025-10-08 12:33:27.915217 | controller | TASK [ci_nmstate : Save k8s nmstate nncp manifests as artifacts] ***************
2025-10-08 12:33:28.344902 | controller | Wednesday 08 October 2025  12:33:27 +0000 (0:00:00.095)       0:01:21.724 *****
2025-10-08 12:33:28.344953 | controller | changed: [instance]
2025-10-08 12:33:28.345196 | controller |
2025-10-08 12:33:28.345217 | controller | TASK [ci_nmstate : Provision nmstate nncp for the node worker] *****************
2025-10-08 12:33:29.289490 | controller | Wednesday 08 October 2025  12:33:28 +0000 (0:00:00.429)       0:01:22.154 *****
2025-10-08 12:33:29.289533 | controller | changed: [instance]
2025-10-08 12:38:53.326915 | controller |
2025-10-08 12:38:53.327020 | controller | TASK [ci_nmstate : Wait for nsmtate nncp to be ready] **************************
2025-10-08 12:38:53.327039 | controller | Wednesday 08 October 2025  12:33:29 +0000 (0:00:00.944)       0:01:23.099 *****
2025-10-08 12:38:53.327053 | controller | fatal: [instance]: FAILED! => changed=false
2025-10-08 12:38:53.327098 | controller |   api_found: true
2025-10-08 12:38:53.327109 | controller |   attempts: 30
2025-10-08 12:38:53.327116 | controller |   resources:
2025-10-08 12:38:53.327123 | controller |   - apiVersion: nmstate.io/v1
2025-10-08 12:38:53.327136 | controller |     kind: NodeNetworkConfigurationPolicy
2025-10-08 12:38:53.327144 | controller |     metadata:
2025-10-08 12:38:53.327154 | controller |       annotations:
2025-10-08 12:38:53.327313 | controller |         nmstate.io/webhook-mutating-timestamp: '1759926809037725567'
2025-10-08 12:38:53.327341 | controller |       creationTimestamp: '2025-10-08T12:33:29Z'
2025-10-08 12:38:53.327426 | controller |       generation: 1
2025-10-08 12:38:53.327615 | controller |       managedFields:
2025-10-08 12:38:53.327755 | controller |       - apiVersion: nmstate.io/v1
2025-10-08 12:38:53.327900 | controller |         fieldsType: FieldsV1
2025-10-08 12:38:53.328120 | controller |         fieldsV1:
2025-10-08 12:38:53.328263 | controller |           f:spec:
2025-10-08 12:38:53.328281 | controller |             .: {}
2025-10-08 12:38:53.329206 | controller |             f:desiredState:
2025-10-08 12:38:53.329343 | controller |               .: {}
2025-10-08 12:38:53.329360 | controller |               f:dns-resolver:
2025-10-08 12:38:53.329374 | controller |                 .: {}
2025-10-08 12:38:53.329387 | controller |                 f:config:
2025-10-08 12:38:53.329400 | controller |                   .: {}
2025-10-08 12:38:53.329418 | controller |                   f:search: {}
2025-10-08 12:38:53.329641 | controller |                   f:server: {}
2025-10-08 12:38:53.329684 | controller |               f:interfaces: {}
2025-10-08 12:38:53.329788 | controller |             f:nodeSelector:
2025-10-08 12:38:53.330002 | controller |               .: {}
2025-10-08 12:38:53.330163 | controller |               f:kubernetes.io/hostname: {}
2025-10-08 12:38:53.330341 | controller |               f:node-role.kubernetes.io/worker: {}
2025-10-08 12:38:53.330488 | controller |         manager: OpenAPI-Generator
2025-10-08 12:38:53.330655 | controller |         operation: Update
2025-10-08 12:38:53.330865 | controller |         time: '2025-10-08T12:33:29Z'
2025-10-08 12:38:53.331052 | controller |       - apiVersion: nmstate.io/v1
2025-10-08 12:38:53.331216 | controller |         fieldsType: FieldsV1
2025-10-08 12:38:53.331395 | controller |         fieldsV1:
2025-10-08 12:38:53.332040 | controller |           f:status:
2025-10-08 12:38:53.332071 | controller |             .: {}
2025-10-08 12:38:53.332081 | controller |             f:conditions: {}
2025-10-08 12:38:53.332095 | controller |             f:lastUnavailableNodeCountUpdate: {}
2025-10-08 12:38:53.332222 | controller |             f:unavailableNodeCount: {}
2025-10-08 12:38:53.332377 | controller |         manager: manager
2025-10-08 12:38:53.332548 | controller |         operation: Update
2025-10-08 12:38:53.332714 | controller |         subresource: status
2025-10-08 12:38:53.332891 | controller |         time: '2025-10-08T12:33:31Z'
2025-10-08 12:38:53.333059 | controller |       name: crc
2025-10-08 12:38:53.333226 | controller |       resourceVersion: '35685'
2025-10-08 12:38:53.333398 | controller |       uid: 40850716-ec0e-493f-a773-0ccff7bc53c2
2025-10-08 12:38:53.333565 | controller |     spec:
2025-10-08 12:38:53.333730 | controller |       desiredState:
2025-10-08 12:38:53.333905 | controller |         dns-resolver:
2025-10-08 12:38:53.334067 | controller |           config:
2025-10-08 12:38:53.334230 | controller |             search:
2025-10-08 12:38:53.334411 | controller |             - testing-untagged.example.com
2025-10-08 12:38:53.334597 | controller |             - testing-vlan.example.com
2025-10-08 12:38:53.334749 | controller |             server:
2025-10-08 12:38:53.334932 | controller |             - 192.168.130.11
2025-10-08 12:38:53.335119 | controller |             - 192.168.130.1
2025-10-08 12:38:53.335277 | controller |         interfaces:
2025-10-08 12:38:53.335441 | controller |         - description: testing-untagged
2025-10-08 12:38:53.335619 | controller |           ipv4:
2025-10-08 12:38:53.335779 | controller |             address:
2025-10-08 12:38:53.336045 | controller |             - ip: 172.17.20.5
2025-10-08 12:38:53.336216 | controller |               prefix-length: 24
2025-10-08 12:38:53.336377 | controller |             enabled: true
2025-10-08 12:38:53.336541 | controller |           ipv6:
2025-10-08 12:38:53.336708 | controller |             enabled: false
2025-10-08 12:38:53.336883 | controller |           mtu: 1500
2025-10-08 12:38:53.337058 | controller |           name: enp6s0
2025-10-08 12:38:53.337220 | controller |           state: up
2025-10-08 12:38:53.337378 | controller |           type: ethernet
2025-10-08 12:38:53.337560 | controller |         - description: testing-vlan
2025-10-08 12:38:53.337723 | controller |           ipv4:
2025-10-08 12:38:53.337892 | controller |             address:
2025-10-08 12:38:53.338071 | controller |             - ip: 172.17.21.5
2025-10-08 12:38:53.338233 | controller |               prefix-length: 24
2025-10-08 12:38:53.338395 | controller |             enabled: true
2025-10-08 12:38:53.338546 | controller |           ipv6:
2025-10-08 12:38:53.338708 | controller |             enabled: false
2025-10-08 12:38:53.338884 | controller |           mtu: 1496
2025-10-08 12:38:53.339089 | controller |           name: enp6s0.21
2025-10-08 12:38:53.339238 | controller |           state: up
2025-10-08 12:38:53.339409 | controller |           type: vlan
2025-10-08 12:38:53.339571 | controller |           vlan:
2025-10-08 12:38:53.339748 | controller |             base-iface: enp6s0
2025-10-08 12:38:53.339910 | controller |             id: 21
2025-10-08 12:38:53.340079 | controller |       nodeSelector:
2025-10-08 12:38:53.340237 | controller |         kubernetes.io/hostname: crc
2025-10-08 12:38:53.340416 | controller |         node-role.kubernetes.io/worker: ''
2025-10-08 12:38:53.340578 | controller |     status:
2025-10-08 12:38:53.340731 | controller |       conditions:
2025-10-08 12:38:53.340909 | controller |       - lastHeartbeatTime: '2025-10-08T12:33:31Z'
2025-10-08 12:38:53.341092 | controller |         lastTransitionTime: '2025-10-08T12:33:29Z'
2025-10-08 12:38:53.341264 | controller |         reason: ConfigurationProgressing
2025-10-08 12:38:53.341420 | controller |         status: Unknown
2025-10-08 12:38:53.341587 | controller |         type: Available
2025-10-08 12:38:53.341749 | controller |       - lastHeartbeatTime: '2025-10-08T12:33:31Z'
2025-10-08 12:38:53.341918 | controller |         lastTransitionTime: '2025-10-08T12:33:29Z'
2025-10-08 12:38:53.342079 | controller |         reason: ConfigurationProgressing
2025-10-08 12:38:53.342245 | controller |         status: Unknown
2025-10-08 12:38:53.342422 | controller |         type: Degraded
2025-10-08 12:38:53.342580 | controller |       - lastHeartbeatTime: '2025-10-08T12:33:31Z'
2025-10-08 12:38:53.342750 | controller |         lastTransitionTime: '2025-10-08T12:33:31Z'
2025-10-08 12:38:53.342931 | controller |         message: Policy is progressing 0/1 nodes finished
2025-10-08 12:38:53.343121 | controller |         reason: ConfigurationProgressing
2025-10-08 12:38:53.343276 | controller |         status: 'True'
2025-10-08 12:38:53.343440 | controller |         type: Progressing
2025-10-08 12:38:53.343608 | controller |       lastUnavailableNodeCountUpdate: '2025-10-08T12:33:31Z'
2025-10-08 12:38:53.343770 | controller |       unavailableNodeCount: 1
2025-10-08 12:38:53.344118 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (30 retries left).
2025-10-08 12:38:53.344298 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (29 retries left).
2025-10-08 12:38:53.344461 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (28 retries left).
2025-10-08 12:38:53.344645 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (27 retries left).
2025-10-08 12:38:53.344809 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (26 retries left).
2025-10-08 12:38:53.345003 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (25 retries left).
2025-10-08 12:38:53.345194 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (24 retries left).
2025-10-08 12:38:53.345371 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (23 retries left).
2025-10-08 12:38:53.345542 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (22 retries left).
2025-10-08 12:38:53.345707 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (21 retries left).
2025-10-08 12:38:53.345890 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (20 retries left).
2025-10-08 12:38:53.346067 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (19 retries left).
2025-10-08 12:38:53.346260 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (18 retries left).
2025-10-08 12:38:53.346437 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (17 retries left).
2025-10-08 12:38:53.346614 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (16 retries left).
2025-10-08 12:38:53.346771 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (15 retries left).
2025-10-08 12:38:53.346958 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (14 retries left).
2025-10-08 12:38:53.347167 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (13 retries left).
2025-10-08 12:38:53.347348 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (12 retries left).
2025-10-08 12:38:53.347530 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (11 retries left).
2025-10-08 12:38:53.347701 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (10 retries left).
2025-10-08 12:38:53.348016 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (9 retries left).
2025-10-08 12:38:53.348201 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (8 retries left).
2025-10-08 12:38:53.348374 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (7 retries left).
2025-10-08 12:38:53.348549 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (6 retries left).
2025-10-08 12:38:53.348713 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (5 retries left).
2025-10-08 12:38:53.348899 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (4 retries left).
2025-10-08 12:38:53.349074 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (3 retries left).
2025-10-08 12:38:53.349247 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (2 retries left).
2025-10-08 12:38:53.349427 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (1 retries left).
2025-10-08 12:38:53.349605 | controller |
2025-10-08 12:38:53.349751 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:38:53.349954 | controller | crc                        : ok=1    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 12:38:53.350141 | controller | instance                   : ok=36   changed=15   unreachable=0    failed=1    skipped=10   rescued=0    ignored=0
2025-10-08 12:38:53.350298 | controller |
2025-10-08 12:38:53.350461 | controller | Wednesday 08 October 2025  12:38:53 +0000 (0:05:24.039)       0:06:47.139 *****
2025-10-08 12:38:53.350630 | controller | ===============================================================================
2025-10-08 12:38:53.350800 | controller | ci_nmstate : Wait for nsmtate nncp to be ready ------------------------ 324.04s
2025-10-08 12:38:53.350992 | controller | ci_nmstate : Wait for nmstate operator to be installed ----------------- 31.80s
2025-10-08 12:38:53.351206 | controller | ci_nmstate : Wait for nsmtate Deployments to be ready ------------------ 31.60s
2025-10-08 12:38:53.351372 | controller | Fetch crc network facts ------------------------------------------------- 4.57s
2025-10-08 12:38:53.351541 | controller | ci_nmstate : Create the OLM subscription resources ---------------------- 1.62s
2025-10-08 12:38:53.351705 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2025-10-08 12:38:53.351884 | controller | ci_nmstate : Wait for nsmtate DaemonSet to be ready --------------------- 1.06s
2025-10-08 12:38:53.352056 | controller | ci_nmstate : Create the nmstate namespace ------------------------------- 0.99s
2025-10-08 12:38:53.352219 | controller | ci_nmstate : Get k8s nodes ---------------------------------------------- 0.99s
2025-10-08 12:38:53.352387 | controller | ci_nmstate : Provision nmstate nncp for the node worker ----------------- 0.94s
2025-10-08 12:38:53.352558 | controller | ci_nmstate : Save k8s nmstate OLM manifests as artifacts ---------------- 0.85s
2025-10-08 12:38:53.352720 | controller | ci_nmstate : Trigger nmstate operator to install nmstate ---------------- 0.81s
2025-10-08 12:38:53.352921 | controller | networking_mapper : Save instances refreshed facts for troubleshooting purposes --- 0.75s
2025-10-08 12:38:53.353093 | controller | Add a dummy ethernet interface to target the crc NMstate ---------------- 0.52s
2025-10-08 12:38:53.353258 | controller | networking_mapper : Write the Networking Definition to file ------------- 0.51s
2025-10-08 12:38:53.353429 | controller | networking_mapper : Write the Networking Environment Definition to file --- 0.49s
2025-10-08 12:38:53.353593 | controller | ci_nmstate : Save nmstate operator config as an artifact ---------------- 0.45s
2025-10-08 12:38:53.353755 | controller | networking_mapper : Ensure CI infrastructure dir exists ----------------- 0.44s
2025-10-08 12:38:53.353937 | controller | ci_nmstate : Save k8s nmstate nncp manifests as artifacts --------------- 0.43s
2025-10-08 12:38:53.354116 | controller | networking_mapper : Gather the facts ------------------------------------ 0.41s
2025-10-08 12:38:53.413394 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/ci_nmstate/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/molecule/default/converge.yml']
2025-10-08 12:38:53.413825 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-10-08 12:38:53.424402 | controller | INFO     Running default > cleanup
2025-10-08 12:38:53.424857 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 12:38:53.434477 | controller | INFO     Running default > destroy
2025-10-08 12:38:53.434823 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 12:38:53.435148 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-08 12:38:53.436760 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 12:38:53.649657 | controller | ERROR
2025-10-08 12:38:53.649956 | controller | {
2025-10-08 12:38:53.650022 | controller |   "delta": "0:08:13.019587",
2025-10-08 12:38:53.650069 | controller |   "end": "2025-10-08 12:38:53.507774",
2025-10-08 12:38:53.650109 | controller |   "msg": "non-zero return code",
2025-10-08 12:38:53.650148 | controller |   "rc": 1,
2025-10-08 12:38:53.650194 | controller |   "start": "2025-10-08 12:30:40.488187"
2025-10-08 12:38:53.650248 | controller | }
failure
2025-10-08 12:38:53.663581 | 
2025-10-08 12:38:53.663976 | PLAY RECAP
2025-10-08 12:38:53.664086 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:38:53.664135 | 
2025-10-08 12:38:53.788586 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:38:53.790500 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:38:54.451895 | 
2025-10-08 12:38:54.452008 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 12:38:54.473540 | 
2025-10-08 12:38:54.473621 | TASK [Filter out host if needed]
2025-10-08 12:38:54.482199 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 12:38:54.486707 | 
2025-10-08 12:38:54.486771 | TASK [Ensure file is present]
2025-10-08 12:38:55.002655 | controller | ok
2025-10-08 12:38:55.017545 | 
2025-10-08 12:38:55.017740 | TASK [Manage molecule report file]
2025-10-08 12:38:55.587902 | controller | changed
2025-10-08 12:38:55.595078 | 
2025-10-08 12:38:55.595251 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 12:38:55.947506 | controller | ok
2025-10-08 12:38:55.959667 | 
2025-10-08 12:38:55.959833 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 12:38:56.458155 | controller | changed
2025-10-08 12:38:56.471474 | 
2025-10-08 12:38:56.471611 | TASK [Copy ci-framework interesting files]
2025-10-08 12:38:56.835931 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 12:38:57.016669 | controller | changed
2025-10-08 12:38:57.028641 | 
2025-10-08 12:38:57.028836 | TASK [Get SELinux listing]
2025-10-08 12:38:57.582456 | controller | changed
2025-10-08 12:38:57.595962 | 
2025-10-08 12:38:57.596157 | TASK [Generate log index]
2025-10-08 12:38:59.163656 | controller | changed
2025-10-08 12:38:59.175089 | 
2025-10-08 12:38:59.175217 | TASK [Get some env related data]
2025-10-08 12:39:00.023258 | controller | /home/zuul/.local/bin/ansible
2025-10-08 12:39:00.736780 | controller | changed
2025-10-08 12:39:00.780895 | 
2025-10-08 12:39:00.781026 | TASK [Generate list of logs to collect in home directory]
2025-10-08 12:39:01.260225 | controller | ok: All paths examined
2025-10-08 12:39:01.268858 | 
2025-10-08 12:39:01.268948 | LOOP [Copy logs from home directory]
2025-10-08 12:39:02.176058 | controller | changed:
2025-10-08 12:39:02.176557 | controller | {
2025-10-08 12:39:02.176625 | controller |   "atime": 1743544925.4788878,
2025-10-08 12:39:02.176669 | controller |   "ctime": 1743545329.1409318,
2025-10-08 12:39:02.176758 | controller |   "dev": 64513,
2025-10-08 12:39:02.176799 | controller |   "gid": 1000,
2025-10-08 12:39:02.176836 | controller |   "gr_name": "zuul",
2025-10-08 12:39:02.177317 | controller |   "inode": 4518807,
2025-10-08 12:39:02.177390 | controller |   "isblk": false,
2025-10-08 12:39:02.177431 | controller |   "ischr": false,
2025-10-08 12:39:02.177467 | controller |   "isdir": false,
2025-10-08 12:39:02.177501 | controller |   "isfifo": false,
2025-10-08 12:39:02.177536 | controller |   "isgid": false,
2025-10-08 12:39:02.177570 | controller |   "islnk": false,
2025-10-08 12:39:02.177603 | controller |   "isreg": true,
2025-10-08 12:39:02.177643 | controller |   "issock": false,
2025-10-08 12:39:02.177722 | controller |   "isuid": false,
2025-10-08 12:39:02.177762 | controller |   "mode": "0644",
2025-10-08 12:39:02.177816 | controller |   "mtime": 1743545329.1409318,
2025-10-08 12:39:02.177867 | controller |   "nlink": 1,
2025-10-08 12:39:02.177912 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 12:39:02.177953 | controller |   "pw_name": "zuul",
2025-10-08 12:39:02.177990 | controller |   "rgrp": true,
2025-10-08 12:39:02.178025 | controller |   "roth": true,
2025-10-08 12:39:02.178059 | controller |   "rusr": true,
2025-10-08 12:39:02.178108 | controller |   "size": 4108,
2025-10-08 12:39:02.178142 | controller |   "uid": 1000,
2025-10-08 12:39:02.178176 | controller |   "wgrp": false,
2025-10-08 12:39:02.178209 | controller |   "woth": false,
2025-10-08 12:39:02.178248 | controller |   "wusr": true,
2025-10-08 12:39:02.178283 | controller |   "xgrp": false,
2025-10-08 12:39:02.178317 | controller |   "xoth": false,
2025-10-08 12:39:02.178350 | controller |   "xusr": false
2025-10-08 12:39:02.178391 | controller | }
2025-10-08 12:39:02.926765 | controller | changed:
2025-10-08 12:39:02.926936 | controller | {
2025-10-08 12:39:02.926987 | controller |   "atime": 1759925969.5134778,
2025-10-08 12:39:02.927027 | controller |   "ctime": 1759925967.3474138,
2025-10-08 12:39:02.927064 | controller |   "dev": 64513,
2025-10-08 12:39:02.927099 | controller |   "gid": 1000,
2025-10-08 12:39:02.927134 | controller |   "gr_name": "zuul",
2025-10-08 12:39:02.927168 | controller |   "inode": 4194437,
2025-10-08 12:39:02.927202 | controller |   "isblk": false,
2025-10-08 12:39:02.927236 | controller |   "ischr": false,
2025-10-08 12:39:02.927268 | controller |   "isdir": false,
2025-10-08 12:39:02.927301 | controller |   "isfifo": false,
2025-10-08 12:39:02.927333 | controller |   "isgid": false,
2025-10-08 12:39:02.927366 | controller |   "islnk": false,
2025-10-08 12:39:02.927399 | controller |   "isreg": true,
2025-10-08 12:39:02.927434 | controller |   "issock": false,
2025-10-08 12:39:02.927468 | controller |   "isuid": false,
2025-10-08 12:39:02.927502 | controller |   "mode": "0644",
2025-10-08 12:39:02.927536 | controller |   "mtime": 1759925967.3474138,
2025-10-08 12:39:02.927570 | controller |   "nlink": 1,
2025-10-08 12:39:02.927604 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 12:39:02.927639 | controller |   "pw_name": "zuul",
2025-10-08 12:39:02.927674 | controller |   "rgrp": true,
2025-10-08 12:39:02.927746 | controller |   "roth": true,
2025-10-08 12:39:02.927782 | controller |   "rusr": true,
2025-10-08 12:39:02.927817 | controller |   "size": 3404,
2025-10-08 12:39:02.927851 | controller |   "uid": 1000,
2025-10-08 12:39:02.927885 | controller |   "wgrp": false,
2025-10-08 12:39:02.927920 | controller |   "woth": false,
2025-10-08 12:39:02.927954 | controller |   "wusr": true,
2025-10-08 12:39:02.927988 | controller |   "xgrp": false,
2025-10-08 12:39:02.928022 | controller |   "xoth": false,
2025-10-08 12:39:02.928056 | controller |   "xusr": false
2025-10-08 12:39:02.928091 | controller | }
2025-10-08 12:39:03.685509 | controller | changed:
2025-10-08 12:39:03.685607 | controller | {
2025-10-08 12:39:03.685638 | controller |   "atime": 1759926619.1944764,
2025-10-08 12:39:03.685664 | controller |   "ctime": 1759926637.826023,
2025-10-08 12:39:03.685711 | controller |   "dev": 64513,
2025-10-08 12:39:03.685736 | controller |   "gid": 1000,
2025-10-08 12:39:03.685759 | controller |   "gr_name": "zuul",
2025-10-08 12:39:03.685781 | controller |   "inode": 4638646,
2025-10-08 12:39:03.685803 | controller |   "isblk": false,
2025-10-08 12:39:03.685824 | controller |   "ischr": false,
2025-10-08 12:39:03.685846 | controller |   "isdir": false,
2025-10-08 12:39:03.685868 | controller |   "isfifo": false,
2025-10-08 12:39:03.685905 | controller |   "isgid": false,
2025-10-08 12:39:03.685929 | controller |   "islnk": false,
2025-10-08 12:39:03.685951 | controller |   "isreg": true,
2025-10-08 12:39:03.685974 | controller |   "issock": false,
2025-10-08 12:39:03.685996 | controller |   "isuid": false,
2025-10-08 12:39:03.686018 | controller |   "mode": "0644",
2025-10-08 12:39:03.686040 | controller |   "mtime": 1759926637.826023,
2025-10-08 12:39:03.686061 | controller |   "nlink": 1,
2025-10-08 12:39:03.686083 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 12:39:03.686106 | controller |   "pw_name": "zuul",
2025-10-08 12:39:03.686128 | controller |   "rgrp": true,
2025-10-08 12:39:03.686151 | controller |   "roth": true,
2025-10-08 12:39:03.686176 | controller |   "rusr": true,
2025-10-08 12:39:03.686200 | controller |   "size": 6749,
2025-10-08 12:39:03.686228 | controller |   "uid": 1000,
2025-10-08 12:39:03.686251 | controller |   "wgrp": false,
2025-10-08 12:39:03.686273 | controller |   "woth": false,
2025-10-08 12:39:03.686295 | controller |   "wusr": true,
2025-10-08 12:39:03.686316 | controller |   "xgrp": false,
2025-10-08 12:39:03.686337 | controller |   "xoth": false,
2025-10-08 12:39:03.686358 | controller |   "xusr": false
2025-10-08 12:39:03.686379 | controller | }
2025-10-08 12:39:03.696840 | 
2025-10-08 12:39:03.696927 | TASK [Copy crio stats log file]
2025-10-08 12:39:03.732757 | controller | skipping: Conditional result was False
2025-10-08 12:39:03.740951 | 
2025-10-08 12:39:03.741034 | TASK [Get SELinux related data]
2025-10-08 12:39:04.127256 | controller | <no matches>
2025-10-08 12:39:04.286229 | controller | ERROR
2025-10-08 12:39:04.286498 | controller | {
2025-10-08 12:39:04.286564 | controller |   "delta": "0:00:00.011808",
2025-10-08 12:39:04.286609 | controller |   "end": "2025-10-08 12:39:04.128410",
2025-10-08 12:39:04.286652 | controller |   "msg": "non-zero return code",
2025-10-08 12:39:04.286751 | controller |   "rc": 1,
2025-10-08 12:39:04.286796 | controller |   "start": "2025-10-08 12:39:04.116602"
2025-10-08 12:39:04.286833 | controller | }
2025-10-08 12:39:04.286895 | controller | ERROR: Ignoring Errors
2025-10-08 12:39:04.300192 | 
2025-10-08 12:39:04.300333 | TASK [Create system configuration directory]
2025-10-08 12:39:04.706661 | controller | changed
2025-10-08 12:39:04.718891 | 
2025-10-08 12:39:04.719161 | TASK [Get some of the system configurations]
2025-10-08 12:39:05.272075 | controller | changed
2025-10-08 12:39:05.284298 | 
2025-10-08 12:39:05.284430 | TASK [Copy generated documentation if available]
2025-10-08 12:39:05.312406 | controller | skipping: Conditional result was False
2025-10-08 12:39:05.325369 | 
2025-10-08 12:39:05.325504 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 12:39:05.353043 | controller | skipping: Conditional result was False
2025-10-08 12:39:05.365988 | 
2025-10-08 12:39:05.366121 | TASK [Compress logs bigger than 2MB]
2025-10-08 12:39:05.917601 | controller | changed
2025-10-08 12:39:05.930612 | 
2025-10-08 12:39:05.930839 | TASK [Copy files from workspace on node]
2025-10-08 12:39:05.960623 | controller | ok
2025-10-08 12:39:06.011616 | 
2025-10-08 12:39:06.011782 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:39:06.047934 | controller | skipping: Conditional result was False
2025-10-08 12:39:06.062163 | 
2025-10-08 12:39:06.062310 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:39:06.112043 | controller | ok
2025-10-08 12:39:06.124923 | 
2025-10-08 12:39:06.125094 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:39:06.440341 | controller -> localhost | ok: "/var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/logs"
2025-10-08 12:39:06.440584 | controller -> localhost | changed: All items complete
2025-10-08 12:39:06.440612 | 
2025-10-08 12:39:06.667407 | controller -> localhost | changed: "/var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/artifacts"
2025-10-08 12:39:06.913615 | controller -> localhost | changed: "/var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/docs"
2025-10-08 12:39:06.937339 | 
2025-10-08 12:39:06.937557 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:39:07.818596 | controller | changed:
2025-10-08 12:39:07.818945 | controller | .d..t...... ./
2025-10-08 12:39:07.819001 | controller | >f+++++++++ README.html
2025-10-08 12:39:07.819041 | controller | >f+++++++++ ansible-execution.log
2025-10-08 12:39:07.819080 | controller | >f+++++++++ ansible.log
2025-10-08 12:39:07.819116 | controller | >f+++++++++ crc-setup.log
2025-10-08 12:39:07.819149 | controller | >f+++++++++ crc-start.log
2025-10-08 12:39:07.819182 | controller | >f+++++++++ dmesg.log
2025-10-08 12:39:07.819218 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 12:39:07.819253 | controller | >f+++++++++ python.log
2025-10-08 12:39:07.819296 | controller | >f+++++++++ registries.conf
2025-10-08 12:39:07.819332 | controller | >f+++++++++ report.html
2025-10-08 12:39:07.819366 | controller | >f+++++++++ selinux-denials.log
2025-10-08 12:39:07.819400 | controller | >f+++++++++ selinux-listing.log
2025-10-08 12:39:07.819433 | controller | cd+++++++++ ci-framework-data/
2025-10-08 12:39:07.819466 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 12:39:07.819501 | controller | >f+++++++++ ci-framework-data/artifacts/networking-mapper-instances-facts.yml
2025-10-08 12:39:07.819535 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 12:39:07.819569 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-08 12:39:07.819603 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/nmstate/
2025-10-08 12:39:07.819636 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/crc-nncp.yaml
2025-10-08 12:39:07.819669 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-nmstate-olm.yaml
2025-10-08 12:39:07.819757 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-operatorgroup-olm.yaml
2025-10-08 12:39:07.819797 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-subscription-olm.yaml
2025-10-08 12:39:07.819832 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 12:39:07.819866 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 12:39:07.819899 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 12:39:07.819932 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 12:39:07.819965 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 12:39:07.819999 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 12:39:07.820032 | controller | cd+++++++++ registries.conf.d/
2025-10-08 12:39:07.820066 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 12:39:07.820099 | controller | cd+++++++++ system-config/
2025-10-08 12:39:07.820132 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 12:39:07.820165 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 12:39:07.820197 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 12:39:07.820229 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 12:39:07.820262 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 12:39:07.820294 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 12:39:07.820326 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 12:39:07.820358 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 12:39:07.820389 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 12:39:07.820422 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 12:39:07.820476 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 12:39:07.820512 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 12:39:07.820546 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 12:39:07.820578 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 12:39:07.820611 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 12:39:07.820644 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 12:39:07.820719 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 12:39:08.434095 | controller | changed: .d..t...... ./
2025-10-08 12:39:09.035752 | controller | changed: .d..t...... ./
2025-10-08 12:39:09.064176 | 
2025-10-08 12:39:09.064331 | TASK [Return artifact to Zuul]
2025-10-08 12:39:09.127051 | controller | ok
2025-10-08 12:39:09.154794 | 
2025-10-08 12:39:09.154886 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 12:39:09.155051 | 
2025-10-08 12:39:09.155097 | PLAY RECAP
2025-10-08 12:39:09.155152 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 12:39:09.155186 | 
2025-10-08 12:39:09.268202 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:39:09.269826 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-08 12:39:09.904219 | 
2025-10-08 12:39:09.904334 | PLAY [Report DLRN results after job run]
2025-10-08 12:39:09.926539 | 
2025-10-08 12:39:09.926653 | TASK [Report job status to DLRN]
2025-10-08 12:39:09.948086 | controller | ok
2025-10-08 12:39:09.974578 | 
2025-10-08 12:39:09.974701 | TASK [dlrn_report : Warning if DLRN is not running]
2025-10-08 12:39:10.040254 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.046589 | 
2025-10-08 12:39:10.046670 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-10-08 12:39:10.102271 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.116814 | 
2025-10-08 12:39:10.116982 | TASK [dlrn_report : Install kinit related package]
2025-10-08 12:39:10.165185 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.181198 | 
2025-10-08 12:39:10.181353 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-10-08 12:39:10.230836 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.244301 | 
2025-10-08 12:39:10.245180 | TASK [Set zuul-log-path fact]
2025-10-08 12:39:10.296324 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.308974 | 
2025-10-08 12:39:10.309138 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-10-08 12:39:10.357129 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.370474 | 
2025-10-08 12:39:10.370625 | TASK [Get hash related data from repo_setup role]
2025-10-08 12:39:10.418669 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.432658 | 
2025-10-08 12:39:10.432838 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-10-08 12:39:10.983780 | controller | skipping: Conditional result was False
2025-10-08 12:39:10.998073 | 
2025-10-08 12:39:10.998228 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-10-08 12:39:11.057108 | controller | skipping: Conditional result was False
2025-10-08 12:39:11.071488 | 
2025-10-08 12:39:11.071656 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-10-08 12:39:11.626625 | controller | skipping: Conditional result was False
2025-10-08 12:39:11.685552 | 
2025-10-08 12:39:11.685674 | PLAY RECAP
2025-10-08 12:39:11.685780 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-10-08 12:39:11.685810 | 
2025-10-08 12:39:11.806765 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-08 12:39:11.807570 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:39:12.398047 | 
2025-10-08 12:39:12.398163 | PLAY [all]
2025-10-08 12:39:12.417596 | 
2025-10-08 12:39:12.417674 | TASK [include_role : fetch-output]
2025-10-08 12:39:12.456825 | controller | ok
2025-10-08 12:39:12.475076 | 
2025-10-08 12:39:12.475167 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:39:12.540119 | controller | skipping: Conditional result was False
2025-10-08 12:39:12.545845 | 
2025-10-08 12:39:12.545912 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:39:12.585380 | controller | ok
2025-10-08 12:39:12.590607 | 
2025-10-08 12:39:12.590671 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:39:13.003209 | controller -> localhost | ok: "/var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/logs"
2025-10-08 12:39:13.217843 | controller -> localhost | ok: "/var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/artifacts"
2025-10-08 12:39:13.467101 | controller -> localhost | ok: "/var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/docs"
2025-10-08 12:39:13.494097 | 
2025-10-08 12:39:13.494293 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:39:14.259885 | controller | ok
2025-10-08 12:39:14.260243 | controller | ok: All items complete
2025-10-08 12:39:14.260449 | 
2025-10-08 12:39:14.848444 | controller | ok
2025-10-08 12:39:15.496637 | controller | ok
2025-10-08 12:39:15.512697 | 
2025-10-08 12:39:15.512837 | TASK [include_role : fetch-output-openshift]
2025-10-08 12:39:15.537342 | controller | skipping: Conditional result was False
2025-10-08 12:39:15.544523 | 
2025-10-08 12:39:15.544611 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 12:39:16.013033 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014049
2025-10-08 12:39:16.275061 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013410
2025-10-08 12:39:16.336721 | 
2025-10-08 12:39:16.336854 | PLAY [all]
2025-10-08 12:39:16.358157 | 
2025-10-08 12:39:16.358249 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 12:39:16.953362 | controller | changed
2025-10-08 12:39:17.001720 | 
2025-10-08 12:39:17.001829 | PLAY RECAP
2025-10-08 12:39:17.001885 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 12:39:17.001914 | 
2025-10-08 12:39:17.100598 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:39:17.101521 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 12:39:17.706421 | 
2025-10-08 12:39:17.706529 | PLAY [localhost]
2025-10-08 12:39:17.725616 | 
2025-10-08 12:39:17.725752 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 12:39:18.179129 | localhost | changed
2025-10-08 12:39:18.189270 | 
2025-10-08 12:39:18.189424 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 12:39:18.226004 | localhost | ok
2025-10-08 12:39:18.246080 | 
2025-10-08 12:39:18.246281 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 12:39:18.683115 | localhost | changed
2025-10-08 12:39:18.694827 | 
2025-10-08 12:39:18.694985 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 12:39:19.449813 | localhost | changed
2025-10-08 12:39:19.455104 | 
2025-10-08 12:39:19.455167 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 12:39:19.885786 | localhost | Identity added: /var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/tmp/ansible.et1lftqb (/var/lib/zuul/builds/bed59b8630754d13bc7467c34886fba8/work/tmp/ansible.et1lftqb)
2025-10-08 12:39:19.886150 | localhost | ok: Runtime: 0:00:00.014037
2025-10-08 12:39:19.896358 | 
2025-10-08 12:39:19.896498 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 12:39:20.230522 | localhost | ok: Runtime: 0:00:00.008728
2025-10-08 12:39:20.244253 | 
2025-10-08 12:39:20.244448 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 12:39:20.321366 | localhost | changed
2025-10-08 12:39:20.331047 | 
2025-10-08 12:39:20.331316 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 12:39:20.770283 | localhost | changed
2025-10-08 12:39:20.799668 | 
2025-10-08 12:39:20.799798 | PLAY [localhost]
2025-10-08 12:39:20.817761 | 
2025-10-08 12:39:20.817853 | TASK [Generate bulk log download script]
2025-10-08 12:39:20.839295 | localhost | ok
2025-10-08 12:39:20.856842 | 
2025-10-08 12:39:20.856936 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 12:39:20.888808 | localhost | ok: All assertions passed
2025-10-08 12:39:20.895366 | 
2025-10-08 12:39:20.895451 | TASK [local-log-download : Create download script]
2025-10-08 12:39:21.361833 | localhost -> localhost | changed
2025-10-08 12:39:21.383602 | 
2025-10-08 12:39:21.383777 | TASK [Register quick-download link]
2025-10-08 12:39:21.457103 | localhost | ok
2025-10-08 12:39:21.479924 | 
2025-10-08 12:39:21.479990 | PLAY [logserver.rdoproject.org]
2025-10-08 12:39:21.489781 | 
2025-10-08 12:39:21.489836 | TASK [Set zuul-log-path fact]
2025-10-08 12:39:21.506906 | logserver.rdoproject.org | ok
2025-10-08 12:39:21.517021 | 
2025-10-08 12:39:21.517080 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:39:21.553515 | logserver.rdoproject.org | ok
2025-10-08 12:39:21.559014 | 
2025-10-08 12:39:21.559076 | TASK [upload-logs : Create log directories]
2025-10-08 12:39:22.270368 | logserver.rdoproject.org | changed
2025-10-08 12:39:22.275815 | 
2025-10-08 12:39:22.275918 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 12:39:22.600738 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009173
2025-10-08 12:39:22.607245 | 
2025-10-08 12:39:22.607599 | TASK [upload-logs : Upload logs to log server]
2025-10-08 12:39:23.374430 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 12:39:23.381483 | 
2025-10-08 12:39:23.381616 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 12:39:23.448064 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:39:23.470869 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:39:23.481431 | 
2025-10-08 12:39:23.481628 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 12:39:23.544035 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:39:23.544475 | 
2025-10-08 12:39:23.548546 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:39:23.563059 | 
2025-10-08 12:39:23.563180 | LOOP [upload-logs : Upload console log and json output]