2025-10-08 12:04:16.709374 | Job console starting...
2025-10-08 12:04:16.948469 | Updating repositories
2025-10-08 12:04:17.677835 | Preparing job workspace
2025-10-08 12:04:24.966035 | Running Ansible setup...
2025-10-08 12:04:28.904468 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:04:29.469202 | 
2025-10-08 12:04:29.469326 | PLAY [localhost]
2025-10-08 12:04:29.478213 | 
2025-10-08 12:04:29.478291 | TASK [Gathering Facts]
2025-10-08 12:04:30.447254 | localhost | ok
2025-10-08 12:04:30.461877 | 
2025-10-08 12:04:30.462014 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 12:04:30.821105 | localhost -> localhost | changed
2025-10-08 12:04:30.826419 | 
2025-10-08 12:04:30.826504 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 12:04:31.644078 | localhost -> localhost | changed
2025-10-08 12:04:31.653971 | 
2025-10-08 12:04:31.654059 | TASK [Setup log path fact]
2025-10-08 12:04:31.692009 | localhost | ok
2025-10-08 12:04:31.714095 | 
2025-10-08 12:04:31.714194 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:04:31.751902 | localhost | ok
2025-10-08 12:04:31.766742 | 
2025-10-08 12:04:31.766855 | TASK [emit-job-header : Print job information]
2025-10-08 12:04:31.795707 | # Job Information
2025-10-08 12:04:31.795922 | Ansible Version: 2.15.12
2025-10-08 12:04:31.795965 | Job: cifmw-molecule-openshift_provisioner_node
2025-10-08 12:04:31.795991 | Pipeline: github-check
2025-10-08 12:04:31.796013 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 12:04:31.796033 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3373
2025-10-08 12:04:31.796054 | Log URL (when completed): https://logserver.rdoproject.org/bef/rdoproject.org/befb55bd01bb4d13bc9357b4625e1682/
2025-10-08 12:04:31.796073 | Event ID: 3a070520-a421-11f0-9b5a-2d6f635919d0
2025-10-08 12:04:31.800271 | 
2025-10-08 12:04:31.800637 | LOOP [emit-job-header : Print node information]
2025-10-08 12:04:31.901490 | localhost | ok:
2025-10-08 12:04:31.901638 | localhost | # Node Information
2025-10-08 12:04:31.901669 | localhost | Inventory Hostname: controller
2025-10-08 12:04:31.901694 | localhost | Hostname: np0005476017
2025-10-08 12:04:31.901716 | localhost | Username: zuul
2025-10-08 12:04:31.901738 | localhost | Distro: CentOS 9
2025-10-08 12:04:31.901759 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 12:04:31.901778 | localhost | Region: regionOne
2025-10-08 12:04:31.901795 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-10-08 12:04:31.901813 | localhost | Product Name: OpenStack Compute
2025-10-08 12:04:31.901832 | localhost | Interface IP: 192.168.26.61
2025-10-08 12:04:31.926239 | 
2025-10-08 12:04:31.926369 | PLAY [all]
2025-10-08 12:04:31.936408 | 
2025-10-08 12:04:31.936476 | TASK [Gather network facts]
2025-10-08 12:04:32.378200 | controller | ok
2025-10-08 12:04:32.394748 | 
2025-10-08 12:04:32.394830 | TASK [include_role : start-zuul-console]
2025-10-08 12:04:32.413150 | controller | ok
2025-10-08 12:04:32.425157 | 
2025-10-08 12:04:32.425234 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 12:04:32.813367 | controller | ok
2025-10-08 12:04:32.833998 | 
2025-10-08 12:04:32.834098 | TASK [include_role : add-build-sshkey]
2025-10-08 12:04:32.857791 | controller | ok
2025-10-08 12:04:32.871789 | 
2025-10-08 12:04:32.871884 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 12:04:33.098732 | controller -> localhost | ok
2025-10-08 12:04:33.106052 | 
2025-10-08 12:04:33.106144 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 12:04:33.138004 | controller | ok
2025-10-08 12:04:33.150580 | controller | included: /var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 12:04:33.156352 | 
2025-10-08 12:04:33.156433 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 12:04:33.834585 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 12:04:33.834771 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/befb55bd01bb4d13bc9357b4625e1682_id_rsa.
2025-10-08 12:04:33.834808 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/befb55bd01bb4d13bc9357b4625e1682_id_rsa.pub.
2025-10-08 12:04:33.834833 | controller -> localhost | The key fingerprint is:
2025-10-08 12:04:33.834853 | controller -> localhost | SHA256:Meswjfe7LMC4Gu765GY5bb+WJqDy0JdawFWj1ARBdbY zuul-build-sshkey
2025-10-08 12:04:33.834872 | controller -> localhost | The key's randomart image is:
2025-10-08 12:04:33.834891 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 12:04:33.835042 | controller -> localhost | |   .=*= o        |
2025-10-08 12:04:33.835101 | controller -> localhost | |   . o.+ .       |
2025-10-08 12:04:33.835130 | controller -> localhost | |    o   E        |
2025-10-08 12:04:33.835152 | controller -> localhost | | . .   o +       |
2025-10-08 12:04:33.835171 | controller -> localhost | |  o  o+ S        |
2025-10-08 12:04:33.835190 | controller -> localhost | | .....o= .       |
2025-10-08 12:04:33.835207 | controller -> localhost | |..+++. o. .      |
2025-10-08 12:04:33.835225 | controller -> localhost | |o==== + .. .     |
2025-10-08 12:04:33.835242 | controller -> localhost | |oBO+ =o. .+.     |
2025-10-08 12:04:33.835258 | controller -> localhost | +----[SHA256]-----+
2025-10-08 12:04:33.835315 | controller -> localhost | ok: Runtime: 0:00:00.232016
2025-10-08 12:04:33.841843 | 
2025-10-08 12:04:33.841943 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 12:04:33.870615 | controller | ok
2025-10-08 12:04:33.881157 | controller | included: /var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 12:04:33.891560 | 
2025-10-08 12:04:33.891645 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 12:04:33.937244 | controller | skipping: Conditional result was False
2025-10-08 12:04:33.943697 | 
2025-10-08 12:04:33.943776 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 12:04:34.440144 | controller | changed
2025-10-08 12:04:34.453717 | 
2025-10-08 12:04:34.455134 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 12:04:34.743944 | controller | ok
2025-10-08 12:04:34.749080 | 
2025-10-08 12:04:34.749160 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 12:04:35.864008 | controller | changed
2025-10-08 12:04:35.871419 | 
2025-10-08 12:04:35.871507 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 12:04:36.939035 | controller | changed
2025-10-08 12:04:36.944107 | 
2025-10-08 12:04:36.944188 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 12:04:36.970125 | controller | skipping: Conditional result was False
2025-10-08 12:04:36.975886 | 
2025-10-08 12:04:36.975983 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 12:04:37.304456 | controller -> localhost | changed
2025-10-08 12:04:37.314451 | 
2025-10-08 12:04:37.314523 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 12:04:37.562571 | controller -> localhost | Identity added: /var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/befb55bd01bb4d13bc9357b4625e1682_id_rsa (zuul-build-sshkey)
2025-10-08 12:04:37.562838 | controller -> localhost | ok: Runtime: 0:00:00.007243
2025-10-08 12:04:37.569544 | 
2025-10-08 12:04:37.569607 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 12:04:37.931316 | controller | ok
2025-10-08 12:04:37.936051 | 
2025-10-08 12:04:37.936128 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 12:04:37.960985 | controller | skipping: Conditional result was False
2025-10-08 12:04:37.970991 | 
2025-10-08 12:04:37.971095 | TASK [include_role : validate-host]
2025-10-08 12:04:37.989311 | controller | ok
2025-10-08 12:04:38.011971 | 
2025-10-08 12:04:38.012082 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 12:04:38.057233 | controller | ok
2025-10-08 12:04:38.065085 | 
2025-10-08 12:04:38.065171 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 12:04:38.318446 | controller -> localhost | ok
2025-10-08 12:04:38.349634 | 
2025-10-08 12:04:38.349732 | TASK [validate-host : Collect information about the host]
2025-10-08 12:04:39.050897 | controller | ok
2025-10-08 12:04:39.061050 | 
2025-10-08 12:04:39.061136 | TASK [validate-host : Sanitize hostname]
2025-10-08 12:04:39.109579 | controller | ok
2025-10-08 12:04:39.115763 | 
2025-10-08 12:04:39.115851 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 12:04:39.546429 | controller -> localhost | changed
2025-10-08 12:04:39.552412 | 
2025-10-08 12:04:39.552495 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 12:04:39.946352 | controller | ok
2025-10-08 12:04:39.951514 | 
2025-10-08 12:04:39.951588 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 12:04:40.412973 | controller -> localhost | changed
2025-10-08 12:04:40.423751 | 
2025-10-08 12:04:40.423849 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 12:04:40.437195 | controller | skipping: Conditional result was False
2025-10-08 12:04:40.443943 | 
2025-10-08 12:04:40.444028 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 12:04:40.457452 | controller | skipping: Conditional result was False
2025-10-08 12:04:40.463901 | 
2025-10-08 12:04:40.464016 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 12:04:40.751739 | controller | ok: "logs"
2025-10-08 12:04:40.751986 | controller | ok: All items complete
2025-10-08 12:04:40.752018 | 
2025-10-08 12:04:40.979654 | controller | ok: "artifacts"
2025-10-08 12:04:41.207990 | controller | ok: "docs"
2025-10-08 12:04:41.214863 | 
2025-10-08 12:04:41.214964 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 12:04:41.479135 | controller | changed: "logs"
2025-10-08 12:04:41.750143 | controller | changed: "artifacts"
2025-10-08 12:04:41.997239 | controller | changed: "docs"
2025-10-08 12:04:42.022883 | 
2025-10-08 12:04:42.022988 | PLAY RECAP
2025-10-08 12:04:42.023034 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:04:42.023066 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:04:42.023087 | 
2025-10-08 12:04:42.114583 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:04:42.115437 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:04:42.870505 | 
2025-10-08 12:04:42.870625 | PLAY [all]
2025-10-08 12:04:42.891715 | 
2025-10-08 12:04:42.891820 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:04:42.951763 | controller | ok
2025-10-08 12:04:42.957282 | 
2025-10-08 12:04:42.957413 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:04:43.397541 | controller | changed
2025-10-08 12:04:43.403523 | 
2025-10-08 12:04:43.403615 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:04:44.850149 | controller | changed
2025-10-08 12:04:44.860384 | 
2025-10-08 12:04:44.860466 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 12:04:45.325005 | controller | changed:
2025-10-08 12:04:45.325166 | controller | {
2025-10-08 12:04:45.325198 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 12:04:45.325228 | controller | }
2025-10-08 12:04:45.622824 | controller | changed:
2025-10-08 12:04:45.622933 | controller | {
2025-10-08 12:04:45.622968 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 12:04:45.622993 | controller | }
2025-10-08 12:04:45.920774 | controller | changed:
2025-10-08 12:04:45.920853 | controller | {
2025-10-08 12:04:45.920881 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 12:04:45.920904 | controller | }
2025-10-08 12:04:46.228444 | controller | changed:
2025-10-08 12:04:46.228550 | controller | {
2025-10-08 12:04:46.228579 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 12:04:46.228602 | controller | }
2025-10-08 12:04:46.534356 | controller | changed:
2025-10-08 12:04:46.534443 | controller | {
2025-10-08 12:04:46.534473 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 12:04:46.534496 | controller | }
2025-10-08 12:04:46.849335 | controller | changed:
2025-10-08 12:04:46.849426 | controller | {
2025-10-08 12:04:46.849454 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 12:04:46.849477 | controller | }
2025-10-08 12:04:47.165723 | controller | changed:
2025-10-08 12:04:47.165813 | controller | {
2025-10-08 12:04:47.165842 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-08 12:04:47.165864 | controller | }
2025-10-08 12:04:47.469149 | controller | changed:
2025-10-08 12:04:47.469230 | controller | {
2025-10-08 12:04:47.469260 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-08 12:04:47.469284 | controller | }
2025-10-08 12:04:47.772878 | controller | changed:
2025-10-08 12:04:47.772997 | controller | {
2025-10-08 12:04:47.773027 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 12:04:47.773050 | controller | }
2025-10-08 12:04:48.088479 | controller | changed:
2025-10-08 12:04:48.088564 | controller | {
2025-10-08 12:04:48.088592 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 12:04:48.088614 | controller | }
2025-10-08 12:04:48.401228 | controller | changed:
2025-10-08 12:04:48.401329 | controller | {
2025-10-08 12:04:48.401357 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 12:04:48.401378 | controller | }
2025-10-08 12:04:48.715944 | controller | changed:
2025-10-08 12:04:48.716035 | controller | {
2025-10-08 12:04:48.716062 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 12:04:48.716084 | controller | }
2025-10-08 12:04:49.023128 | controller | changed:
2025-10-08 12:04:49.023220 | controller | {
2025-10-08 12:04:49.023248 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 12:04:49.023269 | controller | }
2025-10-08 12:04:49.338754 | controller | changed:
2025-10-08 12:04:49.338831 | controller | {
2025-10-08 12:04:49.338857 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 12:04:49.338878 | controller | }
2025-10-08 12:04:49.639030 | controller | changed:
2025-10-08 12:04:49.639353 | controller | {
2025-10-08 12:04:49.639399 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 12:04:49.639422 | controller | }
2025-10-08 12:04:49.939198 | controller | changed:
2025-10-08 12:04:49.939382 | controller | {
2025-10-08 12:04:49.939414 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 12:04:49.939821 | controller | }
2025-10-08 12:04:50.241694 | controller | changed:
2025-10-08 12:04:50.241781 | controller | {
2025-10-08 12:04:50.241810 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-08 12:04:50.241833 | controller | }
2025-10-08 12:04:50.542676 | controller | changed:
2025-10-08 12:04:50.542762 | controller | {
2025-10-08 12:04:50.542789 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-08 12:04:50.542818 | controller | }
2025-10-08 12:04:50.855562 | controller | changed:
2025-10-08 12:04:50.855766 | controller | {
2025-10-08 12:04:50.855794 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 12:04:50.855815 | controller | }
2025-10-08 12:04:51.157131 | controller | changed:
2025-10-08 12:04:51.157227 | controller | {
2025-10-08 12:04:51.157255 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 12:04:51.157279 | controller | }
2025-10-08 12:04:51.462054 | controller | changed:
2025-10-08 12:04:51.462132 | controller | {
2025-10-08 12:04:51.462160 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 12:04:51.462183 | controller | }
2025-10-08 12:04:51.765629 | controller | changed:
2025-10-08 12:04:51.765716 | controller | {
2025-10-08 12:04:51.765743 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 12:04:51.765764 | controller | }
2025-10-08 12:04:52.063499 | controller | changed:
2025-10-08 12:04:52.063591 | controller | {
2025-10-08 12:04:52.063617 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-08 12:04:52.064048 | controller | }
2025-10-08 12:04:52.363060 | controller | changed:
2025-10-08 12:04:52.363149 | controller | {
2025-10-08 12:04:52.363176 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 12:04:52.363197 | controller | }
2025-10-08 12:04:52.668248 | controller | changed:
2025-10-08 12:04:52.668351 | controller | {
2025-10-08 12:04:52.668379 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 12:04:52.668407 | controller | }
2025-10-08 12:04:52.973284 | controller | changed:
2025-10-08 12:04:52.973377 | controller | {
2025-10-08 12:04:52.973407 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 12:04:52.973429 | controller | }
2025-10-08 12:04:52.989738 | 
2025-10-08 12:04:52.989819 | TASK [Set timezone to UTC]
2025-10-08 12:04:54.507982 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 12:04:54.513390 | 
2025-10-08 12:04:54.513482 | TASK [Create nodepool directory]
2025-10-08 12:04:54.787501 | controller | changed
2025-10-08 12:04:54.793779 | 
2025-10-08 12:04:54.793868 | TASK [Create nodepool sub_nodes file]
2025-10-08 12:04:55.803204 | controller | changed
2025-10-08 12:04:55.809332 | 
2025-10-08 12:04:55.809408 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 12:04:56.815023 | controller | changed
2025-10-08 12:04:56.820255 | 
2025-10-08 12:04:56.820347 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 12:04:56.850697 | 
2025-10-08 12:04:56.850856 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 12:04:56.882422 | 
2025-10-08 12:04:56.882566 | TASK [Create nodepool primary file]
2025-10-08 12:04:56.906707 | controller | skipping: Conditional result was False
2025-10-08 12:04:56.916115 | 
2025-10-08 12:04:56.916217 | TASK [Create nodepool node_private for this node]
2025-10-08 12:04:57.949644 | controller | changed
2025-10-08 12:04:57.955502 | 
2025-10-08 12:04:57.955569 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 12:04:58.366633 | controller | ok: Item: id_rsa Runtime: 0:00:00.004785
2025-10-08 12:04:58.366818 | 
2025-10-08 12:04:58.604174 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004932
2025-10-08 12:04:58.612876 | 
2025-10-08 12:04:58.613070 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 12:04:59.574157 | controller | changed
2025-10-08 12:04:59.579380 | 
2025-10-08 12:04:59.579442 | TASK [Validate sudoers config after edits]
2025-10-08 12:04:59.876307 | controller | /etc/sudoers: parsed OK
2025-10-08 12:04:59.876372 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 12:04:59.876382 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 12:04:59.876388 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 12:05:00.115427 | controller | ok: Runtime: 0:00:00.006511
2025-10-08 12:05:00.129052 | 
2025-10-08 12:05:00.129117 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 12:05:00.398937 | controller | SHELL=/bin/bash
2025-10-08 12:05:00.398998 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 12:05:00.399008 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 12:05:00.399015 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/73/3373/1a7b2ae378ead444d1ed756aace4ec009d749f1a
2025-10-08 12:05:00.399021 | controller | PWD=/home/zuul
2025-10-08 12:05:00.399086 | controller | ZUUL_PIPELINE=github-check
2025-10-08 12:05:00.399099 | controller | LOGNAME=zuul
2025-10-08 12:05:00.399111 | controller | XDG_SESSION_TYPE=tty
2025-10-08 12:05:00.399117 | controller | _=/usr/bin/env
2025-10-08 12:05:00.399122 | controller | MOTD_SHOWN=pam
2025-10-08 12:05:00.399128 | controller | HOME=/home/zuul
2025-10-08 12:05:00.399133 | controller | LANG=en_US.UTF-8
2025-10-08 12:05:00.399138 | controller | SSH_CONNECTION=192.168.26.12 45810 192.168.26.61 22
2025-10-08 12:05:00.399144 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-08 12:05:00.399154 | controller | ZUUL_CHANGE_IDS=3373,1a7b2ae378ead444d1ed756aace4ec009d749f1a
2025-10-08 12:05:00.399159 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 12:05:00.399164 | controller | XDG_SESSION_CLASS=user
2025-10-08 12:05:00.399170 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 12:05:00.399175 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 12:05:00.399181 | controller | USER=zuul
2025-10-08 12:05:00.399186 | controller | ZUUL_VOTING=True
2025-10-08 12:05:00.399191 | controller | BUILD_TIMEOUT=1800000
2025-10-08 12:05:00.399196 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 12:05:00.399201 | controller | SHLVL=1
2025-10-08 12:05:00.399207 | controller | ZUUL_PATCHSET=1a7b2ae378ead444d1ed756aace4ec009d749f1a
2025-10-08 12:05:00.399212 | controller | XDG_SESSION_ID=1
2025-10-08 12:05:00.399218 | controller | ZUUL_BRANCH=main
2025-10-08 12:05:00.399223 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 12:05:00.399229 | controller | SSH_CLIENT=192.168.26.12 45810 22
2025-10-08 12:05:00.399234 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 12:05:00.399240 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 12:05:00.399245 | controller | which_declare=declare -f
2025-10-08 12:05:00.399251 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 12:05:00.399257 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 12:05:00.399262 | controller | ZUUL_CHANGE=3373
2025-10-08 12:05:00.399267 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 12:05:00.399273 | controller | ZUUL_UUID=befb55bd01bb4d13bc9357b4625e1682
2025-10-08 12:05:00.399280 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 12:05:00.399285 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 12:05:00.399291 | controller | }
2025-10-08 12:05:00.660583 | controller | ok: Runtime: 0:00:00.006746
2025-10-08 12:05:00.668458 | 
2025-10-08 12:05:00.668529 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 12:05:00.682616 | controller | skipping: Conditional result was False
2025-10-08 12:05:00.688275 | 
2025-10-08 12:05:00.688369 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 12:05:01.235214 | controller | skipping: Conditional result was False
2025-10-08 12:05:01.240860 | 
2025-10-08 12:05:01.240950 | TASK [Ensure legacy workspace directory]
2025-10-08 12:05:01.493605 | controller | changed
2025-10-08 12:05:01.512424 | 
2025-10-08 12:05:01.512492 | PLAY RECAP
2025-10-08 12:05:01.512537 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:05:01.512560 | 
2025-10-08 12:05:01.600776 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:05:01.601766 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:05:02.124587 | 
2025-10-08 12:05:02.124696 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 12:05:02.144956 | 
2025-10-08 12:05:02.145065 | TASK [Create zuul-output directory]
2025-10-08 12:05:02.561146 | controller | changed
2025-10-08 12:05:02.566399 | 
2025-10-08 12:05:02.566480 | TASK [Slurp Zuul inventory test]
2025-10-08 12:05:02.889111 | controller -> localhost | ok
2025-10-08 12:05:02.896225 | 
2025-10-08 12:05:02.896323 | TASK [Save zuul inventory]
2025-10-08 12:05:04.161117 | controller | changed
2025-10-08 12:05:04.168528 | 
2025-10-08 12:05:04.168603 | TASK [Save zuul vars without the change_message]
2025-10-08 12:05:05.318645 | controller | changed
2025-10-08 12:05:05.348112 | 
2025-10-08 12:05:05.348197 | PLAY RECAP
2025-10-08 12:05:05.348245 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:05:05.348269 | 
2025-10-08 12:05:05.460215 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:05:05.460971 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:05:06.068718 | 
2025-10-08 12:05:06.068835 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 12:05:06.089979 | 
2025-10-08 12:05:06.090059 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 12:05:06.114813 | controller | ok
2025-10-08 12:05:06.150960 | 
2025-10-08 12:05:06.151070 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:05:06.178674 | controller | skipping: Conditional result was False
2025-10-08 12:05:06.184513 | 
2025-10-08 12:05:06.184596 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:05:06.598553 | controller | ok
2025-10-08 12:05:06.604663 | 
2025-10-08 12:05:06.604737 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:05:07.577098 | controller | ok
2025-10-08 12:05:07.592526 | 
2025-10-08 12:05:07.592626 | TASK [Prepare workspace]
2025-10-08 12:05:07.611464 | controller | ok
2025-10-08 12:05:07.630552 | 
2025-10-08 12:05:07.630720 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 12:05:08.015241 | controller | ok
2025-10-08 12:05:08.021835 | 
2025-10-08 12:05:08.021939 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 12:05:10.377082 | controller | Output suppressed because no_log was given
2025-10-08 12:05:10.387337 | 
2025-10-08 12:05:10.387418 | LOOP [Create zuul-output directory]
2025-10-08 12:05:10.649388 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 12:05:10.900334 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 12:05:10.919883 | 
2025-10-08 12:05:10.919993 | TASK [Install required packages]
2025-10-08 12:05:37.423855 | controller | ok: Nothing to do
2025-10-08 12:05:37.429831 | 
2025-10-08 12:05:37.429897 | TASK [Install venv]
2025-10-08 12:06:38.326122 | controller | changed
2025-10-08 12:06:38.350607 | 
2025-10-08 12:06:38.350668 | PLAY RECAP
2025-10-08 12:06:38.350713 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:06:38.350738 | 
2025-10-08 12:06:38.468405 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:06:38.469172 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:06:39.039308 | 
2025-10-08 12:06:39.039426 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 12:06:39.060496 | 
2025-10-08 12:06:39.060613 | TASK [Gather required facts]
2025-10-08 12:06:39.629060 | controller | ok
2025-10-08 12:06:39.636797 | 
2025-10-08 12:06:39.636887 | TASK [Load environment var if instructed to]
2025-10-08 12:06:39.660956 | controller | skipping: Conditional result was False
2025-10-08 12:06:39.667238 | 
2025-10-08 12:06:39.667337 | TASK [Run molecule]
2025-10-08 12:06:40.702099 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 12:06:40.771813 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 12:06:57.594853 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.595317 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.595764 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.596221 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.596673 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+1a7b2ae3 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0+1a7b2ae3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.597125 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.597553 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.597981 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.598423 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.598868 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.599326 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.599758 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.600242 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.600682 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.601127 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:06:57.610330 | controller | INFO     Running default > prepare
2025-10-08 12:06:58.327699 | controller |
2025-10-08 12:06:58.327785 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 12:06:58.327894 | controller |
2025-10-08 12:06:58.327989 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:06:58.328093 | controller | Wednesday 08 October 2025  12:06:58 +0000 (0:00:00.029)       0:00:00.029 *****
2025-10-08 12:06:59.182420 | controller | ok: [instance]
2025-10-08 12:06:59.182498 | controller |
2025-10-08 12:06:59.182627 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 12:06:59.182770 | controller | Wednesday 08 October 2025  12:06:59 +0000 (0:00:00.855)       0:00:00.885 *****
2025-10-08 12:06:59.199169 | controller | skipping: [instance]
2025-10-08 12:06:59.199297 | controller |
2025-10-08 12:06:59.199425 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 12:06:59.199550 | controller | Wednesday 08 October 2025  12:06:59 +0000 (0:00:00.016)       0:00:00.902 *****
2025-10-08 12:06:59.239800 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 12:06:59.239903 | controller |
2025-10-08 12:06:59.240033 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 12:06:59.240167 | controller | Wednesday 08 October 2025  12:06:59 +0000 (0:00:00.040)       0:00:00.942 *****
2025-10-08 12:06:59.518169 | controller | ok: [instance]
2025-10-08 12:06:59.518267 | controller |
2025-10-08 12:06:59.518394 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 12:06:59.518515 | controller | Wednesday 08 October 2025  12:06:59 +0000 (0:00:00.278)       0:00:01.221 *****
2025-10-08 12:06:59.827656 | controller | ok: [instance]
2025-10-08 12:06:59.827722 | controller |
2025-10-08 12:06:59.827844 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 12:06:59.827943 | controller | Wednesday 08 October 2025  12:06:59 +0000 (0:00:00.309)       0:00:01.530 *****
2025-10-08 12:07:00.515712 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 12:07:00.515817 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 12:07:00.515925 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 12:07:00.516021 | controller |
2025-10-08 12:07:00.516142 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 12:07:00.516252 | controller | Wednesday 08 October 2025  12:07:00 +0000 (0:00:00.688)       0:00:02.218 *****
2025-10-08 12:07:00.575261 | controller |
2025-10-08 12:07:00.575350 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 12:07:00.575453 | controller | Wednesday 08 October 2025  12:07:00 +0000 (0:00:00.059)       0:00:02.278 *****
2025-10-08 12:07:01.124714 | controller | changed: [instance] => (item=tmp)
2025-10-08 12:07:01.124803 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 12:07:01.125116 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 12:07:01.125220 | controller |
2025-10-08 12:07:01.125321 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 12:07:01.125423 | controller | Wednesday 08 October 2025  12:07:01 +0000 (0:00:00.549)       0:00:02.827 *****
2025-10-08 12:07:01.953199 | controller | ok: [instance]
2025-10-08 12:07:01.953232 | controller |
2025-10-08 12:07:01.953240 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 12:07:01.953252 | controller | Wednesday 08 October 2025  12:07:01 +0000 (0:00:00.828)       0:00:03.655 *****
2025-10-08 12:07:03.173865 | controller | changed: [instance]
2025-10-08 12:07:11.103528 | controller |
2025-10-08 12:07:11.103566 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 12:07:11.103575 | controller | Wednesday 08 October 2025  12:07:03 +0000 (0:00:01.220)       0:00:04.876 *****
2025-10-08 12:07:11.103584 | controller | changed: [instance]
2025-10-08 12:07:11.835756 | controller |
2025-10-08 12:07:11.835798 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 12:07:11.835807 | controller | Wednesday 08 October 2025  12:07:11 +0000 (0:00:07.929)       0:00:12.806 *****
2025-10-08 12:07:11.835817 | controller | changed: [instance]
2025-10-08 12:07:11.857947 | controller |
2025-10-08 12:07:11.857967 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 12:07:11.857975 | controller | Wednesday 08 October 2025  12:07:11 +0000 (0:00:00.732)       0:00:13.538 *****
2025-10-08 12:07:11.857984 | controller | skipping: [instance]
2025-10-08 12:07:12.723140 | controller |
2025-10-08 12:07:12.723181 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 12:07:12.723191 | controller | Wednesday 08 October 2025  12:07:11 +0000 (0:00:00.022)       0:00:13.561 *****
2025-10-08 12:07:12.723205 | controller | changed: [instance]
2025-10-08 12:07:12.761211 | controller |
2025-10-08 12:07:12.761254 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 12:07:12.761263 | controller | Wednesday 08 October 2025  12:07:12 +0000 (0:00:00.864)       0:00:14.425 *****
2025-10-08 12:07:12.761276 | controller | skipping: [instance]
2025-10-08 12:07:12.761307 | controller |
2025-10-08 12:07:12.761317 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 12:07:12.761419 | controller | Wednesday 08 October 2025  12:07:12 +0000 (0:00:00.038)       0:00:14.463 *****
2025-10-08 12:07:12.800145 | controller | skipping: [instance]
2025-10-08 12:07:12.839181 | controller |
2025-10-08 12:07:12.839232 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 12:07:12.839240 | controller | Wednesday 08 October 2025  12:07:12 +0000 (0:00:00.038)       0:00:14.502 *****
2025-10-08 12:07:12.839252 | controller | skipping: [instance]
2025-10-08 12:07:12.839287 | controller |
2025-10-08 12:07:12.839295 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 12:07:12.839303 | controller | Wednesday 08 October 2025  12:07:12 +0000 (0:00:00.039)       0:00:14.541 *****
2025-10-08 12:07:13.400305 | controller | changed: [instance]
2025-10-08 12:07:13.849667 | controller |
2025-10-08 12:07:13.849700 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 12:07:13.849708 | controller | Wednesday 08 October 2025  12:07:13 +0000 (0:00:00.560)       0:00:15.102 *****
2025-10-08 12:07:13.849718 | controller | changed: [instance]
2025-10-08 12:07:13.879210 | controller |
2025-10-08 12:07:13.879248 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 12:07:13.879256 | controller | Wednesday 08 October 2025  12:07:13 +0000 (0:00:00.449)       0:00:15.552 *****
2025-10-08 12:07:13.879267 | controller | skipping: [instance]
2025-10-08 12:07:13.879325 | controller |
2025-10-08 12:07:13.879354 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 12:07:13.879368 | controller | Wednesday 08 October 2025  12:07:13 +0000 (0:00:00.029)       0:00:15.582 *****
2025-10-08 12:07:13.909205 | controller | skipping: [instance]
2025-10-08 12:07:13.938117 | controller |
2025-10-08 12:07:13.938159 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 12:07:13.938169 | controller | Wednesday 08 October 2025  12:07:13 +0000 (0:00:00.029)       0:00:15.611 *****
2025-10-08 12:07:13.938181 | controller | skipping: [instance]
2025-10-08 12:07:13.976749 | controller |
2025-10-08 12:07:13.976783 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 12:07:13.976790 | controller | Wednesday 08 October 2025  12:07:13 +0000 (0:00:00.029)       0:00:15.640 *****
2025-10-08 12:07:13.976800 | controller | ok: [instance]
2025-10-08 12:07:14.004950 | controller |
2025-10-08 12:07:14.004975 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 12:07:14.004982 | controller | Wednesday 08 October 2025  12:07:13 +0000 (0:00:00.038)       0:00:15.679 *****
2025-10-08 12:07:14.004991 | controller | skipping: [instance]
2025-10-08 12:07:14.032193 | controller |
2025-10-08 12:07:14.032228 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 12:07:14.032236 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.028)       0:00:15.707 *****
2025-10-08 12:07:14.032256 | controller | skipping: [instance]
2025-10-08 12:07:14.032262 | controller |
2025-10-08 12:07:14.032270 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 12:07:14.032409 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.027)       0:00:15.735 *****
2025-10-08 12:07:14.059282 | controller | skipping: [instance]
2025-10-08 12:07:14.059406 | controller |
2025-10-08 12:07:14.059527 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 12:07:14.059659 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.027)       0:00:15.762 *****
2025-10-08 12:07:14.086661 | controller | skipping: [instance]
2025-10-08 12:07:14.086820 | controller |
2025-10-08 12:07:14.086965 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 12:07:14.087092 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.027)       0:00:15.789 *****
2025-10-08 12:07:14.113790 | controller | skipping: [instance]
2025-10-08 12:07:14.113949 | controller |
2025-10-08 12:07:14.114112 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 12:07:14.114441 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.027)       0:00:15.817 *****
2025-10-08 12:07:14.144487 | controller | skipping: [instance]
2025-10-08 12:07:14.144654 | controller |
2025-10-08 12:07:14.144854 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 12:07:14.144980 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.030)       0:00:15.847 *****
2025-10-08 12:07:14.171292 | controller | skipping: [instance]
2025-10-08 12:07:14.171468 | controller |
2025-10-08 12:07:14.171603 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 12:07:14.171752 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.027)       0:00:15.874 *****
2025-10-08 12:07:14.357613 | controller | ok: [instance]
2025-10-08 12:07:14.574023 | controller |
2025-10-08 12:07:14.574057 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 12:07:14.574065 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.185)       0:00:16.060 *****
2025-10-08 12:07:14.574091 | controller | changed: [instance]
2025-10-08 12:07:14.805189 | controller |
2025-10-08 12:07:14.805222 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 12:07:14.805232 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.216)       0:00:16.276 *****
2025-10-08 12:07:14.805244 | controller | changed: [instance]
2025-10-08 12:07:14.805275 | controller |
2025-10-08 12:07:14.805448 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 12:07:14.826747 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.231)       0:00:16.508 *****
2025-10-08 12:07:14.826771 | controller | skipping: [instance]
2025-10-08 12:07:14.850143 | controller |
2025-10-08 12:07:14.850164 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 12:07:14.850173 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.021)       0:00:16.529 *****
2025-10-08 12:07:14.850183 | controller | skipping: [instance]
2025-10-08 12:07:14.873124 | controller |
2025-10-08 12:07:14.873177 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 12:07:14.873186 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.023)       0:00:16.553 *****
2025-10-08 12:07:14.873198 | controller | skipping: [instance]
2025-10-08 12:07:14.898547 | controller |
2025-10-08 12:07:14.898584 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:07:14.898592 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.022)       0:00:16.576 *****
2025-10-08 12:07:14.898609 | controller | skipping: [instance]
2025-10-08 12:07:14.922766 | controller |
2025-10-08 12:07:14.922786 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 12:07:14.922794 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.025)       0:00:16.601 *****
2025-10-08 12:07:14.922803 | controller | skipping: [instance]
2025-10-08 12:07:14.946168 | controller |
2025-10-08 12:07:14.946189 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 12:07:14.946203 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.024)       0:00:16.625 *****
2025-10-08 12:07:14.946213 | controller | skipping: [instance]
2025-10-08 12:07:14.946265 | controller |
2025-10-08 12:07:14.946280 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 12:07:14.946290 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.023)       0:00:16.649 *****
2025-10-08 12:07:14.964855 | controller | skipping: [instance]
2025-10-08 12:07:14.965040 | controller |
2025-10-08 12:07:14.965245 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 12:07:14.965417 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.018)       0:00:16.668 *****
2025-10-08 12:07:14.995195 | controller | skipping: [instance]
2025-10-08 12:07:14.995383 | controller |
2025-10-08 12:07:14.995580 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 12:07:14.995745 | controller | Wednesday 08 October 2025  12:07:14 +0000 (0:00:00.030)       0:00:16.698 *****
2025-10-08 12:07:15.014149 | controller | skipping: [instance]
2025-10-08 12:07:15.014297 | controller |
2025-10-08 12:07:15.014445 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 12:07:15.014599 | controller | Wednesday 08 October 2025  12:07:15 +0000 (0:00:00.018)       0:00:16.717 *****
2025-10-08 12:07:15.036887 | controller | skipping: [instance]
2025-10-08 12:07:15.037030 | controller |
2025-10-08 12:07:15.037202 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 12:07:15.037355 | controller | Wednesday 08 October 2025  12:07:15 +0000 (0:00:00.022)       0:00:16.740 *****
2025-10-08 12:07:15.065843 | controller | skipping: [instance]
2025-10-08 12:07:15.065962 | controller |
2025-10-08 12:07:15.066096 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 12:07:15.066239 | controller | Wednesday 08 October 2025  12:07:15 +0000 (0:00:00.028)       0:00:16.769 *****
2025-10-08 12:07:15.095624 | controller | skipping: [instance]
2025-10-08 12:07:15.095738 | controller |
2025-10-08 12:07:15.095858 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 12:07:15.095974 | controller | Wednesday 08 October 2025  12:07:15 +0000 (0:00:00.029)       0:00:16.798 *****
2025-10-08 12:07:15.124794 | controller | skipping: [instance]
2025-10-08 12:07:15.124918 | controller |
2025-10-08 12:07:15.125050 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 12:07:15.125194 | controller | Wednesday 08 October 2025  12:07:15 +0000 (0:00:00.029)       0:00:16.828 *****
2025-10-08 12:07:15.156742 | controller | skipping: [instance]
2025-10-08 12:07:15.156890 | controller |
2025-10-08 12:07:15.157042 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 12:07:15.157182 | controller | Wednesday 08 October 2025  12:07:15 +0000 (0:00:00.031)       0:00:16.860 *****
2025-10-08 12:08:01.868204 | controller | ok: [instance]
2025-10-08 12:08:01.868260 | controller |
2025-10-08 12:08:01.868510 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 12:08:03.089199 | controller | Wednesday 08 October 2025  12:08:01 +0000 (0:00:46.711)       0:01:03.571 *****
2025-10-08 12:08:03.089241 | controller | ok: [instance]
2025-10-08 12:08:03.123241 | controller |
2025-10-08 12:08:03.123284 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 12:08:03.123294 | controller | Wednesday 08 October 2025  12:08:03 +0000 (0:00:01.220)       0:01:04.791 *****
2025-10-08 12:08:03.123308 | controller | skipping: [instance]
2025-10-08 12:08:03.123338 | controller |
2025-10-08 12:08:03.123348 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 12:08:03.123625 | controller | Wednesday 08 October 2025  12:08:03 +0000 (0:00:00.034)       0:01:04.826 *****
2025-10-08 12:08:03.168215 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 12:08:03.168272 | controller |
2025-10-08 12:08:03.168283 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 12:08:03.168360 | controller | Wednesday 08 October 2025  12:08:03 +0000 (0:00:00.044)       0:01:04.871 *****
2025-10-08 12:08:03.188210 | controller | ok: [instance] =>
2025-10-08 12:08:03.188257 | controller |   cifmw_ci_setup_packages:
2025-10-08 12:08:03.188266 | controller |   - bash-completion
2025-10-08 12:08:03.188272 | controller |   - ca-certificates
2025-10-08 12:08:03.188279 | controller |   - git-core
2025-10-08 12:08:03.189038 | controller |   - make
2025-10-08 12:08:08.981185 | controller |   - tar
2025-10-08 12:08:08.981231 | controller |   - tmux
2025-10-08 12:08:08.981239 | controller |   - python3-pip
2025-10-08 12:08:08.981245 | controller |
2025-10-08 12:08:08.981251 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 12:08:08.981258 | controller | Wednesday 08 October 2025  12:08:03 +0000 (0:00:00.019)       0:01:04.890 *****
2025-10-08 12:08:08.981276 | controller | changed: [instance]
2025-10-08 12:08:08.981302 | controller |
2025-10-08 12:08:08.981310 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 12:08:08.981417 | controller | Wednesday 08 October 2025  12:08:08 +0000 (0:00:05.793)       0:01:10.684 *****
2025-10-08 12:08:09.554956 | controller | ok: [instance]
2025-10-08 12:08:09.555004 | controller |
2025-10-08 12:08:09.555268 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 12:08:09.766172 | controller | Wednesday 08 October 2025  12:08:09 +0000 (0:00:00.573)       0:01:11.257 *****
2025-10-08 12:08:09.766210 | controller | changed: [instance]
2025-10-08 12:08:15.692132 | controller |
2025-10-08 12:08:15.692166 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 12:08:15.692174 | controller | Wednesday 08 October 2025  12:08:09 +0000 (0:00:00.210)       0:01:11.468 *****
2025-10-08 12:08:15.692184 | controller | changed: [instance]
2025-10-08 12:08:15.746945 | controller |
2025-10-08 12:08:15.746981 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 12:08:15.746990 | controller | Wednesday 08 October 2025  12:08:15 +0000 (0:00:05.926)       0:01:17.394 *****
2025-10-08 12:08:15.747002 | controller | skipping: [instance]
2025-10-08 12:08:16.006986 | controller |
2025-10-08 12:08:16.007016 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 12:08:16.007023 | controller | Wednesday 08 October 2025  12:08:15 +0000 (0:00:00.054)       0:01:17.449 *****
2025-10-08 12:08:16.007033 | controller | changed: [instance]
2025-10-08 12:08:16.283797 | controller |
2025-10-08 12:08:16.283828 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 12:08:16.283836 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.260)       0:01:17.709 *****
2025-10-08 12:08:16.283846 | controller | changed: [instance]
2025-10-08 12:08:16.298844 | controller |
2025-10-08 12:08:16.298868 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 12:08:16.298876 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.276)       0:01:17.986 *****
2025-10-08 12:08:16.298885 | controller | skipping: [instance]
2025-10-08 12:08:16.313646 | controller |
2025-10-08 12:08:16.313668 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 12:08:16.313675 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.015)       0:01:18.001 *****
2025-10-08 12:08:16.313684 | controller | skipping: [instance]
2025-10-08 12:08:16.329903 | controller |
2025-10-08 12:08:16.329924 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 12:08:16.329931 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.014)       0:01:18.016 *****
2025-10-08 12:08:16.329940 | controller | skipping: [instance]
2025-10-08 12:08:16.347177 | controller |
2025-10-08 12:08:16.347213 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 12:08:16.347222 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.016)       0:01:18.032 *****
2025-10-08 12:08:16.347232 | controller | skipping: [instance]
2025-10-08 12:08:16.362898 | controller |
2025-10-08 12:08:16.362924 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 12:08:16.362933 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.017)       0:01:18.050 *****
2025-10-08 12:08:16.362945 | controller | skipping: [instance]
2025-10-08 12:08:16.384878 | controller |
2025-10-08 12:08:16.384905 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 12:08:16.384919 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.015)       0:01:18.065 *****
2025-10-08 12:08:16.384929 | controller | skipping: [instance]
2025-10-08 12:08:17.390359 | controller |
2025-10-08 12:08:17.390452 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 12:08:17.390463 | controller | Wednesday 08 October 2025  12:08:16 +0000 (0:00:00.021)       0:01:18.087 *****
2025-10-08 12:08:17.390483 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 12:08:17.390524 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 12:08:17.390534 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 12:08:17.390974 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 12:08:17.391033 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 12:08:17.584834 | controller |
2025-10-08 12:08:17.584870 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-10-08 12:08:17.584879 | controller | Wednesday 08 October 2025  12:08:17 +0000 (0:00:01.005)       0:01:19.093 *****
2025-10-08 12:08:17.584892 | controller | ok: [instance]
2025-10-08 12:08:17.759914 | controller |
2025-10-08 12:08:17.759949 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-10-08 12:08:17.759959 | controller | Wednesday 08 October 2025  12:08:17 +0000 (0:00:00.194)       0:01:19.287 *****
2025-10-08 12:08:17.759980 | controller | changed: [instance]
2025-10-08 12:08:17.939968 | controller |
2025-10-08 12:08:17.940001 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-10-08 12:08:17.940010 | controller | Wednesday 08 October 2025  12:08:17 +0000 (0:00:00.175)       0:01:19.462 *****
2025-10-08 12:08:17.940019 | controller | ok: [instance]
2025-10-08 12:08:17.968158 | controller |
2025-10-08 12:08:17.968202 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-10-08 12:08:17.968210 | controller | Wednesday 08 October 2025  12:08:17 +0000 (0:00:00.180)       0:01:19.642 *****
2025-10-08 12:08:17.968221 | controller | skipping: [instance]
2025-10-08 12:08:17.968258 | controller |
2025-10-08 12:08:17.968271 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-10-08 12:08:17.968279 | controller | Wednesday 08 October 2025  12:08:17 +0000 (0:00:00.028)       0:01:19.671 *****
2025-10-08 12:08:17.983751 | controller | skipping: [instance]
2025-10-08 12:08:17.983906 | controller |
2025-10-08 12:08:17.984063 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-10-08 12:08:17.984263 | controller | Wednesday 08 October 2025  12:08:17 +0000 (0:00:00.015)       0:01:19.687 *****
2025-10-08 12:08:18.002700 | controller | ok: [instance]
2025-10-08 12:08:18.002856 | controller |
2025-10-08 12:08:18.003003 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-10-08 12:08:18.003183 | controller | Wednesday 08 October 2025  12:08:18 +0000 (0:00:00.018)       0:01:19.705 *****
2025-10-08 12:08:18.027764 | controller | skipping: [instance]
2025-10-08 12:08:18.027888 | controller |
2025-10-08 12:08:18.028012 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-10-08 12:08:18.028150 | controller | Wednesday 08 October 2025  12:08:18 +0000 (0:00:00.025)       0:01:19.731 *****
2025-10-08 12:08:26.687025 | controller | changed: [instance]
2025-10-08 12:08:26.687167 | controller |
2025-10-08 12:08:26.687207 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-10-08 12:08:26.687234 | controller | Wednesday 08 October 2025  12:08:26 +0000 (0:00:08.658)       0:01:28.389 *****
2025-10-08 12:08:26.897180 | controller | changed: [instance]
2025-10-08 12:08:26.897249 | controller |
2025-10-08 12:08:26.897328 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-10-08 12:08:26.897460 | controller | Wednesday 08 October 2025  12:08:26 +0000 (0:00:00.210)       0:01:28.599 *****
2025-10-08 12:08:27.275023 | controller | changed: [instance]
2025-10-08 12:08:27.849920 | controller |
2025-10-08 12:08:27.849954 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-10-08 12:08:27.849963 | controller | Wednesday 08 October 2025  12:08:27 +0000 (0:00:00.377)       0:01:28.977 *****
2025-10-08 12:08:27.849985 | controller | changed: [instance]
2025-10-08 12:08:28.037128 | controller |
2025-10-08 12:08:28.037158 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-10-08 12:08:28.037167 | controller | Wednesday 08 October 2025  12:08:27 +0000 (0:00:00.574)       0:01:29.552 *****
2025-10-08 12:08:28.037176 | controller | ok: [instance]
2025-10-08 12:08:28.704033 | controller |
2025-10-08 12:08:28.704064 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-10-08 12:08:28.704087 | controller | Wednesday 08 October 2025  12:08:28 +0000 (0:00:00.187)       0:01:29.739 *****
2025-10-08 12:08:28.704102 | controller | ok: [instance]
2025-10-08 12:08:29.605892 | controller |
2025-10-08 12:08:29.605923 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-10-08 12:08:29.605932 | controller | Wednesday 08 October 2025  12:08:28 +0000 (0:00:00.667)       0:01:30.406 *****
2025-10-08 12:08:29.605942 | controller | changed: [instance]
2025-10-08 12:08:29.683207 | controller |
2025-10-08 12:08:29.683237 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-10-08 12:08:29.683246 | controller | Wednesday 08 October 2025  12:08:29 +0000 (0:00:00.901)       0:01:31.308 *****
2025-10-08 12:08:29.683256 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-10-08 12:08:29.683284 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-10-08 12:08:29.683290 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-10-08 12:08:29.683296 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-10-08 12:08:29.683301 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-10-08 12:08:29.683306 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-10-08 12:08:29.683311 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-10-08 12:08:29.683316 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-10-08 12:08:29.683323 | controller |
2025-10-08 12:08:29.683346 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-10-08 12:08:29.683456 | controller | Wednesday 08 October 2025  12:08:29 +0000 (0:00:00.076)       0:01:31.385 *****
2025-10-08 12:08:30.016188 | controller | ok: [instance]
2025-10-08 12:08:30.016220 | controller |
2025-10-08 12:08:30.016230 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-10-08 12:08:30.016240 | controller | Wednesday 08 October 2025  12:08:30 +0000 (0:00:00.333)       0:01:31.718 *****
2025-10-08 12:08:30.334884 | controller | ok: [instance]
2025-10-08 12:08:30.647972 | controller |
2025-10-08 12:08:30.648003 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-10-08 12:08:30.648019 | controller | Wednesday 08 October 2025  12:08:30 +0000 (0:00:00.318)       0:01:32.037 *****
2025-10-08 12:08:30.648029 | controller | ok: [instance]
2025-10-08 12:08:30.981208 | controller |
2025-10-08 12:08:30.981244 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-10-08 12:08:30.981254 | controller | Wednesday 08 October 2025  12:08:30 +0000 (0:00:00.313)       0:01:32.350 *****
2025-10-08 12:08:30.981267 | controller | ok: [instance]
2025-10-08 12:08:31.300525 | controller |
2025-10-08 12:08:31.300557 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-10-08 12:08:31.300565 | controller | Wednesday 08 October 2025  12:08:30 +0000 (0:00:00.333)       0:01:32.684 *****
2025-10-08 12:08:31.300575 | controller | ok: [instance]
2025-10-08 12:08:31.629015 | controller |
2025-10-08 12:08:31.629048 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-10-08 12:08:31.629056 | controller | Wednesday 08 October 2025  12:08:31 +0000 (0:00:00.319)       0:01:33.003 *****
2025-10-08 12:08:31.629066 | controller | ok: [instance]
2025-10-08 12:08:31.954576 | controller |
2025-10-08 12:08:31.954607 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-10-08 12:08:31.954615 | controller | Wednesday 08 October 2025  12:08:31 +0000 (0:00:00.328)       0:01:33.331 *****
2025-10-08 12:08:31.954625 | controller | ok: [instance]
2025-10-08 12:08:32.282131 | controller |
2025-10-08 12:08:32.282167 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-10-08 12:08:32.282176 | controller | Wednesday 08 October 2025  12:08:31 +0000 (0:00:00.325)       0:01:33.657 *****
2025-10-08 12:08:32.282188 | controller | ok: [instance]
2025-10-08 12:08:32.606886 | controller |
2025-10-08 12:08:32.606921 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-10-08 12:08:32.606932 | controller | Wednesday 08 October 2025  12:08:32 +0000 (0:00:00.327)       0:01:33.984 *****
2025-10-08 12:08:32.606943 | controller | ok: [instance]
2025-10-08 12:08:32.928224 | controller |
2025-10-08 12:08:32.928260 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-10-08 12:08:32.928269 | controller | Wednesday 08 October 2025  12:08:32 +0000 (0:00:00.324)       0:01:34.309 *****
2025-10-08 12:08:32.928280 | controller | ok: [instance]
2025-10-08 12:08:33.248757 | controller |
2025-10-08 12:08:33.248792 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-10-08 12:08:33.248800 | controller | Wednesday 08 October 2025  12:08:32 +0000 (0:00:00.321)       0:01:34.630 *****
2025-10-08 12:08:33.248810 | controller | ok: [instance]
2025-10-08 12:08:33.572910 | controller |
2025-10-08 12:08:33.572943 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-10-08 12:08:33.572951 | controller | Wednesday 08 October 2025  12:08:33 +0000 (0:00:00.320)       0:01:34.951 *****
2025-10-08 12:08:33.572961 | controller | ok: [instance]
2025-10-08 12:08:33.902166 | controller |
2025-10-08 12:08:33.902200 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-10-08 12:08:33.902208 | controller | Wednesday 08 October 2025  12:08:33 +0000 (0:00:00.324)       0:01:35.275 *****
2025-10-08 12:08:33.902219 | controller | ok: [instance]
2025-10-08 12:08:33.902244 | controller |
2025-10-08 12:08:33.902253 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-10-08 12:08:33.902379 | controller | Wednesday 08 October 2025  12:08:33 +0000 (0:00:00.329)       0:01:35.605 *****
2025-10-08 12:08:34.221779 | controller | ok: [instance]
2025-10-08 12:08:35.545172 | controller |
2025-10-08 12:08:35.545203 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-10-08 12:08:35.545212 | controller | Wednesday 08 October 2025  12:08:34 +0000 (0:00:00.319)       0:01:35.924 *****
2025-10-08 12:08:35.545222 | controller | ok: [instance]
2025-10-08 12:08:35.545392 | controller |
2025-10-08 12:08:35.545411 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-10-08 12:08:35.874044 | controller | Wednesday 08 October 2025  12:08:35 +0000 (0:00:01.323)       0:01:37.248 *****
2025-10-08 12:08:35.874093 | controller | ok: [instance]
2025-10-08 12:08:36.201768 | controller |
2025-10-08 12:08:36.201802 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-10-08 12:08:36.201810 | controller | Wednesday 08 October 2025  12:08:35 +0000 (0:00:00.328)       0:01:37.576 *****
2025-10-08 12:08:36.201820 | controller | ok: [instance]
2025-10-08 12:08:36.531175 | controller |
2025-10-08 12:08:36.531207 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-10-08 12:08:36.531215 | controller | Wednesday 08 October 2025  12:08:36 +0000 (0:00:00.327)       0:01:37.904 *****
2025-10-08 12:08:36.531232 | controller | ok: [instance]
2025-10-08 12:08:36.531255 | controller |
2025-10-08 12:08:36.531263 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-10-08 12:08:36.531327 | controller | Wednesday 08 October 2025  12:08:36 +0000 (0:00:00.329)       0:01:38.234 *****
2025-10-08 12:08:37.868219 | controller | ok: [instance]
2025-10-08 12:08:38.189203 | controller |
2025-10-08 12:08:38.189238 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-10-08 12:08:38.189246 | controller | Wednesday 08 October 2025  12:08:37 +0000 (0:00:01.336)       0:01:39.570 *****
2025-10-08 12:08:38.189256 | controller | ok: [instance]
2025-10-08 12:08:38.189291 | controller |
2025-10-08 12:08:38.189299 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-10-08 12:08:38.189306 | controller | Wednesday 08 October 2025  12:08:38 +0000 (0:00:00.320)       0:01:39.891 *****
2025-10-08 12:08:38.523841 | controller | ok: [instance]
2025-10-08 12:08:39.850152 | controller |
2025-10-08 12:08:39.850182 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-10-08 12:08:39.850190 | controller | Wednesday 08 October 2025  12:08:38 +0000 (0:00:00.334)       0:01:40.226 *****
2025-10-08 12:08:39.850200 | controller | ok: [instance]
2025-10-08 12:08:39.850217 | controller |
2025-10-08 12:08:39.850223 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-10-08 12:08:39.850231 | controller | Wednesday 08 October 2025  12:08:39 +0000 (0:00:01.326)       0:01:41.552 *****
2025-10-08 12:08:40.172602 | controller | ok: [instance]
2025-10-08 12:08:41.497193 | controller |
2025-10-08 12:08:41.497225 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-10-08 12:08:41.497233 | controller | Wednesday 08 October 2025  12:08:40 +0000 (0:00:00.322)       0:01:41.875 *****
2025-10-08 12:08:41.497244 | controller | ok: [instance]
2025-10-08 12:08:41.516203 | controller |
2025-10-08 12:08:41.516231 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-10-08 12:08:41.516239 | controller | Wednesday 08 October 2025  12:08:41 +0000 (0:00:01.324)       0:01:43.199 *****
2025-10-08 12:08:41.516250 | controller | skipping: [instance]
2025-10-08 12:08:41.797733 | controller |
2025-10-08 12:08:41.797799 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-10-08 12:08:41.797810 | controller | Wednesday 08 October 2025  12:08:41 +0000 (0:00:00.019)       0:01:43.219 *****
2025-10-08 12:08:41.797822 | controller | ok: [instance]
2025-10-08 12:08:42.169379 | controller |
2025-10-08 12:08:42.169452 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-10-08 12:08:42.169461 | controller | Wednesday 08 October 2025  12:08:41 +0000 (0:00:00.280)       0:01:43.500 *****
2025-10-08 12:08:42.169476 | controller | ok: [instance]
2025-10-08 12:08:42.476194 | controller |
2025-10-08 12:08:42.476232 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-10-08 12:08:42.476240 | controller | Wednesday 08 October 2025  12:08:42 +0000 (0:00:00.370)       0:01:43.870 *****
2025-10-08 12:08:42.476251 | controller | changed: [instance]
2025-10-08 12:08:42.476276 | controller |
2025-10-08 12:08:42.476597 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-10-08 12:08:42.659238 | controller | Wednesday 08 October 2025  12:08:42 +0000 (0:00:00.308)       0:01:44.179 *****
2025-10-08 12:08:42.659275 | controller | [WARNING]: Reset is not implemented for this connection
2025-10-08 12:08:42.680167 | controller | changed: [instance]
2025-10-08 12:08:42.682624 | controller |
2025-10-08 12:08:42.682643 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-10-08 12:08:42.682654 | controller | Wednesday 08 October 2025  12:08:42 +0000 (0:00:00.177)       0:01:44.357 *****
2025-10-08 12:08:42.735700 | controller |
2025-10-08 12:08:42.735730 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:08:42.735739 | controller | instance                   : ok=75   changed=23   unreachable=0    failed=0    skipped=41   rescued=0    ignored=0
2025-10-08 12:08:42.735744 | controller |
2025-10-08 12:08:42.735750 | controller | Wednesday 08 October 2025  12:08:42 +0000 (0:00:00.025)       0:01:44.383 *****
2025-10-08 12:08:42.735755 | controller | ===============================================================================
2025-10-08 12:08:42.735773 | controller | test_deps : Install selinux python libs -------------------------------- 46.71s
2025-10-08 12:08:42.735779 | controller | libvirt_manager : Install packages required for using KVM --------------- 8.66s
2025-10-08 12:08:42.735784 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.93s
2025-10-08 12:08:42.735795 | controller | ci_setup : Install openshift client ------------------------------------- 5.93s
2025-10-08 12:08:42.735805 | controller | ci_setup : Install needed packages -------------------------------------- 5.79s
2025-10-08 12:08:42.735811 | controller | libvirt_manager : Ensure read-write socket is enabled for storage. ------ 1.34s
2025-10-08 12:08:42.735816 | controller | libvirt_manager : Ensure read-write socket is enabled for proxy. -------- 1.33s
2025-10-08 12:08:42.735821 | controller | libvirt_manager : Ensure admin socket is enabled for proxy. ------------- 1.32s
2025-10-08 12:08:42.735826 | controller | libvirt_manager : Ensure admin socket is enabled for nwfilter. ---------- 1.32s
2025-10-08 12:08:42.735831 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.22s
2025-10-08 12:08:42.735836 | controller | test_deps : Install python yaml libs ------------------------------------ 1.22s
2025-10-08 12:08:42.735841 | controller | ci_setup : Manage directories ------------------------------------------- 1.01s
2025-10-08 12:08:42.735847 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.90s
2025-10-08 12:08:42.735852 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.86s
2025-10-08 12:08:42.735857 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2025-10-08 12:08:42.735862 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.83s
2025-10-08 12:08:42.735867 | controller | repo_setup : Install repo-setup package --------------------------------- 0.73s
2025-10-08 12:08:42.735872 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-10-08 12:08:42.735877 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.67s
2025-10-08 12:08:42.735882 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.57s
2025-10-08 12:08:42.735892 | controller | INFO     Running default > converge
2025-10-08 12:08:43.150791 | controller |
2025-10-08 12:08:43.151016 | controller | PLAY [Converge] ****************************************************************
2025-10-08 12:08:43.151176 | controller |
2025-10-08 12:08:43.151318 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:08:43.151453 | controller | Wednesday 08 October 2025  12:08:43 +0000 (0:00:00.013)       0:00:00.013 *****
2025-10-08 12:08:43.958967 | controller | ok: [instance]
2025-10-08 12:08:43.999270 | controller |
2025-10-08 12:08:43.999361 | controller | TASK [Including the openshift provisioner node role.] **************************
2025-10-08 12:08:43.999374 | controller | Wednesday 08 October 2025  12:08:43 +0000 (0:00:00.807)       0:00:00.820 *****
2025-10-08 12:08:43.999389 | controller |
2025-10-08 12:08:44.305286 | controller | TASK [openshift_provisioner_node : Create the artifacts directory] *************
2025-10-08 12:08:44.305317 | controller | Wednesday 08 October 2025  12:08:43 +0000 (0:00:00.041)       0:00:00.861 *****
2025-10-08 12:08:44.305329 | controller | ok: [instance -> localhost]
2025-10-08 12:08:44.696808 | controller |
2025-10-08 12:08:44.696840 | controller | TASK [openshift_provisioner_node : Adding user kni] ****************************
2025-10-08 12:08:44.696848 | controller | Wednesday 08 October 2025  12:08:44 +0000 (0:00:00.305)       0:00:01.167 *****
2025-10-08 12:08:44.696857 | controller | changed: [instance]
2025-10-08 12:08:45.062233 | controller |
2025-10-08 12:08:45.062273 | controller | TASK [openshift_provisioner_node : Generate SSH keys] **************************
2025-10-08 12:08:45.062282 | controller | Wednesday 08 October 2025  12:08:44 +0000 (0:00:00.391)       0:00:01.558 *****
2025-10-08 12:08:45.062296 | controller | changed: [instance -> localhost]
2025-10-08 12:08:45.434157 | controller |
2025-10-08 12:08:45.434190 | controller | TASK [openshift_provisioner_node : Enable passwordless access] *****************
2025-10-08 12:08:45.434198 | controller | Wednesday 08 October 2025  12:08:45 +0000 (0:00:00.365)       0:00:01.924 *****
2025-10-08 12:08:45.434209 | controller | changed: [instance]
2025-10-08 12:08:45.434267 | controller |
2025-10-08 12:08:45.434297 | controller | TASK [openshift_provisioner_node : Install required packages need to execute openshift installer] ***
2025-10-08 12:08:45.434309 | controller | Wednesday 08 October 2025  12:08:45 +0000 (0:00:00.372)       0:00:02.296 *****
2025-10-08 12:09:25.321552 | controller | changed: [instance]
2025-10-08 12:09:25.321647 | controller |
2025-10-08 12:09:25.321680 | controller | TASK [openshift_provisioner_node : Create the external network connection] *****
2025-10-08 12:09:25.321690 | controller | Wednesday 08 October 2025  12:09:25 +0000 (0:00:39.887)       0:00:42.184 *****
2025-10-08 12:09:25.764393 | controller | changed: [instance]
2025-10-08 12:09:25.764451 | controller |
2025-10-08 12:09:25.764462 | controller | TASK [openshift_provisioner_node : Verify external network interface is defined] ***
2025-10-08 12:09:25.764559 | controller | Wednesday 08 October 2025  12:09:25 +0000 (0:00:00.442)       0:00:42.626 *****
2025-10-08 12:09:25.796768 | controller | skipping: [instance]
2025-10-08 12:09:25.796985 | controller |
2025-10-08 12:09:25.797020 | controller | TASK [openshift_provisioner_node : Add iface to the external network connection] ***
2025-10-08 12:09:25.824216 | controller | Wednesday 08 October 2025  12:09:25 +0000 (0:00:00.032)       0:00:42.659 *****
2025-10-08 12:09:25.824249 | controller | skipping: [instance]
2025-10-08 12:09:25.824277 | controller |
2025-10-08 12:09:25.824426 | controller | TASK [openshift_provisioner_node : Create the provisioning network connection] ***
2025-10-08 12:09:25.824452 | controller | Wednesday 08 October 2025  12:09:25 +0000 (0:00:00.027)       0:00:42.686 *****
2025-10-08 12:09:25.856581 | controller | skipping: [instance]
2025-10-08 12:09:25.887922 | controller |
2025-10-08 12:09:25.887950 | controller | TASK [openshift_provisioner_node : Add iface to the provisioning network connection] ***
2025-10-08 12:09:25.887959 | controller | Wednesday 08 October 2025  12:09:25 +0000 (0:00:00.032)       0:00:42.719 *****
2025-10-08 12:09:25.887969 | controller | skipping: [instance]
2025-10-08 12:09:25.927143 | controller |
2025-10-08 12:09:25.927224 | controller | TASK [openshift_provisioner_node : Check required virtualization networks] *****
2025-10-08 12:09:25.927235 | controller | Wednesday 08 October 2025  12:09:25 +0000 (0:00:00.031)       0:00:42.750 *****
2025-10-08 12:09:25.927254 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/add_virtual_network.yml for instance => (item=baremetal)
2025-10-08 12:09:25.927310 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/add_virtual_network.yml for instance => (item=provisioning)
2025-10-08 12:09:25.927317 | controller |
2025-10-08 12:09:25.927325 | controller | TASK [openshift_provisioner_node : Define virtual network baremetal] ***********
2025-10-08 12:09:25.927471 | controller | Wednesday 08 October 2025  12:09:25 +0000 (0:00:00.039)       0:00:42.789 *****
2025-10-08 12:09:26.846182 | controller | changed: [instance]
2025-10-08 12:09:26.846227 | controller |
2025-10-08 12:09:26.846235 | controller | TASK [openshift_provisioner_node : Start the network - baremetal] **************
2025-10-08 12:09:26.846244 | controller | Wednesday 08 October 2025  12:09:26 +0000 (0:00:00.918)       0:00:43.708 *****
2025-10-08 12:09:27.097837 | controller | changed: [instance]
2025-10-08 12:09:27.332112 | controller |
2025-10-08 12:09:27.332149 | controller | TASK [openshift_provisioner_node : Enable network autostart for baremetal] *****
2025-10-08 12:09:27.332157 | controller | Wednesday 08 October 2025  12:09:27 +0000 (0:00:00.251)       0:00:43.959 *****
2025-10-08 12:09:27.332168 | controller | changed: [instance]
2025-10-08 12:09:27.570179 | controller |
2025-10-08 12:09:27.570216 | controller | TASK [openshift_provisioner_node : Define virtual network provisioning] ********
2025-10-08 12:09:27.570226 | controller | Wednesday 08 October 2025  12:09:27 +0000 (0:00:00.232)       0:00:44.192 *****
2025-10-08 12:09:27.570238 | controller | changed: [instance]
2025-10-08 12:09:27.807744 | controller |
2025-10-08 12:09:27.807774 | controller | TASK [openshift_provisioner_node : Start the network - provisioning] ***********
2025-10-08 12:09:27.807782 | controller | Wednesday 08 October 2025  12:09:27 +0000 (0:00:00.240)       0:00:44.432 *****
2025-10-08 12:09:27.807793 | controller | changed: [instance]
2025-10-08 12:09:28.032487 | controller |
2025-10-08 12:09:28.032519 | controller | TASK [openshift_provisioner_node : Enable network autostart for provisioning] ***
2025-10-08 12:09:28.032527 | controller | Wednesday 08 October 2025  12:09:27 +0000 (0:00:00.237)       0:00:44.670 *****
2025-10-08 12:09:28.032538 | controller | changed: [instance]
2025-10-08 12:09:28.079750 | controller |
2025-10-08 12:09:28.079784 | controller | TASK [openshift_provisioner_node : Cache the MAC addresses] ********************
2025-10-08 12:09:28.079793 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.224)       0:00:44.894 *****
2025-10-08 12:09:28.079804 | controller | ok: [instance]
2025-10-08 12:09:28.349944 | controller |
2025-10-08 12:09:28.349978 | controller | TASK [openshift_provisioner_node : Read host SSH fingerprint] ******************
2025-10-08 12:09:28.349986 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.047)       0:00:44.942 *****
2025-10-08 12:09:28.349996 | controller | ok: [instance]
2025-10-08 12:09:28.381232 | controller |
2025-10-08 12:09:28.381268 | controller | TASK [openshift_provisioner_node : Set the role output parameters] *************
2025-10-08 12:09:28.381279 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.270)       0:00:45.212 *****
2025-10-08 12:09:28.381292 | controller | ok: [instance]
2025-10-08 12:09:28.423187 | controller |
2025-10-08 12:09:28.423221 | controller | TASK [openshift_provisioner_node : Cache provisioning bridge name] *************
2025-10-08 12:09:28.423237 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.031)       0:00:45.243 *****
2025-10-08 12:09:28.423248 | controller | skipping: [instance]
2025-10-08 12:09:28.423278 | controller |
2025-10-08 12:09:28.423286 | controller | TASK [Verify user is created] **************************************************
2025-10-08 12:09:28.721481 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.041)       0:00:45.285 *****
2025-10-08 12:09:28.721526 | controller | ok: [instance]
2025-10-08 12:09:28.820166 | controller |
2025-10-08 12:09:28.820200 | controller | TASK [Verify external network bridge exists] ***********************************
2025-10-08 12:09:28.820209 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.297)       0:00:45.583 *****
2025-10-08 12:09:28.820219 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-08 12:09:28.820243 | controller | changed: [instance]
2025-10-08 12:09:28.820251 | controller |
2025-10-08 12:09:28.820485 | controller | TASK [Perform cleanup] *********************************************************
2025-10-08 12:09:28.856801 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.099)       0:00:45.682 *****
2025-10-08 12:09:28.856839 | controller |
2025-10-08 12:09:28.888244 | controller | TASK [openshift_provisioner_node : Cleaning up the virtual networks] ***********
2025-10-08 12:09:28.888287 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.036)       0:00:45.719 *****
2025-10-08 12:09:28.888303 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/cleanup_virtual_network.yml for instance => (item=baremetal)
2025-10-08 12:09:28.888310 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/cleanup_virtual_network.yml for instance => (item=provisioning)
2025-10-08 12:09:28.888315 | controller |
2025-10-08 12:09:28.888321 | controller | TASK [openshift_provisioner_node : Stopping virtual network baremetal] *********
2025-10-08 12:09:28.888329 | controller | Wednesday 08 October 2025  12:09:28 +0000 (0:00:00.031)       0:00:45.750 *****
2025-10-08 12:09:29.113711 | controller | ok: [instance]
2025-10-08 12:09:29.346216 | controller |
2025-10-08 12:09:29.346250 | controller | TASK [openshift_provisioner_node : Remove virtual network baremetal] ***********
2025-10-08 12:09:29.346258 | controller | Wednesday 08 October 2025  12:09:29 +0000 (0:00:00.225)       0:00:45.975 *****
2025-10-08 12:09:29.346284 | controller | ok: [instance]
2025-10-08 12:09:29.346310 | controller |
2025-10-08 12:09:29.346319 | controller | TASK [openshift_provisioner_node : Stopping virtual network provisioning] ******
2025-10-08 12:09:29.346339 | controller | Wednesday 08 October 2025  12:09:29 +0000 (0:00:00.232)       0:00:46.208 *****
2025-10-08 12:09:29.571589 | controller | ok: [instance]
2025-10-08 12:09:29.794609 | controller |
2025-10-08 12:09:29.794642 | controller | TASK [openshift_provisioner_node : Remove virtual network provisioning] ********
2025-10-08 12:09:29.794651 | controller | Wednesday 08 October 2025  12:09:29 +0000 (0:00:00.224)       0:00:46.433 *****
2025-10-08 12:09:29.794661 | controller | ok: [instance]
2025-10-08 12:09:30.018214 | controller |
2025-10-08 12:09:30.018248 | controller | TASK [openshift_provisioner_node : Remove provisioning network] ****************
2025-10-08 12:09:30.018264 | controller | Wednesday 08 October 2025  12:09:29 +0000 (0:00:00.223)       0:00:46.657 *****
2025-10-08 12:09:30.018276 | controller | ok: [instance]
2025-10-08 12:09:30.018302 | controller |
2025-10-08 12:09:30.018533 | controller | TASK [openshift_provisioner_node : Remove the user] ****************************
2025-10-08 12:09:30.361480 | controller | Wednesday 08 October 2025  12:09:30 +0000 (0:00:00.223)       0:00:46.880 *****
2025-10-08 12:09:30.361516 | controller | changed: [instance]
2025-10-08 12:09:30.422207 | controller |
2025-10-08 12:09:30.422235 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:09:30.422244 | controller | instance                   : ok=28   changed=13   unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-10-08 12:09:30.422250 | controller |
2025-10-08 12:09:30.422255 | controller | Wednesday 08 October 2025  12:09:30 +0000 (0:00:00.340)       0:00:47.221 *****
2025-10-08 12:09:30.422261 | controller | ===============================================================================
2025-10-08 12:09:30.422266 | controller | openshift_provisioner_node : Install required packages need to execute openshift installer -- 39.89s
2025-10-08 12:09:30.422272 | controller | openshift_provisioner_node : Define virtual network baremetal ----------- 0.92s
2025-10-08 12:09:30.422277 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-10-08 12:09:30.422282 | controller | openshift_provisioner_node : Create the external network connection ----- 0.44s
2025-10-08 12:09:30.422287 | controller | openshift_provisioner_node : Adding user kni ---------------------------- 0.39s
2025-10-08 12:09:30.422293 | controller | openshift_provisioner_node : Enable passwordless access ----------------- 0.37s
2025-10-08 12:09:30.422298 | controller | openshift_provisioner_node : Generate SSH keys -------------------------- 0.37s
2025-10-08 12:09:30.422303 | controller | openshift_provisioner_node : Remove the user ---------------------------- 0.34s
2025-10-08 12:09:30.422312 | controller | openshift_provisioner_node : Create the artifacts directory ------------- 0.31s
2025-10-08 12:09:30.422318 | controller | Verify user is created -------------------------------------------------- 0.30s
2025-10-08 12:09:30.422323 | controller | openshift_provisioner_node : Read host SSH fingerprint ------------------ 0.27s
2025-10-08 12:09:30.422329 | controller | openshift_provisioner_node : Start the network - baremetal -------------- 0.25s
2025-10-08 12:09:30.422334 | controller | openshift_provisioner_node : Define virtual network provisioning -------- 0.24s
2025-10-08 12:09:30.422339 | controller | openshift_provisioner_node : Start the network - provisioning ----------- 0.24s
2025-10-08 12:09:30.422344 | controller | openshift_provisioner_node : Remove virtual network baremetal ----------- 0.23s
2025-10-08 12:09:30.422349 | controller | openshift_provisioner_node : Enable network autostart for baremetal ----- 0.23s
2025-10-08 12:09:30.422354 | controller | openshift_provisioner_node : Stopping virtual network baremetal --------- 0.23s
2025-10-08 12:09:30.422359 | controller | openshift_provisioner_node : Stopping virtual network provisioning ------ 0.22s
2025-10-08 12:09:30.422365 | controller | openshift_provisioner_node : Enable network autostart for provisioning --- 0.22s
2025-10-08 12:09:30.422370 | controller | openshift_provisioner_node : Remove provisioning network ---------------- 0.22s
2025-10-08 12:09:30.422391 | controller | INFO     Running default > cleanup
2025-10-08 12:09:30.422839 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 12:09:30.423562 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 12:09:30.910855 | [controller] Waiting on logger
2025-10-08 12:09:36.050342 | [controller] Waiting on logger
2025-10-08 12:09:46.482431 | [controller] Waiting on logger
2025-10-08 12:09:56.914408 | [controller] Waiting on logger
2025-10-08 12:10:00.498645 | [Zuul] Log Stream did not terminate
2025-10-08 12:10:00.498862 | controller | changed
2025-10-08 12:10:00.521061 | 
2025-10-08 12:10:00.521138 | PLAY RECAP
2025-10-08 12:10:00.521201 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:10:00.521227 | 
2025-10-08 12:10:00.602483 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:10:00.603306 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:10:01.147744 | 
2025-10-08 12:10:01.147864 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 12:10:01.171531 | 
2025-10-08 12:10:01.171651 | TASK [Filter out host if needed]
2025-10-08 12:10:01.180565 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 12:10:01.185406 | 
2025-10-08 12:10:01.185479 | TASK [Ensure file is present]
2025-10-08 12:10:01.579312 | controller | ok
2025-10-08 12:10:01.586721 | 
2025-10-08 12:10:01.586814 | TASK [Manage molecule report file]
2025-10-08 12:10:01.608468 | [controller] Waiting on logger
2025-10-08 12:10:10.966905 | [controller] Waiting on logger
2025-10-08 12:10:21.362631 | [controller] Waiting on logger
2025-10-08 12:10:31.795240 | [controller] Waiting on logger
2025-10-08 12:10:32.001946 | [Zuul] Log Stream did not terminate
2025-10-08 12:10:32.002098 | controller | changed
2025-10-08 12:10:32.008318 | 
2025-10-08 12:10:32.008391 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 12:10:32.270092 | controller | ok
2025-10-08 12:10:32.275461 | 
2025-10-08 12:10:32.275550 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 12:10:32.689132 | controller | changed
2025-10-08 12:10:32.695603 | 
2025-10-08 12:10:32.695692 | TASK [Copy ci-framework interesting files]
2025-10-08 12:10:32.731945 | [controller] Waiting on logger
2025-10-08 12:10:37.836728 | controller | changed
2025-10-08 12:10:37.842484 | 
2025-10-08 12:10:37.842571 | TASK [Get SELinux listing]
2025-10-08 12:10:40.946355 | [controller] Waiting on logger
2025-10-08 12:10:44.274281 | [controller] Waiting on logger
2025-10-08 12:11:02.706467 | [controller] Waiting on logger
2025-10-08 12:11:04.946354 | [controller] Waiting on logger
2025-10-08 12:11:08.160622 | [Zuul] Log Stream did not terminate
2025-10-08 12:11:08.160809 | controller | changed
2025-10-08 12:11:08.167747 | 
2025-10-08 12:11:08.167830 | TASK [Generate log index]
2025-10-08 12:11:09.462532 | controller | changed
2025-10-08 12:11:09.468579 | 
2025-10-08 12:11:09.468670 | TASK [Get some env related data]
2025-10-08 12:11:12.562360 | [controller] Waiting on logger
2025-10-08 12:11:26.706370 | [controller] Waiting on logger
2025-10-08 12:11:30.723409 | controller | changed
2025-10-08 12:11:30.729072 | 
2025-10-08 12:11:30.729175 | TASK [Generate list of logs to collect in home directory]
2025-10-08 12:11:31.123132 | controller | ok: All paths examined
2025-10-08 12:11:31.128763 | 
2025-10-08 12:11:31.128847 | LOOP [Copy logs from home directory]
2025-10-08 12:11:31.734659 | controller | changed:
2025-10-08 12:11:31.734858 | controller | {
2025-10-08 12:11:31.734889 | controller |   "atime": 1743544925.4788878,
2025-10-08 12:11:31.734942 | controller |   "ctime": 1743545329.1409318,
2025-10-08 12:11:31.734969 | controller |   "dev": 64513,
2025-10-08 12:11:31.734990 | controller |   "gid": 1000,
2025-10-08 12:11:31.735008 | controller |   "gr_name": "zuul",
2025-10-08 12:11:31.735026 | controller |   "inode": 4518807,
2025-10-08 12:11:31.735044 | controller |   "isblk": false,
2025-10-08 12:11:31.735061 | controller |   "ischr": false,
2025-10-08 12:11:31.735083 | controller |   "isdir": false,
2025-10-08 12:11:31.735101 | controller |   "isfifo": false,
2025-10-08 12:11:31.735118 | controller |   "isgid": false,
2025-10-08 12:11:31.735135 | controller |   "islnk": false,
2025-10-08 12:11:31.735163 | controller |   "isreg": true,
2025-10-08 12:11:31.735180 | controller |   "issock": false,
2025-10-08 12:11:31.735196 | controller |   "isuid": false,
2025-10-08 12:11:31.735213 | controller |   "mode": "0644",
2025-10-08 12:11:31.735231 | controller |   "mtime": 1743545329.1409318,
2025-10-08 12:11:31.735248 | controller |   "nlink": 1,
2025-10-08 12:11:31.735264 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 12:11:31.735280 | controller |   "pw_name": "zuul",
2025-10-08 12:11:31.735295 | controller |   "rgrp": true,
2025-10-08 12:11:31.735310 | controller |   "roth": true,
2025-10-08 12:11:31.735325 | controller |   "rusr": true,
2025-10-08 12:11:31.735340 | controller |   "size": 4108,
2025-10-08 12:11:31.735355 | controller |   "uid": 1000,
2025-10-08 12:11:31.735370 | controller |   "wgrp": false,
2025-10-08 12:11:31.735385 | controller |   "woth": false,
2025-10-08 12:11:31.735399 | controller |   "wusr": true,
2025-10-08 12:11:31.735414 | controller |   "xgrp": false,
2025-10-08 12:11:31.735428 | controller |   "xoth": false,
2025-10-08 12:11:31.735442 | controller |   "xusr": false
2025-10-08 12:11:31.735456 | controller | }
2025-10-08 12:11:32.316470 | controller | changed:
2025-10-08 12:11:32.316568 | controller | {
2025-10-08 12:11:32.316597 | controller |   "atime": 1743545331.0429637,
2025-10-08 12:11:32.316618 | controller |   "ctime": 1743545842.0111232,
2025-10-08 12:11:32.316638 | controller |   "dev": 64513,
2025-10-08 12:11:32.316657 | controller |   "gid": 1000,
2025-10-08 12:11:32.316675 | controller |   "gr_name": "zuul",
2025-10-08 12:11:32.316693 | controller |   "inode": 4194437,
2025-10-08 12:11:32.316710 | controller |   "isblk": false,
2025-10-08 12:11:32.316727 | controller |   "ischr": false,
2025-10-08 12:11:32.316743 | controller |   "isdir": false,
2025-10-08 12:11:32.316760 | controller |   "isfifo": false,
2025-10-08 12:11:32.316776 | controller |   "isgid": false,
2025-10-08 12:11:32.316793 | controller |   "islnk": false,
2025-10-08 12:11:32.316810 | controller |   "isreg": true,
2025-10-08 12:11:32.316827 | controller |   "issock": false,
2025-10-08 12:11:32.316843 | controller |   "isuid": false,
2025-10-08 12:11:32.316858 | controller |   "mode": "0644",
2025-10-08 12:11:32.316874 | controller |   "mtime": 1743545842.0111232,
2025-10-08 12:11:32.316890 | controller |   "nlink": 1,
2025-10-08 12:11:32.316938 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 12:11:32.316965 | controller |   "pw_name": "zuul",
2025-10-08 12:11:32.316985 | controller |   "rgrp": true,
2025-10-08 12:11:32.317002 | controller |   "roth": true,
2025-10-08 12:11:32.317018 | controller |   "rusr": true,
2025-10-08 12:11:32.317034 | controller |   "size": 4023,
2025-10-08 12:11:32.317050 | controller |   "uid": 1000,
2025-10-08 12:11:32.317073 | controller |   "wgrp": false,
2025-10-08 12:11:32.317092 | controller |   "woth": false,
2025-10-08 12:11:32.317108 | controller |   "wusr": true,
2025-10-08 12:11:32.317124 | controller |   "xgrp": false,
2025-10-08 12:11:32.317141 | controller |   "xoth": false,
2025-10-08 12:11:32.317173 | controller |   "xusr": false
2025-10-08 12:11:32.317191 | controller | }
2025-10-08 12:11:32.946407 | controller | changed:
2025-10-08 12:11:32.946492 | controller | {
2025-10-08 12:11:32.946520 | controller |   "atime": 1759925181.617908,
2025-10-08 12:11:32.946543 | controller |   "ctime": 1759925197.8788614,
2025-10-08 12:11:32.946562 | controller |   "dev": 64513,
2025-10-08 12:11:32.946580 | controller |   "gid": 1000,
2025-10-08 12:11:32.946597 | controller |   "gr_name": "zuul",
2025-10-08 12:11:32.946614 | controller |   "inode": 4340281,
2025-10-08 12:11:32.946630 | controller |   "isblk": false,
2025-10-08 12:11:32.946645 | controller |   "ischr": false,
2025-10-08 12:11:32.946661 | controller |   "isdir": false,
2025-10-08 12:11:32.946677 | controller |   "isfifo": false,
2025-10-08 12:11:32.946692 | controller |   "isgid": false,
2025-10-08 12:11:32.946710 | controller |   "islnk": false,
2025-10-08 12:11:32.946726 | controller |   "isreg": true,
2025-10-08 12:11:32.946742 | controller |   "issock": false,
2025-10-08 12:11:32.946757 | controller |   "isuid": false,
2025-10-08 12:11:32.946772 | controller |   "mode": "0644",
2025-10-08 12:11:32.946787 | controller |   "mtime": 1759925197.8788614,
2025-10-08 12:11:32.946802 | controller |   "nlink": 1,
2025-10-08 12:11:32.946817 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 12:11:32.946832 | controller |   "pw_name": "zuul",
2025-10-08 12:11:32.946847 | controller |   "rgrp": true,
2025-10-08 12:11:32.946984 | controller |   "roth": true,
2025-10-08 12:11:32.947007 | controller |   "rusr": true,
2025-10-08 12:11:32.947029 | controller |   "size": 6749,
2025-10-08 12:11:32.947048 | controller |   "uid": 1000,
2025-10-08 12:11:32.947065 | controller |   "wgrp": false,
2025-10-08 12:11:32.947082 | controller |   "woth": false,
2025-10-08 12:11:32.947100 | controller |   "wusr": true,
2025-10-08 12:11:32.947116 | controller |   "xgrp": false,
2025-10-08 12:11:32.947132 | controller |   "xoth": false,
2025-10-08 12:11:32.947161 | controller |   "xusr": false
2025-10-08 12:11:32.947196 | controller | }
2025-10-08 12:11:32.964625 | 
2025-10-08 12:11:32.964717 | TASK [Copy crio stats log file]
2025-10-08 12:11:32.988793 | controller | skipping: Conditional result was False
2025-10-08 12:11:32.994326 | 
2025-10-08 12:11:32.994402 | TASK [Get SELinux related data]
2025-10-08 12:11:33.020567 | [controller] Waiting on logger
2025-10-08 12:11:43.410597 | [controller] Waiting on logger
2025-10-08 12:11:53.842321 | [controller] Waiting on logger
2025-10-08 12:12:03.186400 | [controller] Waiting on logger
2025-10-08 12:12:03.310966 | [Zuul] Log Stream did not terminate
2025-10-08 12:12:03.311172 | controller | ERROR
2025-10-08 12:12:03.311268 | controller | {
2025-10-08 12:12:03.311298 | controller |   "delta": "0:00:00.009081",
2025-10-08 12:12:03.311319 | controller |   "end": "2025-10-08 12:11:33.288102",
2025-10-08 12:12:03.311338 | controller |   "msg": "non-zero return code",
2025-10-08 12:12:03.311355 | controller |   "rc": 1,
2025-10-08 12:12:03.311372 | controller |   "start": "2025-10-08 12:11:33.279021"
2025-10-08 12:12:03.311389 | controller | }
2025-10-08 12:12:03.311413 | controller | ERROR: Ignoring Errors
2025-10-08 12:12:03.317360 | 
2025-10-08 12:12:03.317427 | TASK [Create system configuration directory]
2025-10-08 12:12:03.586765 | controller | changed
2025-10-08 12:12:03.593670 | 
2025-10-08 12:12:03.593761 | TASK [Get some of the system configurations]
2025-10-08 12:12:08.628324 | controller | changed
2025-10-08 12:12:08.640188 | 
2025-10-08 12:12:08.640292 | TASK [Copy generated documentation if available]
2025-10-08 12:12:08.664194 | controller | skipping: Conditional result was False
2025-10-08 12:12:08.670793 | 
2025-10-08 12:12:08.670897 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 12:12:08.684630 | controller | skipping: Conditional result was False
2025-10-08 12:12:08.691853 | 
2025-10-08 12:12:08.691965 | TASK [Compress logs bigger than 2MB]
2025-10-08 12:12:08.715676 | [controller] Waiting on logger
2025-10-08 12:12:13.822125 | controller | changed
2025-10-08 12:12:13.827703 | 
2025-10-08 12:12:13.827785 | TASK [Copy files from workspace on node]
2025-10-08 12:12:13.846723 | controller | ok
2025-10-08 12:12:13.873480 | 
2025-10-08 12:12:13.873584 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:12:13.897385 | controller | skipping: Conditional result was False
2025-10-08 12:12:13.903426 | 
2025-10-08 12:12:13.903500 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:12:13.932127 | controller | ok
2025-10-08 12:12:13.937421 | 
2025-10-08 12:12:13.937495 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:12:14.149533 | controller -> localhost | ok: "/var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/logs"
2025-10-08 12:12:14.149789 | controller -> localhost | changed: All items complete
2025-10-08 12:12:14.149828 | 
2025-10-08 12:12:14.351074 | controller -> localhost | changed: "/var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/artifacts"
2025-10-08 12:12:14.552050 | controller -> localhost | changed: "/var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/docs"
2025-10-08 12:12:14.563853 | 
2025-10-08 12:12:14.563976 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:12:14.706335 | [controller] Waiting on logger
2025-10-08 12:12:15.473188 | controller | changed:
2025-10-08 12:12:15.473299 | controller | .d..t...... ./
2025-10-08 12:12:15.473331 | controller | >f+++++++++ README.html
2025-10-08 12:12:15.473355 | controller | >f+++++++++ ansible-execution.log
2025-10-08 12:12:15.473380 | controller | >f+++++++++ ansible.log
2025-10-08 12:12:15.473402 | controller | >f+++++++++ crc-setup.log
2025-10-08 12:12:15.473421 | controller | >f+++++++++ crc-start.log
2025-10-08 12:12:15.473440 | controller | >f+++++++++ dmesg.log
2025-10-08 12:12:15.473460 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 12:12:15.473479 | controller | >f+++++++++ python.log
2025-10-08 12:12:15.473498 | controller | >f+++++++++ registries.conf
2025-10-08 12:12:15.473517 | controller | >f+++++++++ report.html
2025-10-08 12:12:15.473535 | controller | >f+++++++++ selinux-denials.log
2025-10-08 12:12:15.473553 | controller | >f+++++++++ selinux-listing.log
2025-10-08 12:12:15.473572 | controller | cd+++++++++ ci-framework-data/
2025-10-08 12:12:15.473590 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 12:12:15.473608 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_verify_external_network.sh
2025-10-08 12:12:15.473626 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw
2025-10-08 12:12:15.473645 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw.pub
2025-10-08 12:12:15.473664 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 12:12:15.473681 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-08 12:12:15.473698 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-08 12:12:15.473715 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-08 12:12:15.473731 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 12:12:15.473748 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 12:12:15.473765 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 12:12:15.473782 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 12:12:15.473799 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 12:12:15.473816 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-08 12:12:15.473839 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 12:12:15.473858 | controller | cd+++++++++ registries.conf.d/
2025-10-08 12:12:15.473876 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 12:12:15.473893 | controller | cd+++++++++ system-config/
2025-10-08 12:12:15.473934 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 12:12:15.473959 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 12:12:15.473979 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 12:12:15.473998 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 12:12:15.474015 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 12:12:15.474033 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 12:12:15.474050 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 12:12:15.474067 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 12:12:15.474084 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 12:12:15.474101 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 12:12:15.474117 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 12:12:15.474145 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 12:12:15.474163 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 12:12:15.474180 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 12:12:15.474197 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 12:12:15.474213 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 12:12:15.474230 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 12:12:16.078023 | controller | changed: .d..t...... ./
2025-10-08 12:12:16.592582 | controller | changed: .d..t...... ./
2025-10-08 12:12:16.612856 | 
2025-10-08 12:12:16.612954 | TASK [Return artifact to Zuul]
2025-10-08 12:12:16.641769 | controller | ok
2025-10-08 12:12:16.662508 | 
2025-10-08 12:12:16.662568 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 12:12:16.662664 | 
2025-10-08 12:12:16.662691 | PLAY RECAP
2025-10-08 12:12:16.662731 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 12:12:16.662754 | 
2025-10-08 12:12:16.769164 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:12:16.769882 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:12:17.367905 | 
2025-10-08 12:12:17.368039 | PLAY [all]
2025-10-08 12:12:17.392600 | 
2025-10-08 12:12:17.393002 | TASK [include_role : fetch-output]
2025-10-08 12:12:17.447294 | controller | ok
2025-10-08 12:12:17.481221 | 
2025-10-08 12:12:17.481326 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:12:17.577016 | controller | skipping: Conditional result was False
2025-10-08 12:12:17.583321 | 
2025-10-08 12:12:17.583396 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:12:17.652749 | controller | ok
2025-10-08 12:12:17.658482 | 
2025-10-08 12:12:17.658575 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:12:18.395743 | controller -> localhost | ok: "/var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/logs"
2025-10-08 12:12:18.671514 | controller -> localhost | ok: "/var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/artifacts"
2025-10-08 12:12:18.900483 | controller -> localhost | ok: "/var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/docs"
2025-10-08 12:12:18.920239 | 
2025-10-08 12:12:18.920388 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:12:19.564029 | controller | ok
2025-10-08 12:12:19.564239 | controller | ok: All items complete
2025-10-08 12:12:19.564271 | 
2025-10-08 12:12:20.167357 | controller | ok
2025-10-08 12:12:20.701552 | controller | ok
2025-10-08 12:12:20.728672 | 
2025-10-08 12:12:20.728788 | TASK [include_role : fetch-output-openshift]
2025-10-08 12:12:20.754607 | controller | skipping: Conditional result was False
2025-10-08 12:12:20.766144 | 
2025-10-08 12:12:20.766517 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 12:12:21.245209 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009553
2025-10-08 12:12:21.483110 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006592
2025-10-08 12:12:21.514651 | 
2025-10-08 12:12:21.514850 | PLAY [all]
2025-10-08 12:12:21.536346 | 
2025-10-08 12:12:21.536470 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 12:12:22.031332 | controller | changed
2025-10-08 12:12:22.054108 | 
2025-10-08 12:12:22.054182 | PLAY RECAP
2025-10-08 12:12:22.054227 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 12:12:22.054251 | 
2025-10-08 12:12:22.122067 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:12:22.122823 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 12:12:22.736861 | 
2025-10-08 12:12:22.737003 | PLAY [localhost]
2025-10-08 12:12:22.756895 | 
2025-10-08 12:12:22.757036 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 12:12:23.109973 | localhost | changed
2025-10-08 12:12:23.114560 | 
2025-10-08 12:12:23.114660 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 12:12:23.142747 | localhost | ok
2025-10-08 12:12:23.151737 | 
2025-10-08 12:12:23.151840 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 12:12:23.495314 | localhost | changed
2025-10-08 12:12:23.501841 | 
2025-10-08 12:12:23.501952 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 12:12:24.113107 | localhost | changed
2025-10-08 12:12:24.119076 | 
2025-10-08 12:12:24.119175 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 12:12:24.492341 | localhost | Identity added: /var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/tmp/ansible.6z2ghukc (/var/lib/zuul/builds/befb55bd01bb4d13bc9357b4625e1682/work/tmp/ansible.6z2ghukc)
2025-10-08 12:12:24.492519 | localhost | ok: Runtime: 0:00:00.006964
2025-10-08 12:12:24.497830 | 
2025-10-08 12:12:24.497926 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 12:12:24.770613 | localhost | ok: Runtime: 0:00:00.004550
2025-10-08 12:12:24.776207 | 
2025-10-08 12:12:24.776329 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 12:12:24.832849 | localhost | changed
2025-10-08 12:12:24.838219 | 
2025-10-08 12:12:24.838309 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 12:12:25.263726 | localhost | changed
2025-10-08 12:12:25.286862 | 
2025-10-08 12:12:25.286997 | PLAY [localhost]
2025-10-08 12:12:25.308488 | 
2025-10-08 12:12:25.308683 | TASK [Generate bulk log download script]
2025-10-08 12:12:25.329998 | localhost | ok
2025-10-08 12:12:25.342503 | 
2025-10-08 12:12:25.342584 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 12:12:25.370197 | localhost | ok: All assertions passed
2025-10-08 12:12:25.374708 | 
2025-10-08 12:12:25.374774 | TASK [local-log-download : Create download script]
2025-10-08 12:12:25.748108 | localhost -> localhost | changed
2025-10-08 12:12:25.756886 | 
2025-10-08 12:12:25.756986 | TASK [Register quick-download link]
2025-10-08 12:12:25.784768 | localhost | ok
2025-10-08 12:12:25.837494 | 
2025-10-08 12:12:25.837589 | PLAY [logserver.rdoproject.org]
2025-10-08 12:12:25.848955 | 
2025-10-08 12:12:25.849056 | TASK [Set zuul-log-path fact]
2025-10-08 12:12:25.865487 | logserver.rdoproject.org | ok
2025-10-08 12:12:25.875757 | 
2025-10-08 12:12:25.875852 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:12:25.902287 | logserver.rdoproject.org | ok
2025-10-08 12:12:25.908637 | 
2025-10-08 12:12:25.908731 | TASK [upload-logs : Create log directories]
2025-10-08 12:12:27.609180 | logserver.rdoproject.org | changed
2025-10-08 12:12:27.612500 | 
2025-10-08 12:12:27.612673 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 12:12:27.879882 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.010142
2025-10-08 12:12:27.885452 | 
2025-10-08 12:12:27.885541 | TASK [upload-logs : Upload logs to log server]
2025-10-08 12:12:29.556263 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 12:12:29.559807 | 
2025-10-08 12:12:29.559893 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 12:12:29.625398 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:12:29.626149 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:12:29.630794 | 
2025-10-08 12:12:29.630935 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 12:12:29.693578 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:12:29.693823 | 
2025-10-08 12:12:29.695104 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:12:29.702291 | 
2025-10-08 12:12:29.702403 | LOOP [upload-logs : Upload console log and json output]