2025-11-25 14:01:53.825314 | Job console starting...
2025-11-25 14:01:54.123347 | Updating repositories
2025-11-25 14:01:54.936231 | Preparing job workspace
2025-11-25 14:02:11.085088 | Running Ansible setup...
2025-11-25 14:02:18.592100 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:02:21.444779 | 
2025-11-25 14:02:21.444871 | PLAY [localhost]
2025-11-25 14:02:21.467540 | 
2025-11-25 14:02:21.467615 | TASK [Gathering Facts]
2025-11-25 14:02:24.094549 | localhost | ok
2025-11-25 14:02:24.132372 | 
2025-11-25 14:02:24.132499 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-25 14:02:26.083389 | localhost -> localhost | changed
2025-11-25 14:02:26.088825 | 
2025-11-25 14:02:26.088897 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-25 14:02:29.246424 | localhost -> localhost | changed
2025-11-25 14:02:29.255727 | 
2025-11-25 14:02:29.255799 | TASK [Setup log path fact]
2025-11-25 14:02:29.341015 | localhost | ok
2025-11-25 14:02:29.373929 | 
2025-11-25 14:02:29.373998 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:02:29.451533 | localhost | ok
2025-11-25 14:02:29.469424 | 
2025-11-25 14:02:29.469496 | TASK [emit-job-header : Print job information]
2025-11-25 14:02:29.607701 | # Job Information
2025-11-25 14:02:29.607891 | Ansible Version: 2.15.12
2025-11-25 14:02:29.607949 | Job: cifmw-molecule-openshift_provisioner_node
2025-11-25 14:02:29.607975 | Pipeline: github-check
2025-11-25 14:02:29.607995 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-11-25 14:02:29.608015 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-25 14:02:29.608034 | Log URL (when completed): https://logserver.rdoproject.org/bc9/rdoproject.org/bc91cb8b90064488a2efdbd553c72ed2/
2025-11-25 14:02:29.608053 | Event ID: 1ddbeee0-ca07-11f0-9bf4-54479d946bd8
2025-11-25 14:02:29.611819 | 
2025-11-25 14:02:29.611887 | LOOP [emit-job-header : Print node information]
2025-11-25 14:02:30.050530 | localhost | ok:
2025-11-25 14:02:30.050756 | localhost | # Node Information
2025-11-25 14:02:30.050788 | localhost | Inventory Hostname: controller
2025-11-25 14:02:30.050814 | localhost | Hostname: np0005535282
2025-11-25 14:02:30.050836 | localhost | Username: zuul
2025-11-25 14:02:30.050858 | localhost | Distro: CentOS 9
2025-11-25 14:02:30.050878 | localhost | Provider: ibm-bm4-nodepool
2025-11-25 14:02:30.050896 | localhost | Region: regionOne
2025-11-25 14:02:30.050951 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-11-25 14:02:30.050976 | localhost | Product Name: OpenStack Compute
2025-11-25 14:02:30.050996 | localhost | Interface IP: 192.168.26.212
2025-11-25 14:02:30.096087 | 
2025-11-25 14:02:30.096145 | PLAY [all]
2025-11-25 14:02:30.115855 | 
2025-11-25 14:02:30.115957 | TASK [Gather network facts]
2025-11-25 14:02:30.606187 | controller | ok
2025-11-25 14:02:30.675411 | 
2025-11-25 14:02:30.675794 | TASK [include_role : start-zuul-console]
2025-11-25 14:02:30.708606 | controller | ok
2025-11-25 14:02:30.738877 | 
2025-11-25 14:02:30.754756 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-25 14:02:31.784971 | controller | ok
2025-11-25 14:02:31.795564 | 
2025-11-25 14:02:31.795669 | TASK [include_role : add-build-sshkey]
2025-11-25 14:02:31.835988 | controller | ok
2025-11-25 14:02:31.862985 | 
2025-11-25 14:02:31.863057 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-25 14:02:32.691661 | controller -> localhost | ok
2025-11-25 14:02:32.697333 | 
2025-11-25 14:02:32.697402 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-25 14:02:32.803117 | controller | ok
2025-11-25 14:02:32.836696 | controller | included: /var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-25 14:02:32.858503 | 
2025-11-25 14:02:32.858636 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-25 14:02:35.875837 | controller -> localhost | Generating public/private rsa key pair.
2025-11-25 14:02:35.876126 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/bc91cb8b90064488a2efdbd553c72ed2_id_rsa.
2025-11-25 14:02:35.876160 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/bc91cb8b90064488a2efdbd553c72ed2_id_rsa.pub.
2025-11-25 14:02:35.876184 | controller -> localhost | The key fingerprint is:
2025-11-25 14:02:35.876205 | controller -> localhost | SHA256:xMPhzvX7W4PuVh73ztDrioLq/DQ5gj4GUUDJeAHqoQE zuul-build-sshkey
2025-11-25 14:02:35.876225 | controller -> localhost | The key's randomart image is:
2025-11-25 14:02:35.876254 | controller -> localhost | +---[RSA 3072]----+
2025-11-25 14:02:35.876275 | controller -> localhost | |E++.    .        |
2025-11-25 14:02:35.876295 | controller -> localhost | |+o..   + .       |
2025-11-25 14:02:35.876316 | controller -> localhost | |oo.     * .      |
2025-11-25 14:02:35.876334 | controller -> localhost | |oo.    + o .     |
2025-11-25 14:02:35.876354 | controller -> localhost | |...     S   .    |
2025-11-25 14:02:35.876374 | controller -> localhost | | .  .   .    ..+.|
2025-11-25 14:02:35.876393 | controller -> localhost | |  .. . =.   ..+.*|
2025-11-25 14:02:35.876411 | controller -> localhost | |  .o. o.o.  oo =+|
2025-11-25 14:02:35.876428 | controller -> localhost | |  ..o+o.  ..o+==o|
2025-11-25 14:02:35.876446 | controller -> localhost | +----[SHA256]-----+
2025-11-25 14:02:35.876491 | controller -> localhost | ok: Runtime: 0:00:01.464067
2025-11-25 14:02:35.882331 | 
2025-11-25 14:02:35.882395 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-25 14:02:35.938759 | controller | ok
2025-11-25 14:02:35.949445 | controller | included: /var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-25 14:02:36.008049 | 
2025-11-25 14:02:36.008214 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-25 14:02:36.132830 | controller | skipping: Conditional result was False
2025-11-25 14:02:36.155607 | 
2025-11-25 14:02:36.155697 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-25 14:02:36.954724 | controller | changed
2025-11-25 14:02:36.961949 | 
2025-11-25 14:02:36.962022 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-25 14:02:37.395006 | controller | ok
2025-11-25 14:02:37.417203 | 
2025-11-25 14:02:37.417283 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-25 14:02:38.769489 | controller | changed
2025-11-25 14:02:38.776369 | 
2025-11-25 14:02:38.776435 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-25 14:02:40.085377 | controller | changed
2025-11-25 14:02:40.090894 | 
2025-11-25 14:02:40.090985 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-25 14:02:40.163761 | controller | skipping: Conditional result was False
2025-11-25 14:02:40.174047 | 
2025-11-25 14:02:40.174115 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-25 14:02:41.230262 | controller -> localhost | changed
2025-11-25 14:02:41.240772 | 
2025-11-25 14:02:41.240843 | TASK [add-build-sshkey : Add back temp key]
2025-11-25 14:02:42.493155 | controller -> localhost | Identity added: /var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/bc91cb8b90064488a2efdbd553c72ed2_id_rsa (zuul-build-sshkey)
2025-11-25 14:02:42.493432 | controller -> localhost | ok: Runtime: 0:00:00.026057
2025-11-25 14:02:42.499095 | 
2025-11-25 14:02:42.499163 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-25 14:02:43.210545 | controller | ok
2025-11-25 14:02:43.219744 | 
2025-11-25 14:02:43.219813 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-25 14:02:43.317423 | controller | skipping: Conditional result was False
2025-11-25 14:02:43.343029 | 
2025-11-25 14:02:43.343142 | TASK [include_role : validate-host]
2025-11-25 14:02:43.425449 | controller | ok
2025-11-25 14:02:43.489354 | 
2025-11-25 14:02:43.489472 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-25 14:02:43.618699 | controller | ok
2025-11-25 14:02:43.625820 | 
2025-11-25 14:02:43.625885 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-25 14:02:44.267391 | controller -> localhost | ok
2025-11-25 14:02:44.274000 | 
2025-11-25 14:02:44.274068 | TASK [validate-host : Collect information about the host]
2025-11-25 14:02:44.994154 | controller | ok
2025-11-25 14:02:45.027690 | 
2025-11-25 14:02:45.027786 | TASK [validate-host : Sanitize hostname]
2025-11-25 14:02:45.168269 | controller | ok
2025-11-25 14:02:45.180314 | 
2025-11-25 14:02:45.180393 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-25 14:02:46.878128 | controller -> localhost | changed
2025-11-25 14:02:46.884457 | 
2025-11-25 14:02:46.884525 | TASK [validate-host : Collect information about zuul worker]
2025-11-25 14:02:47.537086 | controller | ok
2025-11-25 14:02:47.545653 | 
2025-11-25 14:02:47.545728 | TASK [validate-host : Write out all zuul information for each host]
2025-11-25 14:02:49.282735 | controller -> localhost | changed
2025-11-25 14:02:49.292630 | 
2025-11-25 14:02:49.292696 | TASK [include_role : prepare-workspace-openshift]
2025-11-25 14:02:49.385348 | controller | skipping: Conditional result was False
2025-11-25 14:02:49.391968 | 
2025-11-25 14:02:49.392047 | TASK [include_role : remove-zuul-sshkey]
2025-11-25 14:02:49.441869 | controller | skipping: Conditional result was False
2025-11-25 14:02:49.447798 | 
2025-11-25 14:02:49.447872 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-25 14:02:49.809648 | controller | ok: "logs"
2025-11-25 14:02:49.811995 | controller | ok: All items complete
2025-11-25 14:02:49.812045 | 
2025-11-25 14:02:50.064805 | controller | ok: "artifacts"
2025-11-25 14:02:50.322644 | controller | ok: "docs"
2025-11-25 14:02:50.334053 | 
2025-11-25 14:02:50.334157 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-25 14:02:50.692482 | controller | changed: "logs"
2025-11-25 14:02:50.935682 | controller | changed: "artifacts"
2025-11-25 14:02:51.175682 | controller | changed: "docs"
2025-11-25 14:02:51.254344 | 
2025-11-25 14:02:51.254431 | PLAY RECAP
2025-11-25 14:02:51.254477 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:02:51.254508 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:02:51.254529 | 
2025-11-25 14:02:51.738297 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:02:51.739029 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:02:54.152386 | 
2025-11-25 14:02:54.152520 | PLAY [all]
2025-11-25 14:02:54.218271 | 
2025-11-25 14:02:54.218413 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:02:54.413632 | controller | ok
2025-11-25 14:02:54.418855 | 
2025-11-25 14:02:54.418964 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:02:55.454334 | controller | changed
2025-11-25 14:02:55.468152 | 
2025-11-25 14:02:55.468262 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:02:58.009112 | controller | changed
2025-11-25 14:02:58.031071 | 
2025-11-25 14:02:58.031164 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-25 14:02:58.888835 | controller | changed:
2025-11-25 14:02:58.889057 | controller | {
2025-11-25 14:02:58.889092 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-25 14:02:58.889124 | controller | }
2025-11-25 14:02:59.228594 | controller | changed:
2025-11-25 14:02:59.228697 | controller | {
2025-11-25 14:02:59.228727 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-25 14:02:59.228749 | controller | }
2025-11-25 14:02:59.493190 | controller | changed:
2025-11-25 14:02:59.493298 | controller | {
2025-11-25 14:02:59.493326 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-25 14:02:59.493347 | controller | }
2025-11-25 14:02:59.785681 | controller | changed:
2025-11-25 14:02:59.785845 | controller | {
2025-11-25 14:02:59.785880 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-25 14:02:59.785903 | controller | }
2025-11-25 14:03:00.105041 | controller | changed:
2025-11-25 14:03:00.105136 | controller | {
2025-11-25 14:03:00.105163 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-25 14:03:00.105186 | controller | }
2025-11-25 14:03:00.420084 | controller | changed:
2025-11-25 14:03:00.420167 | controller | {
2025-11-25 14:03:00.420195 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-25 14:03:00.420218 | controller | }
2025-11-25 14:03:00.821375 | controller | changed:
2025-11-25 14:03:00.821463 | controller | {
2025-11-25 14:03:00.821491 | 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-11-25 14:03:00.821522 | controller | }
2025-11-25 14:03:01.048273 | controller | changed:
2025-11-25 14:03:01.048360 | controller | {
2025-11-25 14:03:01.048387 | 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-11-25 14:03:01.048409 | controller | }
2025-11-25 14:03:01.370843 | controller | changed:
2025-11-25 14:03:01.370973 | controller | {
2025-11-25 14:03:01.371008 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-25 14:03:01.371031 | controller | }
2025-11-25 14:03:01.694071 | controller | changed:
2025-11-25 14:03:01.694151 | controller | {
2025-11-25 14:03:01.694177 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-25 14:03:01.694198 | controller | }
2025-11-25 14:03:01.995168 | controller | changed:
2025-11-25 14:03:01.995258 | controller | {
2025-11-25 14:03:01.995287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-25 14:03:01.995308 | controller | }
2025-11-25 14:03:02.325745 | controller | changed:
2025-11-25 14:03:02.325825 | controller | {
2025-11-25 14:03:02.325852 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-25 14:03:02.325874 | controller | }
2025-11-25 14:03:02.645237 | controller | changed:
2025-11-25 14:03:02.649416 | controller | {
2025-11-25 14:03:02.649456 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-25 14:03:02.649481 | controller | }
2025-11-25 14:03:02.943689 | controller | changed:
2025-11-25 14:03:02.943774 | controller | {
2025-11-25 14:03:02.943800 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-25 14:03:02.943821 | controller | }
2025-11-25 14:03:03.230565 | controller | changed:
2025-11-25 14:03:03.230649 | controller | {
2025-11-25 14:03:03.230676 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-25 14:03:03.230696 | controller | }
2025-11-25 14:03:03.518381 | controller | changed:
2025-11-25 14:03:03.518563 | controller | {
2025-11-25 14:03:03.518598 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-25 14:03:03.518620 | controller | }
2025-11-25 14:03:03.820022 | controller | changed:
2025-11-25 14:03:03.820111 | controller | {
2025-11-25 14:03:03.820139 | 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-11-25 14:03:03.820162 | controller | }
2025-11-25 14:03:04.128228 | controller | changed:
2025-11-25 14:03:04.128331 | controller | {
2025-11-25 14:03:04.128358 | 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-11-25 14:03:04.128387 | controller | }
2025-11-25 14:03:04.455036 | controller | changed:
2025-11-25 14:03:04.455986 | controller | {
2025-11-25 14:03:04.456241 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-25 14:03:04.456296 | controller | }
2025-11-25 14:03:04.753356 | controller | changed:
2025-11-25 14:03:04.753463 | controller | {
2025-11-25 14:03:04.753491 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-25 14:03:04.753513 | controller | }
2025-11-25 14:03:05.065475 | controller | changed:
2025-11-25 14:03:05.065560 | controller | {
2025-11-25 14:03:05.065586 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-25 14:03:05.065606 | controller | }
2025-11-25 14:03:05.359522 | controller | changed:
2025-11-25 14:03:05.359716 | controller | {
2025-11-25 14:03:05.359747 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-25 14:03:05.359768 | controller | }
2025-11-25 14:03:05.656122 | controller | changed:
2025-11-25 14:03:05.656334 | controller | {
2025-11-25 14:03:05.656370 | 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-11-25 14:03:05.656395 | controller | }
2025-11-25 14:03:05.953258 | controller | changed:
2025-11-25 14:03:05.953387 | controller | {
2025-11-25 14:03:05.953419 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-25 14:03:05.953442 | controller | }
2025-11-25 14:03:06.250981 | controller | changed:
2025-11-25 14:03:06.251087 | controller | {
2025-11-25 14:03:06.251113 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-25 14:03:06.251133 | controller | }
2025-11-25 14:03:06.563389 | controller | changed:
2025-11-25 14:03:06.563516 | controller | {
2025-11-25 14:03:06.563544 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-25 14:03:06.563565 | controller | }
2025-11-25 14:03:06.619254 | 
2025-11-25 14:03:06.619428 | TASK [Set timezone to UTC]
2025-11-25 14:03:07.471810 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-25 14:03:07.478345 | 
2025-11-25 14:03:07.478420 | TASK [Create nodepool directory]
2025-11-25 14:03:07.794370 | controller | changed
2025-11-25 14:03:07.800062 | 
2025-11-25 14:03:07.800131 | TASK [Create nodepool sub_nodes file]
2025-11-25 14:03:08.939592 | controller | changed
2025-11-25 14:03:08.945070 | 
2025-11-25 14:03:08.945166 | TASK [Create nodepool sub_nodes_private file]
2025-11-25 14:03:10.099537 | controller | changed
2025-11-25 14:03:10.115464 | 
2025-11-25 14:03:10.115619 | LOOP [Populate nodepool sub_nodes file]
2025-11-25 14:03:10.207332 | 
2025-11-25 14:03:10.207480 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-25 14:03:10.287226 | 
2025-11-25 14:03:10.287396 | TASK [Create nodepool primary file]
2025-11-25 14:03:10.361384 | controller | skipping: Conditional result was False
2025-11-25 14:03:10.367600 | 
2025-11-25 14:03:10.367678 | TASK [Create nodepool node_private for this node]
2025-11-25 14:03:11.427781 | controller | changed
2025-11-25 14:03:11.434866 | 
2025-11-25 14:03:11.443280 | LOOP [Copy ssh keys to nodepool directory]
2025-11-25 14:03:12.076162 | controller | ok: Item: id_rsa Runtime: 0:00:00.004934
2025-11-25 14:03:12.076433 | 
2025-11-25 14:03:12.309198 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004571
2025-11-25 14:03:12.317458 | 
2025-11-25 14:03:12.317528 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-25 14:03:13.354506 | controller | changed
2025-11-25 14:03:13.361642 | 
2025-11-25 14:03:13.361709 | TASK [Validate sudoers config after edits]
2025-11-25 14:03:13.652264 | controller | /etc/sudoers: parsed OK
2025-11-25 14:03:13.652347 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-25 14:03:13.652358 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-25 14:03:13.652364 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-25 14:03:13.899817 | controller | ok: Runtime: 0:00:00.005271
2025-11-25 14:03:13.906601 | 
2025-11-25 14:03:13.906671 | TASK [Show the environment passed in to job shell scripts]
2025-11-25 14:03:14.434758 | controller | SHELL=/bin/bash
2025-11-25 14:03:14.434810 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-25 14:03:14.434820 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-25 14:03:14.434826 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:03:14.434832 | controller | PWD=/home/zuul
2025-11-25 14:03:14.434837 | controller | ZUUL_PIPELINE=github-check
2025-11-25 14:03:14.434842 | controller | LOGNAME=zuul
2025-11-25 14:03:14.434847 | controller | XDG_SESSION_TYPE=tty
2025-11-25 14:03:14.434853 | controller | _=/usr/bin/env
2025-11-25 14:03:14.434858 | controller | MOTD_SHOWN=pam
2025-11-25 14:03:14.434863 | controller | HOME=/home/zuul
2025-11-25 14:03:14.434868 | controller | LANG=en_US.UTF-8
2025-11-25 14:03:14.434897 | controller | SSH_CONNECTION=192.168.26.12 45480 192.168.26.212 22
2025-11-25 14:03:14.434903 | 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-11-25 14:03:14.434911 | controller | ZUUL_CHANGE_IDS=3505,aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:03:14.434917 | controller | WORKSPACE=/home/zuul/workspace
2025-11-25 14:03:14.434922 | controller | XDG_SESSION_CLASS=user
2025-11-25 14:03:14.434927 | controller | SELINUX_ROLE_REQUESTED=
2025-11-25 14:03:14.434932 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-25 14:03:14.434938 | controller | USER=zuul
2025-11-25 14:03:14.434943 | controller | ZUUL_VOTING=True
2025-11-25 14:03:14.434948 | controller | BUILD_TIMEOUT=1800000
2025-11-25 14:03:14.434953 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-25 14:03:14.434958 | controller | SHLVL=1
2025-11-25 14:03:14.434963 | controller | ZUUL_PATCHSET=aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:03:14.434968 | controller | XDG_SESSION_ID=1
2025-11-25 14:03:14.434973 | controller | ZUUL_BRANCH=main
2025-11-25 14:03:14.434978 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-25 14:03:14.434984 | controller | SSH_CLIENT=192.168.26.12 45480 22
2025-11-25 14:03:14.434989 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-25 14:03:14.434994 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-25 14:03:14.434999 | controller | which_declare=declare -f
2025-11-25 14:03:14.435004 | 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-11-25 14:03:14.435009 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-25 14:03:14.435015 | controller | ZUUL_CHANGE=3505
2025-11-25 14:03:14.435020 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-25 14:03:14.435025 | controller | ZUUL_UUID=bc91cb8b90064488a2efdbd553c72ed2
2025-11-25 14:03:14.435030 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-25 14:03:14.435035 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-25 14:03:14.435040 | controller | }
2025-11-25 14:03:14.534824 | controller | ok: Runtime: 0:00:00.005825
2025-11-25 14:03:14.540504 | 
2025-11-25 14:03:14.540584 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-25 14:03:14.556980 | controller | skipping: Conditional result was False
2025-11-25 14:03:14.562673 | 
2025-11-25 14:03:14.562742 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-25 14:03:15.091702 | controller | skipping: Conditional result was False
2025-11-25 14:03:15.098542 | 
2025-11-25 14:03:15.098612 | TASK [Ensure legacy workspace directory]
2025-11-25 14:03:15.354449 | controller | changed
2025-11-25 14:03:15.388279 | 
2025-11-25 14:03:15.388363 | PLAY RECAP
2025-11-25 14:03:15.388411 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:03:15.388434 | 
2025-11-25 14:03:15.484036 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:03:15.484799 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:03:16.386780 | 
2025-11-25 14:03:16.386888 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-25 14:03:16.407344 | 
2025-11-25 14:03:16.407415 | TASK [Create zuul-output directory]
2025-11-25 14:03:16.800850 | controller | changed
2025-11-25 14:03:16.808526 | 
2025-11-25 14:03:16.808676 | TASK [Slurp Zuul inventory test]
2025-11-25 14:03:17.222406 | controller -> localhost | ok
2025-11-25 14:03:17.228880 | 
2025-11-25 14:03:17.228968 | TASK [Save zuul inventory]
2025-11-25 14:03:18.571358 | controller | changed
2025-11-25 14:03:18.577848 | 
2025-11-25 14:03:18.577961 | TASK [Save zuul vars without the change_message]
2025-11-25 14:03:19.813130 | controller | changed
2025-11-25 14:03:19.856928 | 
2025-11-25 14:03:19.857046 | PLAY RECAP
2025-11-25 14:03:19.857097 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:03:19.857121 | 
2025-11-25 14:03:20.004653 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:03:20.005396 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:03:20.825124 | 
2025-11-25 14:03:20.825225 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-25 14:03:20.874193 | 
2025-11-25 14:03:20.874316 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-25 14:03:20.933362 | controller | ok
2025-11-25 14:03:20.980755 | 
2025-11-25 14:03:20.980892 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:03:21.065681 | controller | skipping: Conditional result was False
2025-11-25 14:03:21.071999 | 
2025-11-25 14:03:21.072073 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:03:21.599041 | controller | ok
2025-11-25 14:03:21.605498 | 
2025-11-25 14:03:21.605586 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:03:22.637494 | controller | ok
2025-11-25 14:03:22.652363 | 
2025-11-25 14:03:22.652437 | TASK [Prepare workspace]
2025-11-25 14:03:22.695483 | controller | ok
2025-11-25 14:03:22.733026 | 
2025-11-25 14:03:22.733126 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-25 14:03:23.151526 | controller | ok
2025-11-25 14:03:23.173630 | 
2025-11-25 14:03:23.173725 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-25 14:03:27.642333 | controller | Output suppressed because no_log was given
2025-11-25 14:03:27.666220 | 
2025-11-25 14:03:27.666315 | LOOP [Create zuul-output directory]
2025-11-25 14:03:27.973278 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-25 14:03:28.194366 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-25 14:03:28.201746 | 
2025-11-25 14:03:28.201865 | TASK [Install required packages]
2025-11-25 14:03:55.934538 | controller | ok: Nothing to do
2025-11-25 14:03:55.940152 | 
2025-11-25 14:03:55.940218 | TASK [Install venv]
2025-11-25 14:04:55.158301 | controller | changed
2025-11-25 14:04:55.219174 | 
2025-11-25 14:04:55.219276 | PLAY RECAP
2025-11-25 14:04:55.219333 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:04:55.219356 | 
2025-11-25 14:04:55.295704 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:04:55.296491 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:04:55.877556 | 
2025-11-25 14:04:55.877677 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-25 14:04:55.901360 | 
2025-11-25 14:04:55.901474 | TASK [Gather required facts]
2025-11-25 14:04:56.459536 | controller | ok
2025-11-25 14:04:56.465233 | 
2025-11-25 14:04:56.465319 | TASK [Load environment var if instructed to]
2025-11-25 14:04:56.489262 | controller | skipping: Conditional result was False
2025-11-25 14:04:56.494826 | 
2025-11-25 14:04:56.494892 | TASK [Ensure group_vars dir exists]
2025-11-25 14:04:56.849325 | controller | ok
2025-11-25 14:04:56.854491 | 
2025-11-25 14:04:56.854561 | TASK [Print related variables]
2025-11-25 14:04:56.882969 | controller | ok:
2025-11-25 14:04:56.883082 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-25 14:04:56.883112 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node
2025-11-25 14:04:56.888054 | 
2025-11-25 14:04:56.888119 | TASK [Run molecule]
2025-11-25 14:04:57.903376 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-25 14:04:57.975392 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-25 14:05:16.018177 | 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-11-25 14:05:16.018634 | 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-11-25 14:05:16.019130 | 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-11-25 14:05:16.019579 | 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-11-25 14:05:16.020078 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+0b589e62 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0+0b589e62 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:16.020525 | 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-11-25 14:05:16.020985 | 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-11-25 14:05:16.021450 | 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-11-25 14:05:16.021904 | 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-11-25 14:05:16.022353 | 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-11-25 14:05:16.022825 | 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-11-25 14:05:16.023290 | 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-11-25 14:05:16.023758 | 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-11-25 14:05:16.024218 | 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-11-25 14:05:16.024667 | 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-11-25 14:05:16.034087 | controller | INFO     Running default > prepare
2025-11-25 14:05:16.737556 | controller |
2025-11-25 14:05:16.737628 | controller | PLAY [Prepare] *****************************************************************
2025-11-25 14:05:16.737770 | controller |
2025-11-25 14:05:16.737870 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:05:16.737963 | controller | Tuesday 25 November 2025  14:05:16 +0000 (0:00:00.028)       0:00:00.028 ******
2025-11-25 14:05:17.570634 | controller | ok: [instance]
2025-11-25 14:05:17.570694 | controller |
2025-11-25 14:05:17.570834 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-25 14:05:17.570959 | controller | Tuesday 25 November 2025  14:05:17 +0000 (0:00:00.833)       0:00:00.861 ******
2025-11-25 14:05:17.589273 | controller | skipping: [instance]
2025-11-25 14:05:17.589373 | controller |
2025-11-25 14:05:17.589474 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-25 14:05:17.589574 | controller | Tuesday 25 November 2025  14:05:17 +0000 (0:00:00.018)       0:00:00.880 ******
2025-11-25 14:05:17.631511 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-25 14:05:17.631585 | controller |
2025-11-25 14:05:17.631763 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-25 14:05:17.631891 | controller | Tuesday 25 November 2025  14:05:17 +0000 (0:00:00.042)       0:00:00.922 ******
2025-11-25 14:05:17.946093 | controller | ok: [instance]
2025-11-25 14:05:17.946169 | controller |
2025-11-25 14:05:17.946273 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-25 14:05:17.946372 | controller | Tuesday 25 November 2025  14:05:17 +0000 (0:00:00.314)       0:00:01.237 ******
2025-11-25 14:05:18.257129 | controller | ok: [instance]
2025-11-25 14:05:18.257226 | controller |
2025-11-25 14:05:18.257337 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-25 14:05:18.257445 | controller | Tuesday 25 November 2025  14:05:18 +0000 (0:00:00.311)       0:00:01.548 ******
2025-11-25 14:05:18.932562 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-25 14:05:18.932614 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-25 14:05:18.932760 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-25 14:05:18.932859 | controller |
2025-11-25 14:05:18.932960 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-25 14:05:18.933077 | controller | Tuesday 25 November 2025  14:05:18 +0000 (0:00:00.675)       0:00:02.223 ******
2025-11-25 14:05:18.992226 | controller |
2025-11-25 14:05:18.992499 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-25 14:05:19.526598 | controller | Tuesday 25 November 2025  14:05:18 +0000 (0:00:00.059)       0:00:02.283 ******
2025-11-25 14:05:19.526632 | controller | changed: [instance] => (item=tmp)
2025-11-25 14:05:20.345399 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-25 14:05:20.345429 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-25 14:05:20.345437 | controller |
2025-11-25 14:05:20.345443 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-25 14:05:20.345449 | controller | Tuesday 25 November 2025  14:05:19 +0000 (0:00:00.533)       0:00:02.817 ******
2025-11-25 14:05:20.345459 | controller | ok: [instance]
2025-11-25 14:05:21.430319 | controller |
2025-11-25 14:05:21.430347 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-25 14:05:21.430358 | controller | Tuesday 25 November 2025  14:05:20 +0000 (0:00:00.818)       0:00:03.636 ******
2025-11-25 14:05:21.430368 | controller | changed: [instance]
2025-11-25 14:05:29.281815 | controller |
2025-11-25 14:05:29.281852 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-25 14:05:29.281860 | controller | Tuesday 25 November 2025  14:05:21 +0000 (0:00:01.085)       0:00:04.721 ******
2025-11-25 14:05:29.281870 | controller | changed: [instance]
2025-11-25 14:05:29.281894 | controller |
2025-11-25 14:05:29.282056 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-25 14:05:29.995782 | controller | Tuesday 25 November 2025  14:05:29 +0000 (0:00:07.851)       0:00:12.573 ******
2025-11-25 14:05:29.995819 | controller | changed: [instance]
2025-11-25 14:05:29.995849 | controller |
2025-11-25 14:05:29.995856 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-25 14:05:29.995863 | controller | Tuesday 25 November 2025  14:05:29 +0000 (0:00:00.713)       0:00:13.286 ******
2025-11-25 14:05:30.016745 | controller | skipping: [instance]
2025-11-25 14:05:30.824516 | controller |
2025-11-25 14:05:30.824545 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-25 14:05:30.824553 | controller | Tuesday 25 November 2025  14:05:30 +0000 (0:00:00.021)       0:00:13.307 ******
2025-11-25 14:05:30.824563 | controller | changed: [instance]
2025-11-25 14:05:30.862541 | controller |
2025-11-25 14:05:30.862565 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-25 14:05:30.862573 | controller | Tuesday 25 November 2025  14:05:30 +0000 (0:00:00.807)       0:00:14.115 ******
2025-11-25 14:05:30.862582 | controller | skipping: [instance]
2025-11-25 14:05:30.899760 | controller |
2025-11-25 14:05:30.899793 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-25 14:05:30.899801 | controller | Tuesday 25 November 2025  14:05:30 +0000 (0:00:00.037)       0:00:14.153 ******
2025-11-25 14:05:30.899812 | controller | skipping: [instance]
2025-11-25 14:05:30.899966 | controller |
2025-11-25 14:05:30.899986 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-25 14:05:30.899997 | controller | Tuesday 25 November 2025  14:05:30 +0000 (0:00:00.037)       0:00:14.190 ******
2025-11-25 14:05:30.936282 | controller | skipping: [instance]
2025-11-25 14:05:31.483800 | controller |
2025-11-25 14:05:31.483832 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-25 14:05:31.483842 | controller | Tuesday 25 November 2025  14:05:30 +0000 (0:00:00.036)       0:00:14.227 ******
2025-11-25 14:05:31.483854 | controller | changed: [instance]
2025-11-25 14:05:31.921400 | controller |
2025-11-25 14:05:31.921427 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-25 14:05:31.921435 | controller | Tuesday 25 November 2025  14:05:31 +0000 (0:00:00.547)       0:00:14.774 ******
2025-11-25 14:05:31.921445 | controller | changed: [instance]
2025-11-25 14:05:31.949755 | controller |
2025-11-25 14:05:31.949789 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-25 14:05:31.949797 | controller | Tuesday 25 November 2025  14:05:31 +0000 (0:00:00.437)       0:00:15.212 ******
2025-11-25 14:05:31.949808 | controller | skipping: [instance]
2025-11-25 14:05:31.949814 | controller |
2025-11-25 14:05:31.949820 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-25 14:05:31.949827 | controller | Tuesday 25 November 2025  14:05:31 +0000 (0:00:00.028)       0:00:15.240 ******
2025-11-25 14:05:31.978426 | controller | skipping: [instance]
2025-11-25 14:05:32.007180 | controller |
2025-11-25 14:05:32.007199 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-25 14:05:32.007206 | controller | Tuesday 25 November 2025  14:05:31 +0000 (0:00:00.028)       0:00:15.269 ******
2025-11-25 14:05:32.007215 | controller | skipping: [instance]
2025-11-25 14:05:32.045197 | controller |
2025-11-25 14:05:32.045218 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-25 14:05:32.045227 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.028)       0:00:15.298 ******
2025-11-25 14:05:32.045238 | controller | ok: [instance]
2025-11-25 14:05:32.072294 | controller |
2025-11-25 14:05:32.072312 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-25 14:05:32.072319 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.037)       0:00:15.336 ******
2025-11-25 14:05:32.072327 | controller | skipping: [instance]
2025-11-25 14:05:32.098792 | controller |
2025-11-25 14:05:32.098815 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-25 14:05:32.098824 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.027)       0:00:15.363 ******
2025-11-25 14:05:32.098844 | controller | skipping: [instance]
2025-11-25 14:05:32.098873 | controller |
2025-11-25 14:05:32.098882 | controller | TASK [Download the RPM] ********************************************************
2025-11-25 14:05:32.098964 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.026)       0:00:15.390 ******
2025-11-25 14:05:32.125431 | controller | skipping: [instance]
2025-11-25 14:05:32.125600 | controller |
2025-11-25 14:05:32.125806 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-25 14:05:32.125974 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.026)       0:00:15.417 ******
2025-11-25 14:05:32.151442 | controller | skipping: [instance]
2025-11-25 14:05:32.151609 | controller |
2025-11-25 14:05:32.151814 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-25 14:05:32.151981 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.026)       0:00:15.443 ******
2025-11-25 14:05:32.177283 | controller | skipping: [instance]
2025-11-25 14:05:32.177468 | controller |
2025-11-25 14:05:32.177642 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-25 14:05:32.177839 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.025)       0:00:15.468 ******
2025-11-25 14:05:32.205816 | controller | skipping: [instance]
2025-11-25 14:05:32.206000 | controller |
2025-11-25 14:05:32.206179 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-25 14:05:32.206326 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.028)       0:00:15.497 ******
2025-11-25 14:05:32.232383 | controller | skipping: [instance]
2025-11-25 14:05:32.232568 | controller |
2025-11-25 14:05:32.232772 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-25 14:05:32.232945 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.026)       0:00:15.523 ******
2025-11-25 14:05:32.425528 | controller | ok: [instance]
2025-11-25 14:05:32.631047 | controller |
2025-11-25 14:05:32.631077 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-25 14:05:32.631085 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.192)       0:00:15.716 ******
2025-11-25 14:05:32.631095 | controller | changed: [instance]
2025-11-25 14:05:32.853376 | controller |
2025-11-25 14:05:32.853404 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-25 14:05:32.853412 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.205)       0:00:15.921 ******
2025-11-25 14:05:32.853422 | controller | changed: [instance]
2025-11-25 14:05:32.874272 | controller |
2025-11-25 14:05:32.874296 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-25 14:05:32.874305 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.222)       0:00:16.144 ******
2025-11-25 14:05:32.874316 | controller | skipping: [instance]
2025-11-25 14:05:32.897575 | controller |
2025-11-25 14:05:32.897593 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-25 14:05:32.897601 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.021)       0:00:16.165 ******
2025-11-25 14:05:32.897609 | controller | skipping: [instance]
2025-11-25 14:05:32.919196 | controller |
2025-11-25 14:05:32.919214 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-25 14:05:32.919221 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.023)       0:00:16.188 ******
2025-11-25 14:05:32.919237 | controller | skipping: [instance]
2025-11-25 14:05:32.940423 | controller |
2025-11-25 14:05:32.940445 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-25 14:05:32.940452 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.021)       0:00:16.210 ******
2025-11-25 14:05:32.940463 | controller | skipping: [instance]
2025-11-25 14:05:32.962098 | controller |
2025-11-25 14:05:32.962117 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-25 14:05:32.962125 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.021)       0:00:16.231 ******
2025-11-25 14:05:32.962134 | controller | skipping: [instance]
2025-11-25 14:05:32.983435 | controller |
2025-11-25 14:05:32.983456 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-25 14:05:32.983474 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.021)       0:00:16.253 ******
2025-11-25 14:05:32.983485 | controller | skipping: [instance]
2025-11-25 14:05:32.999414 | controller |
2025-11-25 14:05:32.999432 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-25 14:05:32.999440 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.021)       0:00:16.274 ******
2025-11-25 14:05:32.999449 | controller | skipping: [instance]
2025-11-25 14:05:33.027733 | controller |
2025-11-25 14:05:33.027754 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-25 14:05:33.027762 | controller | Tuesday 25 November 2025  14:05:32 +0000 (0:00:00.016)       0:00:16.290 ******
2025-11-25 14:05:33.027771 | controller | skipping: [instance]
2025-11-25 14:05:33.028021 | controller |
2025-11-25 14:05:33.028035 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-25 14:05:33.045728 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.028)       0:00:16.319 ******
2025-11-25 14:05:33.045779 | controller | skipping: [instance]
2025-11-25 14:05:33.067072 | controller |
2025-11-25 14:05:33.067092 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-25 14:05:33.067100 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.017)       0:00:16.336 ******
2025-11-25 14:05:33.067110 | controller | skipping: [instance]
2025-11-25 14:05:33.095799 | controller |
2025-11-25 14:05:33.095836 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-25 14:05:33.095846 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.021)       0:00:16.358 ******
2025-11-25 14:05:33.095858 | controller | skipping: [instance]
2025-11-25 14:05:33.095902 | controller |
2025-11-25 14:05:33.095922 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-25 14:05:33.095933 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.028)       0:00:16.387 ******
2025-11-25 14:05:33.125760 | controller | skipping: [instance]
2025-11-25 14:05:33.125872 | controller |
2025-11-25 14:05:33.125898 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-25 14:05:33.126021 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.029)       0:00:16.416 ******
2025-11-25 14:05:33.157826 | controller | skipping: [instance]
2025-11-25 14:05:33.157876 | controller |
2025-11-25 14:05:33.157887 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-25 14:05:33.158000 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.032)       0:00:16.449 ******
2025-11-25 14:05:33.187735 | controller | skipping: [instance]
2025-11-25 14:06:13.586631 | controller |
2025-11-25 14:06:13.586662 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-25 14:06:13.586671 | controller | Tuesday 25 November 2025  14:05:33 +0000 (0:00:00.029)       0:00:16.478 ******
2025-11-25 14:06:13.586695 | controller | ok: [instance]
2025-11-25 14:06:14.795658 | controller |
2025-11-25 14:06:14.795704 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-25 14:06:14.795713 | controller | Tuesday 25 November 2025  14:06:13 +0000 (0:00:40.398)       0:00:56.877 ******
2025-11-25 14:06:14.795723 | controller | ok: [instance]
2025-11-25 14:06:14.827791 | controller |
2025-11-25 14:06:14.827821 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-25 14:06:14.827831 | controller | Tuesday 25 November 2025  14:06:14 +0000 (0:00:01.209)       0:00:58.086 ******
2025-11-25 14:06:14.827843 | controller | skipping: [instance]
2025-11-25 14:06:14.868525 | controller |
2025-11-25 14:06:14.868548 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-11-25 14:06:14.868558 | controller | Tuesday 25 November 2025  14:06:14 +0000 (0:00:00.032)       0:00:58.118 ******
2025-11-25 14:06:14.868569 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-11-25 14:06:14.886813 | controller |
2025-11-25 14:06:14.886849 | controller | TASK [ci_setup : List packages to install] *************************************
2025-11-25 14:06:14.886857 | controller | Tuesday 25 November 2025  14:06:14 +0000 (0:00:00.040)       0:00:58.159 ******
2025-11-25 14:06:14.886876 | controller | ok: [instance] =>
2025-11-25 14:06:14.886902 | controller |   cifmw_ci_setup_packages:
2025-11-25 14:06:14.886909 | controller |   - bash-completion
2025-11-25 14:06:14.886916 | controller |   - ca-certificates
2025-11-25 14:06:14.887687 | controller |   - git-core
2025-11-25 14:06:22.515313 | controller |   - make
2025-11-25 14:06:22.515346 | controller |   - tar
2025-11-25 14:06:22.515355 | controller |   - tmux
2025-11-25 14:06:22.515360 | controller |   - python3-pip
2025-11-25 14:06:22.515366 | controller |
2025-11-25 14:06:22.515372 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-11-25 14:06:22.515397 | controller | Tuesday 25 November 2025  14:06:14 +0000 (0:00:00.018)       0:00:58.177 ******
2025-11-25 14:06:22.515408 | controller | changed: [instance]
2025-11-25 14:06:23.070315 | controller |
2025-11-25 14:06:23.070345 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-11-25 14:06:23.070353 | controller | Tuesday 25 November 2025  14:06:22 +0000 (0:00:07.627)       0:01:05.805 ******
2025-11-25 14:06:23.070363 | controller | ok: [instance]
2025-11-25 14:06:23.271791 | controller |
2025-11-25 14:06:23.271822 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-11-25 14:06:23.271830 | controller | Tuesday 25 November 2025  14:06:23 +0000 (0:00:00.555)       0:01:06.361 ******
2025-11-25 14:06:23.271841 | controller | changed: [instance]
2025-11-25 14:06:23.271864 | controller |
2025-11-25 14:06:23.271872 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-11-25 14:06:23.271969 | controller | Tuesday 25 November 2025  14:06:23 +0000 (0:00:00.201)       0:01:06.562 ******
2025-11-25 14:06:29.249806 | controller | changed: [instance]
2025-11-25 14:06:29.249879 | controller |
2025-11-25 14:06:29.250071 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-11-25 14:06:29.281358 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:05.978)       0:01:12.540 ******
2025-11-25 14:06:29.281385 | controller | skipping: [instance]
2025-11-25 14:06:29.524324 | controller |
2025-11-25 14:06:29.524357 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-11-25 14:06:29.524367 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.031)       0:01:12.572 ******
2025-11-25 14:06:29.524379 | controller | changed: [instance]
2025-11-25 14:06:29.791618 | controller |
2025-11-25 14:06:29.791650 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-11-25 14:06:29.791658 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.242)       0:01:12.815 ******
2025-11-25 14:06:29.791669 | controller | changed: [instance]
2025-11-25 14:06:29.807344 | controller |
2025-11-25 14:06:29.807371 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-11-25 14:06:29.807380 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.267)       0:01:13.082 ******
2025-11-25 14:06:29.807389 | controller | skipping: [instance]
2025-11-25 14:06:29.822319 | controller |
2025-11-25 14:06:29.822344 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-11-25 14:06:29.822353 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.015)       0:01:13.098 ******
2025-11-25 14:06:29.822365 | controller | skipping: [instance]
2025-11-25 14:06:29.837548 | controller |
2025-11-25 14:06:29.837567 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-11-25 14:06:29.837575 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.014)       0:01:13.113 ******
2025-11-25 14:06:29.837583 | controller | skipping: [instance]
2025-11-25 14:06:29.852292 | controller |
2025-11-25 14:06:29.852314 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-11-25 14:06:29.852323 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.015)       0:01:13.128 ******
2025-11-25 14:06:29.852334 | controller | skipping: [instance]
2025-11-25 14:06:29.866778 | controller |
2025-11-25 14:06:29.866821 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-11-25 14:06:29.866832 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.014)       0:01:13.143 ******
2025-11-25 14:06:29.866846 | controller | skipping: [instance]
2025-11-25 14:06:29.885697 | controller |
2025-11-25 14:06:29.885720 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-11-25 14:06:29.885736 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.014)       0:01:13.157 ******
2025-11-25 14:06:29.885746 | controller | skipping: [instance]
2025-11-25 14:06:29.885872 | controller |
2025-11-25 14:06:29.886032 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-11-25 14:06:29.886176 | controller | Tuesday 25 November 2025  14:06:29 +0000 (0:00:00.019)       0:01:13.177 ******
2025-11-25 14:06:30.849836 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-11-25 14:06:30.849901 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-11-25 14:06:30.849911 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-11-25 14:06:30.849919 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-11-25 14:06:30.849928 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-11-25 14:06:30.849954 | controller |
2025-11-25 14:06:30.850177 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-11-25 14:06:31.040187 | controller | Tuesday 25 November 2025  14:06:30 +0000 (0:00:00.963)       0:01:14.140 ******
2025-11-25 14:06:31.040223 | controller | ok: [instance]
2025-11-25 14:06:31.219243 | controller |
2025-11-25 14:06:31.219275 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-11-25 14:06:31.219290 | controller | Tuesday 25 November 2025  14:06:31 +0000 (0:00:00.190)       0:01:14.331 ******
2025-11-25 14:06:31.219300 | controller | changed: [instance]
2025-11-25 14:06:31.398721 | controller |
2025-11-25 14:06:31.398755 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-11-25 14:06:31.398764 | controller | Tuesday 25 November 2025  14:06:31 +0000 (0:00:00.178)       0:01:14.510 ******
2025-11-25 14:06:31.398775 | controller | ok: [instance]
2025-11-25 14:06:31.425778 | controller |
2025-11-25 14:06:31.425807 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-11-25 14:06:31.425818 | controller | Tuesday 25 November 2025  14:06:31 +0000 (0:00:00.179)       0:01:14.689 ******
2025-11-25 14:06:31.425829 | controller | skipping: [instance]
2025-11-25 14:06:31.426038 | controller |
2025-11-25 14:06:31.426079 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-11-25 14:06:31.442348 | controller | Tuesday 25 November 2025  14:06:31 +0000 (0:00:00.027)       0:01:14.717 ******
2025-11-25 14:06:31.442382 | controller | skipping: [instance]
2025-11-25 14:06:31.461816 | controller |
2025-11-25 14:06:31.461865 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-11-25 14:06:31.461876 | controller | Tuesday 25 November 2025  14:06:31 +0000 (0:00:00.016)       0:01:14.733 ******
2025-11-25 14:06:31.461891 | controller | ok: [instance]
2025-11-25 14:06:31.487625 | controller |
2025-11-25 14:06:31.487654 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-11-25 14:06:31.487662 | controller | Tuesday 25 November 2025  14:06:31 +0000 (0:00:00.019)       0:01:14.752 ******
2025-11-25 14:06:31.487671 | controller | skipping: [instance]
2025-11-25 14:06:39.212389 | controller |
2025-11-25 14:06:39.212437 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-11-25 14:06:39.212446 | controller | Tuesday 25 November 2025  14:06:31 +0000 (0:00:00.025)       0:01:14.778 ******
2025-11-25 14:06:39.212457 | controller | changed: [instance]
2025-11-25 14:06:39.212493 | controller |
2025-11-25 14:06:39.212675 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-11-25 14:06:39.212722 | controller | Tuesday 25 November 2025  14:06:39 +0000 (0:00:07.724)       0:01:22.503 ******
2025-11-25 14:06:39.416398 | controller | changed: [instance]
2025-11-25 14:06:39.416560 | controller |
2025-11-25 14:06:39.416573 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-11-25 14:06:39.784154 | controller | Tuesday 25 November 2025  14:06:39 +0000 (0:00:00.203)       0:01:22.707 ******
2025-11-25 14:06:39.784190 | controller | changed: [instance]
2025-11-25 14:06:40.401833 | controller |
2025-11-25 14:06:40.401870 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-11-25 14:06:40.401879 | controller | Tuesday 25 November 2025  14:06:39 +0000 (0:00:00.367)       0:01:23.075 ******
2025-11-25 14:06:40.401898 | controller | changed: [instance]
2025-11-25 14:06:40.605160 | controller |
2025-11-25 14:06:40.605197 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-11-25 14:06:40.605206 | controller | Tuesday 25 November 2025  14:06:40 +0000 (0:00:00.617)       0:01:23.692 ******
2025-11-25 14:06:40.605217 | controller | ok: [instance]
2025-11-25 14:06:41.284590 | controller |
2025-11-25 14:06:41.284632 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-11-25 14:06:41.284642 | controller | Tuesday 25 November 2025  14:06:40 +0000 (0:00:00.203)       0:01:23.895 ******
2025-11-25 14:06:41.284655 | controller | ok: [instance]
2025-11-25 14:06:42.239795 | controller |
2025-11-25 14:06:42.239827 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-11-25 14:06:42.239835 | controller | Tuesday 25 November 2025  14:06:41 +0000 (0:00:00.680)       0:01:24.575 ******
2025-11-25 14:06:42.239845 | controller | changed: [instance]
2025-11-25 14:06:42.322806 | controller |
2025-11-25 14:06:42.322830 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-11-25 14:06:42.322839 | controller | Tuesday 25 November 2025  14:06:42 +0000 (0:00:00.955)       0:01:25.530 ******
2025-11-25 14:06:42.322851 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-11-25 14:06:42.322880 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-11-25 14:06:42.322887 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-11-25 14:06:42.322894 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-11-25 14:06:42.322900 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-11-25 14:06:42.322907 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-11-25 14:06:42.322913 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-11-25 14:06:42.322920 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-11-25 14:06:42.322926 | controller |
2025-11-25 14:06:42.322935 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-11-25 14:06:42.322943 | controller | Tuesday 25 November 2025  14:06:42 +0000 (0:00:00.082)       0:01:25.612 ******
2025-11-25 14:06:42.662335 | controller | ok: [instance]
2025-11-25 14:06:43.026934 | controller |
2025-11-25 14:06:43.027018 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-11-25 14:06:43.027030 | controller | Tuesday 25 November 2025  14:06:42 +0000 (0:00:00.339)       0:01:25.952 ******
2025-11-25 14:06:43.027067 | controller | ok: [instance]
2025-11-25 14:06:43.353888 | controller |
2025-11-25 14:06:43.353963 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-11-25 14:06:43.353974 | controller | Tuesday 25 November 2025  14:06:43 +0000 (0:00:00.365)       0:01:26.317 ******
2025-11-25 14:06:43.353992 | controller | ok: [instance]
2025-11-25 14:06:43.693633 | controller |
2025-11-25 14:06:43.693663 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-11-25 14:06:43.693671 | controller | Tuesday 25 November 2025  14:06:43 +0000 (0:00:00.327)       0:01:26.644 ******
2025-11-25 14:06:43.693697 | controller | ok: [instance]
2025-11-25 14:06:44.041811 | controller |
2025-11-25 14:06:44.041847 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-11-25 14:06:44.041857 | controller | Tuesday 25 November 2025  14:06:43 +0000 (0:00:00.339)       0:01:26.984 ******
2025-11-25 14:06:44.041869 | controller | ok: [instance]
2025-11-25 14:06:44.041905 | controller |
2025-11-25 14:06:44.041915 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-11-25 14:06:44.387141 | controller | Tuesday 25 November 2025  14:06:44 +0000 (0:00:00.348)       0:01:27.332 ******
2025-11-25 14:06:44.387175 | controller | ok: [instance]
2025-11-25 14:06:44.741745 | controller |
2025-11-25 14:06:44.741802 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-11-25 14:06:44.741811 | controller | Tuesday 25 November 2025  14:06:44 +0000 (0:00:00.345)       0:01:27.678 ******
2025-11-25 14:06:44.741826 | controller | ok: [instance]
2025-11-25 14:06:46.078103 | controller |
2025-11-25 14:06:46.078134 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-11-25 14:06:46.078142 | controller | Tuesday 25 November 2025  14:06:44 +0000 (0:00:00.354)       0:01:28.032 ******
2025-11-25 14:06:46.078152 | controller | ok: [instance]
2025-11-25 14:06:46.402712 | controller |
2025-11-25 14:06:46.402743 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-11-25 14:06:46.402753 | controller | Tuesday 25 November 2025  14:06:46 +0000 (0:00:01.336)       0:01:29.369 ******
2025-11-25 14:06:46.402762 | controller | ok: [instance]
2025-11-25 14:06:46.726769 | controller |
2025-11-25 14:06:46.726801 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-11-25 14:06:46.726810 | controller | Tuesday 25 November 2025  14:06:46 +0000 (0:00:00.324)       0:01:29.693 ******
2025-11-25 14:06:46.726822 | controller | ok: [instance]
2025-11-25 14:06:46.727184 | controller |
2025-11-25 14:06:47.057790 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-11-25 14:06:47.057826 | controller | Tuesday 25 November 2025  14:06:46 +0000 (0:00:00.324)       0:01:30.018 ******
2025-11-25 14:06:47.057841 | controller | ok: [instance]
2025-11-25 14:06:48.379481 | controller |
2025-11-25 14:06:48.379513 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-11-25 14:06:48.379524 | controller | Tuesday 25 November 2025  14:06:47 +0000 (0:00:00.330)       0:01:30.348 ******
2025-11-25 14:06:48.379536 | controller | ok: [instance]
2025-11-25 14:06:48.697417 | controller |
2025-11-25 14:06:48.697466 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-11-25 14:06:48.697475 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:01.321)       0:01:31.670 ******
2025-11-25 14:06:48.697484 | controller | ok: [instance]
2025-11-25 14:06:49.015643 | controller |
2025-11-25 14:06:49.015691 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-11-25 14:06:49.015705 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:00.318)       0:01:31.988 ******
2025-11-25 14:06:49.015717 | controller | ok: [instance]
2025-11-25 14:06:49.330131 | controller |
2025-11-25 14:06:49.330159 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-11-25 14:06:49.330167 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.317)       0:01:32.306 ******
2025-11-25 14:06:49.330178 | controller | ok: [instance]
2025-11-25 14:06:49.648751 | controller |
2025-11-25 14:06:49.648787 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-11-25 14:06:49.648795 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.314)       0:01:32.621 ******
2025-11-25 14:06:49.648807 | controller | ok: [instance]
2025-11-25 14:06:50.963600 | controller |
2025-11-25 14:06:50.963633 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-11-25 14:06:50.963641 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.318)       0:01:32.939 ******
2025-11-25 14:06:50.963651 | controller | ok: [instance]
2025-11-25 14:06:51.284455 | controller |
2025-11-25 14:06:51.284488 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-11-25 14:06:51.284502 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:01.314)       0:01:34.254 ******
2025-11-25 14:06:51.284512 | controller | ok: [instance]
2025-11-25 14:06:51.604727 | controller |
2025-11-25 14:06:51.604760 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-11-25 14:06:51.604769 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.320)       0:01:34.575 ******
2025-11-25 14:06:51.604787 | controller | ok: [instance]
2025-11-25 14:06:51.923400 | controller |
2025-11-25 14:06:51.923442 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-11-25 14:06:51.923451 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.320)       0:01:34.895 ******
2025-11-25 14:06:51.923461 | controller | ok: [instance]
2025-11-25 14:06:52.242115 | controller |
2025-11-25 14:06:52.242145 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-11-25 14:06:52.242152 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.318)       0:01:35.214 ******
2025-11-25 14:06:52.242162 | controller | ok: [instance]
2025-11-25 14:06:52.558623 | controller |
2025-11-25 14:06:52.558653 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-11-25 14:06:52.558662 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.318)       0:01:35.533 ******
2025-11-25 14:06:52.558671 | controller | ok: [instance]
2025-11-25 14:06:52.876921 | controller |
2025-11-25 14:06:52.876951 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-11-25 14:06:52.876960 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.316)       0:01:35.849 ******
2025-11-25 14:06:52.876970 | controller | ok: [instance]
2025-11-25 14:06:53.195353 | controller |
2025-11-25 14:06:53.195388 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-11-25 14:06:53.195398 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.318)       0:01:36.167 ******
2025-11-25 14:06:53.195410 | controller | ok: [instance]
2025-11-25 14:06:53.214186 | controller |
2025-11-25 14:06:53.214209 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-11-25 14:06:53.214218 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.318)       0:01:36.486 ******
2025-11-25 14:06:53.214229 | controller | skipping: [instance]
2025-11-25 14:06:53.473790 | controller |
2025-11-25 14:06:53.473824 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-11-25 14:06:53.473834 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.019)       0:01:36.505 ******
2025-11-25 14:06:53.473846 | controller | ok: [instance]
2025-11-25 14:06:53.473878 | controller |
2025-11-25 14:06:53.473889 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-11-25 14:06:53.473914 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.259)       0:01:36.764 ******
2025-11-25 14:06:53.846377 | controller | ok: [instance]
2025-11-25 14:06:54.153632 | controller |
2025-11-25 14:06:54.153665 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-11-25 14:06:54.153674 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.372)       0:01:37.137 ******
2025-11-25 14:06:54.153733 | controller | changed: [instance]
2025-11-25 14:06:54.334476 | controller |
2025-11-25 14:06:54.334515 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-11-25 14:06:54.334523 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.307)       0:01:37.444 ******
2025-11-25 14:06:54.334533 | controller | [WARNING]: Reset is not implemented for this connection
2025-11-25 14:06:54.355840 | controller | changed: [instance]
2025-11-25 14:06:54.356771 | controller |
2025-11-25 14:06:54.356796 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-11-25 14:06:54.357719 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.176)       0:01:37.620 ******
2025-11-25 14:06:54.357737 | controller |
2025-11-25 14:06:54.357745 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:06:54.357751 | controller | instance                   : ok=75   changed=23   unreachable=0    failed=0    skipped=41   rescued=0    ignored=0
2025-11-25 14:06:54.357756 | controller |
2025-11-25 14:06:54.357762 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.026)       0:01:37.646 ******
2025-11-25 14:06:54.357767 | controller | ===============================================================================
2025-11-25 14:06:54.357772 | controller | test_deps : Install selinux python libs -------------------------------- 40.40s
2025-11-25 14:06:54.357777 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.85s
2025-11-25 14:06:54.357786 | controller | libvirt_manager : Install packages required for using KVM --------------- 7.72s
2025-11-25 14:06:54.357819 | controller | ci_setup : Install needed packages -------------------------------------- 7.63s
2025-11-25 14:06:54.357827 | controller | ci_setup : Install openshift client ------------------------------------- 5.98s
2025-11-25 14:06:54.357832 | controller | libvirt_manager : Ensure read-only socket is enabled for network. ------- 1.34s
2025-11-25 14:06:54.357837 | controller | libvirt_manager : Ensure admin socket is enabled for nodedev. ----------- 1.32s
2025-11-25 14:06:54.357843 | controller | libvirt_manager : Ensure read-only socket is enabled for secret. -------- 1.31s
2025-11-25 14:06:54.357848 | controller | test_deps : Install python yaml libs ------------------------------------ 1.21s
2025-11-25 14:06:54.357853 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.09s
2025-11-25 14:06:54.357859 | controller | ci_setup : Manage directories ------------------------------------------- 0.96s
2025-11-25 14:06:54.357864 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.96s
2025-11-25 14:06:54.357871 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-11-25 14:06:54.357892 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.82s
2025-11-25 14:06:54.358416 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.81s
2025-11-25 14:06:54.413696 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-11-25 14:06:54.413729 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.68s
2025-11-25 14:06:54.413737 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-11-25 14:06:54.413742 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.62s
2025-11-25 14:06:54.413748 | controller | ci_setup : Gather version of openshift client --------------------------- 0.56s
2025-11-25 14:06:54.413758 | controller | INFO     Running default > converge
2025-11-25 14:06:54.816227 | controller |
2025-11-25 14:06:54.816422 | controller | PLAY [Converge] ****************************************************************
2025-11-25 14:06:54.816572 | controller |
2025-11-25 14:06:54.816730 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:06:54.816885 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.013)       0:00:00.013 ******
2025-11-25 14:06:55.631524 | controller | ok: [instance]
2025-11-25 14:06:55.671310 | controller |
2025-11-25 14:06:55.671355 | controller | TASK [Including the openshift provisioner node role.] **************************
2025-11-25 14:06:55.671363 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.815)       0:00:00.828 ******
2025-11-25 14:06:55.671373 | controller |
2025-11-25 14:06:55.966820 | controller | TASK [openshift_provisioner_node : Create the artifacts directory] *************
2025-11-25 14:06:55.966854 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.040)       0:00:00.868 ******
2025-11-25 14:06:55.966869 | controller | ok: [instance -> localhost]
2025-11-25 14:06:55.966954 | controller |
2025-11-25 14:06:55.966987 | controller | TASK [openshift_provisioner_node : Adding user kni] ****************************
2025-11-25 14:06:55.966999 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.295)       0:00:01.164 ******
2025-11-25 14:06:56.361769 | controller | changed: [instance]
2025-11-25 14:06:56.361811 | controller |
2025-11-25 14:06:56.361819 | controller | TASK [openshift_provisioner_node : Generate SSH keys] **************************
2025-11-25 14:06:56.361827 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.394)       0:00:01.559 ******
2025-11-25 14:06:56.719847 | controller | changed: [instance -> localhost]
2025-11-25 14:06:56.719877 | controller |
2025-11-25 14:06:56.719885 | controller | TASK [openshift_provisioner_node : Enable passwordless access] *****************
2025-11-25 14:06:56.719893 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.358)       0:00:01.917 ******
2025-11-25 14:06:57.083760 | controller | changed: [instance]
2025-11-25 14:06:57.084105 | controller |
2025-11-25 14:07:20.349052 | controller | TASK [openshift_provisioner_node : Install required packages need to execute openshift installer] ***
2025-11-25 14:07:20.349084 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.364)       0:00:02.281 ******
2025-11-25 14:07:20.349110 | controller | changed: [instance]
2025-11-25 14:07:20.755547 | controller |
2025-11-25 14:07:20.755585 | controller | TASK [openshift_provisioner_node : Create the external network connection] *****
2025-11-25 14:07:20.755594 | controller | Tuesday 25 November 2025  14:07:20 +0000 (0:00:23.264)       0:00:25.546 ******
2025-11-25 14:07:20.755606 | controller | changed: [instance]
2025-11-25 14:07:20.801245 | controller |
2025-11-25 14:07:20.801278 | controller | TASK [openshift_provisioner_node : Verify external network interface is defined] ***
2025-11-25 14:07:20.801286 | controller | Tuesday 25 November 2025  14:07:20 +0000 (0:00:00.406)       0:00:25.952 ******
2025-11-25 14:07:20.801296 | controller | skipping: [instance]
2025-11-25 14:07:20.801321 | controller |
2025-11-25 14:07:20.801330 | controller | TASK [openshift_provisioner_node : Add iface to the external network connection] ***
2025-11-25 14:07:20.801465 | controller | Tuesday 25 November 2025  14:07:20 +0000 (0:00:00.046)       0:00:25.998 ******
2025-11-25 14:07:20.827375 | controller | skipping: [instance]
2025-11-25 14:07:20.827442 | controller |
2025-11-25 14:07:20.827673 | controller | TASK [openshift_provisioner_node : Create the provisioning network connection] ***
2025-11-25 14:07:20.827719 | controller | Tuesday 25 November 2025  14:07:20 +0000 (0:00:00.026)       0:00:26.025 ******
2025-11-25 14:07:20.858452 | controller | skipping: [instance]
2025-11-25 14:07:20.888767 | controller |
2025-11-25 14:07:20.888798 | controller | TASK [openshift_provisioner_node : Add iface to the provisioning network connection] ***
2025-11-25 14:07:20.888806 | controller | Tuesday 25 November 2025  14:07:20 +0000 (0:00:00.030)       0:00:26.055 ******
2025-11-25 14:07:20.888817 | controller | skipping: [instance]
2025-11-25 14:07:20.888843 | controller |
2025-11-25 14:07:20.888949 | controller | TASK [openshift_provisioner_node : Check required virtualization networks] *****
2025-11-25 14:07:20.889039 | controller | Tuesday 25 November 2025  14:07:20 +0000 (0:00:00.030)       0:00:26.086 ******
2025-11-25 14:07:20.926134 | 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-11-25 14:07:20.926179 | 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-11-25 14:07:20.926189 | controller |
2025-11-25 14:07:20.926223 | controller | TASK [openshift_provisioner_node : Define virtual network baremetal] ***********
2025-11-25 14:07:20.926336 | controller | Tuesday 25 November 2025  14:07:20 +0000 (0:00:00.037)       0:00:26.123 ******
2025-11-25 14:07:21.773722 | controller | changed: [instance]
2025-11-25 14:07:21.773847 | controller |
2025-11-25 14:07:21.773858 | controller | TASK [openshift_provisioner_node : Start the network - baremetal] **************
2025-11-25 14:07:21.773867 | controller | Tuesday 25 November 2025  14:07:21 +0000 (0:00:00.847)       0:00:26.971 ******
2025-11-25 14:07:22.008363 | controller | changed: [instance]
2025-11-25 14:07:22.233774 | controller |
2025-11-25 14:07:22.233804 | controller | TASK [openshift_provisioner_node : Enable network autostart for baremetal] *****
2025-11-25 14:07:22.233812 | controller | Tuesday 25 November 2025  14:07:22 +0000 (0:00:00.234)       0:00:27.205 ******
2025-11-25 14:07:22.233822 | controller | changed: [instance]
2025-11-25 14:07:22.233894 | controller |
2025-11-25 14:07:22.233923 | controller | TASK [openshift_provisioner_node : Define virtual network provisioning] ********
2025-11-25 14:07:22.233935 | controller | Tuesday 25 November 2025  14:07:22 +0000 (0:00:00.225)       0:00:27.431 ******
2025-11-25 14:07:22.464823 | controller | changed: [instance]
2025-11-25 14:07:22.464881 | controller |
2025-11-25 14:07:22.465114 | controller | TASK [openshift_provisioner_node : Start the network - provisioning] ***********
2025-11-25 14:07:22.684305 | controller | Tuesday 25 November 2025  14:07:22 +0000 (0:00:00.231)       0:00:27.662 ******
2025-11-25 14:07:22.684341 | controller | changed: [instance]
2025-11-25 14:07:22.904573 | controller |
2025-11-25 14:07:22.904604 | controller | TASK [openshift_provisioner_node : Enable network autostart for provisioning] ***
2025-11-25 14:07:22.904612 | controller | Tuesday 25 November 2025  14:07:22 +0000 (0:00:00.219)       0:00:27.881 ******
2025-11-25 14:07:22.904622 | controller | changed: [instance]
2025-11-25 14:07:22.952820 | controller |
2025-11-25 14:07:22.952881 | controller | TASK [openshift_provisioner_node : Cache the MAC addresses] ********************
2025-11-25 14:07:22.952892 | controller | Tuesday 25 November 2025  14:07:22 +0000 (0:00:00.220)       0:00:28.101 ******
2025-11-25 14:07:22.952907 | controller | ok: [instance]
2025-11-25 14:07:23.224823 | controller |
2025-11-25 14:07:23.224864 | controller | TASK [openshift_provisioner_node : Read host SSH fingerprint] ******************
2025-11-25 14:07:23.224875 | controller | Tuesday 25 November 2025  14:07:22 +0000 (0:00:00.048)       0:00:28.149 ******
2025-11-25 14:07:23.224888 | controller | ok: [instance]
2025-11-25 14:07:23.255796 | controller |
2025-11-25 14:07:23.255839 | controller | TASK [openshift_provisioner_node : Set the role output parameters] *************
2025-11-25 14:07:23.255848 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.272)       0:00:28.422 ******
2025-11-25 14:07:23.255860 | controller | ok: [instance]
2025-11-25 14:07:23.255887 | controller |
2025-11-25 14:07:23.256125 | controller | TASK [openshift_provisioner_node : Cache provisioning bridge name] *************
2025-11-25 14:07:23.290776 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.031)       0:00:28.453 ******
2025-11-25 14:07:23.290796 | controller | skipping: [instance]
2025-11-25 14:07:23.290824 | controller |
2025-11-25 14:07:23.290832 | controller | TASK [Verify user is created] **************************************************
2025-11-25 14:07:23.290841 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.034)       0:00:28.488 ******
2025-11-25 14:07:23.568663 | controller | ok: [instance]
2025-11-25 14:07:23.661253 | controller |
2025-11-25 14:07:23.661280 | controller | TASK [Verify external network bridge exists] ***********************************
2025-11-25 14:07:23.661288 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.277)       0:00:28.765 ******
2025-11-25 14:07:23.661298 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-11-25 14:07:23.696486 | controller | changed: [instance]
2025-11-25 14:07:23.696516 | controller |
2025-11-25 14:07:23.696523 | controller | TASK [Perform cleanup] *********************************************************
2025-11-25 14:07:23.696529 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.093)       0:00:28.858 ******
2025-11-25 14:07:23.696538 | controller |
2025-11-25 14:07:23.724849 | controller | TASK [openshift_provisioner_node : Cleaning up the virtual networks] ***********
2025-11-25 14:07:23.724884 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.035)       0:00:28.894 ******
2025-11-25 14:07:23.724900 | 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-11-25 14:07:23.946812 | 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-11-25 14:07:23.946847 | controller |
2025-11-25 14:07:23.946857 | controller | TASK [openshift_provisioner_node : Stopping virtual network baremetal] *********
2025-11-25 14:07:23.946864 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.028)       0:00:28.922 ******
2025-11-25 14:07:23.946876 | controller | ok: [instance]
2025-11-25 14:07:24.164216 | controller |
2025-11-25 14:07:24.164252 | controller | TASK [openshift_provisioner_node : Remove virtual network baremetal] ***********
2025-11-25 14:07:24.164263 | controller | Tuesday 25 November 2025  14:07:23 +0000 (0:00:00.221)       0:00:29.144 ******
2025-11-25 14:07:24.164275 | controller | ok: [instance]
2025-11-25 14:07:24.381207 | controller |
2025-11-25 14:07:24.381239 | controller | TASK [openshift_provisioner_node : Stopping virtual network provisioning] ******
2025-11-25 14:07:24.381247 | controller | Tuesday 25 November 2025  14:07:24 +0000 (0:00:00.217)       0:00:29.361 ******
2025-11-25 14:07:24.381257 | controller | ok: [instance]
2025-11-25 14:07:24.597813 | controller |
2025-11-25 14:07:24.597842 | controller | TASK [openshift_provisioner_node : Remove virtual network provisioning] ********
2025-11-25 14:07:24.597851 | controller | Tuesday 25 November 2025  14:07:24 +0000 (0:00:00.217)       0:00:29.578 ******
2025-11-25 14:07:24.597861 | controller | ok: [instance]
2025-11-25 14:07:24.597885 | controller |
2025-11-25 14:07:24.598124 | controller | TASK [openshift_provisioner_node : Remove provisioning network] ****************
2025-11-25 14:07:24.814789 | controller | Tuesday 25 November 2025  14:07:24 +0000 (0:00:00.216)       0:00:29.795 ******
2025-11-25 14:07:24.814824 | controller | ok: [instance]
2025-11-25 14:07:24.814878 | controller |
2025-11-25 14:07:24.814906 | controller | TASK [openshift_provisioner_node : Remove the user] ****************************
2025-11-25 14:07:24.814919 | controller | Tuesday 25 November 2025  14:07:24 +0000 (0:00:00.216)       0:00:30.012 ******
2025-11-25 14:07:25.132802 | controller | changed: [instance]
2025-11-25 14:07:25.135090 | controller |
2025-11-25 14:07:25.135124 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:07:25.135137 | controller | instance                   : ok=28   changed=13   unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-11-25 14:07:25.188428 | controller |
2025-11-25 14:07:25.188459 | controller | Tuesday 25 November 2025  14:07:25 +0000 (0:00:00.317)       0:00:30.330 ******
2025-11-25 14:07:25.188468 | controller | ===============================================================================
2025-11-25 14:07:25.188474 | controller | openshift_provisioner_node : Install required packages need to execute openshift installer -- 23.26s
2025-11-25 14:07:25.188480 | controller | openshift_provisioner_node : Define virtual network baremetal ----------- 0.85s
2025-11-25 14:07:25.188485 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2025-11-25 14:07:25.188490 | controller | openshift_provisioner_node : Create the external network connection ----- 0.41s
2025-11-25 14:07:25.188496 | controller | openshift_provisioner_node : Adding user kni ---------------------------- 0.39s
2025-11-25 14:07:25.188512 | controller | openshift_provisioner_node : Enable passwordless access ----------------- 0.36s
2025-11-25 14:07:25.188518 | controller | openshift_provisioner_node : Generate SSH keys -------------------------- 0.36s
2025-11-25 14:07:25.188523 | controller | openshift_provisioner_node : Remove the user ---------------------------- 0.32s
2025-11-25 14:07:25.188534 | controller | openshift_provisioner_node : Create the artifacts directory ------------- 0.30s
2025-11-25 14:07:25.188540 | controller | Verify user is created -------------------------------------------------- 0.28s
2025-11-25 14:07:25.188545 | controller | openshift_provisioner_node : Read host SSH fingerprint ------------------ 0.27s
2025-11-25 14:07:25.188551 | controller | openshift_provisioner_node : Start the network - baremetal -------------- 0.23s
2025-11-25 14:07:25.188556 | controller | openshift_provisioner_node : Define virtual network provisioning -------- 0.23s
2025-11-25 14:07:25.188561 | controller | openshift_provisioner_node : Enable network autostart for baremetal ----- 0.23s
2025-11-25 14:07:25.188566 | controller | openshift_provisioner_node : Stopping virtual network baremetal --------- 0.22s
2025-11-25 14:07:25.188571 | controller | openshift_provisioner_node : Enable network autostart for provisioning --- 0.22s
2025-11-25 14:07:25.188577 | controller | openshift_provisioner_node : Start the network - provisioning ----------- 0.22s
2025-11-25 14:07:25.188582 | controller | openshift_provisioner_node : Remove virtual network baremetal ----------- 0.22s
2025-11-25 14:07:25.188588 | controller | openshift_provisioner_node : Stopping virtual network provisioning ------ 0.22s
2025-11-25 14:07:25.188593 | controller | openshift_provisioner_node : Remove virtual network provisioning -------- 0.22s
2025-11-25 14:07:25.188603 | controller | INFO     Running default > cleanup
2025-11-25 14:07:25.189062 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-11-25 14:07:25.189659 | controller | INFO     Writing /tmp/report.html report.
2025-11-25 14:07:25.598580 | [controller] Waiting on logger
2025-11-25 14:07:30.738385 | [controller] Waiting on logger
2025-11-25 14:07:41.170378 | [controller] Waiting on logger
2025-11-25 14:07:51.602412 | [controller] Waiting on logger
2025-11-25 14:07:55.267438 | [Zuul] Log Stream did not terminate
2025-11-25 14:07:55.267643 | controller | changed
2025-11-25 14:07:55.288249 | 
2025-11-25 14:07:55.288298 | PLAY RECAP
2025-11-25 14:07:55.288345 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:07:55.288382 | 
2025-11-25 14:07:55.348638 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:07:55.349443 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:07:55.872664 | 
2025-11-25 14:07:55.872768 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-25 14:07:55.893023 | 
2025-11-25 14:07:55.893098 | TASK [Filter out host if needed]
2025-11-25 14:07:55.901633 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-25 14:07:55.906088 | 
2025-11-25 14:07:55.906156 | TASK [Ensure file is present]
2025-11-25 14:07:56.290290 | controller | ok
2025-11-25 14:07:56.296937 | 
2025-11-25 14:07:56.297007 | TASK [Manage molecule report file]
2025-11-25 14:07:56.320481 | [controller] Waiting on logger
2025-11-25 14:08:05.655593 | [controller] Waiting on logger
2025-11-25 14:08:16.050320 | [controller] Waiting on logger
2025-11-25 14:08:26.482531 | [controller] Waiting on logger
2025-11-25 14:08:26.691018 | [Zuul] Log Stream did not terminate
2025-11-25 14:08:26.691193 | controller | changed
2025-11-25 14:08:26.696579 | 
2025-11-25 14:08:26.696651 | TASK [Check if we get ci-framework-data basedir]
2025-11-25 14:08:26.958413 | controller | ok
2025-11-25 14:08:26.963753 | 
2025-11-25 14:08:26.963825 | TASK [Create ci-framework-data log directory for zuul]
2025-11-25 14:08:27.338312 | controller | changed
2025-11-25 14:08:27.344282 | 
2025-11-25 14:08:27.344352 | TASK [Copy ci-framework interesting files]
2025-11-25 14:08:27.365098 | [controller] Waiting on logger
2025-11-25 14:08:32.473286 | controller | changed
2025-11-25 14:08:32.478987 | 
2025-11-25 14:08:32.479054 | TASK [Get SELinux listing]
2025-11-25 14:08:36.914462 | [controller] Waiting on logger
2025-11-25 14:08:37.505180 | controller | changed
2025-11-25 14:08:37.511561 | 
2025-11-25 14:08:37.511635 | TASK [Generate log index]
2025-11-25 14:08:38.695765 | controller | changed
2025-11-25 14:08:38.701065 | 
2025-11-25 14:08:38.701141 | TASK [Get some env related data]
2025-11-25 14:08:38.721880 | [controller] Waiting on logger
2025-11-25 14:08:49.138509 | [controller] Waiting on logger
2025-11-25 14:08:59.570393 | [controller] Waiting on logger
2025-11-25 14:09:08.914371 | [controller] Waiting on logger
2025-11-25 14:09:09.950009 | [Zuul] Log Stream did not terminate
2025-11-25 14:09:09.950248 | controller | changed
2025-11-25 14:09:09.956613 | 
2025-11-25 14:09:09.956677 | TASK [Generate list of logs to collect in home directory]
2025-11-25 14:09:10.340144 | controller | ok: All paths examined
2025-11-25 14:09:10.345584 | 
2025-11-25 14:09:10.345644 | LOOP [Copy logs from home directory]
2025-11-25 14:09:10.980664 | controller | changed:
2025-11-25 14:09:10.980881 | controller | {
2025-11-25 14:09:10.980931 | controller |   "atime": 1743544925.4788878,
2025-11-25 14:09:10.980959 | controller |   "ctime": 1743545329.1409318,
2025-11-25 14:09:10.980980 | controller |   "dev": 64513,
2025-11-25 14:09:10.980997 | controller |   "gid": 1000,
2025-11-25 14:09:10.981013 | controller |   "gr_name": "zuul",
2025-11-25 14:09:10.981027 | controller |   "inode": 4518807,
2025-11-25 14:09:10.981042 | controller |   "isblk": false,
2025-11-25 14:09:10.981057 | controller |   "ischr": false,
2025-11-25 14:09:10.981072 | controller |   "isdir": false,
2025-11-25 14:09:10.981090 | controller |   "isfifo": false,
2025-11-25 14:09:10.981106 | controller |   "isgid": false,
2025-11-25 14:09:10.981122 | controller |   "islnk": false,
2025-11-25 14:09:10.981137 | controller |   "isreg": true,
2025-11-25 14:09:10.981151 | controller |   "issock": false,
2025-11-25 14:09:10.981165 | controller |   "isuid": false,
2025-11-25 14:09:10.981180 | controller |   "mode": "0644",
2025-11-25 14:09:10.981195 | controller |   "mtime": 1743545329.1409318,
2025-11-25 14:09:10.981211 | controller |   "nlink": 1,
2025-11-25 14:09:10.981226 | controller |   "path": "/home/zuul/crc-setup.log",
2025-11-25 14:09:10.981242 | controller |   "pw_name": "zuul",
2025-11-25 14:09:10.981257 | controller |   "rgrp": true,
2025-11-25 14:09:10.981273 | controller |   "roth": true,
2025-11-25 14:09:10.981287 | controller |   "rusr": true,
2025-11-25 14:09:10.981301 | controller |   "size": 4108,
2025-11-25 14:09:10.981315 | controller |   "uid": 1000,
2025-11-25 14:09:10.981329 | controller |   "wgrp": false,
2025-11-25 14:09:10.981343 | controller |   "woth": false,
2025-11-25 14:09:10.981357 | controller |   "wusr": true,
2025-11-25 14:09:10.981370 | controller |   "xgrp": false,
2025-11-25 14:09:10.981398 | controller |   "xoth": false,
2025-11-25 14:09:10.981413 | controller |   "xusr": false
2025-11-25 14:09:10.981426 | controller | }
2025-11-25 14:09:11.614350 | controller | changed:
2025-11-25 14:09:11.614540 | controller | {
2025-11-25 14:09:11.614585 | controller |   "atime": 1743545331.0429637,
2025-11-25 14:09:11.614608 | controller |   "ctime": 1743545842.0111232,
2025-11-25 14:09:11.614626 | controller |   "dev": 64513,
2025-11-25 14:09:11.614645 | controller |   "gid": 1000,
2025-11-25 14:09:11.614662 | controller |   "gr_name": "zuul",
2025-11-25 14:09:11.614679 | controller |   "inode": 4194437,
2025-11-25 14:09:11.614694 | controller |   "isblk": false,
2025-11-25 14:09:11.614709 | controller |   "ischr": false,
2025-11-25 14:09:11.614724 | controller |   "isdir": false,
2025-11-25 14:09:11.614739 | controller |   "isfifo": false,
2025-11-25 14:09:11.614755 | controller |   "isgid": false,
2025-11-25 14:09:11.614770 | controller |   "islnk": false,
2025-11-25 14:09:11.614786 | controller |   "isreg": true,
2025-11-25 14:09:11.614801 | controller |   "issock": false,
2025-11-25 14:09:11.614815 | controller |   "isuid": false,
2025-11-25 14:09:11.614830 | controller |   "mode": "0644",
2025-11-25 14:09:11.614850 | controller |   "mtime": 1743545842.0111232,
2025-11-25 14:09:11.614865 | controller |   "nlink": 1,
2025-11-25 14:09:11.614880 | controller |   "path": "/home/zuul/crc-start.log",
2025-11-25 14:09:11.614902 | controller |   "pw_name": "zuul",
2025-11-25 14:09:11.614955 | controller |   "rgrp": true,
2025-11-25 14:09:11.614975 | controller |   "roth": true,
2025-11-25 14:09:11.614992 | controller |   "rusr": true,
2025-11-25 14:09:11.615008 | controller |   "size": 4023,
2025-11-25 14:09:11.615023 | controller |   "uid": 1000,
2025-11-25 14:09:11.615037 | controller |   "wgrp": false,
2025-11-25 14:09:11.615052 | controller |   "woth": false,
2025-11-25 14:09:11.615066 | controller |   "wusr": true,
2025-11-25 14:09:11.615080 | controller |   "xgrp": false,
2025-11-25 14:09:11.615094 | controller |   "xoth": false,
2025-11-25 14:09:11.615107 | controller |   "xusr": false
2025-11-25 14:09:11.615121 | controller | }
2025-11-25 14:09:12.236224 | controller | changed:
2025-11-25 14:09:12.236332 | controller | {
2025-11-25 14:09:12.236359 | controller |   "atime": 1764079479.2640247,
2025-11-25 14:09:12.236389 | controller |   "ctime": 1764079494.7372966,
2025-11-25 14:09:12.236409 | controller |   "dev": 64513,
2025-11-25 14:09:12.236426 | controller |   "gid": 1000,
2025-11-25 14:09:12.236442 | controller |   "gr_name": "zuul",
2025-11-25 14:09:12.236457 | controller |   "inode": 4686333,
2025-11-25 14:09:12.236472 | controller |   "isblk": false,
2025-11-25 14:09:12.236488 | controller |   "ischr": false,
2025-11-25 14:09:12.236503 | controller |   "isdir": false,
2025-11-25 14:09:12.236517 | controller |   "isfifo": false,
2025-11-25 14:09:12.236532 | controller |   "isgid": false,
2025-11-25 14:09:12.236546 | controller |   "islnk": false,
2025-11-25 14:09:12.236563 | controller |   "isreg": true,
2025-11-25 14:09:12.236579 | controller |   "issock": false,
2025-11-25 14:09:12.236593 | controller |   "isuid": false,
2025-11-25 14:09:12.236608 | controller |   "mode": "0644",
2025-11-25 14:09:12.236622 | controller |   "mtime": 1764079494.7372966,
2025-11-25 14:09:12.236635 | controller |   "nlink": 1,
2025-11-25 14:09:12.236649 | controller |   "path": "/home/zuul/ansible.log",
2025-11-25 14:09:12.236663 | controller |   "pw_name": "zuul",
2025-11-25 14:09:12.236677 | controller |   "rgrp": true,
2025-11-25 14:09:12.236691 | controller |   "roth": true,
2025-11-25 14:09:12.236704 | controller |   "rusr": true,
2025-11-25 14:09:12.236717 | controller |   "size": 6749,
2025-11-25 14:09:12.236730 | controller |   "uid": 1000,
2025-11-25 14:09:12.236744 | controller |   "wgrp": false,
2025-11-25 14:09:12.236758 | controller |   "woth": false,
2025-11-25 14:09:12.236771 | controller |   "wusr": true,
2025-11-25 14:09:12.236785 | controller |   "xgrp": false,
2025-11-25 14:09:12.236799 | controller |   "xoth": false,
2025-11-25 14:09:12.236813 | controller |   "xusr": false
2025-11-25 14:09:12.236826 | controller | }
2025-11-25 14:09:12.247262 | 
2025-11-25 14:09:12.247332 | TASK [Copy crio stats log file]
2025-11-25 14:09:12.260771 | controller | skipping: Conditional result was False
2025-11-25 14:09:12.266316 | 
2025-11-25 14:09:12.266392 | TASK [Get SELinux related data]
2025-11-25 14:09:12.290185 | [controller] Waiting on logger
2025-11-25 14:09:18.498404 | controller | ERROR
2025-11-25 14:09:18.498556 | controller | {
2025-11-25 14:09:18.498588 | controller |   "delta": "0:00:00.012162",
2025-11-25 14:09:18.498609 | controller |   "end": "2025-11-25 14:09:12.586936",
2025-11-25 14:09:18.498626 | controller |   "msg": "non-zero return code",
2025-11-25 14:09:18.498651 | controller |   "rc": 1,
2025-11-25 14:09:18.498668 | controller |   "start": "2025-11-25 14:09:12.574774"
2025-11-25 14:09:18.498684 | controller | }
2025-11-25 14:09:18.498707 | controller | ERROR: Ignoring Errors
2025-11-25 14:09:18.503952 | 
2025-11-25 14:09:18.504018 | TASK [Create system configuration directory]
2025-11-25 14:09:18.776202 | controller | changed
2025-11-25 14:09:18.781104 | 
2025-11-25 14:09:18.781167 | TASK [Get some of the system configurations]
2025-11-25 14:09:18.804308 | [controller] Waiting on logger
2025-11-25 14:09:29.202833 | [controller] Waiting on logger
2025-11-25 14:09:39.634798 | [controller] Waiting on logger
2025-11-25 14:09:48.978639 | [controller] Waiting on logger
2025-11-25 14:09:49.113550 | [Zuul] Log Stream did not terminate
2025-11-25 14:09:49.113800 | controller | changed
2025-11-25 14:09:49.119743 | 
2025-11-25 14:09:49.119816 | TASK [Copy generated documentation if available]
2025-11-25 14:09:49.143595 | controller | skipping: Conditional result was False
2025-11-25 14:09:49.149030 | 
2025-11-25 14:09:49.149098 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-25 14:09:49.172509 | controller | skipping: Conditional result was False
2025-11-25 14:09:49.177783 | 
2025-11-25 14:09:49.177850 | TASK [Compress logs bigger than 2MB]
2025-11-25 14:09:52.306550 | [controller] Waiting on logger
2025-11-25 14:10:08.690512 | [controller] Waiting on logger
2025-11-25 14:10:10.930457 | [controller] Waiting on logger
2025-11-25 14:10:19.489583 | [Zuul] Log Stream did not terminate
2025-11-25 14:10:19.489818 | controller | changed
2025-11-25 14:10:19.495258 | 
2025-11-25 14:10:19.495323 | TASK [Copy files from workspace on node]
2025-11-25 14:10:19.513757 | controller | ok
2025-11-25 14:10:19.537333 | 
2025-11-25 14:10:19.537413 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:10:19.551723 | controller | skipping: Conditional result was False
2025-11-25 14:10:19.557652 | 
2025-11-25 14:10:19.557726 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:10:19.586076 | controller | ok
2025-11-25 14:10:19.591131 | 
2025-11-25 14:10:19.591195 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:10:19.786215 | controller -> localhost | ok: "/var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/logs"
2025-11-25 14:10:19.786582 | controller -> localhost | changed: All items complete
2025-11-25 14:10:19.786623 | 
2025-11-25 14:10:19.967514 | controller -> localhost | changed: "/var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/artifacts"
2025-11-25 14:10:20.142706 | controller -> localhost | changed: "/var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/docs"
2025-11-25 14:10:20.158980 | 
2025-11-25 14:10:20.159156 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:10:20.802876 | controller | changed:
2025-11-25 14:10:20.803079 | controller | .d..t...... ./
2025-11-25 14:10:20.803111 | controller | >f+++++++++ README.html
2025-11-25 14:10:20.803134 | controller | >f+++++++++ ansible-execution.log
2025-11-25 14:10:20.803156 | controller | >f+++++++++ ansible.log
2025-11-25 14:10:20.803176 | controller | >f+++++++++ crc-setup.log
2025-11-25 14:10:20.803196 | controller | >f+++++++++ crc-start.log
2025-11-25 14:10:20.803214 | controller | >f+++++++++ dmesg.log
2025-11-25 14:10:20.803233 | controller | >f+++++++++ installed-pkgs.log
2025-11-25 14:10:20.803251 | controller | >f+++++++++ python.log
2025-11-25 14:10:20.803269 | controller | >f+++++++++ registries.conf
2025-11-25 14:10:20.803288 | controller | >f+++++++++ report.html
2025-11-25 14:10:20.803305 | controller | >f+++++++++ selinux-denials.log
2025-11-25 14:10:20.803323 | controller | >f+++++++++ selinux-listing.log
2025-11-25 14:10:20.803339 | controller | cd+++++++++ ci-framework-data/
2025-11-25 14:10:20.803356 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-25 14:10:20.803373 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_verify_external_network.sh
2025-11-25 14:10:20.803391 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw
2025-11-25 14:10:20.803421 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw.pub
2025-11-25 14:10:20.803440 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-25 14:10:20.803458 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-11-25 14:10:20.803476 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-11-25 14:10:20.803498 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-11-25 14:10:20.803517 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-25 14:10:20.803535 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-25 14:10:20.803553 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-25 14:10:20.803571 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-25 14:10:20.803589 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-25 14:10:20.803606 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-11-25 14:10:20.803623 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-25 14:10:20.803640 | controller | cd+++++++++ registries.conf.d/
2025-11-25 14:10:20.803657 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-25 14:10:20.803673 | controller | cd+++++++++ system-config/
2025-11-25 14:10:20.803689 | controller | cd+++++++++ system-config/libvirt/
2025-11-25 14:10:20.803705 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-25 14:10:20.803721 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-25 14:10:20.803737 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-11-25 14:10:20.803753 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-11-25 14:10:20.803769 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-11-25 14:10:20.803784 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-11-25 14:10:20.803800 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-11-25 14:10:20.803816 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-11-25 14:10:20.803832 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-11-25 14:10:20.803848 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-11-25 14:10:20.803864 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-11-25 14:10:20.803879 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-11-25 14:10:20.803895 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-11-25 14:10:20.803931 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-11-25 14:10:20.803952 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-11-25 14:10:20.803970 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-11-25 14:10:21.255048 | controller | changed: .d..t...... ./
2025-11-25 14:10:21.700006 | controller | changed: .d..t...... ./
2025-11-25 14:10:21.715456 | 
2025-11-25 14:10:21.715572 | TASK [Return artifact to Zuul]
2025-11-25 14:10:21.744870 | controller | ok
2025-11-25 14:10:21.763871 | 
2025-11-25 14:10:21.763948 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-25 14:10:21.764044 | 
2025-11-25 14:10:21.764072 | PLAY RECAP
2025-11-25 14:10:21.764111 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-25 14:10:21.764132 | 
2025-11-25 14:10:21.848937 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:10:21.849729 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:10:22.370204 | 
2025-11-25 14:10:22.370310 | PLAY [all]
2025-11-25 14:10:22.389091 | 
2025-11-25 14:10:22.389164 | TASK [include_role : fetch-output]
2025-11-25 14:10:22.417927 | controller | ok
2025-11-25 14:10:22.434781 | 
2025-11-25 14:10:22.434860 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:10:22.479015 | controller | skipping: Conditional result was False
2025-11-25 14:10:22.484706 | 
2025-11-25 14:10:22.484775 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:10:22.513900 | controller | ok
2025-11-25 14:10:22.518763 | 
2025-11-25 14:10:22.518830 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:10:22.851828 | controller -> localhost | ok: "/var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/logs"
2025-11-25 14:10:23.064396 | controller -> localhost | ok: "/var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/artifacts"
2025-11-25 14:10:23.247018 | controller -> localhost | ok: "/var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/docs"
2025-11-25 14:10:23.260358 | 
2025-11-25 14:10:23.260483 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:10:23.859758 | controller | ok
2025-11-25 14:10:23.859987 | controller | ok: All items complete
2025-11-25 14:10:23.860018 | 
2025-11-25 14:10:24.317093 | controller | ok
2025-11-25 14:10:24.760170 | controller | ok
2025-11-25 14:10:24.774038 | 
2025-11-25 14:10:24.774160 | TASK [include_role : fetch-output-openshift]
2025-11-25 14:10:24.788433 | controller | skipping: Conditional result was False
2025-11-25 14:10:24.794387 | 
2025-11-25 14:10:24.794495 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-25 14:10:25.137204 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006715
2025-11-25 14:10:25.329801 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006649
2025-11-25 14:10:25.361281 | 
2025-11-25 14:10:25.361398 | PLAY [all]
2025-11-25 14:10:25.375763 | 
2025-11-25 14:10:25.375840 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-25 14:10:25.842955 | controller | changed
2025-11-25 14:10:25.864600 | 
2025-11-25 14:10:25.864679 | PLAY RECAP
2025-11-25 14:10:25.864725 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-25 14:10:25.864748 | 
2025-11-25 14:10:25.940109 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:10:25.940893 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-25 14:10:26.500589 | 
2025-11-25 14:10:26.500708 | PLAY [localhost]
2025-11-25 14:10:26.518116 | 
2025-11-25 14:10:26.518191 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-25 14:10:26.829489 | localhost | changed
2025-11-25 14:10:26.833886 | 
2025-11-25 14:10:26.833982 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-25 14:10:26.861625 | localhost | ok
2025-11-25 14:10:26.869381 | 
2025-11-25 14:10:26.869462 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-25 14:10:27.170321 | localhost | changed
2025-11-25 14:10:27.175962 | 
2025-11-25 14:10:27.176036 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-25 14:10:27.729463 | localhost | changed
2025-11-25 14:10:27.734524 | 
2025-11-25 14:10:27.734613 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-25 14:10:28.076314 | localhost | Identity added: /var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/tmp/ansible.fbvglyzo (/var/lib/zuul/builds/bc91cb8b90064488a2efdbd553c72ed2/work/tmp/ansible.fbvglyzo)
2025-11-25 14:10:28.076511 | localhost | ok: Runtime: 0:00:00.006171
2025-11-25 14:10:28.080876 | 
2025-11-25 14:10:28.080967 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-25 14:10:28.301065 | localhost | ok: Runtime: 0:00:00.004651
2025-11-25 14:10:28.305704 | 
2025-11-25 14:10:28.305779 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-25 14:10:28.353276 | localhost | changed
2025-11-25 14:10:28.358082 | 
2025-11-25 14:10:28.358148 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-25 14:10:28.678355 | localhost | changed
2025-11-25 14:10:28.697278 | 
2025-11-25 14:10:28.697336 | PLAY [localhost]
2025-11-25 14:10:28.708430 | 
2025-11-25 14:10:28.708492 | TASK [Generate bulk log download script]
2025-11-25 14:10:28.726362 | localhost | ok
2025-11-25 14:10:28.737450 | 
2025-11-25 14:10:28.737517 | TASK [local-log-download : Check API endpoint is defined]
2025-11-25 14:10:28.764356 | localhost | ok: All assertions passed
2025-11-25 14:10:28.768724 | 
2025-11-25 14:10:28.768789 | TASK [local-log-download : Create download script]
2025-11-25 14:10:29.126036 | localhost -> localhost | changed
2025-11-25 14:10:29.134591 | 
2025-11-25 14:10:29.134657 | TASK [Register quick-download link]
2025-11-25 14:10:29.151149 | localhost | ok
2025-11-25 14:10:29.186662 | 
2025-11-25 14:10:29.186738 | PLAY [logserver.rdoproject.org]
2025-11-25 14:10:29.195869 | 
2025-11-25 14:10:29.195948 | TASK [Set zuul-log-path fact]
2025-11-25 14:10:29.211365 | logserver.rdoproject.org | ok
2025-11-25 14:10:29.220262 | 
2025-11-25 14:10:29.220327 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:10:29.246136 | logserver.rdoproject.org | ok
2025-11-25 14:10:29.251717 | 
2025-11-25 14:10:29.251781 | TASK [upload-logs : Create log directories]
2025-11-25 14:10:30.998748 | logserver.rdoproject.org | changed
2025-11-25 14:10:31.001960 | 
2025-11-25 14:10:31.002026 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-25 14:10:31.217945 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004349
2025-11-25 14:10:31.222509 | 
2025-11-25 14:10:31.222578 | TASK [upload-logs : Upload logs to log server]
2025-11-25 14:10:32.698370 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-25 14:10:32.701221 | 
2025-11-25 14:10:32.701290 | LOOP [upload-logs : Compress console log and json output]
2025-11-25 14:10:32.739128 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:10:32.747174 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:10:32.759004 | 
2025-11-25 14:10:32.759080 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-25 14:10:32.792789 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:10:32.793028 | 
2025-11-25 14:10:32.796305 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:10:32.806851 | 
2025-11-25 14:10:32.806939 | LOOP [upload-logs : Upload console log and json output]