2026-02-15 18:11:15.375933 | Job console starting...
2026-02-15 18:11:15.593829 | Updating repositories
2026-02-15 18:11:16.478587 | Preparing job workspace
2026-02-15 18:11:23.216934 | Running Ansible setup...
2026-02-15 18:11:25.813458 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-15 18:11:26.287545 | 
2026-02-15 18:11:26.287638 | PLAY [localhost]
2026-02-15 18:11:26.295121 | 
2026-02-15 18:11:26.295187 | TASK [Gathering Facts]
2026-02-15 18:11:27.049411 | localhost | ok
2026-02-15 18:11:27.061615 | 
2026-02-15 18:11:27.061710 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-15 18:11:27.349367 | localhost -> localhost | changed
2026-02-15 18:11:27.353950 | 
2026-02-15 18:11:27.354016 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-15 18:11:27.975962 | localhost -> localhost | changed
2026-02-15 18:11:27.983336 | 
2026-02-15 18:11:27.983401 | TASK [Setup log path fact]
2026-02-15 18:11:28.000477 | localhost | ok
2026-02-15 18:11:28.010388 | 
2026-02-15 18:11:28.010451 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-15 18:11:28.027478 | localhost | ok
2026-02-15 18:11:28.034010 | 
2026-02-15 18:11:28.034071 | TASK [emit-job-header : Print job information]
2026-02-15 18:11:28.060887 | # Job Information
2026-02-15 18:11:28.060993 | Ansible Version: 2.15.12
2026-02-15 18:11:28.061021 | Job: cifmw-molecule-test_operator
2026-02-15 18:11:28.061042 | Pipeline: github-check
2026-02-15 18:11:28.061060 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-02-15 18:11:28.061077 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3642
2026-02-15 18:11:28.061095 | Log URL (when completed): https://logserver.rdoproject.org/daf/rdoproject.org/dafc77b02d874387ba0af071841b3a8c/
2026-02-15 18:11:28.061112 | Event ID: 8fb67710-0a99-11f1-8d2a-2599eda48d6a
2026-02-15 18:11:28.064499 | 
2026-02-15 18:11:28.064561 | LOOP [emit-job-header : Print node information]
2026-02-15 18:11:28.144103 | localhost | ok:
2026-02-15 18:11:28.144256 | localhost | # Node Information
2026-02-15 18:11:28.144285 | localhost | Inventory Hostname: controller
2026-02-15 18:11:28.144330 | localhost | Hostname: np0005620326
2026-02-15 18:11:28.144354 | localhost | Username: zuul
2026-02-15 18:11:28.144375 | localhost | Distro: CentOS 9
2026-02-15 18:11:28.144394 | localhost | Provider: ibm-bm4-nodepool
2026-02-15 18:11:28.144413 | localhost | Region: regionOne
2026-02-15 18:11:28.144432 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-02-15 18:11:28.144454 | localhost | Product Name: OpenStack Compute
2026-02-15 18:11:28.144473 | localhost | Interface IP: 192.168.26.103
2026-02-15 18:11:28.165479 | 
2026-02-15 18:11:28.165547 | PLAY [all]
2026-02-15 18:11:28.171107 | 
2026-02-15 18:11:28.171166 | TASK [Gather network facts]
2026-02-15 18:11:28.493326 | controller | ok
2026-02-15 18:11:28.506232 | 
2026-02-15 18:11:28.506310 | TASK [include_role : start-zuul-console]
2026-02-15 18:11:28.523756 | controller | ok
2026-02-15 18:11:28.534621 | 
2026-02-15 18:11:28.534681 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-15 18:11:28.794905 | controller | ok
2026-02-15 18:11:28.802926 | 
2026-02-15 18:11:28.802992 | TASK [include_role : add-build-sshkey]
2026-02-15 18:11:28.820480 | controller | ok
2026-02-15 18:11:28.831673 | 
2026-02-15 18:11:28.831735 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-15 18:11:29.007396 | controller -> localhost | ok
2026-02-15 18:11:29.012375 | 
2026-02-15 18:11:29.012436 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-15 18:11:29.030020 | controller | ok
2026-02-15 18:11:29.041883 | controller | included: /var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-15 18:11:29.046804 | 
2026-02-15 18:11:29.046862 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-15 18:11:29.374881 | controller -> localhost | Generating public/private rsa key pair.
2026-02-15 18:11:29.375044 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/dafc77b02d874387ba0af071841b3a8c_id_rsa.
2026-02-15 18:11:29.375076 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/dafc77b02d874387ba0af071841b3a8c_id_rsa.pub.
2026-02-15 18:11:29.375097 | controller -> localhost | The key fingerprint is:
2026-02-15 18:11:29.375116 | controller -> localhost | SHA256:ZcGDxldxLcaym6EXHXowOeLS8elaypmJJ5URsC+L+JM zuul-build-sshkey
2026-02-15 18:11:29.375134 | controller -> localhost | The key's randomart image is:
2026-02-15 18:11:29.375150 | controller -> localhost | +---[RSA 3072]----+
2026-02-15 18:11:29.375165 | controller -> localhost | |       ..+..o+.. |
2026-02-15 18:11:29.375180 | controller -> localhost | |        +.B.*.= .|
2026-02-15 18:11:29.375195 | controller -> localhost | |       ..+o* @ o |
2026-02-15 18:11:29.375221 | controller -> localhost | |        .++ B o  |
2026-02-15 18:11:29.375239 | controller -> localhost | |        S..= *   |
2026-02-15 18:11:29.375255 | controller -> localhost | |     . . o+ *    |
2026-02-15 18:11:29.375270 | controller -> localhost | |    . ...+ O     |
2026-02-15 18:11:29.375284 | controller -> localhost | |     .E o O      |
2026-02-15 18:11:29.375313 | controller -> localhost | |      .. o       |
2026-02-15 18:11:29.375336 | controller -> localhost | +----[SHA256]-----+
2026-02-15 18:11:29.375381 | controller -> localhost | ok: Runtime: 0:00:00.027161
2026-02-15 18:11:29.380410 | 
2026-02-15 18:11:29.380475 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-15 18:11:29.398336 | controller | ok
2026-02-15 18:11:29.406785 | controller | included: /var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-15 18:11:29.413573 | 
2026-02-15 18:11:29.413630 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-15 18:11:29.427080 | controller | skipping: Conditional result was False
2026-02-15 18:11:29.431814 | 
2026-02-15 18:11:29.431877 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-15 18:11:29.754148 | controller | changed
2026-02-15 18:11:29.759543 | 
2026-02-15 18:11:29.759604 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-15 18:11:29.939441 | controller | ok
2026-02-15 18:11:29.943992 | 
2026-02-15 18:11:29.944072 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-15 18:11:30.448648 | controller | changed
2026-02-15 18:11:30.453102 | 
2026-02-15 18:11:30.453167 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-15 18:11:30.947554 | controller | changed
2026-02-15 18:11:30.952113 | 
2026-02-15 18:11:30.952175 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-15 18:11:30.965865 | controller | skipping: Conditional result was False
2026-02-15 18:11:30.970680 | 
2026-02-15 18:11:30.970742 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-15 18:11:31.238138 | controller -> localhost | changed
2026-02-15 18:11:31.247267 | 
2026-02-15 18:11:31.247346 | TASK [add-build-sshkey : Add back temp key]
2026-02-15 18:11:31.452992 | controller -> localhost | Identity added: /var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/dafc77b02d874387ba0af071841b3a8c_id_rsa (zuul-build-sshkey)
2026-02-15 18:11:31.453184 | controller -> localhost | ok: Runtime: 0:00:00.006422
2026-02-15 18:11:31.458593 | 
2026-02-15 18:11:31.458658 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-15 18:11:31.719136 | controller | ok
2026-02-15 18:11:31.723372 | 
2026-02-15 18:11:31.723436 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-15 18:11:31.746993 | controller | skipping: Conditional result was False
2026-02-15 18:11:31.755158 | 
2026-02-15 18:11:31.755224 | TASK [include_role : validate-host]
2026-02-15 18:11:31.772372 | controller | ok
2026-02-15 18:11:31.788621 | 
2026-02-15 18:11:31.788682 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-15 18:11:31.817287 | controller | ok
2026-02-15 18:11:31.821203 | 
2026-02-15 18:11:31.821270 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-15 18:11:32.006587 | controller -> localhost | ok
2026-02-15 18:11:32.011811 | 
2026-02-15 18:11:32.011874 | TASK [validate-host : Collect information about the host]
2026-02-15 18:11:32.568733 | controller | ok
2026-02-15 18:11:32.575921 | 
2026-02-15 18:11:32.575986 | TASK [validate-host : Sanitize hostname]
2026-02-15 18:11:32.624817 | controller | ok
2026-02-15 18:11:32.629497 | 
2026-02-15 18:11:32.629563 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-15 18:11:32.987557 | controller -> localhost | changed
2026-02-15 18:11:32.993802 | 
2026-02-15 18:11:32.993866 | TASK [validate-host : Collect information about zuul worker]
2026-02-15 18:11:33.306650 | controller | ok
2026-02-15 18:11:33.310972 | 
2026-02-15 18:11:33.311035 | TASK [validate-host : Write out all zuul information for each host]
2026-02-15 18:11:33.638408 | controller -> localhost | changed
2026-02-15 18:11:33.646827 | 
2026-02-15 18:11:33.646900 | TASK [include_role : prepare-workspace-openshift]
2026-02-15 18:11:33.660173 | controller | skipping: Conditional result was False
2026-02-15 18:11:33.664662 | 
2026-02-15 18:11:33.664729 | TASK [include_role : remove-zuul-sshkey]
2026-02-15 18:11:33.677842 | controller | skipping: Conditional result was False
2026-02-15 18:11:33.682564 | 
2026-02-15 18:11:33.682631 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-15 18:11:33.852368 | controller | ok: "logs"
2026-02-15 18:11:33.852610 | controller | ok: All items complete
2026-02-15 18:11:33.852641 | 
2026-02-15 18:11:34.003497 | controller | ok: "artifacts"
2026-02-15 18:11:34.152449 | controller | ok: "docs"
2026-02-15 18:11:34.158567 | 
2026-02-15 18:11:34.158683 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-15 18:11:34.336190 | controller | changed: "logs"
2026-02-15 18:11:34.487182 | controller | changed: "artifacts"
2026-02-15 18:11:34.641406 | controller | changed: "docs"
2026-02-15 18:11:34.668829 | 
2026-02-15 18:11:34.668915 | PLAY RECAP
2026-02-15 18:11:34.668956 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-15 18:11:34.668984 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-15 18:11:34.669002 | 
2026-02-15 18:11:34.737629 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-15 18:11:34.738354 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-15 18:11:35.249222 | 
2026-02-15 18:11:35.249335 | PLAY [all]
2026-02-15 18:11:35.266981 | 
2026-02-15 18:11:35.267059 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-15 18:11:35.315417 | controller | ok
2026-02-15 18:11:35.319751 | 
2026-02-15 18:11:35.319822 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-15 18:11:35.652331 | controller | changed
2026-02-15 18:11:35.657591 | 
2026-02-15 18:11:35.657663 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-15 18:11:36.427675 | controller | changed
2026-02-15 18:11:36.436024 | 
2026-02-15 18:11:36.436088 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-02-15 18:11:36.764492 | controller | changed:
2026-02-15 18:11:36.764659 | controller | {
2026-02-15 18:11:36.764693 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-02-15 18:11:36.764732 | controller | }
2026-02-15 18:11:36.952723 | controller | changed:
2026-02-15 18:11:36.952779 | controller | {
2026-02-15 18:11:36.952805 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-02-15 18:11:36.952824 | controller | }
2026-02-15 18:11:37.144811 | controller | changed:
2026-02-15 18:11:37.144862 | controller | {
2026-02-15 18:11:37.144889 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-02-15 18:11:37.144909 | controller | }
2026-02-15 18:11:37.332864 | controller | changed:
2026-02-15 18:11:37.332911 | controller | {
2026-02-15 18:11:37.332934 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-02-15 18:11:37.332953 | controller | }
2026-02-15 18:11:37.521250 | controller | changed:
2026-02-15 18:11:37.521316 | controller | {
2026-02-15 18:11:37.521346 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-02-15 18:11:37.521365 | controller | }
2026-02-15 18:11:37.708769 | controller | changed:
2026-02-15 18:11:37.708819 | controller | {
2026-02-15 18:11:37.708845 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-02-15 18:11:37.708864 | controller | }
2026-02-15 18:11:37.901068 | controller | changed:
2026-02-15 18:11:37.901119 | controller | {
2026-02-15 18:11:37.901144 | 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-02-15 18:11:37.901164 | controller | }
2026-02-15 18:11:38.091513 | controller | changed:
2026-02-15 18:11:38.091560 | controller | {
2026-02-15 18:11:38.091584 | 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-02-15 18:11:38.091603 | controller | }
2026-02-15 18:11:38.279488 | controller | changed:
2026-02-15 18:11:38.279542 | controller | {
2026-02-15 18:11:38.279566 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-02-15 18:11:38.279585 | controller | }
2026-02-15 18:11:38.468643 | controller | changed:
2026-02-15 18:11:38.468691 | controller | {
2026-02-15 18:11:38.468716 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-02-15 18:11:38.468736 | controller | }
2026-02-15 18:11:38.660328 | controller | changed:
2026-02-15 18:11:38.660379 | controller | {
2026-02-15 18:11:38.660404 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-02-15 18:11:38.660423 | controller | }
2026-02-15 18:11:38.863687 | controller | changed:
2026-02-15 18:11:38.863756 | controller | {
2026-02-15 18:11:38.863780 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-02-15 18:11:38.863798 | controller | }
2026-02-15 18:11:39.041593 | controller | changed:
2026-02-15 18:11:39.041645 | controller | {
2026-02-15 18:11:39.041668 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-02-15 18:11:39.041686 | controller | }
2026-02-15 18:11:39.228558 | controller | changed:
2026-02-15 18:11:39.228604 | controller | {
2026-02-15 18:11:39.228627 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-02-15 18:11:39.228645 | controller | }
2026-02-15 18:11:39.415537 | controller | changed:
2026-02-15 18:11:39.415588 | controller | {
2026-02-15 18:11:39.415615 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-02-15 18:11:39.415634 | controller | }
2026-02-15 18:11:39.601151 | controller | changed:
2026-02-15 18:11:39.601197 | controller | {
2026-02-15 18:11:39.601230 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-02-15 18:11:39.601248 | controller | }
2026-02-15 18:11:39.787345 | controller | changed:
2026-02-15 18:11:39.787395 | controller | {
2026-02-15 18:11:39.787420 | 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-02-15 18:11:39.787441 | controller | }
2026-02-15 18:11:39.975362 | controller | changed:
2026-02-15 18:11:39.975413 | controller | {
2026-02-15 18:11:39.975438 | 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-02-15 18:11:39.975466 | controller | }
2026-02-15 18:11:40.163198 | controller | changed:
2026-02-15 18:11:40.163262 | controller | {
2026-02-15 18:11:40.163287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-02-15 18:11:40.163351 | controller | }
2026-02-15 18:11:40.353985 | controller | changed:
2026-02-15 18:11:40.354030 | controller | {
2026-02-15 18:11:40.354053 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-02-15 18:11:40.354072 | controller | }
2026-02-15 18:11:40.541961 | controller | changed:
2026-02-15 18:11:40.542007 | controller | {
2026-02-15 18:11:40.542031 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-02-15 18:11:40.542048 | controller | }
2026-02-15 18:11:40.729431 | controller | changed:
2026-02-15 18:11:40.729481 | controller | {
2026-02-15 18:11:40.729505 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-02-15 18:11:40.729523 | controller | }
2026-02-15 18:11:40.919157 | controller | changed:
2026-02-15 18:11:40.919213 | controller | {
2026-02-15 18:11:40.919238 | 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-02-15 18:11:40.919258 | controller | }
2026-02-15 18:11:41.108467 | controller | changed:
2026-02-15 18:11:41.108516 | controller | {
2026-02-15 18:11:41.108539 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-02-15 18:11:41.108557 | controller | }
2026-02-15 18:11:41.297606 | controller | changed:
2026-02-15 18:11:41.297656 | controller | {
2026-02-15 18:11:41.297679 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-02-15 18:11:41.297696 | controller | }
2026-02-15 18:11:41.484454 | controller | changed:
2026-02-15 18:11:41.484501 | controller | {
2026-02-15 18:11:41.484525 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-02-15 18:11:41.484544 | controller | }
2026-02-15 18:11:41.507405 | 
2026-02-15 18:11:41.507515 | TASK [Set timezone to UTC]
2026-02-15 18:11:42.878958 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-02-15 18:11:42.884148 | 
2026-02-15 18:11:42.884228 | TASK [Create nodepool directory]
2026-02-15 18:11:43.075449 | controller | changed
2026-02-15 18:11:43.080611 | 
2026-02-15 18:11:43.080676 | TASK [Create nodepool sub_nodes file]
2026-02-15 18:11:43.556777 | controller | changed
2026-02-15 18:11:43.561481 | 
2026-02-15 18:11:43.561546 | TASK [Create nodepool sub_nodes_private file]
2026-02-15 18:11:44.038163 | controller | changed
2026-02-15 18:11:44.042900 | 
2026-02-15 18:11:44.042960 | LOOP [Populate nodepool sub_nodes file]
2026-02-15 18:11:44.070970 | 
2026-02-15 18:11:44.071088 | LOOP [Populate nodepool sub_nodes_private file]
2026-02-15 18:11:44.098730 | 
2026-02-15 18:11:44.098849 | TASK [Create nodepool primary file]
2026-02-15 18:11:44.121511 | controller | skipping: Conditional result was False
2026-02-15 18:11:44.126574 | 
2026-02-15 18:11:44.126648 | TASK [Create nodepool node_private for this node]
2026-02-15 18:11:44.620127 | controller | changed
2026-02-15 18:11:44.626177 | 
2026-02-15 18:11:44.626246 | LOOP [Copy ssh keys to nodepool directory]
2026-02-15 18:11:44.912610 | controller | ok: Item: id_rsa Runtime: 0:00:00.004569
2026-02-15 18:11:44.912788 | 
2026-02-15 18:11:45.061103 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.003995
2026-02-15 18:11:45.071030 | 
2026-02-15 18:11:45.071128 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-02-15 18:11:45.566746 | controller | changed
2026-02-15 18:11:45.572159 | 
2026-02-15 18:11:45.572230 | TASK [Validate sudoers config after edits]
2026-02-15 18:11:45.778341 | controller | /etc/sudoers: parsed OK
2026-02-15 18:11:45.778413 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-02-15 18:11:45.778424 | controller | /etc/sudoers.d/zuul: parsed OK
2026-02-15 18:11:45.778430 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-02-15 18:11:46.095756 | controller | ok: Runtime: 0:00:00.005704
2026-02-15 18:11:46.100828 | 
2026-02-15 18:11:46.100886 | TASK [Show the environment passed in to job shell scripts]
2026-02-15 18:11:46.285971 | controller | SHELL=/bin/bash
2026-02-15 18:11:46.286009 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-02-15 18:11:46.286018 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-02-15 18:11:46.286025 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/42/3642/f6d67d31d13a790d4fec59194948f0d7419a3308
2026-02-15 18:11:46.286031 | controller | PWD=/home/zuul
2026-02-15 18:11:46.286036 | controller | ZUUL_PIPELINE=github-check
2026-02-15 18:11:46.286042 | controller | LOGNAME=zuul
2026-02-15 18:11:46.286047 | controller | XDG_SESSION_TYPE=tty
2026-02-15 18:11:46.286053 | controller | _=/usr/bin/env
2026-02-15 18:11:46.286058 | controller | MOTD_SHOWN=pam
2026-02-15 18:11:46.286064 | controller | HOME=/home/zuul
2026-02-15 18:11:46.286069 | controller | LANG=en_US.UTF-8
2026-02-15 18:11:46.286114 | controller | SSH_CONNECTION=192.168.26.12 55868 192.168.26.103 22
2026-02-15 18:11:46.286122 | 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-02-15 18:11:46.286132 | controller | ZUUL_CHANGE_IDS=3642,f6d67d31d13a790d4fec59194948f0d7419a3308
2026-02-15 18:11:46.286138 | controller | WORKSPACE=/home/zuul/workspace
2026-02-15 18:11:46.286143 | controller | XDG_SESSION_CLASS=user
2026-02-15 18:11:46.286149 | controller | SELINUX_ROLE_REQUESTED=
2026-02-15 18:11:46.286154 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-02-15 18:11:46.286160 | controller | USER=zuul
2026-02-15 18:11:46.286165 | controller | ZUUL_VOTING=True
2026-02-15 18:11:46.286170 | controller | BUILD_TIMEOUT=1800000
2026-02-15 18:11:46.286176 | controller | SELINUX_USE_CURRENT_RANGE=
2026-02-15 18:11:46.286181 | controller | SHLVL=1
2026-02-15 18:11:46.286187 | controller | ZUUL_PATCHSET=f6d67d31d13a790d4fec59194948f0d7419a3308
2026-02-15 18:11:46.286192 | controller | XDG_SESSION_ID=1
2026-02-15 18:11:46.286197 | controller | ZUUL_BRANCH=main
2026-02-15 18:11:46.286203 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-02-15 18:11:46.286217 | controller | SSH_CLIENT=192.168.26.12 55868 22
2026-02-15 18:11:46.286224 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-02-15 18:11:46.286230 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-02-15 18:11:46.286236 | controller | which_declare=declare -f
2026-02-15 18:11:46.286242 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-02-15 18:11:46.286247 | controller | SELINUX_LEVEL_REQUESTED=
2026-02-15 18:11:46.286253 | controller | ZUUL_CHANGE=3642
2026-02-15 18:11:46.286258 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-02-15 18:11:46.286264 | controller | ZUUL_UUID=dafc77b02d874387ba0af071841b3a8c
2026-02-15 18:11:46.286269 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-02-15 18:11:46.286275 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-02-15 18:11:46.286280 | controller | }
2026-02-15 18:11:46.624492 | controller | ok: Runtime: 0:00:00.005527
2026-02-15 18:11:46.629870 | 
2026-02-15 18:11:46.629930 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-02-15 18:11:46.643427 | controller | skipping: Conditional result was False
2026-02-15 18:11:46.648452 | 
2026-02-15 18:11:46.648516 | TASK [Symlink /home/zuul-worker/workspace]
2026-02-15 18:11:47.172504 | controller | skipping: Conditional result was False
2026-02-15 18:11:47.178243 | 
2026-02-15 18:11:47.178322 | TASK [Ensure legacy workspace directory]
2026-02-15 18:11:47.358626 | controller | changed
2026-02-15 18:11:47.375010 | 
2026-02-15 18:11:47.375060 | PLAY RECAP
2026-02-15 18:11:47.375102 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-15 18:11:47.375122 | 
2026-02-15 18:11:47.440141 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-15 18:11:47.440918 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-15 18:11:47.928933 | 
2026-02-15 18:11:47.929031 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-02-15 18:11:47.948509 | 
2026-02-15 18:11:47.948578 | TASK [Create zuul-output directory]
2026-02-15 18:11:48.240979 | controller | changed
2026-02-15 18:11:48.245749 | 
2026-02-15 18:11:48.245823 | TASK [Slurp Zuul inventory test]
2026-02-15 18:11:48.492714 | controller -> localhost | ok
2026-02-15 18:11:48.498626 | 
2026-02-15 18:11:48.498694 | TASK [Save zuul inventory]
2026-02-15 18:11:49.180817 | controller | changed
2026-02-15 18:11:49.185791 | 
2026-02-15 18:11:49.185884 | TASK [Save zuul vars without the change_message]
2026-02-15 18:11:49.732878 | controller | changed
2026-02-15 18:11:49.748640 | 
2026-02-15 18:11:49.748703 | PLAY RECAP
2026-02-15 18:11:49.748746 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-15 18:11:49.748770 | 
2026-02-15 18:11:49.814481 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-15 18:11:49.815489 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-15 18:11:50.319380 | 
2026-02-15 18:11:50.319484 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-02-15 18:11:50.338365 | 
2026-02-15 18:11:50.338435 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-02-15 18:11:50.356729 | controller | ok
2026-02-15 18:11:50.372605 | 
2026-02-15 18:11:50.372681 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-15 18:11:50.396059 | controller | skipping: Conditional result was False
2026-02-15 18:11:50.401412 | 
2026-02-15 18:11:50.401477 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-15 18:11:50.693003 | controller | ok
2026-02-15 18:11:50.698001 | 
2026-02-15 18:11:50.698066 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-15 18:11:51.276727 | controller | ok
2026-02-15 18:11:51.286773 | 
2026-02-15 18:11:51.286839 | TASK [Prepare workspace]
2026-02-15 18:11:51.304988 | controller | ok
2026-02-15 18:11:51.321021 | 
2026-02-15 18:11:51.321092 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-15 18:11:51.613045 | controller | ok
2026-02-15 18:11:51.618388 | 
2026-02-15 18:11:51.618463 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-15 18:11:54.012106 | controller | Output suppressed because no_log was given
2026-02-15 18:11:54.020684 | 
2026-02-15 18:11:54.020750 | LOOP [Create zuul-output directory]
2026-02-15 18:11:54.218557 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-02-15 18:11:54.369596 | controller | ok: "/home/zuul/zuul-output/logs"
2026-02-15 18:11:54.375681 | 
2026-02-15 18:11:54.375763 | TASK [Install required packages]
2026-02-15 18:12:29.248864 | controller | changed
2026-02-15 18:12:29.253747 | 
2026-02-15 18:12:29.253810 | TASK [Install venv]
2026-02-15 18:13:26.938746 | controller | changed
2026-02-15 18:13:26.981347 | 
2026-02-15 18:13:26.981421 | PLAY RECAP
2026-02-15 18:13:26.981459 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-15 18:13:26.981479 | 
2026-02-15 18:13:27.042619 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-15 18:13:27.043460 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-15 18:13:27.512716 | 
2026-02-15 18:13:27.512803 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-02-15 18:13:27.531194 | 
2026-02-15 18:13:27.531272 | TASK [Gather required facts]
2026-02-15 18:13:27.965423 | controller | ok
2026-02-15 18:13:27.970158 | 
2026-02-15 18:13:27.970240 | TASK [Load environment var if instructed to]
2026-02-15 18:13:27.984645 | controller | skipping: Conditional result was False
2026-02-15 18:13:27.989354 | 
2026-02-15 18:13:27.989414 | TASK [Ensure group_vars dir exists]
2026-02-15 18:13:28.270033 | controller | ok
2026-02-15 18:13:28.275556 | 
2026-02-15 18:13:28.275617 | TASK [Print related variables]
2026-02-15 18:13:28.303486 | controller | ok:
2026-02-15 18:13:28.303592 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-02-15 18:13:28.303621 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_operator
2026-02-15 18:13:28.308466 | 
2026-02-15 18:13:28.308532 | TASK [Run molecule]
2026-02-15 18:13:29.161461 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-02-15 18:13:29.225887 | controller | INFO     Performing prerun with role_name_check=0...
2026-02-15 18:13:45.407251 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.407667 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.408082 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.408501 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.408923 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f6d67d31 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 1.0.0+f6d67d31 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.409324 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.409717 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.410120 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.410519 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.410920 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.411317 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.411711 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.412101 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.412527 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.412925 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/dec929/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-15 18:13:45.421743 | controller | INFO     Running default > prepare
2026-02-15 18:13:46.026278 | controller |
2026-02-15 18:13:46.026354 | controller | PLAY [Converge] ****************************************************************
2026-02-15 18:13:46.026442 | controller |
2026-02-15 18:13:46.026528 | controller | TASK [Gathering Facts] *********************************************************
2026-02-15 18:13:46.026616 | controller | Sunday 15 February 2026  18:13:46 +0000 (0:00:00.011)       0:00:00.011 *******
2026-02-15 18:13:46.799794 | controller | ok: [instance]
2026-02-15 18:13:46.799881 | controller |
2026-02-15 18:13:46.799982 | controller | TASK [test_operator : Cleanup previous test-operator resources] ****************
2026-02-15 18:13:46.800080 | controller | Sunday 15 February 2026  18:13:46 +0000 (0:00:00.773)       0:00:00.785 *******
2026-02-15 18:13:46.825691 | controller | skipping: [instance]
2026-02-15 18:13:46.825817 | controller |
2026-02-15 18:13:46.825967 | controller | TASK [test_operator : Ensure test_operator folder exists] **********************
2026-02-15 18:13:46.826102 | controller | Sunday 15 February 2026  18:13:46 +0000 (0:00:00.026)       0:00:00.811 *******
2026-02-15 18:13:47.098444 | controller | changed: [instance]
2026-02-15 18:13:47.098538 | controller |
2026-02-15 18:13:47.098647 | controller | TASK [test_operator : Get openstack-operator csv information] ******************
2026-02-15 18:13:47.098744 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.272)       0:00:01.084 *******
2026-02-15 18:13:47.119080 | controller | skipping: [instance]
2026-02-15 18:13:47.119208 | controller |
2026-02-15 18:13:47.119375 | controller | TASK [test_operator : Get full name of openstack-operator CSV] *****************
2026-02-15 18:13:47.119513 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.020)       0:00:01.104 *******
2026-02-15 18:13:47.138658 | controller | skipping: [instance]
2026-02-15 18:13:47.138784 | controller |
2026-02-15 18:13:47.138940 | controller | TASK [test_operator : Get index of test-operator image] ************************
2026-02-15 18:13:47.139077 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.019)       0:00:01.124 *******
2026-02-15 18:13:47.160590 | controller | skipping: [instance]
2026-02-15 18:13:47.160688 | controller |
2026-02-15 18:13:47.160800 | controller | TASK [test_operator : Patch test-operator version in CSV] **********************
2026-02-15 18:13:47.160927 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.021)       0:00:01.146 *******
2026-02-15 18:13:47.180825 | controller | skipping: [instance]
2026-02-15 18:13:47.180958 | controller |
2026-02-15 18:13:47.181092 | controller | TASK [test_operator : Get test-operator-controller-manager pod information] ****
2026-02-15 18:13:47.181226 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.020)       0:00:01.166 *******
2026-02-15 18:13:47.201176 | controller | skipping: [instance]
2026-02-15 18:13:47.201258 | controller |
2026-02-15 18:13:47.201372 | controller | TASK [test_operator : Get full name of test-operator-controller-manager pod] ***
2026-02-15 18:13:47.201461 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.020)       0:00:01.187 *******
2026-02-15 18:13:47.221030 | controller | skipping: [instance]
2026-02-15 18:13:47.221150 | controller |
2026-02-15 18:13:47.221283 | controller | TASK [test_operator : Wait until the test-operator-controller-manager is reloaded] ***
2026-02-15 18:13:47.221441 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.019)       0:00:01.206 *******
2026-02-15 18:13:47.245115 | controller | skipping: [instance]
2026-02-15 18:13:47.245237 | controller |
2026-02-15 18:13:47.245410 | controller | TASK [test_operator : Delete CRDs created by test-operator] ********************
2026-02-15 18:13:47.245531 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.023)       0:00:01.230 *******
2026-02-15 18:13:47.279945 | controller | skipping: [instance] => (item=tempests.test.openstack.org)
2026-02-15 18:13:47.280090 | controller | skipping: [instance] => (item=tobikoes.test.openstack.org)
2026-02-15 18:13:47.280220 | controller | skipping: [instance] => (item=ansibletests.test.openstack.org)
2026-02-15 18:13:47.280388 | controller | skipping: [instance] => (item=horizontests.test.openstack.org)
2026-02-15 18:13:47.280520 | controller | skipping: [instance]
2026-02-15 18:13:47.280642 | controller |
2026-02-15 18:13:47.280772 | controller | TASK [test_operator : Clone test-operator repository and checkout into specified version] ***
2026-02-15 18:13:47.280859 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.034)       0:00:01.265 *******
2026-02-15 18:13:47.301238 | controller | skipping: [instance]
2026-02-15 18:13:47.301388 | controller |
2026-02-15 18:13:47.301529 | controller | TASK [test_operator : Run make generate manifests install from /tmp/test-operator dir] ***
2026-02-15 18:13:47.301633 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.021)       0:00:01.287 *******
2026-02-15 18:13:47.321268 | controller | skipping: [instance]
2026-02-15 18:13:47.321365 | controller |
2026-02-15 18:13:47.321454 | controller | TASK [test_operator : Call test stages loop] ***********************************
2026-02-15 18:13:47.321546 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.020)       0:00:01.307 *******
2026-02-15 18:13:47.347364 | controller | skipping: [instance] => (item={'name': 'tempest', 'type': 'tempest'})
2026-02-15 18:13:47.347508 | controller | skipping: [instance]
2026-02-15 18:13:47.347635 | controller |
2026-02-15 18:13:47.347732 | controller | TASK [test_operator : Fail when any tests failed] ******************************
2026-02-15 18:13:47.347823 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.025)       0:00:01.333 *******
2026-02-15 18:13:47.385712 | controller | skipping: [instance]
2026-02-15 18:13:47.385801 | controller |
2026-02-15 18:13:47.385916 | controller | PLAY RECAP *********************************************************************
2026-02-15 18:13:47.386015 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=13   rescued=0    ignored=0
2026-02-15 18:13:47.386102 | controller |
2026-02-15 18:13:47.386202 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.038)       0:00:01.371 *******
2026-02-15 18:13:47.386319 | controller | ===============================================================================
2026-02-15 18:13:47.386746 | controller | Gathering Facts --------------------------------------------------------- 0.77s
2026-02-15 18:13:47.386843 | controller | test_operator : Ensure test_operator folder exists ---------------------- 0.27s
2026-02-15 18:13:47.386946 | controller | test_operator : Fail when any tests failed ------------------------------ 0.04s
2026-02-15 18:13:47.387035 | controller | test_operator : Delete CRDs created by test-operator -------------------- 0.03s
2026-02-15 18:13:47.387127 | controller | test_operator : Cleanup previous test-operator resources ---------------- 0.03s
2026-02-15 18:13:47.387217 | controller | test_operator : Call test stages loop ----------------------------------- 0.03s
2026-02-15 18:13:47.387370 | controller | test_operator : Wait until the test-operator-controller-manager is reloaded --- 0.02s
2026-02-15 18:13:47.387423 | controller | test_operator : Get index of test-operator image ------------------------ 0.02s
2026-02-15 18:13:47.387505 | controller | test_operator : Clone test-operator repository and checkout into specified version --- 0.02s
2026-02-15 18:13:47.387593 | controller | test_operator : Get openstack-operator csv information ------------------ 0.02s
2026-02-15 18:13:47.387680 | controller | test_operator : Get test-operator-controller-manager pod information ---- 0.02s
2026-02-15 18:13:47.387766 | controller | test_operator : Patch test-operator version in CSV ---------------------- 0.02s
2026-02-15 18:13:47.387857 | controller | test_operator : Run make generate manifests install from /tmp/test-operator dir --- 0.02s
2026-02-15 18:13:47.387955 | controller | test_operator : Get full name of test-operator-controller-manager pod --- 0.02s
2026-02-15 18:13:47.388040 | controller | test_operator : Get full name of openstack-operator CSV ----------------- 0.02s
2026-02-15 18:13:47.441380 | controller | INFO     Running default > converge
2026-02-15 18:13:47.829660 | controller |
2026-02-15 18:13:47.829718 | controller | PLAY [Converge] ****************************************************************
2026-02-15 18:13:47.829761 | controller |
2026-02-15 18:13:47.829858 | controller | TASK [Gathering Facts] *********************************************************
2026-02-15 18:13:47.829953 | controller | Sunday 15 February 2026  18:13:47 +0000 (0:00:00.014)       0:00:00.014 *******
2026-02-15 18:13:48.565810 | controller | ok: [instance]
2026-02-15 18:13:48.565895 | controller |
2026-02-15 18:13:48.566009 | controller | TASK [test_operator : Cleanup previous test-operator resources] ****************
2026-02-15 18:13:48.566114 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.736)       0:00:00.750 *******
2026-02-15 18:13:48.589888 | controller | skipping: [instance]
2026-02-15 18:13:48.590058 | controller |
2026-02-15 18:13:48.590153 | controller | TASK [test_operator : Ensure test_operator folder exists] **********************
2026-02-15 18:13:48.590243 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.024)       0:00:00.774 *******
2026-02-15 18:13:48.867677 | controller | ok: [instance]
2026-02-15 18:13:48.867755 | controller |
2026-02-15 18:13:48.867852 | controller | TASK [test_operator : Get openstack-operator csv information] ******************
2026-02-15 18:13:48.867953 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.277)       0:00:01.052 *******
2026-02-15 18:13:48.888211 | controller | skipping: [instance]
2026-02-15 18:13:48.888368 | controller |
2026-02-15 18:13:48.888501 | controller | TASK [test_operator : Get full name of openstack-operator CSV] *****************
2026-02-15 18:13:48.888615 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.020)       0:00:01.073 *******
2026-02-15 18:13:48.907336 | controller | skipping: [instance]
2026-02-15 18:13:48.907522 | controller |
2026-02-15 18:13:48.907663 | controller | TASK [test_operator : Get index of test-operator image] ************************
2026-02-15 18:13:48.907758 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.019)       0:00:01.092 *******
2026-02-15 18:13:48.926577 | controller | skipping: [instance]
2026-02-15 18:13:48.926758 | controller |
2026-02-15 18:13:48.926959 | controller | TASK [test_operator : Patch test-operator version in CSV] **********************
2026-02-15 18:13:48.927136 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.019)       0:00:01.111 *******
2026-02-15 18:13:48.945720 | controller | skipping: [instance]
2026-02-15 18:13:48.945914 | controller |
2026-02-15 18:13:48.946111 | controller | TASK [test_operator : Get test-operator-controller-manager pod information] ****
2026-02-15 18:13:48.946318 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.019)       0:00:01.130 *******
2026-02-15 18:13:48.966145 | controller | skipping: [instance]
2026-02-15 18:13:48.966357 | controller |
2026-02-15 18:13:48.966549 | controller | TASK [test_operator : Get full name of test-operator-controller-manager pod] ***
2026-02-15 18:13:48.966728 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.020)       0:00:01.151 *******
2026-02-15 18:13:48.984952 | controller | skipping: [instance]
2026-02-15 18:13:48.985038 | controller |
2026-02-15 18:13:48.985131 | controller | TASK [test_operator : Wait until the test-operator-controller-manager is reloaded] ***
2026-02-15 18:13:48.985224 | controller | Sunday 15 February 2026  18:13:48 +0000 (0:00:00.018)       0:00:01.170 *******
2026-02-15 18:13:49.004468 | controller | skipping: [instance]
2026-02-15 18:13:49.004654 | controller |
2026-02-15 18:13:49.004823 | controller | TASK [test_operator : Delete CRDs created by test-operator] ********************
2026-02-15 18:13:49.004925 | controller | Sunday 15 February 2026  18:13:49 +0000 (0:00:00.019)       0:00:01.189 *******
2026-02-15 18:13:49.038711 | controller | skipping: [instance] => (item=tempests.test.openstack.org)
2026-02-15 18:13:49.038897 | controller | skipping: [instance] => (item=tobikoes.test.openstack.org)
2026-02-15 18:13:49.038998 | controller | skipping: [instance] => (item=ansibletests.test.openstack.org)
2026-02-15 18:13:49.039088 | controller | skipping: [instance] => (item=horizontests.test.openstack.org)
2026-02-15 18:13:49.039173 | controller | skipping: [instance]
2026-02-15 18:13:49.039247 | controller |
2026-02-15 18:13:49.039357 | controller | TASK [test_operator : Clone test-operator repository and checkout into specified version] ***
2026-02-15 18:13:49.039445 | controller | Sunday 15 February 2026  18:13:49 +0000 (0:00:00.034)       0:00:01.223 *******
2026-02-15 18:13:49.058009 | controller | skipping: [instance]
2026-02-15 18:13:49.058177 | controller |
2026-02-15 18:13:49.058387 | controller | TASK [test_operator : Run make generate manifests install from /tmp/test-operator dir] ***
2026-02-15 18:13:49.058560 | controller | Sunday 15 February 2026  18:13:49 +0000 (0:00:00.019)       0:00:01.243 *******
2026-02-15 18:13:49.077172 | controller | skipping: [instance]
2026-02-15 18:13:49.077359 | controller |
2026-02-15 18:13:49.077536 | controller | TASK [test_operator : Call test stages loop] ***********************************
2026-02-15 18:13:49.077706 | controller | Sunday 15 February 2026  18:13:49 +0000 (0:00:00.019)       0:00:01.262 *******
2026-02-15 18:13:49.102797 | controller | skipping: [instance] => (item={'name': 'tempest', 'type': 'tempest'})
2026-02-15 18:13:49.102944 | controller | skipping: [instance]
2026-02-15 18:13:49.103065 | controller |
2026-02-15 18:13:49.103196 | controller | TASK [test_operator : Fail when any tests failed] ******************************
2026-02-15 18:13:49.103353 | controller | Sunday 15 February 2026  18:13:49 +0000 (0:00:00.025)       0:00:01.288 *******
2026-02-15 18:13:49.140767 | controller | skipping: [instance]
2026-02-15 18:13:49.140820 | controller |
2026-02-15 18:13:49.140923 | controller | PLAY RECAP *********************************************************************
2026-02-15 18:13:49.141010 | controller | instance                   : ok=2    changed=0    unreachable=0    failed=0    skipped=13   rescued=0    ignored=0
2026-02-15 18:13:49.141104 | controller |
2026-02-15 18:13:49.141183 | controller | Sunday 15 February 2026  18:13:49 +0000 (0:00:00.038)       0:00:01.326 *******
2026-02-15 18:13:49.141277 | controller | ===============================================================================
2026-02-15 18:13:49.141391 | controller | Gathering Facts --------------------------------------------------------- 0.74s
2026-02-15 18:13:49.141463 | controller | test_operator : Ensure test_operator folder exists ---------------------- 0.28s
2026-02-15 18:13:49.141542 | controller | test_operator : Fail when any tests failed ------------------------------ 0.04s
2026-02-15 18:13:49.141626 | controller | test_operator : Delete CRDs created by test-operator -------------------- 0.03s
2026-02-15 18:13:49.141706 | controller | test_operator : Call test stages loop ----------------------------------- 0.03s
2026-02-15 18:13:49.141791 | controller | test_operator : Cleanup previous test-operator resources ---------------- 0.02s
2026-02-15 18:13:49.141881 | controller | test_operator : Get openstack-operator csv information ------------------ 0.02s
2026-02-15 18:13:49.141963 | controller | test_operator : Get test-operator-controller-manager pod information ---- 0.02s
2026-02-15 18:13:49.142042 | controller | test_operator : Clone test-operator repository and checkout into specified version --- 0.02s
2026-02-15 18:13:49.142105 | controller | test_operator : Wait until the test-operator-controller-manager is reloaded --- 0.02s
2026-02-15 18:13:49.142184 | controller | test_operator : Patch test-operator version in CSV ---------------------- 0.02s
2026-02-15 18:13:49.142262 | controller | test_operator : Get index of test-operator image ------------------------ 0.02s
2026-02-15 18:13:49.142371 | controller | test_operator : Run make generate manifests install from /tmp/test-operator dir --- 0.02s
2026-02-15 18:13:49.142454 | controller | test_operator : Get full name of openstack-operator CSV ----------------- 0.02s
2026-02-15 18:13:49.142533 | controller | test_operator : Get full name of test-operator-controller-manager pod --- 0.02s
2026-02-15 18:13:49.192203 | controller | INFO     Running default > cleanup
2026-02-15 18:13:49.192650 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-02-15 18:13:49.193191 | controller | INFO     Writing /tmp/report.html report.
2026-02-15 18:13:49.355577 | controller | changed
2026-02-15 18:13:49.372125 | 
2026-02-15 18:13:49.372182 | PLAY RECAP
2026-02-15 18:13:49.372236 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-15 18:13:49.372265 | 
2026-02-15 18:13:49.429566 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-15 18:13:49.430350 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-15 18:13:49.909689 | 
2026-02-15 18:13:49.909783 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-02-15 18:13:49.928142 | 
2026-02-15 18:13:49.928216 | TASK [Filter out host if needed]
2026-02-15 18:13:49.935798 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-02-15 18:13:49.939851 | 
2026-02-15 18:13:49.939912 | TASK [Ensure file is present]
2026-02-15 18:13:50.221576 | controller | ok
2026-02-15 18:13:50.228093 | 
2026-02-15 18:13:50.228161 | TASK [Manage molecule report file]
2026-02-15 18:13:50.753059 | controller | changed
2026-02-15 18:13:50.758311 | 
2026-02-15 18:13:50.758379 | TASK [Check if we get ci-framework-data basedir]
2026-02-15 18:13:50.928673 | controller | ok
2026-02-15 18:13:50.933234 | 
2026-02-15 18:13:50.933309 | TASK [Create ci-framework-data log directory for zuul]
2026-02-15 18:13:51.227840 | controller | changed
2026-02-15 18:13:51.232848 | 
2026-02-15 18:13:51.232913 | TASK [Copy ci-framework interesting files]
2026-02-15 18:13:51.756128 | controller | changed
2026-02-15 18:13:51.761681 | 
2026-02-15 18:13:51.761770 | TASK [Get SELinux listing]
2026-02-15 18:13:52.288292 | controller | changed
2026-02-15 18:13:52.293486 | 
2026-02-15 18:13:52.293551 | TASK [Generate log index]
2026-02-15 18:13:52.871046 | controller | changed
2026-02-15 18:13:52.875380 | 
2026-02-15 18:13:52.875438 | TASK [Get some env related data]
2026-02-15 18:13:53.370062 | controller | /home/zuul/.local/bin/ansible
2026-02-15 18:13:53.897695 | controller | changed
2026-02-15 18:13:53.902266 | 
2026-02-15 18:13:53.902338 | TASK [Generate list of logs to collect in home directory]
2026-02-15 18:13:54.164914 | controller | ok: All paths examined
2026-02-15 18:13:54.169698 | 
2026-02-15 18:13:54.169761 | LOOP [Copy logs from home directory]
2026-02-15 18:13:54.461834 | controller | changed:
2026-02-15 18:13:54.462050 | controller | {
2026-02-15 18:13:54.462085 | controller |   "atime": 1771179191.0088408,
2026-02-15 18:13:54.462107 | controller |   "ctime": 1771179206.5360897,
2026-02-15 18:13:54.462124 | controller |   "dev": 64513,
2026-02-15 18:13:54.462139 | controller |   "gid": 1000,
2026-02-15 18:13:54.462153 | controller |   "gr_name": "zuul",
2026-02-15 18:13:54.462167 | controller |   "inode": 226555,
2026-02-15 18:13:54.462181 | controller |   "isblk": false,
2026-02-15 18:13:54.462195 | controller |   "ischr": false,
2026-02-15 18:13:54.462223 | controller |   "isdir": false,
2026-02-15 18:13:54.462238 | controller |   "isfifo": false,
2026-02-15 18:13:54.462251 | controller |   "isgid": false,
2026-02-15 18:13:54.462264 | controller |   "islnk": false,
2026-02-15 18:13:54.462277 | controller |   "isreg": true,
2026-02-15 18:13:54.462289 | controller |   "issock": false,
2026-02-15 18:13:54.462332 | controller |   "isuid": false,
2026-02-15 18:13:54.462350 | controller |   "mode": "0644",
2026-02-15 18:13:54.462363 | controller |   "mtime": 1771179206.5360897,
2026-02-15 18:13:54.462376 | controller |   "nlink": 1,
2026-02-15 18:13:54.462389 | controller |   "path": "/home/zuul/ansible.log",
2026-02-15 18:13:54.462402 | controller |   "pw_name": "zuul",
2026-02-15 18:13:54.462415 | controller |   "rgrp": true,
2026-02-15 18:13:54.462429 | controller |   "roth": true,
2026-02-15 18:13:54.462441 | controller |   "rusr": true,
2026-02-15 18:13:54.462454 | controller |   "size": 6749,
2026-02-15 18:13:54.462466 | controller |   "uid": 1000,
2026-02-15 18:13:54.462479 | controller |   "wgrp": false,
2026-02-15 18:13:54.462492 | controller |   "woth": false,
2026-02-15 18:13:54.462504 | controller |   "wusr": true,
2026-02-15 18:13:54.462519 | controller |   "xgrp": false,
2026-02-15 18:13:54.462532 | controller |   "xoth": false,
2026-02-15 18:13:54.462545 | controller |   "xusr": false
2026-02-15 18:13:54.462557 | controller | }
2026-02-15 18:13:54.476996 | 
2026-02-15 18:13:54.477077 | TASK [Copy crio stats log file]
2026-02-15 18:13:54.491074 | controller | skipping: Conditional result was False
2026-02-15 18:13:54.496385 | 
2026-02-15 18:13:54.496450 | TASK [Get SELinux related data]
2026-02-15 18:13:54.698593 | controller | <no matches>
2026-02-15 18:13:55.018910 | controller | ERROR
2026-02-15 18:13:55.019101 | controller | {
2026-02-15 18:13:55.019146 | controller |   "delta": "0:00:00.011680",
2026-02-15 18:13:55.019169 | controller |   "end": "2026-02-15 18:13:54.699849",
2026-02-15 18:13:55.019199 | controller |   "msg": "non-zero return code",
2026-02-15 18:13:55.019234 | controller |   "rc": 1,
2026-02-15 18:13:55.019254 | controller |   "start": "2026-02-15 18:13:54.688169"
2026-02-15 18:13:55.019271 | controller | }
2026-02-15 18:13:55.019328 | controller | ERROR: Ignoring Errors
2026-02-15 18:13:55.024521 | 
2026-02-15 18:13:55.024591 | TASK [Create system configuration directory]
2026-02-15 18:13:55.205362 | controller | changed
2026-02-15 18:13:55.210456 | 
2026-02-15 18:13:55.210533 | TASK [Get some of the system configurations]
2026-02-15 18:13:55.733058 | controller | changed
2026-02-15 18:13:55.738091 | 
2026-02-15 18:13:55.738153 | TASK [Copy generated documentation if available]
2026-02-15 18:13:55.751775 | controller | skipping: Conditional result was False
2026-02-15 18:13:55.756933 | 
2026-02-15 18:13:55.756995 | TASK [Copy generated AsciiDoc documentation if available]
2026-02-15 18:13:55.770157 | controller | skipping: Conditional result was False
2026-02-15 18:13:55.775143 | 
2026-02-15 18:13:55.775212 | TASK [Compress logs bigger than 2MB]
2026-02-15 18:13:56.300537 | controller | changed
2026-02-15 18:13:56.305059 | 
2026-02-15 18:13:56.305116 | TASK [Copy files from workspace on node]
2026-02-15 18:13:56.323019 | controller | ok
2026-02-15 18:13:56.342691 | 
2026-02-15 18:13:56.342756 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-15 18:13:56.355956 | controller | skipping: Conditional result was False
2026-02-15 18:13:56.360956 | 
2026-02-15 18:13:56.361018 | TASK [fetch-output : Set log path for single node]
2026-02-15 18:13:56.388344 | controller | ok
2026-02-15 18:13:56.392693 | 
2026-02-15 18:13:56.392753 | LOOP [fetch-output : Ensure local output dirs]
2026-02-15 18:13:56.571507 | controller -> localhost | ok: "/var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/logs"
2026-02-15 18:13:56.571744 | controller -> localhost | changed: All items complete
2026-02-15 18:13:56.571777 | 
2026-02-15 18:13:56.743383 | controller -> localhost | changed: "/var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/artifacts"
2026-02-15 18:13:56.911026 | controller -> localhost | changed: "/var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/docs"
2026-02-15 18:13:56.921013 | 
2026-02-15 18:13:56.921096 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-15 18:13:57.406716 | controller | changed:
2026-02-15 18:13:57.406910 | controller | .d..t...... ./
2026-02-15 18:13:57.406942 | controller | >f+++++++++ README.html
2026-02-15 18:13:57.406967 | controller | >f+++++++++ ansible-execution.log
2026-02-15 18:13:57.406989 | controller | >f+++++++++ ansible.log
2026-02-15 18:13:57.407008 | controller | >f+++++++++ dmesg.log
2026-02-15 18:13:57.407027 | controller | >f+++++++++ installed-pkgs.log
2026-02-15 18:13:57.407046 | controller | >f+++++++++ python.log
2026-02-15 18:13:57.407068 | controller | >f+++++++++ registries.conf
2026-02-15 18:13:57.407092 | controller | >f+++++++++ report.html
2026-02-15 18:13:57.407112 | controller | >f+++++++++ selinux-denials.log
2026-02-15 18:13:57.407132 | controller | >f+++++++++ selinux-listing.log
2026-02-15 18:13:57.407150 | controller | cd+++++++++ ci-framework-data/
2026-02-15 18:13:57.407168 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-02-15 18:13:57.407186 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-02-15 18:13:57.407215 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-02-15 18:13:57.407237 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-02-15 18:13:57.407255 | controller | cd+++++++++ ci-framework-data/logs/
2026-02-15 18:13:57.407273 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-02-15 18:13:57.407290 | controller | cd+++++++++ ci-framework-data/tests/
2026-02-15 18:13:57.407331 | controller | cd+++++++++ ci-framework-data/tests/test_operator/
2026-02-15 18:13:57.407352 | controller | cd+++++++++ registries.conf.d/
2026-02-15 18:13:57.407371 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-02-15 18:13:57.407398 | controller | cd+++++++++ system-config/
2026-02-15 18:13:57.407418 | controller | cd+++++++++ system-config/libvirt/
2026-02-15 18:13:57.407437 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-02-15 18:13:57.407455 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-02-15 18:13:57.747664 | controller | changed: .d..t...... ./
2026-02-15 18:13:58.096337 | controller | changed: .d..t...... ./
2026-02-15 18:13:58.108721 | 
2026-02-15 18:13:58.108800 | TASK [Return artifact to Zuul]
2026-02-15 18:13:58.137433 | controller | ok
2026-02-15 18:13:58.155683 | 
2026-02-15 18:13:58.155743 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-02-15 18:13:58.155842 | 
2026-02-15 18:13:58.155869 | PLAY RECAP
2026-02-15 18:13:58.155907 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-02-15 18:13:58.155929 | 
2026-02-15 18:13:58.229451 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-15 18:13:58.230154 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-15 18:13:58.803574 | 
2026-02-15 18:13:58.803716 | PLAY [all]
2026-02-15 18:13:58.821551 | 
2026-02-15 18:13:58.821618 | TASK [include_role : fetch-output]
2026-02-15 18:13:58.850075 | controller | ok
2026-02-15 18:13:58.865736 | 
2026-02-15 18:13:58.865813 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-15 18:13:58.920181 | controller | skipping: Conditional result was False
2026-02-15 18:13:58.925667 | 
2026-02-15 18:13:58.925730 | TASK [fetch-output : Set log path for single node]
2026-02-15 18:13:58.964990 | controller | ok
2026-02-15 18:13:58.969701 | 
2026-02-15 18:13:58.969770 | LOOP [fetch-output : Ensure local output dirs]
2026-02-15 18:13:59.320455 | controller -> localhost | ok: "/var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/logs"
2026-02-15 18:13:59.491433 | controller -> localhost | ok: "/var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/artifacts"
2026-02-15 18:13:59.658111 | controller -> localhost | ok: "/var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/docs"
2026-02-15 18:13:59.671372 | 
2026-02-15 18:13:59.671461 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-15 18:14:00.205111 | controller | ok
2026-02-15 18:14:00.205456 | controller | ok: All items complete
2026-02-15 18:14:00.205494 | 
2026-02-15 18:14:00.553215 | controller | ok
2026-02-15 18:14:00.910421 | controller | ok
2026-02-15 18:14:00.924669 | 
2026-02-15 18:14:00.924778 | TASK [include_role : fetch-output-openshift]
2026-02-15 18:14:00.949640 | controller | skipping: Conditional result was False
2026-02-15 18:14:00.955433 | 
2026-02-15 18:14:00.955502 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-15 18:14:01.316640 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007069
2026-02-15 18:14:01.505075 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008603
2026-02-15 18:14:01.531403 | 
2026-02-15 18:14:01.531513 | PLAY [all]
2026-02-15 18:14:01.544656 | 
2026-02-15 18:14:01.544722 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-15 18:14:01.940529 | controller | changed
2026-02-15 18:14:01.959970 | 
2026-02-15 18:14:01.960025 | PLAY RECAP
2026-02-15 18:14:01.960068 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-15 18:14:01.960088 | 
2026-02-15 18:14:02.034828 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-15 18:14:02.035576 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-15 18:14:02.542912 | 
2026-02-15 18:14:02.543012 | PLAY [localhost]
2026-02-15 18:14:02.558872 | 
2026-02-15 18:14:02.558946 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-15 18:14:02.849982 | localhost | changed
2026-02-15 18:14:02.854602 | 
2026-02-15 18:14:02.854689 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-15 18:14:02.882579 | localhost | ok
2026-02-15 18:14:02.890155 | 
2026-02-15 18:14:02.890234 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-15 18:14:03.180889 | localhost | changed
2026-02-15 18:14:03.186021 | 
2026-02-15 18:14:03.186089 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-15 18:14:03.709607 | localhost | changed
2026-02-15 18:14:03.714498 | 
2026-02-15 18:14:03.714564 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-15 18:14:04.045692 | localhost | Identity added: /var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/tmp/ansible.g7syh5hz (/var/lib/zuul/builds/dafc77b02d874387ba0af071841b3a8c/work/tmp/ansible.g7syh5hz)
2026-02-15 18:14:04.045878 | localhost | ok: Runtime: 0:00:00.008201
2026-02-15 18:14:04.049738 | 
2026-02-15 18:14:04.049801 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-15 18:14:04.280415 | localhost | ok: Runtime: 0:00:00.004752
2026-02-15 18:14:04.285116 | 
2026-02-15 18:14:04.285192 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-15 18:14:04.334887 | localhost | changed
2026-02-15 18:14:04.339841 | 
2026-02-15 18:14:04.339907 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-15 18:14:04.652516 | localhost | changed
2026-02-15 18:14:04.671250 | 
2026-02-15 18:14:04.671329 | PLAY [localhost]
2026-02-15 18:14:04.681969 | 
2026-02-15 18:14:04.682038 | TASK [Generate bulk log download script]
2026-02-15 18:14:04.703899 | localhost | ok
2026-02-15 18:14:04.715061 | 
2026-02-15 18:14:04.715129 | TASK [local-log-download : Check API endpoint is defined]
2026-02-15 18:14:04.744964 | localhost | ok: All assertions passed
2026-02-15 18:14:04.749373 | 
2026-02-15 18:14:04.749440 | TASK [local-log-download : Create download script]
2026-02-15 18:14:05.122631 | localhost -> localhost | changed
2026-02-15 18:14:05.132938 | 
2026-02-15 18:14:05.133014 | TASK [Register quick-download link]
2026-02-15 18:14:05.162897 | localhost | ok
2026-02-15 18:14:05.222627 | 
2026-02-15 18:14:05.222746 | PLAY [logserver.rdoproject.org]
2026-02-15 18:14:05.233088 | 
2026-02-15 18:14:05.233156 | TASK [Set zuul-log-path fact]
2026-02-15 18:14:05.251689 | logserver.rdoproject.org | ok
2026-02-15 18:14:05.261451 | 
2026-02-15 18:14:05.261515 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-15 18:14:05.289431 | logserver.rdoproject.org | ok
2026-02-15 18:14:05.295124 | 
2026-02-15 18:14:05.295186 | TASK [upload-logs : Create log directories]
2026-02-15 18:14:07.313543 | logserver.rdoproject.org | changed
2026-02-15 18:14:07.316994 | 
2026-02-15 18:14:07.317086 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-15 18:14:07.523747 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004899
2026-02-15 18:14:07.529293 | 
2026-02-15 18:14:07.529384 | TASK [upload-logs : Upload logs to log server]
2026-02-15 18:14:08.910386 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-15 18:14:08.913055 | 
2026-02-15 18:14:08.913118 | LOOP [upload-logs : Compress console log and json output]
2026-02-15 18:14:08.951015 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-15 18:14:08.958344 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-15 18:14:08.971049 | 
2026-02-15 18:14:08.971120 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-15 18:14:09.005040 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-15 18:14:09.005251 | 
2026-02-15 18:14:09.008063 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-15 18:14:09.019518 | 
2026-02-15 18:14:09.019644 | LOOP [upload-logs : Upload console log and json output]