2025-12-01 08:18:26.321289 | Job console starting...
2025-12-01 08:18:26.536048 | Updating repositories
2025-12-01 08:18:27.276339 | Preparing job workspace
2025-12-01 08:18:34.398780 | Running Ansible setup...
2025-12-01 08:18:37.849334 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 08:18:38.326646 | 
2025-12-01 08:18:38.326757 | PLAY [localhost]
2025-12-01 08:18:38.335522 | 
2025-12-01 08:18:38.335595 | TASK [Gathering Facts]
2025-12-01 08:18:39.131534 | localhost | ok
2025-12-01 08:18:39.144329 | 
2025-12-01 08:18:39.144411 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 08:18:39.451515 | localhost -> localhost | changed
2025-12-01 08:18:39.456466 | 
2025-12-01 08:18:39.456541 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 08:18:40.098862 | localhost -> localhost | changed
2025-12-01 08:18:40.106740 | 
2025-12-01 08:18:40.106803 | TASK [Setup log path fact]
2025-12-01 08:18:40.123452 | localhost | ok
2025-12-01 08:18:40.134095 | 
2025-12-01 08:18:40.134161 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 08:18:40.151553 | localhost | ok
2025-12-01 08:18:40.158716 | 
2025-12-01 08:18:40.158783 | TASK [emit-job-header : Print job information]
2025-12-01 08:18:40.185445 | # Job Information
2025-12-01 08:18:40.185556 | Ansible Version: 2.15.12
2025-12-01 08:18:40.185586 | Job: cifmw-molecule-reproducer
2025-12-01 08:18:40.185608 | Pipeline: github-check
2025-12-01 08:18:40.185628 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-01 08:18:40.185648 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-01 08:18:40.185668 | Log URL (when completed): https://logserver.rdoproject.org/34a/rdoproject.org/34ac555c268942a08b4f0492d8a70011/
2025-12-01 08:18:40.185686 | Event ID: 2cb40ac0-ce8e-11f0-9e93-bda27212598c
2025-12-01 08:18:40.189272 | 
2025-12-01 08:18:40.189336 | LOOP [emit-job-header : Print node information]
2025-12-01 08:18:40.266987 | localhost | ok:
2025-12-01 08:18:40.267139 | localhost | # Node Information
2025-12-01 08:18:40.267166 | localhost | Inventory Hostname: controller
2025-12-01 08:18:40.267188 | localhost | Hostname: np0005540719
2025-12-01 08:18:40.267206 | localhost | Username: zuul
2025-12-01 08:18:40.267225 | localhost | Distro: CentOS 9
2025-12-01 08:18:40.267241 | localhost | Provider: ibm-bm4-nodepool
2025-12-01 08:18:40.267256 | localhost | Region: regionOne
2025-12-01 08:18:40.267270 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-12-01 08:18:40.267284 | localhost | Product Name: OpenStack Compute
2025-12-01 08:18:40.267298 | localhost | Interface IP: 192.168.26.233
2025-12-01 08:18:40.291670 | 
2025-12-01 08:18:40.291745 | PLAY [all]
2025-12-01 08:18:40.297783 | 
2025-12-01 08:18:40.297852 | TASK [Gather network facts]
2025-12-01 08:18:40.762798 | controller | ok
2025-12-01 08:18:40.776512 | 
2025-12-01 08:18:40.776575 | TASK [include_role : start-zuul-console]
2025-12-01 08:18:40.793965 | controller | ok
2025-12-01 08:18:40.804779 | 
2025-12-01 08:18:40.804845 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 08:18:41.189304 | controller | ok
2025-12-01 08:18:41.197626 | 
2025-12-01 08:18:41.197688 | TASK [include_role : add-build-sshkey]
2025-12-01 08:18:41.214620 | controller | ok
2025-12-01 08:18:41.226817 | 
2025-12-01 08:18:41.226893 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 08:18:41.412010 | controller -> localhost | ok
2025-12-01 08:18:41.417609 | 
2025-12-01 08:18:41.417670 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 08:18:41.436366 | controller | ok
2025-12-01 08:18:41.448165 | controller | included: /var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 08:18:41.453539 | 
2025-12-01 08:18:41.453601 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 08:18:41.926562 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 08:18:41.926736 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/34ac555c268942a08b4f0492d8a70011_id_rsa.
2025-12-01 08:18:41.926770 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/34ac555c268942a08b4f0492d8a70011_id_rsa.pub.
2025-12-01 08:18:41.926797 | controller -> localhost | The key fingerprint is:
2025-12-01 08:18:41.926819 | controller -> localhost | SHA256:EJ0xrgUYA+i79R8k/hJ7g6c6mADbZi2TEnoK6eqYZVA zuul-build-sshkey
2025-12-01 08:18:41.926861 | controller -> localhost | The key's randomart image is:
2025-12-01 08:18:41.926884 | controller -> localhost | +---[RSA 3072]----+
2025-12-01 08:18:41.926904 | controller -> localhost | | ...ooo.oo       |
2025-12-01 08:18:41.927026 | controller -> localhost | |.   .. +o.       |
2025-12-01 08:18:41.927053 | controller -> localhost | |. E   . o        |
2025-12-01 08:18:41.927075 | controller -> localhost | |oo     +         |
2025-12-01 08:18:41.927095 | controller -> localhost | |+=.o ...S        |
2025-12-01 08:18:41.927114 | controller -> localhost | |BoO.o.o          |
2025-12-01 08:18:41.927133 | controller -> localhost | |+*Bo..+.         |
2025-12-01 08:18:41.927152 | controller -> localhost | |oO . =.+.        |
2025-12-01 08:18:41.927170 | controller -> localhost | |*. .o.*o.        |
2025-12-01 08:18:41.927189 | controller -> localhost | +----[SHA256]-----+
2025-12-01 08:18:41.927230 | controller -> localhost | ok: Runtime: 0:00:00.153245
2025-12-01 08:18:41.932653 | 
2025-12-01 08:18:41.932716 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 08:18:41.950436 | controller | ok
2025-12-01 08:18:41.959485 | controller | included: /var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 08:18:41.967089 | 
2025-12-01 08:18:41.967155 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 08:18:41.980343 | controller | skipping: Conditional result was False
2025-12-01 08:18:41.985422 | 
2025-12-01 08:18:41.985482 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 08:18:42.449928 | controller | changed
2025-12-01 08:18:42.454576 | 
2025-12-01 08:18:42.454638 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 08:18:42.705073 | controller | ok
2025-12-01 08:18:42.709730 | 
2025-12-01 08:18:42.709790 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 08:18:43.843281 | controller | changed
2025-12-01 08:18:43.847782 | 
2025-12-01 08:18:43.847875 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 08:18:45.018652 | controller | changed
2025-12-01 08:18:45.023304 | 
2025-12-01 08:18:45.023367 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 08:18:45.035941 | controller | skipping: Conditional result was False
2025-12-01 08:18:45.040798 | 
2025-12-01 08:18:45.040871 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 08:18:45.337797 | controller -> localhost | changed
2025-12-01 08:18:45.346583 | 
2025-12-01 08:18:45.346649 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 08:18:45.561754 | controller -> localhost | Identity added: /var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/34ac555c268942a08b4f0492d8a70011_id_rsa (zuul-build-sshkey)
2025-12-01 08:18:45.561961 | controller -> localhost | ok: Runtime: 0:00:00.006598
2025-12-01 08:18:45.567234 | 
2025-12-01 08:18:45.567296 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 08:18:45.923685 | controller | ok
2025-12-01 08:18:45.928145 | 
2025-12-01 08:18:45.928213 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 08:18:45.951333 | controller | skipping: Conditional result was False
2025-12-01 08:18:45.959867 | 
2025-12-01 08:18:45.959963 | TASK [include_role : validate-host]
2025-12-01 08:18:45.976530 | controller | ok
2025-12-01 08:18:45.994402 | 
2025-12-01 08:18:45.994471 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-01 08:18:46.021566 | controller | ok
2025-12-01 08:18:46.026125 | 
2025-12-01 08:18:46.026191 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-01 08:18:46.221528 | controller -> localhost | ok
2025-12-01 08:18:46.226734 | 
2025-12-01 08:18:46.226794 | TASK [validate-host : Collect information about the host]
2025-12-01 08:18:46.922688 | controller | ok
2025-12-01 08:18:46.931323 | 
2025-12-01 08:18:46.931390 | TASK [validate-host : Sanitize hostname]
2025-12-01 08:18:46.974092 | controller | ok
2025-12-01 08:18:46.979261 | 
2025-12-01 08:18:46.979333 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-01 08:18:47.350998 | controller -> localhost | changed
2025-12-01 08:18:47.357900 | 
2025-12-01 08:18:47.357996 | TASK [validate-host : Collect information about zuul worker]
2025-12-01 08:18:47.743087 | controller | ok
2025-12-01 08:18:47.747925 | 
2025-12-01 08:18:47.748016 | TASK [validate-host : Write out all zuul information for each host]
2025-12-01 08:18:48.106582 | controller -> localhost | changed
2025-12-01 08:18:48.116288 | 
2025-12-01 08:18:48.116382 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 08:18:48.130090 | controller | skipping: Conditional result was False
2025-12-01 08:18:48.134803 | 
2025-12-01 08:18:48.134876 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 08:18:48.148401 | controller | skipping: Conditional result was False
2025-12-01 08:18:48.153316 | 
2025-12-01 08:18:48.153380 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 08:18:48.414330 | controller | ok: "logs"
2025-12-01 08:18:48.414599 | controller | ok: All items complete
2025-12-01 08:18:48.414628 | 
2025-12-01 08:18:48.651546 | controller | ok: "artifacts"
2025-12-01 08:18:48.878418 | controller | ok: "docs"
2025-12-01 08:18:48.892969 | 
2025-12-01 08:18:48.893081 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 08:18:49.150084 | controller | changed: "logs"
2025-12-01 08:18:49.381578 | controller | changed: "artifacts"
2025-12-01 08:18:49.623532 | controller | changed: "docs"
2025-12-01 08:18:49.649364 | 
2025-12-01 08:18:49.649461 | PLAY RECAP
2025-12-01 08:18:49.649510 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 08:18:49.649537 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 08:18:49.649557 | 
2025-12-01 08:18:49.724871 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 08:18:49.725607 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 08:18:50.258876 | 
2025-12-01 08:18:50.258997 | PLAY [all]
2025-12-01 08:18:50.278315 | 
2025-12-01 08:18:50.278390 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 08:18:50.326813 | controller | ok
2025-12-01 08:18:50.331414 | 
2025-12-01 08:18:50.331491 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 08:18:50.736030 | controller | changed
2025-12-01 08:18:50.741309 | 
2025-12-01 08:18:50.741379 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 08:18:52.007111 | controller | changed
2025-12-01 08:18:52.016666 | 
2025-12-01 08:18:52.016736 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-01 08:18:52.437526 | controller | changed:
2025-12-01 08:18:52.437701 | controller | {
2025-12-01 08:18:52.437735 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-01 08:18:52.437765 | controller | }
2025-12-01 08:18:52.733960 | controller | changed:
2025-12-01 08:18:52.734017 | controller | {
2025-12-01 08:18:52.734045 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-01 08:18:52.734067 | controller | }
2025-12-01 08:18:53.004052 | controller | changed:
2025-12-01 08:18:53.004108 | controller | {
2025-12-01 08:18:53.004139 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-01 08:18:53.004162 | controller | }
2025-12-01 08:18:53.282880 | controller | changed:
2025-12-01 08:18:53.282984 | controller | {
2025-12-01 08:18:53.283010 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-01 08:18:53.283031 | controller | }
2025-12-01 08:18:53.566367 | controller | changed:
2025-12-01 08:18:53.566426 | controller | {
2025-12-01 08:18:53.566452 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-01 08:18:53.566472 | controller | }
2025-12-01 08:18:53.853901 | controller | changed:
2025-12-01 08:18:53.853981 | controller | {
2025-12-01 08:18:53.854006 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-01 08:18:53.854026 | controller | }
2025-12-01 08:18:54.134977 | controller | changed:
2025-12-01 08:18:54.135057 | controller | {
2025-12-01 08:18:54.135083 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-01 08:18:54.135104 | controller | }
2025-12-01 08:18:54.409195 | controller | changed:
2025-12-01 08:18:54.409301 | controller | {
2025-12-01 08:18:54.409348 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-01 08:18:54.409371 | controller | }
2025-12-01 08:18:54.684768 | controller | changed:
2025-12-01 08:18:54.684878 | controller | {
2025-12-01 08:18:54.684905 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-01 08:18:54.684945 | controller | }
2025-12-01 08:18:54.984095 | controller | changed:
2025-12-01 08:18:54.984175 | controller | {
2025-12-01 08:18:54.984200 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-01 08:18:54.984221 | controller | }
2025-12-01 08:18:55.247906 | controller | changed:
2025-12-01 08:18:55.247992 | controller | {
2025-12-01 08:18:55.248046 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-01 08:18:55.248067 | controller | }
2025-12-01 08:18:55.529628 | controller | changed:
2025-12-01 08:18:55.529712 | controller | {
2025-12-01 08:18:55.529739 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-01 08:18:55.529759 | controller | }
2025-12-01 08:18:55.824738 | controller | changed:
2025-12-01 08:18:55.824809 | controller | {
2025-12-01 08:18:55.824835 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-01 08:18:55.824869 | controller | }
2025-12-01 08:18:56.129077 | controller | changed:
2025-12-01 08:18:56.129166 | controller | {
2025-12-01 08:18:56.129192 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-01 08:18:56.129213 | controller | }
2025-12-01 08:18:56.444122 | controller | changed:
2025-12-01 08:18:56.444199 | controller | {
2025-12-01 08:18:56.444224 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-01 08:18:56.444243 | controller | }
2025-12-01 08:18:56.732515 | controller | changed:
2025-12-01 08:18:56.732614 | controller | {
2025-12-01 08:18:56.732640 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-01 08:18:56.732661 | controller | }
2025-12-01 08:18:57.031031 | controller | changed:
2025-12-01 08:18:57.031099 | controller | {
2025-12-01 08:18:57.031125 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-01 08:18:57.031147 | controller | }
2025-12-01 08:18:57.308071 | controller | changed:
2025-12-01 08:18:57.308146 | controller | {
2025-12-01 08:18:57.308170 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-01 08:18:57.308195 | controller | }
2025-12-01 08:18:57.613956 | controller | changed:
2025-12-01 08:18:57.614042 | controller | {
2025-12-01 08:18:57.614068 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-01 08:18:57.614088 | controller | }
2025-12-01 08:18:57.906566 | controller | changed:
2025-12-01 08:18:57.906659 | controller | {
2025-12-01 08:18:57.906686 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-01 08:18:57.906707 | controller | }
2025-12-01 08:18:58.196357 | controller | changed:
2025-12-01 08:18:58.196434 | controller | {
2025-12-01 08:18:58.196459 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-01 08:18:58.196480 | controller | }
2025-12-01 08:18:58.496663 | controller | changed:
2025-12-01 08:18:58.496722 | controller | {
2025-12-01 08:18:58.496749 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-01 08:18:58.496768 | controller | }
2025-12-01 08:18:58.779818 | controller | changed:
2025-12-01 08:18:58.779877 | controller | {
2025-12-01 08:18:58.779903 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-01 08:18:58.779953 | controller | }
2025-12-01 08:18:59.065656 | controller | changed:
2025-12-01 08:18:59.065711 | controller | {
2025-12-01 08:18:59.065736 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-01 08:18:59.065756 | controller | }
2025-12-01 08:18:59.340198 | controller | changed:
2025-12-01 08:18:59.340270 | controller | {
2025-12-01 08:18:59.340292 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-01 08:18:59.340310 | controller | }
2025-12-01 08:18:59.614784 | controller | changed:
2025-12-01 08:18:59.614884 | controller | {
2025-12-01 08:18:59.614981 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-01 08:18:59.615012 | controller | }
2025-12-01 08:18:59.632185 | 
2025-12-01 08:18:59.632275 | TASK [Set timezone to UTC]
2025-12-01 08:19:00.098258 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-01 08:19:00.103039 | 
2025-12-01 08:19:00.103104 | TASK [Create nodepool directory]
2025-12-01 08:19:00.365138 | controller | changed
2025-12-01 08:19:00.370156 | 
2025-12-01 08:19:00.370220 | TASK [Create nodepool sub_nodes file]
2025-12-01 08:19:01.280428 | controller | changed
2025-12-01 08:19:01.285195 | 
2025-12-01 08:19:01.285258 | TASK [Create nodepool sub_nodes_private file]
2025-12-01 08:19:02.199511 | controller | changed
2025-12-01 08:19:02.204322 | 
2025-12-01 08:19:02.204383 | LOOP [Populate nodepool sub_nodes file]
2025-12-01 08:19:02.233419 | 
2025-12-01 08:19:02.233533 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-01 08:19:02.262176 | 
2025-12-01 08:19:02.262293 | TASK [Create nodepool primary file]
2025-12-01 08:19:02.285814 | controller | skipping: Conditional result was False
2025-12-01 08:19:02.290593 | 
2025-12-01 08:19:02.290660 | TASK [Create nodepool node_private for this node]
2025-12-01 08:19:03.220548 | controller | changed
2025-12-01 08:19:03.227792 | 
2025-12-01 08:19:03.227875 | LOOP [Copy ssh keys to nodepool directory]
2025-12-01 08:19:03.586953 | controller | ok: Item: id_rsa Runtime: 0:00:00.004479
2025-12-01 08:19:03.587220 | 
2025-12-01 08:19:03.798533 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004413
2025-12-01 08:19:03.805224 | 
2025-12-01 08:19:03.805294 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-01 08:19:04.701344 | controller | changed
2025-12-01 08:19:04.706088 | 
2025-12-01 08:19:04.706145 | TASK [Validate sudoers config after edits]
2025-12-01 08:19:04.949585 | controller | /etc/sudoers: parsed OK
2025-12-01 08:19:04.949642 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-01 08:19:04.949652 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-01 08:19:04.949657 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-01 08:19:05.229864 | controller | ok: Runtime: 0:00:00.004910
2025-12-01 08:19:05.235108 | 
2025-12-01 08:19:05.235170 | TASK [Show the environment passed in to job shell scripts]
2025-12-01 08:19:05.475637 | controller | SHELL=/bin/bash
2025-12-01 08:19:05.475676 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-01 08:19:05.475685 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-01 08:19:05.475691 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/299eec7e5ad90f400f0349fc019ba0a4dfaf4281
2025-12-01 08:19:05.475696 | controller | PWD=/home/zuul
2025-12-01 08:19:05.475739 | controller | ZUUL_PIPELINE=github-check
2025-12-01 08:19:05.475747 | controller | LOGNAME=zuul
2025-12-01 08:19:05.475752 | controller | XDG_SESSION_TYPE=tty
2025-12-01 08:19:05.475757 | controller | _=/usr/bin/env
2025-12-01 08:19:05.475762 | controller | MOTD_SHOWN=pam
2025-12-01 08:19:05.475767 | controller | HOME=/home/zuul
2025-12-01 08:19:05.475771 | controller | LANG=en_US.UTF-8
2025-12-01 08:19:05.475776 | controller | SSH_CONNECTION=192.168.26.12 45492 192.168.26.233 22
2025-12-01 08:19:05.475781 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-01 08:19:05.475789 | controller | ZUUL_CHANGE_IDS=3505,299eec7e5ad90f400f0349fc019ba0a4dfaf4281
2025-12-01 08:19:05.475794 | controller | WORKSPACE=/home/zuul/workspace
2025-12-01 08:19:05.475798 | controller | XDG_SESSION_CLASS=user
2025-12-01 08:19:05.475803 | controller | SELINUX_ROLE_REQUESTED=
2025-12-01 08:19:05.475808 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-01 08:19:05.475813 | controller | USER=zuul
2025-12-01 08:19:05.475817 | controller | ZUUL_VOTING=True
2025-12-01 08:19:05.475822 | controller | BUILD_TIMEOUT=5400000
2025-12-01 08:19:05.475827 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-01 08:19:05.475831 | controller | SHLVL=1
2025-12-01 08:19:05.475836 | controller | ZUUL_PATCHSET=299eec7e5ad90f400f0349fc019ba0a4dfaf4281
2025-12-01 08:19:05.475841 | controller | XDG_SESSION_ID=1
2025-12-01 08:19:05.475846 | controller | ZUUL_BRANCH=main
2025-12-01 08:19:05.475850 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-01 08:19:05.475855 | controller | SSH_CLIENT=192.168.26.12 45492 22
2025-12-01 08:19:05.475860 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-01 08:19:05.475865 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-01 08:19:05.475870 | controller | which_declare=declare -f
2025-12-01 08:19:05.475875 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-01 08:19:05.475880 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-01 08:19:05.475885 | controller | ZUUL_CHANGE=3505
2025-12-01 08:19:05.475890 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-01 08:19:05.475894 | controller | ZUUL_UUID=34ac555c268942a08b4f0492d8a70011
2025-12-01 08:19:05.475899 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-01 08:19:05.475904 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-01 08:19:05.475909 | controller | }
2025-12-01 08:19:05.761037 | controller | ok: Runtime: 0:00:00.005130
2025-12-01 08:19:05.766455 | 
2025-12-01 08:19:05.766517 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-01 08:19:05.780024 | controller | skipping: Conditional result was False
2025-12-01 08:19:05.785140 | 
2025-12-01 08:19:05.785204 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-01 08:19:06.326815 | controller | skipping: Conditional result was False
2025-12-01 08:19:06.332356 | 
2025-12-01 08:19:06.332417 | TASK [Ensure legacy workspace directory]
2025-12-01 08:19:06.563455 | controller | changed
2025-12-01 08:19:06.581090 | 
2025-12-01 08:19:06.581141 | PLAY RECAP
2025-12-01 08:19:06.581185 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 08:19:06.581208 | 
2025-12-01 08:19:06.644982 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 08:19:06.645633 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 08:19:07.105733 | 
2025-12-01 08:19:07.105821 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-01 08:19:07.124390 | 
2025-12-01 08:19:07.124462 | TASK [Create zuul-output directory]
2025-12-01 08:19:07.477486 | controller | changed
2025-12-01 08:19:07.482360 | 
2025-12-01 08:19:07.482432 | TASK [Slurp Zuul inventory test]
2025-12-01 08:19:07.738636 | controller -> localhost | ok
2025-12-01 08:19:07.744787 | 
2025-12-01 08:19:07.744864 | TASK [Save zuul inventory]
2025-12-01 08:19:08.856978 | controller | changed
2025-12-01 08:19:08.862140 | 
2025-12-01 08:19:08.862207 | TASK [Save zuul vars without the change_message]
2025-12-01 08:19:09.813417 | controller | changed
2025-12-01 08:19:09.831423 | 
2025-12-01 08:19:09.831484 | PLAY RECAP
2025-12-01 08:19:09.831527 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 08:19:09.831551 | 
2025-12-01 08:19:09.898290 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 08:19:09.899004 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 08:19:10.413744 | 
2025-12-01 08:19:10.413839 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-01 08:19:10.432496 | 
2025-12-01 08:19:10.432565 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-01 08:19:10.450555 | controller | ok
2025-12-01 08:19:10.466607 | 
2025-12-01 08:19:10.466680 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 08:19:10.490412 | controller | skipping: Conditional result was False
2025-12-01 08:19:10.495893 | 
2025-12-01 08:19:10.495973 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 08:19:10.859421 | controller | ok
2025-12-01 08:19:10.864697 | 
2025-12-01 08:19:10.864764 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 08:19:11.716330 | controller | ok
2025-12-01 08:19:11.726478 | 
2025-12-01 08:19:11.726542 | TASK [Prepare workspace]
2025-12-01 08:19:11.744835 | controller | ok
2025-12-01 08:19:11.760391 | 
2025-12-01 08:19:11.760457 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 08:19:12.093920 | controller | ok
2025-12-01 08:19:12.099313 | 
2025-12-01 08:19:12.099373 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 08:19:14.396976 | controller | Output suppressed because no_log was given
2025-12-01 08:19:14.405257 | 
2025-12-01 08:19:14.405330 | LOOP [Create zuul-output directory]
2025-12-01 08:19:14.634453 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-01 08:19:14.842161 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-01 08:19:14.852311 | 
2025-12-01 08:19:14.852414 | TASK [Install required packages]
2025-12-01 08:19:32.764755 | controller | ok: Nothing to do
2025-12-01 08:19:32.769811 | 
2025-12-01 08:19:32.769884 | TASK [Install venv]
2025-12-01 08:20:29.416872 | controller | changed
2025-12-01 08:20:29.439761 | 
2025-12-01 08:20:29.439820 | PLAY RECAP
2025-12-01 08:20:29.439864 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-01 08:20:29.439885 | 
2025-12-01 08:20:29.506608 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 08:20:29.507359 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 08:20:29.987319 | 
2025-12-01 08:20:29.987415 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-01 08:20:30.006567 | 
2025-12-01 08:20:30.006635 | TASK [Gather required facts]
2025-12-01 08:20:30.513647 | controller | ok
2025-12-01 08:20:30.518816 | 
2025-12-01 08:20:30.518888 | TASK [Load environment var if instructed to]
2025-12-01 08:20:30.543052 | controller | skipping: Conditional result was False
2025-12-01 08:20:30.548155 | 
2025-12-01 08:20:30.548220 | TASK [Ensure group_vars dir exists]
2025-12-01 08:20:30.891487 | controller | ok
2025-12-01 08:20:30.897191 | 
2025-12-01 08:20:30.897259 | TASK [Print related variables]
2025-12-01 08:20:30.926471 | controller | ok:
2025-12-01 08:20:30.926593 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-01 08:20:30.926623 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer
2025-12-01 08:20:30.931239 | 
2025-12-01 08:20:30.931307 | TASK [Run molecule]
2025-12-01 08:20:31.863878 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-01 08:20:32.122732 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-01 08:20:47.854088 | 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).
2025-12-01 08:20:47.854521 | 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).
2025-12-01 08:20:47.854969 | 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).
2025-12-01 08:20:47.855445 | 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).
2025-12-01 08:20:47.855929 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+96665145 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0+96665145 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 08:20:47.856377 | 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).
2025-12-01 08:20:47.856814 | 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).
2025-12-01 08:20:47.857238 | 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).
2025-12-01 08:20:47.857675 | 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).
2025-12-01 08:20:47.858100 | 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).
2025-12-01 08:20:47.858563 | 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).
2025-12-01 08:20:47.858987 | 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).
2025-12-01 08:20:47.859449 | 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).
2025-12-01 08:20:47.859878 | 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).
2025-12-01 08:20:47.860355 | 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).
2025-12-01 08:20:47.869691 | controller | INFO     Running crc_layout > prepare
2025-12-01 08:20:48.509400 | controller |
2025-12-01 08:20:48.509471 | controller | PLAY [Prepare] *****************************************************************
2025-12-01 08:20:48.509581 | controller |
2025-12-01 08:20:48.509682 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 08:20:48.509785 | controller | Monday 01 December 2025  08:20:48 +0000 (0:00:00.019)       0:00:00.019 *******
2025-12-01 08:20:49.323737 | controller | ok: [instance]
2025-12-01 08:20:49.323778 | controller |
2025-12-01 08:20:49.323985 | controller | TASK [Create custom basedir] ***************************************************
2025-12-01 08:20:49.324057 | controller | Monday 01 December 2025  08:20:49 +0000 (0:00:00.814)       0:00:00.833 *******
2025-12-01 08:20:49.619031 | controller | changed: [instance]
2025-12-01 08:20:49.619080 | controller |
2025-12-01 08:20:49.619207 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-01 08:20:49.619389 | controller | Monday 01 December 2025  08:20:49 +0000 (0:00:00.295)       0:00:01.129 *******
2025-12-01 08:20:49.636488 | controller | skipping: [instance]
2025-12-01 08:20:49.636579 | controller |
2025-12-01 08:20:49.636710 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-01 08:20:49.636880 | controller | Monday 01 December 2025  08:20:49 +0000 (0:00:00.017)       0:00:01.146 *******
2025-12-01 08:20:49.673818 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-01 08:20:49.673900 | controller |
2025-12-01 08:20:49.674013 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-01 08:20:49.674111 | controller | Monday 01 December 2025  08:20:49 +0000 (0:00:00.037)       0:00:01.184 *******
2025-12-01 08:20:49.946027 | controller | ok: [instance]
2025-12-01 08:20:49.946099 | controller |
2025-12-01 08:20:49.946213 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-01 08:20:49.946319 | controller | Monday 01 December 2025  08:20:49 +0000 (0:00:00.272)       0:00:01.456 *******
2025-12-01 08:20:50.141790 | controller | ok: [instance]
2025-12-01 08:20:50.141869 | controller |
2025-12-01 08:20:50.141975 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-01 08:20:50.142078 | controller | Monday 01 December 2025  08:20:50 +0000 (0:00:00.195)       0:00:01.651 *******
2025-12-01 08:20:50.797143 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-01 08:20:50.797232 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-01 08:20:50.797330 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-01 08:20:50.797454 | controller |
2025-12-01 08:20:50.797555 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-01 08:20:50.797640 | controller | Monday 01 December 2025  08:20:50 +0000 (0:00:00.655)       0:00:02.307 *******
2025-12-01 08:20:50.856468 | controller |
2025-12-01 08:20:50.856574 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-01 08:20:50.856772 | controller | Monday 01 December 2025  08:20:50 +0000 (0:00:00.059)       0:00:02.366 *******
2025-12-01 08:20:51.357290 | controller | changed: [instance] => (item=tmp)
2025-12-01 08:20:51.357392 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-01 08:20:51.357513 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-01 08:20:51.357613 | controller |
2025-12-01 08:20:51.357724 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-01 08:20:51.357821 | controller | Monday 01 December 2025  08:20:51 +0000 (0:00:00.500)       0:00:02.867 *******
2025-12-01 08:20:52.113075 | controller | ok: [instance]
2025-12-01 08:20:52.113405 | controller |
2025-12-01 08:20:53.170057 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-01 08:20:53.170085 | controller | Monday 01 December 2025  08:20:52 +0000 (0:00:00.755)       0:00:03.623 *******
2025-12-01 08:20:53.170095 | controller | changed: [instance]
2025-12-01 08:21:00.458464 | controller |
2025-12-01 08:21:00.458508 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-01 08:21:00.458518 | controller | Monday 01 December 2025  08:20:53 +0000 (0:00:01.056)       0:00:04.679 *******
2025-12-01 08:21:00.458530 | controller | changed: [instance]
2025-12-01 08:21:00.458560 | controller |
2025-12-01 08:21:00.458701 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-01 08:21:00.458734 | controller | Monday 01 December 2025  08:21:00 +0000 (0:00:07.288)       0:00:11.968 *******
2025-12-01 08:21:01.146917 | controller | changed: [instance]
2025-12-01 08:21:01.168075 | controller |
2025-12-01 08:21:01.168095 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-01 08:21:01.168104 | controller | Monday 01 December 2025  08:21:01 +0000 (0:00:00.688)       0:00:12.656 *******
2025-12-01 08:21:01.168114 | controller | skipping: [instance]
2025-12-01 08:21:01.956447 | controller |
2025-12-01 08:21:01.956482 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-01 08:21:01.956493 | controller | Monday 01 December 2025  08:21:01 +0000 (0:00:00.021)       0:00:12.678 *******
2025-12-01 08:21:01.956507 | controller | changed: [instance]
2025-12-01 08:21:01.992450 | controller |
2025-12-01 08:21:01.992484 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-01 08:21:01.992492 | controller | Monday 01 December 2025  08:21:01 +0000 (0:00:00.787)       0:00:13.466 *******
2025-12-01 08:21:01.992503 | controller | skipping: [instance]
2025-12-01 08:21:01.992530 | controller |
2025-12-01 08:21:01.992538 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-01 08:21:01.992619 | controller | Monday 01 December 2025  08:21:01 +0000 (0:00:00.036)       0:00:13.502 *******
2025-12-01 08:21:02.026773 | controller | skipping: [instance]
2025-12-01 08:21:02.061845 | controller |
2025-12-01 08:21:02.061866 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-01 08:21:02.061875 | controller | Monday 01 December 2025  08:21:02 +0000 (0:00:00.034)       0:00:13.536 *******
2025-12-01 08:21:02.061886 | controller | skipping: [instance]
2025-12-01 08:21:02.592940 | controller |
2025-12-01 08:21:02.592973 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-01 08:21:02.592981 | controller | Monday 01 December 2025  08:21:02 +0000 (0:00:00.035)       0:00:13.571 *******
2025-12-01 08:21:02.592991 | controller | changed: [instance]
2025-12-01 08:21:03.014438 | controller |
2025-12-01 08:21:03.014471 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-01 08:21:03.014481 | controller | Monday 01 December 2025  08:21:02 +0000 (0:00:00.530)       0:00:14.102 *******
2025-12-01 08:21:03.014492 | controller | changed: [instance]
2025-12-01 08:21:03.014521 | controller |
2025-12-01 08:21:03.014693 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-01 08:21:03.043869 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.421)       0:00:14.524 *******
2025-12-01 08:21:03.043894 | controller | skipping: [instance]
2025-12-01 08:21:03.072325 | controller |
2025-12-01 08:21:03.072362 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-01 08:21:03.072373 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.029)       0:00:14.553 *******
2025-12-01 08:21:03.072386 | controller | skipping: [instance]
2025-12-01 08:21:03.100389 | controller |
2025-12-01 08:21:03.100415 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-01 08:21:03.100423 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.028)       0:00:14.582 *******
2025-12-01 08:21:03.100436 | controller | skipping: [instance]
2025-12-01 08:21:03.100743 | controller |
2025-12-01 08:21:03.136426 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-01 08:21:03.136447 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.028)       0:00:14.610 *******
2025-12-01 08:21:03.136463 | controller | ok: [instance]
2025-12-01 08:21:03.136485 | controller |
2025-12-01 08:21:03.136696 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-01 08:21:03.162272 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.036)       0:00:14.646 *******
2025-12-01 08:21:03.162302 | controller | skipping: [instance]
2025-12-01 08:21:03.187742 | controller |
2025-12-01 08:21:03.187759 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-01 08:21:03.187766 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.025)       0:00:14.672 *******
2025-12-01 08:21:03.187774 | controller | skipping: [instance]
2025-12-01 08:21:03.212431 | controller |
2025-12-01 08:21:03.212453 | controller | TASK [Download the RPM] ********************************************************
2025-12-01 08:21:03.212463 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.025)       0:00:14.697 *******
2025-12-01 08:21:03.212474 | controller | skipping: [instance]
2025-12-01 08:21:03.212652 | controller |
2025-12-01 08:21:03.212800 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-01 08:21:03.212937 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.024)       0:00:14.722 *******
2025-12-01 08:21:03.237818 | controller | skipping: [instance]
2025-12-01 08:21:03.237987 | controller |
2025-12-01 08:21:03.238148 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-01 08:21:03.238319 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.025)       0:00:14.748 *******
2025-12-01 08:21:03.263038 | controller | skipping: [instance]
2025-12-01 08:21:03.263220 | controller |
2025-12-01 08:21:03.263411 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-01 08:21:03.263572 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.025)       0:00:14.773 *******
2025-12-01 08:21:03.290317 | controller | skipping: [instance]
2025-12-01 08:21:03.290518 | controller |
2025-12-01 08:21:03.290692 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-01 08:21:03.290843 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.027)       0:00:14.800 *******
2025-12-01 08:21:03.314585 | controller | skipping: [instance]
2025-12-01 08:21:03.314759 | controller |
2025-12-01 08:21:03.314905 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-01 08:21:03.315033 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.024)       0:00:14.824 *******
2025-12-01 08:21:03.486255 | controller | ok: [instance]
2025-12-01 08:21:03.486440 | controller |
2025-12-01 08:21:03.486581 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-01 08:21:03.486731 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.171)       0:00:14.996 *******
2025-12-01 08:21:03.680455 | controller | changed: [instance]
2025-12-01 08:21:03.680495 | controller |
2025-12-01 08:21:03.680503 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-01 08:21:03.680512 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.193)       0:00:15.190 *******
2025-12-01 08:21:03.895417 | controller | changed: [instance]
2025-12-01 08:21:03.917433 | controller |
2025-12-01 08:21:03.917456 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-01 08:21:03.917466 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.214)       0:00:15.405 *******
2025-12-01 08:21:03.917477 | controller | skipping: [instance]
2025-12-01 08:21:03.917507 | controller |
2025-12-01 08:21:03.917781 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-01 08:21:03.937969 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.022)       0:00:15.427 *******
2025-12-01 08:21:03.937998 | controller | skipping: [instance]
2025-12-01 08:21:03.958219 | controller |
2025-12-01 08:21:03.958236 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-01 08:21:03.958243 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.020)       0:00:15.447 *******
2025-12-01 08:21:03.958251 | controller | skipping: [instance]
2025-12-01 08:21:03.978555 | controller |
2025-12-01 08:21:03.978594 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-01 08:21:03.978606 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.020)       0:00:15.468 *******
2025-12-01 08:21:03.978619 | controller | skipping: [instance]
2025-12-01 08:21:04.002295 | controller |
2025-12-01 08:21:04.002315 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-01 08:21:04.002328 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.020)       0:00:15.488 *******
2025-12-01 08:21:04.002337 | controller | skipping: [instance]
2025-12-01 08:21:04.024301 | controller |
2025-12-01 08:21:04.024321 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-01 08:21:04.024330 | controller | Monday 01 December 2025  08:21:03 +0000 (0:00:00.023)       0:00:15.512 *******
2025-12-01 08:21:04.024369 | controller | skipping: [instance]
2025-12-01 08:21:04.024543 | controller |
2025-12-01 08:21:04.024706 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-01 08:21:04.024858 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.022)       0:00:15.534 *******
2025-12-01 08:21:04.039857 | controller | skipping: [instance]
2025-12-01 08:21:04.040029 | controller |
2025-12-01 08:21:04.040203 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-01 08:21:04.040366 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.015)       0:00:15.550 *******
2025-12-01 08:21:04.066771 | controller | skipping: [instance]
2025-12-01 08:21:04.066944 | controller |
2025-12-01 08:21:04.067106 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-01 08:21:04.067285 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.026)       0:00:15.577 *******
2025-12-01 08:21:04.082933 | controller | skipping: [instance]
2025-12-01 08:21:04.083105 | controller |
2025-12-01 08:21:04.083281 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-01 08:21:04.083442 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.016)       0:00:15.593 *******
2025-12-01 08:21:04.101677 | controller | skipping: [instance]
2025-12-01 08:21:04.101849 | controller |
2025-12-01 08:21:04.102011 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-01 08:21:04.102164 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.018)       0:00:15.612 *******
2025-12-01 08:21:04.128513 | controller | skipping: [instance]
2025-12-01 08:21:04.128702 | controller |
2025-12-01 08:21:04.128877 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-01 08:21:04.129045 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.026)       0:00:15.638 *******
2025-12-01 08:21:04.155685 | controller | skipping: [instance]
2025-12-01 08:21:04.155874 | controller |
2025-12-01 08:21:04.156047 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-01 08:21:04.156226 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.027)       0:00:15.665 *******
2025-12-01 08:21:04.182059 | controller | skipping: [instance]
2025-12-01 08:21:04.182248 | controller |
2025-12-01 08:21:04.182440 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-01 08:21:04.182602 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.026)       0:00:15.692 *******
2025-12-01 08:21:04.208062 | controller | skipping: [instance]
2025-12-01 08:21:04.208250 | controller |
2025-12-01 08:21:04.208442 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-01 08:21:04.208602 | controller | Monday 01 December 2025  08:21:04 +0000 (0:00:00.025)       0:00:15.718 *******
2025-12-01 08:21:46.334880 | controller | ok: [instance]
2025-12-01 08:21:47.453836 | controller |
2025-12-01 08:21:47.453863 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-01 08:21:47.453871 | controller | Monday 01 December 2025  08:21:46 +0000 (0:00:42.126)       0:00:57.844 *******
2025-12-01 08:21:47.453880 | controller | ok: [instance]
2025-12-01 08:21:47.483502 | controller |
2025-12-01 08:21:47.483537 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-01 08:21:47.483547 | controller | Monday 01 December 2025  08:21:47 +0000 (0:00:01.119)       0:00:58.963 *******
2025-12-01 08:21:47.483559 | controller | skipping: [instance]
2025-12-01 08:21:47.483590 | controller |
2025-12-01 08:21:47.483832 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-12-01 08:21:47.524457 | controller | Monday 01 December 2025  08:21:47 +0000 (0:00:00.029)       0:00:58.993 *******
2025-12-01 08:21:47.524481 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-12-01 08:21:47.544477 | controller |
2025-12-01 08:21:47.544502 | controller | TASK [ci_setup : List packages to install] *************************************
2025-12-01 08:21:47.544517 | controller | Monday 01 December 2025  08:21:47 +0000 (0:00:00.040)       0:00:59.034 *******
2025-12-01 08:21:47.544529 | controller | ok: [instance] =>
2025-12-01 08:21:47.544556 | controller |   cifmw_ci_setup_packages:
2025-12-01 08:21:47.544564 | controller |   - bash-completion
2025-12-01 08:21:47.544570 | controller |   - ca-certificates
2025-12-01 08:21:47.544576 | controller |   - git-core
2025-12-01 08:21:47.544582 | controller |   - make
2025-12-01 08:21:47.544589 | controller |   - tar
2025-12-01 08:21:47.544595 | controller |   - tmux
2025-12-01 08:21:47.544601 | controller |   - python3-pip
2025-12-01 08:21:47.544607 | controller |
2025-12-01 08:21:47.544615 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-12-01 08:21:47.544623 | controller | Monday 01 December 2025  08:21:47 +0000 (0:00:00.019)       0:00:59.053 *******
2025-12-01 08:21:55.590679 | controller | changed: [instance]
2025-12-01 08:21:55.590769 | controller |
2025-12-01 08:21:55.590985 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-12-01 08:21:56.157842 | controller | Monday 01 December 2025  08:21:55 +0000 (0:00:08.046)       0:01:07.100 *******
2025-12-01 08:21:56.157886 | controller | ok: [instance]
2025-12-01 08:21:56.356597 | controller |
2025-12-01 08:21:56.356630 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-12-01 08:21:56.356639 | controller | Monday 01 December 2025  08:21:56 +0000 (0:00:00.566)       0:01:07.667 *******
2025-12-01 08:21:56.356649 | controller | changed: [instance]
2025-12-01 08:21:56.356673 | controller |
2025-12-01 08:21:56.356681 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-12-01 08:21:56.356810 | controller | Monday 01 December 2025  08:21:56 +0000 (0:00:00.198)       0:01:07.866 *******
2025-12-01 08:22:02.133223 | controller | changed: [instance]
2025-12-01 08:22:02.167422 | controller |
2025-12-01 08:22:02.167456 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-12-01 08:22:02.167464 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:05.776)       0:01:13.643 *******
2025-12-01 08:22:02.167474 | controller | ok: [instance]
2025-12-01 08:22:02.167500 | controller |
2025-12-01 08:22:02.167709 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-12-01 08:22:02.413170 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.034)       0:01:13.677 *******
2025-12-01 08:22:02.413206 | controller | changed: [instance]
2025-12-01 08:22:02.667830 | controller |
2025-12-01 08:22:02.667854 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-12-01 08:22:02.667861 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.245)       0:01:13.922 *******
2025-12-01 08:22:02.667870 | controller | changed: [instance]
2025-12-01 08:22:02.684737 | controller |
2025-12-01 08:22:02.684756 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-12-01 08:22:02.684763 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.254)       0:01:14.177 *******
2025-12-01 08:22:02.684773 | controller | skipping: [instance]
2025-12-01 08:22:02.700537 | controller |
2025-12-01 08:22:02.700584 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-12-01 08:22:02.700598 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.016)       0:01:14.194 *******
2025-12-01 08:22:02.700616 | controller | skipping: [instance]
2025-12-01 08:22:02.717129 | controller |
2025-12-01 08:22:02.717153 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-12-01 08:22:02.717162 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.015)       0:01:14.210 *******
2025-12-01 08:22:02.717173 | controller | skipping: [instance]
2025-12-01 08:22:02.732473 | controller |
2025-12-01 08:22:02.732515 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-12-01 08:22:02.732526 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.016)       0:01:14.227 *******
2025-12-01 08:22:02.732540 | controller | skipping: [instance]
2025-12-01 08:22:02.732574 | controller |
2025-12-01 08:22:02.732586 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-12-01 08:22:02.748503 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.015)       0:01:14.242 *******
2025-12-01 08:22:02.748550 | controller | skipping: [instance]
2025-12-01 08:22:02.748582 | controller |
2025-12-01 08:22:02.748600 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-12-01 08:22:02.748620 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.015)       0:01:14.258 *******
2025-12-01 08:22:02.768437 | controller | skipping: [instance]
2025-12-01 08:22:02.768660 | controller |
2025-12-01 08:22:02.768682 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-12-01 08:22:03.702253 | controller | Monday 01 December 2025  08:22:02 +0000 (0:00:00.020)       0:01:14.278 *******
2025-12-01 08:22:03.702298 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2025-12-01 08:22:04.012448 | controller | changed: [instance] => (item=/opt/basedir/logs)
2025-12-01 08:22:04.012493 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2025-12-01 08:22:04.012502 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2025-12-01 08:22:04.012508 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2025-12-01 08:22:04.012513 | controller |
2025-12-01 08:22:04.012519 | controller | TASK [Create secret file] ******************************************************
2025-12-01 08:22:04.012525 | controller | Monday 01 December 2025  08:22:03 +0000 (0:00:00.933)       0:01:15.211 *******
2025-12-01 08:22:04.012543 | controller | changed: [instance]
2025-12-01 08:22:04.012578 | controller |
2025-12-01 08:22:04.012585 | controller | PLAY RECAP *********************************************************************
2025-12-01 08:22:04.012591 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-12-01 08:22:04.012596 | controller |
2025-12-01 08:22:04.012602 | controller | Monday 01 December 2025  08:22:04 +0000 (0:00:00.309)       0:01:15.521 *******
2025-12-01 08:22:04.012607 | controller | ===============================================================================
2025-12-01 08:22:04.012612 | controller | test_deps : Install selinux python libs -------------------------------- 42.13s
2025-12-01 08:22:04.012617 | controller | ci_setup : Install needed packages -------------------------------------- 8.05s
2025-12-01 08:22:04.012624 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.29s
2025-12-01 08:22:04.012645 | controller | ci_setup : Install openshift client ------------------------------------- 5.78s
2025-12-01 08:22:04.012652 | controller | test_deps : Install python yaml libs ------------------------------------ 1.12s
2025-12-01 08:22:04.013978 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.06s
2025-12-01 08:22:04.058782 | controller | ci_setup : Manage directories ------------------------------------------- 0.93s
2025-12-01 08:22:04.058802 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-12-01 08:22:04.058810 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.79s
2025-12-01 08:22:04.058816 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.76s
2025-12-01 08:22:04.058821 | controller | repo_setup : Install repo-setup package --------------------------------- 0.69s
2025-12-01 08:22:04.058826 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.66s
2025-12-01 08:22:04.058831 | controller | ci_setup : Gather version of openshift client --------------------------- 0.57s
2025-12-01 08:22:04.058837 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.53s
2025-12-01 08:22:04.058842 | controller | repo_setup : Ensure directories are present ----------------------------- 0.50s
2025-12-01 08:22:04.058847 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2025-12-01 08:22:04.058852 | controller | Create secret file ------------------------------------------------------ 0.31s
2025-12-01 08:22:04.058857 | controller | Create custom basedir --------------------------------------------------- 0.30s
2025-12-01 08:22:04.058863 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s
2025-12-01 08:22:04.058873 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.25s
2025-12-01 08:22:04.058883 | controller | INFO     Running crc_layout > converge
2025-12-01 08:22:04.623241 | controller |
2025-12-01 08:22:04.623511 | controller | PLAY [Converge] ****************************************************************
2025-12-01 08:22:04.623656 | controller |
2025-12-01 08:22:04.623796 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 08:22:04.623941 | controller | Monday 01 December 2025  08:22:04 +0000 (0:00:00.027)       0:00:00.027 *******
2025-12-01 08:22:05.421251 | controller | ok: [instance]
2025-12-01 08:22:05.442604 | controller |
2025-12-01 08:22:05.442660 | controller | TASK [Load networking definition from scenario file] ***************************
2025-12-01 08:22:05.442671 | controller | Monday 01 December 2025  08:22:05 +0000 (0:00:00.797)       0:00:00.825 *******
2025-12-01 08:22:05.442686 | controller | ok: [instance]
2025-12-01 08:22:05.442712 | controller |
2025-12-01 08:22:05.442796 | controller | TASK [discover_latest_image : Get latest image] ********************************
2025-12-01 08:22:05.442827 | controller | Monday 01 December 2025  08:22:05 +0000 (0:00:00.022)       0:00:00.847 *******
2025-12-01 08:22:07.183057 | controller | changed: [instance]
2025-12-01 08:22:07.208884 | controller |
2025-12-01 08:22:07.208916 | controller | TASK [discover_latest_image : Export facts accordingly] ************************
2025-12-01 08:22:07.208925 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:01.739)       0:00:02.587 *******
2025-12-01 08:22:07.208946 | controller | ok: [instance]
2025-12-01 08:22:07.228985 | controller |
2025-12-01 08:22:07.229014 | controller | TASK [reproducer : Load CI job environment] ************************************
2025-12-01 08:22:07.229023 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.026)       0:00:02.613 *******
2025-12-01 08:22:07.229033 | controller | skipping: [instance]
2025-12-01 08:22:07.276420 | controller |
2025-12-01 08:22:07.276508 | controller | TASK [Build final libvirt layout] **********************************************
2025-12-01 08:22:07.276518 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.020)       0:00:02.633 *******
2025-12-01 08:22:07.276530 | controller |
2025-12-01 08:22:07.352690 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] ***********
2025-12-01 08:22:07.352769 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.046)       0:00:02.680 *******
2025-12-01 08:22:07.352793 | controller | ok: [instance]
2025-12-01 08:22:07.352950 | controller |
2025-12-01 08:22:07.353781 | controller | TASK [libvirt_manager : Patch the layout if needed] ****************************
2025-12-01 08:22:07.353949 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.076)       0:00:02.757 *******
2025-12-01 08:22:07.408564 | controller | skipping: [instance]
2025-12-01 08:22:07.408905 | controller |
2025-12-01 08:22:07.409096 | controller | TASK [reproducer : Assert no conflicting parameters were passed] ***************
2025-12-01 08:22:07.409257 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.055)       0:00:02.812 *******
2025-12-01 08:22:07.432560 | controller | ok: [instance]
2025-12-01 08:22:07.432745 | controller |
2025-12-01 08:22:07.432933 | controller | TASK [reproducer : Assert that deprecated cifmw_reproducer_internal_ca parameters was not passed] ***
2025-12-01 08:22:07.433092 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.024)       0:00:02.837 *******
2025-12-01 08:22:07.455153 | controller | ok: [instance] => changed=false
2025-12-01 08:22:07.455394 | controller |   msg: All assertions passed
2025-12-01 08:22:07.455529 | controller |
2025-12-01 08:22:07.455659 | controller | TASK [reproducer : Set _use_crc based on actual layout] ************************
2025-12-01 08:22:07.455784 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.022)       0:00:02.860 *******
2025-12-01 08:22:07.494257 | controller | ok: [instance]
2025-12-01 08:22:07.494574 | controller |
2025-12-01 08:22:07.494820 | controller | TASK [reproducer : Ensure directories are present] *****************************
2025-12-01 08:22:07.495010 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.039)       0:00:02.899 *******
2025-12-01 08:22:07.941737 | controller | ok: [instance] => (item=artifacts)
2025-12-01 08:22:08.015929 | controller | ok: [instance] => (item=logs)
2025-12-01 08:22:08.015963 | controller |
2025-12-01 08:22:08.015977 | controller | TASK [Bootstrap libvirt if needed] *********************************************
2025-12-01 08:22:08.016003 | controller | Monday 01 December 2025  08:22:07 +0000 (0:00:00.446)       0:00:03.345 *******
2025-12-01 08:22:08.016019 | controller |
2025-12-01 08:22:08.200961 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-12-01 08:22:08.200986 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.074)       0:00:03.420 *******
2025-12-01 08:22:08.200999 | controller | ok: [instance]
2025-12-01 08:22:08.474094 | controller |
2025-12-01 08:22:08.474126 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-12-01 08:22:08.474137 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.185)       0:00:03.605 *******
2025-12-01 08:22:08.474148 | controller | changed: [instance]
2025-12-01 08:22:08.752256 | controller |
2025-12-01 08:22:08.752285 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-12-01 08:22:08.752293 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.273)       0:00:03.878 *******
2025-12-01 08:22:08.752314 | controller | ok: [instance]
2025-12-01 08:22:08.781545 | controller |
2025-12-01 08:22:08.781605 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-12-01 08:22:08.781617 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.278)       0:00:04.157 *******
2025-12-01 08:22:08.781632 | controller | skipping: [instance]
2025-12-01 08:22:08.781668 | controller |
2025-12-01 08:22:08.781678 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-12-01 08:22:08.781871 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.029)       0:00:04.186 *******
2025-12-01 08:22:08.798520 | controller | skipping: [instance]
2025-12-01 08:22:08.798586 | controller |
2025-12-01 08:22:08.798789 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-12-01 08:22:08.819181 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.017)       0:00:04.203 *******
2025-12-01 08:22:08.819208 | controller | ok: [instance]
2025-12-01 08:22:08.844760 | controller |
2025-12-01 08:22:08.844781 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-12-01 08:22:08.844798 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.020)       0:00:04.224 *******
2025-12-01 08:22:08.844809 | controller | skipping: [instance]
2025-12-01 08:22:17.809623 | controller |
2025-12-01 08:22:17.809655 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-12-01 08:22:17.809663 | controller | Monday 01 December 2025  08:22:08 +0000 (0:00:00.025)       0:00:04.249 *******
2025-12-01 08:22:17.809673 | controller | changed: [instance]
2025-12-01 08:22:18.013422 | controller |
2025-12-01 08:22:18.013453 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-12-01 08:22:18.013461 | controller | Monday 01 December 2025  08:22:17 +0000 (0:00:08.964)       0:00:13.213 *******
2025-12-01 08:22:18.013472 | controller | changed: [instance]
2025-12-01 08:22:18.013497 | controller |
2025-12-01 08:22:18.013505 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-12-01 08:22:18.013628 | controller | Monday 01 December 2025  08:22:18 +0000 (0:00:00.204)       0:00:13.418 *******
2025-12-01 08:22:18.496798 | controller | changed: [instance]
2025-12-01 08:22:19.046454 | controller |
2025-12-01 08:22:19.046494 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-12-01 08:22:19.046504 | controller | Monday 01 December 2025  08:22:18 +0000 (0:00:00.483)       0:00:13.901 *******
2025-12-01 08:22:19.046518 | controller | changed: [instance]
2025-12-01 08:22:19.046553 | controller |
2025-12-01 08:22:19.046733 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-12-01 08:22:19.229753 | controller | Monday 01 December 2025  08:22:19 +0000 (0:00:00.549)       0:00:14.451 *******
2025-12-01 08:22:19.229788 | controller | ok: [instance]
2025-12-01 08:22:19.836430 | controller |
2025-12-01 08:22:19.836462 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-12-01 08:22:19.836470 | controller | Monday 01 December 2025  08:22:19 +0000 (0:00:00.183)       0:00:14.634 *******
2025-12-01 08:22:19.836480 | controller | ok: [instance]
2025-12-01 08:22:20.670194 | controller |
2025-12-01 08:22:20.670229 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-12-01 08:22:20.670243 | controller | Monday 01 December 2025  08:22:19 +0000 (0:00:00.606)       0:00:15.241 *******
2025-12-01 08:22:20.670253 | controller | changed: [instance]
2025-12-01 08:22:20.748508 | controller |
2025-12-01 08:22:20.748547 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-12-01 08:22:20.748556 | controller | Monday 01 December 2025  08:22:20 +0000 (0:00:00.833)       0:00:16.074 *******
2025-12-01 08:22:20.748569 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-12-01 08:22:20.748601 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-12-01 08:22:20.748608 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-12-01 08:22:20.748614 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-12-01 08:22:20.748622 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-12-01 08:22:20.749154 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-12-01 08:22:21.067898 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-12-01 08:22:21.067921 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-12-01 08:22:21.067929 | controller |
2025-12-01 08:22:21.067936 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-12-01 08:22:21.067942 | controller | Monday 01 December 2025  08:22:20 +0000 (0:00:00.078)       0:00:16.152 *******
2025-12-01 08:22:21.067951 | controller | ok: [instance]
2025-12-01 08:22:21.368793 | controller |
2025-12-01 08:22:21.368814 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-12-01 08:22:21.368821 | controller | Monday 01 December 2025  08:22:21 +0000 (0:00:00.319)       0:00:16.472 *******
2025-12-01 08:22:21.368829 | controller | ok: [instance]
2025-12-01 08:22:21.662096 | controller |
2025-12-01 08:22:21.662119 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-12-01 08:22:21.662129 | controller | Monday 01 December 2025  08:22:21 +0000 (0:00:00.300)       0:00:16.773 *******
2025-12-01 08:22:21.662140 | controller | ok: [instance]
2025-12-01 08:22:21.966273 | controller |
2025-12-01 08:22:21.966296 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-12-01 08:22:21.966303 | controller | Monday 01 December 2025  08:22:21 +0000 (0:00:00.293)       0:00:17.066 *******
2025-12-01 08:22:21.966325 | controller | ok: [instance]
2025-12-01 08:22:22.275421 | controller |
2025-12-01 08:22:22.275445 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-12-01 08:22:22.275453 | controller | Monday 01 December 2025  08:22:21 +0000 (0:00:00.304)       0:00:17.371 *******
2025-12-01 08:22:22.275462 | controller | ok: [instance]
2025-12-01 08:22:22.575334 | controller |
2025-12-01 08:22:22.575367 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-12-01 08:22:22.575374 | controller | Monday 01 December 2025  08:22:22 +0000 (0:00:00.309)       0:00:17.680 *******
2025-12-01 08:22:22.575383 | controller | ok: [instance]
2025-12-01 08:22:22.876400 | controller |
2025-12-01 08:22:22.876423 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-12-01 08:22:22.876431 | controller | Monday 01 December 2025  08:22:22 +0000 (0:00:00.299)       0:00:17.979 *******
2025-12-01 08:22:22.876446 | controller | ok: [instance]
2025-12-01 08:22:22.876776 | controller |
2025-12-01 08:22:23.188196 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-12-01 08:22:23.188221 | controller | Monday 01 December 2025  08:22:22 +0000 (0:00:00.301)       0:00:18.281 *******
2025-12-01 08:22:23.188238 | controller | ok: [instance]
2025-12-01 08:22:24.496796 | controller |
2025-12-01 08:22:24.496823 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-12-01 08:22:24.496830 | controller | Monday 01 December 2025  08:22:23 +0000 (0:00:00.311)       0:00:18.593 *******
2025-12-01 08:22:24.496839 | controller | ok: [instance]
2025-12-01 08:22:24.787998 | controller |
2025-12-01 08:22:24.788026 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-12-01 08:22:24.788034 | controller | Monday 01 December 2025  08:22:24 +0000 (0:00:01.308)       0:00:19.901 *******
2025-12-01 08:22:24.788044 | controller | ok: [instance]
2025-12-01 08:22:25.082501 | controller |
2025-12-01 08:22:25.082541 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-12-01 08:22:25.082550 | controller | Monday 01 December 2025  08:22:24 +0000 (0:00:00.291)       0:00:20.192 *******
2025-12-01 08:22:25.082563 | controller | ok: [instance]
2025-12-01 08:22:25.082590 | controller |
2025-12-01 08:22:25.082599 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-12-01 08:22:25.082698 | controller | Monday 01 December 2025  08:22:25 +0000 (0:00:00.294)       0:00:20.487 *******
2025-12-01 08:22:25.384192 | controller | ok: [instance]
2025-12-01 08:22:25.682964 | controller |
2025-12-01 08:22:25.682997 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-12-01 08:22:25.683005 | controller | Monday 01 December 2025  08:22:25 +0000 (0:00:00.301)       0:00:20.789 *******
2025-12-01 08:22:25.683014 | controller | ok: [instance]
2025-12-01 08:22:25.982475 | controller |
2025-12-01 08:22:25.982510 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-12-01 08:22:25.982520 | controller | Monday 01 December 2025  08:22:25 +0000 (0:00:00.298)       0:00:21.087 *******
2025-12-01 08:22:25.982531 | controller | ok: [instance]
2025-12-01 08:22:26.283087 | controller |
2025-12-01 08:22:26.283117 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-12-01 08:22:26.283125 | controller | Monday 01 December 2025  08:22:25 +0000 (0:00:00.299)       0:00:21.387 *******
2025-12-01 08:22:26.283135 | controller | ok: [instance]
2025-12-01 08:22:26.607276 | controller |
2025-12-01 08:22:26.607310 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-12-01 08:22:26.607331 | controller | Monday 01 December 2025  08:22:26 +0000 (0:00:00.300)       0:00:21.688 *******
2025-12-01 08:22:26.607372 | controller | ok: [instance]
2025-12-01 08:22:26.909442 | controller |
2025-12-01 08:22:26.909465 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-12-01 08:22:26.909472 | controller | Monday 01 December 2025  08:22:26 +0000 (0:00:00.324)       0:00:22.012 *******
2025-12-01 08:22:26.909481 | controller | ok: [instance]
2025-12-01 08:22:27.206446 | controller |
2025-12-01 08:22:27.206478 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-12-01 08:22:27.206488 | controller | Monday 01 December 2025  08:22:26 +0000 (0:00:00.302)       0:00:22.314 *******
2025-12-01 08:22:27.206499 | controller | ok: [instance]
2025-12-01 08:22:27.206527 | controller |
2025-12-01 08:22:27.206537 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-12-01 08:22:27.206577 | controller | Monday 01 December 2025  08:22:27 +0000 (0:00:00.297)       0:00:22.611 *******
2025-12-01 08:22:27.509905 | controller | ok: [instance]
2025-12-01 08:22:27.806092 | controller |
2025-12-01 08:22:27.806118 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-12-01 08:22:27.806126 | controller | Monday 01 December 2025  08:22:27 +0000 (0:00:00.303)       0:00:22.914 *******
2025-12-01 08:22:27.806134 | controller | ok: [instance]
2025-12-01 08:22:28.114458 | controller |
2025-12-01 08:22:28.114493 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-12-01 08:22:28.114503 | controller | Monday 01 December 2025  08:22:27 +0000 (0:00:00.296)       0:00:23.211 *******
2025-12-01 08:22:28.114516 | controller | ok: [instance]
2025-12-01 08:22:28.114548 | controller |
2025-12-01 08:22:28.114757 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-12-01 08:22:28.423757 | controller | Monday 01 December 2025  08:22:28 +0000 (0:00:00.308)       0:00:23.519 *******
2025-12-01 08:22:28.423798 | controller | ok: [instance]
2025-12-01 08:22:28.734953 | controller |
2025-12-01 08:22:28.734984 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-12-01 08:22:28.734997 | controller | Monday 01 December 2025  08:22:28 +0000 (0:00:00.308)       0:00:23.828 *******
2025-12-01 08:22:28.735008 | controller | ok: [instance]
2025-12-01 08:22:29.032414 | controller |
2025-12-01 08:22:29.032444 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-12-01 08:22:29.032452 | controller | Monday 01 December 2025  08:22:28 +0000 (0:00:00.311)       0:00:24.139 *******
2025-12-01 08:22:29.032461 | controller | ok: [instance]
2025-12-01 08:22:29.032485 | controller |
2025-12-01 08:22:29.032804 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-12-01 08:22:29.050847 | controller | Monday 01 December 2025  08:22:29 +0000 (0:00:00.297)       0:00:24.437 *******
2025-12-01 08:22:29.050867 | controller | skipping: [instance]
2025-12-01 08:22:29.321571 | controller |
2025-12-01 08:22:29.321610 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-12-01 08:22:29.321620 | controller | Monday 01 December 2025  08:22:29 +0000 (0:00:00.018)       0:00:24.455 *******
2025-12-01 08:22:29.321633 | controller | ok: [instance]
2025-12-01 08:22:29.681044 | controller |
2025-12-01 08:22:29.681075 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-12-01 08:22:29.681084 | controller | Monday 01 December 2025  08:22:29 +0000 (0:00:00.270)       0:00:24.726 *******
2025-12-01 08:22:29.681096 | controller | ok: [instance]
2025-12-01 08:22:29.986461 | controller |
2025-12-01 08:22:29.986496 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-12-01 08:22:29.986504 | controller | Monday 01 December 2025  08:22:29 +0000 (0:00:00.359)       0:00:25.085 *******
2025-12-01 08:22:29.986515 | controller | changed: [instance]
2025-12-01 08:22:30.252114 | controller |
2025-12-01 08:22:30.252138 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-12-01 08:22:30.252145 | controller | Monday 01 December 2025  08:22:29 +0000 (0:00:00.305)       0:00:25.391 *******
2025-12-01 08:22:30.252154 | controller | [WARNING]: Reset is not implemented for this connection
2025-12-01 08:22:30.261504 | controller | changed: [instance]
2025-12-01 08:22:30.261562 | controller |
2025-12-01 08:22:30.261574 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-12-01 08:22:30.261971 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.260)       0:00:25.651 *******
2025-12-01 08:22:30.333403 | controller |
2025-12-01 08:22:30.333424 | controller | TASK [reproducer : Deploy networks in libvirt] *********************************
2025-12-01 08:22:30.333432 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.014)       0:00:25.666 *******
2025-12-01 08:22:30.333442 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/prepare_networking.yml for instance
2025-12-01 08:22:30.387054 | controller |
2025-12-01 08:22:30.387071 | controller | TASK [reproducer : Ensure networks are consistent] *****************************
2025-12-01 08:22:30.387078 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.071)       0:00:25.738 *******
2025-12-01 08:22:30.387090 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/network_assertions.yml for instance
2025-12-01 08:22:30.434175 | controller |
2025-12-01 08:22:30.434204 | controller | TASK [reproducer : Ensure all VM types have defined nets parameter] ************
2025-12-01 08:22:30.434212 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.053)       0:00:25.791 *******
2025-12-01 08:22:30.434221 | controller | ok: [instance] => (item=compute)
2025-12-01 08:22:30.475156 | controller | ok: [instance] => (item=controller)
2025-12-01 08:22:30.475173 | controller | ok: [instance] => (item=crc)
2025-12-01 08:22:30.475180 | controller |
2025-12-01 08:22:30.475185 | controller | TASK [reproducer : Ensure all standard attached networks are defined] **********
2025-12-01 08:22:30.475191 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.047)       0:00:25.838 *******
2025-12-01 08:22:30.475199 | controller | ok: [instance]
2025-12-01 08:22:30.511678 | controller |
2025-12-01 08:22:30.511712 | controller | TASK [reproducer : Ensure we don't have extra network defined] *****************
2025-12-01 08:22:30.511735 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.040)       0:00:25.879 *******
2025-12-01 08:22:30.511751 | controller | ok: [instance] => changed=false
2025-12-01 08:22:30.512022 | controller |   msg: All assertions passed
2025-12-01 08:22:30.512239 | controller |
2025-12-01 08:22:30.512408 | controller | TASK [Ensure we get latest OCP config] *****************************************
2025-12-01 08:22:30.512541 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.037)       0:00:25.916 *******
2025-12-01 08:22:30.553707 | controller |
2025-12-01 08:22:30.553999 | controller | TASK [devscripts : Gather the configurations to be passed to dev-scripts.] *****
2025-12-01 08:22:30.554224 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.042)       0:00:25.958 *******
2025-12-01 08:22:30.620040 | controller | ok: [instance] => (item={'working_dir': '/home/dev-scripts', 'assets_extra_folder': '/home/dev-scripts/assets', 'openshift_release_type': 'ga', 'openshift_version': '4.16.0', 'cluster_name': 'ocp', 'base_domain': 'openstack.lab', 'ntp_servers': 'clock.corp.redhat.com', 'provisioning_network_profile': 'Managed', 'provisioning_network': '172.22.0.0/24', 'cluster_subnet_v4': '192.168.16.0/20', 'cluster_host_prefix_v4': '23', 'service_subnet_v4': '172.30.0.0/16', 'external_subnet_v4': '192.168.111.0/24', 'num_masters': 3, 'num_workers': 0, 'ip_stack': 'v4', 'network_type': 'OVNKubernetes'})
2025-12-01 08:22:30.620188 | controller | ok: [instance] => (item={})
2025-12-01 08:22:30.620311 | controller |
2025-12-01 08:22:30.620472 | controller | TASK [devscripts : Replace OCP version if "stable-" alias used] ****************
2025-12-01 08:22:30.620602 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.066)       0:00:26.025 *******
2025-12-01 08:22:30.635458 | controller | skipping: [instance]
2025-12-01 08:22:30.635631 | controller |
2025-12-01 08:22:30.635791 | controller | TASK [devscripts : Ensure output directory exists] *****************************
2025-12-01 08:22:30.635950 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.015)       0:00:26.040 *******
2025-12-01 08:22:30.837011 | controller | ok: [instance]
2025-12-01 08:22:31.157419 | controller |
2025-12-01 08:22:31.157449 | controller | TASK [devscripts : Output built config for easier debugging] *******************
2025-12-01 08:22:31.157458 | controller | Monday 01 December 2025  08:22:30 +0000 (0:00:00.200)       0:00:26.241 *******
2025-12-01 08:22:31.157468 | controller | changed: [instance]
2025-12-01 08:22:31.157665 | controller |
2025-12-01 08:22:31.157679 | controller | TASK [ci_network : Remove the local dns configuration] *************************
2025-12-01 08:22:31.859445 | controller | Monday 01 December 2025  08:22:31 +0000 (0:00:00.320)       0:00:26.562 *******
2025-12-01 08:22:31.859496 | controller | ok: [instance] => (item=/etc/NetworkManager/conf.d/00-use-dnsmasq.conf)
2025-12-01 08:22:31.859529 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/98-cifmw-static-leases.conf)
2025-12-01 08:22:31.859537 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/99-cifmw-dns-forwarders.conf)
2025-12-01 08:22:31.859544 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/97-cifmw-local-domain.conf)
2025-12-01 08:22:31.859550 | controller |
2025-12-01 08:22:31.859558 | controller | TASK [ci_network : Reload the NetworkManager to pick the changes] **************
2025-12-01 08:22:31.859622 | controller | Monday 01 December 2025  08:22:31 +0000 (0:00:00.701)       0:00:27.264 *******
2025-12-01 08:22:31.885188 | controller | skipping: [instance]
2025-12-01 08:22:32.236335 | controller |
2025-12-01 08:22:32.236369 | controller | TASK [reproducer : Ensure NetworkManager is configured to use dnsmasq] *********
2025-12-01 08:22:32.236377 | controller | Monday 01 December 2025  08:22:31 +0000 (0:00:00.025)       0:00:27.290 *******
2025-12-01 08:22:32.236386 | controller | changed: [instance]
2025-12-01 08:22:32.647012 | controller |
2025-12-01 08:22:32.647049 | controller | TASK [reproducer : Flush handlers for NetworkManager restart] ******************
2025-12-01 08:22:32.647058 | controller | Monday 01 December 2025  08:22:32 +0000 (0:00:00.342)       0:00:27.633 *******
2025-12-01 08:22:32.647064 | controller |
2025-12-01 08:22:32.647070 | controller | RUNNING HANDLER [reproducer : Restart NetworkManager] **************************
2025-12-01 08:22:32.647075 | controller | Monday 01 December 2025  08:22:32 +0000 (0:00:00.007)       0:00:27.641 *******
2025-12-01 08:22:32.647093 | controller | changed: [instance]
2025-12-01 08:22:32.647119 | controller |
2025-12-01 08:22:32.647308 | controller | TASK [Ensure no default networks exists] ***************************************
2025-12-01 08:22:32.721843 | controller | Monday 01 December 2025  08:22:32 +0000 (0:00:00.410)       0:00:28.051 *******
2025-12-01 08:22:32.721875 | controller |
2025-12-01 08:22:33.879451 | controller | TASK [libvirt_manager : Deactivate crc] ****************************************
2025-12-01 08:22:33.879484 | controller | Monday 01 December 2025  08:22:32 +0000 (0:00:00.074)       0:00:28.126 *******
2025-12-01 08:22:33.879499 | controller | ok: [instance]
2025-12-01 08:22:33.879528 | controller |
2025-12-01 08:22:33.879538 | controller | TASK [libvirt_manager : Removing crc] ******************************************
2025-12-01 08:22:33.879574 | controller | Monday 01 December 2025  08:22:33 +0000 (0:00:01.157)       0:00:29.284 *******
2025-12-01 08:22:34.093451 | controller | ok: [instance]
2025-12-01 08:22:34.093499 | controller |
2025-12-01 08:22:34.093750 | controller | TASK [libvirt_manager : Deactivate default] ************************************
2025-12-01 08:22:34.570110 | controller | Monday 01 December 2025  08:22:34 +0000 (0:00:00.214)       0:00:29.498 *******
2025-12-01 08:22:34.570146 | controller | ok: [instance]
2025-12-01 08:22:34.785989 | controller |
2025-12-01 08:22:34.786022 | controller | TASK [libvirt_manager : Removing default] **************************************
2025-12-01 08:22:34.786030 | controller | Monday 01 December 2025  08:22:34 +0000 (0:00:00.476)       0:00:29.974 *******
2025-12-01 08:22:34.786040 | controller | ok: [instance]
2025-12-01 08:22:35.927471 | controller |
2025-12-01 08:22:35.927511 | controller | TASK [reproducer : Ensure firewalld is installed] ******************************
2025-12-01 08:22:35.927520 | controller | Monday 01 December 2025  08:22:34 +0000 (0:00:00.215)       0:00:30.190 *******
2025-12-01 08:22:35.927533 | controller | ok: [instance]
2025-12-01 08:22:35.927562 | controller |
2025-12-01 08:22:35.927572 | controller | TASK [reproducer : Ensure firewalld service is enabled and started] ************
2025-12-01 08:22:35.927702 | controller | Monday 01 December 2025  08:22:35 +0000 (0:00:01.141)       0:00:31.332 *******
2025-12-01 08:22:36.219990 | controller | ok: [instance]
2025-12-01 08:22:36.505424 | controller |
2025-12-01 08:22:36.505456 | controller | TASK [reproducer : Ensure br_netfilter module is loaded] ***********************
2025-12-01 08:22:36.505464 | controller | Monday 01 December 2025  08:22:36 +0000 (0:00:00.292)       0:00:31.624 *******
2025-12-01 08:22:36.505474 | controller | changed: [instance]
2025-12-01 08:22:36.505498 | controller |
2025-12-01 08:22:36.505694 | controller | TASK [reproducer : Ensure IP forwarding is enabled] ****************************
2025-12-01 08:22:36.789389 | controller | Monday 01 December 2025  08:22:36 +0000 (0:00:00.285)       0:00:31.910 *******
2025-12-01 08:22:36.789412 | controller | changed: [instance]
2025-12-01 08:22:36.789513 | controller |
2025-12-01 08:22:36.789523 | controller | TASK [reproducer : Ensure the required parameters are loaded] ******************
2025-12-01 08:22:36.789530 | controller | Monday 01 December 2025  08:22:36 +0000 (0:00:00.283)       0:00:32.194 *******
2025-12-01 08:22:37.319254 | controller | changed: [instance] => (item=arptables)
2025-12-01 08:22:37.613402 | controller | changed: [instance] => (item=iptables)
2025-12-01 08:22:37.613426 | controller | changed: [instance] => (item=ip6tables)
2025-12-01 08:22:37.613435 | controller |
2025-12-01 08:22:37.613443 | controller | TASK [reproducer : Ensure firewall service is enabled and started] *************
2025-12-01 08:22:37.613450 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.529)       0:00:32.724 *******
2025-12-01 08:22:37.613460 | controller | ok: [instance]
2025-12-01 08:22:37.697993 | controller |
2025-12-01 08:22:37.698010 | controller | TASK [reproducer : Set cifmw_ci_nmstate_instance_config if needed] *************
2025-12-01 08:22:37.698017 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.294)       0:00:33.018 *******
2025-12-01 08:22:37.698025 | controller | ok: [instance]
2025-12-01 08:22:37.737129 | controller |
2025-12-01 08:22:37.737146 | controller | TASK [Ensure the required network configurations are applied] ******************
2025-12-01 08:22:37.737153 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.084)       0:00:33.102 *******
2025-12-01 08:22:37.737161 | controller |
2025-12-01 08:22:37.759152 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-12-01 08:22:37.759175 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.039)       0:00:33.142 *******
2025-12-01 08:22:37.759185 | controller | skipping: [instance]
2025-12-01 08:22:37.780968 | controller |
2025-12-01 08:22:37.780985 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-12-01 08:22:37.780992 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.021)       0:00:33.164 *******
2025-12-01 08:22:37.781000 | controller | skipping: [instance]
2025-12-01 08:22:37.803438 | controller |
2025-12-01 08:22:37.803480 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-12-01 08:22:37.803489 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.021)       0:00:33.186 *******
2025-12-01 08:22:37.803499 | controller | skipping: [instance]
2025-12-01 08:22:37.803518 | controller |
2025-12-01 08:22:37.803524 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-12-01 08:22:37.803531 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.022)       0:00:33.208 *******
2025-12-01 08:22:37.829543 | controller | skipping: [instance]
2025-12-01 08:22:37.829654 | controller |
2025-12-01 08:22:37.829782 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] **********************
2025-12-01 08:22:37.829905 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.026)       0:00:33.234 *******
2025-12-01 08:22:37.868353 | controller | ok: [instance]
2025-12-01 08:22:37.868465 | controller |
2025-12-01 08:22:37.868576 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] ***
2025-12-01 08:22:37.868685 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.038)       0:00:33.273 *******
2025-12-01 08:22:37.900173 | controller | skipping: [instance]
2025-12-01 08:22:37.900214 | controller |
2025-12-01 08:22:37.900322 | controller | TASK [ci_nmstate : Get k8s nodes] **********************************************
2025-12-01 08:22:37.900445 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.031)       0:00:33.305 *******
2025-12-01 08:22:37.920928 | controller | skipping: [instance]
2025-12-01 08:22:37.920982 | controller |
2025-12-01 08:22:37.921140 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ********
2025-12-01 08:22:37.921219 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.020)       0:00:33.326 *******
2025-12-01 08:22:37.979812 | controller | ok: [instance]
2025-12-01 08:22:38.000269 | controller |
2025-12-01 08:22:38.000313 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] *************************
2025-12-01 08:22:38.000327 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.058)       0:00:33.384 *******
2025-12-01 08:22:38.000391 | controller | skipping: [instance]
2025-12-01 08:22:38.000437 | controller |
2025-12-01 08:22:38.000555 | controller | TASK [ci_nmstate : Provision unmanaged nodes with nmstate] *********************
2025-12-01 08:22:38.000585 | controller | Monday 01 December 2025  08:22:37 +0000 (0:00:00.020)       0:00:33.405 *******
2025-12-01 08:22:38.044255 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_unmanaged_provision_node.yml for instance => (item=localhost)
2025-12-01 08:22:38.044361 | controller |
2025-12-01 08:22:38.044518 | controller | TASK [ci_nmstate : Ensure we have needed facts] ********************************
2025-12-01 08:22:38.044580 | controller | Monday 01 December 2025  08:22:38 +0000 (0:00:00.043)       0:00:33.449 *******
2025-12-01 08:22:38.344034 | controller | ok: [instance -> localhost]
2025-12-01 08:22:38.344128 | controller |
2025-12-01 08:22:38.344246 | controller | TASK [ci_nmstate : Install required packages on localhost] *********************
2025-12-01 08:22:38.344378 | controller | Monday 01 December 2025  08:22:38 +0000 (0:00:00.299)       0:00:33.749 *******
2025-12-01 08:22:42.937091 | controller | changed: [instance -> localhost]
2025-12-01 08:22:42.937523 | controller |
2025-12-01 08:22:43.128149 | controller | TASK [ci_nmstate : Create the target NMstate dump directory] *******************
2025-12-01 08:22:43.128169 | controller | Monday 01 December 2025  08:22:42 +0000 (0:00:04.592)       0:00:38.341 *******
2025-12-01 08:22:43.128179 | controller | changed: [instance]
2025-12-01 08:22:43.456630 | controller |
2025-12-01 08:22:43.456661 | controller | TASK [ci_nmstate : Save nmstate state for localhost] ***************************
2025-12-01 08:22:43.456669 | controller | Monday 01 December 2025  08:22:43 +0000 (0:00:00.191)       0:00:38.533 *******
2025-12-01 08:22:43.456687 | controller | changed: [instance]
2025-12-01 08:22:45.037458 | controller |
2025-12-01 08:22:45.037493 | controller | TASK [ci_nmstate : Apply the desidered state on localhost] *********************
2025-12-01 08:22:45.037503 | controller | Monday 01 December 2025  08:22:43 +0000 (0:00:00.328)       0:00:38.861 *******
2025-12-01 08:22:45.037515 | controller | changed: [instance -> localhost]
2025-12-01 08:22:45.372038 | controller |
2025-12-01 08:22:45.372073 | controller | TASK [ci_nmstate : Save nmstate debugging data for localhost] ******************
2025-12-01 08:22:45.372084 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:01.581)       0:00:40.442 *******
2025-12-01 08:22:45.372096 | controller | changed: [instance]
2025-12-01 08:22:45.404363 | controller |
2025-12-01 08:22:45.404394 | controller | TASK [reproducer : Select right domain] ****************************************
2025-12-01 08:22:45.404402 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.334)       0:00:40.776 *******
2025-12-01 08:22:45.404413 | controller | ok: [instance]
2025-12-01 08:22:45.520745 | controller |
2025-12-01 08:22:45.520777 | controller | TASK [Create the virtual networks] *********************************************
2025-12-01 08:22:45.520785 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.032)       0:00:40.809 *******
2025-12-01 08:22:45.520796 | controller |
2025-12-01 08:22:45.558707 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ********************
2025-12-01 08:22:45.558725 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.116)       0:00:40.925 *******
2025-12-01 08:22:45.558735 | controller | ok: [instance]
2025-12-01 08:22:45.747694 | controller |
2025-12-01 08:22:45.747723 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] *******
2025-12-01 08:22:45.747731 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.038)       0:00:40.963 *******
2025-12-01 08:22:45.747741 | controller | ok: [instance]
2025-12-01 08:22:45.776455 | controller |
2025-12-01 08:22:45.776475 | controller | TASK [libvirt_manager : Load file if exists] ***********************************
2025-12-01 08:22:45.776483 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.188)       0:00:41.152 *******
2025-12-01 08:22:45.776493 | controller | skipping: [instance]
2025-12-01 08:22:45.776516 | controller |
2025-12-01 08:22:45.776525 | controller | TASK [libvirt_manager : Update fact with existing content] *********************
2025-12-01 08:22:45.776616 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.029)       0:00:41.181 *******
2025-12-01 08:22:45.804153 | controller | skipping: [instance]
2025-12-01 08:22:45.804304 | controller |
2025-12-01 08:22:45.804564 | controller | TASK [libvirt_manager : Generate VM list] **************************************
2025-12-01 08:22:45.804710 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.027)       0:00:41.209 *******
2025-12-01 08:22:45.859322 | controller | ok: [instance]
2025-12-01 08:22:45.859513 | controller |
2025-12-01 08:22:45.859663 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] *********************
2025-12-01 08:22:45.859799 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.055)       0:00:41.264 *******
2025-12-01 08:22:45.984212 | controller | ok: [instance] => (item={'key': 'compute-0', 'value': 'compute'})
2025-12-01 08:22:45.984445 | controller | ok: [instance] => (item={'key': 'controller-0', 'value': 'controller'})
2025-12-01 08:22:45.984598 | controller | ok: [instance] => (item={'key': 'crc-0', 'value': 'crc'})
2025-12-01 08:22:45.984727 | controller |
2025-12-01 08:22:45.984859 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] **********************
2025-12-01 08:22:45.984994 | controller | Monday 01 December 2025  08:22:45 +0000 (0:00:00.124)       0:00:41.389 *******
2025-12-01 08:22:46.017636 | controller | skipping: [instance]
2025-12-01 08:22:46.017839 | controller |
2025-12-01 08:22:46.018016 | controller | TASK [libvirt_manager : Dump MAC mapping] **************************************
2025-12-01 08:22:46.018192 | controller | Monday 01 December 2025  08:22:46 +0000 (0:00:00.033)       0:00:41.422 *******
2025-12-01 08:22:46.353259 | controller | changed: [instance]
2025-12-01 08:22:46.353502 | controller |
2025-12-01 08:22:46.353691 | controller | TASK [libvirt_manager : Add libvirt hosts into inventory and dump inventory file] ***
2025-12-01 08:22:46.353859 | controller | Monday 01 December 2025  08:22:46 +0000 (0:00:00.335)       0:00:41.758 *******
2025-12-01 08:22:46.427015 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding compute-0 to computes)
2025-12-01 08:22:46.427203 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding controller-0 to controllers)
2025-12-01 08:22:46.427389 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding crc-0 to ocps)
2025-12-01 08:22:46.427518 | controller |
2025-12-01 08:22:46.427644 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 08:22:46.427766 | controller | Monday 01 December 2025  08:22:46 +0000 (0:00:00.073)       0:00:41.832 *******
2025-12-01 08:22:46.480599 | controller | changed: [instance]
2025-12-01 08:22:46.480778 | controller |
2025-12-01 08:22:46.480941 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 08:22:46.481092 | controller | Monday 01 December 2025  08:22:46 +0000 (0:00:00.053)       0:00:41.885 *******
2025-12-01 08:22:46.747103 | controller | changed: [instance]
2025-12-01 08:22:46.747287 | controller |
2025-12-01 08:22:46.747489 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 08:22:46.747649 | controller | Monday 01 December 2025  08:22:46 +0000 (0:00:00.266)       0:00:42.152 *******
2025-12-01 08:22:46.948844 | controller | changed: [instance]
2025-12-01 08:22:46.949041 | controller |
2025-12-01 08:22:46.949225 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 08:22:46.949405 | controller | Monday 01 December 2025  08:22:46 +0000 (0:00:00.201)       0:00:42.353 *******
2025-12-01 08:22:47.002443 | controller | changed: [instance]
2025-12-01 08:22:47.002620 | controller |
2025-12-01 08:22:47.002783 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 08:22:47.002937 | controller | Monday 01 December 2025  08:22:47 +0000 (0:00:00.053)       0:00:42.407 *******
2025-12-01 08:22:47.194880 | controller | changed: [instance]
2025-12-01 08:22:47.396522 | controller |
2025-12-01 08:22:47.396553 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 08:22:47.396561 | controller | Monday 01 December 2025  08:22:47 +0000 (0:00:00.192)       0:00:42.599 *******
2025-12-01 08:22:47.396572 | controller | changed: [instance]
2025-12-01 08:22:47.449177 | controller |
2025-12-01 08:22:47.449204 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 08:22:47.449211 | controller | Monday 01 December 2025  08:22:47 +0000 (0:00:00.201)       0:00:42.801 *******
2025-12-01 08:22:47.449220 | controller | changed: [instance]
2025-12-01 08:22:47.637177 | controller |
2025-12-01 08:22:47.637205 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 08:22:47.637214 | controller | Monday 01 December 2025  08:22:47 +0000 (0:00:00.052)       0:00:42.854 *******
2025-12-01 08:22:47.637225 | controller | changed: [instance]
2025-12-01 08:22:47.839907 | controller |
2025-12-01 08:22:47.839939 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 08:22:47.839947 | controller | Monday 01 December 2025  08:22:47 +0000 (0:00:00.187)       0:00:43.042 *******
2025-12-01 08:22:47.839956 | controller | changed: [instance]
2025-12-01 08:22:47.865886 | controller |
2025-12-01 08:22:47.865913 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ******************
2025-12-01 08:22:47.865921 | controller | Monday 01 December 2025  08:22:47 +0000 (0:00:00.202)       0:00:43.244 *******
2025-12-01 08:22:47.865931 | controller | fatal: [instance]: FAILED! =>
2025-12-01 08:22:47.865951 | controller |   msg: |-
2025-12-01 08:22:47.865961 | controller |     The conditional check '(_cifmw_libvirt_manager_layout.vms[_vm_type].amount | default(1) | int) > 0' failed. The error was: error while evaluating conditional ((_cifmw_libvirt_manager_layout.vms[_vm_type].amount | default(1) | int) > 0): {{ cifmw_libvirt_manager_all_vms[item.key] | default('none') }}: 'item' is undefined. 'item' is undefined. {{ cifmw_libvirt_manager_all_vms[item.key] | default('none') }}: 'item' is undefined. 'item' is undefined
2025-12-01 08:22:47.865973 | controller |
2025-12-01 08:22:47.866415 | controller |     The error appears to be in '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_networking_data.yml': line 136, column 7, but may
2025-12-01 08:22:47.866668 | controller |     be elsewhere in the file depending on the exact syntax problem.
2025-12-01 08:22:47.866682 | controller |
2025-12-01 08:22:47.866689 | controller |     The offending line appears to be:
2025-12-01 08:22:47.866694 | controller |
2025-12-01 08:22:47.866702 | controller |
2025-12-01 08:22:47.872025 | controller |         - name: Convert ini inventory file into yaml
2025-12-01 08:22:47.872041 | controller |           ^ here
2025-12-01 08:22:47.872051 | controller |
2025-12-01 08:22:48.053408 | controller | TASK [Create dnsmasq dir] ******************************************************
2025-12-01 08:22:48.053437 | controller | Monday 01 December 2025  08:22:47 +0000 (0:00:00.032)       0:00:43.277 *******
2025-12-01 08:22:48.053448 | controller | changed: [instance]
2025-12-01 08:22:48.248013 | controller |
2025-12-01 08:22:48.248042 | controller | TASK [Get journalctl in a file] ************************************************
2025-12-01 08:22:48.248050 | controller | Monday 01 December 2025  08:22:48 +0000 (0:00:00.180)       0:00:43.458 *******
2025-12-01 08:22:48.248060 | controller | changed: [instance]
2025-12-01 08:22:48.584365 | controller |
2025-12-01 08:22:48.584395 | controller | TASK [Dump journalctl output] **************************************************
2025-12-01 08:22:48.584403 | controller | Monday 01 December 2025  08:22:48 +0000 (0:00:00.194)       0:00:43.652 *******
2025-12-01 08:22:48.584412 | controller | changed: [instance]
2025-12-01 08:22:48.910729 | controller |
2025-12-01 08:22:48.910759 | controller | TASK [Copy all dnsmasq config] *************************************************
2025-12-01 08:22:48.910772 | controller | Monday 01 December 2025  08:22:48 +0000 (0:00:00.336)       0:00:43.989 *******
2025-12-01 08:22:48.910782 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.conf) => changed=false
2025-12-01 08:22:48.910805 | controller |   ansible_loop_var: item
2025-12-01 08:22:48.911386 | 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
2025-12-01 08:22:49.219780 | controller |   item: /etc/cifmw-dnsmasq.conf
2025-12-01 08:22:49.219805 | controller |   msg: |-
2025-12-01 08:22:49.219812 | controller |     rsync: [sender] link_stat "/etc/cifmw-dnsmasq.conf" failed: No such file or directory (2)
2025-12-01 08:22:49.219818 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2025-12-01 08:22:49.219823 | controller |   rc: 23
2025-12-01 08:22:49.219832 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.d/) => changed=false
2025-12-01 08:22:49.219853 | controller |   ansible_loop_var: item
2025-12-01 08:22:49.219859 | 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
2025-12-01 08:22:49.219865 | controller |   item: /etc/cifmw-dnsmasq.d/
2025-12-01 08:22:49.219870 | controller |   msg: |-
2025-12-01 08:22:49.219877 | controller |     rsync: [sender] change_dir "/etc/cifmw-dnsmasq.d" failed: No such file or directory (2)
2025-12-01 08:22:49.224537 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2025-12-01 08:22:49.224572 | controller |   rc: 23
2025-12-01 08:22:49.224586 | controller |
2025-12-01 08:22:49.224631 | controller | PLAY RECAP *********************************************************************
2025-12-01 08:22:49.224639 | controller | instance                   : ok=108  changed=33   unreachable=0    failed=2    skipped=18   rescued=0    ignored=0
2025-12-01 08:22:49.224646 | controller |
2025-12-01 08:22:49.224652 | controller | Monday 01 December 2025  08:22:49 +0000 (0:00:00.638)       0:00:44.627 *******
2025-12-01 08:22:49.224658 | controller | ===============================================================================
2025-12-01 08:22:49.224664 | controller | libvirt_manager : Install packages required for using KVM --------------- 8.96s
2025-12-01 08:22:49.224671 | controller | ci_nmstate : Install required packages on localhost --------------------- 4.59s
2025-12-01 08:22:49.224679 | controller | discover_latest_image : Get latest image -------------------------------- 1.74s
2025-12-01 08:22:49.224685 | controller | ci_nmstate : Apply the desidered state on localhost --------------------- 1.58s
2025-12-01 08:22:49.224691 | controller | libvirt_manager : Ensure admin socket is enabled for network. ----------- 1.31s
2025-12-01 08:22:49.224697 | controller | libvirt_manager : Deactivate crc ---------------------------------------- 1.16s
2025-12-01 08:22:49.224703 | controller | reproducer : Ensure firewalld is installed ------------------------------ 1.14s
2025-12-01 08:22:49.224709 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.83s
2025-12-01 08:22:49.224715 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-12-01 08:22:49.224721 | controller | ci_network : Remove the local dns configuration ------------------------- 0.70s
2025-12-01 08:22:49.224727 | controller | Copy all dnsmasq config ------------------------------------------------- 0.64s
2025-12-01 08:22:49.224733 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.61s
2025-12-01 08:22:49.224739 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.55s
2025-12-01 08:22:49.224745 | controller | reproducer : Ensure the required parameters are loaded ------------------ 0.53s
2025-12-01 08:22:49.224751 | controller | libvirt_manager : Enable UNIX socket PolicyKit auth --------------------- 0.48s
2025-12-01 08:22:49.224760 | controller | libvirt_manager : Deactivate default ------------------------------------ 0.48s
2025-12-01 08:22:49.224782 | controller | reproducer : Ensure directories are present ----------------------------- 0.45s
2025-12-01 08:22:49.224791 | controller | reproducer : Restart NetworkManager ------------------------------------- 0.41s
2025-12-01 08:22:49.224813 | controller | libvirt_manager : Add user if not exists in libvirt group --------------- 0.36s
2025-12-01 08:22:49.224943 | controller | reproducer : Ensure NetworkManager is configured to use dnsmasq --------- 0.34s
2025-12-01 08:22:49.280464 | 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']
2025-12-01 08:22:49.280871 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-01 08:22:49.288978 | controller | INFO     Running crc_layout > cleanup
2025-12-01 08:22:49.289444 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-01 08:22:49.296299 | controller | INFO     Running crc_layout > destroy
2025-12-01 08:22:49.296693 | controller | WARNING  Skipping, instances are delegated.
2025-12-01 08:22:49.297025 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-01 08:22:49.298435 | controller | INFO     Writing /tmp/report.html report.
2025-12-01 08:22:49.623721 | [controller] Waiting on logger
2025-12-01 08:22:54.770531 | [controller] Waiting on logger
2025-12-01 08:23:05.202567 | [controller] Waiting on logger
2025-12-01 08:23:15.634568 | [controller] Waiting on logger
2025-12-01 08:23:19.370196 | [Zuul] Log Stream did not terminate
2025-12-01 08:23:19.370584 | controller | ERROR
2025-12-01 08:23:19.370731 | controller | {
2025-12-01 08:23:19.370781 | controller |   "delta": "0:02:18.060264",
2025-12-01 08:23:19.370814 | controller |   "end": "2025-12-01 08:22:49.342718",
2025-12-01 08:23:19.370842 | controller |   "msg": "non-zero return code",
2025-12-01 08:23:19.370869 | controller |   "rc": 1,
2025-12-01 08:23:19.370897 | controller |   "start": "2025-12-01 08:20:31.282454"
2025-12-01 08:23:19.370977 | controller | }
failure
2025-12-01 08:23:19.418106 | 
2025-12-01 08:23:19.418177 | PLAY RECAP
2025-12-01 08:23:19.418231 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-01 08:23:19.418264 | 
2025-12-01 08:23:19.486441 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 08:23:19.487291 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 08:23:19.985369 | 
2025-12-01 08:23:19.985475 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-01 08:23:20.004122 | 
2025-12-01 08:23:20.004195 | TASK [Filter out host if needed]
2025-12-01 08:23:20.012390 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 08:23:20.016589 | 
2025-12-01 08:23:20.016651 | TASK [Ensure file is present]
2025-12-01 08:23:20.360372 | controller | ok
2025-12-01 08:23:20.370629 | 
2025-12-01 08:23:20.370697 | TASK [Manage molecule report file]
2025-12-01 08:23:20.397090 | [controller] Waiting on logger
2025-12-01 08:23:29.751657 | [controller] Waiting on logger
2025-12-01 08:23:40.146710 | [controller] Waiting on logger
2025-12-01 08:23:50.578536 | [controller] Waiting on logger
2025-12-01 08:23:50.738022 | [Zuul] Log Stream did not terminate
2025-12-01 08:23:50.738435 | controller | changed
2025-12-01 08:23:50.744059 | 
2025-12-01 08:23:50.744153 | TASK [Check if we get ci-framework-data basedir]
2025-12-01 08:23:50.997295 | controller | ok
2025-12-01 08:23:51.002243 | 
2025-12-01 08:23:51.002313 | TASK [Create ci-framework-data log directory for zuul]
2025-12-01 08:23:51.387378 | controller | changed
2025-12-01 08:23:51.392488 | 
2025-12-01 08:23:51.392556 | TASK [Copy ci-framework interesting files]
2025-12-01 08:23:56.427205 | controller | changed
2025-12-01 08:23:56.438949 | 
2025-12-01 08:23:56.439062 | TASK [Get SELinux listing]
2025-12-01 08:23:57.490534 | [controller] Waiting on logger
2025-12-01 08:24:06.834493 | [controller] Waiting on logger
2025-12-01 08:24:17.266530 | [controller] Waiting on logger
2025-12-01 08:24:26.647285 | [controller] Waiting on logger
2025-12-01 08:24:26.712175 | [Zuul] Log Stream did not terminate
2025-12-01 08:24:26.712527 | controller | changed
2025-12-01 08:24:26.718860 | 
2025-12-01 08:24:26.718957 | TASK [Generate log index]
2025-12-01 08:24:27.696073 | controller | changed
2025-12-01 08:24:27.700816 | 
2025-12-01 08:24:27.700880 | TASK [Get some env related data]
2025-12-01 08:24:27.724495 | [controller] Waiting on logger
2025-12-01 08:24:42.226608 | [controller] Waiting on logger
2025-12-01 08:24:52.658500 | [controller] Waiting on logger
2025-12-01 08:24:58.819442 | [Zuul] Log Stream did not terminate
2025-12-01 08:24:58.820576 | controller | changed
2025-12-01 08:24:58.834085 | 
2025-12-01 08:24:58.834205 | TASK [Generate list of logs to collect in home directory]
2025-12-01 08:24:59.204555 | controller | ok: All paths examined
2025-12-01 08:24:59.209450 | 
2025-12-01 08:24:59.209508 | LOOP [Copy logs from home directory]
2025-12-01 08:24:59.769066 | controller | changed:
2025-12-01 08:24:59.769334 | controller | {
2025-12-01 08:24:59.769361 | controller |   "atime": 1743544925.4788878,
2025-12-01 08:24:59.769379 | controller |   "ctime": 1743545329.1409318,
2025-12-01 08:24:59.769394 | controller |   "dev": 64513,
2025-12-01 08:24:59.769409 | controller |   "gid": 1000,
2025-12-01 08:24:59.769424 | controller |   "gr_name": "zuul",
2025-12-01 08:24:59.769438 | controller |   "inode": 4518807,
2025-12-01 08:24:59.769451 | controller |   "isblk": false,
2025-12-01 08:24:59.769466 | controller |   "ischr": false,
2025-12-01 08:24:59.769479 | controller |   "isdir": false,
2025-12-01 08:24:59.769492 | controller |   "isfifo": false,
2025-12-01 08:24:59.769505 | controller |   "isgid": false,
2025-12-01 08:24:59.769518 | controller |   "islnk": false,
2025-12-01 08:24:59.769531 | controller |   "isreg": true,
2025-12-01 08:24:59.769543 | controller |   "issock": false,
2025-12-01 08:24:59.769555 | controller |   "isuid": false,
2025-12-01 08:24:59.769570 | controller |   "mode": "0644",
2025-12-01 08:24:59.769584 | controller |   "mtime": 1743545329.1409318,
2025-12-01 08:24:59.769597 | controller |   "nlink": 1,
2025-12-01 08:24:59.769609 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-01 08:24:59.769621 | controller |   "pw_name": "zuul",
2025-12-01 08:24:59.769633 | controller |   "rgrp": true,
2025-12-01 08:24:59.769645 | controller |   "roth": true,
2025-12-01 08:24:59.769657 | controller |   "rusr": true,
2025-12-01 08:24:59.769669 | controller |   "size": 4108,
2025-12-01 08:24:59.769683 | controller |   "uid": 1000,
2025-12-01 08:24:59.769697 | controller |   "wgrp": false,
2025-12-01 08:24:59.769709 | controller |   "woth": false,
2025-12-01 08:24:59.769722 | controller |   "wusr": true,
2025-12-01 08:24:59.769734 | controller |   "xgrp": false,
2025-12-01 08:24:59.769746 | controller |   "xoth": false,
2025-12-01 08:24:59.769758 | controller |   "xusr": false
2025-12-01 08:24:59.769770 | controller | }
2025-12-01 08:25:00.287705 | controller | changed:
2025-12-01 08:25:00.287834 | controller | {
2025-12-01 08:25:00.287860 | controller |   "atime": 1743545331.0429637,
2025-12-01 08:25:00.287880 | controller |   "ctime": 1743545842.0111232,
2025-12-01 08:25:00.287897 | controller |   "dev": 64513,
2025-12-01 08:25:00.287933 | controller |   "gid": 1000,
2025-12-01 08:25:00.287954 | controller |   "gr_name": "zuul",
2025-12-01 08:25:00.287970 | controller |   "inode": 4194437,
2025-12-01 08:25:00.287986 | controller |   "isblk": false,
2025-12-01 08:25:00.288001 | controller |   "ischr": false,
2025-12-01 08:25:00.288016 | controller |   "isdir": false,
2025-12-01 08:25:00.288031 | controller |   "isfifo": false,
2025-12-01 08:25:00.288046 | controller |   "isgid": false,
2025-12-01 08:25:00.288061 | controller |   "islnk": false,
2025-12-01 08:25:00.288083 | controller |   "isreg": true,
2025-12-01 08:25:00.288099 | controller |   "issock": false,
2025-12-01 08:25:00.288113 | controller |   "isuid": false,
2025-12-01 08:25:00.288127 | controller |   "mode": "0644",
2025-12-01 08:25:00.288141 | controller |   "mtime": 1743545842.0111232,
2025-12-01 08:25:00.288154 | controller |   "nlink": 1,
2025-12-01 08:25:00.288168 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-01 08:25:00.288182 | controller |   "pw_name": "zuul",
2025-12-01 08:25:00.288195 | controller |   "rgrp": true,
2025-12-01 08:25:00.288208 | controller |   "roth": true,
2025-12-01 08:25:00.288222 | controller |   "rusr": true,
2025-12-01 08:25:00.288237 | controller |   "size": 4023,
2025-12-01 08:25:00.288251 | controller |   "uid": 1000,
2025-12-01 08:25:00.288265 | controller |   "wgrp": false,
2025-12-01 08:25:00.288279 | controller |   "woth": false,
2025-12-01 08:25:00.288292 | controller |   "wusr": true,
2025-12-01 08:25:00.288305 | controller |   "xgrp": false,
2025-12-01 08:25:00.288320 | controller |   "xoth": false,
2025-12-01 08:25:00.288335 | controller |   "xusr": false
2025-12-01 08:25:00.288349 | controller | }
2025-12-01 08:25:00.823325 | controller | changed:
2025-12-01 08:25:00.823435 | controller | {
2025-12-01 08:25:00.823460 | controller |   "atime": 1764577214.0211852,
2025-12-01 08:25:00.823478 | controller |   "ctime": 1764577229.013014,
2025-12-01 08:25:00.823494 | controller |   "dev": 64513,
2025-12-01 08:25:00.823508 | controller |   "gid": 1000,
2025-12-01 08:25:00.823522 | controller |   "gr_name": "zuul",
2025-12-01 08:25:00.823534 | controller |   "inode": 4685808,
2025-12-01 08:25:00.823548 | controller |   "isblk": false,
2025-12-01 08:25:00.823562 | controller |   "ischr": false,
2025-12-01 08:25:00.823576 | controller |   "isdir": false,
2025-12-01 08:25:00.823590 | controller |   "isfifo": false,
2025-12-01 08:25:00.823605 | controller |   "isgid": false,
2025-12-01 08:25:00.823619 | controller |   "islnk": false,
2025-12-01 08:25:00.823632 | controller |   "isreg": true,
2025-12-01 08:25:00.823644 | controller |   "issock": false,
2025-12-01 08:25:00.823656 | controller |   "isuid": false,
2025-12-01 08:25:00.823668 | controller |   "mode": "0644",
2025-12-01 08:25:00.823680 | controller |   "mtime": 1764577229.013014,
2025-12-01 08:25:00.823692 | controller |   "nlink": 1,
2025-12-01 08:25:00.823704 | controller |   "path": "/home/zuul/ansible.log",
2025-12-01 08:25:00.823716 | controller |   "pw_name": "zuul",
2025-12-01 08:25:00.823728 | controller |   "rgrp": true,
2025-12-01 08:25:00.823740 | controller |   "roth": true,
2025-12-01 08:25:00.823752 | controller |   "rusr": true,
2025-12-01 08:25:00.823764 | controller |   "size": 6749,
2025-12-01 08:25:00.823776 | controller |   "uid": 1000,
2025-12-01 08:25:00.823788 | controller |   "wgrp": false,
2025-12-01 08:25:00.823800 | controller |   "woth": false,
2025-12-01 08:25:00.823812 | controller |   "wusr": true,
2025-12-01 08:25:00.823824 | controller |   "xgrp": false,
2025-12-01 08:25:00.823835 | controller |   "xoth": false,
2025-12-01 08:25:00.823847 | controller |   "xusr": false
2025-12-01 08:25:00.823859 | controller | }
2025-12-01 08:25:00.833695 | 
2025-12-01 08:25:00.833761 | TASK [Copy crio stats log file]
2025-12-01 08:25:00.847705 | controller | skipping: Conditional result was False
2025-12-01 08:25:00.853873 | 
2025-12-01 08:25:00.853979 | TASK [Get SELinux related data]
2025-12-01 08:25:01.874503 | [controller] Waiting on logger
2025-12-01 08:25:04.050474 | [controller] Waiting on logger
2025-12-01 08:25:12.485449 | controller | ERROR
2025-12-01 08:25:12.485721 | controller | {
2025-12-01 08:25:12.485755 | controller |   "delta": "0:00:00.009592",
2025-12-01 08:25:12.485776 | controller |   "end": "2025-12-01 08:25:01.124113",
2025-12-01 08:25:12.485793 | controller |   "msg": "non-zero return code",
2025-12-01 08:25:12.485809 | controller |   "rc": 1,
2025-12-01 08:25:12.485825 | controller |   "start": "2025-12-01 08:25:01.114521"
2025-12-01 08:25:12.485841 | controller | }
2025-12-01 08:25:12.485864 | controller | ERROR: Ignoring Errors
2025-12-01 08:25:12.491466 | 
2025-12-01 08:25:12.491533 | TASK [Create system configuration directory]
2025-12-01 08:25:12.733735 | controller | changed
2025-12-01 08:25:12.738309 | 
2025-12-01 08:25:12.738373 | TASK [Get some of the system configurations]
2025-12-01 08:25:12.761312 | [controller] Waiting on logger
2025-12-01 08:25:23.186655 | [controller] Waiting on logger
2025-12-01 08:25:33.619291 | [controller] Waiting on logger
2025-12-01 08:25:42.964525 | [controller] Waiting on logger
2025-12-01 08:25:43.029839 | [Zuul] Log Stream did not terminate
2025-12-01 08:25:43.031414 | controller | changed
2025-12-01 08:25:43.039699 | 
2025-12-01 08:25:43.039762 | TASK [Copy generated documentation if available]
2025-12-01 08:25:43.064128 | controller | skipping: Conditional result was False
2025-12-01 08:25:43.069893 | 
2025-12-01 08:25:43.069976 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-01 08:25:43.084034 | controller | skipping: Conditional result was False
2025-12-01 08:25:43.089136 | 
2025-12-01 08:25:43.089198 | TASK [Compress logs bigger than 2MB]
2025-12-01 08:25:44.114630 | [controller] Waiting on logger
2025-12-01 08:25:50.376592 | controller | changed
2025-12-01 08:25:50.383513 | 
2025-12-01 08:25:50.383611 | TASK [Copy files from workspace on node]
2025-12-01 08:25:50.404244 | controller | ok
2025-12-01 08:25:50.430272 | 
2025-12-01 08:25:50.430367 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 08:25:50.455402 | controller | skipping: Conditional result was False
2025-12-01 08:25:50.461852 | 
2025-12-01 08:25:50.461940 | TASK [fetch-output : Set log path for single node]
2025-12-01 08:25:50.490275 | controller | ok
2025-12-01 08:25:50.495124 | 
2025-12-01 08:25:50.495190 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 08:25:50.695856 | controller -> localhost | ok: "/var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/logs"
2025-12-01 08:25:50.696193 | controller -> localhost | changed: All items complete
2025-12-01 08:25:50.696231 | 
2025-12-01 08:25:50.878605 | controller -> localhost | changed: "/var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/artifacts"
2025-12-01 08:25:51.067857 | controller -> localhost | changed: "/var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/docs"
2025-12-01 08:25:51.083566 | 
2025-12-01 08:25:51.083741 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 08:25:51.712770 | controller | changed:
2025-12-01 08:25:51.716490 | controller | .d..t...... ./
2025-12-01 08:25:51.719901 | controller | >f+++++++++ README.html
2025-12-01 08:25:51.719958 | controller | >f+++++++++ ansible-execution.log
2025-12-01 08:25:51.719980 | controller | >f+++++++++ ansible.log
2025-12-01 08:25:51.720000 | controller | >f+++++++++ crc-setup.log
2025-12-01 08:25:51.720026 | controller | >f+++++++++ crc-start.log
2025-12-01 08:25:51.720044 | controller | >f+++++++++ dmesg.log
2025-12-01 08:25:51.720061 | controller | >f+++++++++ installed-pkgs.log
2025-12-01 08:25:51.720076 | controller | >f+++++++++ python.log
2025-12-01 08:25:51.720091 | controller | >f+++++++++ registries.conf
2025-12-01 08:25:51.720104 | controller | >f+++++++++ report.html
2025-12-01 08:25:51.720118 | controller | >f+++++++++ selinux-denials.log
2025-12-01 08:25:51.720131 | controller | >f+++++++++ selinux-listing.log
2025-12-01 08:25:51.720145 | controller | cd+++++++++ ci-framework-data/
2025-12-01 08:25:51.720158 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-01 08:25:51.720172 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-01 08:25:51.720186 | controller | cd+++++++++ ci-framework-data/artifacts/dnsmasq/
2025-12-01 08:25:51.720199 | controller | >f+++++++++ ci-framework-data/artifacts/dnsmasq/journalctl.log
2025-12-01 08:25:51.720213 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-01 08:25:51.720226 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-01 08:25:51.720239 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-01 08:25:51.720253 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-01 08:25:51.720267 | controller | cd+++++++++ registries.conf.d/
2025-12-01 08:25:51.720280 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-01 08:25:51.720293 | controller | cd+++++++++ system-config/
2025-12-01 08:25:51.720320 | controller | cd+++++++++ system-config/libvirt/
2025-12-01 08:25:51.720333 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-01 08:25:51.720345 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-01 08:25:51.720358 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-01 08:25:51.720371 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-01 08:25:51.720383 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-01 08:25:51.720395 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-01 08:25:51.720408 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-01 08:25:51.720420 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-01 08:25:51.720438 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-01 08:25:51.720452 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-01 08:25:51.720466 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-01 08:25:51.720480 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-01 08:25:51.720493 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-01 08:25:51.720506 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-01 08:25:51.720518 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-01 08:25:51.720531 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-01 08:25:52.201945 | controller | changed: .d..t...... ./
2025-12-01 08:25:52.657421 | controller | changed: .d..t...... ./
2025-12-01 08:25:52.678070 | 
2025-12-01 08:25:52.678189 | TASK [Return artifact to Zuul]
2025-12-01 08:25:52.707085 | controller | ok
2025-12-01 08:25:52.724370 | 
2025-12-01 08:25:52.724425 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-01 08:25:52.724524 | 
2025-12-01 08:25:52.724552 | PLAY RECAP
2025-12-01 08:25:52.724589 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-01 08:25:52.724609 | 
2025-12-01 08:25:52.804856 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 08:25:52.805673 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 08:25:53.331563 | 
2025-12-01 08:25:53.331693 | PLAY [all]
2025-12-01 08:25:53.349193 | 
2025-12-01 08:25:53.349261 | TASK [include_role : fetch-output]
2025-12-01 08:25:53.377658 | controller | ok
2025-12-01 08:25:53.392963 | 
2025-12-01 08:25:53.393046 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 08:25:53.437072 | controller | skipping: Conditional result was False
2025-12-01 08:25:53.442607 | 
2025-12-01 08:25:53.442672 | TASK [fetch-output : Set log path for single node]
2025-12-01 08:25:53.482049 | controller | ok
2025-12-01 08:25:53.486676 | 
2025-12-01 08:25:53.486740 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 08:25:53.821659 | controller -> localhost | ok: "/var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/logs"
2025-12-01 08:25:54.016093 | controller -> localhost | ok: "/var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/artifacts"
2025-12-01 08:25:54.192441 | controller -> localhost | ok: "/var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/docs"
2025-12-01 08:25:54.208585 | 
2025-12-01 08:25:54.208693 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 08:25:54.805897 | controller | ok
2025-12-01 08:25:54.806137 | controller | ok: All items complete
2025-12-01 08:25:54.806167 | 
2025-12-01 08:25:55.250194 | controller | ok
2025-12-01 08:25:55.693246 | controller | ok
2025-12-01 08:25:55.706246 | 
2025-12-01 08:25:55.706376 | TASK [include_role : fetch-output-openshift]
2025-12-01 08:25:55.719861 | controller | skipping: Conditional result was False
2025-12-01 08:25:55.725786 | 
2025-12-01 08:25:55.725854 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 08:25:56.049631 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008280
2025-12-01 08:25:56.229846 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006598
2025-12-01 08:25:56.258529 | 
2025-12-01 08:25:56.258629 | PLAY [all]
2025-12-01 08:25:56.271794 | 
2025-12-01 08:25:56.271860 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-01 08:25:56.717233 | controller | changed
2025-12-01 08:25:56.737980 | 
2025-12-01 08:25:56.738039 | PLAY RECAP
2025-12-01 08:25:56.738086 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-01 08:25:56.738111 | 
2025-12-01 08:25:56.811046 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 08:25:56.811818 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-01 08:25:57.326928 | 
2025-12-01 08:25:57.327064 | PLAY [localhost]
2025-12-01 08:25:57.342776 | 
2025-12-01 08:25:57.342847 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 08:25:57.623612 | localhost | changed
2025-12-01 08:25:57.627950 | 
2025-12-01 08:25:57.628042 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 08:25:57.655733 | localhost | ok
2025-12-01 08:25:57.663339 | 
2025-12-01 08:25:57.663409 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-01 08:25:57.945359 | localhost | changed
2025-12-01 08:25:57.950542 | 
2025-12-01 08:25:57.950608 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-01 08:25:58.465520 | localhost | changed
2025-12-01 08:25:58.470222 | 
2025-12-01 08:25:58.470286 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-01 08:25:58.791332 | localhost | Identity added: /var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/tmp/ansible.n3x3anc9 (/var/lib/zuul/builds/34ac555c268942a08b4f0492d8a70011/work/tmp/ansible.n3x3anc9)
2025-12-01 08:25:58.791495 | localhost | ok: Runtime: 0:00:00.006071
2025-12-01 08:25:58.795469 | 
2025-12-01 08:25:58.795534 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-01 08:25:58.995372 | localhost | ok: Runtime: 0:00:00.004322
2025-12-01 08:25:59.000080 | 
2025-12-01 08:25:59.000144 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-01 08:25:59.047841 | localhost | changed
2025-12-01 08:25:59.052406 | 
2025-12-01 08:25:59.052468 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-01 08:25:59.343112 | localhost | changed
2025-12-01 08:25:59.361042 | 
2025-12-01 08:25:59.361100 | PLAY [localhost]
2025-12-01 08:25:59.371268 | 
2025-12-01 08:25:59.371327 | TASK [Generate bulk log download script]
2025-12-01 08:25:59.389508 | localhost | ok
2025-12-01 08:25:59.399970 | 
2025-12-01 08:25:59.400047 | TASK [local-log-download : Check API endpoint is defined]
2025-12-01 08:25:59.427212 | localhost | ok: All assertions passed
2025-12-01 08:25:59.431427 | 
2025-12-01 08:25:59.431489 | TASK [local-log-download : Create download script]
2025-12-01 08:25:59.758602 | localhost -> localhost | changed
2025-12-01 08:25:59.767033 | 
2025-12-01 08:25:59.767100 | TASK [Register quick-download link]
2025-12-01 08:25:59.784331 | localhost | ok
2025-12-01 08:25:59.819666 | 
2025-12-01 08:25:59.819755 | PLAY [logserver.rdoproject.org]
2025-12-01 08:25:59.828475 | 
2025-12-01 08:25:59.828538 | TASK [Set zuul-log-path fact]
2025-12-01 08:25:59.844540 | logserver.rdoproject.org | ok
2025-12-01 08:25:59.853564 | 
2025-12-01 08:25:59.853632 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 08:25:59.879732 | logserver.rdoproject.org | ok
2025-12-01 08:25:59.884818 | 
2025-12-01 08:25:59.884885 | TASK [upload-logs : Create log directories]
2025-12-01 08:26:01.620175 | logserver.rdoproject.org | changed
2025-12-01 08:26:01.623460 | 
2025-12-01 08:26:01.623528 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-01 08:26:01.843375 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006465
2025-12-01 08:26:01.848951 | 
2025-12-01 08:26:01.849029 | TASK [upload-logs : Upload logs to log server]
2025-12-01 08:26:03.331057 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-01 08:26:03.334103 | 
2025-12-01 08:26:03.334171 | LOOP [upload-logs : Compress console log and json output]
2025-12-01 08:26:03.376124 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:26:03.383259 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:26:03.393553 | 
2025-12-01 08:26:03.393693 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-01 08:26:03.431101 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:26:03.431343 | 
2025-12-01 08:26:03.433765 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 08:26:03.442357 | 
2025-12-01 08:26:03.442500 | LOOP [upload-logs : Upload console log and json output]