2025-09-29 11:35:36.476251 | Job console starting...
2025-09-29 11:35:36.711759 | Updating repositories
2025-09-29 11:35:37.452604 | Preparing job workspace
2025-09-29 11:35:45.072638 | Running Ansible setup...
2025-09-29 11:35:48.333065 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 11:35:48.943455 | 
2025-09-29 11:35:48.943552 | PLAY [localhost]
2025-09-29 11:35:48.954793 | 
2025-09-29 11:35:48.954866 | TASK [Gathering Facts]
2025-09-29 11:35:50.140485 | localhost | ok
2025-09-29 11:35:50.156479 | 
2025-09-29 11:35:50.156574 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 11:35:50.610818 | localhost -> localhost | changed
2025-09-29 11:35:50.616582 | 
2025-09-29 11:35:50.616664 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 11:35:51.444182 | localhost -> localhost | changed
2025-09-29 11:35:51.454027 | 
2025-09-29 11:35:51.454098 | TASK [Setup log path fact]
2025-09-29 11:35:51.481662 | localhost | ok
2025-09-29 11:35:51.503268 | 
2025-09-29 11:35:51.503337 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 11:35:51.550836 | localhost | ok
2025-09-29 11:35:51.558481 | 
2025-09-29 11:35:51.558549 | TASK [emit-job-header : Print job information]
2025-09-29 11:35:51.596920 | # Job Information
2025-09-29 11:35:51.597044 | Ansible Version: 2.15.12
2025-09-29 11:35:51.597073 | Job: cifmw-molecule-discover_latest_image
2025-09-29 11:35:51.597095 | Pipeline: github-check
2025-09-29 11:35:51.597114 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 11:35:51.597149 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333
2025-09-29 11:35:51.597169 | Log URL (when completed): https://logserver.rdoproject.org/9b0/rdoproject.org/9b038dfa9df04eeaacff0b13a48055d1/
2025-09-29 11:35:51.597188 | Event ID: 414483e0-9d27-11f0-98af-aabcae3c72cc
2025-09-29 11:35:51.610449 | 
2025-09-29 11:35:51.610515 | LOOP [emit-job-header : Print node information]
2025-09-29 11:35:51.721226 | localhost | ok:
2025-09-29 11:35:51.721440 | localhost | # Node Information
2025-09-29 11:35:51.721483 | localhost | Inventory Hostname: controller
2025-09-29 11:35:51.721514 | localhost | Hostname: np0005459253
2025-09-29 11:35:51.721542 | localhost | Username: zuul
2025-09-29 11:35:51.721569 | localhost | Distro: CentOS 9
2025-09-29 11:35:51.721684 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 11:35:51.721709 | localhost | Region: regionOne
2025-09-29 11:35:51.721729 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-29 11:35:51.721747 | localhost | Product Name: OpenStack Compute
2025-09-29 11:35:51.721765 | localhost | Interface IP: 192.168.26.130
2025-09-29 11:35:51.752923 | 
2025-09-29 11:35:51.753018 | PLAY [all]
2025-09-29 11:35:51.759711 | 
2025-09-29 11:35:51.760056 | TASK [Gather network facts]
2025-09-29 11:35:52.136200 | controller | ok
2025-09-29 11:35:52.160505 | 
2025-09-29 11:35:52.160577 | TASK [include_role : start-zuul-console]
2025-09-29 11:35:52.179297 | controller | ok
2025-09-29 11:35:52.200834 | 
2025-09-29 11:35:52.200899 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 11:35:52.513181 | controller | ok
2025-09-29 11:35:52.523026 | 
2025-09-29 11:35:52.523112 | TASK [include_role : add-build-sshkey]
2025-09-29 11:35:52.541438 | controller | ok
2025-09-29 11:35:52.556990 | 
2025-09-29 11:35:52.557088 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 11:35:52.918695 | controller -> localhost | ok
2025-09-29 11:35:52.924260 | 
2025-09-29 11:35:52.924347 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 11:35:52.952656 | controller | ok
2025-09-29 11:35:52.968285 | controller | included: /var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 11:35:52.974987 | 
2025-09-29 11:35:52.975226 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 11:35:53.710427 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 11:35:53.710613 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/9b038dfa9df04eeaacff0b13a48055d1_id_rsa.
2025-09-29 11:35:53.710648 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/9b038dfa9df04eeaacff0b13a48055d1_id_rsa.pub.
2025-09-29 11:35:53.710672 | controller -> localhost | The key fingerprint is:
2025-09-29 11:35:53.710694 | controller -> localhost | SHA256:pPLm5lDp8x/pl/jJw+4F+YgvUQsURp0O8RBCFIAnN8o zuul-build-sshkey
2025-09-29 11:35:53.710715 | controller -> localhost | The key's randomart image is:
2025-09-29 11:35:53.710735 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 11:35:53.710756 | controller -> localhost | |     ..+=oB= .   |
2025-09-29 11:35:53.710776 | controller -> localhost | |    o +  oooo    |
2025-09-29 11:35:53.710794 | controller -> localhost | |   . = ... o.    |
2025-09-29 11:35:53.710812 | controller -> localhost | |    E  +  . o.   |
2025-09-29 11:35:53.710853 | controller -> localhost | |    . + S  oo.   |
2025-09-29 11:35:53.710873 | controller -> localhost | |     =    .o.+   |
2025-09-29 11:35:53.710891 | controller -> localhost | |    . =   ++..o  |
2025-09-29 11:35:53.710921 | controller -> localhost | |     +.o .o+=o   |
2025-09-29 11:35:53.710947 | controller -> localhost | |     oo ..o*B.   |
2025-09-29 11:35:53.710967 | controller -> localhost | +----[SHA256]-----+
2025-09-29 11:35:53.711011 | controller -> localhost | ok: Runtime: 0:00:00.302764
2025-09-29 11:35:53.716846 | 
2025-09-29 11:35:53.716930 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 11:35:53.760754 | controller | ok
2025-09-29 11:35:53.779045 | controller | included: /var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 11:35:53.787308 | 
2025-09-29 11:35:53.787375 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 11:35:53.814562 | controller | skipping: Conditional result was False
2025-09-29 11:35:53.820575 | 
2025-09-29 11:35:53.820643 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 11:35:54.225207 | controller | changed
2025-09-29 11:35:54.230371 | 
2025-09-29 11:35:54.230486 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 11:35:54.444728 | controller | ok
2025-09-29 11:35:54.451106 | 
2025-09-29 11:35:54.451221 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 11:35:55.052569 | controller | changed
2025-09-29 11:35:55.059053 | 
2025-09-29 11:35:55.059147 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 11:35:55.657529 | controller | changed
2025-09-29 11:35:55.662496 | 
2025-09-29 11:35:55.662558 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 11:35:55.676156 | controller | skipping: Conditional result was False
2025-09-29 11:35:55.682539 | 
2025-09-29 11:35:55.682605 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 11:35:56.010227 | controller -> localhost | changed
2025-09-29 11:35:56.019839 | 
2025-09-29 11:35:56.019927 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 11:35:56.301569 | controller -> localhost | Identity added: /var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/9b038dfa9df04eeaacff0b13a48055d1_id_rsa (zuul-build-sshkey)
2025-09-29 11:35:56.301749 | controller -> localhost | ok: Runtime: 0:00:00.013121
2025-09-29 11:35:56.308037 | 
2025-09-29 11:35:56.308109 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 11:35:56.631799 | controller | ok
2025-09-29 11:35:56.636313 | 
2025-09-29 11:35:56.636378 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 11:35:56.660746 | controller | skipping: Conditional result was False
2025-09-29 11:35:56.669858 | 
2025-09-29 11:35:56.669948 | TASK [include_role : validate-host]
2025-09-29 11:35:56.688537 | controller | ok
2025-09-29 11:35:56.709116 | 
2025-09-29 11:35:56.709348 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 11:35:56.737868 | controller | ok
2025-09-29 11:35:56.743622 | 
2025-09-29 11:35:56.743694 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 11:35:56.969145 | controller -> localhost | ok
2025-09-29 11:35:56.974807 | 
2025-09-29 11:35:56.974872 | TASK [validate-host : Collect information about the host]
2025-09-29 11:35:57.689102 | controller | ok
2025-09-29 11:35:57.705407 | 
2025-09-29 11:35:57.705475 | TASK [validate-host : Sanitize hostname]
2025-09-29 11:35:57.779295 | controller | ok
2025-09-29 11:35:57.796622 | 
2025-09-29 11:35:57.796708 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 11:35:58.282481 | controller -> localhost | changed
2025-09-29 11:35:58.288302 | 
2025-09-29 11:35:58.288388 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 11:35:58.648197 | controller | ok
2025-09-29 11:35:58.656968 | 
2025-09-29 11:35:58.657050 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 11:35:59.283193 | controller -> localhost | changed
2025-09-29 11:35:59.295039 | 
2025-09-29 11:35:59.295138 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 11:35:59.341009 | controller | skipping: Conditional result was False
2025-09-29 11:35:59.347598 | 
2025-09-29 11:35:59.347682 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 11:35:59.371563 | controller | skipping: Conditional result was False
2025-09-29 11:35:59.377757 | 
2025-09-29 11:35:59.377837 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 11:35:59.643543 | controller | ok: "logs"
2025-09-29 11:35:59.643783 | controller | ok: All items complete
2025-09-29 11:35:59.643813 | 
2025-09-29 11:35:59.827543 | controller | ok: "artifacts"
2025-09-29 11:35:59.999116 | controller | ok: "docs"
2025-09-29 11:36:00.006580 | 
2025-09-29 11:36:00.006675 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 11:36:00.214648 | controller | changed: "logs"
2025-09-29 11:36:00.394551 | controller | changed: "artifacts"
2025-09-29 11:36:00.574542 | controller | changed: "docs"
2025-09-29 11:36:00.599175 | 
2025-09-29 11:36:00.599261 | PLAY RECAP
2025-09-29 11:36:00.599306 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 11:36:00.599335 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 11:36:00.599355 | 
2025-09-29 11:36:00.694800 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 11:36:00.695556 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 11:36:01.270396 | 
2025-09-29 11:36:01.270505 | PLAY [all]
2025-09-29 11:36:01.296014 | 
2025-09-29 11:36:01.296091 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 11:36:01.345229 | controller | ok
2025-09-29 11:36:01.353158 | 
2025-09-29 11:36:01.353248 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 11:36:01.700152 | controller | changed
2025-09-29 11:36:01.706755 | 
2025-09-29 11:36:01.706841 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 11:36:02.743382 | controller | changed
2025-09-29 11:36:02.761966 | 
2025-09-29 11:36:02.762059 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 11:36:03.193190 | controller | changed:
2025-09-29 11:36:03.193608 | controller | {
2025-09-29 11:36:03.193652 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 11:36:03.194146 | controller | }
2025-09-29 11:36:03.417335 | controller | changed:
2025-09-29 11:36:03.417426 | controller | {
2025-09-29 11:36:03.417462 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 11:36:03.417489 | controller | }
2025-09-29 11:36:03.665302 | controller | changed:
2025-09-29 11:36:03.665386 | controller | {
2025-09-29 11:36:03.665414 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-29 11:36:03.665435 | controller | }
2025-09-29 11:36:03.938382 | controller | changed:
2025-09-29 11:36:03.938461 | controller | {
2025-09-29 11:36:03.938489 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 11:36:03.938510 | controller | }
2025-09-29 11:36:04.191430 | controller | changed:
2025-09-29 11:36:04.191575 | controller | {
2025-09-29 11:36:04.191605 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 11:36:04.191627 | controller | }
2025-09-29 11:36:04.436255 | controller | changed:
2025-09-29 11:36:04.436335 | controller | {
2025-09-29 11:36:04.436363 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 11:36:04.436385 | controller | }
2025-09-29 11:36:04.669416 | controller | changed:
2025-09-29 11:36:04.669492 | controller | {
2025-09-29 11:36:04.669522 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-09-29 11:36:04.669544 | controller | }
2025-09-29 11:36:04.889771 | controller | changed:
2025-09-29 11:36:04.889862 | controller | {
2025-09-29 11:36:04.889897 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-09-29 11:36:04.889992 | controller | }
2025-09-29 11:36:05.096100 | controller | changed:
2025-09-29 11:36:05.096271 | controller | {
2025-09-29 11:36:05.096299 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 11:36:05.096319 | controller | }
2025-09-29 11:36:05.318602 | controller | changed:
2025-09-29 11:36:05.318730 | controller | {
2025-09-29 11:36:05.318757 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 11:36:05.318778 | controller | }
2025-09-29 11:36:05.554750 | controller | changed:
2025-09-29 11:36:05.554838 | controller | {
2025-09-29 11:36:05.554865 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 11:36:05.554886 | controller | }
2025-09-29 11:36:05.738216 | controller | changed:
2025-09-29 11:36:05.738304 | controller | {
2025-09-29 11:36:05.738332 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 11:36:05.738354 | controller | }
2025-09-29 11:36:05.952747 | controller | changed:
2025-09-29 11:36:05.952831 | controller | {
2025-09-29 11:36:05.952859 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 11:36:05.952880 | controller | }
2025-09-29 11:36:06.164865 | controller | changed:
2025-09-29 11:36:06.165044 | controller | {
2025-09-29 11:36:06.165080 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 11:36:06.165104 | controller | }
2025-09-29 11:36:06.374105 | controller | changed:
2025-09-29 11:36:06.374196 | controller | {
2025-09-29 11:36:06.374225 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 11:36:06.374248 | controller | }
2025-09-29 11:36:06.595376 | controller | changed:
2025-09-29 11:36:06.595560 | controller | {
2025-09-29 11:36:06.595597 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 11:36:06.595619 | controller | }
2025-09-29 11:36:06.806442 | controller | changed:
2025-09-29 11:36:06.806571 | controller | {
2025-09-29 11:36:06.806604 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-09-29 11:36:06.806627 | controller | }
2025-09-29 11:36:07.023076 | controller | changed:
2025-09-29 11:36:07.025088 | controller | {
2025-09-29 11:36:07.025160 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-09-29 11:36:07.025195 | controller | }
2025-09-29 11:36:07.217657 | controller | changed:
2025-09-29 11:36:07.217928 | controller | {
2025-09-29 11:36:07.217982 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 11:36:07.218006 | controller | }
2025-09-29 11:36:07.419774 | controller | changed:
2025-09-29 11:36:07.419884 | controller | {
2025-09-29 11:36:07.419936 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 11:36:07.419963 | controller | }
2025-09-29 11:36:07.628836 | controller | changed:
2025-09-29 11:36:07.629006 | controller | {
2025-09-29 11:36:07.629036 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 11:36:07.629057 | controller | }
2025-09-29 11:36:07.833149 | controller | changed:
2025-09-29 11:36:07.833247 | controller | {
2025-09-29 11:36:07.833275 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 11:36:07.833296 | controller | }
2025-09-29 11:36:08.063436 | controller | changed:
2025-09-29 11:36:08.063528 | controller | {
2025-09-29 11:36:08.063556 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-09-29 11:36:08.063579 | controller | }
2025-09-29 11:36:08.282814 | controller | changed:
2025-09-29 11:36:08.282990 | controller | {
2025-09-29 11:36:08.283029 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 11:36:08.283053 | controller | }
2025-09-29 11:36:08.495615 | controller | changed:
2025-09-29 11:36:08.495726 | controller | {
2025-09-29 11:36:08.495752 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 11:36:08.495772 | controller | }
2025-09-29 11:36:08.696898 | controller | changed:
2025-09-29 11:36:08.697011 | controller | {
2025-09-29 11:36:08.697040 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 11:36:08.697061 | controller | }
2025-09-29 11:36:08.720774 | 
2025-09-29 11:36:08.720862 | TASK [Set timezone to UTC]
2025-09-29 11:36:09.178426 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 11:36:09.187157 | 
2025-09-29 11:36:09.187239 | TASK [Create nodepool directory]
2025-09-29 11:36:09.421936 | controller | changed
2025-09-29 11:36:09.431780 | 
2025-09-29 11:36:09.431868 | TASK [Create nodepool sub_nodes file]
2025-09-29 11:36:09.998741 | controller | changed
2025-09-29 11:36:10.003832 | 
2025-09-29 11:36:10.003903 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 11:36:10.572821 | controller | changed
2025-09-29 11:36:10.579804 | 
2025-09-29 11:36:10.579891 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 11:36:10.612176 | 
2025-09-29 11:36:10.612332 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 11:36:10.641931 | 
2025-09-29 11:36:10.642059 | TASK [Create nodepool primary file]
2025-09-29 11:36:10.665707 | controller | skipping: Conditional result was False
2025-09-29 11:36:10.671071 | 
2025-09-29 11:36:10.671164 | TASK [Create nodepool node_private for this node]
2025-09-29 11:36:11.370497 | controller | changed
2025-09-29 11:36:11.381189 | 
2025-09-29 11:36:11.381255 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 11:36:11.981963 | controller | ok: Item: id_rsa Runtime: 0:00:00.007677
2025-09-29 11:36:11.982146 | 
2025-09-29 11:36:12.156199 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005460
2025-09-29 11:36:12.164882 | 
2025-09-29 11:36:12.165042 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 11:36:12.864087 | controller | changed
2025-09-29 11:36:12.871277 | 
2025-09-29 11:36:12.871362 | TASK [Validate sudoers config after edits]
2025-09-29 11:36:13.137062 | controller | /etc/sudoers: parsed OK
2025-09-29 11:36:13.137171 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 11:36:13.137183 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 11:36:13.137190 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 11:36:13.419788 | controller | ok: Runtime: 0:00:00.006695
2025-09-29 11:36:13.426002 | 
2025-09-29 11:36:13.426078 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 11:36:13.696053 | controller | SHELL=/bin/bash
2025-09-29 11:36:13.696111 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-29 11:36:13.696122 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-29 11:36:13.696178 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/33/3333/b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-29 11:36:13.696187 | controller | PWD=/home/zuul
2025-09-29 11:36:13.696193 | controller | ZUUL_PIPELINE=github-check
2025-09-29 11:36:13.696199 | controller | LOGNAME=zuul
2025-09-29 11:36:13.696205 | controller | XDG_SESSION_TYPE=tty
2025-09-29 11:36:13.696210 | controller | _=/usr/bin/env
2025-09-29 11:36:13.696216 | controller | MOTD_SHOWN=pam
2025-09-29 11:36:13.696221 | controller | HOME=/home/zuul
2025-09-29 11:36:13.696226 | controller | LANG=en_US.UTF-8
2025-09-29 11:36:13.696232 | controller | SSH_CONNECTION=192.168.26.12 42256 192.168.26.130 22
2025-09-29 11:36:13.696237 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-09-29 11:36:13.696247 | controller | ZUUL_CHANGE_IDS=3333,b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-29 11:36:13.696253 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 11:36:13.696259 | controller | XDG_SESSION_CLASS=user
2025-09-29 11:36:13.696264 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 11:36:13.696270 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 11:36:13.696275 | controller | USER=zuul
2025-09-29 11:36:13.696281 | controller | ZUUL_VOTING=True
2025-09-29 11:36:13.696286 | controller | BUILD_TIMEOUT=1800000
2025-09-29 11:36:13.696291 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 11:36:13.696297 | controller | SHLVL=1
2025-09-29 11:36:13.696302 | controller | ZUUL_PATCHSET=b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-29 11:36:13.696308 | controller | XDG_SESSION_ID=1
2025-09-29 11:36:13.696313 | controller | ZUUL_BRANCH=main
2025-09-29 11:36:13.696319 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 11:36:13.696324 | controller | SSH_CLIENT=192.168.26.12 42256 22
2025-09-29 11:36:13.696330 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 11:36:13.696335 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 11:36:13.696340 | controller | which_declare=declare -f
2025-09-29 11:36:13.696347 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-29 11:36:13.696352 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 11:36:13.696358 | controller | ZUUL_CHANGE=3333
2025-09-29 11:36:13.696365 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 11:36:13.696370 | controller | ZUUL_UUID=9b038dfa9df04eeaacff0b13a48055d1
2025-09-29 11:36:13.696376 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 11:36:13.696381 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 11:36:13.696387 | controller | }
2025-09-29 11:36:13.975399 | controller | ok: Runtime: 0:00:00.006855
2025-09-29 11:36:13.980724 | 
2025-09-29 11:36:13.980786 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 11:36:14.014536 | controller | skipping: Conditional result was False
2025-09-29 11:36:14.025142 | 
2025-09-29 11:36:14.025213 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 11:36:14.606542 | controller | skipping: Conditional result was False
2025-09-29 11:36:14.612754 | 
2025-09-29 11:36:14.612841 | TASK [Ensure legacy workspace directory]
2025-09-29 11:36:14.834251 | controller | changed
2025-09-29 11:36:14.877358 | 
2025-09-29 11:36:14.877430 | PLAY RECAP
2025-09-29 11:36:14.877475 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 11:36:14.877499 | 
2025-09-29 11:36:15.028138 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 11:36:15.028881 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 11:36:15.913443 | 
2025-09-29 11:36:15.913580 | PLAY [Load global variables]
2025-09-29 11:36:15.965405 | 
2025-09-29 11:36:15.965533 | TASK [Read group_vars all file]
2025-09-29 11:36:16.025485 | controller | ok
2025-09-29 11:36:16.043568 | 
2025-09-29 11:36:16.043675 | TASK [cifmw_helpers : Check if file is available]
2025-09-29 11:36:16.382657 | controller | ok
2025-09-29 11:36:16.389524 | 
2025-09-29 11:36:16.389665 | TASK [cifmw_helpers : Read vars]
2025-09-29 11:36:16.437932 | 
2025-09-29 11:36:16.438096 | LOOP [cifmw_helpers : Set vars as fact]
2025-09-29 11:36:16.513236 | 
2025-09-29 11:36:16.513356 | PLAY RECAP
2025-09-29 11:36:16.513400 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 11:36:16.513423 | 
2025-09-29 11:36:16.585729 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 11:36:16.586736 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 11:36:17.368416 | 
2025-09-29 11:36:17.368524 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 11:36:17.411371 | 
2025-09-29 11:36:17.411505 | TASK [Create zuul-output directory]
2025-09-29 11:36:17.866990 | controller | changed
2025-09-29 11:36:17.872253 | 
2025-09-29 11:36:17.872367 | TASK [Slurp Zuul inventory test]
2025-09-29 11:36:18.366896 | controller -> localhost | ok
2025-09-29 11:36:18.373738 | 
2025-09-29 11:36:18.373823 | TASK [Save zuul inventory]
2025-09-29 11:36:19.371650 | controller | changed
2025-09-29 11:36:19.380452 | 
2025-09-29 11:36:19.380539 | TASK [Save zuul vars without the change_message]
2025-09-29 11:36:20.084412 | controller | changed
2025-09-29 11:36:20.104345 | 
2025-09-29 11:36:20.104419 | PLAY RECAP
2025-09-29 11:36:20.104465 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 11:36:20.104487 | 
2025-09-29 11:36:20.247248 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 11:36:20.248025 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 11:36:21.277793 | 
2025-09-29 11:36:21.277907 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 11:36:21.301513 | 
2025-09-29 11:36:21.301637 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 11:36:21.350592 | controller | ok
2025-09-29 11:36:21.375186 | 
2025-09-29 11:36:21.375322 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 11:36:21.441587 | controller | skipping: Conditional result was False
2025-09-29 11:36:21.447791 | 
2025-09-29 11:36:21.447900 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 11:36:21.831325 | controller | ok
2025-09-29 11:36:21.841633 | 
2025-09-29 11:36:21.841722 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 11:36:22.750883 | controller | ok
2025-09-29 11:36:22.762469 | 
2025-09-29 11:36:22.762547 | TASK [Prepare workspace]
2025-09-29 11:36:22.801305 | controller | ok
2025-09-29 11:36:22.823797 | 
2025-09-29 11:36:22.823891 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 11:36:23.166069 | controller | ok
2025-09-29 11:36:23.181569 | 
2025-09-29 11:36:23.181689 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 11:36:25.840559 | controller | Output suppressed because no_log was given
2025-09-29 11:36:25.857506 | 
2025-09-29 11:36:25.857601 | LOOP [Create zuul-output directory]
2025-09-29 11:36:26.077460 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 11:36:26.238620 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 11:36:26.249964 | 
2025-09-29 11:36:26.250060 | TASK [Install required packages]
2025-09-29 11:37:27.079364 | controller | changed
2025-09-29 11:37:27.084727 | 
2025-09-29 11:37:27.084808 | TASK [Install venv]
2025-09-29 11:38:27.770725 | controller | changed
2025-09-29 11:38:27.810863 | 
2025-09-29 11:38:27.810966 | PLAY RECAP
2025-09-29 11:38:27.811014 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 11:38:27.811037 | 
2025-09-29 11:38:27.901723 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 11:38:27.902716 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 11:38:28.511309 | 
2025-09-29 11:38:28.511415 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 11:38:28.532647 | 
2025-09-29 11:38:28.532733 | TASK [Gather required facts]
2025-09-29 11:38:29.021165 | controller | ok
2025-09-29 11:38:29.028370 | 
2025-09-29 11:38:29.028470 | TASK [Load environment var if instructed to]
2025-09-29 11:38:29.052441 | controller | skipping: Conditional result was False
2025-09-29 11:38:29.059875 | 
2025-09-29 11:38:29.059986 | TASK [Run molecule]
2025-09-29 11:38:30.027988 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-29 11:38:30.098450 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 11:38:46.779411 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.779966 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.780466 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.780986 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.781467 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+d7c08c4a was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 1.0.0+d7c08c4a (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.781923 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.782366 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.782837 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.783274 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.783733 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.784177 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.784626 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.785070 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.785514 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.786011 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/061cf8/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 11:38:46.795592 | controller | INFO     Running default > prepare
2025-09-29 11:38:47.482153 | controller |
2025-09-29 11:38:47.482250 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 11:38:47.482350 | controller |
2025-09-29 11:38:47.482450 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 11:38:47.482582 | controller | Monday 29 September 2025  11:38:47 +0000 (0:00:00.013)       0:00:00.013 ******
2025-09-29 11:38:48.324640 | controller | ok: [instance]
2025-09-29 11:38:48.324750 | controller |
2025-09-29 11:38:48.324879 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-29 11:38:48.324991 | controller | Monday 29 September 2025  11:38:48 +0000 (0:00:00.843)       0:00:00.856 ******
2025-09-29 11:38:48.342586 | controller | skipping: [instance]
2025-09-29 11:38:48.342712 | controller |
2025-09-29 11:38:48.342827 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-29 11:38:48.342933 | controller | Monday 29 September 2025  11:38:48 +0000 (0:00:00.017)       0:00:00.874 ******
2025-09-29 11:38:48.384710 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-09-29 11:38:48.384798 | controller |
2025-09-29 11:38:48.384918 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-29 11:38:48.385024 | controller | Monday 29 September 2025  11:38:48 +0000 (0:00:00.042)       0:00:00.916 ******
2025-09-29 11:38:48.673174 | controller | ok: [instance]
2025-09-29 11:38:48.673262 | controller |
2025-09-29 11:38:48.673372 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-29 11:38:48.673482 | controller | Monday 29 September 2025  11:38:48 +0000 (0:00:00.288)       0:00:01.204 ******
2025-09-29 11:38:48.983143 | controller | ok: [instance]
2025-09-29 11:38:48.983215 | controller |
2025-09-29 11:38:48.983331 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-29 11:38:48.983443 | controller | Monday 29 September 2025  11:38:48 +0000 (0:00:00.309)       0:00:01.514 ******
2025-09-29 11:38:49.680364 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-29 11:38:49.680441 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-29 11:38:49.680544 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-29 11:38:49.680654 | controller |
2025-09-29 11:38:49.680792 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-29 11:38:49.680891 | controller | Monday 29 September 2025  11:38:49 +0000 (0:00:00.697)       0:00:02.212 ******
2025-09-29 11:38:49.738836 | controller |
2025-09-29 11:38:49.738928 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 11:38:49.739043 | controller | Monday 29 September 2025  11:38:49 +0000 (0:00:00.058)       0:00:02.270 ******
2025-09-29 11:38:50.291764 | controller | changed: [instance] => (item=tmp)
2025-09-29 11:38:50.291855 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-29 11:38:50.291966 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-29 11:38:50.292068 | controller |
2025-09-29 11:38:50.292184 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 11:38:50.292292 | controller | Monday 29 September 2025  11:38:50 +0000 (0:00:00.552)       0:00:02.823 ******
2025-09-29 11:38:51.111505 | controller | ok: [instance]
2025-09-29 11:38:51.111870 | controller |
2025-09-29 11:38:52.227204 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 11:38:52.227234 | controller | Monday 29 September 2025  11:38:51 +0000 (0:00:00.819)       0:00:03.643 ******
2025-09-29 11:38:52.227245 | controller | changed: [instance]
2025-09-29 11:39:00.261038 | controller |
2025-09-29 11:39:00.261078 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 11:39:00.261090 | controller | Monday 29 September 2025  11:38:52 +0000 (0:00:01.115)       0:00:04.758 ******
2025-09-29 11:39:00.261101 | controller | changed: [instance]
2025-09-29 11:39:00.971997 | controller |
2025-09-29 11:39:00.972035 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 11:39:00.972043 | controller | Monday 29 September 2025  11:39:00 +0000 (0:00:08.033)       0:00:12.792 ******
2025-09-29 11:39:00.972053 | controller | changed: [instance]
2025-09-29 11:39:00.995391 | controller |
2025-09-29 11:39:00.995416 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 11:39:00.995425 | controller | Monday 29 September 2025  11:39:00 +0000 (0:00:00.711)       0:00:13.503 ******
2025-09-29 11:39:00.995437 | controller | skipping: [instance]
2025-09-29 11:39:01.838000 | controller |
2025-09-29 11:39:01.838029 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 11:39:01.838037 | controller | Monday 29 September 2025  11:39:00 +0000 (0:00:00.023)       0:00:13.526 ******
2025-09-29 11:39:01.838047 | controller | changed: [instance]
2025-09-29 11:39:01.877548 | controller |
2025-09-29 11:39:01.877611 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 11:39:01.877635 | controller | Monday 29 September 2025  11:39:01 +0000 (0:00:00.842)       0:00:14.369 ******
2025-09-29 11:39:01.877648 | controller | skipping: [instance]
2025-09-29 11:39:01.917809 | controller |
2025-09-29 11:39:01.917855 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 11:39:01.917865 | controller | Monday 29 September 2025  11:39:01 +0000 (0:00:00.038)       0:00:14.408 ******
2025-09-29 11:39:01.917877 | controller | skipping: [instance]
2025-09-29 11:39:01.956162 | controller |
2025-09-29 11:39:01.956196 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 11:39:01.956205 | controller | Monday 29 September 2025  11:39:01 +0000 (0:00:00.040)       0:00:14.448 ******
2025-09-29 11:39:01.956216 | controller | skipping: [instance]
2025-09-29 11:39:01.956294 | controller |
2025-09-29 11:39:01.956425 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 11:39:01.956546 | controller | Monday 29 September 2025  11:39:01 +0000 (0:00:00.039)       0:00:14.488 ******
2025-09-29 11:39:02.519736 | controller | changed: [instance]
2025-09-29 11:39:02.519788 | controller |
2025-09-29 11:39:02.520048 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 11:39:02.972364 | controller | Monday 29 September 2025  11:39:02 +0000 (0:00:00.563)       0:00:15.051 ******
2025-09-29 11:39:02.972400 | controller | changed: [instance]
2025-09-29 11:39:03.000727 | controller |
2025-09-29 11:39:03.000770 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 11:39:03.000779 | controller | Monday 29 September 2025  11:39:02 +0000 (0:00:00.452)       0:00:15.503 ******
2025-09-29 11:39:03.000791 | controller | skipping: [instance]
2025-09-29 11:39:03.028726 | controller |
2025-09-29 11:39:03.028756 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 11:39:03.028765 | controller | Monday 29 September 2025  11:39:02 +0000 (0:00:00.028)       0:00:15.532 ******
2025-09-29 11:39:03.028781 | controller | skipping: [instance]
2025-09-29 11:39:03.028788 | controller |
2025-09-29 11:39:03.028796 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 11:39:03.028805 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.028)       0:00:15.560 ******
2025-09-29 11:39:03.056350 | controller | skipping: [instance]
2025-09-29 11:39:03.093333 | controller |
2025-09-29 11:39:03.093357 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 11:39:03.093366 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.027)       0:00:15.587 ******
2025-09-29 11:39:03.093377 | controller | ok: [instance]
2025-09-29 11:39:03.093550 | controller |
2025-09-29 11:39:03.093768 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 11:39:03.093934 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.037)       0:00:15.625 ******
2025-09-29 11:39:03.120141 | controller | skipping: [instance]
2025-09-29 11:39:03.120295 | controller |
2025-09-29 11:39:03.120439 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 11:39:03.120582 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.026)       0:00:15.652 ******
2025-09-29 11:39:03.146594 | controller | skipping: [instance]
2025-09-29 11:39:03.146831 | controller |
2025-09-29 11:39:03.147010 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 11:39:03.147154 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.026)       0:00:15.678 ******
2025-09-29 11:39:03.173231 | controller | skipping: [instance]
2025-09-29 11:39:03.173418 | controller |
2025-09-29 11:39:03.173807 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 11:39:03.173983 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.026)       0:00:15.704 ******
2025-09-29 11:39:03.200440 | controller | skipping: [instance]
2025-09-29 11:39:03.200655 | controller |
2025-09-29 11:39:03.200879 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 11:39:03.201058 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.027)       0:00:15.732 ******
2025-09-29 11:39:03.227126 | controller | skipping: [instance]
2025-09-29 11:39:03.227310 | controller |
2025-09-29 11:39:03.227484 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 11:39:03.227709 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.026)       0:00:15.759 ******
2025-09-29 11:39:03.255864 | controller | skipping: [instance]
2025-09-29 11:39:03.256055 | controller |
2025-09-29 11:39:03.256231 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 11:39:03.256372 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.028)       0:00:15.787 ******
2025-09-29 11:39:03.281440 | controller | skipping: [instance]
2025-09-29 11:39:03.281651 | controller |
2025-09-29 11:39:03.281873 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 11:39:03.282044 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.025)       0:00:15.813 ******
2025-09-29 11:39:03.465773 | controller | ok: [instance]
2025-09-29 11:39:03.465821 | controller |
2025-09-29 11:39:03.465832 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 11:39:03.465952 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.183)       0:00:15.997 ******
2025-09-29 11:39:03.681708 | controller | changed: [instance]
2025-09-29 11:39:03.901673 | controller |
2025-09-29 11:39:03.901706 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 11:39:03.901715 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.215)       0:00:16.213 ******
2025-09-29 11:39:03.901725 | controller | changed: [instance]
2025-09-29 11:39:03.922488 | controller |
2025-09-29 11:39:03.922510 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 11:39:03.922517 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.219)       0:00:16.433 ******
2025-09-29 11:39:03.922527 | controller | skipping: [instance]
2025-09-29 11:39:03.943620 | controller |
2025-09-29 11:39:03.943653 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 11:39:03.943679 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.021)       0:00:16.454 ******
2025-09-29 11:39:03.943693 | controller | skipping: [instance]
2025-09-29 11:39:03.964536 | controller |
2025-09-29 11:39:03.964558 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 11:39:03.964565 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.021)       0:00:16.475 ******
2025-09-29 11:39:03.964574 | controller | skipping: [instance]
2025-09-29 11:39:03.985620 | controller |
2025-09-29 11:39:03.985674 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 11:39:03.985686 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.020)       0:00:16.496 ******
2025-09-29 11:39:03.985696 | controller | skipping: [instance]
2025-09-29 11:39:04.007913 | controller |
2025-09-29 11:39:04.007936 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 11:39:04.007945 | controller | Monday 29 September 2025  11:39:03 +0000 (0:00:00.021)       0:00:16.517 ******
2025-09-29 11:39:04.007962 | controller | skipping: [instance]
2025-09-29 11:39:04.008137 | controller |
2025-09-29 11:39:04.008323 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 11:39:04.008509 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.022)       0:00:16.539 ******
2025-09-29 11:39:04.029140 | controller | skipping: [instance]
2025-09-29 11:39:04.029325 | controller |
2025-09-29 11:39:04.029511 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 11:39:04.029727 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.021)       0:00:16.561 ******
2025-09-29 11:39:04.044927 | controller | skipping: [instance]
2025-09-29 11:39:04.045104 | controller |
2025-09-29 11:39:04.045278 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 11:39:04.045449 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.015)       0:00:16.576 ******
2025-09-29 11:39:04.073115 | controller | skipping: [instance]
2025-09-29 11:39:04.073304 | controller |
2025-09-29 11:39:04.073481 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 11:39:04.073713 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.027)       0:00:16.604 ******
2025-09-29 11:39:04.089045 | controller | skipping: [instance]
2025-09-29 11:39:04.089227 | controller |
2025-09-29 11:39:04.089402 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 11:39:04.089590 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.016)       0:00:16.621 ******
2025-09-29 11:39:04.108802 | controller | skipping: [instance]
2025-09-29 11:39:04.108986 | controller |
2025-09-29 11:39:04.109159 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-29 11:39:04.109329 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.019)       0:00:16.640 ******
2025-09-29 11:39:04.137738 | controller | skipping: [instance]
2025-09-29 11:39:04.137887 | controller |
2025-09-29 11:39:04.138030 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-29 11:39:04.138156 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.028)       0:00:16.669 ******
2025-09-29 11:39:04.167181 | controller | skipping: [instance]
2025-09-29 11:39:04.167334 | controller |
2025-09-29 11:39:04.167490 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-29 11:39:04.167647 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.029)       0:00:16.698 ******
2025-09-29 11:39:04.197432 | controller | skipping: [instance]
2025-09-29 11:39:04.197579 | controller |
2025-09-29 11:39:04.197780 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-29 11:39:04.197934 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.030)       0:00:16.729 ******
2025-09-29 11:39:04.226993 | controller | skipping: [instance]
2025-09-29 11:39:04.227135 | controller |
2025-09-29 11:39:04.227282 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-29 11:39:04.227405 | controller | Monday 29 September 2025  11:39:04 +0000 (0:00:00.029)       0:00:16.758 ******
2025-09-29 11:39:44.265528 | controller | ok: [instance]
2025-09-29 11:39:45.446421 | controller |
2025-09-29 11:39:45.446454 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-29 11:39:45.446462 | controller | Monday 29 September 2025  11:39:44 +0000 (0:00:40.038)       0:00:56.796 ******
2025-09-29 11:39:45.446472 | controller | ok: [instance]
2025-09-29 11:39:45.490797 | controller |
2025-09-29 11:39:45.490834 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-29 11:39:45.490862 | controller | Monday 29 September 2025  11:39:45 +0000 (0:00:01.180)       0:00:57.977 ******
2025-09-29 11:39:45.490884 | controller | skipping: [instance]
2025-09-29 11:39:45.490917 | controller |
2025-09-29 11:39:45.490925 | controller | PLAY RECAP *********************************************************************
2025-09-29 11:39:45.490933 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-09-29 11:39:45.490940 | controller |
2025-09-29 11:39:45.490946 | controller | Monday 29 September 2025  11:39:45 +0000 (0:00:00.044)       0:00:58.021 ******
2025-09-29 11:39:45.490953 | controller | ===============================================================================
2025-09-29 11:39:45.490960 | controller | test_deps : Install selinux python libs -------------------------------- 40.04s
2025-09-29 11:39:45.490977 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.03s
2025-09-29 11:39:45.491004 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2025-09-29 11:39:45.492620 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.12s
2025-09-29 11:39:45.548028 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-09-29 11:39:45.548066 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-09-29 11:39:45.548075 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.82s
2025-09-29 11:39:45.548080 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-09-29 11:39:45.548086 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
2025-09-29 11:39:45.548091 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-09-29 11:39:45.548097 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-09-29 11:39:45.548102 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s
2025-09-29 11:39:45.548107 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-09-29 11:39:45.548113 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.29s
2025-09-29 11:39:45.548118 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-09-29 11:39:45.548124 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-09-29 11:39:45.548129 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-09-29 11:39:45.548135 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-09-29 11:39:45.548140 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-09-29 11:39:45.548145 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-09-29 11:39:45.548156 | controller | INFO     Running default > converge
2025-09-29 11:39:45.966113 | controller |
2025-09-29 11:39:46.762476 | controller | PLAY [Converge] ****************************************************************
2025-09-29 11:39:46.762506 | controller |
2025-09-29 11:39:46.762514 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 11:39:46.762520 | controller | Monday 29 September 2025  11:39:45 +0000 (0:00:00.013)       0:00:00.013 ******
2025-09-29 11:39:46.762529 | controller | ok: [instance]
2025-09-29 11:39:48.705912 | controller |
2025-09-29 11:39:48.705943 | controller | TASK [discover_latest_image : Get latest image] ********************************
2025-09-29 11:39:48.705951 | controller | Monday 29 September 2025  11:39:46 +0000 (0:00:00.796)       0:00:00.810 ******
2025-09-29 11:39:48.705961 | controller | changed: [instance]
2025-09-29 11:39:48.731721 | controller |
2025-09-29 11:39:48.731791 | controller | TASK [discover_latest_image : Export facts accordingly] ************************
2025-09-29 11:39:48.731800 | controller | Monday 29 September 2025  11:39:48 +0000 (0:00:01.943)       0:00:02.754 ******
2025-09-29 11:39:48.731812 | controller | ok: [instance]
2025-09-29 11:39:48.758273 | controller |
2025-09-29 11:39:48.758312 | controller | TASK [Ensure proper facts are available] ***************************************
2025-09-29 11:39:48.758320 | controller | Monday 29 September 2025  11:39:48 +0000 (0:00:00.025)       0:00:02.779 ******
2025-09-29 11:39:48.758331 | controller | ok: [instance] => changed=false
2025-09-29 11:39:48.789873 | controller |   msg: All assertions passed
2025-09-29 11:39:48.789904 | controller |
2025-09-29 11:39:48.789912 | controller | TASK [Print discover_latest_image variables] ***********************************
2025-09-29 11:39:48.789919 | controller | Monday 29 September 2025  11:39:48 +0000 (0:00:00.026)       0:00:02.806 ******
2025-09-29 11:39:48.789929 | controller | ok: [instance] =>
2025-09-29 11:39:48.847880 | controller |   msg: ('CentOS-Stream-GenericCloud-x86_64-9-latest.x86_64.qcow2', 'https://cloud.centos.org/centos/9-stream/x86_64/images//CentOS-Stream-GenericCloud-x86_64-9-latest.x86_64.qcow2', '8b8daf40220ecd1914c4ba3745b56fff6f7590cea908310b93ced56857276d22', 'sha256')
2025-09-29 11:39:48.847921 | controller |
2025-09-29 11:39:48.847930 | controller | PLAY RECAP *********************************************************************
2025-09-29 11:39:48.847937 | controller | instance                   : ok=5    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-09-29 11:39:48.847942 | controller |
2025-09-29 11:39:48.847948 | controller | Monday 29 September 2025  11:39:48 +0000 (0:00:00.031)       0:00:02.837 ******
2025-09-29 11:39:48.847953 | controller | ===============================================================================
2025-09-29 11:39:48.847959 | controller | discover_latest_image : Get latest image -------------------------------- 1.94s
2025-09-29 11:39:48.847964 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-09-29 11:39:48.847973 | controller | Print discover_latest_image variables ----------------------------------- 0.03s
2025-09-29 11:39:48.847979 | controller | Ensure proper facts are available --------------------------------------- 0.03s
2025-09-29 11:39:48.847984 | controller | discover_latest_image : Export facts accordingly ------------------------ 0.03s
2025-09-29 11:39:48.847995 | controller | INFO     Running default > cleanup
2025-09-29 11:39:48.848461 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-29 11:39:48.849080 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 11:39:49.181465 | controller | changed
2025-09-29 11:39:49.201098 | 
2025-09-29 11:39:49.201149 | PLAY RECAP
2025-09-29 11:39:49.201188 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 11:39:49.201210 | 
2025-09-29 11:39:49.268882 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 11:39:49.269707 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 11:39:49.913685 | 
2025-09-29 11:39:49.913797 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 11:39:50.028886 | 
2025-09-29 11:39:50.029044 | TASK [Filter out host if needed]
2025-09-29 11:39:50.060559 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 11:39:50.073280 | 
2025-09-29 11:39:50.073376 | TASK [Ensure file is present]
2025-09-29 11:39:50.429459 | controller | ok
2025-09-29 11:39:50.436184 | 
2025-09-29 11:39:50.436266 | TASK [Manage molecule report file]
2025-09-29 11:39:50.970209 | controller | changed
2025-09-29 11:39:50.975406 | 
2025-09-29 11:39:50.975482 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 11:39:51.167330 | controller | ok
2025-09-29 11:39:51.172399 | 
2025-09-29 11:39:51.172467 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 11:39:51.524586 | controller | changed
2025-09-29 11:39:51.533796 | 
2025-09-29 11:39:51.533887 | TASK [Copy ci-framework interesting files]
2025-09-29 11:39:51.773195 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-29 11:39:52.090992 | controller | changed
2025-09-29 11:39:52.096564 | 
2025-09-29 11:39:52.096637 | TASK [Get SELinux listing]
2025-09-29 11:39:52.624590 | controller | changed
2025-09-29 11:39:52.630527 | 
2025-09-29 11:39:52.630591 | TASK [Generate log index]
2025-09-29 11:39:53.285561 | controller | changed
2025-09-29 11:39:53.293056 | 
2025-09-29 11:39:53.293128 | TASK [Get some env related data]
2025-09-29 11:39:53.883827 | controller | /home/zuul/.local/bin/ansible
2025-09-29 11:39:54.841519 | controller | changed
2025-09-29 11:39:54.847163 | 
2025-09-29 11:39:54.847227 | TASK [Generate list of logs to collect in home directory]
2025-09-29 11:39:55.160316 | controller | ok: All paths examined
2025-09-29 11:39:55.165564 | 
2025-09-29 11:39:55.165628 | LOOP [Copy logs from home directory]
2025-09-29 11:39:55.543003 | controller | changed:
2025-09-29 11:39:55.543129 | controller | {
2025-09-29 11:39:55.543164 | controller |   "atime": 1759145891.2160847,
2025-09-29 11:39:55.543184 | controller |   "ctime": 1759145907.3513496,
2025-09-29 11:39:55.543207 | controller |   "dev": 64513,
2025-09-29 11:39:55.543225 | controller |   "gid": 1000,
2025-09-29 11:39:55.543243 | controller |   "gr_name": "zuul",
2025-09-29 11:39:55.543261 | controller |   "inode": 4407382,
2025-09-29 11:39:55.543279 | controller |   "isblk": false,
2025-09-29 11:39:55.543296 | controller |   "ischr": false,
2025-09-29 11:39:55.543313 | controller |   "isdir": false,
2025-09-29 11:39:55.543329 | controller |   "isfifo": false,
2025-09-29 11:39:55.543346 | controller |   "isgid": false,
2025-09-29 11:39:55.543363 | controller |   "islnk": false,
2025-09-29 11:39:55.543379 | controller |   "isreg": true,
2025-09-29 11:39:55.543395 | controller |   "issock": false,
2025-09-29 11:39:55.543412 | controller |   "isuid": false,
2025-09-29 11:39:55.543428 | controller |   "mode": "0644",
2025-09-29 11:39:55.543444 | controller |   "mtime": 1759145907.3513496,
2025-09-29 11:39:55.543460 | controller |   "nlink": 1,
2025-09-29 11:39:55.543476 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 11:39:55.543493 | controller |   "pw_name": "zuul",
2025-09-29 11:39:55.543510 | controller |   "rgrp": true,
2025-09-29 11:39:55.543526 | controller |   "roth": true,
2025-09-29 11:39:55.543542 | controller |   "rusr": true,
2025-09-29 11:39:55.543558 | controller |   "size": 6749,
2025-09-29 11:39:55.543573 | controller |   "uid": 1000,
2025-09-29 11:39:55.543589 | controller |   "wgrp": false,
2025-09-29 11:39:55.543605 | controller |   "woth": false,
2025-09-29 11:39:55.543623 | controller |   "wusr": true,
2025-09-29 11:39:55.543639 | controller |   "xgrp": false,
2025-09-29 11:39:55.543656 | controller |   "xoth": false,
2025-09-29 11:39:55.543672 | controller |   "xusr": false
2025-09-29 11:39:55.543689 | controller | }
2025-09-29 11:39:55.571179 | 
2025-09-29 11:39:55.571282 | TASK [Copy crio stats log file]
2025-09-29 11:39:55.595366 | controller | skipping: Conditional result was False
2025-09-29 11:39:55.601285 | 
2025-09-29 11:39:55.601369 | TASK [Get SELinux related data]
2025-09-29 11:39:55.842502 | controller | <no matches>
2025-09-29 11:39:56.136612 | controller | ERROR
2025-09-29 11:39:56.136752 | controller | {
2025-09-29 11:39:56.136785 | controller |   "delta": "0:00:00.011242",
2025-09-29 11:39:56.136806 | controller |   "end": "2025-09-29 11:39:55.844938",
2025-09-29 11:39:56.136824 | controller |   "msg": "non-zero return code",
2025-09-29 11:39:56.136840 | controller |   "rc": 1,
2025-09-29 11:39:56.136858 | controller |   "start": "2025-09-29 11:39:55.833696"
2025-09-29 11:39:56.136874 | controller | }
2025-09-29 11:39:56.136897 | controller | ERROR: Ignoring Errors
2025-09-29 11:39:56.142320 | 
2025-09-29 11:39:56.142383 | TASK [Create system configuration directory]
2025-09-29 11:39:56.333851 | controller | changed
2025-09-29 11:39:56.339117 | 
2025-09-29 11:39:56.339204 | TASK [Get some of the system configurations]
2025-09-29 11:39:56.867353 | controller | changed
2025-09-29 11:39:56.872731 | 
2025-09-29 11:39:56.872807 | TASK [Copy generated documentation if available]
2025-09-29 11:39:56.896446 | controller | skipping: Conditional result was False
2025-09-29 11:39:56.901898 | 
2025-09-29 11:39:56.901998 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 11:39:56.946432 | controller | skipping: Conditional result was False
2025-09-29 11:39:56.952817 | 
2025-09-29 11:39:56.952901 | TASK [Compress logs bigger than 2MB]
2025-09-29 11:39:57.482426 | controller | changed
2025-09-29 11:39:57.488175 | 
2025-09-29 11:39:57.488257 | TASK [Copy files from workspace on node]
2025-09-29 11:39:57.506960 | controller | ok
2025-09-29 11:39:57.528993 | 
2025-09-29 11:39:57.529090 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 11:39:57.542441 | controller | skipping: Conditional result was False
2025-09-29 11:39:57.548567 | 
2025-09-29 11:39:57.548652 | TASK [fetch-output : Set log path for single node]
2025-09-29 11:39:57.578129 | controller | ok
2025-09-29 11:39:57.584161 | 
2025-09-29 11:39:57.584251 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 11:39:57.800873 | controller -> localhost | ok: "/var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/logs"
2025-09-29 11:39:57.801272 | controller -> localhost | changed: All items complete
2025-09-29 11:39:57.801310 | 
2025-09-29 11:39:58.000780 | controller -> localhost | changed: "/var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/artifacts"
2025-09-29 11:39:58.183925 | controller -> localhost | changed: "/var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/docs"
2025-09-29 11:39:58.200567 | 
2025-09-29 11:39:58.200702 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 11:39:58.866836 | controller | changed:
2025-09-29 11:39:58.867126 | controller | .d..t...... ./
2025-09-29 11:39:58.867170 | controller | >f+++++++++ README.html
2025-09-29 11:39:58.867195 | controller | >f+++++++++ ansible-execution.log
2025-09-29 11:39:58.867216 | controller | >f+++++++++ ansible.log
2025-09-29 11:39:58.867234 | controller | >f+++++++++ dmesg.log
2025-09-29 11:39:58.867254 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 11:39:58.867275 | controller | >f+++++++++ python.log
2025-09-29 11:39:58.867297 | controller | >f+++++++++ registries.conf
2025-09-29 11:39:58.867316 | controller | >f+++++++++ report.html
2025-09-29 11:39:58.867337 | controller | >f+++++++++ selinux-denials.log
2025-09-29 11:39:58.867355 | controller | >f+++++++++ selinux-listing.log
2025-09-29 11:39:58.867374 | controller | cd+++++++++ ci-framework-data/
2025-09-29 11:39:58.867391 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 11:39:58.867409 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 11:39:58.867427 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 11:39:58.867443 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 11:39:58.867459 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-09-29 11:39:58.867475 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-29 11:39:58.867495 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 11:39:58.867514 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 11:39:58.867531 | controller | cd+++++++++ registries.conf.d/
2025-09-29 11:39:58.867548 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 11:39:58.867564 | controller | cd+++++++++ system-config/
2025-09-29 11:39:58.867581 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 11:39:58.867596 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 11:39:58.867613 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 11:39:59.394557 | controller | changed: .d..t...... ./
2025-09-29 11:39:59.776607 | controller | changed: .d..t...... ./
2025-09-29 11:39:59.797525 | 
2025-09-29 11:39:59.797630 | TASK [Return artifact to Zuul]
2025-09-29 11:39:59.826470 | controller | ok
2025-09-29 11:39:59.845703 | 
2025-09-29 11:39:59.845774 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 11:39:59.845873 | 
2025-09-29 11:39:59.845900 | PLAY RECAP
2025-09-29 11:39:59.845962 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 11:39:59.845987 | 
2025-09-29 11:39:59.926447 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 11:39:59.927186 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 11:40:00.485849 | 
2025-09-29 11:40:00.485981 | PLAY [all]
2025-09-29 11:40:00.504784 | 
2025-09-29 11:40:00.504884 | TASK [include_role : fetch-output]
2025-09-29 11:40:00.543719 | controller | ok
2025-09-29 11:40:00.561750 | 
2025-09-29 11:40:00.561851 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 11:40:00.605988 | controller | skipping: Conditional result was False
2025-09-29 11:40:00.611559 | 
2025-09-29 11:40:00.611641 | TASK [fetch-output : Set log path for single node]
2025-09-29 11:40:00.640225 | controller | ok
2025-09-29 11:40:00.645306 | 
2025-09-29 11:40:00.645384 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 11:40:00.998937 | controller -> localhost | ok: "/var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/logs"
2025-09-29 11:40:01.199126 | controller -> localhost | ok: "/var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/artifacts"
2025-09-29 11:40:01.400100 | controller -> localhost | ok: "/var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/docs"
2025-09-29 11:40:01.418390 | 
2025-09-29 11:40:01.418500 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 11:40:01.961971 | controller | ok
2025-09-29 11:40:01.962188 | controller | ok: All items complete
2025-09-29 11:40:01.962219 | 
2025-09-29 11:40:02.331387 | controller | ok
2025-09-29 11:40:02.740785 | controller | ok
2025-09-29 11:40:02.770999 | 
2025-09-29 11:40:02.771104 | TASK [include_role : fetch-output-openshift]
2025-09-29 11:40:02.794306 | controller | skipping: Conditional result was False
2025-09-29 11:40:02.800739 | 
2025-09-29 11:40:02.800818 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 11:40:03.150341 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006443
2025-09-29 11:40:03.354983 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007400
2025-09-29 11:40:03.387287 | 
2025-09-29 11:40:03.387396 | PLAY [all]
2025-09-29 11:40:03.402196 | 
2025-09-29 11:40:03.402267 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 11:40:03.806052 | controller | changed
2025-09-29 11:40:03.827407 | 
2025-09-29 11:40:03.827465 | PLAY RECAP
2025-09-29 11:40:03.827506 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 11:40:03.827528 | 
2025-09-29 11:40:03.928539 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 11:40:03.929406 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 11:40:04.492081 | 
2025-09-29 11:40:04.492250 | PLAY [localhost]
2025-09-29 11:40:04.510313 | 
2025-09-29 11:40:04.510390 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 11:40:04.831640 | localhost | changed
2025-09-29 11:40:04.836274 | 
2025-09-29 11:40:04.836475 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 11:40:04.864444 | localhost | ok
2025-09-29 11:40:04.872440 | 
2025-09-29 11:40:04.872507 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 11:40:05.193760 | localhost | changed
2025-09-29 11:40:05.199601 | 
2025-09-29 11:40:05.199691 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 11:40:05.790241 | localhost | changed
2025-09-29 11:40:05.795619 | 
2025-09-29 11:40:05.795686 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 11:40:06.261880 | localhost | Identity added: /var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/tmp/ansible.6y4r8_pw (/var/lib/zuul/builds/9b038dfa9df04eeaacff0b13a48055d1/work/tmp/ansible.6y4r8_pw)
2025-09-29 11:40:06.262091 | localhost | ok: Runtime: 0:00:00.012712
2025-09-29 11:40:06.266932 | 
2025-09-29 11:40:06.267095 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 11:40:06.726174 | localhost | ok: Runtime: 0:00:00.004223
2025-09-29 11:40:06.732080 | 
2025-09-29 11:40:06.732154 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 11:40:06.810521 | localhost | changed
2025-09-29 11:40:06.815135 | 
2025-09-29 11:40:06.815214 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 11:40:07.178613 | localhost | changed
2025-09-29 11:40:07.198974 | 
2025-09-29 11:40:07.199051 | PLAY [localhost]
2025-09-29 11:40:07.211538 | 
2025-09-29 11:40:07.211605 | TASK [Generate bulk log download script]
2025-09-29 11:40:07.229456 | localhost | ok
2025-09-29 11:40:07.241666 | 
2025-09-29 11:40:07.241903 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 11:40:07.269867 | localhost | ok: All assertions passed
2025-09-29 11:40:07.274570 | 
2025-09-29 11:40:07.274647 | TASK [local-log-download : Create download script]
2025-09-29 11:40:07.662633 | localhost -> localhost | changed
2025-09-29 11:40:07.671375 | 
2025-09-29 11:40:07.671455 | TASK [Register quick-download link]
2025-09-29 11:40:07.689945 | localhost | ok
2025-09-29 11:40:07.726691 | 
2025-09-29 11:40:07.726780 | PLAY [logserver.rdoproject.org]
2025-09-29 11:40:07.736886 | 
2025-09-29 11:40:07.736967 | TASK [Set zuul-log-path fact]
2025-09-29 11:40:07.752435 | logserver.rdoproject.org | ok
2025-09-29 11:40:07.761563 | 
2025-09-29 11:40:07.761648 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 11:40:07.787790 | logserver.rdoproject.org | ok
2025-09-29 11:40:07.793156 | 
2025-09-29 11:40:07.793226 | TASK [upload-logs : Create log directories]
2025-09-29 11:40:09.482065 | logserver.rdoproject.org | changed
2025-09-29 11:40:09.485529 | 
2025-09-29 11:40:09.485608 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 11:40:09.734169 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.016615
2025-09-29 11:40:09.738512 | 
2025-09-29 11:40:09.738592 | TASK [upload-logs : Upload logs to log server]
2025-09-29 11:40:11.275510 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 11:40:11.278935 | 
2025-09-29 11:40:11.279025 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 11:40:11.350810 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 11:40:11.351827 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 11:40:11.363810 | 
2025-09-29 11:40:11.364040 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 11:40:11.403271 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 11:40:11.403560 | 
2025-09-29 11:40:11.407895 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 11:40:11.422084 | 
2025-09-29 11:40:11.422204 | LOOP [upload-logs : Upload console log and json output]