2025-10-08 11:50:58.265135 | Job console starting...
2025-10-08 11:50:58.504167 | Updating repositories
2025-10-08 11:50:59.229930 | Preparing job workspace
2025-10-08 11:51:06.271824 | Running Ansible setup...
2025-10-08 11:51:10.229473 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 11:51:10.834703 | 
2025-10-08 11:51:10.834827 | PLAY [localhost]
2025-10-08 11:51:10.843498 | 
2025-10-08 11:51:10.843568 | TASK [Gathering Facts]
2025-10-08 11:51:12.048598 | localhost | ok
2025-10-08 11:51:12.062868 | 
2025-10-08 11:51:12.063157 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 11:51:12.590258 | localhost -> localhost | changed
2025-10-08 11:51:12.596070 | 
2025-10-08 11:51:12.596148 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 11:51:13.404299 | localhost -> localhost | changed
2025-10-08 11:51:13.415129 | 
2025-10-08 11:51:13.415218 | TASK [Setup log path fact]
2025-10-08 11:51:13.434252 | localhost | ok
2025-10-08 11:51:13.446856 | 
2025-10-08 11:51:13.446951 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 11:51:13.490370 | localhost | ok
2025-10-08 11:51:13.499272 | 
2025-10-08 11:51:13.499368 | TASK [emit-job-header : Print job information]
2025-10-08 11:51:13.539863 | # Job Information
2025-10-08 11:51:13.540049 | Ansible Version: 2.15.12
2025-10-08 11:51:13.540081 | Job: cifmw-molecule-openshift_setup
2025-10-08 11:51:13.540106 | Pipeline: github-check
2025-10-08 11:51:13.540126 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 11:51:13.540146 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237
2025-10-08 11:51:13.540167 | Log URL (when completed): https://logserver.rdoproject.org/4f3/rdoproject.org/4f3e9be44ff14da78d3a49a60695dcf4/
2025-10-08 11:51:13.540186 | Event ID: 920c7930-a408-11f0-96e0-244f77bacc6d
2025-10-08 11:51:13.543844 | 
2025-10-08 11:51:13.552447 | LOOP [emit-job-header : Print node information]
2025-10-08 11:51:13.640966 | localhost | ok:
2025-10-08 11:51:13.641120 | localhost | # Node Information
2025-10-08 11:51:13.641152 | localhost | Inventory Hostname: controller
2025-10-08 11:51:13.641177 | localhost | Hostname: np0005475917
2025-10-08 11:51:13.641199 | localhost | Username: zuul
2025-10-08 11:51:13.641220 | localhost | Distro: CentOS 9
2025-10-08 11:51:13.641238 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 11:51:13.641256 | localhost | Region: regionOne
2025-10-08 11:51:13.641275 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-10-08 11:51:13.641293 | localhost | Product Name: OpenStack Compute
2025-10-08 11:51:13.641312 | localhost | Interface IP: 192.168.26.123
2025-10-08 11:51:13.677849 | 
2025-10-08 11:51:13.677989 | PLAY [all]
2025-10-08 11:51:13.688819 | 
2025-10-08 11:51:13.688892 | TASK [Gather network facts]
2025-10-08 11:51:14.169947 | controller | ok
2025-10-08 11:51:14.195027 | 
2025-10-08 11:51:14.195142 | TASK [include_role : start-zuul-console]
2025-10-08 11:51:14.234372 | controller | ok
2025-10-08 11:51:14.246139 | 
2025-10-08 11:51:14.246226 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 11:51:14.672395 | controller | ok
2025-10-08 11:51:14.682447 | 
2025-10-08 11:51:14.682513 | TASK [include_role : add-build-sshkey]
2025-10-08 11:51:14.699921 | controller | ok
2025-10-08 11:51:14.712861 | 
2025-10-08 11:51:14.712949 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 11:51:14.931380 | controller -> localhost | ok
2025-10-08 11:51:14.937098 | 
2025-10-08 11:51:14.937178 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 11:51:14.976964 | controller | ok
2025-10-08 11:51:15.000626 | controller | included: /var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 11:51:15.006963 | 
2025-10-08 11:51:15.007039 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 11:51:15.459608 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 11:51:15.459827 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/4f3e9be44ff14da78d3a49a60695dcf4_id_rsa.
2025-10-08 11:51:15.459862 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/4f3e9be44ff14da78d3a49a60695dcf4_id_rsa.pub.
2025-10-08 11:51:15.459888 | controller -> localhost | The key fingerprint is:
2025-10-08 11:51:15.459932 | controller -> localhost | SHA256:6bEeLugQBesw59CLAYdMMr9gSIB3JI8JHbu0NPTP2z8 zuul-build-sshkey
2025-10-08 11:51:15.459962 | controller -> localhost | The key's randomart image is:
2025-10-08 11:51:15.459986 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 11:51:15.460007 | controller -> localhost | |@=*o.            |
2025-10-08 11:51:15.460028 | controller -> localhost | |BOoX.            |
2025-10-08 11:51:15.460047 | controller -> localhost | |Bo%.+            |
2025-10-08 11:51:15.460067 | controller -> localhost | |.& B o   .       |
2025-10-08 11:51:15.460086 | controller -> localhost | |. O   o S        |
2025-10-08 11:51:15.460104 | controller -> localhost | |   .   + o       |
2025-10-08 11:51:15.460123 | controller -> localhost | |  .  .. =        |
2025-10-08 11:51:15.460143 | controller -> localhost | |   .. .o oE      |
2025-10-08 11:51:15.460177 | controller -> localhost | |   ..  .o ..     |
2025-10-08 11:51:15.460199 | controller -> localhost | +----[SHA256]-----+
2025-10-08 11:51:15.460245 | controller -> localhost | ok: Runtime: 0:00:00.052770
2025-10-08 11:51:15.465800 | 
2025-10-08 11:51:15.465867 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 11:51:15.502070 | controller | ok
2025-10-08 11:51:15.516119 | controller | included: /var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 11:51:15.539380 | 
2025-10-08 11:51:15.539468 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 11:51:15.563718 | controller | skipping: Conditional result was False
2025-10-08 11:51:15.569345 | 
2025-10-08 11:51:15.569415 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 11:51:16.068818 | controller | changed
2025-10-08 11:51:16.073773 | 
2025-10-08 11:51:16.073846 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 11:51:16.384384 | controller | ok
2025-10-08 11:51:16.404432 | 
2025-10-08 11:51:16.404550 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 11:51:17.609368 | controller | changed
2025-10-08 11:51:17.614041 | 
2025-10-08 11:51:17.614111 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 11:51:18.778469 | controller | changed
2025-10-08 11:51:18.783570 | 
2025-10-08 11:51:18.783650 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 11:51:18.797950 | controller | skipping: Conditional result was False
2025-10-08 11:51:18.804091 | 
2025-10-08 11:51:18.804162 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 11:51:19.221440 | controller -> localhost | changed
2025-10-08 11:51:19.231645 | 
2025-10-08 11:51:19.231720 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 11:51:19.570457 | controller -> localhost | Identity added: /var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/4f3e9be44ff14da78d3a49a60695dcf4_id_rsa (zuul-build-sshkey)
2025-10-08 11:51:19.570672 | controller -> localhost | ok: Runtime: 0:00:00.013985
2025-10-08 11:51:19.581631 | 
2025-10-08 11:51:19.581701 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 11:51:20.080425 | controller | ok
2025-10-08 11:51:20.085646 | 
2025-10-08 11:51:20.085717 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 11:51:20.150804 | controller | skipping: Conditional result was False
2025-10-08 11:51:20.167555 | 
2025-10-08 11:51:20.167664 | TASK [include_role : validate-host]
2025-10-08 11:51:20.210217 | controller | ok
2025-10-08 11:51:20.270284 | 
2025-10-08 11:51:20.270385 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 11:51:20.330156 | controller | ok
2025-10-08 11:51:20.374270 | 
2025-10-08 11:51:20.374371 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 11:51:20.724609 | controller -> localhost | ok
2025-10-08 11:51:20.730211 | 
2025-10-08 11:51:20.730279 | TASK [validate-host : Collect information about the host]
2025-10-08 11:51:21.418743 | controller | ok
2025-10-08 11:51:21.431613 | 
2025-10-08 11:51:21.431687 | TASK [validate-host : Sanitize hostname]
2025-10-08 11:51:21.501011 | controller | ok
2025-10-08 11:51:21.506323 | 
2025-10-08 11:51:21.506416 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 11:51:22.245171 | controller -> localhost | changed
2025-10-08 11:51:22.251009 | 
2025-10-08 11:51:22.251082 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 11:51:22.726711 | controller | ok
2025-10-08 11:51:22.733013 | 
2025-10-08 11:51:22.733147 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 11:51:23.419200 | controller -> localhost | changed
2025-10-08 11:51:23.430893 | 
2025-10-08 11:51:23.430978 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 11:51:23.473051 | controller | skipping: Conditional result was False
2025-10-08 11:51:23.479297 | 
2025-10-08 11:51:23.479367 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 11:51:23.497311 | controller | skipping: Conditional result was False
2025-10-08 11:51:23.506414 | 
2025-10-08 11:51:23.506485 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 11:51:23.806400 | controller | ok: "logs"
2025-10-08 11:51:23.806610 | controller | ok: All items complete
2025-10-08 11:51:23.806642 | 
2025-10-08 11:51:24.054320 | controller | ok: "artifacts"
2025-10-08 11:51:24.329975 | controller | ok: "docs"
2025-10-08 11:51:24.344358 | 
2025-10-08 11:51:24.344439 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 11:51:24.612122 | controller | changed: "logs"
2025-10-08 11:51:24.856939 | controller | changed: "artifacts"
2025-10-08 11:51:25.121178 | controller | changed: "docs"
2025-10-08 11:51:25.163228 | 
2025-10-08 11:51:25.163322 | PLAY RECAP
2025-10-08 11:51:25.163369 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 11:51:25.163400 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 11:51:25.163420 | 
2025-10-08 11:51:25.251377 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 11:51:25.252113 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 11:51:25.970598 | 
2025-10-08 11:51:25.970713 | PLAY [all]
2025-10-08 11:51:26.005338 | 
2025-10-08 11:51:26.005424 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 11:51:26.099471 | controller | ok
2025-10-08 11:51:26.108560 | 
2025-10-08 11:51:26.108649 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 11:51:26.562607 | controller | changed
2025-10-08 11:51:26.568155 | 
2025-10-08 11:51:26.568230 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 11:51:28.037415 | controller | changed
2025-10-08 11:51:28.052219 | 
2025-10-08 11:51:28.052310 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 11:51:28.510640 | controller | changed:
2025-10-08 11:51:28.510804 | controller | {
2025-10-08 11:51:28.510838 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 11:51:28.510869 | controller | }
2025-10-08 11:51:28.785357 | controller | changed:
2025-10-08 11:51:28.785448 | controller | {
2025-10-08 11:51:28.785480 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 11:51:28.785504 | controller | }
2025-10-08 11:51:29.082897 | controller | changed:
2025-10-08 11:51:29.083001 | controller | {
2025-10-08 11:51:29.083030 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 11:51:29.083053 | controller | }
2025-10-08 11:51:29.385891 | controller | changed:
2025-10-08 11:51:29.385986 | controller | {
2025-10-08 11:51:29.386016 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 11:51:29.386037 | controller | }
2025-10-08 11:51:29.684590 | controller | changed:
2025-10-08 11:51:29.684775 | controller | {
2025-10-08 11:51:29.684813 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 11:51:29.684838 | controller | }
2025-10-08 11:51:29.972978 | controller | changed:
2025-10-08 11:51:29.973060 | controller | {
2025-10-08 11:51:29.973089 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 11:51:29.973110 | controller | }
2025-10-08 11:51:30.255839 | controller | changed:
2025-10-08 11:51:30.256478 | controller | {
2025-10-08 11:51:30.256522 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-08 11:51:30.256547 | controller | }
2025-10-08 11:51:30.539380 | controller | changed:
2025-10-08 11:51:30.539471 | controller | {
2025-10-08 11:51:30.539501 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-08 11:51:30.539524 | controller | }
2025-10-08 11:51:30.825176 | controller | changed:
2025-10-08 11:51:30.825257 | controller | {
2025-10-08 11:51:30.825285 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 11:51:30.825306 | controller | }
2025-10-08 11:51:31.127231 | controller | changed:
2025-10-08 11:51:31.127320 | controller | {
2025-10-08 11:51:31.127349 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 11:51:31.127370 | controller | }
2025-10-08 11:51:31.416095 | controller | changed:
2025-10-08 11:51:31.416184 | controller | {
2025-10-08 11:51:31.416212 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 11:51:31.416234 | controller | }
2025-10-08 11:51:31.712315 | controller | changed:
2025-10-08 11:51:31.712388 | controller | {
2025-10-08 11:51:31.712416 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 11:51:31.712438 | controller | }
2025-10-08 11:51:32.010581 | controller | changed:
2025-10-08 11:51:32.010679 | controller | {
2025-10-08 11:51:32.010727 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 11:51:32.010758 | controller | }
2025-10-08 11:51:32.314829 | controller | changed:
2025-10-08 11:51:32.314940 | controller | {
2025-10-08 11:51:32.314974 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 11:51:32.314997 | controller | }
2025-10-08 11:51:32.604289 | controller | changed:
2025-10-08 11:51:32.604377 | controller | {
2025-10-08 11:51:32.604404 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 11:51:32.604424 | controller | }
2025-10-08 11:51:32.898682 | controller | changed:
2025-10-08 11:51:32.898889 | controller | {
2025-10-08 11:51:32.898952 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 11:51:32.898977 | controller | }
2025-10-08 11:51:33.198850 | controller | changed:
2025-10-08 11:51:33.198945 | controller | {
2025-10-08 11:51:33.198983 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-08 11:51:33.199006 | controller | }
2025-10-08 11:51:33.518064 | controller | changed:
2025-10-08 11:51:33.518137 | controller | {
2025-10-08 11:51:33.518164 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-08 11:51:33.518193 | controller | }
2025-10-08 11:51:33.810608 | controller | changed:
2025-10-08 11:51:33.810701 | controller | {
2025-10-08 11:51:33.811031 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 11:51:33.811081 | controller | }
2025-10-08 11:51:34.096464 | controller | changed:
2025-10-08 11:51:34.096561 | controller | {
2025-10-08 11:51:34.096589 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 11:51:34.096611 | controller | }
2025-10-08 11:51:34.394264 | controller | changed:
2025-10-08 11:51:34.394364 | controller | {
2025-10-08 11:51:34.394393 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 11:51:34.394415 | controller | }
2025-10-08 11:51:34.682303 | controller | changed:
2025-10-08 11:51:34.682383 | controller | {
2025-10-08 11:51:34.682410 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 11:51:34.682432 | controller | }
2025-10-08 11:51:34.986259 | controller | changed:
2025-10-08 11:51:34.986335 | controller | {
2025-10-08 11:51:34.986362 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-08 11:51:34.986385 | controller | }
2025-10-08 11:51:35.290428 | controller | changed:
2025-10-08 11:51:35.290514 | controller | {
2025-10-08 11:51:35.290542 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 11:51:35.290564 | controller | }
2025-10-08 11:51:35.578166 | controller | changed:
2025-10-08 11:51:35.578246 | controller | {
2025-10-08 11:51:35.578273 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 11:51:35.578294 | controller | }
2025-10-08 11:51:35.883426 | controller | changed:
2025-10-08 11:51:35.883511 | controller | {
2025-10-08 11:51:35.883539 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 11:51:35.883560 | controller | }
2025-10-08 11:51:35.912667 | 
2025-10-08 11:51:35.912794 | TASK [Set timezone to UTC]
2025-10-08 11:51:36.602443 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 11:51:36.610099 | 
2025-10-08 11:51:36.610189 | TASK [Create nodepool directory]
2025-10-08 11:51:36.920977 | controller | changed
2025-10-08 11:51:36.929197 | 
2025-10-08 11:51:36.929281 | TASK [Create nodepool sub_nodes file]
2025-10-08 11:51:37.943768 | controller | changed
2025-10-08 11:51:37.949472 | 
2025-10-08 11:51:37.949547 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 11:51:38.901750 | controller | changed
2025-10-08 11:51:38.912817 | 
2025-10-08 11:51:38.912943 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 11:51:38.974496 | 
2025-10-08 11:51:38.974694 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 11:51:39.008431 | 
2025-10-08 11:51:39.008553 | TASK [Create nodepool primary file]
2025-10-08 11:51:39.052019 | controller | skipping: Conditional result was False
2025-10-08 11:51:39.057452 | 
2025-10-08 11:51:39.057525 | TASK [Create nodepool node_private for this node]
2025-10-08 11:51:40.150108 | controller | changed
2025-10-08 11:51:40.156510 | 
2025-10-08 11:51:40.156589 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 11:51:40.614988 | controller | ok: Item: id_rsa Runtime: 0:00:00.004536
2025-10-08 11:51:40.615159 | 
2025-10-08 11:51:40.836425 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004458
2025-10-08 11:51:40.858462 | 
2025-10-08 11:51:40.858557 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 11:51:41.926050 | controller | changed
2025-10-08 11:51:41.934353 | 
2025-10-08 11:51:41.934424 | TASK [Validate sudoers config after edits]
2025-10-08 11:51:42.243936 | controller | /etc/sudoers: parsed OK
2025-10-08 11:51:42.244018 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 11:51:42.244029 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 11:51:42.244036 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 11:51:42.495502 | controller | ok: Runtime: 0:00:00.005488
2025-10-08 11:51:42.501755 | 
2025-10-08 11:51:42.501820 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 11:51:42.823280 | controller | SHELL=/bin/bash
2025-10-08 11:51:42.823321 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 11:51:42.823331 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 11:51:42.823337 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 11:51:42.823343 | controller | PWD=/home/zuul
2025-10-08 11:51:42.823349 | controller | ZUUL_PIPELINE=github-check
2025-10-08 11:51:42.823354 | controller | LOGNAME=zuul
2025-10-08 11:51:42.823360 | controller | XDG_SESSION_TYPE=tty
2025-10-08 11:51:42.823365 | controller | _=/usr/bin/env
2025-10-08 11:51:42.823370 | controller | MOTD_SHOWN=pam
2025-10-08 11:51:42.823376 | controller | HOME=/home/zuul
2025-10-08 11:51:42.823389 | controller | LANG=en_US.UTF-8
2025-10-08 11:51:42.823400 | controller | SSH_CONNECTION=192.168.26.12 53894 192.168.26.123 22
2025-10-08 11:51:42.823412 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-08 11:51:42.823433 | controller | ZUUL_CHANGE_IDS=3237,f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 11:51:42.823484 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 11:51:42.823493 | controller | XDG_SESSION_CLASS=user
2025-10-08 11:51:42.823499 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 11:51:42.823504 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 11:51:42.823509 | controller | USER=zuul
2025-10-08 11:51:42.823514 | controller | ZUUL_VOTING=True
2025-10-08 11:51:42.823520 | controller | BUILD_TIMEOUT=1800000
2025-10-08 11:51:42.823525 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 11:51:42.823530 | controller | SHLVL=1
2025-10-08 11:51:42.823535 | controller | ZUUL_PATCHSET=f73256b19c90fb94359fc527fcfcb675474058ed
2025-10-08 11:51:42.823541 | controller | XDG_SESSION_ID=1
2025-10-08 11:51:42.823546 | controller | ZUUL_BRANCH=main
2025-10-08 11:51:42.823551 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 11:51:42.823556 | controller | SSH_CLIENT=192.168.26.12 53894 22
2025-10-08 11:51:42.823562 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 11:51:42.823567 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 11:51:42.823572 | controller | which_declare=declare -f
2025-10-08 11:51:42.823588 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 11:51:42.823593 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 11:51:42.823599 | controller | ZUUL_CHANGE=3237
2025-10-08 11:51:42.823604 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 11:51:42.823609 | controller | ZUUL_UUID=4f3e9be44ff14da78d3a49a60695dcf4
2025-10-08 11:51:42.823616 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 11:51:42.823623 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 11:51:42.823628 | controller | }
2025-10-08 11:51:43.058795 | controller | ok: Runtime: 0:00:00.006085
2025-10-08 11:51:43.064379 | 
2025-10-08 11:51:43.064462 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 11:51:43.106822 | controller | skipping: Conditional result was False
2025-10-08 11:51:43.112847 | 
2025-10-08 11:51:43.112959 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 11:51:43.690668 | controller | skipping: Conditional result was False
2025-10-08 11:51:43.696620 | 
2025-10-08 11:51:43.696728 | TASK [Ensure legacy workspace directory]
2025-10-08 11:51:43.939683 | controller | changed
2025-10-08 11:51:43.959537 | 
2025-10-08 11:51:43.959616 | PLAY RECAP
2025-10-08 11:51:43.959665 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 11:51:43.959690 | 
2025-10-08 11:51:44.049644 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 11:51:44.050820 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 11:51:44.720998 | 
2025-10-08 11:51:44.721092 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 11:51:44.765668 | 
2025-10-08 11:51:44.765826 | TASK [Create zuul-output directory]
2025-10-08 11:51:45.312537 | controller | changed
2025-10-08 11:51:45.317818 | 
2025-10-08 11:51:45.317894 | TASK [Slurp Zuul inventory test]
2025-10-08 11:51:45.688248 | controller -> localhost | ok
2025-10-08 11:51:45.700576 | 
2025-10-08 11:51:45.700685 | TASK [Save zuul inventory]
2025-10-08 11:51:47.337288 | controller | changed
2025-10-08 11:51:47.344776 | 
2025-10-08 11:51:47.344938 | TASK [Save zuul vars without the change_message]
2025-10-08 11:51:48.342675 | controller | changed
2025-10-08 11:51:48.395840 | 
2025-10-08 11:51:48.395922 | PLAY RECAP
2025-10-08 11:51:48.395974 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 11:51:48.396000 | 
2025-10-08 11:51:48.560512 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 11:51:48.561249 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 11:51:49.512082 | 
2025-10-08 11:51:49.512189 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 11:51:49.551689 | 
2025-10-08 11:51:49.551787 | TASK [Read group_vars]
2025-10-08 11:51:49.580218 | controller | ok
2025-10-08 11:51:49.601295 | 
2025-10-08 11:51:49.601396 | TASK [cifmw_helpers : Check directory is available]
2025-10-08 11:51:50.183283 | controller | ok
2025-10-08 11:51:50.191738 | 
2025-10-08 11:51:50.191823 | TASK [cifmw_helpers : List dir in ansible user home]
2025-10-08 11:51:50.644809 | controller | var one
2025-10-08 11:51:50.646292 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory
2025-10-08 11:51:50.647735 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-08 11:51:50.649099 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied
2025-10-08 11:51:50.650499 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-08 11:51:50.728811 | controller | ERROR
2025-10-08 11:51:50.728945 | controller | {
2025-10-08 11:51:50.728982 | controller |   "delta": "0:00:00.009787",
2025-10-08 11:51:50.729007 | controller |   "end": "2025-10-08 11:51:50.650854",
2025-10-08 11:51:50.729029 | controller |   "msg": "non-zero return code",
2025-10-08 11:51:50.729054 | controller |   "rc": 2,
2025-10-08 11:51:50.729077 | controller |   "start": "2025-10-08 11:51:50.641067"
2025-10-08 11:51:50.729097 | controller | }
2025-10-08 11:51:50.729122 | controller | ERROR: Ignoring Errors
2025-10-08 11:51:50.735121 | 
2025-10-08 11:51:50.735195 | TASK [cifmw_helpers : List available files]
2025-10-08 11:51:51.269625 | controller | skipping: Conditional result was False
2025-10-08 11:51:51.276157 | 
2025-10-08 11:51:51.276247 | LOOP [cifmw_helpers : Read vars]
2025-10-08 11:51:51.316239 | 
2025-10-08 11:51:51.316368 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-08 11:51:51.351272 | 
2025-10-08 11:51:51.351422 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 11:51:51.382590 | controller | ok
2025-10-08 11:51:51.465614 | 
2025-10-08 11:51:51.465734 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 11:51:51.504552 | controller | skipping: Conditional result was False
2025-10-08 11:51:51.510288 | 
2025-10-08 11:51:51.510358 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 11:51:52.007194 | controller | ok
2025-10-08 11:51:52.015554 | 
2025-10-08 11:51:52.015632 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 11:51:52.882681 | controller | ok
2025-10-08 11:51:52.910310 | 
2025-10-08 11:51:52.910391 | TASK [Prepare workspace]
2025-10-08 11:51:52.961132 | controller | ok
2025-10-08 11:51:53.027658 | 
2025-10-08 11:51:53.027759 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 11:51:53.420422 | controller | ok
2025-10-08 11:51:53.426510 | 
2025-10-08 11:51:53.426592 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 11:51:56.001139 | controller | Output suppressed because no_log was given
2025-10-08 11:51:56.011039 | 
2025-10-08 11:51:56.011112 | LOOP [Create zuul-output directory]
2025-10-08 11:51:56.306841 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 11:51:56.307074 | 
2025-10-08 11:51:56.537018 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 11:51:56.551342 | 
2025-10-08 11:51:56.551452 | TASK [Install required packages]
2025-10-08 11:52:22.491538 | controller | ok: Nothing to do
2025-10-08 11:52:22.498676 | 
2025-10-08 11:52:22.498753 | TASK [Install venv]
2025-10-08 11:53:20.885607 | controller | changed
2025-10-08 11:53:20.913557 | 
2025-10-08 11:53:20.913684 | PLAY RECAP
2025-10-08 11:53:20.913772 | controller | ok: 9 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 11:53:20.913814 | 
2025-10-08 11:53:21.008205 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 11:53:21.008990 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 11:53:21.575660 | 
2025-10-08 11:53:21.575762 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 11:53:21.597207 | 
2025-10-08 11:53:21.597298 | TASK [Gather required facts]
2025-10-08 11:53:22.144626 | controller | ok
2025-10-08 11:53:22.151366 | 
2025-10-08 11:53:22.151441 | TASK [Load environment var if instructed to]
2025-10-08 11:53:22.175218 | controller | skipping: Conditional result was False
2025-10-08 11:53:22.181586 | 
2025-10-08 11:53:22.181669 | TASK [Run molecule]
2025-10-08 11:53:23.183141 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 11:53:23.253515 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 11:53:39.703217 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.703743 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.704233 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.704737 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.705272 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+f73256b1 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.0.0+f73256b1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.705773 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.706246 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.706727 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.707206 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.707679 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.708163 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.708638 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.709121 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.709599 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.710093 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/c9682b/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 11:53:39.719243 | controller | INFO     Running default > prepare
2025-10-08 11:53:40.379840 | controller |
2025-10-08 11:53:40.379891 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 11:53:40.380018 | controller |
2025-10-08 11:53:40.380114 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 11:53:40.380215 | controller | Wednesday 08 October 2025  11:53:40 +0000 (0:00:00.013)       0:00:00.013 *****
2025-10-08 11:53:41.228395 | controller | ok: [instance]
2025-10-08 11:53:41.228486 | controller |
2025-10-08 11:53:41.228502 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 11:53:41.228602 | controller | Wednesday 08 October 2025  11:53:41 +0000 (0:00:00.849)       0:00:00.862 *****
2025-10-08 11:53:41.247206 | controller | skipping: [instance]
2025-10-08 11:53:41.247293 | controller |
2025-10-08 11:53:41.247417 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 11:53:41.247540 | controller | Wednesday 08 October 2025  11:53:41 +0000 (0:00:00.019)       0:00:00.881 *****
2025-10-08 11:53:41.290072 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 11:53:41.290127 | controller |
2025-10-08 11:53:41.290296 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 11:53:41.290382 | controller | Wednesday 08 October 2025  11:53:41 +0000 (0:00:00.042)       0:00:00.923 *****
2025-10-08 11:53:41.573732 | controller | ok: [instance]
2025-10-08 11:53:41.573828 | controller |
2025-10-08 11:53:41.573939 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 11:53:41.574044 | controller | Wednesday 08 October 2025  11:53:41 +0000 (0:00:00.283)       0:00:01.207 *****
2025-10-08 11:53:41.886357 | controller | ok: [instance]
2025-10-08 11:53:41.886415 | controller |
2025-10-08 11:53:41.886541 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 11:53:41.886655 | controller | Wednesday 08 October 2025  11:53:41 +0000 (0:00:00.312)       0:00:01.520 *****
2025-10-08 11:53:42.561946 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 11:53:42.562031 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 11:53:42.562139 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 11:53:42.562238 | controller |
2025-10-08 11:53:42.562340 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 11:53:42.562432 | controller | Wednesday 08 October 2025  11:53:42 +0000 (0:00:00.675)       0:00:02.195 *****
2025-10-08 11:53:42.621786 | controller |
2025-10-08 11:53:42.621900 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 11:53:42.622004 | controller | Wednesday 08 October 2025  11:53:42 +0000 (0:00:00.059)       0:00:02.255 *****
2025-10-08 11:53:43.165608 | controller | changed: [instance] => (item=tmp)
2025-10-08 11:53:43.165672 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 11:53:43.165821 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 11:53:43.165914 | controller |
2025-10-08 11:53:43.166014 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 11:53:43.166112 | controller | Wednesday 08 October 2025  11:53:43 +0000 (0:00:00.543)       0:00:02.799 *****
2025-10-08 11:53:43.973470 | controller | ok: [instance]
2025-10-08 11:53:45.139856 | controller |
2025-10-08 11:53:45.139889 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 11:53:45.139903 | controller | Wednesday 08 October 2025  11:53:43 +0000 (0:00:00.807)       0:00:03.606 *****
2025-10-08 11:53:45.139915 | controller | changed: [instance]
2025-10-08 11:53:53.130285 | controller |
2025-10-08 11:53:53.130324 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 11:53:53.130333 | controller | Wednesday 08 October 2025  11:53:45 +0000 (0:00:01.166)       0:00:04.773 *****
2025-10-08 11:53:53.130343 | controller | changed: [instance]
2025-10-08 11:53:53.871763 | controller |
2025-10-08 11:53:53.871804 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 11:53:53.871812 | controller | Wednesday 08 October 2025  11:53:53 +0000 (0:00:07.990)       0:00:12.763 *****
2025-10-08 11:53:53.871840 | controller | changed: [instance]
2025-10-08 11:53:53.893611 | controller |
2025-10-08 11:53:53.893629 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 11:53:53.893636 | controller | Wednesday 08 October 2025  11:53:53 +0000 (0:00:00.741)       0:00:13.505 *****
2025-10-08 11:53:53.893645 | controller | skipping: [instance]
2025-10-08 11:53:54.738893 | controller |
2025-10-08 11:53:54.738931 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 11:53:54.738940 | controller | Wednesday 08 October 2025  11:53:53 +0000 (0:00:00.022)       0:00:13.527 *****
2025-10-08 11:53:54.738951 | controller | changed: [instance]
2025-10-08 11:53:54.777460 | controller |
2025-10-08 11:53:54.777482 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 11:53:54.777491 | controller | Wednesday 08 October 2025  11:53:54 +0000 (0:00:00.844)       0:00:14.372 *****
2025-10-08 11:53:54.777501 | controller | skipping: [instance]
2025-10-08 11:53:54.813830 | controller |
2025-10-08 11:53:54.813870 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 11:53:54.813880 | controller | Wednesday 08 October 2025  11:53:54 +0000 (0:00:00.038)       0:00:14.411 *****
2025-10-08 11:53:54.813891 | controller | skipping: [instance]
2025-10-08 11:53:54.814330 | controller |
2025-10-08 11:53:54.814346 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 11:53:54.814356 | controller | Wednesday 08 October 2025  11:53:54 +0000 (0:00:00.036)       0:00:14.447 *****
2025-10-08 11:53:54.849365 | controller | skipping: [instance]
2025-10-08 11:53:55.433467 | controller |
2025-10-08 11:53:55.433499 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 11:53:55.433507 | controller | Wednesday 08 October 2025  11:53:54 +0000 (0:00:00.035)       0:00:14.483 *****
2025-10-08 11:53:55.433516 | controller | changed: [instance]
2025-10-08 11:53:55.915808 | controller |
2025-10-08 11:53:55.915845 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 11:53:55.915866 | controller | Wednesday 08 October 2025  11:53:55 +0000 (0:00:00.583)       0:00:15.066 *****
2025-10-08 11:53:55.915878 | controller | changed: [instance]
2025-10-08 11:53:55.945441 | controller |
2025-10-08 11:53:55.945474 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 11:53:55.945482 | controller | Wednesday 08 October 2025  11:53:55 +0000 (0:00:00.482)       0:00:15.549 *****
2025-10-08 11:53:55.945491 | controller | skipping: [instance]
2025-10-08 11:53:55.975194 | controller |
2025-10-08 11:53:55.975228 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 11:53:55.975237 | controller | Wednesday 08 October 2025  11:53:55 +0000 (0:00:00.029)       0:00:15.579 *****
2025-10-08 11:53:55.975254 | controller | skipping: [instance]
2025-10-08 11:53:56.004404 | controller |
2025-10-08 11:53:56.004436 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 11:53:56.004444 | controller | Wednesday 08 October 2025  11:53:55 +0000 (0:00:00.029)       0:00:15.608 *****
2025-10-08 11:53:56.004454 | controller | skipping: [instance]
2025-10-08 11:53:56.042831 | controller |
2025-10-08 11:53:56.042882 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 11:53:56.042892 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.029)       0:00:15.638 *****
2025-10-08 11:53:56.042906 | controller | ok: [instance]
2025-10-08 11:53:56.070472 | controller |
2025-10-08 11:53:56.070498 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 11:53:56.070507 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.038)       0:00:15.676 *****
2025-10-08 11:53:56.070518 | controller | skipping: [instance]
2025-10-08 11:53:56.098682 | controller |
2025-10-08 11:53:56.098735 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 11:53:56.098748 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.027)       0:00:15.704 *****
2025-10-08 11:53:56.098768 | controller | skipping: [instance]
2025-10-08 11:53:56.099119 | controller |
2025-10-08 11:53:56.099260 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 11:53:56.099383 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.028)       0:00:15.732 *****
2025-10-08 11:53:56.125942 | controller | skipping: [instance]
2025-10-08 11:53:56.126055 | controller |
2025-10-08 11:53:56.126234 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 11:53:56.126360 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.027)       0:00:15.759 *****
2025-10-08 11:53:56.151938 | controller | skipping: [instance]
2025-10-08 11:53:56.152077 | controller |
2025-10-08 11:53:56.152219 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 11:53:56.152362 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.026)       0:00:15.786 *****
2025-10-08 11:53:56.178138 | controller | skipping: [instance]
2025-10-08 11:53:56.178244 | controller |
2025-10-08 11:53:56.178388 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 11:53:56.178559 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.026)       0:00:15.812 *****
2025-10-08 11:53:56.206287 | controller | skipping: [instance]
2025-10-08 11:53:56.206409 | controller |
2025-10-08 11:53:56.206561 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 11:53:56.206717 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.028)       0:00:15.840 *****
2025-10-08 11:53:56.232273 | controller | skipping: [instance]
2025-10-08 11:53:56.232419 | controller |
2025-10-08 11:53:56.232570 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 11:53:56.232728 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.025)       0:00:15.866 *****
2025-10-08 11:53:56.416176 | controller | ok: [instance]
2025-10-08 11:53:56.416303 | controller |
2025-10-08 11:53:56.416428 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 11:53:56.416550 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.183)       0:00:16.050 *****
2025-10-08 11:53:56.628691 | controller | changed: [instance]
2025-10-08 11:53:56.856127 | controller |
2025-10-08 11:53:56.856158 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 11:53:56.856166 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.212)       0:00:16.262 *****
2025-10-08 11:53:56.856176 | controller | changed: [instance]
2025-10-08 11:53:56.877609 | controller |
2025-10-08 11:53:56.877638 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 11:53:56.877647 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.227)       0:00:16.489 *****
2025-10-08 11:53:56.877659 | controller | skipping: [instance]
2025-10-08 11:53:56.899792 | controller |
2025-10-08 11:53:56.899824 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 11:53:56.899832 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.021)       0:00:16.511 *****
2025-10-08 11:53:56.899843 | controller | skipping: [instance]
2025-10-08 11:53:56.922407 | controller |
2025-10-08 11:53:56.922439 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 11:53:56.922446 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.022)       0:00:16.533 *****
2025-10-08 11:53:56.922462 | controller | skipping: [instance]
2025-10-08 11:53:56.944820 | controller |
2025-10-08 11:53:56.944857 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 11:53:56.944867 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.022)       0:00:16.555 *****
2025-10-08 11:53:56.944899 | controller | skipping: [instance]
2025-10-08 11:53:56.966800 | controller |
2025-10-08 11:53:56.966835 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 11:53:56.966843 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.022)       0:00:16.578 *****
2025-10-08 11:53:56.966854 | controller | skipping: [instance]
2025-10-08 11:53:56.966891 | controller |
2025-10-08 11:53:56.966900 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 11:53:56.966990 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.022)       0:00:16.600 *****
2025-10-08 11:53:56.988734 | controller | skipping: [instance]
2025-10-08 11:53:56.988896 | controller |
2025-10-08 11:53:56.989043 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 11:53:56.989180 | controller | Wednesday 08 October 2025  11:53:56 +0000 (0:00:00.022)       0:00:16.622 *****
2025-10-08 11:53:57.005483 | controller | skipping: [instance]
2025-10-08 11:53:57.005628 | controller |
2025-10-08 11:53:57.005804 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 11:53:57.005989 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.016)       0:00:16.639 *****
2025-10-08 11:53:57.034294 | controller | skipping: [instance]
2025-10-08 11:53:57.034385 | controller |
2025-10-08 11:53:57.034507 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 11:53:57.034640 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.028)       0:00:16.668 *****
2025-10-08 11:53:57.050821 | controller | skipping: [instance]
2025-10-08 11:53:57.050986 | controller |
2025-10-08 11:53:57.051132 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 11:53:57.051276 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.016)       0:00:16.684 *****
2025-10-08 11:53:57.071594 | controller | skipping: [instance]
2025-10-08 11:53:57.071719 | controller |
2025-10-08 11:53:57.071924 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 11:53:57.072065 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.020)       0:00:16.705 *****
2025-10-08 11:53:57.101199 | controller | skipping: [instance]
2025-10-08 11:53:57.101372 | controller |
2025-10-08 11:53:57.101502 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 11:53:57.101656 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.029)       0:00:16.735 *****
2025-10-08 11:53:57.130983 | controller | skipping: [instance]
2025-10-08 11:53:57.131093 | controller |
2025-10-08 11:53:57.131213 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 11:53:57.131335 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.029)       0:00:16.764 *****
2025-10-08 11:53:57.160393 | controller | skipping: [instance]
2025-10-08 11:53:57.160502 | controller |
2025-10-08 11:53:57.160657 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 11:53:57.160771 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.029)       0:00:16.794 *****
2025-10-08 11:53:57.192339 | controller | skipping: [instance]
2025-10-08 11:53:57.192458 | controller |
2025-10-08 11:53:57.192581 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 11:53:57.192720 | controller | Wednesday 08 October 2025  11:53:57 +0000 (0:00:00.031)       0:00:16.826 *****
2025-10-08 11:54:45.869813 | controller | ok: [instance]
2025-10-08 11:54:45.869857 | controller |
2025-10-08 11:54:45.869865 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 11:54:45.869874 | controller | Wednesday 08 October 2025  11:54:45 +0000 (0:00:48.677)       0:01:05.503 *****
2025-10-08 11:54:47.090810 | controller | ok: [instance]
2025-10-08 11:54:47.090857 | controller |
2025-10-08 11:54:47.090867 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 11:54:47.090968 | controller | Wednesday 08 October 2025  11:54:47 +0000 (0:00:01.221)       0:01:06.724 *****
2025-10-08 11:54:47.128149 | controller | skipping: [instance]
2025-10-08 11:57:34.026956 | controller |
2025-10-08 11:57:34.027013 | controller | TASK [Ensure CRC is started] ***************************************************
2025-10-08 11:57:34.027022 | controller | Wednesday 08 October 2025  11:54:47 +0000 (0:00:00.037)       0:01:06.761 *****
2025-10-08 11:57:34.027035 | controller | changed: [instance]
2025-10-08 11:57:34.027056 | controller |
2025-10-08 11:57:34.027064 | controller | PLAY RECAP *********************************************************************
2025-10-08 11:57:34.027121 | controller | instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-08 11:57:34.027225 | controller |
2025-10-08 11:57:34.027313 | controller | Wednesday 08 October 2025  11:57:34 +0000 (0:02:46.898)       0:03:53.660 *****
2025-10-08 11:57:34.027413 | controller | ===============================================================================
2025-10-08 11:57:34.027517 | controller | Ensure CRC is started ------------------------------------------------- 166.90s
2025-10-08 11:57:34.027603 | controller | test_deps : Install selinux python libs -------------------------------- 48.68s
2025-10-08 11:57:34.027728 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.99s
2025-10-08 11:57:34.027831 | controller | test_deps : Install python yaml libs ------------------------------------ 1.22s
2025-10-08 11:57:34.027933 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.17s
2025-10-08 11:57:34.028028 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 11:57:34.028119 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.84s
2025-10-08 11:57:34.028242 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.81s
2025-10-08 11:57:34.028344 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s
2025-10-08 11:57:34.028446 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-08 11:57:34.028538 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-10-08 11:57:34.028662 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-08 11:57:34.028797 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.48s
2025-10-08 11:57:34.028908 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-08 11:57:34.029002 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-10-08 11:57:34.029100 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-08 11:57:34.029209 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-10-08 11:57:34.029308 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-08 11:57:34.029405 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-08 11:57:34.029506 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-10-08 11:57:34.085934 | controller | INFO     Running default > converge
2025-10-08 11:57:34.547832 | controller |
2025-10-08 11:57:35.423313 | controller | PLAY [Converge] ****************************************************************
2025-10-08 11:57:35.423347 | controller |
2025-10-08 11:57:35.423355 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 11:57:35.423361 | controller | Wednesday 08 October 2025  11:57:34 +0000 (0:00:00.017)       0:00:00.017 *****
2025-10-08 11:57:35.423370 | controller | ok: [instance]
2025-10-08 11:57:35.733824 | controller |
2025-10-08 11:57:35.733861 | controller | TASK [openshift_setup : Ensure output directory exists] ************************
2025-10-08 11:57:35.733883 | controller | Wednesday 08 October 2025  11:57:35 +0000 (0:00:00.875)       0:00:00.893 *****
2025-10-08 11:57:35.733896 | controller | ok: [instance]
2025-10-08 11:57:35.773270 | controller |
2025-10-08 11:57:35.773320 | controller | TASK [openshift_setup : Fetch namespaces to create] ****************************
2025-10-08 11:57:35.773329 | controller | Wednesday 08 October 2025  11:57:35 +0000 (0:00:00.310)       0:00:01.204 *****
2025-10-08 11:57:35.773342 | controller | ok: [instance]
2025-10-08 11:57:35.773369 | controller |
2025-10-08 11:57:35.773378 | controller | TASK [openshift_setup : Create required namespaces] ****************************
2025-10-08 11:57:35.773490 | controller | Wednesday 08 October 2025  11:57:35 +0000 (0:00:00.039)       0:00:01.244 *****
2025-10-08 11:57:37.222719 | controller | changed: [instance] => (item=openstack)
2025-10-08 11:57:37.222753 | controller | changed: [instance] => (item=openstack-operators)
2025-10-08 11:57:37.222764 | controller |
2025-10-08 11:57:37.223019 | controller | TASK [openshift_setup : Get internal OpenShift registry route] *****************
2025-10-08 11:57:49.201289 | controller | Wednesday 08 October 2025  11:57:37 +0000 (0:00:01.449)       0:00:02.693 *****
2025-10-08 11:57:49.201352 | controller | ok: [instance]
2025-10-08 11:57:49.201380 | controller |
2025-10-08 11:57:49.201389 | controller | TASK [openshift_setup : Allow anonymous image-pulls in CRC registry for targeted namespaces] ***
2025-10-08 11:57:49.201517 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:11.978)       0:00:14.671 *****
2025-10-08 11:57:49.250138 | controller | skipping: [instance] => (item=openstack)
2025-10-08 11:57:49.250241 | controller | skipping: [instance] => (item=openstack-operators)
2025-10-08 11:57:49.250261 | controller | skipping: [instance]
2025-10-08 11:57:49.250277 | controller |
2025-10-08 11:57:49.250420 | controller | TASK [openshift_setup : Wait for the image registry to be ready] ***************
2025-10-08 11:57:49.250456 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.048)       0:00:14.720 *****
2025-10-08 11:57:49.277924 | controller | skipping: [instance]
2025-10-08 11:57:49.278000 | controller |
2025-10-08 11:57:49.278019 | controller | TASK [openshift_setup : Login into OpenShift internal registry] ****************
2025-10-08 11:57:49.278118 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.028)       0:00:14.748 *****
2025-10-08 11:57:49.301212 | controller | skipping: [instance]
2025-10-08 11:57:49.301340 | controller |
2025-10-08 11:57:49.301351 | controller | TASK [Ensure we have custom CA installed on host] ******************************
2025-10-08 11:57:49.301360 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.023)       0:00:14.772 *****
2025-10-08 11:57:49.337229 | controller |
2025-10-08 11:57:49.337333 | controller | TASK [install_ca : Ensure target directory exists] *****************************
2025-10-08 11:57:49.337350 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.036)       0:00:14.808 *****
2025-10-08 11:57:49.566187 | controller | ok: [instance]
2025-10-08 11:57:49.566274 | controller |
2025-10-08 11:57:49.566457 | controller | TASK [install_ca : Install internal CA from url] *******************************
2025-10-08 11:57:49.594310 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.228)       0:00:15.036 *****
2025-10-08 11:57:49.594362 | controller | skipping: [instance]
2025-10-08 11:57:49.594389 | controller |
2025-10-08 11:57:49.594400 | controller | TASK [install_ca : Install custom CA bundle from inline] ***********************
2025-10-08 11:57:49.594523 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.028)       0:00:15.065 *****
2025-10-08 11:57:49.622314 | controller | skipping: [instance]
2025-10-08 11:57:49.622356 | controller |
2025-10-08 11:57:49.622364 | controller | TASK [install_ca : Install custom CA bundle from file] *************************
2025-10-08 11:57:49.622375 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.027)       0:00:15.093 *****
2025-10-08 11:57:49.652200 | controller | skipping: [instance]
2025-10-08 11:57:50.923747 | controller |
2025-10-08 11:57:50.923803 | controller | TASK [install_ca : Update ca bundle] *******************************************
2025-10-08 11:57:50.923813 | controller | Wednesday 08 October 2025  11:57:49 +0000 (0:00:00.029)       0:00:15.122 *****
2025-10-08 11:57:50.923825 | controller | changed: [instance]
2025-10-08 11:57:50.924078 | controller |
2025-10-08 11:57:52.033733 | controller | TASK [openshift_setup : Update ca bundle] **************************************
2025-10-08 11:57:52.033768 | controller | Wednesday 08 October 2025  11:57:50 +0000 (0:00:01.271)       0:00:16.394 *****
2025-10-08 11:57:52.033781 | controller | changed: [instance]
2025-10-08 11:57:52.333544 | controller |
2025-10-08 11:57:52.333579 | controller | TASK [openshift_setup : Slurp CAs file] ****************************************
2025-10-08 11:57:52.333587 | controller | Wednesday 08 October 2025  11:57:52 +0000 (0:00:01.109)       0:00:17.504 *****
2025-10-08 11:57:52.333598 | controller | ok: [instance]
2025-10-08 11:57:53.051592 | controller |
2025-10-08 11:57:53.051631 | controller | TASK [openshift_setup : Create config map with registry CAs] *******************
2025-10-08 11:57:53.051640 | controller | Wednesday 08 October 2025  11:57:52 +0000 (0:00:00.300)       0:00:17.804 *****
2025-10-08 11:57:53.051652 | controller | changed: [instance]
2025-10-08 11:57:53.730812 | controller |
2025-10-08 11:57:53.730864 | controller | TASK [openshift_setup : Install Red Hat CA for pulling images from internal registry] ***
2025-10-08 11:57:53.730877 | controller | Wednesday 08 October 2025  11:57:53 +0000 (0:00:00.717)       0:00:18.522 *****
2025-10-08 11:57:53.730891 | controller | changed: [instance]
2025-10-08 11:57:53.755798 | controller |
2025-10-08 11:57:53.755842 | controller | TASK [openshift_setup : Add insecure registry] *********************************
2025-10-08 11:57:53.755856 | controller | Wednesday 08 October 2025  11:57:53 +0000 (0:00:00.678)       0:00:19.201 *****
2025-10-08 11:57:53.755873 | controller | skipping: [instance]
2025-10-08 11:57:54.499527 | controller |
2025-10-08 11:57:54.499567 | controller | TASK [openshift_setup : Create a ICSP with repository digest mirrors] **********
2025-10-08 11:57:54.499582 | controller | Wednesday 08 October 2025  11:57:53 +0000 (0:00:00.025)       0:00:19.226 *****
2025-10-08 11:57:54.499597 | controller | changed: [instance]
2025-10-08 11:57:54.529720 | controller |
2025-10-08 11:57:54.529757 | controller | TASK [openshift_setup : Metal3 tweaks] *****************************************
2025-10-08 11:57:54.529771 | controller | Wednesday 08 October 2025  11:57:54 +0000 (0:00:00.743)       0:00:19.969 *****
2025-10-08 11:57:54.529782 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_setup/tasks/metal3_config.yml for instance
2025-10-08 11:57:54.561075 | controller |
2025-10-08 11:57:54.561109 | controller | TASK [openshift_setup : Fetch Metal3 configuration name] ***********************
2025-10-08 11:57:54.561124 | controller | Wednesday 08 October 2025  11:57:54 +0000 (0:00:00.030)       0:00:20.000 *****
2025-10-08 11:57:54.561145 | controller | skipping: [instance]
2025-10-08 11:57:54.591960 | controller |
2025-10-08 11:57:54.591997 | controller | TASK [openshift_setup : Apply the patch to Metal3 Provisioning] ****************
2025-10-08 11:57:54.592005 | controller | Wednesday 08 October 2025  11:57:54 +0000 (0:00:00.031)       0:00:20.031 *****
2025-10-08 11:57:54.592021 | controller | skipping: [instance]
2025-10-08 11:57:55.304643 | controller |
2025-10-08 11:57:55.304681 | controller | TASK [openshift_setup : Gather network.operator info] **************************
2025-10-08 11:57:55.304694 | controller | Wednesday 08 October 2025  11:57:54 +0000 (0:00:00.030)       0:00:20.062 *****
2025-10-08 11:57:55.304724 | controller | ok: [instance]
2025-10-08 11:57:56.231634 | controller |
2025-10-08 11:57:56.231674 | controller | TASK [openshift_setup : Patch network operator] ********************************
2025-10-08 11:57:56.231683 | controller | Wednesday 08 October 2025  11:57:55 +0000 (0:00:00.712)       0:00:20.774 *****
2025-10-08 11:57:56.231694 | controller | changed: [instance]
2025-10-08 11:57:56.947395 | controller |
2025-10-08 11:57:56.947429 | controller | TASK [openshift_setup : Patch samples registry configuration] ******************
2025-10-08 11:57:56.947437 | controller | Wednesday 08 October 2025  11:57:56 +0000 (0:00:00.926)       0:00:21.701 *****
2025-10-08 11:57:56.947447 | controller | changed: [instance]
2025-10-08 11:57:56.969528 | controller |
2025-10-08 11:57:56.969561 | controller | TASK [openshift_setup : Delete the pods from openshift-marketplace namespace] ***
2025-10-08 11:57:56.969569 | controller | Wednesday 08 October 2025  11:57:56 +0000 (0:00:00.715)       0:00:22.417 *****
2025-10-08 11:57:56.969579 | controller | skipping: [instance]
2025-10-08 11:57:56.995517 | controller |
2025-10-08 11:57:56.995547 | controller | TASK [openshift_setup : Wait for openshift-marketplace pods to be running] *****
2025-10-08 11:57:56.995555 | controller | Wednesday 08 October 2025  11:57:56 +0000 (0:00:00.021)       0:00:22.439 *****
2025-10-08 11:57:56.995573 | controller | skipping: [instance]
2025-10-08 11:57:57.719669 | controller |
2025-10-08 11:57:57.719733 | controller | TASK [Check that config map is created] ****************************************
2025-10-08 11:57:57.719743 | controller | Wednesday 08 October 2025  11:57:56 +0000 (0:00:00.026)       0:00:22.466 *****
2025-10-08 11:57:57.719754 | controller | ok: [instance]
2025-10-08 11:57:57.745022 | controller |
2025-10-08 11:57:57.745050 | controller | TASK [Assert that configmap was created] ***************************************
2025-10-08 11:57:57.745059 | controller | Wednesday 08 October 2025  11:57:57 +0000 (0:00:00.720)       0:00:23.186 *****
2025-10-08 11:57:57.745068 | controller | ok: [instance] => changed=false
2025-10-08 11:57:58.533985 | controller |   msg: All assertions passed
2025-10-08 11:57:58.534042 | controller |
2025-10-08 11:57:58.534054 | controller | TASK [Check that Image cluster is patched] *************************************
2025-10-08 11:57:58.534061 | controller | Wednesday 08 October 2025  11:57:57 +0000 (0:00:00.028)       0:00:23.215 *****
2025-10-08 11:57:58.534071 | controller | ok: [instance]
2025-10-08 11:57:58.566443 | controller |
2025-10-08 11:57:58.566498 | controller | TASK [Assert that cluster was patched] *****************************************
2025-10-08 11:57:58.566511 | controller | Wednesday 08 October 2025  11:57:58 +0000 (0:00:00.783)       0:00:23.999 *****
2025-10-08 11:57:58.566522 | controller | ok: [instance] => changed=false
2025-10-08 11:57:59.326464 | controller |   msg: All assertions passed
2025-10-08 11:57:59.326510 | controller |
2025-10-08 11:57:59.326519 | controller | TASK [Check that registry mirror resource is created] **************************
2025-10-08 11:57:59.326526 | controller | Wednesday 08 October 2025  11:57:58 +0000 (0:00:00.033)       0:00:24.033 *****
2025-10-08 11:57:59.326537 | controller | ok: [instance]
2025-10-08 11:57:59.367982 | controller |
2025-10-08 11:57:59.368020 | controller | TASK [Assert that digest mirrors are correct] **********************************
2025-10-08 11:57:59.368028 | controller | Wednesday 08 October 2025  11:57:59 +0000 (0:00:00.763)       0:00:24.796 *****
2025-10-08 11:57:59.368039 | controller | ok: [instance] => changed=false
2025-10-08 11:57:59.429828 | controller |   msg: All assertions passed
2025-10-08 11:57:59.429869 | controller |
2025-10-08 11:57:59.429882 | controller | PLAY RECAP *********************************************************************
2025-10-08 11:57:59.429889 | controller | instance                   : ok=22   changed=8    unreachable=0    failed=0    skipped=11   rescued=0    ignored=0
2025-10-08 11:57:59.429895 | controller |
2025-10-08 11:57:59.429914 | controller | Wednesday 08 October 2025  11:57:59 +0000 (0:00:00.038)       0:00:24.835 *****
2025-10-08 11:57:59.429920 | controller | ===============================================================================
2025-10-08 11:57:59.429925 | controller | openshift_setup : Get internal OpenShift registry route ---------------- 11.98s
2025-10-08 11:57:59.429931 | controller | openshift_setup : Create required namespaces ---------------------------- 1.45s
2025-10-08 11:57:59.429936 | controller | install_ca : Update ca bundle ------------------------------------------- 1.27s
2025-10-08 11:57:59.429946 | controller | openshift_setup : Update ca bundle -------------------------------------- 1.11s
2025-10-08 11:57:59.429952 | controller | openshift_setup : Patch network operator -------------------------------- 0.93s
2025-10-08 11:57:59.429957 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-10-08 11:57:59.429962 | controller | Check that Image cluster is patched ------------------------------------- 0.78s
2025-10-08 11:57:59.429967 | controller | Check that registry mirror resource is created -------------------------- 0.76s
2025-10-08 11:57:59.429972 | controller | openshift_setup : Create a ICSP with repository digest mirrors ---------- 0.74s
2025-10-08 11:57:59.429977 | controller | Check that config map is created ---------------------------------------- 0.72s
2025-10-08 11:57:59.429982 | controller | openshift_setup : Create config map with registry CAs ------------------- 0.72s
2025-10-08 11:57:59.429987 | controller | openshift_setup : Patch samples registry configuration ------------------ 0.72s
2025-10-08 11:57:59.429993 | controller | openshift_setup : Gather network.operator info -------------------------- 0.71s
2025-10-08 11:57:59.429998 | controller | openshift_setup : Install Red Hat CA for pulling images from internal registry --- 0.68s
2025-10-08 11:57:59.430003 | controller | openshift_setup : Ensure output directory exists ------------------------ 0.31s
2025-10-08 11:57:59.430008 | controller | openshift_setup : Slurp CAs file ---------------------------------------- 0.30s
2025-10-08 11:57:59.430013 | controller | install_ca : Ensure target directory exists ----------------------------- 0.23s
2025-10-08 11:57:59.430019 | controller | openshift_setup : Allow anonymous image-pulls in CRC registry for targeted namespaces --- 0.05s
2025-10-08 11:57:59.430024 | controller | openshift_setup : Fetch namespaces to create ---------------------------- 0.04s
2025-10-08 11:57:59.430029 | controller | Assert that digest mirrors are correct ---------------------------------- 0.04s
2025-10-08 11:57:59.430042 | controller | INFO     Running default > cleanup
2025-10-08 11:57:59.430295 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 11:57:59.431135 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 11:57:59.548049 | controller | changed
2025-10-08 11:57:59.635999 | 
2025-10-08 11:57:59.636111 | PLAY RECAP
2025-10-08 11:57:59.636159 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 11:57:59.636184 | 
2025-10-08 11:57:59.801122 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 11:57:59.801875 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 11:58:00.664550 | 
2025-10-08 11:58:00.664656 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 11:58:00.700524 | 
2025-10-08 11:58:00.700640 | TASK [Filter out host if needed]
2025-10-08 11:58:00.713318 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 11:58:00.721623 | 
2025-10-08 11:58:00.721701 | TASK [Ensure file is present]
2025-10-08 11:58:01.418094 | controller | ok
2025-10-08 11:58:01.425805 | 
2025-10-08 11:58:01.425889 | TASK [Manage molecule report file]
2025-10-08 11:58:02.485461 | controller | changed
2025-10-08 11:58:02.491563 | 
2025-10-08 11:58:02.491643 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 11:58:02.788272 | controller | ok
2025-10-08 11:58:02.794137 | 
2025-10-08 11:58:02.794206 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 11:58:03.293599 | controller | changed
2025-10-08 11:58:03.300687 | 
2025-10-08 11:58:03.300756 | TASK [Copy ci-framework interesting files]
2025-10-08 11:58:03.617825 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 11:58:03.854983 | controller | changed
2025-10-08 11:58:03.860265 | 
2025-10-08 11:58:03.860333 | TASK [Get SELinux listing]
2025-10-08 11:58:04.422313 | controller | changed
2025-10-08 11:58:04.437153 | 
2025-10-08 11:58:04.437238 | TASK [Generate log index]
2025-10-08 11:58:06.336113 | controller | changed
2025-10-08 11:58:06.345458 | 
2025-10-08 11:58:06.345560 | TASK [Get some env related data]
2025-10-08 11:58:07.282178 | controller | /home/zuul/.local/bin/ansible
2025-10-08 11:58:07.930673 | controller | changed
2025-10-08 11:58:07.935453 | 
2025-10-08 11:58:07.935541 | TASK [Generate list of logs to collect in home directory]
2025-10-08 11:58:08.415127 | controller | ok: All paths examined
2025-10-08 11:58:08.420836 | 
2025-10-08 11:58:08.420929 | LOOP [Copy logs from home directory]
2025-10-08 11:58:09.492312 | controller | changed:
2025-10-08 11:58:09.492509 | controller | {
2025-10-08 11:58:09.492539 | controller |   "atime": 1743544925.4788878,
2025-10-08 11:58:09.492560 | controller |   "ctime": 1743545329.1409318,
2025-10-08 11:58:09.492578 | controller |   "dev": 64513,
2025-10-08 11:58:09.492595 | controller |   "gid": 1000,
2025-10-08 11:58:09.492612 | controller |   "gr_name": "zuul",
2025-10-08 11:58:09.492629 | controller |   "inode": 4518807,
2025-10-08 11:58:09.492644 | controller |   "isblk": false,
2025-10-08 11:58:09.492659 | controller |   "ischr": false,
2025-10-08 11:58:09.492674 | controller |   "isdir": false,
2025-10-08 11:58:09.492688 | controller |   "isfifo": false,
2025-10-08 11:58:09.492702 | controller |   "isgid": false,
2025-10-08 11:58:09.492716 | controller |   "islnk": false,
2025-10-08 11:58:09.492730 | controller |   "isreg": true,
2025-10-08 11:58:09.492743 | controller |   "issock": false,
2025-10-08 11:58:09.492757 | controller |   "isuid": false,
2025-10-08 11:58:09.492771 | controller |   "mode": "0644",
2025-10-08 11:58:09.492790 | controller |   "mtime": 1743545329.1409318,
2025-10-08 11:58:09.492807 | controller |   "nlink": 1,
2025-10-08 11:58:09.492821 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 11:58:09.492837 | controller |   "pw_name": "zuul",
2025-10-08 11:58:09.492851 | controller |   "rgrp": true,
2025-10-08 11:58:09.492867 | controller |   "roth": true,
2025-10-08 11:58:09.492883 | controller |   "rusr": true,
2025-10-08 11:58:09.492898 | controller |   "size": 4108,
2025-10-08 11:58:09.492946 | controller |   "uid": 1000,
2025-10-08 11:58:09.492967 | controller |   "wgrp": false,
2025-10-08 11:58:09.492984 | controller |   "woth": false,
2025-10-08 11:58:09.493001 | controller |   "wusr": true,
2025-10-08 11:58:09.493017 | controller |   "xgrp": false,
2025-10-08 11:58:09.493248 | controller |   "xoth": false,
2025-10-08 11:58:09.493325 | controller |   "xusr": false
2025-10-08 11:58:09.493353 | controller | }
2025-10-08 11:58:10.413695 | controller | changed:
2025-10-08 11:58:10.413860 | controller | {
2025-10-08 11:58:10.413899 | controller |   "atime": 1743545331.0429637,
2025-10-08 11:58:10.413966 | controller |   "ctime": 1743545842.0111232,
2025-10-08 11:58:10.413989 | controller |   "dev": 64513,
2025-10-08 11:58:10.414007 | controller |   "gid": 1000,
2025-10-08 11:58:10.414034 | controller |   "gr_name": "zuul",
2025-10-08 11:58:10.414052 | controller |   "inode": 4194437,
2025-10-08 11:58:10.414068 | controller |   "isblk": false,
2025-10-08 11:58:10.414084 | controller |   "ischr": false,
2025-10-08 11:58:10.414099 | controller |   "isdir": false,
2025-10-08 11:58:10.414114 | controller |   "isfifo": false,
2025-10-08 11:58:10.414129 | controller |   "isgid": false,
2025-10-08 11:58:10.414143 | controller |   "islnk": false,
2025-10-08 11:58:10.414158 | controller |   "isreg": true,
2025-10-08 11:58:10.414173 | controller |   "issock": false,
2025-10-08 11:58:10.414188 | controller |   "isuid": false,
2025-10-08 11:58:10.414204 | controller |   "mode": "0644",
2025-10-08 11:58:10.414219 | controller |   "mtime": 1743545842.0111232,
2025-10-08 11:58:10.414235 | controller |   "nlink": 1,
2025-10-08 11:58:10.414250 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 11:58:10.414265 | controller |   "pw_name": "zuul",
2025-10-08 11:58:10.414281 | controller |   "rgrp": true,
2025-10-08 11:58:10.414298 | controller |   "roth": true,
2025-10-08 11:58:10.414314 | controller |   "rusr": true,
2025-10-08 11:58:10.414330 | controller |   "size": 4023,
2025-10-08 11:58:10.414346 | controller |   "uid": 1000,
2025-10-08 11:58:10.414361 | controller |   "wgrp": false,
2025-10-08 11:58:10.414376 | controller |   "woth": false,
2025-10-08 11:58:10.414390 | controller |   "wusr": true,
2025-10-08 11:58:10.414404 | controller |   "xgrp": false,
2025-10-08 11:58:10.414419 | controller |   "xoth": false,
2025-10-08 11:58:10.414434 | controller |   "xusr": false
2025-10-08 11:58:10.414448 | controller | }
2025-10-08 11:58:11.543928 | controller | changed:
2025-10-08 11:58:11.544031 | controller | {
2025-10-08 11:58:11.544068 | controller |   "atime": 1759924384.810601,
2025-10-08 11:58:11.544096 | controller |   "ctime": 1759924400.4470332,
2025-10-08 11:58:11.544120 | controller |   "dev": 64513,
2025-10-08 11:58:11.544144 | controller |   "gid": 1000,
2025-10-08 11:58:11.544166 | controller |   "gr_name": "zuul",
2025-10-08 11:58:11.544188 | controller |   "inode": 4340272,
2025-10-08 11:58:11.544209 | controller |   "isblk": false,
2025-10-08 11:58:11.544231 | controller |   "ischr": false,
2025-10-08 11:58:11.544252 | controller |   "isdir": false,
2025-10-08 11:58:11.544274 | controller |   "isfifo": false,
2025-10-08 11:58:11.544294 | controller |   "isgid": false,
2025-10-08 11:58:11.544314 | controller |   "islnk": false,
2025-10-08 11:58:11.544334 | controller |   "isreg": true,
2025-10-08 11:58:11.544354 | controller |   "issock": false,
2025-10-08 11:58:11.544374 | controller |   "isuid": false,
2025-10-08 11:58:11.544395 | controller |   "mode": "0644",
2025-10-08 11:58:11.544521 | controller |   "mtime": 1759924400.4470332,
2025-10-08 11:58:11.544555 | controller |   "nlink": 1,
2025-10-08 11:58:11.544577 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 11:58:11.544596 | controller |   "pw_name": "zuul",
2025-10-08 11:58:11.544613 | controller |   "rgrp": true,
2025-10-08 11:58:11.544630 | controller |   "roth": true,
2025-10-08 11:58:11.544649 | controller |   "rusr": true,
2025-10-08 11:58:11.544666 | controller |   "size": 6749,
2025-10-08 11:58:11.544682 | controller |   "uid": 1000,
2025-10-08 11:58:11.544698 | controller |   "wgrp": false,
2025-10-08 11:58:11.544713 | controller |   "woth": false,
2025-10-08 11:58:11.544728 | controller |   "wusr": true,
2025-10-08 11:58:11.544744 | controller |   "xgrp": false,
2025-10-08 11:58:11.544758 | controller |   "xoth": false,
2025-10-08 11:58:11.544772 | controller |   "xusr": false
2025-10-08 11:58:11.544787 | controller | }
2025-10-08 11:58:11.561834 | 
2025-10-08 11:58:11.562014 | TASK [Copy crio stats log file]
2025-10-08 11:58:11.606652 | controller | skipping: Conditional result was False
2025-10-08 11:58:11.612458 | 
2025-10-08 11:58:11.612538 | TASK [Get SELinux related data]
2025-10-08 11:58:12.393615 | controller | <no matches>
2025-10-08 11:58:12.658815 | controller | ERROR
2025-10-08 11:58:12.659053 | controller | {
2025-10-08 11:58:12.659089 | controller |   "delta": "0:00:00.013691",
2025-10-08 11:58:12.659112 | controller |   "end": "2025-10-08 11:58:12.397487",
2025-10-08 11:58:12.659132 | controller |   "msg": "non-zero return code",
2025-10-08 11:58:12.659150 | controller |   "rc": 1,
2025-10-08 11:58:12.659167 | controller |   "start": "2025-10-08 11:58:12.383796"
2025-10-08 11:58:12.659184 | controller | }
2025-10-08 11:58:12.659209 | controller | ERROR: Ignoring Errors
2025-10-08 11:58:12.664703 | 
2025-10-08 11:58:12.664772 | TASK [Create system configuration directory]
2025-10-08 11:58:13.062967 | controller | changed
2025-10-08 11:58:13.068167 | 
2025-10-08 11:58:13.068235 | TASK [Get some of the system configurations]
2025-10-08 11:58:13.620492 | controller | changed
2025-10-08 11:58:13.625651 | 
2025-10-08 11:58:13.625715 | TASK [Copy generated documentation if available]
2025-10-08 11:58:13.659867 | controller | skipping: Conditional result was False
2025-10-08 11:58:13.665979 | 
2025-10-08 11:58:13.666054 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 11:58:13.690040 | controller | skipping: Conditional result was False
2025-10-08 11:58:13.696092 | 
2025-10-08 11:58:13.696164 | TASK [Compress logs bigger than 2MB]
2025-10-08 11:58:14.744164 | controller | changed
2025-10-08 11:58:14.750026 | 
2025-10-08 11:58:14.750102 | TASK [Copy files from workspace on node]
2025-10-08 11:58:14.769616 | controller | ok
2025-10-08 11:58:14.801514 | 
2025-10-08 11:58:14.801628 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 11:58:14.836309 | controller | skipping: Conditional result was False
2025-10-08 11:58:14.842478 | 
2025-10-08 11:58:14.842568 | TASK [fetch-output : Set log path for single node]
2025-10-08 11:58:14.902301 | controller | ok
2025-10-08 11:58:14.911784 | 
2025-10-08 11:58:14.911872 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 11:58:15.293517 | controller -> localhost | ok: "/var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/logs"
2025-10-08 11:58:15.293749 | controller -> localhost | changed: All items complete
2025-10-08 11:58:15.293780 | 
2025-10-08 11:58:15.693853 | controller -> localhost | changed: "/var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/artifacts"
2025-10-08 11:58:15.968673 | controller -> localhost | changed: "/var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/docs"
2025-10-08 11:58:15.977737 | 
2025-10-08 11:58:15.977851 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 11:58:16.774283 | controller | changed:
2025-10-08 11:58:16.774443 | controller | .d..t...... ./
2025-10-08 11:58:16.774491 | controller | >f+++++++++ README.html
2025-10-08 11:58:16.774520 | controller | >f+++++++++ ansible-execution.log
2025-10-08 11:58:16.774545 | controller | >f+++++++++ ansible.log
2025-10-08 11:58:16.774566 | controller | >f+++++++++ crc-setup.log
2025-10-08 11:58:16.774584 | controller | >f+++++++++ crc-start.log
2025-10-08 11:58:16.774602 | controller | >f+++++++++ dmesg.log
2025-10-08 11:58:16.774620 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 11:58:16.774637 | controller | >f+++++++++ python.log
2025-10-08 11:58:16.774654 | controller | >f+++++++++ registries.conf
2025-10-08 11:58:16.774671 | controller | >f+++++++++ report.html
2025-10-08 11:58:16.774688 | controller | >f+++++++++ selinux-denials.log
2025-10-08 11:58:16.774705 | controller | >f+++++++++ selinux-listing.log
2025-10-08 11:58:16.774721 | controller | cd+++++++++ ci-framework-data/
2025-10-08 11:58:16.774737 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 11:58:16.774754 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 11:58:16.774771 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 11:58:16.774787 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 11:58:16.774804 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 11:58:16.774820 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 11:58:16.774837 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 11:58:16.774853 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 11:58:16.774869 | controller | cd+++++++++ registries.conf.d/
2025-10-08 11:58:16.774886 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 11:58:16.774903 | controller | cd+++++++++ system-config/
2025-10-08 11:58:16.774947 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 11:58:16.774969 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 11:58:16.774987 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 11:58:16.775004 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 11:58:16.775021 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 11:58:16.775038 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 11:58:16.775054 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 11:58:16.775071 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 11:58:16.775087 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 11:58:16.775103 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 11:58:16.775119 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 11:58:16.775135 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 11:58:16.775150 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 11:58:16.775166 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 11:58:16.775181 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 11:58:16.775196 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 11:58:16.775212 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 11:58:17.472668 | controller | changed: .d..t...... ./
2025-10-08 11:58:18.024036 | controller | changed: .d..t...... ./
2025-10-08 11:58:18.048158 | 
2025-10-08 11:58:18.048276 | TASK [Return artifact to Zuul]
2025-10-08 11:58:18.087746 | controller | ok
2025-10-08 11:58:18.115320 | 
2025-10-08 11:58:18.115393 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 11:58:18.115510 | 
2025-10-08 11:58:18.115538 | PLAY RECAP
2025-10-08 11:58:18.115577 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 11:58:18.115599 | 
2025-10-08 11:58:18.211750 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 11:58:18.212452 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 11:58:18.813268 | 
2025-10-08 11:58:18.813369 | PLAY [all]
2025-10-08 11:58:18.847049 | 
2025-10-08 11:58:18.847165 | TASK [include_role : fetch-output]
2025-10-08 11:58:18.887229 | controller | ok
2025-10-08 11:58:18.909900 | 
2025-10-08 11:58:18.910063 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 11:58:18.954394 | controller | skipping: Conditional result was False
2025-10-08 11:58:18.960472 | 
2025-10-08 11:58:18.960568 | TASK [fetch-output : Set log path for single node]
2025-10-08 11:58:18.989170 | controller | ok
2025-10-08 11:58:18.994593 | 
2025-10-08 11:58:18.994676 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 11:58:19.387072 | controller -> localhost | ok: "/var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/logs"
2025-10-08 11:58:19.627584 | controller -> localhost | ok: "/var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/artifacts"
2025-10-08 11:58:19.996835 | controller -> localhost | ok: "/var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/docs"
2025-10-08 11:58:20.015438 | 
2025-10-08 11:58:20.020925 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 11:58:20.958343 | controller | ok
2025-10-08 11:58:20.958566 | controller | ok: All items complete
2025-10-08 11:58:20.958597 | 
2025-10-08 11:58:21.604108 | controller | ok
2025-10-08 11:58:22.435061 | controller | ok
2025-10-08 11:58:22.459823 | 
2025-10-08 11:58:22.459954 | TASK [include_role : fetch-output-openshift]
2025-10-08 11:58:22.488090 | controller | skipping: Conditional result was False
2025-10-08 11:58:22.497348 | 
2025-10-08 11:58:22.497446 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 11:58:23.106299 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013014
2025-10-08 11:58:23.327807 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009299
2025-10-08 11:58:23.361924 | 
2025-10-08 11:58:23.362031 | PLAY [all]
2025-10-08 11:58:23.377729 | 
2025-10-08 11:58:23.377823 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 11:58:23.928949 | controller | changed
2025-10-08 11:58:23.956562 | 
2025-10-08 11:58:23.956662 | PLAY RECAP
2025-10-08 11:58:23.956713 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 11:58:23.957146 | 
2025-10-08 11:58:24.070994 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 11:58:24.072003 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 11:58:24.756577 | 
2025-10-08 11:58:24.756711 | PLAY [localhost]
2025-10-08 11:58:24.784521 | 
2025-10-08 11:58:24.784644 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 11:58:25.370699 | localhost | changed
2025-10-08 11:58:25.375367 | 
2025-10-08 11:58:25.375483 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 11:58:25.430251 | localhost | ok
2025-10-08 11:58:25.444832 | 
2025-10-08 11:58:25.444943 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 11:58:26.168076 | localhost | changed
2025-10-08 11:58:26.175088 | 
2025-10-08 11:58:26.175229 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 11:58:27.141286 | localhost | changed
2025-10-08 11:58:27.146482 | 
2025-10-08 11:58:27.146560 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 11:58:27.826704 | localhost | Identity added: /var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/tmp/ansible.aykm9uh7 (/var/lib/zuul/builds/4f3e9be44ff14da78d3a49a60695dcf4/work/tmp/ansible.aykm9uh7)
2025-10-08 11:58:27.826881 | localhost | ok: Runtime: 0:00:00.032241
2025-10-08 11:58:27.837231 | 
2025-10-08 11:58:27.841948 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 11:58:28.494998 | localhost | ok: Runtime: 0:00:00.026843
2025-10-08 11:58:28.500966 | 
2025-10-08 11:58:28.501069 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 11:58:28.607490 | localhost | changed
2025-10-08 11:58:28.612616 | 
2025-10-08 11:58:28.612735 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 11:58:29.235958 | localhost | changed
2025-10-08 11:58:29.279073 | 
2025-10-08 11:58:29.279162 | PLAY [localhost]
2025-10-08 11:58:29.299774 | 
2025-10-08 11:58:29.299883 | TASK [Generate bulk log download script]
2025-10-08 11:58:29.329145 | localhost | ok
2025-10-08 11:58:29.344805 | 
2025-10-08 11:58:29.344933 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 11:58:29.378522 | localhost | ok: All assertions passed
2025-10-08 11:58:29.391973 | 
2025-10-08 11:58:29.392069 | TASK [local-log-download : Create download script]
2025-10-08 11:58:29.999595 | localhost -> localhost | changed
2025-10-08 11:58:30.009025 | 
2025-10-08 11:58:30.009108 | TASK [Register quick-download link]
2025-10-08 11:58:30.028467 | localhost | ok
2025-10-08 11:58:30.052020 | 
2025-10-08 11:58:30.052116 | PLAY [logserver.rdoproject.org]
2025-10-08 11:58:30.063971 | 
2025-10-08 11:58:30.064061 | TASK [Set zuul-log-path fact]
2025-10-08 11:58:30.086612 | logserver.rdoproject.org | ok
2025-10-08 11:58:30.103530 | 
2025-10-08 11:58:30.103635 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 11:58:30.132041 | logserver.rdoproject.org | ok
2025-10-08 11:58:30.138325 | 
2025-10-08 11:58:30.138404 | TASK [upload-logs : Create log directories]
2025-10-08 11:58:32.014886 | logserver.rdoproject.org | changed
2025-10-08 11:58:32.018520 | 
2025-10-08 11:58:32.018590 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 11:58:32.509229 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.017222
2025-10-08 11:58:32.513943 | 
2025-10-08 11:58:32.514034 | TASK [upload-logs : Upload logs to log server]
2025-10-08 11:58:34.322038 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 11:58:34.325816 | 
2025-10-08 11:58:34.325895 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 11:58:34.387665 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 11:58:34.388899 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 11:58:34.394526 | 
2025-10-08 11:58:34.394603 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 11:58:34.483381 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 11:58:34.483615 | 
2025-10-08 11:58:34.484181 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 11:58:34.499133 | 
2025-10-08 11:58:34.499213 | LOOP [upload-logs : Upload console log and json output]