2026-02-26 21:11:48.819677 | Job console starting...
2026-02-26 21:11:49.029840 | Updating repositories
2026-02-26 21:11:49.797517 | Preparing job workspace
2026-02-26 21:11:56.837844 | Running Ansible setup...
2026-02-26 21:12:00.358460 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 21:12:00.894771 | 
2026-02-26 21:12:00.894912 | PLAY [localhost]
2026-02-26 21:12:00.903692 | 
2026-02-26 21:12:00.903773 | TASK [Gathering Facts]
2026-02-26 21:12:01.699570 | localhost | ok
2026-02-26 21:12:01.712100 | 
2026-02-26 21:12:01.712176 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-26 21:12:02.030231 | localhost -> localhost | changed
2026-02-26 21:12:02.035403 | 
2026-02-26 21:12:02.035474 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-26 21:12:02.688416 | localhost -> localhost | changed
2026-02-26 21:12:02.696508 | 
2026-02-26 21:12:02.696578 | TASK [Setup log path fact]
2026-02-26 21:12:02.713901 | localhost | ok
2026-02-26 21:12:02.724889 | 
2026-02-26 21:12:02.724958 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 21:12:02.752091 | localhost | ok
2026-02-26 21:12:02.759342 | 
2026-02-26 21:12:02.759411 | TASK [emit-job-header : Print job information]
2026-02-26 21:12:02.786876 | # Job Information
2026-02-26 21:12:02.786993 | Ansible Version: 2.15.12
2026-02-26 21:12:02.787022 | Job: cifmw-molecule-reproducer
2026-02-26 21:12:02.787043 | Pipeline: github-check
2026-02-26 21:12:02.787062 | Executor: ibm-bm3-ze.softwarefactory-project.io
2026-02-26 21:12:02.787079 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3711
2026-02-26 21:12:02.787097 | Log URL (when completed): https://logserver.rdoproject.org/81f/rdoproject.org/81fe458368b444e0be9aadada24ea2a6/
2026-02-26 21:12:02.787115 | Event ID: 540c49d0-1357-11f1-9de0-58660dab50be
2026-02-26 21:12:02.790810 | 
2026-02-26 21:12:02.790876 | LOOP [emit-job-header : Print node information]
2026-02-26 21:12:02.875422 | localhost | ok:
2026-02-26 21:12:02.875748 | localhost | # Node Information
2026-02-26 21:12:02.875790 | localhost | Inventory Hostname: controller
2026-02-26 21:12:02.875816 | localhost | Hostname: np0005632061
2026-02-26 21:12:02.875837 | localhost | Username: zuul
2026-02-26 21:12:02.875857 | localhost | Distro: CentOS 9
2026-02-26 21:12:02.875875 | localhost | Provider: ibm-bm3-nodepool
2026-02-26 21:12:02.875892 | localhost | Region: regionOne
2026-02-26 21:12:02.875909 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2026-02-26 21:12:02.875926 | localhost | Product Name: OpenStack Compute
2026-02-26 21:12:02.875942 | localhost | Interface IP: 192.168.25.77
2026-02-26 21:12:02.904847 | 
2026-02-26 21:12:02.904928 | PLAY [all]
2026-02-26 21:12:02.911192 | 
2026-02-26 21:12:02.911282 | TASK [Gather network facts]
2026-02-26 21:12:03.356251 | controller | ok
2026-02-26 21:12:03.370247 | 
2026-02-26 21:12:03.370341 | TASK [include_role : start-zuul-console]
2026-02-26 21:12:03.388448 | controller | ok
2026-02-26 21:12:03.399439 | 
2026-02-26 21:12:03.399502 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-26 21:12:03.782339 | controller | ok
2026-02-26 21:12:03.790628 | 
2026-02-26 21:12:03.790691 | TASK [include_role : add-build-sshkey]
2026-02-26 21:12:03.808805 | controller | ok
2026-02-26 21:12:03.820872 | 
2026-02-26 21:12:03.820940 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-26 21:12:04.016535 | controller -> localhost | ok
2026-02-26 21:12:04.021666 | 
2026-02-26 21:12:04.021728 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-26 21:12:04.040601 | controller | ok
2026-02-26 21:12:04.052544 | controller | included: /var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-26 21:12:04.057909 | 
2026-02-26 21:12:04.057970 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-26 21:12:04.433025 | controller -> localhost | Generating public/private rsa key pair.
2026-02-26 21:12:04.433245 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/81fe458368b444e0be9aadada24ea2a6_id_rsa.
2026-02-26 21:12:04.433307 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/81fe458368b444e0be9aadada24ea2a6_id_rsa.pub.
2026-02-26 21:12:04.433336 | controller -> localhost | The key fingerprint is:
2026-02-26 21:12:04.433360 | controller -> localhost | SHA256:39+sFr2EtKKOE1CCdh2GJ10sPLiVSvtzcow0ALu11uE zuul-build-sshkey
2026-02-26 21:12:04.433383 | controller -> localhost | The key's randomart image is:
2026-02-26 21:12:04.433402 | controller -> localhost | +---[RSA 3072]----+
2026-02-26 21:12:04.433423 | controller -> localhost | |   .o *o=.       |
2026-02-26 21:12:04.433444 | controller -> localhost | |   o.O.@ .       |
2026-02-26 21:12:04.433465 | controller -> localhost | |  ..o.@.o        |
2026-02-26 21:12:04.433485 | controller -> localhost | |    o=+o.    .   |
2026-02-26 21:12:04.433504 | controller -> localhost | |   . o+ES   . o. |
2026-02-26 21:12:04.433522 | controller -> localhost | |    .  * = o o...|
2026-02-26 21:12:04.433540 | controller -> localhost | |        * o o ...|
2026-02-26 21:12:04.433561 | controller -> localhost | |       ...   ..+ |
2026-02-26 21:12:04.433580 | controller -> localhost | |       .o.   .o.o|
2026-02-26 21:12:04.433598 | controller -> localhost | +----[SHA256]-----+
2026-02-26 21:12:04.433647 | controller -> localhost | ok: Runtime: 0:00:00.042584
2026-02-26 21:12:04.439438 | 
2026-02-26 21:12:04.439504 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-26 21:12:04.467990 | controller | ok
2026-02-26 21:12:04.477556 | controller | included: /var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-26 21:12:04.485153 | 
2026-02-26 21:12:04.485226 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-26 21:12:04.509029 | controller | skipping: Conditional result was False
2026-02-26 21:12:04.514116 | 
2026-02-26 21:12:04.514188 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-26 21:12:05.010572 | controller | changed
2026-02-26 21:12:05.015072 | 
2026-02-26 21:12:05.015133 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-26 21:12:05.288132 | controller | ok
2026-02-26 21:12:05.292965 | 
2026-02-26 21:12:05.293034 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-26 21:12:06.470730 | controller | changed
2026-02-26 21:12:06.475570 | 
2026-02-26 21:12:06.475637 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-26 21:12:07.616796 | controller | changed
2026-02-26 21:12:07.621682 | 
2026-02-26 21:12:07.621749 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-26 21:12:07.645564 | controller | skipping: Conditional result was False
2026-02-26 21:12:07.652568 | 
2026-02-26 21:12:07.652636 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-26 21:12:07.950468 | controller -> localhost | changed
2026-02-26 21:12:07.960048 | 
2026-02-26 21:12:07.960112 | TASK [add-build-sshkey : Add back temp key]
2026-02-26 21:12:08.176284 | controller -> localhost | Identity added: /var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/81fe458368b444e0be9aadada24ea2a6_id_rsa (zuul-build-sshkey)
2026-02-26 21:12:08.176565 | controller -> localhost | ok: Runtime: 0:00:00.008743
2026-02-26 21:12:08.182535 | 
2026-02-26 21:12:08.182599 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-26 21:12:08.547545 | controller | ok
2026-02-26 21:12:08.552069 | 
2026-02-26 21:12:08.552140 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-26 21:12:08.577862 | controller | skipping: Conditional result was False
2026-02-26 21:12:08.586605 | 
2026-02-26 21:12:08.586666 | TASK [include_role : validate-host]
2026-02-26 21:12:08.605228 | controller | ok
2026-02-26 21:12:08.623677 | 
2026-02-26 21:12:08.623744 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-26 21:12:08.651968 | controller | ok
2026-02-26 21:12:08.656154 | 
2026-02-26 21:12:08.656224 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-26 21:12:08.851588 | controller -> localhost | ok
2026-02-26 21:12:08.857923 | 
2026-02-26 21:12:08.858019 | TASK [validate-host : Collect information about the host]
2026-02-26 21:12:09.573064 | controller | ok
2026-02-26 21:12:09.580236 | 
2026-02-26 21:12:09.580312 | TASK [validate-host : Sanitize hostname]
2026-02-26 21:12:09.618419 | controller | ok
2026-02-26 21:12:09.622975 | 
2026-02-26 21:12:09.623036 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-26 21:12:09.980768 | controller -> localhost | changed
2026-02-26 21:12:09.986684 | 
2026-02-26 21:12:09.986749 | TASK [validate-host : Collect information about zuul worker]
2026-02-26 21:12:10.372466 | controller | ok
2026-02-26 21:12:10.377546 | 
2026-02-26 21:12:10.377616 | TASK [validate-host : Write out all zuul information for each host]
2026-02-26 21:12:10.725984 | controller -> localhost | changed
2026-02-26 21:12:10.735835 | 
2026-02-26 21:12:10.735902 | TASK [include_role : prepare-workspace-openshift]
2026-02-26 21:12:10.750184 | controller | skipping: Conditional result was False
2026-02-26 21:12:10.754768 | 
2026-02-26 21:12:10.754831 | TASK [include_role : remove-zuul-sshkey]
2026-02-26 21:12:10.767902 | controller | skipping: Conditional result was False
2026-02-26 21:12:10.772401 | 
2026-02-26 21:12:10.772464 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-26 21:12:11.037946 | controller | ok: "logs"
2026-02-26 21:12:11.038468 | controller | ok: All items complete
2026-02-26 21:12:11.038503 | 
2026-02-26 21:12:11.278913 | controller | ok: "artifacts"
2026-02-26 21:12:11.513261 | controller | ok: "docs"
2026-02-26 21:12:11.527515 | 
2026-02-26 21:12:11.527699 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-26 21:12:11.799299 | controller | changed: "logs"
2026-02-26 21:12:12.035000 | controller | changed: "artifacts"
2026-02-26 21:12:12.274375 | controller | changed: "docs"
2026-02-26 21:12:12.296848 | 
2026-02-26 21:12:12.297003 | PLAY RECAP
2026-02-26 21:12:12.297055 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-26 21:12:12.297082 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 21:12:12.297100 | 
2026-02-26 21:12:12.389660 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 21:12:12.390588 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-26 21:12:12.923227 | 
2026-02-26 21:12:12.923380 | PLAY [all]
2026-02-26 21:12:12.942646 | 
2026-02-26 21:12:12.942721 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-26 21:12:13.012611 | controller | ok
2026-02-26 21:12:13.017465 | 
2026-02-26 21:12:13.017554 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-26 21:12:13.423758 | controller | changed
2026-02-26 21:12:13.429329 | 
2026-02-26 21:12:13.429406 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-26 21:12:14.774855 | controller | changed
2026-02-26 21:12:14.785049 | 
2026-02-26 21:12:14.785124 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-02-26 21:12:15.246586 | controller | changed:
2026-02-26 21:12:15.246793 | controller | {
2026-02-26 21:12:15.246827 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-02-26 21:12:15.246863 | controller | }
2026-02-26 21:12:15.529822 | controller | changed:
2026-02-26 21:12:15.529913 | controller | {
2026-02-26 21:12:15.529941 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-02-26 21:12:15.529962 | controller | }
2026-02-26 21:12:15.838115 | controller | changed:
2026-02-26 21:12:15.838251 | controller | {
2026-02-26 21:12:15.838301 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-02-26 21:12:15.838325 | controller | }
2026-02-26 21:12:16.142946 | controller | changed:
2026-02-26 21:12:16.143046 | controller | {
2026-02-26 21:12:16.143074 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-02-26 21:12:16.143094 | controller | }
2026-02-26 21:12:16.457039 | controller | changed:
2026-02-26 21:12:16.457153 | controller | {
2026-02-26 21:12:16.457180 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-02-26 21:12:16.457200 | controller | }
2026-02-26 21:12:16.755698 | controller | changed:
2026-02-26 21:12:16.755753 | controller | {
2026-02-26 21:12:16.755781 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-02-26 21:12:16.755801 | controller | }
2026-02-26 21:12:17.059947 | controller | changed:
2026-02-26 21:12:17.060030 | controller | {
2026-02-26 21:12:17.060058 | 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"
2026-02-26 21:12:17.060080 | controller | }
2026-02-26 21:12:17.376505 | controller | changed:
2026-02-26 21:12:17.376561 | controller | {
2026-02-26 21:12:17.376588 | 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"
2026-02-26 21:12:17.376608 | controller | }
2026-02-26 21:12:17.681375 | controller | changed:
2026-02-26 21:12:17.681435 | controller | {
2026-02-26 21:12:17.681460 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-02-26 21:12:17.681479 | controller | }
2026-02-26 21:12:17.978000 | controller | changed:
2026-02-26 21:12:17.978067 | controller | {
2026-02-26 21:12:17.978091 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-02-26 21:12:17.978112 | controller | }
2026-02-26 21:12:18.276561 | controller | changed:
2026-02-26 21:12:18.276615 | controller | {
2026-02-26 21:12:18.276640 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-02-26 21:12:18.276660 | controller | }
2026-02-26 21:12:18.578401 | controller | changed:
2026-02-26 21:12:18.578451 | controller | {
2026-02-26 21:12:18.578475 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-02-26 21:12:18.578494 | controller | }
2026-02-26 21:12:18.879981 | controller | changed:
2026-02-26 21:12:18.880036 | controller | {
2026-02-26 21:12:18.880060 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-02-26 21:12:18.880079 | controller | }
2026-02-26 21:12:19.195793 | controller | changed:
2026-02-26 21:12:19.195903 | controller | {
2026-02-26 21:12:19.195930 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-02-26 21:12:19.195949 | controller | }
2026-02-26 21:12:19.492089 | controller | changed:
2026-02-26 21:12:19.492143 | controller | {
2026-02-26 21:12:19.492168 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-02-26 21:12:19.492188 | controller | }
2026-02-26 21:12:19.780717 | controller | changed:
2026-02-26 21:12:19.780768 | controller | {
2026-02-26 21:12:19.780791 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-02-26 21:12:19.780809 | controller | }
2026-02-26 21:12:20.067609 | controller | changed:
2026-02-26 21:12:20.067661 | controller | {
2026-02-26 21:12:20.067686 | 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"
2026-02-26 21:12:20.067707 | controller | }
2026-02-26 21:12:20.362167 | controller | changed:
2026-02-26 21:12:20.362229 | controller | {
2026-02-26 21:12:20.362256 | 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"
2026-02-26 21:12:20.362304 | controller | }
2026-02-26 21:12:20.662900 | controller | changed:
2026-02-26 21:12:20.662953 | controller | {
2026-02-26 21:12:20.662978 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-02-26 21:12:20.662997 | controller | }
2026-02-26 21:12:20.967771 | controller | changed:
2026-02-26 21:12:20.967825 | controller | {
2026-02-26 21:12:20.967851 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-02-26 21:12:20.967871 | controller | }
2026-02-26 21:12:21.264735 | controller | changed:
2026-02-26 21:12:21.264802 | controller | {
2026-02-26 21:12:21.264827 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-02-26 21:12:21.264847 | controller | }
2026-02-26 21:12:21.561277 | controller | changed:
2026-02-26 21:12:21.561525 | controller | {
2026-02-26 21:12:21.561550 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-02-26 21:12:21.561571 | controller | }
2026-02-26 21:12:21.859216 | controller | changed:
2026-02-26 21:12:21.859295 | controller | {
2026-02-26 21:12:21.859324 | 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"
2026-02-26 21:12:21.859345 | controller | }
2026-02-26 21:12:22.157705 | controller | changed:
2026-02-26 21:12:22.157880 | controller | {
2026-02-26 21:12:22.157904 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-02-26 21:12:22.157922 | controller | }
2026-02-26 21:12:22.450161 | controller | changed:
2026-02-26 21:12:22.450244 | controller | {
2026-02-26 21:12:22.450291 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-02-26 21:12:22.450313 | controller | }
2026-02-26 21:12:22.749558 | controller | changed:
2026-02-26 21:12:22.749726 | controller | {
2026-02-26 21:12:22.749751 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-02-26 21:12:22.749770 | controller | }
2026-02-26 21:12:22.771729 | 
2026-02-26 21:12:22.771916 | TASK [Set timezone to UTC]
2026-02-26 21:12:23.296102 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-02-26 21:12:23.301764 | 
2026-02-26 21:12:23.301836 | TASK [Create nodepool directory]
2026-02-26 21:12:23.606658 | controller | changed
2026-02-26 21:12:23.612123 | 
2026-02-26 21:12:23.612195 | TASK [Create nodepool sub_nodes file]
2026-02-26 21:12:24.577439 | controller | changed
2026-02-26 21:12:24.582437 | 
2026-02-26 21:12:24.582506 | TASK [Create nodepool sub_nodes_private file]
2026-02-26 21:12:25.545453 | controller | changed
2026-02-26 21:12:25.551863 | 
2026-02-26 21:12:25.551945 | LOOP [Populate nodepool sub_nodes file]
2026-02-26 21:12:25.582759 | 
2026-02-26 21:12:25.582894 | LOOP [Populate nodepool sub_nodes_private file]
2026-02-26 21:12:25.612367 | 
2026-02-26 21:12:25.612503 | TASK [Create nodepool primary file]
2026-02-26 21:12:25.636410 | controller | skipping: Conditional result was False
2026-02-26 21:12:25.641562 | 
2026-02-26 21:12:25.641629 | TASK [Create nodepool node_private for this node]
2026-02-26 21:12:26.615752 | controller | changed
2026-02-26 21:12:26.621454 | 
2026-02-26 21:12:26.621528 | LOOP [Copy ssh keys to nodepool directory]
2026-02-26 21:12:27.016917 | controller | ok: Item: id_rsa Runtime: 0:00:00.004821
2026-02-26 21:12:27.017160 | 
2026-02-26 21:12:27.239468 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005947
2026-02-26 21:12:27.252771 | 
2026-02-26 21:12:27.252942 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-02-26 21:12:28.229875 | controller | changed
2026-02-26 21:12:28.234912 | 
2026-02-26 21:12:28.234983 | TASK [Validate sudoers config after edits]
2026-02-26 21:12:28.512011 | controller | /etc/sudoers: parsed OK
2026-02-26 21:12:28.512064 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-02-26 21:12:28.512075 | controller | /etc/sudoers.d/zuul: parsed OK
2026-02-26 21:12:28.512082 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-02-26 21:12:28.764561 | controller | ok: Runtime: 0:00:00.005309
2026-02-26 21:12:28.771172 | 
2026-02-26 21:12:28.771255 | TASK [Show the environment passed in to job shell scripts]
2026-02-26 21:12:29.041620 | controller | SHELL=/bin/bash
2026-02-26 21:12:29.041664 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-02-26 21:12:29.041673 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-02-26 21:12:29.041680 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/11/3711/da4d1e666b464947a2398ad93b49343a735dde84
2026-02-26 21:12:29.041686 | controller | PWD=/home/zuul
2026-02-26 21:12:29.041692 | controller | ZUUL_PIPELINE=github-check
2026-02-26 21:12:29.041739 | controller | LOGNAME=zuul
2026-02-26 21:12:29.041749 | controller | XDG_SESSION_TYPE=tty
2026-02-26 21:12:29.041755 | controller | _=/usr/bin/env
2026-02-26 21:12:29.041760 | controller | MOTD_SHOWN=pam
2026-02-26 21:12:29.041765 | controller | HOME=/home/zuul
2026-02-26 21:12:29.041771 | controller | LANG=en_US.UTF-8
2026-02-26 21:12:29.041776 | controller | SSH_CONNECTION=192.168.25.12 40436 192.168.25.77 22
2026-02-26 21:12:29.041782 | 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
2026-02-26 21:12:29.041791 | controller | ZUUL_CHANGE_IDS=3711,da4d1e666b464947a2398ad93b49343a735dde84
2026-02-26 21:12:29.041796 | controller | WORKSPACE=/home/zuul/workspace
2026-02-26 21:12:29.041802 | controller | XDG_SESSION_CLASS=user
2026-02-26 21:12:29.041807 | controller | SELINUX_ROLE_REQUESTED=
2026-02-26 21:12:29.041813 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-02-26 21:12:29.041818 | controller | USER=zuul
2026-02-26 21:12:29.041824 | controller | ZUUL_VOTING=True
2026-02-26 21:12:29.041829 | controller | BUILD_TIMEOUT=5400000
2026-02-26 21:12:29.041834 | controller | SELINUX_USE_CURRENT_RANGE=
2026-02-26 21:12:29.041839 | controller | SHLVL=1
2026-02-26 21:12:29.041845 | controller | ZUUL_PATCHSET=da4d1e666b464947a2398ad93b49343a735dde84
2026-02-26 21:12:29.041850 | controller | XDG_SESSION_ID=1
2026-02-26 21:12:29.041856 | controller | ZUUL_BRANCH=main
2026-02-26 21:12:29.041861 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-02-26 21:12:29.041867 | controller | SSH_CLIENT=192.168.25.12 40436 22
2026-02-26 21:12:29.041874 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-02-26 21:12:29.041879 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-02-26 21:12:29.041885 | controller | which_declare=declare -f
2026-02-26 21:12:29.041891 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-02-26 21:12:29.041897 | controller | SELINUX_LEVEL_REQUESTED=
2026-02-26 21:12:29.041902 | controller | ZUUL_CHANGE=3711
2026-02-26 21:12:29.041907 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-02-26 21:12:29.041926 | controller | ZUUL_UUID=81fe458368b444e0be9aadada24ea2a6
2026-02-26 21:12:29.041932 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-02-26 21:12:29.041938 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-02-26 21:12:29.041943 | controller | }
2026-02-26 21:12:29.298795 | controller | ok: Runtime: 0:00:00.005759
2026-02-26 21:12:29.305131 | 
2026-02-26 21:12:29.305201 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-02-26 21:12:29.330190 | controller | skipping: Conditional result was False
2026-02-26 21:12:29.335748 | 
2026-02-26 21:12:29.335816 | TASK [Symlink /home/zuul-worker/workspace]
2026-02-26 21:12:29.889623 | controller | skipping: Conditional result was False
2026-02-26 21:12:29.896238 | 
2026-02-26 21:12:29.896331 | TASK [Ensure legacy workspace directory]
2026-02-26 21:12:30.150140 | controller | changed
2026-02-26 21:12:30.168438 | 
2026-02-26 21:12:30.168490 | PLAY RECAP
2026-02-26 21:12:30.168543 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-26 21:12:30.168565 | 
2026-02-26 21:12:30.261403 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-26 21:12:30.262625 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-26 21:12:30.796306 | 
2026-02-26 21:12:30.796453 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-02-26 21:12:30.817064 | 
2026-02-26 21:12:30.817133 | TASK [Create zuul-output directory]
2026-02-26 21:12:31.204198 | controller | changed
2026-02-26 21:12:31.208835 | 
2026-02-26 21:12:31.208904 | TASK [Slurp Zuul inventory test]
2026-02-26 21:12:31.490358 | controller -> localhost | ok
2026-02-26 21:12:31.497709 | 
2026-02-26 21:12:31.497822 | TASK [Save zuul inventory]
2026-02-26 21:12:32.643816 | controller | changed
2026-02-26 21:12:32.649681 | 
2026-02-26 21:12:32.649750 | TASK [Save zuul vars without the change_message]
2026-02-26 21:12:33.667923 | controller | changed
2026-02-26 21:12:33.685706 | 
2026-02-26 21:12:33.685768 | PLAY RECAP
2026-02-26 21:12:33.685812 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 21:12:33.685833 | 
2026-02-26 21:12:33.814161 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-26 21:12:33.814970 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-26 21:12:34.365316 | 
2026-02-26 21:12:34.365559 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-02-26 21:12:34.388954 | 
2026-02-26 21:12:34.389062 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-02-26 21:12:34.410423 | controller | ok
2026-02-26 21:12:34.426611 | 
2026-02-26 21:12:34.426689 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-26 21:12:34.462169 | controller | skipping: Conditional result was False
2026-02-26 21:12:34.468066 | 
2026-02-26 21:12:34.468135 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-26 21:12:34.895692 | controller | ok
2026-02-26 21:12:34.901856 | 
2026-02-26 21:12:34.901937 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-26 21:12:35.854529 | controller | ok
2026-02-26 21:12:35.864236 | 
2026-02-26 21:12:35.864323 | TASK [Prepare workspace]
2026-02-26 21:12:35.883343 | controller | ok
2026-02-26 21:12:35.900093 | 
2026-02-26 21:12:35.900164 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-26 21:12:36.254107 | controller | ok
2026-02-26 21:12:36.260255 | 
2026-02-26 21:12:36.260349 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-26 21:12:38.708358 | controller | Output suppressed because no_log was given
2026-02-26 21:12:38.717468 | 
2026-02-26 21:12:38.717533 | LOOP [Create zuul-output directory]
2026-02-26 21:12:38.971939 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-02-26 21:12:39.203830 | controller | ok: "/home/zuul/zuul-output/logs"
2026-02-26 21:12:39.218493 | 
2026-02-26 21:12:39.218639 | TASK [Install required packages]
2026-02-26 21:13:12.593143 | controller | ok: Nothing to do
2026-02-26 21:13:12.601450 | 
2026-02-26 21:13:12.601524 | TASK [Install venv]
2026-02-26 21:14:15.187865 | controller | changed
2026-02-26 21:14:15.240731 | 
2026-02-26 21:14:15.240840 | PLAY RECAP
2026-02-26 21:14:15.240880 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-26 21:14:15.240900 | 
2026-02-26 21:14:15.311004 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-26 21:14:15.313149 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-26 21:14:15.823398 | 
2026-02-26 21:14:15.823529 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-02-26 21:14:15.842768 | 
2026-02-26 21:14:15.842845 | TASK [Gather required facts]
2026-02-26 21:14:16.386991 | controller | ok
2026-02-26 21:14:16.392614 | 
2026-02-26 21:14:16.392723 | TASK [Load environment var if instructed to]
2026-02-26 21:14:16.416781 | controller | skipping: Conditional result was False
2026-02-26 21:14:16.422535 | 
2026-02-26 21:14:16.422608 | TASK [Ensure group_vars dir exists]
2026-02-26 21:14:16.818818 | controller | ok
2026-02-26 21:14:16.823773 | 
2026-02-26 21:14:16.823839 | TASK [Print related variables]
2026-02-26 21:14:16.853123 | controller | ok:
2026-02-26 21:14:16.853248 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-02-26 21:14:16.853294 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer
2026-02-26 21:14:16.857610 | 
2026-02-26 21:14:16.857674 | TASK [Run molecule]
2026-02-26 21:14:17.884672 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-02-26 21:14:18.142600 | controller | INFO     Performing prerun with role_name_check=0...
2026-02-26 21:14:35.138799 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.139215 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.139675 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.140124 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.140612 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+e6c16b53 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0+e6c16b53 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.141059 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.141511 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.141957 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.142398 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.142840 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.143258 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.143721 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.144149 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.144607 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.145040 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 21:14:35.154659 | controller | INFO     Running crc_layout > prepare
2026-02-26 21:14:35.824027 | controller |
2026-02-26 21:14:35.824137 | controller | PLAY [Prepare] *****************************************************************
2026-02-26 21:14:35.824231 | controller |
2026-02-26 21:14:35.824348 | controller | TASK [Gathering Facts] *********************************************************
2026-02-26 21:14:35.824643 | controller | Thursday 26 February 2026  21:14:35 +0000 (0:00:00.019)       0:00:00.019 *****
2026-02-26 21:14:36.648779 | controller | ok: [instance]
2026-02-26 21:14:36.648904 | controller |
2026-02-26 21:14:36.649053 | controller | TASK [Create custom basedir] ***************************************************
2026-02-26 21:14:36.649182 | controller | Thursday 26 February 2026  21:14:36 +0000 (0:00:00.826)       0:00:00.845 *****
2026-02-26 21:14:36.956264 | controller | changed: [instance]
2026-02-26 21:14:36.956365 | controller |
2026-02-26 21:14:36.956461 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-02-26 21:14:36.956547 | controller | Thursday 26 February 2026  21:14:36 +0000 (0:00:00.307)       0:00:01.153 *****
2026-02-26 21:14:36.973823 | controller | skipping: [instance]
2026-02-26 21:14:36.973953 | controller |
2026-02-26 21:14:36.974124 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-02-26 21:14:36.974289 | controller | Thursday 26 February 2026  21:14:36 +0000 (0:00:00.017)       0:00:01.171 *****
2026-02-26 21:14:37.015908 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-02-26 21:14:37.016018 | controller |
2026-02-26 21:14:37.016130 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-02-26 21:14:37.016239 | controller | Thursday 26 February 2026  21:14:37 +0000 (0:00:00.041)       0:00:01.213 *****
2026-02-26 21:14:37.297018 | controller | ok: [instance]
2026-02-26 21:14:37.297104 | controller |
2026-02-26 21:14:37.297197 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-02-26 21:14:37.297288 | controller | Thursday 26 February 2026  21:14:37 +0000 (0:00:00.281)       0:00:01.494 *****
2026-02-26 21:14:37.511566 | controller | ok: [instance]
2026-02-26 21:14:37.511614 | controller |
2026-02-26 21:14:37.511714 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-02-26 21:14:37.511803 | controller | Thursday 26 February 2026  21:14:37 +0000 (0:00:00.214)       0:00:01.709 *****
2026-02-26 21:14:38.196753 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-02-26 21:14:38.196830 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-02-26 21:14:38.196924 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-02-26 21:14:38.197013 | controller |
2026-02-26 21:14:38.197104 | controller | TASK [Deploy repo-setup] *******************************************************
2026-02-26 21:14:38.197181 | controller | Thursday 26 February 2026  21:14:38 +0000 (0:00:00.684)       0:00:02.393 *****
2026-02-26 21:14:38.254233 | controller |
2026-02-26 21:14:38.254389 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-02-26 21:14:38.254509 | controller | Thursday 26 February 2026  21:14:38 +0000 (0:00:00.057)       0:00:02.451 *****
2026-02-26 21:14:38.767966 | controller | changed: [instance] => (item=tmp)
2026-02-26 21:14:38.768090 | controller | changed: [instance] => (item=artifacts/repositories)
2026-02-26 21:14:38.768104 | controller | changed: [instance] => (item=venv/repo_setup)
2026-02-26 21:14:38.768175 | controller |
2026-02-26 21:14:38.768280 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-02-26 21:14:38.768401 | controller | Thursday 26 February 2026  21:14:38 +0000 (0:00:00.513)       0:00:02.965 *****
2026-02-26 21:14:39.584329 | controller | ok: [instance]
2026-02-26 21:14:39.584403 | controller |
2026-02-26 21:14:39.584506 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-02-26 21:14:39.584602 | controller | Thursday 26 February 2026  21:14:39 +0000 (0:00:00.816)       0:00:03.781 *****
2026-02-26 21:14:40.671545 | controller | changed: [instance]
2026-02-26 21:14:40.672172 | controller |
2026-02-26 21:14:48.096080 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-02-26 21:14:48.096156 | controller | Thursday 26 February 2026  21:14:40 +0000 (0:00:01.086)       0:00:04.868 *****
2026-02-26 21:14:48.096171 | controller | changed: [instance]
2026-02-26 21:14:48.772011 | controller |
2026-02-26 21:14:48.772073 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-02-26 21:14:48.772084 | controller | Thursday 26 February 2026  21:14:48 +0000 (0:00:07.423)       0:00:12.292 *****
2026-02-26 21:14:48.772099 | controller | changed: [instance]
2026-02-26 21:14:48.793496 | controller |
2026-02-26 21:14:48.793555 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-02-26 21:14:48.793585 | controller | Thursday 26 February 2026  21:14:48 +0000 (0:00:00.676)       0:00:12.968 *****
2026-02-26 21:14:48.793604 | controller | skipping: [instance]
2026-02-26 21:14:49.596438 | controller |
2026-02-26 21:14:49.596483 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-02-26 21:14:49.596494 | controller | Thursday 26 February 2026  21:14:48 +0000 (0:00:00.022)       0:00:12.990 *****
2026-02-26 21:14:49.596507 | controller | changed: [instance]
2026-02-26 21:14:49.633049 | controller |
2026-02-26 21:14:49.633134 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-02-26 21:14:49.633145 | controller | Thursday 26 February 2026  21:14:49 +0000 (0:00:00.802)       0:00:13.793 *****
2026-02-26 21:14:49.633161 | controller | skipping: [instance]
2026-02-26 21:14:49.669157 | controller |
2026-02-26 21:14:49.669186 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-02-26 21:14:49.669196 | controller | Thursday 26 February 2026  21:14:49 +0000 (0:00:00.036)       0:00:13.830 *****
2026-02-26 21:14:49.669206 | controller | skipping: [instance]
2026-02-26 21:14:49.669683 | controller |
2026-02-26 21:14:49.705087 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-02-26 21:14:49.705119 | controller | Thursday 26 February 2026  21:14:49 +0000 (0:00:00.036)       0:00:13.866 *****
2026-02-26 21:14:49.705138 | controller | skipping: [instance]
2026-02-26 21:14:50.235198 | controller |
2026-02-26 21:14:50.235231 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-02-26 21:14:50.235239 | controller | Thursday 26 February 2026  21:14:49 +0000 (0:00:00.035)       0:00:13.901 *****
2026-02-26 21:14:50.235248 | controller | changed: [instance]
2026-02-26 21:14:50.663479 | controller |
2026-02-26 21:14:50.663534 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-02-26 21:14:50.663545 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.530)       0:00:14.432 *****
2026-02-26 21:14:50.663561 | controller | changed: [instance]
2026-02-26 21:14:50.663607 | controller |
2026-02-26 21:14:50.663617 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-02-26 21:14:50.663774 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.428)       0:00:14.860 *****
2026-02-26 21:14:50.693753 | controller | skipping: [instance]
2026-02-26 21:14:50.723452 | controller |
2026-02-26 21:14:50.723510 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-02-26 21:14:50.723522 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.030)       0:00:14.891 *****
2026-02-26 21:14:50.723544 | controller | skipping: [instance]
2026-02-26 21:14:50.723610 | controller |
2026-02-26 21:14:50.723624 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-02-26 21:14:50.723663 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.029)       0:00:14.920 *****
2026-02-26 21:14:50.751707 | controller | skipping: [instance]
2026-02-26 21:14:50.792260 | controller |
2026-02-26 21:14:50.792279 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-02-26 21:14:50.792289 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.028)       0:00:14.949 *****
2026-02-26 21:14:50.792300 | controller | ok: [instance]
2026-02-26 21:14:50.821689 | controller |
2026-02-26 21:14:50.821710 | controller | TASK [repo_setup : Create download directory] **********************************
2026-02-26 21:14:50.821718 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.040)       0:00:14.989 *****
2026-02-26 21:14:50.821738 | controller | skipping: [instance]
2026-02-26 21:14:50.821894 | controller |
2026-02-26 21:14:50.822050 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-02-26 21:14:50.822173 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.029)       0:00:15.019 *****
2026-02-26 21:14:50.850021 | controller | skipping: [instance]
2026-02-26 21:14:50.850183 | controller |
2026-02-26 21:14:50.850353 | controller | TASK [Download the RPM] ********************************************************
2026-02-26 21:14:50.850482 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.028)       0:00:15.047 *****
2026-02-26 21:14:50.878867 | controller | skipping: [instance]
2026-02-26 21:14:50.879152 | controller |
2026-02-26 21:14:50.879507 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-02-26 21:14:50.879745 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.028)       0:00:15.076 *****
2026-02-26 21:14:50.906227 | controller | skipping: [instance]
2026-02-26 21:14:50.906423 | controller |
2026-02-26 21:14:50.906596 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-02-26 21:14:50.906751 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.027)       0:00:15.104 *****
2026-02-26 21:14:50.933533 | controller | skipping: [instance]
2026-02-26 21:14:50.933717 | controller |
2026-02-26 21:14:50.933873 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-02-26 21:14:50.934030 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.027)       0:00:15.131 *****
2026-02-26 21:14:50.962791 | controller | skipping: [instance]
2026-02-26 21:14:50.962953 | controller |
2026-02-26 21:14:50.963108 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-02-26 21:14:50.963260 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.029)       0:00:15.160 *****
2026-02-26 21:14:50.989507 | controller | skipping: [instance]
2026-02-26 21:14:50.989794 | controller |
2026-02-26 21:14:50.990044 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-02-26 21:14:50.990279 | controller | Thursday 26 February 2026  21:14:50 +0000 (0:00:00.026)       0:00:15.187 *****
2026-02-26 21:14:51.175860 | controller | ok: [instance]
2026-02-26 21:14:51.379431 | controller |
2026-02-26 21:14:51.379482 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-02-26 21:14:51.379492 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.186)       0:00:15.373 *****
2026-02-26 21:14:51.379503 | controller | changed: [instance]
2026-02-26 21:14:51.379525 | controller |
2026-02-26 21:14:51.379533 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-02-26 21:14:51.605513 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.203)       0:00:15.577 *****
2026-02-26 21:14:51.605581 | controller | changed: [instance]
2026-02-26 21:14:51.628013 | controller |
2026-02-26 21:14:51.628045 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-02-26 21:14:51.628055 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.225)       0:00:15.802 *****
2026-02-26 21:14:51.628068 | controller | skipping: [instance]
2026-02-26 21:14:51.650266 | controller |
2026-02-26 21:14:51.650299 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-02-26 21:14:51.650347 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.022)       0:00:15.825 *****
2026-02-26 21:14:51.650365 | controller | skipping: [instance]
2026-02-26 21:14:51.672410 | controller |
2026-02-26 21:14:51.672453 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-02-26 21:14:51.672463 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.021)       0:00:15.847 *****
2026-02-26 21:14:51.672477 | controller | skipping: [instance]
2026-02-26 21:14:51.695580 | controller |
2026-02-26 21:14:51.695636 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-02-26 21:14:51.695651 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.022)       0:00:15.869 *****
2026-02-26 21:14:51.695669 | controller | skipping: [instance]
2026-02-26 21:14:51.695717 | controller |
2026-02-26 21:14:51.695732 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-02-26 21:14:51.695872 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.023)       0:00:15.892 *****
2026-02-26 21:14:51.719178 | controller | skipping: [instance]
2026-02-26 21:14:51.719344 | controller |
2026-02-26 21:14:51.719487 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-02-26 21:14:51.719626 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.024)       0:00:15.916 *****
2026-02-26 21:14:51.742958 | controller | skipping: [instance]
2026-02-26 21:14:51.743218 | controller |
2026-02-26 21:14:51.743515 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-02-26 21:14:51.743778 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.023)       0:00:15.940 *****
2026-02-26 21:14:51.759882 | controller | skipping: [instance]
2026-02-26 21:14:51.760046 | controller |
2026-02-26 21:14:51.760211 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-02-26 21:14:51.760392 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.017)       0:00:15.957 *****
2026-02-26 21:14:51.788066 | controller | skipping: [instance]
2026-02-26 21:14:51.788229 | controller |
2026-02-26 21:14:51.788425 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-02-26 21:14:51.788598 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.028)       0:00:15.985 *****
2026-02-26 21:14:51.805204 | controller | skipping: [instance]
2026-02-26 21:14:51.805385 | controller |
2026-02-26 21:14:51.805537 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-02-26 21:14:51.805698 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.017)       0:00:16.003 *****
2026-02-26 21:14:51.825759 | controller | skipping: [instance]
2026-02-26 21:14:51.825992 | controller |
2026-02-26 21:14:51.826130 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-02-26 21:14:51.826268 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.020)       0:00:16.023 *****
2026-02-26 21:14:51.854268 | controller | skipping: [instance]
2026-02-26 21:14:51.854594 | controller |
2026-02-26 21:14:51.854968 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-02-26 21:14:51.855206 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.028)       0:00:16.051 *****
2026-02-26 21:14:51.882916 | controller | skipping: [instance]
2026-02-26 21:14:51.883081 | controller |
2026-02-26 21:14:51.883234 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-02-26 21:14:51.883409 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.028)       0:00:16.080 *****
2026-02-26 21:14:51.911645 | controller | skipping: [instance]
2026-02-26 21:14:51.911822 | controller |
2026-02-26 21:14:51.911987 | controller | TASK [test_deps : Install additional packages] *********************************
2026-02-26 21:14:51.912129 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.028)       0:00:16.109 *****
2026-02-26 21:14:51.942093 | controller | skipping: [instance]
2026-02-26 21:14:51.942379 | controller |
2026-02-26 21:14:51.942661 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-02-26 21:14:51.942914 | controller | Thursday 26 February 2026  21:14:51 +0000 (0:00:00.030)       0:00:16.139 *****
2026-02-26 21:15:36.868369 | controller | ok: [instance]
2026-02-26 21:15:38.042682 | controller |
2026-02-26 21:15:38.042713 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-02-26 21:15:38.042722 | controller | Thursday 26 February 2026  21:15:36 +0000 (0:00:44.925)       0:01:01.065 *****
2026-02-26 21:15:38.042741 | controller | ok: [instance]
2026-02-26 21:15:38.074469 | controller |
2026-02-26 21:15:38.074507 | controller | TASK [test_deps : Install extra packages] **************************************
2026-02-26 21:15:38.074517 | controller | Thursday 26 February 2026  21:15:38 +0000 (0:00:01.174)       0:01:02.239 *****
2026-02-26 21:15:38.074530 | controller | skipping: [instance]
2026-02-26 21:15:38.074563 | controller |
2026-02-26 21:15:38.074572 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2026-02-26 21:15:38.074751 | controller | Thursday 26 February 2026  21:15:38 +0000 (0:00:00.031)       0:01:02.271 *****
2026-02-26 21:15:38.116953 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-02-26 21:15:38.136371 | controller |
2026-02-26 21:15:38.136397 | controller | TASK [ci_setup : List packages to install] *************************************
2026-02-26 21:15:38.136406 | controller | Thursday 26 February 2026  21:15:38 +0000 (0:00:00.042)       0:01:02.314 *****
2026-02-26 21:15:38.136423 | controller | ok: [instance] =>
2026-02-26 21:15:38.136947 | controller |   cifmw_ci_setup_packages:
2026-02-26 21:15:38.136963 | controller |   - bash-completion
2026-02-26 21:15:38.136970 | controller |   - ca-certificates
2026-02-26 21:15:38.136976 | controller |   - git-core
2026-02-26 21:15:38.136984 | controller |   - make
2026-02-26 21:15:41.864064 | controller |   - tar
2026-02-26 21:15:41.864101 | controller |   - tmux
2026-02-26 21:15:41.864109 | controller |   - python3-pip
2026-02-26 21:15:41.864115 | controller |
2026-02-26 21:15:41.864122 | controller | TASK [ci_setup : Install needed packages] **************************************
2026-02-26 21:15:41.864128 | controller | Thursday 26 February 2026  21:15:38 +0000 (0:00:00.019)       0:01:02.333 *****
2026-02-26 21:15:41.864139 | controller | changed: [instance]
2026-02-26 21:15:42.432414 | controller |
2026-02-26 21:15:42.432452 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2026-02-26 21:15:42.432462 | controller | Thursday 26 February 2026  21:15:41 +0000 (0:00:03.726)       0:01:06.059 *****
2026-02-26 21:15:42.432474 | controller | ok: [instance]
2026-02-26 21:15:42.432503 | controller |
2026-02-26 21:15:42.432513 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2026-02-26 21:15:42.432540 | controller | Thursday 26 February 2026  21:15:42 +0000 (0:00:00.569)       0:01:06.629 *****
2026-02-26 21:15:42.627907 | controller | changed: [instance]
2026-02-26 21:15:48.617396 | controller |
2026-02-26 21:15:48.617433 | controller | TASK [ci_setup : Install openshift client] *************************************
2026-02-26 21:15:48.617441 | controller | Thursday 26 February 2026  21:15:42 +0000 (0:00:00.195)       0:01:06.824 *****
2026-02-26 21:15:48.617452 | controller | changed: [instance]
2026-02-26 21:15:48.617475 | controller |
2026-02-26 21:15:48.617483 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2026-02-26 21:15:48.617638 | controller | Thursday 26 February 2026  21:15:48 +0000 (0:00:05.989)       0:01:12.814 *****
2026-02-26 21:15:48.653224 | controller | ok: [instance]
2026-02-26 21:15:48.905377 | controller |
2026-02-26 21:15:48.905410 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-02-26 21:15:48.905418 | controller | Thursday 26 February 2026  21:15:48 +0000 (0:00:00.035)       0:01:12.850 *****
2026-02-26 21:15:48.905429 | controller | changed: [instance]
2026-02-26 21:15:48.905455 | controller |
2026-02-26 21:15:48.905700 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2026-02-26 21:15:49.172789 | controller | Thursday 26 February 2026  21:15:48 +0000 (0:00:00.251)       0:01:13.102 *****
2026-02-26 21:15:49.172839 | controller | changed: [instance]
2026-02-26 21:15:49.190214 | controller |
2026-02-26 21:15:49.190232 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2026-02-26 21:15:49.190239 | controller | Thursday 26 February 2026  21:15:49 +0000 (0:00:00.267)       0:01:13.369 *****
2026-02-26 21:15:49.190249 | controller | skipping: [instance]
2026-02-26 21:15:49.207115 | controller |
2026-02-26 21:15:49.207132 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2026-02-26 21:15:49.207139 | controller | Thursday 26 February 2026  21:15:49 +0000 (0:00:00.017)       0:01:13.387 *****
2026-02-26 21:15:49.207148 | controller | skipping: [instance]
2026-02-26 21:15:49.251265 | controller |
2026-02-26 21:15:49.251284 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2026-02-26 21:15:49.251290 | controller | Thursday 26 February 2026  21:15:49 +0000 (0:00:00.017)       0:01:13.404 *****
2026-02-26 21:15:49.251299 | controller | skipping: [instance]
2026-02-26 21:15:49.269132 | controller |
2026-02-26 21:15:49.269152 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2026-02-26 21:15:49.269160 | controller | Thursday 26 February 2026  21:15:49 +0000 (0:00:00.044)       0:01:13.449 *****
2026-02-26 21:15:49.269170 | controller | skipping: [instance]
2026-02-26 21:15:49.285161 | controller |
2026-02-26 21:15:49.285181 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2026-02-26 21:15:49.285197 | controller | Thursday 26 February 2026  21:15:49 +0000 (0:00:00.017)       0:01:13.466 *****
2026-02-26 21:15:49.285208 | controller | skipping: [instance]
2026-02-26 21:15:49.324374 | controller |
2026-02-26 21:15:49.324401 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2026-02-26 21:15:49.324409 | controller | Thursday 26 February 2026  21:15:49 +0000 (0:00:00.016)       0:01:13.482 *****
2026-02-26 21:15:49.324420 | controller | skipping: [instance]
2026-02-26 21:15:49.324751 | controller |
2026-02-26 21:15:50.255380 | controller | TASK [ci_setup : Manage directories] *******************************************
2026-02-26 21:15:50.255413 | controller | Thursday 26 February 2026  21:15:49 +0000 (0:00:00.039)       0:01:13.522 *****
2026-02-26 21:15:50.255425 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2026-02-26 21:15:50.255450 | controller | changed: [instance] => (item=/opt/basedir/logs)
2026-02-26 21:15:50.255458 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2026-02-26 21:15:50.255898 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2026-02-26 21:15:50.579428 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2026-02-26 21:15:50.579474 | controller |
2026-02-26 21:15:50.579484 | controller | TASK [Create secret file] ******************************************************
2026-02-26 21:15:50.579492 | controller | Thursday 26 February 2026  21:15:50 +0000 (0:00:00.930)       0:01:14.452 *****
2026-02-26 21:15:50.579503 | controller | changed: [instance]
2026-02-26 21:15:50.579531 | controller |
2026-02-26 21:15:50.579539 | controller | PLAY RECAP *********************************************************************
2026-02-26 21:15:50.579546 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2026-02-26 21:15:50.579553 | controller |
2026-02-26 21:15:50.579567 | controller | Thursday 26 February 2026  21:15:50 +0000 (0:00:00.323)       0:01:14.775 *****
2026-02-26 21:15:50.579574 | controller | ===============================================================================
2026-02-26 21:15:50.579580 | controller | test_deps : Install selinux python libs -------------------------------- 44.93s
2026-02-26 21:15:50.579587 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.42s
2026-02-26 21:15:50.579594 | controller | ci_setup : Install openshift client ------------------------------------- 5.99s
2026-02-26 21:15:50.579603 | controller | ci_setup : Install needed packages -------------------------------------- 3.73s
2026-02-26 21:15:50.579626 | controller | test_deps : Install python yaml libs ------------------------------------ 1.17s
2026-02-26 21:15:50.579635 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.09s
2026-02-26 21:15:50.580895 | controller | ci_setup : Manage directories ------------------------------------------- 0.93s
2026-02-26 21:15:50.637906 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2026-02-26 21:15:50.637927 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.82s
2026-02-26 21:15:50.637934 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2026-02-26 21:15:50.637939 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2026-02-26 21:15:50.637944 | controller | repo_setup : Install repo-setup package --------------------------------- 0.68s
2026-02-26 21:15:50.637949 | controller | ci_setup : Gather version of openshift client --------------------------- 0.57s
2026-02-26 21:15:50.637954 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.53s
2026-02-26 21:15:50.637959 | controller | repo_setup : Ensure directories are present ----------------------------- 0.51s
2026-02-26 21:15:50.637964 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
2026-02-26 21:15:50.637968 | controller | Create secret file ------------------------------------------------------ 0.32s
2026-02-26 21:15:50.637973 | controller | Create custom basedir --------------------------------------------------- 0.31s
2026-02-26 21:15:50.637978 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2026-02-26 21:15:50.637988 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.27s
2026-02-26 21:15:50.638005 | controller | INFO     Running crc_layout > converge
2026-02-26 21:15:51.191631 | controller |
2026-02-26 21:15:51.191947 | controller | PLAY [Converge] ****************************************************************
2026-02-26 21:15:51.192072 | controller |
2026-02-26 21:15:51.192191 | controller | TASK [Gathering Facts] *********************************************************
2026-02-26 21:15:51.192329 | controller | Thursday 26 February 2026  21:15:51 +0000 (0:00:00.025)       0:00:00.025 *****
2026-02-26 21:15:51.993706 | controller | ok: [instance]
2026-02-26 21:15:52.013922 | controller |
2026-02-26 21:15:52.013945 | controller | TASK [Load networking definition from scenario file] ***************************
2026-02-26 21:15:52.013955 | controller | Thursday 26 February 2026  21:15:51 +0000 (0:00:00.802)       0:00:00.828 *****
2026-02-26 21:15:52.013966 | controller | ok: [instance]
2026-02-26 21:15:52.040417 | controller |
2026-02-26 21:15:52.040457 | controller | TASK [discover_latest_image : Discover several images if requested] ************
2026-02-26 21:15:52.040468 | controller | Thursday 26 February 2026  21:15:52 +0000 (0:00:00.020)       0:00:00.848 *****
2026-02-26 21:15:52.040481 | controller | skipping: [instance]
2026-02-26 21:15:53.581053 | controller |
2026-02-26 21:15:53.581086 | controller | TASK [discover_latest_image : Discover the image] ******************************
2026-02-26 21:15:53.581094 | controller | Thursday 26 February 2026  21:15:52 +0000 (0:00:00.026)       0:00:00.875 *****
2026-02-26 21:15:53.581109 | controller | changed: [instance]
2026-02-26 21:15:53.615210 | controller |
2026-02-26 21:15:53.615228 | controller | TASK [discover_latest_image : Export facts accordingly] ************************
2026-02-26 21:15:53.615237 | controller | Thursday 26 February 2026  21:15:53 +0000 (0:00:01.540)       0:00:02.415 *****
2026-02-26 21:15:53.615245 | controller | ok: [instance]
2026-02-26 21:15:53.648511 | controller |
2026-02-26 21:15:53.648563 | controller | TASK [discover_latest_image : Also export this via the latest images dict] *****
2026-02-26 21:15:53.648577 | controller | Thursday 26 February 2026  21:15:53 +0000 (0:00:00.034)       0:00:02.450 *****
2026-02-26 21:15:53.648596 | controller | ok: [instance]
2026-02-26 21:15:53.648627 | controller |
2026-02-26 21:15:53.648635 | controller | TASK [discover_latest_image : A debug task] ************************************
2026-02-26 21:15:53.648655 | controller | Thursday 26 February 2026  21:15:53 +0000 (0:00:00.033)       0:00:02.483 *****
2026-02-26 21:15:53.667377 | controller | ok: [instance] =>
2026-02-26 21:15:53.668177 | controller |   cifmw_discover_latest_image_images_dict:
2026-02-26 21:15:53.668193 | controller |     default:
2026-02-26 21:15:53.668201 | controller |       hash: 732aef83ec83d501dd4ceba8f08d29ffba016300c4710e056c77d4908412b10c
2026-02-26 21:15:53.668209 | controller |       hash_algorithm: sha256
2026-02-26 21:15:53.690992 | controller |       image_name: CentOS-Stream-GenericCloud-x86_64-9-latest.x86_64.qcow2
2026-02-26 21:15:53.691013 | controller |       image_url: https://cloud.centos.org/centos/9-stream/x86_64/images//CentOS-Stream-GenericCloud-x86_64-9-latest.x86_64.qcow2
2026-02-26 21:15:53.691022 | controller |
2026-02-26 21:15:53.691028 | controller | TASK [discover_latest_image : another debug task] ******************************
2026-02-26 21:15:53.691035 | controller | Thursday 26 February 2026  21:15:53 +0000 (0:00:00.018)       0:00:02.502 *****
2026-02-26 21:15:53.691044 | controller | ok: [instance] =>
2026-02-26 21:15:53.701684 | controller |   cifmw_discovered_image_url: https://cloud.centos.org/centos/9-stream/x86_64/images//CentOS-Stream-GenericCloud-x86_64-9-latest.x86_64.qcow2
2026-02-26 21:15:53.701705 | controller |
2026-02-26 21:15:53.701713 | controller | TASK [reproducer : Fail for now] ***********************************************
2026-02-26 21:15:53.701720 | controller | Thursday 26 February 2026  21:15:53 +0000 (0:00:00.023)       0:00:02.525 *****
2026-02-26 21:15:53.701729 | controller | fatal: [instance]: FAILED! => changed=false
2026-02-26 21:15:53.701901 | controller |   msg: Failed on purpose!
2026-02-26 21:15:53.707434 | controller |
2026-02-26 21:15:53.707680 | controller | TASK [Create dnsmasq dir] ******************************************************
2026-02-26 21:15:53.707859 | controller | Thursday 26 February 2026  21:15:53 +0000 (0:00:00.016)       0:00:02.542 *****
2026-02-26 21:15:53.988418 | controller | changed: [instance]
2026-02-26 21:15:53.988468 | controller |
2026-02-26 21:15:53.988486 | controller | TASK [Get journalctl in a file] ************************************************
2026-02-26 21:15:53.988594 | controller | Thursday 26 February 2026  21:15:53 +0000 (0:00:00.280)       0:00:02.822 *****
2026-02-26 21:15:54.281008 | controller | changed: [instance]
2026-02-26 21:15:54.816793 | controller |
2026-02-26 21:15:54.816836 | controller | TASK [Dump journalctl output] **************************************************
2026-02-26 21:15:54.816845 | controller | Thursday 26 February 2026  21:15:54 +0000 (0:00:00.292)       0:00:03.115 *****
2026-02-26 21:15:54.816855 | controller | changed: [instance]
2026-02-26 21:15:55.144829 | controller |
2026-02-26 21:15:55.144861 | controller | TASK [Copy all dnsmasq config] *************************************************
2026-02-26 21:15:55.144869 | controller | Thursday 26 February 2026  21:15:54 +0000 (0:00:00.535)       0:00:03.651 *****
2026-02-26 21:15:55.144880 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.conf) => changed=false
2026-02-26 21:15:55.144905 | controller |   ansible_loop_var: item
2026-02-26 21:15:55.145467 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.conf /home/zuul/ci-framework-data/artifacts/dnsmasq
2026-02-26 21:15:55.145501 | controller |   item: /etc/cifmw-dnsmasq.conf
2026-02-26 21:15:55.145509 | controller |   msg: |-
2026-02-26 21:15:55.145516 | controller |     rsync: [sender] link_stat "/etc/cifmw-dnsmasq.conf" failed: No such file or directory (2)
2026-02-26 21:15:55.145522 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2026-02-26 21:15:55.145537 | controller |   rc: 23
2026-02-26 21:15:55.459238 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.d/) => changed=false
2026-02-26 21:15:55.459593 | controller |   ansible_loop_var: item
2026-02-26 21:15:55.459608 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.d/ /home/zuul/ci-framework-data/artifacts/dnsmasq
2026-02-26 21:15:55.459615 | controller |   item: /etc/cifmw-dnsmasq.d/
2026-02-26 21:15:55.459623 | controller |   msg: |-
2026-02-26 21:15:55.462373 | controller |     rsync: [sender] change_dir "/etc/cifmw-dnsmasq.d" failed: No such file or directory (2)
2026-02-26 21:15:55.462390 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2026-02-26 21:15:55.462397 | controller |   rc: 23
2026-02-26 21:15:55.462405 | controller |
2026-02-26 21:15:55.462426 | controller | PLAY RECAP *********************************************************************
2026-02-26 21:15:55.462432 | controller | instance                   : ok=10   changed=4    unreachable=0    failed=2    skipped=1    rescued=0    ignored=0
2026-02-26 21:15:55.462438 | controller |
2026-02-26 21:15:55.462443 | controller | Thursday 26 February 2026  21:15:55 +0000 (0:00:00.646)       0:00:04.297 *****
2026-02-26 21:15:55.462450 | controller | ===============================================================================
2026-02-26 21:15:55.463359 | controller | discover_latest_image : Discover the image ------------------------------ 1.54s
2026-02-26 21:15:55.463517 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2026-02-26 21:15:55.463526 | controller | Copy all dnsmasq config ------------------------------------------------- 0.65s
2026-02-26 21:15:55.463531 | controller | Dump journalctl output -------------------------------------------------- 0.54s
2026-02-26 21:15:55.463536 | controller | Get journalctl in a file ------------------------------------------------ 0.29s
2026-02-26 21:15:55.463541 | controller | Create dnsmasq dir ------------------------------------------------------ 0.28s
2026-02-26 21:15:55.463547 | controller | discover_latest_image : Export facts accordingly ------------------------ 0.03s
2026-02-26 21:15:55.463552 | controller | discover_latest_image : Also export this via the latest images dict ----- 0.03s
2026-02-26 21:15:55.463562 | controller | discover_latest_image : Discover several images if requested ------------ 0.03s
2026-02-26 21:15:55.463567 | controller | discover_latest_image : another debug task ------------------------------ 0.02s
2026-02-26 21:15:55.463573 | controller | Load networking definition from scenario file --------------------------- 0.02s
2026-02-26 21:15:55.463580 | controller | discover_latest_image : A debug task ------------------------------------ 0.02s
2026-02-26 21:15:55.509887 | controller | reproducer : Fail for now ----------------------------------------------- 0.02s
2026-02-26 21:15:55.509924 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/reproducer/crc_layout/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/crc_layout/converge.yml']
2026-02-26 21:15:55.510283 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-02-26 21:15:55.518881 | controller | INFO     Running crc_layout > cleanup
2026-02-26 21:15:55.519300 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-02-26 21:15:55.526824 | controller | INFO     Running crc_layout > destroy
2026-02-26 21:15:55.527130 | controller | WARNING  Skipping, instances are delegated.
2026-02-26 21:15:55.527479 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-02-26 21:15:55.528892 | controller | INFO     Writing /tmp/report.html report.
2026-02-26 21:15:55.999593 | controller | ERROR
2026-02-26 21:15:55.999810 | controller | {
2026-02-26 21:15:55.999846 | controller |   "delta": "0:01:38.329971",
2026-02-26 21:15:55.999870 | controller |   "end": "2026-02-26 21:15:55.572049",
2026-02-26 21:15:55.999888 | controller |   "msg": "non-zero return code",
2026-02-26 21:15:55.999904 | controller |   "rc": 1,
2026-02-26 21:15:55.999921 | controller |   "start": "2026-02-26 21:14:17.242078"
2026-02-26 21:15:55.999936 | controller | }
failure
2026-02-26 21:15:56.005520 | 
2026-02-26 21:15:56.005711 | PLAY RECAP
2026-02-26 21:15:56.005781 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-02-26 21:15:56.005805 | 
2026-02-26 21:15:56.103714 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-26 21:15:56.105393 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-26 21:15:56.625435 | 
2026-02-26 21:15:56.625576 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-02-26 21:15:56.645357 | 
2026-02-26 21:15:56.645426 | TASK [Filter out host if needed]
2026-02-26 21:15:56.653322 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-02-26 21:15:56.657401 | 
2026-02-26 21:15:56.657463 | TASK [Ensure file is present]
2026-02-26 21:15:57.033114 | controller | ok
2026-02-26 21:15:57.038874 | 
2026-02-26 21:15:57.038955 | TASK [Manage molecule report file]
2026-02-26 21:15:57.567060 | controller | changed
2026-02-26 21:15:57.571877 | 
2026-02-26 21:15:57.571944 | TASK [Check if we get ci-framework-data basedir]
2026-02-26 21:15:57.816486 | controller | ok
2026-02-26 21:15:57.821838 | 
2026-02-26 21:15:57.821904 | TASK [Create ci-framework-data log directory for zuul]
2026-02-26 21:15:58.218972 | controller | changed
2026-02-26 21:15:58.225164 | 
2026-02-26 21:15:58.225239 | TASK [Copy ci-framework interesting files]
2026-02-26 21:15:58.478141 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-02-26 21:15:58.752758 | controller | changed
2026-02-26 21:15:58.757542 | 
2026-02-26 21:15:58.757604 | TASK [Get SELinux listing]
2026-02-26 21:15:59.285071 | controller | changed
2026-02-26 21:15:59.291114 | 
2026-02-26 21:15:59.291196 | TASK [Generate log index]
2026-02-26 21:16:00.346423 | controller | changed
2026-02-26 21:16:00.351437 | 
2026-02-26 21:16:00.351503 | TASK [Get some env related data]
2026-02-26 21:16:01.001055 | controller | /home/zuul/.local/bin/ansible
2026-02-26 21:16:01.883197 | controller | changed
2026-02-26 21:16:01.888759 | 
2026-02-26 21:16:01.888825 | TASK [Generate list of logs to collect in home directory]
2026-02-26 21:16:02.283900 | controller | ok: All paths examined
2026-02-26 21:16:02.289336 | 
2026-02-26 21:16:02.289403 | LOOP [Copy logs from home directory]
2026-02-26 21:16:02.852664 | controller | changed:
2026-02-26 21:16:02.853004 | controller | {
2026-02-26 21:16:02.853042 | controller |   "atime": 1772005405.932821,
2026-02-26 21:16:02.853067 | controller |   "ctime": 1772006396.9238591,
2026-02-26 21:16:02.853089 | controller |   "dev": 64513,
2026-02-26 21:16:02.853110 | controller |   "gid": 1000,
2026-02-26 21:16:02.853130 | controller |   "gr_name": "zuul",
2026-02-26 21:16:02.853158 | controller |   "inode": 2773,
2026-02-26 21:16:02.853179 | controller |   "isblk": false,
2026-02-26 21:16:02.853198 | controller |   "ischr": false,
2026-02-26 21:16:02.853217 | controller |   "isdir": false,
2026-02-26 21:16:02.853235 | controller |   "isfifo": false,
2026-02-26 21:16:02.853434 | controller |   "isgid": false,
2026-02-26 21:16:02.853473 | controller |   "islnk": false,
2026-02-26 21:16:02.853492 | controller |   "isreg": true,
2026-02-26 21:16:02.853507 | controller |   "issock": false,
2026-02-26 21:16:02.853520 | controller |   "isuid": false,
2026-02-26 21:16:02.853533 | controller |   "mode": "0644",
2026-02-26 21:16:02.853545 | controller |   "mtime": 1772006396.9238591,
2026-02-26 21:16:02.853558 | controller |   "nlink": 1,
2026-02-26 21:16:02.853570 | controller |   "path": "/home/zuul/crc-setup.log",
2026-02-26 21:16:02.853584 | controller |   "pw_name": "zuul",
2026-02-26 21:16:02.853597 | controller |   "rgrp": true,
2026-02-26 21:16:02.853609 | controller |   "roth": true,
2026-02-26 21:16:02.853622 | controller |   "rusr": true,
2026-02-26 21:16:02.853635 | controller |   "size": 4108,
2026-02-26 21:16:02.853649 | controller |   "uid": 1000,
2026-02-26 21:16:02.853663 | controller |   "wgrp": false,
2026-02-26 21:16:02.853676 | controller |   "woth": false,
2026-02-26 21:16:02.853691 | controller |   "wusr": true,
2026-02-26 21:16:02.853705 | controller |   "xgrp": false,
2026-02-26 21:16:02.853717 | controller |   "xoth": false,
2026-02-26 21:16:02.853729 | controller |   "xusr": false
2026-02-26 21:16:02.853741 | controller | }
2026-02-26 21:16:03.432413 | controller | changed:
2026-02-26 21:16:03.432562 | controller | {
2026-02-26 21:16:03.432588 | controller |   "atime": 1772006398.6759083,
2026-02-26 21:16:03.432607 | controller |   "ctime": 1772007098.5537844,
2026-02-26 21:16:03.432625 | controller |   "dev": 64513,
2026-02-26 21:16:03.432641 | controller |   "gid": 1000,
2026-02-26 21:16:03.432655 | controller |   "gr_name": "zuul",
2026-02-26 21:16:03.432670 | controller |   "inode": 359205,
2026-02-26 21:16:03.432684 | controller |   "isblk": false,
2026-02-26 21:16:03.432697 | controller |   "ischr": false,
2026-02-26 21:16:03.432711 | controller |   "isdir": false,
2026-02-26 21:16:03.432724 | controller |   "isfifo": false,
2026-02-26 21:16:03.432737 | controller |   "isgid": false,
2026-02-26 21:16:03.432750 | controller |   "islnk": false,
2026-02-26 21:16:03.432763 | controller |   "isreg": true,
2026-02-26 21:16:03.432776 | controller |   "issock": false,
2026-02-26 21:16:03.432788 | controller |   "isuid": false,
2026-02-26 21:16:03.432801 | controller |   "mode": "0644",
2026-02-26 21:16:03.432815 | controller |   "mtime": 1772007098.5537844,
2026-02-26 21:16:03.432828 | controller |   "nlink": 1,
2026-02-26 21:16:03.432842 | controller |   "path": "/home/zuul/crc-start.log",
2026-02-26 21:16:03.432855 | controller |   "pw_name": "zuul",
2026-02-26 21:16:03.432868 | controller |   "rgrp": true,
2026-02-26 21:16:03.432882 | controller |   "roth": true,
2026-02-26 21:16:03.432895 | controller |   "rusr": true,
2026-02-26 21:16:03.432909 | controller |   "size": 4654,
2026-02-26 21:16:03.432922 | controller |   "uid": 1000,
2026-02-26 21:16:03.432934 | controller |   "wgrp": false,
2026-02-26 21:16:03.432947 | controller |   "woth": false,
2026-02-26 21:16:03.432960 | controller |   "wusr": true,
2026-02-26 21:16:03.432972 | controller |   "xgrp": false,
2026-02-26 21:16:03.432985 | controller |   "xoth": false,
2026-02-26 21:16:03.432998 | controller |   "xusr": false
2026-02-26 21:16:03.433011 | controller | }
2026-02-26 21:16:04.031764 | controller | changed:
2026-02-26 21:16:04.031909 | controller | {
2026-02-26 21:16:04.031933 | controller |   "atime": 1772140438.6237183,
2026-02-26 21:16:04.031950 | controller |   "ctime": 1772140454.7248132,
2026-02-26 21:16:04.031965 | controller |   "dev": 64513,
2026-02-26 21:16:04.031980 | controller |   "gid": 1000,
2026-02-26 21:16:04.031994 | controller |   "gr_name": "zuul",
2026-02-26 21:16:04.032007 | controller |   "inode": 1188997,
2026-02-26 21:16:04.032020 | controller |   "isblk": false,
2026-02-26 21:16:04.032032 | controller |   "ischr": false,
2026-02-26 21:16:04.032044 | controller |   "isdir": false,
2026-02-26 21:16:04.032056 | controller |   "isfifo": false,
2026-02-26 21:16:04.032068 | controller |   "isgid": false,
2026-02-26 21:16:04.032088 | controller |   "islnk": false,
2026-02-26 21:16:04.032102 | controller |   "isreg": true,
2026-02-26 21:16:04.032115 | controller |   "issock": false,
2026-02-26 21:16:04.032127 | controller |   "isuid": false,
2026-02-26 21:16:04.032139 | controller |   "mode": "0644",
2026-02-26 21:16:04.032165 | controller |   "mtime": 1772140454.7248132,
2026-02-26 21:16:04.032179 | controller |   "nlink": 1,
2026-02-26 21:16:04.032191 | controller |   "path": "/home/zuul/ansible.log",
2026-02-26 21:16:04.032205 | controller |   "pw_name": "zuul",
2026-02-26 21:16:04.032218 | controller |   "rgrp": true,
2026-02-26 21:16:04.032230 | controller |   "roth": true,
2026-02-26 21:16:04.032244 | controller |   "rusr": true,
2026-02-26 21:16:04.032257 | controller |   "size": 6749,
2026-02-26 21:16:04.032287 | controller |   "uid": 1000,
2026-02-26 21:16:04.032304 | controller |   "wgrp": false,
2026-02-26 21:16:04.032317 | controller |   "woth": false,
2026-02-26 21:16:04.032330 | controller |   "wusr": true,
2026-02-26 21:16:04.032342 | controller |   "xgrp": false,
2026-02-26 21:16:04.032354 | controller |   "xoth": false,
2026-02-26 21:16:04.032365 | controller |   "xusr": false
2026-02-26 21:16:04.032377 | controller | }
2026-02-26 21:16:04.041929 | 
2026-02-26 21:16:04.042114 | TASK [Copy crio stats log file]
2026-02-26 21:16:04.068027 | controller | skipping: Conditional result was False
2026-02-26 21:16:04.073573 | 
2026-02-26 21:16:04.073639 | TASK [Get SELinux related data]
2026-02-26 21:16:04.366678 | controller | <no matches>
2026-02-26 21:16:04.601786 | controller | ERROR
2026-02-26 21:16:04.601990 | controller | {
2026-02-26 21:16:04.602022 | controller |   "delta": "0:00:00.016860",
2026-02-26 21:16:04.602042 | controller |   "end": "2026-02-26 21:16:04.367695",
2026-02-26 21:16:04.602061 | controller |   "msg": "non-zero return code",
2026-02-26 21:16:04.602077 | controller |   "rc": 1,
2026-02-26 21:16:04.602093 | controller |   "start": "2026-02-26 21:16:04.350835"
2026-02-26 21:16:04.602107 | controller | }
2026-02-26 21:16:04.602128 | controller | ERROR: Ignoring Errors
2026-02-26 21:16:04.607036 | 
2026-02-26 21:16:04.607103 | TASK [Create system configuration directory]
2026-02-26 21:16:04.882519 | controller | changed
2026-02-26 21:16:04.887376 | 
2026-02-26 21:16:04.887437 | TASK [Get some of the system configurations]
2026-02-26 21:16:05.415612 | controller | changed
2026-02-26 21:16:05.420531 | 
2026-02-26 21:16:05.420596 | TASK [Copy generated documentation if available]
2026-02-26 21:16:05.445584 | controller | skipping: Conditional result was False
2026-02-26 21:16:05.451660 | 
2026-02-26 21:16:05.451726 | TASK [Copy generated AsciiDoc documentation if available]
2026-02-26 21:16:05.476655 | controller | skipping: Conditional result was False
2026-02-26 21:16:05.482312 | 
2026-02-26 21:16:05.482379 | TASK [Compress logs bigger than 2MB]
2026-02-26 21:16:06.009784 | controller | changed
2026-02-26 21:16:06.014810 | 
2026-02-26 21:16:06.014918 | TASK [Copy files from workspace on node]
2026-02-26 21:16:06.035261 | controller | ok
2026-02-26 21:16:06.058003 | 
2026-02-26 21:16:06.058076 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 21:16:06.082824 | controller | skipping: Conditional result was False
2026-02-26 21:16:06.089211 | 
2026-02-26 21:16:06.089302 | TASK [fetch-output : Set log path for single node]
2026-02-26 21:16:06.119168 | controller | ok
2026-02-26 21:16:06.124123 | 
2026-02-26 21:16:06.124196 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 21:16:06.333738 | controller -> localhost | ok: "/var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/logs"
2026-02-26 21:16:06.334099 | controller -> localhost | changed: All items complete
2026-02-26 21:16:06.334128 | 
2026-02-26 21:16:06.505072 | controller -> localhost | changed: "/var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/artifacts"
2026-02-26 21:16:06.681683 | controller -> localhost | changed: "/var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/docs"
2026-02-26 21:16:06.696942 | 
2026-02-26 21:16:06.697100 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 21:16:07.339122 | controller | changed:
2026-02-26 21:16:07.339424 | controller | .d..t...... ./
2026-02-26 21:16:07.339455 | controller | >f+++++++++ README.html
2026-02-26 21:16:07.339478 | controller | >f+++++++++ ansible-execution.log
2026-02-26 21:16:07.339498 | controller | >f+++++++++ ansible.log
2026-02-26 21:16:07.339517 | controller | >f+++++++++ crc-setup.log
2026-02-26 21:16:07.339535 | controller | >f+++++++++ crc-start.log
2026-02-26 21:16:07.339552 | controller | >f+++++++++ dmesg.log
2026-02-26 21:16:07.339571 | controller | >f+++++++++ installed-pkgs.log
2026-02-26 21:16:07.339587 | controller | >f+++++++++ python.log
2026-02-26 21:16:07.339603 | controller | >f+++++++++ registries.conf
2026-02-26 21:16:07.339618 | controller | >f+++++++++ report.html
2026-02-26 21:16:07.339632 | controller | >f+++++++++ selinux-denials.log
2026-02-26 21:16:07.339647 | controller | >f+++++++++ selinux-listing.log
2026-02-26 21:16:07.339661 | controller | cd+++++++++ ci-framework-data/
2026-02-26 21:16:07.339675 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-02-26 21:16:07.339690 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-02-26 21:16:07.339704 | controller | cd+++++++++ ci-framework-data/artifacts/dnsmasq/
2026-02-26 21:16:07.339718 | controller | >f+++++++++ ci-framework-data/artifacts/dnsmasq/journalctl.log
2026-02-26 21:16:07.339732 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-02-26 21:16:07.339745 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-02-26 21:16:07.339759 | controller | cd+++++++++ ci-framework-data/logs/
2026-02-26 21:16:07.339773 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-02-26 21:16:07.339787 | controller | cd+++++++++ registries.conf.d/
2026-02-26 21:16:07.339801 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-02-26 21:16:07.339816 | controller | cd+++++++++ system-config/
2026-02-26 21:16:07.339830 | controller | cd+++++++++ system-config/libvirt/
2026-02-26 21:16:07.339844 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-02-26 21:16:07.339858 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-02-26 21:16:07.339873 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-02-26 21:16:07.339887 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-02-26 21:16:07.339901 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-02-26 21:16:07.339914 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-02-26 21:16:07.339928 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-02-26 21:16:07.339943 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-02-26 21:16:07.339957 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-02-26 21:16:07.339971 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-02-26 21:16:07.339985 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-02-26 21:16:07.339999 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-02-26 21:16:07.340013 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-02-26 21:16:07.340027 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-02-26 21:16:07.340040 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-02-26 21:16:07.340053 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-02-26 21:16:07.823602 | controller | changed: .d..t...... ./
2026-02-26 21:16:08.278715 | controller | changed: .d..t...... ./
2026-02-26 21:16:08.298926 | 
2026-02-26 21:16:08.299185 | TASK [Return artifact to Zuul]
2026-02-26 21:16:08.341120 | controller | ok
2026-02-26 21:16:08.360858 | 
2026-02-26 21:16:08.360922 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-02-26 21:16:08.361036 | 
2026-02-26 21:16:08.361069 | PLAY RECAP
2026-02-26 21:16:08.361108 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-02-26 21:16:08.361129 | 
2026-02-26 21:16:08.475244 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-26 21:16:08.476209 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 21:16:09.003447 | 
2026-02-26 21:16:09.003577 | PLAY [all]
2026-02-26 21:16:09.021371 | 
2026-02-26 21:16:09.021449 | TASK [include_role : fetch-output]
2026-02-26 21:16:09.053438 | controller | ok
2026-02-26 21:16:09.069986 | 
2026-02-26 21:16:09.070073 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 21:16:09.128016 | controller | skipping: Conditional result was False
2026-02-26 21:16:09.133814 | 
2026-02-26 21:16:09.133895 | TASK [fetch-output : Set log path for single node]
2026-02-26 21:16:09.172539 | controller | ok
2026-02-26 21:16:09.177564 | 
2026-02-26 21:16:09.177637 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 21:16:09.524513 | controller -> localhost | ok: "/var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/logs"
2026-02-26 21:16:09.710634 | controller -> localhost | ok: "/var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/artifacts"
2026-02-26 21:16:09.896744 | controller -> localhost | ok: "/var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/docs"
2026-02-26 21:16:09.914158 | 
2026-02-26 21:16:09.914226 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 21:16:10.541277 | controller | ok
2026-02-26 21:16:10.541489 | controller | ok: All items complete
2026-02-26 21:16:10.541518 | 
2026-02-26 21:16:10.968830 | controller | ok
2026-02-26 21:16:11.407833 | controller | ok
2026-02-26 21:16:11.427840 | 
2026-02-26 21:16:11.428004 | TASK [include_role : fetch-output-openshift]
2026-02-26 21:16:11.444961 | controller | skipping: Conditional result was False
2026-02-26 21:16:11.451708 | 
2026-02-26 21:16:11.451799 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-26 21:16:11.804780 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008233
2026-02-26 21:16:12.000777 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007771
2026-02-26 21:16:12.027741 | 
2026-02-26 21:16:12.027826 | PLAY [all]
2026-02-26 21:16:12.044535 | 
2026-02-26 21:16:12.044637 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-26 21:16:12.544278 | controller | changed
2026-02-26 21:16:12.566156 | 
2026-02-26 21:16:12.566258 | PLAY RECAP
2026-02-26 21:16:12.566397 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-26 21:16:12.566428 | 
2026-02-26 21:16:12.670571 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 21:16:12.672121 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-26 21:16:13.242281 | 
2026-02-26 21:16:13.242417 | PLAY [localhost]
2026-02-26 21:16:13.258596 | 
2026-02-26 21:16:13.258675 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-26 21:16:13.583556 | localhost | changed
2026-02-26 21:16:13.588364 | 
2026-02-26 21:16:13.588438 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-26 21:16:13.618118 | localhost | ok
2026-02-26 21:16:13.625550 | 
2026-02-26 21:16:13.625624 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-26 21:16:13.946654 | localhost | changed
2026-02-26 21:16:13.953258 | 
2026-02-26 21:16:13.953380 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-26 21:16:14.488135 | localhost | changed
2026-02-26 21:16:14.493137 | 
2026-02-26 21:16:14.493217 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-26 21:16:14.836840 | localhost | Identity added: /var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/tmp/ansible.1miyy7n3 (/var/lib/zuul/builds/81fe458368b444e0be9aadada24ea2a6/work/tmp/ansible.1miyy7n3)
2026-02-26 21:16:14.837017 | localhost | ok: Runtime: 0:00:00.007940
2026-02-26 21:16:14.840936 | 
2026-02-26 21:16:14.841002 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-26 21:16:15.052503 | localhost | ok: Runtime: 0:00:00.005100
2026-02-26 21:16:15.056917 | 
2026-02-26 21:16:15.056983 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-26 21:16:15.107492 | localhost | changed
2026-02-26 21:16:15.111654 | 
2026-02-26 21:16:15.111719 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-26 21:16:15.435543 | localhost | changed
2026-02-26 21:16:15.453640 | 
2026-02-26 21:16:15.453698 | PLAY [localhost]
2026-02-26 21:16:15.463997 | 
2026-02-26 21:16:15.464061 | TASK [Generate bulk log download script]
2026-02-26 21:16:15.484287 | localhost | ok
2026-02-26 21:16:15.494948 | 
2026-02-26 21:16:15.495008 | TASK [local-log-download : Check API endpoint is defined]
2026-02-26 21:16:15.523309 | localhost | ok: All assertions passed
2026-02-26 21:16:15.527438 | 
2026-02-26 21:16:15.527499 | TASK [local-log-download : Create download script]
2026-02-26 21:16:15.877097 | localhost -> localhost | changed
2026-02-26 21:16:15.885554 | 
2026-02-26 21:16:15.885618 | TASK [Register quick-download link]
2026-02-26 21:16:15.914004 | localhost | ok
2026-02-26 21:16:15.957420 | 
2026-02-26 21:16:15.957507 | PLAY [logserver.rdoproject.org]
2026-02-26 21:16:15.966550 | 
2026-02-26 21:16:15.966607 | TASK [Set zuul-log-path fact]
2026-02-26 21:16:15.983782 | logserver.rdoproject.org | ok
2026-02-26 21:16:15.992503 | 
2026-02-26 21:16:15.992570 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 21:16:16.020180 | logserver.rdoproject.org | ok
2026-02-26 21:16:16.025464 | 
2026-02-26 21:16:16.025528 | TASK [upload-logs : Create log directories]
2026-02-26 21:16:17.906794 | logserver.rdoproject.org | changed
2026-02-26 21:16:17.910086 | 
2026-02-26 21:16:17.910169 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-26 21:16:18.137614 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005528
2026-02-26 21:16:18.142201 | 
2026-02-26 21:16:18.142280 | TASK [upload-logs : Upload logs to log server]
2026-02-26 21:16:19.645488 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-26 21:16:19.649176 | 
2026-02-26 21:16:19.649243 | LOOP [upload-logs : Compress console log and json output]
2026-02-26 21:16:19.693179 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 21:16:19.700839 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 21:16:19.708471 | 
2026-02-26 21:16:19.708579 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-26 21:16:19.745738 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 21:16:19.746009 | 
2026-02-26 21:16:19.748712 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 21:16:19.759283 | 
2026-02-26 21:16:19.759407 | LOOP [upload-logs : Upload console log and json output]