2026-03-18 23:54:02.178159 | Job console starting...
2026-03-18 23:54:02.388523 | Updating repositories
2026-03-18 23:54:03.203942 | Preparing job workspace
2026-03-18 23:54:09.882414 | Running Ansible setup...
2026-03-18 23:54:12.658832 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-18 23:54:13.131079 | 
2026-03-18 23:54:13.131181 | PLAY [localhost]
2026-03-18 23:54:13.139729 | 
2026-03-18 23:54:13.139801 | TASK [Gathering Facts]
2026-03-18 23:54:13.905254 | localhost | ok
2026-03-18 23:54:13.918362 | 
2026-03-18 23:54:13.918476 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-18 23:54:14.215341 | localhost -> localhost | changed
2026-03-18 23:54:14.220443 | 
2026-03-18 23:54:14.220529 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-18 23:54:14.850959 | localhost -> localhost | changed
2026-03-18 23:54:14.858103 | 
2026-03-18 23:54:14.858164 | TASK [Setup log path fact]
2026-03-18 23:54:14.874317 | localhost | ok
2026-03-18 23:54:14.884002 | 
2026-03-18 23:54:14.884062 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-18 23:54:14.910781 | localhost | ok
2026-03-18 23:54:14.917178 | 
2026-03-18 23:54:14.917237 | TASK [emit-job-header : Print job information]
2026-03-18 23:54:14.943942 | # Job Information
2026-03-18 23:54:14.944055 | Ansible Version: 2.15.12
2026-03-18 23:54:14.944084 | Job: cifmw-molecule-copy_container
2026-03-18 23:54:14.944107 | Pipeline: github-check
2026-03-18 23:54:14.944125 | Executor: ibm-bm3-ze.softwarefactory-project.io
2026-03-18 23:54:14.944143 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3778
2026-03-18 23:54:14.944162 | Log URL (when completed): https://logserver.rdoproject.org/655/rdoproject.org/65545c0fc2764f4d9efaaef5985f0a21/
2026-03-18 23:54:14.944180 | Event ID: 9b180200-2325-11f1-965f-de0c4ed2a7ed
2026-03-18 23:54:14.947561 | 
2026-03-18 23:54:14.947619 | LOOP [emit-job-header : Print node information]
2026-03-18 23:54:15.024431 | localhost | ok:
2026-03-18 23:54:15.024550 | localhost | # Node Information
2026-03-18 23:54:15.024578 | localhost | Inventory Hostname: controller
2026-03-18 23:54:15.024600 | localhost | Hostname: np0005651172
2026-03-18 23:54:15.024619 | localhost | Username: zuul
2026-03-18 23:54:15.024638 | localhost | Distro: CentOS 9
2026-03-18 23:54:15.024655 | localhost | Provider: ibm-bm3-nodepool
2026-03-18 23:54:15.024671 | localhost | Region: regionOne
2026-03-18 23:54:15.024686 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-03-18 23:54:15.024701 | localhost | Product Name: OpenStack Compute
2026-03-18 23:54:15.024716 | localhost | Interface IP: 192.168.25.251
2026-03-18 23:54:15.041200 | 
2026-03-18 23:54:15.041251 | PLAY [all]
2026-03-18 23:54:15.046750 | 
2026-03-18 23:54:15.046806 | TASK [Gather network facts]
2026-03-18 23:54:15.410632 | controller | ok
2026-03-18 23:54:15.423141 | 
2026-03-18 23:54:15.423202 | TASK [include_role : start-zuul-console]
2026-03-18 23:54:15.440490 | controller | ok
2026-03-18 23:54:15.451158 | 
2026-03-18 23:54:15.451221 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-18 23:54:15.723112 | controller | ok
2026-03-18 23:54:15.730465 | 
2026-03-18 23:54:15.730522 | TASK [include_role : add-build-sshkey]
2026-03-18 23:54:15.747213 | controller | ok
2026-03-18 23:54:15.758243 | 
2026-03-18 23:54:15.758319 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-18 23:54:15.933678 | controller -> localhost | ok
2026-03-18 23:54:15.938580 | 
2026-03-18 23:54:15.938639 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-18 23:54:15.956118 | controller | ok
2026-03-18 23:54:15.968965 | controller | included: /var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-18 23:54:15.974254 | 
2026-03-18 23:54:15.974335 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-18 23:54:16.433597 | controller -> localhost | Generating public/private rsa key pair.
2026-03-18 23:54:16.433753 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/65545c0fc2764f4d9efaaef5985f0a21_id_rsa.
2026-03-18 23:54:16.433784 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/65545c0fc2764f4d9efaaef5985f0a21_id_rsa.pub.
2026-03-18 23:54:16.433807 | controller -> localhost | The key fingerprint is:
2026-03-18 23:54:16.433827 | controller -> localhost | SHA256:n304GMZTxPgBosExr7AYguHWuLE3rnVk3wLKxWRizS0 zuul-build-sshkey
2026-03-18 23:54:16.433846 | controller -> localhost | The key's randomart image is:
2026-03-18 23:54:16.433865 | controller -> localhost | +---[RSA 3072]----+
2026-03-18 23:54:16.433884 | controller -> localhost | |.    .+.. .+.    |
2026-03-18 23:54:16.433902 | controller -> localhost | |o.o o o= ...o    |
2026-03-18 23:54:16.433921 | controller -> localhost | |o=.+.E...  ...   |
2026-03-18 23:54:16.433939 | controller -> localhost | |..=o=o.. . ..    |
2026-03-18 23:54:16.433957 | controller -> localhost | | o.o.*. S =      |
2026-03-18 23:54:16.433975 | controller -> localhost | |  + * o .o * .   |
2026-03-18 23:54:16.433992 | controller -> localhost | |   = . o .+ + .  |
2026-03-18 23:54:16.434010 | controller -> localhost | |  o .   .    o   |
2026-03-18 23:54:16.434027 | controller -> localhost | | .               |
2026-03-18 23:54:16.434043 | controller -> localhost | +----[SHA256]-----+
2026-03-18 23:54:16.434086 | controller -> localhost | ok: Runtime: 0:00:00.154258
2026-03-18 23:54:16.438980 | 
2026-03-18 23:54:16.439039 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-18 23:54:16.456226 | controller | ok
2026-03-18 23:54:16.465124 | controller | included: /var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-18 23:54:16.472571 | 
2026-03-18 23:54:16.472632 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-18 23:54:16.485723 | controller | skipping: Conditional result was False
2026-03-18 23:54:16.491066 | 
2026-03-18 23:54:16.491130 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-18 23:54:16.823221 | controller | changed
2026-03-18 23:54:16.828721 | 
2026-03-18 23:54:16.828785 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-18 23:54:16.998410 | controller | ok
2026-03-18 23:54:17.002644 | 
2026-03-18 23:54:17.002704 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-18 23:54:17.529086 | controller | changed
2026-03-18 23:54:17.533234 | 
2026-03-18 23:54:17.533306 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-18 23:54:18.057483 | controller | changed
2026-03-18 23:54:18.062129 | 
2026-03-18 23:54:18.062192 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-18 23:54:18.075381 | controller | skipping: Conditional result was False
2026-03-18 23:54:18.080447 | 
2026-03-18 23:54:18.080521 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-18 23:54:18.356739 | controller -> localhost | changed
2026-03-18 23:54:18.365109 | 
2026-03-18 23:54:18.365170 | TASK [add-build-sshkey : Add back temp key]
2026-03-18 23:54:18.570734 | controller -> localhost | Identity added: /var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/65545c0fc2764f4d9efaaef5985f0a21_id_rsa (zuul-build-sshkey)
2026-03-18 23:54:18.570910 | controller -> localhost | ok: Runtime: 0:00:00.006000
2026-03-18 23:54:18.576074 | 
2026-03-18 23:54:18.576134 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-18 23:54:18.837071 | controller | ok
2026-03-18 23:54:18.841443 | 
2026-03-18 23:54:18.841514 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-18 23:54:18.864987 | controller | skipping: Conditional result was False
2026-03-18 23:54:18.873335 | 
2026-03-18 23:54:18.873397 | TASK [include_role : validate-host]
2026-03-18 23:54:18.891434 | controller | ok
2026-03-18 23:54:18.909934 | 
2026-03-18 23:54:18.909999 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-18 23:54:18.937484 | controller | ok
2026-03-18 23:54:18.941857 | 
2026-03-18 23:54:18.941919 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-18 23:54:19.127075 | controller -> localhost | ok
2026-03-18 23:54:19.131993 | 
2026-03-18 23:54:19.132050 | TASK [validate-host : Collect information about the host]
2026-03-18 23:54:19.711133 | controller | ok
2026-03-18 23:54:19.718575 | 
2026-03-18 23:54:19.718637 | TASK [validate-host : Sanitize hostname]
2026-03-18 23:54:19.786858 | controller | ok
2026-03-18 23:54:19.791437 | 
2026-03-18 23:54:19.791520 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-18 23:54:20.161190 | controller -> localhost | changed
2026-03-18 23:54:20.184919 | 
2026-03-18 23:54:20.184997 | TASK [validate-host : Collect information about zuul worker]
2026-03-18 23:54:20.477194 | controller | ok
2026-03-18 23:54:20.481181 | 
2026-03-18 23:54:20.481239 | TASK [validate-host : Write out all zuul information for each host]
2026-03-18 23:54:20.808073 | controller -> localhost | changed
2026-03-18 23:54:20.816807 | 
2026-03-18 23:54:20.816879 | TASK [include_role : prepare-workspace-openshift]
2026-03-18 23:54:20.830110 | controller | skipping: Conditional result was False
2026-03-18 23:54:20.834816 | 
2026-03-18 23:54:20.834878 | TASK [include_role : remove-zuul-sshkey]
2026-03-18 23:54:20.847954 | controller | skipping: Conditional result was False
2026-03-18 23:54:20.852875 | 
2026-03-18 23:54:20.852937 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-18 23:54:21.025153 | controller | ok: "logs"
2026-03-18 23:54:21.025389 | controller | ok: All items complete
2026-03-18 23:54:21.025417 | 
2026-03-18 23:54:21.175407 | controller | ok: "artifacts"
2026-03-18 23:54:21.324524 | controller | ok: "docs"
2026-03-18 23:54:21.336657 | 
2026-03-18 23:54:21.336740 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-18 23:54:21.515418 | controller | changed: "logs"
2026-03-18 23:54:21.662902 | controller | changed: "artifacts"
2026-03-18 23:54:21.814446 | controller | changed: "docs"
2026-03-18 23:54:21.837012 | 
2026-03-18 23:54:21.837078 | PLAY RECAP
2026-03-18 23:54:21.837121 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-18 23:54:21.837150 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-18 23:54:21.837168 | 
2026-03-18 23:54:21.904989 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-18 23:54:21.905726 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-18 23:54:22.404654 | 
2026-03-18 23:54:22.404758 | PLAY [all]
2026-03-18 23:54:22.422587 | 
2026-03-18 23:54:22.422659 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-18 23:54:22.461384 | controller | ok
2026-03-18 23:54:22.466525 | 
2026-03-18 23:54:22.466615 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-18 23:54:22.768806 | controller | changed
2026-03-18 23:54:22.773982 | 
2026-03-18 23:54:22.774061 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-18 23:54:23.491980 | controller | changed
2026-03-18 23:54:23.500677 | 
2026-03-18 23:54:23.500743 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-18 23:54:23.825048 | controller | changed:
2026-03-18 23:54:23.825204 | controller | {
2026-03-18 23:54:23.825232 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-18 23:54:23.825258 | controller | }
2026-03-18 23:54:24.018033 | controller | changed:
2026-03-18 23:54:24.018095 | controller | {
2026-03-18 23:54:24.018121 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-18 23:54:24.018141 | controller | }
2026-03-18 23:54:24.202836 | controller | changed:
2026-03-18 23:54:24.202960 | controller | {
2026-03-18 23:54:24.202992 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-18 23:54:24.203013 | controller | }
2026-03-18 23:54:24.390642 | controller | changed:
2026-03-18 23:54:24.390711 | controller | {
2026-03-18 23:54:24.390736 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-18 23:54:24.390755 | controller | }
2026-03-18 23:54:24.574366 | controller | changed:
2026-03-18 23:54:24.574424 | controller | {
2026-03-18 23:54:24.574448 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-18 23:54:24.574476 | controller | }
2026-03-18 23:54:24.756873 | controller | changed:
2026-03-18 23:54:24.756923 | controller | {
2026-03-18 23:54:24.756948 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-18 23:54:24.756967 | controller | }
2026-03-18 23:54:24.942159 | controller | changed:
2026-03-18 23:54:24.942283 | controller | {
2026-03-18 23:54:24.942318 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-03-18 23:54:24.942341 | controller | }
2026-03-18 23:54:25.122583 | controller | changed:
2026-03-18 23:54:25.122632 | controller | {
2026-03-18 23:54:25.122656 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-03-18 23:54:25.122674 | controller | }
2026-03-18 23:54:25.307993 | controller | changed:
2026-03-18 23:54:25.308046 | controller | {
2026-03-18 23:54:25.308071 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-18 23:54:25.308089 | controller | }
2026-03-18 23:54:25.494724 | controller | changed:
2026-03-18 23:54:25.494770 | controller | {
2026-03-18 23:54:25.494794 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-18 23:54:25.494812 | controller | }
2026-03-18 23:54:25.678348 | controller | changed:
2026-03-18 23:54:25.678439 | controller | {
2026-03-18 23:54:25.678473 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-18 23:54:25.678492 | controller | }
2026-03-18 23:54:25.876405 | controller | changed:
2026-03-18 23:54:25.876494 | controller | {
2026-03-18 23:54:25.876519 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-18 23:54:25.876538 | controller | }
2026-03-18 23:54:26.044318 | controller | changed:
2026-03-18 23:54:26.044386 | controller | {
2026-03-18 23:54:26.044410 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-18 23:54:26.044428 | controller | }
2026-03-18 23:54:26.228664 | controller | changed:
2026-03-18 23:54:26.228714 | controller | {
2026-03-18 23:54:26.228737 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-18 23:54:26.228755 | controller | }
2026-03-18 23:54:26.417305 | controller | changed:
2026-03-18 23:54:26.417356 | controller | {
2026-03-18 23:54:26.417379 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-18 23:54:26.417397 | controller | }
2026-03-18 23:54:26.601613 | controller | changed:
2026-03-18 23:54:26.601658 | controller | {
2026-03-18 23:54:26.601681 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-18 23:54:26.601699 | controller | }
2026-03-18 23:54:26.785719 | controller | changed:
2026-03-18 23:54:26.785765 | controller | {
2026-03-18 23:54:26.785788 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-03-18 23:54:26.785807 | controller | }
2026-03-18 23:54:26.974175 | controller | changed:
2026-03-18 23:54:26.974221 | controller | {
2026-03-18 23:54:26.974245 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-03-18 23:54:26.974287 | controller | }
2026-03-18 23:54:27.159502 | controller | changed:
2026-03-18 23:54:27.159592 | controller | {
2026-03-18 23:54:27.159617 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-18 23:54:27.159635 | controller | }
2026-03-18 23:54:27.345817 | controller | changed:
2026-03-18 23:54:27.345891 | controller | {
2026-03-18 23:54:27.345917 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-18 23:54:27.345937 | controller | }
2026-03-18 23:54:27.542563 | controller | changed:
2026-03-18 23:54:27.542683 | controller | {
2026-03-18 23:54:27.542714 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-18 23:54:27.542736 | controller | }
2026-03-18 23:54:27.732005 | controller | changed:
2026-03-18 23:54:27.732052 | controller | {
2026-03-18 23:54:27.732076 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-18 23:54:27.732094 | controller | }
2026-03-18 23:54:27.926923 | controller | changed:
2026-03-18 23:54:27.926969 | controller | {
2026-03-18 23:54:27.926992 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-03-18 23:54:27.927011 | controller | }
2026-03-18 23:54:28.111891 | controller | changed:
2026-03-18 23:54:28.111946 | controller | {
2026-03-18 23:54:28.111975 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-18 23:54:28.111998 | controller | }
2026-03-18 23:54:28.302084 | controller | changed:
2026-03-18 23:54:28.302130 | controller | {
2026-03-18 23:54:28.302154 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-18 23:54:28.302171 | controller | }
2026-03-18 23:54:28.493201 | controller | changed:
2026-03-18 23:54:28.493246 | controller | {
2026-03-18 23:54:28.493290 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-18 23:54:28.493311 | controller | }
2026-03-18 23:54:28.512250 | 
2026-03-18 23:54:28.512325 | TASK [Set timezone to UTC]
2026-03-18 23:54:28.897371 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-18 23:54:28.901868 | 
2026-03-18 23:54:28.901929 | TASK [Create nodepool directory]
2026-03-18 23:54:29.094492 | controller | changed
2026-03-18 23:54:29.099556 | 
2026-03-18 23:54:29.099622 | TASK [Create nodepool sub_nodes file]
2026-03-18 23:54:29.554668 | controller | changed
2026-03-18 23:54:29.559461 | 
2026-03-18 23:54:29.559544 | TASK [Create nodepool sub_nodes_private file]
2026-03-18 23:54:30.003594 | controller | changed
2026-03-18 23:54:30.008182 | 
2026-03-18 23:54:30.008240 | LOOP [Populate nodepool sub_nodes file]
2026-03-18 23:54:30.036907 | 
2026-03-18 23:54:30.037027 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-18 23:54:30.064991 | 
2026-03-18 23:54:30.065108 | TASK [Create nodepool primary file]
2026-03-18 23:54:30.088241 | controller | skipping: Conditional result was False
2026-03-18 23:54:30.093245 | 
2026-03-18 23:54:30.093332 | TASK [Create nodepool node_private for this node]
2026-03-18 23:54:30.586130 | controller | changed
2026-03-18 23:54:30.591556 | 
2026-03-18 23:54:30.591620 | LOOP [Copy ssh keys to nodepool directory]
2026-03-18 23:54:30.891013 | controller | ok: Item: id_rsa Runtime: 0:00:00.004512
2026-03-18 23:54:30.891168 | 
2026-03-18 23:54:31.038914 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004277
2026-03-18 23:54:31.050805 | 
2026-03-18 23:54:31.050873 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-18 23:54:31.547862 | controller | changed
2026-03-18 23:54:31.552631 | 
2026-03-18 23:54:31.552695 | TASK [Validate sudoers config after edits]
2026-03-18 23:54:31.751294 | controller | /etc/sudoers: parsed OK
2026-03-18 23:54:31.751382 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-18 23:54:31.751394 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-18 23:54:31.751402 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-18 23:54:32.076207 | controller | ok: Runtime: 0:00:00.004328
2026-03-18 23:54:32.081406 | 
2026-03-18 23:54:32.081474 | TASK [Show the environment passed in to job shell scripts]
2026-03-18 23:54:32.265564 | controller | SHELL=/bin/bash
2026-03-18 23:54:32.265601 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-18 23:54:32.265610 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-18 23:54:32.265618 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/78/3778/890266f6bf4fbd819b645f586ef167dfa0d3e848
2026-03-18 23:54:32.265624 | controller | PWD=/home/zuul
2026-03-18 23:54:32.265629 | controller | ZUUL_PIPELINE=github-check
2026-03-18 23:54:32.265635 | controller | LOGNAME=zuul
2026-03-18 23:54:32.265640 | controller | XDG_SESSION_TYPE=tty
2026-03-18 23:54:32.265681 | controller | _=/usr/bin/env
2026-03-18 23:54:32.265689 | controller | MOTD_SHOWN=pam
2026-03-18 23:54:32.265695 | controller | HOME=/home/zuul
2026-03-18 23:54:32.265700 | controller | LANG=en_US.UTF-8
2026-03-18 23:54:32.265705 | controller | SSH_CONNECTION=192.168.25.12 43872 192.168.25.251 22
2026-03-18 23:54:32.265711 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-03-18 23:54:32.265719 | controller | ZUUL_CHANGE_IDS=3778,890266f6bf4fbd819b645f586ef167dfa0d3e848
2026-03-18 23:54:32.265724 | controller | WORKSPACE=/home/zuul/workspace
2026-03-18 23:54:32.265729 | controller | XDG_SESSION_CLASS=user
2026-03-18 23:54:32.265735 | controller | SELINUX_ROLE_REQUESTED=
2026-03-18 23:54:32.265740 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-18 23:54:32.265745 | controller | USER=zuul
2026-03-18 23:54:32.265751 | controller | ZUUL_VOTING=True
2026-03-18 23:54:32.265756 | controller | BUILD_TIMEOUT=1800000
2026-03-18 23:54:32.265761 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-18 23:54:32.265766 | controller | SHLVL=1
2026-03-18 23:54:32.265772 | controller | ZUUL_PATCHSET=890266f6bf4fbd819b645f586ef167dfa0d3e848
2026-03-18 23:54:32.265777 | controller | XDG_SESSION_ID=1
2026-03-18 23:54:32.265782 | controller | ZUUL_BRANCH=main
2026-03-18 23:54:32.265788 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-18 23:54:32.265793 | controller | SSH_CLIENT=192.168.25.12 43872 22
2026-03-18 23:54:32.265800 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-18 23:54:32.265805 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-18 23:54:32.265811 | controller | which_declare=declare -f
2026-03-18 23:54:32.265816 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-18 23:54:32.265821 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-18 23:54:32.265827 | controller | ZUUL_CHANGE=3778
2026-03-18 23:54:32.265832 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-18 23:54:32.265837 | controller | ZUUL_UUID=65545c0fc2764f4d9efaaef5985f0a21
2026-03-18 23:54:32.265843 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-18 23:54:32.265848 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-18 23:54:32.265853 | controller | }
2026-03-18 23:54:32.602646 | controller | ok: Runtime: 0:00:00.005412
2026-03-18 23:54:32.607478 | 
2026-03-18 23:54:32.607540 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-18 23:54:32.620794 | controller | skipping: Conditional result was False
2026-03-18 23:54:32.625651 | 
2026-03-18 23:54:32.625710 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-18 23:54:33.145589 | controller | skipping: Conditional result was False
2026-03-18 23:54:33.150614 | 
2026-03-18 23:54:33.150675 | TASK [Ensure legacy workspace directory]
2026-03-18 23:54:33.331178 | controller | changed
2026-03-18 23:54:33.348930 | 
2026-03-18 23:54:33.348980 | PLAY RECAP
2026-03-18 23:54:33.349024 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-18 23:54:33.349046 | 
2026-03-18 23:54:33.415120 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-18 23:54:33.415858 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-18 23:54:33.891032 | 
2026-03-18 23:54:33.891128 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-18 23:54:33.910692 | 
2026-03-18 23:54:33.910763 | TASK [Create zuul-output directory]
2026-03-18 23:54:34.192212 | controller | changed
2026-03-18 23:54:34.196742 | 
2026-03-18 23:54:34.196812 | TASK [Slurp Zuul inventory test]
2026-03-18 23:54:34.453795 | controller -> localhost | ok
2026-03-18 23:54:34.463907 | 
2026-03-18 23:54:34.463974 | TASK [Save zuul inventory]
2026-03-18 23:54:35.150795 | controller | changed
2026-03-18 23:54:35.155407 | 
2026-03-18 23:54:35.155486 | TASK [Save zuul vars without the change_message]
2026-03-18 23:54:35.680788 | controller | changed
2026-03-18 23:54:35.696739 | 
2026-03-18 23:54:35.696788 | PLAY RECAP
2026-03-18 23:54:35.696827 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-18 23:54:35.696848 | 
2026-03-18 23:54:35.757331 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-18 23:54:35.758023 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-18 23:54:36.251794 | 
2026-03-18 23:54:36.251880 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-18 23:54:36.270510 | 
2026-03-18 23:54:36.270580 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-18 23:54:36.288427 | controller | ok
2026-03-18 23:54:36.303469 | 
2026-03-18 23:54:36.303548 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-18 23:54:36.326525 | controller | skipping: Conditional result was False
2026-03-18 23:54:36.331769 | 
2026-03-18 23:54:36.331838 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-18 23:54:36.612544 | controller | ok
2026-03-18 23:54:36.617555 | 
2026-03-18 23:54:36.617631 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-18 23:54:37.202354 | controller | ok
2026-03-18 23:54:37.212058 | 
2026-03-18 23:54:37.212123 | TASK [Prepare workspace]
2026-03-18 23:54:37.230003 | controller | ok
2026-03-18 23:54:37.246414 | 
2026-03-18 23:54:37.246491 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-18 23:54:37.539632 | controller | ok
2026-03-18 23:54:37.545422 | 
2026-03-18 23:54:37.545499 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-18 23:54:39.726698 | controller | Output suppressed because no_log was given
2026-03-18 23:54:39.734667 | 
2026-03-18 23:54:39.734727 | LOOP [Create zuul-output directory]
2026-03-18 23:54:39.907707 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-18 23:54:40.049572 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-18 23:54:40.061188 | 
2026-03-18 23:54:40.061309 | TASK [Install required packages]
2026-03-18 23:55:17.564511 | controller | changed
2026-03-18 23:55:17.569110 | 
2026-03-18 23:55:17.569169 | TASK [Install venv]
2026-03-18 23:56:16.344299 | controller | changed
2026-03-18 23:56:16.366280 | 
2026-03-18 23:56:16.366339 | PLAY RECAP
2026-03-18 23:56:16.366380 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-18 23:56:16.366401 | 
2026-03-18 23:56:16.430394 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-18 23:56:16.431128 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-18 23:56:16.900232 | 
2026-03-18 23:56:16.900339 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-18 23:56:16.918835 | 
2026-03-18 23:56:16.918903 | TASK [Gather required facts]
2026-03-18 23:56:17.364314 | controller | ok
2026-03-18 23:56:17.369372 | 
2026-03-18 23:56:17.369441 | TASK [Load environment var if instructed to]
2026-03-18 23:56:17.383678 | controller | skipping: Conditional result was False
2026-03-18 23:56:17.388809 | 
2026-03-18 23:56:17.388870 | TASK [Ensure group_vars dir exists]
2026-03-18 23:56:17.670256 | controller | ok
2026-03-18 23:56:17.675594 | 
2026-03-18 23:56:17.675654 | TASK [Print related variables]
2026-03-18 23:56:17.703438 | controller | ok:
2026-03-18 23:56:17.703550 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-03-18 23:56:17.703579 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/copy_container
2026-03-18 23:56:17.707813 | 
2026-03-18 23:56:17.707870 | TASK [Run molecule]
2026-03-18 23:56:18.592632 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-03-18 23:56:18.658230 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-18 23:56:35.384394 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.384817 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.385269 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.385707 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.386172 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+890266f6 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 1.0.0+890266f6 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.386609 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.387012 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.387459 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.387868 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.388297 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.388721 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.389158 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.389584 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.389998 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.390435 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/07860a/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-18 23:56:35.399217 | controller | INFO     Running default > prepare
2026-03-18 23:56:36.012102 | controller |
2026-03-18 23:56:36.012187 | controller | PLAY [Prepare] *****************************************************************
2026-03-18 23:56:36.012286 | controller |
2026-03-18 23:56:36.012387 | controller | TASK [Gathering Facts] *********************************************************
2026-03-18 23:56:36.012472 | controller | Wednesday 18 March 2026  23:56:36 +0000 (0:00:00.011)       0:00:00.011 *******
2026-03-18 23:56:36.826955 | controller | ok: [instance]
2026-03-18 23:56:36.827009 | controller |
2026-03-18 23:56:36.827142 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-03-18 23:56:36.827233 | controller | Wednesday 18 March 2026  23:56:36 +0000 (0:00:00.815)       0:00:00.827 *******
2026-03-18 23:56:36.844851 | controller | skipping: [instance]
2026-03-18 23:56:36.844926 | controller |
2026-03-18 23:56:36.845025 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-03-18 23:56:36.845145 | controller | Wednesday 18 March 2026  23:56:36 +0000 (0:00:00.017)       0:00:00.845 *******
2026-03-18 23:56:36.884998 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-03-18 23:56:36.885102 | controller |
2026-03-18 23:56:36.885201 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-03-18 23:56:36.885301 | controller | Wednesday 18 March 2026  23:56:36 +0000 (0:00:00.040)       0:00:00.885 *******
2026-03-18 23:56:37.145253 | controller | ok: [instance]
2026-03-18 23:56:37.145315 | controller |
2026-03-18 23:56:37.145437 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-03-18 23:56:37.145532 | controller | Wednesday 18 March 2026  23:56:37 +0000 (0:00:00.260)       0:00:01.145 *******
2026-03-18 23:56:37.437394 | controller | ok: [instance]
2026-03-18 23:56:37.437479 | controller |
2026-03-18 23:56:37.437584 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-03-18 23:56:37.437687 | controller | Wednesday 18 March 2026  23:56:37 +0000 (0:00:00.292)       0:00:01.437 *******
2026-03-18 23:56:38.084288 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-03-18 23:56:38.084380 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-03-18 23:56:38.084479 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-03-18 23:56:38.084564 | controller |
2026-03-18 23:56:38.084649 | controller | TASK [Deploy repo-setup] *******************************************************
2026-03-18 23:56:38.084737 | controller | Wednesday 18 March 2026  23:56:38 +0000 (0:00:00.646)       0:00:02.084 *******
2026-03-18 23:56:38.139751 | controller |
2026-03-18 23:56:38.139886 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-03-18 23:56:38.140019 | controller | Wednesday 18 March 2026  23:56:38 +0000 (0:00:00.055)       0:00:02.140 *******
2026-03-18 23:56:38.627609 | controller | changed: [instance] => (item=tmp)
2026-03-18 23:56:38.627662 | controller | changed: [instance] => (item=artifacts/repositories)
2026-03-18 23:56:38.627756 | controller | changed: [instance] => (item=venv/repo_setup)
2026-03-18 23:56:38.627834 | controller |
2026-03-18 23:56:38.627921 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-03-18 23:56:38.628005 | controller | Wednesday 18 March 2026  23:56:38 +0000 (0:00:00.487)       0:00:02.628 *******
2026-03-18 23:56:39.357978 | controller | ok: [instance]
2026-03-18 23:56:39.358023 | controller |
2026-03-18 23:56:39.358098 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-03-18 23:56:39.358192 | controller | Wednesday 18 March 2026  23:56:39 +0000 (0:00:00.730)       0:00:03.358 *******
2026-03-18 23:56:40.350583 | controller | changed: [instance]
2026-03-18 23:56:40.350892 | controller |
2026-03-18 23:56:47.673109 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-03-18 23:56:47.673138 | controller | Wednesday 18 March 2026  23:56:40 +0000 (0:00:00.992)       0:00:04.350 *******
2026-03-18 23:56:47.673149 | controller | changed: [instance]
2026-03-18 23:56:47.673233 | controller |
2026-03-18 23:56:47.673248 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-03-18 23:56:47.673256 | controller | Wednesday 18 March 2026  23:56:47 +0000 (0:00:07.322)       0:00:11.673 *******
2026-03-18 23:56:48.323216 | controller | changed: [instance]
2026-03-18 23:56:48.346096 | controller |
2026-03-18 23:56:48.346130 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-03-18 23:56:48.346138 | controller | Wednesday 18 March 2026  23:56:48 +0000 (0:00:00.649)       0:00:12.323 *******
2026-03-18 23:56:48.346148 | controller | skipping: [instance]
2026-03-18 23:56:49.174491 | controller |
2026-03-18 23:56:49.174526 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-03-18 23:56:49.174535 | controller | Wednesday 18 March 2026  23:56:48 +0000 (0:00:00.022)       0:00:12.346 *******
2026-03-18 23:56:49.174546 | controller | changed: [instance]
2026-03-18 23:56:49.211747 | controller |
2026-03-18 23:56:49.211770 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-03-18 23:56:49.211778 | controller | Wednesday 18 March 2026  23:56:49 +0000 (0:00:00.828)       0:00:13.174 *******
2026-03-18 23:56:49.211788 | controller | skipping: [instance]
2026-03-18 23:56:49.249845 | controller |
2026-03-18 23:56:49.249865 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-03-18 23:56:49.249873 | controller | Wednesday 18 March 2026  23:56:49 +0000 (0:00:00.037)       0:00:13.212 *******
2026-03-18 23:56:49.249883 | controller | skipping: [instance]
2026-03-18 23:56:49.287327 | controller |
2026-03-18 23:56:49.287346 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-03-18 23:56:49.287353 | controller | Wednesday 18 March 2026  23:56:49 +0000 (0:00:00.038)       0:00:13.250 *******
2026-03-18 23:56:49.287363 | controller | skipping: [instance]
2026-03-18 23:56:49.827555 | controller |
2026-03-18 23:56:49.827585 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-03-18 23:56:49.827593 | controller | Wednesday 18 March 2026  23:56:49 +0000 (0:00:00.037)       0:00:13.287 *******
2026-03-18 23:56:49.827602 | controller | changed: [instance]
2026-03-18 23:56:50.245246 | controller |
2026-03-18 23:56:50.245276 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-03-18 23:56:50.245284 | controller | Wednesday 18 March 2026  23:56:49 +0000 (0:00:00.540)       0:00:13.827 *******
2026-03-18 23:56:50.245294 | controller | changed: [instance]
2026-03-18 23:56:50.272626 | controller |
2026-03-18 23:56:50.272646 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-03-18 23:56:50.272654 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.417)       0:00:14.245 *******
2026-03-18 23:56:50.272665 | controller | skipping: [instance]
2026-03-18 23:56:50.298091 | controller |
2026-03-18 23:56:50.298108 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-03-18 23:56:50.298114 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.027)       0:00:14.272 *******
2026-03-18 23:56:50.298126 | controller | skipping: [instance]
2026-03-18 23:56:50.298421 | controller |
2026-03-18 23:56:50.325606 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-03-18 23:56:50.325622 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.025)       0:00:14.298 *******
2026-03-18 23:56:50.325631 | controller | skipping: [instance]
2026-03-18 23:56:50.361118 | controller |
2026-03-18 23:56:50.361151 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-03-18 23:56:50.361165 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.027)       0:00:14.325 *******
2026-03-18 23:56:50.361181 | controller | ok: [instance]
2026-03-18 23:56:50.361682 | controller |
2026-03-18 23:56:50.387128 | controller | TASK [repo_setup : Create download directory] **********************************
2026-03-18 23:56:50.387160 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.035)       0:00:14.361 *******
2026-03-18 23:56:50.387173 | controller | skipping: [instance]
2026-03-18 23:56:50.387180 | controller |
2026-03-18 23:56:50.387186 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-03-18 23:56:50.387193 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.025)       0:00:14.387 *******
2026-03-18 23:56:50.414441 | controller | skipping: [instance]
2026-03-18 23:56:50.462762 | controller |
2026-03-18 23:56:50.462780 | controller | TASK [Download the RPM] ********************************************************
2026-03-18 23:56:50.462788 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.027)       0:00:14.414 *******
2026-03-18 23:56:50.462796 | controller | skipping: [instance]
2026-03-18 23:56:50.488650 | controller |
2026-03-18 23:56:50.488684 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-03-18 23:56:50.488697 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.048)       0:00:14.463 *******
2026-03-18 23:56:50.488713 | controller | skipping: [instance]
2026-03-18 23:56:50.513126 | controller |
2026-03-18 23:56:50.513155 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-03-18 23:56:50.513166 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.025)       0:00:14.488 *******
2026-03-18 23:56:50.513179 | controller | skipping: [instance]
2026-03-18 23:56:50.513220 | controller |
2026-03-18 23:56:50.513249 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-03-18 23:56:50.513343 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.025)       0:00:14.513 *******
2026-03-18 23:56:50.540013 | controller | skipping: [instance]
2026-03-18 23:56:50.564186 | controller |
2026-03-18 23:56:50.564223 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-03-18 23:56:50.564237 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.026)       0:00:14.540 *******
2026-03-18 23:56:50.564253 | controller | skipping: [instance]
2026-03-18 23:56:50.564294 | controller |
2026-03-18 23:56:50.564308 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-03-18 23:56:50.564418 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.024)       0:00:14.564 *******
2026-03-18 23:56:50.734873 | controller | ok: [instance]
2026-03-18 23:56:50.924159 | controller |
2026-03-18 23:56:50.924183 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-03-18 23:56:50.924190 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.170)       0:00:14.735 *******
2026-03-18 23:56:50.924200 | controller | changed: [instance]
2026-03-18 23:56:51.130620 | controller |
2026-03-18 23:56:51.130644 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-03-18 23:56:51.130652 | controller | Wednesday 18 March 2026  23:56:50 +0000 (0:00:00.188)       0:00:14.924 *******
2026-03-18 23:56:51.130662 | controller | changed: [instance]
2026-03-18 23:56:51.151949 | controller |
2026-03-18 23:56:51.151968 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-03-18 23:56:51.151976 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.206)       0:00:15.130 *******
2026-03-18 23:56:51.151985 | controller | skipping: [instance]
2026-03-18 23:56:51.174129 | controller |
2026-03-18 23:56:51.174161 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-03-18 23:56:51.174169 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.021)       0:00:15.152 *******
2026-03-18 23:56:51.174179 | controller | skipping: [instance]
2026-03-18 23:56:51.174205 | controller |
2026-03-18 23:56:51.174476 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-03-18 23:56:51.194699 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.022)       0:00:15.174 *******
2026-03-18 23:56:51.194720 | controller | skipping: [instance]
2026-03-18 23:56:51.215002 | controller |
2026-03-18 23:56:51.215022 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-03-18 23:56:51.215030 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.020)       0:00:15.195 *******
2026-03-18 23:56:51.215061 | controller | skipping: [instance]
2026-03-18 23:56:51.236325 | controller |
2026-03-18 23:56:51.236343 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-03-18 23:56:51.236350 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.020)       0:00:15.215 *******
2026-03-18 23:56:51.236358 | controller | skipping: [instance]
2026-03-18 23:56:51.257973 | controller |
2026-03-18 23:56:51.257990 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-03-18 23:56:51.258002 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.021)       0:00:15.236 *******
2026-03-18 23:56:51.258010 | controller | skipping: [instance]
2026-03-18 23:56:51.274398 | controller |
2026-03-18 23:56:51.274415 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-03-18 23:56:51.274421 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.021)       0:00:15.258 *******
2026-03-18 23:56:51.274430 | controller | skipping: [instance]
2026-03-18 23:56:51.300804 | controller |
2026-03-18 23:56:51.300821 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-03-18 23:56:51.300827 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.016)       0:00:15.274 *******
2026-03-18 23:56:51.300835 | controller | skipping: [instance]
2026-03-18 23:56:51.315968 | controller |
2026-03-18 23:56:51.315985 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-03-18 23:56:51.315993 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.026)       0:00:15.301 *******
2026-03-18 23:56:51.316003 | controller | skipping: [instance]
2026-03-18 23:56:51.335713 | controller |
2026-03-18 23:56:51.335734 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-03-18 23:56:51.335743 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.015)       0:00:15.316 *******
2026-03-18 23:56:51.335753 | controller | skipping: [instance]
2026-03-18 23:56:51.363497 | controller |
2026-03-18 23:56:51.363517 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-03-18 23:56:51.363523 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.019)       0:00:15.336 *******
2026-03-18 23:56:51.363531 | controller | skipping: [instance]
2026-03-18 23:56:51.390868 | controller |
2026-03-18 23:56:51.390888 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-03-18 23:56:51.390897 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.027)       0:00:15.363 *******
2026-03-18 23:56:51.390907 | controller | skipping: [instance]
2026-03-18 23:56:51.419772 | controller |
2026-03-18 23:56:51.419790 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-03-18 23:56:51.419798 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.027)       0:00:15.391 *******
2026-03-18 23:56:51.419807 | controller | skipping: [instance]
2026-03-18 23:56:51.447512 | controller |
2026-03-18 23:56:51.447529 | controller | TASK [test_deps : Install additional packages] *********************************
2026-03-18 23:56:51.447535 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.028)       0:00:15.419 *******
2026-03-18 23:56:51.447544 | controller | skipping: [instance]
2026-03-18 23:57:39.409383 | controller |
2026-03-18 23:57:39.409415 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-03-18 23:57:39.409423 | controller | Wednesday 18 March 2026  23:56:51 +0000 (0:00:00.028)       0:00:15.447 *******
2026-03-18 23:57:39.409433 | controller | ok: [instance]
2026-03-18 23:57:40.542824 | controller |
2026-03-18 23:57:40.542853 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-03-18 23:57:40.542860 | controller | Wednesday 18 March 2026  23:57:39 +0000 (0:00:47.961)       0:01:03.409 *******
2026-03-18 23:57:40.542870 | controller | ok: [instance]
2026-03-18 23:57:40.583812 | controller |
2026-03-18 23:57:40.583836 | controller | TASK [test_deps : Install extra packages] **************************************
2026-03-18 23:57:40.583843 | controller | Wednesday 18 March 2026  23:57:40 +0000 (0:00:01.133)       0:01:04.543 *******
2026-03-18 23:57:40.583853 | controller | skipping: [instance]
2026-03-18 23:57:40.636714 | controller |
2026-03-18 23:57:40.636747 | controller | PLAY RECAP *********************************************************************
2026-03-18 23:57:40.636755 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2026-03-18 23:57:40.636761 | controller |
2026-03-18 23:57:40.636767 | controller | Wednesday 18 March 2026  23:57:40 +0000 (0:00:00.038)       0:01:04.582 *******
2026-03-18 23:57:40.636773 | controller | ===============================================================================
2026-03-18 23:57:40.636778 | controller | test_deps : Install selinux python libs -------------------------------- 47.96s
2026-03-18 23:57:40.636790 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.32s
2026-03-18 23:57:40.636795 | controller | test_deps : Install python yaml libs ------------------------------------ 1.13s
2026-03-18 23:57:40.636805 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.99s
2026-03-18 23:57:40.636811 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2026-03-18 23:57:40.636817 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2026-03-18 23:57:40.636822 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.73s
2026-03-18 23:57:40.636827 | controller | repo_setup : Install repo-setup package --------------------------------- 0.65s
2026-03-18 23:57:40.636832 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.65s
2026-03-18 23:57:40.636838 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2026-03-18 23:57:40.636843 | controller | repo_setup : Ensure directories are present ----------------------------- 0.49s
2026-03-18 23:57:40.636848 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2026-03-18 23:57:40.636854 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.29s
2026-03-18 23:57:40.636859 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.26s
2026-03-18 23:57:40.636864 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.21s
2026-03-18 23:57:40.636870 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.19s
2026-03-18 23:57:40.636875 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.17s
2026-03-18 23:57:40.636881 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2026-03-18 23:57:40.636886 | controller | Download the RPM -------------------------------------------------------- 0.05s
2026-03-18 23:57:40.636891 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2026-03-18 23:57:40.636901 | controller | INFO     Running default > converge
2026-03-18 23:57:41.019537 | controller |
2026-03-18 23:57:41.753699 | controller | PLAY [Converge] ****************************************************************
2026-03-18 23:57:41.753731 | controller |
2026-03-18 23:57:41.753738 | controller | TASK [Gathering Facts] *********************************************************
2026-03-18 23:57:41.753744 | controller | Wednesday 18 March 2026  23:57:41 +0000 (0:00:00.012)       0:00:00.012 *******
2026-03-18 23:57:41.753753 | controller | ok: [instance]
2026-03-18 23:57:41.785496 | controller |
2026-03-18 23:57:41.785527 | controller | TASK [Run copy-container role] *************************************************
2026-03-18 23:57:41.785538 | controller | Wednesday 18 March 2026  23:57:41 +0000 (0:00:00.734)       0:00:00.746 *******
2026-03-18 23:57:41.785551 | controller |
2026-03-18 23:57:42.201867 | controller | TASK [copy_container : Ensure directory is present] ****************************
2026-03-18 23:57:42.201897 | controller | Wednesday 18 March 2026  23:57:41 +0000 (0:00:00.031)       0:00:00.778 *******
2026-03-18 23:57:42.201908 | controller | ok: [instance] => (item=artifacts)
2026-03-18 23:57:45.931078 | controller | ok: [instance] => (item=logs)
2026-03-18 23:57:45.931109 | controller |
2026-03-18 23:57:45.931117 | controller | TASK [copy_container : Install HyperScale repository (btrfs-progs requirement)] ***
2026-03-18 23:57:45.931123 | controller | Wednesday 18 March 2026  23:57:42 +0000 (0:00:00.416)       0:00:01.194 *******
2026-03-18 23:57:45.931133 | controller | changed: [instance]
2026-03-18 23:58:37.722936 | controller |
2026-03-18 23:58:37.722967 | controller | TASK [copy_container : Install required packages] ******************************
2026-03-18 23:58:37.722976 | controller | Wednesday 18 March 2026  23:57:45 +0000 (0:00:03.729)       0:00:04.923 *******
2026-03-18 23:58:37.722986 | controller | changed: [instance]
2026-03-18 23:58:37.977609 | controller |
2026-03-18 23:58:37.977644 | controller | TASK [copy_container : Create temporary directory] *****************************
2026-03-18 23:58:37.977652 | controller | Wednesday 18 March 2026  23:58:37 +0000 (0:00:51.791)       0:00:56.715 *******
2026-03-18 23:58:37.977669 | controller | changed: [instance]
2026-03-18 23:58:37.977901 | controller |
2026-03-18 23:58:41.007461 | controller | TASK [copy_container : Copy go files to temporary directory] *******************
2026-03-18 23:58:41.007499 | controller | Wednesday 18 March 2026  23:58:37 +0000 (0:00:00.254)       0:00:56.970 *******
2026-03-18 23:58:41.007517 | controller | changed: [instance]
2026-03-18 23:59:38.362719 | controller |
2026-03-18 23:59:38.362774 | controller | TASK [copy_container : Build the copy-container] *******************************
2026-03-18 23:59:38.362783 | controller | Wednesday 18 March 2026  23:58:41 +0000 (0:00:03.029)       0:01:00.000 *******
2026-03-18 23:59:38.362794 | controller | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: NoneType: None
2026-03-18 23:59:38.364658 | controller | fatal: [instance]: FAILED! => changed=false
2026-03-18 23:59:38.364694 | controller |   msg: non-zero return code
2026-03-18 23:59:38.364702 | controller |   rc: 1
2026-03-18 23:59:38.364708 | controller |   stderr: ''
2026-03-18 23:59:38.364714 | controller |   stderr_lines: <omitted>
2026-03-18 23:59:38.364719 | controller |   stdout: |-
2026-03-18 23:59:38.364725 | controller |     /tmp/ansible.6j6lldarcopy-container ~/ci-framework-data/artifacts
2026-03-18 23:59:38.364731 | controller |     go: downloading github.com/containers/image/v5 v5.30.1
2026-03-18 23:59:38.364738 | controller |     go: downloading github.com/containers/storage v1.53.0
2026-03-18 23:59:38.364818 | controller |     go: downloading github.com/sirupsen/logrus v1.9.3
2026-03-18 23:59:38.364939 | controller |     go: downloading github.com/spf13/cobra v1.10.2
2026-03-18 23:59:38.364956 | controller |     go: downloading gopkg.in/yaml.v2 v2.4.0
2026-03-18 23:59:38.365076 | controller |     go: downloading github.com/containers/ocicrypt v1.1.9
2026-03-18 23:59:38.365104 | controller |     go: downloading github.com/opencontainers/go-digest v1.0.0
2026-03-18 23:59:38.365798 | controller |     go: downloading github.com/opencontainers/image-spec v1.1.1
2026-03-18 23:59:38.365824 | controller |     go: downloading github.com/vbauerster/mpb/v8 v8.7.2
2026-03-18 23:59:38.365830 | controller |     go: downloading golang.org/x/exp v0.0.0-20250620022241-b7579e27df2b
2026-03-18 23:59:38.365836 | controller |     go: downloading golang.org/x/term v0.38.0
2026-03-18 23:59:38.365841 | controller |     go: downloading golang.org/x/sync v0.19.0
2026-03-18 23:59:38.365846 | controller |     go: downloading github.com/containers/libtrust v0.0.0-20230121012942-c1716e8a8d01
2026-03-18 23:59:38.365852 | controller |     go: downloading github.com/docker/docker v28.5.2+incompatible
2026-03-18 23:59:38.365857 | controller |     go: downloading github.com/proglottis/gpgme v0.1.3
2026-03-18 23:59:38.365864 | controller |     go: downloading github.com/sigstore/fulcio v1.8.5
2026-03-18 23:59:38.365954 | controller |     go: downloading github.com/sigstore/sigstore v1.10.3
2026-03-18 23:59:38.366144 | controller |     go: downloading golang.org/x/crypto v0.46.0
2026-03-18 23:59:38.366203 | controller |     go: downloading golang.org/x/sys v0.39.0
2026-03-18 23:59:38.366214 | controller |     go: downloading github.com/klauspost/compress v1.18.1
2026-03-18 23:59:38.367710 | controller |     go: downloading github.com/klauspost/pgzip v1.2.6
2026-03-18 23:59:38.367768 | controller |     go: downloading github.com/ulikunitz/xz v0.5.14
2026-03-18 23:59:38.367777 | controller |     go: downloading github.com/secure-systems-lab/go-securesystemslib v0.9.1
2026-03-18 23:59:38.367784 | controller |     go: downloading github.com/spf13/pflag v1.0.10
2026-03-18 23:59:38.367789 | controller |     go: downloading gopkg.in/yaml.v3 v3.0.1
2026-03-18 23:59:38.367794 | controller |     go: downloading github.com/acarl005/stripansi v0.0.0-20180116102854-5a71ef0e047d
2026-03-18 23:59:38.367800 | controller |     go: downloading github.com/mattn/go-runewidth v0.0.16
2026-03-18 23:59:38.367805 | controller |     go: downloading github.com/VividCortex/ewma v1.2.0
2026-03-18 23:59:38.367811 | controller |     go: downloading github.com/cyberphone/json-canonicalization v0.0.0-20231217050601-ba74d44ecf5f
2026-03-18 23:59:38.367816 | controller |     go: downloading github.com/sigstore/rekor v1.5.0
2026-03-18 23:59:38.367821 | controller |     go: downloading github.com/docker/distribution v2.8.3+incompatible
2026-03-18 23:59:38.367826 | controller |     go: downloading github.com/docker/go-connections v0.5.0
2026-03-18 23:59:38.367832 | controller |     go: downloading dario.cat/mergo v1.0.1
2026-03-18 23:59:38.367837 | controller |     go: downloading github.com/sylabs/sif/v2 v2.15.1
2026-03-18 23:59:38.367848 | controller |     go: downloading github.com/mattn/go-sqlite3 v1.14.28
2026-03-18 23:59:38.367853 | controller |     go: downloading github.com/vbatts/tar-split v0.12.2
2026-03-18 23:59:38.367858 | controller |     go: downloading github.com/google/go-containerregistry v0.20.7
2026-03-18 23:59:38.367863 | controller |     go: downloading github.com/sigstore/protobuf-specs v0.5.0
2026-03-18 23:59:38.367871 | controller |     go: downloading github.com/go-jose/go-jose/v3 v3.0.4
2026-03-18 23:59:38.367879 | controller |     go: downloading google.golang.org/grpc v1.78.0
2026-03-18 23:59:38.367973 | controller |     go: downloading go.mozilla.org/pkcs7 v0.0.0-20210826202110-33d05740a352
2026-03-18 23:59:38.367983 | controller |     go: downloading github.com/miekg/pkcs11 v1.1.1
2026-03-18 23:59:38.368092 | controller |     go: downloading github.com/stefanberger/go-pkcs11uri v0.0.0-20201008174630-78d3cae3a980
2026-03-18 23:59:38.368211 | controller |     go: downloading github.com/json-iterator/go v1.1.12
2026-03-18 23:59:38.368309 | controller |     go: downloading github.com/rivo/uniseg v0.4.7
2026-03-18 23:59:38.368413 | controller |     go: downloading github.com/opencontainers/runtime-spec v1.2.0
2026-03-18 23:59:38.368713 | controller |     go: downloading github.com/syndtr/gocapability v0.0.0-20200815063812-42c35b437635
2026-03-18 23:59:38.368812 | controller |     go: downloading github.com/docker/docker-credential-helpers v0.9.3
2026-03-18 23:59:38.368909 | controller |     go: downloading github.com/hashicorp/go-multierror v1.1.1
2026-03-18 23:59:38.369007 | controller |     go: downloading github.com/BurntSushi/toml v1.3.2
2026-03-18 23:59:38.369134 | controller |     go: downloading github.com/distribution/reference v0.6.0
2026-03-18 23:59:38.369244 | controller |     go: downloading github.com/gorilla/mux v1.8.1
2026-03-18 23:59:38.369341 | controller |     go: downloading github.com/containerd/errdefs v1.0.0
2026-03-18 23:59:38.369443 | controller |     go: downloading github.com/containerd/errdefs/pkg v0.3.0
2026-03-18 23:59:38.369549 | controller |     go: downloading github.com/pkg/errors v0.9.1
2026-03-18 23:59:38.369649 | controller |     go: downloading go.opentelemetry.io/contrib/instrumentation/net/http/otelhttp v0.63.0
2026-03-18 23:59:38.369747 | controller |     go: downloading go.opentelemetry.io/otel/trace v1.42.0
2026-03-18 23:59:38.369842 | controller |     go: downloading go.opentelemetry.io/otel v1.42.0
2026-03-18 23:59:38.369940 | controller |     go: downloading github.com/moby/sys/user v0.1.0
2026-03-18 23:59:38.370036 | controller |     go: downloading github.com/go-openapi/errors v0.22.6
2026-03-18 23:59:38.370149 | controller |     go: downloading github.com/go-openapi/runtime v0.29.2
2026-03-18 23:59:38.370255 | controller |     go: downloading github.com/go-openapi/strfmt v0.25.0
2026-03-18 23:59:38.370353 | controller |     go: downloading github.com/go-openapi/swag v0.25.4
2026-03-18 23:59:38.370448 | controller |     go: downloading github.com/go-openapi/validate v0.25.1
2026-03-18 23:59:38.370554 | controller |     go: downloading github.com/google/go-intervals v0.0.2
2026-03-18 23:59:38.370648 | controller |     go: downloading github.com/opencontainers/selinux v1.13.0
2026-03-18 23:59:38.370744 | controller |     go: downloading github.com/containerd/stargz-snapshotter/estargz v0.18.1
2026-03-18 23:59:38.370840 | controller |     go: downloading github.com/cyphar/filepath-securejoin v0.6.0
2026-03-18 23:59:38.370938 | controller |     go: downloading google.golang.org/genproto/googleapis/api v0.0.0-20251222181119-0a764e51fe1b
2026-03-18 23:59:38.371033 | controller |     go: downloading google.golang.org/protobuf v1.36.11
2026-03-18 23:59:38.371144 | controller |     go: downloading github.com/golang/protobuf v1.5.4
2026-03-18 23:59:38.371248 | controller |     go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd
2026-03-18 23:59:38.371343 | controller |     go: downloading github.com/modern-go/reflect2 v1.0.2
2026-03-18 23:59:38.371438 | controller |     go: downloading golang.org/x/net v0.48.0
2026-03-18 23:59:38.371541 | controller |     go: downloading github.com/hashicorp/errwrap v1.1.0
2026-03-18 23:59:38.371635 | controller |     go: downloading github.com/docker/go-units v0.5.0
2026-03-18 23:59:38.372002 | controller |     go: downloading github.com/moby/docker-image-spec v1.3.1
2026-03-18 23:59:38.372123 | controller |     go: downloading github.com/felixge/httpsnoop v1.0.4
2026-03-18 23:59:38.372229 | controller |     go: downloading go.opentelemetry.io/otel/metric v1.42.0
2026-03-18 23:59:38.372331 | controller |     go: downloading github.com/go-viper/mapstructure/v2 v2.4.0
2026-03-18 23:59:38.372423 | controller |     go: downloading github.com/google/uuid v1.6.0
2026-03-18 23:59:38.372742 | controller |     go: downloading github.com/oklog/ulid v1.3.1
2026-03-18 23:59:38.372845 | controller |     go: downloading go.mongodb.org/mongo-driver v1.17.6
2026-03-18 23:59:38.372944 | controller |     go: downloading github.com/go-openapi/swag/fileutils v0.25.4
2026-03-18 23:59:38.373058 | controller |     go: downloading github.com/go-openapi/swag/jsonutils v0.25.4
2026-03-18 23:59:38.373164 | controller |     go: downloading github.com/go-openapi/swag/stringutils v0.25.4
2026-03-18 23:59:38.373269 | controller |     go: downloading github.com/go-openapi/swag/cmdutils v0.25.4
2026-03-18 23:59:38.373364 | controller |     go: downloading github.com/go-openapi/swag/conv v0.25.4
2026-03-18 23:59:38.373460 | controller |     go: downloading github.com/go-openapi/swag/jsonname v0.25.4
2026-03-18 23:59:38.373567 | controller |     go: downloading github.com/go-openapi/swag/loading v0.25.4
2026-03-18 23:59:38.373844 | controller |     go: downloading github.com/go-openapi/swag/mangling v0.25.4
2026-03-18 23:59:38.373939 | controller |     go: downloading github.com/go-openapi/swag/netutils v0.25.4
2026-03-18 23:59:38.374036 | controller |     go: downloading github.com/go-openapi/swag/typeutils v0.25.4
2026-03-18 23:59:38.374149 | controller |     go: downloading github.com/go-openapi/swag/yamlutils v0.25.4
2026-03-18 23:59:38.374247 | controller |     go: downloading github.com/moby/sys/mountinfo v0.7.1
2026-03-18 23:59:38.374338 | controller |     go: downloading github.com/tchap/go-patricia/v2 v2.3.1
2026-03-18 23:59:38.374431 | controller |     go: downloading github.com/go-openapi/analysis v0.24.1
2026-03-18 23:59:38.374540 | controller |     go: downloading github.com/go-openapi/jsonpointer v0.22.4
2026-03-18 23:59:38.374631 | controller |     go: downloading github.com/go-openapi/loads v0.23.2
2026-03-18 23:59:38.374726 | controller |     go: downloading github.com/go-openapi/spec v0.22.3
2026-03-18 23:59:38.374823 | controller |     go: downloading google.golang.org/genproto/googleapis/rpc v0.0.0-20251222181119-0a764e51fe1b
2026-03-18 23:59:38.374917 | controller |     go: downloading github.com/go-logr/logr v1.4.3
2026-03-18 23:59:38.375012 | controller |     go: downloading golang.org/x/text v0.32.0
2026-03-18 23:59:38.375139 | controller |     go: downloading github.com/mistifyio/go-zfs/v3 v3.0.1
2026-03-18 23:59:38.375265 | controller |     go: downloading go.yaml.in/yaml/v3 v3.0.4
2026-03-18 23:59:38.375360 | controller |     go: downloading github.com/go-logr/stdr v1.2.2
2026-03-18 23:59:38.375456 | controller |     go: downloading go.opentelemetry.io/auto/sdk v1.2.1
2026-03-18 23:59:38.375566 | controller |     go: downloading github.com/cespare/xxhash/v2 v2.3.0
2026-03-18 23:59:38.375667 | controller |     go: downloading github.com/go-openapi/jsonreference v0.21.4
2026-03-18 23:59:38.375762 | controller |     # github.com/containers/image/v5/docker/daemon
2026-03-18 23:59:38.375865 | controller |     /home/zuul/go/pkg/mod/github.com/containers/image/v5@v5.30.1/docker/daemon/daemon_dest.go:94:40: cannot use true (constant of type bool) as client.ImageLoadOption value in argument to c.ImageLoad: bool does not implement client.ImageLoadOption (missing method Apply)
2026-03-18 23:59:38.375960 | controller |   stdout_lines: <omitted>
2026-03-18 23:59:38.376132 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_build_the_copy.log
2026-03-18 23:59:38.376230 | controller |
2026-03-18 23:59:38.376323 | controller | PLAY RECAP *********************************************************************
2026-03-18 23:59:38.376425 | controller | instance                   : ok=6    changed=4    unreachable=0    failed=1    skipped=0    rescued=0    ignored=0
2026-03-18 23:59:38.376521 | controller |
2026-03-18 23:59:38.376620 | controller | Wednesday 18 March 2026  23:59:38 +0000 (0:00:57.359)       0:01:57.359 *******
2026-03-18 23:59:38.376711 | controller | ===============================================================================
2026-03-18 23:59:38.376804 | controller | copy_container : Build the copy-container ------------------------------ 57.36s
2026-03-18 23:59:38.376902 | controller | copy_container : Install required packages ----------------------------- 51.79s
2026-03-18 23:59:38.376996 | controller | copy_container : Install HyperScale repository (btrfs-progs requirement) --- 3.73s
2026-03-18 23:59:38.377106 | controller | copy_container : Copy go files to temporary directory ------------------- 3.03s
2026-03-18 23:59:38.377211 | controller | Gathering Facts --------------------------------------------------------- 0.73s
2026-03-18 23:59:38.377311 | controller | copy_container : Ensure directory is present ---------------------------- 0.42s
2026-03-18 23:59:38.377413 | controller | copy_container : Create temporary directory ----------------------------- 0.26s
2026-03-18 23:59:38.377523 | controller | Run copy-container role ------------------------------------------------- 0.03s
2026-03-18 23:59:38.421399 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/copy_container/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/copy_container/molecule/default/converge.yml']
2026-03-18 23:59:38.421797 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-03-18 23:59:38.441916 | controller | INFO     Running default > cleanup
2026-03-18 23:59:38.442500 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-03-18 23:59:38.450611 | controller | INFO     Running default > destroy
2026-03-18 23:59:38.450979 | controller | WARNING  Skipping, instances are delegated.
2026-03-18 23:59:38.451387 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-03-18 23:59:38.452994 | controller | INFO     Writing /tmp/report.html report.
2026-03-18 23:59:38.968757 | controller | ERROR
2026-03-18 23:59:38.968925 | controller | {
2026-03-18 23:59:38.968958 | controller |   "delta": "0:03:20.524313",
2026-03-18 23:59:38.968978 | controller |   "end": "2026-03-18 23:59:38.511528",
2026-03-18 23:59:38.968995 | controller |   "msg": "non-zero return code",
2026-03-18 23:59:38.969011 | controller |   "rc": 1,
2026-03-18 23:59:38.969028 | controller |   "start": "2026-03-18 23:56:17.987215"
2026-03-18 23:59:38.969043 | controller | }
failure
2026-03-18 23:59:38.972540 | 
2026-03-18 23:59:38.972583 | PLAY RECAP
2026-03-18 23:59:38.972619 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-18 23:59:38.972638 | 
2026-03-18 23:59:39.025640 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-18 23:59:39.026388 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-18 23:59:39.502510 | 
2026-03-18 23:59:39.502604 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-18 23:59:39.520825 | 
2026-03-18 23:59:39.520893 | TASK [Filter out host if needed]
2026-03-18 23:59:39.528563 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-18 23:59:39.532632 | 
2026-03-18 23:59:39.532694 | TASK [Ensure file is present]
2026-03-18 23:59:39.813943 | controller | ok
2026-03-18 23:59:39.820052 | 
2026-03-18 23:59:39.820127 | TASK [Manage molecule report file]
2026-03-18 23:59:40.342928 | controller | changed
2026-03-18 23:59:40.347603 | 
2026-03-18 23:59:40.347660 | TASK [Check if we get ci-framework-data basedir]
2026-03-18 23:59:40.527750 | controller | ok
2026-03-18 23:59:40.532261 | 
2026-03-18 23:59:40.532348 | TASK [Create ci-framework-data log directory for zuul]
2026-03-18 23:59:40.814948 | controller | changed
2026-03-18 23:59:40.819579 | 
2026-03-18 23:59:40.819640 | TASK [Copy ci-framework interesting files]
2026-03-18 23:59:41.000986 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-18 23:59:41.341116 | controller | changed
2026-03-18 23:59:41.345994 | 
2026-03-18 23:59:41.346058 | TASK [Get SELinux listing]
2026-03-18 23:59:41.868357 | controller | changed
2026-03-18 23:59:41.873495 | 
2026-03-18 23:59:41.873555 | TASK [Generate log index]
2026-03-18 23:59:42.481771 | controller | changed
2026-03-18 23:59:42.486512 | 
2026-03-18 23:59:42.486575 | TASK [Get some env related data]
2026-03-18 23:59:43.022001 | controller | /home/zuul/.local/bin/ansible
2026-03-18 23:59:44.015895 | controller | changed
2026-03-18 23:59:44.020644 | 
2026-03-18 23:59:44.020705 | TASK [Generate list of logs to collect in home directory]
2026-03-18 23:59:44.292327 | controller | ok: All paths examined
2026-03-18 23:59:44.296851 | 
2026-03-18 23:59:44.296909 | LOOP [Copy logs from home directory]
2026-03-18 23:59:44.623837 | controller | changed:
2026-03-18 23:59:44.623941 | controller | {
2026-03-18 23:59:44.623967 | controller |   "atime": 1773878160.1084175,
2026-03-18 23:59:44.623985 | controller |   "ctime": 1773878175.9375398,
2026-03-18 23:59:44.624001 | controller |   "dev": 64513,
2026-03-18 23:59:44.624016 | controller |   "gid": 1000,
2026-03-18 23:59:44.624030 | controller |   "gr_name": "zuul",
2026-03-18 23:59:44.624045 | controller |   "inode": 43502,
2026-03-18 23:59:44.624059 | controller |   "isblk": false,
2026-03-18 23:59:44.624079 | controller |   "ischr": false,
2026-03-18 23:59:44.624095 | controller |   "isdir": false,
2026-03-18 23:59:44.624109 | controller |   "isfifo": false,
2026-03-18 23:59:44.624123 | controller |   "isgid": false,
2026-03-18 23:59:44.624136 | controller |   "islnk": false,
2026-03-18 23:59:44.624150 | controller |   "isreg": true,
2026-03-18 23:59:44.624163 | controller |   "issock": false,
2026-03-18 23:59:44.624176 | controller |   "isuid": false,
2026-03-18 23:59:44.624190 | controller |   "mode": "0644",
2026-03-18 23:59:44.624204 | controller |   "mtime": 1773878175.9375398,
2026-03-18 23:59:44.624218 | controller |   "nlink": 1,
2026-03-18 23:59:44.624231 | controller |   "path": "/home/zuul/ansible.log",
2026-03-18 23:59:44.624244 | controller |   "pw_name": "zuul",
2026-03-18 23:59:44.624258 | controller |   "rgrp": true,
2026-03-18 23:59:44.624293 | controller |   "roth": true,
2026-03-18 23:59:44.624310 | controller |   "rusr": true,
2026-03-18 23:59:44.624324 | controller |   "size": 6749,
2026-03-18 23:59:44.624337 | controller |   "uid": 1000,
2026-03-18 23:59:44.624350 | controller |   "wgrp": false,
2026-03-18 23:59:44.624362 | controller |   "woth": false,
2026-03-18 23:59:44.624377 | controller |   "wusr": true,
2026-03-18 23:59:44.624390 | controller |   "xgrp": false,
2026-03-18 23:59:44.624402 | controller |   "xoth": false,
2026-03-18 23:59:44.624415 | controller |   "xusr": false
2026-03-18 23:59:44.624428 | controller | }
2026-03-18 23:59:44.637407 | 
2026-03-18 23:59:44.637472 | TASK [Copy crio stats log file]
2026-03-18 23:59:44.651136 | controller | skipping: Conditional result was False
2026-03-18 23:59:44.656113 | 
2026-03-18 23:59:44.656179 | TASK [Get SELinux related data]
2026-03-18 23:59:44.867588 | controller | <no matches>
2026-03-18 23:59:45.180624 | controller | ERROR
2026-03-18 23:59:45.180765 | controller | {
2026-03-18 23:59:45.180794 | controller |   "delta": "0:00:00.012532",
2026-03-18 23:59:45.180813 | controller |   "end": "2026-03-18 23:59:44.868815",
2026-03-18 23:59:45.180829 | controller |   "msg": "non-zero return code",
2026-03-18 23:59:45.180845 | controller |   "rc": 1,
2026-03-18 23:59:45.180859 | controller |   "start": "2026-03-18 23:59:44.856283"
2026-03-18 23:59:45.180873 | controller | }
2026-03-18 23:59:45.180892 | controller | ERROR: Ignoring Errors
2026-03-18 23:59:45.185207 | 
2026-03-18 23:59:45.185287 | TASK [Create system configuration directory]
2026-03-18 23:59:45.365004 | controller | changed
2026-03-18 23:59:45.369305 | 
2026-03-18 23:59:45.369364 | TASK [Get some of the system configurations]
2026-03-18 23:59:45.890199 | controller | changed
2026-03-18 23:59:45.895092 | 
2026-03-18 23:59:45.895154 | TASK [Copy generated documentation if available]
2026-03-18 23:59:45.908741 | controller | skipping: Conditional result was False
2026-03-18 23:59:45.914196 | 
2026-03-18 23:59:45.914260 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-18 23:59:45.927673 | controller | skipping: Conditional result was False
2026-03-18 23:59:45.933283 | 
2026-03-18 23:59:45.933347 | TASK [Compress logs bigger than 2MB]
2026-03-18 23:59:46.458538 | controller | changed
2026-03-18 23:59:46.463284 | 
2026-03-18 23:59:46.463348 | TASK [Copy files from workspace on node]
2026-03-18 23:59:46.480713 | controller | ok
2026-03-18 23:59:46.500283 | 
2026-03-18 23:59:46.500345 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-18 23:59:46.514135 | controller | skipping: Conditional result was False
2026-03-18 23:59:46.519423 | 
2026-03-18 23:59:46.519499 | TASK [fetch-output : Set log path for single node]
2026-03-18 23:59:46.547099 | controller | ok
2026-03-18 23:59:46.552218 | 
2026-03-18 23:59:46.552290 | LOOP [fetch-output : Ensure local output dirs]
2026-03-18 23:59:46.736076 | controller -> localhost | ok: "/var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/logs"
2026-03-18 23:59:46.736282 | controller -> localhost | changed: All items complete
2026-03-18 23:59:46.736323 | 
2026-03-18 23:59:46.901046 | controller -> localhost | changed: "/var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/artifacts"
2026-03-18 23:59:47.073956 | controller -> localhost | changed: "/var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/docs"
2026-03-18 23:59:47.088598 | 
2026-03-18 23:59:47.088669 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-18 23:59:47.618890 | controller | changed:
2026-03-18 23:59:47.619117 | controller | .d..t...... ./
2026-03-18 23:59:47.619152 | controller | >f+++++++++ README.html
2026-03-18 23:59:47.619176 | controller | >f+++++++++ ansible-execution.log
2026-03-18 23:59:47.619197 | controller | >f+++++++++ ansible.log
2026-03-18 23:59:47.619216 | controller | >f+++++++++ dmesg.log
2026-03-18 23:59:47.619234 | controller | >f+++++++++ installed-pkgs.log
2026-03-18 23:59:47.619255 | controller | >f+++++++++ python.log
2026-03-18 23:59:47.619299 | controller | >f+++++++++ registries.conf
2026-03-18 23:59:47.619322 | controller | >f+++++++++ report.html
2026-03-18 23:59:47.619343 | controller | >f+++++++++ selinux-denials.log
2026-03-18 23:59:47.619362 | controller | >f+++++++++ selinux-listing.log
2026-03-18 23:59:47.619381 | controller | cd+++++++++ ci-framework-data/
2026-03-18 23:59:47.619398 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-18 23:59:47.619416 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_build_the_copy.sh
2026-03-18 23:59:47.619433 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-18 23:59:47.619451 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-18 23:59:47.619482 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-18 23:59:47.619502 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-03-18 23:59:47.619520 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-03-18 23:59:47.619537 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-18 23:59:47.619554 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_build_the_copy.log
2026-03-18 23:59:47.619571 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-18 23:59:47.619587 | controller | cd+++++++++ registries.conf.d/
2026-03-18 23:59:47.619604 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-18 23:59:47.619620 | controller | cd+++++++++ system-config/
2026-03-18 23:59:47.619637 | controller | cd+++++++++ system-config/libvirt/
2026-03-18 23:59:47.619653 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-18 23:59:47.619669 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-18 23:59:47.985378 | controller | changed: .d..t...... ./
2026-03-18 23:59:48.333822 | controller | changed: .d..t...... ./
2026-03-18 23:59:48.347538 | 
2026-03-18 23:59:48.347639 | TASK [Return artifact to Zuul]
2026-03-18 23:59:48.375433 | controller | ok
2026-03-18 23:59:48.393091 | 
2026-03-18 23:59:48.393144 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-18 23:59:48.393240 | 
2026-03-18 23:59:48.393282 | PLAY RECAP
2026-03-18 23:59:48.393323 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-18 23:59:48.393344 | 
2026-03-18 23:59:48.466023 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-18 23:59:48.466749 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-18 23:59:48.932990 | 
2026-03-18 23:59:48.933108 | PLAY [all]
2026-03-18 23:59:48.949410 | 
2026-03-18 23:59:48.949487 | TASK [include_role : fetch-output]
2026-03-18 23:59:48.977471 | controller | ok
2026-03-18 23:59:48.992281 | 
2026-03-18 23:59:48.992357 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-18 23:59:49.026628 | controller | skipping: Conditional result was False
2026-03-18 23:59:49.031796 | 
2026-03-18 23:59:49.031864 | TASK [fetch-output : Set log path for single node]
2026-03-18 23:59:49.059227 | controller | ok
2026-03-18 23:59:49.063794 | 
2026-03-18 23:59:49.063861 | LOOP [fetch-output : Ensure local output dirs]
2026-03-18 23:59:49.348629 | controller -> localhost | ok: "/var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/logs"
2026-03-18 23:59:49.512307 | controller -> localhost | ok: "/var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/artifacts"
2026-03-18 23:59:49.679719 | controller -> localhost | ok: "/var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/docs"
2026-03-18 23:59:49.690773 | 
2026-03-18 23:59:49.690856 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-18 23:59:50.171627 | controller | ok
2026-03-18 23:59:50.171820 | controller | ok: All items complete
2026-03-18 23:59:50.171847 | 
2026-03-18 23:59:50.514290 | controller | ok
2026-03-18 23:59:50.867938 | controller | ok
2026-03-18 23:59:50.879873 | 
2026-03-18 23:59:50.879981 | TASK [include_role : fetch-output-openshift]
2026-03-18 23:59:50.892813 | controller | skipping: Conditional result was False
2026-03-18 23:59:50.897935 | 
2026-03-18 23:59:50.898004 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-18 23:59:51.207724 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005888
2026-03-18 23:59:51.376871 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005135
2026-03-18 23:59:51.408519 | 
2026-03-18 23:59:51.408599 | PLAY [all]
2026-03-18 23:59:51.420991 | 
2026-03-18 23:59:51.421058 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-18 23:59:51.802551 | controller | changed
2026-03-18 23:59:51.822347 | 
2026-03-18 23:59:51.822397 | PLAY RECAP
2026-03-18 23:59:51.822438 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-18 23:59:51.822459 | 
2026-03-18 23:59:51.888427 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-18 23:59:51.889069 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-18 23:59:52.386320 | 
2026-03-18 23:59:52.386411 | PLAY [localhost]
2026-03-18 23:59:52.401988 | 
2026-03-18 23:59:52.402056 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-18 23:59:52.671984 | localhost | changed
2026-03-18 23:59:52.676415 | 
2026-03-18 23:59:52.676500 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-18 23:59:52.692800 | localhost | ok
2026-03-18 23:59:52.699822 | 
2026-03-18 23:59:52.699887 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-18 23:59:52.959036 | localhost | changed
2026-03-18 23:59:52.964095 | 
2026-03-18 23:59:52.964161 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-18 23:59:53.446023 | localhost | changed
2026-03-18 23:59:53.450547 | 
2026-03-18 23:59:53.450621 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-18 23:59:53.761181 | localhost | Identity added: /var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/tmp/ansible.1b1d4nih (/var/lib/zuul/builds/65545c0fc2764f4d9efaaef5985f0a21/work/tmp/ansible.1b1d4nih)
2026-03-18 23:59:53.761368 | localhost | ok: Runtime: 0:00:00.006106
2026-03-18 23:59:53.765559 | 
2026-03-18 23:59:53.765632 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-18 23:59:53.975001 | localhost | ok: Runtime: 0:00:00.004155
2026-03-18 23:59:53.979565 | 
2026-03-18 23:59:53.979634 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-18 23:59:54.027899 | localhost | changed
2026-03-18 23:59:54.032611 | 
2026-03-18 23:59:54.032681 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-18 23:59:54.332968 | localhost | changed
2026-03-18 23:59:54.350771 | 
2026-03-18 23:59:54.350836 | PLAY [localhost]
2026-03-18 23:59:54.361011 | 
2026-03-18 23:59:54.361070 | TASK [Generate bulk log download script]
2026-03-18 23:59:54.378455 | localhost | ok
2026-03-18 23:59:54.388594 | 
2026-03-18 23:59:54.388658 | TASK [local-log-download : Check API endpoint is defined]
2026-03-18 23:59:54.415399 | localhost | ok: All assertions passed
2026-03-18 23:59:54.419662 | 
2026-03-18 23:59:54.419726 | TASK [local-log-download : Create download script]
2026-03-18 23:59:54.748479 | localhost -> localhost | changed
2026-03-18 23:59:54.755867 | 
2026-03-18 23:59:54.755930 | TASK [Register quick-download link]
2026-03-18 23:59:54.772389 | localhost | ok
2026-03-18 23:59:54.802572 | 
2026-03-18 23:59:54.802635 | PLAY [logserver.rdoproject.org]
2026-03-18 23:59:54.810896 | 
2026-03-18 23:59:54.810952 | TASK [Set zuul-log-path fact]
2026-03-18 23:59:54.826278 | logserver.rdoproject.org | ok
2026-03-18 23:59:54.834020 | 
2026-03-18 23:59:54.834084 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-18 23:59:54.849719 | logserver.rdoproject.org | ok
2026-03-18 23:59:54.854355 | 
2026-03-18 23:59:54.854413 | TASK [upload-logs : Create log directories]
2026-03-18 23:59:56.632216 | logserver.rdoproject.org | changed
2026-03-18 23:59:56.635112 | 
2026-03-18 23:59:56.635175 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-18 23:59:56.841255 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004323
2026-03-18 23:59:56.845566 | 
2026-03-18 23:59:56.845630 | TASK [upload-logs : Upload logs to log server]
2026-03-18 23:59:58.342954 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-18 23:59:58.345616 | 
2026-03-18 23:59:58.345680 | LOOP [upload-logs : Compress console log and json output]
2026-03-18 23:59:58.380634 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 23:59:58.387727 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 23:59:58.392556 | 
2026-03-18 23:59:58.392644 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-18 23:59:58.427127 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 23:59:58.427434 | 
2026-03-18 23:59:58.430381 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-18 23:59:58.439245 | 
2026-03-18 23:59:58.439350 | LOOP [upload-logs : Upload console log and json output]