2025-10-03 03:59:32.312384 | Job console starting...
2025-10-03 03:59:32.331371 | Updating repositories
2025-10-03 03:59:32.375211 | Preparing job workspace
2025-10-03 03:59:37.695805 | Running Ansible setup...
2025-10-03 03:59:43.070057 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 03:59:43.613084 | 
2025-10-03 03:59:43.613210 | PLAY [localhost]
2025-10-03 03:59:43.622551 | 
2025-10-03 03:59:43.622634 | TASK [Gathering Facts]
2025-10-03 03:59:44.580508 | localhost | ok
2025-10-03 03:59:44.613443 | 
2025-10-03 03:59:44.613671 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-03 03:59:45.038721 | localhost -> localhost | changed
2025-10-03 03:59:45.051516 | 
2025-10-03 03:59:45.051672 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-03 03:59:45.986600 | localhost -> localhost | changed
2025-10-03 03:59:46.004444 | 
2025-10-03 03:59:46.004582 | TASK [Setup log path fact]
2025-10-03 03:59:46.038494 | localhost | ok
2025-10-03 03:59:46.058930 | 
2025-10-03 03:59:46.059019 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 03:59:46.095454 | localhost | ok
2025-10-03 03:59:46.105907 | 
2025-10-03 03:59:46.105992 | TASK [emit-job-header : Print job information]
2025-10-03 03:59:46.144553 | # Job Information
2025-10-03 03:59:46.144729 | Ansible Version: 2.15.12
2025-10-03 03:59:46.144755 | Job: cifmw-molecule-tofu
2025-10-03 03:59:46.144774 | Pipeline: github-check
2025-10-03 03:59:46.144821 | Executor: ze04.softwarefactory-project.io
2025-10-03 03:59:46.144840 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-03 03:59:46.144860 | Log URL (when completed): https://logserver.rdoproject.org/9ea/rdoproject.org/9ea99462b46b4e60aaf56b9fe75751dc/
2025-10-03 03:59:46.144878 | Event ID: 3daecbd0-a00d-11f0-93f4-31ce34c57105
2025-10-03 03:59:46.148567 | 
2025-10-03 03:59:46.148628 | LOOP [emit-job-header : Print node information]
2025-10-03 03:59:46.243062 | localhost | ok:
2025-10-03 03:59:46.243207 | localhost | # Node Information
2025-10-03 03:59:46.243233 | localhost | Inventory Hostname: controller
2025-10-03 03:59:46.243255 | localhost | Hostname: np0005467712
2025-10-03 03:59:46.243275 | localhost | Username: zuul
2025-10-03 03:59:46.243295 | localhost | Distro: CentOS 9
2025-10-03 03:59:46.243313 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-03 03:59:46.243330 | localhost | Region: RegionOne
2025-10-03 03:59:46.243362 | localhost | Label: centos-9-stream-crc-2-48-0-xl
2025-10-03 03:59:46.243378 | localhost | Product Name: OpenStack Nova
2025-10-03 03:59:46.243394 | localhost | Interface IP: 38.102.83.251
2025-10-03 03:59:46.264474 | 
2025-10-03 03:59:46.264616 | PLAY [all]
2025-10-03 03:59:46.271171 | 
2025-10-03 03:59:46.271235 | TASK [Gather network facts]
2025-10-03 03:59:46.938065 | controller | ok
2025-10-03 03:59:46.986607 | 
2025-10-03 03:59:46.986756 | TASK [include_role : start-zuul-console]
2025-10-03 03:59:47.007094 | controller | ok
2025-10-03 03:59:47.023121 | 
2025-10-03 03:59:47.023206 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-03 03:59:47.596721 | controller | ok
2025-10-03 03:59:47.617371 | 
2025-10-03 03:59:47.617500 | TASK [include_role : add-build-sshkey]
2025-10-03 03:59:47.649385 | controller | ok
2025-10-03 03:59:47.667814 | 
2025-10-03 03:59:47.667902 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-03 03:59:47.986760 | controller -> localhost | ok
2025-10-03 03:59:47.997249 | 
2025-10-03 03:59:47.997345 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-03 03:59:48.033448 | controller | ok
2025-10-03 03:59:48.057420 | controller | included: /var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-03 03:59:48.065708 | 
2025-10-03 03:59:48.065792 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-03 03:59:48.711893 | controller -> localhost | Generating public/private rsa key pair.
2025-10-03 03:59:48.712152 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/9ea99462b46b4e60aaf56b9fe75751dc_id_rsa.
2025-10-03 03:59:48.712193 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/9ea99462b46b4e60aaf56b9fe75751dc_id_rsa.pub.
2025-10-03 03:59:48.712222 | controller -> localhost | The key fingerprint is:
2025-10-03 03:59:48.712248 | controller -> localhost | SHA256:Z4GBPGDi0hIFzVCKlyRyn1kSp9VTkdqb4+N7RoankLw zuul-build-sshkey
2025-10-03 03:59:48.712273 | controller -> localhost | The key's randomart image is:
2025-10-03 03:59:48.712298 | controller -> localhost | +---[RSA 3072]----+
2025-10-03 03:59:48.712322 | controller -> localhost | |*B* =++o..oo     |
2025-10-03 03:59:48.712347 | controller -> localhost | |oOo= O+ oo.      |
2025-10-03 03:59:48.712372 | controller -> localhost | |= = =  ..+.      |
2025-10-03 03:59:48.712397 | controller -> localhost | | +      . ..     |
2025-10-03 03:59:48.712422 | controller -> localhost | |       .S.o+     |
2025-10-03 03:59:48.712446 | controller -> localhost | |        +o= +    |
2025-10-03 03:59:48.712470 | controller -> localhost | |         + *     |
2025-10-03 03:59:48.712494 | controller -> localhost | |        E + o    |
2025-10-03 03:59:48.712517 | controller -> localhost | |         .o=     |
2025-10-03 03:59:48.712540 | controller -> localhost | +----[SHA256]-----+
2025-10-03 03:59:48.712597 | controller -> localhost | ok: Runtime: 0:00:00.234482
2025-10-03 03:59:48.725913 | 
2025-10-03 03:59:48.726019 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-03 03:59:48.757182 | controller | ok
2025-10-03 03:59:48.771348 | controller | included: /var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-03 03:59:48.789531 | 
2025-10-03 03:59:48.789620 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-03 03:59:48.814240 | controller | skipping: Conditional result was False
2025-10-03 03:59:48.819855 | 
2025-10-03 03:59:48.819918 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-03 03:59:49.518939 | controller | changed
2025-10-03 03:59:49.529947 | 
2025-10-03 03:59:49.530081 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-03 03:59:49.944338 | controller | ok
2025-10-03 03:59:49.969112 | 
2025-10-03 03:59:49.969260 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-03 03:59:51.946606 | controller | changed
2025-10-03 03:59:51.957161 | 
2025-10-03 03:59:51.957288 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-03 03:59:53.837848 | controller | changed
2025-10-03 03:59:53.849182 | 
2025-10-03 03:59:53.849314 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-03 03:59:53.866261 | controller | skipping: Conditional result was False
2025-10-03 03:59:53.878420 | 
2025-10-03 03:59:53.878554 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-03 03:59:54.260356 | controller -> localhost | changed
2025-10-03 03:59:54.270030 | 
2025-10-03 03:59:54.270096 | TASK [add-build-sshkey : Add back temp key]
2025-10-03 03:59:54.612595 | controller -> localhost | Identity added: /var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/9ea99462b46b4e60aaf56b9fe75751dc_id_rsa (zuul-build-sshkey)
2025-10-03 03:59:54.612990 | controller -> localhost | ok: Runtime: 0:00:00.010035
2025-10-03 03:59:54.627102 | 
2025-10-03 03:59:54.627246 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-03 03:59:55.155274 | controller | ok
2025-10-03 03:59:55.165792 | 
2025-10-03 03:59:55.165927 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-03 03:59:55.206643 | controller | skipping: Conditional result was False
2025-10-03 03:59:55.245849 | 
2025-10-03 03:59:55.245980 | TASK [include_role : validate-host]
2025-10-03 03:59:55.266697 | controller | ok
2025-10-03 03:59:55.293016 | 
2025-10-03 03:59:55.293162 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-03 03:59:55.323476 | controller | ok
2025-10-03 03:59:55.328668 | 
2025-10-03 03:59:55.328762 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-03 03:59:55.576810 | controller -> localhost | ok
2025-10-03 03:59:55.582744 | 
2025-10-03 03:59:55.582814 | TASK [validate-host : Collect information about the host]
2025-10-03 03:59:56.595113 | controller | ok
2025-10-03 03:59:56.613649 | 
2025-10-03 03:59:56.613807 | TASK [validate-host : Sanitize hostname]
2025-10-03 03:59:56.669309 | controller | ok
2025-10-03 03:59:56.681297 | 
2025-10-03 03:59:56.681499 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-03 03:59:57.134577 | controller -> localhost | changed
2025-10-03 03:59:57.140776 | 
2025-10-03 03:59:57.140870 | TASK [validate-host : Collect information about zuul worker]
2025-10-03 03:59:57.748624 | controller | ok
2025-10-03 03:59:57.759286 | 
2025-10-03 03:59:57.759416 | TASK [validate-host : Write out all zuul information for each host]
2025-10-03 03:59:58.223245 | controller -> localhost | changed
2025-10-03 03:59:58.241986 | 
2025-10-03 03:59:58.242106 | TASK [include_role : prepare-workspace-openshift]
2025-10-03 03:59:58.267740 | controller | skipping: Conditional result was False
2025-10-03 03:59:58.281014 | 
2025-10-03 03:59:58.281191 | TASK [include_role : remove-zuul-sshkey]
2025-10-03 03:59:58.307570 | controller | skipping: Conditional result was False
2025-10-03 03:59:58.316547 | 
2025-10-03 03:59:58.316661 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 03:59:58.747715 | controller | ok: "logs"
2025-10-03 03:59:58.747943 | controller | ok: All items complete
2025-10-03 03:59:58.747971 | 
2025-10-03 03:59:59.099142 | controller | ok: "artifacts"
2025-10-03 03:59:59.516928 | controller | ok: "docs"
2025-10-03 03:59:59.529924 | 
2025-10-03 03:59:59.529990 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 03:59:59.997989 | controller | changed: "logs"
2025-10-03 04:00:00.432459 | controller | changed: "artifacts"
2025-10-03 04:00:00.850225 | controller | changed: "docs"
2025-10-03 04:00:00.891013 | 
2025-10-03 04:00:00.891154 | PLAY RECAP
2025-10-03 04:00:00.891220 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:00:00.891263 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:00:00.891294 | 
2025-10-03 04:00:01.006274 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 04:00:01.008010 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:00:01.599287 | 
2025-10-03 04:00:01.599425 | PLAY [all]
2025-10-03 04:00:01.620963 | 
2025-10-03 04:00:01.621103 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:00:01.683488 | controller | ok
2025-10-03 04:00:01.689747 | 
2025-10-03 04:00:01.689854 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:00:02.332212 | controller | changed
2025-10-03 04:00:02.344791 | 
2025-10-03 04:00:02.344961 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:00:04.549984 | controller | changed
2025-10-03 04:00:04.573907 | 
2025-10-03 04:00:04.574049 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-03 04:00:05.249301 | controller | changed:
2025-10-03 04:00:05.249484 | controller | {
2025-10-03 04:00:05.249513 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-03 04:00:05.249542 | controller | }
2025-10-03 04:00:05.692461 | controller | changed:
2025-10-03 04:00:05.692569 | controller | {
2025-10-03 04:00:05.692597 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-03 04:00:05.692618 | controller | }
2025-10-03 04:00:06.084791 | controller | changed:
2025-10-03 04:00:06.084913 | controller | {
2025-10-03 04:00:06.084943 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-03 04:00:06.084966 | controller | }
2025-10-03 04:00:06.537428 | controller | changed:
2025-10-03 04:00:06.537537 | controller | {
2025-10-03 04:00:06.537559 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-03 04:00:06.537577 | controller | }
2025-10-03 04:00:07.014108 | controller | changed:
2025-10-03 04:00:07.014350 | controller | {
2025-10-03 04:00:07.014392 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-03 04:00:07.014417 | controller | }
2025-10-03 04:00:07.401288 | controller | changed:
2025-10-03 04:00:07.401396 | controller | {
2025-10-03 04:00:07.401420 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-03 04:00:07.401438 | controller | }
2025-10-03 04:00:07.873882 | controller | changed:
2025-10-03 04:00:07.874036 | controller | {
2025-10-03 04:00:07.874060 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-03 04:00:07.874080 | controller | }
2025-10-03 04:00:08.297146 | controller | changed:
2025-10-03 04:00:08.297305 | controller | {
2025-10-03 04:00:08.297334 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-03 04:00:08.297354 | controller | }
2025-10-03 04:00:08.747103 | controller | changed:
2025-10-03 04:00:08.747196 | controller | {
2025-10-03 04:00:08.747219 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-03 04:00:08.747238 | controller | }
2025-10-03 04:00:09.180171 | controller | changed:
2025-10-03 04:00:09.180265 | controller | {
2025-10-03 04:00:09.180287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-03 04:00:09.180306 | controller | }
2025-10-03 04:00:09.586784 | controller | changed:
2025-10-03 04:00:09.586901 | controller | {
2025-10-03 04:00:09.586938 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-03 04:00:09.586969 | controller | }
2025-10-03 04:00:10.003109 | controller | changed:
2025-10-03 04:00:10.003207 | controller | {
2025-10-03 04:00:10.003232 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-03 04:00:10.003252 | controller | }
2025-10-03 04:00:10.436874 | controller | changed:
2025-10-03 04:00:10.436998 | controller | {
2025-10-03 04:00:10.437022 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-03 04:00:10.437042 | controller | }
2025-10-03 04:00:10.834301 | controller | changed:
2025-10-03 04:00:10.834483 | controller | {
2025-10-03 04:00:10.834518 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-03 04:00:10.834538 | controller | }
2025-10-03 04:00:11.265629 | controller | changed:
2025-10-03 04:00:11.265796 | controller | {
2025-10-03 04:00:11.265824 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-03 04:00:11.265844 | controller | }
2025-10-03 04:00:11.668154 | controller | changed:
2025-10-03 04:00:11.668300 | controller | {
2025-10-03 04:00:11.668352 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-03 04:00:11.668392 | controller | }
2025-10-03 04:00:12.081064 | controller | changed:
2025-10-03 04:00:12.081183 | controller | {
2025-10-03 04:00:12.081216 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-03 04:00:12.081242 | controller | }
2025-10-03 04:00:12.505432 | controller | changed:
2025-10-03 04:00:12.505548 | controller | {
2025-10-03 04:00:12.505574 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-03 04:00:12.505594 | controller | }
2025-10-03 04:00:12.960842 | controller | changed:
2025-10-03 04:00:12.961000 | controller | {
2025-10-03 04:00:12.961051 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-03 04:00:12.961089 | controller | }
2025-10-03 04:00:13.388397 | controller | changed:
2025-10-03 04:00:13.388560 | controller | {
2025-10-03 04:00:13.388598 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-03 04:00:13.388626 | controller | }
2025-10-03 04:00:13.834149 | controller | changed:
2025-10-03 04:00:13.834247 | controller | {
2025-10-03 04:00:13.834270 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-03 04:00:13.834287 | controller | }
2025-10-03 04:00:14.257203 | controller | changed:
2025-10-03 04:00:14.257323 | controller | {
2025-10-03 04:00:14.257354 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-03 04:00:14.257376 | controller | }
2025-10-03 04:00:14.687407 | controller | changed:
2025-10-03 04:00:14.687501 | controller | {
2025-10-03 04:00:14.687524 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-03 04:00:14.687543 | controller | }
2025-10-03 04:00:15.110788 | controller | changed:
2025-10-03 04:00:15.110888 | controller | {
2025-10-03 04:00:15.110911 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-03 04:00:15.110929 | controller | }
2025-10-03 04:00:15.584861 | controller | changed:
2025-10-03 04:00:15.584935 | controller | {
2025-10-03 04:00:15.584958 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-03 04:00:15.584977 | controller | }
2025-10-03 04:00:16.055364 | controller | changed:
2025-10-03 04:00:16.055471 | controller | {
2025-10-03 04:00:16.055494 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-03 04:00:16.055512 | controller | }
2025-10-03 04:00:16.089017 | 
2025-10-03 04:00:16.089130 | TASK [Set timezone to UTC]
2025-10-03 04:00:17.042149 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-03 04:00:17.055618 | 
2025-10-03 04:00:17.055716 | TASK [Create nodepool directory]
2025-10-03 04:00:17.542327 | controller | changed
2025-10-03 04:00:17.547978 | 
2025-10-03 04:00:17.548041 | TASK [Create nodepool sub_nodes file]
2025-10-03 04:00:19.375554 | controller | changed
2025-10-03 04:00:19.386005 | 
2025-10-03 04:00:19.386079 | TASK [Create nodepool sub_nodes_private file]
2025-10-03 04:00:21.226514 | controller | changed
2025-10-03 04:00:21.234838 | 
2025-10-03 04:00:21.234909 | LOOP [Populate nodepool sub_nodes file]
2025-10-03 04:00:21.321251 | 
2025-10-03 04:00:21.321401 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-03 04:00:21.380563 | 
2025-10-03 04:00:21.380743 | TASK [Create nodepool primary file]
2025-10-03 04:00:21.437372 | controller | skipping: Conditional result was False
2025-10-03 04:00:21.443604 | 
2025-10-03 04:00:21.443670 | TASK [Create nodepool node_private for this node]
2025-10-03 04:00:23.273225 | controller | changed
2025-10-03 04:00:23.279444 | 
2025-10-03 04:00:23.279507 | LOOP [Copy ssh keys to nodepool directory]
2025-10-03 04:00:23.924633 | controller | ok: Item: id_rsa Runtime: 0:00:00.011418
2025-10-03 04:00:23.924869 | 
2025-10-03 04:00:24.368507 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.010676
2025-10-03 04:00:24.389953 | 
2025-10-03 04:00:24.390076 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-03 04:00:26.404930 | controller | changed
2025-10-03 04:00:26.410623 | 
2025-10-03 04:00:26.410755 | TASK [Validate sudoers config after edits]
2025-10-03 04:00:26.864564 | controller | /etc/sudoers: parsed OK
2025-10-03 04:00:26.864816 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-03 04:00:26.864835 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-03 04:00:26.864849 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-03 04:00:27.003278 | controller | ok: Runtime: 0:00:00.010753
2025-10-03 04:00:27.009939 | 
2025-10-03 04:00:27.010031 | TASK [Show the environment passed in to job shell scripts]
2025-10-03 04:00:27.510377 | controller | SHELL=/bin/bash
2025-10-03 04:00:27.510488 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-03 04:00:27.510509 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-03 04:00:27.510524 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:00:27.510538 | controller | PWD=/home/zuul
2025-10-03 04:00:27.510552 | controller | ZUUL_PIPELINE=github-check
2025-10-03 04:00:27.510588 | controller | LOGNAME=zuul
2025-10-03 04:00:27.510601 | controller | XDG_SESSION_TYPE=tty
2025-10-03 04:00:27.510615 | controller | _=/usr/bin/env
2025-10-03 04:00:27.510627 | controller | MOTD_SHOWN=pam
2025-10-03 04:00:27.510640 | controller | HOME=/home/zuul
2025-10-03 04:00:27.510653 | controller | LANG=en_US.UTF-8
2025-10-03 04:00:27.510666 | controller | SSH_CONNECTION=38.102.83.114 52486 38.102.83.251 22
2025-10-03 04:00:27.510681 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-03 04:00:27.510788 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:00:27.510805 | controller | WORKSPACE=/home/zuul/workspace
2025-10-03 04:00:27.510819 | controller | XDG_SESSION_CLASS=user
2025-10-03 04:00:27.510832 | controller | SELINUX_ROLE_REQUESTED=
2025-10-03 04:00:27.510846 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-03 04:00:27.510858 | controller | USER=zuul
2025-10-03 04:00:27.510872 | controller | ZUUL_VOTING=True
2025-10-03 04:00:27.510885 | controller | BUILD_TIMEOUT=1800000
2025-10-03 04:00:27.510898 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-03 04:00:27.510911 | controller | SHLVL=1
2025-10-03 04:00:27.510925 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:00:27.510938 | controller | XDG_SESSION_ID=1
2025-10-03 04:00:27.510952 | controller | ZUUL_BRANCH=main
2025-10-03 04:00:27.510965 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-03 04:00:27.510978 | controller | SSH_CLIENT=38.102.83.114 52486 22
2025-10-03 04:00:27.510991 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-03 04:00:27.511004 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-03 04:00:27.511021 | controller | which_declare=declare -f
2025-10-03 04:00:27.511035 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-03 04:00:27.511048 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-03 04:00:27.511061 | controller | ZUUL_CHANGE=3362
2025-10-03 04:00:27.511074 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-03 04:00:27.511088 | controller | ZUUL_UUID=9ea99462b46b4e60aaf56b9fe75751dc
2025-10-03 04:00:27.511104 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-03 04:00:27.511117 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-03 04:00:27.511130 | controller | }
2025-10-03 04:00:27.572576 | controller | ok: Runtime: 0:00:00.012982
2025-10-03 04:00:27.583389 | 
2025-10-03 04:00:27.583476 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-03 04:00:27.676219 | controller | skipping: Conditional result was False
2025-10-03 04:00:27.682773 | 
2025-10-03 04:00:27.682849 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-03 04:00:28.232942 | controller | skipping: Conditional result was False
2025-10-03 04:00:28.238971 | 
2025-10-03 04:00:28.239061 | TASK [Ensure legacy workspace directory]
2025-10-03 04:00:28.588660 | controller | changed
2025-10-03 04:00:28.634429 | 
2025-10-03 04:00:28.634584 | PLAY RECAP
2025-10-03 04:00:28.634632 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:00:28.634651 | 
2025-10-03 04:00:29.136908 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:00:29.137705 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:00:30.095144 | 
2025-10-03 04:00:30.095265 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-03 04:00:30.162121 | 
2025-10-03 04:00:30.162258 | TASK [Create zuul-output directory]
2025-10-03 04:00:31.029471 | controller | changed
2025-10-03 04:00:31.034505 | 
2025-10-03 04:00:31.034574 | TASK [Slurp Zuul inventory test]
2025-10-03 04:00:31.581180 | controller -> localhost | ok
2025-10-03 04:00:31.590166 | 
2025-10-03 04:00:31.590300 | TASK [Save zuul inventory]
2025-10-03 04:00:33.602207 | controller | changed
2025-10-03 04:00:33.610141 | 
2025-10-03 04:00:33.610220 | TASK [Save zuul vars without the change_message]
2025-10-03 04:00:35.390842 | controller | changed
2025-10-03 04:00:35.422357 | 
2025-10-03 04:00:35.422460 | PLAY RECAP
2025-10-03 04:00:35.422507 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:00:35.422528 | 
2025-10-03 04:00:35.544856 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:00:35.545738 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:00:36.425516 | 
2025-10-03 04:00:36.425634 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-03 04:00:36.447595 | 
2025-10-03 04:00:36.447745 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-03 04:00:36.467034 | controller | ok
2025-10-03 04:00:36.484873 | 
2025-10-03 04:00:36.485005 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:00:36.529352 | controller | skipping: Conditional result was False
2025-10-03 04:00:36.535313 | 
2025-10-03 04:00:36.535382 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:00:37.063826 | controller | ok
2025-10-03 04:00:37.071176 | 
2025-10-03 04:00:37.071245 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:00:38.770868 | controller | ok
2025-10-03 04:00:38.795611 | 
2025-10-03 04:00:38.795760 | TASK [Prepare workspace]
2025-10-03 04:00:38.814785 | controller | ok
2025-10-03 04:00:38.840930 | 
2025-10-03 04:00:38.841032 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 04:00:39.398793 | controller | ok
2025-10-03 04:00:39.409404 | 
2025-10-03 04:00:39.409531 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 04:00:43.103742 | controller | Output suppressed because no_log was given
2025-10-03 04:00:43.113105 | 
2025-10-03 04:00:43.113169 | LOOP [Create zuul-output directory]
2025-10-03 04:00:43.487985 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-03 04:00:43.818838 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-03 04:00:43.836080 | 
2025-10-03 04:00:43.836210 | TASK [Install required packages]
2025-10-03 04:00:59.492498 | controller | ok: Nothing to do
2025-10-03 04:00:59.505145 | 
2025-10-03 04:00:59.505291 | TASK [Install venv]
2025-10-03 04:02:01.251111 | controller | changed
2025-10-03 04:02:01.305763 | 
2025-10-03 04:02:01.305861 | PLAY RECAP
2025-10-03 04:02:01.305916 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:02:01.305946 | 
2025-10-03 04:02:01.412430 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:02:01.414261 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:02:02.012383 | 
2025-10-03 04:02:02.012493 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-03 04:02:02.033347 | 
2025-10-03 04:02:02.033431 | TASK [Gather required facts]
2025-10-03 04:02:02.774095 | controller | ok
2025-10-03 04:02:02.791843 | 
2025-10-03 04:02:02.792004 | TASK [Load environment var if instructed to]
2025-10-03 04:02:02.851066 | controller | skipping: Conditional result was False
2025-10-03 04:02:02.859598 | 
2025-10-03 04:02:02.859713 | TASK [Run molecule]
2025-10-03 04:02:04.251394 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-03 04:02:04.324895 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-03 04:02:22.417458 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.418397 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.419751 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.420813 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.421877 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.422855 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.423868 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.424914 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.425944 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.426927 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.427952 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.428987 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.430101 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.431127 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.432165 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/cc52e9/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:02:22.453927 | controller | INFO     Running default > prepare
2025-10-03 04:02:23.266943 | controller |
2025-10-03 04:02:23.267125 | controller | PLAY [Prepare] *****************************************************************
2025-10-03 04:02:23.267346 | controller |
2025-10-03 04:02:23.267598 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:02:23.267808 | controller | Friday 03 October 2025  04:02:23 +0000 (0:00:00.021)       0:00:00.021 ********
2025-10-03 04:02:25.437890 | controller | ok: [instance]
2025-10-03 04:02:25.438086 | controller |
2025-10-03 04:02:25.438112 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-03 04:02:25.438282 | controller | Friday 03 October 2025  04:02:25 +0000 (0:00:02.171)       0:00:02.193 ********
2025-10-03 04:02:25.469022 | controller | skipping: [instance]
2025-10-03 04:02:25.469197 | controller |
2025-10-03 04:02:25.469437 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-03 04:02:25.469683 | controller | Friday 03 October 2025  04:02:25 +0000 (0:00:00.031)       0:00:02.224 ********
2025-10-03 04:02:25.551104 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-03 04:02:25.551296 | controller |
2025-10-03 04:02:25.551481 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-03 04:02:25.551706 | controller | Friday 03 October 2025  04:02:25 +0000 (0:00:00.081)       0:00:02.306 ********
2025-10-03 04:02:25.966456 | controller | ok: [instance]
2025-10-03 04:02:25.966521 | controller |
2025-10-03 04:02:25.966543 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-03 04:02:25.966671 | controller | Friday 03 October 2025  04:02:25 +0000 (0:00:00.415)       0:00:02.721 ********
2025-10-03 04:02:26.456637 | controller | ok: [instance]
2025-10-03 04:02:26.456812 | controller |
2025-10-03 04:02:26.456830 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-03 04:02:26.456849 | controller | Friday 03 October 2025  04:02:26 +0000 (0:00:00.489)       0:00:03.210 ********
2025-10-03 04:02:27.574130 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-03 04:02:27.574667 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-03 04:02:27.574768 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-03 04:02:27.574791 | controller |
2025-10-03 04:02:27.575261 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-03 04:02:27.672855 | controller | Friday 03 October 2025  04:02:27 +0000 (0:00:01.117)       0:00:04.328 ********
2025-10-03 04:02:27.672915 | controller |
2025-10-03 04:02:28.505639 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-03 04:02:28.505700 | controller | Friday 03 October 2025  04:02:27 +0000 (0:00:00.099)       0:00:04.428 ********
2025-10-03 04:02:28.505727 | controller | changed: [instance] => (item=tmp)
2025-10-03 04:02:28.506193 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-03 04:02:28.506250 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-03 04:02:28.506270 | controller |
2025-10-03 04:02:28.506296 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-03 04:02:29.510677 | controller | Friday 03 October 2025  04:02:28 +0000 (0:00:00.831)       0:00:05.259 ********
2025-10-03 04:02:29.510712 | controller | ok: [instance]
2025-10-03 04:02:30.835658 | controller |
2025-10-03 04:02:30.835715 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-03 04:02:30.835739 | controller | Friday 03 October 2025  04:02:29 +0000 (0:00:01.005)       0:00:06.264 ********
2025-10-03 04:02:30.835760 | controller | changed: [instance]
2025-10-03 04:02:30.836108 | controller |
2025-10-03 04:02:30.836181 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-03 04:02:38.778724 | controller | Friday 03 October 2025  04:02:30 +0000 (0:00:01.325)       0:00:07.590 ********
2025-10-03 04:02:38.778804 | controller | changed: [instance]
2025-10-03 04:02:39.804686 | controller |
2025-10-03 04:02:39.804789 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-03 04:02:39.804807 | controller | Friday 03 October 2025  04:02:38 +0000 (0:00:07.943)       0:00:15.533 ********
2025-10-03 04:02:39.804832 | controller | changed: [instance]
2025-10-03 04:02:39.804901 | controller |
2025-10-03 04:02:39.805543 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-03 04:02:39.858459 | controller | Friday 03 October 2025  04:02:39 +0000 (0:00:01.025)       0:00:16.559 ********
2025-10-03 04:02:39.858512 | controller | skipping: [instance]
2025-10-03 04:02:40.666607 | controller |
2025-10-03 04:02:40.666651 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-03 04:02:40.666662 | controller | Friday 03 October 2025  04:02:39 +0000 (0:00:00.053)       0:00:16.613 ********
2025-10-03 04:02:40.666677 | controller | changed: [instance]
2025-10-03 04:02:40.725654 | controller |
2025-10-03 04:02:40.725721 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-03 04:02:40.725739 | controller | Friday 03 October 2025  04:02:40 +0000 (0:00:00.807)       0:00:17.420 ********
2025-10-03 04:02:40.725762 | controller | skipping: [instance]
2025-10-03 04:02:40.725818 | controller |
2025-10-03 04:02:40.725838 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-03 04:02:40.726084 | controller | Friday 03 October 2025  04:02:40 +0000 (0:00:00.059)       0:00:17.480 ********
2025-10-03 04:02:40.785701 | controller | skipping: [instance]
2025-10-03 04:02:40.785810 | controller |
2025-10-03 04:02:40.785834 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-03 04:02:40.786038 | controller | Friday 03 October 2025  04:02:40 +0000 (0:00:00.058)       0:00:17.539 ********
2025-10-03 04:02:40.846602 | controller | skipping: [instance]
2025-10-03 04:02:40.846917 | controller |
2025-10-03 04:02:40.847231 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-03 04:02:40.847586 | controller | Friday 03 October 2025  04:02:40 +0000 (0:00:00.062)       0:00:17.601 ********
2025-10-03 04:02:41.492067 | controller | changed: [instance]
2025-10-03 04:02:42.148655 | controller |
2025-10-03 04:02:42.148719 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-03 04:02:42.148736 | controller | Friday 03 October 2025  04:02:41 +0000 (0:00:00.644)       0:00:18.246 ********
2025-10-03 04:02:42.148760 | controller | changed: [instance]
2025-10-03 04:02:42.213642 | controller |
2025-10-03 04:02:42.213696 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-03 04:02:42.213713 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.656)       0:00:18.903 ********
2025-10-03 04:02:42.213735 | controller | skipping: [instance]
2025-10-03 04:02:42.213794 | controller |
2025-10-03 04:02:42.214237 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-03 04:02:42.275605 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.065)       0:00:18.968 ********
2025-10-03 04:02:42.275663 | controller | skipping: [instance]
2025-10-03 04:02:42.275728 | controller |
2025-10-03 04:02:42.276111 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-03 04:02:42.330647 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.062)       0:00:19.031 ********
2025-10-03 04:02:42.330712 | controller | skipping: [instance]
2025-10-03 04:02:42.330756 | controller |
2025-10-03 04:02:42.330768 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-03 04:02:42.330779 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.054)       0:00:19.085 ********
2025-10-03 04:02:42.398957 | controller | ok: [instance]
2025-10-03 04:02:42.399136 | controller |
2025-10-03 04:02:42.399400 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-03 04:02:42.399712 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.068)       0:00:19.154 ********
2025-10-03 04:02:42.446386 | controller | skipping: [instance]
2025-10-03 04:02:42.446712 | controller |
2025-10-03 04:02:42.446985 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-03 04:02:42.447246 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.047)       0:00:19.201 ********
2025-10-03 04:02:42.489813 | controller | skipping: [instance]
2025-10-03 04:02:42.490060 | controller |
2025-10-03 04:02:42.490318 | controller | TASK [Download the RPM] ********************************************************
2025-10-03 04:02:42.490624 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.043)       0:00:19.245 ********
2025-10-03 04:02:42.533507 | controller | skipping: [instance]
2025-10-03 04:02:42.533756 | controller |
2025-10-03 04:02:42.534010 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-03 04:02:42.534266 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.043)       0:00:19.289 ********
2025-10-03 04:02:42.589043 | controller | skipping: [instance]
2025-10-03 04:02:42.589307 | controller |
2025-10-03 04:02:42.589617 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-03 04:02:42.589888 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.055)       0:00:19.344 ********
2025-10-03 04:02:42.634929 | controller | skipping: [instance]
2025-10-03 04:02:42.635253 | controller |
2025-10-03 04:02:42.635639 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-03 04:02:42.635881 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.045)       0:00:19.390 ********
2025-10-03 04:02:42.687978 | controller | skipping: [instance]
2025-10-03 04:02:42.688194 | controller |
2025-10-03 04:02:42.688625 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-03 04:02:42.688924 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.053)       0:00:19.443 ********
2025-10-03 04:02:42.740074 | controller | skipping: [instance]
2025-10-03 04:02:42.740330 | controller |
2025-10-03 04:02:42.740711 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-03 04:02:42.741035 | controller | Friday 03 October 2025  04:02:42 +0000 (0:00:00.052)       0:00:19.496 ********
2025-10-03 04:02:43.024215 | controller | ok: [instance]
2025-10-03 04:02:43.368082 | controller |
2025-10-03 04:02:43.368128 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-03 04:02:43.368141 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.283)       0:00:19.779 ********
2025-10-03 04:02:43.368155 | controller | changed: [instance]
2025-10-03 04:02:43.751651 | controller |
2025-10-03 04:02:43.751709 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-03 04:02:43.751725 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.343)       0:00:20.122 ********
2025-10-03 04:02:43.751749 | controller | changed: [instance]
2025-10-03 04:02:43.798197 | controller |
2025-10-03 04:02:43.798254 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-03 04:02:43.798272 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.383)       0:00:20.505 ********
2025-10-03 04:02:43.798294 | controller | skipping: [instance]
2025-10-03 04:02:43.849341 | controller |
2025-10-03 04:02:43.849377 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-03 04:02:43.849393 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.046)       0:00:20.552 ********
2025-10-03 04:02:43.849456 | controller | skipping: [instance]
2025-10-03 04:02:43.899394 | controller |
2025-10-03 04:02:43.899469 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-03 04:02:43.899485 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.051)       0:00:20.604 ********
2025-10-03 04:02:43.899519 | controller | skipping: [instance]
2025-10-03 04:02:43.948581 | controller |
2025-10-03 04:02:43.948658 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-03 04:02:43.948676 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.049)       0:00:20.654 ********
2025-10-03 04:02:43.948700 | controller | skipping: [instance]
2025-10-03 04:02:43.948882 | controller |
2025-10-03 04:02:43.949157 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-03 04:02:43.949451 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.049)       0:00:20.704 ********
2025-10-03 04:02:43.999013 | controller | skipping: [instance]
2025-10-03 04:02:43.999294 | controller |
2025-10-03 04:02:43.999626 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-03 04:02:43.999907 | controller | Friday 03 October 2025  04:02:43 +0000 (0:00:00.050)       0:00:20.754 ********
2025-10-03 04:02:44.048570 | controller | skipping: [instance]
2025-10-03 04:02:44.048839 | controller |
2025-10-03 04:02:44.049106 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-03 04:02:44.049399 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.049)       0:00:20.803 ********
2025-10-03 04:02:44.093868 | controller | skipping: [instance]
2025-10-03 04:02:44.094128 | controller |
2025-10-03 04:02:44.094395 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-03 04:02:44.094698 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.044)       0:00:20.848 ********
2025-10-03 04:02:44.160140 | controller | skipping: [instance]
2025-10-03 04:02:44.160432 | controller |
2025-10-03 04:02:44.160737 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-03 04:02:44.161023 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.066)       0:00:20.915 ********
2025-10-03 04:02:44.191688 | controller | skipping: [instance]
2025-10-03 04:02:44.191967 | controller |
2025-10-03 04:02:44.192247 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-03 04:02:44.192569 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.031)       0:00:20.947 ********
2025-10-03 04:02:44.237269 | controller | skipping: [instance]
2025-10-03 04:02:44.237571 | controller |
2025-10-03 04:02:44.237848 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-03 04:02:44.238115 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.046)       0:00:20.993 ********
2025-10-03 04:02:44.293432 | controller | skipping: [instance]
2025-10-03 04:02:44.293756 | controller |
2025-10-03 04:02:44.294004 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-03 04:02:44.294274 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.056)       0:00:21.049 ********
2025-10-03 04:02:44.354395 | controller | skipping: [instance]
2025-10-03 04:02:44.354694 | controller |
2025-10-03 04:02:44.354970 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-03 04:02:44.355263 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.061)       0:00:21.110 ********
2025-10-03 04:02:44.411962 | controller | skipping: [instance]
2025-10-03 04:02:44.412218 | controller |
2025-10-03 04:02:44.412542 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-03 04:02:44.412836 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.057)       0:00:21.167 ********
2025-10-03 04:02:44.469300 | controller | skipping: [instance]
2025-10-03 04:02:44.469751 | controller |
2025-10-03 04:02:44.470191 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-03 04:02:44.470629 | controller | Friday 03 October 2025  04:02:44 +0000 (0:00:00.057)       0:00:21.225 ********
2025-10-03 04:03:23.180640 | controller | ok: [instance]
2025-10-03 04:03:23.180748 | controller |
2025-10-03 04:03:23.180964 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-03 04:03:24.670430 | controller | Friday 03 October 2025  04:03:23 +0000 (0:00:38.710)       0:00:59.935 ********
2025-10-03 04:03:24.670471 | controller | ok: [instance]
2025-10-03 04:03:24.739366 | controller |
2025-10-03 04:03:24.739397 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-03 04:03:24.739440 | controller | Friday 03 October 2025  04:03:24 +0000 (0:00:01.489)       0:01:01.425 ********
2025-10-03 04:03:24.739458 | controller | skipping: [instance]
2025-10-03 04:03:24.821641 | controller |
2025-10-03 04:03:24.821692 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-03 04:03:24.821710 | controller | Friday 03 October 2025  04:03:24 +0000 (0:00:00.068)       0:01:01.494 ********
2025-10-03 04:03:24.821733 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-03 04:03:24.821775 | controller |
2025-10-03 04:03:24.821797 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-03 04:03:24.862628 | controller | Friday 03 October 2025  04:03:24 +0000 (0:00:00.082)       0:01:01.576 ********
2025-10-03 04:03:24.862711 | controller | ok: [instance] =>
2025-10-03 04:03:24.862773 | controller |   cifmw_ci_setup_packages:
2025-10-03 04:03:24.862791 | controller |   - bash-completion
2025-10-03 04:03:24.862805 | controller |   - ca-certificates
2025-10-03 04:03:24.862818 | controller |   - git-core
2025-10-03 04:03:24.862831 | controller |   - make
2025-10-03 04:03:24.862848 | controller |   - tar
2025-10-03 04:03:24.863888 | controller |   - tmux
2025-10-03 04:03:30.825662 | controller |   - python3-pip
2025-10-03 04:03:30.825722 | controller |
2025-10-03 04:03:30.825738 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-03 04:03:30.825765 | controller | Friday 03 October 2025  04:03:24 +0000 (0:00:00.040)       0:01:01.617 ********
2025-10-03 04:03:30.825789 | controller | changed: [instance]
2025-10-03 04:03:32.089922 | controller |
2025-10-03 04:03:32.089990 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-03 04:03:32.090016 | controller | Friday 03 October 2025  04:03:30 +0000 (0:00:05.962)       0:01:07.580 ********
2025-10-03 04:03:32.090047 | controller | ok: [instance]
2025-10-03 04:03:32.090546 | controller |
2025-10-03 04:03:32.090607 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-03 04:03:32.416594 | controller | Friday 03 October 2025  04:03:32 +0000 (0:00:01.264)       0:01:08.844 ********
2025-10-03 04:03:32.416661 | controller | changed: [instance]
2025-10-03 04:03:37.561674 | controller |
2025-10-03 04:03:37.561741 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-03 04:03:37.561759 | controller | Friday 03 October 2025  04:03:32 +0000 (0:00:00.326)       0:01:09.171 ********
2025-10-03 04:03:37.561782 | controller | changed: [instance]
2025-10-03 04:03:37.561844 | controller |
2025-10-03 04:03:37.562241 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-03 04:03:37.643270 | controller | Friday 03 October 2025  04:03:37 +0000 (0:00:05.145)       0:01:14.316 ********
2025-10-03 04:03:37.643314 | controller | ok: [instance]
2025-10-03 04:03:38.137569 | controller |
2025-10-03 04:03:38.137621 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-03 04:03:38.137638 | controller | Friday 03 October 2025  04:03:37 +0000 (0:00:00.081)       0:01:14.398 ********
2025-10-03 04:03:38.137660 | controller | changed: [instance]
2025-10-03 04:03:38.137714 | controller |
2025-10-03 04:03:38.138191 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-03 04:03:38.563602 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.494)       0:01:14.892 ********
2025-10-03 04:03:38.563673 | controller | changed: [instance]
2025-10-03 04:03:38.563728 | controller |
2025-10-03 04:03:38.563749 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-03 04:03:38.563828 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.426)       0:01:15.318 ********
2025-10-03 04:03:38.596565 | controller | skipping: [instance]
2025-10-03 04:03:38.596666 | controller |
2025-10-03 04:03:38.596692 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-03 04:03:38.631322 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.033)       0:01:15.352 ********
2025-10-03 04:03:38.631376 | controller | skipping: [instance]
2025-10-03 04:03:38.670288 | controller |
2025-10-03 04:03:38.670331 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-03 04:03:38.670347 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.033)       0:01:15.385 ********
2025-10-03 04:03:38.670368 | controller | skipping: [instance]
2025-10-03 04:03:38.707242 | controller |
2025-10-03 04:03:38.707282 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-03 04:03:38.707298 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.038)       0:01:15.424 ********
2025-10-03 04:03:38.707319 | controller | skipping: [instance]
2025-10-03 04:03:38.707671 | controller |
2025-10-03 04:03:38.707993 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-03 04:03:38.708320 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.037)       0:01:15.462 ********
2025-10-03 04:03:38.743456 | controller | skipping: [instance]
2025-10-03 04:03:38.743790 | controller |
2025-10-03 04:03:38.744103 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-03 04:03:38.744464 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.036)       0:01:15.498 ********
2025-10-03 04:03:38.793240 | controller | skipping: [instance]
2025-10-03 04:03:38.793596 | controller |
2025-10-03 04:03:38.793909 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-03 04:03:38.794211 | controller | Friday 03 October 2025  04:03:38 +0000 (0:00:00.049)       0:01:15.548 ********
2025-10-03 04:03:40.418631 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-03 04:03:40.418728 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-03 04:03:40.418747 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-03 04:03:40.418766 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-03 04:03:40.419359 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-03 04:10:59.040753 | controller |
2025-10-03 04:10:59.040797 | controller | TASK [Ensure CRC is started] ***************************************************
2025-10-03 04:10:59.040806 | controller | Friday 03 October 2025  04:03:40 +0000 (0:00:01.624)       0:01:17.173 ********
2025-10-03 04:10:59.040816 | controller | changed: [instance]
2025-10-03 04:10:59.124765 | controller |
2025-10-03 04:10:59.124793 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:10:59.124801 | controller | instance                   : ok=30   changed=16   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-10-03 04:10:59.124808 | controller |
2025-10-03 04:10:59.124830 | controller | Friday 03 October 2025  04:10:59 +0000 (0:07:18.619)       0:08:35.792 ********
2025-10-03 04:10:59.124842 | controller | ===============================================================================
2025-10-03 04:10:59.124851 | controller | Ensure CRC is started ------------------------------------------------- 438.62s
2025-10-03 04:10:59.124857 | controller | test_deps : Install selinux python libs -------------------------------- 38.71s
2025-10-03 04:10:59.124862 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.94s
2025-10-03 04:10:59.124868 | controller | ci_setup : Install needed packages -------------------------------------- 5.96s
2025-10-03 04:10:59.124874 | controller | ci_setup : Install openshift client ------------------------------------- 5.15s
2025-10-03 04:10:59.124880 | controller | Gathering Facts --------------------------------------------------------- 2.17s
2025-10-03 04:10:59.124886 | controller | ci_setup : Manage directories ------------------------------------------- 1.62s
2025-10-03 04:10:59.124892 | controller | test_deps : Install python yaml libs ------------------------------------ 1.49s
2025-10-03 04:10:59.124898 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.33s
2025-10-03 04:10:59.124903 | controller | ci_setup : Gather version of openshift client --------------------------- 1.26s
2025-10-03 04:10:59.124909 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 1.12s
2025-10-03 04:10:59.124915 | controller | repo_setup : Install repo-setup package --------------------------------- 1.03s
2025-10-03 04:10:59.124921 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.01s
2025-10-03 04:10:59.124927 | controller | repo_setup : Ensure directories are present ----------------------------- 0.83s
2025-10-03 04:10:59.124936 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.81s
2025-10-03 04:10:59.124944 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.66s
2025-10-03 04:10:59.124950 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.64s
2025-10-03 04:10:59.124956 | controller | ci_setup : Create completion file --------------------------------------- 0.49s
2025-10-03 04:10:59.124966 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.49s
2025-10-03 04:10:59.124973 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.43s
2025-10-03 04:10:59.124983 | controller | INFO     Running default > converge
2025-10-03 04:10:59.678165 | controller |
2025-10-03 04:11:00.860547 | controller | PLAY [Converge] ****************************************************************
2025-10-03 04:11:00.860637 | controller |
2025-10-03 04:11:00.860659 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:11:00.860676 | controller | Friday 03 October 2025  04:10:59 +0000 (0:00:00.019)       0:00:00.019 ********
2025-10-03 04:11:00.860704 | controller | ok: [instance]
2025-10-03 04:11:00.860843 | controller |
2025-10-03 04:11:00.860876 | controller | TASK [tofu : Fail If infrastructure State Is Not Correct] **********************
2025-10-03 04:11:00.860898 | controller | Friday 03 October 2025  04:11:00 +0000 (0:00:01.184)       0:00:01.204 ********
2025-10-03 04:11:00.901027 | controller | skipping: [instance]
2025-10-03 04:11:00.956764 | controller |
2025-10-03 04:11:00.956816 | controller | TASK [tofu : Fail If Check Mode Is Not Correct] ********************************
2025-10-03 04:11:00.956825 | controller | Friday 03 October 2025  04:11:00 +0000 (0:00:00.040)       0:00:01.244 ********
2025-10-03 04:11:00.956838 | controller | skipping: [instance]
2025-10-03 04:11:00.957185 | controller |
2025-10-03 04:11:00.957225 | controller | TASK [tofu : Fail If Tofu Project Path Not Defined] ****************************
2025-10-03 04:11:01.015027 | controller | Friday 03 October 2025  04:11:00 +0000 (0:00:00.055)       0:00:01.299 ********
2025-10-03 04:11:01.015100 | controller | skipping: [instance]
2025-10-03 04:11:01.015130 | controller |
2025-10-03 04:11:01.015140 | controller | TASK [Prepare Remote Environment If Required] **********************************
2025-10-03 04:11:01.015307 | controller | Friday 03 October 2025  04:11:01 +0000 (0:00:00.058)       0:00:01.358 ********
2025-10-03 04:11:01.075843 | controller |
2025-10-03 04:11:01.076081 | controller | TASK [tofu : Create Tofu Directory] ********************************************
2025-10-03 04:11:01.076364 | controller | Friday 03 October 2025  04:11:01 +0000 (0:00:00.061)       0:00:01.419 ********
2025-10-03 04:11:01.481541 | controller | changed: [instance]
2025-10-03 04:11:01.481813 | controller |
2025-10-03 04:11:01.481834 | controller | TASK [tofu : Copy Local Tufo Project Directory To Remote Host '{{ ansible_host }}'] ***
2025-10-03 04:11:01.482002 | controller | Friday 03 October 2025  04:11:01 +0000 (0:00:00.404)       0:00:01.824 ********
2025-10-03 04:11:02.255676 | controller | changed: [instance]
2025-10-03 04:11:02.255729 | controller |
2025-10-03 04:11:02.255822 | controller | TASK [tofu : Update Remote Execution Variable] *********************************
2025-10-03 04:11:02.255924 | controller | Friday 03 October 2025  04:11:02 +0000 (0:00:00.774)       0:00:02.598 ********
2025-10-03 04:11:02.292748 | controller | ok: [instance]
2025-10-03 04:11:02.742059 | controller |
2025-10-03 04:11:02.742153 | controller | TASK [tofu : Add OpenTofu repository] ******************************************
2025-10-03 04:11:02.742162 | controller | Friday 03 October 2025  04:11:02 +0000 (0:00:00.037)       0:00:02.635 ********
2025-10-03 04:11:02.742180 | controller | changed: [instance]
2025-10-03 04:11:09.388942 | controller |
2025-10-03 04:11:09.388999 | controller | TASK [tofu : Install OpenTofu RPM] *********************************************
2025-10-03 04:11:09.389007 | controller | Friday 03 October 2025  04:11:02 +0000 (0:00:00.449)       0:00:03.085 ********
2025-10-03 04:11:09.389022 | controller | changed: [instance]
2025-10-03 04:11:09.389127 | controller |
2025-10-03 04:11:09.389148 | controller | TASK [tofu : Return tofu to registered var] ************************************
2025-10-03 04:11:09.788369 | controller | Friday 03 October 2025  04:11:09 +0000 (0:00:06.645)       0:00:09.731 ********
2025-10-03 04:11:09.788432 | controller | changed: [instance]
2025-10-03 04:11:09.788489 | controller |
2025-10-03 04:11:09.788511 | controller | TASK [tofu : Tofu Action] ******************************************************
2025-10-03 04:11:09.788525 | controller | Friday 03 October 2025  04:11:09 +0000 (0:00:00.400)       0:00:10.131 ********
2025-10-03 04:11:13.928895 | controller | changed: [instance]
2025-10-03 04:11:14.021210 | controller |
2025-10-03 04:11:14.021281 | controller | TASK [tofu : Summarize Tofu Execution] *****************************************
2025-10-03 04:11:14.021298 | controller | Friday 03 October 2025  04:11:13 +0000 (0:00:04.141)       0:00:14.272 ********
2025-10-03 04:11:14.021323 | controller | ok: [instance] =>
2025-10-03 04:11:14.022623 | controller |   msg:
2025-10-03 04:11:14.022649 | controller |   - 'Execution failed: False'
2025-10-03 04:11:14.022663 | controller |   - 'Output:'
2025-10-03 04:11:14.022676 | controller |   - - ''
2025-10-03 04:11:14.022705 | controller |     - OpenTofu used the selected providers to generate the following execution
2025-10-03 04:11:14.022719 | controller |     - 'plan. Resource actions are indicated with the following symbols:'
2025-10-03 04:11:14.022732 | controller |     - '  + create'
2025-10-03 04:11:14.022745 | controller |     - ''
2025-10-03 04:11:14.022758 | controller |     - 'OpenTofu will perform the following actions:'
2025-10-03 04:11:14.022771 | controller |     - ''
2025-10-03 04:11:14.022783 | controller |     - '  # kubernetes_deployment.ubuntu will be created'
2025-10-03 04:11:14.022796 | controller |     - '  + resource "kubernetes_deployment" "ubuntu" {'
2025-10-03 04:11:14.022809 | controller |     - '      + id               = (known after apply)'
2025-10-03 04:11:14.022822 | controller |     - '      + wait_for_rollout = true'
2025-10-03 04:11:14.022836 | controller |     - ''
2025-10-03 04:11:14.022848 | controller |     - '      + metadata {'
2025-10-03 04:11:14.022861 | controller |     - '          + generation       = (known after apply)'
2025-10-03 04:11:14.022875 | controller |     - '          + name             = "ubuntu"'
2025-10-03 04:11:14.022888 | controller |     - '          + namespace        = "tofu-experiements"'
2025-10-03 04:11:14.022901 | controller |     - '          + resource_version = (known after apply)'
2025-10-03 04:11:14.022913 | controller |     - '          + uid              = (known after apply)'
2025-10-03 04:11:14.022926 | controller |     - '        }'
2025-10-03 04:11:14.022939 | controller |     - ''
2025-10-03 04:11:14.022952 | controller |     - '      + spec {'
2025-10-03 04:11:14.022967 | controller |     - '          + min_ready_seconds         = 0'
2025-10-03 04:11:14.022981 | controller |     - '          + paused                    = false'
2025-10-03 04:11:14.022994 | controller |     - '          + progress_deadline_seconds = 600'
2025-10-03 04:11:14.023007 | controller |     - '          + replicas                  = "3"'
2025-10-03 04:11:14.023019 | controller |     - '          + revision_history_limit    = 10'
2025-10-03 04:11:14.023032 | controller |     - ''
2025-10-03 04:11:14.023045 | controller |     - '          + selector {'
2025-10-03 04:11:14.023058 | controller |     - '              + match_labels = {'
2025-10-03 04:11:14.023081 | controller |     - '                  + "app" = "ubuntu"'
2025-10-03 04:11:14.023094 | controller |     - '                }'
2025-10-03 04:11:14.023107 | controller |     - '            }'
2025-10-03 04:11:14.023121 | controller |     - ''
2025-10-03 04:11:14.023134 | controller |     - '          + strategy (known after apply)'
2025-10-03 04:11:14.023147 | controller |     - ''
2025-10-03 04:11:14.023160 | controller |     - '          + template {'
2025-10-03 04:11:14.023173 | controller |     - '              + metadata {'
2025-10-03 04:11:14.023186 | controller |     - '                  + generation       = (known after apply)'
2025-10-03 04:11:14.023199 | controller |     - '                  + labels           = {'
2025-10-03 04:11:14.023211 | controller |     - '                      + "app" = "ubuntu"'
2025-10-03 04:11:14.023224 | controller |     - '                    }'
2025-10-03 04:11:14.023237 | controller |     - '                  + name             = (known after apply)'
2025-10-03 04:11:14.023250 | controller |     - '                  + resource_version = (known after apply)'
2025-10-03 04:11:14.023263 | controller |     - '                  + uid              = (known after apply)'
2025-10-03 04:11:14.023275 | controller |     - '                }'
2025-10-03 04:11:14.023288 | controller |     - '              + spec {'
2025-10-03 04:11:14.023301 | controller |     - '                  + automount_service_account_token  = true'
2025-10-03 04:11:14.023314 | controller |     - '                  + dns_policy                       = "ClusterFirst"'
2025-10-03 04:11:14.023327 | controller |     - '                  + enable_service_links             = true'
2025-10-03 04:11:14.023339 | controller |     - '                  + host_ipc                         = false'
2025-10-03 04:11:14.023352 | controller |     - '                  + host_network                     = false'
2025-10-03 04:11:14.023365 | controller |     - '                  + host_pid                         = false'
2025-10-03 04:11:14.023378 | controller |     - '                  + hostname                         = (known after apply)'
2025-10-03 04:11:14.023391 | controller |     - '                  + node_name                        = (known after apply)'
2025-10-03 04:11:14.023428 | controller |     - '                  + restart_policy                   = "Always"'
2025-10-03 04:11:14.023447 | controller |     - '                  + scheduler_name                   = (known after apply)'
2025-10-03 04:11:14.023472 | controller |     - '                  + service_account_name             = (known after apply)'
2025-10-03 04:11:14.023485 | controller |     - '                  + share_process_namespace          = false'
2025-10-03 04:11:14.023498 | controller |     - '                  + termination_grace_period_seconds = 30'
2025-10-03 04:11:14.023510 | controller |     - ''
2025-10-03 04:11:14.023523 | controller |     - '                  + container {'
2025-10-03 04:11:14.023536 | controller |     - '                      + args                       = ['
2025-10-03 04:11:14.023549 | controller |     - '                          + "while true; do sleep 30; done;",'
2025-10-03 04:11:14.023562 | controller |     - '                        ]'
2025-10-03 04:11:14.023575 | controller |     - '                      + command                    = ['
2025-10-03 04:11:14.023587 | controller |     - '                          + "/bin/bash",'
2025-10-03 04:11:14.023600 | controller |     - '                          + "-c",'
2025-10-03 04:11:14.023613 | controller |     - '                          + "--",'
2025-10-03 04:11:14.023626 | controller |     - '                        ]'
2025-10-03 04:11:14.023639 | controller |     - '                      + image                      = "ubuntu:latest"'
2025-10-03 04:11:14.023652 | controller |     - '                      + image_pull_policy          = (known after apply)'
2025-10-03 04:11:14.023664 | controller |     - '                      + name                       = "ubuntu"'
2025-10-03 04:11:14.023677 | controller |     - '                      + stdin                      = false'
2025-10-03 04:11:14.023690 | controller |     - '                      + stdin_once                 = false'
2025-10-03 04:11:14.023703 | controller |     - '                      + termination_message_path   = "/dev/termination-log"'
2025-10-03 04:11:14.023716 | controller |     - '                      + termination_message_policy = (known after apply)'
2025-10-03 04:11:14.023729 | controller |     - '                      + tty                        = false'
2025-10-03 04:11:14.023741 | controller |     - ''
2025-10-03 04:11:14.023754 | controller |     - '                      + resources (known after apply)'
2025-10-03 04:11:14.023767 | controller |     - '                    }'
2025-10-03 04:11:14.023791 | controller |     - ''
2025-10-03 04:11:14.023868 | controller |     - '                  + image_pull_secrets (known after apply)'
2025-10-03 04:11:14.023883 | controller |     - ''
2025-10-03 04:11:14.023896 | controller |     - '                  + readiness_gate (known after apply)'
2025-10-03 04:11:14.023909 | controller |     - '                }'
2025-10-03 04:11:14.023922 | controller |     - '            }'
2025-10-03 04:11:14.023935 | controller |     - '        }'
2025-10-03 04:11:14.023948 | controller |     - '    }'
2025-10-03 04:11:14.023961 | controller |     - ''
2025-10-03 04:11:14.023973 | controller |     - '  # kubernetes_namespace.tofu-tests will be created'
2025-10-03 04:11:14.023986 | controller |     - '  + resource "kubernetes_namespace" "tofu-tests" {'
2025-10-03 04:11:14.023999 | controller |     - '      + id                               = (known after apply)'
2025-10-03 04:11:14.024012 | controller |     - '      + wait_for_default_service_account = false'
2025-10-03 04:11:14.024029 | controller |     - ''
2025-10-03 04:11:14.024704 | controller |     - '      + metadata {'
2025-10-03 04:11:14.024771 | controller |     - '          + generation       = (known after apply)'
2025-10-03 04:11:14.024791 | controller |     - '          + name             = "tofu-experiements"'
2025-10-03 04:11:14.024889 | controller |     - '          + resource_version = (known after apply)'
2025-10-03 04:11:14.024900 | controller |     - '          + uid              = (known after apply)'
2025-10-03 04:11:14.024906 | controller |     - '        }'
2025-10-03 04:11:14.024912 | controller |     - '    }'
2025-10-03 04:11:14.024918 | controller |     - ''
2025-10-03 04:11:14.024924 | controller |     - 'Plan: 2 to add, 0 to change, 0 to destroy.'
2025-10-03 04:11:14.024930 | controller |   - 'Error:'
2025-10-03 04:11:14.024939 | controller |   - []
2025-10-03 04:11:14.024980 | controller |
2025-10-03 04:11:14.025075 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:11:14.025180 | controller | instance                   : ok=9    changed=6    unreachable=0    failed=0    skipped=3    rescued=0    ignored=0
2025-10-03 04:11:14.025253 | controller |
2025-10-03 04:11:14.025349 | controller | Friday 03 October 2025  04:11:14 +0000 (0:00:00.082)       0:00:14.354 ********
2025-10-03 04:11:14.025456 | controller | ===============================================================================
2025-10-03 04:11:14.025558 | controller | tofu : Install OpenTofu RPM --------------------------------------------- 6.65s
2025-10-03 04:11:14.025649 | controller | tofu : Tofu Action ------------------------------------------------------ 4.14s
2025-10-03 04:11:14.025741 | controller | Gathering Facts --------------------------------------------------------- 1.18s
2025-10-03 04:11:14.025859 | controller | tofu : Copy Local Tufo Project Directory To Remote Host '{{ ansible_host }}' --- 0.77s
2025-10-03 04:11:14.025932 | controller | tofu : Add OpenTofu repository ------------------------------------------ 0.45s
2025-10-03 04:11:14.026060 | controller | tofu : Create Tofu Directory -------------------------------------------- 0.40s
2025-10-03 04:11:14.026184 | controller | tofu : Return tofu to registered var ------------------------------------ 0.40s
2025-10-03 04:11:14.026315 | controller | tofu : Summarize Tofu Execution ----------------------------------------- 0.08s
2025-10-03 04:11:14.026428 | controller | Prepare Remote Environment If Required ---------------------------------- 0.06s
2025-10-03 04:11:14.026535 | controller | tofu : Fail If Tofu Project Path Not Defined ---------------------------- 0.06s
2025-10-03 04:11:14.026633 | controller | tofu : Fail If Check Mode Is Not Correct -------------------------------- 0.06s
2025-10-03 04:11:14.026730 | controller | tofu : Fail If infrastructure State Is Not Correct ---------------------- 0.04s
2025-10-03 04:11:14.026824 | controller | tofu : Update Remote Execution Variable --------------------------------- 0.04s
2025-10-03 04:11:14.079745 | controller | INFO     Running default > cleanup
2025-10-03 04:11:14.080145 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-03 04:11:14.080687 | controller | INFO     Writing /tmp/report.html report.
2025-10-03 04:11:14.225259 | controller | changed
2025-10-03 04:11:14.273140 | 
2025-10-03 04:11:14.273309 | PLAY RECAP
2025-10-03 04:11:14.273371 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:11:14.273399 | 
2025-10-03 04:11:14.369795 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:11:14.370579 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:11:14.948584 | 
2025-10-03 04:11:14.948707 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-03 04:11:14.970485 | 
2025-10-03 04:11:14.970575 | TASK [Filter out host if needed]
2025-10-03 04:11:14.979947 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-03 04:11:14.984819 | 
2025-10-03 04:11:14.984918 | TASK [Ensure file is present]
2025-10-03 04:11:15.577090 | controller | ok
2025-10-03 04:11:15.585766 | 
2025-10-03 04:11:15.585870 | TASK [Manage molecule report file]
2025-10-03 04:11:16.630277 | controller | changed
2025-10-03 04:11:16.637095 | 
2025-10-03 04:11:16.637179 | TASK [Check if we get ci-framework-data basedir]
2025-10-03 04:11:17.100997 | controller | ok
2025-10-03 04:11:17.113896 | 
2025-10-03 04:11:17.114031 | TASK [Create ci-framework-data log directory for zuul]
2025-10-03 04:11:17.684538 | controller | changed
2025-10-03 04:11:17.697397 | 
2025-10-03 04:11:17.697599 | TASK [Copy ci-framework interesting files]
2025-10-03 04:11:18.175557 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-03 04:11:18.248670 | controller | changed
2025-10-03 04:11:18.259941 | 
2025-10-03 04:11:18.260089 | TASK [Get SELinux listing]
2025-10-03 04:11:18.813820 | controller | changed
2025-10-03 04:11:18.826085 | 
2025-10-03 04:11:18.826229 | TASK [Generate log index]
2025-10-03 04:11:20.577074 | controller | changed
2025-10-03 04:11:20.589345 | 
2025-10-03 04:11:20.589478 | TASK [Get some env related data]
2025-10-03 04:11:21.353165 | controller | /home/zuul/.local/bin/ansible
2025-10-03 04:11:22.142979 | controller | changed
2025-10-03 04:11:22.154044 | 
2025-10-03 04:11:22.154172 | TASK [Generate list of logs to collect in home directory]
2025-10-03 04:11:22.598515 | controller | ok: All paths examined
2025-10-03 04:11:22.610898 | 
2025-10-03 04:11:22.611022 | LOOP [Copy logs from home directory]
2025-10-03 04:11:23.421895 | controller | changed:
2025-10-03 04:11:23.422414 | controller | {
2025-10-03 04:11:23.422480 | controller |   "atime": 1743544925.4788878,
2025-10-03 04:11:23.422524 | controller |   "ctime": 1743545329.1409318,
2025-10-03 04:11:23.422564 | controller |   "dev": 64513,
2025-10-03 04:11:23.422604 | controller |   "gid": 1000,
2025-10-03 04:11:23.422642 | controller |   "gr_name": "zuul",
2025-10-03 04:11:23.422734 | controller |   "inode": 4518807,
2025-10-03 04:11:23.422806 | controller |   "isblk": false,
2025-10-03 04:11:23.422857 | controller |   "ischr": false,
2025-10-03 04:11:23.422903 | controller |   "isdir": false,
2025-10-03 04:11:23.422949 | controller |   "isfifo": false,
2025-10-03 04:11:23.422995 | controller |   "isgid": false,
2025-10-03 04:11:23.423040 | controller |   "islnk": false,
2025-10-03 04:11:23.423085 | controller |   "isreg": true,
2025-10-03 04:11:23.423129 | controller |   "issock": false,
2025-10-03 04:11:23.423174 | controller |   "isuid": false,
2025-10-03 04:11:23.423218 | controller |   "mode": "0644",
2025-10-03 04:11:23.423263 | controller |   "mtime": 1743545329.1409318,
2025-10-03 04:11:23.423308 | controller |   "nlink": 1,
2025-10-03 04:11:23.423353 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-03 04:11:23.423405 | controller |   "pw_name": "zuul",
2025-10-03 04:11:23.423453 | controller |   "rgrp": true,
2025-10-03 04:11:23.423500 | controller |   "roth": true,
2025-10-03 04:11:23.423547 | controller |   "rusr": true,
2025-10-03 04:11:23.423594 | controller |   "size": 4108,
2025-10-03 04:11:23.423639 | controller |   "uid": 1000,
2025-10-03 04:11:23.423892 | controller |   "wgrp": false,
2025-10-03 04:11:23.423949 | controller |   "woth": false,
2025-10-03 04:11:23.423994 | controller |   "wusr": true,
2025-10-03 04:11:23.424034 | controller |   "xgrp": false,
2025-10-03 04:11:23.424073 | controller |   "xoth": false,
2025-10-03 04:11:23.424110 | controller |   "xusr": false
2025-10-03 04:11:23.424147 | controller | }
2025-10-03 04:11:24.381866 | controller | changed:
2025-10-03 04:11:24.382014 | controller | {
2025-10-03 04:11:24.382067 | controller |   "atime": 1743545331.0429637,
2025-10-03 04:11:24.382111 | controller |   "ctime": 1743545842.0111232,
2025-10-03 04:11:24.382152 | controller |   "dev": 64513,
2025-10-03 04:11:24.382221 | controller |   "gid": 1000,
2025-10-03 04:11:24.382263 | controller |   "gr_name": "zuul",
2025-10-03 04:11:24.382301 | controller |   "inode": 4194437,
2025-10-03 04:11:24.382339 | controller |   "isblk": false,
2025-10-03 04:11:24.382376 | controller |   "ischr": false,
2025-10-03 04:11:24.382412 | controller |   "isdir": false,
2025-10-03 04:11:24.382447 | controller |   "isfifo": false,
2025-10-03 04:11:24.382483 | controller |   "isgid": false,
2025-10-03 04:11:24.382518 | controller |   "islnk": false,
2025-10-03 04:11:24.382553 | controller |   "isreg": true,
2025-10-03 04:11:24.382591 | controller |   "issock": false,
2025-10-03 04:11:24.382627 | controller |   "isuid": false,
2025-10-03 04:11:24.382664 | controller |   "mode": "0644",
2025-10-03 04:11:24.382746 | controller |   "mtime": 1743545842.0111232,
2025-10-03 04:11:24.382803 | controller |   "nlink": 1,
2025-10-03 04:11:24.382854 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-03 04:11:24.382901 | controller |   "pw_name": "zuul",
2025-10-03 04:11:24.382951 | controller |   "rgrp": true,
2025-10-03 04:11:24.382999 | controller |   "roth": true,
2025-10-03 04:11:24.383046 | controller |   "rusr": true,
2025-10-03 04:11:24.383092 | controller |   "size": 4023,
2025-10-03 04:11:24.383138 | controller |   "uid": 1000,
2025-10-03 04:11:24.383183 | controller |   "wgrp": false,
2025-10-03 04:11:24.383230 | controller |   "woth": false,
2025-10-03 04:11:24.383277 | controller |   "wusr": true,
2025-10-03 04:11:24.383325 | controller |   "xgrp": false,
2025-10-03 04:11:24.383371 | controller |   "xoth": false,
2025-10-03 04:11:24.383417 | controller |   "xusr": false
2025-10-03 04:11:24.383465 | controller | }
2025-10-03 04:11:25.334447 | controller | changed:
2025-10-03 04:11:25.334596 | controller | {
2025-10-03 04:11:25.334659 | controller |   "atime": 1759464104.2114205,
2025-10-03 04:11:25.334738 | controller |   "ctime": 1759464120.3237777,
2025-10-03 04:11:25.334780 | controller |   "dev": 64513,
2025-10-03 04:11:25.334819 | controller |   "gid": 1000,
2025-10-03 04:11:25.334853 | controller |   "gr_name": "zuul",
2025-10-03 04:11:25.334887 | controller |   "inode": 4518815,
2025-10-03 04:11:25.334920 | controller |   "isblk": false,
2025-10-03 04:11:25.334954 | controller |   "ischr": false,
2025-10-03 04:11:25.334986 | controller |   "isdir": false,
2025-10-03 04:11:25.335019 | controller |   "isfifo": false,
2025-10-03 04:11:25.335052 | controller |   "isgid": false,
2025-10-03 04:11:25.335084 | controller |   "islnk": false,
2025-10-03 04:11:25.335116 | controller |   "isreg": true,
2025-10-03 04:11:25.335150 | controller |   "issock": false,
2025-10-03 04:11:25.335183 | controller |   "isuid": false,
2025-10-03 04:11:25.335216 | controller |   "mode": "0644",
2025-10-03 04:11:25.335250 | controller |   "mtime": 1759464120.3237777,
2025-10-03 04:11:25.335284 | controller |   "nlink": 1,
2025-10-03 04:11:25.335318 | controller |   "path": "/home/zuul/ansible.log",
2025-10-03 04:11:25.335353 | controller |   "pw_name": "zuul",
2025-10-03 04:11:25.335389 | controller |   "rgrp": true,
2025-10-03 04:11:25.335424 | controller |   "roth": true,
2025-10-03 04:11:25.335464 | controller |   "rusr": true,
2025-10-03 04:11:25.335500 | controller |   "size": 6701,
2025-10-03 04:11:25.335534 | controller |   "uid": 1000,
2025-10-03 04:11:25.335568 | controller |   "wgrp": false,
2025-10-03 04:11:25.335601 | controller |   "woth": false,
2025-10-03 04:11:25.335633 | controller |   "wusr": true,
2025-10-03 04:11:25.335665 | controller |   "xgrp": false,
2025-10-03 04:11:25.335733 | controller |   "xoth": false,
2025-10-03 04:11:25.335770 | controller |   "xusr": false
2025-10-03 04:11:25.335802 | controller | }
2025-10-03 04:11:25.358563 | 
2025-10-03 04:11:25.358739 | TASK [Copy crio stats log file]
2025-10-03 04:11:25.386385 | controller | skipping: Conditional result was False
2025-10-03 04:11:25.401296 | 
2025-10-03 04:11:25.401502 | TASK [Get SELinux related data]
2025-10-03 04:11:25.880227 | controller | <no matches>
2025-10-03 04:11:25.950928 | controller | ERROR
2025-10-03 04:11:25.951141 | controller | {
2025-10-03 04:11:25.951203 | controller |   "delta": "0:00:00.030157",
2025-10-03 04:11:25.951247 | controller |   "end": "2025-10-03 04:11:25.884344",
2025-10-03 04:11:25.951286 | controller |   "msg": "non-zero return code",
2025-10-03 04:11:25.951323 | controller |   "rc": 1,
2025-10-03 04:11:25.951515 | controller |   "start": "2025-10-03 04:11:25.854187"
2025-10-03 04:11:25.951561 | controller | }
2025-10-03 04:11:25.951618 | controller | ERROR: Ignoring Errors
2025-10-03 04:11:25.963442 | 
2025-10-03 04:11:25.963576 | TASK [Create system configuration directory]
2025-10-03 04:11:26.466441 | controller | changed
2025-10-03 04:11:26.474354 | 
2025-10-03 04:11:26.474453 | TASK [Get some of the system configurations]
2025-10-03 04:11:27.510624 | controller | changed
2025-10-03 04:11:27.526527 | 
2025-10-03 04:11:27.526739 | TASK [Copy generated documentation if available]
2025-10-03 04:11:27.553428 | controller | skipping: Conditional result was False
2025-10-03 04:11:27.566261 | 
2025-10-03 04:11:27.566395 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-03 04:11:27.593084 | controller | skipping: Conditional result was False
2025-10-03 04:11:27.606817 | 
2025-10-03 04:11:27.606967 | TASK [Compress logs bigger than 2MB]
2025-10-03 04:11:28.159111 | controller | changed
2025-10-03 04:11:28.167189 | 
2025-10-03 04:11:28.167281 | TASK [Copy files from workspace on node]
2025-10-03 04:11:28.189701 | controller | ok
2025-10-03 04:11:28.223997 | 
2025-10-03 04:11:28.224116 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:11:28.249649 | controller | skipping: Conditional result was False
2025-10-03 04:11:28.259901 | 
2025-10-03 04:11:28.260009 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:11:28.293419 | controller | ok
2025-10-03 04:11:28.301402 | 
2025-10-03 04:11:28.301492 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:11:28.562638 | controller -> localhost | ok: "/var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/logs"
2025-10-03 04:11:28.562915 | controller -> localhost | changed: All items complete
2025-10-03 04:11:28.562955 | 
2025-10-03 04:11:28.790657 | controller -> localhost | changed: "/var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/artifacts"
2025-10-03 04:11:29.045032 | controller -> localhost | changed: "/var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/docs"
2025-10-03 04:11:29.058503 | 
2025-10-03 04:11:29.058632 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:11:30.024667 | controller | changed:
2025-10-03 04:11:30.025146 | controller | .d..t...... ./
2025-10-03 04:11:30.025214 | controller | >f+++++++++ README.html
2025-10-03 04:11:30.025260 | controller | >f+++++++++ ansible-execution.log
2025-10-03 04:11:30.025303 | controller | >f+++++++++ ansible.log
2025-10-03 04:11:30.025342 | controller | >f+++++++++ crc-setup.log
2025-10-03 04:11:30.025381 | controller | >f+++++++++ crc-start.log
2025-10-03 04:11:30.025417 | controller | >f+++++++++ dmesg.log
2025-10-03 04:11:30.025458 | controller | >f+++++++++ installed-pkgs.log
2025-10-03 04:11:30.025495 | controller | >f+++++++++ python.log
2025-10-03 04:11:30.025532 | controller | >f+++++++++ registries.conf
2025-10-03 04:11:30.025569 | controller | >f+++++++++ report.html
2025-10-03 04:11:30.025606 | controller | >f+++++++++ selinux-denials.log
2025-10-03 04:11:30.025643 | controller | >f+++++++++ selinux-listing.log
2025-10-03 04:11:30.025712 | controller | cd+++++++++ ci-framework-data/
2025-10-03 04:11:30.025764 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-03 04:11:30.025804 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-03 04:11:30.025842 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-03 04:11:30.025879 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-03 04:11:30.025916 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-03 04:11:30.025953 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-03 04:11:30.025989 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-03 04:11:30.026026 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-03 04:11:30.026063 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-03 04:11:30.026101 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-03 04:11:30.026138 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-03 04:11:30.026175 | controller | cd+++++++++ registries.conf.d/
2025-10-03 04:11:30.026212 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-03 04:11:30.026248 | controller | cd+++++++++ system-config/
2025-10-03 04:11:30.026283 | controller | cd+++++++++ system-config/libvirt/
2025-10-03 04:11:30.026319 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-03 04:11:30.026356 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-03 04:11:30.026391 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-03 04:11:30.026427 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-03 04:11:30.026462 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-03 04:11:30.026497 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-03 04:11:30.026532 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-03 04:11:30.026567 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-03 04:11:30.026602 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-03 04:11:30.026637 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-03 04:11:30.026712 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-03 04:11:30.026761 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-03 04:11:30.026798 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-03 04:11:30.026834 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-03 04:11:30.026868 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-03 04:11:30.026903 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-03 04:11:30.725225 | controller | changed: .d..t...... ./
2025-10-03 04:11:31.423904 | controller | changed: .d..t...... ./
2025-10-03 04:11:31.459146 | 
2025-10-03 04:11:31.459336 | TASK [Return artifact to Zuul]
2025-10-03 04:11:31.518153 | controller | ok
2025-10-03 04:11:31.560026 | 
2025-10-03 04:11:31.560148 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-03 04:11:31.560271 | 
2025-10-03 04:11:31.560308 | PLAY RECAP
2025-10-03 04:11:31.560359 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-03 04:11:31.560386 | 
2025-10-03 04:11:31.683908 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:11:31.685575 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:11:32.245029 | 
2025-10-03 04:11:32.245139 | PLAY [all]
2025-10-03 04:11:32.264263 | 
2025-10-03 04:11:32.264347 | TASK [include_role : fetch-output]
2025-10-03 04:11:32.303921 | controller | ok
2025-10-03 04:11:32.322171 | 
2025-10-03 04:11:32.322260 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:11:32.397768 | controller | skipping: Conditional result was False
2025-10-03 04:11:32.411009 | 
2025-10-03 04:11:32.411164 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:11:32.460183 | controller | ok
2025-10-03 04:11:32.471855 | 
2025-10-03 04:11:32.471998 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:11:32.915559 | controller -> localhost | ok: "/var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/logs"
2025-10-03 04:11:33.205202 | controller -> localhost | ok: "/var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/artifacts"
2025-10-03 04:11:33.469570 | controller -> localhost | ok: "/var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/docs"
2025-10-03 04:11:33.487557 | 
2025-10-03 04:11:33.487673 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:11:34.363736 | controller | ok
2025-10-03 04:11:34.364074 | controller | ok: All items complete
2025-10-03 04:11:34.364131 | 
2025-10-03 04:11:35.091194 | controller | ok
2025-10-03 04:11:35.804116 | controller | ok
2025-10-03 04:11:35.839269 | 
2025-10-03 04:11:35.839468 | TASK [include_role : fetch-output-openshift]
2025-10-03 04:11:35.867304 | controller | skipping: Conditional result was False
2025-10-03 04:11:35.880432 | 
2025-10-03 04:11:35.880616 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-03 04:11:36.356810 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008560
2025-10-03 04:11:36.602460 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009117
2025-10-03 04:11:36.650201 | 
2025-10-03 04:11:36.650332 | PLAY [all]
2025-10-03 04:11:36.673387 | 
2025-10-03 04:11:36.673505 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-03 04:11:37.361641 | controller | changed
2025-10-03 04:11:37.410938 | 
2025-10-03 04:11:37.411023 | PLAY RECAP
2025-10-03 04:11:37.411077 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 04:11:37.411105 | 
2025-10-03 04:11:37.517158 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:11:37.517913 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-03 04:11:38.129057 | 
2025-10-03 04:11:38.129211 | PLAY [localhost]
2025-10-03 04:11:38.146719 | 
2025-10-03 04:11:38.146807 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-03 04:11:38.546333 | localhost | changed
2025-10-03 04:11:38.556856 | 
2025-10-03 04:11:38.557024 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-03 04:11:38.603793 | localhost | ok
2025-10-03 04:11:38.622451 | 
2025-10-03 04:11:38.622615 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-03 04:11:39.040184 | localhost | changed
2025-10-03 04:11:39.044908 | 
2025-10-03 04:11:39.044974 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-03 04:11:39.811466 | localhost | changed
2025-10-03 04:11:39.822163 | 
2025-10-03 04:11:39.822292 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-03 04:11:40.321762 | localhost | Identity added: /var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/tmp/ansible.bnl9mfvq (/var/lib/zuul/builds/9ea99462b46b4e60aaf56b9fe75751dc/work/tmp/ansible.bnl9mfvq)
2025-10-03 04:11:40.321987 | localhost | ok: Runtime: 0:00:00.012768
2025-10-03 04:11:40.326270 | 
2025-10-03 04:11:40.326336 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-03 04:11:40.659019 | localhost | ok: Runtime: 0:00:00.008379
2025-10-03 04:11:40.670422 | 
2025-10-03 04:11:40.670567 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-03 04:11:40.757180 | localhost | changed
2025-10-03 04:11:40.766359 | 
2025-10-03 04:11:40.766491 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-03 04:11:41.193293 | localhost | changed
2025-10-03 04:11:41.213347 | 
2025-10-03 04:11:41.213406 | PLAY [localhost]
2025-10-03 04:11:41.225573 | 
2025-10-03 04:11:41.225666 | TASK [Generate bulk log download script]
2025-10-03 04:11:41.244992 | localhost | ok
2025-10-03 04:11:41.257287 | 
2025-10-03 04:11:41.257368 | TASK [local-log-download : Check API endpoint is defined]
2025-10-03 04:11:41.298541 | localhost | ok: All assertions passed
2025-10-03 04:11:41.304783 | 
2025-10-03 04:11:41.304867 | TASK [local-log-download : Create download script]
2025-10-03 04:11:41.777587 | localhost -> localhost | changed
2025-10-03 04:11:41.800024 | 
2025-10-03 04:11:41.800220 | TASK [Register quick-download link]
2025-10-03 04:11:41.871265 | localhost | ok
2025-10-03 04:11:41.892357 | 
2025-10-03 04:11:41.892447 | PLAY [logserver.rdoproject.org]
2025-10-03 04:11:41.905264 | 
2025-10-03 04:11:41.905344 | TASK [Set zuul-log-path fact]
2025-10-03 04:11:41.923586 | logserver.rdoproject.org | ok
2025-10-03 04:11:41.936075 | 
2025-10-03 04:11:41.936169 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 04:11:41.973839 | logserver.rdoproject.org | ok
2025-10-03 04:11:41.980954 | 
2025-10-03 04:11:41.981038 | TASK [upload-logs : Create log directories]
2025-10-03 04:11:42.693787 | logserver.rdoproject.org | changed
2025-10-03 04:11:42.700402 | 
2025-10-03 04:11:42.700540 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-03 04:11:43.033129 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008963
2025-10-03 04:11:43.042798 | 
2025-10-03 04:11:43.042937 | TASK [upload-logs : Upload logs to log server]
2025-10-03 04:11:43.756268 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-03 04:11:43.762346 | 
2025-10-03 04:11:43.762476 | LOOP [upload-logs : Compress console log and json output]
2025-10-03 04:11:43.832751 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:11:43.845371 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:11:43.856387 | 
2025-10-03 04:11:43.856536 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-03 04:11:43.905386 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:11:43.905900 | 
2025-10-03 04:11:43.908402 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:11:43.917128 | 
2025-10-03 04:11:43.917240 | LOOP [upload-logs : Upload console log and json output]