2026-03-17 14:40:52.690395 | Job console starting...
2026-03-17 14:40:52.947105 | Updating repositories
2026-03-17 14:40:53.689918 | Preparing job workspace
2026-03-17 14:41:02.123726 | Running Ansible setup...
2026-03-17 14:41:05.595462 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-17 14:41:06.106591 | 
2026-03-17 14:41:06.106693 | PLAY [localhost]
2026-03-17 14:41:06.115088 | 
2026-03-17 14:41:06.115158 | TASK [Gathering Facts]
2026-03-17 14:41:06.888395 | localhost | ok
2026-03-17 14:41:06.900850 | 
2026-03-17 14:41:06.900960 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-17 14:41:07.208143 | localhost -> localhost | changed
2026-03-17 14:41:07.213517 | 
2026-03-17 14:41:07.213586 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-17 14:41:07.917959 | localhost -> localhost | changed
2026-03-17 14:41:07.926007 | 
2026-03-17 14:41:07.926080 | TASK [Setup log path fact]
2026-03-17 14:41:07.944600 | localhost | ok
2026-03-17 14:41:07.955411 | 
2026-03-17 14:41:07.955474 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 14:41:07.983582 | localhost | ok
2026-03-17 14:41:07.991488 | 
2026-03-17 14:41:07.991554 | TASK [emit-job-header : Print job information]
2026-03-17 14:41:08.023056 | # Job Information
2026-03-17 14:41:08.023245 | Ansible Version: 2.15.12
2026-03-17 14:41:08.023275 | Job: cifmw-molecule-reproducer
2026-03-17 14:41:08.023319 | Pipeline: github-check
2026-03-17 14:41:08.023346 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-03-17 14:41:08.023367 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3129
2026-03-17 14:41:08.023387 | Log URL (when completed): https://logserver.rdoproject.org/e24/rdoproject.org/e24528d88cb14af9a915a1f3e4272715/
2026-03-17 14:41:08.023407 | Event ID: 2801daa0-220f-11f1-9114-09717f396cbf
2026-03-17 14:41:08.027461 | 
2026-03-17 14:41:08.027531 | LOOP [emit-job-header : Print node information]
2026-03-17 14:41:08.120707 | localhost | ok:
2026-03-17 14:41:08.120982 | localhost | # Node Information
2026-03-17 14:41:08.121014 | localhost | Inventory Hostname: controller
2026-03-17 14:41:08.121038 | localhost | Hostname: np0005649812
2026-03-17 14:41:08.121058 | localhost | Username: zuul
2026-03-17 14:41:08.121078 | localhost | Distro: CentOS 9
2026-03-17 14:41:08.121096 | localhost | Provider: ibm-bm4-nodepool
2026-03-17 14:41:08.121113 | localhost | Region: regionOne
2026-03-17 14:41:08.121130 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2026-03-17 14:41:08.121147 | localhost | Product Name: OpenStack Compute
2026-03-17 14:41:08.121164 | localhost | Interface IP: 192.168.26.112
2026-03-17 14:41:08.143006 | 
2026-03-17 14:41:08.143065 | PLAY [all]
2026-03-17 14:41:08.149284 | 
2026-03-17 14:41:08.149364 | TASK [Gather network facts]
2026-03-17 14:41:08.564149 | controller | ok
2026-03-17 14:41:08.577492 | 
2026-03-17 14:41:08.577575 | TASK [include_role : start-zuul-console]
2026-03-17 14:41:08.596400 | controller | ok
2026-03-17 14:41:08.606928 | 
2026-03-17 14:41:08.607000 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-17 14:41:08.969719 | controller | ok
2026-03-17 14:41:08.982625 | 
2026-03-17 14:41:08.982720 | TASK [include_role : add-build-sshkey]
2026-03-17 14:41:09.005648 | controller | ok
2026-03-17 14:41:09.018290 | 
2026-03-17 14:41:09.018385 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-17 14:41:09.204417 | controller -> localhost | ok
2026-03-17 14:41:09.209847 | 
2026-03-17 14:41:09.209910 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-17 14:41:09.229533 | controller | ok
2026-03-17 14:41:09.242060 | controller | included: /var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-17 14:41:09.247650 | 
2026-03-17 14:41:09.247714 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-17 14:41:09.667553 | controller -> localhost | Generating public/private rsa key pair.
2026-03-17 14:41:09.667754 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/e24528d88cb14af9a915a1f3e4272715_id_rsa.
2026-03-17 14:41:09.667788 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/e24528d88cb14af9a915a1f3e4272715_id_rsa.pub.
2026-03-17 14:41:09.667810 | controller -> localhost | The key fingerprint is:
2026-03-17 14:41:09.667830 | controller -> localhost | SHA256:oowohu5zW+iPEYjh/Jww8h8hygWDkuLbGfW/M/hAscU zuul-build-sshkey
2026-03-17 14:41:09.667848 | controller -> localhost | The key's randomart image is:
2026-03-17 14:41:09.667865 | controller -> localhost | +---[RSA 3072]----+
2026-03-17 14:41:09.667883 | controller -> localhost | |                 |
2026-03-17 14:41:09.667899 | controller -> localhost | |..      .        |
2026-03-17 14:41:09.667917 | controller -> localhost | |*o   . . E       |
2026-03-17 14:41:09.667942 | controller -> localhost | |B.+ . . +        |
2026-03-17 14:41:09.667962 | controller -> localhost | |oB.+. .+S        |
2026-03-17 14:41:09.667978 | controller -> localhost | |++O+*o...        |
2026-03-17 14:41:09.667994 | controller -> localhost | |=+oO+. ...       |
2026-03-17 14:41:09.668013 | controller -> localhost | |+..o+. ..o.      |
2026-03-17 14:41:09.668029 | controller -> localhost | |.oo++.  .oo      |
2026-03-17 14:41:09.668045 | controller -> localhost | +----[SHA256]-----+
2026-03-17 14:41:09.668087 | controller -> localhost | ok: Runtime: 0:00:00.071976
2026-03-17 14:41:09.673666 | 
2026-03-17 14:41:09.673725 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-17 14:41:09.691187 | controller | ok
2026-03-17 14:41:09.700151 | controller | included: /var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-17 14:41:09.707564 | 
2026-03-17 14:41:09.707624 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-17 14:41:09.720948 | controller | skipping: Conditional result was False
2026-03-17 14:41:09.726179 | 
2026-03-17 14:41:09.726244 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-17 14:41:10.180726 | controller | changed
2026-03-17 14:41:10.185434 | 
2026-03-17 14:41:10.185499 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-17 14:41:10.437788 | controller | ok
2026-03-17 14:41:10.442467 | 
2026-03-17 14:41:10.442527 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-17 14:41:11.543912 | controller | changed
2026-03-17 14:41:11.549096 | 
2026-03-17 14:41:11.549194 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-17 14:41:12.668130 | controller | changed
2026-03-17 14:41:12.673056 | 
2026-03-17 14:41:12.673120 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-17 14:41:12.686979 | controller | skipping: Conditional result was False
2026-03-17 14:41:12.692416 | 
2026-03-17 14:41:12.692479 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-17 14:41:12.989271 | controller -> localhost | changed
2026-03-17 14:41:12.999248 | 
2026-03-17 14:41:12.999345 | TASK [add-build-sshkey : Add back temp key]
2026-03-17 14:41:13.225539 | controller -> localhost | Identity added: /var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/e24528d88cb14af9a915a1f3e4272715_id_rsa (zuul-build-sshkey)
2026-03-17 14:41:13.225765 | controller -> localhost | ok: Runtime: 0:00:00.010107
2026-03-17 14:41:13.231545 | 
2026-03-17 14:41:13.231608 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-17 14:41:13.587036 | controller | ok
2026-03-17 14:41:13.591541 | 
2026-03-17 14:41:13.591601 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-17 14:41:13.616406 | controller | skipping: Conditional result was False
2026-03-17 14:41:13.624527 | 
2026-03-17 14:41:13.624592 | TASK [include_role : validate-host]
2026-03-17 14:41:13.641788 | controller | ok
2026-03-17 14:41:13.661148 | 
2026-03-17 14:41:13.661220 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-17 14:41:13.689004 | controller | ok
2026-03-17 14:41:13.693912 | 
2026-03-17 14:41:13.693988 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-17 14:41:13.889678 | controller -> localhost | ok
2026-03-17 14:41:13.896057 | 
2026-03-17 14:41:13.896156 | TASK [validate-host : Collect information about the host]
2026-03-17 14:41:14.586610 | controller | ok
2026-03-17 14:41:14.594024 | 
2026-03-17 14:41:14.594083 | TASK [validate-host : Sanitize hostname]
2026-03-17 14:41:14.633720 | controller | ok
2026-03-17 14:41:14.638762 | 
2026-03-17 14:41:14.638829 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-17 14:41:15.017719 | controller -> localhost | changed
2026-03-17 14:41:15.023509 | 
2026-03-17 14:41:15.023576 | TASK [validate-host : Collect information about zuul worker]
2026-03-17 14:41:15.408405 | controller | ok
2026-03-17 14:41:15.413581 | 
2026-03-17 14:41:15.413673 | TASK [validate-host : Write out all zuul information for each host]
2026-03-17 14:41:15.775487 | controller -> localhost | changed
2026-03-17 14:41:15.784928 | 
2026-03-17 14:41:15.785008 | TASK [include_role : prepare-workspace-openshift]
2026-03-17 14:41:15.799362 | controller | skipping: Conditional result was False
2026-03-17 14:41:15.804244 | 
2026-03-17 14:41:15.804322 | TASK [include_role : remove-zuul-sshkey]
2026-03-17 14:41:15.828076 | controller | skipping: Conditional result was False
2026-03-17 14:41:15.833158 | 
2026-03-17 14:41:15.833227 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-17 14:41:16.078674 | controller | ok: "logs"
2026-03-17 14:41:16.079119 | controller | ok: All items complete
2026-03-17 14:41:16.079155 | 
2026-03-17 14:41:16.300524 | controller | ok: "artifacts"
2026-03-17 14:41:16.537523 | controller | ok: "docs"
2026-03-17 14:41:16.544491 | 
2026-03-17 14:41:16.544678 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-17 14:41:16.812002 | controller | changed: "logs"
2026-03-17 14:41:17.023879 | controller | changed: "artifacts"
2026-03-17 14:41:17.253638 | controller | changed: "docs"
2026-03-17 14:41:17.279191 | 
2026-03-17 14:41:17.279386 | PLAY RECAP
2026-03-17 14:41:17.279445 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-17 14:41:17.279475 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 14:41:17.279493 | 
2026-03-17 14:41:17.376286 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-17 14:41:17.376976 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-17 14:41:17.903531 | 
2026-03-17 14:41:17.903672 | PLAY [all]
2026-03-17 14:41:17.921595 | 
2026-03-17 14:41:17.921670 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-17 14:41:17.970714 | controller | ok
2026-03-17 14:41:17.975597 | 
2026-03-17 14:41:17.975675 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-17 14:41:18.390969 | controller | changed
2026-03-17 14:41:18.397200 | 
2026-03-17 14:41:18.397280 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-17 14:41:19.680277 | controller | changed
2026-03-17 14:41:19.690158 | 
2026-03-17 14:41:19.690258 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-17 14:41:20.120239 | controller | changed:
2026-03-17 14:41:20.120451 | controller | {
2026-03-17 14:41:20.120487 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-17 14:41:20.120518 | controller | }
2026-03-17 14:41:20.403115 | controller | changed:
2026-03-17 14:41:20.403192 | controller | {
2026-03-17 14:41:20.403221 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-17 14:41:20.403242 | controller | }
2026-03-17 14:41:20.693455 | controller | changed:
2026-03-17 14:41:20.693657 | controller | {
2026-03-17 14:41:20.693684 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-17 14:41:20.693706 | controller | }
2026-03-17 14:41:20.978713 | controller | changed:
2026-03-17 14:41:20.979010 | controller | {
2026-03-17 14:41:20.979045 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-17 14:41:20.979074 | controller | }
2026-03-17 14:41:21.251839 | controller | changed:
2026-03-17 14:41:21.252029 | controller | {
2026-03-17 14:41:21.252058 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-17 14:41:21.252080 | controller | }
2026-03-17 14:41:21.533001 | controller | changed:
2026-03-17 14:41:21.533128 | controller | {
2026-03-17 14:41:21.533156 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-17 14:41:21.533176 | controller | }
2026-03-17 14:41:21.822508 | controller | changed:
2026-03-17 14:41:21.822617 | controller | {
2026-03-17 14:41:21.822649 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-03-17 14:41:21.822671 | controller | }
2026-03-17 14:41:22.118238 | controller | changed:
2026-03-17 14:41:22.118372 | controller | {
2026-03-17 14:41:22.118402 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-03-17 14:41:22.118423 | controller | }
2026-03-17 14:41:22.405727 | controller | changed:
2026-03-17 14:41:22.405808 | controller | {
2026-03-17 14:41:22.405833 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-17 14:41:22.405852 | controller | }
2026-03-17 14:41:22.714133 | controller | changed:
2026-03-17 14:41:22.714228 | controller | {
2026-03-17 14:41:22.714255 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-17 14:41:22.714276 | controller | }
2026-03-17 14:41:22.973822 | controller | changed:
2026-03-17 14:41:22.973878 | controller | {
2026-03-17 14:41:22.973904 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-17 14:41:22.973925 | controller | }
2026-03-17 14:41:23.256155 | controller | changed:
2026-03-17 14:41:23.256259 | controller | {
2026-03-17 14:41:23.256285 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-17 14:41:23.256377 | controller | }
2026-03-17 14:41:23.542095 | controller | changed:
2026-03-17 14:41:23.542155 | controller | {
2026-03-17 14:41:23.542187 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-17 14:41:23.542212 | controller | }
2026-03-17 14:41:23.814525 | controller | changed:
2026-03-17 14:41:23.814573 | controller | {
2026-03-17 14:41:23.814597 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-17 14:41:23.814616 | controller | }
2026-03-17 14:41:24.097877 | controller | changed:
2026-03-17 14:41:24.097953 | controller | {
2026-03-17 14:41:24.097981 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-17 14:41:24.098002 | controller | }
2026-03-17 14:41:24.374823 | controller | changed:
2026-03-17 14:41:24.375021 | controller | {
2026-03-17 14:41:24.375049 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-17 14:41:24.375069 | controller | }
2026-03-17 14:41:24.654562 | controller | changed:
2026-03-17 14:41:24.654689 | controller | {
2026-03-17 14:41:24.654717 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-03-17 14:41:24.654740 | controller | }
2026-03-17 14:41:24.932943 | controller | changed:
2026-03-17 14:41:24.933006 | controller | {
2026-03-17 14:41:24.933030 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-03-17 14:41:24.933059 | controller | }
2026-03-17 14:41:25.220123 | controller | changed:
2026-03-17 14:41:25.220217 | controller | {
2026-03-17 14:41:25.220241 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-17 14:41:25.220259 | controller | }
2026-03-17 14:41:25.498053 | controller | changed:
2026-03-17 14:41:25.498146 | controller | {
2026-03-17 14:41:25.498171 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-17 14:41:25.498190 | controller | }
2026-03-17 14:41:25.777081 | controller | changed:
2026-03-17 14:41:25.777166 | controller | {
2026-03-17 14:41:25.777198 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-17 14:41:25.777223 | controller | }
2026-03-17 14:41:26.085136 | controller | changed:
2026-03-17 14:41:26.085250 | controller | {
2026-03-17 14:41:26.085277 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-17 14:41:26.085319 | controller | }
2026-03-17 14:41:26.369319 | controller | changed:
2026-03-17 14:41:26.369419 | controller | {
2026-03-17 14:41:26.369447 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-03-17 14:41:26.369470 | controller | }
2026-03-17 14:41:26.648776 | controller | changed:
2026-03-17 14:41:26.648876 | controller | {
2026-03-17 14:41:26.648906 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-17 14:41:26.648956 | controller | }
2026-03-17 14:41:26.946009 | controller | changed:
2026-03-17 14:41:26.946068 | controller | {
2026-03-17 14:41:26.946093 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-17 14:41:26.946112 | controller | }
2026-03-17 14:41:27.219626 | controller | changed:
2026-03-17 14:41:27.219774 | controller | {
2026-03-17 14:41:27.219810 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-17 14:41:27.219833 | controller | }
2026-03-17 14:41:27.245636 | 
2026-03-17 14:41:27.245733 | TASK [Set timezone to UTC]
2026-03-17 14:41:27.742066 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-17 14:41:27.746867 | 
2026-03-17 14:41:27.746962 | TASK [Create nodepool directory]
2026-03-17 14:41:28.050828 | controller | changed
2026-03-17 14:41:28.057803 | 
2026-03-17 14:41:28.057886 | TASK [Create nodepool sub_nodes file]
2026-03-17 14:41:29.040384 | controller | changed
2026-03-17 14:41:29.045289 | 
2026-03-17 14:41:29.045388 | TASK [Create nodepool sub_nodes_private file]
2026-03-17 14:41:30.066461 | controller | changed
2026-03-17 14:41:30.071480 | 
2026-03-17 14:41:30.071559 | LOOP [Populate nodepool sub_nodes file]
2026-03-17 14:41:30.107900 | 
2026-03-17 14:41:30.108039 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-17 14:41:30.137561 | 
2026-03-17 14:41:30.137695 | TASK [Create nodepool primary file]
2026-03-17 14:41:30.161549 | controller | skipping: Conditional result was False
2026-03-17 14:41:30.166921 | 
2026-03-17 14:41:30.167004 | TASK [Create nodepool node_private for this node]
2026-03-17 14:41:31.153189 | controller | changed
2026-03-17 14:41:31.159889 | 
2026-03-17 14:41:31.159978 | LOOP [Copy ssh keys to nodepool directory]
2026-03-17 14:41:31.538664 | controller | ok: Item: id_rsa Runtime: 0:00:00.004020
2026-03-17 14:41:31.538902 | 
2026-03-17 14:41:31.757151 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005214
2026-03-17 14:41:31.769427 | 
2026-03-17 14:41:31.769502 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-17 14:41:32.734356 | controller | changed
2026-03-17 14:41:32.739087 | 
2026-03-17 14:41:32.739146 | TASK [Validate sudoers config after edits]
2026-03-17 14:41:33.038853 | controller | /etc/sudoers: parsed OK
2026-03-17 14:41:33.038898 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-17 14:41:33.038907 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-17 14:41:33.038912 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-17 14:41:33.266365 | controller | ok: Runtime: 0:00:00.004998
2026-03-17 14:41:33.271712 | 
2026-03-17 14:41:33.271772 | TASK [Show the environment passed in to job shell scripts]
2026-03-17 14:41:33.558796 | controller | SHELL=/bin/bash
2026-03-17 14:41:33.558846 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-17 14:41:33.558856 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-17 14:41:33.558863 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/29/3129/72a96c7b2306d286518aebc09ed8ce2fcd0c3042
2026-03-17 14:41:33.558869 | controller | PWD=/home/zuul
2026-03-17 14:41:33.558875 | controller | ZUUL_PIPELINE=github-check
2026-03-17 14:41:33.558880 | controller | LOGNAME=zuul
2026-03-17 14:41:33.558885 | controller | XDG_SESSION_TYPE=tty
2026-03-17 14:41:33.558918 | controller | _=/usr/bin/env
2026-03-17 14:41:33.558926 | controller | MOTD_SHOWN=pam
2026-03-17 14:41:33.558931 | controller | HOME=/home/zuul
2026-03-17 14:41:33.558937 | controller | LANG=en_US.UTF-8
2026-03-17 14:41:33.558942 | controller | SSH_CONNECTION=192.168.26.12 59078 192.168.26.112 22
2026-03-17 14:41:33.558947 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-03-17 14:41:33.558956 | controller | ZUUL_CHANGE_IDS=3129,72a96c7b2306d286518aebc09ed8ce2fcd0c3042
2026-03-17 14:41:33.558961 | controller | WORKSPACE=/home/zuul/workspace
2026-03-17 14:41:33.558967 | controller | XDG_SESSION_CLASS=user
2026-03-17 14:41:33.558972 | controller | SELINUX_ROLE_REQUESTED=
2026-03-17 14:41:33.558977 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-17 14:41:33.558983 | controller | USER=zuul
2026-03-17 14:41:33.558988 | controller | ZUUL_VOTING=True
2026-03-17 14:41:33.558993 | controller | BUILD_TIMEOUT=5400000
2026-03-17 14:41:33.558998 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-17 14:41:33.559003 | controller | SHLVL=1
2026-03-17 14:41:33.559009 | controller | ZUUL_PATCHSET=72a96c7b2306d286518aebc09ed8ce2fcd0c3042
2026-03-17 14:41:33.559015 | controller | XDG_SESSION_ID=1
2026-03-17 14:41:33.559020 | controller | ZUUL_BRANCH=main
2026-03-17 14:41:33.559025 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-17 14:41:33.559031 | controller | SSH_CLIENT=192.168.26.12 59078 22
2026-03-17 14:41:33.559036 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-17 14:41:33.559042 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-17 14:41:33.559047 | controller | which_declare=declare -f
2026-03-17 14:41:33.559052 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-17 14:41:33.559058 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-17 14:41:33.559063 | controller | ZUUL_CHANGE=3129
2026-03-17 14:41:33.559068 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-17 14:41:33.559074 | controller | ZUUL_UUID=e24528d88cb14af9a915a1f3e4272715
2026-03-17 14:41:33.559079 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-17 14:41:33.559084 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-17 14:41:33.559090 | controller | }
2026-03-17 14:41:33.798705 | controller | ok: Runtime: 0:00:00.005273
2026-03-17 14:41:33.803803 | 
2026-03-17 14:41:33.803873 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-17 14:41:33.818093 | controller | skipping: Conditional result was False
2026-03-17 14:41:33.823489 | 
2026-03-17 14:41:33.823556 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-17 14:41:34.369830 | controller | skipping: Conditional result was False
2026-03-17 14:41:34.376971 | 
2026-03-17 14:41:34.377045 | TASK [Ensure legacy workspace directory]
2026-03-17 14:41:34.631463 | controller | changed
2026-03-17 14:41:34.649801 | 
2026-03-17 14:41:34.649880 | PLAY RECAP
2026-03-17 14:41:34.649946 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-17 14:41:34.649974 | 
2026-03-17 14:41:34.725961 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-17 14:41:34.726901 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-17 14:41:35.225493 | 
2026-03-17 14:41:35.225590 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-17 14:41:35.244640 | 
2026-03-17 14:41:35.244715 | TASK [Create zuul-output directory]
2026-03-17 14:41:35.599316 | controller | changed
2026-03-17 14:41:35.604440 | 
2026-03-17 14:41:35.604517 | TASK [Slurp Zuul inventory test]
2026-03-17 14:41:35.861643 | controller -> localhost | ok
2026-03-17 14:41:35.867856 | 
2026-03-17 14:41:35.867937 | TASK [Save zuul inventory]
2026-03-17 14:41:37.070704 | controller | changed
2026-03-17 14:41:37.076371 | 
2026-03-17 14:41:37.076459 | TASK [Save zuul vars without the change_message]
2026-03-17 14:41:38.180766 | controller | changed
2026-03-17 14:41:38.198201 | 
2026-03-17 14:41:38.198271 | PLAY RECAP
2026-03-17 14:41:38.198343 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 14:41:38.198368 | 
2026-03-17 14:41:38.266482 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-17 14:41:38.267367 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-17 14:41:38.788002 | 
2026-03-17 14:41:38.788104 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-17 14:41:38.806720 | 
2026-03-17 14:41:38.806786 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-17 14:41:38.827018 | controller | ok
2026-03-17 14:41:38.843883 | 
2026-03-17 14:41:38.843973 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-17 14:41:38.868896 | controller | skipping: Conditional result was False
2026-03-17 14:41:38.874719 | 
2026-03-17 14:41:38.874790 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-17 14:41:39.247599 | controller | ok
2026-03-17 14:41:39.252920 | 
2026-03-17 14:41:39.253007 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-17 14:41:40.288726 | controller | ok
2026-03-17 14:41:40.297864 | 
2026-03-17 14:41:40.297944 | TASK [Prepare workspace]
2026-03-17 14:41:40.317461 | controller | ok
2026-03-17 14:41:40.333698 | 
2026-03-17 14:41:40.333768 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-17 14:41:40.719478 | controller | ok
2026-03-17 14:41:40.725119 | 
2026-03-17 14:41:40.725185 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-17 14:41:43.125258 | controller | Output suppressed because no_log was given
2026-03-17 14:41:43.134512 | 
2026-03-17 14:41:43.134589 | LOOP [Create zuul-output directory]
2026-03-17 14:41:43.401290 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-17 14:41:43.624864 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-17 14:41:43.635823 | 
2026-03-17 14:41:43.636018 | TASK [Install required packages]
2026-03-17 14:42:00.078893 | controller | ok: Nothing to do
2026-03-17 14:42:00.083446 | 
2026-03-17 14:42:00.083504 | TASK [Install venv]
2026-03-17 14:42:59.123778 | controller | changed
2026-03-17 14:42:59.148692 | 
2026-03-17 14:42:59.148776 | PLAY RECAP
2026-03-17 14:42:59.148821 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-17 14:42:59.148844 | 
2026-03-17 14:42:59.221532 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-17 14:42:59.222514 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-17 14:42:59.725033 | 
2026-03-17 14:42:59.725129 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-17 14:42:59.744974 | 
2026-03-17 14:42:59.745040 | TASK [Gather required facts]
2026-03-17 14:43:00.261823 | controller | ok
2026-03-17 14:43:00.267377 | 
2026-03-17 14:43:00.267454 | TASK [Load environment var if instructed to]
2026-03-17 14:43:00.291328 | controller | skipping: Conditional result was False
2026-03-17 14:43:00.296692 | 
2026-03-17 14:43:00.296757 | TASK [Ensure group_vars dir exists]
2026-03-17 14:43:00.650380 | controller | ok
2026-03-17 14:43:00.656142 | 
2026-03-17 14:43:00.656209 | TASK [Print related variables]
2026-03-17 14:43:00.685721 | controller | ok:
2026-03-17 14:43:00.685896 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-03-17 14:43:00.685944 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer
2026-03-17 14:43:00.690552 | 
2026-03-17 14:43:00.690616 | TASK [Run molecule]
2026-03-17 14:43:01.705433 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-03-17 14:43:01.969146 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-17 14:43:19.851489 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.851924 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.852361 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.852792 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.853253 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+a76b21d8 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0+a76b21d8 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.853681 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.854143 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.854602 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.855014 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.855427 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.855873 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.856286 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.856706 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.857147 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.857566 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-17 14:43:19.867411 | controller | INFO     Running crc_layout > prepare
2026-03-17 14:43:20.522071 | controller |
2026-03-17 14:43:20.522287 | controller | PLAY [Prepare] *****************************************************************
2026-03-17 14:43:20.522400 | controller |
2026-03-17 14:43:20.522636 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 14:43:20.522659 | controller | Tuesday 17 March 2026  14:43:20 +0000 (0:00:00.018)       0:00:00.018 *********
2026-03-17 14:43:21.321823 | controller | ok: [instance]
2026-03-17 14:43:21.321905 | controller |
2026-03-17 14:43:21.322028 | controller | TASK [Create custom basedir] ***************************************************
2026-03-17 14:43:21.322145 | controller | Tuesday 17 March 2026  14:43:21 +0000 (0:00:00.800)       0:00:00.818 *********
2026-03-17 14:43:21.617205 | controller | changed: [instance]
2026-03-17 14:43:21.617292 | controller |
2026-03-17 14:43:21.617357 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-03-17 14:43:21.617443 | controller | Tuesday 17 March 2026  14:43:21 +0000 (0:00:00.295)       0:00:01.114 *********
2026-03-17 14:43:21.635546 | controller | skipping: [instance]
2026-03-17 14:43:21.635639 | controller |
2026-03-17 14:43:21.635743 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-03-17 14:43:21.635907 | controller | Tuesday 17 March 2026  14:43:21 +0000 (0:00:00.018)       0:00:01.132 *********
2026-03-17 14:43:21.674460 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-03-17 14:43:21.674608 | controller |
2026-03-17 14:43:21.674637 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-03-17 14:43:21.674723 | controller | Tuesday 17 March 2026  14:43:21 +0000 (0:00:00.038)       0:00:01.171 *********
2026-03-17 14:43:21.933194 | controller | ok: [instance]
2026-03-17 14:43:21.933283 | controller |
2026-03-17 14:43:21.933378 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-03-17 14:43:21.933471 | controller | Tuesday 17 March 2026  14:43:21 +0000 (0:00:00.258)       0:00:01.430 *********
2026-03-17 14:43:22.132686 | controller | ok: [instance]
2026-03-17 14:43:22.132729 | controller |
2026-03-17 14:43:22.132835 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-03-17 14:43:22.132971 | controller | Tuesday 17 March 2026  14:43:22 +0000 (0:00:00.199)       0:00:01.629 *********
2026-03-17 14:43:22.789956 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-03-17 14:43:22.790025 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-03-17 14:43:22.790115 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-03-17 14:43:22.790199 | controller |
2026-03-17 14:43:22.790275 | controller | TASK [Deploy repo-setup] *******************************************************
2026-03-17 14:43:22.790364 | controller | Tuesday 17 March 2026  14:43:22 +0000 (0:00:00.657)       0:00:02.286 *********
2026-03-17 14:43:22.847337 | controller |
2026-03-17 14:43:22.847459 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-03-17 14:43:22.847578 | controller | Tuesday 17 March 2026  14:43:22 +0000 (0:00:00.057)       0:00:02.344 *********
2026-03-17 14:43:23.334712 | controller | changed: [instance] => (item=tmp)
2026-03-17 14:43:23.334786 | controller | changed: [instance] => (item=artifacts/repositories)
2026-03-17 14:43:23.334902 | controller | changed: [instance] => (item=venv/repo_setup)
2026-03-17 14:43:23.334988 | controller |
2026-03-17 14:43:23.335082 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-03-17 14:43:23.335163 | controller | Tuesday 17 March 2026  14:43:23 +0000 (0:00:00.487)       0:00:02.831 *********
2026-03-17 14:43:24.130452 | controller | ok: [instance]
2026-03-17 14:43:26.218935 | controller |
2026-03-17 14:43:26.218975 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-03-17 14:43:26.218984 | controller | Tuesday 17 March 2026  14:43:24 +0000 (0:00:00.795)       0:00:03.627 *********
2026-03-17 14:43:26.218996 | controller | changed: [instance]
2026-03-17 14:43:33.386086 | controller |
2026-03-17 14:43:33.386162 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-03-17 14:43:33.386171 | controller | Tuesday 17 March 2026  14:43:26 +0000 (0:00:02.088)       0:00:05.715 *********
2026-03-17 14:43:33.386184 | controller | changed: [instance]
2026-03-17 14:43:34.049781 | controller |
2026-03-17 14:43:34.049878 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-03-17 14:43:34.049894 | controller | Tuesday 17 March 2026  14:43:33 +0000 (0:00:07.166)       0:00:12.881 *********
2026-03-17 14:43:34.049907 | controller | changed: [instance]
2026-03-17 14:43:34.076353 | controller |
2026-03-17 14:43:34.076378 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-03-17 14:43:34.076386 | controller | Tuesday 17 March 2026  14:43:34 +0000 (0:00:00.663)       0:00:13.545 *********
2026-03-17 14:43:34.076396 | controller | skipping: [instance]
2026-03-17 14:43:34.892870 | controller |
2026-03-17 14:43:34.892902 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-03-17 14:43:34.892910 | controller | Tuesday 17 March 2026  14:43:34 +0000 (0:00:00.027)       0:00:13.572 *********
2026-03-17 14:43:34.892919 | controller | changed: [instance]
2026-03-17 14:43:34.892944 | controller |
2026-03-17 14:43:34.893153 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-03-17 14:43:34.930210 | controller | Tuesday 17 March 2026  14:43:34 +0000 (0:00:00.816)       0:00:14.389 *********
2026-03-17 14:43:34.930252 | controller | skipping: [instance]
2026-03-17 14:43:34.968692 | controller |
2026-03-17 14:43:34.968725 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-03-17 14:43:34.968733 | controller | Tuesday 17 March 2026  14:43:34 +0000 (0:00:00.037)       0:00:14.426 *********
2026-03-17 14:43:34.968743 | controller | skipping: [instance]
2026-03-17 14:43:35.007628 | controller |
2026-03-17 14:43:35.007662 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-03-17 14:43:35.007670 | controller | Tuesday 17 March 2026  14:43:34 +0000 (0:00:00.037)       0:00:14.464 *********
2026-03-17 14:43:35.007680 | controller | skipping: [instance]
2026-03-17 14:43:35.547418 | controller |
2026-03-17 14:43:35.547459 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-03-17 14:43:35.547466 | controller | Tuesday 17 March 2026  14:43:35 +0000 (0:00:00.039)       0:00:14.504 *********
2026-03-17 14:43:35.547476 | controller | changed: [instance]
2026-03-17 14:43:35.983868 | controller |
2026-03-17 14:43:35.983896 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-03-17 14:43:35.983903 | controller | Tuesday 17 March 2026  14:43:35 +0000 (0:00:00.539)       0:00:15.043 *********
2026-03-17 14:43:35.983912 | controller | changed: [instance]
2026-03-17 14:43:35.984046 | controller |
2026-03-17 14:43:35.984056 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-03-17 14:43:35.984064 | controller | Tuesday 17 March 2026  14:43:35 +0000 (0:00:00.437)       0:00:15.480 *********
2026-03-17 14:43:36.013906 | controller | skipping: [instance]
2026-03-17 14:43:36.013941 | controller |
2026-03-17 14:43:36.013949 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-03-17 14:43:36.013957 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.030)       0:00:15.510 *********
2026-03-17 14:43:36.042981 | controller | skipping: [instance]
2026-03-17 14:43:36.071385 | controller |
2026-03-17 14:43:36.071406 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-03-17 14:43:36.071413 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.029)       0:00:15.539 *********
2026-03-17 14:43:36.071422 | controller | skipping: [instance]
2026-03-17 14:43:36.107198 | controller |
2026-03-17 14:43:36.107214 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-03-17 14:43:36.107221 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.028)       0:00:15.568 *********
2026-03-17 14:43:36.107229 | controller | ok: [instance]
2026-03-17 14:43:36.133388 | controller |
2026-03-17 14:43:36.133405 | controller | TASK [repo_setup : Create download directory] **********************************
2026-03-17 14:43:36.133412 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.035)       0:00:15.604 *********
2026-03-17 14:43:36.133425 | controller | skipping: [instance]
2026-03-17 14:43:36.159955 | controller |
2026-03-17 14:43:36.159992 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-03-17 14:43:36.160001 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.026)       0:00:15.630 *********
2026-03-17 14:43:36.160013 | controller | skipping: [instance]
2026-03-17 14:43:36.200871 | controller |
2026-03-17 14:43:36.200893 | controller | TASK [Download the RPM] ********************************************************
2026-03-17 14:43:36.200900 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.026)       0:00:15.656 *********
2026-03-17 14:43:36.200909 | controller | skipping: [instance]
2026-03-17 14:43:36.227025 | controller |
2026-03-17 14:43:36.227048 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-03-17 14:43:36.227056 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.040)       0:00:15.697 *********
2026-03-17 14:43:36.227066 | controller | skipping: [instance]
2026-03-17 14:43:36.227134 | controller |
2026-03-17 14:43:36.227254 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-03-17 14:43:36.227370 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.026)       0:00:15.724 *********
2026-03-17 14:43:36.252965 | controller | skipping: [instance]
2026-03-17 14:43:36.253040 | controller |
2026-03-17 14:43:36.253194 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-03-17 14:43:36.253310 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.025)       0:00:15.750 *********
2026-03-17 14:43:36.280837 | controller | skipping: [instance]
2026-03-17 14:43:36.280912 | controller |
2026-03-17 14:43:36.281108 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-03-17 14:43:36.281174 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.027)       0:00:15.777 *********
2026-03-17 14:43:36.306318 | controller | skipping: [instance]
2026-03-17 14:43:36.306393 | controller |
2026-03-17 14:43:36.306580 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-03-17 14:43:36.306660 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.025)       0:00:15.803 *********
2026-03-17 14:43:36.481918 | controller | ok: [instance]
2026-03-17 14:43:36.693883 | controller |
2026-03-17 14:43:36.693915 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-03-17 14:43:36.693922 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.175)       0:00:15.978 *********
2026-03-17 14:43:36.693932 | controller | changed: [instance]
2026-03-17 14:43:36.694301 | controller |
2026-03-17 14:43:36.903863 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-03-17 14:43:36.903888 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.212)       0:00:16.190 *********
2026-03-17 14:43:36.903898 | controller | changed: [instance]
2026-03-17 14:43:36.904078 | controller |
2026-03-17 14:43:36.904091 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-03-17 14:43:36.924455 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.209)       0:00:16.400 *********
2026-03-17 14:43:36.924486 | controller | skipping: [instance]
2026-03-17 14:43:36.945222 | controller |
2026-03-17 14:43:36.945238 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-03-17 14:43:36.945244 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.020)       0:00:16.421 *********
2026-03-17 14:43:36.945252 | controller | skipping: [instance]
2026-03-17 14:43:36.966212 | controller |
2026-03-17 14:43:36.966230 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-03-17 14:43:36.966238 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.021)       0:00:16.442 *********
2026-03-17 14:43:36.966248 | controller | skipping: [instance]
2026-03-17 14:43:36.988369 | controller |
2026-03-17 14:43:36.988387 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-03-17 14:43:36.988395 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.021)       0:00:16.463 *********
2026-03-17 14:43:36.988403 | controller | skipping: [instance]
2026-03-17 14:43:37.009976 | controller |
2026-03-17 14:43:37.010023 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-03-17 14:43:37.010046 | controller | Tuesday 17 March 2026  14:43:36 +0000 (0:00:00.022)       0:00:16.485 *********
2026-03-17 14:43:37.010064 | controller | skipping: [instance]
2026-03-17 14:43:37.010115 | controller |
2026-03-17 14:43:37.010127 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-03-17 14:43:37.010140 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.021)       0:00:16.506 *********
2026-03-17 14:43:37.030575 | controller | skipping: [instance]
2026-03-17 14:43:37.030815 | controller |
2026-03-17 14:43:37.031040 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-03-17 14:43:37.031207 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.020)       0:00:16.527 *********
2026-03-17 14:43:37.047958 | controller | skipping: [instance]
2026-03-17 14:43:37.048080 | controller |
2026-03-17 14:43:37.048228 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-03-17 14:43:37.048312 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.017)       0:00:16.545 *********
2026-03-17 14:43:37.076847 | controller | skipping: [instance]
2026-03-17 14:43:37.076948 | controller |
2026-03-17 14:43:37.077132 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-03-17 14:43:37.077206 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.028)       0:00:16.574 *********
2026-03-17 14:43:37.092382 | controller | skipping: [instance]
2026-03-17 14:43:37.092450 | controller |
2026-03-17 14:43:37.092644 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-03-17 14:43:37.092716 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.015)       0:00:16.589 *********
2026-03-17 14:43:37.113775 | controller | skipping: [instance]
2026-03-17 14:43:37.113875 | controller |
2026-03-17 14:43:37.114017 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-03-17 14:43:37.114083 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.021)       0:00:16.611 *********
2026-03-17 14:43:37.142183 | controller | skipping: [instance]
2026-03-17 14:43:37.170760 | controller |
2026-03-17 14:43:37.170821 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-03-17 14:43:37.170840 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.027)       0:00:16.639 *********
2026-03-17 14:43:37.170857 | controller | skipping: [instance]
2026-03-17 14:43:37.171061 | controller |
2026-03-17 14:43:37.171097 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-03-17 14:43:37.171142 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.028)       0:00:16.668 *********
2026-03-17 14:43:37.198507 | controller | skipping: [instance]
2026-03-17 14:43:37.198732 | controller |
2026-03-17 14:43:37.198969 | controller | TASK [test_deps : Install additional packages] *********************************
2026-03-17 14:43:37.199179 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.027)       0:00:16.695 *********
2026-03-17 14:43:37.225632 | controller | skipping: [instance]
2026-03-17 14:43:37.225864 | controller |
2026-03-17 14:43:37.226124 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-03-17 14:43:37.226306 | controller | Tuesday 17 March 2026  14:43:37 +0000 (0:00:00.026)       0:00:16.722 *********
2026-03-17 14:44:20.610713 | controller | ok: [instance]
2026-03-17 14:44:21.793876 | controller |
2026-03-17 14:44:21.793917 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-03-17 14:44:21.793927 | controller | Tuesday 17 March 2026  14:44:20 +0000 (0:00:43.384)       0:01:00.106 *********
2026-03-17 14:44:21.793941 | controller | ok: [instance]
2026-03-17 14:44:21.822845 | controller |
2026-03-17 14:44:21.822885 | controller | TASK [test_deps : Install extra packages] **************************************
2026-03-17 14:44:21.822900 | controller | Tuesday 17 March 2026  14:44:21 +0000 (0:00:01.183)       0:01:01.290 *********
2026-03-17 14:44:21.822917 | controller | skipping: [instance]
2026-03-17 14:44:21.862956 | controller |
2026-03-17 14:44:21.862976 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2026-03-17 14:44:21.862985 | controller | Tuesday 17 March 2026  14:44:21 +0000 (0:00:00.029)       0:01:01.319 *********
2026-03-17 14:44:21.862995 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-03-17 14:44:21.879943 | controller |
2026-03-17 14:44:21.879968 | controller | TASK [ci_setup : List packages to install] *************************************
2026-03-17 14:44:21.879977 | controller | Tuesday 17 March 2026  14:44:21 +0000 (0:00:00.039)       0:01:01.359 *********
2026-03-17 14:44:21.879988 | controller | ok: [instance] =>
2026-03-17 14:44:21.880017 | controller |   cifmw_ci_setup_packages:
2026-03-17 14:44:21.880024 | controller |   - bash-completion
2026-03-17 14:44:21.880030 | controller |   - ca-certificates
2026-03-17 14:44:21.880039 | controller |   - git-core
2026-03-17 14:44:21.880608 | controller |   - make
2026-03-17 14:44:26.272497 | controller |   - tar
2026-03-17 14:44:26.272533 | controller |   - tmux
2026-03-17 14:44:26.272541 | controller |   - python3-pip
2026-03-17 14:44:26.272547 | controller |
2026-03-17 14:44:26.272553 | controller | TASK [ci_setup : Install needed packages] **************************************
2026-03-17 14:44:26.272564 | controller | Tuesday 17 March 2026  14:44:21 +0000 (0:00:00.016)       0:01:01.376 *********
2026-03-17 14:44:26.272575 | controller | changed: [instance]
2026-03-17 14:44:26.796181 | controller |
2026-03-17 14:44:26.796222 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2026-03-17 14:44:26.796230 | controller | Tuesday 17 March 2026  14:44:26 +0000 (0:00:04.392)       0:01:05.769 *********
2026-03-17 14:44:26.796242 | controller | ok: [instance]
2026-03-17 14:44:26.993833 | controller |
2026-03-17 14:44:26.993870 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2026-03-17 14:44:26.993880 | controller | Tuesday 17 March 2026  14:44:26 +0000 (0:00:00.523)       0:01:06.293 *********
2026-03-17 14:44:26.993893 | controller | changed: [instance]
2026-03-17 14:44:32.870916 | controller |
2026-03-17 14:44:32.870951 | controller | TASK [ci_setup : Install openshift client] *************************************
2026-03-17 14:44:32.870961 | controller | Tuesday 17 March 2026  14:44:26 +0000 (0:00:00.197)       0:01:06.490 *********
2026-03-17 14:44:32.870973 | controller | changed: [instance]
2026-03-17 14:44:32.871006 | controller |
2026-03-17 14:44:32.871016 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2026-03-17 14:44:32.904711 | controller | Tuesday 17 March 2026  14:44:32 +0000 (0:00:05.876)       0:01:12.367 *********
2026-03-17 14:44:32.904765 | controller | ok: [instance]
2026-03-17 14:44:33.159870 | controller |
2026-03-17 14:44:33.159903 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-03-17 14:44:33.159911 | controller | Tuesday 17 March 2026  14:44:32 +0000 (0:00:00.033)       0:01:12.401 *********
2026-03-17 14:44:33.159922 | controller | changed: [instance]
2026-03-17 14:44:33.160139 | controller |
2026-03-17 14:44:33.160168 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2026-03-17 14:44:33.411697 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.255)       0:01:12.656 *********
2026-03-17 14:44:33.411717 | controller | changed: [instance]
2026-03-17 14:44:33.427752 | controller |
2026-03-17 14:44:33.427774 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2026-03-17 14:44:33.427784 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.251)       0:01:12.908 *********
2026-03-17 14:44:33.427795 | controller | skipping: [instance]
2026-03-17 14:44:33.442643 | controller |
2026-03-17 14:44:33.442665 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2026-03-17 14:44:33.442674 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.015)       0:01:12.924 *********
2026-03-17 14:44:33.442685 | controller | skipping: [instance]
2026-03-17 14:44:33.458451 | controller |
2026-03-17 14:44:33.458485 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2026-03-17 14:44:33.458493 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.014)       0:01:12.939 *********
2026-03-17 14:44:33.458503 | controller | skipping: [instance]
2026-03-17 14:44:33.473783 | controller |
2026-03-17 14:44:33.473841 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2026-03-17 14:44:33.473858 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.015)       0:01:12.954 *********
2026-03-17 14:44:33.473874 | controller | skipping: [instance]
2026-03-17 14:44:33.488965 | controller |
2026-03-17 14:44:33.488999 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2026-03-17 14:44:33.489021 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.015)       0:01:12.970 *********
2026-03-17 14:44:33.489037 | controller | skipping: [instance]
2026-03-17 14:44:33.489184 | controller |
2026-03-17 14:44:33.489204 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2026-03-17 14:44:33.507887 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.015)       0:01:12.985 *********
2026-03-17 14:44:33.507922 | controller | skipping: [instance]
2026-03-17 14:44:33.508230 | controller |
2026-03-17 14:44:34.383892 | controller | TASK [ci_setup : Manage directories] *******************************************
2026-03-17 14:44:34.383922 | controller | Tuesday 17 March 2026  14:44:33 +0000 (0:00:00.019)       0:01:13.004 *********
2026-03-17 14:44:34.383933 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2026-03-17 14:44:34.383957 | controller | changed: [instance] => (item=/opt/basedir/logs)
2026-03-17 14:44:34.384491 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2026-03-17 14:44:34.696043 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2026-03-17 14:44:34.696102 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2026-03-17 14:44:34.696116 | controller |
2026-03-17 14:44:34.696127 | controller | TASK [Create secret file] ******************************************************
2026-03-17 14:44:34.696138 | controller | Tuesday 17 March 2026  14:44:34 +0000 (0:00:00.876)       0:01:13.881 *********
2026-03-17 14:44:34.696157 | controller | changed: [instance]
2026-03-17 14:44:34.696200 | controller |
2026-03-17 14:44:34.696213 | controller | PLAY RECAP *********************************************************************
2026-03-17 14:44:34.696223 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2026-03-17 14:44:34.696233 | controller |
2026-03-17 14:44:34.696258 | controller | Tuesday 17 March 2026  14:44:34 +0000 (0:00:00.311)       0:01:14.192 *********
2026-03-17 14:44:34.696272 | controller | ===============================================================================
2026-03-17 14:44:34.696308 | controller | test_deps : Install selinux python libs -------------------------------- 43.38s
2026-03-17 14:44:34.696319 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.17s
2026-03-17 14:44:34.696332 | controller | ci_setup : Install openshift client ------------------------------------- 5.88s
2026-03-17 14:44:34.696366 | controller | ci_setup : Install needed packages -------------------------------------- 4.39s
2026-03-17 14:44:34.697617 | controller | repo_setup : Get repo-setup repository ---------------------------------- 2.09s
2026-03-17 14:44:34.741020 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2026-03-17 14:44:34.741039 | controller | ci_setup : Manage directories ------------------------------------------- 0.88s
2026-03-17 14:44:34.741046 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2026-03-17 14:44:34.741051 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2026-03-17 14:44:34.741057 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.80s
2026-03-17 14:44:34.741062 | controller | repo_setup : Install repo-setup package --------------------------------- 0.66s
2026-03-17 14:44:34.741067 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.66s
2026-03-17 14:44:34.741072 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2026-03-17 14:44:34.741077 | controller | ci_setup : Gather version of openshift client --------------------------- 0.52s
2026-03-17 14:44:34.741082 | controller | repo_setup : Ensure directories are present ----------------------------- 0.49s
2026-03-17 14:44:34.741086 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2026-03-17 14:44:34.741091 | controller | Create secret file ------------------------------------------------------ 0.31s
2026-03-17 14:44:34.741096 | controller | Create custom basedir --------------------------------------------------- 0.30s
2026-03-17 14:44:34.741101 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.26s
2026-03-17 14:44:34.741111 | controller | ci_setup : Create completion file --------------------------------------- 0.26s
2026-03-17 14:44:34.741120 | controller | INFO     Running crc_layout > converge
2026-03-17 14:44:35.281983 | controller |
2026-03-17 14:44:35.282199 | controller | PLAY [Converge] ****************************************************************
2026-03-17 14:44:35.282356 | controller |
2026-03-17 14:44:35.282491 | controller | TASK [Gathering Facts] *********************************************************
2026-03-17 14:44:35.282634 | controller | Tuesday 17 March 2026  14:44:35 +0000 (0:00:00.026)       0:00:00.026 *********
2026-03-17 14:44:36.046787 | controller | ok: [instance]
2026-03-17 14:44:36.066149 | controller |
2026-03-17 14:44:36.066199 | controller | TASK [Load networking definition from scenario file] ***************************
2026-03-17 14:44:36.066214 | controller | Tuesday 17 March 2026  14:44:36 +0000 (0:00:00.764)       0:00:00.791 *********
2026-03-17 14:44:36.066234 | controller | ok: [instance]
2026-03-17 14:44:36.081413 | controller |
2026-03-17 14:44:36.081436 | controller | TASK [discover_latest_image : Set the discovered images dict to empty] *********
2026-03-17 14:44:36.081445 | controller | Tuesday 17 March 2026  14:44:36 +0000 (0:00:00.019)       0:00:00.810 *********
2026-03-17 14:44:36.081455 | controller | ok: [instance]
2026-03-17 14:44:36.116981 | controller |
2026-03-17 14:44:36.117025 | controller | TASK [discover_latest_image : Discover images] *********************************
2026-03-17 14:44:36.117039 | controller | Tuesday 17 March 2026  14:44:36 +0000 (0:00:00.015)       0:00:00.826 *********
2026-03-17 14:44:36.117070 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/discover_latest_image/tasks/per-image.yml for instance => (item={'key': 'default', 'value': {'base_url': 'https://cloud.centos.org/centos/9-stream/x86_64/images/', 'qcow_prefix': 'CentOS-Stream-GenericCloud-', 'images_file': 'CHECKSUM'}})
2026-03-17 14:44:36.154770 | controller |
2026-03-17 14:44:36.154833 | controller | TASK [discover_latest_image : Ensure the current image dict entry has the expected data] ***
2026-03-17 14:44:36.154852 | controller | Tuesday 17 March 2026  14:44:36 +0000 (0:00:00.035)       0:00:00.861 *********
2026-03-17 14:44:36.154869 | controller | ok: [instance] => changed=false
2026-03-17 14:44:37.888981 | controller |   msg: All assertions passed
2026-03-17 14:44:37.889014 | controller |
2026-03-17 14:44:37.889023 | controller | TASK [discover_latest_image : Get latest image for the current entry] **********
2026-03-17 14:44:37.889031 | controller | Tuesday 17 March 2026  14:44:36 +0000 (0:00:00.037)       0:00:00.898 *********
2026-03-17 14:44:37.889042 | controller | changed: [instance]
2026-03-17 14:44:37.918764 | controller |
2026-03-17 14:44:37.918798 | controller | TASK [discover_latest_image : Append the current return value to the discovered images dict] ***
2026-03-17 14:44:37.918831 | controller | Tuesday 17 March 2026  14:44:37 +0000 (0:00:01.734)       0:00:02.633 *********
2026-03-17 14:44:37.918842 | controller | ok: [instance]
2026-03-17 14:44:37.948546 | controller |
2026-03-17 14:44:37.948563 | controller | TASK [discover_latest_image : Export single-image facts if an image with name 'default' was discovered] ***
2026-03-17 14:44:37.948570 | controller | Tuesday 17 March 2026  14:44:37 +0000 (0:00:00.029)       0:00:02.662 *********
2026-03-17 14:44:37.948589 | controller | ok: [instance]
2026-03-17 14:44:37.968606 | controller |
2026-03-17 14:44:37.968623 | controller | TASK [reproducer : Load CI job environment] ************************************
2026-03-17 14:44:37.968630 | controller | Tuesday 17 March 2026  14:44:37 +0000 (0:00:00.030)       0:00:02.693 *********
2026-03-17 14:44:37.968638 | controller | skipping: [instance]
2026-03-17 14:44:38.013684 | controller |
2026-03-17 14:44:38.013700 | controller | TASK [Build final libvirt layout] **********************************************
2026-03-17 14:44:38.013707 | controller | Tuesday 17 March 2026  14:44:37 +0000 (0:00:00.019)       0:00:02.712 *********
2026-03-17 14:44:38.013716 | controller |
2026-03-17 14:44:38.086528 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] ***********
2026-03-17 14:44:38.086561 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.045)       0:00:02.758 *********
2026-03-17 14:44:38.086598 | controller | ok: [instance]
2026-03-17 14:44:38.086786 | controller |
2026-03-17 14:44:38.086934 | controller | TASK [libvirt_manager : Patch the layout if needed] ****************************
2026-03-17 14:44:38.087063 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.073)       0:00:02.831 *********
2026-03-17 14:44:38.145894 | controller | skipping: [instance]
2026-03-17 14:44:38.146162 | controller |
2026-03-17 14:44:38.146408 | controller | TASK [reproducer : Assert no conflicting parameters were passed] ***************
2026-03-17 14:44:38.146658 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.059)       0:00:02.890 *********
2026-03-17 14:44:38.170185 | controller | ok: [instance]
2026-03-17 14:44:38.170449 | controller |
2026-03-17 14:44:38.170720 | controller | TASK [reproducer : Assert that deprecated cifmw_reproducer_internal_ca parameters was not passed] ***
2026-03-17 14:44:38.170935 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.024)       0:00:02.915 *********
2026-03-17 14:44:38.192696 | controller | ok: [instance] => changed=false
2026-03-17 14:44:38.192966 | controller |   msg: All assertions passed
2026-03-17 14:44:38.193281 | controller |
2026-03-17 14:44:38.193406 | controller | TASK [reproducer : Set _use_crc based on actual layout] ************************
2026-03-17 14:44:38.193527 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.022)       0:00:02.937 *********
2026-03-17 14:44:38.231083 | controller | ok: [instance]
2026-03-17 14:44:38.231218 | controller |
2026-03-17 14:44:38.231348 | controller | TASK [reproducer : Ensure directories are present] *****************************
2026-03-17 14:44:38.231466 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.038)       0:00:02.976 *********
2026-03-17 14:44:38.657904 | controller | ok: [instance] => (item=artifacts)
2026-03-17 14:44:38.657948 | controller | ok: [instance] => (item=logs)
2026-03-17 14:44:38.658224 | controller |
2026-03-17 14:44:38.728948 | controller | TASK [Bootstrap libvirt if needed] *********************************************
2026-03-17 14:44:38.728994 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.426)       0:00:03.402 *********
2026-03-17 14:44:38.729008 | controller |
2026-03-17 14:44:38.896323 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2026-03-17 14:44:38.896340 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.070)       0:00:03.473 *********
2026-03-17 14:44:38.896349 | controller | ok: [instance]
2026-03-17 14:44:39.153246 | controller |
2026-03-17 14:44:39.153265 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2026-03-17 14:44:39.153272 | controller | Tuesday 17 March 2026  14:44:38 +0000 (0:00:00.167)       0:00:03.641 *********
2026-03-17 14:44:39.153281 | controller | changed: [instance]
2026-03-17 14:44:39.416944 | controller |
2026-03-17 14:44:39.416979 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2026-03-17 14:44:39.416989 | controller | Tuesday 17 March 2026  14:44:39 +0000 (0:00:00.256)       0:00:03.897 *********
2026-03-17 14:44:39.417000 | controller | ok: [instance]
2026-03-17 14:44:39.444910 | controller |
2026-03-17 14:44:39.444940 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2026-03-17 14:44:39.444948 | controller | Tuesday 17 March 2026  14:44:39 +0000 (0:00:00.263)       0:00:04.161 *********
2026-03-17 14:44:39.444965 | controller | skipping: [instance]
2026-03-17 14:44:39.444992 | controller |
2026-03-17 14:44:39.445000 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2026-03-17 14:44:39.445165 | controller | Tuesday 17 March 2026  14:44:39 +0000 (0:00:00.028)       0:00:04.189 *********
2026-03-17 14:44:39.462941 | controller | skipping: [instance]
2026-03-17 14:44:39.483621 | controller |
2026-03-17 14:44:39.483647 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2026-03-17 14:44:39.483655 | controller | Tuesday 17 March 2026  14:44:39 +0000 (0:00:00.017)       0:00:04.207 *********
2026-03-17 14:44:39.483665 | controller | ok: [instance]
2026-03-17 14:44:39.511428 | controller |
2026-03-17 14:44:39.511447 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2026-03-17 14:44:39.511454 | controller | Tuesday 17 March 2026  14:44:39 +0000 (0:00:00.020)       0:00:04.228 *********
2026-03-17 14:44:39.511463 | controller | skipping: [instance]
2026-03-17 14:44:49.474699 | controller |
2026-03-17 14:44:49.474732 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2026-03-17 14:44:49.474740 | controller | Tuesday 17 March 2026  14:44:39 +0000 (0:00:00.027)       0:00:04.256 *********
2026-03-17 14:44:49.474751 | controller | changed: [instance]
2026-03-17 14:44:49.678906 | controller |
2026-03-17 14:44:49.678944 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2026-03-17 14:44:49.678953 | controller | Tuesday 17 March 2026  14:44:49 +0000 (0:00:09.962)       0:00:14.219 *********
2026-03-17 14:44:49.678965 | controller | changed: [instance]
2026-03-17 14:44:49.678972 | controller |
2026-03-17 14:44:49.678977 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2026-03-17 14:44:49.678985 | controller | Tuesday 17 March 2026  14:44:49 +0000 (0:00:00.204)       0:00:14.423 *********
2026-03-17 14:44:50.159886 | controller | changed: [instance]
2026-03-17 14:44:50.702223 | controller |
2026-03-17 14:44:50.702252 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2026-03-17 14:44:50.702260 | controller | Tuesday 17 March 2026  14:44:50 +0000 (0:00:00.480)       0:00:14.904 *********
2026-03-17 14:44:50.702270 | controller | changed: [instance]
2026-03-17 14:44:50.885952 | controller |
2026-03-17 14:44:50.885991 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2026-03-17 14:44:50.886000 | controller | Tuesday 17 March 2026  14:44:50 +0000 (0:00:00.542)       0:00:15.446 *********
2026-03-17 14:44:50.886013 | controller | ok: [instance]
2026-03-17 14:44:50.886033 | controller |
2026-03-17 14:44:50.886042 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2026-03-17 14:44:51.516923 | controller | Tuesday 17 March 2026  14:44:50 +0000 (0:00:00.183)       0:00:15.630 *********
2026-03-17 14:44:51.516970 | controller | ok: [instance]
2026-03-17 14:44:51.517158 | controller |
2026-03-17 14:44:51.517195 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2026-03-17 14:44:52.221880 | controller | Tuesday 17 March 2026  14:44:51 +0000 (0:00:00.630)       0:00:16.261 *********
2026-03-17 14:44:52.221934 | controller | changed: [instance]
2026-03-17 14:44:52.221980 | controller |
2026-03-17 14:44:52.221991 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2026-03-17 14:44:52.305705 | controller | Tuesday 17 March 2026  14:44:52 +0000 (0:00:00.704)       0:00:16.966 *********
2026-03-17 14:44:52.305732 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2026-03-17 14:44:52.305926 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2026-03-17 14:44:52.306635 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2026-03-17 14:44:52.306655 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2026-03-17 14:44:52.639994 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2026-03-17 14:44:52.640041 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2026-03-17 14:44:52.640054 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2026-03-17 14:44:52.640064 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2026-03-17 14:44:52.640074 | controller |
2026-03-17 14:44:52.640085 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2026-03-17 14:44:52.640095 | controller | Tuesday 17 March 2026  14:44:52 +0000 (0:00:00.084)       0:00:17.050 *********
2026-03-17 14:44:52.640112 | controller | ok: [instance]
2026-03-17 14:44:52.942658 | controller |
2026-03-17 14:44:52.942706 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2026-03-17 14:44:52.942716 | controller | Tuesday 17 March 2026  14:44:52 +0000 (0:00:00.333)       0:00:17.384 *********
2026-03-17 14:44:52.942730 | controller | ok: [instance]
2026-03-17 14:44:53.251277 | controller |
2026-03-17 14:44:53.251323 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2026-03-17 14:44:53.251342 | controller | Tuesday 17 March 2026  14:44:52 +0000 (0:00:00.302)       0:00:17.687 *********
2026-03-17 14:44:53.251357 | controller | ok: [instance]
2026-03-17 14:44:53.571694 | controller |
2026-03-17 14:44:53.571728 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2026-03-17 14:44:53.571737 | controller | Tuesday 17 March 2026  14:44:53 +0000 (0:00:00.308)       0:00:17.995 *********
2026-03-17 14:44:53.571748 | controller | ok: [instance]
2026-03-17 14:44:53.879628 | controller |
2026-03-17 14:44:53.879656 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2026-03-17 14:44:53.879669 | controller | Tuesday 17 March 2026  14:44:53 +0000 (0:00:00.320)       0:00:18.316 *********
2026-03-17 14:44:53.879678 | controller | ok: [instance]
2026-03-17 14:44:54.176670 | controller |
2026-03-17 14:44:54.176697 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2026-03-17 14:44:54.176706 | controller | Tuesday 17 March 2026  14:44:53 +0000 (0:00:00.308)       0:00:18.624 *********
2026-03-17 14:44:54.176717 | controller | ok: [instance]
2026-03-17 14:44:54.472167 | controller |
2026-03-17 14:44:54.472195 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2026-03-17 14:44:54.472203 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.297)       0:00:18.921 *********
2026-03-17 14:44:54.472212 | controller | ok: [instance]
2026-03-17 14:44:54.762910 | controller |
2026-03-17 14:44:54.762939 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2026-03-17 14:44:54.762946 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.295)       0:00:19.216 *********
2026-03-17 14:44:54.762956 | controller | ok: [instance]
2026-03-17 14:44:55.062900 | controller |
2026-03-17 14:44:55.062940 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2026-03-17 14:44:55.062949 | controller | Tuesday 17 March 2026  14:44:54 +0000 (0:00:00.290)       0:00:19.507 *********
2026-03-17 14:44:55.062962 | controller | ok: [instance]
2026-03-17 14:44:55.359908 | controller |
2026-03-17 14:44:55.359945 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2026-03-17 14:44:55.359955 | controller | Tuesday 17 March 2026  14:44:55 +0000 (0:00:00.299)       0:00:19.807 *********
2026-03-17 14:44:55.359967 | controller | ok: [instance]
2026-03-17 14:44:55.359995 | controller |
2026-03-17 14:44:55.360005 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2026-03-17 14:44:55.360089 | controller | Tuesday 17 March 2026  14:44:55 +0000 (0:00:00.297)       0:00:20.104 *********
2026-03-17 14:44:55.660628 | controller | ok: [instance]
2026-03-17 14:44:55.955400 | controller |
2026-03-17 14:44:55.955429 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2026-03-17 14:44:55.955437 | controller | Tuesday 17 March 2026  14:44:55 +0000 (0:00:00.300)       0:00:20.405 *********
2026-03-17 14:44:55.955447 | controller | ok: [instance]
2026-03-17 14:44:56.253794 | controller |
2026-03-17 14:44:56.253838 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2026-03-17 14:44:56.253847 | controller | Tuesday 17 March 2026  14:44:55 +0000 (0:00:00.294)       0:00:20.700 *********
2026-03-17 14:44:56.253856 | controller | ok: [instance]
2026-03-17 14:44:56.559339 | controller |
2026-03-17 14:44:56.559373 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2026-03-17 14:44:56.559383 | controller | Tuesday 17 March 2026  14:44:56 +0000 (0:00:00.298)       0:00:20.998 *********
2026-03-17 14:44:56.559396 | controller | ok: [instance]
2026-03-17 14:44:56.875570 | controller |
2026-03-17 14:44:56.875613 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2026-03-17 14:44:56.875621 | controller | Tuesday 17 March 2026  14:44:56 +0000 (0:00:00.305)       0:00:21.303 *********
2026-03-17 14:44:56.875631 | controller | ok: [instance]
2026-03-17 14:44:57.193359 | controller |
2026-03-17 14:44:57.193392 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2026-03-17 14:44:57.193399 | controller | Tuesday 17 March 2026  14:44:56 +0000 (0:00:00.316)       0:00:21.620 *********
2026-03-17 14:44:57.193410 | controller | ok: [instance]
2026-03-17 14:44:57.493869 | controller |
2026-03-17 14:44:57.493898 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2026-03-17 14:44:57.493912 | controller | Tuesday 17 March 2026  14:44:57 +0000 (0:00:00.317)       0:00:21.937 *********
2026-03-17 14:44:57.493923 | controller | ok: [instance]
2026-03-17 14:44:57.494048 | controller |
2026-03-17 14:44:57.494059 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2026-03-17 14:44:57.494067 | controller | Tuesday 17 March 2026  14:44:57 +0000 (0:00:00.300)       0:00:22.238 *********
2026-03-17 14:44:57.792934 | controller | ok: [instance]
2026-03-17 14:44:57.792981 | controller |
2026-03-17 14:44:57.792991 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2026-03-17 14:44:57.793098 | controller | Tuesday 17 March 2026  14:44:57 +0000 (0:00:00.299)       0:00:22.537 *********
2026-03-17 14:44:58.087277 | controller | ok: [instance]
2026-03-17 14:44:58.383309 | controller |
2026-03-17 14:44:58.383341 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2026-03-17 14:44:58.383349 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.294)       0:00:22.831 *********
2026-03-17 14:44:58.383364 | controller | ok: [instance]
2026-03-17 14:44:58.685188 | controller |
2026-03-17 14:44:58.685221 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2026-03-17 14:44:58.685229 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.296)       0:00:23.127 *********
2026-03-17 14:44:58.685239 | controller | ok: [instance]
2026-03-17 14:44:58.980584 | controller |
2026-03-17 14:44:58.980615 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2026-03-17 14:44:58.980623 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.301)       0:00:23.429 *********
2026-03-17 14:44:58.980633 | controller | ok: [instance]
2026-03-17 14:44:59.282250 | controller |
2026-03-17 14:44:59.282276 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2026-03-17 14:44:59.282283 | controller | Tuesday 17 March 2026  14:44:58 +0000 (0:00:00.295)       0:00:23.725 *********
2026-03-17 14:44:59.282292 | controller | ok: [instance]
2026-03-17 14:44:59.573954 | controller |
2026-03-17 14:44:59.573997 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2026-03-17 14:44:59.574007 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.301)       0:00:24.026 *********
2026-03-17 14:44:59.574020 | controller | ok: [instance]
2026-03-17 14:44:59.592358 | controller |
2026-03-17 14:44:59.592383 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2026-03-17 14:44:59.592390 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.291)       0:00:24.318 *********
2026-03-17 14:44:59.592401 | controller | skipping: [instance]
2026-03-17 14:44:59.853617 | controller |
2026-03-17 14:44:59.853647 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2026-03-17 14:44:59.853656 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.018)       0:00:24.337 *********
2026-03-17 14:44:59.853667 | controller | ok: [instance]
2026-03-17 14:45:00.200939 | controller |
2026-03-17 14:45:00.200965 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2026-03-17 14:45:00.200972 | controller | Tuesday 17 March 2026  14:44:59 +0000 (0:00:00.261)       0:00:24.598 *********
2026-03-17 14:45:00.200981 | controller | ok: [instance]
2026-03-17 14:45:00.484547 | controller |
2026-03-17 14:45:00.484594 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2026-03-17 14:45:00.484604 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.347)       0:00:24.945 *********
2026-03-17 14:45:00.484616 | controller | changed: [instance]
2026-03-17 14:45:00.754428 | controller |
2026-03-17 14:45:00.754460 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2026-03-17 14:45:00.754469 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.283)       0:00:25.228 *********
2026-03-17 14:45:00.754482 | controller | [WARNING]: Reset is not implemented for this connection
2026-03-17 14:45:00.763926 | controller | changed: [instance]
2026-03-17 14:45:00.763985 | controller |
2026-03-17 14:45:00.763997 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2026-03-17 14:45:00.764006 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.263)       0:00:25.492 *********
2026-03-17 14:45:00.764014 | controller |
2026-03-17 14:45:00.764032 | controller | TASK [reproducer : Deploy networks in libvirt] *********************************
2026-03-17 14:45:00.764098 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.015)       0:00:25.508 *********
2026-03-17 14:45:00.829925 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/prepare_networking.yml for instance
2026-03-17 14:45:00.829991 | controller |
2026-03-17 14:45:00.830008 | controller | TASK [reproducer : Ensure networks are consistent] *****************************
2026-03-17 14:45:00.830018 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.065)       0:00:25.574 *********
2026-03-17 14:45:00.869928 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/network_assertions.yml for instance
2026-03-17 14:45:00.869970 | controller |
2026-03-17 14:45:00.869981 | controller | TASK [reproducer : Ensure all VM types have defined nets parameter] ************
2026-03-17 14:45:00.870088 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.040)       0:00:25.614 *********
2026-03-17 14:45:00.914948 | controller | ok: [instance] => (item=compute)
2026-03-17 14:45:00.914997 | controller | ok: [instance] => (item=controller)
2026-03-17 14:45:00.915015 | controller | ok: [instance] => (item=crc)
2026-03-17 14:45:00.915022 | controller |
2026-03-17 14:45:00.915028 | controller | TASK [reproducer : Ensure all standard attached networks are defined] **********
2026-03-17 14:45:00.915036 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.044)       0:00:25.658 *********
2026-03-17 14:45:00.952477 | controller | ok: [instance]
2026-03-17 14:45:00.952657 | controller |
2026-03-17 14:45:00.952847 | controller | TASK [reproducer : Ensure we don't have extra network defined] *****************
2026-03-17 14:45:00.953030 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.038)       0:00:25.697 *********
2026-03-17 14:45:00.991716 | controller | ok: [instance] => changed=false
2026-03-17 14:45:00.991935 | controller |   msg: All assertions passed
2026-03-17 14:45:00.992074 | controller |
2026-03-17 14:45:00.992214 | controller | TASK [Ensure we get latest OCP config] *****************************************
2026-03-17 14:45:00.992353 | controller | Tuesday 17 March 2026  14:45:00 +0000 (0:00:00.039)       0:00:25.736 *********
2026-03-17 14:45:01.034654 | controller |
2026-03-17 14:45:01.034992 | controller | TASK [devscripts : Gather the configurations to be passed to dev-scripts.] *****
2026-03-17 14:45:01.035241 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:00.042)       0:00:25.779 *********
2026-03-17 14:45:01.123434 | controller | ok: [instance] => (item={'working_dir': '/home/dev-scripts', 'assets_extra_folder': '/home/dev-scripts/assets', 'openshift_release_type': 'ga', 'openshift_version': '4.16.54', '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'})
2026-03-17 14:45:01.123719 | controller | ok: [instance] => (item={})
2026-03-17 14:45:01.123975 | controller |
2026-03-17 14:45:01.124204 | controller | TASK [devscripts : Check if num_masters is equal to 1 when cifmw_devscripts_sno] ***
2026-03-17 14:45:01.124367 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:00.088)       0:00:25.868 *********
2026-03-17 14:45:01.150907 | controller | skipping: [instance]
2026-03-17 14:45:01.151170 | controller |
2026-03-17 14:45:01.151404 | controller | TASK [devscripts : Replace OCP version if "stable-" alias used] ****************
2026-03-17 14:45:01.151523 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:00.027)       0:00:25.895 *********
2026-03-17 14:45:01.167729 | controller | skipping: [instance]
2026-03-17 14:45:01.168080 | controller |
2026-03-17 14:45:01.168333 | controller | TASK [devscripts : Ensure output directory exists] *****************************
2026-03-17 14:45:01.168476 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:00.016)       0:00:25.912 *********
2026-03-17 14:45:01.367722 | controller | ok: [instance]
2026-03-17 14:45:01.685490 | controller |
2026-03-17 14:45:01.685514 | controller | TASK [devscripts : Output built config for easier debugging] *******************
2026-03-17 14:45:01.685527 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:00.199)       0:00:26.112 *********
2026-03-17 14:45:01.685536 | controller | changed: [instance]
2026-03-17 14:45:02.379720 | controller |
2026-03-17 14:45:02.379749 | controller | TASK [ci_network : Remove the local dns configuration] *************************
2026-03-17 14:45:02.379756 | controller | Tuesday 17 March 2026  14:45:01 +0000 (0:00:00.317)       0:00:26.430 *********
2026-03-17 14:45:02.379766 | controller | ok: [instance] => (item=/etc/NetworkManager/conf.d/00-use-dnsmasq.conf)
2026-03-17 14:45:02.404478 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/98-cifmw-static-leases.conf)
2026-03-17 14:45:02.404493 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/99-cifmw-dns-forwarders.conf)
2026-03-17 14:45:02.404499 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/97-cifmw-local-domain.conf)
2026-03-17 14:45:02.404504 | controller |
2026-03-17 14:45:02.404509 | controller | TASK [ci_network : Reload the NetworkManager to pick the changes] **************
2026-03-17 14:45:02.404514 | controller | Tuesday 17 March 2026  14:45:02 +0000 (0:00:00.693)       0:00:27.124 *********
2026-03-17 14:45:02.404522 | controller | skipping: [instance]
2026-03-17 14:45:02.746836 | controller |
2026-03-17 14:45:02.746865 | controller | TASK [reproducer : Ensure NetworkManager is configured to use dnsmasq] *********
2026-03-17 14:45:02.746873 | controller | Tuesday 17 March 2026  14:45:02 +0000 (0:00:00.025)       0:00:27.149 *********
2026-03-17 14:45:02.746883 | controller | changed: [instance]
2026-03-17 14:45:03.143878 | controller |
2026-03-17 14:45:03.143913 | controller | TASK [reproducer : Flush handlers for NetworkManager restart] ******************
2026-03-17 14:45:03.143922 | controller | Tuesday 17 March 2026  14:45:02 +0000 (0:00:00.333)       0:00:27.482 *********
2026-03-17 14:45:03.143928 | controller |
2026-03-17 14:45:03.143933 | controller | RUNNING HANDLER [reproducer : Restart NetworkManager] **************************
2026-03-17 14:45:03.143939 | controller | Tuesday 17 March 2026  14:45:02 +0000 (0:00:00.008)       0:00:27.491 *********
2026-03-17 14:45:03.143949 | controller | changed: [instance]
2026-03-17 14:45:03.214880 | controller |
2026-03-17 14:45:03.214908 | controller | TASK [Ensure no default networks exists] ***************************************
2026-03-17 14:45:03.214918 | controller | Tuesday 17 March 2026  14:45:03 +0000 (0:00:00.397)       0:00:27.888 *********
2026-03-17 14:45:03.214929 | controller |
2026-03-17 14:45:03.215172 | controller | TASK [libvirt_manager : Deactivate crc] ****************************************
2026-03-17 14:45:04.363896 | controller | Tuesday 17 March 2026  14:45:03 +0000 (0:00:00.071)       0:00:27.959 *********
2026-03-17 14:45:04.363933 | controller | ok: [instance]
2026-03-17 14:45:04.593440 | controller |
2026-03-17 14:45:04.593469 | controller | TASK [libvirt_manager : Removing crc] ******************************************
2026-03-17 14:45:04.593476 | controller | Tuesday 17 March 2026  14:45:04 +0000 (0:00:01.148)       0:00:29.108 *********
2026-03-17 14:45:04.593486 | controller | ok: [instance]
2026-03-17 14:45:05.119645 | controller |
2026-03-17 14:45:05.119680 | controller | TASK [libvirt_manager : Deactivate default] ************************************
2026-03-17 14:45:05.119692 | controller | Tuesday 17 March 2026  14:45:04 +0000 (0:00:00.229)       0:00:29.337 *********
2026-03-17 14:45:05.119703 | controller | ok: [instance]
2026-03-17 14:45:05.336489 | controller |
2026-03-17 14:45:05.336508 | controller | TASK [libvirt_manager : Removing default] **************************************
2026-03-17 14:45:05.336515 | controller | Tuesday 17 March 2026  14:45:05 +0000 (0:00:00.526)       0:00:29.864 *********
2026-03-17 14:45:05.336523 | controller | ok: [instance]
2026-03-17 14:45:06.552418 | controller |
2026-03-17 14:45:06.552456 | controller | TASK [reproducer : Ensure firewalld is installed] ******************************
2026-03-17 14:45:06.552466 | controller | Tuesday 17 March 2026  14:45:05 +0000 (0:00:00.217)       0:00:30.081 *********
2026-03-17 14:45:06.552478 | controller | ok: [instance]
2026-03-17 14:45:06.845884 | controller |
2026-03-17 14:45:06.845919 | controller | TASK [reproducer : Ensure firewalld service is enabled and started] ************
2026-03-17 14:45:06.845927 | controller | Tuesday 17 March 2026  14:45:06 +0000 (0:00:01.215)       0:00:31.296 *********
2026-03-17 14:45:06.845937 | controller | ok: [instance]
2026-03-17 14:45:06.846340 | controller |
2026-03-17 14:45:07.136251 | controller | TASK [reproducer : Ensure br_netfilter module is loaded] ***********************
2026-03-17 14:45:07.136292 | controller | Tuesday 17 March 2026  14:45:06 +0000 (0:00:00.293)       0:00:31.590 *********
2026-03-17 14:45:07.136306 | controller | changed: [instance]
2026-03-17 14:45:07.419886 | controller |
2026-03-17 14:45:07.419918 | controller | TASK [reproducer : Ensure IP forwarding is enabled] ****************************
2026-03-17 14:45:07.419926 | controller | Tuesday 17 March 2026  14:45:07 +0000 (0:00:00.290)       0:00:31.880 *********
2026-03-17 14:45:07.419935 | controller | changed: [instance]
2026-03-17 14:45:07.419960 | controller |
2026-03-17 14:45:07.420185 | controller | TASK [reproducer : Ensure the required parameters are loaded] ******************
2026-03-17 14:45:07.962727 | controller | Tuesday 17 March 2026  14:45:07 +0000 (0:00:00.284)       0:00:32.164 *********
2026-03-17 14:45:07.962760 | controller | changed: [instance] => (item=arptables)
2026-03-17 14:45:08.275864 | controller | changed: [instance] => (item=iptables)
2026-03-17 14:45:08.275892 | controller | changed: [instance] => (item=ip6tables)
2026-03-17 14:45:08.275900 | controller |
2026-03-17 14:45:08.275906 | controller | TASK [reproducer : Ensure firewall service is enabled and started] *************
2026-03-17 14:45:08.275913 | controller | Tuesday 17 March 2026  14:45:07 +0000 (0:00:00.542)       0:00:32.707 *********
2026-03-17 14:45:08.275922 | controller | ok: [instance]
2026-03-17 14:45:08.385964 | controller |
2026-03-17 14:45:08.385998 | controller | TASK [reproducer : Set cifmw_ci_nmstate_instance_config if needed] *************
2026-03-17 14:45:08.386008 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.313)       0:00:33.020 *********
2026-03-17 14:45:08.386020 | controller | ok: [instance]
2026-03-17 14:45:08.427446 | controller |
2026-03-17 14:45:08.427480 | controller | TASK [Ensure the required network configurations are applied] ******************
2026-03-17 14:45:08.427488 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.110)       0:00:33.130 *********
2026-03-17 14:45:08.427498 | controller |
2026-03-17 14:45:08.448751 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2026-03-17 14:45:08.448777 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.041)       0:00:33.172 *********
2026-03-17 14:45:08.448788 | controller | skipping: [instance]
2026-03-17 14:45:08.468910 | controller |
2026-03-17 14:45:08.468945 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2026-03-17 14:45:08.468955 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.021)       0:00:33.193 *********
2026-03-17 14:45:08.468968 | controller | skipping: [instance]
2026-03-17 14:45:08.468989 | controller |
2026-03-17 14:45:08.469001 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2026-03-17 14:45:08.469010 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.020)       0:00:33.213 *********
2026-03-17 14:45:08.490448 | controller | skipping: [instance]
2026-03-17 14:45:08.515906 | controller |
2026-03-17 14:45:08.515940 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2026-03-17 14:45:08.515950 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.021)       0:00:33.234 *********
2026-03-17 14:45:08.515960 | controller | skipping: [instance]
2026-03-17 14:45:08.583221 | controller |
2026-03-17 14:45:08.583261 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] **********************
2026-03-17 14:45:08.583272 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.025)       0:00:33.260 *********
2026-03-17 14:45:08.583285 | controller | ok: [instance]
2026-03-17 14:45:08.583317 | controller |
2026-03-17 14:45:08.583447 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] ***
2026-03-17 14:45:08.583557 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.067)       0:00:33.328 *********
2026-03-17 14:45:08.616488 | controller | skipping: [instance]
2026-03-17 14:45:08.616629 | controller |
2026-03-17 14:45:08.616771 | controller | TASK [ci_nmstate : Get k8s nodes] **********************************************
2026-03-17 14:45:08.616945 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.033)       0:00:33.361 *********
2026-03-17 14:45:08.638656 | controller | skipping: [instance]
2026-03-17 14:45:08.638818 | controller |
2026-03-17 14:45:08.638965 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ********
2026-03-17 14:45:08.639101 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.022)       0:00:33.383 *********
2026-03-17 14:45:08.695393 | controller | ok: [instance]
2026-03-17 14:45:08.695498 | controller |
2026-03-17 14:45:08.695697 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] *************************
2026-03-17 14:45:08.695784 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.056)       0:00:33.440 *********
2026-03-17 14:45:08.717734 | controller | skipping: [instance]
2026-03-17 14:45:08.717899 | controller |
2026-03-17 14:45:08.718050 | controller | TASK [ci_nmstate : Provision unmanaged nodes with nmstate] *********************
2026-03-17 14:45:08.718183 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.022)       0:00:33.462 *********
2026-03-17 14:45:08.765160 | 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)
2026-03-17 14:45:08.765273 | controller |
2026-03-17 14:45:08.765393 | controller | TASK [ci_nmstate : Ensure we have needed facts] ********************************
2026-03-17 14:45:08.765505 | controller | Tuesday 17 March 2026  14:45:08 +0000 (0:00:00.047)       0:00:33.510 *********
2026-03-17 14:45:09.073444 | controller | ok: [instance -> localhost]
2026-03-17 14:45:09.073566 | controller |
2026-03-17 14:45:09.073710 | controller | TASK [ci_nmstate : Install required packages on localhost] *********************
2026-03-17 14:45:09.073847 | controller | Tuesday 17 March 2026  14:45:09 +0000 (0:00:00.308)       0:00:33.818 *********
2026-03-17 14:45:13.848209 | controller | changed: [instance -> localhost]
2026-03-17 14:45:14.021894 | controller |
2026-03-17 14:45:14.021930 | controller | TASK [ci_nmstate : Create the target NMstate dump directory] *******************
2026-03-17 14:45:14.021938 | controller | Tuesday 17 March 2026  14:45:13 +0000 (0:00:04.774)       0:00:38.592 *********
2026-03-17 14:45:14.021949 | controller | changed: [instance]
2026-03-17 14:45:14.341455 | controller |
2026-03-17 14:45:14.341488 | controller | TASK [ci_nmstate : Save nmstate state for localhost] ***************************
2026-03-17 14:45:14.341496 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.173)       0:00:38.766 *********
2026-03-17 14:45:14.341505 | controller | changed: [instance]
2026-03-17 14:45:15.919739 | controller |
2026-03-17 14:45:15.919769 | controller | TASK [ci_nmstate : Apply the desidered state on localhost] *********************
2026-03-17 14:45:15.919777 | controller | Tuesday 17 March 2026  14:45:14 +0000 (0:00:00.319)       0:00:39.085 *********
2026-03-17 14:45:15.919786 | controller | changed: [instance -> localhost]
2026-03-17 14:45:16.245760 | controller |
2026-03-17 14:45:16.245783 | controller | TASK [ci_nmstate : Save nmstate debugging data for localhost] ******************
2026-03-17 14:45:16.245791 | controller | Tuesday 17 March 2026  14:45:15 +0000 (0:00:01.578)       0:00:40.664 *********
2026-03-17 14:45:16.245816 | controller | changed: [instance]
2026-03-17 14:45:16.274847 | controller |
2026-03-17 14:45:16.274865 | controller | TASK [reproducer : Select right domain] ****************************************
2026-03-17 14:45:16.274872 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.326)       0:00:40.990 *********
2026-03-17 14:45:16.274880 | controller | ok: [instance]
2026-03-17 14:45:16.275058 | controller |
2026-03-17 14:45:16.275075 | controller | TASK [Create the virtual networks] *********************************************
2026-03-17 14:45:16.386610 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.029)       0:00:41.019 *********
2026-03-17 14:45:16.386644 | controller |
2026-03-17 14:45:16.404391 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ********************
2026-03-17 14:45:16.404408 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.111)       0:00:41.131 *********
2026-03-17 14:45:16.404418 | controller | ok: [instance]
2026-03-17 14:45:16.577191 | controller |
2026-03-17 14:45:16.577210 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] *******
2026-03-17 14:45:16.577218 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.017)       0:00:41.149 *********
2026-03-17 14:45:16.577227 | controller | ok: [instance]
2026-03-17 14:45:16.604877 | controller |
2026-03-17 14:45:16.604906 | controller | TASK [libvirt_manager : Load file if exists] ***********************************
2026-03-17 14:45:16.604914 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.172)       0:00:41.322 *********
2026-03-17 14:45:16.604924 | controller | skipping: [instance]
2026-03-17 14:45:16.604956 | controller |
2026-03-17 14:45:16.604964 | controller | TASK [libvirt_manager : Update fact with existing content] *********************
2026-03-17 14:45:16.605086 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.027)       0:00:41.349 *********
2026-03-17 14:45:16.631864 | controller | skipping: [instance]
2026-03-17 14:45:16.632091 | controller |
2026-03-17 14:45:16.632115 | controller | TASK [libvirt_manager : Generate VM list] **************************************
2026-03-17 14:45:16.683893 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.026)       0:00:41.376 *********
2026-03-17 14:45:16.683930 | controller | ok: [instance]
2026-03-17 14:45:16.800730 | controller |
2026-03-17 14:45:16.800757 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] *********************
2026-03-17 14:45:16.800766 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.051)       0:00:41.428 *********
2026-03-17 14:45:16.800776 | controller | ok: [instance] => (item={'key': 'compute-0', 'value': 'compute'})
2026-03-17 14:45:16.800819 | controller | ok: [instance] => (item={'key': 'controller-0', 'value': 'controller'})
2026-03-17 14:45:16.801073 | controller | ok: [instance] => (item={'key': 'crc-0', 'value': 'crc'})
2026-03-17 14:45:16.801117 | controller |
2026-03-17 14:45:16.801130 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] **********************
2026-03-17 14:45:16.801235 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.117)       0:00:41.545 *********
2026-03-17 14:45:16.833680 | controller | skipping: [instance]
2026-03-17 14:45:16.833764 | controller |
2026-03-17 14:45:16.833894 | controller | TASK [libvirt_manager : Dump MAC mapping] **************************************
2026-03-17 14:45:16.834008 | controller | Tuesday 17 March 2026  14:45:16 +0000 (0:00:00.032)       0:00:41.578 *********
2026-03-17 14:45:17.165953 | controller | changed: [instance]
2026-03-17 14:45:17.166011 | controller |
2026-03-17 14:45:17.166024 | controller | TASK [libvirt_manager : Inject all VMs in the inventory] ***********************
2026-03-17 14:45:17.166155 | controller | Tuesday 17 March 2026  14:45:17 +0000 (0:00:00.331)       0:00:41.910 *********
2026-03-17 14:45:17.251980 | 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)
2026-03-17 14:45:17.252046 | 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)
2026-03-17 14:45:17.252060 | 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)
2026-03-17 14:45:17.252070 | controller |
2026-03-17 14:45:17.252085 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2026-03-17 14:45:17.306312 | controller | Tuesday 17 March 2026  14:45:17 +0000 (0:00:00.085)       0:00:41.996 *********
2026-03-17 14:45:17.306344 | controller | changed: [instance]
2026-03-17 14:45:17.603392 | controller |
2026-03-17 14:45:17.603422 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2026-03-17 14:45:17.603430 | controller | Tuesday 17 March 2026  14:45:17 +0000 (0:00:00.054)       0:00:42.050 *********
2026-03-17 14:45:17.603440 | controller | changed: [instance]
2026-03-17 14:45:17.818303 | controller |
2026-03-17 14:45:17.818334 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2026-03-17 14:45:17.818343 | controller | Tuesday 17 March 2026  14:45:17 +0000 (0:00:00.296)       0:00:42.347 *********
2026-03-17 14:45:17.818354 | controller | changed: [instance]
2026-03-17 14:45:18.175597 | controller |
2026-03-17 14:45:18.175628 | controller | TASK [libvirt_manager : daniel debug] ******************************************
2026-03-17 14:45:18.175650 | controller | Tuesday 17 March 2026  14:45:17 +0000 (0:00:00.214)       0:00:42.562 *********
2026-03-17 14:45:18.175660 | controller | changed: [instance]
2026-03-17 14:45:18.227978 | controller |
2026-03-17 14:45:18.228006 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2026-03-17 14:45:18.228015 | controller | Tuesday 17 March 2026  14:45:18 +0000 (0:00:00.357)       0:00:42.920 *********
2026-03-17 14:45:18.228027 | controller | changed: [instance]
2026-03-17 14:45:18.420933 | controller |
2026-03-17 14:45:18.420978 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2026-03-17 14:45:18.420987 | controller | Tuesday 17 March 2026  14:45:18 +0000 (0:00:00.052)       0:00:42.972 *********
2026-03-17 14:45:18.420998 | controller | changed: [instance]
2026-03-17 14:45:18.634320 | controller |
2026-03-17 14:45:18.634351 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2026-03-17 14:45:18.634359 | controller | Tuesday 17 March 2026  14:45:18 +0000 (0:00:00.192)       0:00:43.165 *********
2026-03-17 14:45:18.634369 | controller | changed: [instance]
2026-03-17 14:45:18.999922 | controller |
2026-03-17 14:45:18.999957 | controller | TASK [libvirt_manager : daniel debug] ******************************************
2026-03-17 14:45:18.999967 | controller | Tuesday 17 March 2026  14:45:18 +0000 (0:00:00.213)       0:00:43.378 *********
2026-03-17 14:45:18.999979 | controller | changed: [instance]
2026-03-17 14:45:19.000008 | controller |
2026-03-17 14:45:19.000025 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2026-03-17 14:45:19.000051 | controller | Tuesday 17 March 2026  14:45:18 +0000 (0:00:00.365)       0:00:43.744 *********
2026-03-17 14:45:19.051939 | controller | changed: [instance]
2026-03-17 14:45:19.051992 | controller |
2026-03-17 14:45:19.052004 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2026-03-17 14:45:19.052112 | controller | Tuesday 17 March 2026  14:45:19 +0000 (0:00:00.052)       0:00:43.796 *********
2026-03-17 14:45:19.247444 | controller | changed: [instance]
2026-03-17 14:45:19.449873 | controller |
2026-03-17 14:45:19.449908 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2026-03-17 14:45:19.449916 | controller | Tuesday 17 March 2026  14:45:19 +0000 (0:00:00.195)       0:00:43.992 *********
2026-03-17 14:45:19.449925 | controller | changed: [instance]
2026-03-17 14:45:19.806822 | controller |
2026-03-17 14:45:19.806847 | controller | TASK [libvirt_manager : daniel debug] ******************************************
2026-03-17 14:45:19.806855 | controller | Tuesday 17 March 2026  14:45:19 +0000 (0:00:00.202)       0:00:44.194 *********
2026-03-17 14:45:19.806864 | controller | changed: [instance]
2026-03-17 14:45:19.989465 | controller |
2026-03-17 14:45:19.989490 | controller | TASK [libvirt_manager : Check if ini inventory file exists] ********************
2026-03-17 14:45:19.989498 | controller | Tuesday 17 March 2026  14:45:19 +0000 (0:00:00.356)       0:00:44.551 *********
2026-03-17 14:45:19.989507 | controller | ok: [instance]
2026-03-17 14:45:20.563605 | controller |
2026-03-17 14:45:20.563635 | controller | TASK [libvirt_manager : Test] **************************************************
2026-03-17 14:45:20.563657 | controller | Tuesday 17 March 2026  14:45:19 +0000 (0:00:00.182)       0:00:44.734 *********
2026-03-17 14:45:20.563669 | controller | changed: [instance]
2026-03-17 14:45:20.735983 | controller |
2026-03-17 14:45:20.736014 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ******************
2026-03-17 14:45:20.736022 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.573)       0:00:45.308 *********
2026-03-17 14:45:20.736033 | controller | fatal: [instance]: FAILED! => changed=true
2026-03-17 14:45:20.742371 | controller |   cmd: |-
2026-03-17 14:45:20.742394 | controller |     /usr/local/bin/ansible-inventory -i /home/zuul/libvirt_inventory.ini -y --list > /home/zuul/libvirt_inventory.yaml
2026-03-17 14:45:20.742402 | controller |   delta: '0:00:00.004458'
2026-03-17 14:45:20.742408 | controller |   end: '2026-03-17 14:45:20.712703'
2026-03-17 14:45:20.742414 | controller |   msg: non-zero return code
2026-03-17 14:45:20.742420 | controller |   rc: 127
2026-03-17 14:45:20.742425 | controller |   start: '2026-03-17 14:45:20.708245'
2026-03-17 14:45:20.742431 | controller |   stderr: '/bin/sh: line 1: /usr/local/bin/ansible-inventory: No such file or directory'
2026-03-17 14:45:20.742437 | controller |   stderr_lines: <omitted>
2026-03-17 14:45:20.742442 | controller |   stdout: ''
2026-03-17 14:45:20.742448 | controller |   stdout_lines: <omitted>
2026-03-17 14:45:20.742456 | controller | ...ignoring
2026-03-17 14:45:20.766249 | controller |
2026-03-17 14:45:20.766266 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ******
2026-03-17 14:45:20.766273 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.178)       0:00:45.487 *********
2026-03-17 14:45:20.766281 | controller | skipping: [instance]
2026-03-17 14:45:20.788868 | controller |
2026-03-17 14:45:20.788896 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2026-03-17 14:45:20.788907 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.023)       0:00:45.511 *********
2026-03-17 14:45:20.788916 | controller | skipping: [instance]
2026-03-17 14:45:20.788941 | controller |
2026-03-17 14:45:20.789104 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************
2026-03-17 14:45:20.811940 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.022)       0:00:45.533 *********
2026-03-17 14:45:20.812007 | controller | skipping: [instance]
2026-03-17 14:45:20.834469 | controller |
2026-03-17 14:45:20.834490 | controller | TASK [networking_mapper : Load the interfaces info from file] ******************
2026-03-17 14:45:20.834498 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.022)       0:00:45.556 *********
2026-03-17 14:45:20.834506 | controller | skipping: [instance]
2026-03-17 14:45:20.852869 | controller |
2026-03-17 14:45:20.852890 | controller | TASK [networking_mapper : Ensure local fact is empty] **************************
2026-03-17 14:45:20.852897 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.022)       0:00:45.579 *********
2026-03-17 14:45:20.852906 | controller | ok: [instance]
2026-03-17 14:45:20.852931 | controller |
2026-03-17 14:45:20.852937 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] ***
2026-03-17 14:45:20.852945 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.018)       0:00:45.597 *********
2026-03-17 14:45:20.934570 | controller | ok: [instance] => (item={'networks': {'ctlplane': {'network': '192.168.140.0/24', 'gateway': '192.168.140.1', 'dns': ['192.168.140.1'], 'mtu': 1500, 'tools': {'multus': {'ranges': [{'start': 30, 'end': 70}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 120}, {'start': 150, 'end': 170}]}, 'metallb': {'ranges': [{'start': 80, 'end': 90}]}}}, 'internalapi': {'network': '172.17.0.0/24', 'vlan': 20, 'mtu': 1496, 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}}, 'storage': {'network': '172.18.0.0/24', 'vlan': 21, 'mtu': 1496, 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}}, 'tenant': {'network': '172.19.0.0/24', 'tools': {'metallb': {'ranges': [{'start': 80, 'end': 90}]}, 'netconfig': {'ranges': [{'start': 100, 'end': 250}]}, 'multus': {'ranges': [{'start': 30, 'end': 70}]}}, 'vlan': 22, 'mtu': 1496}, 'external': {'network': '10.0.0.0/24', 'tools': {'netconfig': {'ranges': [{'start': 100, 'end': 250}]}}, 'vlan': 22, 'mtu': 1500}, 'storagemgmt': {'network': '172.20.0.0/24', 'tools': {'netconfig': {'ranges': [{'start': 100, 'end': 250}]}}, 'vlan': 23, 'mtu': 1500}}, 'group-templates': {'ocps': {'network-template': {'range': {'start': 10, 'length': 10}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}}}, 'computes': {'network-template': {'range': {'start': 100, 'length': 21}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}, 'storagemgmt': {'trunk-parent': 'ctlplane'}}}, 'cephs': {'network-template': {'range': {'start': 150, 'length': 21}}, 'networks': {'ctlplane': {}, 'internalapi': {'trunk-parent': 'ctlplane'}, 'tenant': {'trunk-parent': 'ctlplane'}, 'storage': {'trunk-parent': 'ctlplane'}, 'storagemgmt': {'trunk-parent': 'ctlplane'}}}}, 'instances': {'controller-0': {'networks': {'ctlplane': {'ip': '192.168.140.9'}}}}})
2026-03-17 14:45:20.934723 | controller |
2026-03-17 14:45:20.934885 | controller | TASK [networking_mapper : Gather facts if requested] ***************************
2026-03-17 14:45:20.935009 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.081)       0:00:45.679 *********
2026-03-17 14:45:20.952479 | controller | skipping: [instance]
2026-03-17 14:45:20.952555 | controller |
2026-03-17 14:45:20.952761 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] *****************
2026-03-17 14:45:20.952860 | controller | Tuesday 17 March 2026  14:45:20 +0000 (0:00:00.017)       0:00:45.697 *********
2026-03-17 14:45:21.150762 | controller | changed: [instance]
2026-03-17 14:45:21.510793 | controller |
2026-03-17 14:45:21.510858 | controller | TASK [networking_mapper : Write the Networking Definition to file] *************
2026-03-17 14:45:21.510866 | controller | Tuesday 17 March 2026  14:45:21 +0000 (0:00:00.197)       0:00:45.895 *********
2026-03-17 14:45:21.510879 | controller | changed: [instance]
2026-03-17 14:45:21.701905 | controller |
2026-03-17 14:45:21.701931 | controller | TASK [networking_mapper : Change cluster network address - daniel] *************
2026-03-17 14:45:21.701939 | controller | Tuesday 17 March 2026  14:45:21 +0000 (0:00:00.360)       0:00:46.255 *********
2026-03-17 14:45:21.701954 | controller | fatal: [instance]: FAILED! => changed=true
2026-03-17 14:45:21.702035 | controller |   cmd: |-
2026-03-17 14:45:21.702043 | controller |     sed -i 's/192.168.122/192.168.111/g' /etc/ci/env/networking-definition.yml
2026-03-17 14:45:21.702049 | controller |   delta: '0:00:00.005349'
2026-03-17 14:45:21.702055 | controller |   end: '2026-03-17 14:45:21.678065'
2026-03-17 14:45:21.702063 | controller |   msg: non-zero return code
2026-03-17 14:45:21.702096 | controller |   rc: 4
2026-03-17 14:45:21.702191 | controller |   start: '2026-03-17 14:45:21.672716'
2026-03-17 14:45:21.702280 | controller |   stderr: 'sed: couldn''t open temporary file /etc/ci/env/sedTI10ng: Permission denied'
2026-03-17 14:45:21.702352 | controller |   stderr_lines: <omitted>
2026-03-17 14:45:21.702437 | controller |   stdout: ''
2026-03-17 14:45:21.702524 | controller |   stdout_lines: <omitted>
2026-03-17 14:45:21.708532 | controller |
2026-03-17 14:45:21.708601 | controller | TASK [Create dnsmasq dir] ******************************************************
2026-03-17 14:45:21.708702 | controller | Tuesday 17 March 2026  14:45:21 +0000 (0:00:00.198)       0:00:46.453 *********
2026-03-17 14:45:21.892667 | controller | changed: [instance]
2026-03-17 14:45:21.892790 | controller |
2026-03-17 14:45:21.892931 | controller | TASK [Get journalctl in a file] ************************************************
2026-03-17 14:45:21.893042 | controller | Tuesday 17 March 2026  14:45:21 +0000 (0:00:00.184)       0:00:46.637 *********
2026-03-17 14:45:22.093646 | controller | changed: [instance]
2026-03-17 14:45:22.093754 | controller |
2026-03-17 14:45:22.093887 | controller | TASK [Dump journalctl output] **************************************************
2026-03-17 14:45:22.093986 | controller | Tuesday 17 March 2026  14:45:22 +0000 (0:00:00.200)       0:00:46.838 *********
2026-03-17 14:45:22.447121 | controller | changed: [instance]
2026-03-17 14:45:22.447199 | controller |
2026-03-17 14:45:22.447264 | controller | TASK [Copy all dnsmasq config] *************************************************
2026-03-17 14:45:22.447356 | controller | Tuesday 17 March 2026  14:45:22 +0000 (0:00:00.353)       0:00:47.191 *********
2026-03-17 14:45:22.774500 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.conf) => changed=false
2026-03-17 14:45:22.774874 | controller |   ansible_loop_var: item
2026-03-17 14:45:22.775114 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.conf /home/zuul/ci-framework-data/artifacts/dnsmasq
2026-03-17 14:45:22.775141 | controller |   item: /etc/cifmw-dnsmasq.conf
2026-03-17 14:45:22.775148 | controller |   msg: |-
2026-03-17 14:45:22.775158 | controller |     rsync: [sender] link_stat "/etc/cifmw-dnsmasq.conf" failed: No such file or directory (2)
2026-03-17 14:45:23.061850 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2026-03-17 14:45:23.061884 | controller |   rc: 23
2026-03-17 14:45:23.061897 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.d/) => changed=false
2026-03-17 14:45:23.062409 | controller |   ansible_loop_var: item
2026-03-17 14:45:23.062440 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.d/ /home/zuul/ci-framework-data/artifacts/dnsmasq
2026-03-17 14:45:23.065857 | controller |   item: /etc/cifmw-dnsmasq.d/
2026-03-17 14:45:23.065877 | controller |   msg: |-
2026-03-17 14:45:23.065889 | controller |     rsync: [sender] change_dir "/etc/cifmw-dnsmasq.d" failed: No such file or directory (2)
2026-03-17 14:45:23.065895 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2026-03-17 14:45:23.065901 | controller |   rc: 23
2026-03-17 14:45:23.065913 | controller |
2026-03-17 14:45:23.067453 | controller | PLAY RECAP *********************************************************************
2026-03-17 14:45:23.067471 | controller | instance                   : ok=122  changed=40   unreachable=0    failed=2    skipped=24   rescued=0    ignored=1
2026-03-17 14:45:23.067479 | controller |
2026-03-17 14:45:23.067485 | controller | Tuesday 17 March 2026  14:45:23 +0000 (0:00:00.618)       0:00:47.810 *********
2026-03-17 14:45:23.067490 | controller | ===============================================================================
2026-03-17 14:45:23.067496 | controller | libvirt_manager : Install packages required for using KVM --------------- 9.96s
2026-03-17 14:45:23.067504 | controller | ci_nmstate : Install required packages on localhost --------------------- 4.77s
2026-03-17 14:45:23.132907 | controller | discover_latest_image : Get latest image for the current entry ---------- 1.73s
2026-03-17 14:45:23.132926 | controller | ci_nmstate : Apply the desidered state on localhost --------------------- 1.58s
2026-03-17 14:45:23.132933 | controller | reproducer : Ensure firewalld is installed ------------------------------ 1.22s
2026-03-17 14:45:23.132939 | controller | libvirt_manager : Deactivate crc ---------------------------------------- 1.15s
2026-03-17 14:45:23.132944 | controller | Gathering Facts --------------------------------------------------------- 0.76s
2026-03-17 14:45:23.132949 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.70s
2026-03-17 14:45:23.132954 | controller | ci_network : Remove the local dns configuration ------------------------- 0.69s
2026-03-17 14:45:23.132958 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.63s
2026-03-17 14:45:23.132963 | controller | Copy all dnsmasq config ------------------------------------------------- 0.62s
2026-03-17 14:45:23.132968 | controller | libvirt_manager : Test -------------------------------------------------- 0.57s
2026-03-17 14:45:23.132973 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.54s
2026-03-17 14:45:23.132978 | controller | reproducer : Ensure the required parameters are loaded ------------------ 0.54s
2026-03-17 14:45:23.132983 | controller | libvirt_manager : Deactivate default ------------------------------------ 0.53s
2026-03-17 14:45:23.132988 | controller | libvirt_manager : Enable UNIX socket PolicyKit auth --------------------- 0.48s
2026-03-17 14:45:23.132993 | controller | reproducer : Ensure directories are present ----------------------------- 0.43s
2026-03-17 14:45:23.133001 | controller | reproducer : Restart NetworkManager ------------------------------------- 0.40s
2026-03-17 14:45:23.133006 | controller | libvirt_manager : daniel debug ------------------------------------------ 0.37s
2026-03-17 14:45:23.133011 | controller | networking_mapper : Write the Networking Definition to file ------------- 0.36s
2026-03-17 14:45:23.133020 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/reproducer/crc_layout/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/crc_layout/converge.yml']
2026-03-17 14:45:23.133402 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-03-17 14:45:23.141204 | controller | INFO     Running crc_layout > cleanup
2026-03-17 14:45:23.141599 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-03-17 14:45:23.148391 | controller | INFO     Running crc_layout > destroy
2026-03-17 14:45:23.148707 | controller | WARNING  Skipping, instances are delegated.
2026-03-17 14:45:23.149024 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-03-17 14:45:23.150400 | controller | INFO     Writing /tmp/report.html report.
2026-03-17 14:45:23.395668 | [controller] Waiting on logger
2026-03-17 14:45:28.571803 | [controller] Waiting on logger
2026-03-17 14:45:39.003730 | [controller] Waiting on logger
2026-03-17 14:45:49.436112 | [controller] Waiting on logger
2026-03-17 14:45:53.217879 | [Zuul] Log Stream did not terminate
2026-03-17 14:45:53.218079 | controller | ERROR
2026-03-17 14:45:53.218171 | controller | {
2026-03-17 14:45:53.218206 | controller |   "delta": "0:02:22.141590",
2026-03-17 14:45:53.218230 | controller |   "end": "2026-03-17 14:45:23.196240",
2026-03-17 14:45:53.218251 | controller |   "msg": "non-zero return code",
2026-03-17 14:45:53.218268 | controller |   "rc": 1,
2026-03-17 14:45:53.218286 | controller |   "start": "2026-03-17 14:43:01.054650"
2026-03-17 14:45:53.218326 | controller | }
failure
2026-03-17 14:45:53.267811 | 
2026-03-17 14:45:53.267874 | PLAY RECAP
2026-03-17 14:45:53.267915 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-17 14:45:53.267937 | 
2026-03-17 14:45:53.327000 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-17 14:45:53.327717 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-17 14:45:53.824558 | 
2026-03-17 14:45:53.824650 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-17 14:45:53.844667 | 
2026-03-17 14:45:53.844735 | TASK [Filter out host if needed]
2026-03-17 14:45:53.852510 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-17 14:45:53.856705 | 
2026-03-17 14:45:53.856767 | TASK [Ensure file is present]
2026-03-17 14:45:54.199015 | controller | ok
2026-03-17 14:45:54.205064 | 
2026-03-17 14:45:54.205129 | TASK [Manage molecule report file]
2026-03-17 14:45:54.225417 | [controller] Waiting on logger
2026-03-17 14:46:03.579976 | [controller] Waiting on logger
2026-03-17 14:46:14.011996 | [controller] Waiting on logger
2026-03-17 14:46:24.444006 | [controller] Waiting on logger
2026-03-17 14:46:24.569445 | [Zuul] Log Stream did not terminate
2026-03-17 14:46:24.569689 | controller | changed
2026-03-17 14:46:24.575063 | 
2026-03-17 14:46:24.575128 | TASK [Check if we get ci-framework-data basedir]
2026-03-17 14:46:24.806534 | controller | ok
2026-03-17 14:46:24.811177 | 
2026-03-17 14:46:24.811236 | TASK [Create ci-framework-data log directory for zuul]
2026-03-17 14:46:25.157089 | controller | changed
2026-03-17 14:46:25.163115 | 
2026-03-17 14:46:25.163187 | TASK [Copy ci-framework interesting files]
2026-03-17 14:46:30.189133 | controller | changed
2026-03-17 14:46:30.194819 | 
2026-03-17 14:46:30.194892 | TASK [Get SELinux listing]
2026-03-17 14:46:30.213937 | [controller] Waiting on logger
2026-03-17 14:46:39.547992 | [controller] Waiting on logger
2026-03-17 14:46:49.979918 | [controller] Waiting on logger
2026-03-17 14:47:00.411840 | [controller] Waiting on logger
2026-03-17 14:47:00.454815 | [Zuul] Log Stream did not terminate
2026-03-17 14:47:00.455007 | controller | changed
2026-03-17 14:47:00.460351 | 
2026-03-17 14:47:00.460422 | TASK [Generate log index]
2026-03-17 14:47:01.430868 | controller | changed
2026-03-17 14:47:01.435280 | 
2026-03-17 14:47:01.435364 | TASK [Get some env related data]
2026-03-17 14:47:01.456838 | [controller] Waiting on logger
2026-03-17 14:47:19.035967 | [controller] Waiting on logger
2026-03-17 14:47:21.147942 | [controller] Waiting on logger
2026-03-17 14:47:26.255066 | controller | changed
2026-03-17 14:47:26.260092 | 
2026-03-17 14:47:26.260154 | TASK [Generate list of logs to collect in home directory]
2026-03-17 14:47:26.582577 | controller | ok: All paths examined
2026-03-17 14:47:26.605685 | 
2026-03-17 14:47:26.605758 | LOOP [Copy logs from home directory]
2026-03-17 14:47:27.105529 | controller | changed:
2026-03-17 14:47:27.105801 | controller | {
2026-03-17 14:47:27.105834 | controller |   "atime": 1772005405.932821,
2026-03-17 14:47:27.105865 | controller |   "ctime": 1772006396.9238591,
2026-03-17 14:47:27.105882 | controller |   "dev": 64513,
2026-03-17 14:47:27.105901 | controller |   "gid": 1000,
2026-03-17 14:47:27.105917 | controller |   "gr_name": "zuul",
2026-03-17 14:47:27.105932 | controller |   "inode": 2773,
2026-03-17 14:47:27.105946 | controller |   "isblk": false,
2026-03-17 14:47:27.105960 | controller |   "ischr": false,
2026-03-17 14:47:27.105978 | controller |   "isdir": false,
2026-03-17 14:47:27.105992 | controller |   "isfifo": false,
2026-03-17 14:47:27.106005 | controller |   "isgid": false,
2026-03-17 14:47:27.106018 | controller |   "islnk": false,
2026-03-17 14:47:27.106031 | controller |   "isreg": true,
2026-03-17 14:47:27.106043 | controller |   "issock": false,
2026-03-17 14:47:27.106055 | controller |   "isuid": false,
2026-03-17 14:47:27.106067 | controller |   "mode": "0644",
2026-03-17 14:47:27.106082 | controller |   "mtime": 1772006396.9238591,
2026-03-17 14:47:27.106095 | controller |   "nlink": 1,
2026-03-17 14:47:27.106108 | controller |   "path": "/home/zuul/crc-setup.log",
2026-03-17 14:47:27.106120 | controller |   "pw_name": "zuul",
2026-03-17 14:47:27.106133 | controller |   "rgrp": true,
2026-03-17 14:47:27.106145 | controller |   "roth": true,
2026-03-17 14:47:27.106157 | controller |   "rusr": true,
2026-03-17 14:47:27.106169 | controller |   "size": 4108,
2026-03-17 14:47:27.106181 | controller |   "uid": 1000,
2026-03-17 14:47:27.106194 | controller |   "wgrp": false,
2026-03-17 14:47:27.106206 | controller |   "woth": false,
2026-03-17 14:47:27.106218 | controller |   "wusr": true,
2026-03-17 14:47:27.106230 | controller |   "xgrp": false,
2026-03-17 14:47:27.106242 | controller |   "xoth": false,
2026-03-17 14:47:27.106255 | controller |   "xusr": false
2026-03-17 14:47:27.106268 | controller | }
2026-03-17 14:47:27.577597 | controller | changed:
2026-03-17 14:47:27.577647 | controller | {
2026-03-17 14:47:27.577670 | controller |   "atime": 1772006398.6759083,
2026-03-17 14:47:27.577687 | controller |   "ctime": 1772007098.5537844,
2026-03-17 14:47:27.577708 | controller |   "dev": 64513,
2026-03-17 14:47:27.577724 | controller |   "gid": 1000,
2026-03-17 14:47:27.577738 | controller |   "gr_name": "zuul",
2026-03-17 14:47:27.577752 | controller |   "inode": 359205,
2026-03-17 14:47:27.577766 | controller |   "isblk": false,
2026-03-17 14:47:27.577781 | controller |   "ischr": false,
2026-03-17 14:47:27.577794 | controller |   "isdir": false,
2026-03-17 14:47:27.577808 | controller |   "isfifo": false,
2026-03-17 14:47:27.577821 | controller |   "isgid": false,
2026-03-17 14:47:27.577833 | controller |   "islnk": false,
2026-03-17 14:47:27.577858 | controller |   "isreg": true,
2026-03-17 14:47:27.577872 | controller |   "issock": false,
2026-03-17 14:47:27.577885 | controller |   "isuid": false,
2026-03-17 14:47:27.577897 | controller |   "mode": "0644",
2026-03-17 14:47:27.577909 | controller |   "mtime": 1772007098.5537844,
2026-03-17 14:47:27.577921 | controller |   "nlink": 1,
2026-03-17 14:47:27.577933 | controller |   "path": "/home/zuul/crc-start.log",
2026-03-17 14:47:27.577946 | controller |   "pw_name": "zuul",
2026-03-17 14:47:27.577958 | controller |   "rgrp": true,
2026-03-17 14:47:27.577970 | controller |   "roth": true,
2026-03-17 14:47:27.577982 | controller |   "rusr": true,
2026-03-17 14:47:27.577995 | controller |   "size": 4654,
2026-03-17 14:47:27.578006 | controller |   "uid": 1000,
2026-03-17 14:47:27.578018 | controller |   "wgrp": false,
2026-03-17 14:47:27.578030 | controller |   "woth": false,
2026-03-17 14:47:27.578042 | controller |   "wusr": true,
2026-03-17 14:47:27.578054 | controller |   "xgrp": false,
2026-03-17 14:47:27.578065 | controller |   "xoth": false,
2026-03-17 14:47:27.578077 | controller |   "xusr": false
2026-03-17 14:47:27.578089 | controller | }
2026-03-17 14:47:28.040553 | controller | changed:
2026-03-17 14:47:28.040599 | controller | {
2026-03-17 14:47:28.040623 | controller |   "atime": 1773758562.499733,
2026-03-17 14:47:28.040641 | controller |   "ctime": 1773758578.7316017,
2026-03-17 14:47:28.040657 | controller |   "dev": 64513,
2026-03-17 14:47:28.040672 | controller |   "gid": 1000,
2026-03-17 14:47:28.040685 | controller |   "gr_name": "zuul",
2026-03-17 14:47:28.040699 | controller |   "inode": 1189028,
2026-03-17 14:47:28.040712 | controller |   "isblk": false,
2026-03-17 14:47:28.040726 | controller |   "ischr": false,
2026-03-17 14:47:28.040739 | controller |   "isdir": false,
2026-03-17 14:47:28.040752 | controller |   "isfifo": false,
2026-03-17 14:47:28.040765 | controller |   "isgid": false,
2026-03-17 14:47:28.040781 | controller |   "islnk": false,
2026-03-17 14:47:28.040794 | controller |   "isreg": true,
2026-03-17 14:47:28.040807 | controller |   "issock": false,
2026-03-17 14:47:28.040820 | controller |   "isuid": false,
2026-03-17 14:47:28.040833 | controller |   "mode": "0644",
2026-03-17 14:47:28.040855 | controller |   "mtime": 1773758578.7316017,
2026-03-17 14:47:28.040870 | controller |   "nlink": 1,
2026-03-17 14:47:28.040883 | controller |   "path": "/home/zuul/ansible.log",
2026-03-17 14:47:28.040895 | controller |   "pw_name": "zuul",
2026-03-17 14:47:28.040908 | controller |   "rgrp": true,
2026-03-17 14:47:28.040920 | controller |   "roth": true,
2026-03-17 14:47:28.040932 | controller |   "rusr": true,
2026-03-17 14:47:28.040944 | controller |   "size": 6749,
2026-03-17 14:47:28.040956 | controller |   "uid": 1000,
2026-03-17 14:47:28.040969 | controller |   "wgrp": false,
2026-03-17 14:47:28.040981 | controller |   "woth": false,
2026-03-17 14:47:28.040994 | controller |   "wusr": true,
2026-03-17 14:47:28.041006 | controller |   "xgrp": false,
2026-03-17 14:47:28.041018 | controller |   "xoth": false,
2026-03-17 14:47:28.041030 | controller |   "xusr": false
2026-03-17 14:47:28.041042 | controller | }
2026-03-17 14:47:28.047619 | 
2026-03-17 14:47:28.047677 | TASK [Copy crio stats log file]
2026-03-17 14:47:28.060963 | controller | skipping: Conditional result was False
2026-03-17 14:47:28.065655 | 
2026-03-17 14:47:28.065715 | TASK [Get SELinux related data]
2026-03-17 14:47:29.147953 | [controller] Waiting on logger
2026-03-17 14:47:38.528429 | [controller] Waiting on logger
2026-03-17 14:47:48.923803 | [controller] Waiting on logger
2026-03-17 14:47:58.345776 | [Zuul] Log Stream did not terminate
2026-03-17 14:47:58.346073 | controller | ERROR
2026-03-17 14:47:58.346244 | controller | {
2026-03-17 14:47:58.346329 | controller |   "delta": "0:00:00.008726",
2026-03-17 14:47:58.346382 | controller |   "end": "2026-03-17 14:47:28.321156",
2026-03-17 14:47:58.346416 | controller |   "msg": "non-zero return code",
2026-03-17 14:47:58.346447 | controller |   "rc": 1,
2026-03-17 14:47:58.346475 | controller |   "start": "2026-03-17 14:47:28.312430"
2026-03-17 14:47:58.346501 | controller | }
2026-03-17 14:47:58.346538 | controller | ERROR: Ignoring Errors
2026-03-17 14:47:58.351660 | 
2026-03-17 14:47:58.351719 | TASK [Create system configuration directory]
2026-03-17 14:47:58.584454 | controller | changed
2026-03-17 14:47:58.589056 | 
2026-03-17 14:47:58.589120 | TASK [Get some of the system configurations]
2026-03-17 14:47:58.608786 | [controller] Waiting on logger
2026-03-17 14:48:01.403870 | [controller] Waiting on logger
2026-03-17 14:48:05.999177 | controller | changed
2026-03-17 14:48:06.004201 | 
2026-03-17 14:48:06.004262 | TASK [Copy generated documentation if available]
2026-03-17 14:48:06.017559 | controller | skipping: Conditional result was False
2026-03-17 14:48:06.022666 | 
2026-03-17 14:48:06.022723 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-17 14:48:06.035785 | controller | skipping: Conditional result was False
2026-03-17 14:48:06.040923 | 
2026-03-17 14:48:06.040980 | TASK [Compress logs bigger than 2MB]
2026-03-17 14:48:07.100064 | [controller] Waiting on logger
2026-03-17 14:48:16.815182 | controller | changed
2026-03-17 14:48:16.820825 | 
2026-03-17 14:48:16.820899 | TASK [Copy files from workspace on node]
2026-03-17 14:48:16.838704 | controller | ok
2026-03-17 14:48:16.858898 | 
2026-03-17 14:48:16.858964 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-17 14:48:16.873998 | controller | skipping: Conditional result was False
2026-03-17 14:48:16.881488 | 
2026-03-17 14:48:16.881570 | TASK [fetch-output : Set log path for single node]
2026-03-17 14:48:16.914277 | controller | ok
2026-03-17 14:48:16.918966 | 
2026-03-17 14:48:16.919026 | LOOP [fetch-output : Ensure local output dirs]
2026-03-17 14:48:17.019912 | [controller] Waiting on logger
2026-03-17 14:48:17.117724 | controller -> localhost | ok: "/var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/logs"
2026-03-17 14:48:17.118083 | controller -> localhost | changed: All items complete
2026-03-17 14:48:17.118135 | 
2026-03-17 14:48:17.292645 | controller -> localhost | changed: "/var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/artifacts"
2026-03-17 14:48:17.461115 | controller -> localhost | changed: "/var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/docs"
2026-03-17 14:48:17.471218 | 
2026-03-17 14:48:17.471378 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-17 14:48:18.062278 | controller | changed:
2026-03-17 14:48:18.062520 | controller | .d..t...... ./
2026-03-17 14:48:18.062557 | controller | >f+++++++++ README.html
2026-03-17 14:48:18.062576 | controller | >f+++++++++ ansible-execution.log
2026-03-17 14:48:18.062593 | controller | >f+++++++++ ansible.log
2026-03-17 14:48:18.062609 | controller | >f+++++++++ crc-setup.log
2026-03-17 14:48:18.062624 | controller | >f+++++++++ crc-start.log
2026-03-17 14:48:18.062638 | controller | >f+++++++++ dmesg.log
2026-03-17 14:48:18.062652 | controller | >f+++++++++ installed-pkgs.log
2026-03-17 14:48:18.062665 | controller | >f+++++++++ python.log
2026-03-17 14:48:18.062679 | controller | >f+++++++++ registries.conf
2026-03-17 14:48:18.062691 | controller | >f+++++++++ report.html
2026-03-17 14:48:18.062705 | controller | >f+++++++++ selinux-denials.log
2026-03-17 14:48:18.062719 | controller | >f+++++++++ selinux-listing.log
2026-03-17 14:48:18.062732 | controller | cd+++++++++ ci-framework-data/
2026-03-17 14:48:18.062755 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-17 14:48:18.062770 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-17 14:48:18.062784 | controller | cd+++++++++ ci-framework-data/artifacts/dnsmasq/
2026-03-17 14:48:18.062798 | controller | >f+++++++++ ci-framework-data/artifacts/dnsmasq/journalctl.log
2026-03-17 14:48:18.062812 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-17 14:48:18.062825 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-17 14:48:18.062848 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-17 14:48:18.062862 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-17 14:48:18.062875 | controller | cd+++++++++ registries.conf.d/
2026-03-17 14:48:18.062889 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-17 14:48:18.062902 | controller | cd+++++++++ system-config/
2026-03-17 14:48:18.062916 | controller | cd+++++++++ system-config/libvirt/
2026-03-17 14:48:18.062929 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-17 14:48:18.062942 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-17 14:48:18.062954 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-17 14:48:18.062967 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-17 14:48:18.062980 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-17 14:48:18.062992 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-17 14:48:18.063005 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-17 14:48:18.063018 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-17 14:48:18.063030 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-17 14:48:18.063043 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-17 14:48:18.063055 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-17 14:48:18.063067 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-17 14:48:18.063079 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-17 14:48:18.063091 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-17 14:48:18.063104 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-17 14:48:18.063116 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-17 14:48:18.472354 | controller | changed: .d..t...... ./
2026-03-17 14:48:18.877189 | controller | changed: .d..t...... ./
2026-03-17 14:48:18.897673 | 
2026-03-17 14:48:18.897798 | TASK [Return artifact to Zuul]
2026-03-17 14:48:18.927254 | controller | ok
2026-03-17 14:48:18.945188 | 
2026-03-17 14:48:18.945240 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-17 14:48:18.945368 | 
2026-03-17 14:48:18.945402 | PLAY RECAP
2026-03-17 14:48:18.945441 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-17 14:48:18.945461 | 
2026-03-17 14:48:19.027681 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-17 14:48:19.028473 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-17 14:48:19.511085 | 
2026-03-17 14:48:19.511224 | PLAY [all]
2026-03-17 14:48:19.528457 | 
2026-03-17 14:48:19.528523 | TASK [include_role : fetch-output]
2026-03-17 14:48:19.556874 | controller | ok
2026-03-17 14:48:19.572715 | 
2026-03-17 14:48:19.572793 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-17 14:48:19.616563 | controller | skipping: Conditional result was False
2026-03-17 14:48:19.621955 | 
2026-03-17 14:48:19.622024 | TASK [fetch-output : Set log path for single node]
2026-03-17 14:48:19.650309 | controller | ok
2026-03-17 14:48:19.655028 | 
2026-03-17 14:48:19.655094 | LOOP [fetch-output : Ensure local output dirs]
2026-03-17 14:48:19.953512 | controller -> localhost | ok: "/var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/logs"
2026-03-17 14:48:20.124480 | controller -> localhost | ok: "/var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/artifacts"
2026-03-17 14:48:20.295618 | controller -> localhost | ok: "/var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/docs"
2026-03-17 14:48:20.307889 | 
2026-03-17 14:48:20.307986 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-17 14:48:20.844994 | controller | ok
2026-03-17 14:48:20.845177 | controller | ok: All items complete
2026-03-17 14:48:20.845204 | 
2026-03-17 14:48:21.250795 | controller | ok
2026-03-17 14:48:21.655391 | controller | ok
2026-03-17 14:48:21.674532 | 
2026-03-17 14:48:21.674632 | TASK [include_role : fetch-output-openshift]
2026-03-17 14:48:21.688026 | controller | skipping: Conditional result was False
2026-03-17 14:48:21.693660 | 
2026-03-17 14:48:21.693726 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-17 14:48:22.017351 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005890
2026-03-17 14:48:22.193622 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006420
2026-03-17 14:48:22.225262 | 
2026-03-17 14:48:22.225373 | PLAY [all]
2026-03-17 14:48:22.237901 | 
2026-03-17 14:48:22.237967 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-17 14:48:22.662189 | controller | changed
2026-03-17 14:48:22.683846 | 
2026-03-17 14:48:22.683944 | PLAY RECAP
2026-03-17 14:48:22.683991 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-17 14:48:22.684013 | 
2026-03-17 14:48:22.748459 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-17 14:48:22.749140 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-17 14:48:23.258462 | 
2026-03-17 14:48:23.258562 | PLAY [localhost]
2026-03-17 14:48:23.274131 | 
2026-03-17 14:48:23.274200 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-17 14:48:23.554494 | localhost | changed
2026-03-17 14:48:23.558603 | 
2026-03-17 14:48:23.558678 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-17 14:48:23.575550 | localhost | ok
2026-03-17 14:48:23.582865 | 
2026-03-17 14:48:23.582933 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-17 14:48:23.853039 | localhost | changed
2026-03-17 14:48:23.858726 | 
2026-03-17 14:48:23.858794 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-17 14:48:24.361366 | localhost | changed
2026-03-17 14:48:24.365855 | 
2026-03-17 14:48:24.365933 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-17 14:48:24.676518 | localhost | Identity added: /var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/tmp/ansible.ytks9rc9 (/var/lib/zuul/builds/e24528d88cb14af9a915a1f3e4272715/work/tmp/ansible.ytks9rc9)
2026-03-17 14:48:24.676681 | localhost | ok: Runtime: 0:00:00.006253
2026-03-17 14:48:24.680808 | 
2026-03-17 14:48:24.680900 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-17 14:48:24.890576 | localhost | ok: Runtime: 0:00:00.004262
2026-03-17 14:48:24.895053 | 
2026-03-17 14:48:24.895117 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-17 14:48:24.942996 | localhost | changed
2026-03-17 14:48:24.947709 | 
2026-03-17 14:48:24.947775 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-17 14:48:25.248645 | localhost | changed
2026-03-17 14:48:25.268558 | 
2026-03-17 14:48:25.268636 | PLAY [localhost]
2026-03-17 14:48:25.279959 | 
2026-03-17 14:48:25.280025 | TASK [Generate bulk log download script]
2026-03-17 14:48:25.298678 | localhost | ok
2026-03-17 14:48:25.309684 | 
2026-03-17 14:48:25.309751 | TASK [local-log-download : Check API endpoint is defined]
2026-03-17 14:48:25.336617 | localhost | ok: All assertions passed
2026-03-17 14:48:25.340935 | 
2026-03-17 14:48:25.341004 | TASK [local-log-download : Create download script]
2026-03-17 14:48:25.667589 | localhost -> localhost | changed
2026-03-17 14:48:25.676673 | 
2026-03-17 14:48:25.676759 | TASK [Register quick-download link]
2026-03-17 14:48:25.704032 | localhost | ok
2026-03-17 14:48:25.739677 | 
2026-03-17 14:48:25.739739 | PLAY [logserver.rdoproject.org]
2026-03-17 14:48:25.748450 | 
2026-03-17 14:48:25.748504 | TASK [Set zuul-log-path fact]
2026-03-17 14:48:25.766062 | logserver.rdoproject.org | ok
2026-03-17 14:48:25.774504 | 
2026-03-17 14:48:25.774567 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 14:48:25.801599 | logserver.rdoproject.org | ok
2026-03-17 14:48:25.806978 | 
2026-03-17 14:48:25.807046 | TASK [upload-logs : Create log directories]
2026-03-17 14:48:28.562066 | logserver.rdoproject.org | changed
2026-03-17 14:48:28.564809 | 
2026-03-17 14:48:28.564880 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-17 14:48:28.760481 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.003793
2026-03-17 14:48:28.764646 | 
2026-03-17 14:48:28.764703 | TASK [upload-logs : Upload logs to log server]
2026-03-17 14:48:30.243381 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-17 14:48:30.246100 | 
2026-03-17 14:48:30.246159 | LOOP [upload-logs : Compress console log and json output]
2026-03-17 14:48:30.283248 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:48:30.291026 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:48:30.303956 | 
2026-03-17 14:48:30.304038 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-17 14:48:30.336979 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:48:30.337186 | 
2026-03-17 14:48:30.340186 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-17 14:48:30.351040 | 
2026-03-17 14:48:30.351120 | LOOP [upload-logs : Upload console log and json output]