2025-10-03 04:00:18.091864 | Job console starting...
2025-10-03 04:00:18.107188 | Updating repositories
2025-10-03 04:00:18.148861 | Preparing job workspace
2025-10-03 04:00:24.288728 | Running Ansible setup...
2025-10-03 04:00:31.488310 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 04:00:32.106390 | 
2025-10-03 04:00:32.106585 | PLAY [localhost]
2025-10-03 04:00:32.117805 | 
2025-10-03 04:00:32.117984 | TASK [Gathering Facts]
2025-10-03 04:00:33.125469 | localhost | ok
2025-10-03 04:00:33.155602 | 
2025-10-03 04:00:33.155785 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-03 04:00:33.665886 | localhost -> localhost | changed
2025-10-03 04:00:33.672319 | 
2025-10-03 04:00:33.672397 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-03 04:00:34.668428 | localhost -> localhost | changed
2025-10-03 04:00:34.677407 | 
2025-10-03 04:00:34.677535 | TASK [Setup log path fact]
2025-10-03 04:00:34.711292 | localhost | ok
2025-10-03 04:00:34.724417 | 
2025-10-03 04:00:34.724580 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 04:00:34.753972 | localhost | ok
2025-10-03 04:00:34.764376 | 
2025-10-03 04:00:34.764530 | TASK [emit-job-header : Print job information]
2025-10-03 04:00:34.804779 | # Job Information
2025-10-03 04:00:34.804956 | Ansible Version: 2.15.12
2025-10-03 04:00:34.804982 | Job: cifmw-molecule-ci_nmstate
2025-10-03 04:00:34.805003 | Pipeline: github-check
2025-10-03 04:00:34.805065 | Executor: ze01.softwarefactory-project.io
2025-10-03 04:00:34.805086 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-03 04:00:34.805106 | Log URL (when completed): https://logserver.rdoproject.org/a96/rdoproject.org/a9648dd01c7b469fa811929128d5a755/
2025-10-03 04:00:34.805125 | Event ID: 3daecbd0-a00d-11f0-93f4-31ce34c57105
2025-10-03 04:00:34.808777 | 
2025-10-03 04:00:34.808836 | LOOP [emit-job-header : Print node information]
2025-10-03 04:00:34.905712 | localhost | ok:
2025-10-03 04:00:34.905901 | localhost | # Node Information
2025-10-03 04:00:34.905928 | localhost | Inventory Hostname: controller
2025-10-03 04:00:34.905950 | localhost | Hostname: np0005467686
2025-10-03 04:00:34.905969 | localhost | Username: zuul
2025-10-03 04:00:34.905989 | localhost | Distro: CentOS 9
2025-10-03 04:00:34.906006 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-03 04:00:34.906050 | localhost | Region: RegionOne
2025-10-03 04:00:34.906067 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-10-03 04:00:34.906084 | localhost | Product Name: OpenStack Nova
2025-10-03 04:00:34.906100 | localhost | Interface IP: 38.102.83.45
2025-10-03 04:00:34.935226 | 
2025-10-03 04:00:34.935351 | PLAY [all]
2025-10-03 04:00:34.941678 | 
2025-10-03 04:00:34.941743 | TASK [Gather network facts]
2025-10-03 04:00:35.575387 | controller | ok
2025-10-03 04:00:35.602488 | 
2025-10-03 04:00:35.602733 | TASK [include_role : start-zuul-console]
2025-10-03 04:00:35.628627 | controller | ok
2025-10-03 04:00:35.650941 | 
2025-10-03 04:00:35.651189 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-03 04:00:36.230490 | controller | ok
2025-10-03 04:00:36.261432 | 
2025-10-03 04:00:36.261601 | TASK [include_role : add-build-sshkey]
2025-10-03 04:00:36.295473 | controller | ok
2025-10-03 04:00:36.333544 | 
2025-10-03 04:00:36.333670 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-03 04:00:36.584264 | controller -> localhost | ok
2025-10-03 04:00:36.590566 | 
2025-10-03 04:00:36.590663 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-03 04:00:36.621612 | controller | ok
2025-10-03 04:00:36.635515 | controller | included: /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-03 04:00:36.642289 | 
2025-10-03 04:00:36.642351 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-03 04:00:37.560658 | controller -> localhost | Generating public/private rsa key pair.
2025-10-03 04:00:37.560895 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/a9648dd01c7b469fa811929128d5a755_id_rsa.
2025-10-03 04:00:37.560928 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/a9648dd01c7b469fa811929128d5a755_id_rsa.pub.
2025-10-03 04:00:37.560950 | controller -> localhost | The key fingerprint is:
2025-10-03 04:00:37.560970 | controller -> localhost | SHA256:rfe8vrZlZcZZUXqkIc7qD2HMHyrNQEgkkrVcJ6HOcTE zuul-build-sshkey
2025-10-03 04:00:37.560992 | controller -> localhost | The key's randomart image is:
2025-10-03 04:00:37.561035 | controller -> localhost | +---[RSA 3072]----+
2025-10-03 04:00:37.561075 | controller -> localhost | |  .oo.E..   . ..+|
2025-10-03 04:00:37.561095 | controller -> localhost | |  .o * *   o . =.|
2025-10-03 04:00:37.561114 | controller -> localhost | |    = o .   o o o|
2025-10-03 04:00:37.561132 | controller -> localhost | |   o o . + .   oo|
2025-10-03 04:00:37.561149 | controller -> localhost | |    o   S B .  .=|
2025-10-03 04:00:37.561166 | controller -> localhost | |         B + . + |
2025-10-03 04:00:37.561183 | controller -> localhost | |        o B . o  |
2025-10-03 04:00:37.561200 | controller -> localhost | |         o =.o   |
2025-10-03 04:00:37.561217 | controller -> localhost | |           oO+   |
2025-10-03 04:00:37.561234 | controller -> localhost | +----[SHA256]-----+
2025-10-03 04:00:37.561288 | controller -> localhost | ok: Runtime: 0:00:00.475027
2025-10-03 04:00:37.567620 | 
2025-10-03 04:00:37.567684 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-03 04:00:37.599851 | controller | ok
2025-10-03 04:00:37.615873 | controller | included: /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-03 04:00:37.627508 | 
2025-10-03 04:00:37.627593 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-03 04:00:37.653330 | controller | skipping: Conditional result was False
2025-10-03 04:00:37.664171 | 
2025-10-03 04:00:37.664252 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-03 04:00:38.336341 | controller | changed
2025-10-03 04:00:38.344301 | 
2025-10-03 04:00:38.344479 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-03 04:00:38.721731 | controller | ok
2025-10-03 04:00:38.734471 | 
2025-10-03 04:00:38.734637 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-03 04:00:40.503253 | controller | changed
2025-10-03 04:00:40.509212 | 
2025-10-03 04:00:40.509296 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-03 04:00:42.232282 | controller | changed
2025-10-03 04:00:42.244071 | 
2025-10-03 04:00:42.244256 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-03 04:00:42.271610 | controller | skipping: Conditional result was False
2025-10-03 04:00:42.283826 | 
2025-10-03 04:00:42.283984 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-03 04:00:42.709581 | controller -> localhost | changed
2025-10-03 04:00:42.720607 | 
2025-10-03 04:00:42.720679 | TASK [add-build-sshkey : Add back temp key]
2025-10-03 04:00:43.036680 | controller -> localhost | Identity added: /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/a9648dd01c7b469fa811929128d5a755_id_rsa (zuul-build-sshkey)
2025-10-03 04:00:43.037058 | controller -> localhost | ok: Runtime: 0:00:00.018735
2025-10-03 04:00:43.053745 | 
2025-10-03 04:00:43.053938 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-03 04:00:43.523188 | controller | ok
2025-10-03 04:00:43.533490 | 
2025-10-03 04:00:43.533618 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-03 04:00:43.561468 | controller | skipping: Conditional result was False
2025-10-03 04:00:43.622405 | 
2025-10-03 04:00:43.622569 | TASK [include_role : validate-host]
2025-10-03 04:00:43.647842 | controller | ok
2025-10-03 04:00:43.678714 | 
2025-10-03 04:00:43.678936 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-03 04:00:43.734261 | controller | ok
2025-10-03 04:00:43.743805 | 
2025-10-03 04:00:43.743928 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-03 04:00:43.995202 | controller -> localhost | ok
2025-10-03 04:00:44.001581 | 
2025-10-03 04:00:44.001668 | TASK [validate-host : Collect information about the host]
2025-10-03 04:00:44.934221 | controller | ok
2025-10-03 04:00:44.952662 | 
2025-10-03 04:00:44.952808 | TASK [validate-host : Sanitize hostname]
2025-10-03 04:00:45.039637 | controller | ok
2025-10-03 04:00:45.052073 | 
2025-10-03 04:00:45.052244 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-03 04:00:45.584041 | controller -> localhost | changed
2025-10-03 04:00:45.595880 | 
2025-10-03 04:00:45.595971 | TASK [validate-host : Collect information about zuul worker]
2025-10-03 04:00:46.159730 | controller | ok
2025-10-03 04:00:46.171107 | 
2025-10-03 04:00:46.171249 | TASK [validate-host : Write out all zuul information for each host]
2025-10-03 04:00:46.710703 | controller -> localhost | changed
2025-10-03 04:00:46.721315 | 
2025-10-03 04:00:46.721384 | TASK [include_role : prepare-workspace-openshift]
2025-10-03 04:00:46.746622 | controller | skipping: Conditional result was False
2025-10-03 04:00:46.752231 | 
2025-10-03 04:00:46.752297 | TASK [include_role : remove-zuul-sshkey]
2025-10-03 04:00:46.776090 | controller | skipping: Conditional result was False
2025-10-03 04:00:46.781739 | 
2025-10-03 04:00:46.781806 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 04:00:47.185359 | controller | ok: "logs"
2025-10-03 04:00:47.185635 | controller | ok: All items complete
2025-10-03 04:00:47.185664 | 
2025-10-03 04:00:47.549328 | controller | ok: "artifacts"
2025-10-03 04:00:47.862444 | controller | ok: "docs"
2025-10-03 04:00:47.871858 | 
2025-10-03 04:00:47.871924 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 04:00:48.299165 | controller | changed: "logs"
2025-10-03 04:00:48.702979 | controller | changed: "artifacts"
2025-10-03 04:00:49.049341 | controller | changed: "docs"
2025-10-03 04:00:49.095993 | 
2025-10-03 04:00:49.096219 | PLAY RECAP
2025-10-03 04:00:49.096286 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:00:49.096324 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:00:49.096350 | 
2025-10-03 04:00:49.291109 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 04:00:49.292680 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:00:49.927447 | 
2025-10-03 04:00:49.927549 | PLAY [all]
2025-10-03 04:00:49.948171 | 
2025-10-03 04:00:49.948253 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:00:49.999399 | controller | ok
2025-10-03 04:00:50.009248 | 
2025-10-03 04:00:50.009370 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:00:50.628849 | controller | changed
2025-10-03 04:00:50.642118 | 
2025-10-03 04:00:50.642272 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:00:52.748305 | controller | changed
2025-10-03 04:00:52.771156 | 
2025-10-03 04:00:52.771264 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-03 04:00:53.396961 | controller | changed:
2025-10-03 04:00:53.397263 | controller | {
2025-10-03 04:00:53.397306 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-03 04:00:53.397354 | controller | }
2025-10-03 04:00:53.833612 | controller | changed:
2025-10-03 04:00:53.833780 | controller | {
2025-10-03 04:00:53.833844 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-03 04:00:53.833889 | controller | }
2025-10-03 04:00:54.251107 | controller | changed:
2025-10-03 04:00:54.251285 | controller | {
2025-10-03 04:00:54.251324 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-03 04:00:54.251355 | controller | }
2025-10-03 04:00:54.637989 | controller | changed:
2025-10-03 04:00:54.638270 | controller | {
2025-10-03 04:00:54.638306 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-03 04:00:54.638332 | controller | }
2025-10-03 04:00:55.030398 | controller | changed:
2025-10-03 04:00:55.030570 | controller | {
2025-10-03 04:00:55.030622 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-03 04:00:55.030663 | controller | }
2025-10-03 04:00:55.496612 | controller | changed:
2025-10-03 04:00:55.496750 | controller | {
2025-10-03 04:00:55.496786 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-03 04:00:55.496815 | controller | }
2025-10-03 04:00:55.882957 | controller | changed:
2025-10-03 04:00:55.883133 | controller | {
2025-10-03 04:00:55.883188 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-03 04:00:55.883232 | controller | }
2025-10-03 04:00:56.325671 | controller | changed:
2025-10-03 04:00:56.325865 | controller | {
2025-10-03 04:00:56.325934 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-03 04:00:56.325972 | controller | }
2025-10-03 04:00:56.755266 | controller | changed:
2025-10-03 04:00:56.755431 | controller | {
2025-10-03 04:00:56.755464 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-03 04:00:56.755490 | controller | }
2025-10-03 04:00:57.177692 | controller | changed:
2025-10-03 04:00:57.179217 | controller | {
2025-10-03 04:00:57.179305 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-03 04:00:57.179351 | controller | }
2025-10-03 04:00:57.623119 | controller | changed:
2025-10-03 04:00:57.623299 | controller | {
2025-10-03 04:00:57.623367 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-03 04:00:57.623418 | controller | }
2025-10-03 04:00:58.032398 | controller | changed:
2025-10-03 04:00:58.032581 | controller | {
2025-10-03 04:00:58.032634 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-03 04:00:58.032753 | controller | }
2025-10-03 04:00:58.417324 | controller | changed:
2025-10-03 04:00:58.417508 | controller | {
2025-10-03 04:00:58.417559 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-03 04:00:58.417598 | controller | }
2025-10-03 04:00:58.864871 | controller | changed:
2025-10-03 04:00:58.865084 | controller | {
2025-10-03 04:00:58.865144 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-03 04:00:58.865184 | controller | }
2025-10-03 04:00:59.266909 | controller | changed:
2025-10-03 04:00:59.267104 | controller | {
2025-10-03 04:00:59.267139 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-03 04:00:59.267165 | controller | }
2025-10-03 04:00:59.710591 | controller | changed:
2025-10-03 04:00:59.710802 | controller | {
2025-10-03 04:00:59.710867 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-03 04:00:59.710917 | controller | }
2025-10-03 04:01:00.132997 | controller | changed:
2025-10-03 04:01:00.133213 | controller | {
2025-10-03 04:01:00.133262 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-03 04:01:00.133302 | controller | }
2025-10-03 04:01:00.548150 | controller | changed:
2025-10-03 04:01:00.548352 | controller | {
2025-10-03 04:01:00.548409 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-03 04:01:00.548451 | controller | }
2025-10-03 04:01:00.944701 | controller | changed:
2025-10-03 04:01:00.944888 | controller | {
2025-10-03 04:01:00.944923 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-03 04:01:00.944950 | controller | }
2025-10-03 04:01:01.358249 | controller | changed:
2025-10-03 04:01:01.358393 | controller | {
2025-10-03 04:01:01.358417 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-03 04:01:01.358435 | controller | }
2025-10-03 04:01:01.771834 | controller | changed:
2025-10-03 04:01:01.771951 | controller | {
2025-10-03 04:01:01.771974 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-03 04:01:01.771992 | controller | }
2025-10-03 04:01:02.166678 | controller | changed:
2025-10-03 04:01:02.166803 | controller | {
2025-10-03 04:01:02.166827 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-03 04:01:02.166846 | controller | }
2025-10-03 04:01:02.615739 | controller | changed:
2025-10-03 04:01:02.615877 | controller | {
2025-10-03 04:01:02.615900 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-03 04:01:02.615919 | controller | }
2025-10-03 04:01:03.010075 | controller | changed:
2025-10-03 04:01:03.010130 | controller | {
2025-10-03 04:01:03.010153 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-03 04:01:03.010170 | controller | }
2025-10-03 04:01:03.407480 | controller | changed:
2025-10-03 04:01:03.407589 | controller | {
2025-10-03 04:01:03.407613 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-03 04:01:03.407631 | controller | }
2025-10-03 04:01:03.776904 | controller | changed:
2025-10-03 04:01:03.777087 | controller | {
2025-10-03 04:01:03.777115 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-03 04:01:03.777134 | controller | }
2025-10-03 04:01:03.830739 | 
2025-10-03 04:01:03.830900 | TASK [Set timezone to UTC]
2025-10-03 04:01:04.612637 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-03 04:01:04.625782 | 
2025-10-03 04:01:04.625988 | TASK [Create nodepool directory]
2025-10-03 04:01:05.084449 | controller | changed
2025-10-03 04:01:05.099372 | 
2025-10-03 04:01:05.099592 | TASK [Create nodepool sub_nodes file]
2025-10-03 04:01:06.581377 | controller | changed
2025-10-03 04:01:06.591122 | 
2025-10-03 04:01:06.591973 | TASK [Create nodepool sub_nodes_private file]
2025-10-03 04:01:08.252467 | controller | changed
2025-10-03 04:01:08.259030 | 
2025-10-03 04:01:08.259122 | LOOP [Populate nodepool sub_nodes file]
2025-10-03 04:01:08.302140 | 
2025-10-03 04:01:08.302334 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-03 04:01:08.347221 | 
2025-10-03 04:01:08.347472 | TASK [Create nodepool primary file]
2025-10-03 04:01:08.374907 | controller | skipping: Conditional result was False
2025-10-03 04:01:08.386143 | 
2025-10-03 04:01:08.386370 | TASK [Create nodepool node_private for this node]
2025-10-03 04:01:10.033471 | controller | changed
2025-10-03 04:01:10.046827 | 
2025-10-03 04:01:10.047088 | LOOP [Copy ssh keys to nodepool directory]
2025-10-03 04:01:10.645647 | controller | ok: Item: id_rsa Runtime: 0:00:00.011112
2025-10-03 04:01:10.646127 | 
2025-10-03 04:01:10.974791 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.009240
2025-10-03 04:01:10.998622 | 
2025-10-03 04:01:10.998857 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-03 04:01:12.704876 | controller | changed
2025-10-03 04:01:12.717196 | 
2025-10-03 04:01:12.717349 | TASK [Validate sudoers config after edits]
2025-10-03 04:01:13.139804 | controller | /etc/sudoers: parsed OK
2025-10-03 04:01:13.139899 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-03 04:01:13.139915 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-03 04:01:13.139928 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-03 04:01:13.268763 | controller | ok: Runtime: 0:00:00.009187
2025-10-03 04:01:13.277980 | 
2025-10-03 04:01:13.278144 | TASK [Show the environment passed in to job shell scripts]
2025-10-03 04:01:13.650611 | controller | SHELL=/bin/bash
2025-10-03 04:01:13.650699 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-03 04:01:13.650719 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-03 04:01:13.650735 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:01:13.650752 | controller | PWD=/home/zuul
2025-10-03 04:01:13.650765 | controller | ZUUL_PIPELINE=github-check
2025-10-03 04:01:13.650781 | controller | LOGNAME=zuul
2025-10-03 04:01:13.650798 | controller | XDG_SESSION_TYPE=tty
2025-10-03 04:01:13.650815 | controller | _=/usr/bin/env
2025-10-03 04:01:13.650830 | controller | MOTD_SHOWN=pam
2025-10-03 04:01:13.650843 | controller | HOME=/home/zuul
2025-10-03 04:01:13.650856 | controller | LANG=en_US.UTF-8
2025-10-03 04:01:13.650869 | controller | SSH_CONNECTION=38.102.83.114 41818 38.102.83.45 22
2025-10-03 04:01:13.650960 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-03 04:01:13.650990 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:01:13.651009 | controller | WORKSPACE=/home/zuul/workspace
2025-10-03 04:01:13.651063 | controller | XDG_SESSION_CLASS=user
2025-10-03 04:01:13.651082 | controller | SELINUX_ROLE_REQUESTED=
2025-10-03 04:01:13.651098 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-03 04:01:13.651116 | controller | USER=zuul
2025-10-03 04:01:13.651131 | controller | ZUUL_VOTING=True
2025-10-03 04:01:13.651144 | controller | BUILD_TIMEOUT=1800000
2025-10-03 04:01:13.651157 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-03 04:01:13.651170 | controller | SHLVL=1
2025-10-03 04:01:13.651183 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:01:13.651196 | controller | XDG_SESSION_ID=1
2025-10-03 04:01:13.651209 | controller | ZUUL_BRANCH=main
2025-10-03 04:01:13.651222 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-03 04:01:13.651236 | controller | SSH_CLIENT=38.102.83.114 41818 22
2025-10-03 04:01:13.651250 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-03 04:01:13.651266 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-03 04:01:13.651283 | controller | which_declare=declare -f
2025-10-03 04:01:13.651301 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-03 04:01:13.651316 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-03 04:01:13.651329 | controller | ZUUL_CHANGE=3362
2025-10-03 04:01:13.651342 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-03 04:01:13.651355 | controller | ZUUL_UUID=a9648dd01c7b469fa811929128d5a755
2025-10-03 04:01:13.651368 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-03 04:01:13.651386 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-03 04:01:13.651399 | controller | }
2025-10-03 04:01:13.831550 | controller | ok: Runtime: 0:00:00.012088
2025-10-03 04:01:13.838556 | 
2025-10-03 04:01:13.838630 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-03 04:01:13.863462 | controller | skipping: Conditional result was False
2025-10-03 04:01:13.870301 | 
2025-10-03 04:01:13.870375 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-03 04:01:14.443406 | controller | skipping: Conditional result was False
2025-10-03 04:01:14.459615 | 
2025-10-03 04:01:14.460872 | TASK [Ensure legacy workspace directory]
2025-10-03 04:01:14.834313 | controller | changed
2025-10-03 04:01:14.870225 | 
2025-10-03 04:01:14.871232 | PLAY RECAP
2025-10-03 04:01:14.871324 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:01:14.871365 | 
2025-10-03 04:01:15.027495 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:01:15.029370 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-03 04:01:15.688808 | 
2025-10-03 04:01:15.688977 | PLAY [all,!compute]
2025-10-03 04:01:15.709982 | 
2025-10-03 04:01:15.710087 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-03 04:01:16.243410 | controller | skipping: Conditional result was False
2025-10-03 04:01:16.256282 | 
2025-10-03 04:01:16.256551 | TASK [Install podman]
2025-10-03 04:01:16.326385 | controller | skipping: Conditional result was False
2025-10-03 04:01:16.339655 | 
2025-10-03 04:01:16.339878 | TASK [Set var name for quay login zuul secret]
2025-10-03 04:01:16.401507 | controller | skipping: Conditional result was False
2025-10-03 04:01:16.413614 | 
2025-10-03 04:01:16.413757 | TASK [Print the username]
2025-10-03 04:01:16.963507 | controller | skipping: Conditional result was False
2025-10-03 04:01:16.977118 | 
2025-10-03 04:01:16.977384 | TASK [Fail when user or password is not set]
2025-10-03 04:01:17.058070 | controller | skipping: Conditional result was False
2025-10-03 04:01:17.069858 | 
2025-10-03 04:01:17.070011 | TASK [Set vars for quay login]
2025-10-03 04:01:17.142070 | 
2025-10-03 04:01:17.142347 | LOOP [Perform container registry login(s) with podman]
2025-10-03 04:01:17.202513 | 
2025-10-03 04:01:17.202796 | LOOP [Perform container registry login(s) with buildah]
2025-10-03 04:01:17.262536 | 
2025-10-03 04:01:17.262840 | TASK [Set Insecure registry for content provider]
2025-10-03 04:01:17.311113 | controller | skipping: Conditional result was False
2025-10-03 04:01:17.354091 | 
2025-10-03 04:01:17.354165 | PLAY RECAP
2025-10-03 04:01:17.354226 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-10-03 04:01:17.354255 | 
2025-10-03 04:01:17.484344 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-03 04:01:17.485976 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-03 04:01:18.130866 | 
2025-10-03 04:01:18.131007 | PLAY [Prefer ipv4 over ipv6]
2025-10-03 04:01:18.150589 | 
2025-10-03 04:01:18.150672 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-10-03 04:01:18.216672 | controller | skipping: Conditional result was False
2025-10-03 04:01:18.246826 | 
2025-10-03 04:01:18.246971 | PLAY [controller]
2025-10-03 04:01:18.268298 | 
2025-10-03 04:01:18.268429 | TASK [Fail when openshift_pull_sec not provided]
2025-10-03 04:01:18.293567 | controller | skipping: Conditional result was False
2025-10-03 04:01:18.300630 | 
2025-10-03 04:01:18.300696 | TASK [Clone repos in the job workspace]
2025-10-03 04:01:18.319990 | controller | ok
2025-10-03 04:01:18.335823 | 
2025-10-03 04:01:18.335905 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 04:01:18.833390 | controller | ok
2025-10-03 04:01:18.850175 | 
2025-10-03 04:01:18.850357 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 04:01:22.083806 | controller | Output suppressed because no_log was given
2025-10-03 04:01:22.095911 | 
2025-10-03 04:01:22.095980 | TASK [Create pull-secret.txt file]
2025-10-03 04:01:23.862384 | controller | Output suppressed because no_log was given
2025-10-03 04:01:23.873656 | 
2025-10-03 04:01:23.873834 | TASK [Apply CRC features and print image build date]
2025-10-03 04:01:23.914190 | controller | ok
2025-10-03 04:01:23.931753 | controller | included: /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-10-03 04:01:23.944214 | 
2025-10-03 04:01:23.944371 | TASK [Set additional parameters for crc - telemetry]
2025-10-03 04:01:24.485543 | controller | skipping: Conditional result was False
2025-10-03 04:01:24.492614 | 
2025-10-03 04:01:24.492710 | TASK [Set additional parameters for crc - monitoring]
2025-10-03 04:01:25.064719 | controller | skipping: Conditional result was False
2025-10-03 04:01:25.077881 | 
2025-10-03 04:01:25.078261 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-10-03 04:01:25.636284 | controller | skipping: Conditional result was False
2025-10-03 04:01:25.647624 | 
2025-10-03 04:01:25.647755 | TASK [Set additional parameters for crc - enable microshift preset]
2025-10-03 04:01:26.203343 | controller | skipping: Conditional result was False
2025-10-03 04:01:26.218228 | 
2025-10-03 04:01:26.218429 | TASK [Set additional parameters for crc - enable okd preset]
2025-10-03 04:01:26.776820 | controller | skipping: Conditional result was False
2025-10-03 04:01:26.788418 | 
2025-10-03 04:01:26.788664 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-10-03 04:01:27.328564 | controller | skipping: Conditional result was False
2025-10-03 04:01:27.335421 | 
2025-10-03 04:01:27.335495 | TASK [Execute crc start command]
2025-10-03 04:09:07.093964 | controller | ok: Runtime: 0:07:38.978047
2025-10-03 04:09:07.106723 | 
2025-10-03 04:09:07.106860 | TASK [Verify that OpenShift is up and running]
2025-10-03 04:09:07.136752 | controller | ok
2025-10-03 04:09:07.156711 | controller | included: /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-10-03 04:09:07.170638 | 
2025-10-03 04:09:07.170718 | TASK [Create script to login and verify services]
2025-10-03 04:09:08.661649 | controller | changed
2025-10-03 04:09:08.673996 | 
2025-10-03 04:09:08.674166 | TASK [Ensure that user is logged]
2025-10-03 04:09:09.117075 | controller | Checking login to the cluster 1...
2025-10-03 04:09:10.112289 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:10.182889 | controller |
2025-10-03 04:09:10.182988 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:10.188642 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:15.192625 | controller | Checking login to the cluster 2...
2025-10-03 04:09:15.386723 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:15.417146 | controller |
2025-10-03 04:09:15.417233 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:15.425208 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:20.427258 | controller | Checking login to the cluster 3...
2025-10-03 04:09:20.579082 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:20.598914 | controller |
2025-10-03 04:09:20.598975 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:20.608889 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:25.611105 | controller | Checking login to the cluster 4...
2025-10-03 04:09:25.768651 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:25.790465 | controller |
2025-10-03 04:09:25.790568 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:25.795859 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:30.798095 | controller | Checking login to the cluster 5...
2025-10-03 04:09:30.958392 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:30.988594 | controller |
2025-10-03 04:09:30.988770 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:30.998874 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:36.002388 | controller | Checking login to the cluster 6...
2025-10-03 04:09:36.189468 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:36.212884 | controller |
2025-10-03 04:09:36.212960 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:36.220626 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:41.224605 | controller | Checking login to the cluster 7...
2025-10-03 04:09:41.411615 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:41.480433 | controller |
2025-10-03 04:09:41.480659 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:41.486045 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:46.489807 | controller | Checking login to the cluster 8...
2025-10-03 04:09:46.639344 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:46.656552 | controller |
2025-10-03 04:09:46.656646 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:46.662783 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:51.665810 | controller | Checking login to the cluster 9...
2025-10-03 04:09:51.850045 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:51.893044 | controller |
2025-10-03 04:09:51.893175 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:51.904171 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:09:56.908311 | controller | Checking login to the cluster 10...
2025-10-03 04:09:57.098993 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:09:57.142888 | controller |
2025-10-03 04:09:57.143040 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:09:57.148991 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:02.151823 | controller | Checking login to the cluster 11...
2025-10-03 04:10:02.314419 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:02.348739 | controller |
2025-10-03 04:10:02.348813 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:10:02.355662 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:07.359056 | controller | Checking login to the cluster 12...
2025-10-03 04:10:07.588950 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:07.748436 | controller |
2025-10-03 04:10:07.748583 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:10:07.760387 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:12.763988 | controller | Checking login to the cluster 13...
2025-10-03 04:10:13.054792 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:13.291375 | controller |
2025-10-03 04:10:13.291551 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:10:13.302239 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:18.305982 | controller | Checking login to the cluster 14...
2025-10-03 04:10:18.597559 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:19.330233 | controller |
2025-10-03 04:10:19.330356 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:10:19.336485 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:24.339772 | controller | Checking login to the cluster 15...
2025-10-03 04:10:24.633675 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:25.570010 | controller |
2025-10-03 04:10:25.570173 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:10:25.580629 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:30.589439 | controller | Checking login to the cluster 16...
2025-10-03 04:10:31.129610 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:32.782911 | controller |
2025-10-03 04:10:32.783075 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:10:32.789591 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:37.793285 | controller | Checking login to the cluster 17...
2025-10-03 04:10:38.016419 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:38.337134 | controller |
2025-10-03 04:10:38.337204 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:10:38.342535 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:43.344963 | controller | Checking login to the cluster 18...
2025-10-03 04:10:43.576708 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:44.368635 | controller |
2025-10-03 04:10:44.369092 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:10:44.379081 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:49.383539 | controller | Checking login to the cluster 19...
2025-10-03 04:10:50.192264 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:50.601928 | controller |
2025-10-03 04:10:50.602082 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:10:50.614138 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:10:55.617312 | controller | Checking login to the cluster 20...
2025-10-03 04:10:56.674132 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:10:58.858204 | controller |
2025-10-03 04:10:58.858313 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:10:58.869951 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:03.873573 | controller | Checking login to the cluster 21...
2025-10-03 04:11:04.071188 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:04.246901 | controller |
2025-10-03 04:11:04.247005 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:04.253702 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:09.256610 | controller | Checking login to the cluster 22...
2025-10-03 04:11:09.492368 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:09.623655 | controller |
2025-10-03 04:11:09.623825 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:09.629632 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:14.632400 | controller | Checking login to the cluster 23...
2025-10-03 04:11:14.817252 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:14.890627 | controller |
2025-10-03 04:11:14.890718 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:14.902394 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:19.909949 | controller | Checking login to the cluster 24...
2025-10-03 04:11:20.088674 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:20.305941 | controller |
2025-10-03 04:11:20.306090 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:20.319646 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:25.322907 | controller | Checking login to the cluster 25...
2025-10-03 04:11:25.504606 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:25.610808 | controller |
2025-10-03 04:11:25.610893 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:25.615303 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:30.618030 | controller | Checking login to the cluster 26...
2025-10-03 04:11:30.802307 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:30.835357 | controller |
2025-10-03 04:11:30.835473 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:30.842767 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:35.847421 | controller | Checking login to the cluster 27...
2025-10-03 04:11:36.020368 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:36.062114 | controller |
2025-10-03 04:11:36.062222 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:36.073094 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:41.078701 | controller | Checking login to the cluster 28...
2025-10-03 04:11:41.250000 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:41.288360 | controller |
2025-10-03 04:11:41.288591 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:41.298149 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:46.302490 | controller | Checking login to the cluster 29...
2025-10-03 04:11:46.496908 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:46.567540 | controller |
2025-10-03 04:11:46.567657 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:46.574427 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:51.578845 | controller | Checking login to the cluster 30...
2025-10-03 04:11:51.777130 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:53.518499 | controller |
2025-10-03 04:11:53.518584 | controller | Login successful.
2025-10-03 04:11:53.620725 | controller |
2025-10-03 04:11:53.620848 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-10-03 04:11:53.662686 | controller |
2025-10-03 04:11:53.662933 | controller | Using project "default".
2025-10-03 04:11:53.944721 | controller | changed
2025-10-03 04:11:53.953669 | 
2025-10-03 04:11:53.953802 | TASK [Delete all openshift-marketplace pods]
2025-10-03 04:11:55.568966 | controller | pod "certified-operators-45v84" deleted
2025-10-03 04:11:55.685488 | controller | pod "certified-operators-f8szs" deleted
2025-10-03 04:11:55.728527 | controller | pod "community-operators-5zsqg" deleted
2025-10-03 04:11:55.793642 | controller | pod "community-operators-hsvtr" deleted
2025-10-03 04:11:55.927719 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2025-10-03 04:11:56.060888 | controller | pod "redhat-marketplace-ps6xk" deleted
2025-10-03 04:11:56.229071 | controller | pod "redhat-marketplace-z2q5p" deleted
2025-10-03 04:11:56.373777 | controller | pod "redhat-operators-m5487" deleted
2025-10-03 04:11:56.503323 | controller | pod "redhat-operators-tqxl9" deleted
2025-10-03 04:13:31.618632 | controller | ok
2025-10-03 04:13:31.626128 | 
2025-10-03 04:13:31.626211 | TASK [Copy script ensure_services_up.sh]
2025-10-03 04:13:31.693424 | controller | skipping: Conditional result was False
2025-10-03 04:13:31.701181 | 
2025-10-03 04:13:31.701297 | TASK [Ensure all pods are up and running]
2025-10-03 04:13:32.236574 | controller | skipping: Conditional result was False
2025-10-03 04:13:32.248920 | 
2025-10-03 04:13:32.249117 | TASK [Check if there is a image build date file]
2025-10-03 04:13:33.121500 | controller | ok
2025-10-03 04:13:33.132989 | 
2025-10-03 04:13:33.133140 | TASK [Print image date if available]
2025-10-03 04:13:33.544807 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2025-10-03 04:13:33.682064 | controller | changed
2025-10-03 04:13:33.693735 | 
2025-10-03 04:13:33.693861 | TASK [Fix machineconfigpool]
2025-10-03 04:13:33.764076 | controller | skipping: Conditional result was False
2025-10-03 04:13:33.774561 | 
2025-10-03 04:13:33.774709 | TASK [Show available nodes]
2025-10-03 04:13:35.671747 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-10-03 04:13:35.674806 | controller | crc    Ready    control-plane,master,worker   221d   v1.31.5
2025-10-03 04:13:35.827212 | controller | ok: Runtime: 0:00:01.536093
2025-10-03 04:13:35.838345 | 
2025-10-03 04:13:35.838468 | TASK [Configure insecure registry inside crc vm]
2025-10-03 04:13:35.875205 | controller | skipping: Conditional result was False
2025-10-03 04:13:35.887398 | 
2025-10-03 04:13:35.887532 | TASK [Catch CRC IP]
2025-10-03 04:13:36.549572 | controller | 192.168.130.11
2025-10-03 04:13:36.976965 | controller | changed
2025-10-03 04:13:36.988794 | 
2025-10-03 04:13:36.988939 | TASK [Check if id_ed25519 key exists]
2025-10-03 04:13:37.436931 | controller | ok
2025-10-03 04:13:37.449392 | 
2025-10-03 04:13:37.449552 | TASK [Set fact if new keypair exists]
2025-10-03 04:13:37.499255 | controller | ok
2025-10-03 04:13:37.510092 | 
2025-10-03 04:13:37.510240 | TASK [Run crc]
2025-10-03 04:13:39.630195 | controller |
2025-10-03 04:13:48.226722 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-10-03 04:13:48.226822 | controller |
2025-10-03 04:13:48.226830 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:13:48.226853 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-10-03 04:13:48.273795 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-10-03 04:13:48.273891 | controller | interpreter could change the meaning of that path. See
2025-10-03 04:13:48.273898 | controller | https://docs.ansible.com/ansible-
2025-10-03 04:13:48.273906 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-10-03 04:13:48.273927 | controller | ok: [192.168.130.11]
2025-10-03 04:13:50.398935 | controller |
2025-10-03 04:13:50.399034 | controller | TASK [Copy pull-secret] ********************************************************
2025-10-03 04:13:50.399062 | controller | changed: [192.168.130.11]
2025-10-03 04:13:52.147892 | controller |
2025-10-03 04:13:52.147966 | controller | TASK [Add crio config] *********************************************************
2025-10-03 04:13:52.147986 | controller | changed: [192.168.130.11]
2025-10-03 04:14:00.734326 | controller |
2025-10-03 04:14:00.734427 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-10-03 04:14:00.734451 | controller | changed: [192.168.130.11]
2025-10-03 04:14:00.824998 | controller |
2025-10-03 04:14:00.825132 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:14:00.825142 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:14:00.825150 | controller |
2025-10-03 04:14:01.103275 | controller | changed
2025-10-03 04:14:01.114718 | 
2025-10-03 04:14:01.114849 | TASK [Set slower etcd profile]
2025-10-03 04:14:01.172500 | controller | ok
2025-10-03 04:14:01.212757 | 
2025-10-03 04:14:01.212950 | TASK [prepare-crc-cloud : Set slower profile]
2025-10-03 04:14:03.127715 | controller | etcd.operator.openshift.io/cluster patched
2025-10-03 04:14:03.254415 | controller | ok
2025-10-03 04:14:03.268291 | 
2025-10-03 04:14:03.268525 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-10-03 04:14:33.322062 | controller | ok
2025-10-03 04:14:33.332895 | 
2025-10-03 04:14:33.333066 | TASK [prepare-crc-cloud : Get pod phase status]
2025-10-03 04:20:02.859261 | controller | ok
2025-10-03 04:20:02.880172 | 
2025-10-03 04:20:02.880301 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-10-03 04:20:02.959103 | controller | ok
2025-10-03 04:20:02.972842 | controller | included: /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-10-03 04:20:02.992253 | 
2025-10-03 04:20:02.992338 | TASK [Set facts for disk limitation]
2025-10-03 04:20:03.025429 | controller | ok
2025-10-03 04:20:03.038129 | 
2025-10-03 04:20:03.038259 | TASK [Print limits for the disk]
2025-10-03 04:20:03.087165 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-10-03 04:20:03.097563 | 
2025-10-03 04:20:03.097687 | TASK [Set limit to crc VM to the vda disk]
2025-10-03 04:20:03.610078 | controller |
2025-10-03 04:20:03.652945 | controller | changed
2025-10-03 04:20:03.664919 | 
2025-10-03 04:20:03.665077 | TASK [Pause machineconfigpool]
2025-10-03 04:20:03.744069 | controller | ok
2025-10-03 04:20:03.787143 | 
2025-10-03 04:20:03.787230 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-10-03 04:20:04.446692 | controller | ok
2025-10-03 04:20:04.969000 | controller | ok
2025-10-03 04:20:04.991602 | 
2025-10-03 04:20:04.991765 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-10-03 04:20:06.007346 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-10-03 04:20:06.007454 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      221d
2025-10-03 04:20:06.023257 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      221d
2025-10-03 04:20:06.072823 | controller | ok
2025-10-03 04:20:06.120867 | 
2025-10-03 04:20:06.120963 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-10-03 04:20:06.121145 | 
2025-10-03 04:20:06.121191 | PLAY RECAP
2025-10-03 04:20:06.121239 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-10-03 04:20:06.121265 | 
2025-10-03 04:20:06.258646 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-03 04:20:06.263059 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:20:06.843499 | 
2025-10-03 04:20:06.843601 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-03 04:20:06.863676 | 
2025-10-03 04:20:06.863779 | TASK [Create zuul-output directory]
2025-10-03 04:20:07.428913 | controller | changed
2025-10-03 04:20:07.440045 | 
2025-10-03 04:20:07.440202 | TASK [Slurp Zuul inventory test]
2025-10-03 04:20:07.857075 | controller -> localhost | ok
2025-10-03 04:20:07.863632 | 
2025-10-03 04:20:07.863697 | TASK [Save zuul inventory]
2025-10-03 04:20:10.130912 | controller | changed
2025-10-03 04:20:10.135974 | 
2025-10-03 04:20:10.136051 | TASK [Save zuul vars without the change_message]
2025-10-03 04:20:12.325067 | controller | changed
2025-10-03 04:20:12.365390 | 
2025-10-03 04:20:12.365477 | PLAY RECAP
2025-10-03 04:20:12.365532 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:20:12.365560 | 
2025-10-03 04:20:12.462341 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:20:12.463138 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:20:13.069895 | 
2025-10-03 04:20:13.070061 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-03 04:20:13.090671 | 
2025-10-03 04:20:13.090765 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-03 04:20:13.111592 | controller | ok
2025-10-03 04:20:13.128312 | 
2025-10-03 04:20:13.128390 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:20:13.172974 | controller | skipping: Conditional result was False
2025-10-03 04:20:13.180527 | 
2025-10-03 04:20:13.180615 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:20:13.717147 | controller | ok
2025-10-03 04:20:13.733546 | 
2025-10-03 04:20:13.733765 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:20:15.160948 | controller | ok
2025-10-03 04:20:15.182399 | 
2025-10-03 04:20:15.182572 | TASK [Prepare workspace]
2025-10-03 04:20:15.210425 | controller | ok
2025-10-03 04:20:15.239866 | 
2025-10-03 04:20:15.239963 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 04:20:15.755058 | controller | ok
2025-10-03 04:20:15.767462 | 
2025-10-03 04:20:15.767593 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 04:20:16.827134 | controller | Output suppressed because no_log was given
2025-10-03 04:20:16.848729 | 
2025-10-03 04:20:16.848861 | LOOP [Create zuul-output directory]
2025-10-03 04:20:17.195701 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-03 04:20:17.551675 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-03 04:20:17.569151 | 
2025-10-03 04:20:17.569293 | TASK [Install required packages]
2025-10-03 04:20:33.949894 | controller | ok: Nothing to do
2025-10-03 04:20:33.957131 | 
2025-10-03 04:20:33.957217 | TASK [Install venv]
2025-10-03 04:21:37.844309 | controller | changed
2025-10-03 04:21:37.893721 | 
2025-10-03 04:21:37.893832 | PLAY RECAP
2025-10-03 04:21:37.893890 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:21:37.893920 | 
2025-10-03 04:21:37.994927 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:21:37.995769 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:21:38.643664 | 
2025-10-03 04:21:38.643783 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-03 04:21:38.664697 | 
2025-10-03 04:21:38.664820 | TASK [Gather required facts]
2025-10-03 04:21:39.433646 | controller | ok
2025-10-03 04:21:39.445513 | 
2025-10-03 04:21:39.445664 | TASK [Load environment var if instructed to]
2025-10-03 04:21:39.472581 | controller | skipping: Conditional result was False
2025-10-03 04:21:39.484779 | 
2025-10-03 04:21:39.484916 | TASK [Run molecule]
2025-10-03 04:21:40.889755 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-03 04:21:40.970973 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-03 04:21:59.156556 | 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-10-03 04:21:59.157183 | 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-10-03 04:21:59.157853 | 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-10-03 04:21:59.158512 | 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-10-03 04:21:59.159237 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections).
2025-10-03 04:21:59.159885 | 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-10-03 04:21:59.160554 | 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-10-03 04:21:59.161208 | 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-10-03 04:21:59.161910 | 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-10-03 04:21:59.162571 | 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-10-03 04:21:59.163324 | 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-10-03 04:21:59.164041 | 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-10-03 04:21:59.164680 | 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-10-03 04:21:59.165336 | 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-10-03 04:21:59.165987 | 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-10-03 04:21:59.181506 | controller | INFO     Running default > prepare
2025-10-03 04:21:59.988662 | controller |
2025-10-03 04:21:59.988791 | controller | PLAY [Prepare] *****************************************************************
2025-10-03 04:21:59.988950 | controller |
2025-10-03 04:21:59.989103 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:21:59.989239 | controller | Friday 03 October 2025  04:21:59 +0000 (0:00:00.014)       0:00:00.014 ********
2025-10-03 04:22:01.255753 | controller | ok: [instance]
2025-10-03 04:22:01.255930 | controller |
2025-10-03 04:22:01.256238 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-03 04:22:01.256471 | controller | Friday 03 October 2025  04:22:01 +0000 (0:00:01.267)       0:00:01.281 ********
2025-10-03 04:22:01.289109 | controller | skipping: [instance]
2025-10-03 04:22:01.289382 | controller |
2025-10-03 04:22:01.289684 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-03 04:22:01.289742 | controller | Friday 03 October 2025  04:22:01 +0000 (0:00:00.033)       0:00:01.314 ********
2025-10-03 04:22:01.355678 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-03 04:22:01.355830 | controller |
2025-10-03 04:22:01.356062 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-03 04:22:01.356336 | controller | Friday 03 October 2025  04:22:01 +0000 (0:00:00.066)       0:00:01.381 ********
2025-10-03 04:22:01.745408 | controller | ok: [instance]
2025-10-03 04:22:01.745628 | controller |
2025-10-03 04:22:01.745855 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-03 04:22:01.746145 | controller | Friday 03 October 2025  04:22:01 +0000 (0:00:00.389)       0:00:01.770 ********
2025-10-03 04:22:02.209500 | controller | ok: [instance]
2025-10-03 04:22:02.209585 | controller |
2025-10-03 04:22:02.209790 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-03 04:22:02.209978 | controller | Friday 03 October 2025  04:22:02 +0000 (0:00:00.463)       0:00:02.234 ********
2025-10-03 04:22:03.196911 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-03 04:22:03.197130 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-03 04:22:03.197431 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-03 04:22:03.197739 | controller |
2025-10-03 04:22:03.197840 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-03 04:22:03.198244 | controller | Friday 03 October 2025  04:22:03 +0000 (0:00:00.986)       0:00:03.221 ********
2025-10-03 04:22:03.269421 | controller |
2025-10-03 04:22:03.269624 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-03 04:22:03.269842 | controller | Friday 03 October 2025  04:22:03 +0000 (0:00:00.074)       0:00:03.295 ********
2025-10-03 04:22:04.061341 | controller | changed: [instance] => (item=tmp)
2025-10-03 04:22:04.061632 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-03 04:22:04.061960 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-03 04:22:04.062248 | controller |
2025-10-03 04:22:04.062482 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-03 04:22:04.062708 | controller | Friday 03 October 2025  04:22:04 +0000 (0:00:00.791)       0:00:04.087 ********
2025-10-03 04:22:05.137134 | controller | ok: [instance]
2025-10-03 04:22:05.137235 | controller |
2025-10-03 04:22:05.137249 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-03 04:22:05.137271 | controller | Friday 03 October 2025  04:22:05 +0000 (0:00:01.075)       0:00:05.162 ********
2025-10-03 04:22:06.181771 | controller | changed: [instance]
2025-10-03 04:22:14.761623 | controller |
2025-10-03 04:22:14.761678 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-03 04:22:14.761688 | controller | Friday 03 October 2025  04:22:06 +0000 (0:00:01.044)       0:00:06.207 ********
2025-10-03 04:22:14.761702 | controller | changed: [instance]
2025-10-03 04:22:14.761711 | controller |
2025-10-03 04:22:14.761727 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-03 04:22:14.761738 | controller | Friday 03 October 2025  04:22:14 +0000 (0:00:08.579)       0:00:14.786 ********
2025-10-03 04:22:15.742255 | controller | changed: [instance]
2025-10-03 04:22:15.767113 | controller |
2025-10-03 04:22:15.767172 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-03 04:22:15.767191 | controller | Friday 03 October 2025  04:22:15 +0000 (0:00:00.981)       0:00:15.768 ********
2025-10-03 04:22:15.767214 | controller | skipping: [instance]
2025-10-03 04:22:15.767270 | controller |
2025-10-03 04:22:15.767292 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-03 04:22:15.767343 | controller | Friday 03 October 2025  04:22:15 +0000 (0:00:00.025)       0:00:15.793 ********
2025-10-03 04:22:16.579755 | controller | changed: [instance]
2025-10-03 04:22:16.579892 | controller |
2025-10-03 04:22:16.579915 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-03 04:22:16.580002 | controller | Friday 03 October 2025  04:22:16 +0000 (0:00:00.811)       0:00:16.605 ********
2025-10-03 04:22:16.638773 | controller | skipping: [instance]
2025-10-03 04:22:16.638906 | controller |
2025-10-03 04:22:16.639059 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-03 04:22:16.639306 | controller | Friday 03 October 2025  04:22:16 +0000 (0:00:00.059)       0:00:16.664 ********
2025-10-03 04:22:16.752278 | controller | skipping: [instance]
2025-10-03 04:22:16.752527 | controller |
2025-10-03 04:22:16.753174 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-03 04:22:16.753398 | controller | Friday 03 October 2025  04:22:16 +0000 (0:00:00.113)       0:00:16.777 ********
2025-10-03 04:22:16.799233 | controller | skipping: [instance]
2025-10-03 04:22:16.799391 | controller |
2025-10-03 04:22:16.799586 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-03 04:22:16.799765 | controller | Friday 03 October 2025  04:22:16 +0000 (0:00:00.047)       0:00:16.825 ********
2025-10-03 04:22:17.365686 | controller | changed: [instance]
2025-10-03 04:22:17.365775 | controller |
2025-10-03 04:22:17.365810 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-03 04:22:17.365938 | controller | Friday 03 October 2025  04:22:17 +0000 (0:00:00.566)       0:00:17.391 ********
2025-10-03 04:22:17.904589 | controller | changed: [instance]
2025-10-03 04:22:17.904826 | controller |
2025-10-03 04:22:17.905232 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-03 04:22:17.905557 | controller | Friday 03 October 2025  04:22:17 +0000 (0:00:00.539)       0:00:17.930 ********
2025-10-03 04:22:17.936848 | controller | skipping: [instance]
2025-10-03 04:22:17.937095 | controller |
2025-10-03 04:22:17.937310 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-03 04:22:17.937511 | controller | Friday 03 October 2025  04:22:17 +0000 (0:00:00.032)       0:00:17.962 ********
2025-10-03 04:22:17.988907 | controller | skipping: [instance]
2025-10-03 04:22:17.989113 | controller |
2025-10-03 04:22:17.989335 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-03 04:22:17.989526 | controller | Friday 03 October 2025  04:22:17 +0000 (0:00:00.052)       0:00:18.015 ********
2025-10-03 04:22:18.036119 | controller | skipping: [instance]
2025-10-03 04:22:18.036381 | controller |
2025-10-03 04:22:18.036716 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-03 04:22:18.037118 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.046)       0:00:18.061 ********
2025-10-03 04:22:18.101394 | controller | ok: [instance]
2025-10-03 04:22:18.101698 | controller |
2025-10-03 04:22:18.101716 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-03 04:22:18.157727 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.065)       0:00:18.126 ********
2025-10-03 04:22:18.157792 | controller | skipping: [instance]
2025-10-03 04:22:18.158418 | controller |
2025-10-03 04:22:18.221082 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-03 04:22:18.221140 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.055)       0:00:18.182 ********
2025-10-03 04:22:18.221173 | controller | skipping: [instance]
2025-10-03 04:22:18.221200 | controller |
2025-10-03 04:22:18.221442 | controller | TASK [Download the RPM] ********************************************************
2025-10-03 04:22:18.221655 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.063)       0:00:18.246 ********
2025-10-03 04:22:18.277684 | controller | skipping: [instance]
2025-10-03 04:22:18.277807 | controller |
2025-10-03 04:22:18.278140 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-03 04:22:18.278456 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.053)       0:00:18.299 ********
2025-10-03 04:22:18.331881 | controller | skipping: [instance]
2025-10-03 04:22:18.331962 | controller |
2025-10-03 04:22:18.332093 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-03 04:22:18.332289 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.055)       0:00:18.355 ********
2025-10-03 04:22:18.382192 | controller | skipping: [instance]
2025-10-03 04:22:18.382276 | controller |
2025-10-03 04:22:18.382378 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-03 04:22:18.382514 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.048)       0:00:18.404 ********
2025-10-03 04:22:18.429040 | controller | skipping: [instance]
2025-10-03 04:22:18.429161 | controller |
2025-10-03 04:22:18.429175 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-03 04:22:18.429267 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.049)       0:00:18.453 ********
2025-10-03 04:22:18.468817 | controller | skipping: [instance]
2025-10-03 04:22:18.468973 | controller |
2025-10-03 04:22:18.469131 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-03 04:22:18.469287 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.035)       0:00:18.489 ********
2025-10-03 04:22:18.709290 | controller | ok: [instance]
2025-10-03 04:22:18.709476 | controller |
2025-10-03 04:22:18.709718 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-03 04:22:18.709944 | controller | Friday 03 October 2025  04:22:18 +0000 (0:00:00.245)       0:00:18.734 ********
2025-10-03 04:22:19.012972 | controller | changed: [instance]
2025-10-03 04:22:19.013209 | controller |
2025-10-03 04:22:19.013502 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-03 04:22:19.013722 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.304)       0:00:19.038 ********
2025-10-03 04:22:19.412195 | controller | changed: [instance]
2025-10-03 04:22:19.412506 | controller |
2025-10-03 04:22:19.412898 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-03 04:22:19.413273 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.398)       0:00:19.437 ********
2025-10-03 04:22:19.457700 | controller | skipping: [instance]
2025-10-03 04:22:19.457932 | controller |
2025-10-03 04:22:19.458228 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-03 04:22:19.458469 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.045)       0:00:19.482 ********
2025-10-03 04:22:19.511401 | controller | skipping: [instance]
2025-10-03 04:22:19.511616 | controller |
2025-10-03 04:22:19.511965 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-03 04:22:19.512505 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.053)       0:00:19.536 ********
2025-10-03 04:22:19.555594 | controller | skipping: [instance]
2025-10-03 04:22:19.555796 | controller |
2025-10-03 04:22:19.556095 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-03 04:22:19.556400 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.044)       0:00:19.581 ********
2025-10-03 04:22:19.588605 | controller | skipping: [instance]
2025-10-03 04:22:19.588676 | controller |
2025-10-03 04:22:19.588740 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-03 04:22:19.588887 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.033)       0:00:19.614 ********
2025-10-03 04:22:19.620621 | controller | skipping: [instance]
2025-10-03 04:22:19.620683 | controller |
2025-10-03 04:22:19.620799 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-03 04:22:19.620898 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.032)       0:00:19.646 ********
2025-10-03 04:22:19.664878 | controller | skipping: [instance]
2025-10-03 04:22:19.664944 | controller |
2025-10-03 04:22:19.665108 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-03 04:22:19.665210 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.044)       0:00:19.690 ********
2025-10-03 04:22:19.693577 | controller | skipping: [instance]
2025-10-03 04:22:19.693646 | controller |
2025-10-03 04:22:19.693868 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-03 04:22:19.694065 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.028)       0:00:19.719 ********
2025-10-03 04:22:19.743974 | controller | skipping: [instance]
2025-10-03 04:22:19.744205 | controller |
2025-10-03 04:22:19.744499 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-03 04:22:19.744830 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.050)       0:00:19.769 ********
2025-10-03 04:22:19.776099 | controller | skipping: [instance]
2025-10-03 04:22:19.776256 | controller |
2025-10-03 04:22:19.776474 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-03 04:22:19.776689 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.032)       0:00:19.801 ********
2025-10-03 04:22:19.809431 | controller | skipping: [instance]
2025-10-03 04:22:19.809608 | controller |
2025-10-03 04:22:19.809849 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-03 04:22:19.810112 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.033)       0:00:19.835 ********
2025-10-03 04:22:19.868183 | controller | skipping: [instance]
2025-10-03 04:22:19.868264 | controller |
2025-10-03 04:22:19.868406 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-03 04:22:19.868553 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.058)       0:00:19.894 ********
2025-10-03 04:22:19.929302 | controller | skipping: [instance]
2025-10-03 04:22:19.929450 | controller |
2025-10-03 04:22:19.929685 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-03 04:22:19.929919 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.060)       0:00:19.954 ********
2025-10-03 04:22:19.990419 | controller | skipping: [instance]
2025-10-03 04:22:19.990583 | controller |
2025-10-03 04:22:19.990815 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-03 04:22:19.991309 | controller | Friday 03 October 2025  04:22:19 +0000 (0:00:00.061)       0:00:20.016 ********
2025-10-03 04:22:20.055922 | controller | skipping: [instance]
2025-10-03 04:22:20.056134 | controller |
2025-10-03 04:22:20.056382 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-03 04:22:20.056608 | controller | Friday 03 October 2025  04:22:20 +0000 (0:00:00.064)       0:00:20.081 ********
2025-10-03 04:23:01.061041 | controller | ok: [instance]
2025-10-03 04:23:01.061156 | controller |
2025-10-03 04:23:01.061168 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-03 04:23:01.061325 | controller | Friday 03 October 2025  04:23:01 +0000 (0:00:41.006)       0:01:01.087 ********
2025-10-03 04:23:02.576315 | controller | ok: [instance]
2025-10-03 04:23:02.576682 | controller |
2025-10-03 04:23:02.576735 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-03 04:23:02.576749 | controller | Friday 03 October 2025  04:23:02 +0000 (0:00:01.515)       0:01:02.602 ********
2025-10-03 04:23:02.642226 | controller | skipping: [instance]
2025-10-03 04:23:02.642407 | controller |
2025-10-03 04:23:02.642670 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:02.642962 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-03 04:23:02.643231 | controller |
2025-10-03 04:23:02.643998 | controller | Friday 03 October 2025  04:23:02 +0000 (0:00:00.065)       0:01:02.668 ********
2025-10-03 04:23:02.644297 | controller | ===============================================================================
2025-10-03 04:23:02.644328 | controller | test_deps : Install selinux python libs -------------------------------- 41.01s
2025-10-03 04:23:02.644358 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.58s
2025-10-03 04:23:02.644581 | controller | test_deps : Install python yaml libs ------------------------------------ 1.52s
2025-10-03 04:23:02.644825 | controller | Gathering Facts --------------------------------------------------------- 1.27s
2025-10-03 04:23:02.645126 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.08s
2025-10-03 04:23:02.645383 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.04s
2025-10-03 04:23:02.645637 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.99s
2025-10-03 04:23:02.645901 | controller | repo_setup : Install repo-setup package --------------------------------- 0.98s
2025-10-03 04:23:02.646181 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.81s
2025-10-03 04:23:02.646424 | controller | repo_setup : Ensure directories are present ----------------------------- 0.79s
2025-10-03 04:23:02.646664 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-03 04:23:02.646906 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.54s
2025-10-03 04:23:02.647204 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.46s
2025-10-03 04:23:02.647465 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.40s
2025-10-03 04:23:02.647732 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.39s
2025-10-03 04:23:02.647976 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.30s
2025-10-03 04:23:02.648266 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.25s
2025-10-03 04:23:02.648523 | controller | repo_setup : Rename component repo -------------------------------------- 0.11s
2025-10-03 04:23:02.648753 | controller | Deploy repo-setup ------------------------------------------------------- 0.07s
2025-10-03 04:23:02.649069 | controller | test_deps : Gather variables for each operating system ------------------ 0.07s
2025-10-03 04:23:02.722551 | controller | INFO     Running default > converge
2025-10-03 04:23:03.250736 | controller |
2025-10-03 04:23:03.251091 | controller | PLAY [Add crc to the inventory] ************************************************
2025-10-03 04:23:03.252207 | controller |
2025-10-03 04:23:03.252297 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:03.252320 | controller | Friday 03 October 2025  04:23:03 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:04.431729 | controller | ok: [instance]
2025-10-03 04:23:04.431853 | controller |
2025-10-03 04:23:04.432150 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-10-03 04:23:04.432453 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:01.182)       0:00:01.198 ********
2025-10-03 04:23:04.853069 | controller | changed: [instance]
2025-10-03 04:23:04.853169 | controller |
2025-10-03 04:23:04.853487 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-10-03 04:23:04.853810 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:00.421)       0:00:01.619 ********
2025-10-03 04:23:04.892795 | controller |
2025-10-03 04:23:04.892952 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-10-03 04:23:04.893277 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:00.040)       0:00:01.659 ********
2025-10-03 04:23:05.302148 | controller | ok: [instance]
2025-10-03 04:23:05.302227 | controller |
2025-10-03 04:23:05.302308 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-10-03 04:23:05.302413 | controller | Friday 03 October 2025  04:23:05 +0000 (0:00:00.409)       0:00:02.069 ********
2025-10-03 04:23:05.344093 | controller | ok: [instance]
2025-10-03 04:23:05.344330 | controller |
2025-10-03 04:23:05.344606 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-10-03 04:23:05.344866 | controller | Friday 03 October 2025  04:23:05 +0000 (0:00:00.041)       0:00:02.110 ********
2025-10-03 04:23:05.385604 | controller | skipping: [instance]
2025-10-03 04:23:05.385706 | controller |
2025-10-03 04:23:05.385842 | controller | TASK [Add the crc host dynamically] ********************************************
2025-10-03 04:23:05.386052 | controller | Friday 03 October 2025  04:23:05 +0000 (0:00:00.041)       0:00:02.152 ********
2025-10-03 04:23:05.458172 | controller | changed: [instance]
2025-10-03 04:23:05.458522 | controller |
2025-10-03 04:23:06.033967 | controller | TASK [Add a dummy ethernet interface to target the crc NMstate] ****************
2025-10-03 04:23:06.034006 | controller | Friday 03 October 2025  04:23:05 +0000 (0:00:00.073)       0:00:02.225 ********
2025-10-03 04:23:06.034033 | controller | changed: [instance]
2025-10-03 04:23:11.310860 | controller |
2025-10-03 04:23:11.310913 | controller | PLAY [Get CRC facts] ***********************************************************
2025-10-03 04:23:11.310922 | controller |
2025-10-03 04:23:11.310930 | controller | TASK [Fetch crc network facts] *************************************************
2025-10-03 04:23:11.310937 | controller | Friday 03 October 2025  04:23:06 +0000 (0:00:00.574)       0:00:02.799 ********
2025-10-03 04:23:11.310951 | controller | ok: [crc]
2025-10-03 04:23:11.365905 | controller |
2025-10-03 04:23:11.365968 | controller | PLAY [Converge] ****************************************************************
2025-10-03 04:23:11.365976 | controller |
2025-10-03 04:23:11.365982 | controller | TASK [Set common molecule test facts] ******************************************
2025-10-03 04:23:11.365988 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:05.277)       0:00:08.077 ********
2025-10-03 04:23:11.366003 | controller | ok: [instance]
2025-10-03 04:23:11.366044 | controller |
2025-10-03 04:23:11.366056 | controller | TASK [Prepare the Networking Env Definition for ci_nmstate role] ***************
2025-10-03 04:23:11.413108 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.055)       0:00:08.133 ********
2025-10-03 04:23:11.413174 | controller |
2025-10-03 04:23:11.413207 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ******
2025-10-03 04:23:11.413227 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.047)       0:00:08.180 ********
2025-10-03 04:23:11.496047 | controller | skipping: [instance]
2025-10-03 04:23:11.496223 | controller |
2025-10-03 04:23:11.496245 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-03 04:23:11.593007 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.083)       0:00:08.263 ********
2025-10-03 04:23:11.593153 | controller | skipping: [instance]
2025-10-03 04:23:11.593324 | controller |
2025-10-03 04:23:11.593345 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************
2025-10-03 04:23:11.593363 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.096)       0:00:08.359 ********
2025-10-03 04:23:11.647093 | controller | skipping: [instance]
2025-10-03 04:23:11.647195 | controller |
2025-10-03 04:23:11.647225 | controller | TASK [networking_mapper : Load the interfaces info from file] ******************
2025-10-03 04:23:11.647375 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.053)       0:00:08.413 ********
2025-10-03 04:23:11.695531 | controller | skipping: [instance]
2025-10-03 04:23:11.695709 | controller |
2025-10-03 04:23:11.695941 | controller | TASK [networking_mapper : Ensure local fact is empty] **************************
2025-10-03 04:23:11.696186 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.048)       0:00:08.462 ********
2025-10-03 04:23:11.736232 | controller | ok: [instance]
2025-10-03 04:23:11.736427 | controller |
2025-10-03 04:23:11.736690 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] ***
2025-10-03 04:23:11.736983 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.040)       0:00:08.502 ********
2025-10-03 04:23:11.867690 | 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.45'}}}}})
2025-10-03 04:23:11.867763 | controller |
2025-10-03 04:23:11.867911 | controller | TASK [networking_mapper : Gather facts if requested] ***************************
2025-10-03 04:23:11.868057 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.132)       0:00:08.634 ********
2025-10-03 04:23:11.909174 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/networking_mapper/tasks/_gather_facts.yml for instance
2025-10-03 04:23:11.909426 | controller |
2025-10-03 04:23:11.909736 | controller | TASK [networking_mapper : Gather the facts] ************************************
2025-10-03 04:23:11.910071 | controller | Friday 03 October 2025  04:23:11 +0000 (0:00:00.041)       0:00:08.675 ********
2025-10-03 04:23:12.313574 | controller | skipping: [instance] => (item=crc)
2025-10-03 04:23:12.313681 | controller | skipping: [instance] => (item=instance)
2025-10-03 04:23:12.313826 | controller | skipping: [instance]
2025-10-03 04:23:12.313856 | controller |
2025-10-03 04:23:12.313947 | controller | TASK [networking_mapper : Save instances refreshed facts for troubleshooting purposes] ***
2025-10-03 04:23:12.314159 | controller | Friday 03 October 2025  04:23:12 +0000 (0:00:00.404)       0:00:09.079 ********
2025-10-03 04:23:13.197552 | controller | changed: [instance]
2025-10-03 04:23:13.197653 | controller |
2025-10-03 04:23:13.197761 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] *****************
2025-10-03 04:23:13.197911 | controller | Friday 03 October 2025  04:23:13 +0000 (0:00:00.884)       0:00:09.964 ********
2025-10-03 04:23:13.722915 | controller | changed: [instance]
2025-10-03 04:23:13.723040 | controller |
2025-10-03 04:23:13.723192 | controller | TASK [networking_mapper : Write the Networking Definition to file] *************
2025-10-03 04:23:13.723383 | controller | Friday 03 October 2025  04:23:13 +0000 (0:00:00.525)       0:00:10.489 ********
2025-10-03 04:23:14.247062 | controller | changed: [instance]
2025-10-03 04:23:14.247138 | controller |
2025-10-03 04:23:14.247294 | controller | TASK [networking_mapper : Call the networking mapper] **************************
2025-10-03 04:23:14.247433 | controller | Friday 03 October 2025  04:23:14 +0000 (0:00:00.523)       0:00:11.013 ********
2025-10-03 04:23:14.340917 | controller | ok: [instance]
2025-10-03 04:23:14.340995 | controller |
2025-10-03 04:23:14.341103 | controller | TASK [networking_mapper : Set networking mapper facts] *************************
2025-10-03 04:23:14.341182 | controller | Friday 03 October 2025  04:23:14 +0000 (0:00:00.094)       0:00:11.107 ********
2025-10-03 04:23:14.378399 | controller | ok: [instance]
2025-10-03 04:23:14.378514 | controller |
2025-10-03 04:23:14.378673 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] ***
2025-10-03 04:23:14.378840 | controller | Friday 03 October 2025  04:23:14 +0000 (0:00:00.037)       0:00:11.145 ********
2025-10-03 04:23:14.912714 | controller | changed: [instance]
2025-10-03 04:23:14.912808 | controller |
2025-10-03 04:23:14.912938 | controller | TASK [Call the role under test] ************************************************
2025-10-03 04:23:14.913123 | controller | Friday 03 October 2025  04:23:14 +0000 (0:00:00.534)       0:00:11.679 ********
2025-10-03 04:23:14.984370 | controller |
2025-10-03 04:23:14.984447 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-03 04:23:14.984589 | controller | Friday 03 October 2025  04:23:14 +0000 (0:00:00.071)       0:00:11.751 ********
2025-10-03 04:23:15.016932 | controller | skipping: [instance]
2025-10-03 04:23:15.017357 | controller |
2025-10-03 04:23:15.017503 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-03 04:23:15.017650 | controller | Friday 03 October 2025  04:23:15 +0000 (0:00:00.032)       0:00:11.783 ********
2025-10-03 04:23:15.047301 | controller | skipping: [instance]
2025-10-03 04:23:15.047402 | controller |
2025-10-03 04:23:15.047546 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-03 04:23:15.047700 | controller | Friday 03 October 2025  04:23:15 +0000 (0:00:00.030)       0:00:11.814 ********
2025-10-03 04:23:15.089725 | controller | skipping: [instance]
2025-10-03 04:23:15.089797 | controller |
2025-10-03 04:23:15.090098 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-03 04:23:15.090340 | controller | Friday 03 October 2025  04:23:15 +0000 (0:00:00.041)       0:00:11.856 ********
2025-10-03 04:23:15.155954 | controller | skipping: [instance]
2025-10-03 04:23:15.156084 | controller |
2025-10-03 04:23:15.156336 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] **********************
2025-10-03 04:23:15.156549 | controller | Friday 03 October 2025  04:23:15 +0000 (0:00:00.066)       0:00:11.922 ********
2025-10-03 04:23:15.220459 | controller | ok: [instance]
2025-10-03 04:23:15.220557 | controller |
2025-10-03 04:23:15.220571 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] ***
2025-10-03 04:23:15.220667 | controller | Friday 03 October 2025  04:23:15 +0000 (0:00:00.064)       0:00:11.987 ********
2025-10-03 04:23:15.307742 | controller | ok: [instance] => (item=crc)
2025-10-03 04:23:15.307850 | controller | ok: [instance] => (item=instance)
2025-10-03 04:23:15.308033 | controller |
2025-10-03 04:23:15.308281 | controller | TASK [ci_nmstate : Get k8s nodes] **********************************************
2025-10-03 04:23:15.308501 | controller | Friday 03 October 2025  04:23:15 +0000 (0:00:00.086)       0:00:12.074 ********
2025-10-03 04:23:16.408164 | controller | ok: [instance]
2025-10-03 04:23:16.408228 | controller |
2025-10-03 04:23:16.408375 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ********
2025-10-03 04:23:16.408502 | controller | Friday 03 October 2025  04:23:16 +0000 (0:00:01.100)       0:00:13.175 ********
2025-10-03 04:23:16.517152 | controller | ok: [instance]
2025-10-03 04:23:16.517293 | controller |
2025-10-03 04:23:16.517492 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] *************************
2025-10-03 04:23:16.517691 | controller | Friday 03 October 2025  04:23:16 +0000 (0:00:00.108)       0:00:13.283 ********
2025-10-03 04:23:16.641125 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_install.yml for instance
2025-10-03 04:23:16.641228 | controller |
2025-10-03 04:23:16.641360 | controller | TASK [ci_nmstate : Create the target dir to dump NMstate manifests] ************
2025-10-03 04:23:16.641524 | controller | Friday 03 October 2025  04:23:16 +0000 (0:00:00.124)       0:00:13.408 ********
2025-10-03 04:23:16.923631 | controller | changed: [instance]
2025-10-03 04:23:16.923809 | controller |
2025-10-03 04:23:16.924087 | controller | TASK [ci_nmstate : Create the nmstate namespace] *******************************
2025-10-03 04:23:16.924459 | controller | Friday 03 October 2025  04:23:16 +0000 (0:00:00.281)       0:00:13.689 ********
2025-10-03 04:23:17.959048 | controller | changed: [instance]
2025-10-03 04:23:17.959136 | controller |
2025-10-03 04:23:17.959198 | controller | TASK [ci_nmstate : Generate the OperatorGroup final CR] ************************
2025-10-03 04:23:17.959312 | controller | Friday 03 October 2025  04:23:17 +0000 (0:00:01.036)       0:00:14.726 ********
2025-10-03 04:23:18.042588 | 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-10-03 04:23:18.042647 | controller |
2025-10-03 04:23:18.042818 | controller | TASK [ci_nmstate : Generate the Subscription final CR] *************************
2025-10-03 04:23:18.042967 | controller | Friday 03 October 2025  04:23:18 +0000 (0:00:00.083)       0:00:14.809 ********
2025-10-03 04:23:18.125283 | 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-10-03 04:23:18.125337 | controller |
2025-10-03 04:23:18.125424 | controller | TASK [ci_nmstate : Save k8s nmstate OLM manifests as artifacts] ****************
2025-10-03 04:23:18.125508 | controller | Friday 03 October 2025  04:23:18 +0000 (0:00:00.082)       0:00:14.892 ********
2025-10-03 04:23:19.056703 | controller | changed: [instance] => (item=openshift-nmstate)
2025-10-03 04:23:19.056846 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-10-03 04:23:19.056857 | controller |
2025-10-03 04:23:19.056980 | controller | TASK [ci_nmstate : Create the OLM subscription resources] **********************
2025-10-03 04:23:19.057124 | controller | Friday 03 October 2025  04:23:19 +0000 (0:00:00.931)       0:00:15.823 ********
2025-10-03 04:23:20.663832 | controller | changed: [instance] => (item=openshift-nmstate)
2025-10-03 04:23:41.930965 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-10-03 04:23:41.931007 | controller |
2025-10-03 04:23:41.931055 | controller | TASK [ci_nmstate : Wait for nmstate operator to be installed] ******************
2025-10-03 04:23:41.931066 | controller | Friday 03 October 2025  04:23:20 +0000 (0:00:01.607)       0:00:17.430 ********
2025-10-03 04:23:41.931080 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (12 retries left).
2025-10-03 04:23:42.466920 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (11 retries left).
2025-10-03 04:23:42.466957 | controller | ok: [instance]
2025-10-03 04:23:42.466965 | controller |
2025-10-03 04:23:42.466972 | controller | TASK [ci_nmstate : Save nmstate operator config as an artifact] ****************
2025-10-03 04:23:42.466978 | controller | Friday 03 October 2025  04:23:41 +0000 (0:00:21.267)       0:00:38.697 ********
2025-10-03 04:23:42.466990 | controller | changed: [instance]
2025-10-03 04:23:43.261697 | controller |
2025-10-03 04:23:43.261742 | controller | TASK [ci_nmstate : Trigger nmstate operator to install nmstate] ****************
2025-10-03 04:23:43.261754 | controller | Friday 03 October 2025  04:23:42 +0000 (0:00:00.535)       0:00:39.233 ********
2025-10-03 04:23:43.261769 | controller | changed: [instance]
2025-10-03 04:24:14.727893 | controller |
2025-10-03 04:24:14.727959 | controller | TASK [ci_nmstate : Wait for nsmtate Deployments to be ready] *******************
2025-10-03 04:24:14.727975 | controller | Friday 03 October 2025  04:23:43 +0000 (0:00:00.794)       0:00:40.028 ********
2025-10-03 04:24:14.727995 | controller | ok: [instance]
2025-10-03 04:24:15.818225 | controller |
2025-10-03 04:24:15.818280 | controller | TASK [ci_nmstate : Wait for nsmtate DaemonSet to be ready] *********************
2025-10-03 04:24:15.818292 | controller | Friday 03 October 2025  04:24:14 +0000 (0:00:31.465)       0:01:11.494 ********
2025-10-03 04:24:15.818308 | controller | ok: [instance]
2025-10-03 04:24:15.867194 | controller |
2025-10-03 04:24:15.867282 | controller | TASK [ci_nmstate : Provision nncps] ********************************************
2025-10-03 04:24:15.867303 | controller | Friday 03 October 2025  04:24:15 +0000 (0:00:01.090)       0:01:12.584 ********
2025-10-03 04:24:15.867334 | 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-10-03 04:24:15.867413 | controller |
2025-10-03 04:24:15.867884 | controller | TASK [ci_nmstate : Prepare the k8s nmstate nncp to apply] **********************
2025-10-03 04:24:16.041547 | controller | Friday 03 October 2025  04:24:15 +0000 (0:00:00.049)       0:01:12.633 ********
2025-10-03 04:24:16.041619 | controller | ok: [instance]
2025-10-03 04:24:16.041746 | controller |
2025-10-03 04:24:16.041762 | controller | TASK [ci_nmstate : Save k8s nmstate nncp manifests as artifacts] ***************
2025-10-03 04:24:16.041957 | controller | Friday 03 October 2025  04:24:16 +0000 (0:00:00.174)       0:01:12.808 ********
2025-10-03 04:24:16.569555 | controller | changed: [instance]
2025-10-03 04:24:17.534882 | controller |
2025-10-03 04:24:17.534941 | controller | TASK [ci_nmstate : Provision nmstate nncp for the node worker] *****************
2025-10-03 04:24:17.534950 | controller | Friday 03 October 2025  04:24:16 +0000 (0:00:00.526)       0:01:13.335 ********
2025-10-03 04:24:17.534963 | controller | changed: [instance]
2025-10-03 04:24:17.535087 | controller |
2025-10-03 04:24:17.535103 | controller | TASK [ci_nmstate : Wait for nsmtate nncp to be ready] **************************
2025-10-03 04:24:17.535113 | controller | Friday 03 October 2025  04:24:17 +0000 (0:00:00.966)       0:01:14.301 ********
2025-10-03 04:25:22.645270 | controller | fatal: [instance]: FAILED! => changed=false
2025-10-03 04:25:22.646128 | controller |   api_found: true
2025-10-03 04:25:22.646172 | controller |   attempts: 6
2025-10-03 04:25:22.646200 | controller |   resources:
2025-10-03 04:25:22.646902 | controller |   - apiVersion: nmstate.io/v1
2025-10-03 04:25:22.646983 | controller |     kind: NodeNetworkConfigurationPolicy
2025-10-03 04:25:22.647043 | controller |     metadata:
2025-10-03 04:25:22.647140 | controller |       annotations:
2025-10-03 04:25:22.647163 | controller |         nmstate.io/webhook-mutating-timestamp: '1759465457270187477'
2025-10-03 04:25:22.647454 | controller |       creationTimestamp: '2025-10-03T04:24:17Z'
2025-10-03 04:25:22.647652 | controller |       generation: 1
2025-10-03 04:25:22.648251 | controller |       managedFields:
2025-10-03 04:25:22.648515 | controller |       - apiVersion: nmstate.io/v1
2025-10-03 04:25:22.648565 | controller |         fieldsType: FieldsV1
2025-10-03 04:25:22.648670 | controller |         fieldsV1:
2025-10-03 04:25:22.648941 | controller |           f:spec:
2025-10-03 04:25:22.649317 | controller |             .: {}
2025-10-03 04:25:22.649712 | controller |             f:desiredState:
2025-10-03 04:25:22.650117 | controller |               .: {}
2025-10-03 04:25:22.650542 | controller |               f:dns-resolver:
2025-10-03 04:25:22.650933 | controller |                 .: {}
2025-10-03 04:25:22.651411 | controller |                 f:config:
2025-10-03 04:25:22.651822 | controller |                   .: {}
2025-10-03 04:25:22.652197 | controller |                   f:search: {}
2025-10-03 04:25:22.652507 | controller |                   f:server: {}
2025-10-03 04:25:22.652869 | controller |               f:interfaces: {}
2025-10-03 04:25:22.653312 | controller |             f:nodeSelector:
2025-10-03 04:25:22.653728 | controller |               .: {}
2025-10-03 04:25:22.654098 | controller |               f:kubernetes.io/hostname: {}
2025-10-03 04:25:22.654531 | controller |               f:node-role.kubernetes.io/worker: {}
2025-10-03 04:25:22.654935 | controller |         manager: OpenAPI-Generator
2025-10-03 04:25:22.655399 | controller |         operation: Update
2025-10-03 04:25:22.655823 | controller |         time: '2025-10-03T04:24:17Z'
2025-10-03 04:25:22.656270 | controller |       - apiVersion: nmstate.io/v1
2025-10-03 04:25:22.656637 | controller |         fieldsType: FieldsV1
2025-10-03 04:25:22.656985 | controller |         fieldsV1:
2025-10-03 04:25:22.657350 | controller |           f:status:
2025-10-03 04:25:22.657693 | controller |             .: {}
2025-10-03 04:25:22.658057 | controller |             f:conditions: {}
2025-10-03 04:25:22.658419 | controller |             f:lastUnavailableNodeCountUpdate: {}
2025-10-03 04:25:22.658765 | controller |             f:unavailableNodeCount: {}
2025-10-03 04:25:22.659191 | controller |         manager: manager
2025-10-03 04:25:22.659563 | controller |         operation: Update
2025-10-03 04:25:22.659914 | controller |         subresource: status
2025-10-03 04:25:22.660279 | controller |         time: '2025-10-03T04:24:20Z'
2025-10-03 04:25:22.660599 | controller |       name: crc
2025-10-03 04:25:22.660932 | controller |       resourceVersion: '35610'
2025-10-03 04:25:22.661300 | controller |       uid: 578d7de0-cff1-488b-a541-632b339a31a0
2025-10-03 04:25:22.661653 | controller |     spec:
2025-10-03 04:25:22.661953 | controller |       desiredState:
2025-10-03 04:25:22.662324 | controller |         dns-resolver:
2025-10-03 04:25:22.662622 | controller |           config:
2025-10-03 04:25:22.662911 | controller |             search:
2025-10-03 04:25:22.663259 | controller |             - testing-untagged.example.com
2025-10-03 04:25:22.663561 | controller |             - testing-vlan.example.com
2025-10-03 04:25:22.663875 | controller |             server:
2025-10-03 04:25:22.664213 | controller |             - 192.168.130.11
2025-10-03 04:25:22.664521 | controller |             - 192.168.130.1
2025-10-03 04:25:22.664821 | controller |         interfaces:
2025-10-03 04:25:22.665178 | controller |         - description: testing-untagged
2025-10-03 04:25:22.665479 | controller |           ipv4:
2025-10-03 04:25:22.665812 | controller |             address:
2025-10-03 04:25:22.666146 | controller |             - ip: 172.17.20.5
2025-10-03 04:25:22.666589 | controller |               prefix-length: 24
2025-10-03 04:25:22.666879 | controller |             enabled: true
2025-10-03 04:25:22.667208 | controller |           ipv6:
2025-10-03 04:25:22.667505 | controller |             enabled: false
2025-10-03 04:25:22.667799 | controller |           mtu: 1500
2025-10-03 04:25:22.668126 | controller |           name: enp6s0
2025-10-03 04:25:22.668426 | controller |           state: up
2025-10-03 04:25:22.668717 | controller |           type: ethernet
2025-10-03 04:25:22.669007 | controller |         - description: testing-vlan
2025-10-03 04:25:22.669351 | controller |           ipv4:
2025-10-03 04:25:22.669679 | controller |             address:
2025-10-03 04:25:22.669970 | controller |             - ip: 172.17.21.5
2025-10-03 04:25:22.670315 | controller |               prefix-length: 24
2025-10-03 04:25:22.670621 | controller |             enabled: true
2025-10-03 04:25:22.670914 | controller |           ipv6:
2025-10-03 04:25:22.671250 | controller |             enabled: false
2025-10-03 04:25:22.671549 | controller |           mtu: 1496
2025-10-03 04:25:22.671841 | controller |           name: enp6s0.21
2025-10-03 04:25:22.672180 | controller |           state: up
2025-10-03 04:25:22.672481 | controller |           type: vlan
2025-10-03 04:25:22.672769 | controller |           vlan:
2025-10-03 04:25:22.673098 | controller |             base-iface: enp6s0
2025-10-03 04:25:22.673412 | controller |             id: 21
2025-10-03 04:25:22.673725 | controller |       nodeSelector:
2025-10-03 04:25:22.674044 | controller |         kubernetes.io/hostname: crc
2025-10-03 04:25:22.674268 | controller |         node-role.kubernetes.io/worker: ''
2025-10-03 04:25:22.674463 | controller |     status:
2025-10-03 04:25:22.674663 | controller |       conditions:
2025-10-03 04:25:22.674870 | controller |       - lastHeartbeatTime: '2025-10-03T04:24:20Z'
2025-10-03 04:25:22.675116 | controller |         lastTransitionTime: '2025-10-03T04:24:17Z'
2025-10-03 04:25:22.675322 | controller |         reason: ConfigurationProgressing
2025-10-03 04:25:22.675516 | controller |         status: Unknown
2025-10-03 04:25:22.675709 | controller |         type: Available
2025-10-03 04:25:22.675904 | controller |       - lastHeartbeatTime: '2025-10-03T04:24:20Z'
2025-10-03 04:25:22.676133 | controller |         lastTransitionTime: '2025-10-03T04:24:17Z'
2025-10-03 04:25:22.676334 | controller |         reason: ConfigurationProgressing
2025-10-03 04:25:22.676529 | controller |         status: Unknown
2025-10-03 04:25:22.676722 | controller |         type: Degraded
2025-10-03 04:25:22.676917 | controller |       - lastHeartbeatTime: '2025-10-03T04:24:20Z'
2025-10-03 04:25:22.677155 | controller |         lastTransitionTime: '2025-10-03T04:24:20Z'
2025-10-03 04:25:22.677360 | controller |         message: Policy is progressing 0/1 nodes finished
2025-10-03 04:25:22.677556 | controller |         reason: ConfigurationProgressing
2025-10-03 04:25:22.677769 | controller |         status: 'True'
2025-10-03 04:25:22.677963 | controller |         type: Progressing
2025-10-03 04:25:22.678197 | controller |       lastUnavailableNodeCountUpdate: '2025-10-03T04:24:20Z'
2025-10-03 04:25:22.678408 | controller |       unavailableNodeCount: 1
2025-10-03 04:25:22.678783 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (6 retries left).
2025-10-03 04:25:22.678993 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (5 retries left).
2025-10-03 04:25:22.679245 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (4 retries left).
2025-10-03 04:25:22.679455 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (3 retries left).
2025-10-03 04:25:22.679659 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (2 retries left).
2025-10-03 04:25:22.679863 | controller | FAILED - RETRYING: [instance]: Wait for nsmtate nncp to be ready (1 retries left).
2025-10-03 04:25:22.680087 | controller |
2025-10-03 04:25:22.680289 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:25:22.680501 | controller | crc                        : ok=1    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:25:22.680710 | controller | instance                   : ok=36   changed=15   unreachable=0    failed=1    skipped=10   rescued=0    ignored=0
2025-10-03 04:25:22.680894 | controller |
2025-10-03 04:25:22.681124 | controller | Friday 03 October 2025  04:25:22 +0000 (0:01:05.114)       0:02:19.415 ********
2025-10-03 04:25:22.681321 | controller | ===============================================================================
2025-10-03 04:25:22.681519 | controller | ci_nmstate : Wait for nsmtate nncp to be ready ------------------------- 65.11s
2025-10-03 04:25:22.681733 | controller | ci_nmstate : Wait for nsmtate Deployments to be ready ------------------ 31.47s
2025-10-03 04:25:22.681929 | controller | ci_nmstate : Wait for nmstate operator to be installed ----------------- 21.27s
2025-10-03 04:25:22.682163 | controller | Fetch crc network facts ------------------------------------------------- 5.28s
2025-10-03 04:25:22.682364 | controller | ci_nmstate : Create the OLM subscription resources ---------------------- 1.61s
2025-10-03 04:25:22.682553 | controller | Gathering Facts --------------------------------------------------------- 1.18s
2025-10-03 04:25:22.682743 | controller | ci_nmstate : Get k8s nodes ---------------------------------------------- 1.10s
2025-10-03 04:25:22.682934 | controller | ci_nmstate : Wait for nsmtate DaemonSet to be ready --------------------- 1.09s
2025-10-03 04:25:22.683156 | controller | ci_nmstate : Create the nmstate namespace ------------------------------- 1.04s
2025-10-03 04:25:22.683351 | controller | ci_nmstate : Provision nmstate nncp for the node worker ----------------- 0.97s
2025-10-03 04:25:22.683541 | controller | ci_nmstate : Save k8s nmstate OLM manifests as artifacts ---------------- 0.93s
2025-10-03 04:25:22.683747 | controller | networking_mapper : Save instances refreshed facts for troubleshooting purposes --- 0.88s
2025-10-03 04:25:22.683943 | controller | ci_nmstate : Trigger nmstate operator to install nmstate ---------------- 0.79s
2025-10-03 04:25:22.684170 | controller | Add a dummy ethernet interface to target the crc NMstate ---------------- 0.57s
2025-10-03 04:25:22.684366 | controller | ci_nmstate : Save nmstate operator config as an artifact ---------------- 0.54s
2025-10-03 04:25:22.684562 | controller | networking_mapper : Write the Networking Environment Definition to file --- 0.53s
2025-10-03 04:25:22.684749 | controller | ci_nmstate : Save k8s nmstate nncp manifests as artifacts --------------- 0.53s
2025-10-03 04:25:22.684937 | controller | networking_mapper : Ensure CI infrastructure dir exists ----------------- 0.53s
2025-10-03 04:25:22.685165 | controller | networking_mapper : Write the Networking Definition to file ------------- 0.52s
2025-10-03 04:25:22.685370 | controller | Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.42s
2025-10-03 04:25:22.769122 | 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-10-03 04:25:22.770175 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-10-03 04:25:22.792908 | controller | INFO     Running default > cleanup
2025-10-03 04:25:22.794082 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-03 04:25:22.814194 | controller | INFO     Running default > destroy
2025-10-03 04:25:22.815066 | controller | WARNING  Skipping, instances are delegated.
2025-10-03 04:25:22.815595 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-03 04:25:22.817759 | controller | INFO     Writing /tmp/report.html report.
2025-10-03 04:25:23.306649 | controller | ERROR
2025-10-03 04:25:23.306883 | controller | {
2025-10-03 04:25:23.306924 | controller |   "delta": "0:03:42.805005",
2025-10-03 04:25:23.306954 | controller |   "end": "2025-10-03 04:25:22.885426",
2025-10-03 04:25:23.306981 | controller |   "msg": "non-zero return code",
2025-10-03 04:25:23.307007 | controller |   "rc": 1,
2025-10-03 04:25:23.307066 | controller |   "start": "2025-10-03 04:21:40.080421"
2025-10-03 04:25:23.307094 | controller | }
failure
2025-10-03 04:25:23.319502 | 
2025-10-03 04:25:23.319614 | PLAY RECAP
2025-10-03 04:25:23.319669 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:25:23.319697 | 
2025-10-03 04:25:23.422520 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:25:23.424184 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:25:24.002337 | 
2025-10-03 04:25:24.002438 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-03 04:25:24.022730 | 
2025-10-03 04:25:24.022826 | TASK [Filter out host if needed]
2025-10-03 04:25:24.031280 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-03 04:25:24.035764 | 
2025-10-03 04:25:24.035825 | TASK [Ensure file is present]
2025-10-03 04:25:24.603510 | controller | ok
2025-10-03 04:25:24.615882 | 
2025-10-03 04:25:24.616046 | TASK [Manage molecule report file]
2025-10-03 04:25:25.178800 | controller | changed
2025-10-03 04:25:25.189784 | 
2025-10-03 04:25:25.189915 | TASK [Check if we get ci-framework-data basedir]
2025-10-03 04:25:25.593925 | controller | ok
2025-10-03 04:25:25.605353 | 
2025-10-03 04:25:25.605487 | TASK [Create ci-framework-data log directory for zuul]
2025-10-03 04:25:26.163337 | controller | changed
2025-10-03 04:25:26.176249 | 
2025-10-03 04:25:26.176387 | TASK [Copy ci-framework interesting files]
2025-10-03 04:25:26.504251 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-03 04:25:26.723963 | controller | changed
2025-10-03 04:25:26.736132 | 
2025-10-03 04:25:26.736272 | TASK [Get SELinux listing]
2025-10-03 04:25:27.291836 | controller | changed
2025-10-03 04:25:27.303947 | 
2025-10-03 04:25:27.304116 | TASK [Generate log index]
2025-10-03 04:25:28.829499 | controller | changed
2025-10-03 04:25:28.841081 | 
2025-10-03 04:25:28.841248 | TASK [Get some env related data]
2025-10-03 04:25:29.589136 | controller | /home/zuul/.local/bin/ansible
2025-10-03 04:25:30.399720 | controller | changed
2025-10-03 04:25:30.448134 | 
2025-10-03 04:25:30.448301 | TASK [Generate list of logs to collect in home directory]
2025-10-03 04:25:30.872366 | controller | ok: All paths examined
2025-10-03 04:25:30.885235 | 
2025-10-03 04:25:30.885374 | LOOP [Copy logs from home directory]
2025-10-03 04:25:31.675469 | controller | changed:
2025-10-03 04:25:31.676061 | controller | {
2025-10-03 04:25:31.676136 | controller |   "atime": 1743544925.4788878,
2025-10-03 04:25:31.676180 | controller |   "ctime": 1743545329.1409318,
2025-10-03 04:25:31.676218 | controller |   "dev": 64513,
2025-10-03 04:25:31.676255 | controller |   "gid": 1000,
2025-10-03 04:25:31.676291 | controller |   "gr_name": "zuul",
2025-10-03 04:25:31.676328 | controller |   "inode": 4518807,
2025-10-03 04:25:31.676364 | controller |   "isblk": false,
2025-10-03 04:25:31.676398 | controller |   "ischr": false,
2025-10-03 04:25:31.676431 | controller |   "isdir": false,
2025-10-03 04:25:31.676462 | controller |   "isfifo": false,
2025-10-03 04:25:31.676494 | controller |   "isgid": false,
2025-10-03 04:25:31.676525 | controller |   "islnk": false,
2025-10-03 04:25:31.676557 | controller |   "isreg": true,
2025-10-03 04:25:31.676589 | controller |   "issock": false,
2025-10-03 04:25:31.676620 | controller |   "isuid": false,
2025-10-03 04:25:31.676652 | controller |   "mode": "0644",
2025-10-03 04:25:31.676683 | controller |   "mtime": 1743545329.1409318,
2025-10-03 04:25:31.676714 | controller |   "nlink": 1,
2025-10-03 04:25:31.676746 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-03 04:25:31.676789 | controller |   "pw_name": "zuul",
2025-10-03 04:25:31.676823 | controller |   "rgrp": true,
2025-10-03 04:25:31.676856 | controller |   "roth": true,
2025-10-03 04:25:31.676888 | controller |   "rusr": true,
2025-10-03 04:25:31.676921 | controller |   "size": 4108,
2025-10-03 04:25:31.676954 | controller |   "uid": 1000,
2025-10-03 04:25:31.676985 | controller |   "wgrp": false,
2025-10-03 04:25:31.677044 | controller |   "woth": false,
2025-10-03 04:25:31.677108 | controller |   "wusr": true,
2025-10-03 04:25:31.677157 | controller |   "xgrp": false,
2025-10-03 04:25:31.677197 | controller |   "xoth": false,
2025-10-03 04:25:31.677232 | controller |   "xusr": false
2025-10-03 04:25:31.677264 | controller | }
2025-10-03 04:25:32.401420 | controller | changed:
2025-10-03 04:25:32.401763 | controller | {
2025-10-03 04:25:32.401792 | controller |   "atime": 1759464549.1084194,
2025-10-03 04:25:32.401811 | controller |   "ctime": 1759464546.832359,
2025-10-03 04:25:32.401828 | controller |   "dev": 64513,
2025-10-03 04:25:32.401844 | controller |   "gid": 1000,
2025-10-03 04:25:32.401859 | controller |   "gr_name": "zuul",
2025-10-03 04:25:32.401874 | controller |   "inode": 4194437,
2025-10-03 04:25:32.401896 | controller |   "isblk": false,
2025-10-03 04:25:32.401911 | controller |   "ischr": false,
2025-10-03 04:25:32.401925 | controller |   "isdir": false,
2025-10-03 04:25:32.401940 | controller |   "isfifo": false,
2025-10-03 04:25:32.401954 | controller |   "isgid": false,
2025-10-03 04:25:32.401968 | controller |   "islnk": false,
2025-10-03 04:25:32.401983 | controller |   "isreg": true,
2025-10-03 04:25:32.401998 | controller |   "issock": false,
2025-10-03 04:25:32.402038 | controller |   "isuid": false,
2025-10-03 04:25:32.402059 | controller |   "mode": "0644",
2025-10-03 04:25:32.402075 | controller |   "mtime": 1759464546.832359,
2025-10-03 04:25:32.402090 | controller |   "nlink": 1,
2025-10-03 04:25:32.402106 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-03 04:25:32.402120 | controller |   "pw_name": "zuul",
2025-10-03 04:25:32.402136 | controller |   "rgrp": true,
2025-10-03 04:25:32.402151 | controller |   "roth": true,
2025-10-03 04:25:32.402165 | controller |   "rusr": true,
2025-10-03 04:25:32.402180 | controller |   "size": 3229,
2025-10-03 04:25:32.402195 | controller |   "uid": 1000,
2025-10-03 04:25:32.402209 | controller |   "wgrp": false,
2025-10-03 04:25:32.402224 | controller |   "woth": false,
2025-10-03 04:25:32.402238 | controller |   "wusr": true,
2025-10-03 04:25:32.402252 | controller |   "xgrp": false,
2025-10-03 04:25:32.402266 | controller |   "xoth": false,
2025-10-03 04:25:32.402281 | controller |   "xusr": false
2025-10-03 04:25:32.402295 | controller | }
2025-10-03 04:25:33.186240 | controller | changed:
2025-10-03 04:25:33.186417 | controller | {
2025-10-03 04:25:33.186473 | controller |   "atime": 1759465280.5398998,
2025-10-03 04:25:33.186523 | controller |   "ctime": 1759465297.1723423,
2025-10-03 04:25:33.186564 | controller |   "dev": 64513,
2025-10-03 04:25:33.186608 | controller |   "gid": 1000,
2025-10-03 04:25:33.186646 | controller |   "gr_name": "zuul",
2025-10-03 04:25:33.186689 | controller |   "inode": 4233412,
2025-10-03 04:25:33.186731 | controller |   "isblk": false,
2025-10-03 04:25:33.186772 | controller |   "ischr": false,
2025-10-03 04:25:33.186812 | controller |   "isdir": false,
2025-10-03 04:25:33.186852 | controller |   "isfifo": false,
2025-10-03 04:25:33.186891 | controller |   "isgid": false,
2025-10-03 04:25:33.186931 | controller |   "islnk": false,
2025-10-03 04:25:33.186971 | controller |   "isreg": true,
2025-10-03 04:25:33.187011 | controller |   "issock": false,
2025-10-03 04:25:33.187094 | controller |   "isuid": false,
2025-10-03 04:25:33.187137 | controller |   "mode": "0644",
2025-10-03 04:25:33.187179 | controller |   "mtime": 1759465297.1723423,
2025-10-03 04:25:33.187220 | controller |   "nlink": 1,
2025-10-03 04:25:33.187260 | controller |   "path": "/home/zuul/ansible.log",
2025-10-03 04:25:33.187302 | controller |   "pw_name": "zuul",
2025-10-03 04:25:33.187346 | controller |   "rgrp": true,
2025-10-03 04:25:33.187391 | controller |   "roth": true,
2025-10-03 04:25:33.187439 | controller |   "rusr": true,
2025-10-03 04:25:33.187482 | controller |   "size": 6749,
2025-10-03 04:25:33.187523 | controller |   "uid": 1000,
2025-10-03 04:25:33.187564 | controller |   "wgrp": false,
2025-10-03 04:25:33.187605 | controller |   "woth": false,
2025-10-03 04:25:33.187645 | controller |   "wusr": true,
2025-10-03 04:25:33.187684 | controller |   "xgrp": false,
2025-10-03 04:25:33.187725 | controller |   "xoth": false,
2025-10-03 04:25:33.187764 | controller |   "xusr": false
2025-10-03 04:25:33.187804 | controller | }
2025-10-03 04:25:33.204647 | 
2025-10-03 04:25:33.204834 | TASK [Copy crio stats log file]
2025-10-03 04:25:33.231951 | controller | skipping: Conditional result was False
2025-10-03 04:25:33.245743 | 
2025-10-03 04:25:33.245874 | TASK [Get SELinux related data]
2025-10-03 04:25:33.630960 | controller | <no matches>
2025-10-03 04:25:33.793326 | controller | ERROR
2025-10-03 04:25:33.793539 | controller | {
2025-10-03 04:25:33.793600 | controller |   "delta": "0:00:00.013171",
2025-10-03 04:25:33.793642 | controller |   "end": "2025-10-03 04:25:33.633770",
2025-10-03 04:25:33.793702 | controller |   "msg": "non-zero return code",
2025-10-03 04:25:33.793888 | controller |   "rc": 1,
2025-10-03 04:25:33.793938 | controller |   "start": "2025-10-03 04:25:33.620599"
2025-10-03 04:25:33.793976 | controller | }
2025-10-03 04:25:33.794058 | controller | ERROR: Ignoring Errors
2025-10-03 04:25:33.804937 | 
2025-10-03 04:25:33.805093 | TASK [Create system configuration directory]
2025-10-03 04:25:34.151410 | controller | changed
2025-10-03 04:25:34.164973 | 
2025-10-03 04:25:34.165255 | TASK [Get some of the system configurations]
2025-10-03 04:25:34.724732 | controller | changed
2025-10-03 04:25:34.736523 | 
2025-10-03 04:25:34.736655 | TASK [Copy generated documentation if available]
2025-10-03 04:25:34.763912 | controller | skipping: Conditional result was False
2025-10-03 04:25:34.778180 | 
2025-10-03 04:25:34.778388 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-03 04:25:34.805407 | controller | skipping: Conditional result was False
2025-10-03 04:25:34.818524 | 
2025-10-03 04:25:34.818727 | TASK [Compress logs bigger than 2MB]
2025-10-03 04:25:35.368443 | controller | changed
2025-10-03 04:25:35.375797 | 
2025-10-03 04:25:35.375875 | TASK [Copy files from workspace on node]
2025-10-03 04:25:35.397791 | controller | ok
2025-10-03 04:25:35.429973 | 
2025-10-03 04:25:35.430123 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:25:35.464927 | controller | skipping: Conditional result was False
2025-10-03 04:25:35.470631 | 
2025-10-03 04:25:35.470701 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:25:35.511483 | controller | ok
2025-10-03 04:25:35.518841 | 
2025-10-03 04:25:35.518932 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:25:35.771662 | controller -> localhost | ok: "/var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/logs"
2025-10-03 04:25:35.771888 | controller -> localhost | changed: All items complete
2025-10-03 04:25:35.771917 | 
2025-10-03 04:25:35.995602 | controller -> localhost | changed: "/var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/artifacts"
2025-10-03 04:25:36.196632 | controller -> localhost | changed: "/var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/docs"
2025-10-03 04:25:36.211744 | 
2025-10-03 04:25:36.211853 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:25:37.033864 | controller | changed:
2025-10-03 04:25:37.034203 | controller | .d..t...... ./
2025-10-03 04:25:37.034263 | controller | >f+++++++++ README.html
2025-10-03 04:25:37.034308 | controller | >f+++++++++ ansible-execution.log
2025-10-03 04:25:37.034350 | controller | >f+++++++++ ansible.log
2025-10-03 04:25:37.034390 | controller | >f+++++++++ crc-setup.log
2025-10-03 04:25:37.034428 | controller | >f+++++++++ crc-start.log
2025-10-03 04:25:37.034465 | controller | >f+++++++++ dmesg.log
2025-10-03 04:25:37.034510 | controller | >f+++++++++ installed-pkgs.log
2025-10-03 04:25:37.034549 | controller | >f+++++++++ python.log
2025-10-03 04:25:37.034615 | controller | >f+++++++++ registries.conf
2025-10-03 04:25:37.034654 | controller | >f+++++++++ report.html
2025-10-03 04:25:37.034690 | controller | >f+++++++++ selinux-denials.log
2025-10-03 04:25:37.034726 | controller | >f+++++++++ selinux-listing.log
2025-10-03 04:25:37.034762 | controller | cd+++++++++ ci-framework-data/
2025-10-03 04:25:37.034799 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-03 04:25:37.034836 | controller | >f+++++++++ ci-framework-data/artifacts/networking-mapper-instances-facts.yml
2025-10-03 04:25:37.034874 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-03 04:25:37.034911 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-03 04:25:37.034948 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/nmstate/
2025-10-03 04:25:37.034983 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/crc-nncp.yaml
2025-10-03 04:25:37.035069 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-nmstate-olm.yaml
2025-10-03 04:25:37.035129 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-operatorgroup-olm.yaml
2025-10-03 04:25:37.035169 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-subscription-olm.yaml
2025-10-03 04:25:37.035208 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-03 04:25:37.035246 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-03 04:25:37.035283 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-03 04:25:37.035320 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-03 04:25:37.035358 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-03 04:25:37.035395 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-03 04:25:37.035431 | controller | cd+++++++++ registries.conf.d/
2025-10-03 04:25:37.035468 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-03 04:25:37.035505 | controller | cd+++++++++ system-config/
2025-10-03 04:25:37.035542 | controller | cd+++++++++ system-config/libvirt/
2025-10-03 04:25:37.035578 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-03 04:25:37.035613 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-03 04:25:37.035649 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-03 04:25:37.035685 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-03 04:25:37.035721 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-03 04:25:37.035757 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-03 04:25:37.035792 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-03 04:25:37.035828 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-03 04:25:37.035864 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-03 04:25:37.035901 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-03 04:25:37.035937 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-03 04:25:37.035973 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-03 04:25:37.036009 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-03 04:25:37.036088 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-03 04:25:37.036126 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-03 04:25:37.036162 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-03 04:25:37.636243 | controller | changed: .d..t...... ./
2025-10-03 04:25:38.254713 | controller | changed: .d..t...... ./
2025-10-03 04:25:38.282617 | 
2025-10-03 04:25:38.282804 | TASK [Return artifact to Zuul]
2025-10-03 04:25:38.342959 | controller | ok
2025-10-03 04:25:38.383928 | 
2025-10-03 04:25:38.384073 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-03 04:25:38.384255 | 
2025-10-03 04:25:38.384308 | PLAY RECAP
2025-10-03 04:25:38.384379 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-03 04:25:38.384421 | 
2025-10-03 04:25:38.490480 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:25:38.492308 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-03 04:25:39.085756 | 
2025-10-03 04:25:39.085864 | PLAY [Report DLRN results after job run]
2025-10-03 04:25:39.106112 | 
2025-10-03 04:25:39.106189 | TASK [Report job status to DLRN]
2025-10-03 04:25:39.125145 | controller | ok
2025-10-03 04:25:39.148082 | 
2025-10-03 04:25:39.148187 | TASK [dlrn_report : Warning if DLRN is not running]
2025-10-03 04:25:39.213371 | controller | skipping: Conditional result was False
2025-10-03 04:25:39.223298 | 
2025-10-03 04:25:39.223449 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-10-03 04:25:39.278696 | controller | skipping: Conditional result was False
2025-10-03 04:25:39.284810 | 
2025-10-03 04:25:39.284882 | TASK [dlrn_report : Install kinit related package]
2025-10-03 04:25:39.328761 | controller | skipping: Conditional result was False
2025-10-03 04:25:39.334595 | 
2025-10-03 04:25:39.334669 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-10-03 04:25:39.378445 | controller | skipping: Conditional result was False
2025-10-03 04:25:39.384182 | 
2025-10-03 04:25:39.384246 | TASK [Set zuul-log-path fact]
2025-10-03 04:25:39.431875 | controller | skipping: Conditional result was False
2025-10-03 04:25:39.443884 | 
2025-10-03 04:25:39.444059 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-10-03 04:25:39.490672 | controller | skipping: Conditional result was False
2025-10-03 04:25:39.502660 | 
2025-10-03 04:25:39.502798 | TASK [Get hash related data from repo_setup role]
2025-10-03 04:25:39.549147 | controller | skipping: Conditional result was False
2025-10-03 04:25:39.561939 | 
2025-10-03 04:25:39.562127 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-10-03 04:25:40.113305 | controller | skipping: Conditional result was False
2025-10-03 04:25:40.127403 | 
2025-10-03 04:25:40.127635 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-10-03 04:25:40.207758 | controller | skipping: Conditional result was False
2025-10-03 04:25:40.222732 | 
2025-10-03 04:25:40.223105 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-10-03 04:25:40.778994 | controller | skipping: Conditional result was False
2025-10-03 04:25:40.834426 | 
2025-10-03 04:25:40.834640 | PLAY RECAP
2025-10-03 04:25:40.834710 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-10-03 04:25:40.834745 | 
2025-10-03 04:25:40.983069 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-03 04:25:40.984917 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:25:41.564218 | 
2025-10-03 04:25:41.564325 | PLAY [all]
2025-10-03 04:25:41.582878 | 
2025-10-03 04:25:41.582951 | TASK [include_role : fetch-output]
2025-10-03 04:25:41.611355 | controller | ok
2025-10-03 04:25:41.628035 | 
2025-10-03 04:25:41.628144 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:25:41.683237 | controller | skipping: Conditional result was False
2025-10-03 04:25:41.692841 | 
2025-10-03 04:25:41.693039 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:25:41.730582 | controller | ok
2025-10-03 04:25:41.741335 | 
2025-10-03 04:25:41.741422 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:25:42.180346 | controller -> localhost | ok: "/var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/logs"
2025-10-03 04:25:42.443337 | controller -> localhost | ok: "/var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/artifacts"
2025-10-03 04:25:42.655826 | controller -> localhost | ok: "/var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/docs"
2025-10-03 04:25:42.667010 | 
2025-10-03 04:25:42.667136 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:25:43.417230 | controller | ok
2025-10-03 04:25:43.417505 | controller | ok: All items complete
2025-10-03 04:25:43.417550 | 
2025-10-03 04:25:44.016594 | controller | ok
2025-10-03 04:25:44.636835 | controller | ok
2025-10-03 04:25:44.665115 | 
2025-10-03 04:25:44.665312 | TASK [include_role : fetch-output-openshift]
2025-10-03 04:25:44.692176 | controller | skipping: Conditional result was False
2025-10-03 04:25:44.705229 | 
2025-10-03 04:25:44.705415 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-03 04:25:45.177575 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012727
2025-10-03 04:25:45.462182 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012700
2025-10-03 04:25:45.512680 | 
2025-10-03 04:25:45.512808 | PLAY [all]
2025-10-03 04:25:45.533104 | 
2025-10-03 04:25:45.533195 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-03 04:25:46.181131 | controller | changed
2025-10-03 04:25:46.228399 | 
2025-10-03 04:25:46.228509 | PLAY RECAP
2025-10-03 04:25:46.228564 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 04:25:46.228592 | 
2025-10-03 04:25:46.323978 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:25:46.324764 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-03 04:25:46.971731 | 
2025-10-03 04:25:46.971844 | PLAY [localhost]
2025-10-03 04:25:46.990644 | 
2025-10-03 04:25:46.990760 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-03 04:25:47.421351 | localhost | changed
2025-10-03 04:25:47.432172 | 
2025-10-03 04:25:47.432389 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-03 04:25:47.471731 | localhost | ok
2025-10-03 04:25:47.491740 | 
2025-10-03 04:25:47.491931 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-03 04:25:47.888529 | localhost | changed
2025-10-03 04:25:47.898757 | 
2025-10-03 04:25:47.898927 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-03 04:25:48.662114 | localhost | changed
2025-10-03 04:25:48.667216 | 
2025-10-03 04:25:48.667285 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-03 04:25:49.108772 | localhost | Identity added: /var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/tmp/ansible.symnp573 (/var/lib/zuul/builds/a9648dd01c7b469fa811929128d5a755/work/tmp/ansible.symnp573)
2025-10-03 04:25:49.109161 | localhost | ok: Runtime: 0:00:00.012238
2025-10-03 04:25:49.118756 | 
2025-10-03 04:25:49.118894 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-03 04:25:49.429063 | localhost | ok: Runtime: 0:00:00.004944
2025-10-03 04:25:49.434309 | 
2025-10-03 04:25:49.434375 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-03 04:25:49.507712 | localhost | changed
2025-10-03 04:25:49.516773 | 
2025-10-03 04:25:49.516905 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-03 04:25:49.954478 | localhost | changed
2025-10-03 04:25:49.973952 | 
2025-10-03 04:25:49.974004 | PLAY [localhost]
2025-10-03 04:25:49.985853 | 
2025-10-03 04:25:49.985911 | TASK [Generate bulk log download script]
2025-10-03 04:25:50.003676 | localhost | ok
2025-10-03 04:25:50.014847 | 
2025-10-03 04:25:50.014913 | TASK [local-log-download : Check API endpoint is defined]
2025-10-03 04:25:50.054854 | localhost | ok: All assertions passed
2025-10-03 04:25:50.061151 | 
2025-10-03 04:25:50.061235 | TASK [local-log-download : Create download script]
2025-10-03 04:25:50.512938 | localhost -> localhost | changed
2025-10-03 04:25:50.521725 | 
2025-10-03 04:25:50.521817 | TASK [Register quick-download link]
2025-10-03 04:25:50.550186 | localhost | ok
2025-10-03 04:25:50.589729 | 
2025-10-03 04:25:50.589842 | PLAY [logserver.rdoproject.org]
2025-10-03 04:25:50.599277 | 
2025-10-03 04:25:50.599382 | TASK [Set zuul-log-path fact]
2025-10-03 04:25:50.615224 | logserver.rdoproject.org | ok
2025-10-03 04:25:50.623979 | 
2025-10-03 04:25:50.624098 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 04:25:50.650674 | logserver.rdoproject.org | ok
2025-10-03 04:25:50.655874 | 
2025-10-03 04:25:50.655945 | TASK [upload-logs : Create log directories]
2025-10-03 04:25:51.526761 | logserver.rdoproject.org | changed
2025-10-03 04:25:51.534562 | 
2025-10-03 04:25:51.534788 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-03 04:25:51.874821 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009565
2025-10-03 04:25:51.884912 | 
2025-10-03 04:25:51.885525 | TASK [upload-logs : Upload logs to log server]
2025-10-03 04:25:52.653507 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-03 04:25:52.659486 | 
2025-10-03 04:25:52.659615 | LOOP [upload-logs : Compress console log and json output]
2025-10-03 04:25:52.701614 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:25:52.709096 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:25:52.722930 | 
2025-10-03 04:25:52.723104 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-03 04:25:52.761227 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:25:52.761617 | 
2025-10-03 04:25:52.764596 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:25:52.776148 | 
2025-10-03 04:25:52.776279 | LOOP [upload-logs : Upload console log and json output]