2025-10-08 12:13:09.588239 | Job console starting...
2025-10-08 12:13:09.831073 | Updating repositories
2025-10-08 12:13:10.560976 | Preparing job workspace
2025-10-08 12:13:18.915020 | Running Ansible setup...
2025-10-08 12:13:22.025730 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:13:22.620141 | 
2025-10-08 12:13:22.620255 | PLAY [localhost]
2025-10-08 12:13:22.629438 | 
2025-10-08 12:13:22.629516 | TASK [Gathering Facts]
2025-10-08 12:13:23.465870 | localhost | ok
2025-10-08 12:13:23.479556 | 
2025-10-08 12:13:23.479676 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 12:13:23.808177 | localhost -> localhost | changed
2025-10-08 12:13:23.814133 | 
2025-10-08 12:13:23.814219 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 12:13:24.567005 | localhost -> localhost | changed
2025-10-08 12:13:24.575228 | 
2025-10-08 12:13:24.575310 | TASK [Setup log path fact]
2025-10-08 12:13:24.592460 | localhost | ok
2025-10-08 12:13:24.603160 | 
2025-10-08 12:13:24.603226 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:13:24.629615 | localhost | ok
2025-10-08 12:13:24.637140 | 
2025-10-08 12:13:24.637208 | TASK [emit-job-header : Print job information]
2025-10-08 12:13:24.664143 | # Job Information
2025-10-08 12:13:24.664264 | Ansible Version: 2.15.12
2025-10-08 12:13:24.664293 | Job: cifmw-molecule-build_containers
2025-10-08 12:13:24.664316 | Pipeline: github-check
2025-10-08 12:13:24.664334 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 12:13:24.664352 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-08 12:13:24.664370 | Log URL (when completed): https://logserver.rdoproject.org/471/rdoproject.org/471ee0e4a0034caaacc1a1fcdf026293/
2025-10-08 12:13:24.664388 | Event ID: e452cdb0-a431-11f0-8caa-4725382b7326
2025-10-08 12:13:24.667981 | 
2025-10-08 12:13:24.668045 | LOOP [emit-job-header : Print node information]
2025-10-08 12:13:24.761631 | localhost | ok:
2025-10-08 12:13:24.761821 | localhost | # Node Information
2025-10-08 12:13:24.761870 | localhost | Inventory Hostname: controller
2025-10-08 12:13:24.761904 | localhost | Hostname: np0005476099
2025-10-08 12:13:24.761959 | localhost | Username: zuul
2025-10-08 12:13:24.761990 | localhost | Distro: CentOS 9
2025-10-08 12:13:24.762017 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 12:13:24.762042 | localhost | Region: regionOne
2025-10-08 12:13:24.762065 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-08 12:13:24.762089 | localhost | Product Name: OpenStack Compute
2025-10-08 12:13:24.762126 | localhost | Interface IP: 192.168.26.17
2025-10-08 12:13:24.791985 | 
2025-10-08 12:13:24.792080 | PLAY [all]
2025-10-08 12:13:24.798279 | 
2025-10-08 12:13:24.798342 | TASK [Gather network facts]
2025-10-08 12:13:25.171847 | controller | ok
2025-10-08 12:13:25.186138 | 
2025-10-08 12:13:25.186225 | TASK [include_role : start-zuul-console]
2025-10-08 12:13:25.213725 | controller | ok
2025-10-08 12:13:25.225955 | 
2025-10-08 12:13:25.226042 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 12:13:25.569466 | controller | ok
2025-10-08 12:13:25.578635 | 
2025-10-08 12:13:25.578718 | TASK [include_role : add-build-sshkey]
2025-10-08 12:13:25.595803 | controller | ok
2025-10-08 12:13:25.608344 | 
2025-10-08 12:13:25.608413 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 12:13:25.824875 | controller -> localhost | ok
2025-10-08 12:13:25.830593 | 
2025-10-08 12:13:25.830671 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 12:13:25.860798 | controller | ok
2025-10-08 12:13:25.877141 | controller | included: /var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 12:13:25.883006 | 
2025-10-08 12:13:25.883079 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 12:13:26.423747 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 12:13:26.424002 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/471ee0e4a0034caaacc1a1fcdf026293_id_rsa.
2025-10-08 12:13:26.424044 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/471ee0e4a0034caaacc1a1fcdf026293_id_rsa.pub.
2025-10-08 12:13:26.424070 | controller -> localhost | The key fingerprint is:
2025-10-08 12:13:26.424091 | controller -> localhost | SHA256:FeiUwAg5Fjaij034ZWG3P1NBdxklUZh8luAXvMDgbdw zuul-build-sshkey
2025-10-08 12:13:26.424131 | controller -> localhost | The key's randomart image is:
2025-10-08 12:13:26.424152 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 12:13:26.424172 | controller -> localhost | |. =+ooo. ++.+oBX+|
2025-10-08 12:13:26.424192 | controller -> localhost | |.++o.o..+ .+++*++|
2025-10-08 12:13:26.424211 | controller -> localhost | |o...o .o  o. =.E.|
2025-10-08 12:13:26.424229 | controller -> localhost | | * o   ..o  . .. |
2025-10-08 12:13:26.424246 | controller -> localhost | |. +     S        |
2025-10-08 12:13:26.424263 | controller -> localhost | |         o       |
2025-10-08 12:13:26.424279 | controller -> localhost | |                 |
2025-10-08 12:13:26.424296 | controller -> localhost | |                 |
2025-10-08 12:13:26.424313 | controller -> localhost | |                 |
2025-10-08 12:13:26.424329 | controller -> localhost | +----[SHA256]-----+
2025-10-08 12:13:26.424371 | controller -> localhost | ok: Runtime: 0:00:00.122930
2025-10-08 12:13:26.430126 | 
2025-10-08 12:13:26.430193 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 12:13:26.458366 | controller | ok
2025-10-08 12:13:26.468191 | controller | included: /var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 12:13:26.476610 | 
2025-10-08 12:13:26.476673 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 12:13:26.491750 | controller | skipping: Conditional result was False
2025-10-08 12:13:26.497745 | 
2025-10-08 12:13:26.497833 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 12:13:26.882832 | controller | changed
2025-10-08 12:13:26.887728 | 
2025-10-08 12:13:26.887797 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 12:13:27.099964 | controller | ok
2025-10-08 12:13:27.106042 | 
2025-10-08 12:13:27.106153 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 12:13:27.727879 | controller | changed
2025-10-08 12:13:27.733667 | 
2025-10-08 12:13:27.733755 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 12:13:28.332161 | controller | changed
2025-10-08 12:13:28.337819 | 
2025-10-08 12:13:28.337920 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 12:13:28.352175 | controller | skipping: Conditional result was False
2025-10-08 12:13:28.359054 | 
2025-10-08 12:13:28.359157 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 12:13:28.677683 | controller -> localhost | changed
2025-10-08 12:13:28.688331 | 
2025-10-08 12:13:28.688439 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 12:13:28.946343 | controller -> localhost | Identity added: /var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/471ee0e4a0034caaacc1a1fcdf026293_id_rsa (zuul-build-sshkey)
2025-10-08 12:13:28.946557 | controller -> localhost | ok: Runtime: 0:00:00.014663
2025-10-08 12:13:28.953708 | 
2025-10-08 12:13:28.953785 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 12:13:29.277588 | controller | ok
2025-10-08 12:13:29.282244 | 
2025-10-08 12:13:29.282319 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 12:13:29.311192 | controller | skipping: Conditional result was False
2025-10-08 12:13:29.320824 | 
2025-10-08 12:13:29.320889 | TASK [include_role : validate-host]
2025-10-08 12:13:29.339299 | controller | ok
2025-10-08 12:13:29.361270 | 
2025-10-08 12:13:29.361353 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 12:13:29.390841 | controller | ok
2025-10-08 12:13:29.395374 | 
2025-10-08 12:13:29.395449 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 12:13:29.613372 | controller -> localhost | ok
2025-10-08 12:13:29.619651 | 
2025-10-08 12:13:29.619734 | TASK [validate-host : Collect information about the host]
2025-10-08 12:13:30.235666 | controller | ok
2025-10-08 12:13:30.244311 | 
2025-10-08 12:13:30.244397 | TASK [validate-host : Sanitize hostname]
2025-10-08 12:13:30.325666 | controller | ok
2025-10-08 12:13:30.354621 | 
2025-10-08 12:13:30.354723 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 12:13:30.764031 | controller -> localhost | changed
2025-10-08 12:13:30.770453 | 
2025-10-08 12:13:30.770527 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 12:13:31.100499 | controller | ok
2025-10-08 12:13:31.105748 | 
2025-10-08 12:13:31.105820 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 12:13:31.497002 | controller -> localhost | changed
2025-10-08 12:13:31.506704 | 
2025-10-08 12:13:31.506791 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 12:13:31.520216 | controller | skipping: Conditional result was False
2025-10-08 12:13:31.526074 | 
2025-10-08 12:13:31.526162 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 12:13:31.540209 | controller | skipping: Conditional result was False
2025-10-08 12:13:31.547128 | 
2025-10-08 12:13:31.547206 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 12:13:31.761895 | controller | ok: "logs"
2025-10-08 12:13:31.762149 | controller | ok: All items complete
2025-10-08 12:13:31.762184 | 
2025-10-08 12:13:31.945125 | controller | ok: "artifacts"
2025-10-08 12:13:32.112716 | controller | ok: "docs"
2025-10-08 12:13:32.126364 | 
2025-10-08 12:13:32.126471 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 12:13:32.344961 | controller | changed: "logs"
2025-10-08 12:13:32.538058 | controller | changed: "artifacts"
2025-10-08 12:13:32.712699 | controller | changed: "docs"
2025-10-08 12:13:32.745838 | 
2025-10-08 12:13:32.746010 | PLAY RECAP
2025-10-08 12:13:32.746066 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:13:32.746095 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:13:32.746138 | 
2025-10-08 12:13:32.856273 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:13:32.857309 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:13:33.421969 | 
2025-10-08 12:13:33.422090 | PLAY [all]
2025-10-08 12:13:33.441905 | 
2025-10-08 12:13:33.442046 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:13:33.495971 | controller | ok
2025-10-08 12:13:33.500925 | 
2025-10-08 12:13:33.501030 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:13:33.844924 | controller | changed
2025-10-08 12:13:33.850382 | 
2025-10-08 12:13:33.850458 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:13:34.701839 | controller | changed
2025-10-08 12:13:34.712249 | 
2025-10-08 12:13:34.712339 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 12:13:35.099461 | controller | changed:
2025-10-08 12:13:35.099633 | controller | {
2025-10-08 12:13:35.099665 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 12:13:35.099696 | controller | }
2025-10-08 12:13:35.315926 | controller | changed:
2025-10-08 12:13:35.316026 | controller | {
2025-10-08 12:13:35.316060 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 12:13:35.316083 | controller | }
2025-10-08 12:13:35.534472 | controller | changed:
2025-10-08 12:13:35.534572 | controller | {
2025-10-08 12:13:35.534602 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 12:13:35.534624 | controller | }
2025-10-08 12:13:35.749031 | controller | changed:
2025-10-08 12:13:35.749134 | controller | {
2025-10-08 12:13:35.749166 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 12:13:35.749188 | controller | }
2025-10-08 12:13:35.967486 | controller | changed:
2025-10-08 12:13:35.967580 | controller | {
2025-10-08 12:13:35.967610 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 12:13:35.967631 | controller | }
2025-10-08 12:13:36.179169 | controller | changed:
2025-10-08 12:13:36.179262 | controller | {
2025-10-08 12:13:36.179293 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 12:13:36.179315 | controller | }
2025-10-08 12:13:36.391842 | controller | changed:
2025-10-08 12:13:36.391944 | controller | {
2025-10-08 12:13:36.391980 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-08 12:13:36.392005 | controller | }
2025-10-08 12:13:36.599023 | controller | changed:
2025-10-08 12:13:36.599346 | controller | {
2025-10-08 12:13:36.599382 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-08 12:13:36.599407 | controller | }
2025-10-08 12:13:36.829142 | controller | changed:
2025-10-08 12:13:36.829239 | controller | {
2025-10-08 12:13:36.829269 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 12:13:36.829290 | controller | }
2025-10-08 12:13:37.043285 | controller | changed:
2025-10-08 12:13:37.043426 | controller | {
2025-10-08 12:13:37.043453 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 12:13:37.043474 | controller | }
2025-10-08 12:13:37.256419 | controller | changed:
2025-10-08 12:13:37.256525 | controller | {
2025-10-08 12:13:37.256553 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 12:13:37.256575 | controller | }
2025-10-08 12:13:37.464526 | controller | changed:
2025-10-08 12:13:37.464641 | controller | {
2025-10-08 12:13:37.464669 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 12:13:37.464690 | controller | }
2025-10-08 12:13:37.680651 | controller | changed:
2025-10-08 12:13:37.680742 | controller | {
2025-10-08 12:13:37.680771 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 12:13:37.680793 | controller | }
2025-10-08 12:13:37.897569 | controller | changed:
2025-10-08 12:13:37.897685 | controller | {
2025-10-08 12:13:37.897714 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 12:13:37.897736 | controller | }
2025-10-08 12:13:38.119579 | controller | changed:
2025-10-08 12:13:38.119663 | controller | {
2025-10-08 12:13:38.119691 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 12:13:38.119714 | controller | }
2025-10-08 12:13:38.342792 | controller | changed:
2025-10-08 12:13:38.342888 | controller | {
2025-10-08 12:13:38.342935 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 12:13:38.342960 | controller | }
2025-10-08 12:13:38.555788 | controller | changed:
2025-10-08 12:13:38.555877 | controller | {
2025-10-08 12:13:38.555923 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-08 12:13:38.555955 | controller | }
2025-10-08 12:13:38.767819 | controller | changed:
2025-10-08 12:13:38.768082 | controller | {
2025-10-08 12:13:38.768131 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-08 12:13:38.768161 | controller | }
2025-10-08 12:13:38.980073 | controller | changed:
2025-10-08 12:13:38.980176 | controller | {
2025-10-08 12:13:38.980211 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 12:13:38.980236 | controller | }
2025-10-08 12:13:39.208665 | controller | changed:
2025-10-08 12:13:39.208743 | controller | {
2025-10-08 12:13:39.208772 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 12:13:39.208795 | controller | }
2025-10-08 12:13:39.425634 | controller | changed:
2025-10-08 12:13:39.425722 | controller | {
2025-10-08 12:13:39.425750 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 12:13:39.425772 | controller | }
2025-10-08 12:13:39.650369 | controller | changed:
2025-10-08 12:13:39.650456 | controller | {
2025-10-08 12:13:39.650483 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 12:13:39.650503 | controller | }
2025-10-08 12:13:39.859379 | controller | changed:
2025-10-08 12:13:39.859517 | controller | {
2025-10-08 12:13:39.859573 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-08 12:13:39.859600 | controller | }
2025-10-08 12:13:40.071512 | controller | changed:
2025-10-08 12:13:40.071670 | controller | {
2025-10-08 12:13:40.071711 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 12:13:40.071735 | controller | }
2025-10-08 12:13:40.291872 | controller | changed:
2025-10-08 12:13:40.291979 | controller | {
2025-10-08 12:13:40.292008 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 12:13:40.292029 | controller | }
2025-10-08 12:13:40.494264 | controller | changed:
2025-10-08 12:13:40.494349 | controller | {
2025-10-08 12:13:40.494378 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 12:13:40.494398 | controller | }
2025-10-08 12:13:40.513797 | 
2025-10-08 12:13:40.513882 | TASK [Set timezone to UTC]
2025-10-08 12:13:40.924035 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 12:13:40.929633 | 
2025-10-08 12:13:40.929706 | TASK [Create nodepool directory]
2025-10-08 12:13:41.140283 | controller | changed
2025-10-08 12:13:41.145933 | 
2025-10-08 12:13:41.146001 | TASK [Create nodepool sub_nodes file]
2025-10-08 12:13:41.701547 | controller | changed
2025-10-08 12:13:41.706606 | 
2025-10-08 12:13:41.706694 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 12:13:42.254064 | controller | changed
2025-10-08 12:13:42.260467 | 
2025-10-08 12:13:42.260537 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 12:13:42.290682 | 
2025-10-08 12:13:42.290828 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 12:13:42.320748 | 
2025-10-08 12:13:42.320896 | TASK [Create nodepool primary file]
2025-10-08 12:13:42.344669 | controller | skipping: Conditional result was False
2025-10-08 12:13:42.350964 | 
2025-10-08 12:13:42.351062 | TASK [Create nodepool node_private for this node]
2025-10-08 12:13:42.941538 | controller | changed
2025-10-08 12:13:42.948672 | 
2025-10-08 12:13:42.948762 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 12:13:43.316045 | controller | ok: Item: id_rsa Runtime: 0:00:00.006727
2025-10-08 12:13:43.316233 | 
2025-10-08 12:13:43.492526 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005389
2025-10-08 12:13:43.498832 | 
2025-10-08 12:13:43.498938 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 12:13:44.058986 | controller | changed
2025-10-08 12:13:44.064222 | 
2025-10-08 12:13:44.064308 | TASK [Validate sudoers config after edits]
2025-10-08 12:13:44.293951 | controller | /etc/sudoers: parsed OK
2025-10-08 12:13:44.294026 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 12:13:44.294036 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 12:13:44.294042 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 12:13:44.593836 | controller | ok: Runtime: 0:00:00.006004
2025-10-08 12:13:44.599564 | 
2025-10-08 12:13:44.599637 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 12:13:44.806749 | controller | SHELL=/bin/bash
2025-10-08 12:13:44.806790 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 12:13:44.806800 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 12:13:44.806807 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/42/1042/4d1575d1c5aa317d07bf95973aac6faacde3d75b^openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:13:44.806815 | controller | PWD=/home/zuul
2025-10-08 12:13:44.806821 | controller | ZUUL_PIPELINE=github-check
2025-10-08 12:13:44.806826 | controller | LOGNAME=zuul
2025-10-08 12:13:44.806831 | controller | XDG_SESSION_TYPE=tty
2025-10-08 12:13:44.806837 | controller | _=/usr/bin/env
2025-10-08 12:13:44.806842 | controller | MOTD_SHOWN=pam
2025-10-08 12:13:44.806847 | controller | HOME=/home/zuul
2025-10-08 12:13:44.806852 | controller | LANG=en_US.UTF-8
2025-10-08 12:13:44.806857 | controller | SSH_CONNECTION=192.168.26.12 52168 192.168.26.17 22
2025-10-08 12:13:44.806900 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-08 12:13:44.806907 | controller | ZUUL_CHANGE_IDS=1042,4d1575d1c5aa317d07bf95973aac6faacde3d75b 3368,a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:13:44.806913 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 12:13:44.806918 | controller | XDG_SESSION_CLASS=user
2025-10-08 12:13:44.806924 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 12:13:44.806929 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 12:13:44.806934 | controller | USER=zuul
2025-10-08 12:13:44.806939 | controller | ZUUL_VOTING=True
2025-10-08 12:13:44.806945 | controller | BUILD_TIMEOUT=1800000
2025-10-08 12:13:44.806950 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 12:13:44.806955 | controller | SHLVL=1
2025-10-08 12:13:44.806960 | controller | ZUUL_PATCHSET=a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:13:44.806965 | controller | XDG_SESSION_ID=1
2025-10-08 12:13:44.806971 | controller | ZUUL_BRANCH=main
2025-10-08 12:13:44.806976 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 12:13:44.806981 | controller | SSH_CLIENT=192.168.26.12 52168 22
2025-10-08 12:13:44.806987 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 12:13:44.806992 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 12:13:44.806997 | controller | which_declare=declare -f
2025-10-08 12:13:44.807003 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 12:13:44.807009 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 12:13:44.807014 | controller | ZUUL_CHANGE=3368
2025-10-08 12:13:44.807019 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 12:13:44.807024 | controller | ZUUL_UUID=471ee0e4a0034caaacc1a1fcdf026293
2025-10-08 12:13:44.807029 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 12:13:44.807035 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 12:13:44.807040 | controller | }
2025-10-08 12:13:45.131083 | controller | ok: Runtime: 0:00:00.006160
2025-10-08 12:13:45.136549 | 
2025-10-08 12:13:45.136617 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 12:13:45.150448 | controller | skipping: Conditional result was False
2025-10-08 12:13:45.156165 | 
2025-10-08 12:13:45.156253 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 12:13:45.680704 | controller | skipping: Conditional result was False
2025-10-08 12:13:45.688135 | 
2025-10-08 12:13:45.688240 | TASK [Ensure legacy workspace directory]
2025-10-08 12:13:45.883878 | controller | changed
2025-10-08 12:13:45.904083 | 
2025-10-08 12:13:45.904184 | PLAY RECAP
2025-10-08 12:13:45.904228 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:13:45.904252 | 
2025-10-08 12:13:46.003892 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:13:46.004719 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:13:46.594401 | 
2025-10-08 12:13:46.594509 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 12:13:46.622712 | 
2025-10-08 12:13:46.622818 | TASK [Create zuul-output directory]
2025-10-08 12:13:46.979515 | controller | changed
2025-10-08 12:13:46.984495 | 
2025-10-08 12:13:46.984581 | TASK [Slurp Zuul inventory test]
2025-10-08 12:13:47.291167 | controller -> localhost | ok
2025-10-08 12:13:47.300262 | 
2025-10-08 12:13:47.300379 | TASK [Save zuul inventory]
2025-10-08 12:13:48.145261 | controller | changed
2025-10-08 12:13:48.151064 | 
2025-10-08 12:13:48.151164 | TASK [Save zuul vars without the change_message]
2025-10-08 12:13:48.759263 | controller | changed
2025-10-08 12:13:48.781153 | 
2025-10-08 12:13:48.781229 | PLAY RECAP
2025-10-08 12:13:48.781276 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:13:48.781299 | 
2025-10-08 12:13:48.871011 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:13:48.871863 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:13:49.469282 | 
2025-10-08 12:13:49.469396 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 12:13:49.498997 | 
2025-10-08 12:13:49.499120 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 12:13:49.517239 | controller | ok
2025-10-08 12:13:49.534487 | 
2025-10-08 12:13:49.534591 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:13:49.558321 | controller | skipping: Conditional result was False
2025-10-08 12:13:49.563992 | 
2025-10-08 12:13:49.564067 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:13:49.897148 | controller | ok
2025-10-08 12:13:49.904257 | 
2025-10-08 12:13:49.904331 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:13:50.565211 | controller | ok
2025-10-08 12:13:50.581199 | 
2025-10-08 12:13:50.581300 | TASK [Prepare workspace]
2025-10-08 12:13:50.600303 | controller | ok
2025-10-08 12:13:50.618277 | 
2025-10-08 12:13:50.618378 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 12:13:50.934548 | controller | ok
2025-10-08 12:13:50.941576 | 
2025-10-08 12:13:50.941661 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 12:13:53.325070 | controller | Output suppressed because no_log was given
2025-10-08 12:13:53.334992 | 
2025-10-08 12:13:53.335085 | LOOP [Create zuul-output directory]
2025-10-08 12:13:53.522752 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 12:13:53.682542 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 12:13:53.690388 | 
2025-10-08 12:13:53.690634 | TASK [Install required packages]
2025-10-08 12:14:43.849555 | controller | changed
2025-10-08 12:14:43.854747 | 
2025-10-08 12:14:43.854813 | TASK [Install venv]
2025-10-08 12:15:49.285584 | controller | changed
2025-10-08 12:15:49.344356 | 
2025-10-08 12:15:49.344461 | PLAY RECAP
2025-10-08 12:15:49.344506 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:15:49.344530 | 
2025-10-08 12:15:49.449823 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:15:49.450668 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:15:50.122365 | 
2025-10-08 12:15:50.122482 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 12:15:50.146760 | 
2025-10-08 12:15:50.147198 | TASK [Gather required facts]
2025-10-08 12:15:50.717960 | controller | ok
2025-10-08 12:15:50.723421 | 
2025-10-08 12:15:50.723528 | TASK [Load environment var if instructed to]
2025-10-08 12:15:50.747475 | controller | skipping: Conditional result was False
2025-10-08 12:15:50.753327 | 
2025-10-08 12:15:50.753430 | TASK [Run molecule]
2025-10-08 12:15:51.755261 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 12:15:51.824876 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 12:16:08.827144 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.827575 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.828066 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.828520 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.829045 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+a7a306bf was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 1.0.0+a7a306bf (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.829476 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.829927 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.830381 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.830837 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.831290 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.831737 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.832194 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.832629 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.833112 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.833546 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:08.843186 | controller | INFO     Running default > prepare
2025-10-08 12:16:09.551246 | controller |
2025-10-08 12:16:09.551340 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 12:16:09.551439 | controller |
2025-10-08 12:16:09.551544 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:16:09.551666 | controller | Wednesday 08 October 2025  12:16:09 +0000 (0:00:00.023)       0:00:00.023 *****
2025-10-08 12:16:10.403995 | controller | ok: [instance]
2025-10-08 12:16:10.404072 | controller |
2025-10-08 12:16:10.404185 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 12:16:10.404294 | controller | Wednesday 08 October 2025  12:16:10 +0000 (0:00:00.853)       0:00:00.876 *****
2025-10-08 12:16:10.421233 | controller | skipping: [instance]
2025-10-08 12:16:10.421292 | controller |
2025-10-08 12:16:10.421411 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 12:16:10.421517 | controller | Wednesday 08 October 2025  12:16:10 +0000 (0:00:00.017)       0:00:00.894 *****
2025-10-08 12:16:10.464456 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 12:16:10.464511 | controller |
2025-10-08 12:16:10.464632 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 12:16:10.464789 | controller | Wednesday 08 October 2025  12:16:10 +0000 (0:00:00.043)       0:00:00.937 *****
2025-10-08 12:16:10.750260 | controller | ok: [instance]
2025-10-08 12:16:10.750338 | controller |
2025-10-08 12:16:10.750461 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 12:16:10.750567 | controller | Wednesday 08 October 2025  12:16:10 +0000 (0:00:00.285)       0:00:01.223 *****
2025-10-08 12:16:11.062516 | controller | ok: [instance]
2025-10-08 12:16:11.062603 | controller |
2025-10-08 12:16:11.062748 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 12:16:11.062858 | controller | Wednesday 08 October 2025  12:16:11 +0000 (0:00:00.312)       0:00:01.535 *****
2025-10-08 12:16:11.770024 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 12:16:11.770115 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 12:16:11.770227 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 12:16:11.770326 | controller |
2025-10-08 12:16:11.770436 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 12:16:11.770532 | controller | Wednesday 08 October 2025  12:16:11 +0000 (0:00:00.707)       0:00:02.242 *****
2025-10-08 12:16:11.825211 | controller |
2025-10-08 12:16:11.825308 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 12:16:11.825413 | controller | Wednesday 08 October 2025  12:16:11 +0000 (0:00:00.055)       0:00:02.298 *****
2025-10-08 12:16:12.373024 | controller | changed: [instance] => (item=tmp)
2025-10-08 12:16:12.373109 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 12:16:12.373167 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 12:16:12.373269 | controller |
2025-10-08 12:16:12.373373 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 12:16:12.373486 | controller | Wednesday 08 October 2025  12:16:12 +0000 (0:00:00.546)       0:00:02.844 *****
2025-10-08 12:16:13.241255 | controller | ok: [instance]
2025-10-08 12:16:13.241676 | controller |
2025-10-08 12:16:14.506584 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 12:16:14.506615 | controller | Wednesday 08 October 2025  12:16:13 +0000 (0:00:00.868)       0:00:03.713 *****
2025-10-08 12:16:14.506628 | controller | changed: [instance]
2025-10-08 12:16:22.518247 | controller |
2025-10-08 12:16:22.518292 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 12:16:22.518303 | controller | Wednesday 08 October 2025  12:16:14 +0000 (0:00:01.265)       0:00:04.979 *****
2025-10-08 12:16:22.518314 | controller | changed: [instance]
2025-10-08 12:16:23.253426 | controller |
2025-10-08 12:16:23.253464 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 12:16:23.253473 | controller | Wednesday 08 October 2025  12:16:22 +0000 (0:00:08.011)       0:00:12.990 *****
2025-10-08 12:16:23.253483 | controller | changed: [instance]
2025-10-08 12:16:23.274696 | controller |
2025-10-08 12:16:23.274715 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 12:16:23.274723 | controller | Wednesday 08 October 2025  12:16:23 +0000 (0:00:00.735)       0:00:13.725 *****
2025-10-08 12:16:23.274731 | controller | skipping: [instance]
2025-10-08 12:16:23.275085 | controller |
2025-10-08 12:16:24.126697 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 12:16:24.126727 | controller | Wednesday 08 October 2025  12:16:23 +0000 (0:00:00.021)       0:00:13.747 *****
2025-10-08 12:16:24.126740 | controller | changed: [instance]
2025-10-08 12:16:24.163666 | controller |
2025-10-08 12:16:24.163695 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 12:16:24.163704 | controller | Wednesday 08 October 2025  12:16:24 +0000 (0:00:00.851)       0:00:14.599 *****
2025-10-08 12:16:24.163714 | controller | skipping: [instance]
2025-10-08 12:16:24.203570 | controller |
2025-10-08 12:16:24.203600 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 12:16:24.203608 | controller | Wednesday 08 October 2025  12:16:24 +0000 (0:00:00.037)       0:00:14.636 *****
2025-10-08 12:16:24.203618 | controller | skipping: [instance]
2025-10-08 12:16:24.241991 | controller |
2025-10-08 12:16:24.242021 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 12:16:24.242028 | controller | Wednesday 08 October 2025  12:16:24 +0000 (0:00:00.039)       0:00:14.675 *****
2025-10-08 12:16:24.242039 | controller | skipping: [instance]
2025-10-08 12:16:24.806829 | controller |
2025-10-08 12:16:24.806862 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 12:16:24.806871 | controller | Wednesday 08 October 2025  12:16:24 +0000 (0:00:00.038)       0:00:14.714 *****
2025-10-08 12:16:24.806881 | controller | changed: [instance]
2025-10-08 12:16:25.271711 | controller |
2025-10-08 12:16:25.271744 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 12:16:25.271752 | controller | Wednesday 08 October 2025  12:16:24 +0000 (0:00:00.564)       0:00:15.279 *****
2025-10-08 12:16:25.271763 | controller | changed: [instance]
2025-10-08 12:16:25.271792 | controller |
2025-10-08 12:16:25.272102 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 12:16:25.302709 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.465)       0:00:15.744 *****
2025-10-08 12:16:25.302744 | controller | skipping: [instance]
2025-10-08 12:16:25.302778 | controller |
2025-10-08 12:16:25.302786 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 12:16:25.302794 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.030)       0:00:15.775 *****
2025-10-08 12:16:25.332525 | controller | skipping: [instance]
2025-10-08 12:16:25.361376 | controller |
2025-10-08 12:16:25.361404 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 12:16:25.361413 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.029)       0:00:15.804 *****
2025-10-08 12:16:25.361423 | controller | skipping: [instance]
2025-10-08 12:16:25.400953 | controller |
2025-10-08 12:16:25.400989 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 12:16:25.400998 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.029)       0:00:15.834 *****
2025-10-08 12:16:25.401010 | controller | ok: [instance]
2025-10-08 12:16:25.401231 | controller |
2025-10-08 12:16:25.401397 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 12:16:25.401558 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.039)       0:00:15.873 *****
2025-10-08 12:16:25.430382 | controller | skipping: [instance]
2025-10-08 12:16:25.430631 | controller |
2025-10-08 12:16:25.430975 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 12:16:25.431205 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.029)       0:00:15.903 *****
2025-10-08 12:16:25.491342 | controller | skipping: [instance]
2025-10-08 12:16:25.491591 | controller |
2025-10-08 12:16:25.491828 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 12:16:25.492018 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.061)       0:00:15.964 *****
2025-10-08 12:16:25.519616 | controller | skipping: [instance]
2025-10-08 12:16:25.519878 | controller |
2025-10-08 12:16:25.520105 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 12:16:25.520275 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.028)       0:00:15.992 *****
2025-10-08 12:16:25.546692 | controller | skipping: [instance]
2025-10-08 12:16:25.546879 | controller |
2025-10-08 12:16:25.547053 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 12:16:25.547242 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.027)       0:00:16.019 *****
2025-10-08 12:16:25.574231 | controller | skipping: [instance]
2025-10-08 12:16:25.604749 | controller |
2025-10-08 12:16:25.604788 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 12:16:25.604797 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.026)       0:00:16.046 *****
2025-10-08 12:16:25.604807 | controller | skipping: [instance]
2025-10-08 12:16:25.604832 | controller |
2025-10-08 12:16:25.605056 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 12:16:25.632742 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.030)       0:00:16.077 *****
2025-10-08 12:16:25.632778 | controller | skipping: [instance]
2025-10-08 12:16:25.632802 | controller |
2025-10-08 12:16:25.632811 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 12:16:25.632922 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.027)       0:00:16.105 *****
2025-10-08 12:16:25.826125 | controller | ok: [instance]
2025-10-08 12:16:26.042438 | controller |
2025-10-08 12:16:26.042469 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 12:16:26.042477 | controller | Wednesday 08 October 2025  12:16:25 +0000 (0:00:00.193)       0:00:16.298 *****
2025-10-08 12:16:26.042487 | controller | changed: [instance]
2025-10-08 12:16:26.282457 | controller |
2025-10-08 12:16:26.282487 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 12:16:26.282495 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.216)       0:00:16.515 *****
2025-10-08 12:16:26.282505 | controller | changed: [instance]
2025-10-08 12:16:26.304025 | controller |
2025-10-08 12:16:26.304056 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 12:16:26.304063 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.239)       0:00:16.754 *****
2025-10-08 12:16:26.304073 | controller | skipping: [instance]
2025-10-08 12:16:26.329036 | controller |
2025-10-08 12:16:26.329071 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 12:16:26.329099 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.021)       0:00:16.776 *****
2025-10-08 12:16:26.329110 | controller | skipping: [instance]
2025-10-08 12:16:26.353350 | controller |
2025-10-08 12:16:26.353377 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 12:16:26.353384 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.024)       0:00:16.801 *****
2025-10-08 12:16:26.353394 | controller | skipping: [instance]
2025-10-08 12:16:26.378328 | controller |
2025-10-08 12:16:26.378360 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:16:26.378368 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.024)       0:00:16.825 *****
2025-10-08 12:16:26.378378 | controller | skipping: [instance]
2025-10-08 12:16:26.404396 | controller |
2025-10-08 12:16:26.404430 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 12:16:26.404447 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.024)       0:00:16.850 *****
2025-10-08 12:16:26.404460 | controller | skipping: [instance]
2025-10-08 12:16:26.404692 | controller |
2025-10-08 12:16:26.404910 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 12:16:26.405072 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.026)       0:00:16.877 *****
2025-10-08 12:16:26.429563 | controller | skipping: [instance]
2025-10-08 12:16:26.429798 | controller |
2025-10-08 12:16:26.429963 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 12:16:26.430132 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.025)       0:00:16.902 *****
2025-10-08 12:16:26.449536 | controller | skipping: [instance]
2025-10-08 12:16:26.449771 | controller |
2025-10-08 12:16:26.449958 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 12:16:26.450124 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.020)       0:00:16.922 *****
2025-10-08 12:16:26.480997 | controller | skipping: [instance]
2025-10-08 12:16:26.481214 | controller |
2025-10-08 12:16:26.481386 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 12:16:26.481534 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.031)       0:00:16.953 *****
2025-10-08 12:16:26.501122 | controller | skipping: [instance]
2025-10-08 12:16:26.501330 | controller |
2025-10-08 12:16:26.501495 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 12:16:26.501676 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.020)       0:00:16.973 *****
2025-10-08 12:16:26.524491 | controller | skipping: [instance]
2025-10-08 12:16:26.524727 | controller |
2025-10-08 12:16:26.524901 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 12:16:26.525052 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.023)       0:00:16.997 *****
2025-10-08 12:16:26.556051 | controller | skipping: [instance]
2025-10-08 12:16:26.556272 | controller |
2025-10-08 12:16:26.556435 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 12:16:26.556583 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.031)       0:00:17.028 *****
2025-10-08 12:16:26.587485 | controller | skipping: [instance]
2025-10-08 12:16:26.587713 | controller |
2025-10-08 12:16:26.587880 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 12:16:26.588032 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.031)       0:00:17.060 *****
2025-10-08 12:16:26.617627 | controller | skipping: [instance]
2025-10-08 12:16:26.617885 | controller |
2025-10-08 12:16:26.618066 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 12:16:26.618243 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.030)       0:00:17.090 *****
2025-10-08 12:16:26.648572 | controller | skipping: [instance]
2025-10-08 12:16:26.648800 | controller |
2025-10-08 12:16:26.648962 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 12:16:26.649121 | controller | Wednesday 08 October 2025  12:16:26 +0000 (0:00:00.030)       0:00:17.121 *****
2025-10-08 12:17:21.883210 | controller | ok: [instance]
2025-10-08 12:17:23.089622 | controller |
2025-10-08 12:17:23.089675 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 12:17:23.089685 | controller | Wednesday 08 October 2025  12:17:21 +0000 (0:00:55.234)       0:01:12.355 *****
2025-10-08 12:17:23.089716 | controller | ok: [instance]
2025-10-08 12:17:23.123825 | controller |
2025-10-08 12:17:23.123862 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 12:17:23.123871 | controller | Wednesday 08 October 2025  12:17:23 +0000 (0:00:01.206)       0:01:13.562 *****
2025-10-08 12:17:23.123882 | controller | skipping: [instance]
2025-10-08 12:17:23.702448 | controller |
2025-10-08 12:17:23.702507 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 12:17:23.702517 | controller | Wednesday 08 October 2025  12:17:23 +0000 (0:00:00.034)       0:01:13.596 *****
2025-10-08 12:17:23.702528 | controller | ok: [instance] => (item=tmp)
2025-10-08 12:17:24.854183 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-08 12:17:24.854214 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-08 12:17:24.854222 | controller |
2025-10-08 12:17:24.854228 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 12:17:24.854244 | controller | Wednesday 08 October 2025  12:17:23 +0000 (0:00:00.578)       0:01:14.174 *****
2025-10-08 12:17:24.854256 | controller | ok: [instance]
2025-10-08 12:17:25.391245 | controller |
2025-10-08 12:17:25.391275 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 12:17:25.391284 | controller | Wednesday 08 October 2025  12:17:24 +0000 (0:00:01.152)       0:01:15.326 *****
2025-10-08 12:17:25.391294 | controller | ok: [instance]
2025-10-08 12:17:26.581925 | controller |
2025-10-08 12:17:26.581955 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 12:17:26.581968 | controller | Wednesday 08 October 2025  12:17:25 +0000 (0:00:00.537)       0:01:15.863 *****
2025-10-08 12:17:26.581978 | controller | ok: [instance]
2025-10-08 12:17:26.796137 | controller |
2025-10-08 12:17:26.796170 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 12:17:26.796179 | controller | Wednesday 08 October 2025  12:17:26 +0000 (0:00:01.190)       0:01:17.054 *****
2025-10-08 12:17:26.796189 | controller | ok: [instance]
2025-10-08 12:17:26.818364 | controller |
2025-10-08 12:17:26.818393 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 12:17:26.818401 | controller | Wednesday 08 October 2025  12:17:26 +0000 (0:00:00.214)       0:01:17.268 *****
2025-10-08 12:17:26.818411 | controller | skipping: [instance]
2025-10-08 12:17:27.658570 | controller |
2025-10-08 12:17:27.658601 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 12:17:27.658609 | controller | Wednesday 08 October 2025  12:17:26 +0000 (0:00:00.022)       0:01:17.291 *****
2025-10-08 12:17:27.658619 | controller | changed: [instance]
2025-10-08 12:17:27.741807 | controller |
2025-10-08 12:17:27.741848 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 12:17:27.741856 | controller | Wednesday 08 October 2025  12:17:27 +0000 (0:00:00.840)       0:01:18.131 *****
2025-10-08 12:17:27.741867 | controller | skipping: [instance]
2025-10-08 12:17:27.741893 | controller |
2025-10-08 12:17:27.741922 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 12:17:27.742086 | controller | Wednesday 08 October 2025  12:17:27 +0000 (0:00:00.083)       0:01:18.214 *****
2025-10-08 12:17:27.783753 | controller | skipping: [instance]
2025-10-08 12:17:27.783803 | controller |
2025-10-08 12:17:27.783813 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 12:17:27.783822 | controller | Wednesday 08 October 2025  12:17:27 +0000 (0:00:00.041)       0:01:18.256 *****
2025-10-08 12:17:27.825253 | controller | skipping: [instance]
2025-10-08 12:17:28.393487 | controller |
2025-10-08 12:17:28.393517 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 12:17:28.393525 | controller | Wednesday 08 October 2025  12:17:27 +0000 (0:00:00.041)       0:01:18.297 *****
2025-10-08 12:17:28.393535 | controller | changed: [instance]
2025-10-08 12:17:28.753931 | controller |
2025-10-08 12:17:28.753965 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 12:17:28.753973 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.568)       0:01:18.866 *****
2025-10-08 12:17:28.753983 | controller | changed: [instance]
2025-10-08 12:17:28.783364 | controller |
2025-10-08 12:17:28.783399 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 12:17:28.783409 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.360)       0:01:19.226 *****
2025-10-08 12:17:28.783420 | controller | skipping: [instance]
2025-10-08 12:17:28.813255 | controller |
2025-10-08 12:17:28.813286 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 12:17:28.813294 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.029)       0:01:19.255 *****
2025-10-08 12:17:28.813304 | controller | skipping: [instance]
2025-10-08 12:17:28.842212 | controller |
2025-10-08 12:17:28.842246 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 12:17:28.842254 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.029)       0:01:19.285 *****
2025-10-08 12:17:28.842264 | controller | skipping: [instance]
2025-10-08 12:17:28.881025 | controller |
2025-10-08 12:17:28.881062 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 12:17:28.881082 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.028)       0:01:19.314 *****
2025-10-08 12:17:28.881093 | controller | ok: [instance]
2025-10-08 12:17:28.881283 | controller |
2025-10-08 12:17:28.881447 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 12:17:28.881598 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.039)       0:01:19.353 *****
2025-10-08 12:17:28.909139 | controller | skipping: [instance]
2025-10-08 12:17:28.909340 | controller |
2025-10-08 12:17:28.909495 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 12:17:28.909668 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.028)       0:01:19.381 *****
2025-10-08 12:17:28.936937 | controller | skipping: [instance]
2025-10-08 12:17:28.937123 | controller |
2025-10-08 12:17:28.937292 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 12:17:28.937449 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.027)       0:01:19.409 *****
2025-10-08 12:17:28.964858 | controller | skipping: [instance]
2025-10-08 12:17:28.965020 | controller |
2025-10-08 12:17:28.965173 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 12:17:28.965326 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.027)       0:01:19.437 *****
2025-10-08 12:17:28.992501 | controller | skipping: [instance]
2025-10-08 12:17:28.992754 | controller |
2025-10-08 12:17:28.992948 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 12:17:28.993121 | controller | Wednesday 08 October 2025  12:17:28 +0000 (0:00:00.027)       0:01:19.465 *****
2025-10-08 12:17:29.023341 | controller | skipping: [instance]
2025-10-08 12:17:29.023578 | controller |
2025-10-08 12:17:29.023797 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 12:17:29.023961 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.030)       0:01:19.496 *****
2025-10-08 12:17:29.052716 | controller | skipping: [instance]
2025-10-08 12:17:29.052884 | controller |
2025-10-08 12:17:29.053051 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 12:17:29.053202 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.029)       0:01:19.525 *****
2025-10-08 12:17:29.080844 | controller | skipping: [instance]
2025-10-08 12:17:29.080947 | controller |
2025-10-08 12:17:29.080972 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 12:17:29.080983 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.027)       0:01:19.553 *****
2025-10-08 12:17:29.276826 | controller | ok: [instance]
2025-10-08 12:17:29.513776 | controller |
2025-10-08 12:17:29.513813 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 12:17:29.513821 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.195)       0:01:19.749 *****
2025-10-08 12:17:29.513832 | controller | changed: [instance]
2025-10-08 12:17:29.747519 | controller |
2025-10-08 12:17:29.747552 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 12:17:29.747560 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.236)       0:01:19.986 *****
2025-10-08 12:17:29.747570 | controller | changed: [instance]
2025-10-08 12:17:29.770458 | controller |
2025-10-08 12:17:29.770484 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 12:17:29.770493 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.233)       0:01:20.220 *****
2025-10-08 12:17:29.770504 | controller | skipping: [instance]
2025-10-08 12:17:29.808361 | controller |
2025-10-08 12:17:29.808391 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 12:17:29.808399 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.023)       0:01:20.243 *****
2025-10-08 12:17:29.808409 | controller | skipping: [instance]
2025-10-08 12:17:29.845486 | controller |
2025-10-08 12:17:29.845522 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 12:17:29.845531 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.037)       0:01:20.280 *****
2025-10-08 12:17:29.845543 | controller | skipping: [instance]
2025-10-08 12:17:29.883073 | controller |
2025-10-08 12:17:29.883108 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:17:29.883117 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.036)       0:01:20.317 *****
2025-10-08 12:17:29.883127 | controller | skipping: [instance]
2025-10-08 12:17:29.883321 | controller |
2025-10-08 12:17:29.883487 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 12:17:29.883662 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.037)       0:01:20.355 *****
2025-10-08 12:17:29.921310 | controller | skipping: [instance]
2025-10-08 12:17:29.921531 | controller |
2025-10-08 12:17:29.921780 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 12:17:29.921950 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.038)       0:01:20.394 *****
2025-10-08 12:17:29.959961 | controller | skipping: [instance]
2025-10-08 12:17:29.960129 | controller |
2025-10-08 12:17:29.960284 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 12:17:29.960425 | controller | Wednesday 08 October 2025  12:17:29 +0000 (0:00:00.038)       0:01:20.432 *****
2025-10-08 12:17:30.272564 | controller | ok: [instance]
2025-10-08 12:17:31.945527 | controller |
2025-10-08 12:17:31.945558 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 12:17:31.945572 | controller | Wednesday 08 October 2025  12:17:30 +0000 (0:00:00.312)       0:01:20.744 *****
2025-10-08 12:17:31.945583 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-10-08 12:17:32.397143 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-10-08 12:17:32.397175 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-10-08 12:17:32.397183 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-10-08 12:17:32.397189 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-10-08 12:17:32.397195 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-10-08 12:17:32.397201 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-10-08 12:17:32.397206 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-10-08 12:17:32.397212 | controller |
2025-10-08 12:17:32.397217 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 12:17:32.397223 | controller | Wednesday 08 October 2025  12:17:31 +0000 (0:00:01.672)       0:01:22.417 *****
2025-10-08 12:17:32.397238 | controller | changed: [instance]
2025-10-08 12:17:32.648657 | controller |
2025-10-08 12:17:32.648693 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 12:17:32.648707 | controller | Wednesday 08 October 2025  12:17:32 +0000 (0:00:00.452)       0:01:22.869 *****
2025-10-08 12:17:32.648739 | controller | changed: [instance]
2025-10-08 12:17:32.648762 | controller |
2025-10-08 12:17:32.649762 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:17:32.649813 | controller | instance                   : ok=35   changed=17   unreachable=0    failed=0    skipped=50   rescued=0    ignored=0
2025-10-08 12:17:32.649822 | controller |
2025-10-08 12:17:32.649828 | controller | Wednesday 08 October 2025  12:17:32 +0000 (0:00:00.251)       0:01:23.121 *****
2025-10-08 12:17:32.649834 | controller | ===============================================================================
2025-10-08 12:17:32.649839 | controller | test_deps : Install selinux python libs -------------------------------- 55.23s
2025-10-08 12:17:32.649844 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.01s
2025-10-08 12:17:32.649850 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.67s
2025-10-08 12:17:32.649855 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.27s
2025-10-08 12:17:32.649862 | controller | test_deps : Install python yaml libs ------------------------------------ 1.21s
2025-10-08 12:17:32.649882 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.19s
2025-10-08 12:17:32.651205 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.15s
2025-10-08 12:17:32.721785 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.87s
2025-10-08 12:17:32.721829 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 12:17:32.721838 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.85s
2025-10-08 12:17:32.721844 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-10-08 12:17:32.721849 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s
2025-10-08 12:17:32.721855 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.71s
2025-10-08 12:17:32.721860 | controller | repo_setup : Ensure directories are present ----------------------------- 0.58s
2025-10-08 12:17:32.721865 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-08 12:17:32.721870 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2025-10-08 12:17:32.721876 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-10-08 12:17:32.721881 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.54s
2025-10-08 12:17:32.721887 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.47s
2025-10-08 12:17:32.721893 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.45s
2025-10-08 12:17:32.721904 | controller | INFO     Running default > converge
2025-10-08 12:17:33.166828 | controller |
2025-10-08 12:17:33.167035 | controller | PLAY [Converge] ****************************************************************
2025-10-08 12:17:33.167183 | controller |
2025-10-08 12:17:33.167333 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:17:33.167495 | controller | Wednesday 08 October 2025  12:17:33 +0000 (0:00:00.017)       0:00:00.017 *****
2025-10-08 12:17:33.973229 | controller | ok: [instance]
2025-10-08 12:17:34.645893 | controller |
2025-10-08 12:17:34.645926 | controller | TASK [build_containers : Ensure directories are present] ***********************
2025-10-08 12:17:34.645934 | controller | Wednesday 08 October 2025  12:17:33 +0000 (0:00:00.806)       0:00:00.823 *****
2025-10-08 12:17:34.645944 | controller | ok: [instance] => (item=tmp)
2025-10-08 12:17:34.674266 | controller | ok: [instance] => (item=artifacts)
2025-10-08 12:17:34.674298 | controller | ok: [instance] => (item=logs)
2025-10-08 12:17:34.674305 | controller |
2025-10-08 12:17:34.674311 | controller | TASK [Install podman] **********************************************************
2025-10-08 12:17:34.674327 | controller | Wednesday 08 October 2025  12:17:34 +0000 (0:00:00.672)       0:00:01.495 *****
2025-10-08 12:17:34.674337 | controller |
2025-10-08 12:18:30.348417 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-10-08 12:18:30.348455 | controller | Wednesday 08 October 2025  12:17:34 +0000 (0:00:00.028)       0:00:01.524 *****
2025-10-08 12:18:30.348468 | controller | ok: [instance]
2025-10-08 12:18:30.688478 | controller |
2025-10-08 12:18:30.688513 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-10-08 12:18:30.688522 | controller | Wednesday 08 October 2025  12:18:30 +0000 (0:00:55.673)       0:00:57.198 *****
2025-10-08 12:18:30.688532 | controller | changed: [instance]
2025-10-08 12:18:30.710167 | controller |
2025-10-08 12:18:30.710193 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-10-08 12:18:30.710200 | controller | Wednesday 08 October 2025  12:18:30 +0000 (0:00:00.340)       0:00:57.538 *****
2025-10-08 12:18:30.710209 | controller | skipping: [instance]
2025-10-08 12:18:30.728480 | controller |
2025-10-08 12:18:30.728506 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-10-08 12:18:30.728513 | controller | Wednesday 08 October 2025  12:18:30 +0000 (0:00:00.021)       0:00:57.560 *****
2025-10-08 12:18:30.728522 | controller | skipping: [instance]
2025-10-08 12:18:30.752694 | controller |
2025-10-08 12:18:30.752732 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-10-08 12:18:30.752741 | controller | Wednesday 08 October 2025  12:18:30 +0000 (0:00:00.018)       0:00:57.578 *****
2025-10-08 12:18:30.752757 | controller | skipping: [instance]
2025-10-08 12:18:32.469199 | controller |
2025-10-08 12:18:32.469235 | controller | TASK [build_containers : Install required packages] ****************************
2025-10-08 12:18:32.469244 | controller | Wednesday 08 October 2025  12:18:30 +0000 (0:00:00.023)       0:00:57.602 *****
2025-10-08 12:18:32.469254 | controller | changed: [instance]
2025-10-08 12:18:32.487545 | controller |
2025-10-08 12:18:32.487576 | controller | TASK [build_containers : Get tcib from repository] *****************************
2025-10-08 12:18:32.487584 | controller | Wednesday 08 October 2025  12:18:32 +0000 (0:00:01.706)       0:00:59.309 *****
2025-10-08 12:18:32.487594 | controller | skipping: [instance]
2025-10-08 12:18:32.487652 | controller |
2025-10-08 12:18:32.487666 | controller | TASK [build_containers : Install tcib package] *********************************
2025-10-08 12:18:32.487832 | controller | Wednesday 08 October 2025  12:18:32 +0000 (0:00:00.028)       0:00:59.338 *****
2025-10-08 12:18:32.505478 | controller | skipping: [instance]
2025-10-08 12:18:32.506112 | controller |
2025-10-08 12:18:39.415338 | controller | TASK [build_containers : Install tcib from RPM] ********************************
2025-10-08 12:18:39.415370 | controller | Wednesday 08 October 2025  12:18:32 +0000 (0:00:00.017)       0:00:59.355 *****
2025-10-08 12:18:39.415383 | controller | changed: [instance]
2025-10-08 12:18:39.995711 | controller |
2025-10-08 12:18:39.995747 | controller | TASK [build_containers : Generate container_build.sh script] *******************
2025-10-08 12:18:39.995756 | controller | Wednesday 08 October 2025  12:18:39 +0000 (0:00:06.906)       0:01:06.261 *****
2025-10-08 12:18:39.995766 | controller | changed: [instance]
2025-10-08 12:18:39.996093 | controller |
2025-10-08 12:18:39.996111 | controller | TASK [build_containers : Run container_build.sh script] ************************
2025-10-08 12:21:19.620537 | controller | Wednesday 08 October 2025  12:18:39 +0000 (0:00:00.584)       0:01:06.846 *****
2025-10-08 12:21:19.620578 | controller | changed: [instance]
2025-10-08 12:21:19.636492 | controller |
2025-10-08 12:21:19.636522 | controller | TASK [build_containers : Run tcib hotfix command] ******************************
2025-10-08 12:21:19.636530 | controller | Wednesday 08 October 2025  12:21:19 +0000 (0:02:39.624)       0:03:46.470 *****
2025-10-08 12:21:19.636540 | controller | skipping: [instance]
2025-10-08 12:21:19.653337 | controller |
2025-10-08 12:21:19.653358 | controller | TASK [build_containers : Check for authfile] ***********************************
2025-10-08 12:21:19.653365 | controller | Wednesday 08 October 2025  12:21:19 +0000 (0:00:00.015)       0:03:46.486 *****
2025-10-08 12:21:19.653374 | controller | skipping: [instance]
2025-10-08 12:21:19.669714 | controller |
2025-10-08 12:21:19.669744 | controller | TASK [build_containers : Make sure authfile exists] ****************************
2025-10-08 12:21:19.669752 | controller | Wednesday 08 October 2025  12:21:19 +0000 (0:00:00.017)       0:03:46.503 *****
2025-10-08 12:21:19.669761 | controller | skipping: [instance]
2025-10-08 12:21:19.669987 | controller |
2025-10-08 12:21:19.670005 | controller | TASK [build_containers : Retrieve built images] ********************************
2025-10-08 12:21:19.926763 | controller | Wednesday 08 October 2025  12:21:19 +0000 (0:00:00.016)       0:03:46.520 *****
2025-10-08 12:21:19.926882 | controller | ok: [instance]
2025-10-08 12:21:20.163143 | controller |
2025-10-08 12:21:20.163208 | controller | TASK [build_containers : Store built images to containers-built.log] ***********
2025-10-08 12:21:20.163218 | controller | Wednesday 08 October 2025  12:21:19 +0000 (0:00:00.256)       0:03:46.776 *****
2025-10-08 12:21:20.163231 | controller | changed: [instance]
2025-10-08 12:21:20.192540 | controller |
2025-10-08 12:21:20.192581 | controller | TASK [build_containers : Push image: {{ item }}] *******************************
2025-10-08 12:21:20.192589 | controller | Wednesday 08 October 2025  12:21:20 +0000 (0:00:00.236)       0:03:47.013 *****
2025-10-08 12:21:20.192601 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-keystone)
2025-10-08 12:21:20.219733 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-os)
2025-10-08 12:21:20.219761 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-base)
2025-10-08 12:21:20.219774 | controller | skipping: [instance]
2025-10-08 12:21:20.219781 | controller |
2025-10-08 12:21:20.219787 | controller | TASK [build_containers : Retag each image and push to registry: {{ item }}] ****
2025-10-08 12:21:20.219802 | controller | Wednesday 08 October 2025  12:21:20 +0000 (0:00:00.028)       0:03:47.042 *****
2025-10-08 12:21:20.219812 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-keystone)
2025-10-08 12:21:20.219831 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-os)
2025-10-08 12:21:20.220339 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-base)
2025-10-08 12:21:20.854095 | controller | skipping: [instance]
2025-10-08 12:21:20.854131 | controller |
2025-10-08 12:21:20.854138 | controller | TASK [build_containers : Cleanup tcib directories after container build] *******
2025-10-08 12:21:20.854145 | controller | Wednesday 08 October 2025  12:21:20 +0000 (0:00:00.027)       0:03:47.070 *****
2025-10-08 12:21:20.854154 | controller | changed: [instance] => (item=tmp)
2025-10-08 12:21:20.921929 | controller | changed: [instance] => (item=artifacts)
2025-10-08 12:21:20.921964 | controller | changed: [instance] => (item=logs)
2025-10-08 12:21:20.921972 | controller |
2025-10-08 12:21:20.921978 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:21:20.921984 | controller | instance                   : ok=11   changed=7    unreachable=0    failed=0    skipped=10   rescued=0    ignored=0
2025-10-08 12:21:20.921990 | controller |
2025-10-08 12:21:20.921995 | controller | Wednesday 08 October 2025  12:21:20 +0000 (0:00:00.631)       0:03:47.701 *****
2025-10-08 12:21:20.922001 | controller | ===============================================================================
2025-10-08 12:21:20.922006 | controller | build_containers : Run container_build.sh script ---------------------- 159.62s
2025-10-08 12:21:20.922012 | controller | podman : Ensure podman is installed ------------------------------------ 55.67s
2025-10-08 12:21:20.922017 | controller | build_containers : Install tcib from RPM -------------------------------- 6.91s
2025-10-08 12:21:20.922022 | controller | build_containers : Install required packages ---------------------------- 1.71s
2025-10-08 12:21:20.922027 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-10-08 12:21:20.922032 | controller | build_containers : Ensure directories are present ----------------------- 0.67s
2025-10-08 12:21:20.922038 | controller | build_containers : Cleanup tcib directories after container build ------- 0.63s
2025-10-08 12:21:20.922043 | controller | build_containers : Generate container_build.sh script ------------------- 0.58s
2025-10-08 12:21:20.922048 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.34s
2025-10-08 12:21:20.922053 | controller | build_containers : Retrieve built images -------------------------------- 0.26s
2025-10-08 12:21:20.922058 | controller | build_containers : Store built images to containers-built.log ----------- 0.24s
2025-10-08 12:21:20.922063 | controller | build_containers : Push image: {{ item }} ------------------------------- 0.03s
2025-10-08 12:21:20.922074 | controller | build_containers : Get tcib from repository ----------------------------- 0.03s
2025-10-08 12:21:20.922080 | controller | Install podman ---------------------------------------------------------- 0.03s
2025-10-08 12:21:20.922085 | controller | build_containers : Retag each image and push to registry: {{ item }} ---- 0.03s
2025-10-08 12:21:20.922090 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.02s
2025-10-08 12:21:20.922095 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.02s
2025-10-08 12:21:20.922100 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.02s
2025-10-08 12:21:20.922108 | controller | build_containers : Install tcib package --------------------------------- 0.02s
2025-10-08 12:21:20.922114 | controller | build_containers : Check for authfile ----------------------------------- 0.02s
2025-10-08 12:21:20.922124 | controller | INFO     Running default > cleanup
2025-10-08 12:21:20.922593 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 12:21:20.923238 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 12:21:21.178128 | controller | changed
2025-10-08 12:21:21.200616 | 
2025-10-08 12:21:21.200685 | PLAY RECAP
2025-10-08 12:21:21.200734 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:21:21.200758 | 
2025-10-08 12:21:21.285804 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:21:21.286670 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:21:21.865801 | 
2025-10-08 12:21:21.865935 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 12:21:21.888414 | 
2025-10-08 12:21:21.888537 | TASK [Filter out host if needed]
2025-10-08 12:21:21.897656 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 12:21:21.902430 | 
2025-10-08 12:21:21.902501 | TASK [Ensure file is present]
2025-10-08 12:21:22.215336 | controller | ok
2025-10-08 12:21:22.222113 | 
2025-10-08 12:21:22.222193 | TASK [Manage molecule report file]
2025-10-08 12:21:22.748316 | controller | changed
2025-10-08 12:21:22.754739 | 
2025-10-08 12:21:22.754831 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 12:21:22.966098 | controller | ok
2025-10-08 12:21:22.971516 | 
2025-10-08 12:21:22.971586 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 12:21:23.315198 | controller | changed
2025-10-08 12:21:23.334198 | 
2025-10-08 12:21:23.334307 | TASK [Copy ci-framework interesting files]
2025-10-08 12:21:23.526169 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory
2025-10-08 12:21:23.527693 | controller | cp: cannot stat '/home/zuul/ci-framework-data/artifacts': No such file or directory
2025-10-08 12:21:23.529112 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 12:21:23.859799 | controller | changed
2025-10-08 12:21:23.865213 | 
2025-10-08 12:21:23.865310 | TASK [Get SELinux listing]
2025-10-08 12:21:24.393059 | controller | changed
2025-10-08 12:21:24.399288 | 
2025-10-08 12:21:24.399376 | TASK [Generate log index]
2025-10-08 12:21:25.189415 | controller | changed
2025-10-08 12:21:25.194556 | 
2025-10-08 12:21:25.194647 | TASK [Get some env related data]
2025-10-08 12:21:25.856099 | controller | /home/zuul/.local/bin/ansible
2025-10-08 12:21:26.730375 | controller | changed
2025-10-08 12:21:26.735880 | 
2025-10-08 12:21:26.735967 | TASK [Generate list of logs to collect in home directory]
2025-10-08 12:21:27.096761 | controller | ok: All paths examined
2025-10-08 12:21:27.102088 | 
2025-10-08 12:21:27.102157 | LOOP [Copy logs from home directory]
2025-10-08 12:21:27.571253 | controller | changed:
2025-10-08 12:21:27.571387 | controller | {
2025-10-08 12:21:27.571417 | controller |   "atime": 1759925732.854749,
2025-10-08 12:21:27.571437 | controller |   "ctime": 1759925923.3277786,
2025-10-08 12:21:27.571455 | controller |   "dev": 64513,
2025-10-08 12:21:27.571474 | controller |   "gid": 1000,
2025-10-08 12:21:27.571491 | controller |   "gr_name": "zuul",
2025-10-08 12:21:27.571507 | controller |   "inode": 4449777,
2025-10-08 12:21:27.571522 | controller |   "isblk": false,
2025-10-08 12:21:27.571537 | controller |   "ischr": false,
2025-10-08 12:21:27.571552 | controller |   "isdir": false,
2025-10-08 12:21:27.571566 | controller |   "isfifo": false,
2025-10-08 12:21:27.571580 | controller |   "isgid": false,
2025-10-08 12:21:27.571593 | controller |   "islnk": false,
2025-10-08 12:21:27.571608 | controller |   "isreg": true,
2025-10-08 12:21:27.571624 | controller |   "issock": false,
2025-10-08 12:21:27.571640 | controller |   "isuid": false,
2025-10-08 12:21:27.571656 | controller |   "mode": "0644",
2025-10-08 12:21:27.571671 | controller |   "mtime": 1759925923.1747768,
2025-10-08 12:21:27.571826 | controller |   "nlink": 1,
2025-10-08 12:21:27.571853 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 12:21:27.571872 | controller |   "pw_name": "zuul",
2025-10-08 12:21:27.571889 | controller |   "rgrp": true,
2025-10-08 12:21:27.571944 | controller |   "roth": true,
2025-10-08 12:21:27.571977 | controller |   "rusr": true,
2025-10-08 12:21:27.571997 | controller |   "size": 10293,
2025-10-08 12:21:27.572027 | controller |   "uid": 1000,
2025-10-08 12:21:27.572047 | controller |   "wgrp": false,
2025-10-08 12:21:27.572064 | controller |   "woth": false,
2025-10-08 12:21:27.572080 | controller |   "wusr": true,
2025-10-08 12:21:27.572096 | controller |   "xgrp": false,
2025-10-08 12:21:27.572111 | controller |   "xoth": false,
2025-10-08 12:21:27.572126 | controller |   "xusr": false
2025-10-08 12:21:27.572141 | controller | }
2025-10-08 12:21:27.585075 | 
2025-10-08 12:21:27.585250 | TASK [Copy crio stats log file]
2025-10-08 12:21:27.602810 | controller | skipping: Conditional result was False
2025-10-08 12:21:27.610558 | 
2025-10-08 12:21:27.611340 | TASK [Get SELinux related data]
2025-10-08 12:21:27.863827 | controller | <no matches>
2025-10-08 12:21:28.142597 | controller | ERROR
2025-10-08 12:21:28.142735 | controller | {
2025-10-08 12:21:28.142766 | controller |   "delta": "0:00:00.016403",
2025-10-08 12:21:28.142790 | controller |   "end": "2025-10-08 12:21:27.866834",
2025-10-08 12:21:28.142810 | controller |   "msg": "non-zero return code",
2025-10-08 12:21:28.142827 | controller |   "rc": 1,
2025-10-08 12:21:28.142843 | controller |   "start": "2025-10-08 12:21:27.850431"
2025-10-08 12:21:28.142859 | controller | }
2025-10-08 12:21:28.142882 | controller | ERROR: Ignoring Errors
2025-10-08 12:21:28.148784 | 
2025-10-08 12:21:28.148852 | TASK [Create system configuration directory]
2025-10-08 12:21:28.407357 | controller | changed
2025-10-08 12:21:28.412821 | 
2025-10-08 12:21:28.412930 | TASK [Get some of the system configurations]
2025-10-08 12:21:28.938081 | controller | changed
2025-10-08 12:21:28.943431 | 
2025-10-08 12:21:28.943512 | TASK [Copy generated documentation if available]
2025-10-08 12:21:28.968060 | controller | skipping: Conditional result was False
2025-10-08 12:21:28.973890 | 
2025-10-08 12:21:28.973983 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 12:21:28.997343 | controller | skipping: Conditional result was False
2025-10-08 12:21:29.002588 | 
2025-10-08 12:21:29.002653 | TASK [Compress logs bigger than 2MB]
2025-10-08 12:21:29.530220 | controller | changed
2025-10-08 12:21:29.536088 | 
2025-10-08 12:21:29.536171 | TASK [Copy files from workspace on node]
2025-10-08 12:21:29.556810 | controller | ok
2025-10-08 12:21:29.586753 | 
2025-10-08 12:21:29.586859 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:21:29.611969 | controller | skipping: Conditional result was False
2025-10-08 12:21:29.619226 | 
2025-10-08 12:21:29.619316 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:21:29.649577 | controller | ok
2025-10-08 12:21:29.654967 | 
2025-10-08 12:21:29.655063 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:21:29.899505 | controller -> localhost | ok: "/var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/logs"
2025-10-08 12:21:29.899793 | controller -> localhost | changed: All items complete
2025-10-08 12:21:29.899824 | 
2025-10-08 12:21:30.133286 | controller -> localhost | changed: "/var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/artifacts"
2025-10-08 12:21:30.329102 | controller -> localhost | changed: "/var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/docs"
2025-10-08 12:21:30.348475 | 
2025-10-08 12:21:30.348632 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:21:30.972589 | controller | changed:
2025-10-08 12:21:30.972862 | controller | .d..t...... ./
2025-10-08 12:21:30.972903 | controller | >f+++++++++ README.html
2025-10-08 12:21:30.972951 | controller | >f+++++++++ ansible-execution.log
2025-10-08 12:21:30.972980 | controller | >f+++++++++ ansible.log
2025-10-08 12:21:30.973004 | controller | >f+++++++++ dmesg.log
2025-10-08 12:21:30.973048 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 12:21:30.973069 | controller | >f+++++++++ python.log
2025-10-08 12:21:30.973090 | controller | >f+++++++++ registries.conf
2025-10-08 12:21:30.973115 | controller | >f+++++++++ report.html
2025-10-08 12:21:30.973135 | controller | >f+++++++++ selinux-denials.log
2025-10-08 12:21:30.973153 | controller | >f+++++++++ selinux-listing.log
2025-10-08 12:21:30.973170 | controller | cd+++++++++ ci-framework-data/
2025-10-08 12:21:30.973188 | controller | cd+++++++++ registries.conf.d/
2025-10-08 12:21:30.973205 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 12:21:30.973223 | controller | cd+++++++++ system-config/
2025-10-08 12:21:30.973241 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 12:21:30.973257 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 12:21:30.973275 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 12:21:31.423360 | controller | changed: .d..t...... ./
2025-10-08 12:21:31.824787 | controller | changed: .d..t...... ./
2025-10-08 12:21:31.839945 | 
2025-10-08 12:21:31.840064 | TASK [Return artifact to Zuul]
2025-10-08 12:21:31.870603 | controller | ok
2025-10-08 12:21:31.893967 | 
2025-10-08 12:21:31.894081 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 12:21:31.894186 | 
2025-10-08 12:21:31.894214 | PLAY RECAP
2025-10-08 12:21:31.894251 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 12:21:31.894273 | 
2025-10-08 12:21:31.988508 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:21:31.989314 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:21:32.558046 | 
2025-10-08 12:21:32.558149 | PLAY [all]
2025-10-08 12:21:32.578254 | 
2025-10-08 12:21:32.578385 | TASK [include_role : fetch-output]
2025-10-08 12:21:32.620207 | controller | ok
2025-10-08 12:21:32.636907 | 
2025-10-08 12:21:32.637025 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:21:32.680620 | controller | skipping: Conditional result was False
2025-10-08 12:21:32.686480 | 
2025-10-08 12:21:32.686566 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:21:32.715041 | controller | ok
2025-10-08 12:21:32.720491 | 
2025-10-08 12:21:32.720565 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:21:33.089801 | controller -> localhost | ok: "/var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/logs"
2025-10-08 12:21:33.301388 | controller -> localhost | ok: "/var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/artifacts"
2025-10-08 12:21:33.494696 | controller -> localhost | ok: "/var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/docs"
2025-10-08 12:21:33.504875 | 
2025-10-08 12:21:33.505026 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:21:34.084079 | controller | ok
2025-10-08 12:21:34.084283 | controller | ok: All items complete
2025-10-08 12:21:34.084314 | 
2025-10-08 12:21:34.531187 | controller | ok
2025-10-08 12:21:34.950552 | controller | ok
2025-10-08 12:21:34.970131 | 
2025-10-08 12:21:34.970247 | TASK [include_role : fetch-output-openshift]
2025-10-08 12:21:34.994096 | controller | skipping: Conditional result was False
2025-10-08 12:21:35.000760 | 
2025-10-08 12:21:35.000848 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 12:21:35.517648 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014449
2025-10-08 12:21:35.800870 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013386
2025-10-08 12:21:35.831067 | 
2025-10-08 12:21:35.831162 | PLAY [all]
2025-10-08 12:21:35.846029 | 
2025-10-08 12:21:35.846131 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 12:21:36.374158 | controller | changed
2025-10-08 12:21:36.404882 | 
2025-10-08 12:21:36.405000 | PLAY RECAP
2025-10-08 12:21:36.405062 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 12:21:36.405088 | 
2025-10-08 12:21:36.506344 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:21:36.507234 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 12:21:37.101145 | 
2025-10-08 12:21:37.101249 | PLAY [localhost]
2025-10-08 12:21:37.119130 | 
2025-10-08 12:21:37.119224 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 12:21:37.467120 | localhost | changed
2025-10-08 12:21:37.472464 | 
2025-10-08 12:21:37.472546 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 12:21:37.500815 | localhost | ok
2025-10-08 12:21:37.510088 | 
2025-10-08 12:21:37.510185 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 12:21:37.838158 | localhost | changed
2025-10-08 12:21:37.844865 | 
2025-10-08 12:21:37.844984 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 12:21:38.486543 | localhost | changed
2025-10-08 12:21:38.492553 | 
2025-10-08 12:21:38.492641 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 12:21:39.082179 | localhost | Identity added: /var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/tmp/ansible.8zufmzle (/var/lib/zuul/builds/471ee0e4a0034caaacc1a1fcdf026293/work/tmp/ansible.8zufmzle)
2025-10-08 12:21:39.082364 | localhost | ok: Runtime: 0:00:00.009539
2025-10-08 12:21:39.086845 | 
2025-10-08 12:21:39.086938 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 12:21:39.490792 | localhost | ok: Runtime: 0:00:00.005686
2025-10-08 12:21:39.496862 | 
2025-10-08 12:21:39.496958 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 12:21:39.550829 | localhost | changed
2025-10-08 12:21:39.555116 | 
2025-10-08 12:21:39.555194 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 12:21:39.987704 | localhost | changed
2025-10-08 12:21:40.013622 | 
2025-10-08 12:21:40.013701 | PLAY [localhost]
2025-10-08 12:21:40.039443 | 
2025-10-08 12:21:40.039511 | TASK [Generate bulk log download script]
2025-10-08 12:21:40.057110 | localhost | ok
2025-10-08 12:21:40.069253 | 
2025-10-08 12:21:40.069331 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 12:21:40.107635 | localhost | ok: All assertions passed
2025-10-08 12:21:40.114772 | 
2025-10-08 12:21:40.114841 | TASK [local-log-download : Create download script]
2025-10-08 12:21:40.746048 | localhost -> localhost | changed
2025-10-08 12:21:40.756088 | 
2025-10-08 12:21:40.756170 | TASK [Register quick-download link]
2025-10-08 12:21:40.829480 | localhost | ok
2025-10-08 12:21:40.860839 | 
2025-10-08 12:21:40.860953 | PLAY [logserver.rdoproject.org]
2025-10-08 12:21:40.885350 | 
2025-10-08 12:21:40.885436 | TASK [Set zuul-log-path fact]
2025-10-08 12:21:40.925184 | logserver.rdoproject.org | ok
2025-10-08 12:21:40.934642 | 
2025-10-08 12:21:40.934725 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:21:40.983164 | logserver.rdoproject.org | ok
2025-10-08 12:21:40.989336 | 
2025-10-08 12:21:40.989430 | TASK [upload-logs : Create log directories]
2025-10-08 12:21:42.871830 | logserver.rdoproject.org | changed
2025-10-08 12:21:42.875111 | 
2025-10-08 12:21:42.875179 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 12:21:43.144167 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.026254
2025-10-08 12:21:43.149003 | 
2025-10-08 12:21:43.149089 | TASK [upload-logs : Upload logs to log server]
2025-10-08 12:21:45.393326 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 12:21:45.396439 | 
2025-10-08 12:21:45.396511 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 12:21:45.435657 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:21:45.445671 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:21:45.454997 | 
2025-10-08 12:21:45.455135 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 12:21:45.491527 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:21:45.491751 | 
2025-10-08 12:21:45.496786 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:21:45.502840 | 
2025-10-08 12:21:45.502950 | LOOP [upload-logs : Upload console log and json output]