2025-10-13 15:09:20.901512 | Job console starting...
2025-10-13 15:09:21.144400 | Updating repositories
2025-10-13 15:09:21.914173 | Preparing job workspace
2025-10-13 15:09:30.771404 | Running Ansible setup...
2025-10-13 15:09:34.017311 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 15:09:34.759890 | 
2025-10-13 15:09:34.760005 | PLAY [localhost]
2025-10-13 15:09:34.778988 | 
2025-10-13 15:09:34.779062 | TASK [Gathering Facts]
2025-10-13 15:09:35.669167 | localhost | ok
2025-10-13 15:09:35.682801 | 
2025-10-13 15:09:35.682886 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 15:09:36.021664 | localhost -> localhost | changed
2025-10-13 15:09:36.026975 | 
2025-10-13 15:09:36.027051 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 15:09:36.882553 | localhost -> localhost | changed
2025-10-13 15:09:36.891455 | 
2025-10-13 15:09:36.891546 | TASK [Setup log path fact]
2025-10-13 15:09:36.909736 | localhost | ok
2025-10-13 15:09:36.921580 | 
2025-10-13 15:09:36.921659 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 15:09:36.939622 | localhost | ok
2025-10-13 15:09:36.947865 | 
2025-10-13 15:09:36.947987 | TASK [emit-job-header : Print job information]
2025-10-13 15:09:36.977952 | # Job Information
2025-10-13 15:09:36.978128 | Ansible Version: 2.15.12
2025-10-13 15:09:36.978162 | Job: cifmw-molecule-operator_build
2025-10-13 15:09:36.978187 | Pipeline: github-check
2025-10-13 15:09:36.978208 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-13 15:09:36.978229 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 15:09:36.978248 | Log URL (when completed): https://logserver.rdoproject.org/9ea/rdoproject.org/9ead035e4da54c0d929bf7282bebf5e8/
2025-10-13 15:09:36.978268 | Event ID: 03ccc7c0-a844-11f0-9fcd-d5792746e07c
2025-10-13 15:09:36.982851 | 
2025-10-13 15:09:36.982957 | LOOP [emit-job-header : Print node information]
2025-10-13 15:09:37.110225 | localhost | ok:
2025-10-13 15:09:37.110376 | localhost | # Node Information
2025-10-13 15:09:37.110410 | localhost | Inventory Hostname: controller
2025-10-13 15:09:37.110436 | localhost | Hostname: np0005485470
2025-10-13 15:09:37.110458 | localhost | Username: zuul
2025-10-13 15:09:37.110481 | localhost | Distro: CentOS 9
2025-10-13 15:09:37.110501 | localhost | Provider: ibm-bm4-nodepool
2025-10-13 15:09:37.110520 | localhost | Region: regionOne
2025-10-13 15:09:37.110538 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-13 15:09:37.110557 | localhost | Product Name: OpenStack Compute
2025-10-13 15:09:37.110574 | localhost | Interface IP: 192.168.26.252
2025-10-13 15:09:37.145409 | 
2025-10-13 15:09:37.145474 | PLAY [all]
2025-10-13 15:09:37.163157 | 
2025-10-13 15:09:37.163225 | TASK [Gather network facts]
2025-10-13 15:09:37.570613 | controller | ok
2025-10-13 15:09:37.585719 | 
2025-10-13 15:09:37.585795 | TASK [include_role : start-zuul-console]
2025-10-13 15:09:37.614731 | controller | ok
2025-10-13 15:09:37.630569 | 
2025-10-13 15:09:37.630648 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 15:09:37.968209 | controller | ok
2025-10-13 15:09:37.976811 | 
2025-10-13 15:09:37.977997 | TASK [include_role : add-build-sshkey]
2025-10-13 15:09:37.996492 | controller | ok
2025-10-13 15:09:38.010954 | 
2025-10-13 15:09:38.011030 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 15:09:38.216299 | controller -> localhost | ok
2025-10-13 15:09:38.222858 | 
2025-10-13 15:09:38.223033 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 15:09:38.244658 | controller | ok
2025-10-13 15:09:38.258490 | controller | included: /var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 15:09:38.266416 | 
2025-10-13 15:09:38.266487 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 15:09:38.833123 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 15:09:38.833346 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/9ead035e4da54c0d929bf7282bebf5e8_id_rsa.
2025-10-13 15:09:38.833384 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/9ead035e4da54c0d929bf7282bebf5e8_id_rsa.pub.
2025-10-13 15:09:38.833410 | controller -> localhost | The key fingerprint is:
2025-10-13 15:09:38.833432 | controller -> localhost | SHA256:rDPNBcGYCD1ZsI+h/WjPHMDrshc1S7Qfhhzxt59+/HU zuul-build-sshkey
2025-10-13 15:09:38.833453 | controller -> localhost | The key's randomart image is:
2025-10-13 15:09:38.833472 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 15:09:38.833490 | controller -> localhost | |  .o.=o=.        |
2025-10-13 15:09:38.833510 | controller -> localhost | |    =.=...       |
2025-10-13 15:09:38.833529 | controller -> localhost | |    o+ +o .      |
2025-10-13 15:09:38.833548 | controller -> localhost | |   + +B.oo .     |
2025-10-13 15:09:38.833567 | controller -> localhost | |  . =o.=S.o      |
2025-10-13 15:09:38.833586 | controller -> localhost | |    .=.+.. . .   |
2025-10-13 15:09:38.833604 | controller -> localhost | |    +.* o   o.  E|
2025-10-13 15:09:38.833623 | controller -> localhost | |  .o.+ +   .  o o|
2025-10-13 15:09:38.833641 | controller -> localhost | |  .+. +     .. ..|
2025-10-13 15:09:38.833659 | controller -> localhost | +----[SHA256]-----+
2025-10-13 15:09:38.833705 | controller -> localhost | ok: Runtime: 0:00:00.123761
2025-10-13 15:09:38.839151 | 
2025-10-13 15:09:38.839216 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 15:09:38.867294 | controller | ok
2025-10-13 15:09:38.876994 | controller | included: /var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 15:09:38.884680 | 
2025-10-13 15:09:38.884743 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 15:09:38.898691 | controller | skipping: Conditional result was False
2025-10-13 15:09:38.905333 | 
2025-10-13 15:09:38.905405 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 15:09:39.305662 | controller | changed
2025-10-13 15:09:39.310827 | 
2025-10-13 15:09:39.310892 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 15:09:39.512660 | controller | ok
2025-10-13 15:09:39.518385 | 
2025-10-13 15:09:39.518455 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 15:09:40.127382 | controller | changed
2025-10-13 15:09:40.140698 | 
2025-10-13 15:09:40.140819 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 15:09:40.749524 | controller | changed
2025-10-13 15:09:40.755309 | 
2025-10-13 15:09:40.755394 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 15:09:40.769926 | controller | skipping: Conditional result was False
2025-10-13 15:09:40.776131 | 
2025-10-13 15:09:40.776203 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 15:09:41.104403 | controller -> localhost | changed
2025-10-13 15:09:41.114034 | 
2025-10-13 15:09:41.114112 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 15:09:41.386454 | controller -> localhost | Identity added: /var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/9ead035e4da54c0d929bf7282bebf5e8_id_rsa (zuul-build-sshkey)
2025-10-13 15:09:41.386627 | controller -> localhost | ok: Runtime: 0:00:00.006904
2025-10-13 15:09:41.392777 | 
2025-10-13 15:09:41.392848 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 15:09:41.703550 | controller | ok
2025-10-13 15:09:41.709294 | 
2025-10-13 15:09:41.709374 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 15:09:41.733689 | controller | skipping: Conditional result was False
2025-10-13 15:09:41.742874 | 
2025-10-13 15:09:41.742979 | TASK [include_role : validate-host]
2025-10-13 15:09:41.765286 | controller | ok
2025-10-13 15:09:41.797942 | 
2025-10-13 15:09:41.798030 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 15:09:41.819079 | controller | ok
2025-10-13 15:09:41.823468 | 
2025-10-13 15:09:41.823531 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 15:09:42.072280 | controller -> localhost | ok
2025-10-13 15:09:42.078302 | 
2025-10-13 15:09:42.078397 | TASK [validate-host : Collect information about the host]
2025-10-13 15:09:42.708639 | controller | ok
2025-10-13 15:09:42.740155 | 
2025-10-13 15:09:42.740248 | TASK [validate-host : Sanitize hostname]
2025-10-13 15:09:42.789293 | controller | ok
2025-10-13 15:09:42.794132 | 
2025-10-13 15:09:42.794197 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 15:09:43.183232 | controller -> localhost | changed
2025-10-13 15:09:43.188778 | 
2025-10-13 15:09:43.188857 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 15:09:43.542285 | controller | ok
2025-10-13 15:09:43.547196 | 
2025-10-13 15:09:43.547277 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 15:09:44.013361 | controller -> localhost | changed
2025-10-13 15:09:44.023603 | 
2025-10-13 15:09:44.023690 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 15:09:44.047590 | controller | skipping: Conditional result was False
2025-10-13 15:09:44.052765 | 
2025-10-13 15:09:44.052833 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 15:09:44.065878 | controller | skipping: Conditional result was False
2025-10-13 15:09:44.071773 | 
2025-10-13 15:09:44.071850 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 15:09:44.281436 | controller | ok: "logs"
2025-10-13 15:09:44.281646 | controller | ok: All items complete
2025-10-13 15:09:44.281676 | 
2025-10-13 15:09:44.455082 | controller | ok: "artifacts"
2025-10-13 15:09:44.627210 | controller | ok: "docs"
2025-10-13 15:09:44.640490 | 
2025-10-13 15:09:44.640568 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 15:09:44.839803 | controller | changed: "logs"
2025-10-13 15:09:45.022896 | controller | changed: "artifacts"
2025-10-13 15:09:45.201658 | controller | changed: "docs"
2025-10-13 15:09:45.226502 | 
2025-10-13 15:09:45.226576 | PLAY RECAP
2025-10-13 15:09:45.226619 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 15:09:45.226649 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 15:09:45.226669 | 
2025-10-13 15:09:45.305307 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 15:09:45.306162 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 15:09:45.875180 | 
2025-10-13 15:09:45.875288 | PLAY [all]
2025-10-13 15:09:45.895060 | 
2025-10-13 15:09:45.895145 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 15:09:45.943816 | controller | ok
2025-10-13 15:09:45.948897 | 
2025-10-13 15:09:45.948998 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 15:09:46.299233 | controller | changed
2025-10-13 15:09:46.305273 | 
2025-10-13 15:09:46.305379 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 15:09:47.195784 | controller | changed
2025-10-13 15:09:47.205146 | 
2025-10-13 15:09:47.205220 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 15:09:47.571577 | controller | changed:
2025-10-13 15:09:47.571740 | controller | {
2025-10-13 15:09:47.571769 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 15:09:47.571798 | controller | }
2025-10-13 15:09:47.786991 | controller | changed:
2025-10-13 15:09:47.787079 | controller | {
2025-10-13 15:09:47.787108 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 15:09:47.787130 | controller | }
2025-10-13 15:09:48.011184 | controller | changed:
2025-10-13 15:09:48.011266 | controller | {
2025-10-13 15:09:48.011295 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-13 15:09:48.011317 | controller | }
2025-10-13 15:09:48.223139 | controller | changed:
2025-10-13 15:09:48.224498 | controller | {
2025-10-13 15:09:48.224555 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 15:09:48.224583 | controller | }
2025-10-13 15:09:48.428732 | controller | changed:
2025-10-13 15:09:48.428815 | controller | {
2025-10-13 15:09:48.428842 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 15:09:48.428863 | controller | }
2025-10-13 15:09:48.671291 | controller | changed:
2025-10-13 15:09:48.671382 | controller | {
2025-10-13 15:09:48.671412 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 15:09:48.671435 | controller | }
2025-10-13 15:09:48.902828 | controller | changed:
2025-10-13 15:09:48.902925 | controller | {
2025-10-13 15:09:48.902961 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-13 15:09:48.902985 | controller | }
2025-10-13 15:09:49.117370 | controller | changed:
2025-10-13 15:09:49.117449 | controller | {
2025-10-13 15:09:49.117477 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-13 15:09:49.117499 | controller | }
2025-10-13 15:09:49.330982 | controller | changed:
2025-10-13 15:09:49.331067 | controller | {
2025-10-13 15:09:49.331095 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 15:09:49.331118 | controller | }
2025-10-13 15:09:49.555833 | controller | changed:
2025-10-13 15:09:49.555944 | controller | {
2025-10-13 15:09:49.555980 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 15:09:49.556005 | controller | }
2025-10-13 15:09:49.768896 | controller | changed:
2025-10-13 15:09:49.768995 | controller | {
2025-10-13 15:09:49.769023 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 15:09:49.769044 | controller | }
2025-10-13 15:09:49.990216 | controller | changed:
2025-10-13 15:09:49.990300 | controller | {
2025-10-13 15:09:49.990343 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 15:09:49.990367 | controller | }
2025-10-13 15:09:50.216833 | controller | changed:
2025-10-13 15:09:50.216931 | controller | {
2025-10-13 15:09:50.216963 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 15:09:50.216984 | controller | }
2025-10-13 15:09:50.454231 | controller | changed:
2025-10-13 15:09:50.454322 | controller | {
2025-10-13 15:09:50.454360 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 15:09:50.454381 | controller | }
2025-10-13 15:09:50.676306 | controller | changed:
2025-10-13 15:09:50.676400 | controller | {
2025-10-13 15:09:50.676432 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 15:09:50.676456 | controller | }
2025-10-13 15:09:50.887664 | controller | changed:
2025-10-13 15:09:50.887743 | controller | {
2025-10-13 15:09:50.887769 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 15:09:50.887788 | controller | }
2025-10-13 15:09:51.102900 | controller | changed:
2025-10-13 15:09:51.103057 | controller | {
2025-10-13 15:09:51.103101 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-13 15:09:51.103127 | controller | }
2025-10-13 15:09:51.318626 | controller | changed:
2025-10-13 15:09:51.318812 | controller | {
2025-10-13 15:09:51.318849 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-13 15:09:51.318881 | controller | }
2025-10-13 15:09:51.535903 | controller | changed:
2025-10-13 15:09:51.536017 | controller | {
2025-10-13 15:09:51.536045 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 15:09:51.536066 | controller | }
2025-10-13 15:09:51.748683 | controller | changed:
2025-10-13 15:09:51.748774 | controller | {
2025-10-13 15:09:51.748802 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 15:09:51.748824 | controller | }
2025-10-13 15:09:51.966766 | controller | changed:
2025-10-13 15:09:51.966855 | controller | {
2025-10-13 15:09:51.966883 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 15:09:51.966995 | controller | }
2025-10-13 15:09:52.191307 | controller | changed:
2025-10-13 15:09:52.191499 | controller | {
2025-10-13 15:09:52.191534 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 15:09:52.191556 | controller | }
2025-10-13 15:09:52.404766 | controller | changed:
2025-10-13 15:09:52.404960 | controller | {
2025-10-13 15:09:52.405002 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-13 15:09:52.405028 | controller | }
2025-10-13 15:09:52.631257 | controller | changed:
2025-10-13 15:09:52.631357 | controller | {
2025-10-13 15:09:52.631387 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 15:09:52.631409 | controller | }
2025-10-13 15:09:52.869105 | controller | changed:
2025-10-13 15:09:52.869194 | controller | {
2025-10-13 15:09:52.869220 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 15:09:52.869240 | controller | }
2025-10-13 15:09:53.088794 | controller | changed:
2025-10-13 15:09:53.088881 | controller | {
2025-10-13 15:09:53.088923 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 15:09:53.088950 | controller | }
2025-10-13 15:09:53.105104 | 
2025-10-13 15:09:53.105209 | TASK [Set timezone to UTC]
2025-10-13 15:09:53.529470 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 15:09:53.535039 | 
2025-10-13 15:09:53.535116 | TASK [Create nodepool directory]
2025-10-13 15:09:53.766235 | controller | changed
2025-10-13 15:09:53.771675 | 
2025-10-13 15:09:53.771758 | TASK [Create nodepool sub_nodes file]
2025-10-13 15:09:54.347309 | controller | changed
2025-10-13 15:09:54.352514 | 
2025-10-13 15:09:54.352647 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 15:09:54.929989 | controller | changed
2025-10-13 15:09:54.935088 | 
2025-10-13 15:09:54.935155 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 15:09:54.964187 | 
2025-10-13 15:09:54.964349 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 15:09:54.993154 | 
2025-10-13 15:09:54.993287 | TASK [Create nodepool primary file]
2025-10-13 15:09:55.017275 | controller | skipping: Conditional result was False
2025-10-13 15:09:55.022769 | 
2025-10-13 15:09:55.022849 | TASK [Create nodepool node_private for this node]
2025-10-13 15:09:55.631425 | controller | changed
2025-10-13 15:09:55.636947 | 
2025-10-13 15:09:55.637014 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 15:09:55.994109 | controller | ok: Item: id_rsa Runtime: 0:00:00.007949
2025-10-13 15:09:55.994275 | 
2025-10-13 15:09:56.167236 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004690
2025-10-13 15:09:56.173431 | 
2025-10-13 15:09:56.173544 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 15:09:56.760058 | controller | changed
2025-10-13 15:09:56.765164 | 
2025-10-13 15:09:56.765229 | TASK [Validate sudoers config after edits]
2025-10-13 15:09:57.005863 | controller | /etc/sudoers: parsed OK
2025-10-13 15:09:57.005934 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 15:09:57.005943 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 15:09:57.006001 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 15:09:57.293386 | controller | ok: Runtime: 0:00:00.005657
2025-10-13 15:09:57.300496 | 
2025-10-13 15:09:57.300599 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 15:09:57.525545 | controller | SHELL=/bin/bash
2025-10-13 15:09:57.525609 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 15:09:57.525619 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 15:09:57.525626 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/04/3404/9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8^openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/d28093521bfef49de97fe37ed681e49283c4131e
2025-10-13 15:09:57.525646 | controller | PWD=/home/zuul
2025-10-13 15:09:57.525710 | controller | ZUUL_PIPELINE=github-check
2025-10-13 15:09:57.525720 | controller | LOGNAME=zuul
2025-10-13 15:09:57.525726 | controller | XDG_SESSION_TYPE=tty
2025-10-13 15:09:57.525732 | controller | _=/usr/bin/env
2025-10-13 15:09:57.525737 | controller | MOTD_SHOWN=pam
2025-10-13 15:09:57.525743 | controller | HOME=/home/zuul
2025-10-13 15:09:57.525748 | controller | LANG=en_US.UTF-8
2025-10-13 15:09:57.525754 | controller | SSH_CONNECTION=192.168.26.12 44674 192.168.26.252 22
2025-10-13 15:09:57.525760 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-13 15:09:57.525766 | controller | ZUUL_CHANGE_IDS=3404,9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8 3402,d28093521bfef49de97fe37ed681e49283c4131e
2025-10-13 15:09:57.525772 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 15:09:57.525777 | controller | XDG_SESSION_CLASS=user
2025-10-13 15:09:57.525783 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 15:09:57.525788 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 15:09:57.525793 | controller | USER=zuul
2025-10-13 15:09:57.525799 | controller | ZUUL_VOTING=True
2025-10-13 15:09:57.525804 | controller | BUILD_TIMEOUT=1800000
2025-10-13 15:09:57.525810 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 15:09:57.525815 | controller | SHLVL=1
2025-10-13 15:09:57.525820 | controller | ZUUL_PATCHSET=d28093521bfef49de97fe37ed681e49283c4131e
2025-10-13 15:09:57.525826 | controller | XDG_SESSION_ID=1
2025-10-13 15:09:57.525831 | controller | ZUUL_BRANCH=main
2025-10-13 15:09:57.525837 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 15:09:57.525842 | controller | SSH_CLIENT=192.168.26.12 44674 22
2025-10-13 15:09:57.525847 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 15:09:57.525853 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 15:09:57.525858 | controller | which_declare=declare -f
2025-10-13 15:09:57.525865 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 15:09:57.525871 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 15:09:57.525877 | controller | ZUUL_CHANGE=3402
2025-10-13 15:09:57.525882 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 15:09:57.525887 | controller | ZUUL_UUID=9ead035e4da54c0d929bf7282bebf5e8
2025-10-13 15:09:57.525892 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 15:09:57.525898 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 15:09:57.525903 | controller | }
2025-10-13 15:09:57.829849 | controller | ok: Runtime: 0:00:00.007109
2025-10-13 15:09:57.835282 | 
2025-10-13 15:09:57.835360 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 15:09:57.858924 | controller | skipping: Conditional result was False
2025-10-13 15:09:57.864363 | 
2025-10-13 15:09:57.864433 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 15:09:58.390572 | controller | skipping: Conditional result was False
2025-10-13 15:09:58.404097 | 
2025-10-13 15:09:58.404191 | TASK [Ensure legacy workspace directory]
2025-10-13 15:09:58.603085 | controller | changed
2025-10-13 15:09:58.631759 | 
2025-10-13 15:09:58.631843 | PLAY RECAP
2025-10-13 15:09:58.631893 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 15:09:58.631939 | 
2025-10-13 15:09:58.729018 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 15:09:58.729806 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 15:09:59.250144 | 
2025-10-13 15:09:59.250256 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 15:09:59.281432 | 
2025-10-13 15:09:59.281537 | TASK [Create zuul-output directory]
2025-10-13 15:09:59.605507 | controller | changed
2025-10-13 15:09:59.610478 | 
2025-10-13 15:09:59.610575 | TASK [Slurp Zuul inventory test]
2025-10-13 15:09:59.908716 | controller -> localhost | ok
2025-10-13 15:09:59.915189 | 
2025-10-13 15:09:59.915258 | TASK [Save zuul inventory]
2025-10-13 15:10:00.718487 | controller | changed
2025-10-13 15:10:00.724012 | 
2025-10-13 15:10:00.724102 | TASK [Save zuul vars without the change_message]
2025-10-13 15:10:01.358847 | controller | changed
2025-10-13 15:10:01.384428 | 
2025-10-13 15:10:01.384518 | PLAY RECAP
2025-10-13 15:10:01.384564 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 15:10:01.384586 | 
2025-10-13 15:10:01.469628 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 15:10:01.470443 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 15:10:02.070803 | 
2025-10-13 15:10:02.070935 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 15:10:02.099285 | 
2025-10-13 15:10:02.099428 | TASK [Read group_vars]
2025-10-13 15:10:02.129668 | controller | ok
2025-10-13 15:10:02.147440 | 
2025-10-13 15:10:02.147534 | TASK [cifmw_helpers : Check directory is available]
2025-10-13 15:10:02.449481 | controller | ok
2025-10-13 15:10:02.456160 | 
2025-10-13 15:10:02.456231 | TASK [cifmw_helpers : Find yaml files]
2025-10-13 15:10:02.469265 | controller | skipping: Conditional result was False
2025-10-13 15:10:02.475002 | 
2025-10-13 15:10:02.475073 | TASK [cifmw_helpers : Print available yaml files]
2025-10-13 15:10:02.487858 | controller | skipping: Conditional result was False
2025-10-13 15:10:02.493469 | 
2025-10-13 15:10:02.493536 | LOOP [cifmw_helpers : Read vars]
2025-10-13 15:10:02.511592 | 
2025-10-13 15:10:02.511712 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 15:10:02.534962 | 
2025-10-13 15:10:02.535082 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 15:10:02.552053 | controller | ok
2025-10-13 15:10:02.571550 | 
2025-10-13 15:10:02.571626 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 15:10:02.594744 | controller | skipping: Conditional result was False
2025-10-13 15:10:02.600131 | 
2025-10-13 15:10:02.600208 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 15:10:02.946412 | controller | ok
2025-10-13 15:10:02.953260 | 
2025-10-13 15:10:02.953340 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 15:10:03.528550 | controller | ok
2025-10-13 15:10:03.538526 | 
2025-10-13 15:10:03.538595 | TASK [Prepare workspace]
2025-10-13 15:10:03.556229 | controller | ok
2025-10-13 15:10:03.597892 | 
2025-10-13 15:10:03.597990 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 15:10:03.890371 | controller | ok
2025-10-13 15:10:03.897606 | 
2025-10-13 15:10:03.897709 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 15:10:06.277941 | controller | Output suppressed because no_log was given
2025-10-13 15:10:06.287674 | 
2025-10-13 15:10:06.287746 | LOOP [Create zuul-output directory]
2025-10-13 15:10:06.490719 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-13 15:10:06.490972 | 
2025-10-13 15:10:06.660873 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-13 15:10:06.676231 | 
2025-10-13 15:10:06.676344 | TASK [Install required packages]
2025-10-13 15:11:11.897535 | controller | changed
2025-10-13 15:11:11.903173 | 
2025-10-13 15:11:11.903258 | TASK [Install venv]
2025-10-13 15:12:16.245102 | controller | changed
2025-10-13 15:12:16.269897 | 
2025-10-13 15:12:16.270000 | PLAY RECAP
2025-10-13 15:12:16.270044 | controller | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 15:12:16.270068 | 
2025-10-13 15:12:16.347027 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 15:12:16.348062 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 15:12:16.896260 | 
2025-10-13 15:12:16.896382 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-13 15:12:16.918951 | 
2025-10-13 15:12:16.919056 | TASK [Gather required facts]
2025-10-13 15:12:17.406042 | controller | ok
2025-10-13 15:12:17.412777 | 
2025-10-13 15:12:17.412863 | TASK [Load environment var if instructed to]
2025-10-13 15:12:17.437743 | controller | skipping: Conditional result was False
2025-10-13 15:12:17.443341 | 
2025-10-13 15:12:17.443415 | TASK [Ensure group_vars dir exists]
2025-10-13 15:12:17.766237 | controller | ok
2025-10-13 15:12:17.772562 | 
2025-10-13 15:12:17.772630 | TASK [Run molecule]
2025-10-13 15:12:18.740690 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-13 15:12:18.814953 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-13 15:12:37.124603 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.125175 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.125692 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.126179 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.126670 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+19db912e was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.0.0+19db912e (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.127142 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.127596 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.128030 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.128513 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.128975 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.129422 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.129877 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.130320 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.130764 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.131213 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 15:12:37.141398 | controller | INFO     Running default > prepare
2025-10-13 15:12:37.826506 | controller |
2025-10-13 15:12:37.826623 | controller | PLAY [Prepare] *****************************************************************
2025-10-13 15:12:37.826719 | controller |
2025-10-13 15:12:37.826823 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 15:12:37.826924 | controller | Monday 13 October 2025  15:12:37 +0000 (0:00:00.013)       0:00:00.013 ********
2025-10-13 15:12:38.683129 | controller | ok: [instance]
2025-10-13 15:12:38.683184 | controller |
2025-10-13 15:12:38.683279 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-13 15:12:38.683391 | controller | Monday 13 October 2025  15:12:38 +0000 (0:00:00.857)       0:00:00.870 ********
2025-10-13 15:12:38.701344 | controller | skipping: [instance]
2025-10-13 15:12:38.701464 | controller |
2025-10-13 15:12:38.701614 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-13 15:12:38.701745 | controller | Monday 13 October 2025  15:12:38 +0000 (0:00:00.018)       0:00:00.889 ********
2025-10-13 15:12:38.743961 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-13 15:12:38.744038 | controller |
2025-10-13 15:12:38.744178 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-13 15:12:38.744283 | controller | Monday 13 October 2025  15:12:38 +0000 (0:00:00.042)       0:00:00.931 ********
2025-10-13 15:12:39.042082 | controller | ok: [instance]
2025-10-13 15:12:39.042153 | controller |
2025-10-13 15:12:39.042265 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-13 15:12:39.042376 | controller | Monday 13 October 2025  15:12:39 +0000 (0:00:00.297)       0:00:01.229 ********
2025-10-13 15:12:39.369173 | controller | ok: [instance]
2025-10-13 15:12:39.369251 | controller |
2025-10-13 15:12:39.369364 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-13 15:12:39.369472 | controller | Monday 13 October 2025  15:12:39 +0000 (0:00:00.327)       0:00:01.556 ********
2025-10-13 15:12:40.105641 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-13 15:12:40.105735 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-13 15:12:40.105841 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-13 15:12:40.105939 | controller |
2025-10-13 15:12:40.106038 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-13 15:12:40.106168 | controller | Monday 13 October 2025  15:12:40 +0000 (0:00:00.736)       0:00:02.293 ********
2025-10-13 15:12:40.166115 | controller |
2025-10-13 15:12:40.166201 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-13 15:12:40.166306 | controller | Monday 13 October 2025  15:12:40 +0000 (0:00:00.060)       0:00:02.353 ********
2025-10-13 15:12:40.753948 | controller | changed: [instance] => (item=tmp)
2025-10-13 15:12:40.754028 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-13 15:12:40.754159 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-13 15:12:40.754254 | controller |
2025-10-13 15:12:40.754353 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-13 15:12:40.754462 | controller | Monday 13 October 2025  15:12:40 +0000 (0:00:00.587)       0:00:02.941 ********
2025-10-13 15:12:41.520573 | controller | ok: [instance]
2025-10-13 15:12:41.520886 | controller |
2025-10-13 15:12:42.740727 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-13 15:12:42.740757 | controller | Monday 13 October 2025  15:12:41 +0000 (0:00:00.766)       0:00:03.708 ********
2025-10-13 15:12:42.740769 | controller | changed: [instance]
2025-10-13 15:12:50.773180 | controller |
2025-10-13 15:12:50.773216 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-13 15:12:50.773228 | controller | Monday 13 October 2025  15:12:42 +0000 (0:00:01.219)       0:00:04.928 ********
2025-10-13 15:12:50.773238 | controller | changed: [instance]
2025-10-13 15:12:50.773260 | controller |
2025-10-13 15:12:50.773274 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-13 15:12:50.773282 | controller | Monday 13 October 2025  15:12:50 +0000 (0:00:08.032)       0:00:12.960 ********
2025-10-13 15:12:51.478617 | controller | changed: [instance]
2025-10-13 15:12:51.499547 | controller |
2025-10-13 15:12:51.499569 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-13 15:12:51.499578 | controller | Monday 13 October 2025  15:12:51 +0000 (0:00:00.705)       0:00:13.666 ********
2025-10-13 15:12:51.499587 | controller | skipping: [instance]
2025-10-13 15:12:52.351126 | controller |
2025-10-13 15:12:52.351157 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-13 15:12:52.351166 | controller | Monday 13 October 2025  15:12:51 +0000 (0:00:00.021)       0:00:13.687 ********
2025-10-13 15:12:52.351176 | controller | changed: [instance]
2025-10-13 15:12:52.385535 | controller |
2025-10-13 15:12:52.385561 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-13 15:12:52.385569 | controller | Monday 13 October 2025  15:12:52 +0000 (0:00:00.851)       0:00:14.538 ********
2025-10-13 15:12:52.385579 | controller | skipping: [instance]
2025-10-13 15:12:52.420232 | controller |
2025-10-13 15:12:52.420271 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-13 15:12:52.420281 | controller | Monday 13 October 2025  15:12:52 +0000 (0:00:00.034)       0:00:14.573 ********
2025-10-13 15:12:52.420294 | controller | skipping: [instance]
2025-10-13 15:12:52.420542 | controller |
2025-10-13 15:12:52.420565 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-13 15:12:52.420996 | controller | Monday 13 October 2025  15:12:52 +0000 (0:00:00.034)       0:00:14.608 ********
2025-10-13 15:12:52.455199 | controller | skipping: [instance]
2025-10-13 15:12:52.455397 | controller |
2025-10-13 15:12:52.455424 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-13 15:12:53.037684 | controller | Monday 13 October 2025  15:12:52 +0000 (0:00:00.035)       0:00:14.643 ********
2025-10-13 15:12:53.037719 | controller | changed: [instance]
2025-10-13 15:12:53.518943 | controller |
2025-10-13 15:12:53.518982 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-13 15:12:53.518992 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.581)       0:00:15.225 ********
2025-10-13 15:12:53.519002 | controller | changed: [instance]
2025-10-13 15:12:53.548025 | controller |
2025-10-13 15:12:53.548051 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-13 15:12:53.548060 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.481)       0:00:15.706 ********
2025-10-13 15:12:53.548072 | controller | skipping: [instance]
2025-10-13 15:12:53.577044 | controller |
2025-10-13 15:12:53.577064 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-13 15:12:53.577071 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.029)       0:00:15.735 ********
2025-10-13 15:12:53.577086 | controller | skipping: [instance]
2025-10-13 15:12:53.606248 | controller |
2025-10-13 15:12:53.606299 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-13 15:12:53.606308 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.029)       0:00:15.764 ********
2025-10-13 15:12:53.606320 | controller | skipping: [instance]
2025-10-13 15:12:53.606347 | controller |
2025-10-13 15:12:53.606380 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-13 15:12:53.606402 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.029)       0:00:15.794 ********
2025-10-13 15:12:53.646225 | controller | ok: [instance]
2025-10-13 15:12:53.674593 | controller |
2025-10-13 15:12:53.674633 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-13 15:12:53.674642 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.039)       0:00:15.833 ********
2025-10-13 15:12:53.674653 | controller | skipping: [instance]
2025-10-13 15:12:53.702957 | controller |
2025-10-13 15:12:53.702997 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-13 15:12:53.703006 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.028)       0:00:15.862 ********
2025-10-13 15:12:53.703027 | controller | skipping: [instance]
2025-10-13 15:12:53.731218 | controller |
2025-10-13 15:12:53.731262 | controller | TASK [Download the RPM] ********************************************************
2025-10-13 15:12:53.731272 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.028)       0:00:15.890 ********
2025-10-13 15:12:53.731285 | controller | skipping: [instance]
2025-10-13 15:12:53.764821 | controller |
2025-10-13 15:12:53.764865 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-13 15:12:53.764874 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.028)       0:00:15.919 ********
2025-10-13 15:12:53.764887 | controller | skipping: [instance]
2025-10-13 15:12:53.812119 | controller |
2025-10-13 15:12:53.812160 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-13 15:12:53.812168 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.033)       0:00:15.952 ********
2025-10-13 15:12:53.812181 | controller | skipping: [instance]
2025-10-13 15:12:53.841922 | controller |
2025-10-13 15:12:53.841953 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-13 15:12:53.841961 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.047)       0:00:15.999 ********
2025-10-13 15:12:53.841972 | controller | skipping: [instance]
2025-10-13 15:12:53.871201 | controller |
2025-10-13 15:12:53.871239 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-13 15:12:53.871247 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.029)       0:00:16.029 ********
2025-10-13 15:12:53.871258 | controller | skipping: [instance]
2025-10-13 15:12:54.067116 | controller |
2025-10-13 15:12:54.067148 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-13 15:12:54.067157 | controller | Monday 13 October 2025  15:12:53 +0000 (0:00:00.029)       0:00:16.058 ********
2025-10-13 15:12:54.067167 | controller | ok: [instance]
2025-10-13 15:12:54.296316 | controller |
2025-10-13 15:12:54.296348 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-13 15:12:54.296356 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.195)       0:00:16.254 ********
2025-10-13 15:12:54.296366 | controller | changed: [instance]
2025-10-13 15:12:54.535209 | controller |
2025-10-13 15:12:54.535239 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-13 15:12:54.535247 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.229)       0:00:16.483 ********
2025-10-13 15:12:54.535258 | controller | changed: [instance]
2025-10-13 15:12:54.535280 | controller |
2025-10-13 15:12:54.535288 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-13 15:12:54.535382 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.239)       0:00:16.722 ********
2025-10-13 15:12:54.557069 | controller | skipping: [instance]
2025-10-13 15:12:54.580092 | controller |
2025-10-13 15:12:54.580141 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-13 15:12:54.580149 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.021)       0:00:16.744 ********
2025-10-13 15:12:54.580159 | controller | skipping: [instance]
2025-10-13 15:12:54.602953 | controller |
2025-10-13 15:12:54.602985 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-13 15:12:54.602993 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.022)       0:00:16.767 ********
2025-10-13 15:12:54.603004 | controller | skipping: [instance]
2025-10-13 15:12:54.625039 | controller |
2025-10-13 15:12:54.625070 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-13 15:12:54.625078 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.022)       0:00:16.790 ********
2025-10-13 15:12:54.625088 | controller | skipping: [instance]
2025-10-13 15:12:54.625260 | controller |
2025-10-13 15:12:54.625414 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-13 15:12:54.625577 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.022)       0:00:16.813 ********
2025-10-13 15:12:54.647940 | controller | skipping: [instance]
2025-10-13 15:12:54.648159 | controller |
2025-10-13 15:12:54.648361 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-13 15:12:54.648536 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.022)       0:00:16.835 ********
2025-10-13 15:12:54.670401 | controller | skipping: [instance]
2025-10-13 15:12:54.670567 | controller |
2025-10-13 15:12:54.670710 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-13 15:12:54.670856 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.022)       0:00:16.858 ********
2025-10-13 15:12:54.687229 | controller | skipping: [instance]
2025-10-13 15:12:54.687374 | controller |
2025-10-13 15:12:54.687526 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-13 15:12:54.687665 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.016)       0:00:16.875 ********
2025-10-13 15:12:54.720284 | controller | skipping: [instance]
2025-10-13 15:12:54.738251 | controller |
2025-10-13 15:12:54.738284 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-13 15:12:54.738292 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.032)       0:00:16.907 ********
2025-10-13 15:12:54.738303 | controller | skipping: [instance]
2025-10-13 15:12:54.759209 | controller |
2025-10-13 15:12:54.759244 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-13 15:12:54.759254 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.018)       0:00:16.926 ********
2025-10-13 15:12:54.759266 | controller | skipping: [instance]
2025-10-13 15:12:54.789177 | controller |
2025-10-13 15:12:54.789209 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-13 15:12:54.789218 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.020)       0:00:16.946 ********
2025-10-13 15:12:54.789228 | controller | skipping: [instance]
2025-10-13 15:12:54.818927 | controller |
2025-10-13 15:12:54.818957 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-13 15:12:54.818966 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.029)       0:00:16.976 ********
2025-10-13 15:12:54.818976 | controller | skipping: [instance]
2025-10-13 15:12:54.849653 | controller |
2025-10-13 15:12:54.849684 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-13 15:12:54.849693 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.029)       0:00:17.006 ********
2025-10-13 15:12:54.849703 | controller | skipping: [instance]
2025-10-13 15:12:54.879944 | controller |
2025-10-13 15:12:54.879976 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-13 15:12:54.879984 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.030)       0:00:17.037 ********
2025-10-13 15:12:54.879995 | controller | skipping: [instance]
2025-10-13 15:14:00.672443 | controller |
2025-10-13 15:14:00.672481 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-13 15:14:00.672489 | controller | Monday 13 October 2025  15:12:54 +0000 (0:00:00.030)       0:00:17.067 ********
2025-10-13 15:14:00.672501 | controller | ok: [instance]
2025-10-13 15:14:01.821003 | controller |
2025-10-13 15:14:01.821036 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-13 15:14:01.821044 | controller | Monday 13 October 2025  15:14:00 +0000 (0:01:05.792)       0:01:22.859 ********
2025-10-13 15:14:01.821055 | controller | ok: [instance]
2025-10-13 15:14:01.866188 | controller |
2025-10-13 15:14:01.866218 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-13 15:14:01.866226 | controller | Monday 13 October 2025  15:14:01 +0000 (0:00:01.148)       0:01:24.008 ********
2025-10-13 15:14:01.866237 | controller | skipping: [instance]
2025-10-13 15:14:01.867480 | controller |
2025-10-13 15:14:01.867498 | controller | PLAY RECAP *********************************************************************
2025-10-13 15:14:01.867505 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-13 15:14:01.867511 | controller |
2025-10-13 15:14:01.867517 | controller | Monday 13 October 2025  15:14:01 +0000 (0:00:00.044)       0:01:24.052 ********
2025-10-13 15:14:01.867522 | controller | ===============================================================================
2025-10-13 15:14:01.867528 | controller | test_deps : Install selinux python libs -------------------------------- 65.79s
2025-10-13 15:14:01.867540 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.03s
2025-10-13 15:14:01.867545 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.22s
2025-10-13 15:14:01.867551 | controller | test_deps : Install python yaml libs ------------------------------------ 1.15s
2025-10-13 15:14:01.867560 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2025-10-13 15:14:01.867566 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.85s
2025-10-13 15:14:01.867575 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-10-13 15:14:01.926130 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.74s
2025-10-13 15:14:01.926161 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-10-13 15:14:01.926169 | controller | repo_setup : Ensure directories are present ----------------------------- 0.59s
2025-10-13 15:14:01.926174 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-10-13 15:14:01.926179 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.48s
2025-10-13 15:14:01.926185 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.33s
2025-10-13 15:14:01.926190 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.30s
2025-10-13 15:14:01.926195 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.24s
2025-10-13 15:14:01.926201 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.23s
2025-10-13 15:14:01.926206 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.20s
2025-10-13 15:14:01.926211 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-13 15:14:01.926217 | controller | repo_setup : Get rhos-release tool version ------------------------------ 0.05s
2025-10-13 15:14:01.926222 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-10-13 15:14:01.926232 | controller | INFO     Running default > converge
2025-10-13 15:14:02.349673 | controller |
2025-10-13 15:14:02.349907 | controller | PLAY [Converge] ****************************************************************
2025-10-13 15:14:02.350052 | controller |
2025-10-13 15:14:02.350232 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 15:14:02.350404 | controller | Monday 13 October 2025  15:14:02 +0000 (0:00:00.013)       0:00:00.013 ********
2025-10-13 15:14:03.145067 | controller | ok: [instance]
2025-10-13 15:14:04.190656 | controller |
2025-10-13 15:14:04.190694 | controller | TASK [Get open PR list for mariadb-operator] ***********************************
2025-10-13 15:14:04.190702 | controller | Monday 13 October 2025  15:14:03 +0000 (0:00:00.795)       0:00:00.809 ********
2025-10-13 15:14:04.190713 | controller | ok: [instance]
2025-10-13 15:14:04.231085 | controller |
2025-10-13 15:14:04.231130 | controller | TASK [Extract latest PR from list] *********************************************
2025-10-13 15:14:04.231139 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:01.045)       0:00:01.854 ********
2025-10-13 15:14:04.231149 | controller | ok: [instance]
2025-10-13 15:14:04.260016 | controller |
2025-10-13 15:14:04.260039 | controller | TASK [Build keystone-operator] *************************************************
2025-10-13 15:14:04.260046 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.040)       0:00:01.895 ********
2025-10-13 15:14:04.260056 | controller |
2025-10-13 15:14:04.717511 | controller | TASK [operator_build : Ensure mandatory directories exist] *********************
2025-10-13 15:14:04.717543 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.029)       0:00:01.924 ********
2025-10-13 15:14:04.717557 | controller | ok: [instance] => (item=artifacts)
2025-10-13 15:14:04.738185 | controller | ok: [instance] => (item=logs)
2025-10-13 15:14:04.738215 | controller |
2025-10-13 15:14:04.738223 | controller | TASK [operator_build : Initialize role output] *********************************
2025-10-13 15:14:04.738229 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.457)       0:00:02.381 ********
2025-10-13 15:14:04.738246 | controller | ok: [instance]
2025-10-13 15:14:04.738320 | controller |
2025-10-13 15:14:04.738330 | controller | TASK [operator_build : Populate operators list with zuul info] *****************
2025-10-13 15:14:04.738338 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.020)       0:00:02.402 ********
2025-10-13 15:14:04.760201 | controller | skipping: [instance]
2025-10-13 15:14:04.760342 | controller |
2025-10-13 15:14:04.760365 | controller | TASK [operator_build : Merge lists of operators] *******************************
2025-10-13 15:14:04.760383 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.022)       0:00:02.424 ********
2025-10-13 15:14:04.810192 | controller | ok: [instance]
2025-10-13 15:14:04.810448 | controller |
2025-10-13 15:14:04.810483 | controller | TASK [operator_build : Get meta_operator src dir from operators_list] **********
2025-10-13 15:14:04.848469 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.050)       0:00:02.474 ********
2025-10-13 15:14:04.848498 | controller | ok: [instance]
2025-10-13 15:14:04.894410 | controller |
2025-10-13 15:14:04.894433 | controller | TASK [operator_build : Adds meta-operator to the list] *************************
2025-10-13 15:14:04.894441 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.038)       0:00:02.512 ********
2025-10-13 15:14:04.894451 | controller | ok: [instance]
2025-10-13 15:14:04.928305 | controller |
2025-10-13 15:14:04.928328 | controller | TASK [operator_build : Clone operator's code when src dir is empty] ************
2025-10-13 15:14:04.928336 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.045)       0:00:02.558 ********
2025-10-13 15:14:04.928346 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/clone.yml for instance => (item={'name': 'keystone-operator', 'src': '/home/zuul/keystone-operator'})
2025-10-13 15:14:05.187178 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/clone.yml for instance => (item={'name': 'openstack-operator', 'src': '/home/zuul/openstack-operator'})
2025-10-13 15:14:05.187212 | controller |
2025-10-13 15:14:05.187221 | controller | TASK [operator_build : keystone-operator - Check if src code exists] ***********
2025-10-13 15:14:05.187227 | controller | Monday 13 October 2025  15:14:04 +0000 (0:00:00.033)       0:00:02.592 ********
2025-10-13 15:14:05.187239 | controller | ok: [instance]
2025-10-13 15:14:05.187261 | controller |
2025-10-13 15:14:05.187291 | controller | TASK [operator_build : keystone-operator - Clone from github if no code was provided] ***
2025-10-13 15:14:05.187300 | controller | Monday 13 October 2025  15:14:05 +0000 (0:00:00.258)       0:00:02.851 ********
2025-10-13 15:14:06.801726 | controller | changed: [instance]
2025-10-13 15:14:06.991740 | controller |
2025-10-13 15:14:06.991773 | controller | TASK [operator_build : openstack-operator - Check if src code exists] **********
2025-10-13 15:14:06.991782 | controller | Monday 13 October 2025  15:14:06 +0000 (0:00:01.614)       0:00:04.465 ********
2025-10-13 15:14:06.991793 | controller | ok: [instance]
2025-10-13 15:14:09.709494 | controller |
2025-10-13 15:14:09.709527 | controller | TASK [operator_build : openstack-operator - Clone from github if no code was provided] ***
2025-10-13 15:14:09.709535 | controller | Monday 13 October 2025  15:14:06 +0000 (0:00:00.189)       0:00:04.655 ********
2025-10-13 15:14:09.709546 | controller | changed: [instance]
2025-10-13 15:14:09.756083 | controller |
2025-10-13 15:14:09.756135 | controller | TASK [operator_build : Building operators] *************************************
2025-10-13 15:14:09.756145 | controller | Monday 13 October 2025  15:14:09 +0000 (0:00:02.717)       0:00:07.373 ********
2025-10-13 15:14:09.756155 | controller | skipping: [instance] => (item={'name': 'openstack-operator', 'src': '/home/zuul/openstack-operator'})
2025-10-13 15:14:10.029187 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/build.yml for instance => (item={'name': 'keystone-operator', 'src': '/home/zuul/keystone-operator'})
2025-10-13 15:14:10.029219 | controller |
2025-10-13 15:14:10.029226 | controller | TASK [operator_build : keystone-operator - Read go.mod file contents of Openstack Operator] ***
2025-10-13 15:14:10.029233 | controller | Monday 13 October 2025  15:14:09 +0000 (0:00:00.046)       0:00:07.420 ********
2025-10-13 15:14:10.029242 | controller | ok: [instance]
2025-10-13 15:14:10.029531 | controller |
2025-10-13 15:14:10.062617 | controller | TASK [operator_build : keystone-operator - Get base module from go.mod] ********
2025-10-13 15:14:10.062654 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.273)       0:00:07.693 ********
2025-10-13 15:14:10.062668 | controller | ok: [instance]
2025-10-13 15:14:10.095872 | controller |
2025-10-13 15:14:10.095896 | controller | TASK [operator_build : Get the base module name not empty operator_base_module] ***
2025-10-13 15:14:10.095905 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.033)       0:00:07.726 ********
2025-10-13 15:14:10.095916 | controller | ok: [instance]
2025-10-13 15:14:10.121187 | controller |
2025-10-13 15:14:10.121222 | controller | TASK [operator_build : keystone-operator - Set default api path] ***************
2025-10-13 15:14:10.121231 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.033)       0:00:07.760 ********
2025-10-13 15:14:10.121242 | controller | ok: [instance]
2025-10-13 15:14:10.121282 | controller |
2025-10-13 15:14:10.121547 | controller | TASK [operator_build : keystone-operator - Update the go.mod file in meta operator for provided PR_SHA] ***
2025-10-13 15:14:10.146431 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.025)       0:00:07.785 ********
2025-10-13 15:14:10.146455 | controller | skipping: [instance]
2025-10-13 15:14:10.429876 | controller |
2025-10-13 15:14:10.429909 | controller | TASK [operator_build : keystone-operator - Get latest commit when no PR is provided] ***
2025-10-13 15:14:10.429917 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.024)       0:00:07.810 ********
2025-10-13 15:14:10.429928 | controller | changed: [instance]
2025-10-13 15:14:10.463041 | controller |
2025-10-13 15:14:10.463068 | controller | TASK [operator_build : keystone-operator - Set pr_sha to be used as image tag] ***
2025-10-13 15:14:10.463076 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.283)       0:00:08.093 ********
2025-10-13 15:14:10.463086 | controller | ok: [instance]
2025-10-13 15:14:10.658607 | controller |
2025-10-13 15:14:10.658639 | controller | TASK [operator_build : keystone-operator - Update the go.mod file using latest commit if no PR is provided] ***
2025-10-13 15:14:10.658648 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.033)       0:00:08.127 ********
2025-10-13 15:14:10.658658 | controller | changed: [instance]
2025-10-13 15:14:22.142468 | controller |
2025-10-13 15:14:22.142515 | controller | TASK [operator_build : keystone-operator - Get golang container image] *********
2025-10-13 15:14:22.142529 | controller | Monday 13 October 2025  15:14:10 +0000 (0:00:00.195)       0:00:08.322 ********
2025-10-13 15:14:22.142541 | controller | changed: [instance]
2025-10-13 15:14:22.166191 | controller |
2025-10-13 15:14:22.166236 | controller | TASK [operator_build : keystone-operator - Set operator image tag] *************
2025-10-13 15:14:22.166245 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:11.483)       0:00:19.806 ********
2025-10-13 15:14:22.166256 | controller | ok: [instance]
2025-10-13 15:14:22.189082 | controller |
2025-10-13 15:14:22.189135 | controller | TASK [operator_build : keystone-operator - Set operator image names] ***********
2025-10-13 15:14:22.189146 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.024)       0:00:19.830 ********
2025-10-13 15:14:22.189157 | controller | ok: [instance]
2025-10-13 15:14:22.222237 | controller |
2025-10-13 15:14:22.222273 | controller | TASK [operator_build : keystone-operator - Set operator build output] **********
2025-10-13 15:14:22.222283 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.022)       0:00:19.853 ********
2025-10-13 15:14:22.222295 | controller | ok: [instance]
2025-10-13 15:14:22.222326 | controller |
2025-10-13 15:14:22.222509 | controller | TASK [operator_build : keystone-operator - Call manifests] *********************
2025-10-13 15:14:22.273603 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.033)       0:00:19.886 ********
2025-10-13 15:14:22.273629 | controller | ok: [instance]
2025-10-13 15:14:22.292150 | controller |
2025-10-13 15:14:22.292169 | controller | TASK [operator_build : keystone-operator - Call bindata] ***********************
2025-10-13 15:14:22.292177 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.050)       0:00:19.937 ********
2025-10-13 15:14:22.292186 | controller | skipping: [instance]
2025-10-13 15:14:22.292335 | controller |
2025-10-13 15:14:22.292346 | controller | TASK [operator_build : keystone-operator - Call docker-build] ******************
2025-10-13 15:14:22.292356 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.018)       0:00:19.956 ********
2025-10-13 15:14:22.342539 | controller | ok: [instance]
2025-10-13 15:14:22.370081 | controller |
2025-10-13 15:14:22.370123 | controller | TASK [operator_build : keystone-operator - Call docker-push] *******************
2025-10-13 15:14:22.370137 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.050)       0:00:20.006 ********
2025-10-13 15:14:22.370149 | controller | skipping: [instance]
2025-10-13 15:14:22.424065 | controller |
2025-10-13 15:14:22.424089 | controller | TASK [operator_build : keystone-operator - Call bundle] ************************
2025-10-13 15:14:22.424112 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.027)       0:00:20.034 ********
2025-10-13 15:14:22.424126 | controller | ok: [instance]
2025-10-13 15:14:22.477465 | controller |
2025-10-13 15:14:22.477487 | controller | TASK [operator_build : keystone-operator - Call bundle-build] ******************
2025-10-13 15:14:22.477494 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.054)       0:00:20.088 ********
2025-10-13 15:14:22.477504 | controller | ok: [instance]
2025-10-13 15:14:22.506074 | controller |
2025-10-13 15:14:22.506095 | controller | TASK [operator_build : keystone-operator - Push bundle image] ******************
2025-10-13 15:14:22.506127 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.053)       0:00:20.141 ********
2025-10-13 15:14:22.506139 | controller | skipping: [instance]
2025-10-13 15:14:22.560819 | controller |
2025-10-13 15:14:22.560843 | controller | TASK [operator_build : keystone-operator - Call catalog-build] *****************
2025-10-13 15:14:22.560851 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.028)       0:00:20.170 ********
2025-10-13 15:14:22.560860 | controller | ok: [instance]
2025-10-13 15:14:22.587666 | controller |
2025-10-13 15:14:22.587684 | controller | TASK [operator_build : keystone-operator - Call catalog-push] ******************
2025-10-13 15:14:22.587691 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.054)       0:00:20.225 ********
2025-10-13 15:14:22.587700 | controller | skipping: [instance]
2025-10-13 15:14:22.646244 | controller |
2025-10-13 15:14:22.646274 | controller | TASK [operator_build : Building meta operator] *********************************
2025-10-13 15:14:22.646284 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.026)       0:00:20.251 ********
2025-10-13 15:14:22.646295 | controller | skipping: [instance] => (item={'name': 'keystone-operator', 'src': '/home/zuul/keystone-operator'})
2025-10-13 15:14:22.646326 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/build.yml for instance => (item={'name': 'openstack-operator', 'src': '/home/zuul/openstack-operator'})
2025-10-13 15:14:22.646658 | controller |
2025-10-13 15:14:22.666157 | controller | TASK [operator_build : openstack-operator - Read go.mod file contents of Openstack Operator] ***
2025-10-13 15:14:22.666179 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.058)       0:00:20.310 ********
2025-10-13 15:14:22.666192 | controller | skipping: [instance]
2025-10-13 15:14:22.666401 | controller |
2025-10-13 15:14:22.666415 | controller | TASK [operator_build : openstack-operator - Get base module from go.mod] *******
2025-10-13 15:14:22.666425 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.019)       0:00:20.330 ********
2025-10-13 15:14:22.684579 | controller | skipping: [instance]
2025-10-13 15:14:22.705861 | controller |
2025-10-13 15:14:22.705881 | controller | TASK [operator_build : Get the base module name not empty operator_base_module] ***
2025-10-13 15:14:22.705895 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.018)       0:00:20.348 ********
2025-10-13 15:14:22.705905 | controller | skipping: [instance]
2025-10-13 15:14:22.745190 | controller |
2025-10-13 15:14:22.745237 | controller | TASK [operator_build : openstack-operator - Set default api path] **************
2025-10-13 15:14:22.745248 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.021)       0:00:20.370 ********
2025-10-13 15:14:22.745260 | controller | skipping: [instance]
2025-10-13 15:14:22.764854 | controller |
2025-10-13 15:14:22.764891 | controller | TASK [operator_build : openstack-operator - Update the go.mod file in meta operator for provided PR_SHA] ***
2025-10-13 15:14:22.764901 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.039)       0:00:20.409 ********
2025-10-13 15:14:22.764913 | controller | skipping: [instance]
2025-10-13 15:14:22.948088 | controller |
2025-10-13 15:14:22.948133 | controller | TASK [operator_build : openstack-operator - Get latest commit when no PR is provided] ***
2025-10-13 15:14:22.948147 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.019)       0:00:20.428 ********
2025-10-13 15:14:22.948158 | controller | changed: [instance]
2025-10-13 15:14:22.981939 | controller |
2025-10-13 15:14:22.981963 | controller | TASK [operator_build : openstack-operator - Set pr_sha to be used as image tag] ***
2025-10-13 15:14:22.981972 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.183)       0:00:20.612 ********
2025-10-13 15:14:22.981984 | controller | ok: [instance]
2025-10-13 15:14:23.002176 | controller |
2025-10-13 15:14:23.002203 | controller | TASK [operator_build : openstack-operator - Update the go.mod file using latest commit if no PR is provided] ***
2025-10-13 15:14:23.002222 | controller | Monday 13 October 2025  15:14:22 +0000 (0:00:00.033)       0:00:20.646 ********
2025-10-13 15:14:23.002233 | controller | skipping: [instance]
2025-10-13 15:14:23.391682 | controller |
2025-10-13 15:14:23.391711 | controller | TASK [operator_build : openstack-operator - Get golang container image] ********
2025-10-13 15:14:23.391719 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.020)       0:00:20.666 ********
2025-10-13 15:14:23.391729 | controller | ok: [instance]
2025-10-13 15:14:23.415147 | controller |
2025-10-13 15:14:23.415168 | controller | TASK [operator_build : openstack-operator - Set operator image tag] ************
2025-10-13 15:14:23.415175 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.389)       0:00:21.055 ********
2025-10-13 15:14:23.415184 | controller | ok: [instance]
2025-10-13 15:14:23.415542 | controller |
2025-10-13 15:14:23.438270 | controller | TASK [operator_build : openstack-operator - Set operator image names] **********
2025-10-13 15:14:23.438293 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.024)       0:00:21.079 ********
2025-10-13 15:14:23.438306 | controller | ok: [instance]
2025-10-13 15:14:23.438386 | controller |
2025-10-13 15:14:23.438400 | controller | TASK [operator_build : openstack-operator - Set operator build output] *********
2025-10-13 15:14:23.438409 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.022)       0:00:21.102 ********
2025-10-13 15:14:23.471608 | controller | ok: [instance]
2025-10-13 15:14:23.523215 | controller |
2025-10-13 15:14:23.523241 | controller | TASK [operator_build : openstack-operator - Call manifests] ********************
2025-10-13 15:14:23.523249 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.033)       0:00:21.135 ********
2025-10-13 15:14:23.523259 | controller | ok: [instance]
2025-10-13 15:14:23.579047 | controller |
2025-10-13 15:14:23.579070 | controller | TASK [operator_build : openstack-operator - Call bindata] **********************
2025-10-13 15:14:23.579078 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.051)       0:00:21.187 ********
2025-10-13 15:14:23.579088 | controller | ok: [instance]
2025-10-13 15:14:23.634064 | controller |
2025-10-13 15:14:23.634089 | controller | TASK [operator_build : openstack-operator - Call docker-build] *****************
2025-10-13 15:14:23.634120 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.055)       0:00:21.243 ********
2025-10-13 15:14:23.634135 | controller | ok: [instance]
2025-10-13 15:14:23.663128 | controller |
2025-10-13 15:14:23.663151 | controller | TASK [operator_build : openstack-operator - Call docker-push] ******************
2025-10-13 15:14:23.663159 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.055)       0:00:21.298 ********
2025-10-13 15:14:23.663171 | controller | skipping: [instance]
2025-10-13 15:14:23.720319 | controller |
2025-10-13 15:14:23.720349 | controller | TASK [operator_build : openstack-operator - Call bundle] ***********************
2025-10-13 15:14:23.720357 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.029)       0:00:21.327 ********
2025-10-13 15:14:23.720367 | controller | ok: [instance]
2025-10-13 15:14:23.774626 | controller |
2025-10-13 15:14:23.774656 | controller | TASK [operator_build : openstack-operator - Call bundle-build] *****************
2025-10-13 15:14:23.774665 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.057)       0:00:21.384 ********
2025-10-13 15:14:23.774675 | controller | ok: [instance]
2025-10-13 15:14:23.804020 | controller |
2025-10-13 15:14:23.804046 | controller | TASK [operator_build : openstack-operator - Push bundle image] *****************
2025-10-13 15:14:23.804054 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.054)       0:00:21.438 ********
2025-10-13 15:14:23.804064 | controller | skipping: [instance]
2025-10-13 15:14:23.860314 | controller |
2025-10-13 15:14:23.860353 | controller | TASK [operator_build : openstack-operator - Call catalog-build] ****************
2025-10-13 15:14:23.860362 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.029)       0:00:21.468 ********
2025-10-13 15:14:23.860373 | controller | ok: [instance]
2025-10-13 15:14:23.889201 | controller |
2025-10-13 15:14:23.889248 | controller | TASK [operator_build : openstack-operator - Call catalog-push] *****************
2025-10-13 15:14:23.889257 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.056)       0:00:21.524 ********
2025-10-13 15:14:23.889269 | controller | skipping: [instance]
2025-10-13 15:14:24.369898 | controller |
2025-10-13 15:14:24.369929 | controller | TASK [operator_build : Gather role output] *************************************
2025-10-13 15:14:24.369937 | controller | Monday 13 October 2025  15:14:23 +0000 (0:00:00.028)       0:00:21.553 ********
2025-10-13 15:14:24.369947 | controller | changed: [instance]
2025-10-13 15:14:24.401879 | controller |
2025-10-13 15:14:24.401908 | controller | TASK [Validate operator_build outputs] *****************************************
2025-10-13 15:14:24.401918 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.480)       0:00:22.033 ********
2025-10-13 15:14:24.401929 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/molecule/default/tasks/validate_outputs.yml for instance => (item={'name': 'keystone-operator', 'src': '/home/zuul/keystone-operator'})
2025-10-13 15:14:24.587177 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/molecule/default/tasks/validate_outputs.yml for instance => (item={'name': 'openstack-operator', 'src': '/home/zuul/openstack-operator'})
2025-10-13 15:14:24.587217 | controller |
2025-10-13 15:14:24.587226 | controller | TASK [keystone-operator - Get commit hash from src repo] ***********************
2025-10-13 15:14:24.587233 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.031)       0:00:22.065 ********
2025-10-13 15:14:24.587243 | controller | changed: [instance]
2025-10-13 15:14:24.587499 | controller |
2025-10-13 15:14:24.587513 | controller | TASK [keystone-operator - Expected commit hash and registry] *******************
2025-10-13 15:14:24.622967 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.185)       0:00:22.251 ********
2025-10-13 15:14:24.622998 | controller | ok: [instance]
2025-10-13 15:14:24.645062 | controller |
2025-10-13 15:14:24.645080 | controller | TASK [keystone-operator - Expected images] *************************************
2025-10-13 15:14:24.645087 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.035)       0:00:22.287 ********
2025-10-13 15:14:24.645097 | controller | ok: [instance]
2025-10-13 15:14:24.675869 | controller |
2025-10-13 15:14:24.675891 | controller | TASK [keystone-operator - Ensure that role output dict contains the expected values] ***
2025-10-13 15:14:24.675898 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.022)       0:00:22.309 ********
2025-10-13 15:14:24.675907 | controller | ok: [instance] => changed=false
2025-10-13 15:14:24.865473 | controller |   msg: All assertions passed
2025-10-13 15:14:24.865506 | controller |
2025-10-13 15:14:24.865514 | controller | TASK [openstack-operator - Get commit hash from src repo] **********************
2025-10-13 15:14:24.865520 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.030)       0:00:22.339 ********
2025-10-13 15:14:24.865536 | controller | changed: [instance]
2025-10-13 15:14:24.903232 | controller |
2025-10-13 15:14:24.903270 | controller | TASK [openstack-operator - Expected commit hash and registry] ******************
2025-10-13 15:14:24.903280 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.189)       0:00:22.529 ********
2025-10-13 15:14:24.903292 | controller | ok: [instance]
2025-10-13 15:14:24.903300 | controller |
2025-10-13 15:14:24.903307 | controller | TASK [openstack-operator - Expected images] ************************************
2025-10-13 15:14:24.903316 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.037)       0:00:22.567 ********
2025-10-13 15:14:24.925925 | controller | ok: [instance]
2025-10-13 15:14:24.955568 | controller |
2025-10-13 15:14:24.955598 | controller | TASK [openstack-operator - Ensure that role output dict contains the expected values] ***
2025-10-13 15:14:24.955606 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.022)       0:00:22.590 ********
2025-10-13 15:14:24.955616 | controller | ok: [instance] => changed=false
2025-10-13 15:14:24.993093 | controller |   msg: All assertions passed
2025-10-13 15:14:24.993139 | controller |
2025-10-13 15:14:24.993148 | controller | TASK [Build mariadb-operator from Zuul job] ************************************
2025-10-13 15:14:24.993154 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.029)       0:00:22.619 ********
2025-10-13 15:14:24.993164 | controller |
2025-10-13 15:14:25.357015 | controller | TASK [operator_build : Ensure mandatory directories exist] *********************
2025-10-13 15:14:25.357045 | controller | Monday 13 October 2025  15:14:24 +0000 (0:00:00.037)       0:00:22.657 ********
2025-10-13 15:14:25.357057 | controller | ok: [instance] => (item=artifacts)
2025-10-13 15:14:25.376266 | controller | ok: [instance] => (item=logs)
2025-10-13 15:14:25.376303 | controller |
2025-10-13 15:14:25.376313 | controller | TASK [operator_build : Initialize role output] *********************************
2025-10-13 15:14:25.376321 | controller | Monday 13 October 2025  15:14:25 +0000 (0:00:00.363)       0:00:23.020 ********
2025-10-13 15:14:25.376333 | controller | ok: [instance]
2025-10-13 15:14:25.376361 | controller |
2025-10-13 15:14:25.376371 | controller | TASK [operator_build : Populate operators list with zuul info] *****************
2025-10-13 15:14:25.376477 | controller | Monday 13 October 2025  15:14:25 +0000 (0:00:00.019)       0:00:23.040 ********
2025-10-13 15:14:25.416028 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/zuul_info.yml for instance => (item={'branch': 'main', 'change': '373', 'change_url': 'https://github.com/openstack-k8s-operators/mariadb-operator/pull/373', 'patchset': 'bb6e4eda8f21ab0ba5ba7eddf2c550e97df63433', 'project': {'canonical_hostname': 'github.com', 'canonical_name': 'github.com/openstack-k8s-operators/mariadb-operator', 'name': 'openstack-k8s-operators/mariadb-operator', 'short_name': 'mariadb-operator', 'src_dir': 'src/github.com/openstack-k8s-operators/mariadb-operator'}})
2025-10-13 15:14:26.221035 | controller |
2025-10-13 15:14:26.221066 | controller | TASK [operator_build : Get the PR owner using github api] **********************
2025-10-13 15:14:26.221074 | controller | Monday 13 October 2025  15:14:25 +0000 (0:00:00.039)       0:00:23.080 ********
2025-10-13 15:14:26.221084 | controller | ok: [instance]
2025-10-13 15:14:26.259041 | controller |
2025-10-13 15:14:26.259070 | controller | TASK [operator_build : Add operator info with new content] *********************
2025-10-13 15:14:26.259078 | controller | Monday 13 October 2025  15:14:26 +0000 (0:00:00.804)       0:00:23.885 ********
2025-10-13 15:14:26.259088 | controller | ok: [instance]
2025-10-13 15:14:26.301837 | controller |
2025-10-13 15:14:26.301864 | controller | TASK [operator_build : Merge lists of operators] *******************************
2025-10-13 15:14:26.301872 | controller | Monday 13 October 2025  15:14:26 +0000 (0:00:00.038)       0:00:23.923 ********
2025-10-13 15:14:26.301882 | controller | ok: [instance]
2025-10-13 15:14:26.341037 | controller |
2025-10-13 15:14:26.341061 | controller | TASK [operator_build : Get meta_operator src dir from operators_list] **********
2025-10-13 15:14:26.341069 | controller | Monday 13 October 2025  15:14:26 +0000 (0:00:00.042)       0:00:23.965 ********
2025-10-13 15:14:26.341079 | controller | ok: [instance]
2025-10-13 15:14:26.361851 | controller |
2025-10-13 15:14:26.361876 | controller | TASK [operator_build : Adds meta-operator to the list] *************************
2025-10-13 15:14:26.361884 | controller | Monday 13 October 2025  15:14:26 +0000 (0:00:00.039)       0:00:24.005 ********
2025-10-13 15:14:26.361894 | controller | skipping: [instance]
2025-10-13 15:14:26.390526 | controller |
2025-10-13 15:14:26.390550 | controller | TASK [operator_build : Clone operator's code when src dir is empty] ************
2025-10-13 15:14:26.390558 | controller | Monday 13 October 2025  15:14:26 +0000 (0:00:00.020)       0:00:24.026 ********
2025-10-13 15:14:26.390572 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/clone.yml for instance => (item={'name': 'mariadb-operator', 'src': '/home/zuul/src/github.com/openstack-k8s-operators/mariadb-operator', 'pr_sha': 'bb6e4eda8f21ab0ba5ba7eddf2c550e97df63433', 'pr_owner': 'openstack-k8s-operators/mariadb-operator'})
2025-10-13 15:14:26.571858 | controller |
2025-10-13 15:14:26.571890 | controller | TASK [operator_build : mariadb-operator - Check if src code exists] ************
2025-10-13 15:14:26.571898 | controller | Monday 13 October 2025  15:14:26 +0000 (0:00:00.028)       0:00:24.054 ********
2025-10-13 15:14:26.571915 | controller | ok: [instance]
2025-10-13 15:14:27.882221 | controller |
2025-10-13 15:14:27.882269 | controller | TASK [operator_build : mariadb-operator - Clone from github if no code was provided] ***
2025-10-13 15:14:27.882278 | controller | Monday 13 October 2025  15:14:26 +0000 (0:00:00.181)       0:00:24.235 ********
2025-10-13 15:14:27.882292 | controller | changed: [instance]
2025-10-13 15:14:27.882320 | controller |
2025-10-13 15:14:27.882555 | controller | TASK [operator_build : Building operators] *************************************
2025-10-13 15:14:27.924291 | controller | Monday 13 October 2025  15:14:27 +0000 (0:00:01.310)       0:00:25.546 ********
2025-10-13 15:14:27.924347 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/build.yml for instance => (item={'name': 'mariadb-operator', 'src': '/home/zuul/src/github.com/openstack-k8s-operators/mariadb-operator', 'pr_sha': 'bb6e4eda8f21ab0ba5ba7eddf2c550e97df63433', 'pr_owner': 'openstack-k8s-operators/mariadb-operator'})
2025-10-13 15:14:27.924357 | controller |
2025-10-13 15:14:27.924364 | controller | TASK [operator_build : mariadb-operator - Read go.mod file contents of Openstack Operator] ***
2025-10-13 15:14:27.924372 | controller | Monday 13 October 2025  15:14:27 +0000 (0:00:00.041)       0:00:25.588 ********
2025-10-13 15:14:27.943215 | controller | skipping: [instance]
2025-10-13 15:14:27.943270 | controller |
2025-10-13 15:14:27.943560 | controller | TASK [operator_build : mariadb-operator - Get base module from go.mod] *********
2025-10-13 15:14:27.960813 | controller | Monday 13 October 2025  15:14:27 +0000 (0:00:00.019)       0:00:25.607 ********
2025-10-13 15:14:27.960854 | controller | skipping: [instance]
2025-10-13 15:14:27.979225 | controller |
2025-10-13 15:14:27.979263 | controller | TASK [operator_build : Get the base module name not empty operator_base_module] ***
2025-10-13 15:14:27.979272 | controller | Monday 13 October 2025  15:14:27 +0000 (0:00:00.017)       0:00:25.624 ********
2025-10-13 15:14:27.979284 | controller | skipping: [instance]
2025-10-13 15:14:27.997211 | controller |
2025-10-13 15:14:27.997244 | controller | TASK [operator_build : mariadb-operator - Set default api path] ****************
2025-10-13 15:14:27.997252 | controller | Monday 13 October 2025  15:14:27 +0000 (0:00:00.018)       0:00:25.643 ********
2025-10-13 15:14:27.997264 | controller | skipping: [instance]
2025-10-13 15:14:27.997372 | controller |
2025-10-13 15:14:27.997530 | controller | TASK [operator_build : mariadb-operator - Update the go.mod file in meta operator for provided PR_SHA] ***
2025-10-13 15:14:27.997683 | controller | Monday 13 October 2025  15:14:27 +0000 (0:00:00.018)       0:00:25.661 ********
2025-10-13 15:14:28.015777 | controller | skipping: [instance]
2025-10-13 15:14:28.015938 | controller |
2025-10-13 15:14:28.016073 | controller | TASK [operator_build : mariadb-operator - Get latest commit when no PR is provided] ***
2025-10-13 15:14:28.016236 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.018)       0:00:25.680 ********
2025-10-13 15:14:28.226148 | controller | changed: [instance]
2025-10-13 15:14:28.262179 | controller |
2025-10-13 15:14:28.262227 | controller | TASK [operator_build : mariadb-operator - Set pr_sha to be used as image tag] ***
2025-10-13 15:14:28.262241 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.209)       0:00:25.890 ********
2025-10-13 15:14:28.262253 | controller | ok: [instance]
2025-10-13 15:14:28.262279 | controller |
2025-10-13 15:14:28.262554 | controller | TASK [operator_build : mariadb-operator - Update the go.mod file using latest commit if no PR is provided] ***
2025-10-13 15:14:28.281715 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.036)       0:00:25.926 ********
2025-10-13 15:14:28.281744 | controller | skipping: [instance]
2025-10-13 15:14:28.687803 | controller |
2025-10-13 15:14:28.687839 | controller | TASK [operator_build : mariadb-operator - Get golang container image] **********
2025-10-13 15:14:28.687848 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.019)       0:00:25.945 ********
2025-10-13 15:14:28.687861 | controller | ok: [instance]
2025-10-13 15:14:28.711081 | controller |
2025-10-13 15:14:28.711120 | controller | TASK [operator_build : mariadb-operator - Set operator image tag] **************
2025-10-13 15:14:28.711130 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.405)       0:00:26.351 ********
2025-10-13 15:14:28.711140 | controller | ok: [instance]
2025-10-13 15:14:28.734075 | controller |
2025-10-13 15:14:28.734092 | controller | TASK [operator_build : mariadb-operator - Set operator image names] ************
2025-10-13 15:14:28.734118 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.023)       0:00:26.375 ********
2025-10-13 15:14:28.734128 | controller | ok: [instance]
2025-10-13 15:14:28.767217 | controller |
2025-10-13 15:14:28.767253 | controller | TASK [operator_build : mariadb-operator - Set operator build output] ***********
2025-10-13 15:14:28.767261 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.023)       0:00:26.398 ********
2025-10-13 15:14:28.767273 | controller | ok: [instance]
2025-10-13 15:14:28.767300 | controller |
2025-10-13 15:14:28.767534 | controller | TASK [operator_build : mariadb-operator - Call manifests] **********************
2025-10-13 15:14:28.820485 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.033)       0:00:26.431 ********
2025-10-13 15:14:28.820509 | controller | ok: [instance]
2025-10-13 15:14:28.839039 | controller |
2025-10-13 15:14:28.839059 | controller | TASK [operator_build : mariadb-operator - Call bindata] ************************
2025-10-13 15:14:28.839066 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.053)       0:00:26.484 ********
2025-10-13 15:14:28.839075 | controller | skipping: [instance]
2025-10-13 15:14:28.893120 | controller |
2025-10-13 15:14:28.893142 | controller | TASK [operator_build : mariadb-operator - Call docker-build] *******************
2025-10-13 15:14:28.893149 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.018)       0:00:26.503 ********
2025-10-13 15:14:28.893159 | controller | ok: [instance]
2025-10-13 15:14:28.921821 | controller |
2025-10-13 15:14:28.921842 | controller | TASK [operator_build : mariadb-operator - Call docker-push] ********************
2025-10-13 15:14:28.921849 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.054)       0:00:26.557 ********
2025-10-13 15:14:28.921858 | controller | skipping: [instance]
2025-10-13 15:14:28.980212 | controller |
2025-10-13 15:14:28.980246 | controller | TASK [operator_build : mariadb-operator - Call bundle] *************************
2025-10-13 15:14:28.980255 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.028)       0:00:26.586 ********
2025-10-13 15:14:28.980266 | controller | ok: [instance]
2025-10-13 15:14:29.036907 | controller |
2025-10-13 15:14:29.036934 | controller | TASK [operator_build : mariadb-operator - Call bundle-build] *******************
2025-10-13 15:14:29.036942 | controller | Monday 13 October 2025  15:14:28 +0000 (0:00:00.058)       0:00:26.644 ********
2025-10-13 15:14:29.036952 | controller | ok: [instance]
2025-10-13 15:14:29.066072 | controller |
2025-10-13 15:14:29.066118 | controller | TASK [operator_build : mariadb-operator - Push bundle image] *******************
2025-10-13 15:14:29.066130 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.056)       0:00:26.701 ********
2025-10-13 15:14:29.066141 | controller | skipping: [instance]
2025-10-13 15:14:29.124166 | controller |
2025-10-13 15:14:29.124202 | controller | TASK [operator_build : mariadb-operator - Call catalog-build] ******************
2025-10-13 15:14:29.124210 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.028)       0:00:26.730 ********
2025-10-13 15:14:29.124220 | controller | ok: [instance]
2025-10-13 15:14:29.124404 | controller |
2025-10-13 15:14:29.124417 | controller | TASK [operator_build : mariadb-operator - Call catalog-push] *******************
2025-10-13 15:14:29.152579 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.058)       0:00:26.788 ********
2025-10-13 15:14:29.152603 | controller | skipping: [instance]
2025-10-13 15:14:29.177900 | controller |
2025-10-13 15:14:29.177922 | controller | TASK [operator_build : Building meta operator] *********************************
2025-10-13 15:14:29.177932 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.028)       0:00:26.816 ********
2025-10-13 15:14:29.177949 | controller | skipping: [instance] => (item={'name': 'mariadb-operator', 'src': '/home/zuul/src/github.com/openstack-k8s-operators/mariadb-operator', 'pr_sha': 'bb6e4eda8f21ab0ba5ba7eddf2c550e97df63433', 'pr_owner': 'openstack-k8s-operators/mariadb-operator'})
2025-10-13 15:14:29.544200 | controller | skipping: [instance]
2025-10-13 15:14:29.544231 | controller |
2025-10-13 15:14:29.544240 | controller | TASK [operator_build : Gather role output] *************************************
2025-10-13 15:14:29.544252 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.025)       0:00:26.841 ********
2025-10-13 15:14:29.544269 | controller | changed: [instance]
2025-10-13 15:14:29.544276 | controller |
2025-10-13 15:14:29.544281 | controller | TASK [Validate operator_build outputs] *****************************************
2025-10-13 15:14:29.544289 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.366)       0:00:27.208 ********
2025-10-13 15:14:29.578196 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/molecule/default/tasks/validate_outputs.yml for instance => (item={'name': 'mariadb-operator', 'src': '/home/zuul/src/github.com/openstack-k8s-operators/mariadb-operator', 'pr_sha': 'bb6e4eda8f21ab0ba5ba7eddf2c550e97df63433'})
2025-10-13 15:14:29.766577 | controller |
2025-10-13 15:14:29.766606 | controller | TASK [mariadb-operator - Get commit hash from src repo] ************************
2025-10-13 15:14:29.766614 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.033)       0:00:27.242 ********
2025-10-13 15:14:29.766624 | controller | changed: [instance]
2025-10-13 15:14:29.803810 | controller |
2025-10-13 15:14:29.803841 | controller | TASK [mariadb-operator - Expected commit hash and registry] ********************
2025-10-13 15:14:29.803850 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.188)       0:00:27.430 ********
2025-10-13 15:14:29.803862 | controller | ok: [instance]
2025-10-13 15:14:29.826253 | controller |
2025-10-13 15:14:29.826292 | controller | TASK [mariadb-operator - Expected images] **************************************
2025-10-13 15:14:29.826303 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.037)       0:00:27.467 ********
2025-10-13 15:14:29.826316 | controller | ok: [instance]
2025-10-13 15:14:29.826345 | controller |
2025-10-13 15:14:29.826355 | controller | TASK [mariadb-operator - Ensure that role output dict contains the expected values] ***
2025-10-13 15:14:29.826382 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.022)       0:00:27.490 ********
2025-10-13 15:14:29.872196 | controller | ok: [instance] => changed=false
2025-10-13 15:14:29.872220 | controller |   msg: All assertions passed
2025-10-13 15:14:29.872227 | controller |
2025-10-13 15:14:29.872233 | controller | PLAY RECAP *********************************************************************
2025-10-13 15:14:29.872239 | controller | instance                   : ok=83   changed=13   unreachable=0    failed=0    skipped=27   rescued=0    ignored=0
2025-10-13 15:14:29.872244 | controller |
2025-10-13 15:14:29.872250 | controller | Monday 13 October 2025  15:14:29 +0000 (0:00:00.043)       0:00:27.534 ********
2025-10-13 15:14:29.872256 | controller | ===============================================================================
2025-10-13 15:14:29.872261 | controller | operator_build : keystone-operator - Get golang container image -------- 11.48s
2025-10-13 15:14:29.872266 | controller | operator_build : openstack-operator - Clone from github if no code was provided --- 2.72s
2025-10-13 15:14:29.872271 | controller | operator_build : keystone-operator - Clone from github if no code was provided --- 1.61s
2025-10-13 15:14:29.872277 | controller | operator_build : mariadb-operator - Clone from github if no code was provided --- 1.31s
2025-10-13 15:14:29.872282 | controller | Get open PR list for mariadb-operator ----------------------------------- 1.05s
2025-10-13 15:14:29.872287 | controller | operator_build : Get the PR owner using github api ---------------------- 0.80s
2025-10-13 15:14:29.872293 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-10-13 15:14:29.872298 | controller | operator_build : Gather role output ------------------------------------- 0.48s
2025-10-13 15:14:29.872303 | controller | operator_build : Ensure mandatory directories exist --------------------- 0.46s
2025-10-13 15:14:29.872308 | controller | operator_build : mariadb-operator - Get golang container image ---------- 0.41s
2025-10-13 15:14:29.872314 | controller | operator_build : openstack-operator - Get golang container image -------- 0.39s
2025-10-13 15:14:29.872319 | controller | operator_build : Gather role output ------------------------------------- 0.37s
2025-10-13 15:14:29.872324 | controller | operator_build : Ensure mandatory directories exist --------------------- 0.36s
2025-10-13 15:14:29.872329 | controller | operator_build : keystone-operator - Get latest commit when no PR is provided --- 0.28s
2025-10-13 15:14:29.872335 | controller | operator_build : keystone-operator - Read go.mod file contents of Openstack Operator --- 0.27s
2025-10-13 15:14:29.872350 | controller | operator_build : keystone-operator - Check if src code exists ----------- 0.26s
2025-10-13 15:14:29.872478 | controller | operator_build : mariadb-operator - Get latest commit when no PR is provided --- 0.21s
2025-10-13 15:14:29.872497 | controller | operator_build : keystone-operator - Update the go.mod file using latest commit if no PR is provided --- 0.20s
2025-10-13 15:14:29.872610 | controller | operator_build : openstack-operator - Check if src code exists ---------- 0.19s
2025-10-13 15:14:29.872713 | controller | openstack-operator - Get commit hash from src repo ---------------------- 0.19s
2025-10-13 15:14:29.939867 | controller | INFO     Running default > cleanup
2025-10-13 15:14:29.940354 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-13 15:14:29.940949 | controller | INFO     Writing /tmp/report.html report.
2025-10-13 15:14:30.453379 | controller | changed
2025-10-13 15:14:30.484116 | 
2025-10-13 15:14:30.484208 | PLAY RECAP
2025-10-13 15:14:30.484254 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-13 15:14:30.484278 | 
2025-10-13 15:14:30.599363 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 15:14:30.600250 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 15:14:31.172084 | 
2025-10-13 15:14:31.172205 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 15:14:31.193751 | 
2025-10-13 15:14:31.193875 | TASK [Filter out host if needed]
2025-10-13 15:14:31.203410 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 15:14:31.208306 | 
2025-10-13 15:14:31.208394 | TASK [Ensure file is present]
2025-10-13 15:14:31.542461 | controller | ok
2025-10-13 15:14:31.549430 | 
2025-10-13 15:14:31.549498 | TASK [Manage molecule report file]
2025-10-13 15:14:32.076090 | controller | changed
2025-10-13 15:14:32.081934 | 
2025-10-13 15:14:32.082011 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 15:14:32.291145 | controller | ok
2025-10-13 15:14:32.297123 | 
2025-10-13 15:14:32.297192 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 15:14:32.634657 | controller | changed
2025-10-13 15:14:32.643207 | 
2025-10-13 15:14:32.643361 | TASK [Copy ci-framework interesting files]
2025-10-13 15:14:32.853590 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 15:14:33.176210 | controller | changed
2025-10-13 15:14:33.182577 | 
2025-10-13 15:14:33.182710 | TASK [Get SELinux listing]
2025-10-13 15:14:33.714280 | controller | changed
2025-10-13 15:14:33.720521 | 
2025-10-13 15:14:33.720607 | TASK [Generate log index]
2025-10-13 15:14:34.382941 | controller | changed
2025-10-13 15:14:34.389797 | 
2025-10-13 15:14:34.389925 | TASK [Get some env related data]
2025-10-13 15:14:34.934400 | controller | /home/zuul/.local/bin/ansible
2025-10-13 15:14:35.924586 | controller | changed
2025-10-13 15:14:35.929873 | 
2025-10-13 15:14:35.929966 | TASK [Generate list of logs to collect in home directory]
2025-10-13 15:14:36.268902 | controller | ok: All paths examined
2025-10-13 15:14:36.274323 | 
2025-10-13 15:14:36.274424 | LOOP [Copy logs from home directory]
2025-10-13 15:14:36.622397 | controller | changed:
2025-10-13 15:14:36.622553 | controller | {
2025-10-13 15:14:36.622581 | controller |   "atime": 1760368318.5492198,
2025-10-13 15:14:36.622601 | controller |   "ctime": 1760368335.8134978,
2025-10-13 15:14:36.622619 | controller |   "dev": 64513,
2025-10-13 15:14:36.622635 | controller |   "gid": 1000,
2025-10-13 15:14:36.622651 | controller |   "gr_name": "zuul",
2025-10-13 15:14:36.622669 | controller |   "inode": 4481081,
2025-10-13 15:14:36.622685 | controller |   "isblk": false,
2025-10-13 15:14:36.622702 | controller |   "ischr": false,
2025-10-13 15:14:36.622718 | controller |   "isdir": false,
2025-10-13 15:14:36.622735 | controller |   "isfifo": false,
2025-10-13 15:14:36.622750 | controller |   "isgid": false,
2025-10-13 15:14:36.622766 | controller |   "islnk": false,
2025-10-13 15:14:36.622781 | controller |   "isreg": true,
2025-10-13 15:14:36.622797 | controller |   "issock": false,
2025-10-13 15:14:36.622813 | controller |   "isuid": false,
2025-10-13 15:14:36.622828 | controller |   "mode": "0644",
2025-10-13 15:14:36.622843 | controller |   "mtime": 1760368335.8134978,
2025-10-13 15:14:36.622859 | controller |   "nlink": 1,
2025-10-13 15:14:36.622875 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 15:14:36.622901 | controller |   "pw_name": "zuul",
2025-10-13 15:14:36.622943 | controller |   "rgrp": true,
2025-10-13 15:14:36.622962 | controller |   "roth": true,
2025-10-13 15:14:36.622979 | controller |   "rusr": true,
2025-10-13 15:14:36.622995 | controller |   "size": 6749,
2025-10-13 15:14:36.623011 | controller |   "uid": 1000,
2025-10-13 15:14:36.623026 | controller |   "wgrp": false,
2025-10-13 15:14:36.623040 | controller |   "woth": false,
2025-10-13 15:14:36.623058 | controller |   "wusr": true,
2025-10-13 15:14:36.623074 | controller |   "xgrp": false,
2025-10-13 15:14:36.623089 | controller |   "xoth": false,
2025-10-13 15:14:36.623104 | controller |   "xusr": false
2025-10-13 15:14:36.623118 | controller | }
2025-10-13 15:14:36.635826 | 
2025-10-13 15:14:36.635948 | TASK [Copy crio stats log file]
2025-10-13 15:14:36.660877 | controller | skipping: Conditional result was False
2025-10-13 15:14:36.666991 | 
2025-10-13 15:14:36.667088 | TASK [Get SELinux related data]
2025-10-13 15:14:36.890567 | controller | <no matches>
2025-10-13 15:14:37.204036 | controller | ERROR
2025-10-13 15:14:37.204273 | controller | {
2025-10-13 15:14:37.204319 | controller |   "delta": "0:00:00.008796",
2025-10-13 15:14:37.204341 | controller |   "end": "2025-10-13 15:14:36.891235",
2025-10-13 15:14:37.204358 | controller |   "msg": "non-zero return code",
2025-10-13 15:14:37.204374 | controller |   "rc": 1,
2025-10-13 15:14:37.204389 | controller |   "start": "2025-10-13 15:14:36.882439"
2025-10-13 15:14:37.204403 | controller | }
2025-10-13 15:14:37.204424 | controller | ERROR: Ignoring Errors
2025-10-13 15:14:37.210405 | 
2025-10-13 15:14:37.210476 | TASK [Create system configuration directory]
2025-10-13 15:14:37.402902 | controller | changed
2025-10-13 15:14:37.408182 | 
2025-10-13 15:14:37.408300 | TASK [Get some of the system configurations]
2025-10-13 15:14:37.942219 | controller | changed
2025-10-13 15:14:37.948966 | 
2025-10-13 15:14:37.949040 | TASK [Copy generated documentation if available]
2025-10-13 15:14:37.973578 | controller | skipping: Conditional result was False
2025-10-13 15:14:37.980050 | 
2025-10-13 15:14:37.980126 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 15:14:38.003698 | controller | skipping: Conditional result was False
2025-10-13 15:14:38.011683 | 
2025-10-13 15:14:38.011758 | TASK [Compress logs bigger than 2MB]
2025-10-13 15:14:38.545672 | controller | changed
2025-10-13 15:14:38.552411 | 
2025-10-13 15:14:38.552493 | TASK [Copy files from workspace on node]
2025-10-13 15:14:38.577426 | controller | ok
2025-10-13 15:14:38.609029 | 
2025-10-13 15:14:38.609158 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 15:14:38.634184 | controller | skipping: Conditional result was False
2025-10-13 15:14:38.639995 | 
2025-10-13 15:14:38.640080 | TASK [fetch-output : Set log path for single node]
2025-10-13 15:14:38.671022 | controller | ok
2025-10-13 15:14:38.676590 | 
2025-10-13 15:14:38.676695 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 15:14:38.912697 | controller -> localhost | ok: "/var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/logs"
2025-10-13 15:14:38.913003 | controller -> localhost | changed: All items complete
2025-10-13 15:14:38.913037 | 
2025-10-13 15:14:39.104790 | controller -> localhost | changed: "/var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/artifacts"
2025-10-13 15:14:39.306371 | controller -> localhost | changed: "/var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/docs"
2025-10-13 15:14:39.320361 | 
2025-10-13 15:14:39.320536 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 15:14:39.915040 | controller | changed:
2025-10-13 15:14:39.915358 | controller | .d..t...... ./
2025-10-13 15:14:39.915468 | controller | >f+++++++++ README.html
2025-10-13 15:14:39.915503 | controller | >f+++++++++ ansible-execution.log
2025-10-13 15:14:39.915529 | controller | >f+++++++++ ansible.log
2025-10-13 15:14:39.915550 | controller | >f+++++++++ dmesg.log
2025-10-13 15:14:39.915576 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 15:14:39.915599 | controller | >f+++++++++ python.log
2025-10-13 15:14:39.915622 | controller | >f+++++++++ registries.conf
2025-10-13 15:14:39.915642 | controller | >f+++++++++ report.html
2025-10-13 15:14:39.915662 | controller | >f+++++++++ selinux-denials.log
2025-10-13 15:14:39.915680 | controller | >f+++++++++ selinux-listing.log
2025-10-13 15:14:39.915698 | controller | cd+++++++++ ci-framework-data/
2025-10-13 15:14:39.915716 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 15:14:39.915733 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_keystone_operator_call.sh
2025-10-13 15:14:39.915751 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_keystone_operator_call_docker.sh
2025-10-13 15:14:39.915768 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_002_keystone_operator_call.sh
2025-10-13 15:14:39.915785 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_003_keystone_operator_call_bundle.sh
2025-10-13 15:14:39.915803 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_004_keystone_operator_call.sh
2025-10-13 15:14:39.915821 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_005_openstack_operator_call.sh
2025-10-13 15:14:39.915838 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_006_openstack_operator_call.sh
2025-10-13 15:14:39.915854 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_007_openstack_operator_call.sh
2025-10-13 15:14:39.915871 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_008_openstack_operator_call.sh
2025-10-13 15:14:39.915887 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_009_openstack_operator_call.sh
2025-10-13 15:14:39.915904 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_010_openstack_operator_call.sh
2025-10-13 15:14:39.915949 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_011_mariadb_operator_call.sh
2025-10-13 15:14:39.915970 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_012_mariadb_operator_call_docker.sh
2025-10-13 15:14:39.915988 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_013_mariadb_operator_call.sh
2025-10-13 15:14:39.916005 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_014_mariadb_operator_call_bundle.sh
2025-10-13 15:14:39.916023 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_015_mariadb_operator_call_catalog.sh
2025-10-13 15:14:39.916040 | controller | >f+++++++++ ci-framework-data/artifacts/custom-operators.yml
2025-10-13 15:14:39.916058 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 15:14:39.916074 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 15:14:39.916091 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 15:14:39.916107 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-13 15:14:39.916123 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-13 15:14:39.916138 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-13 15:14:39.916163 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-13 15:14:39.916186 | controller | cd+++++++++ registries.conf.d/
2025-10-13 15:14:39.916210 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 15:14:39.916233 | controller | cd+++++++++ system-config/
2025-10-13 15:14:39.916345 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 15:14:39.916383 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 15:14:39.916411 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 15:14:40.300243 | controller | changed: .d..t...... ./
2025-10-13 15:14:40.681542 | controller | changed: .d..t...... ./
2025-10-13 15:14:40.701491 | 
2025-10-13 15:14:40.701633 | TASK [Return artifact to Zuul]
2025-10-13 15:14:40.732044 | controller | ok
2025-10-13 15:14:40.755424 | 
2025-10-13 15:14:40.755533 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 15:14:40.755660 | 
2025-10-13 15:14:40.755689 | PLAY RECAP
2025-10-13 15:14:40.755732 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-13 15:14:40.755754 | 
2025-10-13 15:14:40.861646 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 15:14:40.862659 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 15:14:41.466132 | 
2025-10-13 15:14:41.466272 | PLAY [all]
2025-10-13 15:14:41.487721 | 
2025-10-13 15:14:41.487832 | TASK [include_role : fetch-output]
2025-10-13 15:14:41.529048 | controller | ok
2025-10-13 15:14:41.547220 | 
2025-10-13 15:14:41.547348 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 15:14:41.613102 | controller | skipping: Conditional result was False
2025-10-13 15:14:41.619327 | 
2025-10-13 15:14:41.619423 | TASK [fetch-output : Set log path for single node]
2025-10-13 15:14:41.659235 | controller | ok
2025-10-13 15:14:41.664678 | 
2025-10-13 15:14:41.664760 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 15:14:42.038873 | controller -> localhost | ok: "/var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/logs"
2025-10-13 15:14:42.244769 | controller -> localhost | ok: "/var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/artifacts"
2025-10-13 15:14:42.468684 | controller -> localhost | ok: "/var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/docs"
2025-10-13 15:14:42.482553 | 
2025-10-13 15:14:42.482697 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 15:14:43.052873 | controller | ok
2025-10-13 15:14:43.053124 | controller | ok: All items complete
2025-10-13 15:14:43.053156 | 
2025-10-13 15:14:43.455705 | controller | ok
2025-10-13 15:14:43.863567 | controller | ok
2025-10-13 15:14:43.883624 | 
2025-10-13 15:14:43.883759 | TASK [include_role : fetch-output-openshift]
2025-10-13 15:14:43.898959 | controller | skipping: Conditional result was False
2025-10-13 15:14:43.905453 | 
2025-10-13 15:14:43.905525 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 15:14:44.308954 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.016365
2025-10-13 15:14:44.524572 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008103
2025-10-13 15:14:44.562900 | 
2025-10-13 15:14:44.563060 | PLAY [all]
2025-10-13 15:14:44.578969 | 
2025-10-13 15:14:44.579107 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 15:14:45.017546 | controller | changed
2025-10-13 15:14:45.040041 | 
2025-10-13 15:14:45.040138 | PLAY RECAP
2025-10-13 15:14:45.040192 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 15:14:45.040214 | 
2025-10-13 15:14:45.153249 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 15:14:45.154606 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 15:14:45.799126 | 
2025-10-13 15:14:45.799249 | PLAY [localhost]
2025-10-13 15:14:45.817727 | 
2025-10-13 15:14:45.817807 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 15:14:46.169938 | localhost | changed
2025-10-13 15:14:46.174808 | 
2025-10-13 15:14:46.174947 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 15:14:46.202407 | localhost | ok
2025-10-13 15:14:46.210683 | 
2025-10-13 15:14:46.210755 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 15:14:46.748373 | localhost | changed
2025-10-13 15:14:46.753433 | 
2025-10-13 15:14:46.753511 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 15:14:47.382740 | localhost | changed
2025-10-13 15:14:47.390294 | 
2025-10-13 15:14:47.390371 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 15:14:47.780083 | localhost | Identity added: /var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/tmp/ansible.nt6gpg2j (/var/lib/zuul/builds/9ead035e4da54c0d929bf7282bebf5e8/work/tmp/ansible.nt6gpg2j)
2025-10-13 15:14:47.780471 | localhost | ok: Runtime: 0:00:00.006321
2025-10-13 15:14:47.785699 | 
2025-10-13 15:14:47.785782 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 15:14:48.033357 | localhost | ok: Runtime: 0:00:00.005081
2025-10-13 15:14:48.038985 | 
2025-10-13 15:14:48.039178 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 15:14:48.098155 | localhost | changed
2025-10-13 15:14:48.103199 | 
2025-10-13 15:14:48.103272 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 15:14:48.467644 | localhost | changed
2025-10-13 15:14:48.488815 | 
2025-10-13 15:14:48.489150 | PLAY [localhost]
2025-10-13 15:14:48.501783 | 
2025-10-13 15:14:48.501857 | TASK [Generate bulk log download script]
2025-10-13 15:14:48.523373 | localhost | ok
2025-10-13 15:14:48.535302 | 
2025-10-13 15:14:48.535399 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 15:14:48.564407 | localhost | ok: All assertions passed
2025-10-13 15:14:48.569183 | 
2025-10-13 15:14:48.569259 | TASK [local-log-download : Create download script]
2025-10-13 15:14:48.959703 | localhost -> localhost | changed
2025-10-13 15:14:48.968984 | 
2025-10-13 15:14:48.969079 | TASK [Register quick-download link]
2025-10-13 15:14:48.991114 | localhost | ok
2025-10-13 15:14:49.037936 | 
2025-10-13 15:14:49.038164 | PLAY [logserver.rdoproject.org]
2025-10-13 15:14:49.048121 | 
2025-10-13 15:14:49.048195 | TASK [Set zuul-log-path fact]
2025-10-13 15:14:49.076652 | logserver.rdoproject.org | ok
2025-10-13 15:14:49.087302 | 
2025-10-13 15:14:49.087405 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 15:14:49.124881 | logserver.rdoproject.org | ok
2025-10-13 15:14:49.131065 | 
2025-10-13 15:14:49.131144 | TASK [upload-logs : Create log directories]
2025-10-13 15:14:50.867452 | logserver.rdoproject.org | changed
2025-10-13 15:14:50.871177 | 
2025-10-13 15:14:50.871245 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 15:14:51.131815 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009789
2025-10-13 15:14:51.137069 | 
2025-10-13 15:14:51.137134 | TASK [upload-logs : Upload logs to log server]
2025-10-13 15:14:52.680078 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 15:14:52.683274 | 
2025-10-13 15:14:52.683358 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 15:14:52.731727 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:14:52.746426 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:14:52.751922 | 
2025-10-13 15:14:52.752039 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 15:14:52.790784 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:14:52.791069 | 
2025-10-13 15:14:52.795425 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 15:14:52.800834 | 
2025-10-13 15:14:52.800985 | LOOP [upload-logs : Upload console log and json output]