2026-02-26 19:44:26.092165 | Job console starting...
2026-02-26 19:44:26.309400 | Updating repositories
2026-02-26 19:44:27.027791 | Preparing job workspace
2026-02-26 19:44:33.766518 | Running Ansible setup...
2026-02-26 19:44:37.231189 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 19:44:37.701083 | 
2026-02-26 19:44:37.701174 | PLAY [localhost]
2026-02-26 19:44:37.709095 | 
2026-02-26 19:44:37.709169 | TASK [Gathering Facts]
2026-02-26 19:44:38.473393 | localhost | ok
2026-02-26 19:44:38.486183 | 
2026-02-26 19:44:38.486278 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-26 19:44:38.782817 | localhost -> localhost | changed
2026-02-26 19:44:38.787934 | 
2026-02-26 19:44:38.788015 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-26 19:44:39.520138 | localhost -> localhost | changed
2026-02-26 19:44:39.527753 | 
2026-02-26 19:44:39.527827 | TASK [Setup log path fact]
2026-02-26 19:44:39.543966 | localhost | ok
2026-02-26 19:44:39.554125 | 
2026-02-26 19:44:39.554190 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 19:44:39.570897 | localhost | ok
2026-02-26 19:44:39.577676 | 
2026-02-26 19:44:39.577739 | TASK [emit-job-header : Print job information]
2026-02-26 19:44:39.604500 | # Job Information
2026-02-26 19:44:39.604616 | Ansible Version: 2.15.12
2026-02-26 19:44:39.604644 | Job: cifmw-molecule-reproducer
2026-02-26 19:44:39.604665 | Pipeline: github-check
2026-02-26 19:44:39.604685 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-02-26 19:44:39.604703 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3711
2026-02-26 19:44:39.604721 | Log URL (when completed): https://logserver.rdoproject.org/6b9/rdoproject.org/6b914c652e644ce09777667b89b2bc65/
2026-02-26 19:44:39.604739 | Event ID: 6b935a50-134b-11f1-962d-8b69801a00b8
2026-02-26 19:44:39.607881 | 
2026-02-26 19:44:39.607939 | LOOP [emit-job-header : Print node information]
2026-02-26 19:44:39.687992 | localhost | ok:
2026-02-26 19:44:39.688179 | localhost | # Node Information
2026-02-26 19:44:39.688212 | localhost | Inventory Hostname: controller
2026-02-26 19:44:39.688234 | localhost | Hostname: np0005631970
2026-02-26 19:44:39.688254 | localhost | Username: zuul
2026-02-26 19:44:39.688275 | localhost | Distro: CentOS 9
2026-02-26 19:44:39.688310 | localhost | Provider: ibm-bm4-nodepool
2026-02-26 19:44:39.688337 | localhost | Region: regionOne
2026-02-26 19:44:39.688356 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2026-02-26 19:44:39.688373 | localhost | Product Name: OpenStack Compute
2026-02-26 19:44:39.688390 | localhost | Interface IP: 192.168.26.224
2026-02-26 19:44:39.708282 | 
2026-02-26 19:44:39.708371 | PLAY [all]
2026-02-26 19:44:39.714211 | 
2026-02-26 19:44:39.714275 | TASK [Gather network facts]
2026-02-26 19:44:40.138266 | controller | ok
2026-02-26 19:44:40.151783 | 
2026-02-26 19:44:40.151844 | TASK [include_role : start-zuul-console]
2026-02-26 19:44:40.169618 | controller | ok
2026-02-26 19:44:40.180242 | 
2026-02-26 19:44:40.180318 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-26 19:44:40.531864 | controller | ok
2026-02-26 19:44:40.539958 | 
2026-02-26 19:44:40.540020 | TASK [include_role : add-build-sshkey]
2026-02-26 19:44:40.557201 | controller | ok
2026-02-26 19:44:40.569343 | 
2026-02-26 19:44:40.569411 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-26 19:44:40.745432 | controller -> localhost | ok
2026-02-26 19:44:40.750388 | 
2026-02-26 19:44:40.750449 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-26 19:44:40.768400 | controller | ok
2026-02-26 19:44:40.779535 | controller | included: /var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-26 19:44:40.784476 | 
2026-02-26 19:44:40.784551 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-26 19:44:41.152033 | controller -> localhost | Generating public/private rsa key pair.
2026-02-26 19:44:41.152179 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/6b914c652e644ce09777667b89b2bc65_id_rsa.
2026-02-26 19:44:41.152214 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/6b914c652e644ce09777667b89b2bc65_id_rsa.pub.
2026-02-26 19:44:41.152239 | controller -> localhost | The key fingerprint is:
2026-02-26 19:44:41.152260 | controller -> localhost | SHA256:GAXeQ7tBj7zNidyvPsiBYWdv1kGFQuXSmzLdP/mUyIM zuul-build-sshkey
2026-02-26 19:44:41.152279 | controller -> localhost | The key's randomart image is:
2026-02-26 19:44:41.152320 | controller -> localhost | +---[RSA 3072]----+
2026-02-26 19:44:41.152348 | controller -> localhost | |      ..+....o.  |
2026-02-26 19:44:41.152370 | controller -> localhost | |     . * +.oo    |
2026-02-26 19:44:41.152390 | controller -> localhost | |      o B ooo    |
2026-02-26 19:44:41.152408 | controller -> localhost | |      o+oX +.+   |
2026-02-26 19:44:41.152425 | controller -> localhost | |     ..=S.B.+..  |
2026-02-26 19:44:41.152441 | controller -> localhost | |      . . +++ ..o|
2026-02-26 19:44:41.152457 | controller -> localhost | |       . = E.+ +o|
2026-02-26 19:44:41.152473 | controller -> localhost | |        o ..  ..o|
2026-02-26 19:44:41.152488 | controller -> localhost | |         .o.    .|
2026-02-26 19:44:41.152503 | controller -> localhost | +----[SHA256]-----+
2026-02-26 19:44:41.152553 | controller -> localhost | ok: Runtime: 0:00:00.053424
2026-02-26 19:44:41.157754 | 
2026-02-26 19:44:41.157817 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-26 19:44:41.174779 | controller | ok
2026-02-26 19:44:41.183115 | controller | included: /var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-26 19:44:41.190206 | 
2026-02-26 19:44:41.190269 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-26 19:44:41.203472 | controller | skipping: Conditional result was False
2026-02-26 19:44:41.208619 | 
2026-02-26 19:44:41.208684 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-26 19:44:41.673400 | controller | changed
2026-02-26 19:44:41.677927 | 
2026-02-26 19:44:41.677988 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-26 19:44:41.929679 | controller | ok
2026-02-26 19:44:41.934314 | 
2026-02-26 19:44:41.934377 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-26 19:44:43.047280 | controller | changed
2026-02-26 19:44:43.051928 | 
2026-02-26 19:44:43.051993 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-26 19:44:44.135880 | controller | changed
2026-02-26 19:44:44.140685 | 
2026-02-26 19:44:44.140761 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-26 19:44:44.153618 | controller | skipping: Conditional result was False
2026-02-26 19:44:44.158730 | 
2026-02-26 19:44:44.158801 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-26 19:44:44.445133 | controller -> localhost | changed
2026-02-26 19:44:44.453714 | 
2026-02-26 19:44:44.453775 | TASK [add-build-sshkey : Add back temp key]
2026-02-26 19:44:44.668913 | controller -> localhost | Identity added: /var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/6b914c652e644ce09777667b89b2bc65_id_rsa (zuul-build-sshkey)
2026-02-26 19:44:44.669082 | controller -> localhost | ok: Runtime: 0:00:00.007208
2026-02-26 19:44:44.674267 | 
2026-02-26 19:44:44.674346 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-26 19:44:45.017492 | controller | ok
2026-02-26 19:44:45.021944 | 
2026-02-26 19:44:45.022010 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-26 19:44:45.045422 | controller | skipping: Conditional result was False
2026-02-26 19:44:45.054406 | 
2026-02-26 19:44:45.054501 | TASK [include_role : validate-host]
2026-02-26 19:44:45.071609 | controller | ok
2026-02-26 19:44:45.089429 | 
2026-02-26 19:44:45.089500 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-26 19:44:45.116507 | controller | ok
2026-02-26 19:44:45.120873 | 
2026-02-26 19:44:45.120940 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-26 19:44:45.305806 | controller -> localhost | ok
2026-02-26 19:44:45.311312 | 
2026-02-26 19:44:45.311395 | TASK [validate-host : Collect information about the host]
2026-02-26 19:44:46.056972 | controller | ok
2026-02-26 19:44:46.064938 | 
2026-02-26 19:44:46.065012 | TASK [validate-host : Sanitize hostname]
2026-02-26 19:44:46.102876 | controller | ok
2026-02-26 19:44:46.107419 | 
2026-02-26 19:44:46.107486 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-26 19:44:46.455709 | controller -> localhost | changed
2026-02-26 19:44:46.460738 | 
2026-02-26 19:44:46.460797 | TASK [validate-host : Collect information about zuul worker]
2026-02-26 19:44:46.834358 | controller | ok
2026-02-26 19:44:46.838839 | 
2026-02-26 19:44:46.838910 | TASK [validate-host : Write out all zuul information for each host]
2026-02-26 19:44:47.248215 | controller -> localhost | changed
2026-02-26 19:44:47.257612 | 
2026-02-26 19:44:47.257674 | TASK [include_role : prepare-workspace-openshift]
2026-02-26 19:44:47.270856 | controller | skipping: Conditional result was False
2026-02-26 19:44:47.275427 | 
2026-02-26 19:44:47.275490 | TASK [include_role : remove-zuul-sshkey]
2026-02-26 19:44:47.288569 | controller | skipping: Conditional result was False
2026-02-26 19:44:47.293548 | 
2026-02-26 19:44:47.293614 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-26 19:44:47.549247 | controller | ok: "logs"
2026-02-26 19:44:47.549471 | controller | ok: All items complete
2026-02-26 19:44:47.549501 | 
2026-02-26 19:44:47.773530 | controller | ok: "artifacts"
2026-02-26 19:44:48.007601 | controller | ok: "docs"
2026-02-26 19:44:48.013583 | 
2026-02-26 19:44:48.013703 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-26 19:44:48.292222 | controller | changed: "logs"
2026-02-26 19:44:48.534093 | controller | changed: "artifacts"
2026-02-26 19:44:48.766132 | controller | changed: "docs"
2026-02-26 19:44:48.787792 | 
2026-02-26 19:44:48.787883 | PLAY RECAP
2026-02-26 19:44:48.787929 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-26 19:44:48.787958 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 19:44:48.787977 | 
2026-02-26 19:44:48.861987 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 19:44:48.862687 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-26 19:44:49.373007 | 
2026-02-26 19:44:49.373134 | PLAY [all]
2026-02-26 19:44:49.391329 | 
2026-02-26 19:44:49.391402 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-26 19:44:49.439706 | controller | ok
2026-02-26 19:44:49.444255 | 
2026-02-26 19:44:49.444344 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-26 19:44:49.829004 | controller | changed
2026-02-26 19:44:49.834186 | 
2026-02-26 19:44:49.834259 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-26 19:44:51.016668 | controller | changed
2026-02-26 19:44:51.025932 | 
2026-02-26 19:44:51.026017 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-02-26 19:44:51.458560 | controller | changed:
2026-02-26 19:44:51.458720 | controller | {
2026-02-26 19:44:51.458750 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-02-26 19:44:51.458780 | controller | }
2026-02-26 19:44:51.740658 | controller | changed:
2026-02-26 19:44:51.740756 | controller | {
2026-02-26 19:44:51.740791 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-02-26 19:44:51.740819 | controller | }
2026-02-26 19:44:52.014290 | controller | changed:
2026-02-26 19:44:52.014406 | controller | {
2026-02-26 19:44:52.014435 | 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-26 19:44:52.014456 | controller | }
2026-02-26 19:44:52.292080 | controller | changed:
2026-02-26 19:44:52.292170 | controller | {
2026-02-26 19:44:52.292198 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-02-26 19:44:52.292219 | controller | }
2026-02-26 19:44:52.571100 | controller | changed:
2026-02-26 19:44:52.571180 | controller | {
2026-02-26 19:44:52.571207 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-02-26 19:44:52.571227 | controller | }
2026-02-26 19:44:52.851837 | controller | changed:
2026-02-26 19:44:52.851925 | controller | {
2026-02-26 19:44:52.851953 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-02-26 19:44:52.851976 | controller | }
2026-02-26 19:44:53.139355 | controller | changed:
2026-02-26 19:44:53.139490 | controller | {
2026-02-26 19:44:53.139521 | 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-26 19:44:53.139556 | controller | }
2026-02-26 19:44:53.415195 | controller | changed:
2026-02-26 19:44:53.415277 | controller | {
2026-02-26 19:44:53.415325 | 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-26 19:44:53.415352 | controller | }
2026-02-26 19:44:53.691554 | controller | changed:
2026-02-26 19:44:53.691650 | controller | {
2026-02-26 19:44:53.691678 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-02-26 19:44:53.691698 | controller | }
2026-02-26 19:44:53.981041 | controller | changed:
2026-02-26 19:44:53.981129 | controller | {
2026-02-26 19:44:53.981155 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-02-26 19:44:53.981176 | controller | }
2026-02-26 19:44:54.238354 | controller | changed:
2026-02-26 19:44:54.238443 | controller | {
2026-02-26 19:44:54.238469 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-02-26 19:44:54.238490 | controller | }
2026-02-26 19:44:54.511916 | controller | changed:
2026-02-26 19:44:54.512001 | controller | {
2026-02-26 19:44:54.512027 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-02-26 19:44:54.512047 | controller | }
2026-02-26 19:44:54.786947 | controller | changed:
2026-02-26 19:44:54.787042 | controller | {
2026-02-26 19:44:54.787069 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-02-26 19:44:54.787089 | controller | }
2026-02-26 19:44:55.056908 | controller | changed:
2026-02-26 19:44:55.056988 | controller | {
2026-02-26 19:44:55.057016 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-02-26 19:44:55.057036 | controller | }
2026-02-26 19:44:55.335771 | controller | changed:
2026-02-26 19:44:55.335911 | controller | {
2026-02-26 19:44:55.335945 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-02-26 19:44:55.335967 | controller | }
2026-02-26 19:44:55.608182 | controller | changed:
2026-02-26 19:44:55.608267 | controller | {
2026-02-26 19:44:55.608323 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-02-26 19:44:55.608353 | controller | }
2026-02-26 19:44:55.885087 | controller | changed:
2026-02-26 19:44:55.885155 | controller | {
2026-02-26 19:44:55.885182 | 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-26 19:44:55.885204 | controller | }
2026-02-26 19:44:56.161823 | controller | changed:
2026-02-26 19:44:56.161905 | controller | {
2026-02-26 19:44:56.161933 | 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-26 19:44:56.161962 | controller | }
2026-02-26 19:44:56.435256 | controller | changed:
2026-02-26 19:44:56.435363 | controller | {
2026-02-26 19:44:56.435389 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-02-26 19:44:56.435409 | controller | }
2026-02-26 19:44:56.712107 | controller | changed:
2026-02-26 19:44:56.712253 | controller | {
2026-02-26 19:44:56.712287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-02-26 19:44:56.712330 | controller | }
2026-02-26 19:44:56.992668 | controller | changed:
2026-02-26 19:44:56.992773 | controller | {
2026-02-26 19:44:56.992800 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-02-26 19:44:56.992821 | controller | }
2026-02-26 19:44:57.269897 | controller | changed:
2026-02-26 19:44:57.269950 | controller | {
2026-02-26 19:44:57.269976 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-02-26 19:44:57.269995 | controller | }
2026-02-26 19:44:57.538891 | controller | changed:
2026-02-26 19:44:57.538937 | controller | {
2026-02-26 19:44:57.538961 | 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-26 19:44:57.538980 | controller | }
2026-02-26 19:44:57.807944 | controller | changed:
2026-02-26 19:44:57.807995 | controller | {
2026-02-26 19:44:57.808021 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-02-26 19:44:57.808040 | controller | }
2026-02-26 19:44:58.081065 | controller | changed:
2026-02-26 19:44:58.081114 | controller | {
2026-02-26 19:44:58.081140 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-02-26 19:44:58.081159 | controller | }
2026-02-26 19:44:58.358518 | controller | changed:
2026-02-26 19:44:58.358579 | controller | {
2026-02-26 19:44:58.358605 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-02-26 19:44:58.358624 | controller | }
2026-02-26 19:44:58.373852 | 
2026-02-26 19:44:58.373914 | TASK [Set timezone to UTC]
2026-02-26 19:44:58.840158 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-02-26 19:44:58.844775 | 
2026-02-26 19:44:58.844840 | TASK [Create nodepool directory]
2026-02-26 19:44:59.105985 | controller | changed
2026-02-26 19:44:59.112095 | 
2026-02-26 19:44:59.112160 | TASK [Create nodepool sub_nodes file]
2026-02-26 19:45:00.023531 | controller | changed
2026-02-26 19:45:00.028429 | 
2026-02-26 19:45:00.028504 | TASK [Create nodepool sub_nodes_private file]
2026-02-26 19:45:00.931456 | controller | changed
2026-02-26 19:45:00.936140 | 
2026-02-26 19:45:00.936206 | LOOP [Populate nodepool sub_nodes file]
2026-02-26 19:45:00.964723 | 
2026-02-26 19:45:00.964845 | LOOP [Populate nodepool sub_nodes_private file]
2026-02-26 19:45:00.992699 | 
2026-02-26 19:45:00.992818 | TASK [Create nodepool primary file]
2026-02-26 19:45:01.015644 | controller | skipping: Conditional result was False
2026-02-26 19:45:01.020777 | 
2026-02-26 19:45:01.020847 | TASK [Create nodepool node_private for this node]
2026-02-26 19:45:01.991068 | controller | changed
2026-02-26 19:45:01.997800 | 
2026-02-26 19:45:01.997876 | LOOP [Copy ssh keys to nodepool directory]
2026-02-26 19:45:02.381405 | controller | ok: Item: id_rsa Runtime: 0:00:00.004515
2026-02-26 19:45:02.381601 | 
2026-02-26 19:45:02.619076 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004812
2026-02-26 19:45:02.626848 | 
2026-02-26 19:45:02.626956 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-02-26 19:45:03.638848 | controller | changed
2026-02-26 19:45:03.643558 | 
2026-02-26 19:45:03.643619 | TASK [Validate sudoers config after edits]
2026-02-26 19:45:03.921676 | controller | /etc/sudoers: parsed OK
2026-02-26 19:45:03.921799 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-02-26 19:45:03.921818 | controller | /etc/sudoers.d/zuul: parsed OK
2026-02-26 19:45:03.921829 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-02-26 19:45:04.168131 | controller | ok: Runtime: 0:00:00.005396
2026-02-26 19:45:04.173118 | 
2026-02-26 19:45:04.173179 | TASK [Show the environment passed in to job shell scripts]
2026-02-26 19:45:04.445677 | controller | SHELL=/bin/bash
2026-02-26 19:45:04.445716 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-02-26 19:45:04.445725 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-02-26 19:45:04.445730 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/11/3711/1dbc3e26875bf638505c05e2e2de2b6c6868ec9b
2026-02-26 19:45:04.445736 | controller | PWD=/home/zuul
2026-02-26 19:45:04.445741 | controller | ZUUL_PIPELINE=github-check
2026-02-26 19:45:04.445746 | controller | LOGNAME=zuul
2026-02-26 19:45:04.445751 | controller | XDG_SESSION_TYPE=tty
2026-02-26 19:45:04.445796 | controller | _=/usr/bin/env
2026-02-26 19:45:04.445803 | controller | MOTD_SHOWN=pam
2026-02-26 19:45:04.445808 | controller | HOME=/home/zuul
2026-02-26 19:45:04.445813 | controller | LANG=en_US.UTF-8
2026-02-26 19:45:04.445818 | controller | SSH_CONNECTION=192.168.26.12 52070 192.168.26.224 22
2026-02-26 19:45:04.445824 | 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-26 19:45:04.445832 | controller | ZUUL_CHANGE_IDS=3711,1dbc3e26875bf638505c05e2e2de2b6c6868ec9b
2026-02-26 19:45:04.445837 | controller | WORKSPACE=/home/zuul/workspace
2026-02-26 19:45:04.445842 | controller | XDG_SESSION_CLASS=user
2026-02-26 19:45:04.445847 | controller | SELINUX_ROLE_REQUESTED=
2026-02-26 19:45:04.445851 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-02-26 19:45:04.445856 | controller | USER=zuul
2026-02-26 19:45:04.445861 | controller | ZUUL_VOTING=True
2026-02-26 19:45:04.445866 | controller | BUILD_TIMEOUT=5400000
2026-02-26 19:45:04.445870 | controller | SELINUX_USE_CURRENT_RANGE=
2026-02-26 19:45:04.445875 | controller | SHLVL=1
2026-02-26 19:45:04.445880 | controller | ZUUL_PATCHSET=1dbc3e26875bf638505c05e2e2de2b6c6868ec9b
2026-02-26 19:45:04.445885 | controller | XDG_SESSION_ID=1
2026-02-26 19:45:04.445890 | controller | ZUUL_BRANCH=main
2026-02-26 19:45:04.445894 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-02-26 19:45:04.445899 | controller | SSH_CLIENT=192.168.26.12 52070 22
2026-02-26 19:45:04.445906 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-02-26 19:45:04.445911 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-02-26 19:45:04.445916 | controller | which_declare=declare -f
2026-02-26 19:45:04.445922 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-02-26 19:45:04.445926 | controller | SELINUX_LEVEL_REQUESTED=
2026-02-26 19:45:04.445931 | controller | ZUUL_CHANGE=3711
2026-02-26 19:45:04.445936 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-02-26 19:45:04.445941 | controller | ZUUL_UUID=6b914c652e644ce09777667b89b2bc65
2026-02-26 19:45:04.445946 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-02-26 19:45:04.445951 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-02-26 19:45:04.445956 | controller | }
2026-02-26 19:45:04.697251 | controller | ok: Runtime: 0:00:00.006021
2026-02-26 19:45:04.702593 | 
2026-02-26 19:45:04.702664 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-02-26 19:45:04.716373 | controller | skipping: Conditional result was False
2026-02-26 19:45:04.721677 | 
2026-02-26 19:45:04.721748 | TASK [Symlink /home/zuul-worker/workspace]
2026-02-26 19:45:05.263964 | controller | skipping: Conditional result was False
2026-02-26 19:45:05.269763 | 
2026-02-26 19:45:05.269829 | TASK [Ensure legacy workspace directory]
2026-02-26 19:45:05.532421 | controller | changed
2026-02-26 19:45:05.549783 | 
2026-02-26 19:45:05.549835 | PLAY RECAP
2026-02-26 19:45:05.549879 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-26 19:45:05.549902 | 
2026-02-26 19:45:05.616228 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-02-26 19:45:05.616965 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-26 19:45:06.098473 | 
2026-02-26 19:45:06.098592 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-02-26 19:45:06.118745 | 
2026-02-26 19:45:06.118818 | TASK [Create zuul-output directory]
2026-02-26 19:45:06.482171 | controller | changed
2026-02-26 19:45:06.487116 | 
2026-02-26 19:45:06.487192 | TASK [Slurp Zuul inventory test]
2026-02-26 19:45:06.743827 | controller -> localhost | ok
2026-02-26 19:45:06.749607 | 
2026-02-26 19:45:06.749677 | TASK [Save zuul inventory]
2026-02-26 19:45:07.914731 | controller | changed
2026-02-26 19:45:07.920154 | 
2026-02-26 19:45:07.920239 | TASK [Save zuul vars without the change_message]
2026-02-26 19:45:09.033915 | controller | changed
2026-02-26 19:45:09.051623 | 
2026-02-26 19:45:09.051675 | PLAY RECAP
2026-02-26 19:45:09.051717 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 19:45:09.051740 | 
2026-02-26 19:45:09.114150 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-02-26 19:45:09.114915 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-26 19:45:09.642823 | 
2026-02-26 19:45:09.642920 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-02-26 19:45:09.662661 | 
2026-02-26 19:45:09.662734 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-02-26 19:45:09.681212 | controller | ok
2026-02-26 19:45:09.697141 | 
2026-02-26 19:45:09.697219 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-02-26 19:45:09.720365 | controller | skipping: Conditional result was False
2026-02-26 19:45:09.725831 | 
2026-02-26 19:45:09.725900 | TASK [mirror-info-fork : Create /etc/ci]
2026-02-26 19:45:10.109103 | controller | ok
2026-02-26 19:45:10.114469 | 
2026-02-26 19:45:10.114548 | TASK [mirror-info-fork : Install ci_mirror script]
2026-02-26 19:45:11.087558 | controller | ok
2026-02-26 19:45:11.096744 | 
2026-02-26 19:45:11.096824 | TASK [Prepare workspace]
2026-02-26 19:45:11.115256 | controller | ok
2026-02-26 19:45:11.131608 | 
2026-02-26 19:45:11.131685 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-26 19:45:11.485608 | controller | ok
2026-02-26 19:45:11.491515 | 
2026-02-26 19:45:11.491604 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-26 19:45:13.853255 | controller | Output suppressed because no_log was given
2026-02-26 19:45:13.861812 | 
2026-02-26 19:45:13.861881 | LOOP [Create zuul-output directory]
2026-02-26 19:45:14.124959 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-02-26 19:45:14.351212 | controller | ok: "/home/zuul/zuul-output/logs"
2026-02-26 19:45:14.357229 | 
2026-02-26 19:45:14.357312 | TASK [Install required packages]
2026-02-26 19:45:37.376369 | controller | ok: Nothing to do
2026-02-26 19:45:37.381111 | 
2026-02-26 19:45:37.381173 | TASK [Install venv]
2026-02-26 19:46:34.445383 | controller | changed
2026-02-26 19:46:34.487708 | 
2026-02-26 19:46:34.487782 | PLAY RECAP
2026-02-26 19:46:34.487821 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-26 19:46:34.487843 | 
2026-02-26 19:46:34.546647 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-02-26 19:46:34.547451 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-26 19:46:35.049896 | 
2026-02-26 19:46:35.049995 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-02-26 19:46:35.070626 | 
2026-02-26 19:46:35.070720 | TASK [Gather required facts]
2026-02-26 19:46:35.597071 | controller | ok
2026-02-26 19:46:35.602245 | 
2026-02-26 19:46:35.602333 | TASK [Load environment var if instructed to]
2026-02-26 19:46:35.625878 | controller | skipping: Conditional result was False
2026-02-26 19:46:35.631166 | 
2026-02-26 19:46:35.631231 | TASK [Ensure group_vars dir exists]
2026-02-26 19:46:36.015214 | controller | ok
2026-02-26 19:46:36.020148 | 
2026-02-26 19:46:36.020215 | TASK [Print related variables]
2026-02-26 19:46:36.048383 | controller | ok:
2026-02-26 19:46:36.048494 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-02-26 19:46:36.048523 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer
2026-02-26 19:46:36.053131 | 
2026-02-26 19:46:36.053195 | TASK [Run molecule]
2026-02-26 19:46:37.034752 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-02-26 19:46:37.310779 | controller | INFO     Performing prerun with role_name_check=0...
2026-02-26 19:46:54.013081 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.013474 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.013911 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.014380 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.014836 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+78eed5da was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0+78eed5da (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.015288 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.015710 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.016156 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.016585 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.017001 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.017452 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.017871 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.018322 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.018754 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.019215 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-02-26 19:46:54.028440 | controller | INFO     Running crc_layout > prepare
2026-02-26 19:46:54.712627 | controller |
2026-02-26 19:46:54.712699 | controller | PLAY [Prepare] *****************************************************************
2026-02-26 19:46:54.712718 | controller |
2026-02-26 19:46:54.712858 | controller | TASK [Gathering Facts] *********************************************************
2026-02-26 19:46:54.712877 | controller | Thursday 26 February 2026  19:46:54 +0000 (0:00:00.020)       0:00:00.020 *****
2026-02-26 19:46:55.540490 | controller | ok: [instance]
2026-02-26 19:46:55.540580 | controller |
2026-02-26 19:46:55.540709 | controller | TASK [Create custom basedir] ***************************************************
2026-02-26 19:46:55.540831 | controller | Thursday 26 February 2026  19:46:55 +0000 (0:00:00.829)       0:00:00.849 *****
2026-02-26 19:46:55.849343 | controller | changed: [instance]
2026-02-26 19:46:55.849396 | controller |
2026-02-26 19:46:55.849512 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-02-26 19:46:55.849606 | controller | Thursday 26 February 2026  19:46:55 +0000 (0:00:00.308)       0:00:01.158 *****
2026-02-26 19:46:55.867676 | controller | skipping: [instance]
2026-02-26 19:46:55.867786 | controller |
2026-02-26 19:46:55.867908 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-02-26 19:46:55.868033 | controller | Thursday 26 February 2026  19:46:55 +0000 (0:00:00.018)       0:00:01.176 *****
2026-02-26 19:46:55.907911 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-02-26 19:46:55.907990 | controller |
2026-02-26 19:46:55.908182 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-02-26 19:46:55.908274 | controller | Thursday 26 February 2026  19:46:55 +0000 (0:00:00.040)       0:00:01.217 *****
2026-02-26 19:46:56.185233 | controller | ok: [instance]
2026-02-26 19:46:56.185299 | controller |
2026-02-26 19:46:56.185399 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-02-26 19:46:56.185497 | controller | Thursday 26 February 2026  19:46:56 +0000 (0:00:00.277)       0:00:01.494 *****
2026-02-26 19:46:56.390306 | controller | ok: [instance]
2026-02-26 19:46:56.390375 | controller |
2026-02-26 19:46:56.390479 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-02-26 19:46:56.390576 | controller | Thursday 26 February 2026  19:46:56 +0000 (0:00:00.205)       0:00:01.699 *****
2026-02-26 19:46:57.065386 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-02-26 19:46:57.065449 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-02-26 19:46:57.065545 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-02-26 19:46:57.065635 | controller |
2026-02-26 19:46:57.065728 | controller | TASK [Deploy repo-setup] *******************************************************
2026-02-26 19:46:57.065807 | controller | Thursday 26 February 2026  19:46:57 +0000 (0:00:00.674)       0:00:02.374 *****
2026-02-26 19:46:57.124332 | controller |
2026-02-26 19:46:57.124433 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-02-26 19:46:57.124552 | controller | Thursday 26 February 2026  19:46:57 +0000 (0:00:00.059)       0:00:02.433 *****
2026-02-26 19:46:57.627833 | controller | changed: [instance] => (item=tmp)
2026-02-26 19:46:57.627908 | controller | changed: [instance] => (item=artifacts/repositories)
2026-02-26 19:46:57.628005 | controller | changed: [instance] => (item=venv/repo_setup)
2026-02-26 19:46:57.628127 | controller |
2026-02-26 19:46:57.628229 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-02-26 19:46:57.628314 | controller | Thursday 26 February 2026  19:46:57 +0000 (0:00:00.503)       0:00:02.936 *****
2026-02-26 19:46:58.453596 | controller | ok: [instance]
2026-02-26 19:46:58.453646 | controller |
2026-02-26 19:46:58.453896 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-02-26 19:46:59.476153 | controller | Thursday 26 February 2026  19:46:58 +0000 (0:00:00.825)       0:00:03.762 *****
2026-02-26 19:46:59.476191 | controller | changed: [instance]
2026-02-26 19:46:59.476255 | controller |
2026-02-26 19:46:59.476278 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-02-26 19:46:59.476289 | controller | Thursday 26 February 2026  19:46:59 +0000 (0:00:01.022)       0:00:04.785 *****
2026-02-26 19:47:07.117249 | controller | changed: [instance]
2026-02-26 19:47:07.834960 | controller |
2026-02-26 19:47:07.834992 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-02-26 19:47:07.835001 | controller | Thursday 26 February 2026  19:47:07 +0000 (0:00:07.640)       0:00:12.425 *****
2026-02-26 19:47:07.835033 | controller | changed: [instance]
2026-02-26 19:47:07.859122 | controller |
2026-02-26 19:47:07.859173 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-02-26 19:47:07.859184 | controller | Thursday 26 February 2026  19:47:07 +0000 (0:00:00.717)       0:00:13.143 *****
2026-02-26 19:47:07.859211 | controller | skipping: [instance]
2026-02-26 19:47:07.859268 | controller |
2026-02-26 19:47:07.859305 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-02-26 19:47:07.859375 | controller | Thursday 26 February 2026  19:47:07 +0000 (0:00:00.024)       0:00:13.168 *****
2026-02-26 19:47:08.707149 | controller | changed: [instance]
2026-02-26 19:47:08.707185 | controller |
2026-02-26 19:47:08.707195 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-02-26 19:47:08.707206 | controller | Thursday 26 February 2026  19:47:08 +0000 (0:00:00.847)       0:00:14.015 *****
2026-02-26 19:47:08.746652 | controller | skipping: [instance]
2026-02-26 19:47:08.785108 | controller |
2026-02-26 19:47:08.785141 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-02-26 19:47:08.785149 | controller | Thursday 26 February 2026  19:47:08 +0000 (0:00:00.039)       0:00:14.055 *****
2026-02-26 19:47:08.785160 | controller | skipping: [instance]
2026-02-26 19:47:08.785183 | controller |
2026-02-26 19:47:08.785190 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-02-26 19:47:08.785198 | controller | Thursday 26 February 2026  19:47:08 +0000 (0:00:00.038)       0:00:14.093 *****
2026-02-26 19:47:08.823077 | controller | skipping: [instance]
2026-02-26 19:47:08.823412 | controller |
2026-02-26 19:47:09.375105 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-02-26 19:47:09.375135 | controller | Thursday 26 February 2026  19:47:08 +0000 (0:00:00.038)       0:00:14.132 *****
2026-02-26 19:47:09.375147 | controller | changed: [instance]
2026-02-26 19:47:09.375171 | controller |
2026-02-26 19:47:09.375179 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-02-26 19:47:09.375305 | controller | Thursday 26 February 2026  19:47:09 +0000 (0:00:00.551)       0:00:14.683 *****
2026-02-26 19:47:09.851856 | controller | changed: [instance]
2026-02-26 19:47:09.889079 | controller |
2026-02-26 19:47:09.889105 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-02-26 19:47:09.889115 | controller | Thursday 26 February 2026  19:47:09 +0000 (0:00:00.476)       0:00:15.160 *****
2026-02-26 19:47:09.889126 | controller | skipping: [instance]
2026-02-26 19:47:09.920113 | controller |
2026-02-26 19:47:09.920140 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-02-26 19:47:09.920150 | controller | Thursday 26 February 2026  19:47:09 +0000 (0:00:00.037)       0:00:15.197 *****
2026-02-26 19:47:09.920167 | controller | skipping: [instance]
2026-02-26 19:47:09.950106 | controller |
2026-02-26 19:47:09.950145 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-02-26 19:47:09.950156 | controller | Thursday 26 February 2026  19:47:09 +0000 (0:00:00.031)       0:00:15.229 *****
2026-02-26 19:47:09.950169 | controller | skipping: [instance]
2026-02-26 19:47:09.950177 | controller |
2026-02-26 19:47:09.950184 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-02-26 19:47:09.950193 | controller | Thursday 26 February 2026  19:47:09 +0000 (0:00:00.030)       0:00:15.259 *****
2026-02-26 19:47:09.990198 | controller | ok: [instance]
2026-02-26 19:47:09.990295 | controller |
2026-02-26 19:47:09.990310 | controller | TASK [repo_setup : Create download directory] **********************************
2026-02-26 19:47:09.990322 | controller | Thursday 26 February 2026  19:47:09 +0000 (0:00:00.039)       0:00:15.298 *****
2026-02-26 19:47:10.020631 | controller | skipping: [instance]
2026-02-26 19:47:10.051988 | controller |
2026-02-26 19:47:10.052029 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-02-26 19:47:10.052044 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.030)       0:00:15.329 *****
2026-02-26 19:47:10.052057 | controller | skipping: [instance]
2026-02-26 19:47:10.113640 | controller |
2026-02-26 19:47:10.113668 | controller | TASK [Download the RPM] ********************************************************
2026-02-26 19:47:10.113678 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.031)       0:00:15.360 *****
2026-02-26 19:47:10.113690 | controller | skipping: [instance]
2026-02-26 19:47:10.113854 | controller |
2026-02-26 19:47:10.114048 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-02-26 19:47:10.114251 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.062)       0:00:15.422 *****
2026-02-26 19:47:10.143548 | controller | skipping: [instance]
2026-02-26 19:47:10.143709 | controller |
2026-02-26 19:47:10.143846 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-02-26 19:47:10.143978 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.029)       0:00:15.452 *****
2026-02-26 19:47:10.175069 | controller | skipping: [instance]
2026-02-26 19:47:10.175263 | controller |
2026-02-26 19:47:10.175435 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-02-26 19:47:10.175603 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.031)       0:00:15.484 *****
2026-02-26 19:47:10.205756 | controller | skipping: [instance]
2026-02-26 19:47:10.205934 | controller |
2026-02-26 19:47:10.206134 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-02-26 19:47:10.206317 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.030)       0:00:15.515 *****
2026-02-26 19:47:10.233487 | controller | skipping: [instance]
2026-02-26 19:47:10.233663 | controller |
2026-02-26 19:47:10.233829 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-02-26 19:47:10.233960 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.027)       0:00:15.542 *****
2026-02-26 19:47:10.434470 | controller | ok: [instance]
2026-02-26 19:47:10.657451 | controller |
2026-02-26 19:47:10.657489 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-02-26 19:47:10.657498 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.200)       0:00:15.743 *****
2026-02-26 19:47:10.657509 | controller | changed: [instance]
2026-02-26 19:47:10.905894 | controller |
2026-02-26 19:47:10.905936 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-02-26 19:47:10.905946 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.222)       0:00:15.966 *****
2026-02-26 19:47:10.905959 | controller | changed: [instance]
2026-02-26 19:47:10.937095 | controller |
2026-02-26 19:47:10.937119 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-02-26 19:47:10.937128 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.248)       0:00:16.214 *****
2026-02-26 19:47:10.937138 | controller | skipping: [instance]
2026-02-26 19:47:10.937164 | controller |
2026-02-26 19:47:10.937173 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-02-26 19:47:10.937280 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.031)       0:00:16.246 *****
2026-02-26 19:47:10.966200 | controller | skipping: [instance]
2026-02-26 19:47:10.966316 | controller |
2026-02-26 19:47:10.966347 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-02-26 19:47:10.966358 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.029)       0:00:16.275 *****
2026-02-26 19:47:10.997656 | controller | skipping: [instance]
2026-02-26 19:47:11.028208 | controller |
2026-02-26 19:47:11.028263 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-02-26 19:47:11.028276 | controller | Thursday 26 February 2026  19:47:10 +0000 (0:00:00.031)       0:00:16.306 *****
2026-02-26 19:47:11.028291 | controller | skipping: [instance]
2026-02-26 19:47:11.028328 | controller |
2026-02-26 19:47:11.028339 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-02-26 19:47:11.028481 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.030)       0:00:16.336 *****
2026-02-26 19:47:11.057567 | controller | skipping: [instance]
2026-02-26 19:47:11.081248 | controller |
2026-02-26 19:47:11.081272 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-02-26 19:47:11.081282 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.029)       0:00:16.366 *****
2026-02-26 19:47:11.081298 | controller | skipping: [instance]
2026-02-26 19:47:11.081468 | controller |
2026-02-26 19:47:11.081607 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-02-26 19:47:11.081737 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.023)       0:00:16.390 *****
2026-02-26 19:47:11.101293 | controller | skipping: [instance]
2026-02-26 19:47:11.101471 | controller |
2026-02-26 19:47:11.101612 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-02-26 19:47:11.101743 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.020)       0:00:16.410 *****
2026-02-26 19:47:11.133481 | controller | skipping: [instance]
2026-02-26 19:47:11.133646 | controller |
2026-02-26 19:47:11.133783 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-02-26 19:47:11.133917 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.032)       0:00:16.442 *****
2026-02-26 19:47:11.154735 | controller | skipping: [instance]
2026-02-26 19:47:11.154912 | controller |
2026-02-26 19:47:11.155120 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-02-26 19:47:11.155306 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.021)       0:00:16.463 *****
2026-02-26 19:47:11.179511 | controller | skipping: [instance]
2026-02-26 19:47:11.179679 | controller |
2026-02-26 19:47:11.179826 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-02-26 19:47:11.179959 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.024)       0:00:16.488 *****
2026-02-26 19:47:11.212702 | controller | skipping: [instance]
2026-02-26 19:47:11.212879 | controller |
2026-02-26 19:47:11.213076 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-02-26 19:47:11.213261 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.033)       0:00:16.521 *****
2026-02-26 19:47:11.243795 | controller | skipping: [instance]
2026-02-26 19:47:11.243975 | controller |
2026-02-26 19:47:11.244173 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-02-26 19:47:11.244357 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.031)       0:00:16.553 *****
2026-02-26 19:47:11.274430 | controller | skipping: [instance]
2026-02-26 19:47:11.274574 | controller |
2026-02-26 19:47:11.274710 | controller | TASK [test_deps : Install additional packages] *********************************
2026-02-26 19:47:11.274840 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.030)       0:00:16.583 *****
2026-02-26 19:47:11.317606 | controller | skipping: [instance]
2026-02-26 19:47:11.317789 | controller |
2026-02-26 19:47:11.317952 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-02-26 19:47:11.318110 | controller | Thursday 26 February 2026  19:47:11 +0000 (0:00:00.042)       0:00:16.626 *****
2026-02-26 19:48:17.678840 | controller | ok: [instance]
2026-02-26 19:48:18.931082 | controller |
2026-02-26 19:48:18.931115 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-02-26 19:48:18.931124 | controller | Thursday 26 February 2026  19:48:17 +0000 (0:01:06.361)       0:01:22.987 *****
2026-02-26 19:48:18.931134 | controller | ok: [instance]
2026-02-26 19:48:18.963512 | controller |
2026-02-26 19:48:18.963536 | controller | TASK [test_deps : Install extra packages] **************************************
2026-02-26 19:48:18.963544 | controller | Thursday 26 February 2026  19:48:18 +0000 (0:00:01.252)       0:01:24.239 *****
2026-02-26 19:48:18.963554 | controller | skipping: [instance]
2026-02-26 19:48:19.004522 | controller |
2026-02-26 19:48:19.004542 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2026-02-26 19:48:19.004550 | controller | Thursday 26 February 2026  19:48:18 +0000 (0:00:00.032)       0:01:24.272 *****
2026-02-26 19:48:19.004561 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-02-26 19:48:19.024181 | controller |
2026-02-26 19:48:19.024219 | controller | TASK [ci_setup : List packages to install] *************************************
2026-02-26 19:48:19.024237 | controller | Thursday 26 February 2026  19:48:19 +0000 (0:00:00.040)       0:01:24.313 *****
2026-02-26 19:48:19.024250 | controller | ok: [instance] =>
2026-02-26 19:48:19.024286 | controller |   cifmw_ci_setup_packages:
2026-02-26 19:48:19.024294 | controller |   - bash-completion
2026-02-26 19:48:19.024300 | controller |   - ca-certificates
2026-02-26 19:48:19.024307 | controller |   - git-core
2026-02-26 19:48:19.024316 | controller |   - make
2026-02-26 19:48:19.024854 | controller |   - tar
2026-02-26 19:48:27.256284 | controller |   - tmux
2026-02-26 19:48:27.256327 | controller |   - python3-pip
2026-02-26 19:48:27.256335 | controller |
2026-02-26 19:48:27.256342 | controller | TASK [ci_setup : Install needed packages] **************************************
2026-02-26 19:48:27.256348 | controller | Thursday 26 February 2026  19:48:19 +0000 (0:00:00.019)       0:01:24.333 *****
2026-02-26 19:48:27.256359 | controller | changed: [instance]
2026-02-26 19:48:27.256594 | controller |
2026-02-26 19:48:27.828084 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2026-02-26 19:48:27.828114 | controller | Thursday 26 February 2026  19:48:27 +0000 (0:00:08.232)       0:01:32.565 *****
2026-02-26 19:48:27.828126 | controller | ok: [instance]
2026-02-26 19:48:27.828150 | controller |
2026-02-26 19:48:27.828158 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2026-02-26 19:48:27.828267 | controller | Thursday 26 February 2026  19:48:27 +0000 (0:00:00.571)       0:01:33.137 *****
2026-02-26 19:48:28.034212 | controller | changed: [instance]
2026-02-26 19:48:28.034276 | controller |
2026-02-26 19:48:28.034548 | controller | TASK [ci_setup : Install openshift client] *************************************
2026-02-26 19:48:34.259706 | controller | Thursday 26 February 2026  19:48:28 +0000 (0:00:00.206)       0:01:33.343 *****
2026-02-26 19:48:34.259744 | controller | changed: [instance]
2026-02-26 19:48:34.296187 | controller |
2026-02-26 19:48:34.296229 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2026-02-26 19:48:34.296243 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:06.225)       0:01:39.568 *****
2026-02-26 19:48:34.296259 | controller | ok: [instance]
2026-02-26 19:48:34.296304 | controller |
2026-02-26 19:48:34.296317 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-02-26 19:48:34.296428 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.036)       0:01:39.605 *****
2026-02-26 19:48:34.565648 | controller | changed: [instance]
2026-02-26 19:48:34.837755 | controller |
2026-02-26 19:48:34.837789 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2026-02-26 19:48:34.837799 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.268)       0:01:39.874 *****
2026-02-26 19:48:34.837811 | controller | changed: [instance]
2026-02-26 19:48:34.854575 | controller |
2026-02-26 19:48:34.854593 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2026-02-26 19:48:34.854601 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.272)       0:01:40.146 *****
2026-02-26 19:48:34.854610 | controller | skipping: [instance]
2026-02-26 19:48:34.871861 | controller |
2026-02-26 19:48:34.871883 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2026-02-26 19:48:34.871892 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.017)       0:01:40.163 *****
2026-02-26 19:48:34.871903 | controller | skipping: [instance]
2026-02-26 19:48:34.888785 | controller |
2026-02-26 19:48:34.888807 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2026-02-26 19:48:34.888817 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.017)       0:01:40.180 *****
2026-02-26 19:48:34.888827 | controller | skipping: [instance]
2026-02-26 19:48:34.904847 | controller |
2026-02-26 19:48:34.904870 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2026-02-26 19:48:34.904879 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.016)       0:01:40.197 *****
2026-02-26 19:48:34.904890 | controller | skipping: [instance]
2026-02-26 19:48:34.920772 | controller |
2026-02-26 19:48:34.920803 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2026-02-26 19:48:34.920818 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.016)       0:01:40.213 *****
2026-02-26 19:48:34.920829 | controller | skipping: [instance]
2026-02-26 19:48:34.942028 | controller |
2026-02-26 19:48:34.942046 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2026-02-26 19:48:34.942053 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.015)       0:01:40.229 *****
2026-02-26 19:48:34.942062 | controller | skipping: [instance]
2026-02-26 19:48:35.891496 | controller |
2026-02-26 19:48:35.891525 | controller | TASK [ci_setup : Manage directories] *******************************************
2026-02-26 19:48:35.891533 | controller | Thursday 26 February 2026  19:48:34 +0000 (0:00:00.021)       0:01:40.251 *****
2026-02-26 19:48:35.891544 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2026-02-26 19:48:36.222180 | controller | changed: [instance] => (item=/opt/basedir/logs)
2026-02-26 19:48:36.222214 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2026-02-26 19:48:36.222223 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2026-02-26 19:48:36.222231 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2026-02-26 19:48:36.222238 | controller |
2026-02-26 19:48:36.222245 | controller | TASK [Create secret file] ******************************************************
2026-02-26 19:48:36.222252 | controller | Thursday 26 February 2026  19:48:35 +0000 (0:00:00.948)       0:01:41.199 *****
2026-02-26 19:48:36.222270 | controller | changed: [instance]
2026-02-26 19:48:36.222301 | controller |
2026-02-26 19:48:36.222310 | controller | PLAY RECAP *********************************************************************
2026-02-26 19:48:36.222317 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2026-02-26 19:48:36.222324 | controller |
2026-02-26 19:48:36.222330 | controller | Thursday 26 February 2026  19:48:36 +0000 (0:00:00.330)       0:01:41.530 *****
2026-02-26 19:48:36.222337 | controller | ===============================================================================
2026-02-26 19:48:36.222345 | controller | test_deps : Install selinux python libs -------------------------------- 66.36s
2026-02-26 19:48:36.222371 | controller | ci_setup : Install needed packages -------------------------------------- 8.23s
2026-02-26 19:48:36.223168 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.64s
2026-02-26 19:48:36.223220 | controller | ci_setup : Install openshift client ------------------------------------- 6.23s
2026-02-26 19:48:36.223231 | controller | test_deps : Install python yaml libs ------------------------------------ 1.25s
2026-02-26 19:48:36.223238 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.02s
2026-02-26 19:48:36.223245 | controller | ci_setup : Manage directories ------------------------------------------- 0.95s
2026-02-26 19:48:36.223251 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.85s
2026-02-26 19:48:36.223258 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2026-02-26 19:48:36.223264 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.83s
2026-02-26 19:48:36.223273 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2026-02-26 19:48:36.223297 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.67s
2026-02-26 19:48:36.224183 | controller | ci_setup : Gather version of openshift client --------------------------- 0.57s
2026-02-26 19:48:36.281046 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2026-02-26 19:48:36.281074 | controller | repo_setup : Ensure directories are present ----------------------------- 0.50s
2026-02-26 19:48:36.281084 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.48s
2026-02-26 19:48:36.281091 | controller | Create secret file ------------------------------------------------------ 0.33s
2026-02-26 19:48:36.281098 | controller | Create custom basedir --------------------------------------------------- 0.31s
2026-02-26 19:48:36.281104 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2026-02-26 19:48:36.281118 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.27s
2026-02-26 19:48:36.281130 | controller | INFO     Running crc_layout > converge
2026-02-26 19:48:36.877860 | controller |
2026-02-26 19:48:36.877993 | controller | PLAY [Converge] ****************************************************************
2026-02-26 19:48:36.878182 | controller |
2026-02-26 19:48:36.878283 | controller | TASK [Gathering Facts] *********************************************************
2026-02-26 19:48:36.878396 | controller | Thursday 26 February 2026  19:48:36 +0000 (0:00:00.027)       0:00:00.027 *****
2026-02-26 19:48:37.697987 | controller | ok: [instance]
2026-02-26 19:48:37.718422 | controller |
2026-02-26 19:48:37.718449 | controller | TASK [Load networking definition from scenario file] ***************************
2026-02-26 19:48:37.718458 | controller | Thursday 26 February 2026  19:48:37 +0000 (0:00:00.820)       0:00:00.847 *****
2026-02-26 19:48:37.718468 | controller | ok: [instance]
2026-02-26 19:48:37.745957 | controller |
2026-02-26 19:48:37.745979 | controller | TASK [discover_latest_image : Discover several images if requested] ************
2026-02-26 19:48:37.745987 | controller | Thursday 26 February 2026  19:48:37 +0000 (0:00:00.020)       0:00:00.868 *****
2026-02-26 19:48:37.745997 | controller | skipping: [instance]
2026-02-26 19:48:39.309088 | controller |
2026-02-26 19:48:39.309119 | controller | TASK [discover_latest_image : Get latest image] ********************************
2026-02-26 19:48:39.309133 | controller | Thursday 26 February 2026  19:48:37 +0000 (0:00:00.027)       0:00:00.895 *****
2026-02-26 19:48:39.309154 | controller | changed: [instance]
2026-02-26 19:48:39.336152 | controller |
2026-02-26 19:48:39.336176 | controller | TASK [discover_latest_image : Export facts accordingly] ************************
2026-02-26 19:48:39.336186 | controller | Thursday 26 February 2026  19:48:39 +0000 (0:00:01.563)       0:00:02.458 *****
2026-02-26 19:48:39.336197 | controller | ok: [instance]
2026-02-26 19:48:39.336235 | controller |
2026-02-26 19:48:39.336243 | controller | TASK [reproducer : Fail for now] ***********************************************
2026-02-26 19:48:39.336252 | controller | Thursday 26 February 2026  19:48:39 +0000 (0:00:00.027)       0:00:02.485 *****
2026-02-26 19:48:39.346629 | controller | fatal: [instance]: FAILED! => changed=false
2026-02-26 19:48:39.346680 | controller |   msg: Failed on purpose!
2026-02-26 19:48:39.352778 | controller |
2026-02-26 19:48:39.651159 | controller | TASK [Create dnsmasq dir] ******************************************************
2026-02-26 19:48:39.651193 | controller | Thursday 26 February 2026  19:48:39 +0000 (0:00:00.016)       0:00:02.502 *****
2026-02-26 19:48:39.651208 | controller | changed: [instance]
2026-02-26 19:48:39.954771 | controller |
2026-02-26 19:48:39.954799 | controller | TASK [Get journalctl in a file] ************************************************
2026-02-26 19:48:39.954807 | controller | Thursday 26 February 2026  19:48:39 +0000 (0:00:00.297)       0:00:02.800 *****
2026-02-26 19:48:39.954817 | controller | changed: [instance]
2026-02-26 19:48:40.491009 | controller |
2026-02-26 19:48:40.491054 | controller | TASK [Dump journalctl output] **************************************************
2026-02-26 19:48:40.491063 | controller | Thursday 26 February 2026  19:48:39 +0000 (0:00:00.303)       0:00:03.104 *****
2026-02-26 19:48:40.491073 | controller | changed: [instance]
2026-02-26 19:48:40.821290 | controller |
2026-02-26 19:48:40.821323 | controller | TASK [Copy all dnsmasq config] *************************************************
2026-02-26 19:48:40.821332 | controller | Thursday 26 February 2026  19:48:40 +0000 (0:00:00.536)       0:00:03.640 *****
2026-02-26 19:48:40.821342 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.conf) => changed=false
2026-02-26 19:48:40.821367 | controller |   ansible_loop_var: item
2026-02-26 19:48:40.821899 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.conf /home/zuul/ci-framework-data/artifacts/dnsmasq
2026-02-26 19:48:41.135086 | controller |   item: /etc/cifmw-dnsmasq.conf
2026-02-26 19:48:41.135126 | controller |   msg: |-
2026-02-26 19:48:41.135149 | controller |     rsync: [sender] link_stat "/etc/cifmw-dnsmasq.conf" failed: No such file or directory (2)
2026-02-26 19:48:41.135155 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2026-02-26 19:48:41.135160 | controller |   rc: 23
2026-02-26 19:48:41.135172 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.d/) => changed=false
2026-02-26 19:48:41.135212 | controller |   ansible_loop_var: item
2026-02-26 19:48:41.135221 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.d/ /home/zuul/ci-framework-data/artifacts/dnsmasq
2026-02-26 19:48:41.135649 | controller |   item: /etc/cifmw-dnsmasq.d/
2026-02-26 19:48:41.139063 | controller |   msg: |-
2026-02-26 19:48:41.139083 | controller |     rsync: [sender] change_dir "/etc/cifmw-dnsmasq.d" failed: No such file or directory (2)
2026-02-26 19:48:41.139091 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2026-02-26 19:48:41.139097 | controller |   rc: 23
2026-02-26 19:48:41.139106 | controller |
2026-02-26 19:48:41.139127 | controller | PLAY RECAP *********************************************************************
2026-02-26 19:48:41.139143 | controller | instance                   : ok=7    changed=4    unreachable=0    failed=2    skipped=1    rescued=0    ignored=0
2026-02-26 19:48:41.139149 | controller |
2026-02-26 19:48:41.139155 | controller | Thursday 26 February 2026  19:48:41 +0000 (0:00:00.647)       0:00:04.288 *****
2026-02-26 19:48:41.139160 | controller | ===============================================================================
2026-02-26 19:48:41.139165 | controller | discover_latest_image : Get latest image -------------------------------- 1.56s
2026-02-26 19:48:41.139170 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2026-02-26 19:48:41.139176 | controller | Copy all dnsmasq config ------------------------------------------------- 0.65s
2026-02-26 19:48:41.139181 | controller | Dump journalctl output -------------------------------------------------- 0.54s
2026-02-26 19:48:41.139186 | controller | Get journalctl in a file ------------------------------------------------ 0.30s
2026-02-26 19:48:41.139192 | controller | Create dnsmasq dir ------------------------------------------------------ 0.30s
2026-02-26 19:48:41.139197 | controller | discover_latest_image : Discover several images if requested ------------ 0.03s
2026-02-26 19:48:41.139202 | controller | discover_latest_image : Export facts accordingly ------------------------ 0.03s
2026-02-26 19:48:41.139209 | controller | Load networking definition from scenario file --------------------------- 0.02s
2026-02-26 19:48:41.187488 | controller | reproducer : Fail for now ----------------------------------------------- 0.02s
2026-02-26 19:48:41.187517 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/reproducer/crc_layout/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/crc_layout/converge.yml']
2026-02-26 19:48:41.187865 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-02-26 19:48:41.196232 | controller | INFO     Running crc_layout > cleanup
2026-02-26 19:48:41.196624 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-02-26 19:48:41.204105 | controller | INFO     Running crc_layout > destroy
2026-02-26 19:48:41.204412 | controller | WARNING  Skipping, instances are delegated.
2026-02-26 19:48:41.204707 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-02-26 19:48:41.206127 | controller | INFO     Writing /tmp/report.html report.
2026-02-26 19:48:41.730022 | controller | ERROR
2026-02-26 19:48:41.730205 | controller | {
2026-02-26 19:48:41.730248 | controller |   "delta": "0:02:04.839956",
2026-02-26 19:48:41.730273 | controller |   "end": "2026-02-26 19:48:41.247981",
2026-02-26 19:48:41.730321 | controller |   "msg": "non-zero return code",
2026-02-26 19:48:41.730351 | controller |   "rc": 1,
2026-02-26 19:48:41.730372 | controller |   "start": "2026-02-26 19:46:36.408025"
2026-02-26 19:48:41.730392 | controller | }
failure
2026-02-26 19:48:41.733924 | 
2026-02-26 19:48:41.733973 | PLAY RECAP
2026-02-26 19:48:41.734014 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-02-26 19:48:41.734034 | 
2026-02-26 19:48:41.794748 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-02-26 19:48:41.795503 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-26 19:48:42.311126 | 
2026-02-26 19:48:42.311223 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-02-26 19:48:42.331232 | 
2026-02-26 19:48:42.331327 | TASK [Filter out host if needed]
2026-02-26 19:48:42.339894 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-02-26 19:48:42.344343 | 
2026-02-26 19:48:42.344411 | TASK [Ensure file is present]
2026-02-26 19:48:42.798818 | controller | ok
2026-02-26 19:48:42.804593 | 
2026-02-26 19:48:42.804670 | TASK [Manage molecule report file]
2026-02-26 19:48:43.330218 | controller | changed
2026-02-26 19:48:43.335358 | 
2026-02-26 19:48:43.335426 | TASK [Check if we get ci-framework-data basedir]
2026-02-26 19:48:43.577439 | controller | ok
2026-02-26 19:48:43.582459 | 
2026-02-26 19:48:43.582535 | TASK [Create ci-framework-data log directory for zuul]
2026-02-26 19:48:43.935504 | controller | changed
2026-02-26 19:48:43.941235 | 
2026-02-26 19:48:43.941361 | TASK [Copy ci-framework interesting files]
2026-02-26 19:48:44.190159 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-02-26 19:48:44.467375 | controller | changed
2026-02-26 19:48:44.472904 | 
2026-02-26 19:48:44.472980 | TASK [Get SELinux listing]
2026-02-26 19:48:44.999064 | controller | changed
2026-02-26 19:48:45.005174 | 
2026-02-26 19:48:45.005241 | TASK [Generate log index]
2026-02-26 19:48:46.018318 | controller | changed
2026-02-26 19:48:46.023225 | 
2026-02-26 19:48:46.023320 | TASK [Get some env related data]
2026-02-26 19:48:46.643280 | controller | /home/zuul/.local/bin/ansible
2026-02-26 19:48:47.548948 | controller | changed
2026-02-26 19:48:47.554087 | 
2026-02-26 19:48:47.554154 | TASK [Generate list of logs to collect in home directory]
2026-02-26 19:48:47.915921 | controller | ok: All paths examined
2026-02-26 19:48:47.921035 | 
2026-02-26 19:48:47.921097 | LOOP [Copy logs from home directory]
2026-02-26 19:48:48.446205 | controller | changed:
2026-02-26 19:48:48.446430 | controller | {
2026-02-26 19:48:48.446461 | controller |   "atime": 1743544925.4788878,
2026-02-26 19:48:48.446481 | controller |   "ctime": 1743545329.1409318,
2026-02-26 19:48:48.446498 | controller |   "dev": 64513,
2026-02-26 19:48:48.446514 | controller |   "gid": 1000,
2026-02-26 19:48:48.446529 | controller |   "gr_name": "zuul",
2026-02-26 19:48:48.446544 | controller |   "inode": 4518807,
2026-02-26 19:48:48.446570 | controller |   "isblk": false,
2026-02-26 19:48:48.446587 | controller |   "ischr": false,
2026-02-26 19:48:48.446602 | controller |   "isdir": false,
2026-02-26 19:48:48.446616 | controller |   "isfifo": false,
2026-02-26 19:48:48.446631 | controller |   "isgid": false,
2026-02-26 19:48:48.446645 | controller |   "islnk": false,
2026-02-26 19:48:48.446659 | controller |   "isreg": true,
2026-02-26 19:48:48.446673 | controller |   "issock": false,
2026-02-26 19:48:48.446686 | controller |   "isuid": false,
2026-02-26 19:48:48.446699 | controller |   "mode": "0644",
2026-02-26 19:48:48.446713 | controller |   "mtime": 1743545329.1409318,
2026-02-26 19:48:48.446726 | controller |   "nlink": 1,
2026-02-26 19:48:48.446740 | controller |   "path": "/home/zuul/crc-setup.log",
2026-02-26 19:48:48.446754 | controller |   "pw_name": "zuul",
2026-02-26 19:48:48.446768 | controller |   "rgrp": true,
2026-02-26 19:48:48.446785 | controller |   "roth": true,
2026-02-26 19:48:48.446808 | controller |   "rusr": true,
2026-02-26 19:48:48.446822 | controller |   "size": 4108,
2026-02-26 19:48:48.446835 | controller |   "uid": 1000,
2026-02-26 19:48:48.446849 | controller |   "wgrp": false,
2026-02-26 19:48:48.446864 | controller |   "woth": false,
2026-02-26 19:48:48.446881 | controller |   "wusr": true,
2026-02-26 19:48:48.446897 | controller |   "xgrp": false,
2026-02-26 19:48:48.446912 | controller |   "xoth": false,
2026-02-26 19:48:48.446927 | controller |   "xusr": false
2026-02-26 19:48:48.446942 | controller | }
2026-02-26 19:48:48.946508 | controller | changed:
2026-02-26 19:48:48.946635 | controller | {
2026-02-26 19:48:48.946663 | controller |   "atime": 1743545331.0429637,
2026-02-26 19:48:48.946683 | controller |   "ctime": 1743545842.0111232,
2026-02-26 19:48:48.946700 | controller |   "dev": 64513,
2026-02-26 19:48:48.946723 | controller |   "gid": 1000,
2026-02-26 19:48:48.946738 | controller |   "gr_name": "zuul",
2026-02-26 19:48:48.946752 | controller |   "inode": 4194437,
2026-02-26 19:48:48.946775 | controller |   "isblk": false,
2026-02-26 19:48:48.946791 | controller |   "ischr": false,
2026-02-26 19:48:48.946806 | controller |   "isdir": false,
2026-02-26 19:48:48.946828 | controller |   "isfifo": false,
2026-02-26 19:48:48.946842 | controller |   "isgid": false,
2026-02-26 19:48:48.946855 | controller |   "islnk": false,
2026-02-26 19:48:48.946869 | controller |   "isreg": true,
2026-02-26 19:48:48.946891 | controller |   "issock": false,
2026-02-26 19:48:48.946908 | controller |   "isuid": false,
2026-02-26 19:48:48.946922 | controller |   "mode": "0644",
2026-02-26 19:48:48.946936 | controller |   "mtime": 1743545842.0111232,
2026-02-26 19:48:48.946950 | controller |   "nlink": 1,
2026-02-26 19:48:48.946964 | controller |   "path": "/home/zuul/crc-start.log",
2026-02-26 19:48:48.946978 | controller |   "pw_name": "zuul",
2026-02-26 19:48:48.946992 | controller |   "rgrp": true,
2026-02-26 19:48:48.947006 | controller |   "roth": true,
2026-02-26 19:48:48.947020 | controller |   "rusr": true,
2026-02-26 19:48:48.947034 | controller |   "size": 4023,
2026-02-26 19:48:48.947048 | controller |   "uid": 1000,
2026-02-26 19:48:48.947062 | controller |   "wgrp": false,
2026-02-26 19:48:48.947075 | controller |   "woth": false,
2026-02-26 19:48:48.947089 | controller |   "wusr": true,
2026-02-26 19:48:48.947102 | controller |   "xgrp": false,
2026-02-26 19:48:48.947116 | controller |   "xoth": false,
2026-02-26 19:48:48.947129 | controller |   "xusr": false
2026-02-26 19:48:48.947143 | controller | }
2026-02-26 19:48:49.432035 | controller | changed:
2026-02-26 19:48:49.432191 | controller | {
2026-02-26 19:48:49.432225 | controller |   "atime": 1772135177.7836785,
2026-02-26 19:48:49.432247 | controller |   "ctime": 1772135194.0239296,
2026-02-26 19:48:49.432265 | controller |   "dev": 64513,
2026-02-26 19:48:49.432283 | controller |   "gid": 1000,
2026-02-26 19:48:49.432326 | controller |   "gr_name": "zuul",
2026-02-26 19:48:49.432357 | controller |   "inode": 4685789,
2026-02-26 19:48:49.432373 | controller |   "isblk": false,
2026-02-26 19:48:49.432388 | controller |   "ischr": false,
2026-02-26 19:48:49.432402 | controller |   "isdir": false,
2026-02-26 19:48:49.432416 | controller |   "isfifo": false,
2026-02-26 19:48:49.432430 | controller |   "isgid": false,
2026-02-26 19:48:49.432445 | controller |   "islnk": false,
2026-02-26 19:48:49.432460 | controller |   "isreg": true,
2026-02-26 19:48:49.432475 | controller |   "issock": false,
2026-02-26 19:48:49.432489 | controller |   "isuid": false,
2026-02-26 19:48:49.432503 | controller |   "mode": "0644",
2026-02-26 19:48:49.432517 | controller |   "mtime": 1772135194.0239296,
2026-02-26 19:48:49.432531 | controller |   "nlink": 1,
2026-02-26 19:48:49.432545 | controller |   "path": "/home/zuul/ansible.log",
2026-02-26 19:48:49.432574 | controller |   "pw_name": "zuul",
2026-02-26 19:48:49.432593 | controller |   "rgrp": true,
2026-02-26 19:48:49.432608 | controller |   "roth": true,
2026-02-26 19:48:49.432625 | controller |   "rusr": true,
2026-02-26 19:48:49.432640 | controller |   "size": 6749,
2026-02-26 19:48:49.432655 | controller |   "uid": 1000,
2026-02-26 19:48:49.432669 | controller |   "wgrp": false,
2026-02-26 19:48:49.432682 | controller |   "woth": false,
2026-02-26 19:48:49.432696 | controller |   "wusr": true,
2026-02-26 19:48:49.432710 | controller |   "xgrp": false,
2026-02-26 19:48:49.432724 | controller |   "xoth": false,
2026-02-26 19:48:49.432739 | controller |   "xusr": false
2026-02-26 19:48:49.432752 | controller | }
2026-02-26 19:48:49.440221 | 
2026-02-26 19:48:49.440289 | TASK [Copy crio stats log file]
2026-02-26 19:48:49.453284 | controller | skipping: Conditional result was False
2026-02-26 19:48:49.458540 | 
2026-02-26 19:48:49.458624 | TASK [Get SELinux related data]
2026-02-26 19:48:49.720607 | controller | <no matches>
2026-02-26 19:48:49.984082 | controller | ERROR
2026-02-26 19:48:49.984235 | controller | {
2026-02-26 19:48:49.984266 | controller |   "delta": "0:00:00.008622",
2026-02-26 19:48:49.984286 | controller |   "end": "2026-02-26 19:48:49.721521",
2026-02-26 19:48:49.984341 | controller |   "msg": "non-zero return code",
2026-02-26 19:48:49.984362 | controller |   "rc": 1,
2026-02-26 19:48:49.984378 | controller |   "start": "2026-02-26 19:48:49.712899"
2026-02-26 19:48:49.984392 | controller | }
2026-02-26 19:48:49.984413 | controller | ERROR: Ignoring Errors
2026-02-26 19:48:49.989223 | 
2026-02-26 19:48:49.989310 | TASK [Create system configuration directory]
2026-02-26 19:48:50.241036 | controller | changed
2026-02-26 19:48:50.245879 | 
2026-02-26 19:48:50.245944 | TASK [Get some of the system configurations]
2026-02-26 19:48:50.773049 | controller | changed
2026-02-26 19:48:50.778312 | 
2026-02-26 19:48:50.778379 | TASK [Copy generated documentation if available]
2026-02-26 19:48:50.791737 | controller | skipping: Conditional result was False
2026-02-26 19:48:50.797372 | 
2026-02-26 19:48:50.797443 | TASK [Copy generated AsciiDoc documentation if available]
2026-02-26 19:48:50.810722 | controller | skipping: Conditional result was False
2026-02-26 19:48:50.816031 | 
2026-02-26 19:48:50.816099 | TASK [Compress logs bigger than 2MB]
2026-02-26 19:48:51.345150 | controller | changed
2026-02-26 19:48:51.350339 | 
2026-02-26 19:48:51.350408 | TASK [Copy files from workspace on node]
2026-02-26 19:48:51.368715 | controller | ok
2026-02-26 19:48:51.390620 | 
2026-02-26 19:48:51.390694 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 19:48:51.404484 | controller | skipping: Conditional result was False
2026-02-26 19:48:51.410958 | 
2026-02-26 19:48:51.411032 | TASK [fetch-output : Set log path for single node]
2026-02-26 19:48:51.439687 | controller | ok
2026-02-26 19:48:51.444898 | 
2026-02-26 19:48:51.444982 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 19:48:51.636475 | controller -> localhost | ok: "/var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/logs"
2026-02-26 19:48:51.636710 | controller -> localhost | changed: All items complete
2026-02-26 19:48:51.636740 | 
2026-02-26 19:48:51.815879 | controller -> localhost | changed: "/var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/artifacts"
2026-02-26 19:48:51.991397 | controller -> localhost | changed: "/var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/docs"
2026-02-26 19:48:52.002184 | 
2026-02-26 19:48:52.002316 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 19:48:52.590201 | controller | changed:
2026-02-26 19:48:52.590402 | controller | .d..t...... ./
2026-02-26 19:48:52.590435 | controller | >f+++++++++ README.html
2026-02-26 19:48:52.590457 | controller | >f+++++++++ ansible-execution.log
2026-02-26 19:48:52.590480 | controller | >f+++++++++ ansible.log
2026-02-26 19:48:52.590501 | controller | >f+++++++++ crc-setup.log
2026-02-26 19:48:52.590519 | controller | >f+++++++++ crc-start.log
2026-02-26 19:48:52.590537 | controller | >f+++++++++ dmesg.log
2026-02-26 19:48:52.590567 | controller | >f+++++++++ installed-pkgs.log
2026-02-26 19:48:52.590588 | controller | >f+++++++++ python.log
2026-02-26 19:48:52.590607 | controller | >f+++++++++ registries.conf
2026-02-26 19:48:52.590624 | controller | >f+++++++++ report.html
2026-02-26 19:48:52.590642 | controller | >f+++++++++ selinux-denials.log
2026-02-26 19:48:52.590659 | controller | >f+++++++++ selinux-listing.log
2026-02-26 19:48:52.590677 | controller | cd+++++++++ ci-framework-data/
2026-02-26 19:48:52.590695 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-02-26 19:48:52.590713 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-02-26 19:48:52.590731 | controller | cd+++++++++ ci-framework-data/artifacts/dnsmasq/
2026-02-26 19:48:52.590748 | controller | >f+++++++++ ci-framework-data/artifacts/dnsmasq/journalctl.log
2026-02-26 19:48:52.590765 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-02-26 19:48:52.590782 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-02-26 19:48:52.590801 | controller | cd+++++++++ ci-framework-data/logs/
2026-02-26 19:48:52.590818 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-02-26 19:48:52.590836 | controller | cd+++++++++ registries.conf.d/
2026-02-26 19:48:52.590855 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-02-26 19:48:52.590873 | controller | cd+++++++++ system-config/
2026-02-26 19:48:52.590891 | controller | cd+++++++++ system-config/libvirt/
2026-02-26 19:48:52.590913 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-02-26 19:48:52.590931 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-02-26 19:48:52.590948 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-02-26 19:48:52.590964 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-02-26 19:48:52.590981 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-02-26 19:48:52.590997 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-02-26 19:48:52.591013 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-02-26 19:48:52.591030 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-02-26 19:48:52.591046 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-02-26 19:48:52.591063 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-02-26 19:48:52.591079 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-02-26 19:48:52.591095 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-02-26 19:48:52.591112 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-02-26 19:48:52.591128 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-02-26 19:48:52.591143 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-02-26 19:48:52.591159 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-02-26 19:48:53.009647 | controller | changed: .d..t...... ./
2026-02-26 19:48:53.437236 | controller | changed: .d..t...... ./
2026-02-26 19:48:53.454905 | 
2026-02-26 19:48:53.455015 | TASK [Return artifact to Zuul]
2026-02-26 19:48:53.483722 | controller | ok
2026-02-26 19:48:53.502382 | 
2026-02-26 19:48:53.502442 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-02-26 19:48:53.502535 | 
2026-02-26 19:48:53.502574 | PLAY RECAP
2026-02-26 19:48:53.502614 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-02-26 19:48:53.502636 | 
2026-02-26 19:48:53.584230 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-02-26 19:48:53.585142 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 19:48:54.093273 | 
2026-02-26 19:48:54.093404 | PLAY [all]
2026-02-26 19:48:54.111866 | 
2026-02-26 19:48:54.111940 | TASK [include_role : fetch-output]
2026-02-26 19:48:54.140637 | controller | ok
2026-02-26 19:48:54.157461 | 
2026-02-26 19:48:54.157552 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 19:48:54.200919 | controller | skipping: Conditional result was False
2026-02-26 19:48:54.206386 | 
2026-02-26 19:48:54.206458 | TASK [fetch-output : Set log path for single node]
2026-02-26 19:48:54.234426 | controller | ok
2026-02-26 19:48:54.239264 | 
2026-02-26 19:48:54.239359 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 19:48:54.564235 | controller -> localhost | ok: "/var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/logs"
2026-02-26 19:48:54.747258 | controller -> localhost | ok: "/var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/artifacts"
2026-02-26 19:48:54.931161 | controller -> localhost | ok: "/var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/docs"
2026-02-26 19:48:54.939322 | 
2026-02-26 19:48:54.939430 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 19:48:55.519229 | controller | ok
2026-02-26 19:48:55.519454 | controller | ok: All items complete
2026-02-26 19:48:55.519488 | 
2026-02-26 19:48:55.957682 | controller | ok
2026-02-26 19:48:56.383325 | controller | ok
2026-02-26 19:48:56.396868 | 
2026-02-26 19:48:56.396978 | TASK [include_role : fetch-output-openshift]
2026-02-26 19:48:56.410431 | controller | skipping: Conditional result was False
2026-02-26 19:48:56.416474 | 
2026-02-26 19:48:56.416550 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-26 19:48:56.749632 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006443
2026-02-26 19:48:56.937223 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006010
2026-02-26 19:48:56.965344 | 
2026-02-26 19:48:56.965440 | PLAY [all]
2026-02-26 19:48:56.980098 | 
2026-02-26 19:48:56.980173 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-26 19:48:57.425282 | controller | changed
2026-02-26 19:48:57.447742 | 
2026-02-26 19:48:57.447816 | PLAY RECAP
2026-02-26 19:48:57.447861 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-26 19:48:57.447884 | 
2026-02-26 19:48:57.522065 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 19:48:57.522832 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-26 19:48:58.059200 | 
2026-02-26 19:48:58.059357 | PLAY [localhost]
2026-02-26 19:48:58.076458 | 
2026-02-26 19:48:58.076535 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-26 19:48:58.387956 | localhost | changed
2026-02-26 19:48:58.392366 | 
2026-02-26 19:48:58.392442 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-26 19:48:58.419804 | localhost | ok
2026-02-26 19:48:58.427329 | 
2026-02-26 19:48:58.427420 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-26 19:48:58.738134 | localhost | changed
2026-02-26 19:48:58.743565 | 
2026-02-26 19:48:58.743636 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-26 19:48:59.276235 | localhost | changed
2026-02-26 19:48:59.281067 | 
2026-02-26 19:48:59.281152 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-26 19:48:59.611593 | localhost | Identity added: /var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/tmp/ansible.5e09lq2i (/var/lib/zuul/builds/6b914c652e644ce09777667b89b2bc65/work/tmp/ansible.5e09lq2i)
2026-02-26 19:48:59.611751 | localhost | ok: Runtime: 0:00:00.005814
2026-02-26 19:48:59.615868 | 
2026-02-26 19:48:59.615940 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-26 19:48:59.825052 | localhost | ok: Runtime: 0:00:00.004215
2026-02-26 19:48:59.829622 | 
2026-02-26 19:48:59.829712 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-26 19:48:59.876959 | localhost | changed
2026-02-26 19:48:59.881682 | 
2026-02-26 19:48:59.881753 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-26 19:49:00.202786 | localhost | changed
2026-02-26 19:49:00.222596 | 
2026-02-26 19:49:00.222665 | PLAY [localhost]
2026-02-26 19:49:00.233835 | 
2026-02-26 19:49:00.233910 | TASK [Generate bulk log download script]
2026-02-26 19:49:00.252835 | localhost | ok
2026-02-26 19:49:00.263952 | 
2026-02-26 19:49:00.264016 | TASK [local-log-download : Check API endpoint is defined]
2026-02-26 19:49:00.291046 | localhost | ok: All assertions passed
2026-02-26 19:49:00.295291 | 
2026-02-26 19:49:00.295371 | TASK [local-log-download : Create download script]
2026-02-26 19:49:00.644618 | localhost -> localhost | changed
2026-02-26 19:49:00.653439 | 
2026-02-26 19:49:00.653502 | TASK [Register quick-download link]
2026-02-26 19:49:00.670218 | localhost | ok
2026-02-26 19:49:00.701904 | 
2026-02-26 19:49:00.701964 | PLAY [logserver.rdoproject.org]
2026-02-26 19:49:00.711406 | 
2026-02-26 19:49:00.711465 | TASK [Set zuul-log-path fact]
2026-02-26 19:49:00.727271 | logserver.rdoproject.org | ok
2026-02-26 19:49:00.736037 | 
2026-02-26 19:49:00.736102 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 19:49:00.762213 | logserver.rdoproject.org | ok
2026-02-26 19:49:00.767689 | 
2026-02-26 19:49:00.767752 | TASK [upload-logs : Create log directories]
2026-02-26 19:49:02.535320 | logserver.rdoproject.org | changed
2026-02-26 19:49:02.538455 | 
2026-02-26 19:49:02.538519 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-26 19:49:02.754828 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004553
2026-02-26 19:49:02.759002 | 
2026-02-26 19:49:02.759072 | TASK [upload-logs : Upload logs to log server]
2026-02-26 19:49:04.232898 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-26 19:49:04.235797 | 
2026-02-26 19:49:04.235871 | LOOP [upload-logs : Compress console log and json output]
2026-02-26 19:49:04.272735 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 19:49:04.282060 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 19:49:04.292924 | 
2026-02-26 19:49:04.293002 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-26 19:49:04.330536 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 19:49:04.330765 | 
2026-02-26 19:49:04.334140 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 19:49:04.340100 | 
2026-02-26 19:49:04.340181 | LOOP [upload-logs : Upload console log and json output]