2025-09-29 19:08:25.808582 | Job console starting...
2025-09-29 19:08:26.045171 | Updating repositories
2025-09-29 19:08:26.774331 | Preparing job workspace
2025-09-29 19:08:34.914268 | Running Ansible setup...
2025-09-29 19:08:38.661201 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:08:39.238472 | 
2025-09-29 19:08:39.238576 | PLAY [localhost]
2025-09-29 19:08:39.247776 | 
2025-09-29 19:08:39.247852 | TASK [Gathering Facts]
2025-09-29 19:08:40.181047 | localhost | ok
2025-09-29 19:08:40.207223 | 
2025-09-29 19:08:40.207350 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 19:08:40.977220 | localhost -> localhost | changed
2025-09-29 19:08:40.983839 | 
2025-09-29 19:08:40.984072 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 19:08:41.768418 | localhost -> localhost | changed
2025-09-29 19:08:41.776851 | 
2025-09-29 19:08:41.776937 | TASK [Setup log path fact]
2025-09-29 19:08:41.796656 | localhost | ok
2025-09-29 19:08:41.808174 | 
2025-09-29 19:08:41.808244 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:08:41.835308 | localhost | ok
2025-09-29 19:08:41.843024 | 
2025-09-29 19:08:41.843105 | TASK [emit-job-header : Print job information]
2025-09-29 19:08:41.870925 | # Job Information
2025-09-29 19:08:41.871056 | Ansible Version: 2.15.12
2025-09-29 19:08:41.871088 | Job: cifmw-molecule-shiftstack
2025-09-29 19:08:41.871111 | Pipeline: github-check
2025-09-29 19:08:41.871131 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 19:08:41.871151 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3332
2025-09-29 19:08:41.871173 | Log URL (when completed): https://logserver.rdoproject.org/438/rdoproject.org/438c0486a15642b9ae7d28f93f5c758c/
2025-09-29 19:08:41.871193 | Event ID: 01c598b0-9d65-11f0-8b9c-9f217b126b73
2025-09-29 19:08:41.874975 | 
2025-09-29 19:08:41.875050 | LOOP [emit-job-header : Print node information]
2025-09-29 19:08:41.968882 | localhost | ok:
2025-09-29 19:08:41.969105 | localhost | # Node Information
2025-09-29 19:08:41.969157 | localhost | Inventory Hostname: controller
2025-09-29 19:08:41.969194 | localhost | Hostname: np0005460763
2025-09-29 19:08:41.969223 | localhost | Username: zuul
2025-09-29 19:08:41.969251 | localhost | Distro: CentOS 9
2025-09-29 19:08:41.969276 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 19:08:41.969300 | localhost | Region: regionOne
2025-09-29 19:08:41.969323 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-09-29 19:08:41.969351 | localhost | Product Name: OpenStack Compute
2025-09-29 19:08:41.969375 | localhost | Interface IP: 192.168.26.82
2025-09-29 19:08:41.998548 | 
2025-09-29 19:08:41.998647 | PLAY [all]
2025-09-29 19:08:42.005249 | 
2025-09-29 19:08:42.005331 | TASK [Gather network facts]
2025-09-29 19:08:42.421335 | controller | ok
2025-09-29 19:08:42.436382 | 
2025-09-29 19:08:42.436456 | TASK [include_role : start-zuul-console]
2025-09-29 19:08:42.454139 | controller | ok
2025-09-29 19:08:42.465638 | 
2025-09-29 19:08:42.465704 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 19:08:42.828396 | controller | ok
2025-09-29 19:08:42.836657 | 
2025-09-29 19:08:42.836720 | TASK [include_role : add-build-sshkey]
2025-09-29 19:08:42.854161 | controller | ok
2025-09-29 19:08:42.866393 | 
2025-09-29 19:08:42.866460 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 19:08:43.083698 | controller -> localhost | ok
2025-09-29 19:08:43.088873 | 
2025-09-29 19:08:43.088961 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 19:08:43.107244 | controller | ok
2025-09-29 19:08:43.119734 | controller | included: /var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 19:08:43.125523 | 
2025-09-29 19:08:43.125588 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 19:08:43.587746 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 19:08:43.588148 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/438c0486a15642b9ae7d28f93f5c758c_id_rsa.
2025-09-29 19:08:43.588198 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/438c0486a15642b9ae7d28f93f5c758c_id_rsa.pub.
2025-09-29 19:08:43.588228 | controller -> localhost | The key fingerprint is:
2025-09-29 19:08:43.588252 | controller -> localhost | SHA256:sZjr1pdfgps5dzRvaL8Z89KFRDccupaMCAAgPLOHyYM zuul-build-sshkey
2025-09-29 19:08:43.588275 | controller -> localhost | The key's randomart image is:
2025-09-29 19:08:43.588295 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 19:08:43.588314 | controller -> localhost | |o.....        ...|
2025-09-29 19:08:43.588334 | controller -> localhost | |.+    .       oo.|
2025-09-29 19:08:43.588352 | controller -> localhost | |o *    ..    o ..|
2025-09-29 19:08:43.588372 | controller -> localhost | |E* .   o.o. o +  |
2025-09-29 19:08:43.588392 | controller -> localhost | |  o   o S. . * . |
2025-09-29 19:08:43.588411 | controller -> localhost | |       .   .. + .|
2025-09-29 19:08:43.588430 | controller -> localhost | |      ..  .....B.|
2025-09-29 19:08:43.588450 | controller -> localhost | |     .. . ++.o= O|
2025-09-29 19:08:43.588469 | controller -> localhost | |     ..  .++.o *+|
2025-09-29 19:08:43.588487 | controller -> localhost | +----[SHA256]-----+
2025-09-29 19:08:43.588534 | controller -> localhost | ok: Runtime: 0:00:00.093424
2025-09-29 19:08:43.593972 | 
2025-09-29 19:08:43.594048 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 19:08:43.612169 | controller | ok
2025-09-29 19:08:43.621623 | controller | included: /var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 19:08:43.629641 | 
2025-09-29 19:08:43.629710 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 19:08:43.643535 | controller | skipping: Conditional result was False
2025-09-29 19:08:43.650449 | 
2025-09-29 19:08:43.650521 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 19:08:44.114981 | controller | changed
2025-09-29 19:08:44.119823 | 
2025-09-29 19:08:44.119899 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 19:08:44.376714 | controller | ok
2025-09-29 19:08:44.387076 | 
2025-09-29 19:08:44.387149 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 19:08:45.406781 | controller | changed
2025-09-29 19:08:45.412851 | 
2025-09-29 19:08:45.412943 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 19:08:46.404035 | controller | changed
2025-09-29 19:08:46.409039 | 
2025-09-29 19:08:46.409117 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 19:08:46.423194 | controller | skipping: Conditional result was False
2025-09-29 19:08:46.429171 | 
2025-09-29 19:08:46.429261 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 19:08:46.767702 | controller -> localhost | changed
2025-09-29 19:08:46.778032 | 
2025-09-29 19:08:46.778117 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 19:08:47.014814 | controller -> localhost | Identity added: /var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/438c0486a15642b9ae7d28f93f5c758c_id_rsa (zuul-build-sshkey)
2025-09-29 19:08:47.015049 | controller -> localhost | ok: Runtime: 0:00:00.007044
2025-09-29 19:08:47.021664 | 
2025-09-29 19:08:47.021733 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 19:08:47.366300 | controller | ok
2025-09-29 19:08:47.371444 | 
2025-09-29 19:08:47.371540 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 19:08:47.396218 | controller | skipping: Conditional result was False
2025-09-29 19:08:47.406198 | 
2025-09-29 19:08:47.406292 | TASK [include_role : validate-host]
2025-09-29 19:08:47.425946 | controller | ok
2025-09-29 19:08:47.445683 | 
2025-09-29 19:08:47.445787 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 19:08:47.474550 | controller | ok
2025-09-29 19:08:47.479306 | 
2025-09-29 19:08:47.479402 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 19:08:47.696759 | controller -> localhost | ok
2025-09-29 19:08:47.703645 | 
2025-09-29 19:08:47.703737 | TASK [validate-host : Collect information about the host]
2025-09-29 19:08:48.370833 | controller | ok
2025-09-29 19:08:48.378891 | 
2025-09-29 19:08:48.378989 | TASK [validate-host : Sanitize hostname]
2025-09-29 19:08:48.428064 | controller | ok
2025-09-29 19:08:48.432941 | 
2025-09-29 19:08:48.433021 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 19:08:48.893839 | controller -> localhost | changed
2025-09-29 19:08:48.899826 | 
2025-09-29 19:08:48.899935 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 19:08:49.274184 | controller | ok
2025-09-29 19:08:49.279494 | 
2025-09-29 19:08:49.279582 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 19:08:49.671528 | controller -> localhost | changed
2025-09-29 19:08:49.682467 | 
2025-09-29 19:08:49.682594 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 19:08:49.698223 | controller | skipping: Conditional result was False
2025-09-29 19:08:49.705239 | 
2025-09-29 19:08:49.705329 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 19:08:49.730231 | controller | skipping: Conditional result was False
2025-09-29 19:08:49.736755 | 
2025-09-29 19:08:49.736856 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 19:08:49.998806 | controller | ok: "logs"
2025-09-29 19:08:49.999091 | controller | ok: All items complete
2025-09-29 19:08:49.999131 | 
2025-09-29 19:08:50.223699 | controller | ok: "artifacts"
2025-09-29 19:08:50.451160 | controller | ok: "docs"
2025-09-29 19:08:50.463250 | 
2025-09-29 19:08:50.463496 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 19:08:50.737525 | controller | changed: "logs"
2025-09-29 19:08:50.965857 | controller | changed: "artifacts"
2025-09-29 19:08:51.200773 | controller | changed: "docs"
2025-09-29 19:08:51.230740 | 
2025-09-29 19:08:51.230840 | PLAY RECAP
2025-09-29 19:08:51.230986 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:08:51.231038 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:08:51.231060 | 
2025-09-29 19:08:51.325804 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 19:08:51.326615 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:08:51.909451 | 
2025-09-29 19:08:51.909567 | PLAY [all]
2025-09-29 19:08:51.930854 | 
2025-09-29 19:08:51.930976 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:08:51.979876 | controller | ok
2025-09-29 19:08:51.985022 | 
2025-09-29 19:08:51.985122 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:08:52.391074 | controller | changed
2025-09-29 19:08:52.396623 | 
2025-09-29 19:08:52.396716 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:08:53.602360 | controller | changed
2025-09-29 19:08:53.612252 | 
2025-09-29 19:08:53.612329 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 19:08:54.037893 | controller | changed:
2025-09-29 19:08:54.038094 | controller | {
2025-09-29 19:08:54.038125 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 19:08:54.038155 | controller | }
2025-09-29 19:08:54.315613 | controller | changed:
2025-09-29 19:08:54.315697 | controller | {
2025-09-29 19:08:54.315725 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 19:08:54.315746 | controller | }
2025-09-29 19:08:54.597040 | controller | changed:
2025-09-29 19:08:54.597120 | controller | {
2025-09-29 19:08:54.597150 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-29 19:08:54.597171 | controller | }
2025-09-29 19:08:54.873016 | controller | changed:
2025-09-29 19:08:54.873102 | controller | {
2025-09-29 19:08:54.873132 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 19:08:54.873153 | controller | }
2025-09-29 19:08:55.152142 | controller | changed:
2025-09-29 19:08:55.152325 | controller | {
2025-09-29 19:08:55.152370 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 19:08:55.152393 | controller | }
2025-09-29 19:08:55.435392 | controller | changed:
2025-09-29 19:08:55.435469 | controller | {
2025-09-29 19:08:55.435500 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 19:08:55.435522 | controller | }
2025-09-29 19:08:55.719523 | controller | changed:
2025-09-29 19:08:55.719605 | controller | {
2025-09-29 19:08:55.719634 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-09-29 19:08:55.719657 | controller | }
2025-09-29 19:08:56.004291 | controller | changed:
2025-09-29 19:08:56.004369 | controller | {
2025-09-29 19:08:56.004398 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-09-29 19:08:56.004421 | controller | }
2025-09-29 19:08:56.301577 | controller | changed:
2025-09-29 19:08:56.301672 | controller | {
2025-09-29 19:08:56.301702 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 19:08:56.301725 | controller | }
2025-09-29 19:08:56.623260 | controller | changed:
2025-09-29 19:08:56.623350 | controller | {
2025-09-29 19:08:56.623377 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 19:08:56.623399 | controller | }
2025-09-29 19:08:56.885209 | controller | changed:
2025-09-29 19:08:56.885299 | controller | {
2025-09-29 19:08:56.885326 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 19:08:56.885347 | controller | }
2025-09-29 19:08:57.172701 | controller | changed:
2025-09-29 19:08:57.172790 | controller | {
2025-09-29 19:08:57.172817 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 19:08:57.172878 | controller | }
2025-09-29 19:08:57.460886 | controller | changed:
2025-09-29 19:08:57.461150 | controller | {
2025-09-29 19:08:57.461183 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 19:08:57.461206 | controller | }
2025-09-29 19:08:57.743927 | controller | changed:
2025-09-29 19:08:57.744025 | controller | {
2025-09-29 19:08:57.744055 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 19:08:57.744076 | controller | }
2025-09-29 19:08:58.026525 | controller | changed:
2025-09-29 19:08:58.026621 | controller | {
2025-09-29 19:08:58.026659 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 19:08:58.026687 | controller | }
2025-09-29 19:08:58.306813 | controller | changed:
2025-09-29 19:08:58.306894 | controller | {
2025-09-29 19:08:58.306951 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 19:08:58.306976 | controller | }
2025-09-29 19:08:58.610552 | controller | changed:
2025-09-29 19:08:58.611035 | controller | {
2025-09-29 19:08:58.611076 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-09-29 19:08:58.611099 | controller | }
2025-09-29 19:08:58.889016 | controller | changed:
2025-09-29 19:08:58.889171 | controller | {
2025-09-29 19:08:58.889212 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-09-29 19:08:58.889244 | controller | }
2025-09-29 19:08:59.172939 | controller | changed:
2025-09-29 19:08:59.173054 | controller | {
2025-09-29 19:08:59.173174 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 19:08:59.173209 | controller | }
2025-09-29 19:08:59.451499 | controller | changed:
2025-09-29 19:08:59.451585 | controller | {
2025-09-29 19:08:59.451612 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 19:08:59.451633 | controller | }
2025-09-29 19:08:59.725823 | controller | changed:
2025-09-29 19:08:59.725906 | controller | {
2025-09-29 19:08:59.725957 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 19:08:59.725978 | controller | }
2025-09-29 19:09:00.007066 | controller | changed:
2025-09-29 19:09:00.007153 | controller | {
2025-09-29 19:09:00.007179 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 19:09:00.007200 | controller | }
2025-09-29 19:09:00.289410 | controller | changed:
2025-09-29 19:09:00.289517 | controller | {
2025-09-29 19:09:00.289544 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-09-29 19:09:00.289566 | controller | }
2025-09-29 19:09:00.576647 | controller | changed:
2025-09-29 19:09:00.576750 | controller | {
2025-09-29 19:09:00.576778 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 19:09:00.576799 | controller | }
2025-09-29 19:09:00.866990 | controller | changed:
2025-09-29 19:09:00.867085 | controller | {
2025-09-29 19:09:00.867115 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 19:09:00.867137 | controller | }
2025-09-29 19:09:01.154073 | controller | changed:
2025-09-29 19:09:01.154166 | controller | {
2025-09-29 19:09:01.154193 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 19:09:01.154213 | controller | }
2025-09-29 19:09:01.172860 | 
2025-09-29 19:09:01.172976 | TASK [Set timezone to UTC]
2025-09-29 19:09:01.658310 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 19:09:01.663374 | 
2025-09-29 19:09:01.663452 | TASK [Create nodepool directory]
2025-09-29 19:09:01.927335 | controller | changed
2025-09-29 19:09:01.932810 | 
2025-09-29 19:09:01.932892 | TASK [Create nodepool sub_nodes file]
2025-09-29 19:09:02.826584 | controller | changed
2025-09-29 19:09:02.831646 | 
2025-09-29 19:09:02.831732 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 19:09:03.746550 | controller | changed
2025-09-29 19:09:03.752263 | 
2025-09-29 19:09:03.752344 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 19:09:03.781680 | 
2025-09-29 19:09:03.781995 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 19:09:03.810893 | 
2025-09-29 19:09:03.811065 | TASK [Create nodepool primary file]
2025-09-29 19:09:03.834413 | controller | skipping: Conditional result was False
2025-09-29 19:09:03.839997 | 
2025-09-29 19:09:03.840083 | TASK [Create nodepool node_private for this node]
2025-09-29 19:09:04.827029 | controller | changed
2025-09-29 19:09:04.832661 | 
2025-09-29 19:09:04.832743 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 19:09:05.211081 | controller | ok: Item: id_rsa Runtime: 0:00:00.004304
2025-09-29 19:09:05.211330 | 
2025-09-29 19:09:05.439163 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004530
2025-09-29 19:09:05.453558 | 
2025-09-29 19:09:05.453665 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 19:09:06.416775 | controller | changed
2025-09-29 19:09:06.422998 | 
2025-09-29 19:09:06.423090 | TASK [Validate sudoers config after edits]
2025-09-29 19:09:06.708166 | controller | /etc/sudoers: parsed OK
2025-09-29 19:09:06.708281 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 19:09:06.708295 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 19:09:06.708302 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 19:09:06.953294 | controller | ok: Runtime: 0:00:00.006206
2025-09-29 19:09:06.959447 | 
2025-09-29 19:09:06.959523 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 19:09:07.221937 | controller | SHELL=/bin/bash
2025-09-29 19:09:07.221989 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-29 19:09:07.221998 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-29 19:09:07.222005 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/eb5b53fb17db92a72d33d052a56c8edd0d10ea78^openstack-k8s-operators/ci-framework:main:refs/changes/32/3332/2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:09:07.222057 | controller | PWD=/home/zuul
2025-09-29 19:09:07.222067 | controller | ZUUL_PIPELINE=github-check
2025-09-29 19:09:07.222073 | controller | LOGNAME=zuul
2025-09-29 19:09:07.222078 | controller | XDG_SESSION_TYPE=tty
2025-09-29 19:09:07.222084 | controller | _=/usr/bin/env
2025-09-29 19:09:07.222089 | controller | MOTD_SHOWN=pam
2025-09-29 19:09:07.222094 | controller | HOME=/home/zuul
2025-09-29 19:09:07.222099 | controller | LANG=en_US.UTF-8
2025-09-29 19:09:07.222104 | controller | SSH_CONNECTION=192.168.26.12 56002 192.168.26.82 22
2025-09-29 19:09:07.222110 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-09-29 19:09:07.222116 | controller | ZUUL_CHANGE_IDS=3346,eb5b53fb17db92a72d33d052a56c8edd0d10ea78 3332,2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:09:07.222121 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 19:09:07.222127 | controller | XDG_SESSION_CLASS=user
2025-09-29 19:09:07.222132 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 19:09:07.222138 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 19:09:07.222143 | controller | USER=zuul
2025-09-29 19:09:07.222148 | controller | ZUUL_VOTING=True
2025-09-29 19:09:07.222154 | controller | BUILD_TIMEOUT=1800000
2025-09-29 19:09:07.222159 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 19:09:07.222164 | controller | SHLVL=1
2025-09-29 19:09:07.222169 | controller | ZUUL_PATCHSET=2b7f44a769d14bbef534eeafa4b4e5c8b67650d8
2025-09-29 19:09:07.222175 | controller | XDG_SESSION_ID=1
2025-09-29 19:09:07.222180 | controller | ZUUL_BRANCH=main
2025-09-29 19:09:07.222186 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 19:09:07.222192 | controller | SSH_CLIENT=192.168.26.12 56002 22
2025-09-29 19:09:07.222197 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 19:09:07.222202 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 19:09:07.222208 | controller | which_declare=declare -f
2025-09-29 19:09:07.222232 | 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-09-29 19:09:07.222238 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 19:09:07.222243 | controller | ZUUL_CHANGE=3332
2025-09-29 19:09:07.222248 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 19:09:07.222253 | controller | ZUUL_UUID=438c0486a15642b9ae7d28f93f5c758c
2025-09-29 19:09:07.222259 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 19:09:07.222264 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 19:09:07.222270 | controller | }
2025-09-29 19:09:07.486000 | controller | ok: Runtime: 0:00:00.005864
2025-09-29 19:09:07.491858 | 
2025-09-29 19:09:07.491954 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 19:09:07.505893 | controller | skipping: Conditional result was False
2025-09-29 19:09:07.513411 | 
2025-09-29 19:09:07.513528 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 19:09:08.068709 | controller | skipping: Conditional result was False
2025-09-29 19:09:08.074606 | 
2025-09-29 19:09:08.074675 | TASK [Ensure legacy workspace directory]
2025-09-29 19:09:08.319876 | controller | changed
2025-09-29 19:09:08.338555 | 
2025-09-29 19:09:08.338614 | PLAY RECAP
2025-09-29 19:09:08.338657 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 19:09:08.338681 | 
2025-09-29 19:09:08.417455 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 19:09:08.418548 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:09:08.959061 | 
2025-09-29 19:09:08.959163 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 19:09:08.980270 | 
2025-09-29 19:09:08.980369 | TASK [Create zuul-output directory]
2025-09-29 19:09:09.354130 | controller | changed
2025-09-29 19:09:09.359328 | 
2025-09-29 19:09:09.359429 | TASK [Slurp Zuul inventory test]
2025-09-29 19:09:09.640990 | controller -> localhost | ok
2025-09-29 19:09:09.647763 | 
2025-09-29 19:09:09.647852 | TASK [Save zuul inventory]
2025-09-29 19:09:10.802867 | controller | changed
2025-09-29 19:09:10.808506 | 
2025-09-29 19:09:10.808596 | TASK [Save zuul vars without the change_message]
2025-09-29 19:09:11.819282 | controller | changed
2025-09-29 19:09:11.839719 | 
2025-09-29 19:09:11.839799 | PLAY RECAP
2025-09-29 19:09:11.839847 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 19:09:11.839872 | 
2025-09-29 19:09:11.925465 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 19:09:11.926228 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:09:12.511512 | 
2025-09-29 19:09:12.511632 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 19:09:12.533295 | 
2025-09-29 19:09:12.533377 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 19:09:12.562702 | controller | ok
2025-09-29 19:09:12.580071 | 
2025-09-29 19:09:12.580168 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 19:09:12.603981 | controller | skipping: Conditional result was False
2025-09-29 19:09:12.610488 | 
2025-09-29 19:09:12.610576 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 19:09:12.994336 | controller | ok
2025-09-29 19:09:13.000089 | 
2025-09-29 19:09:13.000182 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 19:09:13.904962 | controller | ok
2025-09-29 19:09:13.915350 | 
2025-09-29 19:09:13.915437 | TASK [Prepare workspace]
2025-09-29 19:09:13.934487 | controller | ok
2025-09-29 19:09:13.956014 | 
2025-09-29 19:09:13.956135 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 19:09:14.310734 | controller | ok
2025-09-29 19:09:14.317143 | 
2025-09-29 19:09:14.317228 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 19:09:16.284458 | controller | Output suppressed because no_log was given
2025-09-29 19:09:16.293828 | 
2025-09-29 19:09:16.293932 | LOOP [Create zuul-output directory]
2025-09-29 19:09:16.540292 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 19:09:16.755044 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 19:09:16.762843 | 
2025-09-29 19:09:16.762982 | TASK [Install required packages]
2025-09-29 19:09:36.553443 | controller | ok: Nothing to do
2025-09-29 19:09:36.559777 | 
2025-09-29 19:09:36.559856 | TASK [Install venv]
2025-09-29 19:10:37.176969 | controller | changed
2025-09-29 19:10:37.209456 | 
2025-09-29 19:10:37.209531 | PLAY RECAP
2025-09-29 19:10:37.209576 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:10:37.209599 | 
2025-09-29 19:10:37.291024 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 19:10:37.291847 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:10:37.911631 | 
2025-09-29 19:10:37.911757 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 19:10:37.934336 | 
2025-09-29 19:10:37.934476 | TASK [Gather required facts]
2025-09-29 19:10:38.554037 | controller | ok
2025-09-29 19:10:38.561071 | 
2025-09-29 19:10:38.561177 | TASK [Load environment var if instructed to]
2025-09-29 19:10:38.584806 | controller | skipping: Conditional result was False
2025-09-29 19:10:38.591259 | 
2025-09-29 19:10:38.591344 | TASK [Run molecule]
2025-09-29 19:10:39.594038 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-29 19:10:39.664616 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 19:10:56.267516 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.267949 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.268473 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.268900 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.269396 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+24673bce was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0+24673bce (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.269841 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.270282 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.270744 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.271161 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.271624 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.272052 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.272547 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.272987 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.273444 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.273882 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 19:10:56.283996 | controller | INFO     Running default > prepare
2025-09-29 19:10:57.000309 | controller |
2025-09-29 19:10:57.000400 | controller | PLAY [Prepare] *****************************************************************
2025-09-29 19:10:57.000503 | controller |
2025-09-29 19:10:57.000611 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:10:57.000718 | controller | Monday 29 September 2025  19:10:56 +0000 (0:00:00.020)       0:00:00.021 ******
2025-09-29 19:10:57.849512 | controller | ok: [instance]
2025-09-29 19:10:57.849558 | controller |
2025-09-29 19:10:57.849696 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-29 19:10:57.849777 | controller | Monday 29 September 2025  19:10:57 +0000 (0:00:00.850)       0:00:00.871 ******
2025-09-29 19:10:57.866909 | controller | skipping: [instance]
2025-09-29 19:10:57.866990 | controller |
2025-09-29 19:10:57.867129 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-29 19:10:57.867281 | controller | Monday 29 September 2025  19:10:57 +0000 (0:00:00.017)       0:00:00.889 ******
2025-09-29 19:10:57.908735 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-09-29 19:10:57.908840 | controller |
2025-09-29 19:10:57.908971 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-29 19:10:57.909108 | controller | Monday 29 September 2025  19:10:57 +0000 (0:00:00.041)       0:00:00.931 ******
2025-09-29 19:10:58.197764 | controller | ok: [instance]
2025-09-29 19:10:58.197846 | controller |
2025-09-29 19:10:58.197971 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-29 19:10:58.198087 | controller | Monday 29 September 2025  19:10:58 +0000 (0:00:00.288)       0:00:01.219 ******
2025-09-29 19:10:58.507594 | controller | ok: [instance]
2025-09-29 19:10:58.507691 | controller |
2025-09-29 19:10:58.507804 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-29 19:10:58.507907 | controller | Monday 29 September 2025  19:10:58 +0000 (0:00:00.309)       0:00:01.529 ******
2025-09-29 19:10:59.195166 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-29 19:10:59.195266 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-29 19:10:59.195337 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-29 19:10:59.195448 | controller |
2025-09-29 19:10:59.195556 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-29 19:10:59.195645 | controller | Monday 29 September 2025  19:10:59 +0000 (0:00:00.687)       0:00:02.217 ******
2025-09-29 19:10:59.255946 | controller |
2025-09-29 19:10:59.256012 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-29 19:10:59.256165 | controller | Monday 29 September 2025  19:10:59 +0000 (0:00:00.060)       0:00:02.278 ******
2025-09-29 19:10:59.801102 | controller | changed: [instance] => (item=tmp)
2025-09-29 19:10:59.801193 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-29 19:10:59.801312 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-29 19:10:59.801405 | controller |
2025-09-29 19:10:59.801517 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-29 19:10:59.801605 | controller | Monday 29 September 2025  19:10:59 +0000 (0:00:00.545)       0:00:02.823 ******
2025-09-29 19:11:00.657416 | controller | ok: [instance]
2025-09-29 19:11:00.657765 | controller |
2025-09-29 19:11:01.889376 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-29 19:11:01.889437 | controller | Monday 29 September 2025  19:11:00 +0000 (0:00:00.856)       0:00:03.679 ******
2025-09-29 19:11:01.889452 | controller | changed: [instance]
2025-09-29 19:11:01.889497 | controller |
2025-09-29 19:11:01.889513 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-29 19:11:01.889523 | controller | Monday 29 September 2025  19:11:01 +0000 (0:00:01.231)       0:00:04.911 ******
2025-09-29 19:11:09.934322 | controller | changed: [instance]
2025-09-29 19:11:10.671359 | controller |
2025-09-29 19:11:10.671403 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-29 19:11:10.671411 | controller | Monday 29 September 2025  19:11:09 +0000 (0:00:08.043)       0:00:12.954 ******
2025-09-29 19:11:10.671422 | controller | changed: [instance]
2025-09-29 19:11:10.671458 | controller |
2025-09-29 19:11:10.671467 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-29 19:11:10.671489 | controller | Monday 29 September 2025  19:11:10 +0000 (0:00:00.738)       0:00:13.693 ******
2025-09-29 19:11:10.692947 | controller | skipping: [instance]
2025-09-29 19:11:11.534971 | controller |
2025-09-29 19:11:11.535003 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-29 19:11:11.535011 | controller | Monday 29 September 2025  19:11:10 +0000 (0:00:00.021)       0:00:13.715 ******
2025-09-29 19:11:11.535021 | controller | changed: [instance]
2025-09-29 19:11:11.573696 | controller |
2025-09-29 19:11:11.573728 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-29 19:11:11.573736 | controller | Monday 29 September 2025  19:11:11 +0000 (0:00:00.841)       0:00:14.556 ******
2025-09-29 19:11:11.573745 | controller | skipping: [instance]
2025-09-29 19:11:11.612468 | controller |
2025-09-29 19:11:11.612510 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-29 19:11:11.612518 | controller | Monday 29 September 2025  19:11:11 +0000 (0:00:00.038)       0:00:14.595 ******
2025-09-29 19:11:11.612529 | controller | skipping: [instance]
2025-09-29 19:11:11.612559 | controller |
2025-09-29 19:11:11.612565 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-29 19:11:11.612572 | controller | Monday 29 September 2025  19:11:11 +0000 (0:00:00.038)       0:00:14.633 ******
2025-09-29 19:11:11.650824 | controller | skipping: [instance]
2025-09-29 19:11:12.219869 | controller |
2025-09-29 19:11:12.219899 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-29 19:11:12.219907 | controller | Monday 29 September 2025  19:11:11 +0000 (0:00:00.038)       0:00:14.672 ******
2025-09-29 19:11:12.219917 | controller | changed: [instance]
2025-09-29 19:11:12.674351 | controller |
2025-09-29 19:11:12.674381 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-29 19:11:12.674389 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.568)       0:00:15.241 ******
2025-09-29 19:11:12.674399 | controller | changed: [instance]
2025-09-29 19:11:12.704104 | controller |
2025-09-29 19:11:12.704141 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-29 19:11:12.704150 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.454)       0:00:15.696 ******
2025-09-29 19:11:12.704160 | controller | skipping: [instance]
2025-09-29 19:11:12.732943 | controller |
2025-09-29 19:11:12.732975 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-29 19:11:12.732983 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.029)       0:00:15.725 ******
2025-09-29 19:11:12.732993 | controller | skipping: [instance]
2025-09-29 19:11:12.762685 | controller |
2025-09-29 19:11:12.762720 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-29 19:11:12.762728 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.028)       0:00:15.754 ******
2025-09-29 19:11:12.762744 | controller | skipping: [instance]
2025-09-29 19:11:12.801764 | controller |
2025-09-29 19:11:12.801795 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-29 19:11:12.801802 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.029)       0:00:15.784 ******
2025-09-29 19:11:12.801813 | controller | ok: [instance]
2025-09-29 19:11:12.828900 | controller |
2025-09-29 19:11:12.828932 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-29 19:11:12.828941 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.038)       0:00:15.823 ******
2025-09-29 19:11:12.828952 | controller | skipping: [instance]
2025-09-29 19:11:12.829144 | controller |
2025-09-29 19:11:12.829366 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-29 19:11:12.829572 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.027)       0:00:15.851 ******
2025-09-29 19:11:12.856724 | controller | skipping: [instance]
2025-09-29 19:11:12.856889 | controller |
2025-09-29 19:11:12.857037 | controller | TASK [Download the RPM] ********************************************************
2025-09-29 19:11:12.857183 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.027)       0:00:15.879 ******
2025-09-29 19:11:12.884016 | controller | skipping: [instance]
2025-09-29 19:11:12.884187 | controller |
2025-09-29 19:11:12.884377 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-29 19:11:12.884560 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.027)       0:00:15.906 ******
2025-09-29 19:11:12.911197 | controller | skipping: [instance]
2025-09-29 19:11:12.911596 | controller |
2025-09-29 19:11:12.911755 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-29 19:11:12.911878 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.027)       0:00:15.933 ******
2025-09-29 19:11:12.938189 | controller | skipping: [instance]
2025-09-29 19:11:12.938374 | controller |
2025-09-29 19:11:12.938561 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-29 19:11:12.938704 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.026)       0:00:15.960 ******
2025-09-29 19:11:12.967382 | controller | skipping: [instance]
2025-09-29 19:11:12.967477 | controller |
2025-09-29 19:11:12.967673 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-29 19:11:12.967780 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.029)       0:00:15.989 ******
2025-09-29 19:11:12.993997 | controller | skipping: [instance]
2025-09-29 19:11:12.994090 | controller |
2025-09-29 19:11:12.994227 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-29 19:11:12.994347 | controller | Monday 29 September 2025  19:11:12 +0000 (0:00:00.026)       0:00:16.016 ******
2025-09-29 19:11:13.183349 | controller | ok: [instance]
2025-09-29 19:11:13.399861 | controller |
2025-09-29 19:11:13.399892 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-29 19:11:13.399901 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.188)       0:00:16.205 ******
2025-09-29 19:11:13.399911 | controller | changed: [instance]
2025-09-29 19:11:13.623025 | controller |
2025-09-29 19:11:13.623059 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-29 19:11:13.623069 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.216)       0:00:16.421 ******
2025-09-29 19:11:13.623081 | controller | changed: [instance]
2025-09-29 19:11:13.642374 | controller |
2025-09-29 19:11:13.642417 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-29 19:11:13.642426 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.222)       0:00:16.644 ******
2025-09-29 19:11:13.642439 | controller | skipping: [instance]
2025-09-29 19:11:13.642476 | controller |
2025-09-29 19:11:13.642485 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-29 19:11:13.642494 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.019)       0:00:16.664 ******
2025-09-29 19:11:13.664348 | controller | skipping: [instance]
2025-09-29 19:11:13.664394 | controller |
2025-09-29 19:11:13.664405 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-29 19:11:13.664520 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.022)       0:00:16.686 ******
2025-09-29 19:11:13.688350 | controller | skipping: [instance]
2025-09-29 19:11:13.688406 | controller |
2025-09-29 19:11:13.688425 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-29 19:11:13.688438 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.023)       0:00:16.710 ******
2025-09-29 19:11:13.711331 | controller | skipping: [instance]
2025-09-29 19:11:13.711373 | controller |
2025-09-29 19:11:13.711383 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-29 19:11:13.711391 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.023)       0:00:16.733 ******
2025-09-29 19:11:13.734277 | controller | skipping: [instance]
2025-09-29 19:11:13.734538 | controller |
2025-09-29 19:11:13.734558 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-29 19:11:13.758080 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.023)       0:00:16.756 ******
2025-09-29 19:11:13.758113 | controller | skipping: [instance]
2025-09-29 19:11:13.758315 | controller |
2025-09-29 19:11:13.758514 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-29 19:11:13.758669 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.023)       0:00:16.780 ******
2025-09-29 19:11:13.777502 | controller | skipping: [instance]
2025-09-29 19:11:13.777680 | controller |
2025-09-29 19:11:13.777824 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-29 19:11:13.777966 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.019)       0:00:16.799 ******
2025-09-29 19:11:13.809160 | controller | skipping: [instance]
2025-09-29 19:11:13.809380 | controller |
2025-09-29 19:11:13.809580 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-29 19:11:13.809737 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.031)       0:00:16.831 ******
2025-09-29 19:11:13.828113 | controller | skipping: [instance]
2025-09-29 19:11:13.828338 | controller |
2025-09-29 19:11:13.828555 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-29 19:11:13.828735 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.019)       0:00:16.850 ******
2025-09-29 19:11:13.850386 | controller | skipping: [instance]
2025-09-29 19:11:13.850599 | controller |
2025-09-29 19:11:13.850773 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-29 19:11:13.850916 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.022)       0:00:16.872 ******
2025-09-29 19:11:13.879879 | controller | skipping: [instance]
2025-09-29 19:11:13.880055 | controller |
2025-09-29 19:11:13.880266 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-29 19:11:13.880451 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.029)       0:00:16.902 ******
2025-09-29 19:11:13.909751 | controller | skipping: [instance]
2025-09-29 19:11:13.909912 | controller |
2025-09-29 19:11:13.910060 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-29 19:11:13.910207 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.029)       0:00:16.932 ******
2025-09-29 19:11:13.941569 | controller | skipping: [instance]
2025-09-29 19:11:13.941771 | controller |
2025-09-29 19:11:13.941947 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-29 19:11:13.942090 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.031)       0:00:16.964 ******
2025-09-29 19:11:13.969983 | controller | skipping: [instance]
2025-09-29 19:11:13.970152 | controller |
2025-09-29 19:11:13.970328 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-29 19:11:13.970491 | controller | Monday 29 September 2025  19:11:13 +0000 (0:00:00.028)       0:00:16.992 ******
2025-09-29 19:11:52.864112 | controller | ok: [instance]
2025-09-29 19:11:54.103797 | controller |
2025-09-29 19:11:54.103832 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-29 19:11:54.103840 | controller | Monday 29 September 2025  19:11:52 +0000 (0:00:38.893)       0:00:55.885 ******
2025-09-29 19:11:54.103851 | controller | ok: [instance]
2025-09-29 19:11:54.139916 | controller |
2025-09-29 19:11:54.139947 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-29 19:11:54.139956 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:01.239)       0:00:57.125 ******
2025-09-29 19:11:54.139966 | controller | skipping: [instance]
2025-09-29 19:11:54.193849 | controller |
2025-09-29 19:11:54.193885 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-29 19:11:54.193892 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.036)       0:00:57.161 ******
2025-09-29 19:11:54.193903 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-29 19:11:54.217377 | controller |
2025-09-29 19:11:54.217413 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-29 19:11:54.217423 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.053)       0:00:57.215 ******
2025-09-29 19:11:54.217446 | controller | ok: [instance] =>
2025-09-29 19:11:54.217596 | controller |   cifmw_ci_setup_packages:
2025-09-29 19:11:54.217619 | controller |   - bash-completion
2025-09-29 19:11:54.217646 | controller |   - ca-certificates
2025-09-29 19:11:54.217653 | controller |   - git-core
2025-09-29 19:11:54.217660 | controller |   - make
2025-09-29 19:11:54.217667 | controller |   - tar
2025-09-29 19:11:54.217673 | controller |   - tmux
2025-09-29 19:11:54.217680 | controller |   - python3-pip
2025-09-29 19:11:54.217691 | controller |
2025-09-29 19:11:54.217813 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-29 19:11:54.217835 | controller | Monday 29 September 2025  19:11:54 +0000 (0:00:00.023)       0:00:57.238 ******
2025-09-29 19:12:02.894382 | controller | changed: [instance]
2025-09-29 19:12:02.894477 | controller |
2025-09-29 19:12:02.894506 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-29 19:12:02.894518 | controller | Monday 29 September 2025  19:12:02 +0000 (0:00:08.677)       0:01:05.916 ******
2025-09-29 19:12:03.463824 | controller | ok: [instance]
2025-09-29 19:12:03.463873 | controller |
2025-09-29 19:12:03.463883 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-29 19:12:03.464000 | controller | Monday 29 September 2025  19:12:03 +0000 (0:00:00.569)       0:01:06.486 ******
2025-09-29 19:12:03.666896 | controller | changed: [instance]
2025-09-29 19:12:03.666929 | controller |
2025-09-29 19:12:03.666936 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-29 19:12:03.666945 | controller | Monday 29 September 2025  19:12:03 +0000 (0:00:00.202)       0:01:06.688 ******
2025-09-29 19:12:09.378307 | controller | changed: [instance]
2025-09-29 19:12:09.378357 | controller |
2025-09-29 19:12:09.378366 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-29 19:12:09.378374 | controller | Monday 29 September 2025  19:12:09 +0000 (0:00:05.711)       0:01:12.400 ******
2025-09-29 19:12:09.412858 | controller | skipping: [instance]
2025-09-29 19:12:09.677094 | controller |
2025-09-29 19:12:09.677127 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-29 19:12:09.677135 | controller | Monday 29 September 2025  19:12:09 +0000 (0:00:00.034)       0:01:12.435 ******
2025-09-29 19:12:09.677145 | controller | changed: [instance]
2025-09-29 19:12:09.963922 | controller |
2025-09-29 19:12:09.963957 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-29 19:12:09.963967 | controller | Monday 29 September 2025  19:12:09 +0000 (0:00:00.264)       0:01:12.699 ******
2025-09-29 19:12:09.963979 | controller | changed: [instance]
2025-09-29 19:12:09.979686 | controller |
2025-09-29 19:12:09.979712 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-29 19:12:09.979720 | controller | Monday 29 September 2025  19:12:09 +0000 (0:00:00.286)       0:01:12.985 ******
2025-09-29 19:12:09.979730 | controller | skipping: [instance]
2025-09-29 19:12:09.995358 | controller |
2025-09-29 19:12:09.995397 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-29 19:12:09.995407 | controller | Monday 29 September 2025  19:12:09 +0000 (0:00:00.016)       0:01:13.001 ******
2025-09-29 19:12:09.995420 | controller | skipping: [instance]
2025-09-29 19:12:10.011765 | controller |
2025-09-29 19:12:10.011796 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-29 19:12:10.011804 | controller | Monday 29 September 2025  19:12:09 +0000 (0:00:00.015)       0:01:13.017 ******
2025-09-29 19:12:10.011814 | controller | skipping: [instance]
2025-09-29 19:12:10.028019 | controller |
2025-09-29 19:12:10.028051 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-29 19:12:10.028059 | controller | Monday 29 September 2025  19:12:10 +0000 (0:00:00.016)       0:01:13.033 ******
2025-09-29 19:12:10.028069 | controller | skipping: [instance]
2025-09-29 19:12:10.044371 | controller |
2025-09-29 19:12:10.044412 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-29 19:12:10.044420 | controller | Monday 29 September 2025  19:12:10 +0000 (0:00:00.016)       0:01:13.050 ******
2025-09-29 19:12:10.044432 | controller | skipping: [instance]
2025-09-29 19:12:10.065547 | controller |
2025-09-29 19:12:10.065587 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-29 19:12:10.065610 | controller | Monday 29 September 2025  19:12:10 +0000 (0:00:00.016)       0:01:13.066 ******
2025-09-29 19:12:10.065624 | controller | skipping: [instance]
2025-09-29 19:12:10.065698 | controller |
2025-09-29 19:12:10.065839 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-29 19:12:10.065963 | controller | Monday 29 September 2025  19:12:10 +0000 (0:00:00.021)       0:01:13.087 ******
2025-09-29 19:12:11.083341 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-29 19:12:11.083430 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-29 19:12:11.083441 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-29 19:12:11.083449 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-29 19:12:11.083459 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-29 19:12:11.083740 | controller |
2025-09-29 19:14:52.174445 | controller | TASK [Ensure CRC is started] ***************************************************
2025-09-29 19:14:52.174482 | controller | Monday 29 September 2025  19:12:11 +0000 (0:00:01.017)       0:01:14.105 ******
2025-09-29 19:14:52.174495 | controller | changed: [instance]
2025-09-29 19:14:53.038036 | controller |
2025-09-29 19:14:53.038068 | controller | TASK [Create the openstack namespace] ******************************************
2025-09-29 19:14:53.038084 | controller | Monday 29 September 2025  19:14:52 +0000 (0:02:41.090)       0:03:55.196 ******
2025-09-29 19:14:53.038095 | controller | changed: [instance]
2025-09-29 19:14:53.655270 | controller |
2025-09-29 19:14:53.655314 | controller | TASK [Create a dummy configmap] ************************************************
2025-09-29 19:14:53.655323 | controller | Monday 29 September 2025  19:14:53 +0000 (0:00:00.863)       0:03:56.060 ******
2025-09-29 19:14:53.655336 | controller | changed: [instance]
2025-09-29 19:14:53.655436 | controller |
2025-09-29 19:14:53.655549 | controller | TASK [Create a dummy openstack config secret] **********************************
2025-09-29 19:14:53.655587 | controller | Monday 29 September 2025  19:14:53 +0000 (0:00:00.617)       0:03:56.677 ******
2025-09-29 19:14:54.270045 | controller | changed: [instance]
2025-09-29 19:14:54.881894 | controller |
2025-09-29 19:14:54.881933 | controller | TASK [Create dummy openstack root ca secret] ***********************************
2025-09-29 19:14:54.881942 | controller | Monday 29 September 2025  19:14:54 +0000 (0:00:00.614)       0:03:57.292 ******
2025-09-29 19:14:54.881953 | controller | changed: [instance]
2025-09-29 19:14:54.882054 | controller |
2025-09-29 19:14:54.882073 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:14:54.882079 | controller | instance                   : ok=33   changed=20   unreachable=0    failed=0    skipped=37   rescued=0    ignored=0
2025-09-29 19:14:54.882085 | controller |
2025-09-29 19:14:54.882090 | controller | Monday 29 September 2025  19:14:54 +0000 (0:00:00.611)       0:03:57.903 ******
2025-09-29 19:14:54.882096 | controller | ===============================================================================
2025-09-29 19:14:54.882101 | controller | Ensure CRC is started ------------------------------------------------- 161.09s
2025-09-29 19:14:54.882106 | controller | test_deps : Install selinux python libs -------------------------------- 38.89s
2025-09-29 19:14:54.882111 | controller | ci_setup : Install needed packages -------------------------------------- 8.68s
2025-09-29 19:14:54.882119 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.04s
2025-09-29 19:14:54.882287 | controller | ci_setup : Install openshift client ------------------------------------- 5.71s
2025-09-29 19:14:54.882327 | controller | test_deps : Install python yaml libs ------------------------------------ 1.24s
2025-09-29 19:14:54.882904 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.23s
2025-09-29 19:14:54.882925 | controller | ci_setup : Manage directories ------------------------------------------- 1.02s
2025-09-29 19:14:54.882949 | controller | Create the openstack namespace ------------------------------------------ 0.86s
2025-09-29 19:14:54.882955 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.86s
2025-09-29 19:14:54.882975 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-09-29 19:14:54.882981 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-09-29 19:14:54.882988 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s
2025-09-29 19:14:54.883011 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-09-29 19:14:54.883175 | controller | Create a dummy configmap ------------------------------------------------ 0.62s
2025-09-29 19:14:54.883232 | controller | Create a dummy openstack config secret ---------------------------------- 0.61s
2025-09-29 19:14:54.883323 | controller | Create dummy openstack root ca secret ----------------------------------- 0.61s
2025-09-29 19:14:54.883413 | controller | ci_setup : Gather version of openshift client --------------------------- 0.57s
2025-09-29 19:14:54.883504 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-09-29 19:14:54.883595 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-09-29 19:14:54.940585 | controller | INFO     Running default > converge
2025-09-29 19:14:55.355547 | controller |
2025-09-29 19:14:55.355622 | controller | PLAY [Converge] ****************************************************************
2025-09-29 19:14:55.355696 | controller |
2025-09-29 19:14:55.355795 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:14:55.355898 | controller | Monday 29 September 2025  19:14:55 +0000 (0:00:00.034)       0:00:00.034 ******
2025-09-29 19:14:56.239165 | controller | ok: [instance]
2025-09-29 19:14:56.239284 | controller |
2025-09-29 19:14:56.239420 | controller | TASK [Include the shiftstack role] *********************************************
2025-09-29 19:14:56.239531 | controller | Monday 29 September 2025  19:14:56 +0000 (0:00:00.884)       0:00:00.918 ******
2025-09-29 19:14:56.264678 | controller |
2025-09-29 19:14:56.264800 | controller | TASK [shiftstack : Include pre test shiftstack tasks] **************************
2025-09-29 19:14:56.264919 | controller | Monday 29 September 2025  19:14:56 +0000 (0:00:00.025)       0:00:00.944 ******
2025-09-29 19:14:56.289906 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/pre_test_shiftstack.yml for instance
2025-09-29 19:14:56.290003 | controller |
2025-09-29 19:14:56.290140 | controller | TASK [shiftstack : Fail if the testconfig is not passed] ***********************
2025-09-29 19:14:56.290305 | controller | Monday 29 September 2025  19:14:56 +0000 (0:00:00.025)       0:00:00.969 ******
2025-09-29 19:14:56.320528 | controller | ok: [instance] => changed=false
2025-09-29 19:14:56.320606 | controller |   msg: All assertions passed
2025-09-29 19:14:56.320726 | controller |
2025-09-29 19:14:56.320837 | controller | TASK [shiftstack : Remove the shiftstackclient pod if exists] ******************
2025-09-29 19:14:56.320943 | controller | Monday 29 September 2025  19:14:56 +0000 (0:00:00.030)       0:00:01.000 ******
2025-09-29 19:14:57.107740 | controller | ok: [instance]
2025-09-29 19:14:57.107788 | controller |
2025-09-29 19:14:57.107917 | controller | TASK [shiftstack : Remove the shiftstack role data directory (if exists)] ******
2025-09-29 19:14:57.108030 | controller | Monday 29 September 2025  19:14:57 +0000 (0:00:00.787)       0:00:01.787 ******
2025-09-29 19:14:57.418831 | controller | ok: [instance]
2025-09-29 19:14:57.418883 | controller |
2025-09-29 19:14:57.419145 | controller | TASK [shiftstack : Create the directory '{{ item }}'] **************************
2025-09-29 19:14:58.156691 | controller | Monday 29 September 2025  19:14:57 +0000 (0:00:00.311)       0:00:02.098 ******
2025-09-29 19:14:58.156730 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack)
2025-09-29 19:14:58.184740 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs)
2025-09-29 19:14:58.184773 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/manifests)
2025-09-29 19:14:58.184780 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/artifacts)
2025-09-29 19:14:58.184786 | controller |
2025-09-29 19:14:58.184792 | controller | TASK [shiftstack : Deploy the pod 'shiftstackclient-shiftstack'] ***************
2025-09-29 19:14:58.184798 | controller | Monday 29 September 2025  19:14:58 +0000 (0:00:00.737)       0:00:02.835 ******
2025-09-29 19:14:58.184817 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/deploy_shiftstackclient_pod.yml for instance
2025-09-29 19:14:58.184844 | controller |
2025-09-29 19:14:58.185069 | controller | TASK [shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig] ***
2025-09-29 19:14:58.564945 | controller | Monday 29 September 2025  19:14:58 +0000 (0:00:00.028)       0:00:02.864 ******
2025-09-29 19:14:58.564988 | controller | changed: [instance]
2025-09-29 19:14:58.565014 | controller |
2025-09-29 19:14:58.565021 | controller | TASK [shiftstack : Encode kubeconfig file to Base64] ***************************
2025-09-29 19:14:58.565029 | controller | Monday 29 September 2025  19:14:58 +0000 (0:00:00.379)       0:00:03.244 ******
2025-09-29 19:14:58.597793 | controller | ok: [instance]
2025-09-29 19:14:59.180462 | controller |
2025-09-29 19:14:59.180495 | controller | TASK [shiftstack : Render the incluster kubeconfig secret manifest] ************
2025-09-29 19:14:59.180503 | controller | Monday 29 September 2025  19:14:58 +0000 (0:00:00.032)       0:00:03.277 ******
2025-09-29 19:14:59.180513 | controller | changed: [instance]
2025-09-29 19:14:59.831586 | controller |
2025-09-29 19:14:59.831677 | controller | TASK [shiftstack : Apply the manifest for the incluster kubeconfig secret] *****
2025-09-29 19:14:59.831687 | controller | Monday 29 September 2025  19:14:59 +0000 (0:00:00.582)       0:00:03.860 ******
2025-09-29 19:14:59.831703 | controller | changed: [instance]
2025-09-29 19:15:00.186074 | controller |
2025-09-29 19:15:00.186109 | controller | TASK [shiftstack : Render the pvc manifest] ************************************
2025-09-29 19:15:00.186118 | controller | Monday 29 September 2025  19:14:59 +0000 (0:00:00.650)       0:00:04.510 ******
2025-09-29 19:15:00.186139 | controller | changed: [instance]
2025-09-29 19:15:00.186269 | controller |
2025-09-29 19:15:00.186391 | controller | TASK [shiftstack : Apply the manifest for the PVC creation] ********************
2025-09-29 19:15:00.832037 | controller | Monday 29 September 2025  19:15:00 +0000 (0:00:00.354)       0:00:04.865 ******
2025-09-29 19:15:00.832075 | controller | changed: [instance]
2025-09-29 19:15:01.208249 | controller |
2025-09-29 19:15:01.208285 | controller | TASK [shiftstack : Render the pod manifest from a template] ********************
2025-09-29 19:15:01.208301 | controller | Monday 29 September 2025  19:15:00 +0000 (0:00:00.645)       0:00:05.511 ******
2025-09-29 19:15:01.208312 | controller | changed: [instance]
2025-09-29 19:15:01.208341 | controller |
2025-09-29 19:15:01.208350 | controller | TASK [shiftstack : Apply the manifest for the pod creation] ********************
2025-09-29 19:15:01.208529 | controller | Monday 29 September 2025  19:15:01 +0000 (0:00:00.376)       0:00:05.887 ******
2025-09-29 19:21:04.682555 | controller | fatal: [instance]: FAILED! => changed=true
2025-09-29 19:21:04.682632 | controller |   duration: 362
2025-09-29 19:21:04.682645 | controller |   method: create
2025-09-29 19:21:04.682790 | controller |   msg: '"Pod" "shiftstackclient-shiftstack": Timed out waiting on resource'
2025-09-29 19:21:04.682807 | controller |   result:
2025-09-29 19:21:04.682961 | controller |     apiVersion: v1
2025-09-29 19:21:04.683158 | controller |     kind: Pod
2025-09-29 19:21:04.683193 | controller |     metadata:
2025-09-29 19:21:04.683270 | controller |       annotations:
2025-09-29 19:21:04.683395 | controller |         k8s.ovn.org/pod-networks: '{"default":{"ip_addresses":["10.217.0.40/23"],"mac_address":"0a:58:0a:d9:00:28","gateway_ips":["10.217.0.1"],"routes":[{"dest":"10.217.0.0/22","nextHop":"10.217.0.1"},{"dest":"10.217.4.0/23","nextHop":"10.217.0.1"},{"dest":"169.254.0.5/32","nextHop":"10.217.0.1"},{"dest":"100.64.0.0/16","nextHop":"10.217.0.1"}],"ip_address":"10.217.0.40/23","gateway_ip":"10.217.0.1","role":"primary"}}'
2025-09-29 19:21:04.683742 | controller |         k8s.v1.cni.cncf.io/network-status: |-
2025-09-29 19:21:04.683925 | controller |           [{
2025-09-29 19:21:04.683942 | controller |               "name": "ovn-kubernetes",
2025-09-29 19:21:04.683963 | controller |               "interface": "eth0",
2025-09-29 19:21:04.683972 | controller |               "ips": [
2025-09-29 19:21:04.684134 | controller |                   "10.217.0.40"
2025-09-29 19:21:04.684149 | controller |               ],
2025-09-29 19:21:04.684289 | controller |               "mac": "0a:58:0a:d9:00:28",
2025-09-29 19:21:04.684321 | controller |               "default": true,
2025-09-29 19:21:04.684339 | controller |               "dns": {}
2025-09-29 19:21:04.684473 | controller |           }]
2025-09-29 19:21:04.684627 | controller |         openshift.io/scc: hostpath-provisioner
2025-09-29 19:21:04.684758 | controller |       creationTimestamp: '2025-09-29T19:15:01Z'
2025-09-29 19:21:04.684771 | controller |       managedFields:
2025-09-29 19:21:04.684814 | controller |       - apiVersion: v1
2025-09-29 19:21:04.684925 | controller |         fieldsType: FieldsV1
2025-09-29 19:21:04.685010 | controller |         fieldsV1:
2025-09-29 19:21:04.685114 | controller |           f:spec:
2025-09-29 19:21:04.685238 | controller |             f:containers:
2025-09-29 19:21:04.685327 | controller |               k:{"name":"shiftstackclient-shiftstack"}:
2025-09-29 19:21:04.685424 | controller |                 .: {}
2025-09-29 19:21:04.685519 | controller |                 f:args: {}
2025-09-29 19:21:04.685621 | controller |                 f:command: {}
2025-09-29 19:21:04.685721 | controller |                 f:image: {}
2025-09-29 19:21:04.685820 | controller |                 f:imagePullPolicy: {}
2025-09-29 19:21:04.685915 | controller |                 f:name: {}
2025-09-29 19:21:04.686010 | controller |                 f:resources: {}
2025-09-29 19:21:04.686121 | controller |                 f:securityContext:
2025-09-29 19:21:04.686229 | controller |                   .: {}
2025-09-29 19:21:04.686349 | controller |                   f:privileged: {}
2025-09-29 19:21:04.686449 | controller |                 f:terminationMessagePath: {}
2025-09-29 19:21:04.686528 | controller |                 f:terminationMessagePolicy: {}
2025-09-29 19:21:04.686624 | controller |                 f:volumeMounts:
2025-09-29 19:21:04.686717 | controller |                   .: {}
2025-09-29 19:21:04.686813 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/cert/"}:
2025-09-29 19:21:04.686906 | controller |                     .: {}
2025-09-29 19:21:04.687001 | controller |                     f:mountPath: {}
2025-09-29 19:21:04.687159 | controller |                     f:name: {}
2025-09-29 19:21:04.687260 | controller |                     f:readOnly: {}
2025-09-29 19:21:04.687365 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/openstack/clouds.yaml"}:
2025-09-29 19:21:04.687467 | controller |                     .: {}
2025-09-29 19:21:04.687573 | controller |                     f:mountPath: {}
2025-09-29 19:21:04.687668 | controller |                     f:name: {}
2025-09-29 19:21:04.687764 | controller |                     f:subPath: {}
2025-09-29 19:21:04.687869 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/openstack/secure.yaml"}:
2025-09-29 19:21:04.687963 | controller |                     .: {}
2025-09-29 19:21:04.688069 | controller |                     f:mountPath: {}
2025-09-29 19:21:04.688186 | controller |                     f:name: {}
2025-09-29 19:21:04.688317 | controller |                     f:subPath: {}
2025-09-29 19:21:04.688418 | controller |                   k:{"mountPath":"/home/cloud-admin/artifacts/installation"}:
2025-09-29 19:21:04.688513 | controller |                     .: {}
2025-09-29 19:21:04.688608 | controller |                     f:mountPath: {}
2025-09-29 19:21:04.688701 | controller |                     f:name: {}
2025-09-29 19:21:04.688805 | controller |                   k:{"mountPath":"/home/cloud-admin/incluster-kubeconfig"}:
2025-09-29 19:21:04.688903 | controller |                     .: {}
2025-09-29 19:21:04.688998 | controller |                     f:mountPath: {}
2025-09-29 19:21:04.689106 | controller |                     f:name: {}
2025-09-29 19:21:04.689205 | controller |                     f:readOnly: {}
2025-09-29 19:21:04.689337 | controller |             f:dnsPolicy: {}
2025-09-29 19:21:04.689434 | controller |             f:enableServiceLinks: {}
2025-09-29 19:21:04.689528 | controller |             f:preemptionPolicy: {}
2025-09-29 19:21:04.689621 | controller |             f:priority: {}
2025-09-29 19:21:04.689715 | controller |             f:restartPolicy: {}
2025-09-29 19:21:04.689809 | controller |             f:schedulerName: {}
2025-09-29 19:21:04.689902 | controller |             f:securityContext: {}
2025-09-29 19:21:04.689995 | controller |             f:terminationGracePeriodSeconds: {}
2025-09-29 19:21:04.690105 | controller |             f:tolerations: {}
2025-09-29 19:21:04.690201 | controller |             f:volumes:
2025-09-29 19:21:04.690320 | controller |               .: {}
2025-09-29 19:21:04.690414 | controller |               k:{"name":"incluster-kubeconfig-volume"}:
2025-09-29 19:21:04.690504 | controller |                 .: {}
2025-09-29 19:21:04.690597 | controller |                 f:name: {}
2025-09-29 19:21:04.690699 | controller |                 f:secret:
2025-09-29 19:21:04.690795 | controller |                   .: {}
2025-09-29 19:21:04.690894 | controller |                   f:defaultMode: {}
2025-09-29 19:21:04.690986 | controller |                   f:secretName: {}
2025-09-29 19:21:04.691094 | controller |               k:{"name":"installation-volume"}:
2025-09-29 19:21:04.691190 | controller |                 .: {}
2025-09-29 19:21:04.691304 | controller |                 f:name: {}
2025-09-29 19:21:04.691398 | controller |                 f:persistentVolumeClaim:
2025-09-29 19:21:04.691489 | controller |                   .: {}
2025-09-29 19:21:04.691594 | controller |                   f:claimName: {}
2025-09-29 19:21:04.691688 | controller |               k:{"name":"openstack-cert"}:
2025-09-29 19:21:04.691783 | controller |                 .: {}
2025-09-29 19:21:04.691878 | controller |                 f:name: {}
2025-09-29 19:21:04.691971 | controller |                 f:secret:
2025-09-29 19:21:04.692064 | controller |                   .: {}
2025-09-29 19:21:04.692229 | controller |                   f:defaultMode: {}
2025-09-29 19:21:04.692336 | controller |                   f:secretName: {}
2025-09-29 19:21:04.692426 | controller |               k:{"name":"openstack-config"}:
2025-09-29 19:21:04.692522 | controller |                 .: {}
2025-09-29 19:21:04.692610 | controller |                 f:configMap:
2025-09-29 19:21:04.692692 | controller |                   .: {}
2025-09-29 19:21:04.692785 | controller |                   f:defaultMode: {}
2025-09-29 19:21:04.692876 | controller |                   f:name: {}
2025-09-29 19:21:04.692970 | controller |                 f:name: {}
2025-09-29 19:21:04.693070 | controller |               k:{"name":"openstack-config-secret"}:
2025-09-29 19:21:04.693178 | controller |                 .: {}
2025-09-29 19:21:04.693310 | controller |                 f:name: {}
2025-09-29 19:21:04.693406 | controller |                 f:secret:
2025-09-29 19:21:04.693499 | controller |                   .: {}
2025-09-29 19:21:04.693600 | controller |                   f:defaultMode: {}
2025-09-29 19:21:04.693697 | controller |                   f:secretName: {}
2025-09-29 19:21:04.693791 | controller |         manager: OpenAPI-Generator
2025-09-29 19:21:04.693891 | controller |         operation: Update
2025-09-29 19:21:04.693986 | controller |         time: '2025-09-29T19:15:01Z'
2025-09-29 19:21:04.694092 | controller |       - apiVersion: v1
2025-09-29 19:21:04.694228 | controller |         fieldsType: FieldsV1
2025-09-29 19:21:04.694333 | controller |         fieldsV1:
2025-09-29 19:21:04.694417 | controller |           f:metadata:
2025-09-29 19:21:04.694509 | controller |             f:annotations:
2025-09-29 19:21:04.694600 | controller |               f:k8s.ovn.org/pod-networks: {}
2025-09-29 19:21:04.694684 | controller |         manager: crc
2025-09-29 19:21:04.694777 | controller |         operation: Update
2025-09-29 19:21:04.694871 | controller |         subresource: status
2025-09-29 19:21:04.694967 | controller |         time: '2025-09-29T19:16:09Z'
2025-09-29 19:21:04.695060 | controller |       - apiVersion: v1
2025-09-29 19:21:04.695171 | controller |         fieldsType: FieldsV1
2025-09-29 19:21:04.695312 | controller |         fieldsV1:
2025-09-29 19:21:04.695410 | controller |           f:metadata:
2025-09-29 19:21:04.695504 | controller |             f:annotations:
2025-09-29 19:21:04.695618 | controller |               f:k8s.v1.cni.cncf.io/network-status: {}
2025-09-29 19:21:04.695715 | controller |         manager: multus-daemon
2025-09-29 19:21:04.695809 | controller |         operation: Update
2025-09-29 19:21:04.695902 | controller |         subresource: status
2025-09-29 19:21:04.695998 | controller |         time: '2025-09-29T19:20:20Z'
2025-09-29 19:21:04.696102 | controller |       - apiVersion: v1
2025-09-29 19:21:04.696205 | controller |         fieldsType: FieldsV1
2025-09-29 19:21:04.696322 | controller |         fieldsV1:
2025-09-29 19:21:04.696412 | controller |           f:status:
2025-09-29 19:21:04.696504 | controller |             f:conditions:
2025-09-29 19:21:04.696610 | controller |               k:{"type":"ContainersReady"}:
2025-09-29 19:21:04.696704 | controller |                 .: {}
2025-09-29 19:21:04.696798 | controller |                 f:lastProbeTime: {}
2025-09-29 19:21:04.696891 | controller |                 f:lastTransitionTime: {}
2025-09-29 19:21:04.696985 | controller |                 f:message: {}
2025-09-29 19:21:04.697089 | controller |                 f:reason: {}
2025-09-29 19:21:04.697192 | controller |                 f:status: {}
2025-09-29 19:21:04.697443 | controller |                 f:type: {}
2025-09-29 19:21:04.697495 | controller |               k:{"type":"Initialized"}:
2025-09-29 19:21:04.697505 | controller |                 .: {}
2025-09-29 19:21:04.697590 | controller |                 f:lastProbeTime: {}
2025-09-29 19:21:04.697698 | controller |                 f:lastTransitionTime: {}
2025-09-29 19:21:04.698062 | controller |                 f:status: {}
2025-09-29 19:21:04.698189 | controller |                 f:type: {}
2025-09-29 19:21:04.698335 | controller |               k:{"type":"PodReadyToStartContainers"}:
2025-09-29 19:21:04.698434 | controller |                 .: {}
2025-09-29 19:21:04.698536 | controller |                 f:lastProbeTime: {}
2025-09-29 19:21:04.698625 | controller |                 f:lastTransitionTime: {}
2025-09-29 19:21:04.698718 | controller |                 f:status: {}
2025-09-29 19:21:04.698913 | controller |                 f:type: {}
2025-09-29 19:21:04.699004 | controller |               k:{"type":"Ready"}:
2025-09-29 19:21:04.699019 | controller |                 .: {}
2025-09-29 19:21:04.699115 | controller |                 f:lastProbeTime: {}
2025-09-29 19:21:04.699226 | controller |                 f:lastTransitionTime: {}
2025-09-29 19:21:04.699327 | controller |                 f:message: {}
2025-09-29 19:21:04.699420 | controller |                 f:reason: {}
2025-09-29 19:21:04.699514 | controller |                 f:status: {}
2025-09-29 19:21:04.699608 | controller |                 f:type: {}
2025-09-29 19:21:04.699713 | controller |             f:containerStatuses: {}
2025-09-29 19:21:04.699915 | controller |             f:hostIP: {}
2025-09-29 19:21:04.700064 | controller |             f:hostIPs: {}
2025-09-29 19:21:04.700095 | controller |             f:podIP: {}
2025-09-29 19:21:04.700112 | controller |             f:podIPs:
2025-09-29 19:21:04.700193 | controller |               .: {}
2025-09-29 19:21:04.700327 | controller |               k:{"ip":"10.217.0.40"}:
2025-09-29 19:21:04.700414 | controller |                 .: {}
2025-09-29 19:21:04.700508 | controller |                 f:ip: {}
2025-09-29 19:21:04.700603 | controller |             f:startTime: {}
2025-09-29 19:21:04.700698 | controller |         manager: kubelet
2025-09-29 19:21:04.700792 | controller |         operation: Update
2025-09-29 19:21:04.700886 | controller |         subresource: status
2025-09-29 19:21:04.700981 | controller |         time: '2025-09-29T19:20:53Z'
2025-09-29 19:21:04.701646 | controller |       name: shiftstackclient-shiftstack
2025-09-29 19:21:04.701686 | controller |       namespace: openstack
2025-09-29 19:21:04.701694 | controller |       resourceVersion: '33650'
2025-09-29 19:21:04.701700 | controller |       uid: 9f1fb0e4-adc5-41a3-b8f1-45df5ce2d997
2025-09-29 19:21:04.701706 | controller |     spec:
2025-09-29 19:21:04.701711 | controller |       containers:
2025-09-29 19:21:04.701718 | controller |       - args:
2025-09-29 19:21:04.701773 | controller |         - infinity
2025-09-29 19:21:04.701876 | controller |         command:
2025-09-29 19:21:04.701962 | controller |         - /bin/sleep
2025-09-29 19:21:04.702074 | controller |         image: quay.io/shiftstack-qe/shiftstack-client:latest
2025-09-29 19:21:04.702176 | controller |         imagePullPolicy: Always
2025-09-29 19:21:04.702299 | controller |         name: shiftstackclient-shiftstack
2025-09-29 19:21:04.702395 | controller |         resources: {}
2025-09-29 19:21:04.702490 | controller |         securityContext:
2025-09-29 19:21:04.702584 | controller |           capabilities:
2025-09-29 19:21:04.702681 | controller |             drop:
2025-09-29 19:21:04.702773 | controller |             - KILL
2025-09-29 19:21:04.702867 | controller |             - MKNOD
2025-09-29 19:21:04.702962 | controller |             - SETGID
2025-09-29 19:21:04.703055 | controller |             - SETUID
2025-09-29 19:21:04.703166 | controller |           privileged: true
2025-09-29 19:21:04.703293 | controller |         terminationMessagePath: /dev/termination-log
2025-09-29 19:21:04.703405 | controller |         terminationMessagePolicy: File
2025-09-29 19:21:04.703497 | controller |         volumeMounts:
2025-09-29 19:21:04.703594 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/clouds.yaml
2025-09-29 19:21:04.703693 | controller |           name: openstack-config
2025-09-29 19:21:04.703787 | controller |           subPath: clouds.yaml
2025-09-29 19:21:04.703885 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/secure.yaml
2025-09-29 19:21:04.703979 | controller |           name: openstack-config-secret
2025-09-29 19:21:04.704075 | controller |           subPath: secure.yaml
2025-09-29 19:21:04.704190 | controller |         - mountPath: /home/cloud-admin/.original-config/cert/
2025-09-29 19:21:04.704309 | controller |           name: openstack-cert
2025-09-29 19:21:04.704401 | controller |           readOnly: true
2025-09-29 19:21:04.704500 | controller |         - mountPath: /home/cloud-admin/artifacts/installation
2025-09-29 19:21:04.704592 | controller |           name: installation-volume
2025-09-29 19:21:04.704691 | controller |         - mountPath: /home/cloud-admin/incluster-kubeconfig
2025-09-29 19:21:04.704785 | controller |           name: incluster-kubeconfig-volume
2025-09-29 19:21:04.704882 | controller |           readOnly: true
2025-09-29 19:21:04.704970 | controller |         - mountPath: /var/run/secrets/kubernetes.io/serviceaccount
2025-09-29 19:21:04.705068 | controller |           name: kube-api-access-zvwf9
2025-09-29 19:21:04.705194 | controller |           readOnly: true
2025-09-29 19:21:04.705308 | controller |       dnsPolicy: ClusterFirst
2025-09-29 19:21:04.705394 | controller |       enableServiceLinks: true
2025-09-29 19:21:04.705490 | controller |       nodeName: crc
2025-09-29 19:21:04.705586 | controller |       preemptionPolicy: PreemptLowerPriority
2025-09-29 19:21:04.705675 | controller |       priority: 0
2025-09-29 19:21:04.705769 | controller |       restartPolicy: Always
2025-09-29 19:21:04.705863 | controller |       schedulerName: default-scheduler
2025-09-29 19:21:04.705959 | controller |       securityContext: {}
2025-09-29 19:21:04.706053 | controller |       serviceAccount: default
2025-09-29 19:21:04.706189 | controller |       serviceAccountName: default
2025-09-29 19:21:04.706299 | controller |       terminationGracePeriodSeconds: 0
2025-09-29 19:21:04.706406 | controller |       tolerations:
2025-09-29 19:21:04.706487 | controller |       - effect: NoExecute
2025-09-29 19:21:04.706582 | controller |         key: node.kubernetes.io/not-ready
2025-09-29 19:21:04.706685 | controller |         operator: Exists
2025-09-29 19:21:04.706783 | controller |         tolerationSeconds: 300
2025-09-29 19:21:04.706886 | controller |       - effect: NoExecute
2025-09-29 19:21:04.706993 | controller |         key: node.kubernetes.io/unreachable
2025-09-29 19:21:04.707108 | controller |         operator: Exists
2025-09-29 19:21:04.707182 | controller |         tolerationSeconds: 300
2025-09-29 19:21:04.707293 | controller |       volumes:
2025-09-29 19:21:04.707408 | controller |       - name: incluster-kubeconfig-volume
2025-09-29 19:21:04.707491 | controller |         secret:
2025-09-29 19:21:04.707575 | controller |           defaultMode: 420
2025-09-29 19:21:04.707670 | controller |           secretName: incluster-kubeconfig
2025-09-29 19:21:04.707765 | controller |       - configMap:
2025-09-29 19:21:04.707860 | controller |           defaultMode: 420
2025-09-29 19:21:04.707955 | controller |           name: openstack-config
2025-09-29 19:21:04.708056 | controller |         name: openstack-config
2025-09-29 19:21:04.708164 | controller |       - name: openstack-config-secret
2025-09-29 19:21:04.708274 | controller |         secret:
2025-09-29 19:21:04.708390 | controller |           defaultMode: 420
2025-09-29 19:21:04.708479 | controller |           secretName: openstack-config-secret
2025-09-29 19:21:04.708563 | controller |       - name: openstack-cert
2025-09-29 19:21:04.708664 | controller |         secret:
2025-09-29 19:21:04.708768 | controller |           defaultMode: 292
2025-09-29 19:21:04.708853 | controller |           secretName: rootca-public
2025-09-29 19:21:04.708937 | controller |       - name: installation-volume
2025-09-29 19:21:04.709036 | controller |         persistentVolumeClaim:
2025-09-29 19:21:04.709156 | controller |           claimName: shiftstackclient-shiftstack-pvc
2025-09-29 19:21:04.709260 | controller |       - name: kube-api-access-zvwf9
2025-09-29 19:21:04.709360 | controller |         projected:
2025-09-29 19:21:04.709463 | controller |           defaultMode: 420
2025-09-29 19:21:04.709552 | controller |           sources:
2025-09-29 19:21:04.709637 | controller |           - serviceAccountToken:
2025-09-29 19:21:04.709732 | controller |               expirationSeconds: 3607
2025-09-29 19:21:04.709842 | controller |               path: token
2025-09-29 19:21:04.709952 | controller |           - configMap:
2025-09-29 19:21:04.710035 | controller |               items:
2025-09-29 19:21:04.710144 | controller |               - key: ca.crt
2025-09-29 19:21:04.710265 | controller |                 path: ca.crt
2025-09-29 19:21:04.710343 | controller |               name: kube-root-ca.crt
2025-09-29 19:21:04.710448 | controller |           - downwardAPI:
2025-09-29 19:21:04.710530 | controller |               items:
2025-09-29 19:21:04.710622 | controller |               - fieldRef:
2025-09-29 19:21:04.710715 | controller |                   apiVersion: v1
2025-09-29 19:21:04.710809 | controller |                   fieldPath: metadata.namespace
2025-09-29 19:21:04.710902 | controller |                 path: namespace
2025-09-29 19:21:04.710994 | controller |           - configMap:
2025-09-29 19:21:04.711098 | controller |               items:
2025-09-29 19:21:04.711195 | controller |               - key: service-ca.crt
2025-09-29 19:21:04.711314 | controller |                 path: service-ca.crt
2025-09-29 19:21:04.711418 | controller |               name: openshift-service-ca.crt
2025-09-29 19:21:04.711515 | controller |     status:
2025-09-29 19:21:04.711618 | controller |       conditions:
2025-09-29 19:21:04.711713 | controller |       - lastProbeTime: null
2025-09-29 19:21:04.711807 | controller |         lastTransitionTime: '2025-09-29T19:20:53Z'
2025-09-29 19:21:04.711899 | controller |         status: 'True'
2025-09-29 19:21:04.711994 | controller |         type: PodReadyToStartContainers
2025-09-29 19:21:04.712099 | controller |       - lastProbeTime: null
2025-09-29 19:21:04.712198 | controller |         lastTransitionTime: '2025-09-29T19:16:09Z'
2025-09-29 19:21:04.712329 | controller |         status: 'True'
2025-09-29 19:21:04.712422 | controller |         type: Initialized
2025-09-29 19:21:04.712516 | controller |       - lastProbeTime: null
2025-09-29 19:21:04.712612 | controller |         lastTransitionTime: '2025-09-29T19:16:09Z'
2025-09-29 19:21:04.712706 | controller |         message: 'containers with unready status: [shiftstackclient-shiftstack]'
2025-09-29 19:21:04.712800 | controller |         reason: ContainersNotReady
2025-09-29 19:21:04.712893 | controller |         status: 'False'
2025-09-29 19:21:04.712989 | controller |         type: Ready
2025-09-29 19:21:04.713100 | controller |       - lastProbeTime: null
2025-09-29 19:21:04.713196 | controller |         lastTransitionTime: '2025-09-29T19:16:09Z'
2025-09-29 19:21:04.713320 | controller |         message: 'containers with unready status: [shiftstackclient-shiftstack]'
2025-09-29 19:21:04.713414 | controller |         reason: ContainersNotReady
2025-09-29 19:21:04.713506 | controller |         status: 'False'
2025-09-29 19:21:04.713599 | controller |         type: ContainersReady
2025-09-29 19:21:04.713692 | controller |       - lastProbeTime: null
2025-09-29 19:21:04.713787 | controller |         lastTransitionTime: '2025-09-29T19:16:09Z'
2025-09-29 19:21:04.713881 | controller |         status: 'True'
2025-09-29 19:21:04.713974 | controller |         type: PodScheduled
2025-09-29 19:21:04.714066 | controller |       containerStatuses:
2025-09-29 19:21:04.714176 | controller |       - image: quay.io/shiftstack-qe/shiftstack-client:latest
2025-09-29 19:21:04.714288 | controller |         imageID: ''
2025-09-29 19:21:04.714381 | controller |         lastState: {}
2025-09-29 19:21:04.714476 | controller |         name: shiftstackclient-shiftstack
2025-09-29 19:21:04.714576 | controller |         ready: false
2025-09-29 19:21:04.714671 | controller |         restartCount: 0
2025-09-29 19:21:04.714763 | controller |         started: false
2025-09-29 19:21:04.714856 | controller |         state:
2025-09-29 19:21:04.714948 | controller |           waiting:
2025-09-29 19:21:04.715047 | controller |             message: 'rpc error: code = Canceled desc = copying config: context canceled'
2025-09-29 19:21:04.715158 | controller |             reason: ErrImagePull
2025-09-29 19:21:04.715275 | controller |         volumeMounts:
2025-09-29 19:21:04.715372 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/clouds.yaml
2025-09-29 19:21:04.715467 | controller |           name: openstack-config
2025-09-29 19:21:04.715572 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/secure.yaml
2025-09-29 19:21:04.715664 | controller |           name: openstack-config-secret
2025-09-29 19:21:04.715759 | controller |         - mountPath: /home/cloud-admin/.original-config/cert/
2025-09-29 19:21:04.715862 | controller |           name: openstack-cert
2025-09-29 19:21:04.715957 | controller |           readOnly: true
2025-09-29 19:21:04.716051 | controller |           recursiveReadOnly: Disabled
2025-09-29 19:21:04.716159 | controller |         - mountPath: /home/cloud-admin/artifacts/installation
2025-09-29 19:21:04.716276 | controller |           name: installation-volume
2025-09-29 19:21:04.716372 | controller |         - mountPath: /home/cloud-admin/incluster-kubeconfig
2025-09-29 19:21:04.716474 | controller |           name: incluster-kubeconfig-volume
2025-09-29 19:21:04.716569 | controller |           readOnly: true
2025-09-29 19:21:04.716663 | controller |           recursiveReadOnly: Disabled
2025-09-29 19:21:04.716759 | controller |         - mountPath: /var/run/secrets/kubernetes.io/serviceaccount
2025-09-29 19:21:04.716852 | controller |           name: kube-api-access-zvwf9
2025-09-29 19:21:04.716945 | controller |           readOnly: true
2025-09-29 19:21:04.717039 | controller |           recursiveReadOnly: Disabled
2025-09-29 19:21:04.717152 | controller |       hostIP: 192.168.126.11
2025-09-29 19:21:04.717272 | controller |       hostIPs:
2025-09-29 19:21:04.717371 | controller |       - ip: 192.168.126.11
2025-09-29 19:21:04.717464 | controller |       phase: Pending
2025-09-29 19:21:04.717557 | controller |       podIP: 10.217.0.40
2025-09-29 19:21:04.717649 | controller |       podIPs:
2025-09-29 19:21:04.717742 | controller |       - ip: 10.217.0.40
2025-09-29 19:21:04.717835 | controller |       qosClass: BestEffort
2025-09-29 19:21:04.717929 | controller |       startTime: '2025-09-29T19:16:09Z'
2025-09-29 19:21:04.718281 | controller |
2025-09-29 19:21:04.718385 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:21:04.718489 | controller | instance                   : ok=14   changed=7    unreachable=0    failed=1    skipped=0    rescued=0    ignored=0
2025-09-29 19:21:04.718580 | controller |
2025-09-29 19:21:04.718686 | controller | Monday 29 September 2025  19:21:04 +0000 (0:06:03.477)       0:06:09.364 ******
2025-09-29 19:21:04.718775 | controller | ===============================================================================
2025-09-29 19:21:04.718872 | controller | shiftstack : Apply the manifest for the pod creation ------------------ 363.48s
2025-09-29 19:21:04.718968 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-09-29 19:21:04.719066 | controller | shiftstack : Remove the shiftstackclient pod if exists ------------------ 0.79s
2025-09-29 19:21:04.719182 | controller | shiftstack : Create the directory '{{ item }}' -------------------------- 0.74s
2025-09-29 19:21:04.719320 | controller | shiftstack : Apply the manifest for the incluster kubeconfig secret ----- 0.65s
2025-09-29 19:21:04.719413 | controller | shiftstack : Apply the manifest for the PVC creation -------------------- 0.65s
2025-09-29 19:21:04.719511 | controller | shiftstack : Render the incluster kubeconfig secret manifest ------------ 0.58s
2025-09-29 19:21:04.719626 | controller | shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig --- 0.38s
2025-09-29 19:21:04.719733 | controller | shiftstack : Render the pod manifest from a template -------------------- 0.38s
2025-09-29 19:21:04.719828 | controller | shiftstack : Render the pvc manifest ------------------------------------ 0.35s
2025-09-29 19:21:04.719925 | controller | shiftstack : Remove the shiftstack role data directory (if exists) ------ 0.31s
2025-09-29 19:21:04.720021 | controller | shiftstack : Encode kubeconfig file to Base64 --------------------------- 0.03s
2025-09-29 19:21:04.720140 | controller | shiftstack : Fail if the testconfig is not passed ----------------------- 0.03s
2025-09-29 19:21:04.720251 | controller | shiftstack : Deploy the pod 'shiftstackclient-shiftstack' --------------- 0.03s
2025-09-29 19:21:04.720350 | controller | Include the shiftstack role --------------------------------------------- 0.03s
2025-09-29 19:21:04.720446 | controller | shiftstack : Include pre test shiftstack tasks -------------------------- 0.03s
2025-09-29 19:21:04.735935 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/shiftstack/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/molecule/default/converge.yml']
2025-09-29 19:21:04.736384 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-09-29 19:21:04.745313 | controller | INFO     Running default > cleanup
2025-09-29 19:21:05.240836 | controller |
2025-09-29 19:21:05.240917 | controller | PLAY [Cleanup] *****************************************************************
2025-09-29 19:21:05.241021 | controller |
2025-09-29 19:21:05.241155 | controller | TASK [Gathering Facts] *********************************************************
2025-09-29 19:21:05.241288 | controller | Monday 29 September 2025  19:21:05 +0000 (0:00:00.050)       0:00:00.050 ******
2025-09-29 19:21:06.126952 | controller | ok: [instance]
2025-09-29 19:21:06.127013 | controller |
2025-09-29 19:21:06.127195 | controller | TASK [Include the shiftstack role and run the cleanup] *************************
2025-09-29 19:21:06.127383 | controller | Monday 29 September 2025  19:21:06 +0000 (0:00:00.886)       0:00:00.937 ******
2025-09-29 19:21:06.152479 | controller |
2025-09-29 19:21:06.152584 | controller | TASK [shiftstack : Destroy the Openshift cluster] ******************************
2025-09-29 19:21:06.152683 | controller | Monday 29 September 2025  19:21:06 +0000 (0:00:00.025)       0:00:00.963 ******
2025-09-29 19:21:06.172689 | controller | ok: [instance] =>
2025-09-29 19:21:06.172792 | controller |   msg: 'TODO: to be added once it''s supported in shiftstack-qa automation'
2025-09-29 19:21:06.172914 | controller |
2025-09-29 19:21:06.173052 | controller | TASK [shiftstack : Delete the secret 'incluster-kubeconfig'] *******************
2025-09-29 19:21:06.173199 | controller | Monday 29 September 2025  19:21:06 +0000 (0:00:00.020)       0:00:00.983 ******
2025-09-29 19:21:07.000724 | controller | changed: [instance]
2025-09-29 19:21:07.000798 | controller |
2025-09-29 19:21:07.000928 | controller | TASK [shiftstack : Delete the pod 'shiftstackclient-shiftstack'] ***************
2025-09-29 19:21:07.001043 | controller | Monday 29 September 2025  19:21:06 +0000 (0:00:00.827)       0:00:01.811 ******
2025-09-29 19:21:07.651763 | controller | changed: [instance]
2025-09-29 19:21:07.651824 | controller |
2025-09-29 19:21:07.651968 | controller | TASK [Delete the openstack namespace] ******************************************
2025-09-29 19:21:07.652075 | controller | Monday 29 September 2025  19:21:07 +0000 (0:00:00.651)       0:00:02.462 ******
2025-09-29 19:21:08.283048 | controller | changed: [instance]
2025-09-29 19:21:08.283121 | controller |
2025-09-29 19:21:08.283265 | controller | PLAY RECAP *********************************************************************
2025-09-29 19:21:08.283399 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-09-29 19:21:08.283504 | controller |
2025-09-29 19:21:08.283618 | controller | Monday 29 September 2025  19:21:08 +0000 (0:00:00.631)       0:00:03.093 ******
2025-09-29 19:21:08.283717 | controller | ===============================================================================
2025-09-29 19:21:08.283827 | controller | Gathering Facts --------------------------------------------------------- 0.89s
2025-09-29 19:21:08.283934 | controller | shiftstack : Delete the secret 'incluster-kubeconfig' ------------------- 0.83s
2025-09-29 19:21:08.284042 | controller | shiftstack : Delete the pod 'shiftstackclient-shiftstack' --------------- 0.65s
2025-09-29 19:21:08.284175 | controller | Delete the openstack namespace ------------------------------------------ 0.63s
2025-09-29 19:21:08.284307 | controller | Include the shiftstack role and run the cleanup ------------------------- 0.03s
2025-09-29 19:21:08.284419 | controller | shiftstack : Destroy the Openshift cluster ------------------------------ 0.02s
2025-09-29 19:21:08.341937 | controller | INFO     Running default > destroy
2025-09-29 19:21:08.342268 | controller | WARNING  Skipping, instances are delegated.
2025-09-29 19:21:08.342590 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-09-29 19:21:08.344099 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 19:21:08.808992 | controller | ERROR
2025-09-29 19:21:08.809095 | controller | {
2025-09-29 19:21:08.809128 | controller |   "delta": "0:10:29.429742",
2025-09-29 19:21:08.809151 | controller |   "end": "2025-09-29 19:21:08.395614",
2025-09-29 19:21:08.809178 | controller |   "msg": "non-zero return code",
2025-09-29 19:21:08.809198 | controller |   "rc": 1,
2025-09-29 19:21:08.809219 | controller |   "start": "2025-09-29 19:10:38.965872"
2025-09-29 19:21:08.809236 | controller | }
failure
2025-09-29 19:21:08.865088 | 
2025-09-29 19:21:08.865183 | PLAY RECAP
2025-09-29 19:21:08.865230 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-09-29 19:21:08.865255 | 
2025-09-29 19:21:08.941515 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 19:21:08.942341 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:21:09.516361 | 
2025-09-29 19:21:09.516479 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 19:21:09.537447 | 
2025-09-29 19:21:09.537541 | TASK [Filter out host if needed]
2025-09-29 19:21:09.546377 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 19:21:09.551021 | 
2025-09-29 19:21:09.551093 | TASK [Ensure file is present]
2025-09-29 19:21:09.987414 | controller | ok
2025-09-29 19:21:09.994429 | 
2025-09-29 19:21:09.994528 | TASK [Manage molecule report file]
2025-09-29 19:21:10.525273 | controller | changed
2025-09-29 19:21:10.541019 | 
2025-09-29 19:21:10.541112 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 19:21:10.812969 | controller | ok
2025-09-29 19:21:10.818477 | 
2025-09-29 19:21:10.818556 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 19:21:11.336207 | controller | changed
2025-09-29 19:21:11.342719 | 
2025-09-29 19:21:11.342809 | TASK [Copy ci-framework interesting files]
2025-09-29 19:21:11.866603 | controller | changed
2025-09-29 19:21:11.873048 | 
2025-09-29 19:21:11.873135 | TASK [Get SELinux listing]
2025-09-29 19:21:12.427979 | controller | changed
2025-09-29 19:21:12.433645 | 
2025-09-29 19:21:12.433718 | TASK [Generate log index]
2025-09-29 19:21:13.914197 | controller | changed
2025-09-29 19:21:13.919504 | 
2025-09-29 19:21:13.919577 | TASK [Get some env related data]
2025-09-29 19:21:14.813955 | controller | /home/zuul/.local/bin/ansible
2025-09-29 19:21:15.463776 | controller | changed
2025-09-29 19:21:15.496582 | 
2025-09-29 19:21:15.496665 | TASK [Generate list of logs to collect in home directory]
2025-09-29 19:21:15.870427 | controller | ok: All paths examined
2025-09-29 19:21:15.876615 | 
2025-09-29 19:21:15.876707 | LOOP [Copy logs from home directory]
2025-09-29 19:21:16.531463 | controller | changed:
2025-09-29 19:21:16.531679 | controller | {
2025-09-29 19:21:16.531714 | controller |   "atime": 1743544925.4788878,
2025-09-29 19:21:16.531735 | controller |   "ctime": 1743545329.1409318,
2025-09-29 19:21:16.531754 | controller |   "dev": 64513,
2025-09-29 19:21:16.531771 | controller |   "gid": 1000,
2025-09-29 19:21:16.531786 | controller |   "gr_name": "zuul",
2025-09-29 19:21:16.531801 | controller |   "inode": 4518807,
2025-09-29 19:21:16.531816 | controller |   "isblk": false,
2025-09-29 19:21:16.531831 | controller |   "ischr": false,
2025-09-29 19:21:16.531845 | controller |   "isdir": false,
2025-09-29 19:21:16.531859 | controller |   "isfifo": false,
2025-09-29 19:21:16.531874 | controller |   "isgid": false,
2025-09-29 19:21:16.531889 | controller |   "islnk": false,
2025-09-29 19:21:16.531904 | controller |   "isreg": true,
2025-09-29 19:21:16.531945 | controller |   "issock": false,
2025-09-29 19:21:16.531962 | controller |   "isuid": false,
2025-09-29 19:21:16.531978 | controller |   "mode": "0644",
2025-09-29 19:21:16.531991 | controller |   "mtime": 1743545329.1409318,
2025-09-29 19:21:16.532005 | controller |   "nlink": 1,
2025-09-29 19:21:16.532018 | controller |   "path": "/home/zuul/crc-setup.log",
2025-09-29 19:21:16.532032 | controller |   "pw_name": "zuul",
2025-09-29 19:21:16.532048 | controller |   "rgrp": true,
2025-09-29 19:21:16.532062 | controller |   "roth": true,
2025-09-29 19:21:16.532075 | controller |   "rusr": true,
2025-09-29 19:21:16.532089 | controller |   "size": 4108,
2025-09-29 19:21:16.532103 | controller |   "uid": 1000,
2025-09-29 19:21:16.532118 | controller |   "wgrp": false,
2025-09-29 19:21:16.532132 | controller |   "woth": false,
2025-09-29 19:21:16.532146 | controller |   "wusr": true,
2025-09-29 19:21:16.532162 | controller |   "xgrp": false,
2025-09-29 19:21:16.532177 | controller |   "xoth": false,
2025-09-29 19:21:16.532191 | controller |   "xusr": false
2025-09-29 19:21:16.532204 | controller | }
2025-09-29 19:21:17.220344 | controller | changed:
2025-09-29 19:21:17.220435 | controller | {
2025-09-29 19:21:17.220463 | controller |   "atime": 1743545331.0429637,
2025-09-29 19:21:17.220484 | controller |   "ctime": 1743545842.0111232,
2025-09-29 19:21:17.220502 | controller |   "dev": 64513,
2025-09-29 19:21:17.220519 | controller |   "gid": 1000,
2025-09-29 19:21:17.220535 | controller |   "gr_name": "zuul",
2025-09-29 19:21:17.220550 | controller |   "inode": 4194437,
2025-09-29 19:21:17.220565 | controller |   "isblk": false,
2025-09-29 19:21:17.220580 | controller |   "ischr": false,
2025-09-29 19:21:17.220594 | controller |   "isdir": false,
2025-09-29 19:21:17.220609 | controller |   "isfifo": false,
2025-09-29 19:21:17.220623 | controller |   "isgid": false,
2025-09-29 19:21:17.220638 | controller |   "islnk": false,
2025-09-29 19:21:17.220652 | controller |   "isreg": true,
2025-09-29 19:21:17.220665 | controller |   "issock": false,
2025-09-29 19:21:17.220679 | controller |   "isuid": false,
2025-09-29 19:21:17.220697 | controller |   "mode": "0644",
2025-09-29 19:21:17.220712 | controller |   "mtime": 1743545842.0111232,
2025-09-29 19:21:17.220727 | controller |   "nlink": 1,
2025-09-29 19:21:17.220741 | controller |   "path": "/home/zuul/crc-start.log",
2025-09-29 19:21:17.220757 | controller |   "pw_name": "zuul",
2025-09-29 19:21:17.220773 | controller |   "rgrp": true,
2025-09-29 19:21:17.220815 | controller |   "roth": true,
2025-09-29 19:21:17.220835 | controller |   "rusr": true,
2025-09-29 19:21:17.220851 | controller |   "size": 4023,
2025-09-29 19:21:17.220866 | controller |   "uid": 1000,
2025-09-29 19:21:17.220881 | controller |   "wgrp": false,
2025-09-29 19:21:17.220895 | controller |   "woth": false,
2025-09-29 19:21:17.220946 | controller |   "wusr": true,
2025-09-29 19:21:17.220970 | controller |   "xgrp": false,
2025-09-29 19:21:17.220986 | controller |   "xoth": false,
2025-09-29 19:21:17.221002 | controller |   "xusr": false
2025-09-29 19:21:17.221018 | controller | }
2025-09-29 19:21:17.911798 | controller | changed:
2025-09-29 19:21:17.912016 | controller | {
2025-09-29 19:21:17.912067 | controller |   "atime": 1759173020.969999,
2025-09-29 19:21:17.912091 | controller |   "ctime": 1759173036.7631419,
2025-09-29 19:21:17.912109 | controller |   "dev": 64513,
2025-09-29 19:21:17.912125 | controller |   "gid": 1000,
2025-09-29 19:21:17.912141 | controller |   "gr_name": "zuul",
2025-09-29 19:21:17.912156 | controller |   "inode": 4233902,
2025-09-29 19:21:17.912170 | controller |   "isblk": false,
2025-09-29 19:21:17.912184 | controller |   "ischr": false,
2025-09-29 19:21:17.912200 | controller |   "isdir": false,
2025-09-29 19:21:17.912214 | controller |   "isfifo": false,
2025-09-29 19:21:17.912227 | controller |   "isgid": false,
2025-09-29 19:21:17.912241 | controller |   "islnk": false,
2025-09-29 19:21:17.912254 | controller |   "isreg": true,
2025-09-29 19:21:17.912267 | controller |   "issock": false,
2025-09-29 19:21:17.912281 | controller |   "isuid": false,
2025-09-29 19:21:17.912316 | controller |   "mode": "0644",
2025-09-29 19:21:17.912332 | controller |   "mtime": 1759173036.7631419,
2025-09-29 19:21:17.912347 | controller |   "nlink": 1,
2025-09-29 19:21:17.912361 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 19:21:17.912375 | controller |   "pw_name": "zuul",
2025-09-29 19:21:17.912389 | controller |   "rgrp": true,
2025-09-29 19:21:17.912404 | controller |   "roth": true,
2025-09-29 19:21:17.912417 | controller |   "rusr": true,
2025-09-29 19:21:17.912433 | controller |   "size": 6749,
2025-09-29 19:21:17.912448 | controller |   "uid": 1000,
2025-09-29 19:21:17.912461 | controller |   "wgrp": false,
2025-09-29 19:21:17.912476 | controller |   "woth": false,
2025-09-29 19:21:17.912491 | controller |   "wusr": true,
2025-09-29 19:21:17.912505 | controller |   "xgrp": false,
2025-09-29 19:21:17.912519 | controller |   "xoth": false,
2025-09-29 19:21:17.912532 | controller |   "xusr": false
2025-09-29 19:21:17.912545 | controller | }
2025-09-29 19:21:17.929983 | 
2025-09-29 19:21:17.930105 | TASK [Copy crio stats log file]
2025-09-29 19:21:17.944845 | controller | skipping: Conditional result was False
2025-09-29 19:21:17.950801 | 
2025-09-29 19:21:17.950868 | TASK [Get SELinux related data]
2025-09-29 19:21:18.245562 | controller | <no matches>
2025-09-29 19:21:18.476013 | controller | ERROR
2025-09-29 19:21:18.476200 | controller | {
2025-09-29 19:21:18.476232 | controller |   "delta": "0:00:00.013448",
2025-09-29 19:21:18.476254 | controller |   "end": "2025-09-29 19:21:18.246813",
2025-09-29 19:21:18.476272 | controller |   "msg": "non-zero return code",
2025-09-29 19:21:18.476308 | controller |   "rc": 1,
2025-09-29 19:21:18.476327 | controller |   "start": "2025-09-29 19:21:18.233365"
2025-09-29 19:21:18.476344 | controller | }
2025-09-29 19:21:18.476365 | controller | ERROR: Ignoring Errors
2025-09-29 19:21:18.482275 | 
2025-09-29 19:21:18.482378 | TASK [Create system configuration directory]
2025-09-29 19:21:18.758788 | controller | changed
2025-09-29 19:21:18.764320 | 
2025-09-29 19:21:18.764433 | TASK [Get some of the system configurations]
2025-09-29 19:21:19.298267 | controller | changed
2025-09-29 19:21:19.305672 | 
2025-09-29 19:21:19.305802 | TASK [Copy generated documentation if available]
2025-09-29 19:21:19.331161 | controller | skipping: Conditional result was False
2025-09-29 19:21:19.339975 | 
2025-09-29 19:21:19.340119 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 19:21:19.354699 | controller | skipping: Conditional result was False
2025-09-29 19:21:19.360605 | 
2025-09-29 19:21:19.360677 | TASK [Compress logs bigger than 2MB]
2025-09-29 19:21:19.889636 | controller | changed
2025-09-29 19:21:19.895555 | 
2025-09-29 19:21:19.895623 | TASK [Copy files from workspace on node]
2025-09-29 19:21:19.913665 | controller | ok
2025-09-29 19:21:19.938617 | 
2025-09-29 19:21:19.938702 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:21:19.954902 | controller | skipping: Conditional result was False
2025-09-29 19:21:19.961616 | 
2025-09-29 19:21:19.961694 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:21:20.012660 | controller | ok
2025-09-29 19:21:20.017808 | 
2025-09-29 19:21:20.017876 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:21:20.230415 | controller -> localhost | ok: "/var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/logs"
2025-09-29 19:21:20.230668 | controller -> localhost | changed: All items complete
2025-09-29 19:21:20.230706 | 
2025-09-29 19:21:20.430231 | controller -> localhost | changed: "/var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/artifacts"
2025-09-29 19:21:20.642890 | controller -> localhost | changed: "/var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/docs"
2025-09-29 19:21:20.654308 | 
2025-09-29 19:21:20.654449 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:21:21.368478 | controller | changed:
2025-09-29 19:21:21.368659 | controller | .d..t...... ./
2025-09-29 19:21:21.368689 | controller | >f+++++++++ README.html
2025-09-29 19:21:21.368713 | controller | >f+++++++++ ansible-execution.log
2025-09-29 19:21:21.368734 | controller | >f+++++++++ ansible.log
2025-09-29 19:21:21.368754 | controller | >f+++++++++ crc-setup.log
2025-09-29 19:21:21.368772 | controller | >f+++++++++ crc-start.log
2025-09-29 19:21:21.368790 | controller | >f+++++++++ dmesg.log
2025-09-29 19:21:21.368808 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 19:21:21.368828 | controller | >f+++++++++ python.log
2025-09-29 19:21:21.368846 | controller | >f+++++++++ registries.conf
2025-09-29 19:21:21.368863 | controller | >f+++++++++ report.html
2025-09-29 19:21:21.368880 | controller | >f+++++++++ selinux-denials.log
2025-09-29 19:21:21.368896 | controller | >f+++++++++ selinux-listing.log
2025-09-29 19:21:21.368946 | controller | cd+++++++++ ci-framework-data/
2025-09-29 19:21:21.368973 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 19:21:21.368994 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 19:21:21.369012 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-09-29 19:21:21.369030 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-09-29 19:21:21.369048 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-09-29 19:21:21.369065 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 19:21:21.369082 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 19:21:21.369099 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-09-29 19:21:21.369116 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-29 19:21:21.369132 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 19:21:21.369150 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 19:21:21.369166 | controller | cd+++++++++ ci-framework-data/tests/
2025-09-29 19:21:21.369188 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/
2025-09-29 19:21:21.369205 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/
2025-09-29 19:21:21.369222 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/
2025-09-29 19:21:21.369238 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/manifests/
2025-09-29 19:21:21.369255 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_incluster_secret.yml
2025-09-29 19:21:21.369271 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pod.yml
2025-09-29 19:21:21.369300 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pvc.yml
2025-09-29 19:21:21.369319 | controller | cd+++++++++ registries.conf.d/
2025-09-29 19:21:21.369336 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 19:21:21.369352 | controller | cd+++++++++ system-config/
2025-09-29 19:21:21.369369 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 19:21:21.369384 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 19:21:21.369400 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 19:21:21.369416 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-09-29 19:21:21.369431 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-09-29 19:21:21.369447 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-09-29 19:21:21.369463 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-09-29 19:21:21.369479 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-09-29 19:21:21.369495 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-09-29 19:21:21.369511 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-09-29 19:21:21.369526 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-09-29 19:21:21.369541 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-09-29 19:21:21.369556 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-09-29 19:21:21.369572 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-09-29 19:21:21.369588 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-09-29 19:21:21.369604 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-09-29 19:21:21.369620 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-09-29 19:21:21.846269 | controller | changed: .d..t...... ./
2025-09-29 19:21:22.478241 | controller | changed: .d..t...... ./
2025-09-29 19:21:22.517304 | 
2025-09-29 19:21:22.517406 | TASK [Return artifact to Zuul]
2025-09-29 19:21:22.546246 | controller | ok
2025-09-29 19:21:22.589593 | 
2025-09-29 19:21:22.589694 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 19:21:22.589859 | 
2025-09-29 19:21:22.589934 | PLAY RECAP
2025-09-29 19:21:22.589981 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 19:21:22.590004 | 
2025-09-29 19:21:22.674622 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 19:21:22.675465 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:21:23.216263 | 
2025-09-29 19:21:23.216367 | PLAY [all]
2025-09-29 19:21:23.235678 | 
2025-09-29 19:21:23.235759 | TASK [include_role : fetch-output]
2025-09-29 19:21:23.264106 | controller | ok
2025-09-29 19:21:23.281327 | 
2025-09-29 19:21:23.281440 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 19:21:23.356944 | controller | skipping: Conditional result was False
2025-09-29 19:21:23.363242 | 
2025-09-29 19:21:23.363381 | TASK [fetch-output : Set log path for single node]
2025-09-29 19:21:23.395358 | controller | ok
2025-09-29 19:21:23.400562 | 
2025-09-29 19:21:23.400643 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 19:21:23.792895 | controller -> localhost | ok: "/var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/logs"
2025-09-29 19:21:24.019164 | controller -> localhost | ok: "/var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/artifacts"
2025-09-29 19:21:24.276355 | controller -> localhost | ok: "/var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/docs"
2025-09-29 19:21:24.294749 | 
2025-09-29 19:21:24.295155 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 19:21:24.917532 | controller | ok
2025-09-29 19:21:24.917718 | controller | ok: All items complete
2025-09-29 19:21:24.917748 | 
2025-09-29 19:21:25.408235 | controller | ok
2025-09-29 19:21:25.852892 | controller | ok
2025-09-29 19:21:25.865677 | 
2025-09-29 19:21:25.865786 | TASK [include_role : fetch-output-openshift]
2025-09-29 19:21:25.879976 | controller | skipping: Conditional result was False
2025-09-29 19:21:25.885860 | 
2025-09-29 19:21:25.885956 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 19:21:26.275749 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.018690
2025-09-29 19:21:26.489342 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007236
2025-09-29 19:21:26.528150 | 
2025-09-29 19:21:26.528250 | PLAY [all]
2025-09-29 19:21:26.542528 | 
2025-09-29 19:21:26.542616 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 19:21:27.008132 | controller | changed
2025-09-29 19:21:27.030531 | 
2025-09-29 19:21:27.030602 | PLAY RECAP
2025-09-29 19:21:27.030646 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 19:21:27.030669 | 
2025-09-29 19:21:27.107349 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 19:21:27.108113 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 19:21:27.711260 | 
2025-09-29 19:21:27.711473 | PLAY [localhost]
2025-09-29 19:21:27.729780 | 
2025-09-29 19:21:27.729867 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 19:21:28.083479 | localhost | changed
2025-09-29 19:21:28.089228 | 
2025-09-29 19:21:28.089359 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 19:21:28.117702 | localhost | ok
2025-09-29 19:21:28.126514 | 
2025-09-29 19:21:28.126597 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 19:21:28.529266 | localhost | changed
2025-09-29 19:21:28.536942 | 
2025-09-29 19:21:28.537108 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 19:21:29.153504 | localhost | changed
2025-09-29 19:21:29.158848 | 
2025-09-29 19:21:29.158951 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 19:21:29.532396 | localhost | Identity added: /var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/tmp/ansible.6n6huqpm (/var/lib/zuul/builds/438c0486a15642b9ae7d28f93f5c758c/work/tmp/ansible.6n6huqpm)
2025-09-29 19:21:29.532580 | localhost | ok: Runtime: 0:00:00.006982
2025-09-29 19:21:29.537048 | 
2025-09-29 19:21:29.537122 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 19:21:29.777741 | localhost | ok: Runtime: 0:00:00.004521
2025-09-29 19:21:29.789391 | 
2025-09-29 19:21:29.789499 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 19:21:29.838881 | localhost | changed
2025-09-29 19:21:29.843376 | 
2025-09-29 19:21:29.843458 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 19:21:30.226424 | localhost | changed
2025-09-29 19:21:30.246234 | 
2025-09-29 19:21:30.246320 | PLAY [localhost]
2025-09-29 19:21:30.258364 | 
2025-09-29 19:21:30.258433 | TASK [Generate bulk log download script]
2025-09-29 19:21:30.275701 | localhost | ok
2025-09-29 19:21:30.287484 | 
2025-09-29 19:21:30.287551 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 19:21:30.315130 | localhost | ok: All assertions passed
2025-09-29 19:21:30.319648 | 
2025-09-29 19:21:30.319720 | TASK [local-log-download : Create download script]
2025-09-29 19:21:30.708435 | localhost -> localhost | changed
2025-09-29 19:21:30.717384 | 
2025-09-29 19:21:30.717459 | TASK [Register quick-download link]
2025-09-29 19:21:30.735582 | localhost | ok
2025-09-29 19:21:30.773240 | 
2025-09-29 19:21:30.773353 | PLAY [logserver.rdoproject.org]
2025-09-29 19:21:30.783202 | 
2025-09-29 19:21:30.783275 | TASK [Set zuul-log-path fact]
2025-09-29 19:21:30.799347 | logserver.rdoproject.org | ok
2025-09-29 19:21:30.809268 | 
2025-09-29 19:21:30.809361 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 19:21:30.835322 | logserver.rdoproject.org | ok
2025-09-29 19:21:30.841110 | 
2025-09-29 19:21:30.841175 | TASK [upload-logs : Create log directories]
2025-09-29 19:21:32.440566 | logserver.rdoproject.org | changed
2025-09-29 19:21:32.443699 | 
2025-09-29 19:21:32.443766 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 19:21:32.669796 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004632
2025-09-29 19:21:32.674680 | 
2025-09-29 19:21:32.674768 | TASK [upload-logs : Upload logs to log server]
2025-09-29 19:21:34.293663 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 19:21:34.297210 | 
2025-09-29 19:21:34.297327 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 19:21:34.338541 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:21:34.347400 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:21:34.359539 | 
2025-09-29 19:21:34.359632 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 19:21:34.396685 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:21:34.396891 | 
2025-09-29 19:21:34.400254 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 19:21:34.407627 | 
2025-09-29 19:21:34.407709 | LOOP [upload-logs : Upload console log and json output]