2026-01-25 07:37:24.628336 | Job console starting...
2026-01-25 07:37:24.847765 | Updating repositories
2026-01-25 07:37:25.891666 | Preparing job workspace
2026-01-25 07:37:44.480410 | Running Ansible setup...
2026-01-25 07:37:53.217175 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-25 07:37:56.141424 | 
2026-01-25 07:37:56.141542 | PLAY [localhost]
2026-01-25 07:37:56.192312 | 
2026-01-25 07:37:56.192434 | TASK [Gathering Facts]
2026-01-25 07:38:00.152881 | localhost | ok
2026-01-25 07:38:00.179924 | 
2026-01-25 07:38:00.180037 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-25 07:38:02.181947 | localhost -> localhost | changed
2026-01-25 07:38:02.188371 | 
2026-01-25 07:38:02.188465 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-25 07:38:06.709818 | localhost -> localhost | changed
2026-01-25 07:38:06.718942 | 
2026-01-25 07:38:06.719037 | TASK [Setup log path fact]
2026-01-25 07:38:06.797721 | localhost | ok
2026-01-25 07:38:06.839811 | 
2026-01-25 07:38:06.839912 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 07:38:06.940124 | localhost | ok
2026-01-25 07:38:06.960495 | 
2026-01-25 07:38:06.960586 | TASK [emit-job-header : Print job information]
2026-01-25 07:38:07.100244 | # Job Information
2026-01-25 07:38:07.100403 | Ansible Version: 2.15.12
2026-01-25 07:38:07.100437 | Job: cifmw-molecule-deploy_bmh
2026-01-25 07:38:07.100460 | Pipeline: github-check
2026-01-25 07:38:07.100482 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-25 07:38:07.100502 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3619
2026-01-25 07:38:07.100521 | Log URL (when completed): https://logserver.rdoproject.org/85a/rdoproject.org/85a1a9e732ff40f9aec7d8a6f43ebfea/
2026-01-25 07:38:07.100540 | Event ID: 782b3520-f9c0-11f0-9fc9-b2aac073cae4
2026-01-25 07:38:07.104269 | 
2026-01-25 07:38:07.104376 | LOOP [emit-job-header : Print node information]
2026-01-25 07:38:07.753141 | localhost | ok:
2026-01-25 07:38:07.753280 | localhost | # Node Information
2026-01-25 07:38:07.753334 | localhost | Inventory Hostname: controller
2026-01-25 07:38:07.753364 | localhost | Hostname: np0005594885
2026-01-25 07:38:07.753386 | localhost | Username: zuul
2026-01-25 07:38:07.753409 | localhost | Distro: CentOS 9
2026-01-25 07:38:07.753429 | localhost | Provider: ibm-bm4-nodepool
2026-01-25 07:38:07.753448 | localhost | Region: regionOne
2026-01-25 07:38:07.753467 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-01-25 07:38:07.753485 | localhost | Product Name: OpenStack Compute
2026-01-25 07:38:07.753503 | localhost | Interface IP: 192.168.26.27
2026-01-25 07:38:07.829505 | 
2026-01-25 07:38:07.829596 | PLAY [all]
2026-01-25 07:38:07.858794 | 
2026-01-25 07:38:07.858877 | TASK [Gather network facts]
2026-01-25 07:38:08.316105 | controller | ok
2026-01-25 07:38:08.419589 | 
2026-01-25 07:38:08.419689 | TASK [include_role : start-zuul-console]
2026-01-25 07:38:08.460002 | controller | ok
2026-01-25 07:38:08.502970 | 
2026-01-25 07:38:08.503048 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-25 07:38:09.811353 | controller | ok
2026-01-25 07:38:09.840865 | 
2026-01-25 07:38:09.840975 | TASK [include_role : add-build-sshkey]
2026-01-25 07:38:09.957153 | controller | ok
2026-01-25 07:38:10.049612 | 
2026-01-25 07:38:10.049777 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-25 07:38:11.136655 | controller -> localhost | ok
2026-01-25 07:38:11.147882 | 
2026-01-25 07:38:11.147976 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-25 07:38:11.238100 | controller | ok
2026-01-25 07:38:11.298797 | controller | included: /var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-25 07:38:11.330070 | 
2026-01-25 07:38:11.330182 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-25 07:38:14.017489 | controller -> localhost | Generating public/private rsa key pair.
2026-01-25 07:38:14.017706 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/85a1a9e732ff40f9aec7d8a6f43ebfea_id_rsa.
2026-01-25 07:38:14.017746 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/85a1a9e732ff40f9aec7d8a6f43ebfea_id_rsa.pub.
2026-01-25 07:38:14.017771 | controller -> localhost | The key fingerprint is:
2026-01-25 07:38:14.017791 | controller -> localhost | SHA256:DN7BOIPrDoX4cqyPMv2wYhvojslE34VyQ8ECcPk7uxI zuul-build-sshkey
2026-01-25 07:38:14.017811 | controller -> localhost | The key's randomart image is:
2026-01-25 07:38:14.017831 | controller -> localhost | +---[RSA 3072]----+
2026-01-25 07:38:14.017851 | controller -> localhost | |o.o...           |
2026-01-25 07:38:14.017872 | controller -> localhost | | ... o.o         |
2026-01-25 07:38:14.017891 | controller -> localhost | |   .o.= o        |
2026-01-25 07:38:14.017910 | controller -> localhost | | . .oo.* .       |
2026-01-25 07:38:14.017928 | controller -> localhost | |....o=..S        |
2026-01-25 07:38:14.017947 | controller -> localhost | |ooEo* o          |
2026-01-25 07:38:14.017966 | controller -> localhost | |o+*o.+           |
2026-01-25 07:38:14.017984 | controller -> localhost | |OB+=.            |
2026-01-25 07:38:14.018003 | controller -> localhost | |OO+o+.           |
2026-01-25 07:38:14.018020 | controller -> localhost | +----[SHA256]-----+
2026-01-25 07:38:14.018067 | controller -> localhost | ok: Runtime: 0:00:00.924654
2026-01-25 07:38:14.025508 | 
2026-01-25 07:38:14.025577 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-25 07:38:14.280750 | controller | ok
2026-01-25 07:38:14.378948 | controller | included: /var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-25 07:38:14.435685 | 
2026-01-25 07:38:14.435779 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-25 07:38:14.517049 | controller | skipping: Conditional result was False
2026-01-25 07:38:14.523080 | 
2026-01-25 07:38:14.523170 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-25 07:38:15.173594 | controller | changed
2026-01-25 07:38:15.178568 | 
2026-01-25 07:38:15.178644 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-25 07:38:15.510769 | controller | ok
2026-01-25 07:38:15.526884 | 
2026-01-25 07:38:15.526978 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-25 07:38:16.472141 | controller | changed
2026-01-25 07:38:16.490256 | 
2026-01-25 07:38:16.490398 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-25 07:38:17.536482 | controller | changed
2026-01-25 07:38:17.548800 | 
2026-01-25 07:38:17.548880 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-25 07:38:17.625617 | controller | skipping: Conditional result was False
2026-01-25 07:38:17.638059 | 
2026-01-25 07:38:17.638157 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-25 07:38:19.248344 | controller -> localhost | changed
2026-01-25 07:38:19.261868 | 
2026-01-25 07:38:19.262048 | TASK [add-build-sshkey : Add back temp key]
2026-01-25 07:38:20.693251 | controller -> localhost | Identity added: /var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/85a1a9e732ff40f9aec7d8a6f43ebfea_id_rsa (zuul-build-sshkey)
2026-01-25 07:38:20.693512 | controller -> localhost | ok: Runtime: 0:00:00.046672
2026-01-25 07:38:20.699495 | 
2026-01-25 07:38:20.699564 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-25 07:38:21.454671 | controller | ok
2026-01-25 07:38:21.462638 | 
2026-01-25 07:38:21.462893 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-25 07:38:21.565371 | controller | skipping: Conditional result was False
2026-01-25 07:38:21.580075 | 
2026-01-25 07:38:21.580181 | TASK [include_role : validate-host]
2026-01-25 07:38:21.643646 | controller | ok
2026-01-25 07:38:21.710040 | 
2026-01-25 07:38:21.710141 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-25 07:38:21.829795 | controller | ok
2026-01-25 07:38:21.842508 | 
2026-01-25 07:38:21.842594 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-25 07:38:22.951601 | controller -> localhost | ok
2026-01-25 07:38:22.957120 | 
2026-01-25 07:38:22.957197 | TASK [validate-host : Collect information about the host]
2026-01-25 07:38:23.637590 | controller | ok
2026-01-25 07:38:23.664405 | 
2026-01-25 07:38:23.664508 | TASK [validate-host : Sanitize hostname]
2026-01-25 07:38:23.818528 | controller | ok
2026-01-25 07:38:23.826894 | 
2026-01-25 07:38:23.828360 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-25 07:38:25.604149 | controller -> localhost | changed
2026-01-25 07:38:25.610338 | 
2026-01-25 07:38:25.610418 | TASK [validate-host : Collect information about zuul worker]
2026-01-25 07:38:26.154457 | controller | ok
2026-01-25 07:38:26.159816 | 
2026-01-25 07:38:26.159894 | TASK [validate-host : Write out all zuul information for each host]
2026-01-25 07:38:27.515924 | controller -> localhost | changed
2026-01-25 07:38:27.527245 | 
2026-01-25 07:38:27.527354 | TASK [include_role : prepare-workspace-openshift]
2026-01-25 07:38:27.586374 | controller | skipping: Conditional result was False
2026-01-25 07:38:27.591816 | 
2026-01-25 07:38:27.591892 | TASK [include_role : remove-zuul-sshkey]
2026-01-25 07:38:27.666576 | controller | skipping: Conditional result was False
2026-01-25 07:38:27.672796 | 
2026-01-25 07:38:27.673024 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-25 07:38:27.982879 | controller | ok: "logs"
2026-01-25 07:38:27.983113 | controller | ok: All items complete
2026-01-25 07:38:27.983151 | 
2026-01-25 07:38:28.189265 | controller | ok: "artifacts"
2026-01-25 07:38:28.370122 | controller | ok: "docs"
2026-01-25 07:38:28.379747 | 
2026-01-25 07:38:28.379929 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-25 07:38:28.643908 | controller | changed: "logs"
2026-01-25 07:38:28.827395 | controller | changed: "artifacts"
2026-01-25 07:38:29.022366 | controller | changed: "docs"
2026-01-25 07:38:29.089469 | 
2026-01-25 07:38:29.089557 | PLAY RECAP
2026-01-25 07:38:29.089608 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-25 07:38:29.089639 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-25 07:38:29.089659 | 
2026-01-25 07:38:29.398700 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-25 07:38:29.399450 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-25 07:38:30.678518 | 
2026-01-25 07:38:30.678669 | PLAY [all]
2026-01-25 07:38:30.714337 | 
2026-01-25 07:38:30.714458 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-25 07:38:30.855904 | controller | ok
2026-01-25 07:38:30.861506 | 
2026-01-25 07:38:30.861601 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-25 07:38:31.642450 | controller | changed
2026-01-25 07:38:31.665220 | 
2026-01-25 07:38:31.665350 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-25 07:38:33.126721 | controller | changed
2026-01-25 07:38:33.153986 | 
2026-01-25 07:38:33.154118 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-25 07:38:34.113188 | controller | changed:
2026-01-25 07:38:34.113398 | controller | {
2026-01-25 07:38:34.113437 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-25 07:38:34.113469 | controller | }
2026-01-25 07:38:34.334208 | controller | changed:
2026-01-25 07:38:34.334331 | controller | {
2026-01-25 07:38:34.334373 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-25 07:38:34.334399 | controller | }
2026-01-25 07:38:34.543506 | controller | changed:
2026-01-25 07:38:34.543600 | controller | {
2026-01-25 07:38:34.543628 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-25 07:38:34.543650 | controller | }
2026-01-25 07:38:34.763168 | controller | changed:
2026-01-25 07:38:34.763257 | controller | {
2026-01-25 07:38:34.763285 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-25 07:38:34.763337 | controller | }
2026-01-25 07:38:34.988435 | controller | changed:
2026-01-25 07:38:34.988514 | controller | {
2026-01-25 07:38:34.988542 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-25 07:38:34.988564 | controller | }
2026-01-25 07:38:35.210654 | controller | changed:
2026-01-25 07:38:35.210734 | controller | {
2026-01-25 07:38:35.210763 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-25 07:38:35.210785 | controller | }
2026-01-25 07:38:35.427464 | controller | changed:
2026-01-25 07:38:35.427637 | controller | {
2026-01-25 07:38:35.427677 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-01-25 07:38:35.427712 | controller | }
2026-01-25 07:38:35.635462 | controller | changed:
2026-01-25 07:38:35.635561 | controller | {
2026-01-25 07:38:35.635592 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-01-25 07:38:35.635615 | controller | }
2026-01-25 07:38:35.882157 | controller | changed:
2026-01-25 07:38:35.882242 | controller | {
2026-01-25 07:38:35.882271 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-25 07:38:35.882322 | controller | }
2026-01-25 07:38:36.189692 | controller | changed:
2026-01-25 07:38:36.190165 | controller | {
2026-01-25 07:38:36.190203 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-25 07:38:36.190255 | controller | }
2026-01-25 07:38:36.340710 | controller | changed:
2026-01-25 07:38:36.340794 | controller | {
2026-01-25 07:38:36.340823 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-25 07:38:36.340845 | controller | }
2026-01-25 07:38:36.591428 | controller | changed:
2026-01-25 07:38:36.591512 | controller | {
2026-01-25 07:38:36.591540 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-25 07:38:36.591561 | controller | }
2026-01-25 07:38:36.823931 | controller | changed:
2026-01-25 07:38:36.824018 | controller | {
2026-01-25 07:38:36.824047 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-25 07:38:36.824069 | controller | }
2026-01-25 07:38:37.062129 | controller | changed:
2026-01-25 07:38:37.062221 | controller | {
2026-01-25 07:38:37.062248 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-25 07:38:37.062269 | controller | }
2026-01-25 07:38:37.313997 | controller | changed:
2026-01-25 07:38:37.314092 | controller | {
2026-01-25 07:38:37.314123 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-25 07:38:37.314145 | controller | }
2026-01-25 07:38:37.555470 | controller | changed:
2026-01-25 07:38:37.555853 | controller | {
2026-01-25 07:38:37.555889 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-25 07:38:37.555912 | controller | }
2026-01-25 07:38:37.803075 | controller | changed:
2026-01-25 07:38:37.803164 | controller | {
2026-01-25 07:38:37.803192 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-01-25 07:38:37.803214 | controller | }
2026-01-25 07:38:38.036926 | controller | changed:
2026-01-25 07:38:38.037022 | controller | {
2026-01-25 07:38:38.037052 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-01-25 07:38:38.037097 | controller | }
2026-01-25 07:38:38.284541 | controller | changed:
2026-01-25 07:38:38.284625 | controller | {
2026-01-25 07:38:38.284653 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-25 07:38:38.284782 | controller | }
2026-01-25 07:38:38.519642 | controller | changed:
2026-01-25 07:38:38.519719 | controller | {
2026-01-25 07:38:38.519746 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-25 07:38:38.519768 | controller | }
2026-01-25 07:38:38.738245 | controller | changed:
2026-01-25 07:38:38.738461 | controller | {
2026-01-25 07:38:38.738495 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-25 07:38:38.738517 | controller | }
2026-01-25 07:38:38.961911 | controller | changed:
2026-01-25 07:38:38.961991 | controller | {
2026-01-25 07:38:38.962019 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-25 07:38:38.962041 | controller | }
2026-01-25 07:38:39.191479 | controller | changed:
2026-01-25 07:38:39.191569 | controller | {
2026-01-25 07:38:39.191597 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-01-25 07:38:39.191620 | controller | }
2026-01-25 07:38:39.417967 | controller | changed:
2026-01-25 07:38:39.418056 | controller | {
2026-01-25 07:38:39.418093 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-25 07:38:39.418117 | controller | }
2026-01-25 07:38:39.638331 | controller | changed:
2026-01-25 07:38:39.638412 | controller | {
2026-01-25 07:38:39.638441 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-25 07:38:39.638463 | controller | }
2026-01-25 07:38:39.855069 | controller | changed:
2026-01-25 07:38:39.855174 | controller | {
2026-01-25 07:38:39.855203 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-25 07:38:39.855225 | controller | }
2026-01-25 07:38:39.897591 | 
2026-01-25 07:38:39.897713 | TASK [Set timezone to UTC]
2026-01-25 07:38:40.776412 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-25 07:38:40.781227 | 
2026-01-25 07:38:40.785184 | TASK [Create nodepool directory]
2026-01-25 07:38:41.069396 | controller | changed
2026-01-25 07:38:41.075876 | 
2026-01-25 07:38:41.075967 | TASK [Create nodepool sub_nodes file]
2026-01-25 07:38:41.765152 | controller | changed
2026-01-25 07:38:41.774195 | 
2026-01-25 07:38:41.774284 | TASK [Create nodepool sub_nodes_private file]
2026-01-25 07:38:42.502972 | controller | changed
2026-01-25 07:38:42.522909 | 
2026-01-25 07:38:42.522993 | LOOP [Populate nodepool sub_nodes file]
2026-01-25 07:38:42.626623 | 
2026-01-25 07:38:42.626817 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-25 07:38:42.728065 | 
2026-01-25 07:38:42.728227 | TASK [Create nodepool primary file]
2026-01-25 07:38:42.802594 | controller | skipping: Conditional result was False
2026-01-25 07:38:42.808630 | 
2026-01-25 07:38:42.808723 | TASK [Create nodepool node_private for this node]
2026-01-25 07:38:43.526037 | controller | changed
2026-01-25 07:38:43.534974 | 
2026-01-25 07:38:43.535063 | LOOP [Copy ssh keys to nodepool directory]
2026-01-25 07:38:43.941618 | controller | ok: Item: id_rsa Runtime: 0:00:00.004545
2026-01-25 07:38:43.941782 | 
2026-01-25 07:38:44.110406 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004682
2026-01-25 07:38:44.128901 | 
2026-01-25 07:38:44.129008 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-25 07:38:44.797105 | controller | changed
2026-01-25 07:38:44.804947 | 
2026-01-25 07:38:44.805033 | TASK [Validate sudoers config after edits]
2026-01-25 07:38:45.091685 | controller | /etc/sudoers: parsed OK
2026-01-25 07:38:45.091776 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-25 07:38:45.091787 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-25 07:38:45.091794 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-25 07:38:45.364098 | controller | ok: Runtime: 0:00:00.004818
2026-01-25 07:38:45.370057 | 
2026-01-25 07:38:45.370231 | TASK [Show the environment passed in to job shell scripts]
2026-01-25 07:38:45.653617 | controller | SHELL=/bin/bash
2026-01-25 07:38:45.653659 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-25 07:38:45.653687 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-25 07:38:45.653695 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/19/3619/357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:38:45.653701 | controller | PWD=/home/zuul
2026-01-25 07:38:45.653707 | controller | ZUUL_PIPELINE=github-check
2026-01-25 07:38:45.653712 | controller | LOGNAME=zuul
2026-01-25 07:38:45.653717 | controller | XDG_SESSION_TYPE=tty
2026-01-25 07:38:45.653723 | controller | _=/usr/bin/env
2026-01-25 07:38:45.653728 | controller | MOTD_SHOWN=pam
2026-01-25 07:38:45.653733 | controller | HOME=/home/zuul
2026-01-25 07:38:45.653739 | controller | LANG=en_US.UTF-8
2026-01-25 07:38:45.653749 | controller | SSH_CONNECTION=192.168.26.12 38612 192.168.26.27 22
2026-01-25 07:38:45.653789 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-01-25 07:38:45.653800 | controller | ZUUL_CHANGE_IDS=3619,357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:38:45.653806 | controller | WORKSPACE=/home/zuul/workspace
2026-01-25 07:38:45.653811 | controller | XDG_SESSION_CLASS=user
2026-01-25 07:38:45.653817 | controller | SELINUX_ROLE_REQUESTED=
2026-01-25 07:38:45.653822 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-25 07:38:45.653827 | controller | USER=zuul
2026-01-25 07:38:45.653833 | controller | ZUUL_VOTING=True
2026-01-25 07:38:45.653838 | controller | BUILD_TIMEOUT=1800000
2026-01-25 07:38:45.653844 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-25 07:38:45.653849 | controller | SHLVL=1
2026-01-25 07:38:45.653854 | controller | ZUUL_PATCHSET=357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:38:45.653860 | controller | XDG_SESSION_ID=1
2026-01-25 07:38:45.653865 | controller | ZUUL_BRANCH=main
2026-01-25 07:38:45.653870 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-25 07:38:45.653876 | controller | SSH_CLIENT=192.168.26.12 38612 22
2026-01-25 07:38:45.653881 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-25 07:38:45.653887 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-25 07:38:45.653892 | controller | which_declare=declare -f
2026-01-25 07:38:45.653898 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-25 07:38:45.653903 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-25 07:38:45.653908 | controller | ZUUL_CHANGE=3619
2026-01-25 07:38:45.653915 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-25 07:38:45.653920 | controller | ZUUL_UUID=85a1a9e732ff40f9aec7d8a6f43ebfea
2026-01-25 07:38:45.653925 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-25 07:38:45.653931 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-25 07:38:45.653936 | controller | }
2026-01-25 07:38:45.910174 | controller | ok: Runtime: 0:00:00.005877
2026-01-25 07:38:45.915773 | 
2026-01-25 07:38:45.915838 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-25 07:38:45.969987 | controller | skipping: Conditional result was False
2026-01-25 07:38:45.976224 | 
2026-01-25 07:38:45.976345 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-25 07:38:46.562989 | controller | skipping: Conditional result was False
2026-01-25 07:38:46.569061 | 
2026-01-25 07:38:46.569264 | TASK [Ensure legacy workspace directory]
2026-01-25 07:38:46.795018 | controller | changed
2026-01-25 07:38:46.984811 | 
2026-01-25 07:38:46.984920 | PLAY RECAP
2026-01-25 07:38:46.984970 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-25 07:38:46.984994 | 
2026-01-25 07:38:47.091899 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-25 07:38:47.092693 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-25 07:38:48.880120 | 
2026-01-25 07:38:48.880220 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-25 07:38:48.930991 | 
2026-01-25 07:38:48.931098 | TASK [Create zuul-output directory]
2026-01-25 07:38:49.500506 | controller | changed
2026-01-25 07:38:49.509686 | 
2026-01-25 07:38:49.509786 | TASK [Slurp Zuul inventory test]
2026-01-25 07:38:50.358844 | controller -> localhost | ok
2026-01-25 07:38:50.368848 | 
2026-01-25 07:38:50.368995 | TASK [Save zuul inventory]
2026-01-25 07:38:51.348230 | controller | changed
2026-01-25 07:38:51.356760 | 
2026-01-25 07:38:51.356844 | TASK [Save zuul vars without the change_message]
2026-01-25 07:38:52.001543 | controller | changed
2026-01-25 07:38:52.032479 | 
2026-01-25 07:38:52.032556 | PLAY RECAP
2026-01-25 07:38:52.032602 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-25 07:38:52.032627 | 
2026-01-25 07:38:52.117382 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-25 07:38:52.118140 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-25 07:38:52.767580 | 
2026-01-25 07:38:52.767681 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-25 07:38:52.795421 | 
2026-01-25 07:38:52.795551 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-25 07:38:52.813796 | controller | ok
2026-01-25 07:38:52.830931 | 
2026-01-25 07:38:52.831020 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-25 07:38:52.854546 | controller | skipping: Conditional result was False
2026-01-25 07:38:52.861512 | 
2026-01-25 07:38:52.861633 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-25 07:38:53.205063 | controller | ok
2026-01-25 07:38:53.214933 | 
2026-01-25 07:38:53.215058 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-25 07:38:53.843147 | controller | ok
2026-01-25 07:38:53.854494 | 
2026-01-25 07:38:53.854582 | TASK [Prepare workspace]
2026-01-25 07:38:53.873470 | controller | ok
2026-01-25 07:38:53.892429 | 
2026-01-25 07:38:53.892583 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-25 07:38:54.196143 | controller | ok
2026-01-25 07:38:54.202888 | 
2026-01-25 07:38:54.202968 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-25 07:38:56.814726 | controller | Output suppressed because no_log was given
2026-01-25 07:38:56.823502 | 
2026-01-25 07:38:56.823574 | LOOP [Create zuul-output directory]
2026-01-25 07:38:57.029775 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-25 07:38:57.216688 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-25 07:38:57.231208 | 
2026-01-25 07:38:57.231291 | TASK [Install required packages]
2026-01-25 07:39:35.385115 | controller | changed
2026-01-25 07:39:35.390573 | 
2026-01-25 07:39:35.390651 | TASK [Install venv]
2026-01-25 07:40:42.685180 | controller | changed
2026-01-25 07:40:42.710746 | 
2026-01-25 07:40:42.710826 | PLAY RECAP
2026-01-25 07:40:42.710871 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-25 07:40:42.710894 | 
2026-01-25 07:40:42.791217 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-25 07:40:42.792042 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-25 07:40:43.346237 | 
2026-01-25 07:40:43.346362 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-25 07:40:43.369105 | 
2026-01-25 07:40:43.369219 | TASK [Gather required facts]
2026-01-25 07:40:43.891078 | controller | ok
2026-01-25 07:40:43.901818 | 
2026-01-25 07:40:43.901924 | TASK [Load environment var if instructed to]
2026-01-25 07:40:43.926507 | controller | skipping: Conditional result was False
2026-01-25 07:40:43.931898 | 
2026-01-25 07:40:43.931979 | TASK [Ensure group_vars dir exists]
2026-01-25 07:40:44.244224 | controller | ok
2026-01-25 07:40:44.251099 | 
2026-01-25 07:40:44.251193 | TASK [Print related variables]
2026-01-25 07:40:44.281346 | controller | ok:
2026-01-25 07:40:44.281497 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-25 07:40:44.281531 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh
2026-01-25 07:40:44.286966 | 
2026-01-25 07:40:44.287037 | TASK [Run molecule]
2026-01-25 07:40:45.238603 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-25 07:40:45.307753 | controller | INFO     Performing prerun with role_name_check=0...
2026-01-25 07:41:02.362678 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.363161 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.363688 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.364136 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.364630 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+357a1d5b was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.0.0+357a1d5b (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.365077 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.365525 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.365953 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.366414 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.366836 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.367310 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.367748 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.368162 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.368623 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.369045 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/e43bbd/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:41:02.378303 | controller | INFO     Running default > prepare
2026-01-25 07:41:03.011674 | controller |
2026-01-25 07:41:03.011724 | controller | PLAY [Prepare] *****************************************************************
2026-01-25 07:41:03.011820 | controller |
2026-01-25 07:41:03.011908 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:41:03.011999 | controller | Sunday 25 January 2026  07:41:03 +0000 (0:00:00.010)       0:00:00.010 ********
2026-01-25 07:41:03.832412 | controller | ok: [instance]
2026-01-25 07:41:03.832462 | controller |
2026-01-25 07:41:03.832571 | controller | TASK [Create temp file] ********************************************************
2026-01-25 07:41:03.832669 | controller | Sunday 25 January 2026  07:41:03 +0000 (0:00:00.821)       0:00:00.831 ********
2026-01-25 07:41:04.362642 | controller | changed: [instance]
2026-01-25 07:41:04.362702 | controller |
2026-01-25 07:41:04.362785 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:41:04.362883 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-01-25 07:41:04.362968 | controller |
2026-01-25 07:41:04.363065 | controller | Sunday 25 January 2026  07:41:04 +0000 (0:00:00.530)       0:00:01.362 ********
2026-01-25 07:41:04.363156 | controller | ===============================================================================
2026-01-25 07:41:04.363271 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2026-01-25 07:41:04.363390 | controller | Create temp file -------------------------------------------------------- 0.53s
2026-01-25 07:41:04.419025 | controller | INFO     Running default > converge
2026-01-25 07:41:04.827636 | controller |
2026-01-25 07:41:04.827684 | controller | PLAY [Converge] ****************************************************************
2026-01-25 07:41:04.827833 | controller |
2026-01-25 07:41:04.827889 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:41:04.827992 | controller | Sunday 25 January 2026  07:41:04 +0000 (0:00:00.035)       0:00:00.035 ********
2026-01-25 07:41:05.603628 | controller | ok: [instance]
2026-01-25 07:41:05.603708 | controller |
2026-01-25 07:41:05.603832 | controller | TASK [Include deploy_bmh role] *************************************************
2026-01-25 07:41:05.603950 | controller | Sunday 25 January 2026  07:41:05 +0000 (0:00:00.776)       0:00:00.811 ********
2026-01-25 07:41:05.628964 | controller |
2026-01-25 07:41:05.629082 | controller | TASK [deploy_bmh : Load baremetal hosts information from file] *****************
2026-01-25 07:41:05.629196 | controller | Sunday 25 January 2026  07:41:05 +0000 (0:00:00.025)       0:00:00.837 ********
2026-01-25 07:41:05.653190 | controller | ok: [instance]
2026-01-25 07:41:05.653281 | controller |
2026-01-25 07:41:05.653390 | controller | TASK [deploy_bmh : Set baremetal hosts facts] **********************************
2026-01-25 07:41:05.653491 | controller | Sunday 25 January 2026  07:41:05 +0000 (0:00:00.024)       0:00:00.861 ********
2026-01-25 07:41:05.686943 | controller | ok: [instance]
2026-01-25 07:41:05.687023 | controller |
2026-01-25 07:41:05.687116 | controller | TASK [deploy_bmh : Create destination directory] *******************************
2026-01-25 07:41:05.687208 | controller | Sunday 25 January 2026  07:41:05 +0000 (0:00:00.033)       0:00:00.895 ********
2026-01-25 07:41:05.991943 | controller | changed: [instance]
2026-01-25 07:41:05.991995 | controller |
2026-01-25 07:41:05.992079 | controller | TASK [deploy_bmh : Build and create Secret CRs] ********************************
2026-01-25 07:41:05.992173 | controller | Sunday 25 January 2026  07:41:05 +0000 (0:00:00.304)       0:00:01.200 ********
2026-01-25 07:41:06.049700 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-0)
2026-01-25 07:41:06.049800 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-1)
2026-01-25 07:41:06.049926 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=controller-0)
2026-01-25 07:41:06.050052 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=crc-0)
2026-01-25 07:41:06.050139 | controller |
2026-01-25 07:41:06.050230 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:06.050363 | controller | Sunday 25 January 2026  07:41:06 +0000 (0:00:00.057)       0:00:01.257 ********
2026-01-25 07:41:06.614715 | controller | changed: [instance]
2026-01-25 07:41:06.614770 | controller |
2026-01-25 07:41:06.615019 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:06.644845 | controller | Sunday 25 January 2026  07:41:06 +0000 (0:00:00.564)       0:00:01.822 ********
2026-01-25 07:41:06.644876 | controller | skipping: [instance]
2026-01-25 07:41:06.673734 | controller |
2026-01-25 07:41:06.673754 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:06.673761 | controller | Sunday 25 January 2026  07:41:06 +0000 (0:00:00.030)       0:00:01.852 ********
2026-01-25 07:41:06.673772 | controller | skipping: [instance]
2026-01-25 07:41:07.039501 | controller |
2026-01-25 07:41:07.039533 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:07.039541 | controller | Sunday 25 January 2026  07:41:06 +0000 (0:00:00.028)       0:00:01.881 ********
2026-01-25 07:41:07.039552 | controller | changed: [instance]
2026-01-25 07:41:07.074161 | controller |
2026-01-25 07:41:07.074187 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:07.074194 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.365)       0:00:02.247 ********
2026-01-25 07:41:07.074203 | controller | skipping: [instance]
2026-01-25 07:41:07.107443 | controller |
2026-01-25 07:41:07.107464 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:07.107473 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.034)       0:00:02.282 ********
2026-01-25 07:41:07.107483 | controller | skipping: [instance]
2026-01-25 07:41:07.494877 | controller |
2026-01-25 07:41:07.494908 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:07.494916 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.033)       0:00:02.315 ********
2026-01-25 07:41:07.494927 | controller | changed: [instance]
2026-01-25 07:41:07.525672 | controller |
2026-01-25 07:41:07.525699 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:07.525708 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.387)       0:00:02.702 ********
2026-01-25 07:41:07.525718 | controller | skipping: [instance]
2026-01-25 07:41:07.555712 | controller |
2026-01-25 07:41:07.555741 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:07.555750 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.030)       0:00:02.733 ********
2026-01-25 07:41:07.555761 | controller | skipping: [instance]
2026-01-25 07:41:07.919292 | controller |
2026-01-25 07:41:07.919341 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:07.919350 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.029)       0:00:02.763 ********
2026-01-25 07:41:07.919361 | controller | changed: [instance]
2026-01-25 07:41:07.948756 | controller |
2026-01-25 07:41:07.948780 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:07.948787 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.363)       0:00:03.127 ********
2026-01-25 07:41:07.948802 | controller | skipping: [instance]
2026-01-25 07:41:07.977878 | controller |
2026-01-25 07:41:07.977909 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:07.977917 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.029)       0:00:03.156 ********
2026-01-25 07:41:07.977927 | controller | skipping: [instance]
2026-01-25 07:41:08.026273 | controller |
2026-01-25 07:41:08.026307 | controller | TASK [deploy_bmh : Build and create BMH Netconfig Secrets] *********************
2026-01-25 07:41:08.026315 | controller | Sunday 25 January 2026  07:41:07 +0000 (0:00:00.029)       0:00:03.185 ********
2026-01-25 07:41:08.026344 | controller | skipping: [instance] => (item=compute-0)
2026-01-25 07:41:08.087386 | controller | skipping: [instance] => (item=compute-1)
2026-01-25 07:41:08.087427 | controller | skipping: [instance] => (item=controller-0)
2026-01-25 07:41:08.087437 | controller | skipping: [instance] => (item=crc-0)
2026-01-25 07:41:08.087445 | controller | skipping: [instance]
2026-01-25 07:41:08.087452 | controller |
2026-01-25 07:41:08.087459 | controller | TASK [deploy_bmh : Build and create BMH CRs] ***********************************
2026-01-25 07:41:08.087466 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.047)       0:00:03.233 ********
2026-01-25 07:41:08.087478 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-0)
2026-01-25 07:41:08.087508 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=compute-1)
2026-01-25 07:41:08.087516 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=controller-0)
2026-01-25 07:41:08.087523 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/deploy_bmh/tasks/create_templated_resource.yml for instance => (item=crc-0)
2026-01-25 07:41:08.087530 | controller |
2026-01-25 07:41:08.087539 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:08.458811 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.061)       0:00:03.295 ********
2026-01-25 07:41:08.458846 | controller | changed: [instance]
2026-01-25 07:41:08.488685 | controller |
2026-01-25 07:41:08.488708 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:08.488716 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.371)       0:00:03.666 ********
2026-01-25 07:41:08.488727 | controller | skipping: [instance]
2026-01-25 07:41:08.518203 | controller |
2026-01-25 07:41:08.518227 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:08.518235 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.030)       0:00:03.696 ********
2026-01-25 07:41:08.518263 | controller | skipping: [instance]
2026-01-25 07:41:08.877408 | controller |
2026-01-25 07:41:08.877439 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:08.877447 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.029)       0:00:03.726 ********
2026-01-25 07:41:08.877457 | controller | changed: [instance]
2026-01-25 07:41:08.906698 | controller |
2026-01-25 07:41:08.906723 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:08.906731 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.358)       0:00:04.085 ********
2026-01-25 07:41:08.906741 | controller | skipping: [instance]
2026-01-25 07:41:08.935827 | controller |
2026-01-25 07:41:08.935852 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:08.935859 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.029)       0:00:04.114 ********
2026-01-25 07:41:08.935869 | controller | skipping: [instance]
2026-01-25 07:41:09.295337 | controller |
2026-01-25 07:41:09.295368 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:09.295376 | controller | Sunday 25 January 2026  07:41:08 +0000 (0:00:00.029)       0:00:04.143 ********
2026-01-25 07:41:09.295386 | controller | changed: [instance]
2026-01-25 07:41:09.295630 | controller |
2026-01-25 07:41:09.295644 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:09.328064 | controller | Sunday 25 January 2026  07:41:09 +0000 (0:00:00.359)       0:00:04.503 ********
2026-01-25 07:41:09.328098 | controller | skipping: [instance]
2026-01-25 07:41:09.358720 | controller |
2026-01-25 07:41:09.358750 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:09.358760 | controller | Sunday 25 January 2026  07:41:09 +0000 (0:00:00.032)       0:00:04.535 ********
2026-01-25 07:41:09.358772 | controller | skipping: [instance]
2026-01-25 07:41:09.733700 | controller |
2026-01-25 07:41:09.733736 | controller | TASK [deploy_bmh : Create the templates CR files] ******************************
2026-01-25 07:41:09.733745 | controller | Sunday 25 January 2026  07:41:09 +0000 (0:00:00.030)       0:00:04.566 ********
2026-01-25 07:41:09.733754 | controller | changed: [instance]
2026-01-25 07:41:09.762922 | controller |
2026-01-25 07:41:09.762944 | controller | TASK [deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists] *********
2026-01-25 07:41:09.762952 | controller | Sunday 25 January 2026  07:41:09 +0000 (0:00:00.374)       0:00:04.941 ********
2026-01-25 07:41:09.762962 | controller | skipping: [instance]
2026-01-25 07:41:09.809362 | controller |
2026-01-25 07:41:09.809390 | controller | TASK [deploy_bmh : Apply the generated CRs] ************************************
2026-01-25 07:41:09.809398 | controller | Sunday 25 January 2026  07:41:09 +0000 (0:00:00.029)       0:00:04.971 ********
2026-01-25 07:41:09.809409 | controller | skipping: [instance]
2026-01-25 07:41:09.809432 | controller |
2026-01-25 07:41:09.809439 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:41:09.809444 | controller | instance                   : ok=20   changed=9    unreachable=0    failed=0    skipped=17   rescued=0    ignored=0
2026-01-25 07:41:09.809450 | controller |
2026-01-25 07:41:09.809456 | controller | Sunday 25 January 2026  07:41:09 +0000 (0:00:00.044)       0:00:05.015 ********
2026-01-25 07:41:09.809461 | controller | ===============================================================================
2026-01-25 07:41:09.809466 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2026-01-25 07:41:09.809471 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.56s
2026-01-25 07:41:09.809477 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.39s
2026-01-25 07:41:09.809482 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.37s
2026-01-25 07:41:09.809487 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.37s
2026-01-25 07:41:09.809493 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.37s
2026-01-25 07:41:09.809498 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.36s
2026-01-25 07:41:09.809503 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.36s
2026-01-25 07:41:09.809509 | controller | deploy_bmh : Create the templates CR files ------------------------------ 0.36s
2026-01-25 07:41:09.809514 | controller | deploy_bmh : Create destination directory ------------------------------- 0.30s
2026-01-25 07:41:09.809519 | controller | deploy_bmh : Build and create BMH CRs ----------------------------------- 0.06s
2026-01-25 07:41:09.809524 | controller | deploy_bmh : Build and create Secret CRs -------------------------------- 0.06s
2026-01-25 07:41:09.809530 | controller | deploy_bmh : Build and create BMH Netconfig Secrets --------------------- 0.05s
2026-01-25 07:41:09.809537 | controller | deploy_bmh : Apply the generated CRs ------------------------------------ 0.04s
2026-01-25 07:41:09.809556 | controller | deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists --------- 0.03s
2026-01-25 07:41:09.809874 | controller | deploy_bmh : Set baremetal hosts facts ---------------------------------- 0.03s
2026-01-25 07:41:09.809893 | controller | deploy_bmh : Apply the generated CRs ------------------------------------ 0.03s
2026-01-25 07:41:09.861801 | controller | deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists --------- 0.03s
2026-01-25 07:41:09.861824 | controller | deploy_bmh : Ensure cifmw_deploy_bmh_namespace namespace exists --------- 0.03s
2026-01-25 07:41:09.861832 | controller | deploy_bmh : Apply the generated CRs ------------------------------------ 0.03s
2026-01-25 07:41:09.861841 | controller | INFO     Running default > cleanup
2026-01-25 07:41:10.272270 | controller |
2026-01-25 07:41:11.073767 | controller | PLAY [Cleanup] *****************************************************************
2026-01-25 07:41:11.073837 | controller |
2026-01-25 07:41:11.073847 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:41:11.073853 | controller | Sunday 25 January 2026  07:41:10 +0000 (0:00:00.034)       0:00:00.034 ********
2026-01-25 07:41:11.073863 | controller | ok: [instance]
2026-01-25 07:41:11.099143 | controller |
2026-01-25 07:41:11.099187 | controller | TASK [Include deploy_bmh role] *************************************************
2026-01-25 07:41:11.099197 | controller | Sunday 25 January 2026  07:41:11 +0000 (0:00:00.801)       0:00:00.836 ********
2026-01-25 07:41:11.099217 | controller |
2026-01-25 07:41:11.389955 | controller | TASK [deploy_bmh : Get the BM manifests] ***************************************
2026-01-25 07:41:11.389986 | controller | Sunday 25 January 2026  07:41:11 +0000 (0:00:00.025)       0:00:00.862 ********
2026-01-25 07:41:11.389999 | controller | ok: [instance]
2026-01-25 07:41:11.446003 | controller |
2026-01-25 07:41:11.446033 | controller | TASK [deploy_bmh : Removing resources if deployed] *****************************
2026-01-25 07:41:11.446042 | controller | Sunday 25 January 2026  07:41:11 +0000 (0:00:00.290)       0:00:01.152 ********
2026-01-25 07:41:11.446054 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 12790085, 'dev': 64513, 'nlink': 1, 'atime': 1769326866.5795002, 'mtime': 1769326866.333497, 'ctime': 1769326866.5825002, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:11.446080 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 29404828, 'dev': 64513, 'nlink': 1, 'atime': 1769326867.0035052, 'mtime': 1769326866.8635035, 'ctime': 1769326867.0065053, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:11.446092 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 177, 'inode': 42031851, 'dev': 64513, 'nlink': 1, 'atime': 1769326867.4585106, 'mtime': 1769326867.321509, 'ctime': 1769326867.4615107, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:11.446099 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 170, 'inode': 54550401, 'dev': 64513, 'nlink': 1, 'atime': 1769326867.8845155, 'mtime': 1769326867.750514, 'ctime': 1769326867.8875155, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:11.446104 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 67129558, 'dev': 64513, 'nlink': 1, 'atime': 1769326868.425522, 'mtime': 1769326868.2935205, 'ctime': 1769326868.428522, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:11.446112 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 79717004, 'dev': 64513, 'nlink': 1, 'atime': 1769326868.842527, 'mtime': 1769326868.7105255, 'ctime': 1769326868.845527, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899167 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 425, 'inode': 92295135, 'dev': 64513, 'nlink': 1, 'atime': 1769326869.261532, 'mtime': 1769326869.1275303, 'ctime': 1769326869.264532, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899203 | controller | skipping: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 404, 'inode': 104870077, 'dev': 64513, 'nlink': 1, 'atime': 1769326869.698537, 'mtime': 1769326869.5575354, 'ctime': 1769326869.7015371, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899211 | controller | skipping: [instance]
2026-01-25 07:41:12.899218 | controller |
2026-01-25 07:41:12.899224 | controller | TASK [deploy_bmh : Delete CR files] ********************************************
2026-01-25 07:41:12.899230 | controller | Sunday 25 January 2026  07:41:11 +0000 (0:00:00.055)       0:00:01.208 ********
2026-01-25 07:41:12.899260 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 12790085, 'dev': 64513, 'nlink': 1, 'atime': 1769326866.5795002, 'mtime': 1769326866.333497, 'ctime': 1769326866.5825002, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899271 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 174, 'inode': 29404828, 'dev': 64513, 'nlink': 1, 'atime': 1769326867.0035052, 'mtime': 1769326866.8635035, 'ctime': 1769326867.0065053, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899277 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 177, 'inode': 42031851, 'dev': 64513, 'nlink': 1, 'atime': 1769326867.4585106, 'mtime': 1769326867.321509, 'ctime': 1769326867.4615107, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899283 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-secret-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 170, 'inode': 54550401, 'dev': 64513, 'nlink': 1, 'atime': 1769326867.8845155, 'mtime': 1769326867.750514, 'ctime': 1769326867.8875155, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899293 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 67129558, 'dev': 64513, 'nlink': 1, 'atime': 1769326868.425522, 'mtime': 1769326868.2935205, 'ctime': 1769326868.428522, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899304 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-compute-1.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 416, 'inode': 79717004, 'dev': 64513, 'nlink': 1, 'atime': 1769326868.842527, 'mtime': 1769326868.7105255, 'ctime': 1769326868.845527, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.899314 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-controller-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 425, 'inode': 92295135, 'dev': 64513, 'nlink': 1, 'atime': 1769326869.261532, 'mtime': 1769326869.1275303, 'ctime': 1769326869.264532, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.947568 | controller | changed: [instance] => (item={'path': '/home/zuul/ci-framework-data/artifacts/deploy_bmh/bmh-crc-0.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 404, 'inode': 104870077, 'dev': 64513, 'nlink': 1, 'atime': 1769326869.698537, 'mtime': 1769326869.5575354, 'ctime': 1769326869.7015371, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2026-01-25 07:41:12.947608 | controller |
2026-01-25 07:41:12.947617 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:41:12.947623 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-01-25 07:41:12.947629 | controller |
2026-01-25 07:41:12.947635 | controller | Sunday 25 January 2026  07:41:12 +0000 (0:00:01.452)       0:00:02.661 ********
2026-01-25 07:41:12.947641 | controller | ===============================================================================
2026-01-25 07:41:12.947646 | controller | deploy_bmh : Delete CR files -------------------------------------------- 1.45s
2026-01-25 07:41:12.947652 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2026-01-25 07:41:12.947657 | controller | deploy_bmh : Get the BM manifests --------------------------------------- 0.29s
2026-01-25 07:41:12.947662 | controller | deploy_bmh : Removing resources if deployed ----------------------------- 0.06s
2026-01-25 07:41:12.947674 | controller | Include deploy_bmh role ------------------------------------------------- 0.03s
2026-01-25 07:41:12.947685 | controller | INFO     Writing /tmp/report.html report.
2026-01-25 07:41:13.352623 | controller | changed
2026-01-25 07:41:13.374814 | 
2026-01-25 07:41:13.374872 | PLAY RECAP
2026-01-25 07:41:13.374912 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-25 07:41:13.374936 | 
2026-01-25 07:41:13.451019 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-25 07:41:13.451896 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-25 07:41:14.011682 | 
2026-01-25 07:41:14.011783 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-25 07:41:14.032072 | 
2026-01-25 07:41:14.032161 | TASK [Filter out host if needed]
2026-01-25 07:41:14.041787 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-25 07:41:14.046386 | 
2026-01-25 07:41:14.046456 | TASK [Ensure file is present]
2026-01-25 07:41:14.366992 | controller | ok
2026-01-25 07:41:14.374741 | 
2026-01-25 07:41:14.375242 | TASK [Manage molecule report file]
2026-01-25 07:41:14.903364 | controller | changed
2026-01-25 07:41:14.909042 | 
2026-01-25 07:41:14.909131 | TASK [Check if we get ci-framework-data basedir]
2026-01-25 07:41:15.093788 | controller | ok
2026-01-25 07:41:15.098988 | 
2026-01-25 07:41:15.099068 | TASK [Create ci-framework-data log directory for zuul]
2026-01-25 07:41:15.421001 | controller | changed
2026-01-25 07:41:15.426674 | 
2026-01-25 07:41:15.426764 | TASK [Copy ci-framework interesting files]
2026-01-25 07:41:15.640191 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-25 07:41:15.954183 | controller | changed
2026-01-25 07:41:15.959722 | 
2026-01-25 07:41:15.959813 | TASK [Get SELinux listing]
2026-01-25 07:41:16.486921 | controller | changed
2026-01-25 07:41:16.492953 | 
2026-01-25 07:41:16.493042 | TASK [Generate log index]
2026-01-25 07:41:17.140912 | controller | changed
2026-01-25 07:41:17.145914 | 
2026-01-25 07:41:17.145986 | TASK [Get some env related data]
2026-01-25 07:41:17.698588 | controller | /home/zuul/.local/bin/ansible
2026-01-25 07:41:18.682064 | controller | changed
2026-01-25 07:41:18.687511 | 
2026-01-25 07:41:18.687590 | TASK [Generate list of logs to collect in home directory]
2026-01-25 07:41:18.990518 | controller | ok: All paths examined
2026-01-25 07:41:18.995597 | 
2026-01-25 07:41:18.995657 | LOOP [Copy logs from home directory]
2026-01-25 07:41:19.310722 | controller | changed:
2026-01-25 07:41:19.310838 | controller | {
2026-01-25 07:41:19.310866 | controller |   "atime": 1769326825.6020126,
2026-01-25 07:41:19.310886 | controller |   "ctime": 1769326842.2442107,
2026-01-25 07:41:19.310903 | controller |   "dev": 64513,
2026-01-25 07:41:19.310920 | controller |   "gid": 1000,
2026-01-25 07:41:19.310936 | controller |   "gr_name": "zuul",
2026-01-25 07:41:19.310959 | controller |   "inode": 4330351,
2026-01-25 07:41:19.310976 | controller |   "isblk": false,
2026-01-25 07:41:19.310991 | controller |   "ischr": false,
2026-01-25 07:41:19.311005 | controller |   "isdir": false,
2026-01-25 07:41:19.311019 | controller |   "isfifo": false,
2026-01-25 07:41:19.311035 | controller |   "isgid": false,
2026-01-25 07:41:19.311052 | controller |   "islnk": false,
2026-01-25 07:41:19.311067 | controller |   "isreg": true,
2026-01-25 07:41:19.311082 | controller |   "issock": false,
2026-01-25 07:41:19.311096 | controller |   "isuid": false,
2026-01-25 07:41:19.311111 | controller |   "mode": "0644",
2026-01-25 07:41:19.311125 | controller |   "mtime": 1769326842.2442107,
2026-01-25 07:41:19.311139 | controller |   "nlink": 1,
2026-01-25 07:41:19.311168 | controller |   "path": "/home/zuul/ansible.log",
2026-01-25 07:41:19.311186 | controller |   "pw_name": "zuul",
2026-01-25 07:41:19.311201 | controller |   "rgrp": true,
2026-01-25 07:41:19.311216 | controller |   "roth": true,
2026-01-25 07:41:19.311231 | controller |   "rusr": true,
2026-01-25 07:41:19.311246 | controller |   "size": 6749,
2026-01-25 07:41:19.311260 | controller |   "uid": 1000,
2026-01-25 07:41:19.311273 | controller |   "wgrp": false,
2026-01-25 07:41:19.311287 | controller |   "woth": false,
2026-01-25 07:41:19.311327 | controller |   "wusr": true,
2026-01-25 07:41:19.311368 | controller |   "xgrp": false,
2026-01-25 07:41:19.311389 | controller |   "xoth": false,
2026-01-25 07:41:19.311405 | controller |   "xusr": false
2026-01-25 07:41:19.311420 | controller | }
2026-01-25 07:41:19.325191 | 
2026-01-25 07:41:19.325321 | TASK [Copy crio stats log file]
2026-01-25 07:41:19.349602 | controller | skipping: Conditional result was False
2026-01-25 07:41:19.355136 | 
2026-01-25 07:41:19.355212 | TASK [Get SELinux related data]
2026-01-25 07:41:19.562886 | controller | <no matches>
2026-01-25 07:41:19.881623 | controller | ERROR
2026-01-25 07:41:19.881844 | controller | {
2026-01-25 07:41:19.881882 | controller |   "delta": "0:00:00.008918",
2026-01-25 07:41:19.881904 | controller |   "end": "2026-01-25 07:41:19.564359",
2026-01-25 07:41:19.881925 | controller |   "msg": "non-zero return code",
2026-01-25 07:41:19.881943 | controller |   "rc": 1,
2026-01-25 07:41:19.881961 | controller |   "start": "2026-01-25 07:41:19.555441"
2026-01-25 07:41:19.881977 | controller | }
2026-01-25 07:41:19.882001 | controller | ERROR: Ignoring Errors
2026-01-25 07:41:19.887507 | 
2026-01-25 07:41:19.887574 | TASK [Create system configuration directory]
2026-01-25 07:41:20.078752 | controller | changed
2026-01-25 07:41:20.084820 | 
2026-01-25 07:41:20.084915 | TASK [Get some of the system configurations]
2026-01-25 07:41:20.637335 | controller | changed
2026-01-25 07:41:20.643004 | 
2026-01-25 07:41:20.643194 | TASK [Copy generated documentation if available]
2026-01-25 07:41:20.667290 | controller | skipping: Conditional result was False
2026-01-25 07:41:20.673878 | 
2026-01-25 07:41:20.673966 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-25 07:41:20.698514 | controller | skipping: Conditional result was False
2026-01-25 07:41:20.708339 | 
2026-01-25 07:41:20.708455 | TASK [Compress logs bigger than 2MB]
2026-01-25 07:41:21.237948 | controller | changed
2026-01-25 07:41:21.244354 | 
2026-01-25 07:41:21.244441 | TASK [Copy files from workspace on node]
2026-01-25 07:41:21.263447 | controller | ok
2026-01-25 07:41:21.287968 | 
2026-01-25 07:41:21.288067 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-25 07:41:21.311660 | controller | skipping: Conditional result was False
2026-01-25 07:41:21.318395 | 
2026-01-25 07:41:21.318483 | TASK [fetch-output : Set log path for single node]
2026-01-25 07:41:21.348553 | controller | ok
2026-01-25 07:41:21.353706 | 
2026-01-25 07:41:21.353787 | LOOP [fetch-output : Ensure local output dirs]
2026-01-25 07:41:21.583467 | controller -> localhost | ok: "/var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/logs"
2026-01-25 07:41:21.583702 | controller -> localhost | changed: All items complete
2026-01-25 07:41:21.583735 | 
2026-01-25 07:41:21.773934 | controller -> localhost | changed: "/var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/artifacts"
2026-01-25 07:41:21.973127 | controller -> localhost | changed: "/var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/docs"
2026-01-25 07:41:21.984510 | 
2026-01-25 07:41:21.984610 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-25 07:41:22.531024 | controller | changed:
2026-01-25 07:41:22.531210 | controller | .d..t...... ./
2026-01-25 07:41:22.531244 | controller | >f+++++++++ README.html
2026-01-25 07:41:22.531267 | controller | >f+++++++++ ansible-execution.log
2026-01-25 07:41:22.531289 | controller | >f+++++++++ ansible.log
2026-01-25 07:41:22.531351 | controller | >f+++++++++ dmesg.log
2026-01-25 07:41:22.531373 | controller | >f+++++++++ installed-pkgs.log
2026-01-25 07:41:22.531396 | controller | >f+++++++++ python.log
2026-01-25 07:41:22.531416 | controller | >f+++++++++ registries.conf
2026-01-25 07:41:22.531434 | controller | >f+++++++++ report.html
2026-01-25 07:41:22.531454 | controller | >f+++++++++ selinux-denials.log
2026-01-25 07:41:22.531471 | controller | >f+++++++++ selinux-listing.log
2026-01-25 07:41:22.531488 | controller | cd+++++++++ ci-framework-data/
2026-01-25 07:41:22.531504 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-25 07:41:22.531521 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-25 07:41:22.531537 | controller | cd+++++++++ ci-framework-data/artifacts/deploy_bmh/
2026-01-25 07:41:22.531555 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-25 07:41:22.531571 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-25 07:41:22.531587 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-25 07:41:22.531603 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-25 07:41:22.531619 | controller | cd+++++++++ registries.conf.d/
2026-01-25 07:41:22.531634 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-25 07:41:22.531650 | controller | cd+++++++++ system-config/
2026-01-25 07:41:22.531666 | controller | cd+++++++++ system-config/libvirt/
2026-01-25 07:41:22.531683 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-25 07:41:22.531698 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-25 07:41:22.927763 | controller | changed: .d..t...... ./
2026-01-25 07:41:23.294200 | controller | changed: .d..t...... ./
2026-01-25 07:41:23.314785 | 
2026-01-25 07:41:23.314881 | TASK [Return artifact to Zuul]
2026-01-25 07:41:23.343674 | controller | ok
2026-01-25 07:41:23.364263 | 
2026-01-25 07:41:23.364507 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-25 07:41:23.364630 | 
2026-01-25 07:41:23.364660 | PLAY RECAP
2026-01-25 07:41:23.364698 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-25 07:41:23.364719 | 
2026-01-25 07:41:23.462966 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-25 07:41:23.463735 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-25 07:41:24.022215 | 
2026-01-25 07:41:24.022335 | PLAY [all]
2026-01-25 07:41:24.041612 | 
2026-01-25 07:41:24.041697 | TASK [include_role : fetch-output]
2026-01-25 07:41:24.094687 | controller | ok
2026-01-25 07:41:24.118716 | 
2026-01-25 07:41:24.118825 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-25 07:41:24.173715 | controller | skipping: Conditional result was False
2026-01-25 07:41:24.179867 | 
2026-01-25 07:41:24.179955 | TASK [fetch-output : Set log path for single node]
2026-01-25 07:41:24.209056 | controller | ok
2026-01-25 07:41:24.214378 | 
2026-01-25 07:41:24.214478 | LOOP [fetch-output : Ensure local output dirs]
2026-01-25 07:41:24.594459 | controller -> localhost | ok: "/var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/logs"
2026-01-25 07:41:24.784005 | controller -> localhost | ok: "/var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/artifacts"
2026-01-25 07:41:24.980822 | controller -> localhost | ok: "/var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/docs"
2026-01-25 07:41:24.990898 | 
2026-01-25 07:41:24.990986 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-25 07:41:25.546948 | controller | ok
2026-01-25 07:41:25.547141 | controller | ok: All items complete
2026-01-25 07:41:25.547189 | 
2026-01-25 07:41:25.989470 | controller | ok
2026-01-25 07:41:26.401308 | controller | ok
2026-01-25 07:41:26.420134 | 
2026-01-25 07:41:26.420244 | TASK [include_role : fetch-output-openshift]
2026-01-25 07:41:26.443608 | controller | skipping: Conditional result was False
2026-01-25 07:41:26.449669 | 
2026-01-25 07:41:26.449749 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-25 07:41:26.804517 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006851
2026-01-25 07:41:27.001888 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006262
2026-01-25 07:41:27.039096 | 
2026-01-25 07:41:27.039210 | PLAY [all]
2026-01-25 07:41:27.054731 | 
2026-01-25 07:41:27.054818 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-25 07:41:27.444178 | controller | changed
2026-01-25 07:41:27.474076 | 
2026-01-25 07:41:27.474190 | PLAY RECAP
2026-01-25 07:41:27.474242 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-25 07:41:27.474268 | 
2026-01-25 07:41:27.565583 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-25 07:41:27.566372 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-25 07:41:28.149818 | 
2026-01-25 07:41:28.149931 | PLAY [localhost]
2026-01-25 07:41:28.168530 | 
2026-01-25 07:41:28.168620 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-25 07:41:28.517320 | localhost | changed
2026-01-25 07:41:28.522230 | 
2026-01-25 07:41:28.522364 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-25 07:41:28.554108 | localhost | ok
2026-01-25 07:41:28.562328 | 
2026-01-25 07:41:28.562421 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-25 07:41:28.884950 | localhost | changed
2026-01-25 07:41:28.890928 | 
2026-01-25 07:41:28.891018 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-25 07:41:29.525874 | localhost | changed
2026-01-25 07:41:29.535733 | 
2026-01-25 07:41:29.535824 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-25 07:41:29.897430 | localhost | Identity added: /var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/tmp/ansible.ijdfte44 (/var/lib/zuul/builds/85a1a9e732ff40f9aec7d8a6f43ebfea/work/tmp/ansible.ijdfte44)
2026-01-25 07:41:29.897610 | localhost | ok: Runtime: 0:00:00.006652
2026-01-25 07:41:29.902115 | 
2026-01-25 07:41:29.902208 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-25 07:41:30.134443 | localhost | ok: Runtime: 0:00:00.004052
2026-01-25 07:41:30.139097 | 
2026-01-25 07:41:30.139194 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-25 07:41:30.187474 | localhost | changed
2026-01-25 07:41:30.191661 | 
2026-01-25 07:41:30.191733 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-25 07:41:30.575936 | localhost | changed
2026-01-25 07:41:30.596862 | 
2026-01-25 07:41:30.596943 | PLAY [localhost]
2026-01-25 07:41:30.609047 | 
2026-01-25 07:41:30.609119 | TASK [Generate bulk log download script]
2026-01-25 07:41:30.626317 | localhost | ok
2026-01-25 07:41:30.639250 | 
2026-01-25 07:41:30.639361 | TASK [local-log-download : Check API endpoint is defined]
2026-01-25 07:41:30.666735 | localhost | ok: All assertions passed
2026-01-25 07:41:30.671643 | 
2026-01-25 07:41:30.671717 | TASK [local-log-download : Create download script]
2026-01-25 07:41:31.051350 | localhost -> localhost | changed
2026-01-25 07:41:31.061001 | 
2026-01-25 07:41:31.061094 | TASK [Register quick-download link]
2026-01-25 07:41:31.079425 | localhost | ok
2026-01-25 07:41:31.119605 | 
2026-01-25 07:41:31.119689 | PLAY [logserver.rdoproject.org]
2026-01-25 07:41:31.130002 | 
2026-01-25 07:41:31.130067 | TASK [Set zuul-log-path fact]
2026-01-25 07:41:31.146018 | logserver.rdoproject.org | ok
2026-01-25 07:41:31.155758 | 
2026-01-25 07:41:31.155829 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 07:41:31.182196 | logserver.rdoproject.org | ok
2026-01-25 07:41:31.188119 | 
2026-01-25 07:41:31.188217 | TASK [upload-logs : Create log directories]
2026-01-25 07:41:32.770543 | logserver.rdoproject.org | changed
2026-01-25 07:41:32.774085 | 
2026-01-25 07:41:32.774189 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-25 07:41:33.012072 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004612
2026-01-25 07:41:33.017142 | 
2026-01-25 07:41:33.017243 | TASK [upload-logs : Upload logs to log server]
2026-01-25 07:41:34.448801 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-25 07:41:34.451763 | 
2026-01-25 07:41:34.451859 | LOOP [upload-logs : Compress console log and json output]
2026-01-25 07:41:34.490765 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:41:34.499205 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:41:34.509780 | 
2026-01-25 07:41:34.509876 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-25 07:41:34.544829 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:41:34.545072 | 
2026-01-25 07:41:34.547924 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:41:34.557228 | 
2026-01-25 07:41:34.557778 | LOOP [upload-logs : Upload console log and json output]