2025-09-30 11:17:46.288465 | Job console starting...
2025-09-30 11:17:46.525444 | Updating repositories
2025-09-30 11:17:47.454805 | Preparing job workspace
2025-09-30 11:18:07.419926 | Running Ansible setup...
2025-09-30 11:18:16.237670 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 11:18:18.426273 | 
2025-09-30 11:18:18.426402 | PLAY [localhost]
2025-09-30 11:18:18.451396 | 
2025-09-30 11:18:18.451493 | TASK [Gathering Facts]
2025-09-30 11:18:20.776408 | localhost | ok
2025-09-30 11:18:20.823225 | 
2025-09-30 11:18:20.823344 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-30 11:18:22.464836 | localhost -> localhost | changed
2025-09-30 11:18:22.471061 | 
2025-09-30 11:18:22.471135 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-30 11:18:25.124904 | localhost -> localhost | changed
2025-09-30 11:18:25.133455 | 
2025-09-30 11:18:25.133525 | TASK [Setup log path fact]
2025-09-30 11:18:25.185589 | localhost | ok
2025-09-30 11:18:25.239672 | 
2025-09-30 11:18:25.239758 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 11:18:25.303276 | localhost | ok
2025-09-30 11:18:25.312092 | 
2025-09-30 11:18:25.312193 | TASK [emit-job-header : Print job information]
2025-09-30 11:18:25.414041 | # Job Information
2025-09-30 11:18:25.414199 | Ansible Version: 2.15.12
2025-09-30 11:18:25.414230 | Job: cifmw-molecule-operator_build
2025-09-30 11:18:25.414254 | Pipeline: github-check
2025-09-30 11:18:25.414274 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-09-30 11:18:25.414296 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333
2025-09-30 11:18:25.414318 | Log URL (when completed): https://logserver.rdoproject.org/44e/rdoproject.org/44eb7d83f89d46b08ac576e1bcb719f8/
2025-09-30 11:18:25.414340 | Event ID: c7820690-9dee-11f0-870e-caa834dfb11c
2025-09-30 11:18:25.417934 | 
2025-09-30 11:18:25.418004 | LOOP [emit-job-header : Print node information]
2025-09-30 11:18:25.807934 | localhost | ok:
2025-09-30 11:18:25.808209 | localhost | # Node Information
2025-09-30 11:18:25.808244 | localhost | Inventory Hostname: controller
2025-09-30 11:18:25.808269 | localhost | Hostname: np0005462692
2025-09-30 11:18:25.808290 | localhost | Username: zuul
2025-09-30 11:18:25.808312 | localhost | Distro: CentOS 9
2025-09-30 11:18:25.808330 | localhost | Provider: ibm-bm3-nodepool
2025-09-30 11:18:25.808348 | localhost | Region: regionOne
2025-09-30 11:18:25.808364 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-30 11:18:25.808397 | localhost | Product Name: OpenStack Compute
2025-09-30 11:18:25.808421 | localhost | Interface IP: 192.168.25.180
2025-09-30 11:18:25.869811 | 
2025-09-30 11:18:25.869893 | PLAY [all]
2025-09-30 11:18:25.890463 | 
2025-09-30 11:18:25.890559 | TASK [Gather network facts]
2025-09-30 11:18:26.317432 | controller | ok
2025-09-30 11:18:26.363969 | 
2025-09-30 11:18:26.364062 | TASK [include_role : start-zuul-console]
2025-09-30 11:18:26.422086 | controller | ok
2025-09-30 11:18:26.448027 | 
2025-09-30 11:18:26.448118 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-30 11:18:27.014888 | controller | ok
2025-09-30 11:18:27.039433 | 
2025-09-30 11:18:27.039519 | TASK [include_role : add-build-sshkey]
2025-09-30 11:18:27.071898 | controller | ok
2025-09-30 11:18:27.088726 | 
2025-09-30 11:18:27.088811 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-30 11:18:27.922669 | controller -> localhost | ok
2025-09-30 11:18:27.928447 | 
2025-09-30 11:18:27.928517 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-30 11:18:28.015285 | controller | ok
2025-09-30 11:18:28.051111 | controller | included: /var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-30 11:18:28.076272 | 
2025-09-30 11:18:28.076351 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-30 11:18:29.829931 | controller -> localhost | Generating public/private rsa key pair.
2025-09-30 11:18:29.830098 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/44eb7d83f89d46b08ac576e1bcb719f8_id_rsa.
2025-09-30 11:18:29.830132 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/44eb7d83f89d46b08ac576e1bcb719f8_id_rsa.pub.
2025-09-30 11:18:29.830167 | controller -> localhost | The key fingerprint is:
2025-09-30 11:18:29.830191 | controller -> localhost | SHA256:wAeJXPR957znGcAN3UpwQDntsvFZt9TpZCMu7Xt8Ekc zuul-build-sshkey
2025-09-30 11:18:29.830211 | controller -> localhost | The key's randomart image is:
2025-09-30 11:18:29.830232 | controller -> localhost | +---[RSA 3072]----+
2025-09-30 11:18:29.830251 | controller -> localhost | |   . ++.   .++.  |
2025-09-30 11:18:29.830270 | controller -> localhost | |    o..o .  ooo .|
2025-09-30 11:18:29.830289 | controller -> localhost | |      o o . .+o.+|
2025-09-30 11:18:29.830310 | controller -> localhost | |       o   .+*+BE|
2025-09-30 11:18:29.830328 | controller -> localhost | |        S   oBX=+|
2025-09-30 11:18:29.830346 | controller -> localhost | |           ..o=+.|
2025-09-30 11:18:29.830363 | controller -> localhost | |            o o+.|
2025-09-30 11:18:29.830469 | controller -> localhost | |             ..=+|
2025-09-30 11:18:29.830511 | controller -> localhost | |             .ooo|
2025-09-30 11:18:29.830536 | controller -> localhost | +----[SHA256]-----+
2025-09-30 11:18:29.830581 | controller -> localhost | ok: Runtime: 0:00:00.431909
2025-09-30 11:18:29.835799 | 
2025-09-30 11:18:29.835867 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-30 11:18:29.935630 | controller | ok
2025-09-30 11:18:29.965008 | controller | included: /var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-30 11:18:29.988090 | 
2025-09-30 11:18:29.988182 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-30 11:18:30.078203 | controller | skipping: Conditional result was False
2025-09-30 11:18:30.083723 | 
2025-09-30 11:18:30.083791 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-30 11:18:30.934024 | controller | changed
2025-09-30 11:18:30.946357 | 
2025-09-30 11:18:30.946463 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-30 11:18:31.233066 | controller | ok
2025-09-30 11:18:31.241300 | 
2025-09-30 11:18:31.241367 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-30 11:18:32.023987 | controller | changed
2025-09-30 11:18:32.028920 | 
2025-09-30 11:18:32.028987 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-30 11:18:32.824967 | controller | changed
2025-09-30 11:18:32.834724 | 
2025-09-30 11:18:32.834792 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-30 11:18:32.896641 | controller | skipping: Conditional result was False
2025-09-30 11:18:32.903047 | 
2025-09-30 11:18:32.903122 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-30 11:18:33.809591 | controller -> localhost | changed
2025-09-30 11:18:33.821222 | 
2025-09-30 11:18:33.821294 | TASK [add-build-sshkey : Add back temp key]
2025-09-30 11:18:34.694811 | controller -> localhost | Identity added: /var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/44eb7d83f89d46b08ac576e1bcb719f8_id_rsa (zuul-build-sshkey)
2025-09-30 11:18:34.694995 | controller -> localhost | ok: Runtime: 0:00:00.034741
2025-09-30 11:18:34.701605 | 
2025-09-30 11:18:34.701674 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-30 11:18:35.297124 | controller | ok
2025-09-30 11:18:35.301803 | 
2025-09-30 11:18:35.301871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-30 11:18:35.378227 | controller | skipping: Conditional result was False
2025-09-30 11:18:35.392029 | 
2025-09-30 11:18:35.392291 | TASK [include_role : validate-host]
2025-09-30 11:18:35.474717 | controller | ok
2025-09-30 11:18:35.517121 | 
2025-09-30 11:18:35.517221 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-30 11:18:35.562377 | controller | ok
2025-09-30 11:18:35.569330 | 
2025-09-30 11:18:35.573498 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-30 11:18:36.164033 | controller -> localhost | ok
2025-09-30 11:18:36.170091 | 
2025-09-30 11:18:36.170167 | TASK [validate-host : Collect information about the host]
2025-09-30 11:18:36.778175 | controller | ok
2025-09-30 11:18:36.802010 | 
2025-09-30 11:18:36.802092 | TASK [validate-host : Sanitize hostname]
2025-09-30 11:18:36.978341 | controller | ok
2025-09-30 11:18:36.988559 | 
2025-09-30 11:18:36.988640 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-30 11:18:38.592023 | controller -> localhost | changed
2025-09-30 11:18:38.597657 | 
2025-09-30 11:18:38.597731 | TASK [validate-host : Collect information about zuul worker]
2025-09-30 11:18:39.325696 | controller | ok
2025-09-30 11:18:39.330192 | 
2025-09-30 11:18:39.330259 | TASK [validate-host : Write out all zuul information for each host]
2025-09-30 11:18:40.402981 | controller -> localhost | changed
2025-09-30 11:18:40.412491 | 
2025-09-30 11:18:40.412558 | TASK [include_role : prepare-workspace-openshift]
2025-09-30 11:18:40.468345 | controller | skipping: Conditional result was False
2025-09-30 11:18:40.474109 | 
2025-09-30 11:18:40.474184 | TASK [include_role : remove-zuul-sshkey]
2025-09-30 11:18:40.527551 | controller | skipping: Conditional result was False
2025-09-30 11:18:40.533458 | 
2025-09-30 11:18:40.533541 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-30 11:18:40.827792 | controller | ok: "logs"
2025-09-30 11:18:40.828007 | controller | ok: All items complete
2025-09-30 11:18:40.828037 | 
2025-09-30 11:18:41.015162 | controller | ok: "artifacts"
2025-09-30 11:18:41.192557 | controller | ok: "docs"
2025-09-30 11:18:41.204010 | 
2025-09-30 11:18:41.204098 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-30 11:18:41.453200 | controller | changed: "logs"
2025-09-30 11:18:41.642272 | controller | changed: "artifacts"
2025-09-30 11:18:41.823613 | controller | changed: "docs"
2025-09-30 11:18:41.875515 | 
2025-09-30 11:18:41.875603 | PLAY RECAP
2025-09-30 11:18:41.875646 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 11:18:41.875674 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 11:18:41.875694 | 
2025-09-30 11:18:42.038123 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 11:18:42.038885 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 11:18:43.273230 | 
2025-09-30 11:18:43.273338 | PLAY [all]
2025-09-30 11:18:43.294010 | 
2025-09-30 11:18:43.294085 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 11:18:43.409961 | controller | ok
2025-09-30 11:18:43.415642 | 
2025-09-30 11:18:43.415725 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 11:18:43.965078 | controller | changed
2025-09-30 11:18:43.970566 | 
2025-09-30 11:18:43.970641 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 11:18:45.228478 | controller | changed
2025-09-30 11:18:45.238860 | 
2025-09-30 11:18:45.238942 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-30 11:18:45.753458 | controller | changed:
2025-09-30 11:18:45.753647 | controller | {
2025-09-30 11:18:45.753682 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-30 11:18:45.753712 | controller | }
2025-09-30 11:18:45.964295 | controller | changed:
2025-09-30 11:18:45.964376 | controller | {
2025-09-30 11:18:45.964425 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-30 11:18:45.964451 | controller | }
2025-09-30 11:18:46.172117 | controller | changed:
2025-09-30 11:18:46.172326 | controller | {
2025-09-30 11:18:46.172625 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-30 11:18:46.172666 | controller | }
2025-09-30 11:18:46.389297 | controller | changed:
2025-09-30 11:18:46.389403 | controller | {
2025-09-30 11:18:46.389437 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-30 11:18:46.389459 | controller | }
2025-09-30 11:18:46.608833 | controller | changed:
2025-09-30 11:18:46.608916 | controller | {
2025-09-30 11:18:46.608943 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-30 11:18:46.608966 | controller | }
2025-09-30 11:18:46.827595 | controller | changed:
2025-09-30 11:18:46.827717 | controller | {
2025-09-30 11:18:46.827745 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-30 11:18:46.827767 | controller | }
2025-09-30 11:18:47.042894 | controller | changed:
2025-09-30 11:18:47.042991 | controller | {
2025-09-30 11:18:47.043018 | 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-09-30 11:18:47.043039 | controller | }
2025-09-30 11:18:47.258105 | controller | changed:
2025-09-30 11:18:47.258281 | controller | {
2025-09-30 11:18:47.258317 | 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-09-30 11:18:47.258431 | controller | }
2025-09-30 11:18:47.471927 | controller | changed:
2025-09-30 11:18:47.472019 | controller | {
2025-09-30 11:18:47.472047 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-30 11:18:47.472068 | controller | }
2025-09-30 11:18:47.693523 | controller | changed:
2025-09-30 11:18:47.693613 | controller | {
2025-09-30 11:18:47.693640 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-30 11:18:47.693662 | controller | }
2025-09-30 11:18:47.952113 | controller | changed:
2025-09-30 11:18:47.952216 | controller | {
2025-09-30 11:18:47.952245 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-30 11:18:47.952266 | controller | }
2025-09-30 11:18:48.132994 | controller | changed:
2025-09-30 11:18:48.133079 | controller | {
2025-09-30 11:18:48.133106 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-30 11:18:48.133435 | controller | }
2025-09-30 11:18:48.386571 | controller | changed:
2025-09-30 11:18:48.386657 | controller | {
2025-09-30 11:18:48.386684 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-30 11:18:48.386705 | controller | }
2025-09-30 11:18:48.574043 | controller | changed:
2025-09-30 11:18:48.574123 | controller | {
2025-09-30 11:18:48.574159 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-30 11:18:48.574182 | controller | }
2025-09-30 11:18:48.796275 | controller | changed:
2025-09-30 11:18:48.796360 | controller | {
2025-09-30 11:18:48.796411 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-30 11:18:48.796437 | controller | }
2025-09-30 11:18:49.029197 | controller | changed:
2025-09-30 11:18:49.029279 | controller | {
2025-09-30 11:18:49.029304 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-30 11:18:49.029324 | controller | }
2025-09-30 11:18:49.252114 | controller | changed:
2025-09-30 11:18:49.252196 | controller | {
2025-09-30 11:18:49.252223 | 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-09-30 11:18:49.252245 | controller | }
2025-09-30 11:18:49.473270 | controller | changed:
2025-09-30 11:18:49.473356 | controller | {
2025-09-30 11:18:49.473398 | 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-09-30 11:18:49.473435 | controller | }
2025-09-30 11:18:49.690076 | controller | changed:
2025-09-30 11:18:49.690452 | controller | {
2025-09-30 11:18:49.690487 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-30 11:18:49.690510 | controller | }
2025-09-30 11:18:49.908469 | controller | changed:
2025-09-30 11:18:49.908563 | controller | {
2025-09-30 11:18:49.908590 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-30 11:18:49.908612 | controller | }
2025-09-30 11:18:50.110556 | controller | changed:
2025-09-30 11:18:50.110630 | controller | {
2025-09-30 11:18:50.110657 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-30 11:18:50.110677 | controller | }
2025-09-30 11:18:50.336426 | controller | changed:
2025-09-30 11:18:50.336647 | controller | {
2025-09-30 11:18:50.336793 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-30 11:18:50.336825 | controller | }
2025-09-30 11:18:50.546668 | controller | changed:
2025-09-30 11:18:50.546753 | controller | {
2025-09-30 11:18:50.546778 | 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-09-30 11:18:50.546799 | controller | }
2025-09-30 11:18:50.765978 | controller | changed:
2025-09-30 11:18:50.766059 | controller | {
2025-09-30 11:18:50.766088 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-30 11:18:50.766109 | controller | }
2025-09-30 11:18:50.978191 | controller | changed:
2025-09-30 11:18:50.978274 | controller | {
2025-09-30 11:18:50.978300 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-30 11:18:50.978320 | controller | }
2025-09-30 11:18:51.190863 | controller | changed:
2025-09-30 11:18:51.191031 | controller | {
2025-09-30 11:18:51.191068 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-30 11:18:51.191092 | controller | }
2025-09-30 11:18:51.217713 | 
2025-09-30 11:18:51.217819 | TASK [Set timezone to UTC]
2025-09-30 11:18:51.685997 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-30 11:18:51.691642 | 
2025-09-30 11:18:51.691708 | TASK [Create nodepool directory]
2025-09-30 11:18:51.894070 | controller | changed
2025-09-30 11:18:51.900576 | 
2025-09-30 11:18:51.900642 | TASK [Create nodepool sub_nodes file]
2025-09-30 11:18:52.399767 | controller | changed
2025-09-30 11:18:52.405954 | 
2025-09-30 11:18:52.406031 | TASK [Create nodepool sub_nodes_private file]
2025-09-30 11:18:52.897171 | controller | changed
2025-09-30 11:18:52.902399 | 
2025-09-30 11:18:52.902461 | LOOP [Populate nodepool sub_nodes file]
2025-09-30 11:18:52.938204 | 
2025-09-30 11:18:52.938346 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-30 11:18:52.966963 | 
2025-09-30 11:18:52.967089 | TASK [Create nodepool primary file]
2025-09-30 11:18:52.989844 | controller | skipping: Conditional result was False
2025-09-30 11:18:52.995521 | 
2025-09-30 11:18:52.995595 | TASK [Create nodepool node_private for this node]
2025-09-30 11:18:53.542573 | controller | changed
2025-09-30 11:18:53.551504 | 
2025-09-30 11:18:53.551571 | LOOP [Copy ssh keys to nodepool directory]
2025-09-30 11:18:53.909449 | controller | ok: Item: id_rsa Runtime: 0:00:00.005354
2025-09-30 11:18:53.909615 | 
2025-09-30 11:18:54.096821 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004586
2025-09-30 11:18:54.111805 | 
2025-09-30 11:18:54.111905 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-30 11:18:54.650781 | controller | changed
2025-09-30 11:18:54.656226 | 
2025-09-30 11:18:54.656301 | TASK [Validate sudoers config after edits]
2025-09-30 11:18:54.879622 | controller | /etc/sudoers: parsed OK
2025-09-30 11:18:54.879692 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-30 11:18:54.879703 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-30 11:18:54.879710 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-30 11:18:55.188517 | controller | ok: Runtime: 0:00:00.005335
2025-09-30 11:18:55.193784 | 
2025-09-30 11:18:55.193850 | TASK [Show the environment passed in to job shell scripts]
2025-09-30 11:18:55.407382 | controller | SHELL=/bin/bash
2025-09-30 11:18:55.407422 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-30 11:18:55.407431 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-30 11:18:55.407438 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/53/3353/70225949d764f12a7e698f7b0c4331d3defbb889^openstack-k8s-operators/ci-framework:main:refs/changes/33/3333/b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 11:18:55.407445 | controller | PWD=/home/zuul
2025-09-30 11:18:55.407451 | controller | ZUUL_PIPELINE=github-check
2025-09-30 11:18:55.407456 | controller | LOGNAME=zuul
2025-09-30 11:18:55.407461 | controller | XDG_SESSION_TYPE=tty
2025-09-30 11:18:55.407466 | controller | _=/usr/bin/env
2025-09-30 11:18:55.407472 | controller | MOTD_SHOWN=pam
2025-09-30 11:18:55.407477 | controller | HOME=/home/zuul
2025-09-30 11:18:55.407482 | controller | LANG=en_US.UTF-8
2025-09-30 11:18:55.407487 | controller | SSH_CONNECTION=192.168.25.12 57118 192.168.25.180 22
2025-09-30 11:18:55.407519 | 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-09-30 11:18:55.407526 | controller | ZUUL_CHANGE_IDS=3353,70225949d764f12a7e698f7b0c4331d3defbb889 3333,b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 11:18:55.407531 | controller | WORKSPACE=/home/zuul/workspace
2025-09-30 11:18:55.407536 | controller | XDG_SESSION_CLASS=user
2025-09-30 11:18:55.407542 | controller | SELINUX_ROLE_REQUESTED=
2025-09-30 11:18:55.407547 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-30 11:18:55.407552 | controller | USER=zuul
2025-09-30 11:18:55.407557 | controller | ZUUL_VOTING=True
2025-09-30 11:18:55.407562 | controller | BUILD_TIMEOUT=1800000
2025-09-30 11:18:55.407568 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-30 11:18:55.407573 | controller | SHLVL=1
2025-09-30 11:18:55.407578 | controller | ZUUL_PATCHSET=b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 11:18:55.407583 | controller | XDG_SESSION_ID=1
2025-09-30 11:18:55.407588 | controller | ZUUL_BRANCH=main
2025-09-30 11:18:55.407594 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-30 11:18:55.407599 | controller | SSH_CLIENT=192.168.25.12 57118 22
2025-09-30 11:18:55.407604 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-30 11:18:55.407609 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-30 11:18:55.407614 | controller | which_declare=declare -f
2025-09-30 11:18:55.407619 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-30 11:18:55.407625 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-30 11:18:55.407630 | controller | ZUUL_CHANGE=3333
2025-09-30 11:18:55.407635 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-30 11:18:55.407640 | controller | ZUUL_UUID=44eb7d83f89d46b08ac576e1bcb719f8
2025-09-30 11:18:55.407647 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-30 11:18:55.407653 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-30 11:18:55.407658 | controller | }
2025-09-30 11:18:55.720850 | controller | ok: Runtime: 0:00:00.005689
2025-09-30 11:18:55.726790 | 
2025-09-30 11:18:55.726859 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-30 11:18:55.742821 | controller | skipping: Conditional result was False
2025-09-30 11:18:55.749025 | 
2025-09-30 11:18:55.749091 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-30 11:18:56.275134 | controller | skipping: Conditional result was False
2025-09-30 11:18:56.280646 | 
2025-09-30 11:18:56.280712 | TASK [Ensure legacy workspace directory]
2025-09-30 11:18:56.471862 | controller | changed
2025-09-30 11:18:56.493037 | 
2025-09-30 11:18:56.493116 | PLAY RECAP
2025-09-30 11:18:56.493180 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 11:18:56.493206 | 
2025-09-30 11:18:56.578116 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 11:18:56.578888 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 11:18:57.116299 | 
2025-09-30 11:18:57.116410 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-30 11:18:57.144928 | 
2025-09-30 11:18:57.145037 | TASK [Create zuul-output directory]
2025-09-30 11:18:57.468224 | controller | changed
2025-09-30 11:18:57.473094 | 
2025-09-30 11:18:57.473189 | TASK [Slurp Zuul inventory test]
2025-09-30 11:18:57.777744 | controller -> localhost | ok
2025-09-30 11:18:57.783854 | 
2025-09-30 11:18:57.783922 | TASK [Save zuul inventory]
2025-09-30 11:18:58.577726 | controller | changed
2025-09-30 11:18:58.583310 | 
2025-09-30 11:18:58.583405 | TASK [Save zuul vars without the change_message]
2025-09-30 11:18:59.185515 | controller | changed
2025-09-30 11:18:59.203649 | 
2025-09-30 11:18:59.203728 | PLAY RECAP
2025-09-30 11:18:59.203771 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 11:18:59.203793 | 
2025-09-30 11:18:59.289548 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 11:18:59.290328 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 11:18:59.922230 | 
2025-09-30 11:18:59.922374 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-30 11:18:59.944452 | 
2025-09-30 11:18:59.944557 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-30 11:18:59.964923 | controller | ok
2025-09-30 11:18:59.983206 | 
2025-09-30 11:18:59.983310 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 11:19:00.020694 | controller | skipping: Conditional result was False
2025-09-30 11:19:00.026901 | 
2025-09-30 11:19:00.026982 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 11:19:00.363215 | controller | ok
2025-09-30 11:19:00.368948 | 
2025-09-30 11:19:00.369019 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 11:19:00.967218 | controller | ok
2025-09-30 11:19:00.978317 | 
2025-09-30 11:19:00.978441 | TASK [Prepare workspace]
2025-09-30 11:19:00.997749 | controller | ok
2025-09-30 11:19:01.014944 | 
2025-09-30 11:19:01.015038 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-30 11:19:01.310295 | controller | ok
2025-09-30 11:19:01.316272 | 
2025-09-30 11:19:01.316348 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-30 11:19:03.492572 | controller | Output suppressed because no_log was given
2025-09-30 11:19:03.508119 | 
2025-09-30 11:19:03.508242 | LOOP [Create zuul-output directory]
2025-09-30 11:19:03.697507 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-30 11:19:03.856973 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-30 11:19:03.873721 | 
2025-09-30 11:19:03.874171 | TASK [Install required packages]
2025-09-30 11:19:56.822062 | controller | changed
2025-09-30 11:19:56.827766 | 
2025-09-30 11:19:56.827830 | TASK [Install venv]
2025-09-30 11:21:02.933088 | controller | changed
2025-09-30 11:21:02.962900 | 
2025-09-30 11:21:02.963017 | PLAY RECAP
2025-09-30 11:21:02.963068 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 11:21:02.963092 | 
2025-09-30 11:21:03.062728 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 11:21:03.063680 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 11:21:03.611778 | 
2025-09-30 11:21:03.611877 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-30 11:21:03.632164 | 
2025-09-30 11:21:03.632242 | TASK [Gather required facts]
2025-09-30 11:21:04.128039 | controller | ok
2025-09-30 11:21:04.133800 | 
2025-09-30 11:21:04.133883 | TASK [Load environment var if instructed to]
2025-09-30 11:21:04.158492 | controller | skipping: Conditional result was False
2025-09-30 11:21:04.164410 | 
2025-09-30 11:21:04.164482 | TASK [Run molecule]
2025-09-30 11:21:05.089824 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-30 11:21:05.157931 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-30 11:21:21.593306 | 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-09-30 11:21:21.593772 | 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-09-30 11:21:21.594230 | 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-09-30 11:21:21.594710 | 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-09-30 11:21:21.595205 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f3b7a729 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.0.0+f3b7a729 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 11:21:21.595693 | 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-09-30 11:21:21.596142 | 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-09-30 11:21:21.596593 | 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-09-30 11:21:21.597055 | 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-09-30 11:21:21.597506 | 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-09-30 11:21:21.597975 | 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-09-30 11:21:21.598428 | 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-09-30 11:21:21.598907 | 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-09-30 11:21:21.599362 | 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-09-30 11:21:21.599815 | 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-09-30 11:21:21.609462 | controller | INFO     Running default > prepare
2025-09-30 11:21:22.266867 | controller |
2025-09-30 11:21:22.267006 | controller | PLAY [Prepare] *****************************************************************
2025-09-30 11:21:22.267125 | controller |
2025-09-30 11:21:22.267230 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 11:21:22.267351 | controller | Tuesday 30 September 2025  11:21:22 +0000 (0:00:00.012)       0:00:00.013 *****
2025-09-30 11:21:23.097961 | controller | ok: [instance]
2025-09-30 11:21:23.098076 | controller |
2025-09-30 11:21:23.098226 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-30 11:21:23.098385 | controller | Tuesday 30 September 2025  11:21:23 +0000 (0:00:00.831)       0:00:00.844 *****
2025-09-30 11:21:23.116024 | controller | skipping: [instance]
2025-09-30 11:21:23.116093 | controller |
2025-09-30 11:21:23.116196 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-30 11:21:23.116299 | controller | Tuesday 30 September 2025  11:21:23 +0000 (0:00:00.018)       0:00:00.862 *****
2025-09-30 11:21:23.157143 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-09-30 11:21:23.157224 | controller |
2025-09-30 11:21:23.157347 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-30 11:21:23.157450 | controller | Tuesday 30 September 2025  11:21:23 +0000 (0:00:00.041)       0:00:00.904 *****
2025-09-30 11:21:23.434009 | controller | ok: [instance]
2025-09-30 11:21:23.434084 | controller |
2025-09-30 11:21:23.434196 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-30 11:21:23.434301 | controller | Tuesday 30 September 2025  11:21:23 +0000 (0:00:00.276)       0:00:01.180 *****
2025-09-30 11:21:23.739719 | controller | ok: [instance]
2025-09-30 11:21:23.739790 | controller |
2025-09-30 11:21:23.739903 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-30 11:21:23.739999 | controller | Tuesday 30 September 2025  11:21:23 +0000 (0:00:00.305)       0:00:01.486 *****
2025-09-30 11:21:24.416884 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-30 11:21:24.416986 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-30 11:21:24.417110 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-30 11:21:24.417223 | controller |
2025-09-30 11:21:24.417331 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-30 11:21:24.417435 | controller | Tuesday 30 September 2025  11:21:24 +0000 (0:00:00.677)       0:00:02.163 *****
2025-09-30 11:21:24.476775 | controller |
2025-09-30 11:21:24.476879 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-30 11:21:24.477003 | controller | Tuesday 30 September 2025  11:21:24 +0000 (0:00:00.060)       0:00:02.223 *****
2025-09-30 11:21:25.001027 | controller | changed: [instance] => (item=tmp)
2025-09-30 11:21:25.001111 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-30 11:21:25.001226 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-30 11:21:25.001374 | controller |
2025-09-30 11:21:25.001480 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-30 11:21:25.001595 | controller | Tuesday 30 September 2025  11:21:24 +0000 (0:00:00.523)       0:00:02.747 *****
2025-09-30 11:21:25.769876 | controller | ok: [instance]
2025-09-30 11:21:25.770181 | controller |
2025-09-30 11:21:26.969915 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-30 11:21:26.969945 | controller | Tuesday 30 September 2025  11:21:25 +0000 (0:00:00.768)       0:00:03.516 *****
2025-09-30 11:21:26.969957 | controller | changed: [instance]
2025-09-30 11:21:35.896077 | controller |
2025-09-30 11:21:35.896117 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-30 11:21:35.896129 | controller | Tuesday 30 September 2025  11:21:26 +0000 (0:00:01.199)       0:00:04.716 *****
2025-09-30 11:21:35.896139 | controller | changed: [instance]
2025-09-30 11:21:36.597386 | controller |
2025-09-30 11:21:36.597424 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-30 11:21:36.597432 | controller | Tuesday 30 September 2025  11:21:35 +0000 (0:00:08.926)       0:00:13.642 *****
2025-09-30 11:21:36.597448 | controller | changed: [instance]
2025-09-30 11:21:36.597466 | controller |
2025-09-30 11:21:36.597694 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-30 11:21:36.619000 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.701)       0:00:14.344 *****
2025-09-30 11:21:36.619020 | controller | skipping: [instance]
2025-09-30 11:21:37.428234 | controller |
2025-09-30 11:21:37.428263 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-30 11:21:37.428271 | controller | Tuesday 30 September 2025  11:21:36 +0000 (0:00:00.021)       0:00:14.365 *****
2025-09-30 11:21:37.428282 | controller | changed: [instance]
2025-09-30 11:21:37.464422 | controller |
2025-09-30 11:21:37.464445 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-30 11:21:37.464453 | controller | Tuesday 30 September 2025  11:21:37 +0000 (0:00:00.808)       0:00:15.174 *****
2025-09-30 11:21:37.464463 | controller | skipping: [instance]
2025-09-30 11:21:37.500403 | controller |
2025-09-30 11:21:37.500467 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-30 11:21:37.500478 | controller | Tuesday 30 September 2025  11:21:37 +0000 (0:00:00.036)       0:00:15.211 *****
2025-09-30 11:21:37.500489 | controller | skipping: [instance]
2025-09-30 11:21:37.500514 | controller |
2025-09-30 11:21:37.500522 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-30 11:21:37.501049 | controller | Tuesday 30 September 2025  11:21:37 +0000 (0:00:00.035)       0:00:15.247 *****
2025-09-30 11:21:37.536444 | controller | skipping: [instance]
2025-09-30 11:21:38.115839 | controller |
2025-09-30 11:21:38.115875 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-30 11:21:38.115884 | controller | Tuesday 30 September 2025  11:21:37 +0000 (0:00:00.036)       0:00:15.283 *****
2025-09-30 11:21:38.115895 | controller | changed: [instance]
2025-09-30 11:21:38.557725 | controller |
2025-09-30 11:21:38.557756 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-30 11:21:38.557765 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.579)       0:00:15.862 *****
2025-09-30 11:21:38.557775 | controller | changed: [instance]
2025-09-30 11:21:38.586002 | controller |
2025-09-30 11:21:38.586024 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-30 11:21:38.586033 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.442)       0:00:16.304 *****
2025-09-30 11:21:38.586042 | controller | skipping: [instance]
2025-09-30 11:21:38.614769 | controller |
2025-09-30 11:21:38.614797 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-30 11:21:38.614805 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.028)       0:00:16.332 *****
2025-09-30 11:21:38.614819 | controller | skipping: [instance]
2025-09-30 11:21:38.642978 | controller |
2025-09-30 11:21:38.643007 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-30 11:21:38.643015 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.028)       0:00:16.361 *****
2025-09-30 11:21:38.643025 | controller | skipping: [instance]
2025-09-30 11:21:38.679837 | controller |
2025-09-30 11:21:38.679865 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-30 11:21:38.679873 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.028)       0:00:16.389 *****
2025-09-30 11:21:38.679883 | controller | ok: [instance]
2025-09-30 11:21:38.705431 | controller |
2025-09-30 11:21:38.705459 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-30 11:21:38.705467 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.036)       0:00:16.426 *****
2025-09-30 11:21:38.705476 | controller | skipping: [instance]
2025-09-30 11:21:38.705666 | controller |
2025-09-30 11:21:38.705685 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-30 11:21:38.732392 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.025)       0:00:16.452 *****
2025-09-30 11:21:38.732427 | controller | skipping: [instance]
2025-09-30 11:21:38.732659 | controller |
2025-09-30 11:21:38.732680 | controller | TASK [Download the RPM] ********************************************************
2025-09-30 11:21:38.759385 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.026)       0:00:16.479 *****
2025-09-30 11:21:38.759408 | controller | skipping: [instance]
2025-09-30 11:21:38.759598 | controller |
2025-09-30 11:21:38.759616 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-30 11:21:38.785969 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.027)       0:00:16.506 *****
2025-09-30 11:21:38.785994 | controller | skipping: [instance]
2025-09-30 11:21:38.812241 | controller |
2025-09-30 11:21:38.812263 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-30 11:21:38.812272 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.026)       0:00:16.532 *****
2025-09-30 11:21:38.812282 | controller | skipping: [instance]
2025-09-30 11:21:38.842276 | controller |
2025-09-30 11:21:38.842300 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-30 11:21:38.842309 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.026)       0:00:16.558 *****
2025-09-30 11:21:38.842340 | controller | skipping: [instance]
2025-09-30 11:21:38.883381 | controller |
2025-09-30 11:21:38.883413 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-30 11:21:38.883421 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.029)       0:00:16.588 *****
2025-09-30 11:21:38.883431 | controller | skipping: [instance]
2025-09-30 11:21:39.062750 | controller |
2025-09-30 11:21:39.062779 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-30 11:21:39.062787 | controller | Tuesday 30 September 2025  11:21:38 +0000 (0:00:00.041)       0:00:16.630 *****
2025-09-30 11:21:39.062797 | controller | ok: [instance]
2025-09-30 11:21:39.267418 | controller |
2025-09-30 11:21:39.267448 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-30 11:21:39.267456 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.179)       0:00:16.809 *****
2025-09-30 11:21:39.267465 | controller | changed: [instance]
2025-09-30 11:21:39.493007 | controller |
2025-09-30 11:21:39.493038 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-30 11:21:39.493046 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.204)       0:00:17.013 *****
2025-09-30 11:21:39.493056 | controller | changed: [instance]
2025-09-30 11:21:39.513887 | controller |
2025-09-30 11:21:39.513908 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-30 11:21:39.513915 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.225)       0:00:17.239 *****
2025-09-30 11:21:39.513924 | controller | skipping: [instance]
2025-09-30 11:21:39.534852 | controller |
2025-09-30 11:21:39.534871 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-30 11:21:39.534878 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.021)       0:00:17.260 *****
2025-09-30 11:21:39.534886 | controller | skipping: [instance]
2025-09-30 11:21:39.556098 | controller |
2025-09-30 11:21:39.556118 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-30 11:21:39.556126 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.020)       0:00:17.281 *****
2025-09-30 11:21:39.556136 | controller | skipping: [instance]
2025-09-30 11:21:39.577376 | controller |
2025-09-30 11:21:39.577405 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-30 11:21:39.577413 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.021)       0:00:17.302 *****
2025-09-30 11:21:39.577424 | controller | skipping: [instance]
2025-09-30 11:21:39.598206 | controller |
2025-09-30 11:21:39.598243 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-30 11:21:39.598268 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.021)       0:00:17.323 *****
2025-09-30 11:21:39.598281 | controller | skipping: [instance]
2025-09-30 11:21:39.598498 | controller |
2025-09-30 11:21:39.598712 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-30 11:21:39.598882 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.021)       0:00:17.345 *****
2025-09-30 11:21:39.618805 | controller | skipping: [instance]
2025-09-30 11:21:39.618981 | controller |
2025-09-30 11:21:39.619158 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-30 11:21:39.619347 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.020)       0:00:17.365 *****
2025-09-30 11:21:39.634566 | controller | skipping: [instance]
2025-09-30 11:21:39.634749 | controller |
2025-09-30 11:21:39.634907 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-30 11:21:39.635040 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.015)       0:00:17.381 *****
2025-09-30 11:21:39.662445 | controller | skipping: [instance]
2025-09-30 11:21:39.662667 | controller |
2025-09-30 11:21:39.662851 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-30 11:21:39.663016 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.027)       0:00:17.409 *****
2025-09-30 11:21:39.678543 | controller | skipping: [instance]
2025-09-30 11:21:39.678728 | controller |
2025-09-30 11:21:39.678899 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-30 11:21:39.679052 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.016)       0:00:17.425 *****
2025-09-30 11:21:39.697859 | controller | skipping: [instance]
2025-09-30 11:21:39.698017 | controller |
2025-09-30 11:21:39.698161 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-30 11:21:39.698300 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.019)       0:00:17.444 *****
2025-09-30 11:21:39.726315 | controller | skipping: [instance]
2025-09-30 11:21:39.726545 | controller |
2025-09-30 11:21:39.726726 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-30 11:21:39.726894 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.028)       0:00:17.473 *****
2025-09-30 11:21:39.757652 | controller | skipping: [instance]
2025-09-30 11:21:39.757850 | controller |
2025-09-30 11:21:39.758026 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-30 11:21:39.758175 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.031)       0:00:17.504 *****
2025-09-30 11:21:39.786743 | controller | skipping: [instance]
2025-09-30 11:21:39.786936 | controller |
2025-09-30 11:21:39.787110 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-30 11:21:39.787262 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.029)       0:00:17.533 *****
2025-09-30 11:21:39.815731 | controller | skipping: [instance]
2025-09-30 11:21:39.815894 | controller |
2025-09-30 11:21:39.816045 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-30 11:21:39.816193 | controller | Tuesday 30 September 2025  11:21:39 +0000 (0:00:00.028)       0:00:17.562 *****
2025-09-30 11:22:33.979981 | controller | ok: [instance]
2025-09-30 11:22:35.148473 | controller |
2025-09-30 11:22:35.148510 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-30 11:22:35.148518 | controller | Tuesday 30 September 2025  11:22:33 +0000 (0:00:54.163)       0:01:11.726 *****
2025-09-30 11:22:35.148528 | controller | ok: [instance]
2025-09-30 11:22:35.192707 | controller |
2025-09-30 11:22:35.192741 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-30 11:22:35.192749 | controller | Tuesday 30 September 2025  11:22:35 +0000 (0:00:01.168)       0:01:12.894 *****
2025-09-30 11:22:35.192759 | controller | skipping: [instance]
2025-09-30 11:22:35.192782 | controller |
2025-09-30 11:22:35.194363 | controller | PLAY RECAP *********************************************************************
2025-09-30 11:22:35.195120 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-09-30 11:22:35.195136 | controller |
2025-09-30 11:22:35.195143 | controller | Tuesday 30 September 2025  11:22:35 +0000 (0:00:00.044)       0:01:12.939 *****
2025-09-30 11:22:35.195149 | controller | ===============================================================================
2025-09-30 11:22:35.195154 | controller | test_deps : Install selinux python libs -------------------------------- 54.16s
2025-09-30 11:22:35.195168 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.93s
2025-09-30 11:22:35.195173 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.20s
2025-09-30 11:22:35.195179 | controller | test_deps : Install python yaml libs ------------------------------------ 1.17s
2025-09-30 11:22:35.195189 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-09-30 11:22:35.195195 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.81s
2025-09-30 11:22:35.195200 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-09-30 11:22:35.195205 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-09-30 11:22:35.195210 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-09-30 11:22:35.195216 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-09-30 11:22:35.195221 | controller | repo_setup : Ensure directories are present ----------------------------- 0.52s
2025-09-30 11:22:35.195226 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-09-30 11:22:35.195234 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-09-30 11:22:35.250656 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-09-30 11:22:35.250685 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-09-30 11:22:35.250693 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2025-09-30 11:22:35.250698 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-09-30 11:22:35.250704 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-09-30 11:22:35.250709 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-09-30 11:22:35.250714 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-09-30 11:22:35.250725 | controller | INFO     Running default > converge
2025-09-30 11:22:35.672096 | controller |
2025-09-30 11:22:35.672304 | controller | PLAY [Converge] ****************************************************************
2025-09-30 11:22:35.672502 | controller |
2025-09-30 11:22:35.672648 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 11:22:35.672787 | controller | Tuesday 30 September 2025  11:22:35 +0000 (0:00:00.013)       0:00:00.013 *****
2025-09-30 11:22:36.448684 | controller | ok: [instance]
2025-09-30 11:22:37.470483 | controller |
2025-09-30 11:22:37.470524 | controller | TASK [Get open PR list for mariadb-operator] ***********************************
2025-09-30 11:22:37.470533 | controller | Tuesday 30 September 2025  11:22:36 +0000 (0:00:00.776)       0:00:00.789 *****
2025-09-30 11:22:37.470543 | controller | ok: [instance]
2025-09-30 11:22:37.470567 | controller |
2025-09-30 11:22:37.470575 | controller | TASK [Extract latest PR from list] *********************************************
2025-09-30 11:22:37.470678 | controller | Tuesday 30 September 2025  11:22:37 +0000 (0:00:01.021)       0:00:01.811 *****
2025-09-30 11:22:37.510762 | controller | ok: [instance]
2025-09-30 11:22:37.539878 | controller |
2025-09-30 11:22:37.539900 | controller | TASK [Build keystone-operator] *************************************************
2025-09-30 11:22:37.539909 | controller | Tuesday 30 September 2025  11:22:37 +0000 (0:00:00.040)       0:00:01.852 *****
2025-09-30 11:22:37.539920 | controller |
2025-09-30 11:22:37.997960 | controller | TASK [operator_build : Ensure mandatory directories exist] *********************
2025-09-30 11:22:37.997990 | controller | Tuesday 30 September 2025  11:22:37 +0000 (0:00:00.029)       0:00:01.881 *****
2025-09-30 11:22:37.998001 | controller | ok: [instance] => (item=artifacts)
2025-09-30 11:22:38.017715 | controller | ok: [instance] => (item=logs)
2025-09-30 11:22:38.017734 | controller |
2025-09-30 11:22:38.017742 | controller | TASK [operator_build : Initialize role output] *********************************
2025-09-30 11:22:38.017747 | controller | Tuesday 30 September 2025  11:22:37 +0000 (0:00:00.457)       0:00:02.339 *****
2025-09-30 11:22:38.017763 | controller | ok: [instance]
2025-09-30 11:22:38.039585 | controller |
2025-09-30 11:22:38.039603 | controller | TASK [operator_build : Populate operators list with zuul info] *****************
2025-09-30 11:22:38.039611 | controller | Tuesday 30 September 2025  11:22:38 +0000 (0:00:00.020)       0:00:02.359 *****
2025-09-30 11:22:38.039620 | controller | skipping: [instance]
2025-09-30 11:22:38.089015 | controller |
2025-09-30 11:22:38.089032 | controller | TASK [operator_build : Merge lists of operators] *******************************
2025-09-30 11:22:38.089045 | controller | Tuesday 30 September 2025  11:22:38 +0000 (0:00:00.021)       0:00:02.381 *****
2025-09-30 11:22:38.089054 | controller | ok: [instance]
2025-09-30 11:22:38.126809 | controller |
2025-09-30 11:22:38.126826 | controller | TASK [operator_build : Get meta_operator src dir from operators_list] **********
2025-09-30 11:22:38.126834 | controller | Tuesday 30 September 2025  11:22:38 +0000 (0:00:00.049)       0:00:02.430 *****
2025-09-30 11:22:38.126842 | controller | ok: [instance]
2025-09-30 11:22:38.170385 | controller |
2025-09-30 11:22:38.170403 | controller | TASK [operator_build : Adds meta-operator to the list] *************************
2025-09-30 11:22:38.170410 | controller | Tuesday 30 September 2025  11:22:38 +0000 (0:00:00.037)       0:00:02.468 *****
2025-09-30 11:22:38.170419 | controller | ok: [instance]
2025-09-30 11:22:38.229087 | controller |
2025-09-30 11:22:38.229106 | controller | TASK [operator_build : Clone operator's code when src dir is empty] ************
2025-09-30 11:22:38.229113 | controller | Tuesday 30 September 2025  11:22:38 +0000 (0:00:00.043)       0:00:02.511 *****
2025-09-30 11:22:38.229123 | 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-09-30 11:22:38.476396 | 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-09-30 11:22:38.476437 | controller |
2025-09-30 11:22:38.476445 | controller | TASK [operator_build : keystone-operator - Check if src code exists] ***********
2025-09-30 11:22:38.476451 | controller | Tuesday 30 September 2025  11:22:38 +0000 (0:00:00.058)       0:00:02.570 *****
2025-09-30 11:22:38.476461 | controller | ok: [instance]
2025-09-30 11:22:38.476485 | controller |
2025-09-30 11:22:38.476507 | controller | TASK [operator_build : keystone-operator - Clone from github if no code was provided] ***
2025-09-30 11:22:38.476668 | controller | Tuesday 30 September 2025  11:22:38 +0000 (0:00:00.247)       0:00:02.817 *****
2025-09-30 11:22:39.978643 | controller | changed: [instance]
2025-09-30 11:22:40.154431 | controller |
2025-09-30 11:22:40.154463 | controller | TASK [operator_build : openstack-operator - Check if src code exists] **********
2025-09-30 11:22:40.154471 | controller | Tuesday 30 September 2025  11:22:39 +0000 (0:00:01.502)       0:00:04.319 *****
2025-09-30 11:22:40.154482 | controller | ok: [instance]
2025-09-30 11:22:42.730537 | controller |
2025-09-30 11:22:42.730567 | controller | TASK [operator_build : openstack-operator - Clone from github if no code was provided] ***
2025-09-30 11:22:42.730575 | controller | Tuesday 30 September 2025  11:22:40 +0000 (0:00:00.175)       0:00:04.495 *****
2025-09-30 11:22:42.730585 | controller | changed: [instance]
2025-09-30 11:22:42.778118 | controller |
2025-09-30 11:22:42.778142 | controller | TASK [operator_build : Building operators] *************************************
2025-09-30 11:22:42.778150 | controller | Tuesday 30 September 2025  11:22:42 +0000 (0:00:02.576)       0:00:07.071 *****
2025-09-30 11:22:42.778160 | controller | skipping: [instance] => (item={'name': 'openstack-operator', 'src': '/home/zuul/openstack-operator'})
2025-09-30 11:22:43.068900 | 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-09-30 11:22:43.068932 | controller |
2025-09-30 11:22:43.068940 | controller | TASK [operator_build : keystone-operator - Read go.mod file contents of Openstack Operator] ***
2025-09-30 11:22:43.068946 | controller | Tuesday 30 September 2025  11:22:42 +0000 (0:00:00.047)       0:00:07.119 *****
2025-09-30 11:22:43.068956 | controller | ok: [instance]
2025-09-30 11:22:43.103829 | controller |
2025-09-30 11:22:43.103858 | controller | TASK [operator_build : keystone-operator - Get base module from go.mod] ********
2025-09-30 11:22:43.103873 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.290)       0:00:07.410 *****
2025-09-30 11:22:43.103883 | controller | ok: [instance]
2025-09-30 11:22:43.138207 | controller |
2025-09-30 11:22:43.138236 | controller | TASK [operator_build : Get the base module name not empty operator_base_module] ***
2025-09-30 11:22:43.138244 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.034)       0:00:07.445 *****
2025-09-30 11:22:43.138255 | controller | ok: [instance]
2025-09-30 11:22:43.164748 | controller |
2025-09-30 11:22:43.164779 | controller | TASK [operator_build : keystone-operator - Set default api path] ***************
2025-09-30 11:22:43.164787 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.034)       0:00:07.479 *****
2025-09-30 11:22:43.164797 | controller | ok: [instance]
2025-09-30 11:22:43.189832 | controller |
2025-09-30 11:22:43.189864 | controller | TASK [operator_build : keystone-operator - Update the go.mod file in meta operator for provided PR_SHA] ***
2025-09-30 11:22:43.189873 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.026)       0:00:07.505 *****
2025-09-30 11:22:43.189884 | controller | skipping: [instance]
2025-09-30 11:22:43.190055 | controller |
2025-09-30 11:22:43.190242 | controller | TASK [operator_build : keystone-operator - Get latest commit when no PR is provided] ***
2025-09-30 11:22:43.190454 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.025)       0:00:07.531 *****
2025-09-30 11:22:43.470711 | controller | changed: [instance]
2025-09-30 11:22:43.502461 | controller |
2025-09-30 11:22:43.502503 | controller | TASK [operator_build : keystone-operator - Set pr_sha to be used as image tag] ***
2025-09-30 11:22:43.502511 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.280)       0:00:07.812 *****
2025-09-30 11:22:43.502523 | controller | ok: [instance]
2025-09-30 11:22:43.697861 | controller |
2025-09-30 11:22:43.697889 | controller | TASK [operator_build : keystone-operator - Update the go.mod file using latest commit if no PR is provided] ***
2025-09-30 11:22:43.697898 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.031)       0:00:07.844 *****
2025-09-30 11:22:43.697908 | controller | changed: [instance]
2025-09-30 11:22:54.970115 | controller |
2025-09-30 11:22:54.970152 | controller | TASK [operator_build : keystone-operator - Get golang container image] *********
2025-09-30 11:22:54.970166 | controller | Tuesday 30 September 2025  11:22:43 +0000 (0:00:00.195)       0:00:08.039 *****
2025-09-30 11:22:54.970177 | controller | changed: [instance]
2025-09-30 11:22:54.994358 | controller |
2025-09-30 11:22:54.994392 | controller | TASK [operator_build : keystone-operator - Set operator image tag] *************
2025-09-30 11:22:54.994400 | controller | Tuesday 30 September 2025  11:22:54 +0000 (0:00:11.272)       0:00:19.311 *****
2025-09-30 11:22:54.994410 | controller | ok: [instance]
2025-09-30 11:22:55.016095 | controller |
2025-09-30 11:22:55.016122 | controller | TASK [operator_build : keystone-operator - Set operator image names] ***********
2025-09-30 11:22:55.016130 | controller | Tuesday 30 September 2025  11:22:54 +0000 (0:00:00.024)       0:00:19.335 *****
2025-09-30 11:22:55.016139 | controller | ok: [instance]
2025-09-30 11:22:55.050446 | controller |
2025-09-30 11:22:55.050474 | controller | TASK [operator_build : keystone-operator - Set operator build output] **********
2025-09-30 11:22:55.050482 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.021)       0:00:19.357 *****
2025-09-30 11:22:55.050492 | controller | ok: [instance]
2025-09-30 11:22:55.117496 | controller |
2025-09-30 11:22:55.117527 | controller | TASK [operator_build : keystone-operator - Call manifests] *********************
2025-09-30 11:22:55.117536 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.034)       0:00:19.391 *****
2025-09-30 11:22:55.117546 | controller | ok: [instance]
2025-09-30 11:22:55.136374 | controller |
2025-09-30 11:22:55.136397 | controller | TASK [operator_build : keystone-operator - Call bindata] ***********************
2025-09-30 11:22:55.136405 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.066)       0:00:19.458 *****
2025-09-30 11:22:55.136416 | controller | skipping: [instance]
2025-09-30 11:22:55.136771 | controller |
2025-09-30 11:22:55.187536 | controller | TASK [operator_build : keystone-operator - Call docker-build] ******************
2025-09-30 11:22:55.187571 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.019)       0:00:19.478 *****
2025-09-30 11:22:55.187591 | controller | ok: [instance]
2025-09-30 11:22:55.215074 | controller |
2025-09-30 11:22:55.215111 | controller | TASK [operator_build : keystone-operator - Call docker-push] *******************
2025-09-30 11:22:55.215120 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.050)       0:00:19.528 *****
2025-09-30 11:22:55.215132 | controller | skipping: [instance]
2025-09-30 11:22:55.268001 | controller |
2025-09-30 11:22:55.268035 | controller | TASK [operator_build : keystone-operator - Call bundle] ************************
2025-09-30 11:22:55.268043 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.027)       0:00:19.556 *****
2025-09-30 11:22:55.268054 | controller | ok: [instance]
2025-09-30 11:22:55.321687 | controller |
2025-09-30 11:22:55.321722 | controller | TASK [operator_build : keystone-operator - Call bundle-build] ******************
2025-09-30 11:22:55.321730 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.053)       0:00:19.609 *****
2025-09-30 11:22:55.321740 | controller | ok: [instance]
2025-09-30 11:22:55.350842 | controller |
2025-09-30 11:22:55.350873 | controller | TASK [operator_build : keystone-operator - Push bundle image] ******************
2025-09-30 11:22:55.350881 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.053)       0:00:19.663 *****
2025-09-30 11:22:55.350891 | controller | skipping: [instance]
2025-09-30 11:22:55.404670 | controller |
2025-09-30 11:22:55.404700 | controller | TASK [operator_build : keystone-operator - Call catalog-build] *****************
2025-09-30 11:22:55.404708 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.029)       0:00:19.692 *****
2025-09-30 11:22:55.404718 | controller | ok: [instance]
2025-09-30 11:22:55.431137 | controller |
2025-09-30 11:22:55.431166 | controller | TASK [operator_build : keystone-operator - Call catalog-push] ******************
2025-09-30 11:22:55.431174 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.053)       0:00:19.746 *****
2025-09-30 11:22:55.431184 | controller | skipping: [instance]
2025-09-30 11:22:55.487359 | controller |
2025-09-30 11:22:55.487383 | controller | TASK [operator_build : Building meta operator] *********************************
2025-09-30 11:22:55.487390 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.026)       0:00:19.772 *****
2025-09-30 11:22:55.487400 | controller | skipping: [instance] => (item={'name': 'keystone-operator', 'src': '/home/zuul/keystone-operator'})
2025-09-30 11:22:55.505856 | 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-09-30 11:22:55.505878 | controller |
2025-09-30 11:22:55.505886 | controller | TASK [operator_build : openstack-operator - Read go.mod file contents of Openstack Operator] ***
2025-09-30 11:22:55.505893 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.056)       0:00:19.828 *****
2025-09-30 11:22:55.505903 | controller | skipping: [instance]
2025-09-30 11:22:55.522411 | controller |
2025-09-30 11:22:55.522472 | controller | TASK [operator_build : openstack-operator - Get base module from go.mod] *******
2025-09-30 11:22:55.522482 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.018)       0:00:19.847 *****
2025-09-30 11:22:55.522497 | controller | skipping: [instance]
2025-09-30 11:22:55.522594 | controller |
2025-09-30 11:22:55.522618 | controller | TASK [operator_build : Get the base module name not empty operator_base_module] ***
2025-09-30 11:22:55.544096 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.016)       0:00:19.864 *****
2025-09-30 11:22:55.544184 | controller | skipping: [instance]
2025-09-30 11:22:55.562793 | controller |
2025-09-30 11:22:55.562833 | controller | TASK [operator_build : openstack-operator - Set default api path] **************
2025-09-30 11:22:55.562842 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.020)       0:00:19.884 *****
2025-09-30 11:22:55.562855 | controller | skipping: [instance]
2025-09-30 11:22:55.585190 | controller |
2025-09-30 11:22:55.585270 | controller | TASK [operator_build : openstack-operator - Update the go.mod file in meta operator for provided PR_SHA] ***
2025-09-30 11:22:55.585302 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.019)       0:00:19.903 *****
2025-09-30 11:22:55.585338 | controller | skipping: [instance]
2025-09-30 11:22:55.780804 | controller |
2025-09-30 11:22:55.780847 | controller | TASK [operator_build : openstack-operator - Get latest commit when no PR is provided] ***
2025-09-30 11:22:55.780862 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.021)       0:00:19.925 *****
2025-09-30 11:22:55.780873 | controller | changed: [instance]
2025-09-30 11:22:55.815399 | controller |
2025-09-30 11:22:55.815430 | controller | TASK [operator_build : openstack-operator - Set pr_sha to be used as image tag] ***
2025-09-30 11:22:55.815438 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.196)       0:00:20.122 *****
2025-09-30 11:22:55.815449 | controller | ok: [instance]
2025-09-30 11:22:55.815667 | controller |
2025-09-30 11:22:55.815680 | controller | TASK [operator_build : openstack-operator - Update the go.mod file using latest commit if no PR is provided] ***
2025-09-30 11:22:55.836548 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.034)       0:00:20.156 *****
2025-09-30 11:22:55.836574 | controller | skipping: [instance]
2025-09-30 11:22:56.230864 | controller |
2025-09-30 11:22:56.230897 | controller | TASK [operator_build : openstack-operator - Get golang container image] ********
2025-09-30 11:22:56.230905 | controller | Tuesday 30 September 2025  11:22:55 +0000 (0:00:00.020)       0:00:20.177 *****
2025-09-30 11:22:56.230915 | controller | ok: [instance]
2025-09-30 11:22:56.252170 | controller |
2025-09-30 11:22:56.252194 | controller | TASK [operator_build : openstack-operator - Set operator image tag] ************
2025-09-30 11:22:56.252201 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.394)       0:00:20.572 *****
2025-09-30 11:22:56.252210 | controller | ok: [instance]
2025-09-30 11:22:56.273215 | controller |
2025-09-30 11:22:56.273232 | controller | TASK [operator_build : openstack-operator - Set operator image names] **********
2025-09-30 11:22:56.273238 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.021)       0:00:20.593 *****
2025-09-30 11:22:56.273247 | controller | ok: [instance]
2025-09-30 11:22:56.303564 | controller |
2025-09-30 11:22:56.303585 | controller | TASK [operator_build : openstack-operator - Set operator build output] *********
2025-09-30 11:22:56.303592 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.021)       0:00:20.614 *****
2025-09-30 11:22:56.303602 | controller | ok: [instance]
2025-09-30 11:22:56.352793 | controller |
2025-09-30 11:22:56.352821 | controller | TASK [operator_build : openstack-operator - Call manifests] ********************
2025-09-30 11:22:56.352828 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.030)       0:00:20.644 *****
2025-09-30 11:22:56.352838 | controller | ok: [instance]
2025-09-30 11:22:56.405426 | controller |
2025-09-30 11:22:56.405455 | controller | TASK [operator_build : openstack-operator - Call bindata] **********************
2025-09-30 11:22:56.405463 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.049)       0:00:20.694 *****
2025-09-30 11:22:56.405473 | controller | ok: [instance]
2025-09-30 11:22:56.456219 | controller |
2025-09-30 11:22:56.456243 | controller | TASK [operator_build : openstack-operator - Call docker-build] *****************
2025-09-30 11:22:56.456251 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.052)       0:00:20.746 *****
2025-09-30 11:22:56.456260 | controller | ok: [instance]
2025-09-30 11:22:56.482686 | controller |
2025-09-30 11:22:56.482711 | controller | TASK [operator_build : openstack-operator - Call docker-push] ******************
2025-09-30 11:22:56.482718 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.050)       0:00:20.797 *****
2025-09-30 11:22:56.482728 | controller | skipping: [instance]
2025-09-30 11:22:56.534816 | controller |
2025-09-30 11:22:56.534841 | controller | TASK [operator_build : openstack-operator - Call bundle] ***********************
2025-09-30 11:22:56.534849 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.026)       0:00:20.824 *****
2025-09-30 11:22:56.534858 | controller | ok: [instance]
2025-09-30 11:22:56.587389 | controller |
2025-09-30 11:22:56.587425 | controller | TASK [operator_build : openstack-operator - Call bundle-build] *****************
2025-09-30 11:22:56.587434 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.052)       0:00:20.876 *****
2025-09-30 11:22:56.587446 | controller | ok: [instance]
2025-09-30 11:22:56.613417 | controller |
2025-09-30 11:22:56.613450 | controller | TASK [operator_build : openstack-operator - Push bundle image] *****************
2025-09-30 11:22:56.613458 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.052)       0:00:20.928 *****
2025-09-30 11:22:56.613475 | controller | skipping: [instance]
2025-09-30 11:22:56.613609 | controller |
2025-09-30 11:22:56.613627 | controller | TASK [operator_build : openstack-operator - Call catalog-build] ****************
2025-09-30 11:22:56.613729 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.026)       0:00:20.955 *****
2025-09-30 11:22:56.667611 | controller | ok: [instance]
2025-09-30 11:22:56.667681 | controller |
2025-09-30 11:22:56.667790 | controller | TASK [operator_build : openstack-operator - Call catalog-push] *****************
2025-09-30 11:22:56.667890 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.054)       0:00:21.009 *****
2025-09-30 11:22:56.694643 | controller | skipping: [instance]
2025-09-30 11:22:56.694727 | controller |
2025-09-30 11:22:56.694830 | controller | TASK [operator_build : Gather role output] *************************************
2025-09-30 11:22:56.694931 | controller | Tuesday 30 September 2025  11:22:56 +0000 (0:00:00.026)       0:00:21.036 *****
2025-09-30 11:22:57.155379 | controller | changed: [instance]
2025-09-30 11:22:57.155572 | controller |
2025-09-30 11:22:57.155708 | controller | TASK [Validate operator_build outputs] *****************************************
2025-09-30 11:22:57.155835 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.460)       0:00:21.497 *****
2025-09-30 11:22:57.185886 | 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-09-30 11:22:57.185953 | 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-09-30 11:22:57.186041 | controller |
2025-09-30 11:22:57.186149 | controller | TASK [keystone-operator - Get commit hash from src repo] ***********************
2025-09-30 11:22:57.186246 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.030)       0:00:21.527 *****
2025-09-30 11:22:57.369765 | controller | changed: [instance]
2025-09-30 11:22:57.369828 | controller |
2025-09-30 11:22:57.369944 | controller | TASK [keystone-operator - Expected commit hash and registry] *******************
2025-09-30 11:22:57.370056 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.183)       0:00:21.711 *****
2025-09-30 11:22:57.404098 | controller | ok: [instance]
2025-09-30 11:22:57.404205 | controller |
2025-09-30 11:22:57.404350 | controller | TASK [keystone-operator - Expected images] *************************************
2025-09-30 11:22:57.404496 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.034)       0:00:21.746 *****
2025-09-30 11:22:57.424554 | controller | ok: [instance]
2025-09-30 11:22:57.424633 | controller |
2025-09-30 11:22:57.424771 | controller | TASK [keystone-operator - Ensure that role output dict contains the expected values] ***
2025-09-30 11:22:57.424915 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.020)       0:00:21.766 *****
2025-09-30 11:22:57.452767 | controller | ok: [instance] => changed=false
2025-09-30 11:22:57.452888 | controller |   msg: All assertions passed
2025-09-30 11:22:57.453004 | controller |
2025-09-30 11:22:57.453130 | controller | TASK [openstack-operator - Get commit hash from src repo] **********************
2025-09-30 11:22:57.453252 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.028)       0:00:21.794 *****
2025-09-30 11:22:57.635486 | controller | changed: [instance]
2025-09-30 11:22:57.635562 | controller |
2025-09-30 11:22:57.635679 | controller | TASK [openstack-operator - Expected commit hash and registry] ******************
2025-09-30 11:22:57.635783 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.182)       0:00:21.977 *****
2025-09-30 11:22:57.669129 | controller | ok: [instance]
2025-09-30 11:22:57.669166 | controller |
2025-09-30 11:22:57.669330 | controller | TASK [openstack-operator - Expected images] ************************************
2025-09-30 11:22:57.669438 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.033)       0:00:22.011 *****
2025-09-30 11:22:57.688452 | controller | ok: [instance]
2025-09-30 11:22:57.688546 | controller |
2025-09-30 11:22:57.688685 | controller | TASK [openstack-operator - Ensure that role output dict contains the expected values] ***
2025-09-30 11:22:57.688808 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.019)       0:00:22.030 *****
2025-09-30 11:22:57.715316 | controller | ok: [instance] => changed=false
2025-09-30 11:22:57.715453 | controller |   msg: All assertions passed
2025-09-30 11:22:57.715571 | controller |
2025-09-30 11:22:57.715694 | controller | TASK [Build mariadb-operator from Zuul job] ************************************
2025-09-30 11:22:57.715819 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.026)       0:00:22.057 *****
2025-09-30 11:22:57.749153 | controller |
2025-09-30 11:22:57.749281 | controller | TASK [operator_build : Ensure mandatory directories exist] *********************
2025-09-30 11:22:57.749403 | controller | Tuesday 30 September 2025  11:22:57 +0000 (0:00:00.033)       0:00:22.091 *****
2025-09-30 11:22:58.116189 | controller | ok: [instance] => (item=artifacts)
2025-09-30 11:22:58.116268 | controller | ok: [instance] => (item=logs)
2025-09-30 11:22:58.116396 | controller |
2025-09-30 11:22:58.116505 | controller | TASK [operator_build : Initialize role output] *********************************
2025-09-30 11:22:58.116612 | controller | Tuesday 30 September 2025  11:22:58 +0000 (0:00:00.366)       0:00:22.457 *****
2025-09-30 11:22:58.134831 | controller | ok: [instance]
2025-09-30 11:22:58.134920 | controller |
2025-09-30 11:22:58.135028 | controller | TASK [operator_build : Populate operators list with zuul info] *****************
2025-09-30 11:22:58.135139 | controller | Tuesday 30 September 2025  11:22:58 +0000 (0:00:00.018)       0:00:22.476 *****
2025-09-30 11:22:58.171442 | 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': '366', 'change_url': 'https://github.com/openstack-k8s-operators/mariadb-operator/pull/366', 'patchset': '517a40994d01374250200599409147d085bd4809', '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-09-30 11:22:58.171562 | controller |
2025-09-30 11:22:58.171691 | controller | TASK [operator_build : Get the PR owner using github api] **********************
2025-09-30 11:22:58.171815 | controller | Tuesday 30 September 2025  11:22:58 +0000 (0:00:00.036)       0:00:22.513 *****
2025-09-30 11:22:58.909139 | controller | ok: [instance]
2025-09-30 11:22:58.909187 | controller |
2025-09-30 11:22:58.909375 | controller | TASK [operator_build : Add operator info with new content] *********************
2025-09-30 11:22:58.909498 | controller | Tuesday 30 September 2025  11:22:58 +0000 (0:00:00.737)       0:00:23.250 *****
2025-09-30 11:22:58.947150 | controller | ok: [instance]
2025-09-30 11:22:58.986851 | controller |
2025-09-30 11:22:58.986871 | controller | TASK [operator_build : Merge lists of operators] *******************************
2025-09-30 11:22:58.986879 | controller | Tuesday 30 September 2025  11:22:58 +0000 (0:00:00.037)       0:00:23.288 *****
2025-09-30 11:22:58.986888 | controller | ok: [instance]
2025-09-30 11:22:59.022905 | controller |
2025-09-30 11:22:59.022924 | controller | TASK [operator_build : Get meta_operator src dir from operators_list] **********
2025-09-30 11:22:59.022932 | controller | Tuesday 30 September 2025  11:22:58 +0000 (0:00:00.039)       0:00:23.328 *****
2025-09-30 11:22:59.022941 | controller | ok: [instance]
2025-09-30 11:22:59.042405 | controller |
2025-09-30 11:22:59.042438 | controller | TASK [operator_build : Adds meta-operator to the list] *************************
2025-09-30 11:22:59.042446 | controller | Tuesday 30 September 2025  11:22:59 +0000 (0:00:00.035)       0:00:23.364 *****
2025-09-30 11:22:59.042457 | controller | skipping: [instance]
2025-09-30 11:22:59.067927 | controller |
2025-09-30 11:22:59.067958 | controller | TASK [operator_build : Clone operator's code when src dir is empty] ************
2025-09-30 11:22:59.067966 | controller | Tuesday 30 September 2025  11:22:59 +0000 (0:00:00.019)       0:00:23.383 *****
2025-09-30 11:22:59.067983 | 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': '517a40994d01374250200599409147d085bd4809', 'pr_owner': 'openstack-k8s-operators/mariadb-operator'})
2025-09-30 11:22:59.068081 | controller |
2025-09-30 11:22:59.068233 | controller | TASK [operator_build : mariadb-operator - Check if src code exists] ************
2025-09-30 11:22:59.068449 | controller | Tuesday 30 September 2025  11:22:59 +0000 (0:00:00.025)       0:00:23.409 *****
2025-09-30 11:22:59.241845 | controller | ok: [instance]
2025-09-30 11:23:00.563414 | controller |
2025-09-30 11:23:00.563448 | controller | TASK [operator_build : mariadb-operator - Clone from github if no code was provided] ***
2025-09-30 11:23:00.563456 | controller | Tuesday 30 September 2025  11:22:59 +0000 (0:00:00.173)       0:00:23.583 *****
2025-09-30 11:23:00.563467 | controller | changed: [instance]
2025-09-30 11:23:00.613462 | controller |
2025-09-30 11:23:00.613502 | controller | TASK [operator_build : Building operators] *************************************
2025-09-30 11:23:00.613512 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:01.320)       0:00:24.904 *****
2025-09-30 11:23:00.613525 | 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': '517a40994d01374250200599409147d085bd4809', 'pr_owner': 'openstack-k8s-operators/mariadb-operator'})
2025-09-30 11:23:00.613557 | controller |
2025-09-30 11:23:00.613801 | controller | TASK [operator_build : mariadb-operator - Read go.mod file contents of Openstack Operator] ***
2025-09-30 11:23:00.636409 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.050)       0:00:24.954 *****
2025-09-30 11:23:00.636440 | controller | skipping: [instance]
2025-09-30 11:23:00.656481 | controller |
2025-09-30 11:23:00.656534 | controller | TASK [operator_build : mariadb-operator - Get base module from go.mod] *********
2025-09-30 11:23:00.656551 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.023)       0:00:24.977 *****
2025-09-30 11:23:00.656563 | controller | skipping: [instance]
2025-09-30 11:23:00.656582 | controller |
2025-09-30 11:23:00.656590 | controller | TASK [operator_build : Get the base module name not empty operator_base_module] ***
2025-09-30 11:23:00.656615 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.020)       0:00:24.997 *****
2025-09-30 11:23:00.677337 | controller | skipping: [instance]
2025-09-30 11:23:00.677455 | controller |
2025-09-30 11:23:00.677561 | controller | TASK [operator_build : mariadb-operator - Set default api path] ****************
2025-09-30 11:23:00.677726 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.021)       0:00:25.019 *****
2025-09-30 11:23:00.699725 | controller | skipping: [instance]
2025-09-30 11:23:00.719086 | controller |
2025-09-30 11:23:00.719123 | controller | TASK [operator_build : mariadb-operator - Update the go.mod file in meta operator for provided PR_SHA] ***
2025-09-30 11:23:00.719131 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.021)       0:00:25.041 *****
2025-09-30 11:23:00.719142 | controller | skipping: [instance]
2025-09-30 11:23:00.939867 | controller |
2025-09-30 11:23:00.939900 | controller | TASK [operator_build : mariadb-operator - Get latest commit when no PR is provided] ***
2025-09-30 11:23:00.939908 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.019)       0:00:25.060 *****
2025-09-30 11:23:00.939919 | controller | changed: [instance]
2025-09-30 11:23:00.976433 | controller |
2025-09-30 11:23:00.976451 | controller | TASK [operator_build : mariadb-operator - Set pr_sha to be used as image tag] ***
2025-09-30 11:23:00.976465 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.220)       0:00:25.281 *****
2025-09-30 11:23:00.976474 | controller | ok: [instance]
2025-09-30 11:23:00.996174 | controller |
2025-09-30 11:23:00.996192 | controller | TASK [operator_build : mariadb-operator - Update the go.mod file using latest commit if no PR is provided] ***
2025-09-30 11:23:00.996199 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.036)       0:00:25.317 *****
2025-09-30 11:23:00.996208 | controller | skipping: [instance]
2025-09-30 11:23:01.440248 | controller |
2025-09-30 11:23:01.440297 | controller | TASK [operator_build : mariadb-operator - Get golang container image] **********
2025-09-30 11:23:01.440306 | controller | Tuesday 30 September 2025  11:23:00 +0000 (0:00:00.019)       0:00:25.337 *****
2025-09-30 11:23:01.440317 | controller | ok: [instance]
2025-09-30 11:23:01.469140 | controller |
2025-09-30 11:23:01.469163 | controller | TASK [operator_build : mariadb-operator - Set operator image tag] **************
2025-09-30 11:23:01.469172 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.443)       0:00:25.781 *****
2025-09-30 11:23:01.469183 | controller | ok: [instance]
2025-09-30 11:23:01.497018 | controller |
2025-09-30 11:23:01.497037 | controller | TASK [operator_build : mariadb-operator - Set operator image names] ************
2025-09-30 11:23:01.497044 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.029)       0:00:25.810 *****
2025-09-30 11:23:01.497053 | controller | ok: [instance]
2025-09-30 11:23:01.535287 | controller |
2025-09-30 11:23:01.535313 | controller | TASK [operator_build : mariadb-operator - Set operator build output] ***********
2025-09-30 11:23:01.535341 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.028)       0:00:25.838 *****
2025-09-30 11:23:01.535356 | controller | ok: [instance]
2025-09-30 11:23:01.595809 | controller |
2025-09-30 11:23:01.595831 | controller | TASK [operator_build : mariadb-operator - Call manifests] **********************
2025-09-30 11:23:01.595839 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.038)       0:00:25.876 *****
2025-09-30 11:23:01.595850 | controller | ok: [instance]
2025-09-30 11:23:01.617172 | controller |
2025-09-30 11:23:01.617190 | controller | TASK [operator_build : mariadb-operator - Call bindata] ************************
2025-09-30 11:23:01.617196 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.060)       0:00:25.937 *****
2025-09-30 11:23:01.617205 | controller | skipping: [instance]
2025-09-30 11:23:01.675977 | controller |
2025-09-30 11:23:01.676007 | controller | TASK [operator_build : mariadb-operator - Call docker-build] *******************
2025-09-30 11:23:01.676014 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.021)       0:00:25.958 *****
2025-09-30 11:23:01.676024 | controller | ok: [instance]
2025-09-30 11:23:01.706134 | controller |
2025-09-30 11:23:01.706156 | controller | TASK [operator_build : mariadb-operator - Call docker-push] ********************
2025-09-30 11:23:01.706163 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.058)       0:00:26.017 *****
2025-09-30 11:23:01.706172 | controller | skipping: [instance]
2025-09-30 11:23:01.765008 | controller |
2025-09-30 11:23:01.765038 | controller | TASK [operator_build : mariadb-operator - Call bundle] *************************
2025-09-30 11:23:01.765046 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.030)       0:00:26.047 *****
2025-09-30 11:23:01.765056 | controller | ok: [instance]
2025-09-30 11:23:01.825949 | controller |
2025-09-30 11:23:01.825983 | controller | TASK [operator_build : mariadb-operator - Call bundle-build] *******************
2025-09-30 11:23:01.825991 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.058)       0:00:26.106 *****
2025-09-30 11:23:01.826002 | controller | ok: [instance]
2025-09-30 11:23:01.859756 | controller |
2025-09-30 11:23:01.859790 | controller | TASK [operator_build : mariadb-operator - Push bundle image] *******************
2025-09-30 11:23:01.859798 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.060)       0:00:26.167 *****
2025-09-30 11:23:01.859808 | controller | skipping: [instance]
2025-09-30 11:23:01.919760 | controller |
2025-09-30 11:23:01.919792 | controller | TASK [operator_build : mariadb-operator - Call catalog-build] ******************
2025-09-30 11:23:01.919799 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.033)       0:00:26.201 *****
2025-09-30 11:23:01.919810 | controller | ok: [instance]
2025-09-30 11:23:01.949484 | controller |
2025-09-30 11:23:01.949506 | controller | TASK [operator_build : mariadb-operator - Call catalog-push] *******************
2025-09-30 11:23:01.949514 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.059)       0:00:26.261 *****
2025-09-30 11:23:01.949523 | controller | skipping: [instance]
2025-09-30 11:23:01.975941 | controller |
2025-09-30 11:23:01.975965 | controller | TASK [operator_build : Building meta operator] *********************************
2025-09-30 11:23:01.975973 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.029)       0:00:26.290 *****
2025-09-30 11:23:01.975992 | controller | skipping: [instance] => (item={'name': 'mariadb-operator', 'src': '/home/zuul/src/github.com/openstack-k8s-operators/mariadb-operator', 'pr_sha': '517a40994d01374250200599409147d085bd4809', 'pr_owner': 'openstack-k8s-operators/mariadb-operator'})
2025-09-30 11:23:02.366126 | controller | skipping: [instance]
2025-09-30 11:23:02.366158 | controller |
2025-09-30 11:23:02.366166 | controller | TASK [operator_build : Gather role output] *************************************
2025-09-30 11:23:02.366181 | controller | Tuesday 30 September 2025  11:23:01 +0000 (0:00:00.026)       0:00:26.317 *****
2025-09-30 11:23:02.366197 | controller | changed: [instance]
2025-09-30 11:23:02.409343 | controller |
2025-09-30 11:23:02.409372 | controller | TASK [Validate operator_build outputs] *****************************************
2025-09-30 11:23:02.409380 | controller | Tuesday 30 September 2025  11:23:02 +0000 (0:00:00.390)       0:00:26.707 *****
2025-09-30 11:23:02.409396 | 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': '517a40994d01374250200599409147d085bd4809'})
2025-09-30 11:23:02.618692 | controller |
2025-09-30 11:23:02.618726 | controller | TASK [mariadb-operator - Get commit hash from src repo] ************************
2025-09-30 11:23:02.618734 | controller | Tuesday 30 September 2025  11:23:02 +0000 (0:00:00.043)       0:00:26.750 *****
2025-09-30 11:23:02.618744 | controller | changed: [instance]
2025-09-30 11:23:02.667122 | controller |
2025-09-30 11:23:02.667161 | controller | TASK [mariadb-operator - Expected commit hash and registry] ********************
2025-09-30 11:23:02.667170 | controller | Tuesday 30 September 2025  11:23:02 +0000 (0:00:00.209)       0:00:26.959 *****
2025-09-30 11:23:02.667182 | controller | ok: [instance]
2025-09-30 11:23:02.696520 | controller |
2025-09-30 11:23:02.696553 | controller | TASK [mariadb-operator - Expected images] **************************************
2025-09-30 11:23:02.696561 | controller | Tuesday 30 September 2025  11:23:02 +0000 (0:00:00.048)       0:00:27.008 *****
2025-09-30 11:23:02.696571 | controller | ok: [instance]
2025-09-30 11:23:02.744468 | controller |
2025-09-30 11:23:02.744545 | controller | TASK [mariadb-operator - Ensure that role output dict contains the expected values] ***
2025-09-30 11:23:02.744554 | controller | Tuesday 30 September 2025  11:23:02 +0000 (0:00:00.029)       0:00:27.037 *****
2025-09-30 11:23:02.744570 | controller | ok: [instance] => changed=false
2025-09-30 11:23:02.744602 | controller |   msg: All assertions passed
2025-09-30 11:23:02.744609 | controller |
2025-09-30 11:23:02.744617 | controller | PLAY RECAP *********************************************************************
2025-09-30 11:23:02.744645 | controller | instance                   : ok=83   changed=13   unreachable=0    failed=0    skipped=27   rescued=0    ignored=0
2025-09-30 11:23:02.744770 | controller |
2025-09-30 11:23:02.744905 | controller | Tuesday 30 September 2025  11:23:02 +0000 (0:00:00.047)       0:00:27.085 *****
2025-09-30 11:23:02.745029 | controller | ===============================================================================
2025-09-30 11:23:02.745149 | controller | operator_build : keystone-operator - Get golang container image -------- 11.27s
2025-09-30 11:23:02.745289 | controller | operator_build : openstack-operator - Clone from github if no code was provided --- 2.58s
2025-09-30 11:23:02.745430 | controller | operator_build : keystone-operator - Clone from github if no code was provided --- 1.50s
2025-09-30 11:23:02.745564 | controller | operator_build : mariadb-operator - Clone from github if no code was provided --- 1.32s
2025-09-30 11:23:02.745677 | controller | Get open PR list for mariadb-operator ----------------------------------- 1.02s
2025-09-30 11:23:02.745791 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-09-30 11:23:02.745910 | controller | operator_build : Get the PR owner using github api ---------------------- 0.74s
2025-09-30 11:23:02.746031 | controller | operator_build : Gather role output ------------------------------------- 0.46s
2025-09-30 11:23:02.746154 | controller | operator_build : Ensure mandatory directories exist --------------------- 0.46s
2025-09-30 11:23:02.746281 | controller | operator_build : mariadb-operator - Get golang container image ---------- 0.44s
2025-09-30 11:23:02.746433 | controller | operator_build : openstack-operator - Get golang container image -------- 0.39s
2025-09-30 11:23:02.746559 | controller | operator_build : Gather role output ------------------------------------- 0.39s
2025-09-30 11:23:02.746670 | controller | operator_build : Ensure mandatory directories exist --------------------- 0.37s
2025-09-30 11:23:02.746786 | controller | operator_build : keystone-operator - Read go.mod file contents of Openstack Operator --- 0.29s
2025-09-30 11:23:02.746903 | controller | operator_build : keystone-operator - Get latest commit when no PR is provided --- 0.28s
2025-09-30 11:23:02.747016 | controller | operator_build : keystone-operator - Check if src code exists ----------- 0.25s
2025-09-30 11:23:02.747130 | controller | operator_build : mariadb-operator - Get latest commit when no PR is provided --- 0.22s
2025-09-30 11:23:02.747254 | controller | mariadb-operator - Get commit hash from src repo ------------------------ 0.21s
2025-09-30 11:23:02.747409 | controller | operator_build : openstack-operator - Get latest commit when no PR is provided --- 0.20s
2025-09-30 11:23:02.747530 | controller | operator_build : keystone-operator - Update the go.mod file using latest commit if no PR is provided --- 0.20s
2025-09-30 11:23:02.831925 | controller | INFO     Running default > cleanup
2025-09-30 11:23:02.832387 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-30 11:23:02.832932 | controller | INFO     Writing /tmp/report.html report.
2025-09-30 11:23:03.323371 | controller | changed
2025-09-30 11:23:03.364885 | 
2025-09-30 11:23:03.364994 | PLAY RECAP
2025-09-30 11:23:03.365044 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 11:23:03.365067 | 
2025-09-30 11:23:03.480924 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 11:23:03.481784 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 11:23:04.108783 | 
2025-09-30 11:23:04.108894 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-30 11:23:04.129426 | 
2025-09-30 11:23:04.129505 | TASK [Filter out host if needed]
2025-09-30 11:23:04.137977 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-30 11:23:04.142440 | 
2025-09-30 11:23:04.142507 | TASK [Ensure file is present]
2025-09-30 11:23:04.516554 | controller | ok
2025-09-30 11:23:04.523286 | 
2025-09-30 11:23:04.523366 | TASK [Manage molecule report file]
2025-09-30 11:23:05.062050 | controller | changed
2025-09-30 11:23:05.067608 | 
2025-09-30 11:23:05.067675 | TASK [Check if we get ci-framework-data basedir]
2025-09-30 11:23:05.303223 | controller | ok
2025-09-30 11:23:05.312075 | 
2025-09-30 11:23:05.312190 | TASK [Create ci-framework-data log directory for zuul]
2025-09-30 11:23:05.880582 | controller | changed
2025-09-30 11:23:05.890773 | 
2025-09-30 11:23:05.890985 | TASK [Copy ci-framework interesting files]
2025-09-30 11:23:06.163707 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-30 11:23:06.450576 | controller | changed
2025-09-30 11:23:06.456030 | 
2025-09-30 11:23:06.456100 | TASK [Get SELinux listing]
2025-09-30 11:23:07.000109 | controller | changed
2025-09-30 11:23:07.007410 | 
2025-09-30 11:23:07.007488 | TASK [Generate log index]
2025-09-30 11:23:07.928273 | controller | changed
2025-09-30 11:23:07.937729 | 
2025-09-30 11:23:07.937821 | TASK [Get some env related data]
2025-09-30 11:23:08.520515 | controller | /home/zuul/.local/bin/ansible
2025-09-30 11:23:09.503106 | controller | changed
2025-09-30 11:23:09.508546 | 
2025-09-30 11:23:09.508609 | TASK [Generate list of logs to collect in home directory]
2025-09-30 11:23:09.831035 | controller | ok: All paths examined
2025-09-30 11:23:09.858641 | 
2025-09-30 11:23:09.858739 | LOOP [Copy logs from home directory]
2025-09-30 11:23:10.191686 | controller | changed:
2025-09-30 11:23:10.191822 | controller | {
2025-09-30 11:23:10.191935 | controller |   "atime": 1759231244.9796152,
2025-09-30 11:23:10.191964 | controller |   "ctime": 1759231262.4736102,
2025-09-30 11:23:10.191984 | controller |   "dev": 64513,
2025-09-30 11:23:10.192002 | controller |   "gid": 1000,
2025-09-30 11:23:10.192020 | controller |   "gr_name": "zuul",
2025-09-30 11:23:10.192038 | controller |   "inode": 4410053,
2025-09-30 11:23:10.192214 | controller |   "isblk": false,
2025-09-30 11:23:10.192240 | controller |   "ischr": false,
2025-09-30 11:23:10.192259 | controller |   "isdir": false,
2025-09-30 11:23:10.192276 | controller |   "isfifo": false,
2025-09-30 11:23:10.192293 | controller |   "isgid": false,
2025-09-30 11:23:10.192310 | controller |   "islnk": false,
2025-09-30 11:23:10.192326 | controller |   "isreg": true,
2025-09-30 11:23:10.192341 | controller |   "issock": false,
2025-09-30 11:23:10.192356 | controller |   "isuid": false,
2025-09-30 11:23:10.192371 | controller |   "mode": "0644",
2025-09-30 11:23:10.192425 | controller |   "mtime": 1759231262.4736102,
2025-09-30 11:23:10.192447 | controller |   "nlink": 1,
2025-09-30 11:23:10.192464 | controller |   "path": "/home/zuul/ansible.log",
2025-09-30 11:23:10.192482 | controller |   "pw_name": "zuul",
2025-09-30 11:23:10.192498 | controller |   "rgrp": true,
2025-09-30 11:23:10.192513 | controller |   "roth": true,
2025-09-30 11:23:10.192529 | controller |   "rusr": true,
2025-09-30 11:23:10.192545 | controller |   "size": 6749,
2025-09-30 11:23:10.192562 | controller |   "uid": 1000,
2025-09-30 11:23:10.192578 | controller |   "wgrp": false,
2025-09-30 11:23:10.192604 | controller |   "woth": false,
2025-09-30 11:23:10.192622 | controller |   "wusr": true,
2025-09-30 11:23:10.192639 | controller |   "xgrp": false,
2025-09-30 11:23:10.192654 | controller |   "xoth": false,
2025-09-30 11:23:10.192668 | controller |   "xusr": false
2025-09-30 11:23:10.192683 | controller | }
2025-09-30 11:23:10.209614 | 
2025-09-30 11:23:10.209720 | TASK [Copy crio stats log file]
2025-09-30 11:23:10.244777 | controller | skipping: Conditional result was False
2025-09-30 11:23:10.251040 | 
2025-09-30 11:23:10.251118 | TASK [Get SELinux related data]
2025-09-30 11:23:10.528124 | controller | <no matches>
2025-09-30 11:23:10.812350 | controller | ERROR
2025-09-30 11:23:10.812557 | controller | {
2025-09-30 11:23:10.812605 | controller |   "delta": "0:00:00.009540",
2025-09-30 11:23:10.812629 | controller |   "end": "2025-09-30 11:23:10.529175",
2025-09-30 11:23:10.812649 | controller |   "msg": "non-zero return code",
2025-09-30 11:23:10.812666 | controller |   "rc": 1,
2025-09-30 11:23:10.812686 | controller |   "start": "2025-09-30 11:23:10.519635"
2025-09-30 11:23:10.812705 | controller | }
2025-09-30 11:23:10.812730 | controller | ERROR: Ignoring Errors
2025-09-30 11:23:10.818905 | 
2025-09-30 11:23:10.818976 | TASK [Create system configuration directory]
2025-09-30 11:23:11.051255 | controller | changed
2025-09-30 11:23:11.056606 | 
2025-09-30 11:23:11.056673 | TASK [Get some of the system configurations]
2025-09-30 11:23:11.626429 | controller | changed
2025-09-30 11:23:11.632267 | 
2025-09-30 11:23:11.632334 | TASK [Copy generated documentation if available]
2025-09-30 11:23:11.696490 | controller | skipping: Conditional result was False
2025-09-30 11:23:11.702538 | 
2025-09-30 11:23:11.702604 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-30 11:23:11.727210 | controller | skipping: Conditional result was False
2025-09-30 11:23:11.733177 | 
2025-09-30 11:23:11.733256 | TASK [Compress logs bigger than 2MB]
2025-09-30 11:23:12.294719 | controller | changed
2025-09-30 11:23:12.306293 | 
2025-09-30 11:23:12.306361 | TASK [Copy files from workspace on node]
2025-09-30 11:23:12.348044 | controller | ok
2025-09-30 11:23:12.410168 | 
2025-09-30 11:23:12.410262 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 11:23:12.453836 | controller | skipping: Conditional result was False
2025-09-30 11:23:12.461030 | 
2025-09-30 11:23:12.461104 | TASK [fetch-output : Set log path for single node]
2025-09-30 11:23:12.534785 | controller | ok
2025-09-30 11:23:12.540365 | 
2025-09-30 11:23:12.540453 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 11:23:12.853359 | controller -> localhost | ok: "/var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/logs"
2025-09-30 11:23:12.853584 | controller -> localhost | changed: All items complete
2025-09-30 11:23:12.853615 | 
2025-09-30 11:23:13.113677 | controller -> localhost | changed: "/var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/artifacts"
2025-09-30 11:23:13.441872 | controller -> localhost | changed: "/var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/docs"
2025-09-30 11:23:13.453217 | 
2025-09-30 11:23:13.453314 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 11:23:14.047198 | controller | changed:
2025-09-30 11:23:14.047358 | controller | .d..t...... ./
2025-09-30 11:23:14.047414 | controller | >f+++++++++ README.html
2025-09-30 11:23:14.047442 | controller | >f+++++++++ ansible-execution.log
2025-09-30 11:23:14.047465 | controller | >f+++++++++ ansible.log
2025-09-30 11:23:14.047485 | controller | >f+++++++++ dmesg.log
2025-09-30 11:23:14.047505 | controller | >f+++++++++ installed-pkgs.log
2025-09-30 11:23:14.047527 | controller | >f+++++++++ python.log
2025-09-30 11:23:14.047547 | controller | >f+++++++++ registries.conf
2025-09-30 11:23:14.047565 | controller | >f+++++++++ report.html
2025-09-30 11:23:14.047585 | controller | >f+++++++++ selinux-denials.log
2025-09-30 11:23:14.047603 | controller | >f+++++++++ selinux-listing.log
2025-09-30 11:23:14.047620 | controller | cd+++++++++ ci-framework-data/
2025-09-30 11:23:14.047638 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-30 11:23:14.047655 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_keystone_operator_call.sh
2025-09-30 11:23:14.047673 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_keystone_operator_call_docker.sh
2025-09-30 11:23:14.047690 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_002_keystone_operator_call.sh
2025-09-30 11:23:14.047706 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_003_keystone_operator_call_bundle.sh
2025-09-30 11:23:14.047727 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_004_keystone_operator_call.sh
2025-09-30 11:23:14.047744 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_005_openstack_operator_call.sh
2025-09-30 11:23:14.047760 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_006_openstack_operator_call.sh
2025-09-30 11:23:14.047775 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_007_openstack_operator_call.sh
2025-09-30 11:23:14.047791 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_008_openstack_operator_call.sh
2025-09-30 11:23:14.047807 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_009_openstack_operator_call.sh
2025-09-30 11:23:14.047823 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_010_openstack_operator_call.sh
2025-09-30 11:23:14.047839 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_011_mariadb_operator_call.sh
2025-09-30 11:23:14.047855 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_012_mariadb_operator_call_docker.sh
2025-09-30 11:23:14.047871 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_013_mariadb_operator_call.sh
2025-09-30 11:23:14.047886 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_014_mariadb_operator_call_bundle.sh
2025-09-30 11:23:14.047902 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_015_mariadb_operator_call_catalog.sh
2025-09-30 11:23:14.047918 | controller | >f+++++++++ ci-framework-data/artifacts/custom-operators.yml
2025-09-30 11:23:14.047935 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-30 11:23:14.047951 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-30 11:23:14.047967 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-30 11:23:14.047982 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-09-30 11:23:14.047998 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-30 11:23:14.048013 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-30 11:23:14.048029 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-30 11:23:14.048054 | controller | cd+++++++++ registries.conf.d/
2025-09-30 11:23:14.048070 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-30 11:23:14.048087 | controller | cd+++++++++ system-config/
2025-09-30 11:23:14.048102 | controller | cd+++++++++ system-config/libvirt/
2025-09-30 11:23:14.048118 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-30 11:23:14.048136 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-30 11:23:14.485942 | controller | changed: .d..t...... ./
2025-09-30 11:23:15.022364 | controller | changed: .d..t...... ./
2025-09-30 11:23:15.039456 | 
2025-09-30 11:23:15.039560 | TASK [Return artifact to Zuul]
2025-09-30 11:23:15.068794 | controller | ok
2025-09-30 11:23:15.088923 | 
2025-09-30 11:23:15.089007 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-30 11:23:15.089109 | 
2025-09-30 11:23:15.089136 | PLAY RECAP
2025-09-30 11:23:15.089186 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-30 11:23:15.089208 | 
2025-09-30 11:23:15.186996 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 11:23:15.187793 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 11:23:15.885375 | 
2025-09-30 11:23:15.885499 | PLAY [all]
2025-09-30 11:23:15.916778 | 
2025-09-30 11:23:15.916854 | TASK [include_role : fetch-output]
2025-09-30 11:23:15.945725 | controller | ok
2025-09-30 11:23:15.964578 | 
2025-09-30 11:23:15.964657 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 11:23:16.041871 | controller | skipping: Conditional result was False
2025-09-30 11:23:16.047974 | 
2025-09-30 11:23:16.048051 | TASK [fetch-output : Set log path for single node]
2025-09-30 11:23:16.096031 | controller | ok
2025-09-30 11:23:16.101355 | 
2025-09-30 11:23:16.101452 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 11:23:16.557287 | controller -> localhost | ok: "/var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/logs"
2025-09-30 11:23:17.031908 | controller -> localhost | ok: "/var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/artifacts"
2025-09-30 11:23:17.307918 | controller -> localhost | ok: "/var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/docs"
2025-09-30 11:23:17.319066 | 
2025-09-30 11:23:17.319178 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 11:23:18.233290 | controller | ok
2025-09-30 11:23:18.238446 | controller | ok: All items complete
2025-09-30 11:23:18.238495 | 
2025-09-30 11:23:18.728948 | controller | ok
2025-09-30 11:23:19.150034 | controller | ok
2025-09-30 11:23:19.162541 | 
2025-09-30 11:23:19.162647 | TASK [include_role : fetch-output-openshift]
2025-09-30 11:23:19.196307 | controller | skipping: Conditional result was False
2025-09-30 11:23:19.202173 | 
2025-09-30 11:23:19.202256 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-30 11:23:19.737862 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.020416
2025-09-30 11:23:19.990450 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011165
2025-09-30 11:23:20.030780 | 
2025-09-30 11:23:20.030892 | PLAY [all]
2025-09-30 11:23:20.057632 | 
2025-09-30 11:23:20.057717 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-30 11:23:20.654121 | controller | changed
2025-09-30 11:23:20.708826 | 
2025-09-30 11:23:20.708922 | PLAY RECAP
2025-09-30 11:23:20.708967 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-30 11:23:20.709083 | 
2025-09-30 11:23:20.890558 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 11:23:20.891359 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-30 11:23:21.757357 | 
2025-09-30 11:23:21.757504 | PLAY [localhost]
2025-09-30 11:23:21.775625 | 
2025-09-30 11:23:21.775727 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-30 11:23:22.180212 | localhost | changed
2025-09-30 11:23:22.184780 | 
2025-09-30 11:23:22.184865 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-30 11:23:22.217201 | localhost | ok
2025-09-30 11:23:22.229861 | 
2025-09-30 11:23:22.229957 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-30 11:23:22.717566 | localhost | changed
2025-09-30 11:23:22.725888 | 
2025-09-30 11:23:22.725976 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-30 11:23:23.446932 | localhost | changed
2025-09-30 11:23:23.453974 | 
2025-09-30 11:23:23.454051 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-30 11:23:23.806719 | localhost | Identity added: /var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/tmp/ansible.n2sm3cgb (/var/lib/zuul/builds/44eb7d83f89d46b08ac576e1bcb719f8/work/tmp/ansible.n2sm3cgb)
2025-09-30 11:23:23.806888 | localhost | ok: Runtime: 0:00:00.011745
2025-09-30 11:23:23.811609 | 
2025-09-30 11:23:23.811695 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-30 11:23:24.064433 | localhost | ok: Runtime: 0:00:00.004965
2025-09-30 11:23:24.069797 | 
2025-09-30 11:23:24.069866 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-30 11:23:24.120772 | localhost | changed
2025-09-30 11:23:24.125417 | 
2025-09-30 11:23:24.125505 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-30 11:23:24.524039 | localhost | changed
2025-09-30 11:23:24.544220 | 
2025-09-30 11:23:24.544295 | PLAY [localhost]
2025-09-30 11:23:24.556556 | 
2025-09-30 11:23:24.556621 | TASK [Generate bulk log download script]
2025-09-30 11:23:24.573971 | localhost | ok
2025-09-30 11:23:24.586040 | 
2025-09-30 11:23:24.586117 | TASK [local-log-download : Check API endpoint is defined]
2025-09-30 11:23:24.613723 | localhost | ok: All assertions passed
2025-09-30 11:23:24.618490 | 
2025-09-30 11:23:24.618561 | TASK [local-log-download : Create download script]
2025-09-30 11:23:24.998704 | localhost -> localhost | changed
2025-09-30 11:23:25.008028 | 
2025-09-30 11:23:25.008110 | TASK [Register quick-download link]
2025-09-30 11:23:25.026500 | localhost | ok
2025-09-30 11:23:25.062353 | 
2025-09-30 11:23:25.062449 | PLAY [logserver.rdoproject.org]
2025-09-30 11:23:25.072503 | 
2025-09-30 11:23:25.072570 | TASK [Set zuul-log-path fact]
2025-09-30 11:23:25.088012 | logserver.rdoproject.org | ok
2025-09-30 11:23:25.098013 | 
2025-09-30 11:23:25.098091 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 11:23:25.124663 | logserver.rdoproject.org | ok
2025-09-30 11:23:25.131017 | 
2025-09-30 11:23:25.131090 | TASK [upload-logs : Create log directories]
2025-09-30 11:23:26.822650 | logserver.rdoproject.org | changed
2025-09-30 11:23:26.825732 | 
2025-09-30 11:23:26.825798 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-30 11:23:27.088242 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004510
2025-09-30 11:23:27.094109 | 
2025-09-30 11:23:27.094194 | TASK [upload-logs : Upload logs to log server]
2025-09-30 11:23:28.666513 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-30 11:23:28.671244 | 
2025-09-30 11:23:28.671315 | LOOP [upload-logs : Compress console log and json output]
2025-09-30 11:23:28.748652 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:28.749401 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:28.753758 | 
2025-09-30 11:23:28.753828 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-30 11:23:28.814979 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:28.815200 | 
2025-09-30 11:23:28.819122 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 11:23:28.823085 | 
2025-09-30 11:23:28.823167 | LOOP [upload-logs : Upload console log and json output]