2025-10-02 16:38:13.853635 | Job console starting...
2025-10-02 16:38:13.866889 | Updating repositories
2025-10-02 16:38:13.894332 | Preparing job workspace
2025-10-02 16:38:18.868693 | Running Ansible setup...
2025-10-02 16:38:24.526111 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 16:38:25.172356 | 
2025-10-02 16:38:25.172482 | PLAY [localhost]
2025-10-02 16:38:25.184553 | 
2025-10-02 16:38:25.184667 | TASK [Gathering Facts]
2025-10-02 16:38:26.314534 | localhost | ok
2025-10-02 16:38:26.342342 | 
2025-10-02 16:38:26.342489 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 16:38:26.766087 | localhost -> localhost | changed
2025-10-02 16:38:26.771556 | 
2025-10-02 16:38:26.771656 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 16:38:27.619142 | localhost -> localhost | changed
2025-10-02 16:38:27.627559 | 
2025-10-02 16:38:27.627650 | TASK [Setup log path fact]
2025-10-02 16:38:27.649655 | localhost | ok
2025-10-02 16:38:27.661585 | 
2025-10-02 16:38:27.661686 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 16:38:27.690312 | localhost | ok
2025-10-02 16:38:27.699975 | 
2025-10-02 16:38:27.700155 | TASK [emit-job-header : Print job information]
2025-10-02 16:38:27.784847 | # Job Information
2025-10-02 16:38:27.784996 | Ansible Version: 2.15.12
2025-10-02 16:38:27.785041 | Job: cifmw-molecule-tofu
2025-10-02 16:38:27.785064 | Pipeline: github-check
2025-10-02 16:38:27.785082 | Executor: ze01.softwarefactory-project.io
2025-10-02 16:38:27.785100 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 16:38:27.785119 | Log URL (when completed): https://logserver.rdoproject.org/66f/rdoproject.org/66f5cb1829354aba8d42ef077c33dbfb/
2025-10-02 16:38:27.785137 | Event ID: 0b5c9c70-9fae-11f0-9df5-29799d33dc04
2025-10-02 16:38:27.789819 | 
2025-10-02 16:38:27.789921 | LOOP [emit-job-header : Print node information]
2025-10-02 16:38:27.884991 | localhost | ok:
2025-10-02 16:38:27.885198 | localhost | # Node Information
2025-10-02 16:38:27.885226 | localhost | Inventory Hostname: controller
2025-10-02 16:38:27.885249 | localhost | Hostname: np0005466904
2025-10-02 16:38:27.885269 | localhost | Username: zuul
2025-10-02 16:38:27.885289 | localhost | Distro: CentOS 9
2025-10-02 16:38:27.885306 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-02 16:38:27.885322 | localhost | Region: RegionOne
2025-10-02 16:38:27.885339 | localhost | Label: centos-9-stream-crc-2-48-0-xl
2025-10-02 16:38:27.885355 | localhost | Product Name: OpenStack Nova
2025-10-02 16:38:27.885371 | localhost | Interface IP: 38.102.83.198
2025-10-02 16:38:27.906277 | 
2025-10-02 16:38:27.906392 | PLAY [all]
2025-10-02 16:38:27.914670 | 
2025-10-02 16:38:27.914822 | TASK [Gather network facts]
2025-10-02 16:38:28.512278 | controller | ok
2025-10-02 16:38:28.535980 | 
2025-10-02 16:38:28.536157 | TASK [include_role : start-zuul-console]
2025-10-02 16:38:28.558210 | controller | ok
2025-10-02 16:38:28.576144 | 
2025-10-02 16:38:28.576247 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 16:38:29.122229 | controller | ok
2025-10-02 16:38:29.131674 | 
2025-10-02 16:38:29.131773 | TASK [include_role : add-build-sshkey]
2025-10-02 16:38:29.181945 | controller | ok
2025-10-02 16:38:29.201461 | 
2025-10-02 16:38:29.201603 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 16:38:29.503477 | controller -> localhost | ok
2025-10-02 16:38:29.509147 | 
2025-10-02 16:38:29.509258 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 16:38:29.529555 | controller | ok
2025-10-02 16:38:29.544331 | controller | included: /var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 16:38:29.550433 | 
2025-10-02 16:38:29.550509 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 16:38:30.194903 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 16:38:30.195145 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/66f5cb1829354aba8d42ef077c33dbfb_id_rsa.
2025-10-02 16:38:30.195185 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/66f5cb1829354aba8d42ef077c33dbfb_id_rsa.pub.
2025-10-02 16:38:30.195219 | controller -> localhost | The key fingerprint is:
2025-10-02 16:38:30.195249 | controller -> localhost | SHA256:jCMOBZqq7E2Ij4IJSXAVXZ2bkG9YyMk/kgvJO5nimVs zuul-build-sshkey
2025-10-02 16:38:30.195279 | controller -> localhost | The key's randomart image is:
2025-10-02 16:38:30.195310 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 16:38:30.195339 | controller -> localhost | |  ..oo +.= .     |
2025-10-02 16:38:30.195370 | controller -> localhost | |.o..  . B +      |
2025-10-02 16:38:30.195393 | controller -> localhost | |+.  .. . B o     |
2025-10-02 16:38:30.195413 | controller -> localhost | |.. .  +o+ B      |
2025-10-02 16:38:30.195431 | controller -> localhost | |o.. . o=S+ .     |
2025-10-02 16:38:30.195449 | controller -> localhost | |* .o..=..        |
2025-10-02 16:38:30.195467 | controller -> localhost | |=+ o.+E.         |
2025-10-02 16:38:30.195487 | controller -> localhost | |=oo +.           |
2025-10-02 16:38:30.195505 | controller -> localhost | |o.....           |
2025-10-02 16:38:30.195522 | controller -> localhost | +----[SHA256]-----+
2025-10-02 16:38:30.195564 | controller -> localhost | ok: Runtime: 0:00:00.178935
2025-10-02 16:38:30.201429 | 
2025-10-02 16:38:30.201506 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 16:38:30.230521 | controller | ok
2025-10-02 16:38:30.245623 | controller | included: /var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 16:38:30.266423 | 
2025-10-02 16:38:30.266543 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 16:38:30.292350 | controller | skipping: Conditional result was False
2025-10-02 16:38:30.302590 | 
2025-10-02 16:38:30.302732 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 16:38:30.994064 | controller | changed
2025-10-02 16:38:31.008459 | 
2025-10-02 16:38:31.008561 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 16:38:31.449434 | controller | ok
2025-10-02 16:38:31.457243 | 
2025-10-02 16:38:31.457368 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 16:38:33.532008 | controller | changed
2025-10-02 16:38:33.537061 | 
2025-10-02 16:38:33.537141 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 16:38:35.455504 | controller | changed
2025-10-02 16:38:35.468860 | 
2025-10-02 16:38:35.468977 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 16:38:35.493073 | controller | skipping: Conditional result was False
2025-10-02 16:38:35.498422 | 
2025-10-02 16:38:35.498485 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 16:38:35.959937 | controller -> localhost | changed
2025-10-02 16:38:35.976448 | 
2025-10-02 16:38:35.976591 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 16:38:36.261300 | controller -> localhost | Identity added: /var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/66f5cb1829354aba8d42ef077c33dbfb_id_rsa (zuul-build-sshkey)
2025-10-02 16:38:36.261570 | controller -> localhost | ok: Runtime: 0:00:00.010341
2025-10-02 16:38:36.267394 | 
2025-10-02 16:38:36.267457 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 16:38:36.767225 | controller | ok
2025-10-02 16:38:36.773322 | 
2025-10-02 16:38:36.773396 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 16:38:36.797769 | controller | skipping: Conditional result was False
2025-10-02 16:38:36.841731 | 
2025-10-02 16:38:36.841874 | TASK [include_role : validate-host]
2025-10-02 16:38:36.876786 | controller | ok
2025-10-02 16:38:36.902552 | 
2025-10-02 16:38:36.902651 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 16:38:36.932104 | controller | ok
2025-10-02 16:38:36.938379 | 
2025-10-02 16:38:36.938478 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 16:38:37.167324 | controller -> localhost | ok
2025-10-02 16:38:37.173947 | 
2025-10-02 16:38:37.174033 | TASK [validate-host : Collect information about the host]
2025-10-02 16:38:38.133200 | controller | ok
2025-10-02 16:38:38.148345 | 
2025-10-02 16:38:38.148657 | TASK [validate-host : Sanitize hostname]
2025-10-02 16:38:38.209294 | controller | ok
2025-10-02 16:38:38.217511 | 
2025-10-02 16:38:38.217623 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 16:38:38.724996 | controller -> localhost | changed
2025-10-02 16:38:38.730760 | 
2025-10-02 16:38:38.730870 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 16:38:39.306308 | controller | ok
2025-10-02 16:38:39.311464 | 
2025-10-02 16:38:39.311553 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 16:38:39.797622 | controller -> localhost | changed
2025-10-02 16:38:39.811622 | 
2025-10-02 16:38:39.811737 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 16:38:39.846871 | controller | skipping: Conditional result was False
2025-10-02 16:38:39.859102 | 
2025-10-02 16:38:39.859223 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 16:38:39.897761 | controller | skipping: Conditional result was False
2025-10-02 16:38:39.905953 | 
2025-10-02 16:38:39.906108 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 16:38:40.298646 | controller | ok: "logs"
2025-10-02 16:38:40.298965 | controller | ok: All items complete
2025-10-02 16:38:40.299008 | 
2025-10-02 16:38:40.655761 | controller | ok: "artifacts"
2025-10-02 16:38:41.018303 | controller | ok: "docs"
2025-10-02 16:38:41.030252 | 
2025-10-02 16:38:41.030360 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 16:38:41.393860 | controller | changed: "logs"
2025-10-02 16:38:41.768936 | controller | changed: "artifacts"
2025-10-02 16:38:42.102350 | controller | changed: "docs"
2025-10-02 16:38:42.143522 | 
2025-10-02 16:38:42.143635 | PLAY RECAP
2025-10-02 16:38:42.143690 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 16:38:42.143724 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 16:38:42.143749 | 
2025-10-02 16:38:42.270776 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 16:38:42.272130 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 16:38:42.888948 | 
2025-10-02 16:38:42.889085 | PLAY [all]
2025-10-02 16:38:42.909490 | 
2025-10-02 16:38:42.909589 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 16:38:42.959525 | controller | ok
2025-10-02 16:38:42.964858 | 
2025-10-02 16:38:42.964955 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 16:38:43.555672 | controller | changed
2025-10-02 16:38:43.564086 | 
2025-10-02 16:38:43.564229 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 16:38:45.850668 | controller | changed
2025-10-02 16:38:45.861438 | 
2025-10-02 16:38:45.861552 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-02 16:38:46.521964 | controller | changed:
2025-10-02 16:38:46.522179 | controller | {
2025-10-02 16:38:46.522214 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-02 16:38:46.522246 | controller | }
2025-10-02 16:38:46.956953 | controller | changed:
2025-10-02 16:38:46.957087 | controller | {
2025-10-02 16:38:46.957121 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-02 16:38:46.957143 | controller | }
2025-10-02 16:38:47.392009 | controller | changed:
2025-10-02 16:38:47.392131 | controller | {
2025-10-02 16:38:47.392157 | 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-02 16:38:47.392179 | controller | }
2025-10-02 16:38:47.807945 | controller | changed:
2025-10-02 16:38:47.808066 | controller | {
2025-10-02 16:38:47.808092 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-02 16:38:47.808111 | controller | }
2025-10-02 16:38:48.216103 | controller | changed:
2025-10-02 16:38:48.216424 | controller | {
2025-10-02 16:38:48.216454 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-02 16:38:48.216474 | controller | }
2025-10-02 16:38:48.638496 | controller | changed:
2025-10-02 16:38:48.638609 | controller | {
2025-10-02 16:38:48.638634 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-02 16:38:48.638654 | controller | }
2025-10-02 16:38:49.167911 | controller | changed:
2025-10-02 16:38:49.168034 | controller | {
2025-10-02 16:38:49.168063 | 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-02 16:38:49.168084 | controller | }
2025-10-02 16:38:49.559410 | controller | changed:
2025-10-02 16:38:49.559522 | controller | {
2025-10-02 16:38:49.559549 | 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-02 16:38:49.559569 | controller | }
2025-10-02 16:38:50.016183 | controller | changed:
2025-10-02 16:38:50.016338 | controller | {
2025-10-02 16:38:50.016393 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-02 16:38:50.016436 | controller | }
2025-10-02 16:38:50.432265 | controller | changed:
2025-10-02 16:38:50.432402 | controller | {
2025-10-02 16:38:50.432444 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-02 16:38:50.432471 | controller | }
2025-10-02 16:38:50.882007 | controller | changed:
2025-10-02 16:38:50.882151 | controller | {
2025-10-02 16:38:50.882187 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-02 16:38:50.882215 | controller | }
2025-10-02 16:38:51.337590 | controller | changed:
2025-10-02 16:38:51.337960 | controller | {
2025-10-02 16:38:51.338083 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-02 16:38:51.338137 | controller | }
2025-10-02 16:38:51.751117 | controller | changed:
2025-10-02 16:38:51.751215 | controller | {
2025-10-02 16:38:51.751240 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-02 16:38:51.751260 | controller | }
2025-10-02 16:38:52.216263 | controller | changed:
2025-10-02 16:38:52.216388 | controller | {
2025-10-02 16:38:52.216423 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-02 16:38:52.216451 | controller | }
2025-10-02 16:38:52.655418 | controller | changed:
2025-10-02 16:38:52.655534 | controller | {
2025-10-02 16:38:52.655567 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-02 16:38:52.655595 | controller | }
2025-10-02 16:38:53.042971 | controller | changed:
2025-10-02 16:38:53.043343 | controller | {
2025-10-02 16:38:53.043409 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-02 16:38:53.043452 | controller | }
2025-10-02 16:38:53.487446 | controller | changed:
2025-10-02 16:38:53.487605 | controller | {
2025-10-02 16:38:53.487667 | 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-02 16:38:53.487719 | controller | }
2025-10-02 16:38:53.951801 | controller | changed:
2025-10-02 16:38:53.951920 | controller | {
2025-10-02 16:38:53.951959 | 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-02 16:38:53.951992 | controller | }
2025-10-02 16:38:54.395711 | controller | changed:
2025-10-02 16:38:54.395786 | controller | {
2025-10-02 16:38:54.395810 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-02 16:38:54.395828 | controller | }
2025-10-02 16:38:54.836103 | controller | changed:
2025-10-02 16:38:54.836214 | controller | {
2025-10-02 16:38:54.836249 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-02 16:38:54.836323 | controller | }
2025-10-02 16:38:55.307665 | controller | changed:
2025-10-02 16:38:55.308114 | controller | {
2025-10-02 16:38:55.308205 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-02 16:38:55.308258 | controller | }
2025-10-02 16:38:55.733191 | controller | changed:
2025-10-02 16:38:55.733290 | controller | {
2025-10-02 16:38:55.733315 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-02 16:38:55.733335 | controller | }
2025-10-02 16:38:56.191951 | controller | changed:
2025-10-02 16:38:56.192066 | controller | {
2025-10-02 16:38:56.192093 | 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-02 16:38:56.192113 | controller | }
2025-10-02 16:38:56.654426 | controller | changed:
2025-10-02 16:38:56.654523 | controller | {
2025-10-02 16:38:56.654548 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-02 16:38:56.654569 | controller | }
2025-10-02 16:38:57.113692 | controller | changed:
2025-10-02 16:38:57.113842 | controller | {
2025-10-02 16:38:57.113869 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-02 16:38:57.113890 | controller | }
2025-10-02 16:38:57.552134 | controller | changed:
2025-10-02 16:38:57.552240 | controller | {
2025-10-02 16:38:57.552262 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-02 16:38:57.552281 | controller | }
2025-10-02 16:38:57.581786 | 
2025-10-02 16:38:57.581994 | TASK [Set timezone to UTC]
2025-10-02 16:38:58.437217 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-02 16:38:58.445763 | 
2025-10-02 16:38:58.445903 | TASK [Create nodepool directory]
2025-10-02 16:38:58.953892 | controller | changed
2025-10-02 16:38:58.969964 | 
2025-10-02 16:38:58.970123 | TASK [Create nodepool sub_nodes file]
2025-10-02 16:39:00.772874 | controller | changed
2025-10-02 16:39:00.778692 | 
2025-10-02 16:39:00.778773 | TASK [Create nodepool sub_nodes_private file]
2025-10-02 16:39:02.611268 | controller | changed
2025-10-02 16:39:02.617244 | 
2025-10-02 16:39:02.617325 | LOOP [Populate nodepool sub_nodes file]
2025-10-02 16:39:02.654405 | 
2025-10-02 16:39:02.654628 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-02 16:39:02.687219 | 
2025-10-02 16:39:02.687436 | TASK [Create nodepool primary file]
2025-10-02 16:39:02.713845 | controller | skipping: Conditional result was False
2025-10-02 16:39:02.719671 | 
2025-10-02 16:39:02.719791 | TASK [Create nodepool node_private for this node]
2025-10-02 16:39:04.621650 | controller | changed
2025-10-02 16:39:04.634009 | 
2025-10-02 16:39:04.634152 | LOOP [Copy ssh keys to nodepool directory]
2025-10-02 16:39:05.238687 | controller | ok: Item: id_rsa Runtime: 0:00:00.011142
2025-10-02 16:39:05.238918 | 
2025-10-02 16:39:05.600277 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.008361
2025-10-02 16:39:05.616943 | 
2025-10-02 16:39:05.617074 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-02 16:39:07.445673 | controller | changed
2025-10-02 16:39:07.453951 | 
2025-10-02 16:39:07.454072 | TASK [Validate sudoers config after edits]
2025-10-02 16:39:07.940184 | controller | /etc/sudoers: parsed OK
2025-10-02 16:39:07.940455 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-02 16:39:07.940483 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-02 16:39:07.940550 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-02 16:39:08.044586 | controller | ok: Runtime: 0:00:00.009627
2025-10-02 16:39:08.051559 | 
2025-10-02 16:39:08.051668 | TASK [Show the environment passed in to job shell scripts]
2025-10-02 16:39:08.461016 | controller | SHELL=/bin/bash
2025-10-02 16:39:08.461086 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-02 16:39:08.461105 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-02 16:39:08.461119 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 16:39:08.461133 | controller | PWD=/home/zuul
2025-10-02 16:39:08.461146 | controller | ZUUL_PIPELINE=github-check
2025-10-02 16:39:08.461160 | controller | LOGNAME=zuul
2025-10-02 16:39:08.461173 | controller | XDG_SESSION_TYPE=tty
2025-10-02 16:39:08.461186 | controller | _=/usr/bin/env
2025-10-02 16:39:08.461199 | controller | MOTD_SHOWN=pam
2025-10-02 16:39:08.461212 | controller | HOME=/home/zuul
2025-10-02 16:39:08.461259 | controller | LANG=en_US.UTF-8
2025-10-02 16:39:08.461300 | controller | SSH_CONNECTION=38.102.83.114 47838 38.102.83.198 22
2025-10-02 16:39:08.461315 | 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-02 16:39:08.461333 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 16:39:08.461347 | controller | WORKSPACE=/home/zuul/workspace
2025-10-02 16:39:08.461360 | controller | XDG_SESSION_CLASS=user
2025-10-02 16:39:08.461373 | controller | SELINUX_ROLE_REQUESTED=
2025-10-02 16:39:08.461386 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-02 16:39:08.461399 | controller | USER=zuul
2025-10-02 16:39:08.461438 | controller | ZUUL_VOTING=True
2025-10-02 16:39:08.461466 | controller | BUILD_TIMEOUT=1800000
2025-10-02 16:39:08.461483 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-02 16:39:08.461496 | controller | SHLVL=1
2025-10-02 16:39:08.461509 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 16:39:08.461523 | controller | XDG_SESSION_ID=1
2025-10-02 16:39:08.461538 | controller | ZUUL_BRANCH=main
2025-10-02 16:39:08.461551 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-02 16:39:08.461564 | controller | SSH_CLIENT=38.102.83.114 47838 22
2025-10-02 16:39:08.461581 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-02 16:39:08.461594 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-02 16:39:08.461608 | controller | which_declare=declare -f
2025-10-02 16:39:08.461623 | 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-02 16:39:08.461636 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-02 16:39:08.461649 | controller | ZUUL_CHANGE=3362
2025-10-02 16:39:08.461662 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-02 16:39:08.461675 | controller | ZUUL_UUID=66f5cb1829354aba8d42ef077c33dbfb
2025-10-02 16:39:08.461688 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-02 16:39:08.461701 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-02 16:39:08.461714 | controller | }
2025-10-02 16:39:08.595156 | controller | ok: Runtime: 0:00:00.010590
2025-10-02 16:39:08.606989 | 
2025-10-02 16:39:08.607134 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-02 16:39:08.632763 | controller | skipping: Conditional result was False
2025-10-02 16:39:08.638960 | 
2025-10-02 16:39:08.639089 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-02 16:39:09.199139 | controller | skipping: Conditional result was False
2025-10-02 16:39:09.211394 | 
2025-10-02 16:39:09.211549 | TASK [Ensure legacy workspace directory]
2025-10-02 16:39:09.644704 | controller | changed
2025-10-02 16:39:09.683065 | 
2025-10-02 16:39:09.683200 | PLAY RECAP
2025-10-02 16:39:09.683268 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 16:39:09.683302 | 
2025-10-02 16:39:09.813399 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 16:39:09.815716 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 16:39:10.538270 | 
2025-10-02 16:39:10.538399 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-02 16:39:10.584720 | 
2025-10-02 16:39:10.584859 | TASK [Create zuul-output directory]
2025-10-02 16:39:11.086693 | controller | changed
2025-10-02 16:39:11.094998 | 
2025-10-02 16:39:11.095202 | TASK [Slurp Zuul inventory test]
2025-10-02 16:39:11.640370 | controller -> localhost | ok
2025-10-02 16:39:11.649428 | 
2025-10-02 16:39:11.649567 | TASK [Save zuul inventory]
2025-10-02 16:39:13.590703 | controller | changed
2025-10-02 16:39:13.596781 | 
2025-10-02 16:39:13.596858 | TASK [Save zuul vars without the change_message]
2025-10-02 16:39:15.330360 | controller | changed
2025-10-02 16:39:15.352461 | 
2025-10-02 16:39:15.352608 | PLAY RECAP
2025-10-02 16:39:15.352653 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 16:39:15.352674 | 
2025-10-02 16:39:15.467500 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 16:39:15.469104 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 16:39:16.159620 | 
2025-10-02 16:39:16.159747 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-02 16:39:16.183885 | 
2025-10-02 16:39:16.184080 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-02 16:39:16.214975 | controller | ok
2025-10-02 16:39:16.234350 | 
2025-10-02 16:39:16.234494 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 16:39:16.268975 | controller | skipping: Conditional result was False
2025-10-02 16:39:16.277858 | 
2025-10-02 16:39:16.278024 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 16:39:16.893583 | controller | ok
2025-10-02 16:39:16.902941 | 
2025-10-02 16:39:16.903125 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 16:39:18.480919 | controller | ok
2025-10-02 16:39:18.491065 | 
2025-10-02 16:39:18.491153 | TASK [Prepare workspace]
2025-10-02 16:39:18.510804 | controller | ok
2025-10-02 16:39:18.530482 | 
2025-10-02 16:39:18.530589 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 16:39:19.101305 | controller | ok
2025-10-02 16:39:19.112957 | 
2025-10-02 16:39:19.113217 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 16:39:22.555623 | controller | Output suppressed because no_log was given
2025-10-02 16:39:22.579208 | 
2025-10-02 16:39:22.579400 | LOOP [Create zuul-output directory]
2025-10-02 16:39:22.982106 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-02 16:39:23.358018 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-02 16:39:23.378528 | 
2025-10-02 16:39:23.378867 | TASK [Install required packages]
2025-10-02 16:39:38.560297 | controller | ok: Nothing to do
2025-10-02 16:39:38.568684 | 
2025-10-02 16:39:38.568797 | TASK [Install venv]
2025-10-02 16:40:41.693062 | controller | changed
2025-10-02 16:40:41.720853 | 
2025-10-02 16:40:41.720954 | PLAY RECAP
2025-10-02 16:40:41.720996 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 16:40:41.721041 | 
2025-10-02 16:40:41.826800 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 16:40:41.828966 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 16:40:42.544523 | 
2025-10-02 16:40:42.544650 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-02 16:40:42.566731 | 
2025-10-02 16:40:42.566854 | TASK [Gather required facts]
2025-10-02 16:40:43.361412 | controller | ok
2025-10-02 16:40:43.375299 | 
2025-10-02 16:40:43.375502 | TASK [Load environment var if instructed to]
2025-10-02 16:40:43.413785 | controller | skipping: Conditional result was False
2025-10-02 16:40:43.423701 | 
2025-10-02 16:40:43.423837 | TASK [Run molecule]
2025-10-02 16:40:44.733046 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-02 16:40:44.809234 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-02 16:41:01.958510 | 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-02 16:41:01.959595 | 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-02 16:41:01.960621 | 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-02 16:41:01.961637 | 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-02 16:41:01.962740 | 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-02 16:41:01.963844 | 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-02 16:41:01.964828 | 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-02 16:41:01.965836 | 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-02 16:41:01.966805 | 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-02 16:41:01.967764 | 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-02 16:41:01.968736 | 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-02 16:41:01.969725 | 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-02 16:41:01.970691 | 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-02 16:41:01.971667 | 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-02 16:41:01.972627 | 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-02 16:41:01.993271 | controller | INFO     Running default > prepare
2025-10-02 16:41:02.746288 | controller |
2025-10-02 16:41:02.746600 | controller | PLAY [Prepare] *****************************************************************
2025-10-02 16:41:02.746889 | controller |
2025-10-02 16:41:02.747124 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 16:41:02.747394 | controller | Thursday 02 October 2025  16:41:02 +0000 (0:00:00.020)       0:00:00.020 ******
2025-10-02 16:41:04.913123 | controller | ok: [instance]
2025-10-02 16:41:04.913279 | controller |
2025-10-02 16:41:04.913544 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-02 16:41:04.913762 | controller | Thursday 02 October 2025  16:41:04 +0000 (0:00:02.167)       0:00:02.187 ******
2025-10-02 16:41:04.947190 | controller | skipping: [instance]
2025-10-02 16:41:04.947346 | controller |
2025-10-02 16:41:04.947601 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-02 16:41:04.947794 | controller | Thursday 02 October 2025  16:41:04 +0000 (0:00:00.034)       0:00:02.221 ******
2025-10-02 16:41:05.027242 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-02 16:41:05.027409 | controller |
2025-10-02 16:41:05.027673 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-02 16:41:05.027893 | controller | Thursday 02 October 2025  16:41:05 +0000 (0:00:00.079)       0:00:02.301 ******
2025-10-02 16:41:05.440589 | controller | ok: [instance]
2025-10-02 16:41:05.440707 | controller |
2025-10-02 16:41:05.440930 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-02 16:41:05.441159 | controller | Thursday 02 October 2025  16:41:05 +0000 (0:00:00.413)       0:00:02.715 ******
2025-10-02 16:41:05.876463 | controller | ok: [instance]
2025-10-02 16:41:05.876598 | controller |
2025-10-02 16:41:05.876814 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-02 16:41:05.877028 | controller | Thursday 02 October 2025  16:41:05 +0000 (0:00:00.435)       0:00:03.150 ******
2025-10-02 16:41:06.955687 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-02 16:41:06.955935 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-02 16:41:06.956162 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-02 16:41:06.956360 | controller |
2025-10-02 16:41:06.956640 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-02 16:41:06.956858 | controller | Thursday 02 October 2025  16:41:06 +0000 (0:00:01.079)       0:00:04.229 ******
2025-10-02 16:41:07.055486 | controller |
2025-10-02 16:41:07.055699 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-02 16:41:07.055942 | controller | Thursday 02 October 2025  16:41:07 +0000 (0:00:00.100)       0:00:04.330 ******
2025-10-02 16:41:07.872199 | controller | changed: [instance] => (item=tmp)
2025-10-02 16:41:07.872342 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-02 16:41:07.872570 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-02 16:41:07.872746 | controller |
2025-10-02 16:41:07.872941 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-02 16:41:07.873139 | controller | Thursday 02 October 2025  16:41:07 +0000 (0:00:00.816)       0:00:05.146 ******
2025-10-02 16:41:08.904283 | controller | ok: [instance]
2025-10-02 16:41:08.905183 | controller |
2025-10-02 16:41:10.204618 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-02 16:41:10.204673 | controller | Thursday 02 October 2025  16:41:08 +0000 (0:00:01.031)       0:00:06.178 ******
2025-10-02 16:41:10.204698 | controller | changed: [instance]
2025-10-02 16:41:10.204752 | controller |
2025-10-02 16:41:10.204782 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-02 16:41:10.205021 | controller | Thursday 02 October 2025  16:41:10 +0000 (0:00:01.299)       0:00:07.477 ******
2025-10-02 16:41:18.226593 | controller | changed: [instance]
2025-10-02 16:41:19.156557 | controller |
2025-10-02 16:41:19.156600 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-02 16:41:19.156608 | controller | Thursday 02 October 2025  16:41:18 +0000 (0:00:08.022)       0:00:15.500 ******
2025-10-02 16:41:19.156618 | controller | changed: [instance]
2025-10-02 16:41:19.156648 | controller |
2025-10-02 16:41:19.157100 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-02 16:41:19.195528 | controller | Thursday 02 October 2025  16:41:19 +0000 (0:00:00.930)       0:00:16.431 ******
2025-10-02 16:41:19.195588 | controller | skipping: [instance]
2025-10-02 16:41:20.009212 | controller |
2025-10-02 16:41:20.009243 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-02 16:41:20.009251 | controller | Thursday 02 October 2025  16:41:19 +0000 (0:00:00.038)       0:00:16.469 ******
2025-10-02 16:41:20.009261 | controller | changed: [instance]
2025-10-02 16:41:20.083727 | controller |
2025-10-02 16:41:20.083812 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-02 16:41:20.083833 | controller | Thursday 02 October 2025  16:41:20 +0000 (0:00:00.813)       0:00:17.283 ******
2025-10-02 16:41:20.083864 | controller | skipping: [instance]
2025-10-02 16:41:20.148496 | controller |
2025-10-02 16:41:20.148548 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-02 16:41:20.148568 | controller | Thursday 02 October 2025  16:41:20 +0000 (0:00:00.074)       0:00:17.357 ******
2025-10-02 16:41:20.148593 | controller | skipping: [instance]
2025-10-02 16:41:20.149669 | controller |
2025-10-02 16:41:20.149746 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-02 16:41:20.205685 | controller | Thursday 02 October 2025  16:41:20 +0000 (0:00:00.064)       0:00:17.422 ******
2025-10-02 16:41:20.205757 | controller | skipping: [instance]
2025-10-02 16:41:20.205816 | controller |
2025-10-02 16:41:20.205838 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-02 16:41:20.205972 | controller | Thursday 02 October 2025  16:41:20 +0000 (0:00:00.057)       0:00:17.480 ******
2025-10-02 16:41:20.757116 | controller | changed: [instance]
2025-10-02 16:41:21.404470 | controller |
2025-10-02 16:41:21.404506 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-02 16:41:21.404517 | controller | Thursday 02 October 2025  16:41:20 +0000 (0:00:00.551)       0:00:18.031 ******
2025-10-02 16:41:21.404531 | controller | changed: [instance]
2025-10-02 16:41:21.465675 | controller |
2025-10-02 16:41:21.465738 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-02 16:41:21.465757 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.646)       0:00:18.678 ******
2025-10-02 16:41:21.465780 | controller | skipping: [instance]
2025-10-02 16:41:21.465846 | controller |
2025-10-02 16:41:21.466302 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-02 16:41:21.531541 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.061)       0:00:18.739 ******
2025-10-02 16:41:21.531631 | controller | skipping: [instance]
2025-10-02 16:41:21.531918 | controller |
2025-10-02 16:41:21.531972 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-02 16:41:21.532000 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.065)       0:00:18.805 ******
2025-10-02 16:41:21.588708 | controller | skipping: [instance]
2025-10-02 16:41:21.661320 | controller |
2025-10-02 16:41:21.661356 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-02 16:41:21.661366 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.058)       0:00:18.863 ******
2025-10-02 16:41:21.661380 | controller | ok: [instance]
2025-10-02 16:41:21.713768 | controller |
2025-10-02 16:41:21.713805 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-02 16:41:21.713821 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.072)       0:00:18.935 ******
2025-10-02 16:41:21.713840 | controller | skipping: [instance]
2025-10-02 16:41:21.714141 | controller |
2025-10-02 16:41:21.714515 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-02 16:41:21.714833 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.053)       0:00:18.988 ******
2025-10-02 16:41:21.768734 | controller | skipping: [instance]
2025-10-02 16:41:21.769121 | controller |
2025-10-02 16:41:21.769531 | controller | TASK [Download the RPM] ********************************************************
2025-10-02 16:41:21.770025 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.054)       0:00:19.043 ******
2025-10-02 16:41:21.823841 | controller | skipping: [instance]
2025-10-02 16:41:21.824194 | controller |
2025-10-02 16:41:21.824563 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-02 16:41:21.824890 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.054)       0:00:19.098 ******
2025-10-02 16:41:21.875975 | controller | skipping: [instance]
2025-10-02 16:41:21.876293 | controller |
2025-10-02 16:41:21.876653 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-02 16:41:21.876974 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.052)       0:00:19.151 ******
2025-10-02 16:41:21.928775 | controller | skipping: [instance]
2025-10-02 16:41:21.929089 | controller |
2025-10-02 16:41:21.929404 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-02 16:41:21.929777 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.052)       0:00:19.204 ******
2025-10-02 16:41:21.977697 | controller | skipping: [instance]
2025-10-02 16:41:21.978010 | controller |
2025-10-02 16:41:21.978338 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-02 16:41:21.978692 | controller | Thursday 02 October 2025  16:41:21 +0000 (0:00:00.048)       0:00:19.252 ******
2025-10-02 16:41:22.020105 | controller | skipping: [instance]
2025-10-02 16:41:22.020471 | controller |
2025-10-02 16:41:22.020822 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-02 16:41:22.021131 | controller | Thursday 02 October 2025  16:41:22 +0000 (0:00:00.041)       0:00:19.294 ******
2025-10-02 16:41:22.310289 | controller | ok: [instance]
2025-10-02 16:41:22.671666 | controller |
2025-10-02 16:41:22.671721 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-02 16:41:22.671735 | controller | Thursday 02 October 2025  16:41:22 +0000 (0:00:00.289)       0:00:19.584 ******
2025-10-02 16:41:22.671754 | controller | changed: [instance]
2025-10-02 16:41:22.671808 | controller |
2025-10-02 16:41:22.671827 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-02 16:41:22.672124 | controller | Thursday 02 October 2025  16:41:22 +0000 (0:00:00.361)       0:00:19.945 ******
2025-10-02 16:41:23.054013 | controller | changed: [instance]
2025-10-02 16:41:23.096652 | controller |
2025-10-02 16:41:23.096709 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-02 16:41:23.096726 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.382)       0:00:20.328 ******
2025-10-02 16:41:23.096748 | controller | skipping: [instance]
2025-10-02 16:41:23.145684 | controller |
2025-10-02 16:41:23.145793 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-02 16:41:23.145811 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.042)       0:00:20.370 ******
2025-10-02 16:41:23.145842 | controller | skipping: [instance]
2025-10-02 16:41:23.194658 | controller |
2025-10-02 16:41:23.194725 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-02 16:41:23.194743 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.049)       0:00:20.419 ******
2025-10-02 16:41:23.194780 | controller | skipping: [instance]
2025-10-02 16:41:23.237770 | controller |
2025-10-02 16:41:23.237869 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-02 16:41:23.237889 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.048)       0:00:20.468 ******
2025-10-02 16:41:23.237920 | controller | skipping: [instance]
2025-10-02 16:41:23.238081 | controller |
2025-10-02 16:41:23.238106 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-02 16:41:23.238261 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.043)       0:00:20.512 ******
2025-10-02 16:41:23.283803 | controller | skipping: [instance]
2025-10-02 16:41:23.284023 | controller |
2025-10-02 16:41:23.284278 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-02 16:41:23.284560 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.046)       0:00:20.558 ******
2025-10-02 16:41:23.319718 | controller | skipping: [instance]
2025-10-02 16:41:23.319861 | controller |
2025-10-02 16:41:23.319998 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-02 16:41:23.320123 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.036)       0:00:20.594 ******
2025-10-02 16:41:23.343668 | controller | skipping: [instance]
2025-10-02 16:41:23.343812 | controller |
2025-10-02 16:41:23.343938 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-02 16:41:23.344063 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.024)       0:00:20.618 ******
2025-10-02 16:41:23.384698 | controller | skipping: [instance]
2025-10-02 16:41:23.384828 | controller |
2025-10-02 16:41:23.384951 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-02 16:41:23.385077 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.040)       0:00:20.659 ******
2025-10-02 16:41:23.409045 | controller | skipping: [instance]
2025-10-02 16:41:23.409188 | controller |
2025-10-02 16:41:23.409344 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-02 16:41:23.409532 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.024)       0:00:20.684 ******
2025-10-02 16:41:23.445615 | controller | skipping: [instance]
2025-10-02 16:41:23.445972 | controller |
2025-10-02 16:41:23.446339 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-02 16:41:23.446747 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.035)       0:00:20.720 ******
2025-10-02 16:41:23.506790 | controller | skipping: [instance]
2025-10-02 16:41:23.507050 | controller |
2025-10-02 16:41:23.507370 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-02 16:41:23.507750 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.061)       0:00:20.781 ******
2025-10-02 16:41:23.569960 | controller | skipping: [instance]
2025-10-02 16:41:23.570263 | controller |
2025-10-02 16:41:23.570587 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-02 16:41:23.570882 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.062)       0:00:20.844 ******
2025-10-02 16:41:23.630952 | controller | skipping: [instance]
2025-10-02 16:41:23.631258 | controller |
2025-10-02 16:41:23.631573 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-02 16:41:23.631862 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.060)       0:00:20.905 ******
2025-10-02 16:41:23.686575 | controller | skipping: [instance]
2025-10-02 16:41:23.686727 | controller |
2025-10-02 16:41:23.686901 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-02 16:41:23.687136 | controller | Thursday 02 October 2025  16:41:23 +0000 (0:00:00.055)       0:00:20.960 ******
2025-10-02 16:42:01.502627 | controller | ok: [instance]
2025-10-02 16:42:01.502716 | controller |
2025-10-02 16:42:01.502734 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-02 16:42:01.502890 | controller | Thursday 02 October 2025  16:42:01 +0000 (0:00:37.815)       0:00:58.776 ******
2025-10-02 16:42:03.045627 | controller | ok: [instance]
2025-10-02 16:42:03.045715 | controller |
2025-10-02 16:42:03.045739 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-02 16:42:03.045942 | controller | Thursday 02 October 2025  16:42:03 +0000 (0:00:01.543)       0:01:00.319 ******
2025-10-02 16:42:03.118260 | controller | skipping: [instance]
2025-10-02 16:42:03.194173 | controller |
2025-10-02 16:42:03.194210 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-02 16:42:03.194227 | controller | Thursday 02 October 2025  16:42:03 +0000 (0:00:00.072)       0:01:00.392 ******
2025-10-02 16:42:03.194246 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-02 16:42:03.234601 | controller |
2025-10-02 16:42:03.234658 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-02 16:42:03.234676 | controller | Thursday 02 October 2025  16:42:03 +0000 (0:00:00.075)       0:01:00.468 ******
2025-10-02 16:42:03.234714 | controller | ok: [instance] =>
2025-10-02 16:42:03.234939 | controller |   cifmw_ci_setup_packages:
2025-10-02 16:42:03.234959 | controller |   - bash-completion
2025-10-02 16:42:03.234972 | controller |   - ca-certificates
2025-10-02 16:42:03.234986 | controller |   - git-core
2025-10-02 16:42:03.235004 | controller |   - make
2025-10-02 16:42:03.235196 | controller |   - tar
2025-10-02 16:42:03.235448 | controller |   - tmux
2025-10-02 16:42:03.235755 | controller |   - python3-pip
2025-10-02 16:42:03.236003 | controller |
2025-10-02 16:42:03.236266 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-02 16:42:03.236576 | controller | Thursday 02 October 2025  16:42:03 +0000 (0:00:00.040)       0:01:00.508 ******
2025-10-02 16:42:09.004589 | controller | changed: [instance]
2025-10-02 16:42:10.268711 | controller |
2025-10-02 16:42:10.268781 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-02 16:42:10.268799 | controller | Thursday 02 October 2025  16:42:08 +0000 (0:00:05.769)       0:01:06.277 ******
2025-10-02 16:42:10.268819 | controller | ok: [instance]
2025-10-02 16:42:10.590636 | controller |
2025-10-02 16:42:10.590687 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-02 16:42:10.590704 | controller | Thursday 02 October 2025  16:42:10 +0000 (0:00:01.264)       0:01:07.542 ******
2025-10-02 16:42:10.590726 | controller | changed: [instance]
2025-10-02 16:42:10.591275 | controller |
2025-10-02 16:42:15.800386 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-02 16:42:15.800443 | controller | Thursday 02 October 2025  16:42:10 +0000 (0:00:00.322)       0:01:07.864 ******
2025-10-02 16:42:15.800459 | controller | changed: [instance]
2025-10-02 16:42:15.873660 | controller |
2025-10-02 16:42:15.873715 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-02 16:42:15.873732 | controller | Thursday 02 October 2025  16:42:15 +0000 (0:00:05.209)       0:01:13.074 ******
2025-10-02 16:42:15.873754 | controller | ok: [instance]
2025-10-02 16:42:16.344278 | controller |
2025-10-02 16:42:16.344314 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-02 16:42:16.344325 | controller | Thursday 02 October 2025  16:42:15 +0000 (0:00:00.072)       0:01:13.147 ******
2025-10-02 16:42:16.344339 | controller | changed: [instance]
2025-10-02 16:42:16.785348 | controller |
2025-10-02 16:42:16.785442 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-02 16:42:16.785465 | controller | Thursday 02 October 2025  16:42:16 +0000 (0:00:00.470)       0:01:13.618 ******
2025-10-02 16:42:16.785487 | controller | changed: [instance]
2025-10-02 16:42:16.820552 | controller |
2025-10-02 16:42:16.820573 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-02 16:42:16.820583 | controller | Thursday 02 October 2025  16:42:16 +0000 (0:00:00.440)       0:01:14.058 ******
2025-10-02 16:42:16.820595 | controller | skipping: [instance]
2025-10-02 16:42:16.821153 | controller |
2025-10-02 16:42:16.856533 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-02 16:42:16.856552 | controller | Thursday 02 October 2025  16:42:16 +0000 (0:00:00.036)       0:01:14.095 ******
2025-10-02 16:42:16.856564 | controller | skipping: [instance]
2025-10-02 16:42:16.857032 | controller |
2025-10-02 16:42:16.857091 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-02 16:42:16.893808 | controller | Thursday 02 October 2025  16:42:16 +0000 (0:00:00.035)       0:01:14.130 ******
2025-10-02 16:42:16.893853 | controller | skipping: [instance]
2025-10-02 16:42:16.894156 | controller |
2025-10-02 16:42:16.894511 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-02 16:42:16.894830 | controller | Thursday 02 October 2025  16:42:16 +0000 (0:00:00.037)       0:01:14.168 ******
2025-10-02 16:42:16.928858 | controller | skipping: [instance]
2025-10-02 16:42:16.929179 | controller |
2025-10-02 16:42:16.929534 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-02 16:42:16.929869 | controller | Thursday 02 October 2025  16:42:16 +0000 (0:00:00.035)       0:01:14.203 ******
2025-10-02 16:42:16.963781 | controller | skipping: [instance]
2025-10-02 16:42:16.964107 | controller |
2025-10-02 16:42:16.964460 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-02 16:42:16.964846 | controller | Thursday 02 October 2025  16:42:16 +0000 (0:00:00.034)       0:01:14.238 ******
2025-10-02 16:42:17.009869 | controller | skipping: [instance]
2025-10-02 16:42:17.009983 | controller |
2025-10-02 16:42:17.010205 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-02 16:42:17.010320 | controller | Thursday 02 October 2025  16:42:17 +0000 (0:00:00.046)       0:01:14.284 ******
2025-10-02 16:42:18.601647 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-02 16:42:18.602781 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-02 16:42:18.602844 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-02 16:45:13.488747 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-02 16:45:13.488851 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-02 16:45:13.488868 | controller |
2025-10-02 16:45:13.488883 | controller | TASK [Ensure CRC is started] ***************************************************
2025-10-02 16:45:13.488897 | controller | Thursday 02 October 2025  16:42:18 +0000 (0:00:01.590)       0:01:15.875 ******
2025-10-02 16:45:13.488921 | controller | changed: [instance]
2025-10-02 16:45:13.489122 | controller |
2025-10-02 16:45:13.489145 | controller | PLAY RECAP *********************************************************************
2025-10-02 16:45:13.489159 | controller | instance                   : ok=30   changed=16   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-10-02 16:45:13.489192 | controller |
2025-10-02 16:45:13.489357 | controller | Thursday 02 October 2025  16:45:13 +0000 (0:02:54.887)       0:04:10.762 ******
2025-10-02 16:45:13.489567 | controller | ===============================================================================
2025-10-02 16:45:13.489789 | controller | Ensure CRC is started ------------------------------------------------- 174.89s
2025-10-02 16:45:13.490009 | controller | test_deps : Install selinux python libs -------------------------------- 37.82s
2025-10-02 16:45:13.490215 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.02s
2025-10-02 16:45:13.490438 | controller | ci_setup : Install needed packages -------------------------------------- 5.77s
2025-10-02 16:45:13.490657 | controller | ci_setup : Install openshift client ------------------------------------- 5.21s
2025-10-02 16:45:13.490853 | controller | Gathering Facts --------------------------------------------------------- 2.17s
2025-10-02 16:45:13.491056 | controller | ci_setup : Manage directories ------------------------------------------- 1.59s
2025-10-02 16:45:13.491286 | controller | test_deps : Install python yaml libs ------------------------------------ 1.54s
2025-10-02 16:45:13.491507 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.30s
2025-10-02 16:45:13.491718 | controller | ci_setup : Gather version of openshift client --------------------------- 1.26s
2025-10-02 16:45:13.491952 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 1.08s
2025-10-02 16:45:13.492187 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.03s
2025-10-02 16:45:13.492390 | controller | repo_setup : Install repo-setup package --------------------------------- 0.93s
2025-10-02 16:45:13.492632 | controller | repo_setup : Ensure directories are present ----------------------------- 0.82s
2025-10-02 16:45:13.492847 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.81s
2025-10-02 16:45:13.493050 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.65s
2025-10-02 16:45:13.493238 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-10-02 16:45:13.493484 | controller | ci_setup : Create completion file --------------------------------------- 0.47s
2025-10-02 16:45:13.493694 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.44s
2025-10-02 16:45:13.493903 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.44s
2025-10-02 16:45:13.599407 | controller | INFO     Running default > converge
2025-10-02 16:45:14.174326 | controller |
2025-10-02 16:45:14.175772 | controller | PLAY [Converge] ****************************************************************
2025-10-02 16:45:15.372668 | controller |
2025-10-02 16:45:15.372858 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 16:45:15.372877 | controller | Thursday 02 October 2025  16:45:14 +0000 (0:00:00.018)       0:00:00.018 ******
2025-10-02 16:45:15.372903 | controller | ok: [instance]
2025-10-02 16:45:15.413121 | controller |
2025-10-02 16:45:15.413160 | controller | TASK [tofu : Fail If infrastructure State Is Not Correct] **********************
2025-10-02 16:45:15.413175 | controller | Thursday 02 October 2025  16:45:15 +0000 (0:00:01.199)       0:00:01.218 ******
2025-10-02 16:45:15.413197 | controller | skipping: [instance]
2025-10-02 16:45:15.413295 | controller |
2025-10-02 16:45:15.413318 | controller | TASK [tofu : Fail If Check Mode Is Not Correct] ********************************
2025-10-02 16:45:15.413381 | controller | Thursday 02 October 2025  16:45:15 +0000 (0:00:00.040)       0:00:01.258 ******
2025-10-02 16:45:15.462542 | controller | skipping: [instance]
2025-10-02 16:45:15.462685 | controller |
2025-10-02 16:45:15.462710 | controller | TASK [tofu : Fail If Tofu Project Path Not Defined] ****************************
2025-10-02 16:45:15.462777 | controller | Thursday 02 October 2025  16:45:15 +0000 (0:00:00.049)       0:00:01.308 ******
2025-10-02 16:45:15.526846 | controller | skipping: [instance]
2025-10-02 16:45:15.526969 | controller |
2025-10-02 16:45:15.526984 | controller | TASK [Prepare Remote Environment If Required] **********************************
2025-10-02 16:45:15.527078 | controller | Thursday 02 October 2025  16:45:15 +0000 (0:00:00.064)       0:00:01.372 ******
2025-10-02 16:45:15.571875 | controller |
2025-10-02 16:45:15.571945 | controller | TASK [tofu : Create Tofu Directory] ********************************************
2025-10-02 16:45:15.572048 | controller | Thursday 02 October 2025  16:45:15 +0000 (0:00:00.045)       0:00:01.417 ******
2025-10-02 16:45:15.991974 | controller | changed: [instance]
2025-10-02 16:45:15.992050 | controller |
2025-10-02 16:45:15.992110 | controller | TASK [tofu : Copy Local Tufo Project Directory To Remote Host '{{ ansible_host }}'] ***
2025-10-02 16:45:15.992214 | controller | Thursday 02 October 2025  16:45:15 +0000 (0:00:00.420)       0:00:01.837 ******
2025-10-02 16:45:16.755098 | controller | changed: [instance]
2025-10-02 16:45:16.755389 | controller |
2025-10-02 16:45:16.788732 | controller | TASK [tofu : Update Remote Execution Variable] *********************************
2025-10-02 16:45:16.788783 | controller | Thursday 02 October 2025  16:45:16 +0000 (0:00:00.762)       0:00:02.600 ******
2025-10-02 16:45:16.788801 | controller | ok: [instance]
2025-10-02 16:45:17.227660 | controller |
2025-10-02 16:45:17.227730 | controller | TASK [tofu : Add OpenTofu repository] ******************************************
2025-10-02 16:45:17.227742 | controller | Thursday 02 October 2025  16:45:16 +0000 (0:00:00.032)       0:00:02.633 ******
2025-10-02 16:45:17.227758 | controller | changed: [instance]
2025-10-02 16:45:17.227982 | controller |
2025-10-02 16:45:17.228053 | controller | TASK [tofu : Install OpenTofu RPM] *********************************************
2025-10-02 16:45:23.393513 | controller | Thursday 02 October 2025  16:45:17 +0000 (0:00:00.439)       0:00:03.072 ******
2025-10-02 16:45:23.393588 | controller | changed: [instance]
2025-10-02 16:45:23.393852 | controller |
2025-10-02 16:45:23.394050 | controller | TASK [tofu : Return tofu to registered var] ************************************
2025-10-02 16:45:23.394112 | controller | Thursday 02 October 2025  16:45:23 +0000 (0:00:06.165)       0:00:09.238 ******
2025-10-02 16:45:23.824964 | controller | changed: [instance]
2025-10-02 16:45:28.062083 | controller |
2025-10-02 16:45:28.062185 | controller | TASK [tofu : Tofu Action] ******************************************************
2025-10-02 16:45:28.062215 | controller | Thursday 02 October 2025  16:45:23 +0000 (0:00:00.431)       0:00:09.669 ******
2025-10-02 16:45:28.062241 | controller | changed: [instance]
2025-10-02 16:45:28.182184 | controller |
2025-10-02 16:45:28.182233 | controller | TASK [tofu : Summarize Tofu Execution] *****************************************
2025-10-02 16:45:28.182244 | controller | Thursday 02 October 2025  16:45:28 +0000 (0:00:04.232)       0:00:13.902 ******
2025-10-02 16:45:28.182261 | controller | ok: [instance] =>
2025-10-02 16:45:28.182296 | controller |   msg:
2025-10-02 16:45:28.182453 | controller |   - 'Execution failed: False'
2025-10-02 16:45:28.182606 | controller |   - 'Output:'
2025-10-02 16:45:28.182756 | controller |   - - ''
2025-10-02 16:45:28.182900 | controller |     - OpenTofu used the selected providers to generate the following execution
2025-10-02 16:45:28.183038 | controller |     - 'plan. Resource actions are indicated with the following symbols:'
2025-10-02 16:45:28.183177 | controller |     - '  + create'
2025-10-02 16:45:28.183325 | controller |     - ''
2025-10-02 16:45:28.183481 | controller |     - 'OpenTofu will perform the following actions:'
2025-10-02 16:45:28.183620 | controller |     - ''
2025-10-02 16:45:28.183801 | controller |     - '  # kubernetes_deployment.ubuntu will be created'
2025-10-02 16:45:28.183948 | controller |     - '  + resource "kubernetes_deployment" "ubuntu" {'
2025-10-02 16:45:28.184114 | controller |     - '      + id               = (known after apply)'
2025-10-02 16:45:28.184257 | controller |     - '      + wait_for_rollout = true'
2025-10-02 16:45:28.184389 | controller |     - ''
2025-10-02 16:45:28.184706 | controller |     - '      + metadata {'
2025-10-02 16:45:28.184824 | controller |     - '          + generation       = (known after apply)'
2025-10-02 16:45:28.184838 | controller |     - '          + name             = "ubuntu"'
2025-10-02 16:45:28.184977 | controller |     - '          + namespace        = "tofu-experiements"'
2025-10-02 16:45:28.185112 | controller |     - '          + resource_version = (known after apply)'
2025-10-02 16:45:28.185247 | controller |     - '          + uid              = (known after apply)'
2025-10-02 16:45:28.185379 | controller |     - '        }'
2025-10-02 16:45:28.185539 | controller |     - ''
2025-10-02 16:45:28.185678 | controller |     - '      + spec {'
2025-10-02 16:45:28.185813 | controller |     - '          + min_ready_seconds         = 0'
2025-10-02 16:45:28.185949 | controller |     - '          + paused                    = false'
2025-10-02 16:45:28.186092 | controller |     - '          + progress_deadline_seconds = 600'
2025-10-02 16:45:28.186224 | controller |     - '          + replicas                  = "3"'
2025-10-02 16:45:28.186366 | controller |     - '          + revision_history_limit    = 10'
2025-10-02 16:45:28.186518 | controller |     - ''
2025-10-02 16:45:28.186663 | controller |     - '          + selector {'
2025-10-02 16:45:28.186808 | controller |     - '              + match_labels = {'
2025-10-02 16:45:28.186943 | controller |     - '                  + "app" = "ubuntu"'
2025-10-02 16:45:28.187078 | controller |     - '                }'
2025-10-02 16:45:28.187219 | controller |     - '            }'
2025-10-02 16:45:28.187355 | controller |     - ''
2025-10-02 16:45:28.187514 | controller |     - '          + strategy (known after apply)'
2025-10-02 16:45:28.187652 | controller |     - ''
2025-10-02 16:45:28.187786 | controller |     - '          + template {'
2025-10-02 16:45:28.187926 | controller |     - '              + metadata {'
2025-10-02 16:45:28.188070 | controller |     - '                  + generation       = (known after apply)'
2025-10-02 16:45:28.188189 | controller |     - '                  + labels           = {'
2025-10-02 16:45:28.188330 | controller |     - '                      + "app" = "ubuntu"'
2025-10-02 16:45:28.188500 | controller |     - '                    }'
2025-10-02 16:45:28.188644 | controller |     - '                  + name             = (known after apply)'
2025-10-02 16:45:28.188782 | controller |     - '                  + resource_version = (known after apply)'
2025-10-02 16:45:28.188941 | controller |     - '                  + uid              = (known after apply)'
2025-10-02 16:45:28.189080 | controller |     - '                }'
2025-10-02 16:45:28.189212 | controller |     - '              + spec {'
2025-10-02 16:45:28.189349 | controller |     - '                  + automount_service_account_token  = true'
2025-10-02 16:45:28.189519 | controller |     - '                  + dns_policy                       = "ClusterFirst"'
2025-10-02 16:45:28.189653 | controller |     - '                  + enable_service_links             = true'
2025-10-02 16:45:28.189789 | controller |     - '                  + host_ipc                         = false'
2025-10-02 16:45:28.189923 | controller |     - '                  + host_network                     = false'
2025-10-02 16:45:28.190075 | controller |     - '                  + host_pid                         = false'
2025-10-02 16:45:28.190258 | controller |     - '                  + hostname                         = (known after apply)'
2025-10-02 16:45:28.190429 | controller |     - '                  + node_name                        = (known after apply)'
2025-10-02 16:45:28.190567 | controller |     - '                  + restart_policy                   = "Always"'
2025-10-02 16:45:28.190715 | controller |     - '                  + scheduler_name                   = (known after apply)'
2025-10-02 16:45:28.190865 | controller |     - '                  + service_account_name             = (known after apply)'
2025-10-02 16:45:28.190999 | controller |     - '                  + share_process_namespace          = false'
2025-10-02 16:45:28.191142 | controller |     - '                  + termination_grace_period_seconds = 30'
2025-10-02 16:45:28.191289 | controller |     - ''
2025-10-02 16:45:28.191504 | controller |     - '                  + container {'
2025-10-02 16:45:28.191587 | controller |     - '                      + args                       = ['
2025-10-02 16:45:28.191727 | controller |     - '                          + "while true; do sleep 30; done;",'
2025-10-02 16:45:28.191863 | controller |     - '                        ]'
2025-10-02 16:45:28.192007 | controller |     - '                      + command                    = ['
2025-10-02 16:45:28.192138 | controller |     - '                          + "/bin/bash",'
2025-10-02 16:45:28.192274 | controller |     - '                          + "-c",'
2025-10-02 16:45:28.192426 | controller |     - '                          + "--",'
2025-10-02 16:45:28.192566 | controller |     - '                        ]'
2025-10-02 16:45:28.192705 | controller |     - '                      + image                      = "ubuntu:latest"'
2025-10-02 16:45:28.192845 | controller |     - '                      + image_pull_policy          = (known after apply)'
2025-10-02 16:45:28.193001 | controller |     - '                      + name                       = "ubuntu"'
2025-10-02 16:45:28.193146 | controller |     - '                      + stdin                      = false'
2025-10-02 16:45:28.193284 | controller |     - '                      + stdin_once                 = false'
2025-10-02 16:45:28.193444 | controller |     - '                      + termination_message_path   = "/dev/termination-log"'
2025-10-02 16:45:28.193594 | controller |     - '                      + termination_message_policy = (known after apply)'
2025-10-02 16:45:28.193730 | controller |     - '                      + tty                        = false'
2025-10-02 16:45:28.193864 | controller |     - ''
2025-10-02 16:45:28.194003 | controller |     - '                      + resources (known after apply)'
2025-10-02 16:45:28.194147 | controller |     - '                    }'
2025-10-02 16:45:28.194285 | controller |     - ''
2025-10-02 16:45:28.194435 | controller |     - '                  + image_pull_secrets (known after apply)'
2025-10-02 16:45:28.194581 | controller |     - ''
2025-10-02 16:45:28.194716 | controller |     - '                  + readiness_gate (known after apply)'
2025-10-02 16:45:28.194849 | controller |     - '                }'
2025-10-02 16:45:28.194984 | controller |     - '            }'
2025-10-02 16:45:28.195118 | controller |     - '        }'
2025-10-02 16:45:28.195256 | controller |     - '    }'
2025-10-02 16:45:28.195390 | controller |     - ''
2025-10-02 16:45:28.195556 | controller |     - '  # kubernetes_namespace.tofu-tests will be created'
2025-10-02 16:45:28.195696 | controller |     - '  + resource "kubernetes_namespace" "tofu-tests" {'
2025-10-02 16:45:28.195834 | controller |     - '      + id                               = (known after apply)'
2025-10-02 16:45:28.195975 | controller |     - '      + wait_for_default_service_account = false'
2025-10-02 16:45:28.196118 | controller |     - ''
2025-10-02 16:45:28.196249 | controller |     - '      + metadata {'
2025-10-02 16:45:28.196388 | controller |     - '          + generation       = (known after apply)'
2025-10-02 16:45:28.196549 | controller |     - '          + name             = "tofu-experiements"'
2025-10-02 16:45:28.196691 | controller |     - '          + resource_version = (known after apply)'
2025-10-02 16:45:28.196830 | controller |     - '          + uid              = (known after apply)'
2025-10-02 16:45:28.196979 | controller |     - '        }'
2025-10-02 16:45:28.197117 | controller |     - '    }'
2025-10-02 16:45:28.197256 | controller |     - ''
2025-10-02 16:45:28.197397 | controller |     - 'Plan: 2 to add, 0 to change, 0 to destroy.'
2025-10-02 16:45:28.197558 | controller |   - 'Error:'
2025-10-02 16:45:28.197693 | controller |   - []
2025-10-02 16:45:28.197823 | controller |
2025-10-02 16:45:28.197962 | controller | PLAY RECAP *********************************************************************
2025-10-02 16:45:28.198115 | controller | instance                   : ok=9    changed=6    unreachable=0    failed=0    skipped=3    rescued=0    ignored=0
2025-10-02 16:45:28.198244 | controller |
2025-10-02 16:45:28.198383 | controller | Thursday 02 October 2025  16:45:28 +0000 (0:00:00.125)       0:00:14.027 ******
2025-10-02 16:45:28.198535 | controller | ===============================================================================
2025-10-02 16:45:28.198684 | controller | tofu : Install OpenTofu RPM --------------------------------------------- 6.17s
2025-10-02 16:45:28.198825 | controller | tofu : Tofu Action ------------------------------------------------------ 4.23s
2025-10-02 16:45:28.198964 | controller | Gathering Facts --------------------------------------------------------- 1.20s
2025-10-02 16:45:28.199123 | controller | tofu : Copy Local Tufo Project Directory To Remote Host '{{ ansible_host }}' --- 0.76s
2025-10-02 16:45:28.199255 | controller | tofu : Add OpenTofu repository ------------------------------------------ 0.44s
2025-10-02 16:45:28.199433 | controller | tofu : Return tofu to registered var ------------------------------------ 0.43s
2025-10-02 16:45:28.199563 | controller | tofu : Create Tofu Directory -------------------------------------------- 0.42s
2025-10-02 16:45:28.199704 | controller | tofu : Summarize Tofu Execution ----------------------------------------- 0.13s
2025-10-02 16:45:28.199845 | controller | tofu : Fail If Tofu Project Path Not Defined ---------------------------- 0.06s
2025-10-02 16:45:28.199985 | controller | tofu : Fail If Check Mode Is Not Correct -------------------------------- 0.05s
2025-10-02 16:45:28.200126 | controller | Prepare Remote Environment If Required ---------------------------------- 0.05s
2025-10-02 16:45:28.200280 | controller | tofu : Fail If infrastructure State Is Not Correct ---------------------- 0.04s
2025-10-02 16:45:28.200435 | controller | tofu : Update Remote Execution Variable --------------------------------- 0.03s
2025-10-02 16:45:28.269085 | controller | INFO     Running default > cleanup
2025-10-02 16:45:28.269482 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-02 16:45:28.270019 | controller | INFO     Writing /tmp/report.html report.
2025-10-02 16:45:28.372316 | controller | changed
2025-10-02 16:45:28.417508 | 
2025-10-02 16:45:28.417624 | PLAY RECAP
2025-10-02 16:45:28.417688 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 16:45:28.417727 | 
2025-10-02 16:45:28.527865 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 16:45:28.528892 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 16:45:29.132356 | 
2025-10-02 16:45:29.132465 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-02 16:45:29.154417 | 
2025-10-02 16:45:29.154523 | TASK [Filter out host if needed]
2025-10-02 16:45:29.163586 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-02 16:45:29.168516 | 
2025-10-02 16:45:29.168586 | TASK [Ensure file is present]
2025-10-02 16:45:29.731282 | controller | ok
2025-10-02 16:45:29.742684 | 
2025-10-02 16:45:29.742818 | TASK [Manage molecule report file]
2025-10-02 16:45:30.789813 | controller | changed
2025-10-02 16:45:30.802804 | 
2025-10-02 16:45:30.802992 | TASK [Check if we get ci-framework-data basedir]
2025-10-02 16:45:31.236779 | controller | ok
2025-10-02 16:45:31.250959 | 
2025-10-02 16:45:31.251258 | TASK [Create ci-framework-data log directory for zuul]
2025-10-02 16:45:31.776651 | controller | changed
2025-10-02 16:45:31.789578 | 
2025-10-02 16:45:31.794187 | TASK [Copy ci-framework interesting files]
2025-10-02 16:45:32.197680 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-02 16:45:32.330668 | controller | changed
2025-10-02 16:45:32.336410 | 
2025-10-02 16:45:32.336492 | TASK [Get SELinux listing]
2025-10-02 16:45:32.866179 | controller | changed
2025-10-02 16:45:32.880666 | 
2025-10-02 16:45:32.880857 | TASK [Generate log index]
2025-10-02 16:45:34.491274 | controller | changed
2025-10-02 16:45:34.499303 | 
2025-10-02 16:45:34.499439 | TASK [Get some env related data]
2025-10-02 16:45:35.269269 | controller | /home/zuul/.local/bin/ansible
2025-10-02 16:45:36.047243 | controller | changed
2025-10-02 16:45:36.060193 | 
2025-10-02 16:45:36.060340 | TASK [Generate list of logs to collect in home directory]
2025-10-02 16:45:36.515179 | controller | ok: All paths examined
2025-10-02 16:45:36.534113 | 
2025-10-02 16:45:36.534217 | LOOP [Copy logs from home directory]
2025-10-02 16:45:37.380977 | controller | changed:
2025-10-02 16:45:37.381207 | controller | {
2025-10-02 16:45:37.381235 | controller |   "atime": 1743544925.4788878,
2025-10-02 16:45:37.381259 | controller |   "ctime": 1743545329.1409318,
2025-10-02 16:45:37.381285 | controller |   "dev": 64513,
2025-10-02 16:45:37.381309 | controller |   "gid": 1000,
2025-10-02 16:45:37.381326 | controller |   "gr_name": "zuul",
2025-10-02 16:45:37.381342 | controller |   "inode": 4518807,
2025-10-02 16:45:37.381357 | controller |   "isblk": false,
2025-10-02 16:45:37.381372 | controller |   "ischr": false,
2025-10-02 16:45:37.381387 | controller |   "isdir": false,
2025-10-02 16:45:37.381404 | controller |   "isfifo": false,
2025-10-02 16:45:37.381421 | controller |   "isgid": false,
2025-10-02 16:45:37.381435 | controller |   "islnk": false,
2025-10-02 16:45:37.381450 | controller |   "isreg": true,
2025-10-02 16:45:37.381464 | controller |   "issock": false,
2025-10-02 16:45:37.381478 | controller |   "isuid": false,
2025-10-02 16:45:37.381492 | controller |   "mode": "0644",
2025-10-02 16:45:37.381506 | controller |   "mtime": 1743545329.1409318,
2025-10-02 16:45:37.381520 | controller |   "nlink": 1,
2025-10-02 16:45:37.381535 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-02 16:45:37.381554 | controller |   "pw_name": "zuul",
2025-10-02 16:45:37.381570 | controller |   "rgrp": true,
2025-10-02 16:45:37.381586 | controller |   "roth": true,
2025-10-02 16:45:37.381603 | controller |   "rusr": true,
2025-10-02 16:45:37.381620 | controller |   "size": 4108,
2025-10-02 16:45:37.381634 | controller |   "uid": 1000,
2025-10-02 16:45:37.381649 | controller |   "wgrp": false,
2025-10-02 16:45:37.381664 | controller |   "woth": false,
2025-10-02 16:45:37.381682 | controller |   "wusr": true,
2025-10-02 16:45:37.381697 | controller |   "xgrp": false,
2025-10-02 16:45:37.381712 | controller |   "xoth": false,
2025-10-02 16:45:37.381727 | controller |   "xusr": false
2025-10-02 16:45:37.381741 | controller | }
2025-10-02 16:45:38.172991 | controller | changed:
2025-10-02 16:45:38.173115 | controller | {
2025-10-02 16:45:38.173141 | controller |   "atime": 1743545331.0429637,
2025-10-02 16:45:38.173166 | controller |   "ctime": 1743545842.0111232,
2025-10-02 16:45:38.173185 | controller |   "dev": 64513,
2025-10-02 16:45:38.173202 | controller |   "gid": 1000,
2025-10-02 16:45:38.173219 | controller |   "gr_name": "zuul",
2025-10-02 16:45:38.173236 | controller |   "inode": 4194437,
2025-10-02 16:45:38.173252 | controller |   "isblk": false,
2025-10-02 16:45:38.173269 | controller |   "ischr": false,
2025-10-02 16:45:38.173290 | controller |   "isdir": false,
2025-10-02 16:45:38.173307 | controller |   "isfifo": false,
2025-10-02 16:45:38.173328 | controller |   "isgid": false,
2025-10-02 16:45:38.173545 | controller |   "islnk": false,
2025-10-02 16:45:38.173575 | controller |   "isreg": true,
2025-10-02 16:45:38.173594 | controller |   "issock": false,
2025-10-02 16:45:38.173611 | controller |   "isuid": false,
2025-10-02 16:45:38.173627 | controller |   "mode": "0644",
2025-10-02 16:45:38.173643 | controller |   "mtime": 1743545842.0111232,
2025-10-02 16:45:38.173660 | controller |   "nlink": 1,
2025-10-02 16:45:38.173677 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-02 16:45:38.173693 | controller |   "pw_name": "zuul",
2025-10-02 16:45:38.173709 | controller |   "rgrp": true,
2025-10-02 16:45:38.173725 | controller |   "roth": true,
2025-10-02 16:45:38.173742 | controller |   "rusr": true,
2025-10-02 16:45:38.173757 | controller |   "size": 4023,
2025-10-02 16:45:38.173773 | controller |   "uid": 1000,
2025-10-02 16:45:38.173790 | controller |   "wgrp": false,
2025-10-02 16:45:38.173805 | controller |   "woth": false,
2025-10-02 16:45:38.173820 | controller |   "wusr": true,
2025-10-02 16:45:38.173836 | controller |   "xgrp": false,
2025-10-02 16:45:38.173854 | controller |   "xoth": false,
2025-10-02 16:45:38.173871 | controller |   "xusr": false
2025-10-02 16:45:38.173888 | controller | }
2025-10-02 16:45:38.937981 | controller | changed:
2025-10-02 16:45:38.938087 | controller | {
2025-10-02 16:45:38.938116 | controller |   "atime": 1759423224.2062128,
2025-10-02 16:45:38.938135 | controller |   "ctime": 1759423240.8834543,
2025-10-02 16:45:38.938152 | controller |   "dev": 64513,
2025-10-02 16:45:38.938168 | controller |   "gid": 1000,
2025-10-02 16:45:38.938183 | controller |   "gr_name": "zuul",
2025-10-02 16:45:38.938199 | controller |   "inode": 4233435,
2025-10-02 16:45:38.938215 | controller |   "isblk": false,
2025-10-02 16:45:38.938230 | controller |   "ischr": false,
2025-10-02 16:45:38.938245 | controller |   "isdir": false,
2025-10-02 16:45:38.938260 | controller |   "isfifo": false,
2025-10-02 16:45:38.938275 | controller |   "isgid": false,
2025-10-02 16:45:38.938289 | controller |   "islnk": false,
2025-10-02 16:45:38.938304 | controller |   "isreg": true,
2025-10-02 16:45:38.938319 | controller |   "issock": false,
2025-10-02 16:45:38.938334 | controller |   "isuid": false,
2025-10-02 16:45:38.938349 | controller |   "mode": "0644",
2025-10-02 16:45:38.938364 | controller |   "mtime": 1759423240.8834543,
2025-10-02 16:45:38.938380 | controller |   "nlink": 1,
2025-10-02 16:45:38.938395 | controller |   "path": "/home/zuul/ansible.log",
2025-10-02 16:45:38.938411 | controller |   "pw_name": "zuul",
2025-10-02 16:45:38.938428 | controller |   "rgrp": true,
2025-10-02 16:45:38.938443 | controller |   "roth": true,
2025-10-02 16:45:38.938461 | controller |   "rusr": true,
2025-10-02 16:45:38.938479 | controller |   "size": 6701,
2025-10-02 16:45:38.938498 | controller |   "uid": 1000,
2025-10-02 16:45:38.938514 | controller |   "wgrp": false,
2025-10-02 16:45:38.938529 | controller |   "woth": false,
2025-10-02 16:45:38.938544 | controller |   "wusr": true,
2025-10-02 16:45:38.938559 | controller |   "xgrp": false,
2025-10-02 16:45:38.938573 | controller |   "xoth": false,
2025-10-02 16:45:38.938587 | controller |   "xusr": false
2025-10-02 16:45:38.938602 | controller | }
2025-10-02 16:45:38.956305 | 
2025-10-02 16:45:38.956460 | TASK [Copy crio stats log file]
2025-10-02 16:45:38.974300 | controller | skipping: Conditional result was False
2025-10-02 16:45:38.987300 | 
2025-10-02 16:45:38.987458 | TASK [Get SELinux related data]
2025-10-02 16:45:39.526812 | controller | <no matches>
2025-10-02 16:45:39.563235 | controller | ERROR
2025-10-02 16:45:39.563509 | controller | {
2025-10-02 16:45:39.563575 | controller |   "delta": "0:00:00.022585",
2025-10-02 16:45:39.563622 | controller |   "end": "2025-10-02 16:45:39.527564",
2025-10-02 16:45:39.563673 | controller |   "msg": "non-zero return code",
2025-10-02 16:45:39.563725 | controller |   "rc": 1,
2025-10-02 16:45:39.563771 | controller |   "start": "2025-10-02 16:45:39.504979"
2025-10-02 16:45:39.563807 | controller | }
2025-10-02 16:45:39.563868 | controller | ERROR: Ignoring Errors
2025-10-02 16:45:39.576483 | 
2025-10-02 16:45:39.576680 | TASK [Create system configuration directory]
2025-10-02 16:45:39.999036 | controller | changed
2025-10-02 16:45:40.004392 | 
2025-10-02 16:45:40.004461 | TASK [Get some of the system configurations]
2025-10-02 16:45:40.533942 | controller | changed
2025-10-02 16:45:40.547272 | 
2025-10-02 16:45:40.547424 | TASK [Copy generated documentation if available]
2025-10-02 16:45:40.576073 | controller | skipping: Conditional result was False
2025-10-02 16:45:40.589994 | 
2025-10-02 16:45:40.590192 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-02 16:45:40.618252 | controller | skipping: Conditional result was False
2025-10-02 16:45:40.630908 | 
2025-10-02 16:45:40.631082 | TASK [Compress logs bigger than 2MB]
2025-10-02 16:45:41.182370 | controller | changed
2025-10-02 16:45:41.188033 | 
2025-10-02 16:45:41.188125 | TASK [Copy files from workspace on node]
2025-10-02 16:45:41.207557 | controller | ok
2025-10-02 16:45:41.232556 | 
2025-10-02 16:45:41.232667 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 16:45:41.256659 | controller | skipping: Conditional result was False
2025-10-02 16:45:41.262813 | 
2025-10-02 16:45:41.262890 | TASK [fetch-output : Set log path for single node]
2025-10-02 16:45:41.292112 | controller | ok
2025-10-02 16:45:41.297110 | 
2025-10-02 16:45:41.297179 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 16:45:41.566390 | controller -> localhost | ok: "/var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/logs"
2025-10-02 16:45:41.566616 | controller -> localhost | changed: All items complete
2025-10-02 16:45:41.566648 | 
2025-10-02 16:45:41.787140 | controller -> localhost | changed: "/var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/artifacts"
2025-10-02 16:45:42.054550 | controller -> localhost | changed: "/var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/docs"
2025-10-02 16:45:42.067945 | 
2025-10-02 16:45:42.068108 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 16:45:42.989331 | controller | changed:
2025-10-02 16:45:42.989679 | controller | .d..t...... ./
2025-10-02 16:45:42.989717 | controller | >f+++++++++ README.html
2025-10-02 16:45:42.989739 | controller | >f+++++++++ ansible-execution.log
2025-10-02 16:45:42.989758 | controller | >f+++++++++ ansible.log
2025-10-02 16:45:42.989775 | controller | >f+++++++++ crc-setup.log
2025-10-02 16:45:42.989792 | controller | >f+++++++++ crc-start.log
2025-10-02 16:45:42.989809 | controller | >f+++++++++ dmesg.log
2025-10-02 16:45:42.989832 | controller | >f+++++++++ installed-pkgs.log
2025-10-02 16:45:42.989850 | controller | >f+++++++++ python.log
2025-10-02 16:45:42.989867 | controller | >f+++++++++ registries.conf
2025-10-02 16:45:42.989883 | controller | >f+++++++++ report.html
2025-10-02 16:45:42.989898 | controller | >f+++++++++ selinux-denials.log
2025-10-02 16:45:42.989914 | controller | >f+++++++++ selinux-listing.log
2025-10-02 16:45:42.989929 | controller | cd+++++++++ ci-framework-data/
2025-10-02 16:45:42.989945 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-02 16:45:42.989961 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-02 16:45:42.989977 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-02 16:45:42.989993 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-02 16:45:42.990009 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-02 16:45:42.990045 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-02 16:45:42.990067 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-02 16:45:42.990084 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-02 16:45:42.990100 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-02 16:45:42.990116 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-02 16:45:42.990133 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-02 16:45:42.990149 | controller | cd+++++++++ registries.conf.d/
2025-10-02 16:45:42.990165 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-02 16:45:42.990180 | controller | cd+++++++++ system-config/
2025-10-02 16:45:42.990196 | controller | cd+++++++++ system-config/libvirt/
2025-10-02 16:45:42.990212 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-02 16:45:42.990228 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-02 16:45:42.990243 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-02 16:45:42.990259 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-02 16:45:42.990275 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-02 16:45:42.990291 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-02 16:45:42.990306 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-02 16:45:42.990321 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-02 16:45:42.990336 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-02 16:45:42.990352 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-02 16:45:42.990374 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-02 16:45:42.990390 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-02 16:45:42.990406 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-02 16:45:42.990421 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-02 16:45:42.990436 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-02 16:45:42.990452 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-02 16:45:43.553476 | controller | changed: .d..t...... ./
2025-10-02 16:45:44.113966 | controller | changed: .d..t...... ./
2025-10-02 16:45:44.156456 | 
2025-10-02 16:45:44.156713 | TASK [Return artifact to Zuul]
2025-10-02 16:45:44.197154 | controller | ok
2025-10-02 16:45:44.245479 | 
2025-10-02 16:45:44.245594 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-02 16:45:44.245727 | 
2025-10-02 16:45:44.245768 | PLAY RECAP
2025-10-02 16:45:44.245819 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-02 16:45:44.245848 | 
2025-10-02 16:45:44.371612 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 16:45:44.372990 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 16:45:45.001597 | 
2025-10-02 16:45:45.001740 | PLAY [all]
2025-10-02 16:45:45.022296 | 
2025-10-02 16:45:45.022430 | TASK [include_role : fetch-output]
2025-10-02 16:45:45.052323 | controller | ok
2025-10-02 16:45:45.072165 | 
2025-10-02 16:45:45.072333 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 16:45:45.118079 | controller | skipping: Conditional result was False
2025-10-02 16:45:45.125339 | 
2025-10-02 16:45:45.125481 | TASK [fetch-output : Set log path for single node]
2025-10-02 16:45:45.166025 | controller | ok
2025-10-02 16:45:45.171687 | 
2025-10-02 16:45:45.171814 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 16:45:45.585660 | controller -> localhost | ok: "/var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/logs"
2025-10-02 16:45:45.830560 | controller -> localhost | ok: "/var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/artifacts"
2025-10-02 16:45:46.069350 | controller -> localhost | ok: "/var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/docs"
2025-10-02 16:45:46.084629 | 
2025-10-02 16:45:46.084741 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 16:45:46.905678 | controller | ok
2025-10-02 16:45:46.905870 | controller | ok: All items complete
2025-10-02 16:45:46.905900 | 
2025-10-02 16:45:47.510558 | controller | ok
2025-10-02 16:45:48.210869 | controller | ok
2025-10-02 16:45:48.233383 | 
2025-10-02 16:45:48.233504 | TASK [include_role : fetch-output-openshift]
2025-10-02 16:45:48.247338 | controller | skipping: Conditional result was False
2025-10-02 16:45:48.254375 | 
2025-10-02 16:45:48.254537 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 16:45:48.654461 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007480
2025-10-02 16:45:48.890855 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007515
2025-10-02 16:45:48.925433 | 
2025-10-02 16:45:48.925573 | PLAY [all]
2025-10-02 16:45:48.941986 | 
2025-10-02 16:45:48.942214 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 16:45:49.494387 | controller | changed
2025-10-02 16:45:49.520509 | 
2025-10-02 16:45:49.520623 | PLAY RECAP
2025-10-02 16:45:49.520667 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 16:45:49.520688 | 
2025-10-02 16:45:49.627766 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 16:45:49.628542 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 16:45:50.325278 | 
2025-10-02 16:45:50.325416 | PLAY [localhost]
2025-10-02 16:45:50.346121 | 
2025-10-02 16:45:50.346272 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 16:45:50.725371 | localhost | changed
2025-10-02 16:45:50.731643 | 
2025-10-02 16:45:50.731794 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 16:45:50.763348 | localhost | ok
2025-10-02 16:45:50.775787 | 
2025-10-02 16:45:50.775922 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 16:45:51.142234 | localhost | changed
2025-10-02 16:45:51.148086 | 
2025-10-02 16:45:51.148180 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 16:45:51.807444 | localhost | changed
2025-10-02 16:45:51.815511 | 
2025-10-02 16:45:51.815642 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 16:45:52.270916 | localhost | Identity added: /var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/tmp/ansible.2gh1aivu (/var/lib/zuul/builds/66f5cb1829354aba8d42ef077c33dbfb/work/tmp/ansible.2gh1aivu)
2025-10-02 16:45:52.271355 | localhost | ok: Runtime: 0:00:00.008986
2025-10-02 16:45:52.281861 | 
2025-10-02 16:45:52.282060 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 16:45:52.616362 | localhost | ok: Runtime: 0:00:00.006398
2025-10-02 16:45:52.628892 | 
2025-10-02 16:45:52.629141 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 16:45:52.697274 | localhost | changed
2025-10-02 16:45:52.707969 | 
2025-10-02 16:45:52.708242 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 16:45:53.133054 | localhost | changed
2025-10-02 16:45:53.174591 | 
2025-10-02 16:45:53.174718 | PLAY [localhost]
2025-10-02 16:45:53.193630 | 
2025-10-02 16:45:53.193749 | TASK [Generate bulk log download script]
2025-10-02 16:45:53.216483 | localhost | ok
2025-10-02 16:45:53.230082 | 
2025-10-02 16:45:53.230191 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 16:45:53.269817 | localhost | ok: All assertions passed
2025-10-02 16:45:53.274538 | 
2025-10-02 16:45:53.274639 | TASK [local-log-download : Create download script]
2025-10-02 16:45:53.808960 | localhost -> localhost | changed
2025-10-02 16:45:53.818193 | 
2025-10-02 16:45:53.818292 | TASK [Register quick-download link]
2025-10-02 16:45:53.837550 | localhost | ok
2025-10-02 16:45:53.878509 | 
2025-10-02 16:45:53.878628 | PLAY [logserver.rdoproject.org]
2025-10-02 16:45:53.888839 | 
2025-10-02 16:45:53.888942 | TASK [Set zuul-log-path fact]
2025-10-02 16:45:53.916171 | logserver.rdoproject.org | ok
2025-10-02 16:45:53.925346 | 
2025-10-02 16:45:53.925438 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 16:45:53.953658 | logserver.rdoproject.org | ok
2025-10-02 16:45:53.958871 | 
2025-10-02 16:45:53.958931 | TASK [upload-logs : Create log directories]
2025-10-02 16:45:54.863250 | logserver.rdoproject.org | changed
2025-10-02 16:45:54.871379 | 
2025-10-02 16:45:54.871528 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 16:45:55.215687 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.010708
2025-10-02 16:45:55.221163 | 
2025-10-02 16:45:55.221241 | TASK [upload-logs : Upload logs to log server]
2025-10-02 16:45:55.993192 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 16:45:55.997524 | 
2025-10-02 16:45:55.997612 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 16:45:56.078802 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 16:45:56.098297 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 16:45:56.111916 | 
2025-10-02 16:45:56.112090 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 16:45:56.163408 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 16:45:56.163649 | 
2025-10-02 16:45:56.167465 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 16:45:56.178015 | 
2025-10-02 16:45:56.178157 | LOOP [upload-logs : Upload console log and json output]