2025-11-21 07:23:20.818546 | Job console starting...
2025-11-21 07:23:20.829129 | Updating repositories
2025-11-21 07:23:20.869132 | Preparing job workspace
2025-11-21 07:23:33.018959 | Running Ansible setup...
2025-11-21 07:23:38.391126 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-21 07:23:39.015297 | 
2025-11-21 07:23:39.015417 | PLAY [localhost]
2025-11-21 07:23:39.027025 | 
2025-11-21 07:23:39.027110 | TASK [Gathering Facts]
2025-11-21 07:23:40.078654 | localhost | ok
2025-11-21 07:23:40.092406 | 
2025-11-21 07:23:40.092486 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-21 07:23:40.505610 | localhost -> localhost | changed
2025-11-21 07:23:40.511133 | 
2025-11-21 07:23:40.511201 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-21 07:23:41.482391 | localhost -> localhost | changed
2025-11-21 07:23:41.491109 | 
2025-11-21 07:23:41.491176 | TASK [Setup log path fact]
2025-11-21 07:23:41.510064 | localhost | ok
2025-11-21 07:23:41.521708 | 
2025-11-21 07:23:41.521788 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-21 07:23:41.548396 | localhost | ok
2025-11-21 07:23:41.557020 | 
2025-11-21 07:23:41.557085 | TASK [emit-job-header : Print job information]
2025-11-21 07:23:41.585476 | # Job Information
2025-11-21 07:23:41.585628 | Ansible Version: 2.15.12
2025-11-21 07:23:41.585664 | Job: cifmw-molecule-ci_nmstate
2025-11-21 07:23:41.585730 | Pipeline: github-check
2025-11-21 07:23:41.585759 | Executor: ze04.softwarefactory-project.io
2025-11-21 07:23:41.585784 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2025-11-21 07:23:41.585812 | Log URL (when completed): https://logserver.rdoproject.org/dde/rdoproject.org/ddeb168632cb4c05a5c5838fd7ef690b/
2025-11-21 07:23:41.585837 | Event ID: aa2448d0-c6aa-11f0-81ca-65f0647a9628
2025-11-21 07:23:41.591958 | 
2025-11-21 07:23:41.592045 | LOOP [emit-job-header : Print node information]
2025-11-21 07:23:41.693872 | localhost | ok:
2025-11-21 07:23:41.694037 | localhost | # Node Information
2025-11-21 07:23:41.694065 | localhost | Inventory Hostname: controller
2025-11-21 07:23:41.694090 | localhost | Hostname: np0005530462
2025-11-21 07:23:41.694111 | localhost | Username: zuul
2025-11-21 07:23:41.694131 | localhost | Distro: CentOS 9
2025-11-21 07:23:41.694149 | localhost | Provider: vexxhost-nodepool-tripleo
2025-11-21 07:23:41.694168 | localhost | Region: RegionOne
2025-11-21 07:23:41.694186 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-11-21 07:23:41.694203 | localhost | Product Name: OpenStack Nova
2025-11-21 07:23:41.694220 | localhost | Interface IP: 38.102.83.213
2025-11-21 07:23:41.719984 | 
2025-11-21 07:23:41.720108 | PLAY [all]
2025-11-21 07:23:41.727042 | 
2025-11-21 07:23:41.727107 | TASK [Gather network facts]
2025-11-21 07:23:42.301469 | controller | ok
2025-11-21 07:23:42.326989 | 
2025-11-21 07:23:42.327064 | TASK [include_role : start-zuul-console]
2025-11-21 07:23:42.349647 | controller | ok
2025-11-21 07:23:42.366811 | 
2025-11-21 07:23:42.366931 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-21 07:23:42.963256 | controller | ok
2025-11-21 07:23:42.972449 | 
2025-11-21 07:23:42.972509 | TASK [include_role : add-build-sshkey]
2025-11-21 07:23:42.990737 | controller | ok
2025-11-21 07:23:43.004833 | 
2025-11-21 07:23:43.004907 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-21 07:23:43.279869 | controller -> localhost | ok
2025-11-21 07:23:43.285829 | 
2025-11-21 07:23:43.285895 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-21 07:23:43.319196 | controller | ok
2025-11-21 07:23:43.332269 | controller | included: /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-21 07:23:43.338182 | 
2025-11-21 07:23:43.338242 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-21 07:23:43.995495 | controller -> localhost | Generating public/private rsa key pair.
2025-11-21 07:23:43.995856 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/ddeb168632cb4c05a5c5838fd7ef690b_id_rsa.
2025-11-21 07:23:43.995903 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/ddeb168632cb4c05a5c5838fd7ef690b_id_rsa.pub.
2025-11-21 07:23:43.995933 | controller -> localhost | The key fingerprint is:
2025-11-21 07:23:43.995960 | controller -> localhost | SHA256:PFBfkywaRmE++ZLrg4JNscFPZeD/FMcemQcmqv1ob00 zuul-build-sshkey
2025-11-21 07:23:43.995986 | controller -> localhost | The key's randomart image is:
2025-11-21 07:23:43.996011 | controller -> localhost | +---[RSA 3072]----+
2025-11-21 07:23:43.996035 | controller -> localhost | |      .o=...=.   |
2025-11-21 07:23:43.996061 | controller -> localhost | |     . +=+.=o=   |
2025-11-21 07:23:43.996084 | controller -> localhost | |   .  o+=oo.* .  |
2025-11-21 07:23:43.996109 | controller -> localhost | |    + .*.+ + o   |
2025-11-21 07:23:43.996132 | controller -> localhost | |     *. S o .    |
2025-11-21 07:23:43.996155 | controller -> localhost | |    o .  O  E    |
2025-11-21 07:23:43.996178 | controller -> localhost | |   +   .+ oo     |
2025-11-21 07:23:43.996204 | controller -> localhost | |  . o .o... .    |
2025-11-21 07:23:43.996228 | controller -> localhost | |     .  .o.      |
2025-11-21 07:23:43.996250 | controller -> localhost | +----[SHA256]-----+
2025-11-21 07:23:43.996310 | controller -> localhost | ok: Runtime: 0:00:00.224113
2025-11-21 07:23:44.004532 | 
2025-11-21 07:23:44.004619 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-21 07:23:44.045847 | controller | ok
2025-11-21 07:23:44.062705 | controller | included: /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-21 07:23:44.070924 | 
2025-11-21 07:23:44.070991 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-21 07:23:44.095412 | controller | skipping: Conditional result was False
2025-11-21 07:23:44.101125 | 
2025-11-21 07:23:44.101190 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-21 07:23:44.754636 | controller | changed
2025-11-21 07:23:44.763611 | 
2025-11-21 07:23:44.763767 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-21 07:23:45.138186 | controller | ok
2025-11-21 07:23:45.144100 | 
2025-11-21 07:23:45.144162 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-21 07:23:47.117994 | controller | changed
2025-11-21 07:23:47.131255 | 
2025-11-21 07:23:47.131432 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-21 07:23:48.834751 | controller | changed
2025-11-21 07:23:48.844970 | 
2025-11-21 07:23:48.845044 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-21 07:23:48.869207 | controller | skipping: Conditional result was False
2025-11-21 07:23:48.875280 | 
2025-11-21 07:23:48.875352 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-21 07:23:49.237987 | controller -> localhost | changed
2025-11-21 07:23:49.248549 | 
2025-11-21 07:23:49.248632 | TASK [add-build-sshkey : Add back temp key]
2025-11-21 07:23:49.535921 | controller -> localhost | Identity added: /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/ddeb168632cb4c05a5c5838fd7ef690b_id_rsa (zuul-build-sshkey)
2025-11-21 07:23:49.536296 | controller -> localhost | ok: Runtime: 0:00:00.009223
2025-11-21 07:23:49.549625 | 
2025-11-21 07:23:49.549798 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-21 07:23:50.084081 | controller | ok
2025-11-21 07:23:50.088919 | 
2025-11-21 07:23:50.088984 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-21 07:23:50.113033 | controller | skipping: Conditional result was False
2025-11-21 07:23:50.123194 | 
2025-11-21 07:23:50.123260 | TASK [include_role : validate-host]
2025-11-21 07:23:50.174977 | controller | ok
2025-11-21 07:23:50.193594 | 
2025-11-21 07:23:50.193673 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-21 07:23:50.221983 | controller | ok
2025-11-21 07:23:50.230497 | 
2025-11-21 07:23:50.230579 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-21 07:23:50.521427 | controller -> localhost | ok
2025-11-21 07:23:50.528044 | 
2025-11-21 07:23:50.528150 | TASK [validate-host : Collect information about the host]
2025-11-21 07:23:51.497118 | controller | ok
2025-11-21 07:23:51.510175 | 
2025-11-21 07:23:51.510317 | TASK [validate-host : Sanitize hostname]
2025-11-21 07:23:51.563718 | controller | ok
2025-11-21 07:23:51.576327 | 
2025-11-21 07:23:51.576422 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-21 07:23:52.014508 | controller -> localhost | changed
2025-11-21 07:23:52.019930 | 
2025-11-21 07:23:52.019991 | TASK [validate-host : Collect information about zuul worker]
2025-11-21 07:23:52.578497 | controller | ok
2025-11-21 07:23:52.589484 | 
2025-11-21 07:23:52.589578 | TASK [validate-host : Write out all zuul information for each host]
2025-11-21 07:23:53.036991 | controller -> localhost | changed
2025-11-21 07:23:53.046282 | 
2025-11-21 07:23:53.046351 | TASK [include_role : prepare-workspace-openshift]
2025-11-21 07:23:53.062935 | controller | skipping: Conditional result was False
2025-11-21 07:23:53.068317 | 
2025-11-21 07:23:53.068380 | TASK [include_role : remove-zuul-sshkey]
2025-11-21 07:23:53.083067 | controller | skipping: Conditional result was False
2025-11-21 07:23:53.089016 | 
2025-11-21 07:23:53.089082 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-21 07:23:53.431504 | controller | ok: "logs"
2025-11-21 07:23:53.431780 | controller | ok: All items complete
2025-11-21 07:23:53.431809 | 
2025-11-21 07:23:53.817953 | controller | ok: "artifacts"
2025-11-21 07:23:54.153310 | controller | ok: "docs"
2025-11-21 07:23:54.169592 | 
2025-11-21 07:23:54.169731 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-21 07:23:54.554251 | controller | changed: "logs"
2025-11-21 07:23:54.889616 | controller | changed: "artifacts"
2025-11-21 07:23:55.264033 | controller | changed: "docs"
2025-11-21 07:23:55.326964 | 
2025-11-21 07:23:55.327053 | PLAY RECAP
2025-11-21 07:23:55.327095 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-21 07:23:55.327122 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-21 07:23:55.327140 | 
2025-11-21 07:23:55.503535 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-21 07:23:55.504317 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-21 07:23:56.260564 | 
2025-11-21 07:23:56.260703 | PLAY [all]
2025-11-21 07:23:56.282451 | 
2025-11-21 07:23:56.282542 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-21 07:23:56.342272 | controller | ok
2025-11-21 07:23:56.350482 | 
2025-11-21 07:23:56.350568 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-21 07:23:56.888575 | controller | changed
2025-11-21 07:23:56.905371 | 
2025-11-21 07:23:56.905535 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-21 07:23:58.825501 | controller | changed
2025-11-21 07:23:58.843816 | 
2025-11-21 07:23:58.843916 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-21 07:23:59.410458 | controller | changed:
2025-11-21 07:23:59.410673 | controller | {
2025-11-21 07:23:59.410743 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-21 07:23:59.410782 | controller | }
2025-11-21 07:23:59.864049 | controller | changed:
2025-11-21 07:23:59.864134 | controller | {
2025-11-21 07:23:59.864162 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-21 07:23:59.864185 | controller | }
2025-11-21 07:24:00.248764 | controller | changed:
2025-11-21 07:24:00.248921 | controller | {
2025-11-21 07:24:00.248973 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-21 07:24:00.249015 | controller | }
2025-11-21 07:24:00.626136 | controller | changed:
2025-11-21 07:24:00.626237 | controller | {
2025-11-21 07:24:00.626260 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-21 07:24:00.626279 | controller | }
2025-11-21 07:24:01.113375 | controller | changed:
2025-11-21 07:24:01.113556 | controller | {
2025-11-21 07:24:01.113632 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-21 07:24:01.113752 | controller | }
2025-11-21 07:24:01.492599 | controller | changed:
2025-11-21 07:24:01.492829 | controller | {
2025-11-21 07:24:01.492892 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-21 07:24:01.492936 | controller | }
2025-11-21 07:24:01.896263 | controller | changed:
2025-11-21 07:24:01.896408 | controller | {
2025-11-21 07:24:01.896522 | 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-11-21 07:24:01.896572 | controller | }
2025-11-21 07:24:02.321855 | controller | changed:
2025-11-21 07:24:02.322013 | controller | {
2025-11-21 07:24:02.322067 | 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-11-21 07:24:02.322133 | controller | }
2025-11-21 07:24:02.633799 | controller | changed:
2025-11-21 07:24:02.633941 | controller | {
2025-11-21 07:24:02.633982 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-21 07:24:02.634016 | controller | }
2025-11-21 07:24:03.001188 | controller | changed:
2025-11-21 07:24:03.001338 | controller | {
2025-11-21 07:24:03.001398 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-21 07:24:03.001594 | controller | }
2025-11-21 07:24:03.436892 | controller | changed:
2025-11-21 07:24:03.436968 | controller | {
2025-11-21 07:24:03.436990 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-21 07:24:03.437008 | controller | }
2025-11-21 07:24:03.920807 | controller | changed:
2025-11-21 07:24:03.921134 | controller | {
2025-11-21 07:24:03.921222 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-21 07:24:03.921269 | controller | }
2025-11-21 07:24:04.336534 | controller | changed:
2025-11-21 07:24:04.336743 | controller | {
2025-11-21 07:24:04.336805 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-21 07:24:04.336850 | controller | }
2025-11-21 07:24:04.730958 | controller | changed:
2025-11-21 07:24:04.731070 | controller | {
2025-11-21 07:24:04.731102 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-21 07:24:04.731127 | controller | }
2025-11-21 07:24:05.140700 | controller | changed:
2025-11-21 07:24:05.140779 | controller | {
2025-11-21 07:24:05.140802 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-21 07:24:05.140820 | controller | }
2025-11-21 07:24:05.541597 | controller | changed:
2025-11-21 07:24:05.541784 | controller | {
2025-11-21 07:24:05.541838 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-21 07:24:05.541877 | controller | }
2025-11-21 07:24:05.924247 | controller | changed:
2025-11-21 07:24:05.924353 | controller | {
2025-11-21 07:24:05.924376 | 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-11-21 07:24:05.924395 | controller | }
2025-11-21 07:24:06.357931 | controller | changed:
2025-11-21 07:24:06.358037 | controller | {
2025-11-21 07:24:06.358061 | 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-11-21 07:24:06.358080 | controller | }
2025-11-21 07:24:06.792785 | controller | changed:
2025-11-21 07:24:06.792900 | controller | {
2025-11-21 07:24:06.792931 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-21 07:24:06.792954 | controller | }
2025-11-21 07:24:07.234175 | controller | changed:
2025-11-21 07:24:07.234277 | controller | {
2025-11-21 07:24:07.234300 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-21 07:24:07.234319 | controller | }
2025-11-21 07:24:07.662862 | controller | changed:
2025-11-21 07:24:07.662965 | controller | {
2025-11-21 07:24:07.662989 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-21 07:24:07.663008 | controller | }
2025-11-21 07:24:08.075977 | controller | changed:
2025-11-21 07:24:08.076079 | controller | {
2025-11-21 07:24:08.076102 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-21 07:24:08.076120 | controller | }
2025-11-21 07:24:08.489630 | controller | changed:
2025-11-21 07:24:08.489762 | controller | {
2025-11-21 07:24:08.489789 | 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-11-21 07:24:08.489808 | controller | }
2025-11-21 07:24:08.889084 | controller | changed:
2025-11-21 07:24:08.889231 | controller | {
2025-11-21 07:24:08.889257 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-21 07:24:08.889276 | controller | }
2025-11-21 07:24:09.305749 | controller | changed:
2025-11-21 07:24:09.305848 | controller | {
2025-11-21 07:24:09.305871 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-21 07:24:09.305889 | controller | }
2025-11-21 07:24:09.704005 | controller | changed:
2025-11-21 07:24:09.704101 | controller | {
2025-11-21 07:24:09.704124 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-21 07:24:09.704141 | controller | }
2025-11-21 07:24:09.723965 | 
2025-11-21 07:24:09.724030 | TASK [Set timezone to UTC]
2025-11-21 07:24:11.515554 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-21 07:24:11.529227 | 
2025-11-21 07:24:11.529374 | TASK [Create nodepool directory]
2025-11-21 07:24:11.988159 | controller | changed
2025-11-21 07:24:12.009952 | 
2025-11-21 07:24:12.010068 | TASK [Create nodepool sub_nodes file]
2025-11-21 07:24:13.715858 | controller | changed
2025-11-21 07:24:13.729561 | 
2025-11-21 07:24:13.729741 | TASK [Create nodepool sub_nodes_private file]
2025-11-21 07:24:15.266562 | controller | changed
2025-11-21 07:24:15.278670 | 
2025-11-21 07:24:15.278841 | LOOP [Populate nodepool sub_nodes file]
2025-11-21 07:24:15.330668 | 
2025-11-21 07:24:15.330990 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-21 07:24:15.390207 | 
2025-11-21 07:24:15.390486 | TASK [Create nodepool primary file]
2025-11-21 07:24:15.428319 | controller | skipping: Conditional result was False
2025-11-21 07:24:15.441567 | 
2025-11-21 07:24:15.441762 | TASK [Create nodepool node_private for this node]
2025-11-21 07:24:17.154996 | controller | changed
2025-11-21 07:24:17.163938 | 
2025-11-21 07:24:17.164033 | LOOP [Copy ssh keys to nodepool directory]
2025-11-21 07:24:17.687645 | controller | ok: Item: id_rsa Runtime: 0:00:00.009130
2025-11-21 07:24:17.688106 | 
2025-11-21 07:24:18.020182 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006594
2025-11-21 07:24:18.043114 | 
2025-11-21 07:24:18.043300 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-21 07:24:19.542566 | controller | changed
2025-11-21 07:24:19.549937 | 
2025-11-21 07:24:19.550022 | TASK [Validate sudoers config after edits]
2025-11-21 07:24:19.972184 | controller | /etc/sudoers: parsed OK
2025-11-21 07:24:19.972311 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-21 07:24:19.972327 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-21 07:24:19.972336 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-21 07:24:20.090170 | controller | ok: Runtime: 0:00:00.010465
2025-11-21 07:24:20.104300 | 
2025-11-21 07:24:20.104479 | TASK [Show the environment passed in to job shell scripts]
2025-11-21 07:24:20.589577 | controller | SHELL=/bin/bash
2025-11-21 07:24:20.589656 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-21 07:24:20.589669 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-21 07:24:20.589680 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/51/3451/d8ff45fa1fdb6f23dba01e400ef34b2cbca8927f
2025-11-21 07:24:20.589689 | controller | PWD=/home/zuul
2025-11-21 07:24:20.589697 | controller | ZUUL_PIPELINE=github-check
2025-11-21 07:24:20.589704 | controller | LOGNAME=zuul
2025-11-21 07:24:20.589712 | controller | XDG_SESSION_TYPE=tty
2025-11-21 07:24:20.589720 | controller | _=/usr/bin/env
2025-11-21 07:24:20.589727 | controller | MOTD_SHOWN=pam
2025-11-21 07:24:20.589735 | controller | HOME=/home/zuul
2025-11-21 07:24:20.589742 | controller | LANG=en_US.UTF-8
2025-11-21 07:24:20.589750 | controller | SSH_CONNECTION=38.102.83.114 38186 38.102.83.213 22
2025-11-21 07:24:20.589758 | 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-11-21 07:24:20.589793 | controller | ZUUL_CHANGE_IDS=3451,d8ff45fa1fdb6f23dba01e400ef34b2cbca8927f
2025-11-21 07:24:20.589802 | controller | WORKSPACE=/home/zuul/workspace
2025-11-21 07:24:20.589811 | controller | XDG_SESSION_CLASS=user
2025-11-21 07:24:20.589818 | controller | SELINUX_ROLE_REQUESTED=
2025-11-21 07:24:20.589826 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-21 07:24:20.589834 | controller | USER=zuul
2025-11-21 07:24:20.589842 | controller | ZUUL_VOTING=True
2025-11-21 07:24:20.589849 | controller | BUILD_TIMEOUT=1800000
2025-11-21 07:24:20.589857 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-21 07:24:20.589864 | controller | SHLVL=1
2025-11-21 07:24:20.589872 | controller | ZUUL_PATCHSET=d8ff45fa1fdb6f23dba01e400ef34b2cbca8927f
2025-11-21 07:24:20.589880 | controller | XDG_SESSION_ID=1
2025-11-21 07:24:20.589887 | controller | ZUUL_BRANCH=main
2025-11-21 07:24:20.589895 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-21 07:24:20.589902 | controller | SSH_CLIENT=38.102.83.114 38186 22
2025-11-21 07:24:20.589911 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-21 07:24:20.590001 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-21 07:24:20.590014 | controller | which_declare=declare -f
2025-11-21 07:24:20.590023 | 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-11-21 07:24:20.590031 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-21 07:24:20.590039 | controller | ZUUL_CHANGE=3451
2025-11-21 07:24:20.590046 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-21 07:24:20.590054 | controller | ZUUL_UUID=ddeb168632cb4c05a5c5838fd7ef690b
2025-11-21 07:24:20.590061 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-21 07:24:20.590069 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-21 07:24:20.590077 | controller | }
2025-11-21 07:24:20.724170 | controller | ok: Runtime: 0:00:00.012995
2025-11-21 07:24:20.731859 | 
2025-11-21 07:24:20.731947 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-21 07:24:20.746169 | controller | skipping: Conditional result was False
2025-11-21 07:24:20.752528 | 
2025-11-21 07:24:20.752612 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-21 07:24:21.283346 | controller | skipping: Conditional result was False
2025-11-21 07:24:21.296043 | 
2025-11-21 07:24:21.296181 | TASK [Ensure legacy workspace directory]
2025-11-21 07:24:21.661673 | controller | changed
2025-11-21 07:24:21.700172 | 
2025-11-21 07:24:21.700289 | PLAY RECAP
2025-11-21 07:24:21.700351 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-21 07:24:21.700379 | 
2025-11-21 07:24:21.799817 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-21 07:24:21.800637 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-11-21 07:24:22.414452 | 
2025-11-21 07:24:22.414557 | PLAY [all,!compute]
2025-11-21 07:24:22.434729 | 
2025-11-21 07:24:22.434821 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-11-21 07:24:22.963846 | controller | skipping: Conditional result was False
2025-11-21 07:24:22.976270 | 
2025-11-21 07:24:22.976435 | TASK [Install podman]
2025-11-21 07:24:23.035021 | controller | skipping: Conditional result was False
2025-11-21 07:24:23.047481 | 
2025-11-21 07:24:23.047722 | TASK [Set var name for quay login zuul secret]
2025-11-21 07:24:23.084668 | controller | skipping: Conditional result was False
2025-11-21 07:24:23.098074 | 
2025-11-21 07:24:23.098284 | TASK [Print the username]
2025-11-21 07:24:23.653119 | controller | skipping: Conditional result was False
2025-11-21 07:24:23.665724 | 
2025-11-21 07:24:23.665870 | TASK [Fail when user or password is not set]
2025-11-21 07:24:23.734275 | controller | skipping: Conditional result was False
2025-11-21 07:24:23.747945 | 
2025-11-21 07:24:23.748094 | TASK [Set vars for quay login]
2025-11-21 07:24:23.828501 | 
2025-11-21 07:24:23.828816 | LOOP [Perform container registry login(s) with podman]
2025-11-21 07:24:23.894452 | 
2025-11-21 07:24:23.894644 | LOOP [Perform container registry login(s) with buildah]
2025-11-21 07:24:23.971273 | 
2025-11-21 07:24:23.971479 | TASK [Set Insecure registry for content provider]
2025-11-21 07:24:24.026825 | controller | skipping: Conditional result was False
2025-11-21 07:24:24.057470 | 
2025-11-21 07:24:24.057554 | PLAY RECAP
2025-11-21 07:24:24.057602 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-11-21 07:24:24.057626 | 
2025-11-21 07:24:24.156296 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-11-21 07:24:24.157161 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-11-21 07:24:24.772557 | 
2025-11-21 07:24:24.772666 | PLAY [Prefer ipv4 over ipv6]
2025-11-21 07:24:24.793479 | 
2025-11-21 07:24:24.793572 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-11-21 07:24:24.848221 | controller | skipping: Conditional result was False
2025-11-21 07:24:24.869444 | 
2025-11-21 07:24:24.869518 | PLAY [controller]
2025-11-21 07:24:24.883875 | 
2025-11-21 07:24:24.883990 | TASK [Fail when openshift_pull_sec not provided]
2025-11-21 07:24:24.907519 | controller | skipping: Conditional result was False
2025-11-21 07:24:24.914501 | 
2025-11-21 07:24:24.914591 | TASK [Clone repos in the job workspace]
2025-11-21 07:24:24.939932 | controller | ok
2025-11-21 07:24:24.969770 | 
2025-11-21 07:24:24.969877 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-21 07:24:25.446890 | controller | ok
2025-11-21 07:24:25.455073 | 
2025-11-21 07:24:25.455148 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-21 07:24:29.500911 | controller | Output suppressed because no_log was given
2025-11-21 07:24:29.514626 | 
2025-11-21 07:24:29.514717 | TASK [Create pull-secret.txt file]
2025-11-21 07:24:31.168142 | controller | Output suppressed because no_log was given
2025-11-21 07:24:31.174553 | 
2025-11-21 07:24:31.174795 | TASK [Apply CRC features and print image build date]
2025-11-21 07:24:31.216947 | controller | ok
2025-11-21 07:24:31.259133 | controller | included: /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-11-21 07:24:31.267606 | 
2025-11-21 07:24:31.267667 | TASK [Set additional parameters for crc - telemetry]
2025-11-21 07:24:31.804802 | controller | skipping: Conditional result was False
2025-11-21 07:24:31.813059 | 
2025-11-21 07:24:31.813141 | TASK [Set additional parameters for crc - monitoring]
2025-11-21 07:24:32.347018 | controller | skipping: Conditional result was False
2025-11-21 07:24:32.354875 | 
2025-11-21 07:24:32.354967 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-11-21 07:24:32.902952 | controller | skipping: Conditional result was False
2025-11-21 07:24:32.917526 | 
2025-11-21 07:24:32.917728 | TASK [Set additional parameters for crc - enable microshift preset]
2025-11-21 07:24:33.485749 | controller | skipping: Conditional result was False
2025-11-21 07:24:33.493863 | 
2025-11-21 07:24:33.493958 | TASK [Set additional parameters for crc - enable okd preset]
2025-11-21 07:24:34.037662 | controller | skipping: Conditional result was False
2025-11-21 07:24:34.043651 | 
2025-11-21 07:24:34.043729 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-11-21 07:24:34.574191 | controller | skipping: Conditional result was False
2025-11-21 07:24:34.604337 | 
2025-11-21 07:24:34.604503 | TASK [Execute crc start command]
2025-11-21 07:27:46.897176 | controller | ok: Runtime: 0:03:11.636540
2025-11-21 07:27:46.902891 | 
2025-11-21 07:27:46.902951 | TASK [Verify that OpenShift is up and running]
2025-11-21 07:27:46.922388 | controller | ok
2025-11-21 07:27:46.931466 | controller | included: /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-11-21 07:27:46.937972 | 
2025-11-21 07:27:46.938031 | TASK [Create script to login and verify services]
2025-11-21 07:27:48.312116 | controller | changed
2025-11-21 07:27:48.319570 | 
2025-11-21 07:27:48.319652 | TASK [Ensure that user is logged]
2025-11-21 07:27:48.748263 | controller | Checking login to the cluster 1...
2025-11-21 07:27:50.013141 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:27:50.070156 | controller |
2025-11-21 07:27:50.070240 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:27:50.077335 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:27:55.080087 | controller | Checking login to the cluster 2...
2025-11-21 07:27:55.241329 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:27:55.269305 | controller |
2025-11-21 07:27:55.269370 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:27:55.278644 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:00.281655 | controller | Checking login to the cluster 3...
2025-11-21 07:28:00.411797 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:00.431270 | controller |
2025-11-21 07:28:00.431366 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:00.440871 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:05.442912 | controller | Checking login to the cluster 4...
2025-11-21 07:28:05.570511 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:05.592450 | controller |
2025-11-21 07:28:05.592513 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:05.597119 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:10.599834 | controller | Checking login to the cluster 5...
2025-11-21 07:28:10.753324 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:10.772867 | controller |
2025-11-21 07:28:10.772939 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:10.777987 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:15.781036 | controller | Checking login to the cluster 6...
2025-11-21 07:28:15.957830 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:15.978283 | controller |
2025-11-21 07:28:15.978358 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:15.983666 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:20.986077 | controller | Checking login to the cluster 7...
2025-11-21 07:28:21.123901 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:21.142748 | controller |
2025-11-21 07:28:21.142851 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:21.147819 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:26.150126 | controller | Checking login to the cluster 8...
2025-11-21 07:28:26.269041 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:26.287393 | controller |
2025-11-21 07:28:26.287457 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:26.298187 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:31.300559 | controller | Checking login to the cluster 9...
2025-11-21 07:28:31.433633 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:31.459827 | controller |
2025-11-21 07:28:31.459909 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:31.470866 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:36.473693 | controller | Checking login to the cluster 10...
2025-11-21 07:28:36.590478 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:36.608581 | controller |
2025-11-21 07:28:36.608667 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:36.614161 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:41.616426 | controller | Checking login to the cluster 11...
2025-11-21 07:28:41.745135 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:41.762284 | controller |
2025-11-21 07:28:41.762364 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:28:41.767040 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:46.769982 | controller | Checking login to the cluster 12...
2025-11-21 07:28:46.888349 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:46.948065 | controller |
2025-11-21 07:28:46.948148 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:28:46.953161 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:51.956032 | controller | Checking login to the cluster 13...
2025-11-21 07:28:52.090973 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:52.132629 | controller |
2025-11-21 07:28:52.132712 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:28:52.137093 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:28:57.139052 | controller | Checking login to the cluster 14...
2025-11-21 07:28:57.265004 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:28:57.325305 | controller |
2025-11-21 07:28:57.325390 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:28:57.331822 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:02.335261 | controller | Checking login to the cluster 15...
2025-11-21 07:29:02.501240 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:02.590528 | controller |
2025-11-21 07:29:02.590633 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:29:02.602124 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:07.607577 | controller | Checking login to the cluster 16...
2025-11-21 07:29:07.762847 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:07.851377 | controller |
2025-11-21 07:29:07.851517 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:29:07.858959 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:12.861510 | controller | Checking login to the cluster 17...
2025-11-21 07:29:13.035981 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:13.099802 | controller |
2025-11-21 07:29:13.099896 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:29:13.104293 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:18.112884 | controller | Checking login to the cluster 18...
2025-11-21 07:29:18.256649 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:18.355389 | controller |
2025-11-21 07:29:18.355459 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:29:18.363118 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:23.366426 | controller | Checking login to the cluster 19...
2025-11-21 07:29:23.586643 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:24.225255 | controller |
2025-11-21 07:29:24.225373 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-11-21 07:29:24.235551 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:29.241200 | controller | Checking login to the cluster 20...
2025-11-21 07:29:29.740433 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:30.336422 | controller |
2025-11-21 07:29:30.336486 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:29:30.345396 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:35.348748 | controller | Checking login to the cluster 21...
2025-11-21 07:29:35.690701 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:35.825725 | controller |
2025-11-21 07:29:35.825847 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:29:35.838759 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:40.845141 | controller | Checking login to the cluster 22...
2025-11-21 07:29:41.241113 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:42.905395 | controller |
2025-11-21 07:29:42.905547 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:29:42.911750 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:47.915040 | controller | Checking login to the cluster 23...
2025-11-21 07:29:48.109006 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:48.164310 | controller |
2025-11-21 07:29:48.164428 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:29:48.170136 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:53.173274 | controller | Checking login to the cluster 24...
2025-11-21 07:29:53.393914 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:29:53.467299 | controller |
2025-11-21 07:29:53.467381 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:29:53.472939 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:29:58.476810 | controller | Checking login to the cluster 25...
2025-11-21 07:29:59.756810 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:02.325628 | controller |
2025-11-21 07:30:02.325930 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:02.352795 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:07.361172 | controller | Checking login to the cluster 26...
2025-11-21 07:30:07.760588 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:08.198310 | controller |
2025-11-21 07:30:08.198426 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:08.210664 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:13.215311 | controller | Checking login to the cluster 27...
2025-11-21 07:30:13.456500 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:13.557193 | controller |
2025-11-21 07:30:13.557272 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:13.567713 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:18.570415 | controller | Checking login to the cluster 28...
2025-11-21 07:30:19.726137 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:20.140845 | controller |
2025-11-21 07:30:20.140961 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:20.149579 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:25.161446 | controller | Checking login to the cluster 29...
2025-11-21 07:30:25.314013 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:25.366610 | controller |
2025-11-21 07:30:25.366690 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:25.372614 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:30.377304 | controller | Checking login to the cluster 30...
2025-11-21 07:30:30.572080 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:30.589277 | controller |
2025-11-21 07:30:30.589466 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:30.598098 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:35.601113 | controller | Checking login to the cluster 31...
2025-11-21 07:30:35.790300 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:35.831351 | controller |
2025-11-21 07:30:35.831417 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:35.838195 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:40.842217 | controller | Checking login to the cluster 32...
2025-11-21 07:30:41.030387 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:41.077312 | controller |
2025-11-21 07:30:41.077398 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:41.083329 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:46.088800 | controller | Checking login to the cluster 33...
2025-11-21 07:30:46.240439 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:46.264527 | controller |
2025-11-21 07:30:46.264615 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:46.272424 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:51.277877 | controller | Checking login to the cluster 34...
2025-11-21 07:30:51.500199 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:30:51.960639 | controller |
2025-11-21 07:30:51.960858 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-11-21 07:30:51.971123 | controller | Can not login to the CRC cluster. Sleeping...
2025-11-21 07:30:56.978163 | controller | Checking login to the cluster 35...
2025-11-21 07:30:57.146860 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-11-21 07:31:10.982071 | controller |
2025-11-21 07:31:10.982210 | controller | Login successful.
2025-11-21 07:31:11.068646 | controller |
2025-11-21 07:31:11.068733 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-11-21 07:31:11.088487 | controller |
2025-11-21 07:31:11.088548 | controller | Using project "default".
2025-11-21 07:31:11.172495 | controller | changed
2025-11-21 07:31:11.187085 | 
2025-11-21 07:31:11.187222 | TASK [Delete all openshift-marketplace pods]
2025-11-21 07:31:15.369964 | controller | pod "certified-operators-f8szs" deleted
2025-11-21 07:31:15.548752 | controller | pod "certified-operators-j6bdx" deleted
2025-11-21 07:31:15.568965 | controller | pod "community-operators-5zsqg" deleted
2025-11-21 07:31:15.624459 | controller | pod "community-operators-qf6m9" deleted
2025-11-21 07:31:15.765465 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2025-11-21 07:31:15.799899 | controller | pod "redhat-marketplace-ps6xk" deleted
2025-11-21 07:31:15.964984 | controller | pod "redhat-marketplace-rszj7" deleted
2025-11-21 07:31:16.107233 | controller | pod "redhat-operators-qpjgw" deleted
2025-11-21 07:31:16.225722 | controller | pod "redhat-operators-tqxl9" deleted
2025-11-21 07:31:53.793443 | controller | ok
2025-11-21 07:31:53.805085 | 
2025-11-21 07:31:53.805217 | TASK [Copy script ensure_services_up.sh]
2025-11-21 07:31:53.873830 | controller | skipping: Conditional result was False
2025-11-21 07:31:53.885871 | 
2025-11-21 07:31:53.886004 | TASK [Ensure all pods are up and running]
2025-11-21 07:31:54.437412 | controller | skipping: Conditional result was False
2025-11-21 07:31:54.450333 | 
2025-11-21 07:31:54.450464 | TASK [Check if there is a image build date file]
2025-11-21 07:31:55.172571 | controller | ok
2025-11-21 07:31:55.183986 | 
2025-11-21 07:31:55.184114 | TASK [Print image date if available]
2025-11-21 07:31:55.577433 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2025-11-21 07:31:55.734097 | controller | changed
2025-11-21 07:31:55.745845 | 
2025-11-21 07:31:55.745988 | TASK [Fix machineconfigpool]
2025-11-21 07:31:55.814531 | controller | skipping: Conditional result was False
2025-11-21 07:31:55.827327 | 
2025-11-21 07:31:55.827516 | TASK [Show available nodes]
2025-11-21 07:31:58.284525 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-11-21 07:31:58.287271 | controller | crc    Ready    control-plane,master,worker   271d   v1.31.5
2025-11-21 07:31:58.386434 | controller | ok: Runtime: 0:00:02.037239
2025-11-21 07:31:58.397525 | 
2025-11-21 07:31:58.397653 | TASK [Configure insecure registry inside crc vm]
2025-11-21 07:31:58.435308 | controller | skipping: Conditional result was False
2025-11-21 07:31:58.447319 | 
2025-11-21 07:31:58.447453 | TASK [Catch CRC IP]
2025-11-21 07:31:58.919870 | controller | 192.168.130.11
2025-11-21 07:31:58.997538 | controller | changed
2025-11-21 07:31:59.008994 | 
2025-11-21 07:31:59.009121 | TASK [Check if id_ed25519 key exists]
2025-11-21 07:31:59.394556 | controller | ok
2025-11-21 07:31:59.407848 | 
2025-11-21 07:31:59.407975 | TASK [Set fact if new keypair exists]
2025-11-21 07:31:59.526988 | controller | ok
2025-11-21 07:31:59.537310 | 
2025-11-21 07:31:59.537445 | TASK [Run crc]
2025-11-21 07:32:02.205932 | controller |
2025-11-21 07:32:12.114002 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-11-21 07:32:12.114065 | controller |
2025-11-21 07:32:12.114073 | controller | TASK [Gathering Facts] *********************************************************
2025-11-21 07:32:12.114091 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-11-21 07:32:12.159325 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-11-21 07:32:12.159389 | controller | interpreter could change the meaning of that path. See
2025-11-21 07:32:12.159398 | controller | https://docs.ansible.com/ansible-
2025-11-21 07:32:12.159404 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-11-21 07:32:12.159421 | controller | ok: [192.168.130.11]
2025-11-21 07:32:14.214141 | controller |
2025-11-21 07:32:14.214198 | controller | TASK [Copy pull-secret] ********************************************************
2025-11-21 07:32:14.214217 | controller | changed: [192.168.130.11]
2025-11-21 07:32:15.795192 | controller |
2025-11-21 07:32:15.795251 | controller | TASK [Add crio config] *********************************************************
2025-11-21 07:32:15.795267 | controller | changed: [192.168.130.11]
2025-11-21 07:32:19.991884 | controller |
2025-11-21 07:32:19.991974 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-11-21 07:32:19.992005 | controller | changed: [192.168.130.11]
2025-11-21 07:32:20.058627 | controller |
2025-11-21 07:32:20.058691 | controller | PLAY RECAP *********************************************************************
2025-11-21 07:32:20.058700 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-11-21 07:32:20.058706 | controller |
2025-11-21 07:32:20.103405 | controller | changed
2025-11-21 07:32:20.115089 | 
2025-11-21 07:32:20.115215 | TASK [Set slower etcd profile]
2025-11-21 07:32:20.173968 | controller | ok
2025-11-21 07:32:20.221183 | 
2025-11-21 07:32:20.221321 | TASK [prepare-crc-cloud : Set slower profile]
2025-11-21 07:32:20.757227 | controller | etcd.operator.openshift.io/cluster patched
2025-11-21 07:32:21.261780 | controller | ok
2025-11-21 07:32:21.276147 | 
2025-11-21 07:32:21.276321 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-11-21 07:32:51.327601 | controller | ok
2025-11-21 07:32:51.339578 | 
2025-11-21 07:32:51.339744 | TASK [prepare-crc-cloud : Get pod phase status]
2025-11-21 07:38:21.349284 | controller | ok
2025-11-21 07:38:21.372865 | 
2025-11-21 07:38:21.373001 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-11-21 07:38:21.453907 | controller | ok
2025-11-21 07:38:21.468445 | controller | included: /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-11-21 07:38:21.487030 | 
2025-11-21 07:38:21.487119 | TASK [Set facts for disk limitation]
2025-11-21 07:38:21.521769 | controller | ok
2025-11-21 07:38:21.561534 | 
2025-11-21 07:38:21.561671 | TASK [Print limits for the disk]
2025-11-21 07:38:21.603207 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-11-21 07:38:21.610129 | 
2025-11-21 07:38:21.610213 | TASK [Set limit to crc VM to the vda disk]
2025-11-21 07:38:22.036251 | controller |
2025-11-21 07:38:22.153814 | controller | changed
2025-11-21 07:38:22.166931 | 
2025-11-21 07:38:22.167104 | TASK [Pause machineconfigpool]
2025-11-21 07:38:22.230915 | controller | ok
2025-11-21 07:38:22.282829 | 
2025-11-21 07:38:22.282989 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-11-21 07:38:22.805591 | controller | ok
2025-11-21 07:38:23.260767 | controller | ok
2025-11-21 07:38:23.287572 | 
2025-11-21 07:38:23.287842 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-11-21 07:38:23.889953 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-11-21 07:38:23.901205 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      271d
2025-11-21 07:38:23.901269 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      271d
2025-11-21 07:38:24.349040 | controller | ok
2025-11-21 07:38:24.374617 | 
2025-11-21 07:38:24.374755 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-11-21 07:38:24.374857 | 
2025-11-21 07:38:24.374884 | PLAY RECAP
2025-11-21 07:38:24.374922 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-11-21 07:38:24.374941 | 
2025-11-21 07:38:24.477376 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-11-21 07:38:24.479072 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-21 07:38:25.026614 | 
2025-11-21 07:38:25.026743 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-21 07:38:25.048856 | 
2025-11-21 07:38:25.048944 | TASK [Create zuul-output directory]
2025-11-21 07:38:25.566134 | controller | changed
2025-11-21 07:38:25.571216 | 
2025-11-21 07:38:25.571293 | TASK [Slurp Zuul inventory test]
2025-11-21 07:38:25.966312 | controller -> localhost | ok
2025-11-21 07:38:25.985768 | 
2025-11-21 07:38:25.985990 | TASK [Save zuul inventory]
2025-11-21 07:38:27.658403 | controller | changed
2025-11-21 07:38:27.670560 | 
2025-11-21 07:38:27.670730 | TASK [Save zuul vars without the change_message]
2025-11-21 07:38:28.965294 | controller | changed
2025-11-21 07:38:29.006124 | 
2025-11-21 07:38:29.006226 | PLAY RECAP
2025-11-21 07:38:29.006280 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-21 07:38:29.006310 | 
2025-11-21 07:38:29.110795 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-21 07:38:29.111558 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-21 07:38:29.720652 | 
2025-11-21 07:38:29.720818 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-21 07:38:29.741941 | 
2025-11-21 07:38:29.742043 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-21 07:38:29.762220 | controller | ok
2025-11-21 07:38:29.780527 | 
2025-11-21 07:38:29.780639 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-21 07:38:29.825091 | controller | skipping: Conditional result was False
2025-11-21 07:38:29.831290 | 
2025-11-21 07:38:29.831362 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-21 07:38:30.324542 | controller | ok
2025-11-21 07:38:30.337243 | 
2025-11-21 07:38:30.337392 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-21 07:38:31.713014 | controller | ok
2025-11-21 07:38:31.735328 | 
2025-11-21 07:38:31.735462 | TASK [Prepare workspace]
2025-11-21 07:38:31.764131 | controller | ok
2025-11-21 07:38:31.795526 | 
2025-11-21 07:38:31.795639 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-21 07:38:32.240916 | controller | ok
2025-11-21 07:38:32.254290 | 
2025-11-21 07:38:32.254426 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-21 07:38:33.505598 | controller | Output suppressed because no_log was given
2025-11-21 07:38:33.527069 | 
2025-11-21 07:38:33.527161 | LOOP [Create zuul-output directory]
2025-11-21 07:38:33.882674 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-21 07:38:34.181416 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-21 07:38:34.197279 | 
2025-11-21 07:38:34.197451 | TASK [Install required packages]
2025-11-21 07:38:37.579446 | controller | ok: Nothing to do
2025-11-21 07:38:37.591000 | 
2025-11-21 07:38:37.591134 | TASK [Install venv]
2025-11-21 07:39:49.983948 | controller | changed
2025-11-21 07:39:50.012009 | 
2025-11-21 07:39:50.012085 | PLAY RECAP
2025-11-21 07:39:50.012129 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-21 07:39:50.012150 | 
2025-11-21 07:39:50.114743 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-21 07:39:50.115938 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-21 07:39:50.737561 | 
2025-11-21 07:39:50.737666 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-21 07:39:50.759386 | 
2025-11-21 07:39:50.759470 | TASK [Gather required facts]
2025-11-21 07:39:51.470528 | controller | ok
2025-11-21 07:39:51.484474 | 
2025-11-21 07:39:51.484654 | TASK [Load environment var if instructed to]
2025-11-21 07:39:51.522403 | controller | skipping: Conditional result was False
2025-11-21 07:39:51.535000 | 
2025-11-21 07:39:51.535141 | TASK [Ensure group_vars dir exists]
2025-11-21 07:39:52.054080 | controller | ok
2025-11-21 07:39:52.063411 | 
2025-11-21 07:39:52.063616 | TASK [Print related variables]
2025-11-21 07:39:52.108410 | controller | ok:
2025-11-21 07:39:52.108602 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-21 07:39:52.108637 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate
2025-11-21 07:39:52.115606 | 
2025-11-21 07:39:52.115722 | TASK [Run molecule]
2025-11-21 07:39:53.415168 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-21 07:39:53.530545 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-21 07:40:11.737920 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.738978 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.740113 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.741266 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.742387 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+0ebbea52 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0+0ebbea52 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.743495 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.744708 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.745817 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.746878 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.747922 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.749202 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.750349 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.751506 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.752952 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.754094 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-21 07:40:11.776285 | controller | INFO     Running default > prepare
2025-11-21 07:40:12.548174 | controller |
2025-11-21 07:40:12.548250 | controller | PLAY [Prepare] *****************************************************************
2025-11-21 07:40:12.548359 | controller |
2025-11-21 07:40:12.548489 | controller | TASK [Gathering Facts] *********************************************************
2025-11-21 07:40:12.548624 | controller | Friday 21 November 2025  07:40:12 +0000 (0:00:00.013)       0:00:00.013 *******
2025-11-21 07:40:13.582415 | controller | ok: [instance]
2025-11-21 07:40:13.582489 | controller |
2025-11-21 07:40:13.582557 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-21 07:40:13.582680 | controller | Friday 21 November 2025  07:40:13 +0000 (0:00:01.034)       0:00:01.048 *******
2025-11-21 07:40:13.605649 | controller | skipping: [instance]
2025-11-21 07:40:13.605722 | controller |
2025-11-21 07:40:13.606019 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-21 07:40:13.606250 | controller | Friday 21 November 2025  07:40:13 +0000 (0:00:00.023)       0:00:01.071 *******
2025-11-21 07:40:13.674845 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-21 07:40:13.674908 | controller |
2025-11-21 07:40:13.675086 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-21 07:40:13.675239 | controller | Friday 21 November 2025  07:40:13 +0000 (0:00:00.069)       0:00:01.140 *******
2025-11-21 07:40:14.027598 | controller | ok: [instance]
2025-11-21 07:40:14.027666 | controller |
2025-11-21 07:40:14.027796 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-21 07:40:14.027962 | controller | Friday 21 November 2025  07:40:14 +0000 (0:00:00.352)       0:00:01.492 *******
2025-11-21 07:40:14.443239 | controller | ok: [instance]
2025-11-21 07:40:14.443459 | controller |
2025-11-21 07:40:14.443587 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-21 07:40:14.443813 | controller | Friday 21 November 2025  07:40:14 +0000 (0:00:00.415)       0:00:01.908 *******
2025-11-21 07:40:15.359706 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-21 07:40:15.359923 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-21 07:40:15.365856 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-21 07:40:15.432326 | controller |
2025-11-21 07:40:15.432409 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-21 07:40:15.432421 | controller | Friday 21 November 2025  07:40:15 +0000 (0:00:00.916)       0:00:02.824 *******
2025-11-21 07:40:15.432444 | controller |
2025-11-21 07:40:15.432483 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-21 07:40:15.432497 | controller | Friday 21 November 2025  07:40:15 +0000 (0:00:00.073)       0:00:02.897 *******
2025-11-21 07:40:16.088242 | controller | changed: [instance] => (item=tmp)
2025-11-21 07:40:16.088311 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-21 07:40:16.088406 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-21 07:40:16.088496 | controller |
2025-11-21 07:40:16.088595 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-21 07:40:16.088694 | controller | Friday 21 November 2025  07:40:16 +0000 (0:00:00.655)       0:00:03.553 *******
2025-11-21 07:40:17.551899 | controller | ok: [instance]
2025-11-21 07:40:17.552082 | controller |
2025-11-21 07:40:17.552095 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-21 07:40:18.523477 | controller | Friday 21 November 2025  07:40:17 +0000 (0:00:01.463)       0:00:05.016 *******
2025-11-21 07:40:18.523529 | controller | changed: [instance]
2025-11-21 07:40:18.523676 | controller |
2025-11-21 07:40:18.523702 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-21 07:40:18.523802 | controller | Friday 21 November 2025  07:40:18 +0000 (0:00:00.971)       0:00:05.988 *******
2025-11-21 07:40:26.833860 | controller | changed: [instance]
2025-11-21 07:40:26.834128 | controller |
2025-11-21 07:40:26.834151 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-21 07:40:26.834495 | controller | Friday 21 November 2025  07:40:26 +0000 (0:00:08.309)       0:00:14.298 *******
2025-11-21 07:40:27.755671 | controller | changed: [instance]
2025-11-21 07:40:27.755880 | controller |
2025-11-21 07:40:27.755897 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-21 07:40:27.755927 | controller | Friday 21 November 2025  07:40:27 +0000 (0:00:00.922)       0:00:15.220 *******
2025-11-21 07:40:27.785913 | controller | skipping: [instance]
2025-11-21 07:40:27.786077 | controller |
2025-11-21 07:40:27.786088 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-21 07:40:27.786097 | controller | Friday 21 November 2025  07:40:27 +0000 (0:00:00.029)       0:00:15.250 *******
2025-11-21 07:40:28.511486 | controller | changed: [instance]
2025-11-21 07:40:28.511747 | controller |
2025-11-21 07:40:28.511777 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-21 07:40:28.556664 | controller | Friday 21 November 2025  07:40:28 +0000 (0:00:00.726)       0:00:15.976 *******
2025-11-21 07:40:28.556780 | controller | skipping: [instance]
2025-11-21 07:40:28.599626 | controller |
2025-11-21 07:40:28.599723 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-21 07:40:28.599735 | controller | Friday 21 November 2025  07:40:28 +0000 (0:00:00.044)       0:00:16.021 *******
2025-11-21 07:40:28.599758 | controller | skipping: [instance]
2025-11-21 07:40:28.599833 | controller |
2025-11-21 07:40:28.599849 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-21 07:40:28.600256 | controller | Friday 21 November 2025  07:40:28 +0000 (0:00:00.043)       0:00:16.065 *******
2025-11-21 07:40:28.640378 | controller | skipping: [instance]
2025-11-21 07:40:28.640531 | controller |
2025-11-21 07:40:28.640551 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-21 07:40:29.223914 | controller | Friday 21 November 2025  07:40:28 +0000 (0:00:00.040)       0:00:16.105 *******
2025-11-21 07:40:29.224000 | controller | changed: [instance]
2025-11-21 07:40:29.224126 | controller |
2025-11-21 07:40:29.224143 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-21 07:40:29.224154 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.583)       0:00:16.689 *******
2025-11-21 07:40:29.778015 | controller | changed: [instance]
2025-11-21 07:40:29.778217 | controller |
2025-11-21 07:40:29.778379 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-21 07:40:29.778391 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.554)       0:00:17.243 *******
2025-11-21 07:40:29.818642 | controller | skipping: [instance]
2025-11-21 07:40:29.818696 | controller |
2025-11-21 07:40:29.818836 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-21 07:40:29.818933 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.040)       0:00:17.284 *******
2025-11-21 07:40:29.851560 | controller | skipping: [instance]
2025-11-21 07:40:29.851735 | controller |
2025-11-21 07:40:29.851992 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-21 07:40:29.852201 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.032)       0:00:17.317 *******
2025-11-21 07:40:29.885041 | controller | skipping: [instance]
2025-11-21 07:40:29.885242 | controller |
2025-11-21 07:40:29.885620 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-21 07:40:29.885882 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.033)       0:00:17.350 *******
2025-11-21 07:40:29.934479 | controller | ok: [instance]
2025-11-21 07:40:29.934546 | controller |
2025-11-21 07:40:29.934625 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-21 07:40:29.934723 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.049)       0:00:17.399 *******
2025-11-21 07:40:29.969299 | controller | skipping: [instance]
2025-11-21 07:40:29.969355 | controller |
2025-11-21 07:40:29.969457 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-21 07:40:29.969562 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.034)       0:00:17.434 *******
2025-11-21 07:40:30.002397 | controller | skipping: [instance]
2025-11-21 07:40:30.002463 | controller |
2025-11-21 07:40:30.002633 | controller | TASK [Download the RPM] ********************************************************
2025-11-21 07:40:30.002776 | controller | Friday 21 November 2025  07:40:29 +0000 (0:00:00.033)       0:00:17.467 *******
2025-11-21 07:40:30.044259 | controller | skipping: [instance]
2025-11-21 07:40:30.044325 | controller |
2025-11-21 07:40:30.044461 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-21 07:40:30.044629 | controller | Friday 21 November 2025  07:40:30 +0000 (0:00:00.041)       0:00:17.509 *******
2025-11-21 07:40:30.082160 | controller | skipping: [instance]
2025-11-21 07:40:30.082334 | controller |
2025-11-21 07:40:30.082589 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-21 07:40:30.082844 | controller | Friday 21 November 2025  07:40:30 +0000 (0:00:00.038)       0:00:17.547 *******
2025-11-21 07:40:30.115817 | controller | skipping: [instance]
2025-11-21 07:40:30.116009 | controller |
2025-11-21 07:40:30.116299 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-21 07:40:30.116566 | controller | Friday 21 November 2025  07:40:30 +0000 (0:00:00.033)       0:00:17.581 *******
2025-11-21 07:40:30.165783 | controller | skipping: [instance]
2025-11-21 07:40:30.165841 | controller |
2025-11-21 07:40:30.165988 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-21 07:40:30.166124 | controller | Friday 21 November 2025  07:40:30 +0000 (0:00:00.049)       0:00:17.630 *******
2025-11-21 07:40:30.199075 | controller | skipping: [instance]
2025-11-21 07:40:30.199237 | controller |
2025-11-21 07:40:30.199466 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-21 07:40:30.199661 | controller | Friday 21 November 2025  07:40:30 +0000 (0:00:00.033)       0:00:17.664 *******
2025-11-21 07:40:30.471542 | controller | ok: [instance]
2025-11-21 07:40:30.471682 | controller |
2025-11-21 07:40:30.471811 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-21 07:40:30.471974 | controller | Friday 21 November 2025  07:40:30 +0000 (0:00:00.272)       0:00:17.937 *******
2025-11-21 07:40:30.810459 | controller | changed: [instance]
2025-11-21 07:40:31.147725 | controller |
2025-11-21 07:40:31.147808 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-21 07:40:31.147822 | controller | Friday 21 November 2025  07:40:30 +0000 (0:00:00.337)       0:00:18.275 *******
2025-11-21 07:40:31.147836 | controller | changed: [instance]
2025-11-21 07:40:31.187570 | controller |
2025-11-21 07:40:31.187665 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-21 07:40:31.187683 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.337)       0:00:18.612 *******
2025-11-21 07:40:31.187713 | controller | skipping: [instance]
2025-11-21 07:40:31.187813 | controller |
2025-11-21 07:40:31.187836 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-21 07:40:31.187857 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.039)       0:00:18.652 *******
2025-11-21 07:40:31.231989 | controller | skipping: [instance]
2025-11-21 07:40:31.232100 | controller |
2025-11-21 07:40:31.232127 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-21 07:40:31.232314 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.044)       0:00:18.697 *******
2025-11-21 07:40:31.267364 | controller | skipping: [instance]
2025-11-21 07:40:31.267412 | controller |
2025-11-21 07:40:31.267517 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-21 07:40:31.267629 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.035)       0:00:18.732 *******
2025-11-21 07:40:31.294142 | controller | skipping: [instance]
2025-11-21 07:40:31.294193 | controller |
2025-11-21 07:40:31.294292 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-21 07:40:31.294405 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.027)       0:00:18.759 *******
2025-11-21 07:40:31.319604 | controller | skipping: [instance]
2025-11-21 07:40:31.319751 | controller |
2025-11-21 07:40:31.319877 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-21 07:40:31.319899 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.025)       0:00:18.785 *******
2025-11-21 07:40:31.350394 | controller | skipping: [instance]
2025-11-21 07:40:31.350490 | controller |
2025-11-21 07:40:31.350614 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-21 07:40:31.350918 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.030)       0:00:18.815 *******
2025-11-21 07:40:31.373312 | controller | skipping: [instance]
2025-11-21 07:40:31.373398 | controller |
2025-11-21 07:40:31.373504 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-21 07:40:31.373641 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.023)       0:00:18.838 *******
2025-11-21 07:40:31.407489 | controller | skipping: [instance]
2025-11-21 07:40:31.407555 | controller |
2025-11-21 07:40:31.407660 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-21 07:40:31.407795 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.034)       0:00:18.873 *******
2025-11-21 07:40:31.427823 | controller | skipping: [instance]
2025-11-21 07:40:31.427874 | controller |
2025-11-21 07:40:31.427977 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-21 07:40:31.428085 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.020)       0:00:18.893 *******
2025-11-21 07:40:31.455857 | controller | skipping: [instance]
2025-11-21 07:40:31.455916 | controller |
2025-11-21 07:40:31.456017 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-21 07:40:31.456118 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.028)       0:00:18.921 *******
2025-11-21 07:40:31.497632 | controller | skipping: [instance]
2025-11-21 07:40:31.497708 | controller |
2025-11-21 07:40:31.497849 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-21 07:40:31.497973 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.041)       0:00:18.963 *******
2025-11-21 07:40:31.534678 | controller | skipping: [instance]
2025-11-21 07:40:31.534888 | controller |
2025-11-21 07:40:31.534912 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-21 07:40:31.534937 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.036)       0:00:19.000 *******
2025-11-21 07:40:31.573691 | controller | skipping: [instance]
2025-11-21 07:40:31.573788 | controller |
2025-11-21 07:40:31.573895 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-21 07:40:31.574028 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.039)       0:00:19.039 *******
2025-11-21 07:40:31.609609 | controller | skipping: [instance]
2025-11-21 07:40:31.609678 | controller |
2025-11-21 07:40:31.609812 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-21 07:40:31.609935 | controller | Friday 21 November 2025  07:40:31 +0000 (0:00:00.036)       0:00:19.075 *******
2025-11-21 07:41:15.916162 | controller | ok: [instance]
2025-11-21 07:41:15.916293 | controller |
2025-11-21 07:41:15.916355 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-21 07:41:15.916375 | controller | Friday 21 November 2025  07:41:15 +0000 (0:00:44.306)       0:01:03.381 *******
2025-11-21 07:41:17.443696 | controller | ok: [instance]
2025-11-21 07:41:17.443797 | controller |
2025-11-21 07:41:17.443887 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-21 07:41:17.444028 | controller | Friday 21 November 2025  07:41:17 +0000 (0:00:01.527)       0:01:04.908 *******
2025-11-21 07:41:17.511262 | controller | skipping: [instance]
2025-11-21 07:41:17.511342 | controller |
2025-11-21 07:41:17.511443 | controller | PLAY RECAP *********************************************************************
2025-11-21 07:41:17.511632 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-11-21 07:41:17.511737 | controller |
2025-11-21 07:41:17.511897 | controller | Friday 21 November 2025  07:41:17 +0000 (0:00:00.067)       0:01:04.976 *******
2025-11-21 07:41:17.512017 | controller | ===============================================================================
2025-11-21 07:41:17.512157 | controller | test_deps : Install selinux python libs -------------------------------- 44.31s
2025-11-21 07:41:17.512290 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.31s
2025-11-21 07:41:17.512419 | controller | test_deps : Install python yaml libs ------------------------------------ 1.53s
2025-11-21 07:41:17.512575 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.46s
2025-11-21 07:41:17.512698 | controller | Gathering Facts --------------------------------------------------------- 1.03s
2025-11-21 07:41:17.512839 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.97s
2025-11-21 07:41:17.512971 | controller | repo_setup : Install repo-setup package --------------------------------- 0.92s
2025-11-21 07:41:17.513116 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.92s
2025-11-21 07:41:17.513261 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.73s
2025-11-21 07:41:17.513364 | controller | repo_setup : Ensure directories are present ----------------------------- 0.66s
2025-11-21 07:41:17.513448 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-11-21 07:41:17.513597 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.55s
2025-11-21 07:41:17.513729 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.42s
2025-11-21 07:41:17.513866 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.35s
2025-11-21 07:41:17.513973 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.34s
2025-11-21 07:41:17.514108 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.34s
2025-11-21 07:41:17.514264 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.27s
2025-11-21 07:41:17.514386 | controller | Deploy repo-setup ------------------------------------------------------- 0.07s
2025-11-21 07:41:17.514489 | controller | test_deps : Gather variables for each operating system ------------------ 0.07s
2025-11-21 07:41:17.514617 | controller | test_deps : Install extra packages -------------------------------------- 0.07s
2025-11-21 07:41:17.619729 | controller | INFO     Running default > converge
2025-11-21 07:41:18.167474 | controller |
2025-11-21 07:41:18.167884 | controller | PLAY [Add crc to the inventory] ************************************************
2025-11-21 07:41:18.168997 | controller |
2025-11-21 07:41:19.328377 | controller | TASK [Gathering Facts] *********************************************************
2025-11-21 07:41:19.328433 | controller | Friday 21 November 2025  07:41:18 +0000 (0:00:00.015)       0:00:00.015 *******
2025-11-21 07:41:19.328455 | controller | ok: [instance]
2025-11-21 07:41:19.328905 | controller |
2025-11-21 07:41:19.710444 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-11-21 07:41:19.710493 | controller | Friday 21 November 2025  07:41:19 +0000 (0:00:01.161)       0:00:01.177 *******
2025-11-21 07:41:19.710516 | controller | changed: [instance]
2025-11-21 07:41:19.741139 | controller |
2025-11-21 07:41:19.741206 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-11-21 07:41:19.741214 | controller | Friday 21 November 2025  07:41:19 +0000 (0:00:00.381)       0:00:01.558 *******
2025-11-21 07:41:19.741229 | controller |
2025-11-21 07:41:19.741385 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-11-21 07:41:19.741431 | controller | Friday 21 November 2025  07:41:19 +0000 (0:00:00.031)       0:00:01.589 *******
2025-11-21 07:41:20.117264 | controller | ok: [instance]
2025-11-21 07:41:20.117373 | controller |
2025-11-21 07:41:20.117388 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-11-21 07:41:20.117584 | controller | Friday 21 November 2025  07:41:20 +0000 (0:00:00.376)       0:00:01.966 *******
2025-11-21 07:41:20.140875 | controller | ok: [instance]
2025-11-21 07:41:20.169543 | controller |
2025-11-21 07:41:20.169616 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-11-21 07:41:20.169631 | controller | Friday 21 November 2025  07:41:20 +0000 (0:00:00.023)       0:00:01.990 *******
2025-11-21 07:41:20.169652 | controller | skipping: [instance]
2025-11-21 07:41:20.169728 | controller |
2025-11-21 07:41:20.169781 | controller | TASK [Add the crc host dynamically] ********************************************
2025-11-21 07:41:20.169854 | controller | Friday 21 November 2025  07:41:20 +0000 (0:00:00.028)       0:00:02.018 *******
2025-11-21 07:41:20.227468 | controller | changed: [instance]
2025-11-21 07:41:20.227533 | controller |
2025-11-21 07:41:20.227641 | controller | TASK [Add a dummy ethernet interface to target the crc NMstate] ****************
2025-11-21 07:41:20.227805 | controller | Friday 21 November 2025  07:41:20 +0000 (0:00:00.058)       0:00:02.076 *******
2025-11-21 07:41:20.798849 | controller | changed: [instance]
2025-11-21 07:41:25.703240 | controller |
2025-11-21 07:41:25.703290 | controller | PLAY [Get CRC facts] ***********************************************************
2025-11-21 07:41:25.703300 | controller |
2025-11-21 07:41:25.703307 | controller | TASK [Fetch crc network facts] *************************************************
2025-11-21 07:41:25.703315 | controller | Friday 21 November 2025  07:41:20 +0000 (0:00:00.569)       0:00:02.646 *******
2025-11-21 07:41:25.703329 | controller | ok: [crc]
2025-11-21 07:41:25.767293 | controller |
2025-11-21 07:41:25.767328 | controller | PLAY [Converge] ****************************************************************
2025-11-21 07:41:25.767336 | controller |
2025-11-21 07:41:25.767342 | controller | TASK [Set common molecule test facts] ******************************************
2025-11-21 07:41:25.767348 | controller | Friday 21 November 2025  07:41:25 +0000 (0:00:04.905)       0:00:07.551 *******
2025-11-21 07:41:25.767358 | controller | ok: [instance]
2025-11-21 07:41:25.828037 | controller |
2025-11-21 07:41:25.828086 | controller | TASK [Prepare the Networking Env Definition for ci_nmstate role] ***************
2025-11-21 07:41:25.828094 | controller | Friday 21 November 2025  07:41:25 +0000 (0:00:00.064)       0:00:07.616 *******
2025-11-21 07:41:25.828109 | controller |
2025-11-21 07:41:25.928400 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ******
2025-11-21 07:41:25.928439 | controller | Friday 21 November 2025  07:41:25 +0000 (0:00:00.060)       0:00:07.677 *******
2025-11-21 07:41:25.928451 | controller | skipping: [instance]
2025-11-21 07:41:25.928598 | controller |
2025-11-21 07:41:25.928639 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-11-21 07:41:26.032533 | controller | Friday 21 November 2025  07:41:25 +0000 (0:00:00.100)       0:00:07.777 *******
2025-11-21 07:41:26.032598 | controller | skipping: [instance]
2025-11-21 07:41:26.079161 | controller |
2025-11-21 07:41:26.079230 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************
2025-11-21 07:41:26.079248 | controller | Friday 21 November 2025  07:41:26 +0000 (0:00:00.104)       0:00:07.881 *******
2025-11-21 07:41:26.079270 | controller | skipping: [instance]
2025-11-21 07:41:26.079310 | controller |
2025-11-21 07:41:26.079341 | controller | TASK [networking_mapper : Load the interfaces info from file] ******************
2025-11-21 07:41:26.079381 | controller | Friday 21 November 2025  07:41:26 +0000 (0:00:00.046)       0:00:07.928 *******
2025-11-21 07:41:26.125827 | controller | skipping: [instance]
2025-11-21 07:41:26.125935 | controller |
2025-11-21 07:41:26.126218 | controller | TASK [networking_mapper : Ensure local fact is empty] **************************
2025-11-21 07:41:26.151933 | controller | Friday 21 November 2025  07:41:26 +0000 (0:00:00.046)       0:00:07.974 *******
2025-11-21 07:41:26.151985 | controller | ok: [instance]
2025-11-21 07:41:26.152050 | controller |
2025-11-21 07:41:26.152071 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] ***
2025-11-21 07:41:26.152135 | controller | Friday 21 November 2025  07:41:26 +0000 (0:00:00.026)       0:00:08.001 *******
2025-11-21 07:41:26.290935 | controller | ok: [instance] => (item={'networks': {'default': {'network': '38.102.83.0/24', 'gateway': '38.102.83.1', 'dns': ['1.1.1.1', '1.0.0.1'], 'mtu': '1450'}, 'testing-untagged': {'network': '172.17.20.0/24', 'dns': ['192.168.130.11', '192.168.130.1'], 'mtu': '1500'}, 'testing-vlan': {'network': '172.17.21.0/24', 'vlan': 21}}, 'instances': {'crc': {'networks': {'testing-untagged': {'ip': '172.17.20.5'}, 'testing-vlan': {'ip': '172.17.21.5', 'trunk-parent': 'testing-untagged'}}}, 'instance': {'networks': {'default': {'ip': '38.102.83.213'}}}}})
2025-11-21 07:41:26.291007 | controller |
2025-11-21 07:41:26.291023 | controller | TASK [networking_mapper : Gather facts if requested] ***************************
2025-11-21 07:41:26.291045 | controller | Friday 21 November 2025  07:41:26 +0000 (0:00:00.138)       0:00:08.139 *******
2025-11-21 07:41:26.335157 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/networking_mapper/tasks/_gather_facts.yml for instance
2025-11-21 07:41:26.744255 | controller |
2025-11-21 07:41:26.744306 | controller | TASK [networking_mapper : Gather the facts] ************************************
2025-11-21 07:41:26.744314 | controller | Friday 21 November 2025  07:41:26 +0000 (0:00:00.043)       0:00:08.183 *******
2025-11-21 07:41:26.744327 | controller | skipping: [instance] => (item=crc)
2025-11-21 07:41:26.744399 | controller | skipping: [instance] => (item=instance)
2025-11-21 07:41:26.744410 | controller | skipping: [instance]
2025-11-21 07:41:26.744592 | controller |
2025-11-21 07:41:26.744629 | controller | TASK [networking_mapper : Save instances refreshed facts for troubleshooting purposes] ***
2025-11-21 07:41:27.559890 | controller | Friday 21 November 2025  07:41:26 +0000 (0:00:00.408)       0:00:08.592 *******
2025-11-21 07:41:27.559935 | controller | changed: [instance]
2025-11-21 07:41:27.560140 | controller |
2025-11-21 07:41:27.560187 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] *****************
2025-11-21 07:41:27.560211 | controller | Friday 21 November 2025  07:41:27 +0000 (0:00:00.815)       0:00:09.408 *******
2025-11-21 07:41:27.975093 | controller | changed: [instance]
2025-11-21 07:41:28.590742 | controller |
2025-11-21 07:41:28.590833 | controller | TASK [networking_mapper : Write the Networking Definition to file] *************
2025-11-21 07:41:28.590851 | controller | Friday 21 November 2025  07:41:27 +0000 (0:00:00.414)       0:00:09.823 *******
2025-11-21 07:41:28.590875 | controller | changed: [instance]
2025-11-21 07:41:28.682966 | controller |
2025-11-21 07:41:28.683008 | controller | TASK [networking_mapper : Call the networking mapper] **************************
2025-11-21 07:41:28.683016 | controller | Friday 21 November 2025  07:41:28 +0000 (0:00:00.615)       0:00:10.438 *******
2025-11-21 07:41:28.683029 | controller | ok: [instance]
2025-11-21 07:41:28.683251 | controller |
2025-11-21 07:41:28.683426 | controller | TASK [networking_mapper : Set networking mapper facts] *************************
2025-11-21 07:41:28.683753 | controller | Friday 21 November 2025  07:41:28 +0000 (0:00:00.092)       0:00:10.531 *******
2025-11-21 07:41:28.712371 | controller | ok: [instance]
2025-11-21 07:41:28.712559 | controller |
2025-11-21 07:41:28.712838 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] ***
2025-11-21 07:41:28.713072 | controller | Friday 21 November 2025  07:41:28 +0000 (0:00:00.030)       0:00:10.561 *******
2025-11-21 07:41:29.283273 | controller | changed: [instance]
2025-11-21 07:41:29.283326 | controller |
2025-11-21 07:41:29.283459 | controller | TASK [Call the role under test] ************************************************
2025-11-21 07:41:29.283564 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.571)       0:00:11.132 *******
2025-11-21 07:41:29.338138 | controller |
2025-11-21 07:41:29.338221 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-11-21 07:41:29.338261 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.054)       0:00:11.187 *******
2025-11-21 07:41:29.376287 | controller | skipping: [instance]
2025-11-21 07:41:29.376360 | controller |
2025-11-21 07:41:29.376812 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-11-21 07:41:29.376965 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.037)       0:00:11.225 *******
2025-11-21 07:41:29.407233 | controller | skipping: [instance]
2025-11-21 07:41:29.407318 | controller |
2025-11-21 07:41:29.407475 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-11-21 07:41:29.407665 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.031)       0:00:11.256 *******
2025-11-21 07:41:29.445632 | controller | skipping: [instance]
2025-11-21 07:41:29.445748 | controller |
2025-11-21 07:41:29.445978 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-11-21 07:41:29.446164 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.037)       0:00:11.294 *******
2025-11-21 07:41:29.485395 | controller | skipping: [instance]
2025-11-21 07:41:29.485496 | controller |
2025-11-21 07:41:29.485683 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] **********************
2025-11-21 07:41:29.485877 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.039)       0:00:11.334 *******
2025-11-21 07:41:29.530798 | controller | ok: [instance]
2025-11-21 07:41:29.530871 | controller |
2025-11-21 07:41:29.531033 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] ***
2025-11-21 07:41:29.531135 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.045)       0:00:11.380 *******
2025-11-21 07:41:29.602241 | controller | ok: [instance] => (item=crc)
2025-11-21 07:41:29.602313 | controller | ok: [instance] => (item=instance)
2025-11-21 07:41:29.602409 | controller |
2025-11-21 07:41:29.602524 | controller | TASK [ci_nmstate : Get k8s nodes] **********************************************
2025-11-21 07:41:29.602641 | controller | Friday 21 November 2025  07:41:29 +0000 (0:00:00.071)       0:00:11.451 *******
2025-11-21 07:41:30.665145 | controller | ok: [instance]
2025-11-21 07:41:30.665361 | controller |
2025-11-21 07:41:30.769341 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ********
2025-11-21 07:41:30.769377 | controller | Friday 21 November 2025  07:41:30 +0000 (0:00:01.062)       0:00:12.514 *******
2025-11-21 07:41:30.769389 | controller | ok: [instance]
2025-11-21 07:41:30.769652 | controller |
2025-11-21 07:41:30.769751 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] *************************
2025-11-21 07:41:30.769799 | controller | Friday 21 November 2025  07:41:30 +0000 (0:00:00.104)       0:00:12.618 *******
2025-11-21 07:41:30.844388 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_install.yml for instance
2025-11-21 07:41:30.844974 | controller |
2025-11-21 07:41:30.845039 | controller | TASK [ci_nmstate : Create the target dir to dump NMstate manifests] ************
2025-11-21 07:41:31.090881 | controller | Friday 21 November 2025  07:41:30 +0000 (0:00:00.074)       0:00:12.693 *******
2025-11-21 07:41:31.090923 | controller | changed: [instance]
2025-11-21 07:41:31.090959 | controller |
2025-11-21 07:41:31.091246 | controller | TASK [ci_nmstate : Create the nmstate namespace] *******************************
2025-11-21 07:41:31.990432 | controller | Friday 21 November 2025  07:41:31 +0000 (0:00:00.246)       0:00:12.940 *******
2025-11-21 07:41:31.990473 | controller | changed: [instance]
2025-11-21 07:41:32.095606 | controller |
2025-11-21 07:41:32.095647 | controller | TASK [ci_nmstate : Generate the OperatorGroup final CR] ************************
2025-11-21 07:41:32.095658 | controller | Friday 21 November 2025  07:41:31 +0000 (0:00:00.899)       0:00:13.839 *******
2025-11-21 07:41:32.095673 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1', 'kind': 'OperatorGroup', 'metadata': {'annotations': {'olm.providedAPIs': 'NMState.v1.nmstate.io'}, 'name': 'openshift-nmstate', 'namespace': 'openshift-nmstate'}, 'spec': {'targetNamespaces': ['openshift-nmstate']}})
2025-11-21 07:41:32.194129 | controller |
2025-11-21 07:41:32.194174 | controller | TASK [ci_nmstate : Generate the Subscription final CR] *************************
2025-11-21 07:41:32.194183 | controller | Friday 21 November 2025  07:41:32 +0000 (0:00:00.104)       0:00:13.944 *******
2025-11-21 07:41:32.194202 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1alpha1', 'kind': 'Subscription', 'metadata': {'labels': {'operators.coreos.com/kubernetes-nmstate-operator.openshift-nmstate': ''}, 'name': 'kubernetes-nmstate-operator', 'namespace': 'openshift-nmstate'}, 'spec': {'channel': 'stable', 'installPlanApproval': 'Automatic', 'name': 'kubernetes-nmstate-operator', 'source': 'redhat-operators', 'sourceNamespace': 'openshift-marketplace'}})
2025-11-21 07:41:32.194275 | controller |
2025-11-21 07:41:32.194310 | controller | TASK [ci_nmstate : Save k8s nmstate OLM manifests as artifacts] ****************
2025-11-21 07:41:33.047491 | controller | Friday 21 November 2025  07:41:32 +0000 (0:00:00.098)       0:00:14.043 *******
2025-11-21 07:41:33.047557 | controller | changed: [instance] => (item=openshift-nmstate)
2025-11-21 07:41:33.047588 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-11-21 07:41:33.047706 | controller |
2025-11-21 07:41:33.047879 | controller | TASK [ci_nmstate : Create the OLM subscription resources] **********************
2025-11-21 07:41:33.048028 | controller | Friday 21 November 2025  07:41:33 +0000 (0:00:00.851)       0:00:14.895 *******
2025-11-21 07:41:34.983492 | controller | changed: [instance] => (item=openshift-nmstate)
2025-11-21 07:42:06.662142 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-11-21 07:42:06.662190 | controller |
2025-11-21 07:42:06.662206 | controller | TASK [ci_nmstate : Wait for nmstate operator to be installed] ******************
2025-11-21 07:42:06.662215 | controller | Friday 21 November 2025  07:41:34 +0000 (0:00:01.937)       0:00:16.832 *******
2025-11-21 07:42:06.662229 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (12 retries left).
2025-11-21 07:42:07.329758 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (11 retries left).
2025-11-21 07:42:07.329825 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (10 retries left).
2025-11-21 07:42:07.329835 | controller | ok: [instance]
2025-11-21 07:42:07.329843 | controller |
2025-11-21 07:42:07.329851 | controller | TASK [ci_nmstate : Save nmstate operator config as an artifact] ****************
2025-11-21 07:42:07.329860 | controller | Friday 21 November 2025  07:42:06 +0000 (0:00:31.677)       0:00:48.510 *******
2025-11-21 07:42:07.329876 | controller | changed: [instance]
2025-11-21 07:42:07.330016 | controller |
2025-11-21 07:42:07.330070 | controller | TASK [ci_nmstate : Trigger nmstate operator to install nmstate] ****************
2025-11-21 07:42:07.330102 | controller | Friday 21 November 2025  07:42:07 +0000 (0:00:00.667)       0:00:49.178 *******
2025-11-21 07:42:08.178698 | controller | changed: [instance]
2025-11-21 07:42:40.142301 | controller |
2025-11-21 07:42:40.142369 | controller | TASK [ci_nmstate : Wait for nsmtate Deployments to be ready] *******************
2025-11-21 07:42:40.142381 | controller | Friday 21 November 2025  07:42:08 +0000 (0:00:00.849)       0:00:50.027 *******
2025-11-21 07:42:40.142397 | controller | ok: [instance]
2025-11-21 07:42:40.142437 | controller |
2025-11-21 07:42:40.142450 | controller | TASK [ci_nmstate : Wait for nsmtate DaemonSet to be ready] *********************
2025-11-21 07:42:41.217695 | controller | Friday 21 November 2025  07:42:40 +0000 (0:00:31.962)       0:01:21.990 *******
2025-11-21 07:42:41.217755 | controller | ok: [instance]
2025-11-21 07:42:41.217803 | controller |
2025-11-21 07:42:41.217815 | controller | TASK [ci_nmstate : Provision nncps] ********************************************
2025-11-21 07:42:41.218014 | controller | Friday 21 November 2025  07:42:41 +0000 (0:00:01.076)       0:01:23.067 *******
2025-11-21 07:42:41.261409 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_provision_node.yml for instance => (item=crc)
2025-11-21 07:42:41.261482 | controller |
2025-11-21 07:42:41.261502 | controller | TASK [ci_nmstate : Prepare the k8s nmstate nncp to apply] **********************
2025-11-21 07:42:41.261520 | controller | Friday 21 November 2025  07:42:41 +0000 (0:00:00.043)       0:01:23.110 *******
2025-11-21 07:42:41.370898 | controller | ok: [instance]
2025-11-21 07:42:41.371188 | controller |
2025-11-21 07:42:41.371428 | controller | TASK [ci_nmstate : Save k8s nmstate nncp manifests as artifacts] ***************
2025-11-21 07:42:41.371630 | controller | Friday 21 November 2025  07:42:41 +0000 (0:00:00.108)       0:01:23.219 *******
2025-11-21 07:42:41.821541 | controller | changed: [instance]
2025-11-21 07:42:41.821644 | controller |
2025-11-21 07:42:41.821994 | controller | TASK [ci_nmstate : Provision nmstate nncp for the node worker] *****************
2025-11-21 07:42:41.822260 | controller | Friday 21 November 2025  07:42:41 +0000 (0:00:00.450)       0:01:23.669 *******
2025-11-21 07:42:43.295477 | controller | changed: [instance]
2025-11-21 07:42:43.295545 | controller |
2025-11-21 07:42:43.295734 | controller | TASK [ci_nmstate : Wait for nsmtate nncp to be ready] **************************
2025-11-21 07:42:43.295918 | controller | Friday 21 November 2025  07:42:43 +0000 (0:00:01.474)       0:01:25.144 *******
2025-11-21 07:48:07.853269 | controller | fatal: [instance]: FAILED! => changed=false
2025-11-21 07:48:07.853383 | controller |   api_found: true
2025-11-21 07:48:07.853403 | controller |   attempts: 30
2025-11-21 07:48:07.853417 | controller |   resources:
2025-11-21 07:48:07.853436 | controller |   - apiVersion: nmstate.io/v1
2025-11-21 07:48:07.853483 | controller |     kind: NodeNetworkConfigurationPolicy
2025-11-21 07:48:07.853503 | controller |     metadata:
2025-11-21 07:48:07.854354 | controller |       annotations:
2025-11-21 07:48:07.855050 | controller |         nmstate.io/webhook-mutating-timestamp: '1763710963063084000'
2025-11-21 07:48:07.855192 | controller |       creationTimestamp: '2025-11-21T07:42:43Z'
2025-11-21 07:48:07.855248 | controller |       generation: 1
2025-11-21 07:48:07.855268 | controller |       managedFields:
2025-11-21 07:48:07.855285 | controller |       - apiVersion: nmstate.io/v1
2025-11-21 07:48:07.855303 | controller |         fieldsType: FieldsV1
2025-11-21 07:48:07.855320 | controller |         fieldsV1:
2025-11-21 07:48:07.855336 | controller |           f:spec:
2025-11-21 07:48:07.855361 | controller |             .: {}
2025-11-21 07:48:07.855640 | controller |             f:desiredState:
2025-11-21 07:48:07.855668 | controller |               .: {}
2025-11-21 07:48:07.855685 | controller |               f:dns-resolver:
2025-11-21 07:48:07.855702 | controller |                 .: {}
2025-11-21 07:48:07.855720 | controller |                 f:config:
2025-11-21 07:48:07.855737 | controller |                   .: {}
2025-11-21 07:48:07.855760 | controller |                   f:search: {}
2025-11-21 07:48:07.855942 | controller |                   f:server: {}
2025-11-21 07:48:07.855971 | controller |               f:interfaces: {}
2025-11-21 07:48:07.855990 | controller |             f:nodeSelector:
2025-11-21 07:48:07.856007 | controller |               .: {}
2025-11-21 07:48:07.856032 | controller |               f:kubernetes.io/hostname: {}
2025-11-21 07:48:07.856182 | controller |               f:node-role.kubernetes.io/worker: {}
2025-11-21 07:48:07.856220 | controller |         manager: OpenAPI-Generator
2025-11-21 07:48:07.856412 | controller |         operation: Update
2025-11-21 07:48:07.856440 | controller |         time: '2025-11-21T07:42:43Z'
2025-11-21 07:48:07.856464 | controller |       - apiVersion: nmstate.io/v1
2025-11-21 07:48:07.856874 | controller |         fieldsType: FieldsV1
2025-11-21 07:48:07.856915 | controller |         fieldsV1:
2025-11-21 07:48:07.857065 | controller |           f:status:
2025-11-21 07:48:07.857092 | controller |             .: {}
2025-11-21 07:48:07.857110 | controller |             f:conditions: {}
2025-11-21 07:48:07.857146 | controller |             f:lastUnavailableNodeCountUpdate: {}
2025-11-21 07:48:07.857164 | controller |             f:unavailableNodeCount: {}
2025-11-21 07:48:07.857187 | controller |         manager: manager
2025-11-21 07:48:07.857280 | controller |         operation: Update
2025-11-21 07:48:07.857311 | controller |         subresource: status
2025-11-21 07:48:07.857426 | controller |         time: '2025-11-21T07:42:46Z'
2025-11-21 07:48:07.857497 | controller |       name: crc
2025-11-21 07:48:07.857632 | controller |       resourceVersion: '35517'
2025-11-21 07:48:07.857794 | controller |       uid: 4789bee0-3af3-4b53-9f20-d0169fabcf76
2025-11-21 07:48:07.857870 | controller |     spec:
2025-11-21 07:48:07.857995 | controller |       desiredState:
2025-11-21 07:48:07.858123 | controller |         dns-resolver:
2025-11-21 07:48:07.858238 | controller |           config:
2025-11-21 07:48:07.858392 | controller |             search:
2025-11-21 07:48:07.858500 | controller |             - testing-untagged.example.com
2025-11-21 07:48:07.858626 | controller |             - testing-vlan.example.com
2025-11-21 07:48:07.858733 | controller |             server:
2025-11-21 07:48:07.858920 | controller |             - 192.168.130.11
2025-11-21 07:48:07.858993 | controller |             - 192.168.130.1
2025-11-21 07:48:07.859123 | controller |         interfaces:
2025-11-21 07:48:07.859240 | controller |         - description: testing-untagged
2025-11-21 07:48:07.859390 | controller |           ipv4:
2025-11-21 07:48:07.859533 | controller |             address:
2025-11-21 07:48:07.859656 | controller |             - ip: 172.17.20.5
2025-11-21 07:48:07.859790 | controller |               prefix-length: 24
2025-11-21 07:48:07.859908 | controller |             enabled: true
2025-11-21 07:48:07.860019 | controller |           ipv6:
2025-11-21 07:48:07.860134 | controller |             enabled: false
2025-11-21 07:48:07.860257 | controller |           mtu: 1500
2025-11-21 07:48:07.860381 | controller |           name: enp6s0
2025-11-21 07:48:07.860482 | controller |           state: up
2025-11-21 07:48:07.860607 | controller |           type: ethernet
2025-11-21 07:48:07.860717 | controller |         - description: testing-vlan
2025-11-21 07:48:07.860865 | controller |           ipv4:
2025-11-21 07:48:07.860948 | controller |             address:
2025-11-21 07:48:07.861073 | controller |             - ip: 172.17.21.5
2025-11-21 07:48:07.861174 | controller |               prefix-length: 24
2025-11-21 07:48:07.861287 | controller |             enabled: true
2025-11-21 07:48:07.861399 | controller |           ipv6:
2025-11-21 07:48:07.861511 | controller |             enabled: false
2025-11-21 07:48:07.861622 | controller |           mtu: 1496
2025-11-21 07:48:07.861737 | controller |           name: enp6s0.21
2025-11-21 07:48:07.861903 | controller |           state: up
2025-11-21 07:48:07.862068 | controller |           type: vlan
2025-11-21 07:48:07.862185 | controller |           vlan:
2025-11-21 07:48:07.862301 | controller |             base-iface: enp6s0
2025-11-21 07:48:07.862414 | controller |             id: 21
2025-11-21 07:48:07.862526 | controller |       nodeSelector:
2025-11-21 07:48:07.862640 | controller |         kubernetes.io/hostname: crc
2025-11-21 07:48:07.862818 | controller |         node-role.kubernetes.io/worker: ''
2025-11-21 07:48:07.862921 | controller |     status:
2025-11-21 07:48:07.863051 | controller |       conditions:
2025-11-21 07:48:07.863234 | controller |       - lastHeartbeatTime: '2025-11-21T07:42:46Z'
2025-11-21 07:48:07.863348 | controller |         lastTransitionTime: '2025-11-21T07:42:43Z'
2025-11-21 07:48:07.863508 | controller |         reason: ConfigurationProgressing
2025-11-21 07:48:07.863705 | controller |         status: Unknown
2025-11-21 07:48:07.863896 | controller |         type: Available
2025-11-21 07:48:07.864018 | controller |       - lastHeartbeatTime: '2025-11-21T07:42:46Z'
2025-11-21 07:48:07.864136 | controller |         lastTransitionTime: '2025-11-21T07:42:43Z'
2025-11-21 07:48:07.864250 | controller |         reason: ConfigurationProgressing
2025-11-21 07:48:07.864439 | controller |         status: Unknown
2025-11-21 07:48:07.864578 | controller |         type: Degraded
2025-11-21 07:48:07.864790 | controller |       - lastHeartbeatTime: '2025-11-21T07:42:46Z'
2025-11-21 07:48:07.864964 | controller |         lastTransitionTime: '2025-11-21T07:42:46Z'
2025-11-21 07:48:07.865101 | controller |         message: Policy is progressing 0/1 nodes finished
2025-11-21 07:48:07.865216 | controller |         reason: ConfigurationProgressing
2025-11-21 07:48:07.865328 | controller |         status: 'True'
2025-11-21 07:48:07.865490 | controller |         type: Progressing
2025-11-21 07:48:07.865660 | controller |       lastUnavailableNodeCountUpdate: '2025-11-21T07:42:46Z'
2025-11-21 07:48:07.865795 | controller |       unavailableNodeCount: 1
2025-11-21 07:48:07.866129 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (30 retries left).
2025-11-21 07:48:07.866275 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (29 retries left).
2025-11-21 07:48:07.866404 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (28 retries left).
2025-11-21 07:48:07.866589 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (27 retries left).
2025-11-21 07:48:07.866832 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (26 retries left).
2025-11-21 07:48:07.866945 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (25 retries left).
2025-11-21 07:48:07.867161 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (24 retries left).
2025-11-21 07:48:07.867271 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (23 retries left).
2025-11-21 07:48:07.867447 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (22 retries left).
2025-11-21 07:48:07.867589 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (21 retries left).
2025-11-21 07:48:07.867791 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (20 retries left).
2025-11-21 07:48:07.867921 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (19 retries left).
2025-11-21 07:48:07.868046 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (18 retries left).
2025-11-21 07:48:07.868202 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (17 retries left).
2025-11-21 07:48:07.868361 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (16 retries left).
2025-11-21 07:48:07.868527 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (15 retries left).
2025-11-21 07:48:07.868690 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (14 retries left).
2025-11-21 07:48:07.868881 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (13 retries left).
2025-11-21 07:48:07.869050 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (12 retries left).
2025-11-21 07:48:07.869217 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (11 retries left).
2025-11-21 07:48:07.869469 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (10 retries left).
2025-11-21 07:48:07.869653 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (9 retries left).
2025-11-21 07:48:07.869851 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (8 retries left).
2025-11-21 07:48:07.870042 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (7 retries left).
2025-11-21 07:48:07.870242 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (6 retries left).
2025-11-21 07:48:07.870418 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (5 retries left).
2025-11-21 07:48:07.870585 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (4 retries left).
2025-11-21 07:48:07.870712 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (3 retries left).
2025-11-21 07:48:07.870889 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (2 retries left).
2025-11-21 07:48:07.871022 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (1 retries left).
2025-11-21 07:48:07.871143 | controller |
2025-11-21 07:48:07.871409 | controller | PLAY RECAP *********************************************************************
2025-11-21 07:48:07.871579 | controller | crc                        : ok=1    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-11-21 07:48:07.871706 | controller | instance                   : ok=36   changed=15   unreachable=0    failed=1    skipped=10   rescued=0    ignored=0
2025-11-21 07:48:07.871887 | controller |
2025-11-21 07:48:07.872069 | controller | Friday 21 November 2025  07:48:07 +0000 (0:05:24.561)       0:06:49.706 *******
2025-11-21 07:48:07.872441 | controller | ===============================================================================
2025-11-21 07:48:07.872634 | controller | ci_nmstate : Wait for nsmtate nncp to be ready ------------------------ 324.56s
2025-11-21 07:48:07.872807 | controller | ci_nmstate : Wait for nsmtate Deployments to be ready ------------------ 31.96s
2025-11-21 07:48:07.873031 | controller | ci_nmstate : Wait for nmstate operator to be installed ----------------- 31.68s
2025-11-21 07:48:07.873188 | controller | Fetch crc network facts ------------------------------------------------- 4.91s
2025-11-21 07:48:07.873372 | controller | ci_nmstate : Create the OLM subscription resources ---------------------- 1.94s
2025-11-21 07:48:07.873512 | controller | ci_nmstate : Provision nmstate nncp for the node worker ----------------- 1.47s
2025-11-21 07:48:07.873645 | controller | Gathering Facts --------------------------------------------------------- 1.16s
2025-11-21 07:48:07.873813 | controller | ci_nmstate : Wait for nsmtate DaemonSet to be ready --------------------- 1.08s
2025-11-21 07:48:07.873941 | controller | ci_nmstate : Get k8s nodes ---------------------------------------------- 1.06s
2025-11-21 07:48:07.874082 | controller | ci_nmstate : Create the nmstate namespace ------------------------------- 0.90s
2025-11-21 07:48:07.874236 | controller | ci_nmstate : Save k8s nmstate OLM manifests as artifacts ---------------- 0.85s
2025-11-21 07:48:07.874395 | controller | ci_nmstate : Trigger nmstate operator to install nmstate ---------------- 0.85s
2025-11-21 07:48:07.874574 | controller | networking_mapper : Save instances refreshed facts for troubleshooting purposes --- 0.82s
2025-11-21 07:48:07.874725 | controller | ci_nmstate : Save nmstate operator config as an artifact ---------------- 0.67s
2025-11-21 07:48:07.874881 | controller | networking_mapper : Write the Networking Definition to file ------------- 0.62s
2025-11-21 07:48:07.875336 | controller | networking_mapper : Write the Networking Environment Definition to file --- 0.57s
2025-11-21 07:48:07.875500 | controller | Add a dummy ethernet interface to target the crc NMstate ---------------- 0.57s
2025-11-21 07:48:07.875528 | controller | ci_nmstate : Save k8s nmstate nncp manifests as artifacts --------------- 0.45s
2025-11-21 07:48:07.875551 | controller | networking_mapper : Ensure CI infrastructure dir exists ----------------- 0.41s
2025-11-21 07:48:07.875633 | controller | networking_mapper : Gather the facts ------------------------------------ 0.41s
2025-11-21 07:48:07.943388 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/ci_nmstate/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/molecule/default/converge.yml']
2025-11-21 07:48:07.943890 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-11-21 07:48:07.953579 | controller | INFO     Running default > cleanup
2025-11-21 07:48:07.954665 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-11-21 07:48:07.964026 | controller | INFO     Running default > destroy
2025-11-21 07:48:07.964406 | controller | WARNING  Skipping, instances are delegated.
2025-11-21 07:48:07.964839 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-11-21 07:48:07.966884 | controller | INFO     Writing /tmp/report.html report.
2025-11-21 07:48:08.249606 | controller | ERROR
2025-11-21 07:48:08.249939 | controller | {
2025-11-21 07:48:08.250012 | controller |   "delta": "0:08:15.396116",
2025-11-21 07:48:08.250056 | controller |   "end": "2025-11-21 07:48:08.040337",
2025-11-21 07:48:08.250095 | controller |   "msg": "non-zero return code",
2025-11-21 07:48:08.250131 | controller |   "rc": 1,
2025-11-21 07:48:08.250168 | controller |   "start": "2025-11-21 07:39:52.644221"
2025-11-21 07:48:08.250205 | controller | }
failure
2025-11-21 07:48:08.269593 | 
2025-11-21 07:48:08.269759 | PLAY RECAP
2025-11-21 07:48:08.269840 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-11-21 07:48:08.269885 | 
2025-11-21 07:48:08.381602 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-21 07:48:08.382477 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-21 07:48:09.052366 | 
2025-11-21 07:48:09.052484 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-21 07:48:09.075795 | 
2025-11-21 07:48:09.075900 | TASK [Filter out host if needed]
2025-11-21 07:48:09.084833 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-21 07:48:09.089493 | 
2025-11-21 07:48:09.089568 | TASK [Ensure file is present]
2025-11-21 07:48:09.634431 | controller | ok
2025-11-21 07:48:09.647927 | 
2025-11-21 07:48:09.648065 | TASK [Manage molecule report file]
2025-11-21 07:48:10.191466 | controller | changed
2025-11-21 07:48:10.203212 | 
2025-11-21 07:48:10.203374 | TASK [Check if we get ci-framework-data basedir]
2025-11-21 07:48:10.523498 | controller | ok
2025-11-21 07:48:10.531223 | 
2025-11-21 07:48:10.531313 | TASK [Create ci-framework-data log directory for zuul]
2025-11-21 07:48:11.038664 | controller | changed
2025-11-21 07:48:11.045111 | 
2025-11-21 07:48:11.045176 | TASK [Copy ci-framework interesting files]
2025-11-21 07:48:11.364373 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-21 07:48:11.575388 | controller | changed
2025-11-21 07:48:11.590304 | 
2025-11-21 07:48:11.590454 | TASK [Get SELinux listing]
2025-11-21 07:48:12.139134 | controller | changed
2025-11-21 07:48:12.148486 | 
2025-11-21 07:48:12.148570 | TASK [Generate log index]
2025-11-21 07:48:13.697253 | controller | changed
2025-11-21 07:48:13.708810 | 
2025-11-21 07:48:13.708936 | TASK [Get some env related data]
2025-11-21 07:48:14.528775 | controller | /home/zuul/.local/bin/ansible
2025-11-21 07:48:15.268778 | controller | changed
2025-11-21 07:48:15.280356 | 
2025-11-21 07:48:15.280483 | TASK [Generate list of logs to collect in home directory]
2025-11-21 07:48:15.742254 | controller | ok: All paths examined
2025-11-21 07:48:15.755250 | 
2025-11-21 07:48:15.755399 | LOOP [Copy logs from home directory]
2025-11-21 07:48:16.639461 | controller | changed:
2025-11-21 07:48:16.640006 | controller | {
2025-11-21 07:48:16.640072 | controller |   "atime": 1743544925.4788878,
2025-11-21 07:48:16.640122 | controller |   "ctime": 1743545329.1409318,
2025-11-21 07:48:16.640162 | controller |   "dev": 64513,
2025-11-21 07:48:16.640200 | controller |   "gid": 1000,
2025-11-21 07:48:16.640236 | controller |   "gr_name": "zuul",
2025-11-21 07:48:16.640273 | controller |   "inode": 4518807,
2025-11-21 07:48:16.640343 | controller |   "isblk": false,
2025-11-21 07:48:16.640396 | controller |   "ischr": false,
2025-11-21 07:48:16.640432 | controller |   "isdir": false,
2025-11-21 07:48:16.640467 | controller |   "isfifo": false,
2025-11-21 07:48:16.640501 | controller |   "isgid": false,
2025-11-21 07:48:16.640535 | controller |   "islnk": false,
2025-11-21 07:48:16.640568 | controller |   "isreg": true,
2025-11-21 07:48:16.640601 | controller |   "issock": false,
2025-11-21 07:48:16.640635 | controller |   "isuid": false,
2025-11-21 07:48:16.640668 | controller |   "mode": "0644",
2025-11-21 07:48:16.640745 | controller |   "mtime": 1743545329.1409318,
2025-11-21 07:48:16.640781 | controller |   "nlink": 1,
2025-11-21 07:48:16.640815 | controller |   "path": "/home/zuul/crc-setup.log",
2025-11-21 07:48:16.640856 | controller |   "pw_name": "zuul",
2025-11-21 07:48:16.640891 | controller |   "rgrp": true,
2025-11-21 07:48:16.640926 | controller |   "roth": true,
2025-11-21 07:48:16.640961 | controller |   "rusr": true,
2025-11-21 07:48:16.640996 | controller |   "size": 4108,
2025-11-21 07:48:16.641031 | controller |   "uid": 1000,
2025-11-21 07:48:16.641066 | controller |   "wgrp": false,
2025-11-21 07:48:16.641100 | controller |   "woth": false,
2025-11-21 07:48:16.641140 | controller |   "wusr": true,
2025-11-21 07:48:16.641178 | controller |   "xgrp": false,
2025-11-21 07:48:16.641213 | controller |   "xoth": false,
2025-11-21 07:48:16.641249 | controller |   "xusr": false
2025-11-21 07:48:16.641284 | controller | }
2025-11-21 07:48:17.442775 | controller | changed:
2025-11-21 07:48:17.442928 | controller | {
2025-11-21 07:48:17.442977 | controller |   "atime": 1763710068.7421403,
2025-11-21 07:48:17.443018 | controller |   "ctime": 1763710066.777083,
2025-11-21 07:48:17.443057 | controller |   "dev": 64513,
2025-11-21 07:48:17.443094 | controller |   "gid": 1000,
2025-11-21 07:48:17.443130 | controller |   "gr_name": "zuul",
2025-11-21 07:48:17.443165 | controller |   "inode": 4194437,
2025-11-21 07:48:17.443200 | controller |   "isblk": false,
2025-11-21 07:48:17.443237 | controller |   "ischr": false,
2025-11-21 07:48:17.443273 | controller |   "isdir": false,
2025-11-21 07:48:17.443307 | controller |   "isfifo": false,
2025-11-21 07:48:17.443341 | controller |   "isgid": false,
2025-11-21 07:48:17.443374 | controller |   "islnk": false,
2025-11-21 07:48:17.443408 | controller |   "isreg": true,
2025-11-21 07:48:17.443444 | controller |   "issock": false,
2025-11-21 07:48:17.443477 | controller |   "isuid": false,
2025-11-21 07:48:17.443511 | controller |   "mode": "0644",
2025-11-21 07:48:17.443546 | controller |   "mtime": 1763710066.777083,
2025-11-21 07:48:17.443580 | controller |   "nlink": 1,
2025-11-21 07:48:17.443616 | controller |   "path": "/home/zuul/crc-start.log",
2025-11-21 07:48:17.443652 | controller |   "pw_name": "zuul",
2025-11-21 07:48:17.443750 | controller |   "rgrp": true,
2025-11-21 07:48:17.443791 | controller |   "roth": true,
2025-11-21 07:48:17.443827 | controller |   "rusr": true,
2025-11-21 07:48:17.443862 | controller |   "size": 3404,
2025-11-21 07:48:17.443926 | controller |   "uid": 1000,
2025-11-21 07:48:17.443966 | controller |   "wgrp": false,
2025-11-21 07:48:17.444001 | controller |   "woth": false,
2025-11-21 07:48:17.444036 | controller |   "wusr": true,
2025-11-21 07:48:17.444071 | controller |   "xgrp": false,
2025-11-21 07:48:17.444106 | controller |   "xoth": false,
2025-11-21 07:48:17.444141 | controller |   "xusr": false
2025-11-21 07:48:17.444176 | controller | }
2025-11-21 07:48:18.227879 | controller | changed:
2025-11-21 07:48:18.228031 | controller | {
2025-11-21 07:48:18.228081 | controller |   "atime": 1763710769.0081394,
2025-11-21 07:48:18.228122 | controller |   "ctime": 1763710789.419719,
2025-11-21 07:48:18.228160 | controller |   "dev": 64513,
2025-11-21 07:48:18.228196 | controller |   "gid": 1000,
2025-11-21 07:48:18.228231 | controller |   "gr_name": "zuul",
2025-11-21 07:48:18.228266 | controller |   "inode": 4685807,
2025-11-21 07:48:18.228300 | controller |   "isblk": false,
2025-11-21 07:48:18.228344 | controller |   "ischr": false,
2025-11-21 07:48:18.228379 | controller |   "isdir": false,
2025-11-21 07:48:18.228412 | controller |   "isfifo": false,
2025-11-21 07:48:18.228444 | controller |   "isgid": false,
2025-11-21 07:48:18.228477 | controller |   "islnk": false,
2025-11-21 07:48:18.228511 | controller |   "isreg": true,
2025-11-21 07:48:18.228545 | controller |   "issock": false,
2025-11-21 07:48:18.228579 | controller |   "isuid": false,
2025-11-21 07:48:18.228612 | controller |   "mode": "0644",
2025-11-21 07:48:18.228646 | controller |   "mtime": 1763710789.419719,
2025-11-21 07:48:18.228732 | controller |   "nlink": 1,
2025-11-21 07:48:18.228781 | controller |   "path": "/home/zuul/ansible.log",
2025-11-21 07:48:18.228819 | controller |   "pw_name": "zuul",
2025-11-21 07:48:18.228854 | controller |   "rgrp": true,
2025-11-21 07:48:18.228889 | controller |   "roth": true,
2025-11-21 07:48:18.228929 | controller |   "rusr": true,
2025-11-21 07:48:18.228966 | controller |   "size": 6749,
2025-11-21 07:48:18.229001 | controller |   "uid": 1000,
2025-11-21 07:48:18.229035 | controller |   "wgrp": false,
2025-11-21 07:48:18.229069 | controller |   "woth": false,
2025-11-21 07:48:18.229104 | controller |   "wusr": true,
2025-11-21 07:48:18.229138 | controller |   "xgrp": false,
2025-11-21 07:48:18.229172 | controller |   "xoth": false,
2025-11-21 07:48:18.229206 | controller |   "xusr": false
2025-11-21 07:48:18.229239 | controller | }
2025-11-21 07:48:18.251563 | 
2025-11-21 07:48:18.251802 | TASK [Copy crio stats log file]
2025-11-21 07:48:18.279537 | controller | skipping: Conditional result was False
2025-11-21 07:48:18.293299 | 
2025-11-21 07:48:18.293431 | TASK [Get SELinux related data]
2025-11-21 07:48:18.646356 | controller | <no matches>
2025-11-21 07:48:18.843530 | controller | ERROR
2025-11-21 07:48:18.844043 | controller | {
2025-11-21 07:48:18.844119 | controller |   "delta": "0:00:00.015120",
2025-11-21 07:48:18.844165 | controller |   "end": "2025-11-21 07:48:18.650589",
2025-11-21 07:48:18.844207 | controller |   "msg": "non-zero return code",
2025-11-21 07:48:18.844245 | controller |   "rc": 1,
2025-11-21 07:48:18.844283 | controller |   "start": "2025-11-21 07:48:18.635469"
2025-11-21 07:48:18.844318 | controller | }
2025-11-21 07:48:18.844369 | controller | ERROR: Ignoring Errors
2025-11-21 07:48:18.858011 | 
2025-11-21 07:48:18.858148 | TASK [Create system configuration directory]
2025-11-21 07:48:19.223239 | controller | changed
2025-11-21 07:48:19.234637 | 
2025-11-21 07:48:19.234792 | TASK [Get some of the system configurations]
2025-11-21 07:48:19.783321 | controller | changed
2025-11-21 07:48:19.795517 | 
2025-11-21 07:48:19.795652 | TASK [Copy generated documentation if available]
2025-11-21 07:48:19.822995 | controller | skipping: Conditional result was False
2025-11-21 07:48:19.835932 | 
2025-11-21 07:48:19.836067 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-21 07:48:19.862937 | controller | skipping: Conditional result was False
2025-11-21 07:48:19.875420 | 
2025-11-21 07:48:19.875551 | TASK [Compress logs bigger than 2MB]
2025-11-21 07:48:20.424462 | controller | changed
2025-11-21 07:48:20.436133 | 
2025-11-21 07:48:20.436263 | TASK [Copy files from workspace on node]
2025-11-21 07:48:20.465879 | controller | ok
2025-11-21 07:48:20.516879 | 
2025-11-21 07:48:20.517006 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-21 07:48:20.553002 | controller | skipping: Conditional result was False
2025-11-21 07:48:20.561177 | 
2025-11-21 07:48:20.561266 | TASK [fetch-output : Set log path for single node]
2025-11-21 07:48:20.603355 | controller | ok
2025-11-21 07:48:20.610734 | 
2025-11-21 07:48:20.610827 | LOOP [fetch-output : Ensure local output dirs]
2025-11-21 07:48:20.917704 | controller -> localhost | ok: "/var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/logs"
2025-11-21 07:48:20.917949 | controller -> localhost | changed: All items complete
2025-11-21 07:48:20.917976 | 
2025-11-21 07:48:21.167439 | controller -> localhost | changed: "/var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/artifacts"
2025-11-21 07:48:21.399317 | controller -> localhost | changed: "/var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/docs"
2025-11-21 07:48:21.415600 | 
2025-11-21 07:48:21.415737 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-21 07:48:22.227529 | controller | changed:
2025-11-21 07:48:22.227728 | controller | .d..t...... ./
2025-11-21 07:48:22.227756 | controller | >f+++++++++ README.html
2025-11-21 07:48:22.227777 | controller | >f+++++++++ ansible-execution.log
2025-11-21 07:48:22.227796 | controller | >f+++++++++ ansible.log
2025-11-21 07:48:22.227814 | controller | >f+++++++++ crc-setup.log
2025-11-21 07:48:22.227832 | controller | >f+++++++++ crc-start.log
2025-11-21 07:48:22.227848 | controller | >f+++++++++ dmesg.log
2025-11-21 07:48:22.227869 | controller | >f+++++++++ installed-pkgs.log
2025-11-21 07:48:22.227886 | controller | >f+++++++++ python.log
2025-11-21 07:48:22.227902 | controller | >f+++++++++ registries.conf
2025-11-21 07:48:22.227918 | controller | >f+++++++++ report.html
2025-11-21 07:48:22.227935 | controller | >f+++++++++ selinux-denials.log
2025-11-21 07:48:22.227950 | controller | >f+++++++++ selinux-listing.log
2025-11-21 07:48:22.227966 | controller | cd+++++++++ ci-framework-data/
2025-11-21 07:48:22.227982 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-21 07:48:22.227999 | controller | >f+++++++++ ci-framework-data/artifacts/networking-mapper-instances-facts.yml
2025-11-21 07:48:22.228016 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-21 07:48:22.228032 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-11-21 07:48:22.228048 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/nmstate/
2025-11-21 07:48:22.228064 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/crc-nncp.yaml
2025-11-21 07:48:22.228080 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-nmstate-olm.yaml
2025-11-21 07:48:22.228096 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-operatorgroup-olm.yaml
2025-11-21 07:48:22.228112 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-subscription-olm.yaml
2025-11-21 07:48:22.228128 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-21 07:48:22.228144 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-21 07:48:22.228161 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-21 07:48:22.228178 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-21 07:48:22.228194 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-21 07:48:22.228210 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-21 07:48:22.228227 | controller | cd+++++++++ registries.conf.d/
2025-11-21 07:48:22.228243 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-21 07:48:22.228259 | controller | cd+++++++++ system-config/
2025-11-21 07:48:22.228275 | controller | cd+++++++++ system-config/libvirt/
2025-11-21 07:48:22.228291 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-21 07:48:22.228307 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-21 07:48:22.228323 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-11-21 07:48:22.228339 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-11-21 07:48:22.228356 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-11-21 07:48:22.228372 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-11-21 07:48:22.228388 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-11-21 07:48:22.228404 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-11-21 07:48:22.228419 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-11-21 07:48:22.228442 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-11-21 07:48:22.228461 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-11-21 07:48:22.228479 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-11-21 07:48:22.228495 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-11-21 07:48:22.228511 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-11-21 07:48:22.228527 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-11-21 07:48:22.228542 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-11-21 07:48:22.826023 | controller | changed: .d..t...... ./
2025-11-21 07:48:23.456302 | controller | changed: .d..t...... ./
2025-11-21 07:48:23.489591 | 
2025-11-21 07:48:23.489844 | TASK [Return artifact to Zuul]
2025-11-21 07:48:23.548458 | controller | ok
2025-11-21 07:48:23.586541 | 
2025-11-21 07:48:23.586616 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-21 07:48:23.586779 | 
2025-11-21 07:48:23.586823 | PLAY RECAP
2025-11-21 07:48:23.586878 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-21 07:48:23.586903 | 
2025-11-21 07:48:23.702589 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-21 07:48:23.704117 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-11-21 07:48:24.356294 | 
2025-11-21 07:48:24.356407 | PLAY [Report DLRN results after job run]
2025-11-21 07:48:24.377625 | 
2025-11-21 07:48:24.377740 | TASK [Report job status to DLRN]
2025-11-21 07:48:24.398257 | controller | ok
2025-11-21 07:48:24.423765 | 
2025-11-21 07:48:24.423875 | TASK [dlrn_report : Warning if DLRN is not running]
2025-11-21 07:48:24.489221 | controller | skipping: Conditional result was False
2025-11-21 07:48:24.502864 | 
2025-11-21 07:48:24.503025 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-11-21 07:48:24.560507 | controller | skipping: Conditional result was False
2025-11-21 07:48:24.573989 | 
2025-11-21 07:48:24.574148 | TASK [dlrn_report : Install kinit related package]
2025-11-21 07:48:24.631534 | controller | skipping: Conditional result was False
2025-11-21 07:48:24.643981 | 
2025-11-21 07:48:24.644140 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-11-21 07:48:24.701826 | controller | skipping: Conditional result was False
2025-11-21 07:48:24.716599 | 
2025-11-21 07:48:24.716799 | TASK [Set zuul-log-path fact]
2025-11-21 07:48:24.774220 | controller | skipping: Conditional result was False
2025-11-21 07:48:24.787302 | 
2025-11-21 07:48:24.787502 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-11-21 07:48:24.844909 | controller | skipping: Conditional result was False
2025-11-21 07:48:24.857145 | 
2025-11-21 07:48:24.857296 | TASK [Get hash related data from repo_setup role]
2025-11-21 07:48:24.914840 | controller | skipping: Conditional result was False
2025-11-21 07:48:24.928243 | 
2025-11-21 07:48:24.928417 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-11-21 07:48:25.478436 | controller | skipping: Conditional result was False
2025-11-21 07:48:25.492284 | 
2025-11-21 07:48:25.492462 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-11-21 07:48:25.570271 | controller | skipping: Conditional result was False
2025-11-21 07:48:25.582874 | 
2025-11-21 07:48:25.583020 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-11-21 07:48:26.134616 | controller | skipping: Conditional result was False
2025-11-21 07:48:26.186439 | 
2025-11-21 07:48:26.186546 | PLAY RECAP
2025-11-21 07:48:26.186606 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-11-21 07:48:26.186635 | 
2025-11-21 07:48:26.283984 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-11-21 07:48:26.285541 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-21 07:48:26.979639 | 
2025-11-21 07:48:26.979781 | PLAY [all]
2025-11-21 07:48:27.002945 | 
2025-11-21 07:48:27.003068 | TASK [include_role : fetch-output]
2025-11-21 07:48:27.036909 | controller | ok
2025-11-21 07:48:27.057981 | 
2025-11-21 07:48:27.058108 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-21 07:48:27.112720 | controller | skipping: Conditional result was False
2025-11-21 07:48:27.119827 | 
2025-11-21 07:48:27.119904 | TASK [fetch-output : Set log path for single node]
2025-11-21 07:48:27.160756 | controller | ok
2025-11-21 07:48:27.167469 | 
2025-11-21 07:48:27.167619 | LOOP [fetch-output : Ensure local output dirs]
2025-11-21 07:48:27.600208 | controller -> localhost | ok: "/var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/logs"
2025-11-21 07:48:27.885034 | controller -> localhost | ok: "/var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/artifacts"
2025-11-21 07:48:28.151383 | controller -> localhost | ok: "/var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/docs"
2025-11-21 07:48:28.163516 | 
2025-11-21 07:48:28.163642 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-21 07:48:28.983733 | controller | ok
2025-11-21 07:48:28.984071 | controller | ok: All items complete
2025-11-21 07:48:28.984130 | 
2025-11-21 07:48:29.621431 | controller | ok
2025-11-21 07:48:30.242786 | controller | ok
2025-11-21 07:48:30.261453 | 
2025-11-21 07:48:30.261544 | TASK [include_role : fetch-output-openshift]
2025-11-21 07:48:30.285188 | controller | skipping: Conditional result was False
2025-11-21 07:48:30.291256 | 
2025-11-21 07:48:30.291325 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-21 07:48:30.731393 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013056
2025-11-21 07:48:31.009515 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013138
2025-11-21 07:48:31.036223 | 
2025-11-21 07:48:31.036313 | PLAY [all]
2025-11-21 07:48:31.051757 | 
2025-11-21 07:48:31.051824 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-21 07:48:31.666507 | controller | changed
2025-11-21 07:48:31.712006 | 
2025-11-21 07:48:31.712079 | PLAY RECAP
2025-11-21 07:48:31.712134 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-21 07:48:31.712161 | 
2025-11-21 07:48:31.813074 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-21 07:48:31.814711 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-21 07:48:32.441761 | 
2025-11-21 07:48:32.441877 | PLAY [localhost]
2025-11-21 07:48:32.459577 | 
2025-11-21 07:48:32.459667 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-21 07:48:32.900802 | localhost | changed
2025-11-21 07:48:32.912358 | 
2025-11-21 07:48:32.912526 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-21 07:48:32.951824 | localhost | ok
2025-11-21 07:48:32.972235 | 
2025-11-21 07:48:32.972385 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-21 07:48:33.366605 | localhost | changed
2025-11-21 07:48:33.377536 | 
2025-11-21 07:48:33.377747 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-21 07:48:34.134990 | localhost | changed
2025-11-21 07:48:34.147463 | 
2025-11-21 07:48:34.147752 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-21 07:48:34.613043 | localhost | Identity added: /var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/tmp/ansible._zdn2hz5 (/var/lib/zuul/builds/ddeb168632cb4c05a5c5838fd7ef690b/work/tmp/ansible._zdn2hz5)
2025-11-21 07:48:34.613351 | localhost | ok: Runtime: 0:00:00.013059
2025-11-21 07:48:34.623646 | 
2025-11-21 07:48:34.623826 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-21 07:48:34.923981 | localhost | ok: Runtime: 0:00:00.008918
2025-11-21 07:48:34.935838 | 
2025-11-21 07:48:34.935987 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-21 07:48:35.002145 | localhost | changed
2025-11-21 07:48:35.011483 | 
2025-11-21 07:48:35.011617 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-21 07:48:35.435051 | localhost | changed
2025-11-21 07:48:35.476374 | 
2025-11-21 07:48:35.476457 | PLAY [localhost]
2025-11-21 07:48:35.493173 | 
2025-11-21 07:48:35.493255 | TASK [Generate bulk log download script]
2025-11-21 07:48:35.514548 | localhost | ok
2025-11-21 07:48:35.530715 | 
2025-11-21 07:48:35.530813 | TASK [local-log-download : Check API endpoint is defined]
2025-11-21 07:48:35.572061 | localhost | ok: All assertions passed
2025-11-21 07:48:35.578487 | 
2025-11-21 07:48:35.578574 | TASK [local-log-download : Create download script]
2025-11-21 07:48:36.050401 | localhost -> localhost | changed
2025-11-21 07:48:36.059741 | 
2025-11-21 07:48:36.059808 | TASK [Register quick-download link]
2025-11-21 07:48:36.088522 | localhost | ok
2025-11-21 07:48:36.129439 | 
2025-11-21 07:48:36.129548 | PLAY [logserver.rdoproject.org]
2025-11-21 07:48:36.139506 | 
2025-11-21 07:48:36.139578 | TASK [Set zuul-log-path fact]
2025-11-21 07:48:36.157224 | logserver.rdoproject.org | ok
2025-11-21 07:48:36.166287 | 
2025-11-21 07:48:36.166351 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-21 07:48:36.203258 | logserver.rdoproject.org | ok
2025-11-21 07:48:36.208551 | 
2025-11-21 07:48:36.208614 | TASK [upload-logs : Create log directories]
2025-11-21 07:48:37.063954 | logserver.rdoproject.org | changed
2025-11-21 07:48:37.070859 | 
2025-11-21 07:48:37.070993 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-21 07:48:37.372095 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009011
2025-11-21 07:48:37.376854 | 
2025-11-21 07:48:37.376920 | TASK [upload-logs : Upload logs to log server]
2025-11-21 07:48:38.107574 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-21 07:48:38.114092 | 
2025-11-21 07:48:38.114226 | LOOP [upload-logs : Compress console log and json output]
2025-11-21 07:48:38.184726 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 07:48:38.199365 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 07:48:38.210083 | 
2025-11-21 07:48:38.210263 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-21 07:48:38.278333 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 07:48:38.278995 | 
2025-11-21 07:48:38.283440 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 07:48:38.296327 | 
2025-11-21 07:48:38.296512 | LOOP [upload-logs : Upload console log and json output]