2025-10-08 15:47:35.292689 | Job console starting...
2025-10-08 15:47:35.556716 | Updating repositories
2025-10-08 15:47:36.281130 | Preparing job workspace
2025-10-08 15:47:43.284401 | Running Ansible setup...
2025-10-08 15:47:47.048116 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 15:47:47.685504 | 
2025-10-08 15:47:47.685627 | PLAY [localhost]
2025-10-08 15:47:47.702461 | 
2025-10-08 15:47:47.702552 | TASK [Gathering Facts]
2025-10-08 15:47:48.727923 | localhost | ok
2025-10-08 15:47:48.741596 | 
2025-10-08 15:47:48.741695 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 15:47:49.142957 | localhost -> localhost | changed
2025-10-08 15:47:49.148421 | 
2025-10-08 15:47:49.148493 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 15:47:49.923246 | localhost -> localhost | changed
2025-10-08 15:47:49.931815 | 
2025-10-08 15:47:49.931884 | TASK [Setup log path fact]
2025-10-08 15:47:49.950040 | localhost | ok
2025-10-08 15:47:49.961152 | 
2025-10-08 15:47:49.961222 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 15:47:49.987676 | localhost | ok
2025-10-08 15:47:49.995395 | 
2025-10-08 15:47:49.995474 | TASK [emit-job-header : Print job information]
2025-10-08 15:47:50.023351 | # Job Information
2025-10-08 15:47:50.023489 | Ansible Version: 2.15.12
2025-10-08 15:47:50.023520 | Job: cifmw-molecule-rhol_crc
2025-10-08 15:47:50.023543 | Pipeline: github-check
2025-10-08 15:47:50.023564 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 15:47:50.023584 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3385
2025-10-08 15:47:50.023604 | Log URL (when completed): https://logserver.rdoproject.org/17d/rdoproject.org/17d727d03c3e457d98fcd36282cd841a/
2025-10-08 15:47:50.023624 | Event ID: 32fe2bb0-a45c-11f0-9dbb-741a4abaa18c
2025-10-08 15:47:50.027263 | 
2025-10-08 15:47:50.027343 | LOOP [emit-job-header : Print node information]
2025-10-08 15:47:50.120203 | localhost | ok:
2025-10-08 15:47:50.120412 | localhost | # Node Information
2025-10-08 15:47:50.120444 | localhost | Inventory Hostname: controller
2025-10-08 15:47:50.120470 | localhost | Hostname: np0005477281
2025-10-08 15:47:50.120492 | localhost | Username: zuul
2025-10-08 15:47:50.120513 | localhost | Distro: CentOS 9
2025-10-08 15:47:50.120531 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 15:47:50.120548 | localhost | Region: regionOne
2025-10-08 15:47:50.120565 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-10-08 15:47:50.120582 | localhost | Product Name: OpenStack Compute
2025-10-08 15:47:50.120599 | localhost | Interface IP: 192.168.26.216
2025-10-08 15:47:50.141926 | 
2025-10-08 15:47:50.142018 | PLAY [all]
2025-10-08 15:47:50.149484 | 
2025-10-08 15:47:50.149566 | TASK [Gather network facts]
2025-10-08 15:47:50.575287 | controller | ok
2025-10-08 15:47:50.596598 | 
2025-10-08 15:47:50.596698 | TASK [include_role : start-zuul-console]
2025-10-08 15:47:50.615096 | controller | ok
2025-10-08 15:47:50.628758 | 
2025-10-08 15:47:50.628846 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 15:47:51.013362 | controller | ok
2025-10-08 15:47:51.023688 | 
2025-10-08 15:47:51.023784 | TASK [include_role : add-build-sshkey]
2025-10-08 15:47:51.042134 | controller | ok
2025-10-08 15:47:51.055691 | 
2025-10-08 15:47:51.055892 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 15:47:51.274102 | controller -> localhost | ok
2025-10-08 15:47:51.281029 | 
2025-10-08 15:47:51.281113 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 15:47:51.312328 | controller | ok
2025-10-08 15:47:51.329185 | controller | included: /var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 15:47:51.336177 | 
2025-10-08 15:47:51.336260 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 15:47:51.891464 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 15:47:51.891650 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/17d727d03c3e457d98fcd36282cd841a_id_rsa.
2025-10-08 15:47:51.891689 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/17d727d03c3e457d98fcd36282cd841a_id_rsa.pub.
2025-10-08 15:47:51.891715 | controller -> localhost | The key fingerprint is:
2025-10-08 15:47:51.891737 | controller -> localhost | SHA256:poFYdzaZkJaMNiZkv/0O93F+f3CsvXbikdnxSSSy5vs zuul-build-sshkey
2025-10-08 15:47:51.891759 | controller -> localhost | The key's randomart image is:
2025-10-08 15:47:51.891778 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 15:47:51.891798 | controller -> localhost | | .o  o.o         |
2025-10-08 15:47:51.891818 | controller -> localhost | | ...= =. o       |
2025-10-08 15:47:51.891838 | controller -> localhost | |   +oo. *  . . . |
2025-10-08 15:47:51.891857 | controller -> localhost | |   o = o .  o o  |
2025-10-08 15:47:51.891875 | controller -> localhost | |  . o o S  o   + |
2025-10-08 15:47:51.891893 | controller -> localhost | |       =  o   o+*|
2025-10-08 15:47:51.891951 | controller -> localhost | |      o o ... +*o|
2025-10-08 15:47:51.891982 | controller -> localhost | |       + . +. +o+|
2025-10-08 15:47:51.892004 | controller -> localhost | |        . ..oEo+=|
2025-10-08 15:47:51.892024 | controller -> localhost | +----[SHA256]-----+
2025-10-08 15:47:51.892073 | controller -> localhost | ok: Runtime: 0:00:00.087160
2025-10-08 15:47:51.898284 | 
2025-10-08 15:47:51.898368 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 15:47:51.927530 | controller | ok
2025-10-08 15:47:51.938997 | controller | included: /var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 15:47:51.948058 | 
2025-10-08 15:47:51.948129 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 15:47:51.972120 | controller | skipping: Conditional result was False
2025-10-08 15:47:51.978953 | 
2025-10-08 15:47:51.979026 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 15:47:52.651559 | controller | changed
2025-10-08 15:47:52.669557 | 
2025-10-08 15:47:52.669667 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 15:47:52.933573 | controller | ok
2025-10-08 15:47:52.938991 | 
2025-10-08 15:47:52.939082 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 15:47:54.049012 | controller | changed
2025-10-08 15:47:54.054968 | 
2025-10-08 15:47:54.055048 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 15:47:55.166664 | controller | changed
2025-10-08 15:47:55.181222 | 
2025-10-08 15:47:55.181322 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 15:47:55.205646 | controller | skipping: Conditional result was False
2025-10-08 15:47:55.211563 | 
2025-10-08 15:47:55.211639 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 15:47:55.584654 | controller -> localhost | changed
2025-10-08 15:47:55.595785 | 
2025-10-08 15:47:55.595885 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 15:47:55.858189 | controller -> localhost | Identity added: /var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/17d727d03c3e457d98fcd36282cd841a_id_rsa (zuul-build-sshkey)
2025-10-08 15:47:55.858442 | controller -> localhost | ok: Runtime: 0:00:00.008326
2025-10-08 15:47:55.866043 | 
2025-10-08 15:47:55.866121 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 15:47:56.253700 | controller | ok
2025-10-08 15:47:56.258768 | 
2025-10-08 15:47:56.258838 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 15:47:56.317357 | controller | skipping: Conditional result was False
2025-10-08 15:47:56.327949 | 
2025-10-08 15:47:56.328023 | TASK [include_role : validate-host]
2025-10-08 15:47:56.360594 | controller | ok
2025-10-08 15:47:56.406888 | 
2025-10-08 15:47:56.407053 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 15:47:56.481522 | controller | ok
2025-10-08 15:47:56.491002 | 
2025-10-08 15:47:56.491086 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 15:47:56.824854 | controller -> localhost | ok
2025-10-08 15:47:56.860209 | 
2025-10-08 15:47:56.860433 | TASK [validate-host : Collect information about the host]
2025-10-08 15:47:57.536942 | controller | ok
2025-10-08 15:47:57.545979 | 
2025-10-08 15:47:57.546071 | TASK [validate-host : Sanitize hostname]
2025-10-08 15:47:57.599757 | controller | ok
2025-10-08 15:47:57.605287 | 
2025-10-08 15:47:57.605419 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 15:47:58.193248 | controller -> localhost | changed
2025-10-08 15:47:58.199263 | 
2025-10-08 15:47:58.199350 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 15:47:58.603060 | controller | ok
2025-10-08 15:47:58.608734 | 
2025-10-08 15:47:58.608826 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 15:47:59.024775 | controller -> localhost | changed
2025-10-08 15:47:59.036472 | 
2025-10-08 15:47:59.036581 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 15:47:59.050482 | controller | skipping: Conditional result was False
2025-10-08 15:47:59.057651 | 
2025-10-08 15:47:59.057738 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 15:47:59.091866 | controller | skipping: Conditional result was False
2025-10-08 15:47:59.097848 | 
2025-10-08 15:47:59.097957 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 15:47:59.352072 | controller | ok: "logs"
2025-10-08 15:47:59.352318 | controller | ok: All items complete
2025-10-08 15:47:59.352350 | 
2025-10-08 15:47:59.579202 | controller | ok: "artifacts"
2025-10-08 15:47:59.812623 | controller | ok: "docs"
2025-10-08 15:47:59.827822 | 
2025-10-08 15:47:59.827937 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 15:48:00.082170 | controller | changed: "logs"
2025-10-08 15:48:00.313169 | controller | changed: "artifacts"
2025-10-08 15:48:00.534642 | controller | changed: "docs"
2025-10-08 15:48:00.565061 | 
2025-10-08 15:48:00.565140 | PLAY RECAP
2025-10-08 15:48:00.565187 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 15:48:00.565216 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 15:48:00.565237 | 
2025-10-08 15:48:00.641064 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 15:48:00.641794 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 15:48:01.238838 | 
2025-10-08 15:48:01.238963 | PLAY [all]
2025-10-08 15:48:01.260065 | 
2025-10-08 15:48:01.260194 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 15:48:01.319439 | controller | ok
2025-10-08 15:48:01.324789 | 
2025-10-08 15:48:01.324895 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 15:48:01.769456 | controller | changed
2025-10-08 15:48:01.776267 | 
2025-10-08 15:48:01.776365 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 15:48:03.156932 | controller | changed
2025-10-08 15:48:03.169796 | 
2025-10-08 15:48:03.169886 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 15:48:03.643088 | controller | changed:
2025-10-08 15:48:03.643259 | controller | {
2025-10-08 15:48:03.643481 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 15:48:03.643521 | controller | }
2025-10-08 15:48:03.950962 | controller | changed:
2025-10-08 15:48:03.951057 | controller | {
2025-10-08 15:48:03.951087 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 15:48:03.951109 | controller | }
2025-10-08 15:48:04.255507 | controller | changed:
2025-10-08 15:48:04.255599 | controller | {
2025-10-08 15:48:04.255628 | 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-08 15:48:04.255650 | controller | }
2025-10-08 15:48:04.545130 | controller | changed:
2025-10-08 15:48:04.545224 | controller | {
2025-10-08 15:48:04.545253 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 15:48:04.545275 | controller | }
2025-10-08 15:48:04.863760 | controller | changed:
2025-10-08 15:48:04.863835 | controller | {
2025-10-08 15:48:04.863862 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 15:48:04.863882 | controller | }
2025-10-08 15:48:05.161682 | controller | changed:
2025-10-08 15:48:05.161778 | controller | {
2025-10-08 15:48:05.161806 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 15:48:05.161829 | controller | }
2025-10-08 15:48:05.455776 | controller | changed:
2025-10-08 15:48:05.455873 | controller | {
2025-10-08 15:48:05.455901 | 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-08 15:48:05.455947 | controller | }
2025-10-08 15:48:05.739202 | controller | changed:
2025-10-08 15:48:05.739311 | controller | {
2025-10-08 15:48:05.739340 | 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-08 15:48:05.739363 | controller | }
2025-10-08 15:48:06.043781 | controller | changed:
2025-10-08 15:48:06.043880 | controller | {
2025-10-08 15:48:06.043927 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 15:48:06.043957 | controller | }
2025-10-08 15:48:06.338073 | controller | changed:
2025-10-08 15:48:06.339881 | controller | {
2025-10-08 15:48:06.339947 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 15:48:06.339975 | controller | }
2025-10-08 15:48:06.642347 | controller | changed:
2025-10-08 15:48:06.642441 | controller | {
2025-10-08 15:48:06.642469 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 15:48:06.642490 | controller | }
2025-10-08 15:48:06.936904 | controller | changed:
2025-10-08 15:48:06.939488 | controller | {
2025-10-08 15:48:06.939522 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 15:48:06.939545 | controller | }
2025-10-08 15:48:07.228983 | controller | changed:
2025-10-08 15:48:07.229070 | controller | {
2025-10-08 15:48:07.229098 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 15:48:07.229120 | controller | }
2025-10-08 15:48:07.523835 | controller | changed:
2025-10-08 15:48:07.523946 | controller | {
2025-10-08 15:48:07.523983 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 15:48:07.524006 | controller | }
2025-10-08 15:48:07.813817 | controller | changed:
2025-10-08 15:48:07.813986 | controller | {
2025-10-08 15:48:07.814025 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 15:48:07.814052 | controller | }
2025-10-08 15:48:08.111758 | controller | changed:
2025-10-08 15:48:08.111850 | controller | {
2025-10-08 15:48:08.111878 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 15:48:08.111899 | controller | }
2025-10-08 15:48:08.385929 | controller | changed:
2025-10-08 15:48:08.386021 | controller | {
2025-10-08 15:48:08.386049 | 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-08 15:48:08.386072 | controller | }
2025-10-08 15:48:08.675549 | controller | changed:
2025-10-08 15:48:08.675647 | controller | {
2025-10-08 15:48:08.675676 | 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-08 15:48:08.675706 | controller | }
2025-10-08 15:48:08.963058 | controller | changed:
2025-10-08 15:48:08.963155 | controller | {
2025-10-08 15:48:08.963184 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 15:48:08.963204 | controller | }
2025-10-08 15:48:09.262598 | controller | changed:
2025-10-08 15:48:09.262686 | controller | {
2025-10-08 15:48:09.262721 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 15:48:09.262742 | controller | }
2025-10-08 15:48:09.569257 | controller | changed:
2025-10-08 15:48:09.569364 | controller | {
2025-10-08 15:48:09.569392 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 15:48:09.569412 | controller | }
2025-10-08 15:48:09.871965 | controller | changed:
2025-10-08 15:48:09.872065 | controller | {
2025-10-08 15:48:09.872093 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 15:48:09.872113 | controller | }
2025-10-08 15:48:10.174057 | controller | changed:
2025-10-08 15:48:10.174243 | controller | {
2025-10-08 15:48:10.174279 | 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-08 15:48:10.174323 | controller | }
2025-10-08 15:48:10.464845 | controller | changed:
2025-10-08 15:48:10.464984 | controller | {
2025-10-08 15:48:10.465016 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 15:48:10.465038 | controller | }
2025-10-08 15:48:10.756295 | controller | changed:
2025-10-08 15:48:10.756409 | controller | {
2025-10-08 15:48:10.756435 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 15:48:10.756456 | controller | }
2025-10-08 15:48:11.052186 | controller | changed:
2025-10-08 15:48:11.052284 | controller | {
2025-10-08 15:48:11.052327 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 15:48:11.052349 | controller | }
2025-10-08 15:48:11.085108 | 
2025-10-08 15:48:11.085225 | TASK [Set timezone to UTC]
2025-10-08 15:48:11.596704 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 15:48:11.601807 | 
2025-10-08 15:48:11.601884 | TASK [Create nodepool directory]
2025-10-08 15:48:11.854527 | controller | changed
2025-10-08 15:48:11.864434 | 
2025-10-08 15:48:11.864558 | TASK [Create nodepool sub_nodes file]
2025-10-08 15:48:12.778778 | controller | changed
2025-10-08 15:48:12.783901 | 
2025-10-08 15:48:12.784005 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 15:48:13.752536 | controller | changed
2025-10-08 15:48:13.757763 | 
2025-10-08 15:48:13.757844 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 15:48:13.790759 | 
2025-10-08 15:48:13.790928 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 15:48:13.819335 | 
2025-10-08 15:48:13.819475 | TASK [Create nodepool primary file]
2025-10-08 15:48:13.842396 | controller | skipping: Conditional result was False
2025-10-08 15:48:13.847934 | 
2025-10-08 15:48:13.848018 | TASK [Create nodepool node_private for this node]
2025-10-08 15:48:14.827318 | controller | changed
2025-10-08 15:48:14.832719 | 
2025-10-08 15:48:14.832787 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 15:48:15.210067 | controller | ok: Item: id_rsa Runtime: 0:00:00.004255
2025-10-08 15:48:15.210249 | 
2025-10-08 15:48:15.436573 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004115
2025-10-08 15:48:15.451718 | 
2025-10-08 15:48:15.451828 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 15:48:16.459745 | controller | changed
2025-10-08 15:48:16.466222 | 
2025-10-08 15:48:16.466332 | TASK [Validate sudoers config after edits]
2025-10-08 15:48:16.762228 | controller | /etc/sudoers: parsed OK
2025-10-08 15:48:16.762298 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 15:48:16.762310 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 15:48:16.762317 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 15:48:16.998586 | controller | ok: Runtime: 0:00:00.005289
2025-10-08 15:48:17.004723 | 
2025-10-08 15:48:17.004792 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 15:48:17.275883 | controller | SHELL=/bin/bash
2025-10-08 15:48:17.275932 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 15:48:17.275943 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 15:48:17.275949 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/85/3385/f8a3c957fc51bd892aee4b1f66ce262682944823
2025-10-08 15:48:17.275966 | controller | PWD=/home/zuul
2025-10-08 15:48:17.275972 | controller | ZUUL_PIPELINE=github-check
2025-10-08 15:48:17.275977 | controller | LOGNAME=zuul
2025-10-08 15:48:17.275982 | controller | XDG_SESSION_TYPE=tty
2025-10-08 15:48:17.275987 | controller | _=/usr/bin/env
2025-10-08 15:48:17.275992 | controller | MOTD_SHOWN=pam
2025-10-08 15:48:17.275998 | controller | HOME=/home/zuul
2025-10-08 15:48:17.276037 | controller | LANG=en_US.UTF-8
2025-10-08 15:48:17.276046 | controller | SSH_CONNECTION=192.168.26.12 42350 192.168.26.216 22
2025-10-08 15:48:17.276051 | 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-08 15:48:17.276060 | controller | ZUUL_CHANGE_IDS=3385,f8a3c957fc51bd892aee4b1f66ce262682944823
2025-10-08 15:48:17.276065 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 15:48:17.276070 | controller | XDG_SESSION_CLASS=user
2025-10-08 15:48:17.276075 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 15:48:17.276080 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 15:48:17.276085 | controller | USER=zuul
2025-10-08 15:48:17.276091 | controller | ZUUL_VOTING=True
2025-10-08 15:48:17.276096 | controller | BUILD_TIMEOUT=5400000
2025-10-08 15:48:17.276101 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 15:48:17.276106 | controller | SHLVL=1
2025-10-08 15:48:17.276111 | controller | ZUUL_PATCHSET=f8a3c957fc51bd892aee4b1f66ce262682944823
2025-10-08 15:48:17.276116 | controller | XDG_SESSION_ID=1
2025-10-08 15:48:17.276121 | controller | ZUUL_BRANCH=main
2025-10-08 15:48:17.276127 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 15:48:17.276132 | controller | SSH_CLIENT=192.168.26.12 42350 22
2025-10-08 15:48:17.276137 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 15:48:17.276142 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 15:48:17.276149 | controller | which_declare=declare -f
2025-10-08 15:48:17.276154 | 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-08 15:48:17.276159 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 15:48:17.276164 | controller | ZUUL_CHANGE=3385
2025-10-08 15:48:17.276169 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 15:48:17.276174 | controller | ZUUL_UUID=17d727d03c3e457d98fcd36282cd841a
2025-10-08 15:48:17.276180 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 15:48:17.276185 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 15:48:17.276190 | controller | }
2025-10-08 15:48:17.535190 | controller | ok: Runtime: 0:00:00.006812
2025-10-08 15:48:17.540993 | 
2025-10-08 15:48:17.541060 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 15:48:17.554575 | controller | skipping: Conditional result was False
2025-10-08 15:48:17.560445 | 
2025-10-08 15:48:17.560523 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 15:48:18.110287 | controller | skipping: Conditional result was False
2025-10-08 15:48:18.116252 | 
2025-10-08 15:48:18.116346 | TASK [Ensure legacy workspace directory]
2025-10-08 15:48:18.388426 | controller | changed
2025-10-08 15:48:18.407615 | 
2025-10-08 15:48:18.407690 | PLAY RECAP
2025-10-08 15:48:18.407732 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 15:48:18.407756 | 
2025-10-08 15:48:18.493863 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 15:48:18.494630 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 15:48:19.051075 | 
2025-10-08 15:48:19.051257 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 15:48:19.072395 | 
2025-10-08 15:48:19.072494 | TASK [Create zuul-output directory]
2025-10-08 15:48:19.517353 | controller | changed
2025-10-08 15:48:19.523230 | 
2025-10-08 15:48:19.523341 | TASK [Slurp Zuul inventory test]
2025-10-08 15:48:19.831158 | controller -> localhost | ok
2025-10-08 15:48:19.838493 | 
2025-10-08 15:48:19.838585 | TASK [Save zuul inventory]
2025-10-08 15:48:21.055073 | controller | changed
2025-10-08 15:48:21.061687 | 
2025-10-08 15:48:21.061780 | TASK [Save zuul vars without the change_message]
2025-10-08 15:48:22.117502 | controller | changed
2025-10-08 15:48:22.150586 | 
2025-10-08 15:48:22.150854 | PLAY RECAP
2025-10-08 15:48:22.150985 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 15:48:22.151092 | 
2025-10-08 15:48:22.306152 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 15:48:22.307294 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 15:48:22.936989 | 
2025-10-08 15:48:22.937100 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 15:48:22.959337 | 
2025-10-08 15:48:22.959457 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 15:48:22.989049 | controller | ok
2025-10-08 15:48:23.008063 | 
2025-10-08 15:48:23.008188 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 15:48:23.042891 | controller | skipping: Conditional result was False
2025-10-08 15:48:23.048981 | 
2025-10-08 15:48:23.049070 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 15:48:23.457772 | controller | ok
2025-10-08 15:48:23.464223 | 
2025-10-08 15:48:23.464330 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 15:48:24.467905 | controller | ok
2025-10-08 15:48:24.478457 | 
2025-10-08 15:48:24.478537 | TASK [Prepare workspace]
2025-10-08 15:48:24.500021 | controller | ok
2025-10-08 15:48:24.520360 | 
2025-10-08 15:48:24.520465 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 15:48:24.903810 | controller | ok
2025-10-08 15:48:24.910986 | 
2025-10-08 15:48:24.911053 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 15:48:26.862434 | controller | Output suppressed because no_log was given
2025-10-08 15:48:26.872528 | 
2025-10-08 15:48:26.872622 | LOOP [Create zuul-output directory]
2025-10-08 15:48:27.144339 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 15:48:27.388564 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 15:48:27.400020 | 
2025-10-08 15:48:27.400121 | TASK [Install required packages]
2025-10-08 15:48:45.392853 | controller | ok: Nothing to do
2025-10-08 15:48:45.398400 | 
2025-10-08 15:48:45.398497 | TASK [Install venv]
2025-10-08 15:49:44.607051 | controller | changed
2025-10-08 15:49:44.640534 | 
2025-10-08 15:49:44.640639 | PLAY RECAP
2025-10-08 15:49:44.640687 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 15:49:44.640710 | 
2025-10-08 15:49:44.751896 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 15:49:44.752678 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 15:49:45.334289 | 
2025-10-08 15:49:45.334408 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 15:49:45.355844 | 
2025-10-08 15:49:45.355961 | TASK [Gather required facts]
2025-10-08 15:49:45.974137 | controller | ok
2025-10-08 15:49:45.979743 | 
2025-10-08 15:49:45.979838 | TASK [Load environment var if instructed to]
2025-10-08 15:49:46.003825 | controller | skipping: Conditional result was False
2025-10-08 15:49:46.009663 | 
2025-10-08 15:49:46.009740 | TASK [Ensure group_vars dir exists]
2025-10-08 15:49:46.383846 | controller | ok
2025-10-08 15:49:46.389236 | 
2025-10-08 15:49:46.389304 | TASK [Run molecule]
2025-10-08 15:49:47.435960 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 15:49:47.793960 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 15:50:04.317467 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.317943 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.318418 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.318885 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.319371 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f8a3c957 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+f8a3c957 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.319839 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.320301 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.320753 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.321220 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.321681 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.322149 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.322599 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.323055 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.323478 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.323927 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:50:04.334124 | controller | INFO     Running add_crc_creds > prepare
2025-10-08 15:50:05.071369 | controller |
2025-10-08 15:50:05.071425 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 15:50:05.071534 | controller |
2025-10-08 15:50:05.071664 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:50:05.071777 | controller | Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.016)       0:00:00.016 *****
2025-10-08 15:50:05.883807 | controller | ok: [instance]
2025-10-08 15:50:05.883864 | controller |
2025-10-08 15:50:05.883967 | controller | TASK [Setup libvirt] ***********************************************************
2025-10-08 15:50:05.884093 | controller | Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.813)       0:00:00.829 *****
2025-10-08 15:50:05.906685 | controller | skipping: [instance]
2025-10-08 15:50:05.906807 | controller |
2025-10-08 15:50:05.906930 | controller | TASK [Setup CRC] ***************************************************************
2025-10-08 15:50:05.907047 | controller | Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.022)       0:00:00.852 *****
2025-10-08 15:50:05.931065 | controller | skipping: [instance]
2025-10-08 15:50:05.931136 | controller |
2025-10-08 15:50:05.931279 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-10-08 15:50:05.931375 | controller | Wednesday 08 October 2025  15:50:05 +0000 (0:00:00.024)       0:00:00.876 *****
2025-10-08 15:50:06.347470 | controller | changed: [instance]
2025-10-08 15:50:06.347514 | controller |
2025-10-08 15:50:06.347648 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-10-08 15:50:06.347752 | controller | Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.416)       0:00:01.292 *****
2025-10-08 15:50:06.932877 | controller | changed: [instance]
2025-10-08 15:50:06.932957 | controller |
2025-10-08 15:50:06.933090 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 15:50:06.933202 | controller | Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.585)       0:00:01.878 *****
2025-10-08 15:50:06.951493 | controller | skipping: [instance]
2025-10-08 15:50:06.951570 | controller |
2025-10-08 15:50:06.951702 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 15:50:06.951813 | controller | Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.018)       0:00:01.897 *****
2025-10-08 15:50:06.994334 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 15:50:06.994414 | controller |
2025-10-08 15:50:06.994523 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 15:50:06.994649 | controller | Wednesday 08 October 2025  15:50:06 +0000 (0:00:00.042)       0:00:01.939 *****
2025-10-08 15:50:07.195617 | controller | ok: [instance]
2025-10-08 15:50:07.195725 | controller |
2025-10-08 15:50:07.195956 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 15:50:07.518288 | controller | Wednesday 08 October 2025  15:50:07 +0000 (0:00:00.201)       0:00:02.141 *****
2025-10-08 15:50:07.518346 | controller | ok: [instance]
2025-10-08 15:50:08.243473 | controller |
2025-10-08 15:50:08.243510 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 15:50:08.243522 | controller | Wednesday 08 October 2025  15:50:07 +0000 (0:00:00.322)       0:00:02.463 *****
2025-10-08 15:50:08.243534 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 15:50:08.243738 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 15:50:08.243787 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 15:50:08.243798 | controller |
2025-10-08 15:50:08.243891 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 15:50:08.244002 | controller | Wednesday 08 October 2025  15:50:08 +0000 (0:00:00.725)       0:00:03.189 *****
2025-10-08 15:50:08.305450 | controller |
2025-10-08 15:50:08.305536 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 15:50:08.305654 | controller | Wednesday 08 October 2025  15:50:08 +0000 (0:00:00.061)       0:00:03.251 *****
2025-10-08 15:50:08.895733 | controller | changed: [instance] => (item=tmp)
2025-10-08 15:50:09.804712 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 15:50:09.804750 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 15:50:09.804775 | controller |
2025-10-08 15:50:09.804782 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 15:50:09.804788 | controller | Wednesday 08 October 2025  15:50:08 +0000 (0:00:00.589)       0:00:03.840 *****
2025-10-08 15:50:09.804802 | controller | ok: [instance]
2025-10-08 15:50:11.016418 | controller |
2025-10-08 15:50:11.016454 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 15:50:11.016463 | controller | Wednesday 08 October 2025  15:50:09 +0000 (0:00:00.908)       0:00:04.749 *****
2025-10-08 15:50:11.016477 | controller | changed: [instance]
2025-10-08 15:50:20.004205 | controller |
2025-10-08 15:50:20.004248 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 15:50:20.004264 | controller | Wednesday 08 October 2025  15:50:11 +0000 (0:00:01.211)       0:00:05.960 *****
2025-10-08 15:50:20.004275 | controller | changed: [instance]
2025-10-08 15:50:20.746877 | controller |
2025-10-08 15:50:20.746911 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 15:50:20.746919 | controller | Wednesday 08 October 2025  15:50:20 +0000 (0:00:08.987)       0:00:14.948 *****
2025-10-08 15:50:20.746929 | controller | changed: [instance]
2025-10-08 15:50:20.777799 | controller |
2025-10-08 15:50:20.777828 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 15:50:20.777838 | controller | Wednesday 08 October 2025  15:50:20 +0000 (0:00:00.741)       0:00:15.690 *****
2025-10-08 15:50:20.777850 | controller | skipping: [instance]
2025-10-08 15:50:21.709735 | controller |
2025-10-08 15:50:21.709773 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 15:50:21.709784 | controller | Wednesday 08 October 2025  15:50:20 +0000 (0:00:00.032)       0:00:15.723 *****
2025-10-08 15:50:21.709795 | controller | changed: [instance]
2025-10-08 15:50:21.753629 | controller |
2025-10-08 15:50:21.753651 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 15:50:21.753659 | controller | Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.931)       0:00:16.654 *****
2025-10-08 15:50:21.753673 | controller | skipping: [instance]
2025-10-08 15:50:21.754036 | controller |
2025-10-08 15:50:21.798989 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 15:50:21.799014 | controller | Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.044)       0:00:16.699 *****
2025-10-08 15:50:21.799026 | controller | skipping: [instance]
2025-10-08 15:50:21.842911 | controller |
2025-10-08 15:50:21.842935 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 15:50:21.842943 | controller | Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.044)       0:00:16.744 *****
2025-10-08 15:50:21.842953 | controller | skipping: [instance]
2025-10-08 15:50:22.460138 | controller |
2025-10-08 15:50:22.460180 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 15:50:22.460188 | controller | Wednesday 08 October 2025  15:50:21 +0000 (0:00:00.043)       0:00:16.788 *****
2025-10-08 15:50:22.460208 | controller | changed: [instance]
2025-10-08 15:50:22.460353 | controller |
2025-10-08 15:50:22.460389 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 15:50:22.844086 | controller | Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.617)       0:00:17.405 *****
2025-10-08 15:50:22.844122 | controller | changed: [instance]
2025-10-08 15:50:22.873645 | controller |
2025-10-08 15:50:22.873673 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 15:50:22.873681 | controller | Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.383)       0:00:17.789 *****
2025-10-08 15:50:22.873690 | controller | skipping: [instance]
2025-10-08 15:50:22.903392 | controller |
2025-10-08 15:50:22.903420 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 15:50:22.903428 | controller | Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.029)       0:00:17.818 *****
2025-10-08 15:50:22.903451 | controller | skipping: [instance]
2025-10-08 15:50:22.934634 | controller |
2025-10-08 15:50:22.934664 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 15:50:22.934671 | controller | Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.029)       0:00:17.848 *****
2025-10-08 15:50:22.934681 | controller | skipping: [instance]
2025-10-08 15:50:22.975785 | controller |
2025-10-08 15:50:22.975821 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 15:50:22.975829 | controller | Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.031)       0:00:17.879 *****
2025-10-08 15:50:22.975839 | controller | ok: [instance]
2025-10-08 15:50:23.012475 | controller |
2025-10-08 15:50:23.012528 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 15:50:23.012538 | controller | Wednesday 08 October 2025  15:50:22 +0000 (0:00:00.041)       0:00:17.920 *****
2025-10-08 15:50:23.012553 | controller | skipping: [instance]
2025-10-08 15:50:23.048051 | controller |
2025-10-08 15:50:23.048085 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 15:50:23.048095 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.036)       0:00:17.957 *****
2025-10-08 15:50:23.048112 | controller | skipping: [instance]
2025-10-08 15:50:23.048345 | controller |
2025-10-08 15:50:23.048544 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 15:50:23.048751 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.036)       0:00:17.993 *****
2025-10-08 15:50:23.082376 | controller | skipping: [instance]
2025-10-08 15:50:23.082600 | controller |
2025-10-08 15:50:23.082802 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 15:50:23.083010 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.034)       0:00:18.027 *****
2025-10-08 15:50:23.115114 | controller | skipping: [instance]
2025-10-08 15:50:23.115297 | controller |
2025-10-08 15:50:23.115469 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 15:50:23.115660 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.033)       0:00:18.060 *****
2025-10-08 15:50:23.148437 | controller | skipping: [instance]
2025-10-08 15:50:23.148671 | controller |
2025-10-08 15:50:23.148872 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 15:50:23.149069 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.033)       0:00:18.093 *****
2025-10-08 15:50:23.183140 | controller | skipping: [instance]
2025-10-08 15:50:23.183365 | controller |
2025-10-08 15:50:23.183616 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 15:50:23.183807 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.034)       0:00:18.128 *****
2025-10-08 15:50:23.214184 | controller | skipping: [instance]
2025-10-08 15:50:23.214407 | controller |
2025-10-08 15:50:23.214641 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 15:50:23.214833 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.031)       0:00:18.159 *****
2025-10-08 15:50:23.418607 | controller | ok: [instance]
2025-10-08 15:50:23.672622 | controller |
2025-10-08 15:50:23.672660 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 15:50:23.672670 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.203)       0:00:18.363 *****
2025-10-08 15:50:23.672690 | controller | changed: [instance]
2025-10-08 15:50:23.955302 | controller |
2025-10-08 15:50:23.955335 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 15:50:23.955344 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.253)       0:00:18.617 *****
2025-10-08 15:50:23.955357 | controller | changed: [instance]
2025-10-08 15:50:23.980907 | controller |
2025-10-08 15:50:23.980937 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 15:50:23.980946 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.282)       0:00:18.900 *****
2025-10-08 15:50:23.980973 | controller | skipping: [instance]
2025-10-08 15:50:24.007662 | controller |
2025-10-08 15:50:24.007690 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 15:50:24.007706 | controller | Wednesday 08 October 2025  15:50:23 +0000 (0:00:00.025)       0:00:18.926 *****
2025-10-08 15:50:24.007720 | controller | skipping: [instance]
2025-10-08 15:50:24.034069 | controller |
2025-10-08 15:50:24.034090 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 15:50:24.034103 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:18.952 *****
2025-10-08 15:50:24.034114 | controller | skipping: [instance]
2025-10-08 15:50:24.060664 | controller |
2025-10-08 15:50:24.060689 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 15:50:24.060698 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:18.979 *****
2025-10-08 15:50:24.060708 | controller | skipping: [instance]
2025-10-08 15:50:24.088353 | controller |
2025-10-08 15:50:24.088380 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 15:50:24.088388 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:19.005 *****
2025-10-08 15:50:24.088398 | controller | skipping: [instance]
2025-10-08 15:50:24.122166 | controller |
2025-10-08 15:50:24.122206 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 15:50:24.122215 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.027)       0:00:19.033 *****
2025-10-08 15:50:24.122229 | controller | skipping: [instance]
2025-10-08 15:50:24.146439 | controller |
2025-10-08 15:50:24.146479 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 15:50:24.146489 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.032)       0:00:19.066 *****
2025-10-08 15:50:24.146512 | controller | skipping: [instance]
2025-10-08 15:50:24.186260 | controller |
2025-10-08 15:50:24.186292 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 15:50:24.186301 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.024)       0:00:19.091 *****
2025-10-08 15:50:24.186315 | controller | skipping: [instance]
2025-10-08 15:50:24.186830 | controller |
2025-10-08 15:50:24.212458 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 15:50:24.212489 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.039)       0:00:19.130 *****
2025-10-08 15:50:24.212502 | controller | skipping: [instance]
2025-10-08 15:50:24.242746 | controller |
2025-10-08 15:50:24.242771 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 15:50:24.242779 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.026)       0:00:19.157 *****
2025-10-08 15:50:24.242789 | controller | skipping: [instance]
2025-10-08 15:50:24.279696 | controller |
2025-10-08 15:50:24.279734 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 15:50:24.279743 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.030)       0:00:19.187 *****
2025-10-08 15:50:24.279753 | controller | skipping: [instance]
2025-10-08 15:50:24.320244 | controller |
2025-10-08 15:50:24.320271 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 15:50:24.320286 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.036)       0:00:19.224 *****
2025-10-08 15:50:24.320300 | controller | skipping: [instance]
2025-10-08 15:50:24.356458 | controller |
2025-10-08 15:50:24.356495 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 15:50:24.356507 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.040)       0:00:19.265 *****
2025-10-08 15:50:24.356518 | controller | skipping: [instance]
2025-10-08 15:50:24.394050 | controller |
2025-10-08 15:50:24.394081 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 15:50:24.394089 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.036)       0:00:19.301 *****
2025-10-08 15:50:24.394099 | controller | skipping: [instance]
2025-10-08 15:51:14.541304 | controller |
2025-10-08 15:51:14.541387 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 15:51:14.541397 | controller | Wednesday 08 October 2025  15:50:24 +0000 (0:00:00.037)       0:00:19.339 *****
2025-10-08 15:51:14.541408 | controller | ok: [instance]
2025-10-08 15:51:14.541905 | controller |
2025-10-08 15:51:15.947711 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 15:51:15.947800 | controller | Wednesday 08 October 2025  15:51:14 +0000 (0:00:50.147)       0:01:09.486 *****
2025-10-08 15:51:15.947908 | controller | ok: [instance]
2025-10-08 15:51:15.989779 | controller |
2025-10-08 15:51:15.989822 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 15:51:15.989832 | controller | Wednesday 08 October 2025  15:51:15 +0000 (0:00:01.401)       0:01:10.888 *****
2025-10-08 15:51:15.989842 | controller | skipping: [instance]
2025-10-08 15:51:15.989948 | controller |
2025-10-08 15:51:15.989987 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 15:51:15.989999 | controller | Wednesday 08 October 2025  15:51:15 +0000 (0:00:00.046)       0:01:10.934 *****
2025-10-08 15:51:16.039086 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 15:51:16.063769 | controller |
2025-10-08 15:51:16.063803 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 15:51:16.063812 | controller | Wednesday 08 October 2025  15:51:16 +0000 (0:00:00.049)       0:01:10.984 *****
2025-10-08 15:51:16.063822 | controller | ok: [instance] =>
2025-10-08 15:51:22.699001 | controller |   cifmw_ci_setup_packages:
2025-10-08 15:51:22.699032 | controller |   - bash-completion
2025-10-08 15:51:22.699040 | controller |   - ca-certificates
2025-10-08 15:51:22.699046 | controller |   - git-core
2025-10-08 15:51:22.699051 | controller |   - make
2025-10-08 15:51:22.699057 | controller |   - tar
2025-10-08 15:51:22.699062 | controller |   - tmux
2025-10-08 15:51:22.699067 | controller |   - python3-pip
2025-10-08 15:51:22.699073 | controller |
2025-10-08 15:51:22.699078 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 15:51:22.699084 | controller | Wednesday 08 October 2025  15:51:16 +0000 (0:00:00.023)       0:01:11.007 *****
2025-10-08 15:51:22.699094 | controller | changed: [instance]
2025-10-08 15:51:23.273309 | controller |
2025-10-08 15:51:23.273340 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 15:51:23.273348 | controller | Wednesday 08 October 2025  15:51:22 +0000 (0:00:06.636)       0:01:17.644 *****
2025-10-08 15:51:23.273358 | controller | ok: [instance]
2025-10-08 15:51:23.487026 | controller |
2025-10-08 15:51:23.487065 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 15:51:23.487074 | controller | Wednesday 08 October 2025  15:51:23 +0000 (0:00:00.574)       0:01:18.218 *****
2025-10-08 15:51:23.487084 | controller | changed: [instance]
2025-10-08 15:51:29.663565 | controller |
2025-10-08 15:51:29.663707 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 15:51:29.663721 | controller | Wednesday 08 October 2025  15:51:23 +0000 (0:00:00.213)       0:01:18.432 *****
2025-10-08 15:51:29.663732 | controller | changed: [instance]
2025-10-08 15:51:29.712345 | controller |
2025-10-08 15:51:29.712380 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 15:51:29.712388 | controller | Wednesday 08 October 2025  15:51:29 +0000 (0:00:06.175)       0:01:24.607 *****
2025-10-08 15:51:29.712402 | controller | ok: [instance]
2025-10-08 15:51:30.013228 | controller |
2025-10-08 15:51:30.013264 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 15:51:30.013273 | controller | Wednesday 08 October 2025  15:51:29 +0000 (0:00:00.049)       0:01:24.657 *****
2025-10-08 15:51:30.013283 | controller | changed: [instance]
2025-10-08 15:51:30.333886 | controller |
2025-10-08 15:51:30.333922 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 15:51:30.333930 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.300)       0:01:24.957 *****
2025-10-08 15:51:30.333941 | controller | changed: [instance]
2025-10-08 15:51:30.352641 | controller |
2025-10-08 15:51:30.352691 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 15:51:30.352701 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.320)       0:01:25.278 *****
2025-10-08 15:51:30.352714 | controller | skipping: [instance]
2025-10-08 15:51:30.352749 | controller |
2025-10-08 15:51:30.352761 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 15:51:30.352841 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.019)       0:01:25.298 *****
2025-10-08 15:51:30.371220 | controller | skipping: [instance]
2025-10-08 15:51:30.390145 | controller |
2025-10-08 15:51:30.390169 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 15:51:30.390177 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.018)       0:01:25.316 *****
2025-10-08 15:51:30.390190 | controller | skipping: [instance]
2025-10-08 15:51:30.409881 | controller |
2025-10-08 15:51:30.409906 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 15:51:30.409918 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.019)       0:01:25.335 *****
2025-10-08 15:51:30.409932 | controller | skipping: [instance]
2025-10-08 15:51:30.430412 | controller |
2025-10-08 15:51:30.430435 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 15:51:30.430443 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.019)       0:01:25.354 *****
2025-10-08 15:51:30.430458 | controller | skipping: [instance]
2025-10-08 15:51:30.455748 | controller |
2025-10-08 15:51:30.455772 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 15:51:30.455793 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.020)       0:01:25.375 *****
2025-10-08 15:51:30.455803 | controller | skipping: [instance]
2025-10-08 15:51:31.634701 | controller |
2025-10-08 15:51:31.634743 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 15:51:31.634751 | controller | Wednesday 08 October 2025  15:51:30 +0000 (0:00:00.025)       0:01:25.401 *****
2025-10-08 15:51:31.634767 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 15:53:12.467899 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 15:53:12.467999 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 15:53:12.468008 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 15:53:12.468014 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 15:53:12.468020 | controller |
2025-10-08 15:53:12.468026 | controller | TASK [Check for CRC startup] ***************************************************
2025-10-08 15:53:12.468031 | controller | Wednesday 08 October 2025  15:51:31 +0000 (0:00:01.177)       0:01:26.578 *****
2025-10-08 15:53:12.468041 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (120 retries left).
2025-10-08 15:53:13.181887 | controller | FAILED - RETRYING: [instance]: Check for CRC startup (119 retries left).
2025-10-08 15:53:13.181924 | controller | changed: [instance]
2025-10-08 15:53:13.181933 | controller |
2025-10-08 15:53:13.181943 | controller | RUNNING HANDLER [Restart NetworkManager] ***************************************
2025-10-08 15:53:13.181949 | controller | Wednesday 08 October 2025  15:53:12 +0000 (0:01:40.833)       0:03:07.412 *****
2025-10-08 15:53:13.181958 | controller | changed: [instance]
2025-10-08 15:53:13.181983 | controller |
2025-10-08 15:53:13.181989 | controller | PLAY RECAP *********************************************************************
2025-10-08 15:53:13.181995 | controller | instance                   : ok=33   changed=19   unreachable=0    failed=0    skipped=38   rescued=0    ignored=0
2025-10-08 15:53:13.182001 | controller |
2025-10-08 15:53:13.182006 | controller | Wednesday 08 October 2025  15:53:13 +0000 (0:00:00.712)       0:03:08.125 *****
2025-10-08 15:53:13.182011 | controller | ===============================================================================
2025-10-08 15:53:13.182016 | controller | Check for CRC startup ------------------------------------------------- 100.83s
2025-10-08 15:53:13.182021 | controller | test_deps : Install selinux python libs -------------------------------- 50.15s
2025-10-08 15:53:13.182026 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.99s
2025-10-08 15:53:13.182032 | controller | ci_setup : Install needed packages -------------------------------------- 6.64s
2025-10-08 15:53:13.182037 | controller | ci_setup : Install openshift client ------------------------------------- 6.18s
2025-10-08 15:53:13.182042 | controller | test_deps : Install python yaml libs ------------------------------------ 1.40s
2025-10-08 15:53:13.182054 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.21s
2025-10-08 15:53:13.182060 | controller | ci_setup : Manage directories ------------------------------------------- 1.18s
2025-10-08 15:53:13.182065 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.93s
2025-10-08 15:53:13.182070 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.91s
2025-10-08 15:53:13.182076 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-10-08 15:53:13.182081 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s
2025-10-08 15:53:13.182086 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.73s
2025-10-08 15:53:13.182093 | controller | Restart NetworkManager -------------------------------------------------- 0.71s
2025-10-08 15:53:13.182509 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.62s
2025-10-08 15:53:13.182542 | controller | repo_setup : Ensure directories are present ----------------------------- 0.59s
2025-10-08 15:53:13.246980 | controller | Inject our own NetworkManager configuration ----------------------------- 0.59s
2025-10-08 15:53:13.247018 | controller | ci_setup : Gather version of openshift client --------------------------- 0.57s
2025-10-08 15:53:13.247026 | controller | Start RHOL/CRC ---------------------------------------------------------- 0.42s
2025-10-08 15:53:13.247032 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.38s
2025-10-08 15:53:13.247048 | controller | INFO     Running add_crc_creds > converge
2025-10-08 15:53:13.688610 | controller |
2025-10-08 15:53:13.688856 | controller | PLAY [Converge add_crc_creds] **************************************************
2025-10-08 15:53:13.689006 | controller |
2025-10-08 15:53:13.689164 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:53:13.689336 | controller | Wednesday 08 October 2025  15:53:13 +0000 (0:00:00.041)       0:00:00.041 *****
2025-10-08 15:53:14.606774 | controller | ok: [instance]
2025-10-08 15:53:14.606972 | controller |
2025-10-08 15:53:14.607130 | controller | TASK [Verify crc creds playbook] ***********************************************
2025-10-08 15:53:14.607280 | controller | Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.918)       0:00:00.960 *****
2025-10-08 15:53:14.635698 | controller |
2025-10-08 15:53:14.635951 | controller | TASK [rhol_crc : Make sure kubeconfig file exists] *****************************
2025-10-08 15:53:14.636115 | controller | Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.028)       0:00:00.989 *****
2025-10-08 15:53:14.942082 | controller | ok: [instance]
2025-10-08 15:53:14.972039 | controller |
2025-10-08 15:53:14.972071 | controller | TASK [rhol_crc : Warn the user that the kubeconfig files doesn't exist] ********
2025-10-08 15:53:14.972079 | controller | Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.302)       0:00:01.291 *****
2025-10-08 15:53:14.972089 | controller | skipping: [instance]
2025-10-08 15:53:15.267816 | controller |
2025-10-08 15:53:15.267849 | controller | TASK [rhol_crc : Add crc creds in the user bashrc file] ************************
2025-10-08 15:53:15.267857 | controller | Wednesday 08 October 2025  15:53:14 +0000 (0:00:00.033)       0:00:01.325 *****
2025-10-08 15:53:15.267868 | controller | changed: [instance]
2025-10-08 15:53:15.856962 | controller |
2025-10-08 15:53:15.856993 | controller | TASK [rhol_crc : Source bashrc and confirm crc login] **************************
2025-10-08 15:53:15.857001 | controller | Wednesday 08 October 2025  15:53:15 +0000 (0:00:00.292)       0:00:01.617 *****
2025-10-08 15:53:15.857012 | controller | ok: [instance]
2025-10-08 15:53:15.902275 | controller |
2025-10-08 15:53:15.902327 | controller | TASK [rhol_crc : Check bashrc results] *****************************************
2025-10-08 15:53:15.902335 | controller | Wednesday 08 October 2025  15:53:15 +0000 (0:00:00.592)       0:00:02.209 *****
2025-10-08 15:53:15.902347 | controller | ok: [instance] =>
2025-10-08 15:53:15.961765 | controller |   bashrc_result.stdout: |-
2025-10-08 15:53:15.961799 | controller |     /usr/local/bin/crc
2025-10-08 15:53:15.961807 | controller |     system:admin
2025-10-08 15:53:15.961813 | controller |
2025-10-08 15:53:15.961819 | controller | PLAY RECAP *********************************************************************
2025-10-08 15:53:15.961830 | controller | instance                   : ok=5    changed=1    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2025-10-08 15:53:15.961835 | controller |
2025-10-08 15:53:15.961841 | controller | Wednesday 08 October 2025  15:53:15 +0000 (0:00:00.044)       0:00:02.254 *****
2025-10-08 15:53:15.961846 | controller | ===============================================================================
2025-10-08 15:53:15.961851 | controller | Gathering Facts --------------------------------------------------------- 0.92s
2025-10-08 15:53:15.961856 | controller | rhol_crc : Source bashrc and confirm crc login -------------------------- 0.59s
2025-10-08 15:53:15.961861 | controller | rhol_crc : Make sure kubeconfig file exists ----------------------------- 0.30s
2025-10-08 15:53:15.961867 | controller | rhol_crc : Add crc creds in the user bashrc file ------------------------ 0.29s
2025-10-08 15:53:15.961872 | controller | rhol_crc : Check bashrc results ----------------------------------------- 0.04s
2025-10-08 15:53:15.961877 | controller | rhol_crc : Warn the user that the kubeconfig files doesn't exist -------- 0.03s
2025-10-08 15:53:15.961882 | controller | Verify crc creds playbook ----------------------------------------------- 0.03s
2025-10-08 15:53:15.961892 | controller | INFO     Running add_crc_creds > cleanup
2025-10-08 15:53:16.418218 | controller |
2025-10-08 15:53:16.418457 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 15:53:16.418631 | controller |
2025-10-08 15:53:16.418794 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:53:16.418945 | controller | Wednesday 08 October 2025  15:53:16 +0000 (0:00:00.016)       0:00:00.016 *****
2025-10-08 15:53:17.316405 | controller | ok: [instance]
2025-10-08 15:53:17.316456 | controller |
2025-10-08 15:53:17.316738 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
2025-10-08 15:53:17.599446 | controller | Wednesday 08 October 2025  15:53:17 +0000 (0:00:00.898)       0:00:00.914 *****
2025-10-08 15:53:17.599489 | controller | ok: [instance]
2025-10-08 15:53:17.599512 | controller |
2025-10-08 15:53:17.599612 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
2025-10-08 15:53:17.599703 | controller | Wednesday 08 October 2025  15:53:17 +0000 (0:00:00.282)       0:00:01.197 *****
2025-10-08 15:53:21.183922 | controller | changed: [instance]
2025-10-08 15:53:21.183998 | controller |
2025-10-08 15:53:21.184114 | controller | PLAY RECAP *********************************************************************
2025-10-08 15:53:21.184273 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 15:53:21.184866 | controller |
2025-10-08 15:53:21.184969 | controller | Wednesday 08 October 2025  15:53:21 +0000 (0:00:03.585)       0:00:04.782 *****
2025-10-08 15:53:21.184980 | controller | ===============================================================================
2025-10-08 15:53:21.185118 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.59s
2025-10-08 15:53:21.185230 | controller | Gathering Facts --------------------------------------------------------- 0.90s
2025-10-08 15:53:21.185359 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.28s
2025-10-08 15:53:21.234522 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 15:53:53.709126 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.709621 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.710103 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.710591 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.711096 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f8a3c957 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+f8a3c957 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.711550 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.712012 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.712479 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.712931 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.713400 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.713882 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.714331 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.714813 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.715250 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.715715 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:53:53.725331 | controller | INFO     Running binary > prepare
2025-10-08 15:53:54.200056 | controller |
2025-10-08 15:53:54.200096 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 15:53:54.200215 | controller |
2025-10-08 15:53:54.200342 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:53:54.200479 | controller | Wednesday 08 October 2025  15:53:54 +0000 (0:00:00.019)       0:00:00.019 *****
2025-10-08 15:53:55.050640 | controller | ok: [instance]
2025-10-08 15:53:55.073299 | controller |
2025-10-08 15:53:55.073324 | controller | TASK [Setup libvirt] ***********************************************************
2025-10-08 15:53:55.073332 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.850)       0:00:00.869 *****
2025-10-08 15:53:55.073342 | controller | skipping: [instance]
2025-10-08 15:53:55.095735 | controller |
2025-10-08 15:53:55.095771 | controller | TASK [Setup CRC] ***************************************************************
2025-10-08 15:53:55.095785 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.023)       0:00:00.892 *****
2025-10-08 15:53:55.095798 | controller | skipping: [instance]
2025-10-08 15:53:55.117574 | controller |
2025-10-08 15:53:55.117631 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-10-08 15:53:55.117640 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.022)       0:00:00.914 *****
2025-10-08 15:53:55.117650 | controller | skipping: [instance]
2025-10-08 15:53:55.714705 | controller |
2025-10-08 15:53:55.714741 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-10-08 15:53:55.714749 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.021)       0:00:00.936 *****
2025-10-08 15:53:55.714761 | controller | ok: [instance]
2025-10-08 15:53:55.714815 | controller |
2025-10-08 15:53:55.714846 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 15:53:55.714859 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.596)       0:00:01.533 *****
2025-10-08 15:53:55.735443 | controller | skipping: [instance]
2025-10-08 15:53:55.778699 | controller |
2025-10-08 15:53:55.778731 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 15:53:55.778740 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.020)       0:00:01.554 *****
2025-10-08 15:53:55.778750 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 15:53:55.778777 | controller |
2025-10-08 15:53:55.779013 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 15:53:55.994635 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.043)       0:00:01.598 *****
2025-10-08 15:53:55.994680 | controller | ok: [instance]
2025-10-08 15:53:56.209673 | controller |
2025-10-08 15:53:56.209705 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 15:53:56.209713 | controller | Wednesday 08 October 2025  15:53:55 +0000 (0:00:00.215)       0:00:01.813 *****
2025-10-08 15:53:56.209723 | controller | ok: [instance]
2025-10-08 15:53:56.209823 | controller |
2025-10-08 15:53:56.209856 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 15:53:56.209868 | controller | Wednesday 08 October 2025  15:53:56 +0000 (0:00:00.215)       0:00:02.028 *****
2025-10-08 15:53:56.916709 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 15:53:56.916830 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 15:53:56.916858 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 15:53:56.916866 | controller |
2025-10-08 15:53:56.916872 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 15:53:56.916882 | controller | Wednesday 08 October 2025  15:53:56 +0000 (0:00:00.706)       0:00:02.735 *****
2025-10-08 15:53:56.982085 | controller |
2025-10-08 15:53:57.548658 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 15:53:57.548688 | controller | Wednesday 08 October 2025  15:53:56 +0000 (0:00:00.065)       0:00:02.801 *****
2025-10-08 15:53:57.548699 | controller | ok: [instance] => (item=tmp)
2025-10-08 15:53:58.927071 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-08 15:53:58.927101 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-08 15:53:58.927109 | controller |
2025-10-08 15:53:58.927115 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 15:53:58.927121 | controller | Wednesday 08 October 2025  15:53:57 +0000 (0:00:00.565)       0:00:03.367 *****
2025-10-08 15:53:58.927131 | controller | ok: [instance]
2025-10-08 15:53:59.522444 | controller |
2025-10-08 15:53:59.522472 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 15:53:59.522480 | controller | Wednesday 08 October 2025  15:53:58 +0000 (0:00:01.378)       0:00:04.746 *****
2025-10-08 15:53:59.522490 | controller | ok: [instance]
2025-10-08 15:54:00.837247 | controller |
2025-10-08 15:54:00.837278 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 15:54:00.837286 | controller | Wednesday 08 October 2025  15:53:59 +0000 (0:00:00.595)       0:00:05.341 *****
2025-10-08 15:54:00.837303 | controller | ok: [instance]
2025-10-08 15:54:01.141443 | controller |
2025-10-08 15:54:01.141472 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 15:54:01.141481 | controller | Wednesday 08 October 2025  15:54:00 +0000 (0:00:01.314)       0:00:06.656 *****
2025-10-08 15:54:01.141490 | controller | ok: [instance]
2025-10-08 15:54:01.166351 | controller |
2025-10-08 15:54:01.166381 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 15:54:01.166389 | controller | Wednesday 08 October 2025  15:54:01 +0000 (0:00:00.304)       0:00:06.960 *****
2025-10-08 15:54:01.166399 | controller | skipping: [instance]
2025-10-08 15:54:02.100260 | controller |
2025-10-08 15:54:02.100292 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 15:54:02.100300 | controller | Wednesday 08 October 2025  15:54:01 +0000 (0:00:00.025)       0:00:06.985 *****
2025-10-08 15:54:02.100310 | controller | changed: [instance]
2025-10-08 15:54:02.141435 | controller |
2025-10-08 15:54:02.141466 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 15:54:02.141474 | controller | Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.933)       0:00:07.919 *****
2025-10-08 15:54:02.141485 | controller | skipping: [instance]
2025-10-08 15:54:02.182784 | controller |
2025-10-08 15:54:02.182822 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 15:54:02.182831 | controller | Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.041)       0:00:07.960 *****
2025-10-08 15:54:02.182840 | controller | skipping: [instance]
2025-10-08 15:54:02.223084 | controller |
2025-10-08 15:54:02.223111 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 15:54:02.223120 | controller | Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.041)       0:00:08.001 *****
2025-10-08 15:54:02.223130 | controller | skipping: [instance]
2025-10-08 15:54:02.772684 | controller |
2025-10-08 15:54:02.772715 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 15:54:02.772724 | controller | Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.040)       0:00:08.042 *****
2025-10-08 15:54:02.772735 | controller | changed: [instance]
2025-10-08 15:54:02.772758 | controller |
2025-10-08 15:54:02.772766 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 15:54:02.772891 | controller | Wednesday 08 October 2025  15:54:02 +0000 (0:00:00.549)       0:00:08.591 *****
2025-10-08 15:54:03.147068 | controller | ok: [instance]
2025-10-08 15:54:03.179636 | controller |
2025-10-08 15:54:03.179663 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 15:54:03.179671 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.374)       0:00:08.966 *****
2025-10-08 15:54:03.179681 | controller | skipping: [instance]
2025-10-08 15:54:03.230286 | controller |
2025-10-08 15:54:03.230341 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 15:54:03.230352 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.032)       0:00:08.998 *****
2025-10-08 15:54:03.230364 | controller | skipping: [instance]
2025-10-08 15:54:03.230395 | controller |
2025-10-08 15:54:03.230653 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 15:54:03.262466 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.051)       0:00:09.049 *****
2025-10-08 15:54:03.262503 | controller | skipping: [instance]
2025-10-08 15:54:03.304179 | controller |
2025-10-08 15:54:03.304208 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 15:54:03.304227 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.031)       0:00:09.081 *****
2025-10-08 15:54:03.304238 | controller | ok: [instance]
2025-10-08 15:54:03.333671 | controller |
2025-10-08 15:54:03.333700 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 15:54:03.333708 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.041)       0:00:09.123 *****
2025-10-08 15:54:03.333718 | controller | skipping: [instance]
2025-10-08 15:54:03.361997 | controller |
2025-10-08 15:54:03.362024 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 15:54:03.362031 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.029)       0:00:09.152 *****
2025-10-08 15:54:03.362050 | controller | skipping: [instance]
2025-10-08 15:54:03.362195 | controller |
2025-10-08 15:54:03.362359 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 15:54:03.362505 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.028)       0:00:09.181 *****
2025-10-08 15:54:03.390149 | controller | skipping: [instance]
2025-10-08 15:54:03.390299 | controller |
2025-10-08 15:54:03.390461 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 15:54:03.390626 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.028)       0:00:09.209 *****
2025-10-08 15:54:03.423792 | controller | skipping: [instance]
2025-10-08 15:54:03.423945 | controller |
2025-10-08 15:54:03.424094 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 15:54:03.424232 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.033)       0:00:09.243 *****
2025-10-08 15:54:03.453431 | controller | skipping: [instance]
2025-10-08 15:54:03.453635 | controller |
2025-10-08 15:54:03.453812 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 15:54:03.453975 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.029)       0:00:09.273 *****
2025-10-08 15:54:03.483950 | controller | skipping: [instance]
2025-10-08 15:54:03.484117 | controller |
2025-10-08 15:54:03.484280 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 15:54:03.484448 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.030)       0:00:09.303 *****
2025-10-08 15:54:03.513275 | controller | skipping: [instance]
2025-10-08 15:54:03.513434 | controller |
2025-10-08 15:54:03.513601 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 15:54:03.513756 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.029)       0:00:09.332 *****
2025-10-08 15:54:03.702138 | controller | ok: [instance]
2025-10-08 15:54:03.920196 | controller |
2025-10-08 15:54:03.920228 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 15:54:03.920236 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.188)       0:00:09.521 *****
2025-10-08 15:54:03.920246 | controller | changed: [instance]
2025-10-08 15:54:04.151690 | controller |
2025-10-08 15:54:04.151723 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 15:54:04.151733 | controller | Wednesday 08 October 2025  15:54:03 +0000 (0:00:00.218)       0:00:09.739 *****
2025-10-08 15:54:04.151746 | controller | changed: [instance]
2025-10-08 15:54:04.174064 | controller |
2025-10-08 15:54:04.174101 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 15:54:04.174111 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.231)       0:00:09.970 *****
2025-10-08 15:54:04.174123 | controller | skipping: [instance]
2025-10-08 15:54:04.198241 | controller |
2025-10-08 15:54:04.198267 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 15:54:04.198276 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.022)       0:00:09.993 *****
2025-10-08 15:54:04.198287 | controller | skipping: [instance]
2025-10-08 15:54:04.224421 | controller |
2025-10-08 15:54:04.224441 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 15:54:04.224448 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.024)       0:00:10.017 *****
2025-10-08 15:54:04.224458 | controller | skipping: [instance]
2025-10-08 15:54:04.249682 | controller |
2025-10-08 15:54:04.249732 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 15:54:04.249741 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.026)       0:00:10.043 *****
2025-10-08 15:54:04.249751 | controller | skipping: [instance]
2025-10-08 15:54:04.274659 | controller |
2025-10-08 15:54:04.274690 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 15:54:04.274699 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.025)       0:00:10.068 *****
2025-10-08 15:54:04.274709 | controller | skipping: [instance]
2025-10-08 15:54:04.299693 | controller |
2025-10-08 15:54:04.299721 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 15:54:04.299743 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.025)       0:00:10.094 *****
2025-10-08 15:54:04.299756 | controller | skipping: [instance]
2025-10-08 15:54:04.299765 | controller |
2025-10-08 15:54:04.299774 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 15:54:04.299917 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.025)       0:00:10.119 *****
2025-10-08 15:54:04.319695 | controller | skipping: [instance]
2025-10-08 15:54:04.319811 | controller |
2025-10-08 15:54:04.319936 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 15:54:04.320055 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.020)       0:00:10.139 *****
2025-10-08 15:54:04.352050 | controller | skipping: [instance]
2025-10-08 15:54:04.352208 | controller |
2025-10-08 15:54:04.352378 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 15:54:04.352528 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.032)       0:00:10.171 *****
2025-10-08 15:54:04.372372 | controller | skipping: [instance]
2025-10-08 15:54:04.372518 | controller |
2025-10-08 15:54:04.372706 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 15:54:04.372848 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.020)       0:00:10.192 *****
2025-10-08 15:54:04.400209 | controller | skipping: [instance]
2025-10-08 15:54:04.400339 | controller |
2025-10-08 15:54:04.400496 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 15:54:04.400665 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.027)       0:00:10.219 *****
2025-10-08 15:54:04.432236 | controller | skipping: [instance]
2025-10-08 15:54:04.432388 | controller |
2025-10-08 15:54:04.432546 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 15:54:04.432704 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.032)       0:00:10.251 *****
2025-10-08 15:54:04.466050 | controller | skipping: [instance]
2025-10-08 15:54:04.466184 | controller |
2025-10-08 15:54:04.466336 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 15:54:04.466492 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.033)       0:00:10.285 *****
2025-10-08 15:54:04.497986 | controller | skipping: [instance]
2025-10-08 15:54:04.498095 | controller |
2025-10-08 15:54:04.498225 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 15:54:04.498370 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.031)       0:00:10.317 *****
2025-10-08 15:54:04.530833 | controller | skipping: [instance]
2025-10-08 15:54:04.530985 | controller |
2025-10-08 15:54:04.531123 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 15:54:04.531241 | controller | Wednesday 08 October 2025  15:54:04 +0000 (0:00:00.032)       0:00:10.350 *****
2025-10-08 15:54:48.765729 | controller | ok: [instance]
2025-10-08 15:54:50.042703 | controller |
2025-10-08 15:54:50.042745 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 15:54:50.042753 | controller | Wednesday 08 October 2025  15:54:48 +0000 (0:00:44.233)       0:00:54.584 *****
2025-10-08 15:54:50.042765 | controller | ok: [instance]
2025-10-08 15:54:50.078767 | controller |
2025-10-08 15:54:50.078802 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 15:54:50.078812 | controller | Wednesday 08 October 2025  15:54:50 +0000 (0:00:01.277)       0:00:55.861 *****
2025-10-08 15:54:50.078824 | controller | skipping: [instance]
2025-10-08 15:54:50.126562 | controller |
2025-10-08 15:54:50.126612 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 15:54:50.126621 | controller | Wednesday 08 October 2025  15:54:50 +0000 (0:00:00.035)       0:00:55.897 *****
2025-10-08 15:54:50.126633 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 15:54:50.147759 | controller |
2025-10-08 15:54:50.147801 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 15:54:50.147810 | controller | Wednesday 08 October 2025  15:54:50 +0000 (0:00:00.047)       0:00:55.945 *****
2025-10-08 15:54:50.147832 | controller | ok: [instance] =>
2025-10-08 15:54:50.147863 | controller |   cifmw_ci_setup_packages:
2025-10-08 15:54:50.147870 | controller |   - bash-completion
2025-10-08 15:54:50.147875 | controller |   - ca-certificates
2025-10-08 15:54:50.147881 | controller |   - git-core
2025-10-08 15:54:50.147886 | controller |   - make
2025-10-08 15:54:50.147894 | controller |   - tar
2025-10-08 15:54:50.148308 | controller |   - tmux
2025-10-08 15:54:50.148341 | controller |   - python3-pip
2025-10-08 15:54:51.412896 | controller |
2025-10-08 15:54:51.412931 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 15:54:51.412939 | controller | Wednesday 08 October 2025  15:54:50 +0000 (0:00:00.021)       0:00:55.966 *****
2025-10-08 15:54:51.412949 | controller | ok: [instance]
2025-10-08 15:54:51.682704 | controller |
2025-10-08 15:54:51.682740 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 15:54:51.682748 | controller | Wednesday 08 October 2025  15:54:51 +0000 (0:00:01.265)       0:00:57.231 *****
2025-10-08 15:54:51.682759 | controller | ok: [instance]
2025-10-08 15:54:51.896709 | controller |
2025-10-08 15:54:51.896741 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 15:54:51.896749 | controller | Wednesday 08 October 2025  15:54:51 +0000 (0:00:00.269)       0:00:57.501 *****
2025-10-08 15:54:51.896759 | controller | ok: [instance]
2025-10-08 15:54:51.896950 | controller |
2025-10-08 15:54:51.896983 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 15:54:51.967504 | controller | Wednesday 08 October 2025  15:54:51 +0000 (0:00:00.214)       0:00:57.716 *****
2025-10-08 15:54:51.967543 | controller | skipping: [instance]
2025-10-08 15:54:52.005740 | controller |
2025-10-08 15:54:52.005776 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 15:54:52.005785 | controller | Wednesday 08 October 2025  15:54:51 +0000 (0:00:00.069)       0:00:57.785 *****
2025-10-08 15:54:52.005795 | controller | skipping: [instance]
2025-10-08 15:54:52.005801 | controller |
2025-10-08 15:54:52.005807 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 15:54:52.005814 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.039)       0:00:57.824 *****
2025-10-08 15:54:52.190467 | controller | ok: [instance]
2025-10-08 15:54:52.480344 | controller |
2025-10-08 15:54:52.480381 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 15:54:52.480391 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.184)       0:00:58.009 *****
2025-10-08 15:54:52.480403 | controller | changed: [instance]
2025-10-08 15:54:52.498720 | controller |
2025-10-08 15:54:52.498756 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 15:54:52.498767 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.289)       0:00:58.299 *****
2025-10-08 15:54:52.498779 | controller | skipping: [instance]
2025-10-08 15:54:52.498814 | controller |
2025-10-08 15:54:52.498825 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 15:54:52.517048 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.318 *****
2025-10-08 15:54:52.517082 | controller | skipping: [instance]
2025-10-08 15:54:52.536020 | controller |
2025-10-08 15:54:52.536053 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 15:54:52.536061 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.336 *****
2025-10-08 15:54:52.536070 | controller | skipping: [instance]
2025-10-08 15:54:52.554727 | controller |
2025-10-08 15:54:52.554774 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 15:54:52.554784 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.019)       0:00:58.355 *****
2025-10-08 15:54:52.554797 | controller | skipping: [instance]
2025-10-08 15:54:52.572961 | controller |
2025-10-08 15:54:52.572994 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 15:54:52.573004 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.374 *****
2025-10-08 15:54:52.573018 | controller | skipping: [instance]
2025-10-08 15:54:52.573120 | controller |
2025-10-08 15:54:52.573243 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 15:54:52.573380 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.018)       0:00:58.392 *****
2025-10-08 15:54:52.597470 | controller | skipping: [instance]
2025-10-08 15:54:52.597524 | controller |
2025-10-08 15:54:52.597709 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 15:54:52.597836 | controller | Wednesday 08 October 2025  15:54:52 +0000 (0:00:00.024)       0:00:58.417 *****
2025-10-08 15:54:53.606660 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 15:54:53.606724 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 15:54:53.606733 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 15:54:53.606739 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 15:54:53.606745 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 15:54:53.606753 | controller |
2025-10-08 15:54:53.606945 | controller | TASK [Check for CRC startup] ***************************************************
2025-10-08 15:54:53.644754 | controller | Wednesday 08 October 2025  15:54:53 +0000 (0:00:01.008)       0:00:59.425 *****
2025-10-08 15:54:53.644800 | controller | skipping: [instance]
2025-10-08 15:54:53.644830 | controller |
2025-10-08 15:54:53.644836 | controller | PLAY RECAP *********************************************************************
2025-10-08 15:54:53.644842 | controller | instance                   : ok=28   changed=5    unreachable=0    failed=0    skipped=42   rescued=0    ignored=0
2025-10-08 15:54:53.644849 | controller |
2025-10-08 15:54:53.646889 | controller | Wednesday 08 October 2025  15:54:53 +0000 (0:00:00.038)       0:00:59.463 *****
2025-10-08 15:54:53.701274 | controller | ===============================================================================
2025-10-08 15:54:53.701306 | controller | test_deps : Install selinux python libs -------------------------------- 44.23s
2025-10-08 15:54:53.701313 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.38s
2025-10-08 15:54:53.701319 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.31s
2025-10-08 15:54:53.701324 | controller | test_deps : Install python yaml libs ------------------------------------ 1.28s
2025-10-08 15:54:53.701335 | controller | ci_setup : Install needed packages -------------------------------------- 1.27s
2025-10-08 15:54:53.701341 | controller | ci_setup : Manage directories ------------------------------------------- 1.01s
2025-10-08 15:54:53.701346 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.93s
2025-10-08 15:54:53.701351 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 15:54:53.701356 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.71s
2025-10-08 15:54:53.701361 | controller | Inject our own NetworkManager configuration ----------------------------- 0.60s
2025-10-08 15:54:53.701366 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.60s
2025-10-08 15:54:53.701371 | controller | repo_setup : Ensure directories are present ----------------------------- 0.57s
2025-10-08 15:54:53.701376 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-10-08 15:54:53.701382 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.37s
2025-10-08 15:54:53.701387 | controller | repo_setup : Install repo-setup package --------------------------------- 0.30s
2025-10-08 15:54:53.701392 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.29s
2025-10-08 15:54:53.701397 | controller | ci_setup : Gather version of openshift client --------------------------- 0.27s
2025-10-08 15:54:53.701402 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-08 15:54:53.701408 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-10-08 15:54:53.701413 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.22s
2025-10-08 15:54:53.701422 | controller | INFO     Running binary > converge
2025-10-08 15:54:54.132831 | controller |
2025-10-08 15:54:54.133050 | controller | PLAY [Converge binary] *********************************************************
2025-10-08 15:54:54.133188 | controller |
2025-10-08 15:54:54.133323 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:54:54.133461 | controller | Wednesday 08 October 2025  15:54:54 +0000 (0:00:00.015)       0:00:00.015 *****
2025-10-08 15:54:54.980491 | controller | ok: [instance]
2025-10-08 15:54:55.303351 | controller |
2025-10-08 15:54:55.303381 | controller | TASK [rhol_crc : Create bin directory if it does not exist] ********************
2025-10-08 15:54:55.303389 | controller | Wednesday 08 October 2025  15:54:54 +0000 (0:00:00.847)       0:00:00.863 *****
2025-10-08 15:54:55.303399 | controller | ok: [instance]
2025-10-08 15:54:55.571132 | controller |
2025-10-08 15:54:55.571164 | controller | TASK [rhol_crc : Create temporary directory for downloading RHOL/CRC] **********
2025-10-08 15:54:55.571172 | controller | Wednesday 08 October 2025  15:54:55 +0000 (0:00:00.322)       0:00:01.186 *****
2025-10-08 15:54:55.571182 | controller | changed: [instance]
2025-10-08 15:55:06.382206 | controller |
2025-10-08 15:55:06.382236 | controller | TASK [rhol_crc : Download RHOL/CRC with sha256 checksum] ***********************
2025-10-08 15:55:06.382244 | controller | Wednesday 08 October 2025  15:54:55 +0000 (0:00:00.267)       0:00:01.453 *****
2025-10-08 15:55:06.382254 | controller | changed: [instance]
2025-10-08 15:55:11.583738 | controller |
2025-10-08 15:55:11.583783 | controller | TASK [rhol_crc : Unarchive RHOL/CRC binary] ************************************
2025-10-08 15:55:11.583793 | controller | Wednesday 08 October 2025  15:55:06 +0000 (0:00:10.811)       0:00:12.264 *****
2025-10-08 15:55:11.583805 | controller | changed: [instance]
2025-10-08 15:55:11.583832 | controller |
2025-10-08 15:55:11.583842 | controller | TASK [rhol_crc : Delete temporary directory for downloading RHOL/CRC] **********
2025-10-08 15:55:11.583925 | controller | Wednesday 08 October 2025  15:55:11 +0000 (0:00:05.201)       0:00:17.466 *****
2025-10-08 15:55:11.774428 | controller | ok: [instance]
2025-10-08 15:55:12.253763 | controller |
2025-10-08 15:55:12.253810 | controller | TASK [rhol_crc : Get CRC binary version] ***************************************
2025-10-08 15:55:12.253820 | controller | Wednesday 08 October 2025  15:55:11 +0000 (0:00:00.190)       0:00:17.657 *****
2025-10-08 15:55:12.253834 | controller | changed: [instance]
2025-10-08 15:55:12.291083 | controller |
2025-10-08 15:55:12.291120 | controller | TASK [rhol_crc : Extract versions] *********************************************
2025-10-08 15:55:12.291138 | controller | Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.479)       0:00:18.136 *****
2025-10-08 15:55:12.291148 | controller | ok: [instance]
2025-10-08 15:55:12.338732 | controller |
2025-10-08 15:55:12.338770 | controller | TASK [rhol_crc : Output found binary location and versions] ********************
2025-10-08 15:55:12.338779 | controller | Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.037)       0:00:18.173 *****
2025-10-08 15:55:12.338790 | controller | ok: [instance] =>
2025-10-08 15:55:12.338817 | controller |   msg: 'Found crc at /usr/local/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.'
2025-10-08 15:55:12.338823 | controller |
2025-10-08 15:55:12.338828 | controller | PLAY [Verify get_version] ******************************************************
2025-10-08 15:55:12.338834 | controller |
2025-10-08 15:55:12.338841 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:55:12.338863 | controller | Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.047)       0:00:18.221 *****
2025-10-08 15:55:12.983716 | controller | ok: [instance]
2025-10-08 15:55:12.984096 | controller |
2025-10-08 15:55:13.049764 | controller | TASK [Ensure facts are set and look correct] ***********************************
2025-10-08 15:55:13.049797 | controller | Wednesday 08 October 2025  15:55:12 +0000 (0:00:00.645)       0:00:18.866 *****
2025-10-08 15:55:13.049809 | controller | ok: [instance] => changed=false
2025-10-08 15:55:13.049835 | controller |   msg: All assertions passed
2025-10-08 15:55:13.049843 | controller |
2025-10-08 15:55:13.051457 | controller | PLAY RECAP *********************************************************************
2025-10-08 15:55:13.104367 | controller | instance                   : ok=11   changed=4    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 15:55:13.104400 | controller |
2025-10-08 15:55:13.104409 | controller | Wednesday 08 October 2025  15:55:13 +0000 (0:00:00.066)       0:00:18.932 *****
2025-10-08 15:55:13.104419 | controller | ===============================================================================
2025-10-08 15:55:13.104425 | controller | rhol_crc : Download RHOL/CRC with sha256 checksum ---------------------- 10.81s
2025-10-08 15:55:13.104430 | controller | rhol_crc : Unarchive RHOL/CRC binary ------------------------------------ 5.20s
2025-10-08 15:55:13.104435 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 15:55:13.104440 | controller | Gathering Facts --------------------------------------------------------- 0.65s
2025-10-08 15:55:13.104445 | controller | rhol_crc : Get CRC binary version --------------------------------------- 0.48s
2025-10-08 15:55:13.104451 | controller | rhol_crc : Create bin directory if it does not exist -------------------- 0.32s
2025-10-08 15:55:13.104456 | controller | rhol_crc : Create temporary directory for downloading RHOL/CRC ---------- 0.27s
2025-10-08 15:55:13.104461 | controller | rhol_crc : Delete temporary directory for downloading RHOL/CRC ---------- 0.19s
2025-10-08 15:55:13.104466 | controller | Ensure facts are set and look correct ----------------------------------- 0.07s
2025-10-08 15:55:13.104471 | controller | rhol_crc : Output found binary location and versions -------------------- 0.05s
2025-10-08 15:55:13.104476 | controller | rhol_crc : Extract versions --------------------------------------------- 0.04s
2025-10-08 15:55:13.104486 | controller | INFO     Running binary > cleanup
2025-10-08 15:55:13.542928 | controller |
2025-10-08 15:55:13.543148 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 15:55:13.543293 | controller |
2025-10-08 15:55:13.543436 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:55:13.543603 | controller | Wednesday 08 October 2025  15:55:13 +0000 (0:00:00.016)       0:00:00.016 *****
2025-10-08 15:55:14.385532 | controller | ok: [instance]
2025-10-08 15:55:14.850710 | controller |
2025-10-08 15:55:14.850773 | controller | TASK [Cleanup RHOL/CRC after tests] ********************************************
2025-10-08 15:55:14.850784 | controller | Wednesday 08 October 2025  15:55:14 +0000 (0:00:00.842)       0:00:00.858 *****
2025-10-08 15:55:14.850803 | controller | changed: [instance]
2025-10-08 15:55:14.901373 | controller |
2025-10-08 15:55:14.901429 | controller | PLAY RECAP *********************************************************************
2025-10-08 15:55:14.901438 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 15:55:14.901444 | controller |
2025-10-08 15:55:14.901449 | controller | Wednesday 08 October 2025  15:55:14 +0000 (0:00:00.465)       0:00:01.323 *****
2025-10-08 15:55:14.901454 | controller | ===============================================================================
2025-10-08 15:55:14.901460 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-10-08 15:55:14.901476 | controller | Cleanup RHOL/CRC after tests -------------------------------------------- 0.47s
2025-10-08 15:55:14.901493 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 15:55:31.798399 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.798823 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.799295 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.799756 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.800260 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f8a3c957 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0+f8a3c957 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.800718 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.801177 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.801639 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.802092 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.802513 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.802994 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.803431 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.804104 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.804559 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.805044 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b025d2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 15:55:31.814040 | controller | INFO     Running default > prepare
2025-10-08 15:55:32.279814 | controller |
2025-10-08 15:55:32.279983 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 15:55:32.280099 | controller |
2025-10-08 15:55:32.280221 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:55:32.280345 | controller | Wednesday 08 October 2025  15:55:32 +0000 (0:00:00.018)       0:00:00.018 *****
2025-10-08 15:55:33.152165 | controller | ok: [instance]
2025-10-08 15:55:33.174204 | controller |
2025-10-08 15:55:33.174236 | controller | TASK [Setup libvirt] ***********************************************************
2025-10-08 15:55:33.174245 | controller | Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.872)       0:00:00.890 *****
2025-10-08 15:55:33.174257 | controller | skipping: [instance]
2025-10-08 15:55:33.197057 | controller |
2025-10-08 15:55:33.197079 | controller | TASK [Setup CRC] ***************************************************************
2025-10-08 15:55:33.197088 | controller | Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.022)       0:00:00.912 *****
2025-10-08 15:55:33.197099 | controller | skipping: [instance]
2025-10-08 15:55:33.220308 | controller |
2025-10-08 15:55:33.220332 | controller | TASK [Start RHOL/CRC] **********************************************************
2025-10-08 15:55:33.220341 | controller | Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.022)       0:00:00.935 *****
2025-10-08 15:55:33.220352 | controller | skipping: [instance]
2025-10-08 15:55:33.806710 | controller |
2025-10-08 15:55:33.806745 | controller | TASK [Inject our own NetworkManager configuration] *****************************
2025-10-08 15:55:33.806755 | controller | Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.022)       0:00:00.958 *****
2025-10-08 15:55:33.806767 | controller | ok: [instance]
2025-10-08 15:55:33.806799 | controller |
2025-10-08 15:55:33.806824 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 15:55:33.806954 | controller | Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.586)       0:00:01.545 *****
2025-10-08 15:55:33.827445 | controller | skipping: [instance]
2025-10-08 15:55:33.872370 | controller |
2025-10-08 15:55:33.872403 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 15:55:33.872412 | controller | Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.020)       0:00:01.566 *****
2025-10-08 15:55:33.872422 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 15:55:34.065154 | controller |
2025-10-08 15:55:34.065190 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 15:55:34.065202 | controller | Wednesday 08 October 2025  15:55:33 +0000 (0:00:00.044)       0:00:01.610 *****
2025-10-08 15:55:34.065212 | controller | ok: [instance]
2025-10-08 15:55:34.279666 | controller |
2025-10-08 15:55:34.279696 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 15:55:34.279705 | controller | Wednesday 08 October 2025  15:55:34 +0000 (0:00:00.192)       0:00:01.803 *****
2025-10-08 15:55:34.279715 | controller | ok: [instance]
2025-10-08 15:55:34.279739 | controller |
2025-10-08 15:55:34.279747 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 15:55:34.279851 | controller | Wednesday 08 October 2025  15:55:34 +0000 (0:00:00.214)       0:00:02.018 *****
2025-10-08 15:55:35.001677 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 15:55:35.001719 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 15:55:35.001727 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 15:55:35.001734 | controller |
2025-10-08 15:55:35.001739 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 15:55:35.001748 | controller | Wednesday 08 October 2025  15:55:34 +0000 (0:00:00.721)       0:00:02.740 *****
2025-10-08 15:55:35.065275 | controller |
2025-10-08 15:55:35.632682 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 15:55:35.632717 | controller | Wednesday 08 October 2025  15:55:35 +0000 (0:00:00.063)       0:00:02.803 *****
2025-10-08 15:55:35.632729 | controller | ok: [instance] => (item=tmp)
2025-10-08 15:55:35.632817 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-08 15:55:35.632849 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-08 15:55:35.632857 | controller |
2025-10-08 15:55:35.632863 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 15:55:35.632874 | controller | Wednesday 08 October 2025  15:55:35 +0000 (0:00:00.567)       0:00:03.371 *****
2025-10-08 15:55:37.033598 | controller | ok: [instance]
2025-10-08 15:55:37.631727 | controller |
2025-10-08 15:55:37.631761 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 15:55:37.631769 | controller | Wednesday 08 October 2025  15:55:37 +0000 (0:00:01.401)       0:00:04.772 *****
2025-10-08 15:55:37.631779 | controller | ok: [instance]
2025-10-08 15:55:37.631805 | controller |
2025-10-08 15:55:37.631813 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 15:55:37.631889 | controller | Wednesday 08 October 2025  15:55:37 +0000 (0:00:00.598)       0:00:05.370 *****
2025-10-08 15:55:38.951535 | controller | ok: [instance]
2025-10-08 15:55:39.252109 | controller |
2025-10-08 15:55:39.252144 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 15:55:39.252152 | controller | Wednesday 08 October 2025  15:55:38 +0000 (0:00:01.319)       0:00:06.690 *****
2025-10-08 15:55:39.252171 | controller | ok: [instance]
2025-10-08 15:55:39.276462 | controller |
2025-10-08 15:55:39.276490 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 15:55:39.276498 | controller | Wednesday 08 October 2025  15:55:39 +0000 (0:00:00.300)       0:00:06.990 *****
2025-10-08 15:55:39.276507 | controller | skipping: [instance]
2025-10-08 15:55:40.162595 | controller |
2025-10-08 15:55:40.162631 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 15:55:40.162640 | controller | Wednesday 08 October 2025  15:55:39 +0000 (0:00:00.024)       0:00:07.015 *****
2025-10-08 15:55:40.162650 | controller | changed: [instance]
2025-10-08 15:55:40.203042 | controller |
2025-10-08 15:55:40.203070 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 15:55:40.203078 | controller | Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.885)       0:00:07.900 *****
2025-10-08 15:55:40.203088 | controller | skipping: [instance]
2025-10-08 15:55:40.242663 | controller |
2025-10-08 15:55:40.242715 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 15:55:40.242726 | controller | Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.040)       0:00:07.941 *****
2025-10-08 15:55:40.242739 | controller | skipping: [instance]
2025-10-08 15:55:40.282734 | controller |
2025-10-08 15:55:40.282782 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 15:55:40.282793 | controller | Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.039)       0:00:07.981 *****
2025-10-08 15:55:40.282806 | controller | skipping: [instance]
2025-10-08 15:55:40.282840 | controller |
2025-10-08 15:55:40.282850 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 15:55:40.282978 | controller | Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.040)       0:00:08.021 *****
2025-10-08 15:55:40.853689 | controller | changed: [instance]
2025-10-08 15:55:40.853724 | controller |
2025-10-08 15:55:40.853732 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 15:55:40.853741 | controller | Wednesday 08 October 2025  15:55:40 +0000 (0:00:00.570)       0:00:08.592 *****
2025-10-08 15:55:41.215399 | controller | ok: [instance]
2025-10-08 15:55:41.246437 | controller |
2025-10-08 15:55:41.246468 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 15:55:41.246476 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.361)       0:00:08.953 *****
2025-10-08 15:55:41.246486 | controller | skipping: [instance]
2025-10-08 15:55:41.276992 | controller |
2025-10-08 15:55:41.277058 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 15:55:41.277077 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.031)       0:00:08.985 *****
2025-10-08 15:55:41.277088 | controller | skipping: [instance]
2025-10-08 15:55:41.308730 | controller |
2025-10-08 15:55:41.308777 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 15:55:41.308785 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.030)       0:00:09.015 *****
2025-10-08 15:55:41.308796 | controller | skipping: [instance]
2025-10-08 15:55:41.350842 | controller |
2025-10-08 15:55:41.350875 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 15:55:41.350883 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.031)       0:00:09.047 *****
2025-10-08 15:55:41.350893 | controller | ok: [instance]
2025-10-08 15:55:41.351062 | controller |
2025-10-08 15:55:41.351225 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 15:55:41.351370 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.042)       0:00:09.089 *****
2025-10-08 15:55:41.382266 | controller | skipping: [instance]
2025-10-08 15:55:41.382444 | controller |
2025-10-08 15:55:41.382626 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 15:55:41.382786 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.031)       0:00:09.121 *****
2025-10-08 15:55:41.412975 | controller | skipping: [instance]
2025-10-08 15:55:41.413190 | controller |
2025-10-08 15:55:41.413344 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 15:55:41.413495 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.030)       0:00:09.151 *****
2025-10-08 15:55:41.442281 | controller | skipping: [instance]
2025-10-08 15:55:41.442444 | controller |
2025-10-08 15:55:41.442619 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 15:55:41.442774 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.029)       0:00:09.181 *****
2025-10-08 15:55:41.471112 | controller | skipping: [instance]
2025-10-08 15:55:41.471295 | controller |
2025-10-08 15:55:41.471440 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 15:55:41.471606 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.028)       0:00:09.210 *****
2025-10-08 15:55:41.528533 | controller | skipping: [instance]
2025-10-08 15:55:41.528735 | controller |
2025-10-08 15:55:41.528887 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 15:55:41.529055 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.057)       0:00:09.267 *****
2025-10-08 15:55:41.561137 | controller | skipping: [instance]
2025-10-08 15:55:41.561312 | controller |
2025-10-08 15:55:41.561487 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 15:55:41.561687 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.032)       0:00:09.300 *****
2025-10-08 15:55:41.590306 | controller | skipping: [instance]
2025-10-08 15:55:41.590498 | controller |
2025-10-08 15:55:41.590680 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 15:55:41.590827 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.029)       0:00:09.329 *****
2025-10-08 15:55:41.788361 | controller | ok: [instance]
2025-10-08 15:55:42.004428 | controller |
2025-10-08 15:55:42.004460 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 15:55:42.004468 | controller | Wednesday 08 October 2025  15:55:41 +0000 (0:00:00.197)       0:00:09.526 *****
2025-10-08 15:55:42.004478 | controller | changed: [instance]
2025-10-08 15:55:42.240647 | controller |
2025-10-08 15:55:42.240677 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 15:55:42.240685 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.216)       0:00:09.742 *****
2025-10-08 15:55:42.240695 | controller | changed: [instance]
2025-10-08 15:55:42.261654 | controller |
2025-10-08 15:55:42.261691 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 15:55:42.261701 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.236)       0:00:09.979 *****
2025-10-08 15:55:42.261715 | controller | skipping: [instance]
2025-10-08 15:55:42.287114 | controller |
2025-10-08 15:55:42.287146 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 15:55:42.287154 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.021)       0:00:10.000 *****
2025-10-08 15:55:42.287165 | controller | skipping: [instance]
2025-10-08 15:55:42.312562 | controller |
2025-10-08 15:55:42.312607 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 15:55:42.312616 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.025 *****
2025-10-08 15:55:42.312627 | controller | skipping: [instance]
2025-10-08 15:55:42.337692 | controller |
2025-10-08 15:55:42.337733 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 15:55:42.337743 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.051 *****
2025-10-08 15:55:42.337757 | controller | skipping: [instance]
2025-10-08 15:55:42.337786 | controller |
2025-10-08 15:55:42.337796 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 15:55:42.361541 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.076 *****
2025-10-08 15:55:42.361573 | controller | skipping: [instance]
2025-10-08 15:55:42.386654 | controller |
2025-10-08 15:55:42.386693 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 15:55:42.386701 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.023)       0:00:10.100 *****
2025-10-08 15:55:42.386723 | controller | skipping: [instance]
2025-10-08 15:55:42.386752 | controller |
2025-10-08 15:55:42.386931 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 15:55:42.406620 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.025)       0:00:10.125 *****
2025-10-08 15:55:42.406645 | controller | skipping: [instance]
2025-10-08 15:55:42.436242 | controller |
2025-10-08 15:55:42.436272 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 15:55:42.436280 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.019)       0:00:10.145 *****
2025-10-08 15:55:42.436290 | controller | skipping: [instance]
2025-10-08 15:55:42.436456 | controller |
2025-10-08 15:55:42.436641 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 15:55:42.436799 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.029)       0:00:10.175 *****
2025-10-08 15:55:42.456432 | controller | skipping: [instance]
2025-10-08 15:55:42.456673 | controller |
2025-10-08 15:55:42.456864 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 15:55:42.457063 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.020)       0:00:10.195 *****
2025-10-08 15:55:42.480738 | controller | skipping: [instance]
2025-10-08 15:55:42.480920 | controller |
2025-10-08 15:55:42.481093 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 15:55:42.481251 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.024)       0:00:10.219 *****
2025-10-08 15:55:42.513073 | controller | skipping: [instance]
2025-10-08 15:55:42.513272 | controller |
2025-10-08 15:55:42.513429 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 15:55:42.513575 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.031)       0:00:10.251 *****
2025-10-08 15:55:42.544351 | controller | skipping: [instance]
2025-10-08 15:55:42.544530 | controller |
2025-10-08 15:55:42.544733 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 15:55:42.544908 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.031)       0:00:10.283 *****
2025-10-08 15:55:42.575384 | controller | skipping: [instance]
2025-10-08 15:55:42.575552 | controller |
2025-10-08 15:55:42.575753 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 15:55:42.575904 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.031)       0:00:10.314 *****
2025-10-08 15:55:42.607595 | controller | skipping: [instance]
2025-10-08 15:55:42.607776 | controller |
2025-10-08 15:55:42.607932 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 15:55:42.608099 | controller | Wednesday 08 October 2025  15:55:42 +0000 (0:00:00.032)       0:00:10.346 *****
2025-10-08 15:56:40.935258 | controller | ok: [instance]
2025-10-08 15:56:42.161638 | controller |
2025-10-08 15:56:42.161680 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 15:56:42.161689 | controller | Wednesday 08 October 2025  15:56:40 +0000 (0:00:58.326)       0:01:08.673 *****
2025-10-08 15:56:42.161701 | controller | ok: [instance]
2025-10-08 15:56:42.197667 | controller |
2025-10-08 15:56:42.197695 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 15:56:42.197702 | controller | Wednesday 08 October 2025  15:56:42 +0000 (0:00:01.226)       0:01:09.900 *****
2025-10-08 15:56:42.197713 | controller | skipping: [instance]
2025-10-08 15:56:42.197735 | controller |
2025-10-08 15:56:42.197743 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 15:56:42.197874 | controller | Wednesday 08 October 2025  15:56:42 +0000 (0:00:00.035)       0:01:09.936 *****
2025-10-08 15:56:42.244653 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 15:56:42.244693 | controller |
2025-10-08 15:56:42.244702 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 15:56:42.244841 | controller | Wednesday 08 October 2025  15:56:42 +0000 (0:00:00.047)       0:01:09.983 *****
2025-10-08 15:56:42.267725 | controller | ok: [instance] =>
2025-10-08 15:56:42.267780 | controller |   cifmw_ci_setup_packages:
2025-10-08 15:56:42.267795 | controller |   - bash-completion
2025-10-08 15:56:42.267802 | controller |   - ca-certificates
2025-10-08 15:56:42.267807 | controller |   - git-core
2025-10-08 15:56:42.267815 | controller |   - make
2025-10-08 15:56:42.268376 | controller |   - tar
2025-10-08 15:56:43.520474 | controller |   - tmux
2025-10-08 15:56:43.520505 | controller |   - python3-pip
2025-10-08 15:56:43.520513 | controller |
2025-10-08 15:56:43.520519 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 15:56:43.520525 | controller | Wednesday 08 October 2025  15:56:42 +0000 (0:00:00.022)       0:01:10.006 *****
2025-10-08 15:56:43.520535 | controller | ok: [instance]
2025-10-08 15:56:43.789753 | controller |
2025-10-08 15:56:43.789786 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 15:56:43.789794 | controller | Wednesday 08 October 2025  15:56:43 +0000 (0:00:01.252)       0:01:11.259 *****
2025-10-08 15:56:43.789804 | controller | ok: [instance]
2025-10-08 15:56:43.999677 | controller |
2025-10-08 15:56:43.999708 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 15:56:43.999716 | controller | Wednesday 08 October 2025  15:56:43 +0000 (0:00:00.269)       0:01:11.528 *****
2025-10-08 15:56:43.999726 | controller | ok: [instance]
2025-10-08 15:56:44.066128 | controller |
2025-10-08 15:56:44.066157 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 15:56:44.066165 | controller | Wednesday 08 October 2025  15:56:43 +0000 (0:00:00.209)       0:01:11.738 *****
2025-10-08 15:56:44.066175 | controller | skipping: [instance]
2025-10-08 15:56:44.102690 | controller |
2025-10-08 15:56:44.102723 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 15:56:44.102733 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.066)       0:01:11.804 *****
2025-10-08 15:56:44.102745 | controller | skipping: [instance]
2025-10-08 15:56:44.102774 | controller |
2025-10-08 15:56:44.102784 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 15:56:44.102881 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.036)       0:01:11.841 *****
2025-10-08 15:56:44.285716 | controller | ok: [instance]
2025-10-08 15:56:44.285842 | controller |
2025-10-08 15:56:44.285872 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 15:56:44.285885 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.182)       0:01:12.024 *****
2025-10-08 15:56:44.575696 | controller | ok: [instance]
2025-10-08 15:56:44.575774 | controller |
2025-10-08 15:56:44.575806 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 15:56:44.575818 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.289)       0:01:12.314 *****
2025-10-08 15:56:44.593685 | controller | skipping: [instance]
2025-10-08 15:56:44.613056 | controller |
2025-10-08 15:56:44.613089 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 15:56:44.613097 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.018)       0:01:12.332 *****
2025-10-08 15:56:44.613107 | controller | skipping: [instance]
2025-10-08 15:56:44.632729 | controller |
2025-10-08 15:56:44.632763 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 15:56:44.632773 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.018)       0:01:12.351 *****
2025-10-08 15:56:44.632785 | controller | skipping: [instance]
2025-10-08 15:56:44.651617 | controller |
2025-10-08 15:56:44.651652 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 15:56:44.651660 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.019)       0:01:12.371 *****
2025-10-08 15:56:44.651670 | controller | skipping: [instance]
2025-10-08 15:56:44.670537 | controller |
2025-10-08 15:56:44.670566 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 15:56:44.670574 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.019)       0:01:12.390 *****
2025-10-08 15:56:44.670605 | controller | skipping: [instance]
2025-10-08 15:56:44.698368 | controller |
2025-10-08 15:56:44.698402 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 15:56:44.698412 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.018)       0:01:12.409 *****
2025-10-08 15:56:44.698427 | controller | skipping: [instance]
2025-10-08 15:56:44.698636 | controller |
2025-10-08 15:56:44.698829 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 15:56:44.699019 | controller | Wednesday 08 October 2025  15:56:44 +0000 (0:00:00.028)       0:01:12.437 *****
2025-10-08 15:56:45.707904 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 15:56:45.747700 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 15:56:45.747730 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 15:56:45.747738 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 15:56:45.747743 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 15:56:45.747749 | controller |
2025-10-08 15:56:45.747755 | controller | TASK [Check for CRC startup] ***************************************************
2025-10-08 15:56:45.747761 | controller | Wednesday 08 October 2025  15:56:45 +0000 (0:00:01.008)       0:01:13.446 *****
2025-10-08 15:56:45.747770 | controller | skipping: [instance]
2025-10-08 15:56:45.747796 | controller |
2025-10-08 15:56:45.747802 | controller | PLAY RECAP *********************************************************************
2025-10-08 15:56:45.747808 | controller | instance                   : ok=28   changed=4    unreachable=0    failed=0    skipped=42   rescued=0    ignored=0
2025-10-08 15:56:45.747813 | controller |
2025-10-08 15:56:45.747818 | controller | Wednesday 08 October 2025  15:56:45 +0000 (0:00:00.039)       0:01:13.485 *****
2025-10-08 15:56:45.747823 | controller | ===============================================================================
2025-10-08 15:56:45.747831 | controller | test_deps : Install selinux python libs -------------------------------- 58.33s
2025-10-08 15:56:45.749527 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.40s
2025-10-08 15:56:45.749552 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.32s
2025-10-08 15:56:45.807629 | controller | ci_setup : Install needed packages -------------------------------------- 1.25s
2025-10-08 15:56:45.807662 | controller | test_deps : Install python yaml libs ------------------------------------ 1.23s
2025-10-08 15:56:45.807670 | controller | ci_setup : Manage directories ------------------------------------------- 1.01s
2025-10-08 15:56:45.807675 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.89s
2025-10-08 15:56:45.807680 | controller | Gathering Facts --------------------------------------------------------- 0.87s
2025-10-08 15:56:45.807686 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.72s
2025-10-08 15:56:45.807691 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.60s
2025-10-08 15:56:45.807696 | controller | Inject our own NetworkManager configuration ----------------------------- 0.59s
2025-10-08 15:56:45.807701 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-08 15:56:45.807712 | controller | repo_setup : Ensure directories are present ----------------------------- 0.57s
2025-10-08 15:56:45.807717 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.36s
2025-10-08 15:56:45.807722 | controller | repo_setup : Install repo-setup package --------------------------------- 0.30s
2025-10-08 15:56:45.807727 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.29s
2025-10-08 15:56:45.807732 | controller | ci_setup : Gather version of openshift client --------------------------- 0.27s
2025-10-08 15:56:45.807737 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.24s
2025-10-08 15:56:45.807742 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-10-08 15:56:45.807748 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.21s
2025-10-08 15:56:45.807758 | controller | INFO     Running default > converge
2025-10-08 15:56:46.293750 | controller |
2025-10-08 15:56:46.293999 | controller | PLAY [Converge default] ********************************************************
2025-10-08 15:56:46.294160 | controller |
2025-10-08 15:56:46.294300 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 15:56:46.294456 | controller | Wednesday 08 October 2025  15:56:46 +0000 (0:00:00.030)       0:00:00.030 *****
2025-10-08 15:56:47.132063 | controller | ok: [instance]
2025-10-08 15:56:47.810454 | controller |
2025-10-08 15:56:47.810490 | controller | TASK [rhol_crc : Ensure directories exist] *************************************
2025-10-08 15:56:47.810499 | controller | Wednesday 08 October 2025  15:56:47 +0000 (0:00:00.838)       0:00:00.868 *****
2025-10-08 15:56:47.810509 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
2025-10-08 15:56:48.025101 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 15:56:48.025136 | controller | ok: [instance] => (item=/usr/local/bin)
2025-10-08 15:56:48.025144 | controller |
2025-10-08 15:56:48.025150 | controller | TASK [manage_secrets : Ensure directory tree exist] ****************************
2025-10-08 15:56:48.025156 | controller | Wednesday 08 October 2025  15:56:47 +0000 (0:00:00.678)       0:00:01.546 *****
2025-10-08 15:56:48.025166 | controller | changed: [instance]
2025-10-08 15:56:48.331500 | controller |
2025-10-08 15:56:48.331532 | controller | TASK [rhol_crc : Check for binary] *********************************************
2025-10-08 15:56:48.331540 | controller | Wednesday 08 October 2025  15:56:48 +0000 (0:00:00.214)       0:00:01.761 *****
2025-10-08 15:56:48.331550 | controller | ok: [instance]
2025-10-08 15:56:48.369725 | controller |
2025-10-08 15:56:48.369760 | controller | TASK [rhol_crc : Get versions if binary is available] **************************
2025-10-08 15:56:48.369768 | controller | Wednesday 08 October 2025  15:56:48 +0000 (0:00:00.306)       0:00:02.067 *****
2025-10-08 15:56:48.369779 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/get_versions.yml for instance
2025-10-08 15:56:48.369804 | controller |
2025-10-08 15:56:48.369812 | controller | TASK [rhol_crc : Get CRC binary version] ***************************************
2025-10-08 15:56:48.370014 | controller | Wednesday 08 October 2025  15:56:48 +0000 (0:00:00.038)       0:00:02.106 *****
2025-10-08 15:56:49.769669 | controller | changed: [instance]
2025-10-08 15:56:49.769795 | controller |
2025-10-08 15:56:49.769899 | controller | TASK [rhol_crc : Extract versions] *********************************************
2025-10-08 15:56:49.769918 | controller | Wednesday 08 October 2025  15:56:49 +0000 (0:00:01.400)       0:00:03.506 *****
2025-10-08 15:56:49.809704 | controller | ok: [instance]
2025-10-08 15:56:49.833253 | controller |
2025-10-08 15:56:49.833284 | controller | TASK [rhol_crc : Output found binary location and versions] ********************
2025-10-08 15:56:49.833292 | controller | Wednesday 08 October 2025  15:56:49 +0000 (0:00:00.039)       0:00:03.545 *****
2025-10-08 15:56:49.833302 | controller | ok: [instance] =>
2025-10-08 15:56:50.216434 | controller |   msg: 'Found crc at /usr/local/bin/crc, with version 2.39.0 (wants: 2.39.0) and serving OpenShift version 4.16.0.'
2025-10-08 15:56:50.216483 | controller |
2025-10-08 15:56:50.216491 | controller | TASK [rhol_crc : Get CRC VM availability] **************************************
2025-10-08 15:56:50.216498 | controller | Wednesday 08 October 2025  15:56:49 +0000 (0:00:00.023)       0:00:03.569 *****
2025-10-08 15:56:50.216509 | controller | ok: [instance]
2025-10-08 15:56:50.246556 | controller |
2025-10-08 15:56:50.246631 | controller | TASK [rhol_crc : Set CRC status] ***********************************************
2025-10-08 15:56:50.246639 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.382)       0:00:03.952 *****
2025-10-08 15:56:50.246651 | controller | ok: [instance]
2025-10-08 15:56:50.275826 | controller |
2025-10-08 15:56:50.275870 | controller | TASK [rhol_crc : Fail if crc domain is already defined] ************************
2025-10-08 15:56:50.275878 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.030)       0:00:03.982 *****
2025-10-08 15:56:50.275905 | controller | skipping: [instance]
2025-10-08 15:56:50.275912 | controller |
2025-10-08 15:56:50.275917 | controller | TASK [Ensure pull-secret is in place if we need to configure CRC] **************
2025-10-08 15:56:50.275925 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.029)       0:00:04.011 *****
2025-10-08 15:56:50.322458 | controller |
2025-10-08 15:56:50.348401 | controller | TASK [manage_secrets : Assert we get needed data] ******************************
2025-10-08 15:56:50.348436 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.046)       0:00:04.058 *****
2025-10-08 15:56:50.348463 | controller | ok: [instance]
2025-10-08 15:56:50.348667 | controller |
2025-10-08 15:56:50.348843 | controller | TASK [manage_secrets : Manage secret] ******************************************
2025-10-08 15:56:50.349042 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.026)       0:00:04.085 *****
2025-10-08 15:56:50.379540 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/manage_secrets/tasks/_push_secret.yml for instance
2025-10-08 15:56:50.379740 | controller |
2025-10-08 15:56:50.379902 | controller | TASK [manage_secrets : Debug _secret_dest] *************************************
2025-10-08 15:56:50.380075 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.031)       0:00:04.116 *****
2025-10-08 15:56:50.406971 | controller | ok: [instance] =>
2025-10-08 15:56:50.407173 | controller |   _secret_dest: /home/zuul/pull-secret.json
2025-10-08 15:56:50.407304 | controller |
2025-10-08 15:56:50.407448 | controller | TASK [manage_secrets : Debug _secret_file] *************************************
2025-10-08 15:56:50.407605 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.027)       0:00:04.143 *****
2025-10-08 15:56:50.429669 | controller | ok: [instance] =>
2025-10-08 15:56:50.429865 | controller |   _secret_file: ''
2025-10-08 15:56:50.430056 | controller |
2025-10-08 15:56:50.430218 | controller | TASK [manage_secrets : Debug _secret_content] **********************************
2025-10-08 15:56:50.430357 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.022)       0:00:04.166 *****
2025-10-08 15:56:50.453354 | controller | ok: [instance] =>
2025-10-08 15:56:50.453565 | controller |   _secret_content:
2025-10-08 15:56:50.453770 | controller |     auths:
2025-10-08 15:56:50.453947 | controller |       fake:
2025-10-08 15:56:50.454132 | controller |         auth: Zm9vOmJhcgo=
2025-10-08 15:56:50.454269 | controller |
2025-10-08 15:56:50.454409 | controller | TASK [manage_secrets : Ensure parameter is an absolute path] *******************
2025-10-08 15:56:50.454545 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.023)       0:00:04.190 *****
2025-10-08 15:56:50.473411 | controller | skipping: [instance]
2025-10-08 15:56:50.473607 | controller |
2025-10-08 15:56:50.473770 | controller | TASK [manage_secrets : Check if pull secret src file exists] *******************
2025-10-08 15:56:50.473915 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.020)       0:00:04.210 *****
2025-10-08 15:56:50.500177 | controller | skipping: [instance]
2025-10-08 15:56:50.500680 | controller |
2025-10-08 15:56:50.500844 | controller | TASK [manage_secrets : Copy file to location] **********************************
2025-10-08 15:56:50.500998 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.022)       0:00:04.232 *****
2025-10-08 15:56:50.517620 | controller | skipping: [instance]
2025-10-08 15:56:50.517812 | controller |
2025-10-08 15:56:50.518011 | controller | TASK [manage_secrets : Create file from content] *******************************
2025-10-08 15:56:50.518187 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.021)       0:00:04.254 *****
2025-10-08 15:56:50.996752 | controller | changed: [instance]
2025-10-08 15:56:51.032763 | controller |
2025-10-08 15:56:51.032844 | controller | TASK [rhol_crc : Get wanted RHOL/CRC version binary if needed] *****************
2025-10-08 15:56:51.032853 | controller | Wednesday 08 October 2025  15:56:50 +0000 (0:00:00.478)       0:00:04.732 *****
2025-10-08 15:56:51.032867 | controller | skipping: [instance]
2025-10-08 15:56:51.071268 | controller |
2025-10-08 15:56:51.071316 | controller | TASK [rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup] *******
2025-10-08 15:56:51.071325 | controller | Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.036)       0:00:04.768 *****
2025-10-08 15:56:51.071337 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/sudoers_grant.yml for instance
2025-10-08 15:56:51.395633 | controller |
2025-10-08 15:56:51.395674 | controller | TASK [rhol_crc : Add passwordless sudo access for CRC commands] ****************
2025-10-08 15:56:51.395683 | controller | Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.038)       0:00:04.807 *****
2025-10-08 15:56:51.395768 | controller | changed: [instance]
2025-10-08 15:56:51.426687 | controller |
2025-10-08 15:56:51.426721 | controller | TASK [rhol_crc : Clean RHOL/CRC if wanted] *************************************
2025-10-08 15:56:51.426730 | controller | Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.324)       0:00:05.131 *****
2025-10-08 15:56:51.426753 | controller | skipping: [instance]
2025-10-08 15:56:51.465736 | controller |
2025-10-08 15:56:51.465768 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
2025-10-08 15:56:51.465776 | controller | Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.031)       0:00:05.162 *****
2025-10-08 15:56:51.465787 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/tasks/configuration.yml for instance
2025-10-08 15:56:53.510688 | controller |
2025-10-08 15:56:53.510728 | controller | TASK [rhol_crc : Set RHOL/CRC configuration options] ***************************
2025-10-08 15:56:53.510742 | controller | Wednesday 08 October 2025  15:56:51 +0000 (0:00:00.039)       0:00:05.202 *****
2025-10-08 15:56:53.510754 | controller | changed: [instance] => (item={'key': 'consent-telemetry', 'value': 'no'})
2025-10-08 15:56:53.510782 | controller | changed: [instance] => (item={'key': 'disk-size', 'value': 32})
2025-10-08 15:56:53.510789 | controller | changed: [instance] => (item={'key': 'memory', 'value': 10752})
2025-10-08 15:56:53.510795 | controller | changed: [instance] => (item={'key': 'cpus', 'value': 4})
2025-10-08 15:56:53.510800 | controller | changed: [instance] => (item={'key': 'preset', 'value': 'openshift'})
2025-10-08 15:56:53.510805 | controller | changed: [instance] => (item={'key': 'pull-secret-file', 'value': '/home/zuul/pull-secret.json'})
2025-10-08 15:56:53.510813 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-unit', 'value': True})
2025-10-08 15:56:53.510836 | controller | changed: [instance] => (item={'key': 'skip-check-daemon-systemd-sockets', 'value': True})
2025-10-08 15:56:53.511309 | controller | changed: [instance] => (item={'key': 'kubeadmin-password', 'value': 12345678})
2025-10-08 16:00:56.104249 | controller |
2025-10-08 16:00:56.104498 | controller | TASK [rhol_crc : Setup RHOL/CRC] ***********************************************
2025-10-08 16:00:56.104511 | controller | Wednesday 08 October 2025  15:56:53 +0000 (0:00:02.044)       0:00:07.246 *****
2025-10-08 16:00:56.104522 | controller | changed: [instance]
2025-10-08 16:24:03.207434 | controller |
2025-10-08 16:24:03.207492 | controller | TASK [rhol_crc : Start RHOL/CRC] ***********************************************
2025-10-08 16:24:03.207502 | controller | Wednesday 08 October 2025  16:00:56 +0000 (0:04:02.593)       0:04:09.840 *****
2025-10-08 16:24:03.207513 | controller | fatal: [instance]: FAILED! => changed=true
2025-10-08 16:24:03.207553 | controller |   cmd: set -o pipefail; /usr/local/bin/crc start 2>&1 | tee /home/zuul/ci-framework-data/logs/crc-start.log
2025-10-08 16:24:03.207560 | controller |   delta: '0:23:06.871473'
2025-10-08 16:24:03.207565 | controller |   end: '2025-10-08 16:24:03.177967'
2025-10-08 16:24:03.207571 | controller |   msg: non-zero return code
2025-10-08 16:24:03.207597 | controller |   rc: 1
2025-10-08 16:24:03.207613 | controller |   start: '2025-10-08 16:00:56.306494'
2025-10-08 16:24:03.207770 | controller |   stderr: ''
2025-10-08 16:24:03.207781 | controller |   stderr_lines: <omitted>
2025-10-08 16:24:03.208374 | controller |   stdout: |-
2025-10-08 16:24:03.208408 | controller |     level=warning msg="A new version (2.54.0) has been published on https://developers.redhat.com/content-gateway/file/pub/openshift-v4/clients/crc/2.54.0/crc-linux-amd64.tar.xz"
2025-10-08 16:24:03.208433 | controller |     level=info msg="Using bundle path /home/zuul/.crc/cache/crc_libvirt_4.16.0_amd64.crcbundle"
2025-10-08 16:24:03.208440 | controller |     level=info msg="Checking if running as non-root"
2025-10-08 16:24:03.208445 | controller |     level=info msg="Checking if running inside WSL2"
2025-10-08 16:24:03.208451 | controller |     level=info msg="Checking if crc-admin-helper executable is cached"
2025-10-08 16:24:03.208456 | controller |     level=info msg="Checking if running on a supported CPU architecture"
2025-10-08 16:24:03.208464 | controller |     level=info msg="Checking if crc executable symlink exists"
2025-10-08 16:24:03.208654 | controller |     level=info msg="Checking minimum RAM requirements"
2025-10-08 16:24:03.208818 | controller |     level=info msg="Check if Podman binary exists in: /home/zuul/.crc/bin/oc"
2025-10-08 16:24:03.208844 | controller |     level=info msg="Checking if Virtualization is enabled"
2025-10-08 16:24:03.208875 | controller |     level=info msg="Checking if KVM is enabled"
2025-10-08 16:24:03.209006 | controller |     level=info msg="Checking if libvirt is installed"
2025-10-08 16:24:03.209080 | controller |     level=info msg="Checking if user is part of libvirt group"
2025-10-08 16:24:03.209234 | controller |     level=info msg="Checking if active user/process is currently part of the libvirt group"
2025-10-08 16:24:03.209375 | controller |     level=info msg="Checking if libvirt daemon is running"
2025-10-08 16:24:03.209461 | controller |     level=info msg="Checking if a supported libvirt version is installed"
2025-10-08 16:24:03.209481 | controller |     level=info msg="Checking if crc-driver-libvirt is installed"
2025-10-08 16:24:03.209627 | controller |     level=info msg="Checking crc daemon systemd socket units"
2025-10-08 16:24:03.209659 | controller |     level=warning msg="Skipping above check..."
2025-10-08 16:24:03.209893 | controller |     level=info msg="Checking if systemd-networkd is running"
2025-10-08 16:24:03.210030 | controller |     level=info msg="Checking if NetworkManager is installed"
2025-10-08 16:24:03.210043 | controller |     level=info msg="Checking if NetworkManager service is running"
2025-10-08 16:24:03.210320 | controller |     level=info msg="Checking if /etc/NetworkManager/conf.d/crc-nm-dnsmasq.conf exists"
2025-10-08 16:24:03.210341 | controller |     level=info msg="Checking if /etc/NetworkManager/dnsmasq.d/crc.conf exists"
2025-10-08 16:24:03.210365 | controller |     level=info msg="Checking if libvirt 'crc' network is available"
2025-10-08 16:24:03.210373 | controller |     level=info msg="Checking if libvirt 'crc' network is active"
2025-10-08 16:24:03.210449 | controller |     level=info msg="Loading bundle: crc_libvirt_4.16.0_amd64..."
2025-10-08 16:24:03.210563 | controller |     level=info msg="Creating CRC VM for OpenShift 4.16.0..."
2025-10-08 16:24:03.210918 | controller |     level=info msg="Generating new SSH key pair..."
2025-10-08 16:24:03.211027 | controller |     level=info msg="Generating new password for the kubeadmin user"
2025-10-08 16:24:03.211038 | controller |     level=info msg="Starting CRC VM for openshift 4.16.0..."
2025-10-08 16:24:03.211043 | controller |     level=info msg="CRC instance is running with IP 192.168.130.11"
2025-10-08 16:24:03.211073 | controller |     level=info msg="CRC VM is running"
2025-10-08 16:24:03.211172 | controller |     level=info msg="Updating authorized keys..."
2025-10-08 16:24:03.211290 | controller |     level=info msg="Resizing /dev/vda4 filesystem"
2025-10-08 16:24:03.211404 | controller |     level=info msg="Configuring shared directories"
2025-10-08 16:24:03.211520 | controller |     level=info msg="Check internal and public DNS query..."
2025-10-08 16:24:03.211645 | controller |     level=info msg="Check DNS query from host..."
2025-10-08 16:24:03.211780 | controller |     level=info msg="Verifying validity of the kubelet certificates..."
2025-10-08 16:24:03.211898 | controller |     level=info msg="Starting kubelet service"
2025-10-08 16:24:03.212028 | controller |     level=info msg="Kubelet client certificate has expired, renewing it... [will take up to 10 minutes]"
2025-10-08 16:24:03.212162 | controller |     level=info msg="Kubelet serving certificate has expired, waiting for automatic renewal... [will take up to 5 minutes]"
2025-10-08 16:24:03.212298 | controller |     level=info msg="Waiting for kube-apiserver availability... [takes around 2min]"
2025-10-08 16:24:03.212422 | controller |     level=info msg="Adding user's pull secret to the cluster..."
2025-10-08 16:24:03.212535 | controller |     level=info msg="Updating SSH key to machine config resource..."
2025-10-08 16:24:03.212674 | controller |     level=info msg="Waiting until the user's pull secret is written to the instance disk..."
2025-10-08 16:24:03.212799 | controller |     Failed to update pull secret on the disk: Temporary error: pull secret not updated to disk (x205)
2025-10-08 16:24:03.212910 | controller |   stdout_lines: <omitted>
2025-10-08 16:24:03.214804 | controller |
2025-10-08 16:24:03.214934 | controller | TASK [rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup] ***
2025-10-08 16:24:03.215061 | controller | Wednesday 08 October 2025  16:24:03 +0000 (0:23:07.111)       0:27:16.951 *****
2025-10-08 16:24:03.421714 | controller | changed: [instance]
2025-10-08 16:24:03.422710 | controller |
2025-10-08 16:24:03.422849 | controller | PLAY RECAP *********************************************************************
2025-10-08 16:24:03.422862 | controller | instance                   : ok=22   changed=7    unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2025-10-08 16:24:03.422869 | controller |
2025-10-08 16:24:03.422875 | controller | Wednesday 08 October 2025  16:24:03 +0000 (0:00:00.206)       0:27:17.158 *****
2025-10-08 16:24:03.422886 | controller | ===============================================================================
2025-10-08 16:24:03.422894 | controller | rhol_crc : Start RHOL/CRC -------------------------------------------- 1387.11s
2025-10-08 16:24:03.423828 | controller | rhol_crc : Setup RHOL/CRC --------------------------------------------- 242.59s
2025-10-08 16:24:03.423847 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 2.04s
2025-10-08 16:24:03.424434 | controller | rhol_crc : Get CRC binary version --------------------------------------- 1.40s
2025-10-08 16:24:03.424453 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-10-08 16:24:03.424459 | controller | rhol_crc : Ensure directories exist ------------------------------------- 0.68s
2025-10-08 16:24:03.424465 | controller | manage_secrets : Create file from content ------------------------------- 0.48s
2025-10-08 16:24:03.424471 | controller | rhol_crc : Get CRC VM availability -------------------------------------- 0.38s
2025-10-08 16:24:03.424476 | controller | rhol_crc : Add passwordless sudo access for CRC commands ---------------- 0.32s
2025-10-08 16:24:03.424481 | controller | rhol_crc : Check for binary --------------------------------------------- 0.31s
2025-10-08 16:24:03.424486 | controller | manage_secrets : Ensure directory tree exist ---------------------------- 0.21s
2025-10-08 16:24:03.424491 | controller | rhol_crc : Revoke permissions granted in sudoers file for CRC/RHOL setup --- 0.21s
2025-10-08 16:24:03.424500 | controller | Ensure pull-secret is in place if we need to configure CRC -------------- 0.05s
2025-10-08 16:24:03.424526 | controller | rhol_crc : Extract versions --------------------------------------------- 0.04s
2025-10-08 16:24:03.424532 | controller | rhol_crc : Set RHOL/CRC configuration options --------------------------- 0.04s
2025-10-08 16:24:03.424542 | controller | rhol_crc : Setup sudoers file for sudo commands in RHOL/CRC setup ------- 0.04s
2025-10-08 16:24:03.424548 | controller | rhol_crc : Get versions if binary is available -------------------------- 0.04s
2025-10-08 16:24:03.424553 | controller | rhol_crc : Get wanted RHOL/CRC version binary if needed ----------------- 0.04s
2025-10-08 16:24:03.424560 | controller | rhol_crc : Clean RHOL/CRC if wanted ------------------------------------- 0.03s
2025-10-08 16:24:03.424599 | controller | manage_secrets : Manage secret ------------------------------------------ 0.03s
2025-10-08 16:24:03.481515 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/rhol_crc/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/rhol_crc/molecule/default/converge.yml']
2025-10-08 16:24:03.481923 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-10-08 16:24:03.491489 | controller | INFO     Running default > cleanup
2025-10-08 16:24:03.944804 | controller |
2025-10-08 16:24:03.944866 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 16:24:03.944985 | controller |
2025-10-08 16:24:03.945152 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 16:24:03.945181 | controller | Wednesday 08 October 2025  16:24:03 +0000 (0:00:00.016)       0:00:00.016 *****
2025-10-08 16:24:04.821972 | controller | ok: [instance]
2025-10-08 16:24:04.822059 | controller |
2025-10-08 16:24:04.822187 | controller | TASK [rhol_crc : Check RHOL/CRC binary exists] *********************************
2025-10-08 16:24:04.822295 | controller | Wednesday 08 October 2025  16:24:04 +0000 (0:00:00.877)       0:00:00.893 *****
2025-10-08 16:24:05.110383 | controller | ok: [instance]
2025-10-08 16:24:05.110448 | controller |
2025-10-08 16:24:05.110571 | controller | TASK [rhol_crc : Delete RHOL/CRC instance and associated configuration] ********
2025-10-08 16:24:05.110703 | controller | Wednesday 08 October 2025  16:24:05 +0000 (0:00:00.288)       0:00:01.182 *****
2025-10-08 16:24:08.658327 | controller | changed: [instance]
2025-10-08 16:24:08.658382 | controller |
2025-10-08 16:24:08.658522 | controller | PLAY RECAP *********************************************************************
2025-10-08 16:24:08.658674 | controller | instance                   : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 16:24:08.658771 | controller |
2025-10-08 16:24:08.658875 | controller | Wednesday 08 October 2025  16:24:08 +0000 (0:00:03.548)       0:00:04.730 *****
2025-10-08 16:24:08.658963 | controller | ===============================================================================
2025-10-08 16:24:08.659078 | controller | rhol_crc : Delete RHOL/CRC instance and associated configuration -------- 3.55s
2025-10-08 16:24:08.659182 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-10-08 16:24:08.659290 | controller | rhol_crc : Check RHOL/CRC binary exists --------------------------------- 0.29s
2025-10-08 16:24:08.714565 | controller | INFO     Running default > destroy
2025-10-08 16:24:08.714910 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 16:24:08.715228 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-08 16:24:08.716904 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 16:24:09.247006 | controller | ERROR
2025-10-08 16:24:09.247194 | controller | {
2025-10-08 16:24:09.247226 | controller |   "delta": "0:34:21.982666",
2025-10-08 16:24:09.247247 | controller |   "end": "2025-10-08 16:24:08.767333",
2025-10-08 16:24:09.247265 | controller |   "msg": "non-zero return code",
2025-10-08 16:24:09.247286 | controller |   "rc": 1,
2025-10-08 16:24:09.247305 | controller |   "start": "2025-10-08 15:49:46.784667"
2025-10-08 16:24:09.247322 | controller | }
failure
2025-10-08 16:24:09.301162 | 
2025-10-08 16:24:09.301283 | PLAY RECAP
2025-10-08 16:24:09.301338 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-08 16:24:09.301361 | 
2025-10-08 16:24:09.386943 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 16:24:09.387777 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 16:24:09.959565 | 
2025-10-08 16:24:09.959682 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 16:24:09.981139 | 
2025-10-08 16:24:09.981236 | TASK [Filter out host if needed]
2025-10-08 16:24:09.990154 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 16:24:09.994863 | 
2025-10-08 16:24:09.994949 | TASK [Ensure file is present]
2025-10-08 16:24:10.350742 | controller | ok
2025-10-08 16:24:10.357926 | 
2025-10-08 16:24:10.358019 | TASK [Manage molecule report file]
2025-10-08 16:24:10.885247 | controller | changed
2025-10-08 16:24:10.891078 | 
2025-10-08 16:24:10.891244 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 16:24:11.114452 | controller | ok
2025-10-08 16:24:11.120482 | 
2025-10-08 16:24:11.120560 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 16:24:11.465247 | controller | changed
2025-10-08 16:24:11.470657 | 
2025-10-08 16:24:11.470752 | TASK [Copy ci-framework interesting files]
2025-10-08 16:24:11.697558 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 16:24:11.996017 | controller | changed
2025-10-08 16:24:12.001784 | 
2025-10-08 16:24:12.001854 | TASK [Get SELinux listing]
2025-10-08 16:24:12.526333 | controller | changed
2025-10-08 16:24:12.532935 | 
2025-10-08 16:24:12.533010 | TASK [Generate log index]
2025-10-08 16:24:13.312721 | controller | changed
2025-10-08 16:24:13.318814 | 
2025-10-08 16:24:13.318881 | TASK [Get some env related data]
2025-10-08 16:24:13.965822 | controller | /home/zuul/.local/bin/ansible
2025-10-08 16:24:14.849621 | controller | changed
2025-10-08 16:24:14.855871 | 
2025-10-08 16:24:14.855969 | TASK [Generate list of logs to collect in home directory]
2025-10-08 16:24:15.171590 | controller | ok: All paths examined
2025-10-08 16:24:15.219921 | 
2025-10-08 16:24:15.220047 | LOOP [Copy logs from home directory]
2025-10-08 16:24:15.613822 | controller | changed:
2025-10-08 16:24:15.614060 | controller | {
2025-10-08 16:24:15.614090 | controller |   "atime": 1743544925.4788878,
2025-10-08 16:24:15.614111 | controller |   "ctime": 1743545329.1409318,
2025-10-08 16:24:15.614129 | controller |   "dev": 64513,
2025-10-08 16:24:15.614145 | controller |   "gid": 1000,
2025-10-08 16:24:15.614161 | controller |   "gr_name": "zuul",
2025-10-08 16:24:15.614178 | controller |   "inode": 4518807,
2025-10-08 16:24:15.614193 | controller |   "isblk": false,
2025-10-08 16:24:15.614207 | controller |   "ischr": false,
2025-10-08 16:24:15.614221 | controller |   "isdir": false,
2025-10-08 16:24:15.614240 | controller |   "isfifo": false,
2025-10-08 16:24:15.614256 | controller |   "isgid": false,
2025-10-08 16:24:15.614271 | controller |   "islnk": false,
2025-10-08 16:24:15.614285 | controller |   "isreg": true,
2025-10-08 16:24:15.614302 | controller |   "issock": false,
2025-10-08 16:24:15.614318 | controller |   "isuid": false,
2025-10-08 16:24:15.614333 | controller |   "mode": "0644",
2025-10-08 16:24:15.614346 | controller |   "mtime": 1743545329.1409318,
2025-10-08 16:24:15.614360 | controller |   "nlink": 1,
2025-10-08 16:24:15.614374 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 16:24:15.614395 | controller |   "pw_name": "zuul",
2025-10-08 16:24:15.614412 | controller |   "rgrp": true,
2025-10-08 16:24:15.614427 | controller |   "roth": true,
2025-10-08 16:24:15.614442 | controller |   "rusr": true,
2025-10-08 16:24:15.614457 | controller |   "size": 4108,
2025-10-08 16:24:15.614473 | controller |   "uid": 1000,
2025-10-08 16:24:15.614488 | controller |   "wgrp": false,
2025-10-08 16:24:15.614502 | controller |   "woth": false,
2025-10-08 16:24:15.614519 | controller |   "wusr": true,
2025-10-08 16:24:15.614533 | controller |   "xgrp": false,
2025-10-08 16:24:15.614559 | controller |   "xoth": false,
2025-10-08 16:24:15.614576 | controller |   "xusr": false
2025-10-08 16:24:15.614590 | controller | }
2025-10-08 16:24:15.967966 | controller | changed:
2025-10-08 16:24:15.968069 | controller | {
2025-10-08 16:24:15.968096 | controller |   "atime": 1743545331.0429637,
2025-10-08 16:24:15.968116 | controller |   "ctime": 1743545842.0111232,
2025-10-08 16:24:15.968134 | controller |   "dev": 64513,
2025-10-08 16:24:15.968152 | controller |   "gid": 1000,
2025-10-08 16:24:15.968168 | controller |   "gr_name": "zuul",
2025-10-08 16:24:15.968184 | controller |   "inode": 4194437,
2025-10-08 16:24:15.968199 | controller |   "isblk": false,
2025-10-08 16:24:15.968213 | controller |   "ischr": false,
2025-10-08 16:24:15.968227 | controller |   "isdir": false,
2025-10-08 16:24:15.968242 | controller |   "isfifo": false,
2025-10-08 16:24:15.968256 | controller |   "isgid": false,
2025-10-08 16:24:15.968270 | controller |   "islnk": false,
2025-10-08 16:24:15.968285 | controller |   "isreg": true,
2025-10-08 16:24:15.968299 | controller |   "issock": false,
2025-10-08 16:24:15.968312 | controller |   "isuid": false,
2025-10-08 16:24:15.968326 | controller |   "mode": "0644",
2025-10-08 16:24:15.968339 | controller |   "mtime": 1743545842.0111232,
2025-10-08 16:24:15.968352 | controller |   "nlink": 1,
2025-10-08 16:24:15.968413 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 16:24:15.968431 | controller |   "pw_name": "zuul",
2025-10-08 16:24:15.968448 | controller |   "rgrp": true,
2025-10-08 16:24:15.968463 | controller |   "roth": true,
2025-10-08 16:24:15.968478 | controller |   "rusr": true,
2025-10-08 16:24:15.968492 | controller |   "size": 4023,
2025-10-08 16:24:15.968506 | controller |   "uid": 1000,
2025-10-08 16:24:15.968520 | controller |   "wgrp": false,
2025-10-08 16:24:15.968533 | controller |   "woth": false,
2025-10-08 16:24:15.968562 | controller |   "wusr": true,
2025-10-08 16:24:15.968578 | controller |   "xgrp": false,
2025-10-08 16:24:15.968592 | controller |   "xoth": false,
2025-10-08 16:24:15.968606 | controller |   "xusr": false
2025-10-08 16:24:15.968620 | controller | }
2025-10-08 16:24:16.327958 | controller | changed:
2025-10-08 16:24:16.328073 | controller | {
2025-10-08 16:24:16.328100 | controller |   "atime": 1759938568.3538852,
2025-10-08 16:24:16.328121 | controller |   "ctime": 1759938584.1730893,
2025-10-08 16:24:16.328138 | controller |   "dev": 64513,
2025-10-08 16:24:16.328154 | controller |   "gid": 1000,
2025-10-08 16:24:16.328169 | controller |   "gr_name": "zuul",
2025-10-08 16:24:16.328184 | controller |   "inode": 4233824,
2025-10-08 16:24:16.328198 | controller |   "isblk": false,
2025-10-08 16:24:16.328212 | controller |   "ischr": false,
2025-10-08 16:24:16.328226 | controller |   "isdir": false,
2025-10-08 16:24:16.328245 | controller |   "isfifo": false,
2025-10-08 16:24:16.328261 | controller |   "isgid": false,
2025-10-08 16:24:16.328275 | controller |   "islnk": false,
2025-10-08 16:24:16.328288 | controller |   "isreg": true,
2025-10-08 16:24:16.328302 | controller |   "issock": false,
2025-10-08 16:24:16.328315 | controller |   "isuid": false,
2025-10-08 16:24:16.328329 | controller |   "mode": "0644",
2025-10-08 16:24:16.328342 | controller |   "mtime": 1759938584.1730893,
2025-10-08 16:24:16.328356 | controller |   "nlink": 1,
2025-10-08 16:24:16.328369 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 16:24:16.328383 | controller |   "pw_name": "zuul",
2025-10-08 16:24:16.328397 | controller |   "rgrp": true,
2025-10-08 16:24:16.328411 | controller |   "roth": true,
2025-10-08 16:24:16.328427 | controller |   "rusr": true,
2025-10-08 16:24:16.328442 | controller |   "size": 6749,
2025-10-08 16:24:16.328455 | controller |   "uid": 1000,
2025-10-08 16:24:16.328469 | controller |   "wgrp": false,
2025-10-08 16:24:16.328482 | controller |   "woth": false,
2025-10-08 16:24:16.328495 | controller |   "wusr": true,
2025-10-08 16:24:16.328507 | controller |   "xgrp": false,
2025-10-08 16:24:16.328520 | controller |   "xoth": false,
2025-10-08 16:24:16.328532 | controller |   "xusr": false
2025-10-08 16:24:16.328545 | controller | }
2025-10-08 16:24:16.338490 | 
2025-10-08 16:24:16.338576 | TASK [Copy crio stats log file]
2025-10-08 16:24:16.353108 | controller | skipping: Conditional result was False
2025-10-08 16:24:16.358973 | 
2025-10-08 16:24:16.359044 | TASK [Get SELinux related data]
2025-10-08 16:24:16.594439 | controller | <no matches>
2025-10-08 16:24:16.887195 | controller | ERROR
2025-10-08 16:24:16.887437 | controller | {
2025-10-08 16:24:16.887480 | controller |   "delta": "0:00:00.008864",
2025-10-08 16:24:16.887504 | controller |   "end": "2025-10-08 16:24:16.595382",
2025-10-08 16:24:16.887524 | controller |   "msg": "non-zero return code",
2025-10-08 16:24:16.887541 | controller |   "rc": 1,
2025-10-08 16:24:16.887577 | controller |   "start": "2025-10-08 16:24:16.586518"
2025-10-08 16:24:16.887596 | controller | }
2025-10-08 16:24:16.887621 | controller | ERROR: Ignoring Errors
2025-10-08 16:24:16.894068 | 
2025-10-08 16:24:16.894145 | TASK [Create system configuration directory]
2025-10-08 16:24:17.108114 | controller | changed
2025-10-08 16:24:17.113597 | 
2025-10-08 16:24:17.113696 | TASK [Get some of the system configurations]
2025-10-08 16:24:17.638785 | controller | changed
2025-10-08 16:24:17.644693 | 
2025-10-08 16:24:17.644780 | TASK [Copy generated documentation if available]
2025-10-08 16:24:17.659075 | controller | skipping: Conditional result was False
2025-10-08 16:24:17.665167 | 
2025-10-08 16:24:17.665246 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 16:24:17.688908 | controller | skipping: Conditional result was False
2025-10-08 16:24:17.695339 | 
2025-10-08 16:24:17.695412 | TASK [Compress logs bigger than 2MB]
2025-10-08 16:24:18.220296 | controller | changed
2025-10-08 16:24:18.226043 | 
2025-10-08 16:24:18.226110 | TASK [Copy files from workspace on node]
2025-10-08 16:24:18.246170 | controller | ok
2025-10-08 16:24:18.269763 | 
2025-10-08 16:24:18.269847 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 16:24:18.294808 | controller | skipping: Conditional result was False
2025-10-08 16:24:18.300631 | 
2025-10-08 16:24:18.300705 | TASK [fetch-output : Set log path for single node]
2025-10-08 16:24:18.331838 | controller | ok
2025-10-08 16:24:18.336972 | 
2025-10-08 16:24:18.337039 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 16:24:18.538952 | controller -> localhost | ok: "/var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/logs"
2025-10-08 16:24:18.539194 | controller -> localhost | changed: All items complete
2025-10-08 16:24:18.539223 | 
2025-10-08 16:24:18.732539 | controller -> localhost | changed: "/var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/artifacts"
2025-10-08 16:24:18.931824 | controller -> localhost | changed: "/var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/docs"
2025-10-08 16:24:18.947126 | 
2025-10-08 16:24:18.947245 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 16:24:19.555618 | controller | changed:
2025-10-08 16:24:19.555824 | controller | .d..t...... ./
2025-10-08 16:24:19.555855 | controller | >f+++++++++ README.html
2025-10-08 16:24:19.555877 | controller | >f+++++++++ ansible-execution.log
2025-10-08 16:24:19.555897 | controller | >f+++++++++ ansible.log
2025-10-08 16:24:19.555938 | controller | >f+++++++++ crc-setup.log
2025-10-08 16:24:19.555961 | controller | >f+++++++++ crc-start.log
2025-10-08 16:24:19.555979 | controller | >f+++++++++ dmesg.log
2025-10-08 16:24:19.555998 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 16:24:19.556014 | controller | >f+++++++++ python.log
2025-10-08 16:24:19.556030 | controller | >f+++++++++ registries.conf
2025-10-08 16:24:19.556046 | controller | >f+++++++++ report.html
2025-10-08 16:24:19.556062 | controller | >f+++++++++ selinux-denials.log
2025-10-08 16:24:19.556078 | controller | >f+++++++++ selinux-listing.log
2025-10-08 16:24:19.556094 | controller | cd+++++++++ ci-framework-data/
2025-10-08 16:24:19.556111 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 16:24:19.556127 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 16:24:19.556144 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-08 16:24:19.556160 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-08 16:24:19.556178 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-08 16:24:19.556195 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 16:24:19.556211 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 16:24:19.556228 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 16:24:19.556245 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 16:24:19.556262 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 16:24:19.556278 | controller | >f+++++++++ ci-framework-data/logs/crc-config.log
2025-10-08 16:24:19.556293 | controller | >f+++++++++ ci-framework-data/logs/crc-setup.log
2025-10-08 16:24:19.556308 | controller | >f+++++++++ ci-framework-data/logs/crc-start-add_crc_creds
2025-10-08 16:24:19.556323 | controller | >f+++++++++ ci-framework-data/logs/crc-start.log
2025-10-08 16:24:19.556338 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 16:24:19.556353 | controller | cd+++++++++ registries.conf.d/
2025-10-08 16:24:19.556368 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 16:24:19.556385 | controller | cd+++++++++ system-config/
2025-10-08 16:24:19.556401 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 16:24:19.556417 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 16:24:19.556434 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 16:24:19.556450 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 16:24:19.556465 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 16:24:19.556480 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 16:24:19.556495 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 16:24:19.556509 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 16:24:19.556525 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 16:24:19.556540 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 16:24:19.556569 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 16:24:19.556586 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 16:24:19.556602 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 16:24:19.556624 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 16:24:19.556640 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 16:24:19.556655 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 16:24:19.556669 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 16:24:19.954218 | controller | changed: .d..t...... ./
2025-10-08 16:24:20.364032 | controller | changed: .d..t...... ./
2025-10-08 16:24:20.380367 | 
2025-10-08 16:24:20.380481 | TASK [Return artifact to Zuul]
2025-10-08 16:24:20.410102 | controller | ok
2025-10-08 16:24:20.431097 | 
2025-10-08 16:24:20.431166 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 16:24:20.431272 | 
2025-10-08 16:24:20.431300 | PLAY RECAP
2025-10-08 16:24:20.431341 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 16:24:20.431363 | 
2025-10-08 16:24:20.528221 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 16:24:20.529013 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 16:24:21.071273 | 
2025-10-08 16:24:21.071452 | PLAY [all]
2025-10-08 16:24:21.090209 | 
2025-10-08 16:24:21.090286 | TASK [include_role : fetch-output]
2025-10-08 16:24:21.120090 | controller | ok
2025-10-08 16:24:21.137839 | 
2025-10-08 16:24:21.137951 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 16:24:21.183018 | controller | skipping: Conditional result was False
2025-10-08 16:24:21.189097 | 
2025-10-08 16:24:21.189173 | TASK [fetch-output : Set log path for single node]
2025-10-08 16:24:21.217069 | controller | ok
2025-10-08 16:24:21.222097 | 
2025-10-08 16:24:21.222165 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 16:24:21.564111 | controller -> localhost | ok: "/var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/logs"
2025-10-08 16:24:21.755350 | controller -> localhost | ok: "/var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/artifacts"
2025-10-08 16:24:21.945090 | controller -> localhost | ok: "/var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/docs"
2025-10-08 16:24:21.962602 | 
2025-10-08 16:24:21.962690 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 16:24:22.519839 | controller | ok
2025-10-08 16:24:22.520073 | controller | ok: All items complete
2025-10-08 16:24:22.520105 | 
2025-10-08 16:24:22.920670 | controller | ok
2025-10-08 16:24:23.339052 | controller | ok
2025-10-08 16:24:23.350354 | 
2025-10-08 16:24:23.350438 | TASK [include_role : fetch-output-openshift]
2025-10-08 16:24:23.364740 | controller | skipping: Conditional result was False
2025-10-08 16:24:23.370590 | 
2025-10-08 16:24:23.370666 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 16:24:23.711880 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008337
2025-10-08 16:24:23.917163 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008683
2025-10-08 16:24:23.948008 | 
2025-10-08 16:24:23.948146 | PLAY [all]
2025-10-08 16:24:23.963153 | 
2025-10-08 16:24:23.963237 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 16:24:24.398733 | controller | changed
2025-10-08 16:24:24.419704 | 
2025-10-08 16:24:24.419755 | PLAY RECAP
2025-10-08 16:24:24.419807 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 16:24:24.419828 | 
2025-10-08 16:24:24.491103 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 16:24:24.491945 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 16:24:25.048021 | 
2025-10-08 16:24:25.048156 | PLAY [localhost]
2025-10-08 16:24:25.066494 | 
2025-10-08 16:24:25.066612 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 16:24:25.409125 | localhost | changed
2025-10-08 16:24:25.413747 | 
2025-10-08 16:24:25.413826 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 16:24:25.442416 | localhost | ok
2025-10-08 16:24:25.450171 | 
2025-10-08 16:24:25.450243 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 16:24:25.771376 | localhost | changed
2025-10-08 16:24:25.777637 | 
2025-10-08 16:24:25.777712 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 16:24:26.384851 | localhost | changed
2025-10-08 16:24:26.389955 | 
2025-10-08 16:24:26.390030 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 16:24:26.754016 | localhost | Identity added: /var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/tmp/ansible._tzoiq4s (/var/lib/zuul/builds/17d727d03c3e457d98fcd36282cd841a/work/tmp/ansible._tzoiq4s)
2025-10-08 16:24:26.754213 | localhost | ok: Runtime: 0:00:00.008043
2025-10-08 16:24:26.758636 | 
2025-10-08 16:24:26.758711 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 16:24:27.000640 | localhost | ok: Runtime: 0:00:00.005674
2025-10-08 16:24:27.005785 | 
2025-10-08 16:24:27.005887 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 16:24:27.057385 | localhost | changed
2025-10-08 16:24:27.061590 | 
2025-10-08 16:24:27.061656 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 16:24:27.394592 | localhost | changed
2025-10-08 16:24:27.414114 | 
2025-10-08 16:24:27.414200 | PLAY [localhost]
2025-10-08 16:24:27.425340 | 
2025-10-08 16:24:27.425402 | TASK [Generate bulk log download script]
2025-10-08 16:24:27.444342 | localhost | ok
2025-10-08 16:24:27.457527 | 
2025-10-08 16:24:27.457660 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 16:24:27.487615 | localhost | ok: All assertions passed
2025-10-08 16:24:27.492578 | 
2025-10-08 16:24:27.492655 | TASK [local-log-download : Create download script]
2025-10-08 16:24:27.872842 | localhost -> localhost | changed
2025-10-08 16:24:27.881880 | 
2025-10-08 16:24:27.881979 | TASK [Register quick-download link]
2025-10-08 16:24:27.910850 | localhost | ok
2025-10-08 16:24:27.947305 | 
2025-10-08 16:24:27.947389 | PLAY [logserver.rdoproject.org]
2025-10-08 16:24:27.956956 | 
2025-10-08 16:24:27.957020 | TASK [Set zuul-log-path fact]
2025-10-08 16:24:27.973577 | logserver.rdoproject.org | ok
2025-10-08 16:24:27.982801 | 
2025-10-08 16:24:27.982869 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 16:24:28.009945 | logserver.rdoproject.org | ok
2025-10-08 16:24:28.015118 | 
2025-10-08 16:24:28.015181 | TASK [upload-logs : Create log directories]
2025-10-08 16:24:29.803893 | logserver.rdoproject.org | changed
2025-10-08 16:24:29.807089 | 
2025-10-08 16:24:29.807158 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 16:24:30.044520 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005970
2025-10-08 16:24:30.049749 | 
2025-10-08 16:24:30.049823 | TASK [upload-logs : Upload logs to log server]
2025-10-08 16:24:31.640452 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 16:24:31.647628 | 
2025-10-08 16:24:31.647709 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 16:24:31.689769 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:24:31.697446 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:24:31.706262 | 
2025-10-08 16:24:31.706381 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 16:24:31.746102 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:24:31.746408 | 
2025-10-08 16:24:31.749253 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:24:31.755878 | 
2025-10-08 16:24:31.756012 | LOOP [upload-logs : Upload console log and json output]