2025-10-14 06:55:36.599678 | Job console starting...
2025-10-14 06:55:36.864166 | Updating repositories
2025-10-14 06:55:36.890900 | Preparing job workspace
2025-10-14 06:55:45.333441 | Running Ansible setup...
2025-10-14 06:55:48.331451 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-14 06:55:48.890306 | 
2025-10-14 06:55:48.890394 | PLAY [localhost]
2025-10-14 06:55:48.898993 | 
2025-10-14 06:55:48.899066 | TASK [Gathering Facts]
2025-10-14 06:55:49.769981 | localhost | ok
2025-10-14 06:55:49.785089 | 
2025-10-14 06:55:49.785211 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-14 06:55:50.135190 | localhost -> localhost | changed
2025-10-14 06:55:50.142082 | 
2025-10-14 06:55:50.142185 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-14 06:55:50.895777 | localhost -> localhost | changed
2025-10-14 06:55:50.904903 | 
2025-10-14 06:55:50.905017 | TASK [Setup log path fact]
2025-10-14 06:55:50.921905 | localhost | ok
2025-10-14 06:55:50.932845 | 
2025-10-14 06:55:50.932937 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-14 06:55:50.960347 | localhost | ok
2025-10-14 06:55:50.967721 | 
2025-10-14 06:55:50.967800 | TASK [emit-job-header : Print job information]
2025-10-14 06:55:50.994860 | # Job Information
2025-10-14 06:55:50.995008 | Ansible Version: 2.15.12
2025-10-14 06:55:50.995040 | Job: cifmw-molecule-build_containers
2025-10-14 06:55:50.995064 | Pipeline: github-check
2025-10-14 06:55:50.995085 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-14 06:55:50.995104 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237
2025-10-14 06:55:50.995133 | Log URL (when completed): https://logserver.rdoproject.org/003/rdoproject.org/003e3f39e55e4984a750cdb408071976/
2025-10-14 06:55:50.995155 | Event ID: c940b7c0-a8c9-11f0-80da-b48bf6033ab5
2025-10-14 06:55:50.998627 | 
2025-10-14 06:55:50.998693 | LOOP [emit-job-header : Print node information]
2025-10-14 06:55:51.089717 | localhost | ok:
2025-10-14 06:55:51.089904 | localhost | # Node Information
2025-10-14 06:55:51.089964 | localhost | Inventory Hostname: controller
2025-10-14 06:55:51.089996 | localhost | Hostname: np0005486865
2025-10-14 06:55:51.090019 | localhost | Username: zuul
2025-10-14 06:55:51.090041 | localhost | Distro: CentOS 9
2025-10-14 06:55:51.090059 | localhost | Provider: ibm-bm4-nodepool
2025-10-14 06:55:51.090076 | localhost | Region: regionOne
2025-10-14 06:55:51.090094 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-14 06:55:51.090111 | localhost | Product Name: OpenStack Compute
2025-10-14 06:55:51.090144 | localhost | Interface IP: 192.168.26.61
2025-10-14 06:55:51.111889 | 
2025-10-14 06:55:51.112002 | PLAY [all]
2025-10-14 06:55:51.118552 | 
2025-10-14 06:55:51.118620 | TASK [Gather network facts]
2025-10-14 06:55:51.500454 | controller | ok
2025-10-14 06:55:51.514874 | 
2025-10-14 06:55:51.514986 | TASK [include_role : start-zuul-console]
2025-10-14 06:55:51.532279 | controller | ok
2025-10-14 06:55:51.544262 | 
2025-10-14 06:55:51.544334 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-14 06:55:51.869957 | controller | ok
2025-10-14 06:55:51.878493 | 
2025-10-14 06:55:51.878573 | TASK [include_role : add-build-sshkey]
2025-10-14 06:55:51.909272 | controller | ok
2025-10-14 06:55:51.929193 | 
2025-10-14 06:55:51.929295 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-14 06:55:52.166945 | controller -> localhost | ok
2025-10-14 06:55:52.173069 | 
2025-10-14 06:55:52.173160 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-14 06:55:52.190369 | controller | ok
2025-10-14 06:55:52.207711 | controller | included: /var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-14 06:55:52.213467 | 
2025-10-14 06:55:52.213535 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-14 06:55:52.806311 | controller -> localhost | Generating public/private rsa key pair.
2025-10-14 06:55:52.806544 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/003e3f39e55e4984a750cdb408071976_id_rsa.
2025-10-14 06:55:52.806606 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/003e3f39e55e4984a750cdb408071976_id_rsa.pub.
2025-10-14 06:55:52.806636 | controller -> localhost | The key fingerprint is:
2025-10-14 06:55:52.806658 | controller -> localhost | SHA256:BSeNgRgcKEq6bPuDcUPptDLwqe3NYSPrk1aaSlvmkuk zuul-build-sshkey
2025-10-14 06:55:52.806788 | controller -> localhost | The key's randomart image is:
2025-10-14 06:55:52.806816 | controller -> localhost | +---[RSA 3072]----+
2025-10-14 06:55:52.806860 | controller -> localhost | |   oo+ .++.      |
2025-10-14 06:55:52.806882 | controller -> localhost | |... o . .+.      |
2025-10-14 06:55:52.806902 | controller -> localhost | |+.  .     .      |
2025-10-14 06:55:52.806968 | controller -> localhost | |+  +     .       |
2025-10-14 06:55:52.806992 | controller -> localhost | |oo+..   S        |
2025-10-14 06:55:52.807037 | controller -> localhost | |.*o=.            |
2025-10-14 06:55:52.807058 | controller -> localhost | |.+@B=            |
2025-10-14 06:55:52.807122 | controller -> localhost | |o*%B o           |
2025-10-14 06:55:52.807166 | controller -> localhost | |oE*++            |
2025-10-14 06:55:52.807188 | controller -> localhost | +----[SHA256]-----+
2025-10-14 06:55:52.807237 | controller -> localhost | ok: Runtime: 0:00:00.248254
2025-10-14 06:55:52.813603 | 
2025-10-14 06:55:52.813675 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-14 06:55:52.832840 | controller | ok
2025-10-14 06:55:52.853400 | controller | included: /var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-14 06:55:52.862396 | 
2025-10-14 06:55:52.862479 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-14 06:55:52.875795 | controller | skipping: Conditional result was False
2025-10-14 06:55:52.881584 | 
2025-10-14 06:55:52.881660 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-14 06:55:53.255335 | controller | changed
2025-10-14 06:55:53.260086 | 
2025-10-14 06:55:53.260165 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-14 06:55:53.451279 | controller | ok
2025-10-14 06:55:53.456018 | 
2025-10-14 06:55:53.456085 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-14 06:55:54.031740 | controller | changed
2025-10-14 06:55:54.036604 | 
2025-10-14 06:55:54.036677 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-14 06:55:54.612400 | controller | changed
2025-10-14 06:55:54.618057 | 
2025-10-14 06:55:54.618149 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-14 06:55:54.632202 | controller | skipping: Conditional result was False
2025-10-14 06:55:54.637994 | 
2025-10-14 06:55:54.638065 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-14 06:55:54.975610 | controller -> localhost | changed
2025-10-14 06:55:54.985587 | 
2025-10-14 06:55:54.985676 | TASK [add-build-sshkey : Add back temp key]
2025-10-14 06:55:55.225299 | controller -> localhost | Identity added: /var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/003e3f39e55e4984a750cdb408071976_id_rsa (zuul-build-sshkey)
2025-10-14 06:55:55.225515 | controller -> localhost | ok: Runtime: 0:00:00.007351
2025-10-14 06:55:55.231978 | 
2025-10-14 06:55:55.232050 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-14 06:55:55.525085 | controller | ok
2025-10-14 06:55:55.530027 | 
2025-10-14 06:55:55.530208 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-14 06:55:55.553289 | controller | skipping: Conditional result was False
2025-10-14 06:55:55.562763 | 
2025-10-14 06:55:55.562840 | TASK [include_role : validate-host]
2025-10-14 06:55:55.580527 | controller | ok
2025-10-14 06:55:55.603866 | 
2025-10-14 06:55:55.603975 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-14 06:55:55.631453 | controller | ok
2025-10-14 06:55:55.636402 | 
2025-10-14 06:55:55.636484 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-14 06:55:55.857792 | controller -> localhost | ok
2025-10-14 06:55:55.864003 | 
2025-10-14 06:55:55.864083 | TASK [validate-host : Collect information about the host]
2025-10-14 06:55:56.491527 | controller | ok
2025-10-14 06:55:56.500111 | 
2025-10-14 06:55:56.500191 | TASK [validate-host : Sanitize hostname]
2025-10-14 06:55:56.578362 | controller | ok
2025-10-14 06:55:56.584397 | 
2025-10-14 06:55:56.584484 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-14 06:55:57.032782 | controller -> localhost | changed
2025-10-14 06:55:57.038130 | 
2025-10-14 06:55:57.038218 | TASK [validate-host : Collect information about zuul worker]
2025-10-14 06:55:57.359402 | controller | ok
2025-10-14 06:55:57.364022 | 
2025-10-14 06:55:57.364124 | TASK [validate-host : Write out all zuul information for each host]
2025-10-14 06:55:57.773377 | controller -> localhost | changed
2025-10-14 06:55:57.783009 | 
2025-10-14 06:55:57.783090 | TASK [include_role : prepare-workspace-openshift]
2025-10-14 06:55:57.795977 | controller | skipping: Conditional result was False
2025-10-14 06:55:57.802250 | 
2025-10-14 06:55:57.802320 | TASK [include_role : remove-zuul-sshkey]
2025-10-14 06:55:57.815225 | controller | skipping: Conditional result was False
2025-10-14 06:55:57.820213 | 
2025-10-14 06:55:57.820292 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-14 06:55:58.016778 | controller | ok: "logs"
2025-10-14 06:55:58.017084 | controller | ok: All items complete
2025-10-14 06:55:58.017122 | 
2025-10-14 06:55:58.187212 | controller | ok: "artifacts"
2025-10-14 06:55:58.357396 | controller | ok: "docs"
2025-10-14 06:55:58.367951 | 
2025-10-14 06:55:58.368046 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-14 06:55:58.592634 | controller | changed: "logs"
2025-10-14 06:55:58.777661 | controller | changed: "artifacts"
2025-10-14 06:55:58.960250 | controller | changed: "docs"
2025-10-14 06:55:58.996125 | 
2025-10-14 06:55:58.996208 | PLAY RECAP
2025-10-14 06:55:58.996251 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-14 06:55:58.996281 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-14 06:55:58.996302 | 
2025-10-14 06:55:59.081441 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-14 06:55:59.082685 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-14 06:55:59.716988 | 
2025-10-14 06:55:59.717104 | PLAY [all]
2025-10-14 06:55:59.736818 | 
2025-10-14 06:55:59.736899 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-14 06:55:59.786035 | controller | ok
2025-10-14 06:55:59.791175 | 
2025-10-14 06:55:59.791261 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-14 06:56:00.144958 | controller | changed
2025-10-14 06:56:00.150335 | 
2025-10-14 06:56:00.150446 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-14 06:56:01.016539 | controller | changed
2025-10-14 06:56:01.026288 | 
2025-10-14 06:56:01.026674 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-14 06:56:01.421545 | controller | changed:
2025-10-14 06:56:01.421708 | controller | {
2025-10-14 06:56:01.421738 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-14 06:56:01.421766 | controller | }
2025-10-14 06:56:01.633550 | controller | changed:
2025-10-14 06:56:01.633663 | controller | {
2025-10-14 06:56:01.633693 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-14 06:56:01.633715 | controller | }
2025-10-14 06:56:01.866515 | controller | changed:
2025-10-14 06:56:01.866610 | controller | {
2025-10-14 06:56:01.866639 | 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-14 06:56:01.866661 | controller | }
2025-10-14 06:56:02.101553 | controller | changed:
2025-10-14 06:56:02.101645 | controller | {
2025-10-14 06:56:02.101673 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-14 06:56:02.101695 | controller | }
2025-10-14 06:56:02.320143 | controller | changed:
2025-10-14 06:56:02.320250 | controller | {
2025-10-14 06:56:02.320277 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-14 06:56:02.320298 | controller | }
2025-10-14 06:56:02.528716 | controller | changed:
2025-10-14 06:56:02.528808 | controller | {
2025-10-14 06:56:02.528838 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-14 06:56:02.528860 | controller | }
2025-10-14 06:56:02.749571 | controller | changed:
2025-10-14 06:56:02.749747 | controller | {
2025-10-14 06:56:02.749783 | 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-14 06:56:02.749807 | controller | }
2025-10-14 06:56:02.972206 | controller | changed:
2025-10-14 06:56:02.972303 | controller | {
2025-10-14 06:56:02.972332 | 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-14 06:56:02.972355 | controller | }
2025-10-14 06:56:03.195106 | controller | changed:
2025-10-14 06:56:03.195208 | controller | {
2025-10-14 06:56:03.195237 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-14 06:56:03.195258 | controller | }
2025-10-14 06:56:03.411667 | controller | changed:
2025-10-14 06:56:03.412180 | controller | {
2025-10-14 06:56:03.412228 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-14 06:56:03.412256 | controller | }
2025-10-14 06:56:03.612099 | controller | changed:
2025-10-14 06:56:03.612204 | controller | {
2025-10-14 06:56:03.612236 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-14 06:56:03.612258 | controller | }
2025-10-14 06:56:03.842370 | controller | changed:
2025-10-14 06:56:03.842453 | controller | {
2025-10-14 06:56:03.842481 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-14 06:56:03.842504 | controller | }
2025-10-14 06:56:04.061407 | controller | changed:
2025-10-14 06:56:04.061504 | controller | {
2025-10-14 06:56:04.061533 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-14 06:56:04.061554 | controller | }
2025-10-14 06:56:04.279309 | controller | changed:
2025-10-14 06:56:04.279410 | controller | {
2025-10-14 06:56:04.279440 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-14 06:56:04.279462 | controller | }
2025-10-14 06:56:04.490419 | controller | changed:
2025-10-14 06:56:04.490518 | controller | {
2025-10-14 06:56:04.490546 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-14 06:56:04.490568 | controller | }
2025-10-14 06:56:04.691947 | controller | changed:
2025-10-14 06:56:04.692042 | controller | {
2025-10-14 06:56:04.692070 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-14 06:56:04.692091 | controller | }
2025-10-14 06:56:04.902695 | controller | changed:
2025-10-14 06:56:04.902783 | controller | {
2025-10-14 06:56:04.902812 | 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-14 06:56:04.902834 | controller | }
2025-10-14 06:56:05.127395 | controller | changed:
2025-10-14 06:56:05.127483 | controller | {
2025-10-14 06:56:05.127511 | 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-14 06:56:05.127541 | controller | }
2025-10-14 06:56:05.351990 | controller | changed:
2025-10-14 06:56:05.352122 | controller | {
2025-10-14 06:56:05.352166 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-14 06:56:05.352201 | controller | }
2025-10-14 06:56:05.593375 | controller | changed:
2025-10-14 06:56:05.593634 | controller | {
2025-10-14 06:56:05.593668 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-14 06:56:05.593692 | controller | }
2025-10-14 06:56:05.808396 | controller | changed:
2025-10-14 06:56:05.808474 | controller | {
2025-10-14 06:56:05.808504 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-14 06:56:05.808526 | controller | }
2025-10-14 06:56:06.022035 | controller | changed:
2025-10-14 06:56:06.022127 | controller | {
2025-10-14 06:56:06.022179 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-14 06:56:06.022203 | controller | }
2025-10-14 06:56:06.229093 | controller | changed:
2025-10-14 06:56:06.229184 | controller | {
2025-10-14 06:56:06.229216 | 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-14 06:56:06.229238 | controller | }
2025-10-14 06:56:06.441899 | controller | changed:
2025-10-14 06:56:06.442001 | controller | {
2025-10-14 06:56:06.442029 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-14 06:56:06.442049 | controller | }
2025-10-14 06:56:06.658928 | controller | changed:
2025-10-14 06:56:06.659023 | controller | {
2025-10-14 06:56:06.659051 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-14 06:56:06.659078 | controller | }
2025-10-14 06:56:06.859432 | controller | changed:
2025-10-14 06:56:06.859513 | controller | {
2025-10-14 06:56:06.859540 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-14 06:56:06.859560 | controller | }
2025-10-14 06:56:06.878838 | 
2025-10-14 06:56:06.878954 | TASK [Set timezone to UTC]
2025-10-14 06:56:07.267204 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-14 06:56:07.273486 | 
2025-10-14 06:56:07.273575 | TASK [Create nodepool directory]
2025-10-14 06:56:07.486147 | controller | changed
2025-10-14 06:56:07.493822 | 
2025-10-14 06:56:07.493902 | TASK [Create nodepool sub_nodes file]
2025-10-14 06:56:08.004336 | controller | changed
2025-10-14 06:56:08.009868 | 
2025-10-14 06:56:08.009967 | TASK [Create nodepool sub_nodes_private file]
2025-10-14 06:56:08.547932 | controller | changed
2025-10-14 06:56:08.553517 | 
2025-10-14 06:56:08.553607 | LOOP [Populate nodepool sub_nodes file]
2025-10-14 06:56:08.585379 | 
2025-10-14 06:56:08.585604 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-14 06:56:08.617683 | 
2025-10-14 06:56:08.617840 | TASK [Create nodepool primary file]
2025-10-14 06:56:08.641411 | controller | skipping: Conditional result was False
2025-10-14 06:56:08.647233 | 
2025-10-14 06:56:08.647337 | TASK [Create nodepool node_private for this node]
2025-10-14 06:56:09.203741 | controller | changed
2025-10-14 06:56:09.211923 | 
2025-10-14 06:56:09.212038 | LOOP [Copy ssh keys to nodepool directory]
2025-10-14 06:56:09.600138 | controller | ok: Item: id_rsa Runtime: 0:00:00.008200
2025-10-14 06:56:09.600321 | 
2025-10-14 06:56:09.766363 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004872
2025-10-14 06:56:09.782629 | 
2025-10-14 06:56:09.782724 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-14 06:56:10.455052 | controller | changed
2025-10-14 06:56:10.466218 | 
2025-10-14 06:56:10.466310 | TASK [Validate sudoers config after edits]
2025-10-14 06:56:10.767358 | controller | /etc/sudoers: parsed OK
2025-10-14 06:56:10.767413 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-14 06:56:10.767422 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-14 06:56:10.767428 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-14 06:56:11.023397 | controller | ok: Runtime: 0:00:00.005402
2025-10-14 06:56:11.029147 | 
2025-10-14 06:56:11.029224 | TASK [Show the environment passed in to job shell scripts]
2025-10-14 06:56:11.240587 | controller | SHELL=/bin/bash
2025-10-14 06:56:11.240649 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-14 06:56:11.240670 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-14 06:56:11.240727 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/04/3404/9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8^openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/2f8c81ac66bb5746ceb3bd299e433353061ca719
2025-10-14 06:56:11.240742 | controller | PWD=/home/zuul
2025-10-14 06:56:11.240749 | controller | ZUUL_PIPELINE=github-check
2025-10-14 06:56:11.240756 | controller | LOGNAME=zuul
2025-10-14 06:56:11.240763 | controller | XDG_SESSION_TYPE=tty
2025-10-14 06:56:11.240770 | controller | _=/usr/bin/env
2025-10-14 06:56:11.240777 | controller | MOTD_SHOWN=pam
2025-10-14 06:56:11.240783 | controller | HOME=/home/zuul
2025-10-14 06:56:11.240790 | controller | LANG=en_US.UTF-8
2025-10-14 06:56:11.240797 | controller | SSH_CONNECTION=192.168.26.12 47244 192.168.26.61 22
2025-10-14 06:56:11.240804 | 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-14 06:56:11.240811 | controller | ZUUL_CHANGE_IDS=3404,9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8 3237,2f8c81ac66bb5746ceb3bd299e433353061ca719
2025-10-14 06:56:11.240818 | controller | WORKSPACE=/home/zuul/workspace
2025-10-14 06:56:11.240825 | controller | XDG_SESSION_CLASS=user
2025-10-14 06:56:11.240831 | controller | SELINUX_ROLE_REQUESTED=
2025-10-14 06:56:11.240838 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-14 06:56:11.240845 | controller | USER=zuul
2025-10-14 06:56:11.240852 | controller | ZUUL_VOTING=True
2025-10-14 06:56:11.240858 | controller | BUILD_TIMEOUT=1800000
2025-10-14 06:56:11.240865 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-14 06:56:11.240871 | controller | SHLVL=1
2025-10-14 06:56:11.240878 | controller | ZUUL_PATCHSET=2f8c81ac66bb5746ceb3bd299e433353061ca719
2025-10-14 06:56:11.240885 | controller | XDG_SESSION_ID=1
2025-10-14 06:56:11.240891 | controller | ZUUL_BRANCH=main
2025-10-14 06:56:11.240898 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-14 06:56:11.240905 | controller | SSH_CLIENT=192.168.26.12 47244 22
2025-10-14 06:56:11.240915 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-14 06:56:11.240921 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-14 06:56:11.240927 | controller | which_declare=declare -f
2025-10-14 06:56:11.240933 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-14 06:56:11.240939 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-14 06:56:11.240944 | controller | ZUUL_CHANGE=3237
2025-10-14 06:56:11.240949 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-14 06:56:11.240955 | controller | ZUUL_UUID=003e3f39e55e4984a750cdb408071976
2025-10-14 06:56:11.240960 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-14 06:56:11.240966 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-14 06:56:11.240971 | controller | }
2025-10-14 06:56:11.555464 | controller | ok: Runtime: 0:00:00.005903
2025-10-14 06:56:11.561849 | 
2025-10-14 06:56:11.561951 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-14 06:56:11.575376 | controller | skipping: Conditional result was False
2025-10-14 06:56:11.580866 | 
2025-10-14 06:56:11.580973 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-14 06:56:12.107604 | controller | skipping: Conditional result was False
2025-10-14 06:56:12.113803 | 
2025-10-14 06:56:12.113876 | TASK [Ensure legacy workspace directory]
2025-10-14 06:56:12.365531 | controller | changed
2025-10-14 06:56:12.393406 | 
2025-10-14 06:56:12.393511 | PLAY RECAP
2025-10-14 06:56:12.393563 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-14 06:56:12.393588 | 
2025-10-14 06:56:12.562499 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-14 06:56:12.563412 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-14 06:56:13.455848 | 
2025-10-14 06:56:13.455980 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-14 06:56:13.499959 | 
2025-10-14 06:56:13.500097 | TASK [Create zuul-output directory]
2025-10-14 06:56:13.864859 | controller | changed
2025-10-14 06:56:13.877385 | 
2025-10-14 06:56:13.877515 | TASK [Slurp Zuul inventory test]
2025-10-14 06:56:14.297210 | controller -> localhost | ok
2025-10-14 06:56:14.303559 | 
2025-10-14 06:56:14.303629 | TASK [Save zuul inventory]
2025-10-14 06:56:15.342048 | controller | changed
2025-10-14 06:56:15.349551 | 
2025-10-14 06:56:15.349686 | TASK [Save zuul vars without the change_message]
2025-10-14 06:56:15.946204 | controller | changed
2025-10-14 06:56:15.964340 | 
2025-10-14 06:56:15.964416 | PLAY RECAP
2025-10-14 06:56:15.964464 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-14 06:56:15.964489 | 
2025-10-14 06:56:16.068546 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-14 06:56:16.069298 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-14 06:56:16.839766 | 
2025-10-14 06:56:16.839880 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-14 06:56:16.861000 | 
2025-10-14 06:56:16.861090 | TASK [Read group_vars]
2025-10-14 06:56:16.880414 | controller | ok
2025-10-14 06:56:16.900028 | 
2025-10-14 06:56:16.900135 | TASK [cifmw_helpers : Check directory is available]
2025-10-14 06:56:17.254134 | controller | ok
2025-10-14 06:56:17.262733 | 
2025-10-14 06:56:17.262847 | TASK [cifmw_helpers : Print var]
2025-10-14 06:56:17.298709 | controller | ok:
2025-10-14 06:56:17.298947 | controller | provide_var is /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars
2025-10-14 06:56:17.298985 | controller |  and registered one is {'changed': False, 'stat': {'exists': False}, 'failed': False}
2025-10-14 06:56:17.306877 | 
2025-10-14 06:56:17.306969 | TASK [cifmw_helpers : List dir in ansible user home]
2025-10-14 06:56:17.643320 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-14 06:56:17.644711 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-14 06:56:17.646043 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied
2025-10-14 06:56:17.647302 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-14 06:56:17.842270 | controller | ERROR
2025-10-14 06:56:17.842434 | controller | {
2025-10-14 06:56:17.842467 | controller |   "delta": "0:00:00.009591",
2025-10-14 06:56:17.842497 | controller |   "end": "2025-10-14 06:56:17.647697",
2025-10-14 06:56:17.842518 | controller |   "msg": "non-zero return code",
2025-10-14 06:56:17.842536 | controller |   "rc": 2,
2025-10-14 06:56:17.842553 | controller |   "start": "2025-10-14 06:56:17.638106"
2025-10-14 06:56:17.842571 | controller | }
2025-10-14 06:56:17.842598 | controller | ERROR: Ignoring Errors
2025-10-14 06:56:17.848395 | 
2025-10-14 06:56:17.848467 | TASK [cifmw_helpers : Find yaml files]
2025-10-14 06:56:17.882246 | controller | skipping: Conditional result was False
2025-10-14 06:56:17.888067 | 
2025-10-14 06:56:17.888143 | TASK [cifmw_helpers : Print available yaml files]
2025-10-14 06:56:17.911884 | controller | skipping: Conditional result was False
2025-10-14 06:56:17.918281 | 
2025-10-14 06:56:17.918355 | LOOP [cifmw_helpers : Read vars]
2025-10-14 06:56:17.937443 | 
2025-10-14 06:56:17.937585 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-14 06:56:17.979159 | 
2025-10-14 06:56:17.979350 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-14 06:56:18.007457 | controller | ok
2025-10-14 06:56:18.034368 | 
2025-10-14 06:56:18.034482 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-14 06:56:18.130850 | controller | skipping: Conditional result was False
2025-10-14 06:56:18.174246 | 
2025-10-14 06:56:18.174345 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-14 06:56:18.515964 | controller | ok
2025-10-14 06:56:18.521812 | 
2025-10-14 06:56:18.521881 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-14 06:56:19.011358 | controller | ok
2025-10-14 06:56:19.024508 | 
2025-10-14 06:56:19.024603 | TASK [Prepare workspace]
2025-10-14 06:56:19.055231 | controller | ok
2025-10-14 06:56:19.080172 | 
2025-10-14 06:56:19.080295 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-14 06:56:19.393583 | controller | ok
2025-10-14 06:56:19.400535 | 
2025-10-14 06:56:19.400628 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-14 06:56:22.032060 | controller | Output suppressed because no_log was given
2025-10-14 06:56:22.044812 | 
2025-10-14 06:56:22.044990 | LOOP [Create zuul-output directory]
2025-10-14 06:56:22.235571 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-14 06:56:22.235815 | 
2025-10-14 06:56:22.401616 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-14 06:56:22.417286 | 
2025-10-14 06:56:22.417427 | TASK [Install required packages]
2025-10-14 06:57:32.511581 | controller | changed
2025-10-14 06:57:32.516768 | 
2025-10-14 06:57:32.516845 | TASK [Install venv]
2025-10-14 06:58:36.302478 | controller | changed
2025-10-14 06:58:36.329834 | 
2025-10-14 06:58:36.329981 | PLAY RECAP
2025-10-14 06:58:36.330029 | controller | ok: 10 changed: 5 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 1
2025-10-14 06:58:36.330053 | 
2025-10-14 06:58:36.408500 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-14 06:58:36.409355 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-14 06:58:36.963063 | 
2025-10-14 06:58:36.963204 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-14 06:58:36.983681 | 
2025-10-14 06:58:36.983767 | TASK [Gather required facts]
2025-10-14 06:58:37.490470 | controller | ok
2025-10-14 06:58:37.496253 | 
2025-10-14 06:58:37.496347 | TASK [Load environment var if instructed to]
2025-10-14 06:58:37.520263 | controller | skipping: Conditional result was False
2025-10-14 06:58:37.525451 | 
2025-10-14 06:58:37.525528 | TASK [Ensure group_vars dir exists]
2025-10-14 06:58:37.847418 | controller | ok
2025-10-14 06:58:37.855124 | 
2025-10-14 06:58:37.855196 | TASK [Run molecule]
2025-10-14 06:58:38.822805 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-14 06:58:38.893336 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-14 06:58:57.795712 | 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-14 06:58:57.796178 | 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-14 06:58:57.796661 | 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-14 06:58:57.797106 | 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-14 06:58:57.797633 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+2ef920f0 was found installed in /home/zuul/.cache/ansible-compat/48a970/collections/ansible_collections, only the first one will be used, 1.0.0+2ef920f0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-14 06:58:57.798086 | 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-14 06:58:57.798552 | 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-14 06:58:57.798991 | 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-14 06:58:57.799450 | 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-14 06:58:57.799906 | 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-14 06:58:57.800339 | 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-14 06:58:57.800827 | 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-14 06:58:57.801264 | 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-14 06:58:57.801758 | 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-14 06:58:57.802190 | 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-14 06:58:57.811564 | controller | INFO     Running default > prepare
2025-10-14 06:58:58.525086 | controller |
2025-10-14 06:58:58.525175 | controller | PLAY [Prepare] *****************************************************************
2025-10-14 06:58:58.525274 | controller |
2025-10-14 06:58:58.525402 | controller | TASK [Gathering Facts] *********************************************************
2025-10-14 06:58:58.525539 | controller | Tuesday 14 October 2025  06:58:58 +0000 (0:00:00.023)       0:00:00.023 *******
2025-10-14 06:58:59.416501 | controller | ok: [instance]
2025-10-14 06:58:59.416585 | controller |
2025-10-14 06:58:59.416694 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-14 06:58:59.416791 | controller | Tuesday 14 October 2025  06:58:59 +0000 (0:00:00.892)       0:00:00.915 *******
2025-10-14 06:58:59.436114 | controller | skipping: [instance]
2025-10-14 06:58:59.436171 | controller |
2025-10-14 06:58:59.436293 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-14 06:58:59.436416 | controller | Tuesday 14 October 2025  06:58:59 +0000 (0:00:00.019)       0:00:00.935 *******
2025-10-14 06:58:59.478966 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-14 06:58:59.479031 | controller |
2025-10-14 06:58:59.479135 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-14 06:58:59.479246 | controller | Tuesday 14 October 2025  06:58:59 +0000 (0:00:00.042)       0:00:00.978 *******
2025-10-14 06:58:59.769764 | controller | ok: [instance]
2025-10-14 06:58:59.769837 | controller |
2025-10-14 06:58:59.769946 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-14 06:58:59.770052 | controller | Tuesday 14 October 2025  06:58:59 +0000 (0:00:00.290)       0:00:01.269 *******
2025-10-14 06:59:00.082817 | controller | ok: [instance]
2025-10-14 06:59:00.082892 | controller |
2025-10-14 06:59:00.083004 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-14 06:59:00.083111 | controller | Tuesday 14 October 2025  06:59:00 +0000 (0:00:00.312)       0:00:01.582 *******
2025-10-14 06:59:00.804038 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-14 06:59:00.804149 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-14 06:59:00.804258 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-14 06:59:00.804391 | controller |
2025-10-14 06:59:00.804531 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-14 06:59:00.804622 | controller | Tuesday 14 October 2025  06:59:00 +0000 (0:00:00.721)       0:00:02.303 *******
2025-10-14 06:59:00.856966 | controller |
2025-10-14 06:59:00.857098 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-14 06:59:00.857234 | controller | Tuesday 14 October 2025  06:59:00 +0000 (0:00:00.053)       0:00:02.356 *******
2025-10-14 06:59:01.423838 | controller | changed: [instance] => (item=tmp)
2025-10-14 06:59:01.423931 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-14 06:59:01.424037 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-14 06:59:01.424138 | controller |
2025-10-14 06:59:01.424242 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-14 06:59:01.424378 | controller | Tuesday 14 October 2025  06:59:01 +0000 (0:00:00.566)       0:00:02.923 *******
2025-10-14 06:59:02.216740 | controller | ok: [instance]
2025-10-14 06:59:02.217074 | controller |
2025-10-14 06:59:03.428093 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-14 06:59:03.428122 | controller | Tuesday 14 October 2025  06:59:02 +0000 (0:00:00.792)       0:00:03.715 *******
2025-10-14 06:59:03.428134 | controller | changed: [instance]
2025-10-14 06:59:11.734558 | controller |
2025-10-14 06:59:11.734598 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-14 06:59:11.734609 | controller | Tuesday 14 October 2025  06:59:03 +0000 (0:00:01.210)       0:00:04.926 *******
2025-10-14 06:59:11.734624 | controller | changed: [instance]
2025-10-14 06:59:12.492702 | controller |
2025-10-14 06:59:12.492745 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-14 06:59:12.492754 | controller | Tuesday 14 October 2025  06:59:11 +0000 (0:00:08.306)       0:00:13.233 *******
2025-10-14 06:59:12.492764 | controller | changed: [instance]
2025-10-14 06:59:12.514163 | controller |
2025-10-14 06:59:12.514188 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-14 06:59:12.514197 | controller | Tuesday 14 October 2025  06:59:12 +0000 (0:00:00.758)       0:00:13.991 *******
2025-10-14 06:59:12.514207 | controller | skipping: [instance]
2025-10-14 06:59:13.379662 | controller |
2025-10-14 06:59:13.379694 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-14 06:59:13.379703 | controller | Tuesday 14 October 2025  06:59:12 +0000 (0:00:00.021)       0:00:14.013 *******
2025-10-14 06:59:13.379713 | controller | changed: [instance]
2025-10-14 06:59:13.418529 | controller |
2025-10-14 06:59:13.418564 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-14 06:59:13.418574 | controller | Tuesday 14 October 2025  06:59:13 +0000 (0:00:00.865)       0:00:14.878 *******
2025-10-14 06:59:13.418587 | controller | skipping: [instance]
2025-10-14 06:59:13.418609 | controller |
2025-10-14 06:59:13.418617 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-14 06:59:13.418626 | controller | Tuesday 14 October 2025  06:59:13 +0000 (0:00:00.038)       0:00:14.917 *******
2025-10-14 06:59:13.457037 | controller | skipping: [instance]
2025-10-14 06:59:13.495699 | controller |
2025-10-14 06:59:13.495728 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-14 06:59:13.495736 | controller | Tuesday 14 October 2025  06:59:13 +0000 (0:00:00.038)       0:00:14.955 *******
2025-10-14 06:59:13.495746 | controller | skipping: [instance]
2025-10-14 06:59:14.061603 | controller |
2025-10-14 06:59:14.061635 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-14 06:59:14.061644 | controller | Tuesday 14 October 2025  06:59:13 +0000 (0:00:00.039)       0:00:14.994 *******
2025-10-14 06:59:14.061654 | controller | changed: [instance]
2025-10-14 06:59:14.510201 | controller |
2025-10-14 06:59:14.510234 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-14 06:59:14.510243 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.565)       0:00:15.560 *******
2025-10-14 06:59:14.510253 | controller | changed: [instance]
2025-10-14 06:59:14.540380 | controller |
2025-10-14 06:59:14.540412 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-14 06:59:14.540420 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.448)       0:00:16.009 *******
2025-10-14 06:59:14.540431 | controller | skipping: [instance]
2025-10-14 06:59:14.570621 | controller |
2025-10-14 06:59:14.570650 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-14 06:59:14.570657 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.030)       0:00:16.039 *******
2025-10-14 06:59:14.570671 | controller | skipping: [instance]
2025-10-14 06:59:14.601266 | controller |
2025-10-14 06:59:14.601300 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-14 06:59:14.601309 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.030)       0:00:16.069 *******
2025-10-14 06:59:14.601321 | controller | skipping: [instance]
2025-10-14 06:59:14.641309 | controller |
2025-10-14 06:59:14.641339 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-14 06:59:14.641348 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.030)       0:00:16.100 *******
2025-10-14 06:59:14.641359 | controller | ok: [instance]
2025-10-14 06:59:14.641588 | controller |
2025-10-14 06:59:14.641756 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-14 06:59:14.641905 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.040)       0:00:16.140 *******
2025-10-14 06:59:14.670075 | controller | skipping: [instance]
2025-10-14 06:59:14.670305 | controller |
2025-10-14 06:59:14.670523 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-14 06:59:14.670704 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.028)       0:00:16.169 *******
2025-10-14 06:59:14.698266 | controller | skipping: [instance]
2025-10-14 06:59:14.698513 | controller |
2025-10-14 06:59:14.698708 | controller | TASK [Download the RPM] ********************************************************
2025-10-14 06:59:14.698859 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.028)       0:00:16.197 *******
2025-10-14 06:59:14.765641 | controller | skipping: [instance]
2025-10-14 06:59:14.765855 | controller |
2025-10-14 06:59:14.766022 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-14 06:59:14.766191 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.067)       0:00:16.264 *******
2025-10-14 06:59:14.793788 | controller | skipping: [instance]
2025-10-14 06:59:14.794017 | controller |
2025-10-14 06:59:14.794207 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-14 06:59:14.794364 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.028)       0:00:16.293 *******
2025-10-14 06:59:14.821843 | controller | skipping: [instance]
2025-10-14 06:59:14.822056 | controller |
2025-10-14 06:59:14.822236 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-14 06:59:14.822384 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.028)       0:00:16.321 *******
2025-10-14 06:59:14.851928 | controller | skipping: [instance]
2025-10-14 06:59:14.852163 | controller |
2025-10-14 06:59:14.852336 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-14 06:59:14.852513 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.030)       0:00:16.351 *******
2025-10-14 06:59:14.879471 | controller | skipping: [instance]
2025-10-14 06:59:14.879687 | controller |
2025-10-14 06:59:14.879855 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-14 06:59:14.880009 | controller | Tuesday 14 October 2025  06:59:14 +0000 (0:00:00.027)       0:00:16.378 *******
2025-10-14 06:59:15.067530 | controller | ok: [instance]
2025-10-14 06:59:15.301706 | controller |
2025-10-14 06:59:15.301735 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-14 06:59:15.301743 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.187)       0:00:16.566 *******
2025-10-14 06:59:15.301753 | controller | changed: [instance]
2025-10-14 06:59:15.529702 | controller |
2025-10-14 06:59:15.529736 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-14 06:59:15.529744 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.234)       0:00:16.800 *******
2025-10-14 06:59:15.529753 | controller | changed: [instance]
2025-10-14 06:59:15.550955 | controller |
2025-10-14 06:59:15.550985 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-14 06:59:15.550993 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.227)       0:00:17.028 *******
2025-10-14 06:59:15.551003 | controller | skipping: [instance]
2025-10-14 06:59:15.574938 | controller |
2025-10-14 06:59:15.574974 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-14 06:59:15.574983 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.021)       0:00:17.050 *******
2025-10-14 06:59:15.574995 | controller | skipping: [instance]
2025-10-14 06:59:15.598193 | controller |
2025-10-14 06:59:15.598220 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-14 06:59:15.598228 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.023)       0:00:17.073 *******
2025-10-14 06:59:15.598238 | controller | skipping: [instance]
2025-10-14 06:59:15.622528 | controller |
2025-10-14 06:59:15.622561 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-14 06:59:15.622571 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.023)       0:00:17.097 *******
2025-10-14 06:59:15.622583 | controller | skipping: [instance]
2025-10-14 06:59:15.646338 | controller |
2025-10-14 06:59:15.646371 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-14 06:59:15.646385 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.024)       0:00:17.121 *******
2025-10-14 06:59:15.646396 | controller | skipping: [instance]
2025-10-14 06:59:15.646478 | controller |
2025-10-14 06:59:15.646640 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-14 06:59:15.646779 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.024)       0:00:17.145 *******
2025-10-14 06:59:15.670505 | controller | skipping: [instance]
2025-10-14 06:59:15.670828 | controller |
2025-10-14 06:59:15.690294 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-14 06:59:15.690327 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.024)       0:00:17.169 *******
2025-10-14 06:59:15.690340 | controller | skipping: [instance]
2025-10-14 06:59:15.720469 | controller |
2025-10-14 06:59:15.720499 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-14 06:59:15.720507 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.019)       0:00:17.189 *******
2025-10-14 06:59:15.720517 | controller | skipping: [instance]
2025-10-14 06:59:15.738332 | controller |
2025-10-14 06:59:15.738358 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-14 06:59:15.738366 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.030)       0:00:17.219 *******
2025-10-14 06:59:15.738376 | controller | skipping: [instance]
2025-10-14 06:59:15.760429 | controller |
2025-10-14 06:59:15.760474 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-14 06:59:15.760482 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.017)       0:00:17.237 *******
2025-10-14 06:59:15.760491 | controller | skipping: [instance]
2025-10-14 06:59:15.789686 | controller |
2025-10-14 06:59:15.789719 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-14 06:59:15.789729 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.022)       0:00:17.259 *******
2025-10-14 06:59:15.789741 | controller | skipping: [instance]
2025-10-14 06:59:15.819071 | controller |
2025-10-14 06:59:15.819128 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-14 06:59:15.819137 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.029)       0:00:17.288 *******
2025-10-14 06:59:15.819147 | controller | skipping: [instance]
2025-10-14 06:59:15.848527 | controller |
2025-10-14 06:59:15.848556 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-14 06:59:15.848564 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.029)       0:00:17.318 *******
2025-10-14 06:59:15.848574 | controller | skipping: [instance]
2025-10-14 06:59:15.848750 | controller |
2025-10-14 06:59:15.848910 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-14 06:59:15.849059 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.029)       0:00:17.347 *******
2025-10-14 06:59:15.878914 | controller | skipping: [instance]
2025-10-14 06:59:15.879143 | controller |
2025-10-14 06:59:15.879314 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-14 06:59:15.879483 | controller | Tuesday 14 October 2025  06:59:15 +0000 (0:00:00.030)       0:00:17.378 *******
2025-10-14 07:00:26.421583 | controller | ok: [instance]
2025-10-14 07:00:26.421649 | controller |
2025-10-14 07:00:26.421661 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-14 07:00:26.421803 | controller | Tuesday 14 October 2025  07:00:26 +0000 (0:01:10.542)       0:01:27.920 *******
2025-10-14 07:00:27.610174 | controller | ok: [instance]
2025-10-14 07:00:27.643236 | controller |
2025-10-14 07:00:27.643264 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-14 07:00:27.643272 | controller | Tuesday 14 October 2025  07:00:27 +0000 (0:00:01.188)       0:01:29.109 *******
2025-10-14 07:00:27.643282 | controller | skipping: [instance]
2025-10-14 07:00:28.203242 | controller |
2025-10-14 07:00:28.203275 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-14 07:00:28.203283 | controller | Tuesday 14 October 2025  07:00:27 +0000 (0:00:00.033)       0:01:29.142 *******
2025-10-14 07:00:28.203292 | controller | ok: [instance] => (item=tmp)
2025-10-14 07:00:29.385725 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-14 07:00:29.385758 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-14 07:00:29.385770 | controller |
2025-10-14 07:00:29.385777 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-14 07:00:29.385790 | controller | Tuesday 14 October 2025  07:00:28 +0000 (0:00:00.559)       0:01:29.702 *******
2025-10-14 07:00:29.385800 | controller | ok: [instance]
2025-10-14 07:00:29.862519 | controller |
2025-10-14 07:00:29.862603 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-14 07:00:29.862612 | controller | Tuesday 14 October 2025  07:00:29 +0000 (0:00:01.182)       0:01:30.884 *******
2025-10-14 07:00:29.862625 | controller | ok: [instance]
2025-10-14 07:00:29.862809 | controller |
2025-10-14 07:00:29.862843 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-14 07:00:31.038564 | controller | Tuesday 14 October 2025  07:00:29 +0000 (0:00:00.477)       0:01:31.361 *******
2025-10-14 07:00:31.038600 | controller | ok: [instance]
2025-10-14 07:00:31.038813 | controller |
2025-10-14 07:00:31.038828 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-14 07:00:31.241544 | controller | Tuesday 14 October 2025  07:00:31 +0000 (0:00:01.175)       0:01:32.537 *******
2025-10-14 07:00:31.241580 | controller | ok: [instance]
2025-10-14 07:00:31.262393 | controller |
2025-10-14 07:00:31.262455 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-14 07:00:31.262466 | controller | Tuesday 14 October 2025  07:00:31 +0000 (0:00:00.202)       0:01:32.740 *******
2025-10-14 07:00:31.262476 | controller | skipping: [instance]
2025-10-14 07:00:32.097514 | controller |
2025-10-14 07:00:32.097548 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-14 07:00:32.097556 | controller | Tuesday 14 October 2025  07:00:31 +0000 (0:00:00.020)       0:01:32.761 *******
2025-10-14 07:00:32.097566 | controller | changed: [instance]
2025-10-14 07:00:32.097591 | controller |
2025-10-14 07:00:32.097599 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-14 07:00:32.097720 | controller | Tuesday 14 October 2025  07:00:32 +0000 (0:00:00.835)       0:01:33.596 *******
2025-10-14 07:00:32.138625 | controller | skipping: [instance]
2025-10-14 07:00:32.179539 | controller |
2025-10-14 07:00:32.179577 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-14 07:00:32.179587 | controller | Tuesday 14 October 2025  07:00:32 +0000 (0:00:00.040)       0:01:33.637 *******
2025-10-14 07:00:32.179598 | controller | skipping: [instance]
2025-10-14 07:00:32.180008 | controller |
2025-10-14 07:00:32.220996 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-14 07:00:32.221033 | controller | Tuesday 14 October 2025  07:00:32 +0000 (0:00:00.041)       0:01:33.678 *******
2025-10-14 07:00:32.221047 | controller | skipping: [instance]
2025-10-14 07:00:32.791269 | controller |
2025-10-14 07:00:32.791299 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-14 07:00:32.791307 | controller | Tuesday 14 October 2025  07:00:32 +0000 (0:00:00.040)       0:01:33.719 *******
2025-10-14 07:00:32.791317 | controller | changed: [instance]
2025-10-14 07:00:33.143238 | controller |
2025-10-14 07:00:33.143268 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-14 07:00:33.143276 | controller | Tuesday 14 October 2025  07:00:32 +0000 (0:00:00.570)       0:01:34.290 *******
2025-10-14 07:00:33.143286 | controller | changed: [instance]
2025-10-14 07:00:33.171489 | controller |
2025-10-14 07:00:33.171515 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-14 07:00:33.171524 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.351)       0:01:34.642 *******
2025-10-14 07:00:33.171535 | controller | skipping: [instance]
2025-10-14 07:00:33.200488 | controller |
2025-10-14 07:00:33.200509 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-14 07:00:33.200516 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.028)       0:01:34.670 *******
2025-10-14 07:00:33.200525 | controller | skipping: [instance]
2025-10-14 07:00:33.200555 | controller |
2025-10-14 07:00:33.200562 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-14 07:00:33.200570 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.029)       0:01:34.699 *******
2025-10-14 07:00:33.228196 | controller | skipping: [instance]
2025-10-14 07:00:33.265455 | controller |
2025-10-14 07:00:33.265475 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-14 07:00:33.265491 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.027)       0:01:34.727 *******
2025-10-14 07:00:33.265502 | controller | ok: [instance]
2025-10-14 07:00:33.291489 | controller |
2025-10-14 07:00:33.291508 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-14 07:00:33.291515 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.037)       0:01:34.764 *******
2025-10-14 07:00:33.291524 | controller | skipping: [instance]
2025-10-14 07:00:33.291598 | controller |
2025-10-14 07:00:33.291615 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-14 07:00:33.291626 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.026)       0:01:34.790 *******
2025-10-14 07:00:33.317995 | controller | skipping: [instance]
2025-10-14 07:00:33.344959 | controller |
2025-10-14 07:00:33.344978 | controller | TASK [Download the RPM] ********************************************************
2025-10-14 07:00:33.344986 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.026)       0:01:34.817 *******
2025-10-14 07:00:33.344999 | controller | skipping: [instance]
2025-10-14 07:00:33.371946 | controller |
2025-10-14 07:00:33.371966 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-14 07:00:33.371973 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.026)       0:01:34.844 *******
2025-10-14 07:00:33.371982 | controller | skipping: [instance]
2025-10-14 07:00:33.398509 | controller |
2025-10-14 07:00:33.398528 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-14 07:00:33.398535 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.027)       0:01:34.871 *******
2025-10-14 07:00:33.398544 | controller | skipping: [instance]
2025-10-14 07:00:33.398623 | controller |
2025-10-14 07:00:33.398639 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-14 07:00:33.398649 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.026)       0:01:34.897 *******
2025-10-14 07:00:33.425147 | controller | skipping: [instance]
2025-10-14 07:00:33.454260 | controller |
2025-10-14 07:00:33.454281 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-14 07:00:33.454291 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.026)       0:01:34.924 *******
2025-10-14 07:00:33.454302 | controller | skipping: [instance]
2025-10-14 07:00:33.634445 | controller |
2025-10-14 07:00:33.634476 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-14 07:00:33.634484 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.028)       0:01:34.953 *******
2025-10-14 07:00:33.634494 | controller | ok: [instance]
2025-10-14 07:00:33.850737 | controller |
2025-10-14 07:00:33.850767 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-14 07:00:33.850774 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.179)       0:01:35.133 *******
2025-10-14 07:00:33.850784 | controller | changed: [instance]
2025-10-14 07:00:34.071233 | controller |
2025-10-14 07:00:34.071263 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-14 07:00:34.071271 | controller | Tuesday 14 October 2025  07:00:33 +0000 (0:00:00.216)       0:01:35.349 *******
2025-10-14 07:00:34.071281 | controller | changed: [instance]
2025-10-14 07:00:34.093547 | controller |
2025-10-14 07:00:34.093585 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-14 07:00:34.093595 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.220)       0:01:35.570 *******
2025-10-14 07:00:34.093608 | controller | skipping: [instance]
2025-10-14 07:00:34.093671 | controller |
2025-10-14 07:00:34.093692 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-14 07:00:34.093704 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.022)       0:01:35.592 *******
2025-10-14 07:00:34.131154 | controller | skipping: [instance]
2025-10-14 07:00:34.169059 | controller |
2025-10-14 07:00:34.169078 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-14 07:00:34.169085 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.037)       0:01:35.630 *******
2025-10-14 07:00:34.169094 | controller | skipping: [instance]
2025-10-14 07:00:34.207534 | controller |
2025-10-14 07:00:34.207570 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-14 07:00:34.207578 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.037)       0:01:35.668 *******
2025-10-14 07:00:34.207588 | controller | skipping: [instance]
2025-10-14 07:00:34.207613 | controller |
2025-10-14 07:00:34.207621 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-14 07:00:34.207736 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.038)       0:01:35.706 *******
2025-10-14 07:00:34.244106 | controller | skipping: [instance]
2025-10-14 07:00:34.244240 | controller |
2025-10-14 07:00:34.244393 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-14 07:00:34.244583 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.036)       0:01:35.743 *******
2025-10-14 07:00:34.280400 | controller | skipping: [instance]
2025-10-14 07:00:34.573037 | controller |
2025-10-14 07:00:34.573065 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-14 07:00:34.573073 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.036)       0:01:35.779 *******
2025-10-14 07:00:34.573082 | controller | ok: [instance]
2025-10-14 07:00:36.109567 | controller |
2025-10-14 07:00:36.109605 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-14 07:00:36.109614 | controller | Tuesday 14 October 2025  07:00:34 +0000 (0:00:00.292)       0:01:36.072 *******
2025-10-14 07:00:36.109625 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-10-14 07:00:36.109650 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-10-14 07:00:36.109658 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-10-14 07:00:36.110452 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-10-14 07:00:36.558115 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-10-14 07:00:36.558145 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-10-14 07:00:36.558154 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-10-14 07:00:36.558159 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-10-14 07:00:36.558165 | controller |
2025-10-14 07:00:36.558170 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-14 07:00:36.558176 | controller | Tuesday 14 October 2025  07:00:36 +0000 (0:00:01.536)       0:01:37.608 *******
2025-10-14 07:00:36.558192 | controller | changed: [instance]
2025-10-14 07:00:36.826527 | controller |
2025-10-14 07:00:36.826563 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-14 07:00:36.826573 | controller | Tuesday 14 October 2025  07:00:36 +0000 (0:00:00.448)       0:01:38.057 *******
2025-10-14 07:00:36.826585 | controller | changed: [instance]
2025-10-14 07:00:36.829126 | controller |
2025-10-14 07:00:36.829146 | controller | PLAY RECAP *********************************************************************
2025-10-14 07:00:36.829157 | controller | instance                   : ok=35   changed=17   unreachable=0    failed=0    skipped=50   rescued=0    ignored=0
2025-10-14 07:00:36.893238 | controller |
2025-10-14 07:00:36.893267 | controller | Tuesday 14 October 2025  07:00:36 +0000 (0:00:00.268)       0:01:38.325 *******
2025-10-14 07:00:36.893275 | controller | ===============================================================================
2025-10-14 07:00:36.893281 | controller | test_deps : Install selinux python libs -------------------------------- 70.54s
2025-10-14 07:00:36.893286 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.31s
2025-10-14 07:00:36.893292 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.54s
2025-10-14 07:00:36.893297 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.21s
2025-10-14 07:00:36.893302 | controller | test_deps : Install python yaml libs ------------------------------------ 1.19s
2025-10-14 07:00:36.893308 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.18s
2025-10-14 07:00:36.893319 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.18s
2025-10-14 07:00:36.893325 | controller | Gathering Facts --------------------------------------------------------- 0.89s
2025-10-14 07:00:36.893330 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.87s
2025-10-14 07:00:36.893335 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-10-14 07:00:36.893340 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.79s
2025-10-14 07:00:36.893346 | controller | repo_setup : Install repo-setup package --------------------------------- 0.76s
2025-10-14 07:00:36.893351 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.72s
2025-10-14 07:00:36.893356 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-14 07:00:36.893361 | controller | repo_setup : Ensure directories are present ----------------------------- 0.57s
2025-10-14 07:00:36.893367 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-14 07:00:36.893372 | controller | repo_setup : Ensure directories are present ----------------------------- 0.56s
2025-10-14 07:00:36.893377 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.48s
2025-10-14 07:00:36.893383 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s
2025-10-14 07:00:36.893401 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.45s
2025-10-14 07:00:36.893411 | controller | INFO     Running default > converge
2025-10-14 07:00:37.335963 | controller |
2025-10-14 07:00:37.336162 | controller | PLAY [Converge] ****************************************************************
2025-10-14 07:00:37.336306 | controller |
2025-10-14 07:00:37.336486 | controller | TASK [Gathering Facts] *********************************************************
2025-10-14 07:00:37.336642 | controller | Tuesday 14 October 2025  07:00:37 +0000 (0:00:00.017)       0:00:00.017 *******
2025-10-14 07:00:38.132515 | controller | ok: [instance]
2025-10-14 07:00:38.132626 | controller |
2025-10-14 07:00:38.132637 | controller | TASK [build_containers : Ensure directories are present] ***********************
2025-10-14 07:00:38.132646 | controller | Tuesday 14 October 2025  07:00:38 +0000 (0:00:00.796)       0:00:00.813 *******
2025-10-14 07:00:38.793685 | controller | ok: [instance] => (item=tmp)
2025-10-14 07:00:38.819972 | controller | ok: [instance] => (item=artifacts)
2025-10-14 07:00:38.819995 | controller | ok: [instance] => (item=logs)
2025-10-14 07:00:38.820005 | controller |
2025-10-14 07:00:38.820012 | controller | TASK [Install podman] **********************************************************
2025-10-14 07:00:38.820025 | controller | Tuesday 14 October 2025  07:00:38 +0000 (0:00:00.661)       0:00:01.474 *******
2025-10-14 07:00:38.820037 | controller |
2025-10-14 07:01:04.491415 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-10-14 07:01:04.491464 | controller | Tuesday 14 October 2025  07:00:38 +0000 (0:00:00.026)       0:00:01.501 *******
2025-10-14 07:01:04.491476 | controller | ok: [instance]
2025-10-14 07:01:04.828028 | controller |
2025-10-14 07:01:04.828058 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-10-14 07:01:04.828066 | controller | Tuesday 14 October 2025  07:01:04 +0000 (0:00:25.671)       0:00:27.172 *******
2025-10-14 07:01:04.828076 | controller | changed: [instance]
2025-10-14 07:01:04.850531 | controller |
2025-10-14 07:01:04.850556 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-10-14 07:01:04.850565 | controller | Tuesday 14 October 2025  07:01:04 +0000 (0:00:00.336)       0:00:27.509 *******
2025-10-14 07:01:04.850575 | controller | skipping: [instance]
2025-10-14 07:01:04.869193 | controller |
2025-10-14 07:01:04.869229 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-10-14 07:01:04.869238 | controller | Tuesday 14 October 2025  07:01:04 +0000 (0:00:00.022)       0:00:27.532 *******
2025-10-14 07:01:04.869247 | controller | skipping: [instance]
2025-10-14 07:01:04.892369 | controller |
2025-10-14 07:01:04.892390 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-10-14 07:01:04.892399 | controller | Tuesday 14 October 2025  07:01:04 +0000 (0:00:00.018)       0:00:27.550 *******
2025-10-14 07:01:04.892414 | controller | skipping: [instance]
2025-10-14 07:01:06.544750 | controller |
2025-10-14 07:01:06.544782 | controller | TASK [build_containers : Install required packages] ****************************
2025-10-14 07:01:06.544790 | controller | Tuesday 14 October 2025  07:01:04 +0000 (0:00:00.023)       0:00:27.574 *******
2025-10-14 07:01:06.544800 | controller | changed: [instance]
2025-10-14 07:01:06.544825 | controller |
2025-10-14 07:01:06.544958 | controller | TASK [build_containers : Get tcib from repository] *****************************
2025-10-14 07:01:06.544993 | controller | Tuesday 14 October 2025  07:01:06 +0000 (0:00:01.652)       0:00:29.226 *******
2025-10-14 07:01:06.562510 | controller | skipping: [instance]
2025-10-14 07:01:06.579195 | controller |
2025-10-14 07:01:06.579225 | controller | TASK [build_containers : Install tcib package] *********************************
2025-10-14 07:01:06.579233 | controller | Tuesday 14 October 2025  07:01:06 +0000 (0:00:00.017)       0:00:29.244 *******
2025-10-14 07:01:06.579242 | controller | skipping: [instance]
2025-10-14 07:01:06.579408 | controller |
2025-10-14 07:01:06.579420 | controller | TASK [build_containers : Install tcib from RPM] ********************************
2025-10-14 07:01:06.579504 | controller | Tuesday 14 October 2025  07:01:06 +0000 (0:00:00.017)       0:00:29.261 *******
2025-10-14 07:01:14.764125 | controller | changed: [instance]
2025-10-14 07:01:15.322300 | controller |
2025-10-14 07:01:15.322330 | controller | TASK [build_containers : Generate container_build.sh script] *******************
2025-10-14 07:01:15.322339 | controller | Tuesday 14 October 2025  07:01:14 +0000 (0:00:08.184)       0:00:37.445 *******
2025-10-14 07:01:15.322348 | controller | changed: [instance]
2025-10-14 07:04:20.915482 | controller |
2025-10-14 07:04:20.915516 | controller | TASK [build_containers : Run container_build.sh script] ************************
2025-10-14 07:04:20.915524 | controller | Tuesday 14 October 2025  07:01:15 +0000 (0:00:00.558)       0:00:38.003 *******
2025-10-14 07:04:20.915534 | controller | changed: [instance]
2025-10-14 07:04:20.933564 | controller |
2025-10-14 07:04:20.933614 | controller | TASK [build_containers : Run tcib hotfix command] ******************************
2025-10-14 07:04:20.933624 | controller | Tuesday 14 October 2025  07:04:20 +0000 (0:03:05.593)       0:03:43.596 *******
2025-10-14 07:04:20.933647 | controller | skipping: [instance]
2025-10-14 07:04:20.933669 | controller |
2025-10-14 07:04:20.933685 | controller | TASK [build_containers : Check for authfile] ***********************************
2025-10-14 07:04:20.933694 | controller | Tuesday 14 October 2025  07:04:20 +0000 (0:00:00.018)       0:03:43.615 *******
2025-10-14 07:04:20.951705 | controller | skipping: [instance]
2025-10-14 07:04:20.951815 | controller |
2025-10-14 07:04:20.951947 | controller | TASK [build_containers : Make sure authfile exists] ****************************
2025-10-14 07:04:20.952077 | controller | Tuesday 14 October 2025  07:04:20 +0000 (0:00:00.018)       0:03:43.633 *******
2025-10-14 07:04:20.970877 | controller | skipping: [instance]
2025-10-14 07:04:21.232563 | controller |
2025-10-14 07:04:21.232592 | controller | TASK [build_containers : Retrieve built images] ********************************
2025-10-14 07:04:21.232600 | controller | Tuesday 14 October 2025  07:04:20 +0000 (0:00:00.018)       0:03:43.652 *******
2025-10-14 07:04:21.232616 | controller | ok: [instance]
2025-10-14 07:04:21.466362 | controller |
2025-10-14 07:04:21.466394 | controller | TASK [build_containers : Store built images to containers-built.log] ***********
2025-10-14 07:04:21.466402 | controller | Tuesday 14 October 2025  07:04:21 +0000 (0:00:00.261)       0:03:43.914 *******
2025-10-14 07:04:21.466412 | controller | changed: [instance]
2025-10-14 07:04:21.495566 | controller |
2025-10-14 07:04:21.495599 | controller | TASK [build_containers : Push image: {{ item }}] *******************************
2025-10-14 07:04:21.495607 | controller | Tuesday 14 October 2025  07:04:21 +0000 (0:00:00.233)       0:03:44.147 *******
2025-10-14 07:04:21.495617 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-keystone)
2025-10-14 07:04:21.522535 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-os)
2025-10-14 07:04:21.522569 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-base)
2025-10-14 07:04:21.522576 | controller | skipping: [instance]
2025-10-14 07:04:21.522583 | controller |
2025-10-14 07:04:21.522589 | controller | TASK [build_containers : Retag each image and push to registry: {{ item }}] ****
2025-10-14 07:04:21.522604 | controller | Tuesday 14 October 2025  07:04:21 +0000 (0:00:00.028)       0:03:44.176 *******
2025-10-14 07:04:21.522615 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-keystone)
2025-10-14 07:04:21.522638 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-os)
2025-10-14 07:04:21.522645 | controller | skipping: [instance] => (item=quay.io/podified-master-centos9/openstack-base)
2025-10-14 07:04:21.522650 | controller | skipping: [instance]
2025-10-14 07:04:21.522656 | controller |
2025-10-14 07:04:21.522663 | controller | TASK [build_containers : Cleanup tcib directories after container build] *******
2025-10-14 07:04:21.522804 | controller | Tuesday 14 October 2025  07:04:21 +0000 (0:00:00.027)       0:03:44.204 *******
2025-10-14 07:04:22.132357 | controller | changed: [instance] => (item=tmp)
2025-10-14 07:04:22.193915 | controller | changed: [instance] => (item=artifacts)
2025-10-14 07:04:22.193947 | controller | changed: [instance] => (item=logs)
2025-10-14 07:04:22.193955 | controller |
2025-10-14 07:04:22.193961 | controller | PLAY RECAP *********************************************************************
2025-10-14 07:04:22.193968 | controller | instance                   : ok=11   changed=7    unreachable=0    failed=0    skipped=10   rescued=0    ignored=0
2025-10-14 07:04:22.193973 | controller |
2025-10-14 07:04:22.193979 | controller | Tuesday 14 October 2025  07:04:22 +0000 (0:00:00.607)       0:03:44.811 *******
2025-10-14 07:04:22.193984 | controller | ===============================================================================
2025-10-14 07:04:22.193990 | controller | build_containers : Run container_build.sh script ---------------------- 185.59s
2025-10-14 07:04:22.193995 | controller | podman : Ensure podman is installed ------------------------------------ 25.67s
2025-10-14 07:04:22.194000 | controller | build_containers : Install tcib from RPM -------------------------------- 8.18s
2025-10-14 07:04:22.194006 | controller | build_containers : Install required packages ---------------------------- 1.65s
2025-10-14 07:04:22.194011 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-10-14 07:04:22.194016 | controller | build_containers : Ensure directories are present ----------------------- 0.66s
2025-10-14 07:04:22.194021 | controller | build_containers : Cleanup tcib directories after container build ------- 0.61s
2025-10-14 07:04:22.194027 | controller | build_containers : Generate container_build.sh script ------------------- 0.56s
2025-10-14 07:04:22.194032 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.34s
2025-10-14 07:04:22.194038 | controller | build_containers : Retrieve built images -------------------------------- 0.26s
2025-10-14 07:04:22.194043 | controller | build_containers : Store built images to containers-built.log ----------- 0.23s
2025-10-14 07:04:22.194048 | controller | build_containers : Push image: {{ item }} ------------------------------- 0.03s
2025-10-14 07:04:22.194060 | controller | build_containers : Retag each image and push to registry: {{ item }} ---- 0.03s
2025-10-14 07:04:22.194066 | controller | Install podman ---------------------------------------------------------- 0.03s
2025-10-14 07:04:22.194071 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.02s
2025-10-14 07:04:22.194076 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.02s
2025-10-14 07:04:22.194082 | controller | build_containers : Make sure authfile exists ---------------------------- 0.02s
2025-10-14 07:04:22.194087 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.02s
2025-10-14 07:04:22.194095 | controller | build_containers : Check for authfile ----------------------------------- 0.02s
2025-10-14 07:04:22.194100 | controller | build_containers : Run tcib hotfix command ------------------------------ 0.02s
2025-10-14 07:04:22.194111 | controller | INFO     Running default > cleanup
2025-10-14 07:04:22.194602 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-14 07:04:22.195203 | controller | INFO     Writing /tmp/report.html report.
2025-10-14 07:04:22.263729 | controller | changed
2025-10-14 07:04:22.285118 | 
2025-10-14 07:04:22.285195 | PLAY RECAP
2025-10-14 07:04:22.285557 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-14 07:04:22.285602 | 
2025-10-14 07:04:22.360575 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-14 07:04:22.361418 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-14 07:04:22.923757 | 
2025-10-14 07:04:22.923873 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-14 07:04:22.944859 | 
2025-10-14 07:04:22.944967 | TASK [Filter out host if needed]
2025-10-14 07:04:22.953734 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-14 07:04:22.959504 | 
2025-10-14 07:04:22.959574 | TASK [Ensure file is present]
2025-10-14 07:04:23.273214 | controller | ok
2025-10-14 07:04:23.282040 | 
2025-10-14 07:04:23.282120 | TASK [Manage molecule report file]
2025-10-14 07:04:23.808497 | controller | changed
2025-10-14 07:04:23.813863 | 
2025-10-14 07:04:23.813954 | TASK [Check if we get ci-framework-data basedir]
2025-10-14 07:04:24.005265 | controller | ok
2025-10-14 07:04:24.012167 | 
2025-10-14 07:04:24.012264 | TASK [Create ci-framework-data log directory for zuul]
2025-10-14 07:04:24.345801 | controller | changed
2025-10-14 07:04:24.357272 | 
2025-10-14 07:04:24.357373 | TASK [Copy ci-framework interesting files]
2025-10-14 07:04:24.559243 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory
2025-10-14 07:04:24.560854 | controller | cp: cannot stat '/home/zuul/ci-framework-data/artifacts': No such file or directory
2025-10-14 07:04:24.562309 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-14 07:04:24.882512 | controller | changed
2025-10-14 07:04:24.887890 | 
2025-10-14 07:04:24.887987 | TASK [Get SELinux listing]
2025-10-14 07:04:25.416139 | controller | changed
2025-10-14 07:04:25.422240 | 
2025-10-14 07:04:25.422331 | TASK [Generate log index]
2025-10-14 07:04:26.306107 | controller | changed
2025-10-14 07:04:26.310925 | 
2025-10-14 07:04:26.310993 | TASK [Get some env related data]
2025-10-14 07:04:26.880338 | controller | /home/zuul/.local/bin/ansible
2025-10-14 07:04:27.835834 | controller | changed
2025-10-14 07:04:27.841295 | 
2025-10-14 07:04:27.841372 | TASK [Generate list of logs to collect in home directory]
2025-10-14 07:04:28.186940 | controller | ok: All paths examined
2025-10-14 07:04:28.196881 | 
2025-10-14 07:04:28.196981 | LOOP [Copy logs from home directory]
2025-10-14 07:04:28.659744 | controller | changed:
2025-10-14 07:04:28.659930 | controller | {
2025-10-14 07:04:28.659968 | controller |   "atime": 1760425098.551247,
2025-10-14 07:04:28.659993 | controller |   "ctime": 1760425278.47376,
2025-10-14 07:04:28.660012 | controller |   "dev": 64513,
2025-10-14 07:04:28.660031 | controller |   "gid": 1000,
2025-10-14 07:04:28.660048 | controller |   "gr_name": "zuul",
2025-10-14 07:04:28.660064 | controller |   "inode": 4429223,
2025-10-14 07:04:28.660079 | controller |   "isblk": false,
2025-10-14 07:04:28.660095 | controller |   "ischr": false,
2025-10-14 07:04:28.660110 | controller |   "isdir": false,
2025-10-14 07:04:28.660124 | controller |   "isfifo": false,
2025-10-14 07:04:28.660139 | controller |   "isgid": false,
2025-10-14 07:04:28.660154 | controller |   "islnk": false,
2025-10-14 07:04:28.660169 | controller |   "isreg": true,
2025-10-14 07:04:28.660183 | controller |   "issock": false,
2025-10-14 07:04:28.660198 | controller |   "isuid": false,
2025-10-14 07:04:28.660212 | controller |   "mode": "0644",
2025-10-14 07:04:28.660227 | controller |   "mtime": 1760425278.331759,
2025-10-14 07:04:28.660242 | controller |   "nlink": 1,
2025-10-14 07:04:28.660258 | controller |   "path": "/home/zuul/ansible.log",
2025-10-14 07:04:28.660273 | controller |   "pw_name": "zuul",
2025-10-14 07:04:28.660288 | controller |   "rgrp": true,
2025-10-14 07:04:28.660303 | controller |   "roth": true,
2025-10-14 07:04:28.660317 | controller |   "rusr": true,
2025-10-14 07:04:28.660332 | controller |   "size": 10293,
2025-10-14 07:04:28.660362 | controller |   "uid": 1000,
2025-10-14 07:04:28.660382 | controller |   "wgrp": false,
2025-10-14 07:04:28.660399 | controller |   "woth": false,
2025-10-14 07:04:28.660415 | controller |   "wusr": true,
2025-10-14 07:04:28.660431 | controller |   "xgrp": false,
2025-10-14 07:04:28.660446 | controller |   "xoth": false,
2025-10-14 07:04:28.660462 | controller |   "xusr": false
2025-10-14 07:04:28.660476 | controller | }
2025-10-14 07:04:28.675617 | 
2025-10-14 07:04:28.675688 | TASK [Copy crio stats log file]
2025-10-14 07:04:28.688920 | controller | skipping: Conditional result was False
2025-10-14 07:04:28.696731 | 
2025-10-14 07:04:28.696867 | TASK [Get SELinux related data]
2025-10-14 07:04:28.945025 | controller | <no matches>
2025-10-14 07:04:29.225779 | controller | ERROR
2025-10-14 07:04:29.225967 | controller | {
2025-10-14 07:04:29.226011 | controller |   "delta": "0:00:00.015429",
2025-10-14 07:04:29.226035 | controller |   "end": "2025-10-14 07:04:28.946717",
2025-10-14 07:04:29.226061 | controller |   "msg": "non-zero return code",
2025-10-14 07:04:29.226081 | controller |   "rc": 1,
2025-10-14 07:04:29.226097 | controller |   "start": "2025-10-14 07:04:28.931288"
2025-10-14 07:04:29.226120 | controller | }
2025-10-14 07:04:29.226145 | controller | ERROR: Ignoring Errors
2025-10-14 07:04:29.231429 | 
2025-10-14 07:04:29.231496 | TASK [Create system configuration directory]
2025-10-14 07:04:29.493171 | controller | changed
2025-10-14 07:04:29.498057 | 
2025-10-14 07:04:29.498126 | TASK [Get some of the system configurations]
2025-10-14 07:04:30.023592 | controller | changed
2025-10-14 07:04:30.028858 | 
2025-10-14 07:04:30.028961 | TASK [Copy generated documentation if available]
2025-10-14 07:04:30.042692 | controller | skipping: Conditional result was False
2025-10-14 07:04:30.048099 | 
2025-10-14 07:04:30.048163 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-14 07:04:30.061115 | controller | skipping: Conditional result was False
2025-10-14 07:04:30.066197 | 
2025-10-14 07:04:30.066265 | TASK [Compress logs bigger than 2MB]
2025-10-14 07:04:30.591124 | controller | changed
2025-10-14 07:04:30.596544 | 
2025-10-14 07:04:30.596608 | TASK [Copy files from workspace on node]
2025-10-14 07:04:30.614552 | controller | ok
2025-10-14 07:04:30.636271 | 
2025-10-14 07:04:30.636352 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-14 07:04:30.649766 | controller | skipping: Conditional result was False
2025-10-14 07:04:30.656221 | 
2025-10-14 07:04:30.656297 | TASK [fetch-output : Set log path for single node]
2025-10-14 07:04:30.684207 | controller | ok
2025-10-14 07:04:30.688939 | 
2025-10-14 07:04:30.689004 | LOOP [fetch-output : Ensure local output dirs]
2025-10-14 07:04:30.885451 | controller -> localhost | ok: "/var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/logs"
2025-10-14 07:04:30.885708 | controller -> localhost | changed: All items complete
2025-10-14 07:04:30.885740 | 
2025-10-14 07:04:31.068306 | controller -> localhost | changed: "/var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/artifacts"
2025-10-14 07:04:31.251537 | controller -> localhost | changed: "/var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/docs"
2025-10-14 07:04:31.267778 | 
2025-10-14 07:04:31.267886 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-14 07:04:31.832890 | controller | changed:
2025-10-14 07:04:31.833082 | controller | .d..t...... ./
2025-10-14 07:04:31.833113 | controller | >f+++++++++ README.html
2025-10-14 07:04:31.833135 | controller | >f+++++++++ ansible-execution.log
2025-10-14 07:04:31.833157 | controller | >f+++++++++ ansible.log
2025-10-14 07:04:31.833178 | controller | >f+++++++++ dmesg.log
2025-10-14 07:04:31.833198 | controller | >f+++++++++ installed-pkgs.log
2025-10-14 07:04:31.833217 | controller | >f+++++++++ python.log
2025-10-14 07:04:31.833236 | controller | >f+++++++++ registries.conf
2025-10-14 07:04:31.833254 | controller | >f+++++++++ report.html
2025-10-14 07:04:31.833270 | controller | >f+++++++++ selinux-denials.log
2025-10-14 07:04:31.833286 | controller | >f+++++++++ selinux-listing.log
2025-10-14 07:04:31.833302 | controller | cd+++++++++ ci-framework-data/
2025-10-14 07:04:31.833318 | controller | cd+++++++++ registries.conf.d/
2025-10-14 07:04:31.833349 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-14 07:04:31.833370 | controller | cd+++++++++ system-config/
2025-10-14 07:04:31.833391 | controller | cd+++++++++ system-config/libvirt/
2025-10-14 07:04:31.833408 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-14 07:04:31.833424 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-14 07:04:32.244616 | controller | changed: .d..t...... ./
2025-10-14 07:04:32.643453 | controller | changed: .d..t...... ./
2025-10-14 07:04:32.662620 | 
2025-10-14 07:04:32.662687 | TASK [Return artifact to Zuul]
2025-10-14 07:04:32.691007 | controller | ok
2025-10-14 07:04:32.709581 | 
2025-10-14 07:04:32.709640 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-14 07:04:32.709746 | 
2025-10-14 07:04:32.709781 | PLAY RECAP
2025-10-14 07:04:32.709824 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-14 07:04:32.709874 | 
2025-10-14 07:04:32.784393 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-14 07:04:32.785416 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-14 07:04:33.343267 | 
2025-10-14 07:04:33.343389 | PLAY [all]
2025-10-14 07:04:33.361883 | 
2025-10-14 07:04:33.361993 | TASK [include_role : fetch-output]
2025-10-14 07:04:33.390403 | controller | ok
2025-10-14 07:04:33.407633 | 
2025-10-14 07:04:33.407749 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-14 07:04:33.452054 | controller | skipping: Conditional result was False
2025-10-14 07:04:33.457521 | 
2025-10-14 07:04:33.457606 | TASK [fetch-output : Set log path for single node]
2025-10-14 07:04:33.485448 | controller | ok
2025-10-14 07:04:33.490399 | 
2025-10-14 07:04:33.490482 | LOOP [fetch-output : Ensure local output dirs]
2025-10-14 07:04:33.847860 | controller -> localhost | ok: "/var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/logs"
2025-10-14 07:04:34.043484 | controller -> localhost | ok: "/var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/artifacts"
2025-10-14 07:04:34.234796 | controller -> localhost | ok: "/var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/docs"
2025-10-14 07:04:34.244170 | 
2025-10-14 07:04:34.244274 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-14 07:04:34.808182 | controller | ok
2025-10-14 07:04:34.808396 | controller | ok: All items complete
2025-10-14 07:04:34.808433 | 
2025-10-14 07:04:35.218846 | controller | ok
2025-10-14 07:04:35.651325 | controller | ok
2025-10-14 07:04:35.673078 | 
2025-10-14 07:04:35.673182 | TASK [include_role : fetch-output-openshift]
2025-10-14 07:04:35.686974 | controller | skipping: Conditional result was False
2025-10-14 07:04:35.693309 | 
2025-10-14 07:04:35.693398 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-14 07:04:36.060553 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008310
2025-10-14 07:04:36.264686 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007399
2025-10-14 07:04:36.291461 | 
2025-10-14 07:04:36.291582 | PLAY [all]
2025-10-14 07:04:36.305537 | 
2025-10-14 07:04:36.305609 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-14 07:04:36.782132 | controller | changed
2025-10-14 07:04:36.803820 | 
2025-10-14 07:04:36.803898 | PLAY RECAP
2025-10-14 07:04:36.803975 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-14 07:04:36.804003 | 
2025-10-14 07:04:36.890999 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-14 07:04:36.891930 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-14 07:04:37.475892 | 
2025-10-14 07:04:37.476022 | PLAY [localhost]
2025-10-14 07:04:37.494485 | 
2025-10-14 07:04:37.494560 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-14 07:04:37.832117 | localhost | changed
2025-10-14 07:04:37.836930 | 
2025-10-14 07:04:37.837019 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-14 07:04:37.855827 | localhost | ok
2025-10-14 07:04:37.864257 | 
2025-10-14 07:04:37.864341 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-14 07:04:38.165743 | localhost | changed
2025-10-14 07:04:38.171900 | 
2025-10-14 07:04:38.172027 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-14 07:04:38.727359 | localhost | changed
2025-10-14 07:04:38.732561 | 
2025-10-14 07:04:38.732632 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-14 07:04:39.084756 | localhost | Identity added: /var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/tmp/ansible.03us3x10 (/var/lib/zuul/builds/003e3f39e55e4984a750cdb408071976/work/tmp/ansible.03us3x10)
2025-10-14 07:04:39.084965 | localhost | ok: Runtime: 0:00:00.006774
2025-10-14 07:04:39.089734 | 
2025-10-14 07:04:39.089809 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-14 07:04:39.330491 | localhost | ok: Runtime: 0:00:00.004458
2025-10-14 07:04:39.335058 | 
2025-10-14 07:04:39.335132 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-14 07:04:39.384719 | localhost | changed
2025-10-14 07:04:39.389301 | 
2025-10-14 07:04:39.389397 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-14 07:04:39.711316 | localhost | changed
2025-10-14 07:04:39.730870 | 
2025-10-14 07:04:39.730955 | PLAY [localhost]
2025-10-14 07:04:39.742322 | 
2025-10-14 07:04:39.742395 | TASK [Generate bulk log download script]
2025-10-14 07:04:39.759800 | localhost | ok
2025-10-14 07:04:39.770819 | 
2025-10-14 07:04:39.770889 | TASK [local-log-download : Check API endpoint is defined]
2025-10-14 07:04:39.798104 | localhost | ok: All assertions passed
2025-10-14 07:04:39.802388 | 
2025-10-14 07:04:39.802536 | TASK [local-log-download : Create download script]
2025-10-14 07:04:40.171808 | localhost -> localhost | changed
2025-10-14 07:04:40.180433 | 
2025-10-14 07:04:40.180501 | TASK [Register quick-download link]
2025-10-14 07:04:40.196889 | localhost | ok
2025-10-14 07:04:40.232805 | 
2025-10-14 07:04:40.232892 | PLAY [logserver.rdoproject.org]
2025-10-14 07:04:40.242453 | 
2025-10-14 07:04:40.242517 | TASK [Set zuul-log-path fact]
2025-10-14 07:04:40.258071 | logserver.rdoproject.org | ok
2025-10-14 07:04:40.266825 | 
2025-10-14 07:04:40.266903 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-14 07:04:40.292816 | logserver.rdoproject.org | ok
2025-10-14 07:04:40.298992 | 
2025-10-14 07:04:40.299102 | TASK [upload-logs : Create log directories]
2025-10-14 07:04:42.031868 | logserver.rdoproject.org | changed
2025-10-14 07:04:42.035639 | 
2025-10-14 07:04:42.035711 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-14 07:04:42.273226 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004728
2025-10-14 07:04:42.277958 | 
2025-10-14 07:04:42.278038 | TASK [upload-logs : Upload logs to log server]
2025-10-14 07:04:43.805902 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-14 07:04:43.809026 | 
2025-10-14 07:04:43.809094 | LOOP [upload-logs : Compress console log and json output]
2025-10-14 07:04:43.846607 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-14 07:04:43.854783 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-14 07:04:43.866511 | 
2025-10-14 07:04:43.866609 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-14 07:04:43.900476 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-14 07:04:43.900810 | 
2025-10-14 07:04:43.905651 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-14 07:04:43.914777 | 
2025-10-14 07:04:43.914874 | LOOP [upload-logs : Upload console log and json output]