2025-10-02 19:26:51.757378 | Job console starting...
2025-10-02 19:26:51.767462 | Updating repositories
2025-10-02 19:26:51.810779 | Preparing job workspace
2025-10-02 19:26:57.794282 | Running Ansible setup...
2025-10-02 19:27:03.087933 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 19:27:03.703239 | 
2025-10-02 19:27:03.703350 | PLAY [localhost]
2025-10-02 19:27:03.712318 | 
2025-10-02 19:27:03.712391 | TASK [Gathering Facts]
2025-10-02 19:27:04.850468 | localhost | ok
2025-10-02 19:27:04.874053 | 
2025-10-02 19:27:04.874249 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 19:27:05.256508 | localhost -> localhost | changed
2025-10-02 19:27:05.262803 | 
2025-10-02 19:27:05.262936 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 19:27:06.116078 | localhost -> localhost | changed
2025-10-02 19:27:06.124430 | 
2025-10-02 19:27:06.124534 | TASK [Setup log path fact]
2025-10-02 19:27:06.154695 | localhost | ok
2025-10-02 19:27:06.167017 | 
2025-10-02 19:27:06.167133 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 19:27:06.194849 | localhost | ok
2025-10-02 19:27:06.202357 | 
2025-10-02 19:27:06.202451 | TASK [emit-job-header : Print job information]
2025-10-02 19:27:06.229932 | # Job Information
2025-10-02 19:27:06.230150 | Ansible Version: 2.15.12
2025-10-02 19:27:06.230177 | Job: cifmw-molecule-ci_multus
2025-10-02 19:27:06.230196 | Pipeline: github-check
2025-10-02 19:27:06.230214 | Executor: ze03.softwarefactory-project.io
2025-10-02 19:27:06.230232 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 19:27:06.230250 | Log URL (when completed): https://logserver.rdoproject.org/d9b/rdoproject.org/d9bc47dd8a9c4b0a847bd8bf1ced091f/
2025-10-02 19:27:06.230267 | Event ID: 9ca491d0-9fc5-11f0-8734-f8eb35e200a5
2025-10-02 19:27:06.235006 | 
2025-10-02 19:27:06.235104 | LOOP [emit-job-header : Print node information]
2025-10-02 19:27:06.335667 | localhost | ok:
2025-10-02 19:27:06.335847 | localhost | # Node Information
2025-10-02 19:27:06.335876 | localhost | Inventory Hostname: controller
2025-10-02 19:27:06.335902 | localhost | Hostname: np0005467128
2025-10-02 19:27:06.335924 | localhost | Username: zuul
2025-10-02 19:27:06.335945 | localhost | Distro: CentOS 9
2025-10-02 19:27:06.335965 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-02 19:27:06.335985 | localhost | Region: RegionOne
2025-10-02 19:27:06.336003 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-10-02 19:27:06.336021 | localhost | Product Name: OpenStack Nova
2025-10-02 19:27:06.336039 | localhost | Interface IP: 38.102.83.189
2025-10-02 19:27:06.358322 | 
2025-10-02 19:27:06.358405 | PLAY [all]
2025-10-02 19:27:06.367011 | 
2025-10-02 19:27:06.367107 | TASK [Gather network facts]
2025-10-02 19:27:06.998108 | controller | ok
2025-10-02 19:27:07.018081 | 
2025-10-02 19:27:07.018194 | TASK [include_role : start-zuul-console]
2025-10-02 19:27:07.036540 | controller | ok
2025-10-02 19:27:07.049103 | 
2025-10-02 19:27:07.049213 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 19:27:07.640690 | controller | ok
2025-10-02 19:27:07.653588 | 
2025-10-02 19:27:07.653753 | TASK [include_role : add-build-sshkey]
2025-10-02 19:27:07.684603 | controller | ok
2025-10-02 19:27:07.706960 | 
2025-10-02 19:27:07.707161 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 19:27:07.960525 | controller -> localhost | ok
2025-10-02 19:27:07.967327 | 
2025-10-02 19:27:07.967531 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 19:27:08.004490 | controller | ok
2025-10-02 19:27:08.018294 | controller | included: /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 19:27:08.024510 | 
2025-10-02 19:27:08.024618 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 19:27:08.640057 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 19:27:08.640270 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/d9bc47dd8a9c4b0a847bd8bf1ced091f_id_rsa.
2025-10-02 19:27:08.640302 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/d9bc47dd8a9c4b0a847bd8bf1ced091f_id_rsa.pub.
2025-10-02 19:27:08.640324 | controller -> localhost | The key fingerprint is:
2025-10-02 19:27:08.640345 | controller -> localhost | SHA256:/oZB++N4E8TNvh1ZLNiJDFXbkKfvPdb5R4SXMj/S3O8 zuul-build-sshkey
2025-10-02 19:27:08.640364 | controller -> localhost | The key's randomart image is:
2025-10-02 19:27:08.640383 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 19:27:08.640402 | controller -> localhost | |           ...o. |
2025-10-02 19:27:08.640421 | controller -> localhost | |          .   .+.|
2025-10-02 19:27:08.640438 | controller -> localhost | |         . = +.*o|
2025-10-02 19:27:08.640456 | controller -> localhost | |        . o *o*.=|
2025-10-02 19:27:08.640473 | controller -> localhost | |       .So .  *B.|
2025-10-02 19:27:08.640491 | controller -> localhost | |       .o . ..o=+|
2025-10-02 19:27:08.640509 | controller -> localhost | |        .+ . o.+*|
2025-10-02 19:27:08.640526 | controller -> localhost | |        .o* . .+*|
2025-10-02 19:27:08.640544 | controller -> localhost | |        .+oo  ..E|
2025-10-02 19:27:08.640612 | controller -> localhost | +----[SHA256]-----+
2025-10-02 19:27:08.640660 | controller -> localhost | ok: Runtime: 0:00:00.199687
2025-10-02 19:27:08.646826 | 
2025-10-02 19:27:08.646891 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 19:27:08.674495 | controller | ok
2025-10-02 19:27:08.685603 | controller | included: /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 19:27:08.694075 | 
2025-10-02 19:27:08.694174 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 19:27:08.708130 | controller | skipping: Conditional result was False
2025-10-02 19:27:08.721074 | 
2025-10-02 19:27:08.721212 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 19:27:09.447686 | controller | changed
2025-10-02 19:27:09.460528 | 
2025-10-02 19:27:09.460643 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 19:27:09.822806 | controller | ok
2025-10-02 19:27:09.836465 | 
2025-10-02 19:27:09.836623 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 19:27:11.791524 | controller | changed
2025-10-02 19:27:11.797141 | 
2025-10-02 19:27:11.797244 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 19:27:13.555344 | controller | changed
2025-10-02 19:27:13.565855 | 
2025-10-02 19:27:13.565959 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 19:27:13.593821 | controller | skipping: Conditional result was False
2025-10-02 19:27:13.599651 | 
2025-10-02 19:27:13.599737 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 19:27:13.990651 | controller -> localhost | changed
2025-10-02 19:27:14.011867 | 
2025-10-02 19:27:14.011997 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 19:27:14.302716 | controller -> localhost | Identity added: /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/d9bc47dd8a9c4b0a847bd8bf1ced091f_id_rsa (zuul-build-sshkey)
2025-10-02 19:27:14.302915 | controller -> localhost | ok: Runtime: 0:00:00.008598
2025-10-02 19:27:14.309892 | 
2025-10-02 19:27:14.309980 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 19:27:14.895664 | controller | ok
2025-10-02 19:27:14.900408 | 
2025-10-02 19:27:14.900475 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 19:27:14.941795 | controller | skipping: Conditional result was False
2025-10-02 19:27:15.007076 | 
2025-10-02 19:27:15.007205 | TASK [include_role : validate-host]
2025-10-02 19:27:15.048536 | controller | ok
2025-10-02 19:27:15.075705 | 
2025-10-02 19:27:15.075842 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 19:27:15.115600 | controller | ok
2025-10-02 19:27:15.120335 | 
2025-10-02 19:27:15.120408 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 19:27:15.369955 | controller -> localhost | ok
2025-10-02 19:27:15.376374 | 
2025-10-02 19:27:15.376480 | TASK [validate-host : Collect information about the host]
2025-10-02 19:27:16.324380 | controller | ok
2025-10-02 19:27:16.340779 | 
2025-10-02 19:27:16.340902 | TASK [validate-host : Sanitize hostname]
2025-10-02 19:27:16.431135 | controller | ok
2025-10-02 19:27:16.436099 | 
2025-10-02 19:27:16.436169 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 19:27:17.133338 | controller -> localhost | changed
2025-10-02 19:27:17.145350 | 
2025-10-02 19:27:17.145475 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 19:27:17.743439 | controller | ok
2025-10-02 19:27:17.748729 | 
2025-10-02 19:27:17.748820 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 19:27:18.380530 | controller -> localhost | changed
2025-10-02 19:27:18.400310 | 
2025-10-02 19:27:18.400437 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 19:27:18.446416 | controller | skipping: Conditional result was False
2025-10-02 19:27:18.451683 | 
2025-10-02 19:27:18.451766 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 19:27:18.497020 | controller | skipping: Conditional result was False
2025-10-02 19:27:18.503300 | 
2025-10-02 19:27:18.503399 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 19:27:18.934527 | controller | ok: "logs"
2025-10-02 19:27:18.934797 | controller | ok: All items complete
2025-10-02 19:27:18.934830 | 
2025-10-02 19:27:19.302707 | controller | ok: "artifacts"
2025-10-02 19:27:19.701431 | controller | ok: "docs"
2025-10-02 19:27:19.715844 | 
2025-10-02 19:27:19.715987 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 19:27:20.170211 | controller | changed: "logs"
2025-10-02 19:27:20.550617 | controller | changed: "artifacts"
2025-10-02 19:27:20.925090 | controller | changed: "docs"
2025-10-02 19:27:20.979913 | 
2025-10-02 19:27:20.979998 | PLAY RECAP
2025-10-02 19:27:20.980042 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 19:27:20.980068 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 19:27:20.980085 | 
2025-10-02 19:27:21.137872 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 19:27:21.138713 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 19:27:21.769455 | 
2025-10-02 19:27:21.769585 | PLAY [all]
2025-10-02 19:27:21.790476 | 
2025-10-02 19:27:21.790587 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 19:27:21.861601 | controller | ok
2025-10-02 19:27:21.866755 | 
2025-10-02 19:27:21.866833 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 19:27:22.486883 | controller | changed
2025-10-02 19:27:22.496551 | 
2025-10-02 19:27:22.496794 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 19:27:24.630393 | controller | changed
2025-10-02 19:27:24.641660 | 
2025-10-02 19:27:24.641804 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-02 19:27:25.229002 | controller | changed:
2025-10-02 19:27:25.229232 | controller | {
2025-10-02 19:27:25.229267 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-02 19:27:25.229303 | controller | }
2025-10-02 19:27:25.643442 | controller | changed:
2025-10-02 19:27:25.643560 | controller | {
2025-10-02 19:27:25.643596 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-02 19:27:25.643625 | controller | }
2025-10-02 19:27:26.096927 | controller | changed:
2025-10-02 19:27:26.097101 | controller | {
2025-10-02 19:27:26.097129 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-02 19:27:26.097149 | controller | }
2025-10-02 19:27:26.503083 | controller | changed:
2025-10-02 19:27:26.503256 | controller | {
2025-10-02 19:27:26.503287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-02 19:27:26.503344 | controller | }
2025-10-02 19:27:26.906401 | controller | changed:
2025-10-02 19:27:26.906502 | controller | {
2025-10-02 19:27:26.906524 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-02 19:27:26.906543 | controller | }
2025-10-02 19:27:27.297542 | controller | changed:
2025-10-02 19:27:27.297626 | controller | {
2025-10-02 19:27:27.297650 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-02 19:27:27.297670 | controller | }
2025-10-02 19:27:27.670407 | controller | changed:
2025-10-02 19:27:27.670517 | controller | {
2025-10-02 19:27:27.670551 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-02 19:27:27.670578 | controller | }
2025-10-02 19:27:28.096847 | controller | changed:
2025-10-02 19:27:28.097025 | controller | {
2025-10-02 19:27:28.097053 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-02 19:27:28.097073 | controller | }
2025-10-02 19:27:28.502669 | controller | changed:
2025-10-02 19:27:28.502814 | controller | {
2025-10-02 19:27:28.502839 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-02 19:27:28.502858 | controller | }
2025-10-02 19:27:28.868938 | controller | changed:
2025-10-02 19:27:28.869182 | controller | {
2025-10-02 19:27:28.869219 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-02 19:27:28.869239 | controller | }
2025-10-02 19:27:29.272405 | controller | changed:
2025-10-02 19:27:29.272509 | controller | {
2025-10-02 19:27:29.272532 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-02 19:27:29.272552 | controller | }
2025-10-02 19:27:29.678714 | controller | changed:
2025-10-02 19:27:29.678841 | controller | {
2025-10-02 19:27:29.678864 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-02 19:27:29.678883 | controller | }
2025-10-02 19:27:30.077286 | controller | changed:
2025-10-02 19:27:30.077451 | controller | {
2025-10-02 19:27:30.077521 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-02 19:27:30.077575 | controller | }
2025-10-02 19:27:30.452853 | controller | changed:
2025-10-02 19:27:30.452986 | controller | {
2025-10-02 19:27:30.453040 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-02 19:27:30.453082 | controller | }
2025-10-02 19:27:30.908673 | controller | changed:
2025-10-02 19:27:30.908891 | controller | {
2025-10-02 19:27:30.908961 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-02 19:27:30.909049 | controller | }
2025-10-02 19:27:31.299074 | controller | changed:
2025-10-02 19:27:31.299436 | controller | {
2025-10-02 19:27:31.299472 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-02 19:27:31.299491 | controller | }
2025-10-02 19:27:31.691025 | controller | changed:
2025-10-02 19:27:31.691162 | controller | {
2025-10-02 19:27:31.691211 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-02 19:27:31.691252 | controller | }
2025-10-02 19:27:32.079620 | controller | changed:
2025-10-02 19:27:32.079759 | controller | {
2025-10-02 19:27:32.079804 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-02 19:27:32.079838 | controller | }
2025-10-02 19:27:32.493606 | controller | changed:
2025-10-02 19:27:32.493798 | controller | {
2025-10-02 19:27:32.493867 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-02 19:27:32.493919 | controller | }
2025-10-02 19:27:32.934497 | controller | changed:
2025-10-02 19:27:32.935182 | controller | {
2025-10-02 19:27:32.935238 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-02 19:27:32.935271 | controller | }
2025-10-02 19:27:33.357292 | controller | changed:
2025-10-02 19:27:33.357391 | controller | {
2025-10-02 19:27:33.357414 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-02 19:27:33.357432 | controller | }
2025-10-02 19:27:33.773166 | controller | changed:
2025-10-02 19:27:33.773344 | controller | {
2025-10-02 19:27:33.773402 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-02 19:27:33.773448 | controller | }
2025-10-02 19:27:34.187683 | controller | changed:
2025-10-02 19:27:34.187888 | controller | {
2025-10-02 19:27:34.187925 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-02 19:27:34.187954 | controller | }
2025-10-02 19:27:34.569675 | controller | changed:
2025-10-02 19:27:34.569796 | controller | {
2025-10-02 19:27:34.569820 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-02 19:27:34.569839 | controller | }
2025-10-02 19:27:34.966785 | controller | changed:
2025-10-02 19:27:34.967049 | controller | {
2025-10-02 19:27:34.967084 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-02 19:27:34.967102 | controller | }
2025-10-02 19:27:35.353320 | controller | changed:
2025-10-02 19:27:35.353428 | controller | {
2025-10-02 19:27:35.353450 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-02 19:27:35.353467 | controller | }
2025-10-02 19:27:35.412300 | 
2025-10-02 19:27:35.412461 | TASK [Set timezone to UTC]
2025-10-02 19:27:36.269254 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-02 19:27:36.274569 | 
2025-10-02 19:27:36.274656 | TASK [Create nodepool directory]
2025-10-02 19:27:36.731773 | controller | changed
2025-10-02 19:27:36.742074 | 
2025-10-02 19:27:36.742156 | TASK [Create nodepool sub_nodes file]
2025-10-02 19:27:38.505954 | controller | changed
2025-10-02 19:27:38.519254 | 
2025-10-02 19:27:38.519565 | TASK [Create nodepool sub_nodes_private file]
2025-10-02 19:27:40.214763 | controller | changed
2025-10-02 19:27:40.220515 | 
2025-10-02 19:27:40.220583 | LOOP [Populate nodepool sub_nodes file]
2025-10-02 19:27:40.261379 | 
2025-10-02 19:27:40.261545 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-02 19:27:40.301317 | 
2025-10-02 19:27:40.301478 | TASK [Create nodepool primary file]
2025-10-02 19:27:40.325032 | controller | skipping: Conditional result was False
2025-10-02 19:27:40.331590 | 
2025-10-02 19:27:40.331680 | TASK [Create nodepool node_private for this node]
2025-10-02 19:27:42.012986 | controller | changed
2025-10-02 19:27:42.018935 | 
2025-10-02 19:27:42.019007 | LOOP [Copy ssh keys to nodepool directory]
2025-10-02 19:27:42.603020 | controller | ok: Item: id_rsa Runtime: 0:00:00.010268
2025-10-02 19:27:42.603320 | 
2025-10-02 19:27:43.000632 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.008714
2025-10-02 19:27:43.016485 | 
2025-10-02 19:27:43.016684 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-02 19:27:44.694479 | controller | changed
2025-10-02 19:27:44.699875 | 
2025-10-02 19:27:44.699985 | TASK [Validate sudoers config after edits]
2025-10-02 19:27:45.185732 | controller | /etc/sudoers: parsed OK
2025-10-02 19:27:45.185875 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-02 19:27:45.185896 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-02 19:27:45.185997 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-02 19:27:45.274257 | controller | ok: Runtime: 0:00:00.010055
2025-10-02 19:27:45.283255 | 
2025-10-02 19:27:45.283387 | TASK [Show the environment passed in to job shell scripts]
2025-10-02 19:27:45.691771 | controller | SHELL=/bin/bash
2025-10-02 19:27:45.691846 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-02 19:27:45.691859 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-02 19:27:45.691869 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 19:27:45.691877 | controller | PWD=/home/zuul
2025-10-02 19:27:45.691885 | controller | ZUUL_PIPELINE=github-check
2025-10-02 19:27:45.691893 | controller | LOGNAME=zuul
2025-10-02 19:27:45.691900 | controller | XDG_SESSION_TYPE=tty
2025-10-02 19:27:45.691908 | controller | _=/usr/bin/env
2025-10-02 19:27:45.691915 | controller | MOTD_SHOWN=pam
2025-10-02 19:27:45.691923 | controller | HOME=/home/zuul
2025-10-02 19:27:45.691930 | controller | LANG=en_US.UTF-8
2025-10-02 19:27:45.691937 | controller | SSH_CONNECTION=38.102.83.114 56176 38.102.83.189 22
2025-10-02 19:27:45.691945 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-02 19:27:45.691956 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 19:27:45.691964 | controller | WORKSPACE=/home/zuul/workspace
2025-10-02 19:27:45.691971 | controller | XDG_SESSION_CLASS=user
2025-10-02 19:27:45.691979 | controller | SELINUX_ROLE_REQUESTED=
2025-10-02 19:27:45.691986 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-02 19:27:45.691996 | controller | USER=zuul
2025-10-02 19:27:45.692004 | controller | ZUUL_VOTING=True
2025-10-02 19:27:45.692011 | controller | BUILD_TIMEOUT=1800000
2025-10-02 19:27:45.692019 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-02 19:27:45.692026 | controller | SHLVL=1
2025-10-02 19:27:45.692033 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 19:27:45.692040 | controller | XDG_SESSION_ID=1
2025-10-02 19:27:45.692047 | controller | ZUUL_BRANCH=main
2025-10-02 19:27:45.692055 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-02 19:27:45.692062 | controller | SSH_CLIENT=38.102.83.114 56176 22
2025-10-02 19:27:45.692069 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-02 19:27:45.692076 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-02 19:27:45.692152 | controller | which_declare=declare -f
2025-10-02 19:27:45.692163 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-02 19:27:45.692171 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-02 19:27:45.692177 | controller | ZUUL_CHANGE=3362
2025-10-02 19:27:45.692184 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-02 19:27:45.692191 | controller | ZUUL_UUID=d9bc47dd8a9c4b0a847bd8bf1ced091f
2025-10-02 19:27:45.692198 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-02 19:27:45.692206 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-02 19:27:45.692214 | controller | }
2025-10-02 19:27:45.852921 | controller | ok: Runtime: 0:00:00.011465
2025-10-02 19:27:45.860065 | 
2025-10-02 19:27:45.860179 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-02 19:27:45.886264 | controller | skipping: Conditional result was False
2025-10-02 19:27:45.895590 | 
2025-10-02 19:27:45.895682 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-02 19:27:46.466089 | controller | skipping: Conditional result was False
2025-10-02 19:27:46.481239 | 
2025-10-02 19:27:46.481412 | TASK [Ensure legacy workspace directory]
2025-10-02 19:27:46.821549 | controller | changed
2025-10-02 19:27:46.843370 | 
2025-10-02 19:27:46.843473 | PLAY RECAP
2025-10-02 19:27:46.843513 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 19:27:46.843533 | 
2025-10-02 19:27:46.987072 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 19:27:46.988963 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-02 19:27:47.590672 | 
2025-10-02 19:27:47.590796 | PLAY [all,!compute]
2025-10-02 19:27:47.610759 | 
2025-10-02 19:27:47.610877 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-02 19:27:48.142803 | controller | skipping: Conditional result was False
2025-10-02 19:27:48.157722 | 
2025-10-02 19:27:48.157952 | TASK [Install podman]
2025-10-02 19:27:48.215356 | controller | skipping: Conditional result was False
2025-10-02 19:27:48.223899 | 
2025-10-02 19:27:48.224008 | TASK [Set var name for quay login zuul secret]
2025-10-02 19:27:48.324852 | controller | skipping: Conditional result was False
2025-10-02 19:27:48.332920 | 
2025-10-02 19:27:48.333072 | TASK [Print the username]
2025-10-02 19:27:48.871292 | controller | skipping: Conditional result was False
2025-10-02 19:27:48.884949 | 
2025-10-02 19:27:48.885222 | TASK [Fail when user or password is not set]
2025-10-02 19:27:48.952953 | controller | skipping: Conditional result was False
2025-10-02 19:27:48.964664 | 
2025-10-02 19:27:48.964844 | TASK [Set vars for quay login]
2025-10-02 19:27:49.038955 | 
2025-10-02 19:27:49.039228 | LOOP [Perform container registry login(s) with podman]
2025-10-02 19:27:49.105766 | 
2025-10-02 19:27:49.105979 | LOOP [Perform container registry login(s) with buildah]
2025-10-02 19:27:49.156072 | 
2025-10-02 19:27:49.156227 | TASK [Set Insecure registry for content provider]
2025-10-02 19:27:49.200067 | controller | skipping: Conditional result was False
2025-10-02 19:27:49.226374 | 
2025-10-02 19:27:49.226479 | PLAY RECAP
2025-10-02 19:27:49.226523 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-10-02 19:27:49.226543 | 
2025-10-02 19:27:49.337000 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-02 19:27:49.337964 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-02 19:27:49.907927 | 
2025-10-02 19:27:49.908058 | PLAY [Prefer ipv4 over ipv6]
2025-10-02 19:27:49.927941 | 
2025-10-02 19:27:49.928072 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-10-02 19:27:49.983865 | controller | skipping: Conditional result was False
2025-10-02 19:27:50.005889 | 
2025-10-02 19:27:50.006015 | PLAY [controller]
2025-10-02 19:27:50.030156 | 
2025-10-02 19:27:50.030400 | TASK [Fail when openshift_pull_sec not provided]
2025-10-02 19:27:50.055558 | controller | skipping: Conditional result was False
2025-10-02 19:27:50.065287 | 
2025-10-02 19:27:50.065413 | TASK [Clone repos in the job workspace]
2025-10-02 19:27:50.087563 | controller | ok
2025-10-02 19:27:50.106355 | 
2025-10-02 19:27:50.106471 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 19:27:50.545197 | controller | ok
2025-10-02 19:27:50.559332 | 
2025-10-02 19:27:50.559551 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 19:27:53.892857 | controller | Output suppressed because no_log was given
2025-10-02 19:27:53.902271 | 
2025-10-02 19:27:53.902335 | TASK [Create pull-secret.txt file]
2025-10-02 19:27:55.487315 | controller | Output suppressed because no_log was given
2025-10-02 19:27:55.502084 | 
2025-10-02 19:27:55.502291 | TASK [Apply CRC features and print image build date]
2025-10-02 19:27:55.531698 | controller | ok
2025-10-02 19:27:55.547908 | controller | included: /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-10-02 19:27:55.558585 | 
2025-10-02 19:27:55.558685 | TASK [Set additional parameters for crc - telemetry]
2025-10-02 19:27:56.145212 | controller | skipping: Conditional result was False
2025-10-02 19:27:56.151212 | 
2025-10-02 19:27:56.151323 | TASK [Set additional parameters for crc - monitoring]
2025-10-02 19:27:56.679913 | controller | skipping: Conditional result was False
2025-10-02 19:27:56.685918 | 
2025-10-02 19:27:56.685990 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-10-02 19:27:57.216395 | controller | skipping: Conditional result was False
2025-10-02 19:27:57.224232 | 
2025-10-02 19:27:57.224370 | TASK [Set additional parameters for crc - enable microshift preset]
2025-10-02 19:27:57.759917 | controller | skipping: Conditional result was False
2025-10-02 19:27:57.765560 | 
2025-10-02 19:27:57.765626 | TASK [Set additional parameters for crc - enable okd preset]
2025-10-02 19:27:58.295684 | controller | skipping: Conditional result was False
2025-10-02 19:27:58.304183 | 
2025-10-02 19:27:58.304332 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-10-02 19:27:58.837256 | controller | skipping: Conditional result was False
2025-10-02 19:27:58.849690 | 
2025-10-02 19:27:58.849898 | TASK [Execute crc start command]
2025-10-02 19:31:05.662705 | controller | ok: Runtime: 0:03:06.008817
2025-10-02 19:31:05.674567 | 
2025-10-02 19:31:05.674769 | TASK [Verify that OpenShift is up and running]
2025-10-02 19:31:05.702795 | controller | ok
2025-10-02 19:31:05.721629 | controller | included: /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-10-02 19:31:05.734060 | 
2025-10-02 19:31:05.734198 | TASK [Create script to login and verify services]
2025-10-02 19:31:07.153771 | controller | changed
2025-10-02 19:31:07.166660 | 
2025-10-02 19:31:07.166877 | TASK [Ensure that user is logged]
2025-10-02 19:31:07.608742 | controller | Checking login to the cluster 1...
2025-10-02 19:31:08.658981 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:08.716117 | controller |
2025-10-02 19:31:08.716199 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:08.720794 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:13.722754 | controller | Checking login to the cluster 2...
2025-10-02 19:31:13.842826 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:13.864420 | controller |
2025-10-02 19:31:13.864508 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:13.871186 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:18.873322 | controller | Checking login to the cluster 3...
2025-10-02 19:31:19.004300 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:19.024175 | controller |
2025-10-02 19:31:19.024269 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:19.031482 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:24.033832 | controller | Checking login to the cluster 4...
2025-10-02 19:31:24.183077 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:24.212056 | controller |
2025-10-02 19:31:24.212209 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:24.217551 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:29.221593 | controller | Checking login to the cluster 5...
2025-10-02 19:31:29.380225 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:29.408266 | controller |
2025-10-02 19:31:29.408320 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:29.414350 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:34.417973 | controller | Checking login to the cluster 6...
2025-10-02 19:31:34.530300 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:34.548188 | controller |
2025-10-02 19:31:34.548252 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:34.552996 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:39.554932 | controller | Checking login to the cluster 7...
2025-10-02 19:31:39.684721 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:39.705504 | controller |
2025-10-02 19:31:39.705574 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:39.711815 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:44.714723 | controller | Checking login to the cluster 8...
2025-10-02 19:31:44.865632 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:44.888871 | controller |
2025-10-02 19:31:44.888985 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:44.894367 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:49.897934 | controller | Checking login to the cluster 9...
2025-10-02 19:31:50.057130 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:50.094690 | controller |
2025-10-02 19:31:50.094784 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:50.105349 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:31:55.108366 | controller | Checking login to the cluster 10...
2025-10-02 19:31:55.203022 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:31:55.220528 | controller |
2025-10-02 19:31:55.220603 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:31:55.229678 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:00.233278 | controller | Checking login to the cluster 11...
2025-10-02 19:32:00.367743 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:00.389229 | controller |
2025-10-02 19:32:00.389295 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:32:00.395530 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:05.398497 | controller | Checking login to the cluster 12...
2025-10-02 19:32:05.505360 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:05.564677 | controller |
2025-10-02 19:32:05.564802 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:05.569577 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:10.571868 | controller | Checking login to the cluster 13...
2025-10-02 19:32:10.738104 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:10.792444 | controller |
2025-10-02 19:32:10.792517 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:10.801835 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:15.805136 | controller | Checking login to the cluster 14...
2025-10-02 19:32:15.918054 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:15.959338 | controller |
2025-10-02 19:32:15.959433 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:15.964204 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:20.966476 | controller | Checking login to the cluster 15...
2025-10-02 19:32:21.122681 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:21.176689 | controller |
2025-10-02 19:32:21.176784 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:21.182591 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:26.185106 | controller | Checking login to the cluster 16...
2025-10-02 19:32:26.328273 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:26.405497 | controller |
2025-10-02 19:32:26.405603 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:26.416226 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:31.422635 | controller | Checking login to the cluster 17...
2025-10-02 19:32:31.565838 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:31.654708 | controller |
2025-10-02 19:32:31.654993 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:31.667760 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:36.672300 | controller | Checking login to the cluster 18...
2025-10-02 19:32:36.791950 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:36.853625 | controller |
2025-10-02 19:32:36.853692 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:36.858792 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:41.862031 | controller | Checking login to the cluster 19...
2025-10-02 19:32:42.069643 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:42.248308 | controller |
2025-10-02 19:32:42.248417 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:42.265536 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:47.269514 | controller | Checking login to the cluster 20...
2025-10-02 19:32:47.455471 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:47.789600 | controller |
2025-10-02 19:32:47.789685 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-02 19:32:47.800838 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:52.805646 | controller | Checking login to the cluster 21...
2025-10-02 19:32:53.087463 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:53.334986 | controller |
2025-10-02 19:32:53.335130 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:32:53.342154 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:32:58.346076 | controller | Checking login to the cluster 22...
2025-10-02 19:32:58.638155 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:32:59.449564 | controller |
2025-10-02 19:32:59.449656 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:32:59.454844 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:04.459760 | controller | Checking login to the cluster 23...
2025-10-02 19:33:04.779157 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:04.948605 | controller |
2025-10-02 19:33:04.948698 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:04.954315 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:09.957564 | controller | Checking login to the cluster 24...
2025-10-02 19:33:10.234480 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:10.337198 | controller |
2025-10-02 19:33:10.337313 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:10.347984 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:15.351268 | controller | Checking login to the cluster 25...
2025-10-02 19:33:15.694492 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:17.946268 | controller |
2025-10-02 19:33:17.946452 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:17.954432 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:22.958813 | controller | Checking login to the cluster 26...
2025-10-02 19:33:23.125974 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:23.303640 | controller |
2025-10-02 19:33:23.303721 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:23.309296 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:28.312070 | controller | Checking login to the cluster 27...
2025-10-02 19:33:28.460157 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:28.494435 | controller |
2025-10-02 19:33:28.494510 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:28.504572 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:33.508808 | controller | Checking login to the cluster 28...
2025-10-02 19:33:33.647071 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:33.795328 | controller |
2025-10-02 19:33:33.795422 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:33.801292 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:38.808222 | controller | Checking login to the cluster 29...
2025-10-02 19:33:39.335027 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:39.906914 | controller |
2025-10-02 19:33:39.907051 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:39.914598 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:44.918516 | controller | Checking login to the cluster 30...
2025-10-02 19:33:45.068137 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:45.106731 | controller |
2025-10-02 19:33:45.106795 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:45.113326 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:50.115607 | controller | Checking login to the cluster 31...
2025-10-02 19:33:50.245721 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:50.277043 | controller |
2025-10-02 19:33:50.277126 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:50.281797 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:33:55.283997 | controller | Checking login to the cluster 32...
2025-10-02 19:33:55.418519 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:33:55.443223 | controller |
2025-10-02 19:33:55.443293 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:33:55.450493 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:34:00.456483 | controller | Checking login to the cluster 33...
2025-10-02 19:34:00.657789 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:34:00.677662 | controller |
2025-10-02 19:34:00.677746 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:34:00.683234 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:34:05.690516 | controller | Checking login to the cluster 34...
2025-10-02 19:34:05.832537 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:34:05.852508 | controller |
2025-10-02 19:34:05.852589 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:34:05.860738 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:34:10.863755 | controller | Checking login to the cluster 35...
2025-10-02 19:34:10.994395 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:34:11.016275 | controller |
2025-10-02 19:34:11.016417 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:34:11.023414 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:34:16.026533 | controller | Checking login to the cluster 36...
2025-10-02 19:34:16.165366 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:34:16.185775 | controller |
2025-10-02 19:34:16.185852 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-02 19:34:16.190334 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-02 19:34:21.193871 | controller | Checking login to the cluster 37...
2025-10-02 19:34:21.396836 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-02 19:34:21.785120 | controller |
2025-10-02 19:34:21.785204 | controller | Login successful.
2025-10-02 19:34:21.818258 | controller |
2025-10-02 19:34:21.818351 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-10-02 19:34:21.841997 | controller |
2025-10-02 19:34:21.842203 | controller | Using project "default".
2025-10-02 19:34:21.960868 | controller | changed
2025-10-02 19:34:21.975396 | 
2025-10-02 19:34:21.975592 | TASK [Delete all openshift-marketplace pods]
2025-10-02 19:34:24.014616 | controller | pod "certified-operators-6sgqc" deleted
2025-10-02 19:34:24.033896 | controller | pod "certified-operators-f8szs" deleted
2025-10-02 19:34:24.048917 | controller | pod "community-operators-5zsqg" deleted
2025-10-02 19:34:24.086125 | controller | pod "community-operators-sjzxt" deleted
2025-10-02 19:34:24.110404 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2025-10-02 19:34:24.137746 | controller | pod "redhat-marketplace-chd8w" deleted
2025-10-02 19:34:24.153970 | controller | pod "redhat-marketplace-ps6xk" deleted
2025-10-02 19:34:24.203974 | controller | pod "redhat-operators-tqxl9" deleted
2025-10-02 19:34:24.259808 | controller | pod "redhat-operators-w5r8t" deleted
2025-10-02 19:35:05.585822 | controller | ok
2025-10-02 19:35:05.599694 | 
2025-10-02 19:35:05.599875 | TASK [Copy script ensure_services_up.sh]
2025-10-02 19:35:05.677762 | controller | skipping: Conditional result was False
2025-10-02 19:35:05.692369 | 
2025-10-02 19:35:05.692518 | TASK [Ensure all pods are up and running]
2025-10-02 19:35:06.243925 | controller | skipping: Conditional result was False
2025-10-02 19:35:06.258777 | 
2025-10-02 19:35:06.258974 | TASK [Check if there is a image build date file]
2025-10-02 19:35:07.055049 | controller | ok
2025-10-02 19:35:07.067770 | 
2025-10-02 19:35:07.067923 | TASK [Print image date if available]
2025-10-02 19:35:07.481129 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2025-10-02 19:35:07.617118 | controller | changed
2025-10-02 19:35:07.631836 | 
2025-10-02 19:35:07.631987 | TASK [Fix machineconfigpool]
2025-10-02 19:35:07.701797 | controller | skipping: Conditional result was False
2025-10-02 19:35:07.718201 | 
2025-10-02 19:35:07.718355 | TASK [Show available nodes]
2025-10-02 19:35:11.305330 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-10-02 19:35:11.309333 | controller | crc    Ready    control-plane,master,worker   221d   v1.31.5
2025-10-02 19:35:11.778477 | controller | ok: Runtime: 0:00:03.120852
2025-10-02 19:35:11.791478 | 
2025-10-02 19:35:11.791618 | TASK [Configure insecure registry inside crc vm]
2025-10-02 19:35:11.839844 | controller | skipping: Conditional result was False
2025-10-02 19:35:11.854067 | 
2025-10-02 19:35:11.854494 | TASK [Catch CRC IP]
2025-10-02 19:35:12.357764 | controller | 192.168.130.11
2025-10-02 19:35:12.405237 | controller | changed
2025-10-02 19:35:12.419637 | 
2025-10-02 19:35:12.419889 | TASK [Check if id_ed25519 key exists]
2025-10-02 19:35:12.751065 | controller | ok
2025-10-02 19:35:12.760542 | 
2025-10-02 19:35:12.760648 | TASK [Set fact if new keypair exists]
2025-10-02 19:35:12.811691 | controller | ok
2025-10-02 19:35:12.858910 | 
2025-10-02 19:35:12.859051 | TASK [Run crc]
2025-10-02 19:35:16.147524 | controller |
2025-10-02 19:35:25.657491 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-10-02 19:35:25.657572 | controller |
2025-10-02 19:35:25.657582 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:35:25.657595 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-10-02 19:35:25.703296 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-10-02 19:35:25.703340 | controller | interpreter could change the meaning of that path. See
2025-10-02 19:35:25.703348 | controller | https://docs.ansible.com/ansible-
2025-10-02 19:35:25.703355 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-10-02 19:35:25.703366 | controller | ok: [192.168.130.11]
2025-10-02 19:35:27.593223 | controller |
2025-10-02 19:35:27.593275 | controller | TASK [Copy pull-secret] ********************************************************
2025-10-02 19:35:27.593291 | controller | changed: [192.168.130.11]
2025-10-02 19:35:29.256762 | controller |
2025-10-02 19:35:29.256845 | controller | TASK [Add crio config] *********************************************************
2025-10-02 19:35:29.256867 | controller | changed: [192.168.130.11]
2025-10-02 19:35:46.427115 | controller |
2025-10-02 19:35:46.427189 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-10-02 19:35:46.427216 | controller | changed: [192.168.130.11]
2025-10-02 19:35:46.487441 | controller |
2025-10-02 19:35:46.487501 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:35:46.487509 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:35:46.487515 | controller |
2025-10-02 19:35:46.944185 | controller | changed
2025-10-02 19:35:46.958427 | 
2025-10-02 19:35:46.958771 | TASK [Set slower etcd profile]
2025-10-02 19:35:47.041007 | controller | ok
2025-10-02 19:35:47.083069 | 
2025-10-02 19:35:47.083355 | TASK [prepare-crc-cloud : Set slower profile]
2025-10-02 19:35:47.640980 | controller | etcd.operator.openshift.io/cluster patched
2025-10-02 19:35:48.122266 | controller | ok
2025-10-02 19:35:48.137265 | 
2025-10-02 19:35:48.137475 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-10-02 19:36:18.187815 | controller | ok
2025-10-02 19:36:18.199376 | 
2025-10-02 19:36:18.199511 | TASK [prepare-crc-cloud : Get pod phase status]
2025-10-02 19:41:16.691439 | controller | ok
2025-10-02 19:41:16.714630 | 
2025-10-02 19:41:16.714835 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-10-02 19:41:16.767128 | controller | ok
2025-10-02 19:41:16.773872 | controller | included: /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-10-02 19:41:16.783838 | 
2025-10-02 19:41:16.783957 | TASK [Set facts for disk limitation]
2025-10-02 19:41:16.804252 | controller | ok
2025-10-02 19:41:16.810213 | 
2025-10-02 19:41:16.810289 | TASK [Print limits for the disk]
2025-10-02 19:41:16.860888 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-10-02 19:41:16.865912 | 
2025-10-02 19:41:16.866004 | TASK [Set limit to crc VM to the vda disk]
2025-10-02 19:41:17.293255 | controller |
2025-10-02 19:41:17.395061 | controller | changed
2025-10-02 19:41:17.407083 | 
2025-10-02 19:41:17.407273 | TASK [Pause machineconfigpool]
2025-10-02 19:41:17.482999 | controller | ok
2025-10-02 19:41:17.526396 | 
2025-10-02 19:41:17.526532 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-10-02 19:41:22.120337 | controller | ok
2025-10-02 19:41:22.609940 | controller | ok
2025-10-02 19:41:22.631324 | 
2025-10-02 19:41:22.631515 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-10-02 19:41:23.201340 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-10-02 19:41:23.201456 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      221d
2025-10-02 19:41:23.207872 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      221d
2025-10-02 19:41:23.680714 | controller | ok
2025-10-02 19:41:23.736846 | 
2025-10-02 19:41:23.737034 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-10-02 19:41:23.737166 | 
2025-10-02 19:41:23.737202 | PLAY RECAP
2025-10-02 19:41:23.737248 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-10-02 19:41:23.737289 | 
2025-10-02 19:41:23.839394 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-02 19:41:23.840181 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 19:41:24.467503 | 
2025-10-02 19:41:24.467627 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-02 19:41:24.489447 | 
2025-10-02 19:41:24.489592 | TASK [Create zuul-output directory]
2025-10-02 19:41:24.963243 | controller | changed
2025-10-02 19:41:24.974569 | 
2025-10-02 19:41:24.974803 | TASK [Slurp Zuul inventory test]
2025-10-02 19:41:25.278030 | controller -> localhost | ok
2025-10-02 19:41:25.284534 | 
2025-10-02 19:41:25.284602 | TASK [Save zuul inventory]
2025-10-02 19:41:27.006020 | controller | changed
2025-10-02 19:41:27.017214 | 
2025-10-02 19:41:27.017349 | TASK [Save zuul vars without the change_message]
2025-10-02 19:41:28.495147 | controller | changed
2025-10-02 19:41:28.535398 | 
2025-10-02 19:41:28.535546 | PLAY RECAP
2025-10-02 19:41:28.535604 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 19:41:28.535634 | 
2025-10-02 19:41:28.640563 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 19:41:28.641374 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 19:41:29.284840 | 
2025-10-02 19:41:29.284949 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-02 19:41:29.305938 | 
2025-10-02 19:41:29.306028 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-02 19:41:29.324934 | controller | ok
2025-10-02 19:41:29.342420 | 
2025-10-02 19:41:29.342516 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 19:41:29.376691 | controller | skipping: Conditional result was False
2025-10-02 19:41:29.382951 | 
2025-10-02 19:41:29.383023 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 19:41:29.957263 | controller | ok
2025-10-02 19:41:29.970673 | 
2025-10-02 19:41:29.971427 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 19:41:31.431955 | controller | ok
2025-10-02 19:41:31.455326 | 
2025-10-02 19:41:31.455517 | TASK [Prepare workspace]
2025-10-02 19:41:31.484114 | controller | ok
2025-10-02 19:41:31.518954 | 
2025-10-02 19:41:31.519102 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 19:41:32.098582 | controller | ok
2025-10-02 19:41:32.112332 | 
2025-10-02 19:41:32.112476 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 19:41:33.313547 | controller | Output suppressed because no_log was given
2025-10-02 19:41:33.361806 | 
2025-10-02 19:41:33.362000 | LOOP [Create zuul-output directory]
2025-10-02 19:41:33.712226 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-02 19:41:34.010549 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-02 19:41:34.024016 | 
2025-10-02 19:41:34.024182 | TASK [Install required packages]
2025-10-02 19:41:48.986206 | controller | ok: Nothing to do
2025-10-02 19:41:48.991979 | 
2025-10-02 19:41:48.992052 | TASK [Install venv]
2025-10-02 19:42:54.179840 | controller | changed
2025-10-02 19:42:54.230051 | 
2025-10-02 19:42:54.230154 | PLAY RECAP
2025-10-02 19:42:54.230218 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 19:42:54.230253 | 
2025-10-02 19:42:54.331360 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 19:42:54.333276 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 19:42:54.944931 | 
2025-10-02 19:42:54.945035 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-02 19:42:54.965732 | 
2025-10-02 19:42:54.965833 | TASK [Gather required facts]
2025-10-02 19:42:55.729007 | controller | ok
2025-10-02 19:42:55.741700 | 
2025-10-02 19:42:55.741891 | TASK [Load environment var if instructed to]
2025-10-02 19:42:55.779260 | controller | skipping: Conditional result was False
2025-10-02 19:42:55.792553 | 
2025-10-02 19:42:55.792695 | TASK [Run molecule]
2025-10-02 19:42:57.116263 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-02 19:42:57.364474 | controller | INFO     Running default > destroy
2025-10-02 19:42:57.364743 | controller | WARNING  Skipping, instances are delegated.
2025-10-02 19:42:57.388495 | controller | INFO     Running default > create
2025-10-02 19:42:57.389531 | controller | WARNING  Skipping, instances are delegated.
2025-10-02 19:42:57.398298 | controller | INFO     Running default > converge
2025-10-02 19:42:58.271254 | controller |
2025-10-02 19:42:58.271316 | controller | PLAY [Converge] ****************************************************************
2025-10-02 19:42:58.271480 | controller |
2025-10-02 19:42:58.271703 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:42:58.272396 | controller | Thursday 02 October 2025  19:42:58 +0000 (0:00:00.017)       0:00:00.017 ******
2025-10-02 19:42:59.431136 | controller | ok: [instance]
2025-10-02 19:42:59.431219 | controller |
2025-10-02 19:42:59.431482 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-10-02 19:42:59.431740 | controller | Thursday 02 October 2025  19:42:59 +0000 (0:00:01.160)       0:00:01.177 ******
2025-10-02 19:42:59.849265 | controller | changed: [instance]
2025-10-02 19:42:59.849320 | controller |
2025-10-02 19:42:59.849432 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-10-02 19:42:59.849534 | controller | Thursday 02 October 2025  19:42:59 +0000 (0:00:00.418)       0:00:01.596 ******
2025-10-02 19:42:59.881238 | controller |
2025-10-02 19:42:59.881409 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-10-02 19:42:59.881602 | controller | Thursday 02 October 2025  19:42:59 +0000 (0:00:00.031)       0:00:01.628 ******
2025-10-02 19:43:00.222138 | controller | ok: [instance]
2025-10-02 19:43:00.222211 | controller |
2025-10-02 19:43:00.222361 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-10-02 19:43:00.222568 | controller | Thursday 02 October 2025  19:43:00 +0000 (0:00:00.341)       0:00:01.969 ******
2025-10-02 19:43:00.244184 | controller | ok: [instance]
2025-10-02 19:43:00.244263 | controller |
2025-10-02 19:43:00.244369 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-10-02 19:43:00.245059 | controller | Thursday 02 October 2025  19:43:00 +0000 (0:00:00.022)       0:00:01.991 ******
2025-10-02 19:43:00.273123 | controller | skipping: [instance]
2025-10-02 19:43:00.273180 | controller |
2025-10-02 19:43:00.273371 | controller | TASK [Add the crc host dynamically] ********************************************
2025-10-02 19:43:00.273431 | controller | Thursday 02 October 2025  19:43:00 +0000 (0:00:00.028)       0:00:02.020 ******
2025-10-02 19:43:00.298155 | controller | changed: [instance]
2025-10-02 19:43:00.298254 | controller |
2025-10-02 19:43:00.298428 | controller | TASK [Fetch crc network facts] *************************************************
2025-10-02 19:43:00.298673 | controller | Thursday 02 October 2025  19:43:00 +0000 (0:00:00.025)       0:00:02.045 ******
2025-10-02 19:43:05.055362 | controller | ok: [instance -> crc]
2025-10-02 19:43:05.055600 | controller |
2025-10-02 19:43:05.055897 | controller | TASK [Load shared variables] ***************************************************
2025-10-02 19:43:05.056149 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:04.756)       0:00:06.802 ******
2025-10-02 19:43:05.078246 | controller | ok: [instance]
2025-10-02 19:43:05.078533 | controller |
2025-10-02 19:43:05.078791 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-02 19:43:05.079037 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.023)       0:00:06.825 ******
2025-10-02 19:43:05.157090 | controller |
2025-10-02 19:43:05.157164 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-02 19:43:05.157224 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.078)       0:00:06.904 ******
2025-10-02 19:43:05.511980 | controller | changed: [instance]
2025-10-02 19:43:05.512123 | controller |
2025-10-02 19:43:05.512405 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-02 19:43:05.512662 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.353)       0:00:07.258 ******
2025-10-02 19:43:05.552321 | controller | skipping: [instance]
2025-10-02 19:43:05.552489 | controller |
2025-10-02 19:43:05.552759 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-02 19:43:05.552975 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.040)       0:00:07.298 ******
2025-10-02 19:43:05.583924 | controller | skipping: [instance]
2025-10-02 19:43:05.584066 | controller |
2025-10-02 19:43:05.584300 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-02 19:43:05.584551 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.032)       0:00:07.331 ******
2025-10-02 19:43:05.624596 | controller | skipping: [instance]
2025-10-02 19:43:05.624751 | controller |
2025-10-02 19:43:05.625010 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-02 19:43:05.625306 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.040)       0:00:07.371 ******
2025-10-02 19:43:05.669063 | controller | skipping: [instance]
2025-10-02 19:43:05.669223 | controller |
2025-10-02 19:43:05.669520 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-02 19:43:05.669759 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.044)       0:00:07.416 ******
2025-10-02 19:43:05.724666 | controller | ok: [instance]
2025-10-02 19:43:05.724751 | controller |
2025-10-02 19:43:05.724917 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-02 19:43:05.725248 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.055)       0:00:07.472 ******
2025-10-02 19:43:05.957169 | controller | ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
2025-10-02 19:43:05.957470 | controller | ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'macvlan'}}}})
2025-10-02 19:43:05.957805 | controller | ok: [instance] => (item={'bridge-to-linux-bridge': {'gw_v4': '192.168.122.1', 'network_name': 'bridge-to-linux-bridge', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth1', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge', 'multus_attach': 'linux-bridge'}}}})
2025-10-02 19:43:05.958012 | controller |
2025-10-02 19:43:05.958261 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-02 19:43:05.958508 | controller | Thursday 02 October 2025  19:43:05 +0000 (0:00:00.231)       0:00:07.703 ******
2025-10-02 19:43:06.056166 | controller | ok: [instance]
2025-10-02 19:43:06.056602 | controller |
2025-10-02 19:43:06.056832 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-02 19:43:06.057157 | controller | Thursday 02 October 2025  19:43:06 +0000 (0:00:00.098)       0:00:07.802 ******
2025-10-02 19:43:06.129565 | controller | ok: [instance]
2025-10-02 19:43:06.130490 | controller |
2025-10-02 19:43:06.237356 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-02 19:43:06.237413 | controller | Thursday 02 October 2025  19:43:06 +0000 (0:00:00.072)       0:00:07.874 ******
2025-10-02 19:43:06.237433 | controller | ok: [instance]
2025-10-02 19:43:06.857511 | controller |
2025-10-02 19:43:06.857558 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-02 19:43:06.857568 | controller | Thursday 02 October 2025  19:43:06 +0000 (0:00:00.109)       0:00:07.984 ******
2025-10-02 19:43:06.857609 | controller | changed: [instance]
2025-10-02 19:43:07.960423 | controller |
2025-10-02 19:43:07.960463 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-02 19:43:07.960473 | controller | Thursday 02 October 2025  19:43:06 +0000 (0:00:00.619)       0:00:08.604 ******
2025-10-02 19:43:07.960493 | controller | changed: [instance]
2025-10-02 19:43:08.776083 | controller |
2025-10-02 19:43:08.776169 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-02 19:43:08.776185 | controller | Thursday 02 October 2025  19:43:07 +0000 (0:00:01.102)       0:00:09.706 ******
2025-10-02 19:43:08.776213 | controller | changed: [instance]
2025-10-02 19:43:08.776305 | controller |
2025-10-02 19:43:08.776324 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:43:08.776341 | controller | instance                   : ok=16   changed=6    unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-10-02 19:43:08.776508 | controller |
2025-10-02 19:43:08.776749 | controller | Thursday 02 October 2025  19:43:08 +0000 (0:00:00.815)       0:00:10.522 ******
2025-10-02 19:43:08.776929 | controller | ===============================================================================
2025-10-02 19:43:08.777146 | controller | Fetch crc network facts ------------------------------------------------- 4.76s
2025-10-02 19:43:08.777348 | controller | Gathering Facts --------------------------------------------------------- 1.16s
2025-10-02 19:43:08.777598 | controller | ci_multus : Create the multus namespace --------------------------------- 1.10s
2025-10-02 19:43:08.777828 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.82s
2025-10-02 19:43:08.778069 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.62s
2025-10-02 19:43:08.778261 | controller | Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.42s
2025-10-02 19:43:08.778509 | controller | ci_multus : Create manifests directory ---------------------------------- 0.35s
2025-10-02 19:43:08.778732 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.34s
2025-10-02 19:43:08.778952 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.23s
2025-10-02 19:43:08.779162 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.11s
2025-10-02 19:43:08.779399 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.10s
2025-10-02 19:43:08.779621 | controller | Call ci_multus role ----------------------------------------------------- 0.08s
2025-10-02 19:43:08.779817 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.07s
2025-10-02 19:43:08.780034 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.06s
2025-10-02 19:43:08.780238 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-10-02 19:43:08.780471 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.04s
2025-10-02 19:43:08.780680 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.04s
2025-10-02 19:43:08.780888 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-10-02 19:43:08.781093 | controller | Check if new ssh keypair exists ----------------------------------------- 0.03s
2025-10-02 19:43:08.781349 | controller | recognize_ssh_keypair : Set fact if new keypair does not exists --------- 0.03s
2025-10-02 19:43:08.862443 | controller | INFO     Running default > verify
2025-10-02 19:43:08.862860 | controller | INFO     Running Ansible Verifier
2025-10-02 19:43:09.365832 | controller |
2025-10-02 19:43:09.365969 | controller | PLAY [Verify] ******************************************************************
2025-10-02 19:43:09.366151 | controller |
2025-10-02 19:43:09.366315 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:43:09.367191 | controller | Thursday 02 October 2025  19:43:09 +0000 (0:00:00.017)       0:00:00.017 ******
2025-10-02 19:43:10.486404 | controller | ok: [instance]
2025-10-02 19:43:10.486462 | controller |
2025-10-02 19:43:10.486719 | controller | TASK [Load shared variables] ***************************************************
2025-10-02 19:43:10.511721 | controller | Thursday 02 October 2025  19:43:10 +0000 (0:00:01.121)       0:00:01.138 ******
2025-10-02 19:43:10.511780 | controller | ok: [instance]
2025-10-02 19:43:10.511985 | controller |
2025-10-02 19:43:10.890785 | controller | TASK [Fetch files stat results] ************************************************
2025-10-02 19:43:10.890854 | controller | Thursday 02 October 2025  19:43:10 +0000 (0:00:00.025)       0:00:01.164 ******
2025-10-02 19:43:10.890883 | controller | ok: [instance]
2025-10-02 19:43:10.891040 | controller |
2025-10-02 19:43:10.891061 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-02 19:43:10.891080 | controller | Thursday 02 October 2025  19:43:10 +0000 (0:00:00.378)       0:00:01.543 ******
2025-10-02 19:43:10.927262 | controller | ok: [instance] => changed=false
2025-10-02 19:43:10.930184 | controller |   msg: All assertions passed
2025-10-02 19:43:10.930341 | controller |
2025-10-02 19:43:10.930492 | controller | TASK [Fetch file content] ******************************************************
2025-10-02 19:43:10.930626 | controller | Thursday 02 October 2025  19:43:10 +0000 (0:00:00.036)       0:00:01.579 ******
2025-10-02 19:43:11.288730 | controller | ok: [instance]
2025-10-02 19:43:11.288814 | controller |
2025-10-02 19:43:11.288951 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-02 19:43:11.289130 | controller | Thursday 02 October 2025  19:43:11 +0000 (0:00:00.361)       0:00:01.940 ******
2025-10-02 19:43:11.325449 | controller | ok: [instance]
2025-10-02 19:43:11.325520 | controller |
2025-10-02 19:43:11.325606 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-02 19:43:11.325714 | controller | Thursday 02 October 2025  19:43:11 +0000 (0:00:00.036)       0:00:01.977 ******
2025-10-02 19:43:11.384203 | controller | ok: [instance]
2025-10-02 19:43:11.384276 | controller |
2025-10-02 19:43:11.384287 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:43:11.384297 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:43:11.384306 | controller |
2025-10-02 19:43:11.384315 | controller | Thursday 02 October 2025  19:43:11 +0000 (0:00:00.057)       0:00:02.035 ******
2025-10-02 19:43:11.384327 | controller | ===============================================================================
2025-10-02 19:43:11.384538 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2025-10-02 19:43:11.384756 | controller | Fetch files stat results ------------------------------------------------ 0.38s
2025-10-02 19:43:11.384952 | controller | Fetch file content ------------------------------------------------------ 0.36s
2025-10-02 19:43:11.385101 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-10-02 19:43:11.385249 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-10-02 19:43:11.385434 | controller | Assert that expected file exist ----------------------------------------- 0.04s
2025-10-02 19:43:11.385574 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-02 19:43:11.467119 | controller | INFO     Verifier completed successfully.
2025-10-02 19:43:11.487776 | controller | INFO     Running default > verify
2025-10-02 19:43:11.488199 | controller | INFO     Running Ansible Verifier
2025-10-02 19:43:12.095026 | controller |
2025-10-02 19:43:12.095491 | controller | PLAY [Verify CRC] **************************************************************
2025-10-02 19:43:13.121592 | controller |
2025-10-02 19:43:13.121657 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:43:13.121673 | controller | Thursday 02 October 2025  19:43:12 +0000 (0:00:00.019)       0:00:00.019 ******
2025-10-02 19:43:13.121695 | controller | ok: [instance]
2025-10-02 19:43:13.121838 | controller |
2025-10-02 19:43:13.121924 | controller | TASK [Include default vars] ****************************************************
2025-10-02 19:43:13.122192 | controller | Thursday 02 October 2025  19:43:13 +0000 (0:00:01.026)       0:00:01.046 ******
2025-10-02 19:43:13.163820 | controller | ok: [instance]
2025-10-02 19:43:13.163959 | controller |
2025-10-02 19:43:13.164182 | controller | TASK [Check if NADs were created] **********************************************
2025-10-02 19:43:13.164408 | controller | Thursday 02 October 2025  19:43:13 +0000 (0:00:00.042)       0:00:01.088 ******
2025-10-02 19:43:14.108564 | controller | ok: [instance]
2025-10-02 19:43:14.108639 | controller |
2025-10-02 19:43:14.108795 | controller | TASK [Store output spec] *******************************************************
2025-10-02 19:43:14.108964 | controller | Thursday 02 October 2025  19:43:14 +0000 (0:00:00.944)       0:00:02.033 ******
2025-10-02 19:43:14.171153 | controller | ok: [instance]
2025-10-02 19:43:14.171289 | controller |
2025-10-02 19:43:14.171601 | controller | TASK [Ensure both lists have the same length] **********************************
2025-10-02 19:43:14.171781 | controller | Thursday 02 October 2025  19:43:14 +0000 (0:00:00.061)       0:00:02.095 ******
2025-10-02 19:43:14.238494 | controller | ok: [instance] => changed=false
2025-10-02 19:43:14.238672 | controller |   msg: All assertions passed
2025-10-02 19:43:14.238880 | controller |
2025-10-02 19:43:14.239103 | controller | TASK [Compare each corresponding element in the lists] *************************
2025-10-02 19:43:14.239322 | controller | Thursday 02 October 2025  19:43:14 +0000 (0:00:00.068)       0:00:02.163 ******
2025-10-02 19:43:14.294754 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}', '{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
2025-10-02 19:43:14.295000 | controller |   ansible_loop_var: item
2025-10-02 19:43:14.295307 | controller |   item:
2025-10-02 19:43:14.296600 | controller |   - |-
2025-10-02 19:43:14.296680 | controller |     {
2025-10-02 19:43:14.296798 | controller |       "cniVersion": "0.3.1",
2025-10-02 19:43:14.296904 | controller |       "name": "bridge-to-linux-bridge",
2025-10-02 19:43:14.296998 | controller |       "type": "bridge",
2025-10-02 19:43:14.297089 | controller |       "bridge": "bridge-to-linux-bridge",
2025-10-02 19:43:14.297204 | controller |       "ipam": {
2025-10-02 19:43:14.297289 | controller |         "type": "whereabouts",
2025-10-02 19:43:14.297363 | controller |         "range": "192.168.122.0/24",
2025-10-02 19:43:14.297775 | controller |         "range_start": "192.168.122.30",
2025-10-02 19:43:14.298143 | controller |         "range_end": "192.168.122.70"
2025-10-02 19:43:14.298169 | controller |       }
2025-10-02 19:43:14.298184 | controller |     }
2025-10-02 19:43:14.298205 | controller |   - |-
2025-10-02 19:43:14.298320 | controller |     {
2025-10-02 19:43:14.298341 | controller |       "cniVersion": "0.3.1",
2025-10-02 19:43:14.298356 | controller |       "name": "bridge-to-linux-bridge",
2025-10-02 19:43:14.298370 | controller |       "type": "bridge",
2025-10-02 19:43:14.298423 | controller |       "bridge": "bridge-to-linux-bridge",
2025-10-02 19:43:14.298543 | controller |       "ipam": {
2025-10-02 19:43:14.298570 | controller |         "type": "whereabouts",
2025-10-02 19:43:14.298589 | controller |         "range": "192.168.122.0/24",
2025-10-02 19:43:14.299251 | controller |         "range_start": "192.168.122.30",
2025-10-02 19:43:14.299307 | controller |         "range_end": "192.168.122.70"
2025-10-02 19:43:14.299316 | controller |       }
2025-10-02 19:43:14.299322 | controller |     }
2025-10-02 19:43:14.299328 | controller |   msg: All assertions passed
2025-10-02 19:43:14.299336 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
2025-10-02 19:43:14.299346 | controller |   ansible_loop_var: item
2025-10-02 19:43:14.299373 | controller |   item:
2025-10-02 19:43:14.299522 | controller |   - |-
2025-10-02 19:43:14.299610 | controller |     {
2025-10-02 19:43:14.299685 | controller |       "cniVersion": "0.3.1",
2025-10-02 19:43:14.299784 | controller |       "name": "default",
2025-10-02 19:43:14.299883 | controller |       "type": "bridge",
2025-10-02 19:43:14.299974 | controller |       "bridge": "eth0",
2025-10-02 19:43:14.300081 | controller |       "ipam": {
2025-10-02 19:43:14.300178 | controller |         "type": "whereabouts",
2025-10-02 19:43:14.300265 | controller |         "range": "192.168.122.0/24",
2025-10-02 19:43:14.300364 | controller |         "range_start": "192.168.122.30",
2025-10-02 19:43:14.300484 | controller |         "range_end": "192.168.122.70"
2025-10-02 19:43:14.301024 | controller |       }
2025-10-02 19:43:14.301141 | controller |     }
2025-10-02 19:43:14.301180 | controller |   - |-
2025-10-02 19:43:14.301410 | controller |     {
2025-10-02 19:43:14.301660 | controller |       "cniVersion": "0.3.1",
2025-10-02 19:43:14.301677 | controller |       "name": "default",
2025-10-02 19:43:14.301705 | controller |       "type": "bridge",
2025-10-02 19:43:14.301715 | controller |       "bridge": "eth0",
2025-10-02 19:43:14.302250 | controller |       "ipam": {
2025-10-02 19:43:14.302313 | controller |         "type": "whereabouts",
2025-10-02 19:43:14.302323 | controller |         "range": "192.168.122.0/24",
2025-10-02 19:43:14.302329 | controller |         "range_start": "192.168.122.30",
2025-10-02 19:43:14.302335 | controller |         "range_end": "192.168.122.70"
2025-10-02 19:43:14.302340 | controller |       }
2025-10-02 19:43:14.302348 | controller |     }
2025-10-02 19:43:14.302473 | controller |   msg: All assertions passed
2025-10-02 19:43:14.302558 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}']) => changed=false
2025-10-02 19:43:14.302676 | controller |   ansible_loop_var: item
2025-10-02 19:43:14.302765 | controller |   item:
2025-10-02 19:43:14.302859 | controller |   - |-
2025-10-02 19:43:14.302951 | controller |     {
2025-10-02 19:43:14.303045 | controller |       "cniVersion": "0.3.1",
2025-10-02 19:43:14.303136 | controller |       "name": "patchnetwork",
2025-10-02 19:43:14.303230 | controller |       "type": "macvlan",
2025-10-02 19:43:14.303315 | controller |       "master": "eth2",
2025-10-02 19:43:14.303427 | controller |       "ipam": {
2025-10-02 19:43:14.303518 | controller |         "type": "whereabouts",
2025-10-02 19:43:14.303611 | controller |         "range": "192.168.122.0/24",
2025-10-02 19:43:14.303719 | controller |         "range_start": "192.168.122.30",
2025-10-02 19:43:14.303812 | controller |         "range_end": "192.168.122.70"
2025-10-02 19:43:14.303902 | controller |       }
2025-10-02 19:43:14.304009 | controller |     }
2025-10-02 19:43:14.304083 | controller |   - |-
2025-10-02 19:43:14.304176 | controller |     {
2025-10-02 19:43:14.304267 | controller |       "cniVersion": "0.3.1",
2025-10-02 19:43:14.304361 | controller |       "name": "patchnetwork",
2025-10-02 19:43:14.304502 | controller |       "type": "macvlan",
2025-10-02 19:43:14.304566 | controller |       "master": "eth2",
2025-10-02 19:43:14.304659 | controller |       "ipam": {
2025-10-02 19:43:14.304749 | controller |         "type": "whereabouts",
2025-10-02 19:43:14.304834 | controller |         "range": "192.168.122.0/24",
2025-10-02 19:43:14.304933 | controller |         "range_start": "192.168.122.30",
2025-10-02 19:43:14.305027 | controller |         "range_end": "192.168.122.70"
2025-10-02 19:43:14.305132 | controller |       }
2025-10-02 19:43:14.305218 | controller |     }
2025-10-02 19:43:14.305313 | controller |   msg: All assertions passed
2025-10-02 19:43:14.305429 | controller |
2025-10-02 19:43:14.305553 | controller | TASK [Create a test pod to attach a network] ***********************************
2025-10-02 19:43:14.305641 | controller | Thursday 02 October 2025  19:43:14 +0000 (0:00:00.055)       0:00:02.219 ******
2025-10-02 19:44:35.548155 | controller | [WARNING]: unknown field "api_version"
2025-10-02 19:44:35.556237 | controller | FAILED - RETRYING: [instance]: Create a test pod to attach a network (30 retries left).
2025-10-02 19:44:35.556338 | controller | FAILED - RETRYING: [instance]: Create a test pod to attach a network (29 retries left).
2025-10-02 19:44:35.556490 | controller | FAILED - RETRYING: [instance]: Create a test pod to attach a network (28 retries left).
2025-10-02 19:44:35.556591 | controller | changed: [instance]
2025-10-02 19:44:35.556716 | controller |
2025-10-02 19:44:35.556797 | controller | TASK [Assert that test pod has the additional network] *************************
2025-10-02 19:44:35.556895 | controller | Thursday 02 October 2025  19:44:35 +0000 (0:01:21.261)       0:01:23.481 ******
2025-10-02 19:44:35.602173 | controller | ok: [instance] => changed=false
2025-10-02 19:44:35.602236 | controller |   msg: All assertions passed
2025-10-02 19:44:35.602370 | controller |
2025-10-02 19:44:35.602532 | controller | TASK [Delete test pod] *********************************************************
2025-10-02 19:44:35.602663 | controller | Thursday 02 October 2025  19:44:35 +0000 (0:00:00.045)       0:01:23.526 ******
2025-10-02 19:44:36.511519 | controller | changed: [instance]
2025-10-02 19:44:36.511573 | controller |
2025-10-02 19:44:36.511685 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:36.511806 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:44:36.511903 | controller |
2025-10-02 19:44:36.512035 | controller | Thursday 02 October 2025  19:44:36 +0000 (0:00:00.910)       0:01:24.437 ******
2025-10-02 19:44:36.512173 | controller | ===============================================================================
2025-10-02 19:44:36.512274 | controller | Create a test pod to attach a network ---------------------------------- 81.26s
2025-10-02 19:44:36.512393 | controller | Gathering Facts --------------------------------------------------------- 1.03s
2025-10-02 19:44:36.512522 | controller | Check if NADs were created ---------------------------------------------- 0.94s
2025-10-02 19:44:36.512628 | controller | Delete test pod --------------------------------------------------------- 0.91s
2025-10-02 19:44:36.512712 | controller | Ensure both lists have the same length ---------------------------------- 0.07s
2025-10-02 19:44:36.512819 | controller | Store output spec ------------------------------------------------------- 0.06s
2025-10-02 19:44:36.512914 | controller | Compare each corresponding element in the lists ------------------------- 0.06s
2025-10-02 19:44:36.513009 | controller | Assert that test pod has the additional network ------------------------- 0.05s
2025-10-02 19:44:36.513113 | controller | Include default vars ---------------------------------------------------- 0.04s
2025-10-02 19:44:36.586704 | controller | INFO     Verifier completed successfully.
2025-10-02 19:44:36.599606 | controller | INFO     Running default > side_effect
2025-10-02 19:44:37.091540 | controller |
2025-10-02 19:44:37.091611 | controller | PLAY [Clean] *******************************************************************
2025-10-02 19:44:37.091755 | controller |
2025-10-02 19:44:37.091823 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:44:37.091929 | controller | Thursday 02 October 2025  19:44:37 +0000 (0:00:00.018)       0:00:00.018 ******
2025-10-02 19:44:38.178248 | controller | ok: [instance]
2025-10-02 19:44:38.178294 | controller |
2025-10-02 19:44:38.178446 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-02 19:44:38.178561 | controller | Thursday 02 October 2025  19:44:38 +0000 (0:00:01.087)       0:00:01.105 ******
2025-10-02 19:44:38.523341 | controller | changed: [instance]
2025-10-02 19:44:38.523392 | controller |
2025-10-02 19:44:38.523518 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-02 19:44:38.523622 | controller | Thursday 02 October 2025  19:44:38 +0000 (0:00:00.345)       0:00:01.450 ******
2025-10-02 19:44:39.414744 | controller | ok: [instance]
2025-10-02 19:44:39.414829 | controller |
2025-10-02 19:44:39.414879 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-02 19:44:39.414988 | controller | Thursday 02 October 2025  19:44:39 +0000 (0:00:00.891)       0:00:02.342 ******
2025-10-02 19:44:42.108663 | controller | changed: [instance] => (item=bridge-to-linux-bridge)
2025-10-02 19:44:42.108765 | controller | changed: [instance] => (item=default)
2025-10-02 19:44:42.108814 | controller | changed: [instance] => (item=patchnetwork)
2025-10-02 19:44:42.108976 | controller |
2025-10-02 19:44:42.109126 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-02 19:44:42.109301 | controller | Thursday 02 October 2025  19:44:42 +0000 (0:00:02.693)       0:00:05.035 ******
2025-10-02 19:44:42.544210 | controller | changed: [instance]
2025-10-02 19:44:42.544595 | controller |
2025-10-02 19:44:42.544669 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:42.545152 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:44:42.546296 | controller |
2025-10-02 19:44:42.546327 | controller | Thursday 02 October 2025  19:44:42 +0000 (0:00:00.436)       0:00:05.471 ******
2025-10-02 19:44:42.546546 | controller | ===============================================================================
2025-10-02 19:44:42.546566 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 2.69s
2025-10-02 19:44:42.546573 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2025-10-02 19:44:42.546579 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.89s
2025-10-02 19:44:42.546588 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.44s
2025-10-02 19:44:42.546751 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.35s
2025-10-02 19:44:42.631020 | controller | INFO     Running default > verify
2025-10-02 19:44:42.631448 | controller | INFO     Running Ansible Verifier
2025-10-02 19:44:43.146463 | controller |
2025-10-02 19:44:44.314063 | controller | PLAY [Verify Clean] ************************************************************
2025-10-02 19:44:44.314125 | controller |
2025-10-02 19:44:44.314137 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:44:44.314148 | controller | Thursday 02 October 2025  19:44:43 +0000 (0:00:00.017)       0:00:00.017 ******
2025-10-02 19:44:44.314169 | controller | ok: [instance]
2025-10-02 19:44:44.347181 | controller |
2025-10-02 19:44:44.347235 | controller | TASK [Include default vars] ****************************************************
2025-10-02 19:44:44.347247 | controller | Thursday 02 October 2025  19:44:44 +0000 (0:00:01.169)       0:00:01.187 ******
2025-10-02 19:44:44.347264 | controller | ok: [instance]
2025-10-02 19:44:44.693904 | controller |
2025-10-02 19:44:44.693946 | controller | TASK [Fetch file stat results] *************************************************
2025-10-02 19:44:44.693955 | controller | Thursday 02 October 2025  19:44:44 +0000 (0:00:00.032)       0:00:01.219 ******
2025-10-02 19:44:44.693966 | controller | ok: [instance]
2025-10-02 19:44:44.743519 | controller |
2025-10-02 19:44:44.743553 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-02 19:44:44.743561 | controller | Thursday 02 October 2025  19:44:44 +0000 (0:00:00.346)       0:00:01.566 ******
2025-10-02 19:44:44.743572 | controller | ok: [instance] => changed=false
2025-10-02 19:44:44.743708 | controller |   msg: All assertions passed
2025-10-02 19:44:44.743719 | controller |
2025-10-02 19:44:44.743725 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:44.743731 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:44:44.743737 | controller |
2025-10-02 19:44:44.743743 | controller | Thursday 02 October 2025  19:44:44 +0000 (0:00:00.049)       0:00:01.616 ******
2025-10-02 19:44:44.743749 | controller | ===============================================================================
2025-10-02 19:44:44.743755 | controller | Gathering Facts --------------------------------------------------------- 1.17s
2025-10-02 19:44:44.743760 | controller | Fetch file stat results ------------------------------------------------- 0.35s
2025-10-02 19:44:44.743766 | controller | Assert that created file are removed by cleanup ------------------------- 0.05s
2025-10-02 19:44:44.743774 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-02 19:44:44.811721 | controller | INFO     Verifier completed successfully.
2025-10-02 19:44:44.828015 | controller | INFO     Running default > verify
2025-10-02 19:44:44.828535 | controller | INFO     Running Ansible Verifier
2025-10-02 19:44:45.389054 | controller |
2025-10-02 19:44:46.379013 | controller | PLAY [Verify Clean CRC] ********************************************************
2025-10-02 19:44:46.379058 | controller |
2025-10-02 19:44:46.379069 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:44:46.379077 | controller | Thursday 02 October 2025  19:44:45 +0000 (0:00:00.016)       0:00:00.016 ******
2025-10-02 19:44:46.379090 | controller | ok: [instance]
2025-10-02 19:44:46.407758 | controller |
2025-10-02 19:44:46.407799 | controller | TASK [Include default vars] ****************************************************
2025-10-02 19:44:46.407809 | controller | Thursday 02 October 2025  19:44:46 +0000 (0:00:00.990)       0:00:01.006 ******
2025-10-02 19:44:46.407835 | controller | ok: [instance]
2025-10-02 19:44:47.329749 | controller |
2025-10-02 19:44:47.329780 | controller | TASK [Check if NADs were deleted] **********************************************
2025-10-02 19:44:47.329788 | controller | Thursday 02 October 2025  19:44:46 +0000 (0:00:00.028)       0:00:01.035 ******
2025-10-02 19:44:47.329798 | controller | ok: [instance]
2025-10-02 19:44:47.329826 | controller |
2025-10-02 19:44:47.329836 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:47.330067 | controller | instance                   : ok=3    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:44:47.330238 | controller |
2025-10-02 19:44:47.330464 | controller | Thursday 02 October 2025  19:44:47 +0000 (0:00:00.922)       0:00:01.957 ******
2025-10-02 19:44:47.330646 | controller | ===============================================================================
2025-10-02 19:44:47.330816 | controller | Gathering Facts --------------------------------------------------------- 0.99s
2025-10-02 19:44:47.330955 | controller | Check if NADs were deleted ---------------------------------------------- 0.92s
2025-10-02 19:44:47.331160 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-02 19:44:47.406449 | controller | INFO     Verifier completed successfully.
2025-10-02 19:44:47.407268 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-02 19:44:47.421927 | controller | INFO     Running local > destroy
2025-10-02 19:44:47.422212 | controller | WARNING  Skipping, instances are delegated.
2025-10-02 19:44:47.432433 | controller | INFO     Running local > create
2025-10-02 19:44:47.433414 | controller | WARNING  Skipping, instances are delegated.
2025-10-02 19:44:47.442924 | controller | INFO     Running local > converge
2025-10-02 19:44:47.955069 | controller |
2025-10-02 19:44:49.019564 | controller | PLAY [Converge] ****************************************************************
2025-10-02 19:44:49.019610 | controller |
2025-10-02 19:44:49.019620 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:44:49.019628 | controller | Thursday 02 October 2025  19:44:47 +0000 (0:00:00.016)       0:00:00.016 ******
2025-10-02 19:44:49.019641 | controller | ok: [instance]
2025-10-02 19:44:49.054067 | controller |
2025-10-02 19:44:49.054110 | controller | TASK [Load shared variables] ***************************************************
2025-10-02 19:44:49.054121 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:01.065)       0:00:01.081 ******
2025-10-02 19:44:49.054135 | controller | ok: [instance]
2025-10-02 19:44:49.109033 | controller |
2025-10-02 19:44:49.109087 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-02 19:44:49.109103 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.030)       0:00:01.112 ******
2025-10-02 19:44:49.109124 | controller |
2025-10-02 19:44:49.477789 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-02 19:44:49.477832 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.058)       0:00:01.171 ******
2025-10-02 19:44:49.477848 | controller | changed: [instance]
2025-10-02 19:44:49.509446 | controller |
2025-10-02 19:44:49.509485 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-02 19:44:49.509493 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.368)       0:00:01.540 ******
2025-10-02 19:44:49.509506 | controller | skipping: [instance]
2025-10-02 19:44:49.509582 | controller |
2025-10-02 19:44:49.509595 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-02 19:44:49.509791 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.032)       0:00:01.572 ******
2025-10-02 19:44:49.537766 | controller | skipping: [instance]
2025-10-02 19:44:49.537814 | controller |
2025-10-02 19:44:49.537942 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-02 19:44:49.538114 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.028)       0:00:01.600 ******
2025-10-02 19:44:49.567554 | controller | skipping: [instance]
2025-10-02 19:44:49.567636 | controller |
2025-10-02 19:44:49.567730 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-02 19:44:49.567847 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.029)       0:00:01.630 ******
2025-10-02 19:44:49.610747 | controller | skipping: [instance]
2025-10-02 19:44:49.610786 | controller |
2025-10-02 19:44:49.610923 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-02 19:44:49.611024 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.043)       0:00:01.673 ******
2025-10-02 19:44:49.658368 | controller | ok: [instance]
2025-10-02 19:44:49.658450 | controller |
2025-10-02 19:44:49.658521 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-02 19:44:49.658621 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.048)       0:00:01.721 ******
2025-10-02 19:44:49.810707 | controller | ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
2025-10-02 19:44:49.810830 | controller | ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}]}}}})
2025-10-02 19:44:49.810954 | controller |
2025-10-02 19:44:49.811068 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-02 19:44:49.811174 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.152)       0:00:01.874 ******
2025-10-02 19:44:49.906608 | controller | ok: [instance]
2025-10-02 19:44:49.906662 | controller |
2025-10-02 19:44:49.906762 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-02 19:44:49.906861 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.095)       0:00:01.969 ******
2025-10-02 19:44:49.983481 | controller | ok: [instance]
2025-10-02 19:44:49.983554 | controller |
2025-10-02 19:44:49.983707 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-02 19:44:49.983841 | controller | Thursday 02 October 2025  19:44:49 +0000 (0:00:00.076)       0:00:02.046 ******
2025-10-02 19:44:50.063677 | controller | ok: [instance]
2025-10-02 19:44:50.063733 | controller |
2025-10-02 19:44:50.063824 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-02 19:44:50.063922 | controller | Thursday 02 October 2025  19:44:50 +0000 (0:00:00.079)       0:00:02.126 ******
2025-10-02 19:44:50.792368 | controller | changed: [instance]
2025-10-02 19:44:50.792489 | controller |
2025-10-02 19:44:50.792606 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-02 19:44:50.792708 | controller | Thursday 02 October 2025  19:44:50 +0000 (0:00:00.729)       0:00:02.855 ******
2025-10-02 19:44:50.817331 | controller | skipping: [instance]
2025-10-02 19:44:50.817431 | controller |
2025-10-02 19:44:50.817545 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-02 19:44:50.817657 | controller | Thursday 02 October 2025  19:44:50 +0000 (0:00:00.022)       0:00:02.877 ******
2025-10-02 19:44:50.865201 | controller | skipping: [instance]
2025-10-02 19:44:50.865237 | controller |
2025-10-02 19:44:50.865337 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:50.865495 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-10-02 19:44:50.865607 | controller |
2025-10-02 19:44:50.865707 | controller | Thursday 02 October 2025  19:44:50 +0000 (0:00:00.050)       0:00:02.928 ******
2025-10-02 19:44:50.865795 | controller | ===============================================================================
2025-10-02 19:44:50.865919 | controller | Gathering Facts --------------------------------------------------------- 1.07s
2025-10-02 19:44:50.866016 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.73s
2025-10-02 19:44:50.866112 | controller | ci_multus : Create manifests directory ---------------------------------- 0.37s
2025-10-02 19:44:50.866213 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.15s
2025-10-02 19:44:50.866306 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.10s
2025-10-02 19:44:50.866429 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.08s
2025-10-02 19:44:50.866523 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
2025-10-02 19:44:50.866616 | controller | Call ci_multus role ----------------------------------------------------- 0.06s
2025-10-02 19:44:50.866714 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.05s
2025-10-02 19:44:50.866813 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.05s
2025-10-02 19:44:50.866910 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-10-02 19:44:50.867015 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2025-10-02 19:44:50.867109 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-02 19:44:50.867211 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2025-10-02 19:44:50.867300 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-10-02 19:44:50.867421 | controller | ci_multus : Create the multus namespace --------------------------------- 0.02s
2025-10-02 19:44:50.953332 | controller | INFO     Running local > verify
2025-10-02 19:44:50.953636 | controller | INFO     Running Ansible Verifier
2025-10-02 19:44:51.452444 | controller |
2025-10-02 19:44:51.452900 | controller | PLAY [Verify] ******************************************************************
2025-10-02 19:44:52.538155 | controller |
2025-10-02 19:44:52.538199 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:44:52.538207 | controller | Thursday 02 October 2025  19:44:51 +0000 (0:00:00.016)       0:00:00.016 ******
2025-10-02 19:44:52.538218 | controller | ok: [instance]
2025-10-02 19:44:52.538581 | controller |
2025-10-02 19:44:52.538762 | controller | TASK [Load shared variables] ***************************************************
2025-10-02 19:44:52.538788 | controller | Thursday 02 October 2025  19:44:52 +0000 (0:00:01.086)       0:00:01.102 ******
2025-10-02 19:44:52.564094 | controller | ok: [instance]
2025-10-02 19:44:52.933660 | controller |
2025-10-02 19:44:52.933724 | controller | TASK [Fetch files stat results] ************************************************
2025-10-02 19:44:52.933734 | controller | Thursday 02 October 2025  19:44:52 +0000 (0:00:00.025)       0:00:01.128 ******
2025-10-02 19:44:52.933748 | controller | ok: [instance]
2025-10-02 19:44:52.961015 | controller |
2025-10-02 19:44:52.961070 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-02 19:44:52.961078 | controller | Thursday 02 October 2025  19:44:52 +0000 (0:00:00.369)       0:00:01.497 ******
2025-10-02 19:44:52.961090 | controller | ok: [instance] => changed=false
2025-10-02 19:44:53.322785 | controller |   msg: All assertions passed
2025-10-02 19:44:53.322842 | controller |
2025-10-02 19:44:53.322852 | controller | TASK [Fetch file content] ******************************************************
2025-10-02 19:44:53.322860 | controller | Thursday 02 October 2025  19:44:52 +0000 (0:00:00.023)       0:00:01.521 ******
2025-10-02 19:44:53.322874 | controller | ok: [instance]
2025-10-02 19:44:53.364818 | controller |
2025-10-02 19:44:53.364868 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-02 19:44:53.364879 | controller | Thursday 02 October 2025  19:44:53 +0000 (0:00:00.359)       0:00:01.880 ******
2025-10-02 19:44:53.364904 | controller | ok: [instance]
2025-10-02 19:44:53.422272 | controller |
2025-10-02 19:44:53.422318 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-02 19:44:53.422329 | controller | Thursday 02 October 2025  19:44:53 +0000 (0:00:00.044)       0:00:01.925 ******
2025-10-02 19:44:53.422344 | controller | ok: [instance]
2025-10-02 19:44:53.490955 | controller |
2025-10-02 19:44:53.491027 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:53.491035 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:44:53.491042 | controller |
2025-10-02 19:44:53.491048 | controller | Thursday 02 October 2025  19:44:53 +0000 (0:00:00.060)       0:00:01.985 ******
2025-10-02 19:44:53.491054 | controller | ===============================================================================
2025-10-02 19:44:53.491060 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2025-10-02 19:44:53.491066 | controller | Fetch files stat results ------------------------------------------------ 0.37s
2025-10-02 19:44:53.491071 | controller | Fetch file content ------------------------------------------------------ 0.36s
2025-10-02 19:44:53.491077 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-10-02 19:44:53.491083 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-10-02 19:44:53.491089 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-02 19:44:53.491094 | controller | Assert that expected file exist ----------------------------------------- 0.02s
2025-10-02 19:44:53.491112 | controller | INFO     Verifier completed successfully.
2025-10-02 19:44:53.499698 | controller | INFO     Running local > side_effect
2025-10-02 19:44:54.007418 | controller |
2025-10-02 19:44:54.008063 | controller | PLAY [Clean] *******************************************************************
2025-10-02 19:44:54.008124 | controller |
2025-10-02 19:44:54.008139 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:44:54.008320 | controller | Thursday 02 October 2025  19:44:54 +0000 (0:00:00.015)       0:00:00.015 ******
2025-10-02 19:44:55.109437 | controller | ok: [instance]
2025-10-02 19:44:55.109517 | controller |
2025-10-02 19:44:55.109642 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-02 19:44:55.109785 | controller | Thursday 02 October 2025  19:44:55 +0000 (0:00:01.102)       0:00:01.118 ******
2025-10-02 19:44:55.465516 | controller | changed: [instance]
2025-10-02 19:44:55.465582 | controller |
2025-10-02 19:44:55.465717 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-02 19:44:55.465829 | controller | Thursday 02 October 2025  19:44:55 +0000 (0:00:00.350)       0:00:01.468 ******
2025-10-02 19:44:55.492083 | controller | skipping: [instance]
2025-10-02 19:44:55.492153 | controller |
2025-10-02 19:44:55.492272 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-02 19:44:55.492447 | controller | Thursday 02 October 2025  19:44:55 +0000 (0:00:00.028)       0:00:01.497 ******
2025-10-02 19:44:55.525107 | controller | skipping: [instance]
2025-10-02 19:44:55.525172 | controller |
2025-10-02 19:44:55.525274 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-02 19:44:55.525431 | controller | Thursday 02 October 2025  19:44:55 +0000 (0:00:00.032)       0:00:01.530 ******
2025-10-02 19:44:55.885569 | controller | changed: [instance]
2025-10-02 19:44:55.885684 | controller |
2025-10-02 19:44:55.885838 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:55.886046 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-02 19:44:55.886171 | controller |
2025-10-02 19:44:55.886341 | controller | Thursday 02 October 2025  19:44:55 +0000 (0:00:00.364)       0:00:01.894 ******
2025-10-02 19:44:55.886480 | controller | ===============================================================================
2025-10-02 19:44:55.886596 | controller | Gathering Facts --------------------------------------------------------- 1.10s
2025-10-02 19:44:55.886818 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.36s
2025-10-02 19:44:55.886962 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.35s
2025-10-02 19:44:55.887104 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.03s
2025-10-02 19:44:55.887254 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s
2025-10-02 19:44:55.966807 | controller | INFO     Running local > verify
2025-10-02 19:44:55.967154 | controller | INFO     Running Ansible Verifier
2025-10-02 19:44:56.531671 | controller |
2025-10-02 19:44:56.531745 | controller | PLAY [Verify Clean] ************************************************************
2025-10-02 19:44:56.531889 | controller |
2025-10-02 19:44:56.532033 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:44:56.532180 | controller | Thursday 02 October 2025  19:44:56 +0000 (0:00:00.018)       0:00:00.018 ******
2025-10-02 19:44:57.660496 | controller | ok: [instance]
2025-10-02 19:44:57.692300 | controller |
2025-10-02 19:44:57.692356 | controller | TASK [Include default vars] ****************************************************
2025-10-02 19:44:57.692373 | controller | Thursday 02 October 2025  19:44:57 +0000 (0:00:01.127)       0:00:01.145 ******
2025-10-02 19:44:57.692426 | controller | ok: [instance]
2025-10-02 19:44:58.033164 | controller |
2025-10-02 19:44:58.033200 | controller | TASK [Fetch file stat results] *************************************************
2025-10-02 19:44:58.033208 | controller | Thursday 02 October 2025  19:44:57 +0000 (0:00:00.030)       0:00:01.176 ******
2025-10-02 19:44:58.033219 | controller | ok: [instance]
2025-10-02 19:44:58.090752 | controller |
2025-10-02 19:44:58.090797 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-02 19:44:58.090807 | controller | Thursday 02 October 2025  19:44:58 +0000 (0:00:00.337)       0:00:01.513 ******
2025-10-02 19:44:58.090821 | controller | ok: [instance] => changed=false
2025-10-02 19:44:58.158578 | controller |   msg: All assertions passed
2025-10-02 19:44:58.158615 | controller |
2025-10-02 19:44:58.158623 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:44:58.158630 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:44:58.158636 | controller |
2025-10-02 19:44:58.158642 | controller | Thursday 02 October 2025  19:44:58 +0000 (0:00:00.061)       0:00:01.575 ******
2025-10-02 19:44:58.158648 | controller | ===============================================================================
2025-10-02 19:44:58.158654 | controller | Gathering Facts --------------------------------------------------------- 1.13s
2025-10-02 19:44:58.158660 | controller | Fetch file stat results ------------------------------------------------- 0.34s
2025-10-02 19:44:58.158665 | controller | Assert that created file are removed by cleanup ------------------------- 0.06s
2025-10-02 19:44:58.158678 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-02 19:44:58.158690 | controller | INFO     Verifier completed successfully.
2025-10-02 19:44:58.159153 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-02 19:44:58.169885 | controller | INFO     Running local_ipv6 > destroy
2025-10-02 19:44:58.170251 | controller | WARNING  Skipping, instances are delegated.
2025-10-02 19:44:58.181629 | controller | INFO     Running local_ipv6 > create
2025-10-02 19:44:58.182953 | controller | WARNING  Skipping, instances are delegated.
2025-10-02 19:44:58.193954 | controller | INFO     Running local_ipv6 > converge
2025-10-02 19:44:58.742616 | controller |
2025-10-02 19:45:00.816426 | controller | PLAY [Converge] ****************************************************************
2025-10-02 19:45:00.816499 | controller |
2025-10-02 19:45:00.816509 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:45:00.816517 | controller | Thursday 02 October 2025  19:44:58 +0000 (0:00:00.017)       0:00:00.017 ******
2025-10-02 19:45:00.816530 | controller | ok: [instance]
2025-10-02 19:45:00.846634 | controller |
2025-10-02 19:45:00.846675 | controller | TASK [Load shared variables] ***************************************************
2025-10-02 19:45:00.846686 | controller | Thursday 02 October 2025  19:45:00 +0000 (0:00:02.074)       0:00:02.091 ******
2025-10-02 19:45:00.846700 | controller | ok: [instance]
2025-10-02 19:45:00.903024 | controller |
2025-10-02 19:45:00.903077 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-02 19:45:00.903093 | controller | Thursday 02 October 2025  19:45:00 +0000 (0:00:00.026)       0:00:02.118 ******
2025-10-02 19:45:00.903115 | controller |
2025-10-02 19:45:01.312037 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-02 19:45:01.312069 | controller | Thursday 02 October 2025  19:45:00 +0000 (0:00:00.059)       0:00:02.177 ******
2025-10-02 19:45:01.312086 | controller | changed: [instance]
2025-10-02 19:45:01.312107 | controller |
2025-10-02 19:45:01.312426 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-02 19:45:01.337195 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.409)       0:00:02.587 ******
2025-10-02 19:45:01.337241 | controller | skipping: [instance]
2025-10-02 19:45:01.365773 | controller |
2025-10-02 19:45:01.365814 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-02 19:45:01.365825 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.024)       0:00:02.612 ******
2025-10-02 19:45:01.365839 | controller | skipping: [instance]
2025-10-02 19:45:01.366186 | controller |
2025-10-02 19:45:01.393367 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-02 19:45:01.393415 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.028)       0:00:02.641 ******
2025-10-02 19:45:01.393427 | controller | skipping: [instance]
2025-10-02 19:45:01.425805 | controller |
2025-10-02 19:45:01.425847 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-02 19:45:01.425858 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.027)       0:00:02.669 ******
2025-10-02 19:45:01.425874 | controller | skipping: [instance]
2025-10-02 19:45:01.464004 | controller |
2025-10-02 19:45:01.464037 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-02 19:45:01.464048 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.032)       0:00:02.701 ******
2025-10-02 19:45:01.464061 | controller | ok: [instance]
2025-10-02 19:45:01.464671 | controller |
2025-10-02 19:45:01.569650 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-02 19:45:01.569686 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.038)       0:00:02.739 ******
2025-10-02 19:45:01.569703 | controller | skipping: [instance] => (item={'default': {'interface_name': 'eth1', 'network_name': 'default', 'gw_v6': 'fdc0:8b54:108a:c949:0000:0000:0000:0001', 'network_v6': 'fdc0:8b54:108a:c949::/64', 'tools': {'multus': {'ipv6_ranges': [{'start': 'fdc0:8b54:108a:c949:0000:0000:0000:001e', 'end': 'fdc0:8b54:108a:c949:0000:0000:0000:0027'}]}}}})
2025-10-02 19:45:01.569998 | controller | skipping: [instance]
2025-10-02 19:45:01.655517 | controller |
2025-10-02 19:45:01.655555 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-02 19:45:01.655563 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.105)       0:00:02.845 ******
2025-10-02 19:45:01.655575 | controller | ok: [instance]
2025-10-02 19:45:01.655800 | controller |
2025-10-02 19:45:01.655840 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-02 19:45:01.726648 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.084)       0:00:02.930 ******
2025-10-02 19:45:01.726697 | controller | ok: [instance]
2025-10-02 19:45:01.800930 | controller |
2025-10-02 19:45:01.800973 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-02 19:45:01.800985 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.071)       0:00:03.001 ******
2025-10-02 19:45:01.801000 | controller | ok: [instance]
2025-10-02 19:45:02.562410 | controller |
2025-10-02 19:45:02.562454 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-02 19:45:02.562465 | controller | Thursday 02 October 2025  19:45:01 +0000 (0:00:00.074)       0:00:03.076 ******
2025-10-02 19:45:02.562479 | controller | changed: [instance]
2025-10-02 19:45:02.562886 | controller |
2025-10-02 19:45:02.562909 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-02 19:45:02.587262 | controller | Thursday 02 October 2025  19:45:02 +0000 (0:00:00.761)       0:00:03.837 ******
2025-10-02 19:45:02.587336 | controller | skipping: [instance]
2025-10-02 19:45:02.645103 | controller |
2025-10-02 19:45:02.645136 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-02 19:45:02.645144 | controller | Thursday 02 October 2025  19:45:02 +0000 (0:00:00.024)       0:00:03.861 ******
2025-10-02 19:45:02.645155 | controller | skipping: [instance]
2025-10-02 19:45:02.725863 | controller |
2025-10-02 19:45:02.725904 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:45:02.725913 | controller | instance                   : ok=8    changed=2    unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-10-02 19:45:02.725921 | controller |
2025-10-02 19:45:02.725929 | controller | Thursday 02 October 2025  19:45:02 +0000 (0:00:00.056)       0:00:03.918 ******
2025-10-02 19:45:02.725937 | controller | ===============================================================================
2025-10-02 19:45:02.725944 | controller | Gathering Facts --------------------------------------------------------- 2.07s
2025-10-02 19:45:02.725952 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.76s
2025-10-02 19:45:02.725959 | controller | ci_multus : Create manifests directory ---------------------------------- 0.41s
2025-10-02 19:45:02.725967 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.11s
2025-10-02 19:45:02.725974 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.09s
2025-10-02 19:45:02.725982 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.07s
2025-10-02 19:45:02.725990 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.07s
2025-10-02 19:45:02.725998 | controller | Call ci_multus role ----------------------------------------------------- 0.06s
2025-10-02 19:45:02.726005 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.06s
2025-10-02 19:45:02.726012 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.04s
2025-10-02 19:45:02.726020 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.03s
2025-10-02 19:45:02.726027 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-10-02 19:45:02.726035 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2025-10-02 19:45:02.726042 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-02 19:45:02.726050 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2025-10-02 19:45:02.726058 | controller | ci_multus : Create the multus namespace --------------------------------- 0.02s
2025-10-02 19:45:02.726073 | controller | INFO     Running local_ipv6 > verify
2025-10-02 19:45:02.726543 | controller | INFO     Running Ansible Verifier
2025-10-02 19:45:03.260596 | controller |
2025-10-02 19:45:04.318153 | controller | PLAY [Verify] ******************************************************************
2025-10-02 19:45:04.318195 | controller |
2025-10-02 19:45:04.318207 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:45:04.318216 | controller | Thursday 02 October 2025  19:45:03 +0000 (0:00:00.017)       0:00:00.017 ******
2025-10-02 19:45:04.318231 | controller | ok: [instance]
2025-10-02 19:45:04.345533 | controller |
2025-10-02 19:45:04.345569 | controller | TASK [Load shared variables] ***************************************************
2025-10-02 19:45:04.345577 | controller | Thursday 02 October 2025  19:45:04 +0000 (0:00:01.057)       0:00:01.075 ******
2025-10-02 19:45:04.345587 | controller | ok: [instance]
2025-10-02 19:45:04.345948 | controller |
2025-10-02 19:45:04.708012 | controller | TASK [Fetch files stat results] ************************************************
2025-10-02 19:45:04.708066 | controller | Thursday 02 October 2025  19:45:04 +0000 (0:00:00.027)       0:00:01.103 ******
2025-10-02 19:45:04.708081 | controller | ok: [instance]
2025-10-02 19:45:04.729767 | controller |
2025-10-02 19:45:04.729820 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-02 19:45:04.729829 | controller | Thursday 02 October 2025  19:45:04 +0000 (0:00:00.357)       0:00:01.460 ******
2025-10-02 19:45:04.729871 | controller | ok: [instance] => changed=false
2025-10-02 19:45:04.729961 | controller |   msg: All assertions passed
2025-10-02 19:45:04.729974 | controller |
2025-10-02 19:45:04.730073 | controller | TASK [Fetch file content] ******************************************************
2025-10-02 19:45:04.730191 | controller | Thursday 02 October 2025  19:45:04 +0000 (0:00:00.026)       0:00:01.487 ******
2025-10-02 19:45:05.017302 | controller | ok: [instance]
2025-10-02 19:45:05.017357 | controller |
2025-10-02 19:45:05.017593 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-02 19:45:05.017741 | controller | Thursday 02 October 2025  19:45:05 +0000 (0:00:00.287)       0:00:01.775 ******
2025-10-02 19:45:05.049004 | controller | ok: [instance]
2025-10-02 19:45:05.049060 | controller |
2025-10-02 19:45:05.049232 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-02 19:45:05.049404 | controller | Thursday 02 October 2025  19:45:05 +0000 (0:00:00.031)       0:00:01.807 ******
2025-10-02 19:45:05.096824 | controller | ok: [instance]
2025-10-02 19:45:05.096882 | controller |
2025-10-02 19:45:05.097051 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:45:05.097219 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:45:05.097423 | controller |
2025-10-02 19:45:05.097675 | controller | Thursday 02 October 2025  19:45:05 +0000 (0:00:00.048)       0:00:01.855 ******
2025-10-02 19:45:05.097888 | controller | ===============================================================================
2025-10-02 19:45:05.098117 | controller | Gathering Facts --------------------------------------------------------- 1.06s
2025-10-02 19:45:05.098329 | controller | Fetch files stat results ------------------------------------------------ 0.36s
2025-10-02 19:45:05.098547 | controller | Fetch file content ------------------------------------------------------ 0.29s
2025-10-02 19:45:05.098758 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.05s
2025-10-02 19:45:05.098965 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.03s
2025-10-02 19:45:05.099188 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-02 19:45:05.099406 | controller | Assert that expected file exist ----------------------------------------- 0.03s
2025-10-02 19:45:05.157736 | controller | INFO     Verifier completed successfully.
2025-10-02 19:45:05.180240 | controller | INFO     Running local_ipv6 > side_effect
2025-10-02 19:45:05.701909 | controller |
2025-10-02 19:45:05.702074 | controller | PLAY [Clean] *******************************************************************
2025-10-02 19:45:05.702251 | controller |
2025-10-02 19:45:05.702680 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:45:06.730695 | controller | Thursday 02 October 2025  19:45:05 +0000 (0:00:00.017)       0:00:00.017 ******
2025-10-02 19:45:06.730785 | controller | ok: [instance]
2025-10-02 19:45:06.730802 | controller |
2025-10-02 19:45:06.730820 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-02 19:45:06.730968 | controller | Thursday 02 October 2025  19:45:06 +0000 (0:00:01.029)       0:00:01.046 ******
2025-10-02 19:45:07.134990 | controller | changed: [instance]
2025-10-02 19:45:07.135051 | controller |
2025-10-02 19:45:07.135201 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-02 19:45:07.135317 | controller | Thursday 02 October 2025  19:45:07 +0000 (0:00:00.404)       0:00:01.451 ******
2025-10-02 19:45:07.165534 | controller | skipping: [instance]
2025-10-02 19:45:07.165634 | controller |
2025-10-02 19:45:07.165833 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-02 19:45:07.166054 | controller | Thursday 02 October 2025  19:45:07 +0000 (0:00:00.029)       0:00:01.481 ******
2025-10-02 19:45:07.211780 | controller | skipping: [instance]
2025-10-02 19:45:07.211852 | controller |
2025-10-02 19:45:07.211942 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-02 19:45:07.212051 | controller | Thursday 02 October 2025  19:45:07 +0000 (0:00:00.046)       0:00:01.527 ******
2025-10-02 19:45:07.615888 | controller | changed: [instance]
2025-10-02 19:45:07.615973 | controller |
2025-10-02 19:45:07.616052 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:45:07.616225 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-02 19:45:07.616298 | controller |
2025-10-02 19:45:07.616423 | controller | Thursday 02 October 2025  19:45:07 +0000 (0:00:00.404)       0:00:01.932 ******
2025-10-02 19:45:07.616547 | controller | ===============================================================================
2025-10-02 19:45:07.616658 | controller | Gathering Facts --------------------------------------------------------- 1.03s
2025-10-02 19:45:07.617203 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.40s
2025-10-02 19:45:07.617326 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.40s
2025-10-02 19:45:07.617545 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.05s
2025-10-02 19:45:07.617677 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s
2025-10-02 19:45:07.693985 | controller | INFO     Running local_ipv6 > verify
2025-10-02 19:45:07.694237 | controller | INFO     Running Ansible Verifier
2025-10-02 19:45:08.183303 | controller |
2025-10-02 19:45:08.183614 | controller | PLAY [Verify Clean] ************************************************************
2025-10-02 19:45:08.183706 | controller |
2025-10-02 19:45:08.184081 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 19:45:08.184122 | controller | Thursday 02 October 2025  19:45:08 +0000 (0:00:00.014)       0:00:00.014 ******
2025-10-02 19:45:09.347468 | controller | ok: [instance]
2025-10-02 19:45:09.347683 | controller |
2025-10-02 19:45:09.347703 | controller | TASK [Include default vars] ****************************************************
2025-10-02 19:45:09.383426 | controller | Thursday 02 October 2025  19:45:09 +0000 (0:00:01.165)       0:00:01.180 ******
2025-10-02 19:45:09.383495 | controller | ok: [instance]
2025-10-02 19:45:09.383640 | controller |
2025-10-02 19:45:09.383654 | controller | TASK [Fetch file stat results] *************************************************
2025-10-02 19:45:09.770832 | controller | Thursday 02 October 2025  19:45:09 +0000 (0:00:00.035)       0:00:01.215 ******
2025-10-02 19:45:09.770904 | controller | ok: [instance]
2025-10-02 19:45:09.770916 | controller |
2025-10-02 19:45:09.771294 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-02 19:45:09.828324 | controller | Thursday 02 October 2025  19:45:09 +0000 (0:00:00.387)       0:00:01.603 ******
2025-10-02 19:45:09.828419 | controller | ok: [instance] => changed=false
2025-10-02 19:45:09.828548 | controller |   msg: All assertions passed
2025-10-02 19:45:09.828786 | controller |
2025-10-02 19:45:09.829013 | controller | PLAY RECAP *********************************************************************
2025-10-02 19:45:09.829244 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-02 19:45:09.829489 | controller |
2025-10-02 19:45:09.829715 | controller | Thursday 02 October 2025  19:45:09 +0000 (0:00:00.058)       0:00:01.661 ******
2025-10-02 19:45:09.829940 | controller | ===============================================================================
2025-10-02 19:45:09.830145 | controller | Gathering Facts --------------------------------------------------------- 1.17s
2025-10-02 19:45:09.830339 | controller | Fetch file stat results ------------------------------------------------- 0.39s
2025-10-02 19:45:09.830587 | controller | Assert that created file are removed by cleanup ------------------------- 0.06s
2025-10-02 19:45:09.830810 | controller | Include default vars ---------------------------------------------------- 0.04s
2025-10-02 19:45:09.890977 | controller | INFO     Verifier completed successfully.
2025-10-02 19:45:09.892072 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-02 19:45:09.895933 | controller | INFO     Writing /tmp/report.html report.
2025-10-02 19:45:10.046302 | controller | changed
2025-10-02 19:45:10.095155 | 
2025-10-02 19:45:10.095249 | PLAY RECAP
2025-10-02 19:45:10.095298 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 19:45:10.095327 | 
2025-10-02 19:45:10.197554 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 19:45:10.199207 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 19:45:10.804883 | 
2025-10-02 19:45:10.804990 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-02 19:45:10.825929 | 
2025-10-02 19:45:10.826022 | TASK [Filter out host if needed]
2025-10-02 19:45:10.834915 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-02 19:45:10.839855 | 
2025-10-02 19:45:10.839927 | TASK [Ensure file is present]
2025-10-02 19:45:11.448521 | controller | ok
2025-10-02 19:45:11.461498 | 
2025-10-02 19:45:11.461636 | TASK [Manage molecule report file]
2025-10-02 19:45:12.516947 | controller | changed
2025-10-02 19:45:12.529233 | 
2025-10-02 19:45:12.529370 | TASK [Check if we get ci-framework-data basedir]
2025-10-02 19:45:12.865806 | controller | ok
2025-10-02 19:45:12.877794 | 
2025-10-02 19:45:12.877949 | TASK [Create ci-framework-data log directory for zuul]
2025-10-02 19:45:13.410194 | controller | changed
2025-10-02 19:45:13.423711 | 
2025-10-02 19:45:13.423898 | TASK [Copy ci-framework interesting files]
2025-10-02 19:45:13.791911 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-02 19:45:13.975257 | controller | changed
2025-10-02 19:45:13.987153 | 
2025-10-02 19:45:13.987345 | TASK [Get SELinux listing]
2025-10-02 19:45:14.541439 | controller | changed
2025-10-02 19:45:14.554422 | 
2025-10-02 19:45:14.554582 | TASK [Generate log index]
2025-10-02 19:45:16.280854 | controller | changed
2025-10-02 19:45:16.293374 | 
2025-10-02 19:45:16.293522 | TASK [Get some env related data]
2025-10-02 19:45:17.105180 | controller | /home/zuul/.local/bin/ansible
2025-10-02 19:45:17.853976 | controller | changed
2025-10-02 19:45:17.863478 | 
2025-10-02 19:45:17.863602 | TASK [Generate list of logs to collect in home directory]
2025-10-02 19:45:18.308268 | controller | ok: All paths examined
2025-10-02 19:45:18.321056 | 
2025-10-02 19:45:18.321194 | LOOP [Copy logs from home directory]
2025-10-02 19:45:19.251393 | controller | changed:
2025-10-02 19:45:19.251882 | controller | {
2025-10-02 19:45:19.251950 | controller |   "atime": 1743544925.4788878,
2025-10-02 19:45:19.251993 | controller |   "ctime": 1743545329.1409318,
2025-10-02 19:45:19.252033 | controller |   "dev": 64513,
2025-10-02 19:45:19.252073 | controller |   "gid": 1000,
2025-10-02 19:45:19.252108 | controller |   "gr_name": "zuul",
2025-10-02 19:45:19.252147 | controller |   "inode": 4518807,
2025-10-02 19:45:19.252183 | controller |   "isblk": false,
2025-10-02 19:45:19.252219 | controller |   "ischr": false,
2025-10-02 19:45:19.252267 | controller |   "isdir": false,
2025-10-02 19:45:19.252322 | controller |   "isfifo": false,
2025-10-02 19:45:19.252388 | controller |   "isgid": false,
2025-10-02 19:45:19.252456 | controller |   "islnk": false,
2025-10-02 19:45:19.252503 | controller |   "isreg": true,
2025-10-02 19:45:19.252541 | controller |   "issock": false,
2025-10-02 19:45:19.252576 | controller |   "isuid": false,
2025-10-02 19:45:19.252611 | controller |   "mode": "0644",
2025-10-02 19:45:19.252647 | controller |   "mtime": 1743545329.1409318,
2025-10-02 19:45:19.252681 | controller |   "nlink": 1,
2025-10-02 19:45:19.252717 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-02 19:45:19.252815 | controller |   "pw_name": "zuul",
2025-10-02 19:45:19.252855 | controller |   "rgrp": true,
2025-10-02 19:45:19.252890 | controller |   "roth": true,
2025-10-02 19:45:19.252924 | controller |   "rusr": true,
2025-10-02 19:45:19.252958 | controller |   "size": 4108,
2025-10-02 19:45:19.252992 | controller |   "uid": 1000,
2025-10-02 19:45:19.253026 | controller |   "wgrp": false,
2025-10-02 19:45:19.253062 | controller |   "woth": false,
2025-10-02 19:45:19.253101 | controller |   "wusr": true,
2025-10-02 19:45:19.253138 | controller |   "xgrp": false,
2025-10-02 19:45:19.253173 | controller |   "xoth": false,
2025-10-02 19:45:19.253209 | controller |   "xusr": false
2025-10-02 19:45:19.253245 | controller | }
2025-10-02 19:45:20.086140 | controller | changed:
2025-10-02 19:45:20.086440 | controller | {
2025-10-02 19:45:20.086531 | controller |   "atime": 1759433467.6047177,
2025-10-02 19:45:20.086596 | controller |   "ctime": 1759433465.326641,
2025-10-02 19:45:20.086654 | controller |   "dev": 64513,
2025-10-02 19:45:20.086703 | controller |   "gid": 1000,
2025-10-02 19:45:20.086831 | controller |   "gr_name": "zuul",
2025-10-02 19:45:20.086882 | controller |   "inode": 4194437,
2025-10-02 19:45:20.086922 | controller |   "isblk": false,
2025-10-02 19:45:20.086958 | controller |   "ischr": false,
2025-10-02 19:45:20.086993 | controller |   "isdir": false,
2025-10-02 19:45:20.087030 | controller |   "isfifo": false,
2025-10-02 19:45:20.087067 | controller |   "isgid": false,
2025-10-02 19:45:20.087102 | controller |   "islnk": false,
2025-10-02 19:45:20.087139 | controller |   "isreg": true,
2025-10-02 19:45:20.087176 | controller |   "issock": false,
2025-10-02 19:45:20.087211 | controller |   "isuid": false,
2025-10-02 19:45:20.087248 | controller |   "mode": "0644",
2025-10-02 19:45:20.087285 | controller |   "mtime": 1759433465.326641,
2025-10-02 19:45:20.087333 | controller |   "nlink": 1,
2025-10-02 19:45:20.087372 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-02 19:45:20.087408 | controller |   "pw_name": "zuul",
2025-10-02 19:45:20.087444 | controller |   "rgrp": true,
2025-10-02 19:45:20.087482 | controller |   "roth": true,
2025-10-02 19:45:20.087517 | controller |   "rusr": true,
2025-10-02 19:45:20.087554 | controller |   "size": 3404,
2025-10-02 19:45:20.087604 | controller |   "uid": 1000,
2025-10-02 19:45:20.087660 | controller |   "wgrp": false,
2025-10-02 19:45:20.087717 | controller |   "woth": false,
2025-10-02 19:45:20.087836 | controller |   "wusr": true,
2025-10-02 19:45:20.087879 | controller |   "xgrp": false,
2025-10-02 19:45:20.087916 | controller |   "xoth": false,
2025-10-02 19:45:20.087952 | controller |   "xusr": false
2025-10-02 19:45:20.087989 | controller | }
2025-10-02 19:45:20.945618 | controller | changed:
2025-10-02 19:45:20.945720 | controller | {
2025-10-02 19:45:20.945772 | controller |   "atime": 1759434156.7092345,
2025-10-02 19:45:20.945792 | controller |   "ctime": 1759434173.6327298,
2025-10-02 19:45:20.945809 | controller |   "dev": 64513,
2025-10-02 19:45:20.945824 | controller |   "gid": 1000,
2025-10-02 19:45:20.945840 | controller |   "gr_name": "zuul",
2025-10-02 19:45:20.945856 | controller |   "inode": 4685791,
2025-10-02 19:45:20.945871 | controller |   "isblk": false,
2025-10-02 19:45:20.945887 | controller |   "ischr": false,
2025-10-02 19:45:20.945902 | controller |   "isdir": false,
2025-10-02 19:45:20.945917 | controller |   "isfifo": false,
2025-10-02 19:45:20.945932 | controller |   "isgid": false,
2025-10-02 19:45:20.945947 | controller |   "islnk": false,
2025-10-02 19:45:20.945961 | controller |   "isreg": true,
2025-10-02 19:45:20.945977 | controller |   "issock": false,
2025-10-02 19:45:20.945992 | controller |   "isuid": false,
2025-10-02 19:45:20.946007 | controller |   "mode": "0644",
2025-10-02 19:45:20.946023 | controller |   "mtime": 1759434173.6327298,
2025-10-02 19:45:20.946038 | controller |   "nlink": 1,
2025-10-02 19:45:20.946054 | controller |   "path": "/home/zuul/ansible.log",
2025-10-02 19:45:20.946070 | controller |   "pw_name": "zuul",
2025-10-02 19:45:20.946085 | controller |   "rgrp": true,
2025-10-02 19:45:20.946101 | controller |   "roth": true,
2025-10-02 19:45:20.946119 | controller |   "rusr": true,
2025-10-02 19:45:20.946137 | controller |   "size": 6749,
2025-10-02 19:45:20.946153 | controller |   "uid": 1000,
2025-10-02 19:45:20.946169 | controller |   "wgrp": false,
2025-10-02 19:45:20.946185 | controller |   "woth": false,
2025-10-02 19:45:20.946200 | controller |   "wusr": true,
2025-10-02 19:45:20.946215 | controller |   "xgrp": false,
2025-10-02 19:45:20.946231 | controller |   "xoth": false,
2025-10-02 19:45:20.946246 | controller |   "xusr": false
2025-10-02 19:45:20.946261 | controller | }
2025-10-02 19:45:20.960184 | 
2025-10-02 19:45:20.960307 | TASK [Copy crio stats log file]
2025-10-02 19:45:20.975490 | controller | skipping: Conditional result was False
2025-10-02 19:45:20.982314 | 
2025-10-02 19:45:20.982395 | TASK [Get SELinux related data]
2025-10-02 19:45:21.707859 | controller | <no matches>
2025-10-02 19:45:22.012681 | controller | ERROR
2025-10-02 19:45:22.012933 | controller | {
2025-10-02 19:45:22.012996 | controller |   "delta": "0:00:00.014906",
2025-10-02 19:45:22.013038 | controller |   "end": "2025-10-02 19:45:21.709266",
2025-10-02 19:45:22.013102 | controller |   "msg": "non-zero return code",
2025-10-02 19:45:22.013140 | controller |   "rc": 1,
2025-10-02 19:45:22.013175 | controller |   "start": "2025-10-02 19:45:21.694360"
2025-10-02 19:45:22.013210 | controller | }
2025-10-02 19:45:22.013256 | controller | ERROR: Ignoring Errors
2025-10-02 19:45:22.024512 | 
2025-10-02 19:45:22.024640 | TASK [Create system configuration directory]
2025-10-02 19:45:22.353002 | controller | changed
2025-10-02 19:45:22.360077 | 
2025-10-02 19:45:22.360161 | TASK [Get some of the system configurations]
2025-10-02 19:45:22.899511 | controller | changed
2025-10-02 19:45:22.911339 | 
2025-10-02 19:45:22.911513 | TASK [Copy generated documentation if available]
2025-10-02 19:45:22.948261 | controller | skipping: Conditional result was False
2025-10-02 19:45:22.960587 | 
2025-10-02 19:45:22.960834 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-02 19:45:22.988400 | controller | skipping: Conditional result was False
2025-10-02 19:45:23.003086 | 
2025-10-02 19:45:23.003286 | TASK [Compress logs bigger than 2MB]
2025-10-02 19:45:23.553186 | controller | changed
2025-10-02 19:45:23.561765 | 
2025-10-02 19:45:23.561887 | TASK [Copy files from workspace on node]
2025-10-02 19:45:23.584498 | controller | ok
2025-10-02 19:45:23.616094 | 
2025-10-02 19:45:23.616244 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 19:45:23.651514 | controller | skipping: Conditional result was False
2025-10-02 19:45:23.660337 | 
2025-10-02 19:45:23.660493 | TASK [fetch-output : Set log path for single node]
2025-10-02 19:45:23.705918 | controller | ok
2025-10-02 19:45:23.713743 | 
2025-10-02 19:45:23.713876 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 19:45:24.025515 | controller -> localhost | ok: "/var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/logs"
2025-10-02 19:45:24.026111 | controller -> localhost | changed: All items complete
2025-10-02 19:45:24.026182 | 
2025-10-02 19:45:24.282111 | controller -> localhost | changed: "/var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/artifacts"
2025-10-02 19:45:24.500875 | controller -> localhost | changed: "/var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/docs"
2025-10-02 19:45:24.516431 | 
2025-10-02 19:45:24.516578 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 19:45:25.280468 | controller | changed:
2025-10-02 19:45:25.280989 | controller | .d..t...... ./
2025-10-02 19:45:25.281055 | controller | >f+++++++++ README.html
2025-10-02 19:45:25.281099 | controller | >f+++++++++ ansible-execution.log
2025-10-02 19:45:25.281140 | controller | >f+++++++++ ansible.log
2025-10-02 19:45:25.281178 | controller | >f+++++++++ crc-setup.log
2025-10-02 19:45:25.281215 | controller | >f+++++++++ crc-start.log
2025-10-02 19:45:25.281251 | controller | >f+++++++++ dmesg.log
2025-10-02 19:45:25.281294 | controller | >f+++++++++ installed-pkgs.log
2025-10-02 19:45:25.281331 | controller | >f+++++++++ python.log
2025-10-02 19:45:25.281366 | controller | >f+++++++++ registries.conf
2025-10-02 19:45:25.281401 | controller | >f+++++++++ report.html
2025-10-02 19:45:25.281435 | controller | >f+++++++++ selinux-denials.log
2025-10-02 19:45:25.281469 | controller | >f+++++++++ selinux-listing.log
2025-10-02 19:45:25.281503 | controller | cd+++++++++ ci-framework-data/
2025-10-02 19:45:25.281537 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-02 19:45:25.281572 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-02 19:45:25.281607 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-02 19:45:25.281641 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/
2025-10-02 19:45:25.281675 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/
2025-10-02 19:45:25.281710 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/ci_multus_nads.yml
2025-10-02 19:45:25.281786 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-02 19:45:25.281824 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-02 19:45:25.281861 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-02 19:45:25.281897 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-02 19:45:25.281931 | controller | cd+++++++++ registries.conf.d/
2025-10-02 19:45:25.281965 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-02 19:45:25.282000 | controller | cd+++++++++ system-config/
2025-10-02 19:45:25.282034 | controller | cd+++++++++ system-config/libvirt/
2025-10-02 19:45:25.282084 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-02 19:45:25.282120 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-02 19:45:25.282155 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-02 19:45:25.282190 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-02 19:45:25.282225 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-02 19:45:25.282260 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-02 19:45:25.282295 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-02 19:45:25.282330 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-02 19:45:25.282364 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-02 19:45:25.282398 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-02 19:45:25.282433 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-02 19:45:25.282468 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-02 19:45:25.282502 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-02 19:45:25.282536 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-02 19:45:25.282571 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-02 19:45:25.282607 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-02 19:45:25.837173 | controller | changed: .d..t...... ./
2025-10-02 19:45:26.422943 | controller | changed: .d..t...... ./
2025-10-02 19:45:26.446280 | 
2025-10-02 19:45:26.446403 | TASK [Return artifact to Zuul]
2025-10-02 19:45:26.502522 | controller | ok
2025-10-02 19:45:26.538925 | 
2025-10-02 19:45:26.539005 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-02 19:45:26.539125 | 
2025-10-02 19:45:26.539161 | PLAY RECAP
2025-10-02 19:45:26.539210 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-02 19:45:26.539237 | 
2025-10-02 19:45:26.647823 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 19:45:26.649428 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-02 19:45:27.168788 | 
2025-10-02 19:45:27.168890 | PLAY [Report DLRN results after job run]
2025-10-02 19:45:27.189118 | 
2025-10-02 19:45:27.189196 | TASK [Report job status to DLRN]
2025-10-02 19:45:27.210139 | controller | ok
2025-10-02 19:45:27.232564 | 
2025-10-02 19:45:27.232662 | TASK [dlrn_report : Warning if DLRN is not running]
2025-10-02 19:45:27.296712 | controller | skipping: Conditional result was False
2025-10-02 19:45:27.305121 | 
2025-10-02 19:45:27.305219 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-10-02 19:45:27.370669 | controller | skipping: Conditional result was False
2025-10-02 19:45:27.383868 | 
2025-10-02 19:45:27.384023 | TASK [dlrn_report : Install kinit related package]
2025-10-02 19:45:27.433400 | controller | skipping: Conditional result was False
2025-10-02 19:45:27.447206 | 
2025-10-02 19:45:27.447364 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-10-02 19:45:27.503814 | controller | skipping: Conditional result was False
2025-10-02 19:45:27.518512 | 
2025-10-02 19:45:27.518659 | TASK [Set zuul-log-path fact]
2025-10-02 19:45:27.565796 | controller | skipping: Conditional result was False
2025-10-02 19:45:27.584249 | 
2025-10-02 19:45:27.584440 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-10-02 19:45:27.630453 | controller | skipping: Conditional result was False
2025-10-02 19:45:27.642486 | 
2025-10-02 19:45:27.642657 | TASK [Get hash related data from repo_setup role]
2025-10-02 19:45:27.690151 | controller | skipping: Conditional result was False
2025-10-02 19:45:27.705451 | 
2025-10-02 19:45:27.705615 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-10-02 19:45:28.259214 | controller | skipping: Conditional result was False
2025-10-02 19:45:28.273852 | 
2025-10-02 19:45:28.274021 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-10-02 19:45:28.353140 | controller | skipping: Conditional result was False
2025-10-02 19:45:28.366396 | 
2025-10-02 19:45:28.366552 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-10-02 19:45:28.917670 | controller | skipping: Conditional result was False
2025-10-02 19:45:28.960969 | 
2025-10-02 19:45:28.961136 | PLAY RECAP
2025-10-02 19:45:28.961224 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-10-02 19:45:28.961273 | 
2025-10-02 19:45:29.077070 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-02 19:45:29.078601 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 19:45:29.676895 | 
2025-10-02 19:45:29.677010 | PLAY [all]
2025-10-02 19:45:29.695790 | 
2025-10-02 19:45:29.695872 | TASK [include_role : fetch-output]
2025-10-02 19:45:29.734958 | controller | ok
2025-10-02 19:45:29.751467 | 
2025-10-02 19:45:29.751551 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 19:45:29.815490 | controller | skipping: Conditional result was False
2025-10-02 19:45:29.821232 | 
2025-10-02 19:45:29.821317 | TASK [fetch-output : Set log path for single node]
2025-10-02 19:45:29.856467 | controller | ok
2025-10-02 19:45:29.867898 | 
2025-10-02 19:45:29.868039 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 19:45:30.299677 | controller -> localhost | ok: "/var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/logs"
2025-10-02 19:45:30.517521 | controller -> localhost | ok: "/var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/artifacts"
2025-10-02 19:45:30.756325 | controller -> localhost | ok: "/var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/docs"
2025-10-02 19:45:30.765089 | 
2025-10-02 19:45:30.765182 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 19:45:31.488955 | controller | ok
2025-10-02 19:45:31.489304 | controller | ok: All items complete
2025-10-02 19:45:31.489366 | 
2025-10-02 19:45:32.148110 | controller | ok
2025-10-02 19:45:32.782970 | controller | ok
2025-10-02 19:45:32.816165 | 
2025-10-02 19:45:32.816365 | TASK [include_role : fetch-output-openshift]
2025-10-02 19:45:32.852050 | controller | skipping: Conditional result was False
2025-10-02 19:45:32.865647 | 
2025-10-02 19:45:32.865922 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 19:45:33.303440 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013152
2025-10-02 19:45:33.547295 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012133
2025-10-02 19:45:33.582431 | 
2025-10-02 19:45:33.582550 | PLAY [all]
2025-10-02 19:45:33.597618 | 
2025-10-02 19:45:33.597761 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 19:45:34.191500 | controller | changed
2025-10-02 19:45:34.222288 | 
2025-10-02 19:45:34.222397 | PLAY RECAP
2025-10-02 19:45:34.222439 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 19:45:34.222459 | 
2025-10-02 19:45:34.338175 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 19:45:34.339709 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 19:45:34.949039 | 
2025-10-02 19:45:34.949147 | PLAY [localhost]
2025-10-02 19:45:34.967482 | 
2025-10-02 19:45:34.967569 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 19:45:35.403655 | localhost | changed
2025-10-02 19:45:35.410164 | 
2025-10-02 19:45:35.410292 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 19:45:35.453505 | localhost | ok
2025-10-02 19:45:35.465235 | 
2025-10-02 19:45:35.465371 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 19:45:35.856074 | localhost | changed
2025-10-02 19:45:35.863028 | 
2025-10-02 19:45:35.863293 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 19:45:36.574908 | localhost | changed
2025-10-02 19:45:36.586344 | 
2025-10-02 19:45:36.586497 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 19:45:37.009705 | localhost | Identity added: /var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/tmp/ansible._xvn9kiz (/var/lib/zuul/builds/d9bc47dd8a9c4b0a847bd8bf1ced091f/work/tmp/ansible._xvn9kiz)
2025-10-02 19:45:37.010070 | localhost | ok: Runtime: 0:00:00.008273
2025-10-02 19:45:37.020166 | 
2025-10-02 19:45:37.020344 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 19:45:37.322774 | localhost | ok: Runtime: 0:00:00.005047
2025-10-02 19:45:37.334331 | 
2025-10-02 19:45:37.334509 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 19:45:37.391396 | localhost | changed
2025-10-02 19:45:37.400916 | 
2025-10-02 19:45:37.401098 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 19:45:37.811811 | localhost | changed
2025-10-02 19:45:37.831900 | 
2025-10-02 19:45:37.831958 | PLAY [localhost]
2025-10-02 19:45:37.844060 | 
2025-10-02 19:45:37.844118 | TASK [Generate bulk log download script]
2025-10-02 19:45:37.862035 | localhost | ok
2025-10-02 19:45:37.873375 | 
2025-10-02 19:45:37.873437 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 19:45:37.901160 | localhost | ok: All assertions passed
2025-10-02 19:45:37.906048 | 
2025-10-02 19:45:37.906134 | TASK [local-log-download : Create download script]
2025-10-02 19:45:38.357682 | localhost -> localhost | changed
2025-10-02 19:45:38.367258 | 
2025-10-02 19:45:38.367333 | TASK [Register quick-download link]
2025-10-02 19:45:38.386720 | localhost | ok
2025-10-02 19:45:38.424363 | 
2025-10-02 19:45:38.424461 | PLAY [logserver.rdoproject.org]
2025-10-02 19:45:38.433695 | 
2025-10-02 19:45:38.433768 | TASK [Set zuul-log-path fact]
2025-10-02 19:45:38.450475 | logserver.rdoproject.org | ok
2025-10-02 19:45:38.459109 | 
2025-10-02 19:45:38.459171 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 19:45:38.495442 | logserver.rdoproject.org | ok
2025-10-02 19:45:38.500817 | 
2025-10-02 19:45:38.500877 | TASK [upload-logs : Create log directories]
2025-10-02 19:45:39.418284 | logserver.rdoproject.org | changed
2025-10-02 19:45:39.425364 | 
2025-10-02 19:45:39.425491 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 19:45:39.758297 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009308
2025-10-02 19:45:39.765867 | 
2025-10-02 19:45:39.765997 | TASK [upload-logs : Upload logs to log server]
2025-10-02 19:45:40.509193 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 19:45:40.513465 | 
2025-10-02 19:45:40.513559 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 19:45:40.579666 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 19:45:40.595656 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 19:45:40.606352 | 
2025-10-02 19:45:40.606444 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 19:45:40.645261 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 19:45:40.645703 | 
2025-10-02 19:45:40.648990 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 19:45:40.659154 | 
2025-10-02 19:45:40.659296 | LOOP [upload-logs : Upload console log and json output]