2025-10-08 16:08:55.575240 | Job console starting...
2025-10-08 16:08:55.824015 | Updating repositories
2025-10-08 16:08:56.636646 | Preparing job workspace
2025-10-08 16:09:06.092354 | Running Ansible setup...
2025-10-08 16:09:10.054899 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 16:09:10.636701 | 
2025-10-08 16:09:10.636800 | PLAY [localhost]
2025-10-08 16:09:10.646760 | 
2025-10-08 16:09:10.646884 | TASK [Gathering Facts]
2025-10-08 16:09:11.545267 | localhost | ok
2025-10-08 16:09:11.559561 | 
2025-10-08 16:09:11.559676 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 16:09:11.917643 | localhost -> localhost | changed
2025-10-08 16:09:11.923482 | 
2025-10-08 16:09:11.923571 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 16:09:12.645857 | localhost -> localhost | changed
2025-10-08 16:09:12.659521 | 
2025-10-08 16:09:12.659608 | TASK [Setup log path fact]
2025-10-08 16:09:12.676336 | localhost | ok
2025-10-08 16:09:12.687370 | 
2025-10-08 16:09:12.687439 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 16:09:12.704724 | localhost | ok
2025-10-08 16:09:12.712433 | 
2025-10-08 16:09:12.712501 | TASK [emit-job-header : Print job information]
2025-10-08 16:09:12.739727 | # Job Information
2025-10-08 16:09:12.739841 | Ansible Version: 2.15.12
2025-10-08 16:09:12.739869 | Job: cifmw-molecule-openshift_provisioner_node
2025-10-08 16:09:12.739892 | Pipeline: github-check
2025-10-08 16:09:12.739943 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 16:09:12.739969 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-08 16:09:12.739989 | Log URL (when completed): https://logserver.rdoproject.org/bb8/rdoproject.org/bb8ba8f3a06b4b899f127670b46e06e7/
2025-10-08 16:09:12.740007 | Event ID: b612d780-a45c-11f0-87cb-9ea07ef2b05c
2025-10-08 16:09:12.743374 | 
2025-10-08 16:09:12.743439 | LOOP [emit-job-header : Print node information]
2025-10-08 16:09:12.843533 | localhost | ok:
2025-10-08 16:09:12.843682 | localhost | # Node Information
2025-10-08 16:09:12.843712 | localhost | Inventory Hostname: controller
2025-10-08 16:09:12.843739 | localhost | Hostname: np0005477429
2025-10-08 16:09:12.843761 | localhost | Username: zuul
2025-10-08 16:09:12.843782 | localhost | Distro: CentOS 9
2025-10-08 16:09:12.843802 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 16:09:12.843822 | localhost | Region: regionOne
2025-10-08 16:09:12.843839 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-10-08 16:09:12.843856 | localhost | Product Name: OpenStack Compute
2025-10-08 16:09:12.843873 | localhost | Interface IP: 192.168.26.165
2025-10-08 16:09:12.866057 | 
2025-10-08 16:09:12.866121 | PLAY [all]
2025-10-08 16:09:12.872302 | 
2025-10-08 16:09:12.872367 | TASK [Gather network facts]
2025-10-08 16:09:13.301533 | controller | ok
2025-10-08 16:09:13.320305 | 
2025-10-08 16:09:13.320392 | TASK [include_role : start-zuul-console]
2025-10-08 16:09:13.339643 | controller | ok
2025-10-08 16:09:13.357288 | 
2025-10-08 16:09:13.357374 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 16:09:13.780968 | controller | ok
2025-10-08 16:09:13.790182 | 
2025-10-08 16:09:13.790265 | TASK [include_role : add-build-sshkey]
2025-10-08 16:09:13.811238 | controller | ok
2025-10-08 16:09:13.825128 | 
2025-10-08 16:09:13.825204 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 16:09:14.033794 | controller -> localhost | ok
2025-10-08 16:09:14.040357 | 
2025-10-08 16:09:14.040448 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 16:09:14.071678 | controller | ok
2025-10-08 16:09:14.084779 | controller | included: /var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 16:09:14.091105 | 
2025-10-08 16:09:14.091169 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 16:09:14.663458 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 16:09:14.663654 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/bb8ba8f3a06b4b899f127670b46e06e7_id_rsa.
2025-10-08 16:09:14.663688 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/bb8ba8f3a06b4b899f127670b46e06e7_id_rsa.pub.
2025-10-08 16:09:14.663710 | controller -> localhost | The key fingerprint is:
2025-10-08 16:09:14.663730 | controller -> localhost | SHA256:Gj+1u61PWeBA7yAFl10i3a1n0bZn1JA024X1Otilumk zuul-build-sshkey
2025-10-08 16:09:14.663749 | controller -> localhost | The key's randomart image is:
2025-10-08 16:09:14.663767 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 16:09:14.663785 | controller -> localhost | |        ..=+.+=*=|
2025-10-08 16:09:14.663802 | controller -> localhost | |         +.oo.+=O|
2025-10-08 16:09:14.663818 | controller -> localhost | |        . o o .+*|
2025-10-08 16:09:14.663834 | controller -> localhost | |         . = +.==|
2025-10-08 16:09:14.663851 | controller -> localhost | |      . S . + *+.|
2025-10-08 16:09:14.663867 | controller -> localhost | |       + . . + . |
2025-10-08 16:09:14.663882 | controller -> localhost | |      . o . +    |
2025-10-08 16:09:14.663898 | controller -> localhost | |         . +Eo   |
2025-10-08 16:09:14.663933 | controller -> localhost | |          +==    |
2025-10-08 16:09:14.663953 | controller -> localhost | +----[SHA256]-----+
2025-10-08 16:09:14.663995 | controller -> localhost | ok: Runtime: 0:00:00.200533
2025-10-08 16:09:14.671816 | 
2025-10-08 16:09:14.671992 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 16:09:14.695364 | controller | ok
2025-10-08 16:09:14.722493 | controller | included: /var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 16:09:14.733310 | 
2025-10-08 16:09:14.733403 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 16:09:14.747581 | controller | skipping: Conditional result was False
2025-10-08 16:09:14.757240 | 
2025-10-08 16:09:14.757316 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 16:09:15.280985 | controller | changed
2025-10-08 16:09:15.285824 | 
2025-10-08 16:09:15.285889 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 16:09:15.550658 | controller | ok
2025-10-08 16:09:15.556120 | 
2025-10-08 16:09:15.556245 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 16:09:16.714573 | controller | changed
2025-10-08 16:09:16.720536 | 
2025-10-08 16:09:16.720612 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 16:09:17.945041 | controller | changed
2025-10-08 16:09:17.950006 | 
2025-10-08 16:09:17.950071 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 16:09:17.973755 | controller | skipping: Conditional result was False
2025-10-08 16:09:17.979812 | 
2025-10-08 16:09:17.979882 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 16:09:18.375313 | controller -> localhost | changed
2025-10-08 16:09:18.387606 | 
2025-10-08 16:09:18.387693 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 16:09:18.705755 | controller -> localhost | Identity added: /var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/bb8ba8f3a06b4b899f127670b46e06e7_id_rsa (zuul-build-sshkey)
2025-10-08 16:09:18.705960 | controller -> localhost | ok: Runtime: 0:00:00.006724
2025-10-08 16:09:18.712599 | 
2025-10-08 16:09:18.712667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 16:09:19.118947 | controller | ok
2025-10-08 16:09:19.123791 | 
2025-10-08 16:09:19.123860 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 16:09:19.147958 | controller | skipping: Conditional result was False
2025-10-08 16:09:19.157211 | 
2025-10-08 16:09:19.157286 | TASK [include_role : validate-host]
2025-10-08 16:09:19.175200 | controller | ok
2025-10-08 16:09:19.193615 | 
2025-10-08 16:09:19.193685 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 16:09:19.222352 | controller | ok
2025-10-08 16:09:19.226875 | 
2025-10-08 16:09:19.226955 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 16:09:19.473712 | controller -> localhost | ok
2025-10-08 16:09:19.481096 | 
2025-10-08 16:09:19.481185 | TASK [validate-host : Collect information about the host]
2025-10-08 16:09:20.186425 | controller | ok
2025-10-08 16:09:20.195278 | 
2025-10-08 16:09:20.195343 | TASK [validate-host : Sanitize hostname]
2025-10-08 16:09:20.244380 | controller | ok
2025-10-08 16:09:20.249149 | 
2025-10-08 16:09:20.249228 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 16:09:20.744356 | controller -> localhost | changed
2025-10-08 16:09:20.750322 | 
2025-10-08 16:09:20.750409 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 16:09:21.146679 | controller | ok
2025-10-08 16:09:21.151536 | 
2025-10-08 16:09:21.151629 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 16:09:21.677530 | controller -> localhost | changed
2025-10-08 16:09:21.686899 | 
2025-10-08 16:09:21.686989 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 16:09:21.700754 | controller | skipping: Conditional result was False
2025-10-08 16:09:21.706765 | 
2025-10-08 16:09:21.706833 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 16:09:21.720116 | controller | skipping: Conditional result was False
2025-10-08 16:09:21.725485 | 
2025-10-08 16:09:21.725566 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 16:09:21.989721 | controller | ok: "logs"
2025-10-08 16:09:21.990058 | controller | ok: All items complete
2025-10-08 16:09:21.990143 | 
2025-10-08 16:09:22.231523 | controller | ok: "artifacts"
2025-10-08 16:09:22.507738 | controller | ok: "docs"
2025-10-08 16:09:22.519562 | 
2025-10-08 16:09:22.519654 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 16:09:22.859949 | controller | changed: "logs"
2025-10-08 16:09:23.075783 | controller | changed: "artifacts"
2025-10-08 16:09:23.309048 | controller | changed: "docs"
2025-10-08 16:09:23.339539 | 
2025-10-08 16:09:23.339645 | PLAY RECAP
2025-10-08 16:09:23.339692 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 16:09:23.339722 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 16:09:23.339742 | 
2025-10-08 16:09:23.421311 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 16:09:23.422077 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 16:09:24.224613 | 
2025-10-08 16:09:24.224711 | PLAY [all]
2025-10-08 16:09:24.264999 | 
2025-10-08 16:09:24.265114 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 16:09:24.369358 | controller | ok
2025-10-08 16:09:24.378039 | 
2025-10-08 16:09:24.378188 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 16:09:24.803726 | controller | changed
2025-10-08 16:09:24.811791 | 
2025-10-08 16:09:24.811866 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 16:09:26.153296 | controller | changed
2025-10-08 16:09:26.163785 | 
2025-10-08 16:09:26.163856 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 16:09:26.740560 | controller | changed:
2025-10-08 16:09:26.740730 | controller | {
2025-10-08 16:09:26.740835 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 16:09:26.740994 | controller | }
2025-10-08 16:09:27.024220 | controller | changed:
2025-10-08 16:09:27.024303 | controller | {
2025-10-08 16:09:27.024334 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 16:09:27.024358 | controller | }
2025-10-08 16:09:27.309981 | controller | changed:
2025-10-08 16:09:27.310055 | controller | {
2025-10-08 16:09:27.310088 | 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 16:09:27.310113 | controller | }
2025-10-08 16:09:27.605828 | controller | changed:
2025-10-08 16:09:27.606057 | controller | {
2025-10-08 16:09:27.606103 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 16:09:27.606130 | controller | }
2025-10-08 16:09:27.898802 | controller | changed:
2025-10-08 16:09:27.898878 | controller | {
2025-10-08 16:09:27.898927 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 16:09:27.898959 | controller | }
2025-10-08 16:09:28.182786 | controller | changed:
2025-10-08 16:09:28.182867 | controller | {
2025-10-08 16:09:28.182897 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 16:09:28.182948 | controller | }
2025-10-08 16:09:28.480714 | controller | changed:
2025-10-08 16:09:28.480792 | controller | {
2025-10-08 16:09:28.480901 | 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 16:09:28.480969 | controller | }
2025-10-08 16:09:28.771735 | controller | changed:
2025-10-08 16:09:28.771815 | controller | {
2025-10-08 16:09:28.771844 | 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 16:09:28.772201 | controller | }
2025-10-08 16:09:29.062452 | controller | changed:
2025-10-08 16:09:29.062702 | controller | {
2025-10-08 16:09:29.062737 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 16:09:29.062761 | controller | }
2025-10-08 16:09:29.349122 | controller | changed:
2025-10-08 16:09:29.349203 | controller | {
2025-10-08 16:09:29.349229 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 16:09:29.349249 | controller | }
2025-10-08 16:09:29.628948 | controller | changed:
2025-10-08 16:09:29.629025 | controller | {
2025-10-08 16:09:29.629052 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 16:09:29.629074 | controller | }
2025-10-08 16:09:29.908374 | controller | changed:
2025-10-08 16:09:29.908463 | controller | {
2025-10-08 16:09:29.908490 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 16:09:29.908512 | controller | }
2025-10-08 16:09:30.184568 | controller | changed:
2025-10-08 16:09:30.184649 | controller | {
2025-10-08 16:09:30.184678 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 16:09:30.184700 | controller | }
2025-10-08 16:09:30.463992 | controller | changed:
2025-10-08 16:09:30.464069 | controller | {
2025-10-08 16:09:30.464096 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 16:09:30.464118 | controller | }
2025-10-08 16:09:30.765059 | controller | changed:
2025-10-08 16:09:30.765147 | controller | {
2025-10-08 16:09:30.765176 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 16:09:30.765198 | controller | }
2025-10-08 16:09:31.056241 | controller | changed:
2025-10-08 16:09:31.056319 | controller | {
2025-10-08 16:09:31.056346 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 16:09:31.056366 | controller | }
2025-10-08 16:09:31.354150 | controller | changed:
2025-10-08 16:09:31.354261 | controller | {
2025-10-08 16:09:31.354290 | 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 16:09:31.354312 | controller | }
2025-10-08 16:09:31.632970 | controller | changed:
2025-10-08 16:09:31.633054 | controller | {
2025-10-08 16:09:31.633082 | 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 16:09:31.633110 | controller | }
2025-10-08 16:09:31.922957 | controller | changed:
2025-10-08 16:09:31.923034 | controller | {
2025-10-08 16:09:31.923063 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 16:09:31.923085 | controller | }
2025-10-08 16:09:32.206248 | controller | changed:
2025-10-08 16:09:32.206337 | controller | {
2025-10-08 16:09:32.206365 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 16:09:32.206845 | controller | }
2025-10-08 16:09:32.483538 | controller | changed:
2025-10-08 16:09:32.483621 | controller | {
2025-10-08 16:09:32.483649 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 16:09:32.483671 | controller | }
2025-10-08 16:09:32.776401 | controller | changed:
2025-10-08 16:09:32.776494 | controller | {
2025-10-08 16:09:32.776536 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 16:09:32.776560 | controller | }
2025-10-08 16:09:33.076948 | controller | changed:
2025-10-08 16:09:33.077035 | controller | {
2025-10-08 16:09:33.077062 | 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 16:09:33.077084 | controller | }
2025-10-08 16:09:33.355291 | controller | changed:
2025-10-08 16:09:33.355384 | controller | {
2025-10-08 16:09:33.355418 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 16:09:33.355445 | controller | }
2025-10-08 16:09:33.644062 | controller | changed:
2025-10-08 16:09:33.644138 | controller | {
2025-10-08 16:09:33.644165 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 16:09:33.644186 | controller | }
2025-10-08 16:09:33.931267 | controller | changed:
2025-10-08 16:09:33.931375 | controller | {
2025-10-08 16:09:33.931404 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 16:09:33.931427 | controller | }
2025-10-08 16:09:33.951346 | 
2025-10-08 16:09:33.951439 | TASK [Set timezone to UTC]
2025-10-08 16:09:34.456317 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 16:09:34.461378 | 
2025-10-08 16:09:34.461448 | TASK [Create nodepool directory]
2025-10-08 16:09:34.724840 | controller | changed
2025-10-08 16:09:34.730608 | 
2025-10-08 16:09:34.730705 | TASK [Create nodepool sub_nodes file]
2025-10-08 16:09:35.651708 | controller | changed
2025-10-08 16:09:35.657071 | 
2025-10-08 16:09:35.657142 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 16:09:36.558070 | controller | changed
2025-10-08 16:09:36.563948 | 
2025-10-08 16:09:36.564012 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 16:09:36.618039 | 
2025-10-08 16:09:36.618217 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 16:09:36.692368 | 
2025-10-08 16:09:36.692538 | TASK [Create nodepool primary file]
2025-10-08 16:09:36.716414 | controller | skipping: Conditional result was False
2025-10-08 16:09:36.721723 | 
2025-10-08 16:09:36.721795 | TASK [Create nodepool node_private for this node]
2025-10-08 16:09:37.735320 | controller | changed
2025-10-08 16:09:37.742767 | 
2025-10-08 16:09:37.742883 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 16:09:38.168924 | controller | ok: Item: id_rsa Runtime: 0:00:00.004566
2025-10-08 16:09:38.169098 | 
2025-10-08 16:09:38.400237 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004648
2025-10-08 16:09:38.409975 | 
2025-10-08 16:09:38.410083 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 16:09:39.338378 | controller | changed
2025-10-08 16:09:39.344166 | 
2025-10-08 16:09:39.344305 | TASK [Validate sudoers config after edits]
2025-10-08 16:09:39.661745 | controller | /etc/sudoers: parsed OK
2025-10-08 16:09:39.661858 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 16:09:39.661870 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 16:09:39.661876 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 16:09:39.876173 | controller | ok: Runtime: 0:00:00.005108
2025-10-08 16:09:39.881789 | 
2025-10-08 16:09:39.881858 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 16:09:40.154491 | controller | SHELL=/bin/bash
2025-10-08 16:09:40.154531 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 16:09:40.154541 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 16:09:40.154549 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/85/3385/f8a3c957fc51bd892aee4b1f66ce262682944823^openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/127d45eecba72e2daf3d33e73e380ccf7b6e7649
2025-10-08 16:09:40.154557 | controller | PWD=/home/zuul
2025-10-08 16:09:40.154562 | controller | ZUUL_PIPELINE=github-check
2025-10-08 16:09:40.154567 | controller | LOGNAME=zuul
2025-10-08 16:09:40.154615 | controller | XDG_SESSION_TYPE=tty
2025-10-08 16:09:40.154623 | controller | _=/usr/bin/env
2025-10-08 16:09:40.154628 | controller | MOTD_SHOWN=pam
2025-10-08 16:09:40.154634 | controller | HOME=/home/zuul
2025-10-08 16:09:40.154639 | controller | LANG=en_US.UTF-8
2025-10-08 16:09:40.154644 | controller | SSH_CONNECTION=192.168.26.12 52542 192.168.26.165 22
2025-10-08 16:09:40.154650 | 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 16:09:40.154655 | controller | ZUUL_CHANGE_IDS=3385,f8a3c957fc51bd892aee4b1f66ce262682944823 3368,127d45eecba72e2daf3d33e73e380ccf7b6e7649
2025-10-08 16:09:40.154661 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 16:09:40.154666 | controller | XDG_SESSION_CLASS=user
2025-10-08 16:09:40.154671 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 16:09:40.154676 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 16:09:40.154682 | controller | USER=zuul
2025-10-08 16:09:40.154687 | controller | ZUUL_VOTING=True
2025-10-08 16:09:40.154692 | controller | BUILD_TIMEOUT=1800000
2025-10-08 16:09:40.154697 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 16:09:40.154703 | controller | SHLVL=1
2025-10-08 16:09:40.154708 | controller | ZUUL_PATCHSET=127d45eecba72e2daf3d33e73e380ccf7b6e7649
2025-10-08 16:09:40.154713 | controller | XDG_SESSION_ID=1
2025-10-08 16:09:40.154718 | controller | ZUUL_BRANCH=main
2025-10-08 16:09:40.154724 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 16:09:40.154729 | controller | SSH_CLIENT=192.168.26.12 52542 22
2025-10-08 16:09:40.154734 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 16:09:40.154740 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 16:09:40.154745 | controller | which_declare=declare -f
2025-10-08 16:09:40.154751 | 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 16:09:40.154756 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 16:09:40.154761 | controller | ZUUL_CHANGE=3368
2025-10-08 16:09:40.154767 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 16:09:40.154772 | controller | ZUUL_UUID=bb8ba8f3a06b4b899f127670b46e06e7
2025-10-08 16:09:40.154777 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 16:09:40.154782 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 16:09:40.154788 | controller | }
2025-10-08 16:09:40.408361 | controller | ok: Runtime: 0:00:00.005713
2025-10-08 16:09:40.414017 | 
2025-10-08 16:09:40.414165 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 16:09:40.427597 | controller | skipping: Conditional result was False
2025-10-08 16:09:40.433312 | 
2025-10-08 16:09:40.433393 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 16:09:41.022394 | controller | skipping: Conditional result was False
2025-10-08 16:09:41.028645 | 
2025-10-08 16:09:41.028714 | TASK [Ensure legacy workspace directory]
2025-10-08 16:09:41.304232 | controller | changed
2025-10-08 16:09:41.337421 | 
2025-10-08 16:09:41.337490 | PLAY RECAP
2025-10-08 16:09:41.337553 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 16:09:41.337580 | 
2025-10-08 16:09:41.415423 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 16:09:41.416241 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 16:09:41.981020 | 
2025-10-08 16:09:41.981121 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 16:09:42.008292 | 
2025-10-08 16:09:42.008421 | TASK [Create zuul-output directory]
2025-10-08 16:09:42.408416 | controller | changed
2025-10-08 16:09:42.415894 | 
2025-10-08 16:09:42.415993 | TASK [Slurp Zuul inventory test]
2025-10-08 16:09:42.810314 | controller -> localhost | ok
2025-10-08 16:09:42.818501 | 
2025-10-08 16:09:42.818631 | TASK [Save zuul inventory]
2025-10-08 16:09:44.353621 | controller | changed
2025-10-08 16:09:44.359142 | 
2025-10-08 16:09:44.359223 | TASK [Save zuul vars without the change_message]
2025-10-08 16:09:45.413205 | controller | changed
2025-10-08 16:09:45.433924 | 
2025-10-08 16:09:45.434012 | PLAY RECAP
2025-10-08 16:09:45.434059 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 16:09:45.434083 | 
2025-10-08 16:09:45.518940 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 16:09:45.519689 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 16:09:46.124332 | 
2025-10-08 16:09:46.124451 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 16:09:46.146445 | 
2025-10-08 16:09:46.146558 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 16:09:46.175705 | controller | ok
2025-10-08 16:09:46.193006 | 
2025-10-08 16:09:46.193105 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 16:09:46.216757 | controller | skipping: Conditional result was False
2025-10-08 16:09:46.222416 | 
2025-10-08 16:09:46.222488 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 16:09:46.633363 | controller | ok
2025-10-08 16:09:46.639657 | 
2025-10-08 16:09:46.639744 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 16:09:47.632617 | controller | ok
2025-10-08 16:09:47.648563 | 
2025-10-08 16:09:47.648686 | TASK [Prepare workspace]
2025-10-08 16:09:47.668664 | controller | ok
2025-10-08 16:09:47.687990 | 
2025-10-08 16:09:47.688080 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 16:09:48.042577 | controller | ok
2025-10-08 16:09:48.048605 | 
2025-10-08 16:09:48.048670 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 16:09:50.547857 | controller | Output suppressed because no_log was given
2025-10-08 16:09:50.558797 | 
2025-10-08 16:09:50.558932 | LOOP [Create zuul-output directory]
2025-10-08 16:09:50.827670 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 16:09:51.040608 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 16:09:51.047668 | 
2025-10-08 16:09:51.047766 | TASK [Install required packages]
2025-10-08 16:10:08.103048 | controller | ok: Nothing to do
2025-10-08 16:10:08.109356 | 
2025-10-08 16:10:08.109428 | TASK [Install venv]
2025-10-08 16:11:07.255168 | controller | changed
2025-10-08 16:11:07.301528 | 
2025-10-08 16:11:07.301668 | PLAY RECAP
2025-10-08 16:11:07.301720 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 16:11:07.301745 | 
2025-10-08 16:11:07.379673 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 16:11:07.380492 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 16:11:07.908058 | 
2025-10-08 16:11:07.908156 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 16:11:07.928294 | 
2025-10-08 16:11:07.928371 | TASK [Gather required facts]
2025-10-08 16:11:08.486459 | controller | ok
2025-10-08 16:11:08.492294 | 
2025-10-08 16:11:08.492374 | TASK [Load environment var if instructed to]
2025-10-08 16:11:08.516169 | controller | skipping: Conditional result was False
2025-10-08 16:11:08.521649 | 
2025-10-08 16:11:08.521715 | TASK [Ensure group_vars dir exists]
2025-10-08 16:11:08.896460 | controller | ok
2025-10-08 16:11:08.901721 | 
2025-10-08 16:11:08.901789 | TASK [Run molecule]
2025-10-08 16:11:09.913897 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 16:11:09.984162 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 16:11:26.650365 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.650796 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.651306 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.651780 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.652322 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+4772ac9e was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0+4772ac9e (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.652804 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.653327 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.653800 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.654303 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.654777 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.655291 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.655756 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.656250 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.656715 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.657210 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:11:26.666524 | controller | INFO     Running default > prepare
2025-10-08 16:11:27.405440 | controller |
2025-10-08 16:11:27.405587 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 16:11:27.405620 | controller |
2025-10-08 16:11:27.405631 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 16:11:27.405747 | controller | Wednesday 08 October 2025  16:11:27 +0000 (0:00:00.028)       0:00:00.028 *****
2025-10-08 16:11:28.259271 | controller | ok: [instance]
2025-10-08 16:11:28.259311 | controller |
2025-10-08 16:11:28.259360 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 16:11:28.259481 | controller | Wednesday 08 October 2025  16:11:28 +0000 (0:00:00.854)       0:00:00.883 *****
2025-10-08 16:11:28.278118 | controller | skipping: [instance]
2025-10-08 16:11:28.278174 | controller |
2025-10-08 16:11:28.278324 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 16:11:28.278423 | controller | Wednesday 08 October 2025  16:11:28 +0000 (0:00:00.018)       0:00:00.902 *****
2025-10-08 16:11:28.322220 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 16:11:28.322287 | controller |
2025-10-08 16:11:28.322401 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 16:11:28.322497 | controller | Wednesday 08 October 2025  16:11:28 +0000 (0:00:00.044)       0:00:00.946 *****
2025-10-08 16:11:28.608651 | controller | ok: [instance]
2025-10-08 16:11:28.608710 | controller |
2025-10-08 16:11:28.608824 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 16:11:28.608947 | controller | Wednesday 08 October 2025  16:11:28 +0000 (0:00:00.286)       0:00:01.232 *****
2025-10-08 16:11:28.932225 | controller | ok: [instance]
2025-10-08 16:11:28.932276 | controller |
2025-10-08 16:11:28.932389 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 16:11:28.932496 | controller | Wednesday 08 October 2025  16:11:28 +0000 (0:00:00.323)       0:00:01.556 *****
2025-10-08 16:11:29.661108 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 16:11:29.661162 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 16:11:29.661289 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 16:11:29.661389 | controller |
2025-10-08 16:11:29.661482 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 16:11:29.661600 | controller | Wednesday 08 October 2025  16:11:29 +0000 (0:00:00.728)       0:00:02.285 *****
2025-10-08 16:11:29.722112 | controller |
2025-10-08 16:11:29.722178 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 16:11:29.722299 | controller | Wednesday 08 October 2025  16:11:29 +0000 (0:00:00.060)       0:00:02.346 *****
2025-10-08 16:11:30.275843 | controller | changed: [instance] => (item=tmp)
2025-10-08 16:11:30.275928 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 16:11:30.276046 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 16:11:30.276138 | controller |
2025-10-08 16:11:30.276234 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 16:11:30.276334 | controller | Wednesday 08 October 2025  16:11:30 +0000 (0:00:00.553)       0:00:02.899 *****
2025-10-08 16:11:31.112119 | controller | ok: [instance]
2025-10-08 16:11:32.434627 | controller |
2025-10-08 16:11:32.434668 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 16:11:32.434684 | controller | Wednesday 08 October 2025  16:11:31 +0000 (0:00:00.835)       0:00:03.735 *****
2025-10-08 16:11:32.434695 | controller | changed: [instance]
2025-10-08 16:11:40.590550 | controller |
2025-10-08 16:11:40.590593 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 16:11:40.590602 | controller | Wednesday 08 October 2025  16:11:32 +0000 (0:00:01.322)       0:00:05.058 *****
2025-10-08 16:11:40.590612 | controller | changed: [instance]
2025-10-08 16:11:41.329596 | controller |
2025-10-08 16:11:41.329633 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 16:11:41.329642 | controller | Wednesday 08 October 2025  16:11:40 +0000 (0:00:08.156)       0:00:13.214 *****
2025-10-08 16:11:41.329652 | controller | changed: [instance]
2025-10-08 16:11:41.352767 | controller |
2025-10-08 16:11:41.352795 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 16:11:41.352803 | controller | Wednesday 08 October 2025  16:11:41 +0000 (0:00:00.739)       0:00:13.953 *****
2025-10-08 16:11:41.352812 | controller | skipping: [instance]
2025-10-08 16:11:42.292509 | controller |
2025-10-08 16:11:42.292542 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 16:11:42.292550 | controller | Wednesday 08 October 2025  16:11:41 +0000 (0:00:00.023)       0:00:13.976 *****
2025-10-08 16:11:42.292560 | controller | changed: [instance]
2025-10-08 16:11:42.340184 | controller |
2025-10-08 16:11:42.340221 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 16:11:42.340230 | controller | Wednesday 08 October 2025  16:11:42 +0000 (0:00:00.939)       0:00:14.916 *****
2025-10-08 16:11:42.340240 | controller | skipping: [instance]
2025-10-08 16:11:42.385064 | controller |
2025-10-08 16:11:42.385115 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 16:11:42.385123 | controller | Wednesday 08 October 2025  16:11:42 +0000 (0:00:00.047)       0:00:14.963 *****
2025-10-08 16:11:42.385136 | controller | skipping: [instance]
2025-10-08 16:11:42.385280 | controller |
2025-10-08 16:11:42.385325 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 16:11:42.385339 | controller | Wednesday 08 October 2025  16:11:42 +0000 (0:00:00.044)       0:00:15.008 *****
2025-10-08 16:11:42.452744 | controller | skipping: [instance]
2025-10-08 16:11:43.036510 | controller |
2025-10-08 16:11:43.036542 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 16:11:43.036550 | controller | Wednesday 08 October 2025  16:11:42 +0000 (0:00:00.068)       0:00:15.076 *****
2025-10-08 16:11:43.036560 | controller | changed: [instance]
2025-10-08 16:11:43.494040 | controller |
2025-10-08 16:11:43.494092 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 16:11:43.494103 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.583)       0:00:15.660 *****
2025-10-08 16:11:43.494118 | controller | changed: [instance]
2025-10-08 16:11:43.527059 | controller |
2025-10-08 16:11:43.527096 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 16:11:43.527106 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.457)       0:00:16.117 *****
2025-10-08 16:11:43.527118 | controller | skipping: [instance]
2025-10-08 16:11:43.527148 | controller |
2025-10-08 16:11:43.527379 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 16:11:43.568032 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.033)       0:00:16.151 *****
2025-10-08 16:11:43.568056 | controller | skipping: [instance]
2025-10-08 16:11:43.568086 | controller |
2025-10-08 16:11:43.568351 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 16:11:43.603054 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.041)       0:00:16.192 *****
2025-10-08 16:11:43.603086 | controller | skipping: [instance]
2025-10-08 16:11:43.603399 | controller |
2025-10-08 16:11:43.646694 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 16:11:43.646724 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.035)       0:00:16.227 *****
2025-10-08 16:11:43.646735 | controller | ok: [instance]
2025-10-08 16:11:43.688665 | controller |
2025-10-08 16:11:43.688691 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 16:11:43.688700 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.043)       0:00:16.270 *****
2025-10-08 16:11:43.688712 | controller | skipping: [instance]
2025-10-08 16:11:43.720611 | controller |
2025-10-08 16:11:43.720633 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 16:11:43.720640 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.041)       0:00:16.312 *****
2025-10-08 16:11:43.720657 | controller | skipping: [instance]
2025-10-08 16:11:43.753007 | controller |
2025-10-08 16:11:43.753052 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 16:11:43.753062 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.032)       0:00:16.344 *****
2025-10-08 16:11:43.753074 | controller | skipping: [instance]
2025-10-08 16:11:43.753153 | controller |
2025-10-08 16:11:43.753302 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 16:11:43.753420 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.032)       0:00:16.377 *****
2025-10-08 16:11:43.781595 | controller | skipping: [instance]
2025-10-08 16:11:43.781721 | controller |
2025-10-08 16:11:43.781910 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 16:11:43.782067 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.028)       0:00:16.405 *****
2025-10-08 16:11:43.816748 | controller | skipping: [instance]
2025-10-08 16:11:43.816849 | controller |
2025-10-08 16:11:43.816992 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 16:11:43.817132 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.035)       0:00:16.441 *****
2025-10-08 16:11:43.849539 | controller | skipping: [instance]
2025-10-08 16:11:43.849639 | controller |
2025-10-08 16:11:43.849768 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 16:11:43.849949 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.032)       0:00:16.473 *****
2025-10-08 16:11:43.881398 | controller | skipping: [instance]
2025-10-08 16:11:43.881499 | controller |
2025-10-08 16:11:43.881667 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 16:11:43.881761 | controller | Wednesday 08 October 2025  16:11:43 +0000 (0:00:00.031)       0:00:16.505 *****
2025-10-08 16:11:44.089880 | controller | ok: [instance]
2025-10-08 16:11:44.324700 | controller |
2025-10-08 16:11:44.324730 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 16:11:44.324738 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.207)       0:00:16.713 *****
2025-10-08 16:11:44.324748 | controller | changed: [instance]
2025-10-08 16:11:44.594055 | controller |
2025-10-08 16:11:44.594115 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 16:11:44.594123 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.234)       0:00:16.948 *****
2025-10-08 16:11:44.594142 | controller | changed: [instance]
2025-10-08 16:11:44.594163 | controller |
2025-10-08 16:11:44.594422 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 16:11:44.629015 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.269)       0:00:17.218 *****
2025-10-08 16:11:44.629058 | controller | skipping: [instance]
2025-10-08 16:11:44.629102 | controller |
2025-10-08 16:11:44.629109 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 16:11:44.629116 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.034)       0:00:17.252 *****
2025-10-08 16:11:44.666799 | controller | skipping: [instance]
2025-10-08 16:11:44.695735 | controller |
2025-10-08 16:11:44.695760 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 16:11:44.695768 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.037)       0:00:17.290 *****
2025-10-08 16:11:44.695785 | controller | skipping: [instance]
2025-10-08 16:11:44.733889 | controller |
2025-10-08 16:11:44.733932 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 16:11:44.733946 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.028)       0:00:17.319 *****
2025-10-08 16:11:44.733961 | controller | skipping: [instance]
2025-10-08 16:11:44.763492 | controller |
2025-10-08 16:11:44.763510 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 16:11:44.763518 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.037)       0:00:17.357 *****
2025-10-08 16:11:44.763527 | controller | skipping: [instance]
2025-10-08 16:11:44.801530 | controller |
2025-10-08 16:11:44.801549 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 16:11:44.801567 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.029)       0:00:17.387 *****
2025-10-08 16:11:44.801576 | controller | skipping: [instance]
2025-10-08 16:11:44.825728 | controller |
2025-10-08 16:11:44.825754 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 16:11:44.825764 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.037)       0:00:17.425 *****
2025-10-08 16:11:44.825775 | controller | skipping: [instance]
2025-10-08 16:11:44.825976 | controller |
2025-10-08 16:11:44.826179 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 16:11:44.826345 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.024)       0:00:17.449 *****
2025-10-08 16:11:44.871965 | controller | skipping: [instance]
2025-10-08 16:11:44.872205 | controller |
2025-10-08 16:11:44.872386 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 16:11:44.872534 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.046)       0:00:17.496 *****
2025-10-08 16:11:44.896141 | controller | skipping: [instance]
2025-10-08 16:11:44.896335 | controller |
2025-10-08 16:11:44.896506 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 16:11:44.896654 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.024)       0:00:17.520 *****
2025-10-08 16:11:44.926545 | controller | skipping: [instance]
2025-10-08 16:11:44.926709 | controller |
2025-10-08 16:11:44.926854 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 16:11:44.927022 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.030)       0:00:17.550 *****
2025-10-08 16:11:44.971401 | controller | skipping: [instance]
2025-10-08 16:11:44.971588 | controller |
2025-10-08 16:11:44.971751 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 16:11:44.971890 | controller | Wednesday 08 October 2025  16:11:44 +0000 (0:00:00.045)       0:00:17.595 *****
2025-10-08 16:11:45.014586 | controller | skipping: [instance]
2025-10-08 16:11:45.014771 | controller |
2025-10-08 16:11:45.014946 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 16:11:45.015144 | controller | Wednesday 08 October 2025  16:11:45 +0000 (0:00:00.043)       0:00:17.638 *****
2025-10-08 16:11:45.058901 | controller | skipping: [instance]
2025-10-08 16:11:45.059112 | controller |
2025-10-08 16:11:45.059265 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 16:11:45.059408 | controller | Wednesday 08 October 2025  16:11:45 +0000 (0:00:00.044)       0:00:17.682 *****
2025-10-08 16:11:45.099893 | controller | skipping: [instance]
2025-10-08 16:11:45.100108 | controller |
2025-10-08 16:11:45.100263 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 16:11:45.100406 | controller | Wednesday 08 October 2025  16:11:45 +0000 (0:00:00.040)       0:00:17.723 *****
2025-10-08 16:12:38.101864 | controller | ok: [instance]
2025-10-08 16:12:39.348757 | controller |
2025-10-08 16:12:39.348791 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 16:12:39.348799 | controller | Wednesday 08 October 2025  16:12:38 +0000 (0:00:53.001)       0:01:10.725 *****
2025-10-08 16:12:39.348809 | controller | ok: [instance]
2025-10-08 16:12:39.380694 | controller |
2025-10-08 16:12:39.380725 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 16:12:39.380734 | controller | Wednesday 08 October 2025  16:12:39 +0000 (0:00:01.246)       0:01:11.972 *****
2025-10-08 16:12:39.380746 | controller | skipping: [instance]
2025-10-08 16:12:39.423029 | controller |
2025-10-08 16:12:39.423071 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-10-08 16:12:39.423081 | controller | Wednesday 08 October 2025  16:12:39 +0000 (0:00:00.031)       0:01:12.004 *****
2025-10-08 16:12:39.423096 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-10-08 16:12:39.443055 | controller |
2025-10-08 16:12:39.443087 | controller | TASK [ci_setup : List packages to install] *************************************
2025-10-08 16:12:39.443096 | controller | Wednesday 08 October 2025  16:12:39 +0000 (0:00:00.042)       0:01:12.046 *****
2025-10-08 16:12:39.443115 | controller | ok: [instance] =>
2025-10-08 16:12:39.443143 | controller |   cifmw_ci_setup_packages:
2025-10-08 16:12:39.443151 | controller |   - bash-completion
2025-10-08 16:12:39.443156 | controller |   - ca-certificates
2025-10-08 16:12:39.443162 | controller |   - git-core
2025-10-08 16:12:39.443168 | controller |   - make
2025-10-08 16:12:39.443173 | controller |   - tar
2025-10-08 16:12:39.443179 | controller |   - tmux
2025-10-08 16:12:39.443184 | controller |   - python3-pip
2025-10-08 16:12:39.443189 | controller |
2025-10-08 16:12:39.443196 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-10-08 16:12:39.443224 | controller | Wednesday 08 October 2025  16:12:39 +0000 (0:00:00.019)       0:01:12.066 *****
2025-10-08 16:12:50.087026 | controller | changed: [instance]
2025-10-08 16:12:50.087073 | controller |
2025-10-08 16:12:50.087084 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-10-08 16:12:50.087223 | controller | Wednesday 08 October 2025  16:12:50 +0000 (0:00:10.644)       0:01:22.711 *****
2025-10-08 16:12:50.692025 | controller | ok: [instance]
2025-10-08 16:12:50.692113 | controller |
2025-10-08 16:12:50.692367 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-10-08 16:12:50.893399 | controller | Wednesday 08 October 2025  16:12:50 +0000 (0:00:00.605)       0:01:23.316 *****
2025-10-08 16:12:50.893436 | controller | changed: [instance]
2025-10-08 16:12:50.893463 | controller |
2025-10-08 16:12:50.893717 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-10-08 16:12:56.781381 | controller | Wednesday 08 October 2025  16:12:50 +0000 (0:00:00.201)       0:01:23.517 *****
2025-10-08 16:12:56.781415 | controller | changed: [instance]
2025-10-08 16:12:56.816069 | controller |
2025-10-08 16:12:56.816105 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-10-08 16:12:56.816115 | controller | Wednesday 08 October 2025  16:12:56 +0000 (0:00:05.887)       0:01:29.405 *****
2025-10-08 16:12:56.816128 | controller | skipping: [instance]
2025-10-08 16:12:57.076759 | controller |
2025-10-08 16:12:57.076792 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-10-08 16:12:57.076801 | controller | Wednesday 08 October 2025  16:12:56 +0000 (0:00:00.034)       0:01:29.440 *****
2025-10-08 16:12:57.076811 | controller | changed: [instance]
2025-10-08 16:12:57.352854 | controller |
2025-10-08 16:12:57.352893 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-10-08 16:12:57.352902 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.260)       0:01:29.700 *****
2025-10-08 16:12:57.352939 | controller | changed: [instance]
2025-10-08 16:12:57.368051 | controller |
2025-10-08 16:12:57.368090 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-10-08 16:12:57.368100 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.276)       0:01:29.976 *****
2025-10-08 16:12:57.368112 | controller | skipping: [instance]
2025-10-08 16:12:57.368143 | controller |
2025-10-08 16:12:57.368154 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-10-08 16:12:57.383994 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.015)       0:01:29.992 *****
2025-10-08 16:12:57.384033 | controller | skipping: [instance]
2025-10-08 16:12:57.384060 | controller |
2025-10-08 16:12:57.384069 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-10-08 16:12:57.384152 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.015)       0:01:30.008 *****
2025-10-08 16:12:57.399794 | controller | skipping: [instance]
2025-10-08 16:12:57.415046 | controller |
2025-10-08 16:12:57.415083 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-10-08 16:12:57.415093 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.015)       0:01:30.023 *****
2025-10-08 16:12:57.415106 | controller | skipping: [instance]
2025-10-08 16:12:57.430678 | controller |
2025-10-08 16:12:57.430704 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-10-08 16:12:57.430714 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.015)       0:01:30.039 *****
2025-10-08 16:12:57.430725 | controller | skipping: [instance]
2025-10-08 16:12:57.451367 | controller |
2025-10-08 16:12:57.451391 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-10-08 16:12:57.451408 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.015)       0:01:30.054 *****
2025-10-08 16:12:57.451420 | controller | skipping: [instance]
2025-10-08 16:12:57.451592 | controller |
2025-10-08 16:12:57.451764 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-10-08 16:12:57.451962 | controller | Wednesday 08 October 2025  16:12:57 +0000 (0:00:00.021)       0:01:30.075 *****
2025-10-08 16:12:58.430080 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-10-08 16:12:58.430186 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-08 16:12:58.430215 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-10-08 16:12:58.430222 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-10-08 16:12:58.430228 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-10-08 16:12:58.430234 | controller |
2025-10-08 16:12:58.430240 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-10-08 16:12:58.430249 | controller | Wednesday 08 October 2025  16:12:58 +0000 (0:00:00.977)       0:01:31.053 *****
2025-10-08 16:12:58.625998 | controller | ok: [instance]
2025-10-08 16:12:58.626054 | controller |
2025-10-08 16:12:58.626368 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-10-08 16:12:58.804964 | controller | Wednesday 08 October 2025  16:12:58 +0000 (0:00:00.196)       0:01:31.250 *****
2025-10-08 16:12:58.805004 | controller | changed: [instance]
2025-10-08 16:12:58.994080 | controller |
2025-10-08 16:12:58.994126 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-10-08 16:12:58.994136 | controller | Wednesday 08 October 2025  16:12:58 +0000 (0:00:00.178)       0:01:31.428 *****
2025-10-08 16:12:58.994151 | controller | ok: [instance]
2025-10-08 16:12:58.994180 | controller |
2025-10-08 16:12:58.994189 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-10-08 16:12:58.994346 | controller | Wednesday 08 October 2025  16:12:58 +0000 (0:00:00.189)       0:01:31.618 *****
2025-10-08 16:12:59.023667 | controller | skipping: [instance]
2025-10-08 16:12:59.040895 | controller |
2025-10-08 16:12:59.040943 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-10-08 16:12:59.040956 | controller | Wednesday 08 October 2025  16:12:59 +0000 (0:00:00.029)       0:01:31.647 *****
2025-10-08 16:12:59.040968 | controller | skipping: [instance]
2025-10-08 16:12:59.060993 | controller |
2025-10-08 16:12:59.061020 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-10-08 16:12:59.061028 | controller | Wednesday 08 October 2025  16:12:59 +0000 (0:00:00.017)       0:01:31.664 *****
2025-10-08 16:12:59.061038 | controller | ok: [instance]
2025-10-08 16:12:59.061069 | controller |
2025-10-08 16:12:59.061274 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-10-08 16:12:59.087953 | controller | Wednesday 08 October 2025  16:12:59 +0000 (0:00:00.020)       0:01:31.685 *****
2025-10-08 16:12:59.087978 | controller | skipping: [instance]
2025-10-08 16:12:59.088151 | controller |
2025-10-08 16:12:59.088322 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-10-08 16:12:59.088470 | controller | Wednesday 08 October 2025  16:12:59 +0000 (0:00:00.027)       0:01:31.712 *****
2025-10-08 16:13:12.140007 | controller | changed: [instance]
2025-10-08 16:13:12.140045 | controller |
2025-10-08 16:13:12.140055 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-10-08 16:13:12.140190 | controller | Wednesday 08 October 2025  16:13:12 +0000 (0:00:13.051)       0:01:44.763 *****
2025-10-08 16:13:12.359209 | controller | changed: [instance]
2025-10-08 16:13:12.359446 | controller |
2025-10-08 16:13:12.359649 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-10-08 16:13:12.359716 | controller | Wednesday 08 October 2025  16:13:12 +0000 (0:00:00.218)       0:01:44.982 *****
2025-10-08 16:13:12.770775 | controller | changed: [instance]
2025-10-08 16:13:12.770829 | controller |
2025-10-08 16:13:12.770856 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-10-08 16:13:12.770864 | controller | Wednesday 08 October 2025  16:13:12 +0000 (0:00:00.411)       0:01:45.394 *****
2025-10-08 16:13:13.406739 | controller | changed: [instance]
2025-10-08 16:13:13.605019 | controller |
2025-10-08 16:13:13.605085 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-10-08 16:13:13.605096 | controller | Wednesday 08 October 2025  16:13:13 +0000 (0:00:00.635)       0:01:46.030 *****
2025-10-08 16:13:13.605113 | controller | ok: [instance]
2025-10-08 16:13:14.280510 | controller |
2025-10-08 16:13:14.280543 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-10-08 16:13:14.280553 | controller | Wednesday 08 October 2025  16:13:13 +0000 (0:00:00.198)       0:01:46.228 *****
2025-10-08 16:13:14.280565 | controller | ok: [instance]
2025-10-08 16:13:15.174738 | controller |
2025-10-08 16:13:15.174770 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-10-08 16:13:15.174779 | controller | Wednesday 08 October 2025  16:13:14 +0000 (0:00:00.675)       0:01:46.904 *****
2025-10-08 16:13:15.174790 | controller | changed: [instance]
2025-10-08 16:13:15.254342 | controller |
2025-10-08 16:13:15.254373 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-10-08 16:13:15.254381 | controller | Wednesday 08 October 2025  16:13:15 +0000 (0:00:00.894)       0:01:47.798 *****
2025-10-08 16:13:15.254391 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-10-08 16:13:15.584684 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-10-08 16:13:15.584718 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-10-08 16:13:15.584726 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-10-08 16:13:15.584732 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-10-08 16:13:15.584737 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-10-08 16:13:15.584743 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-10-08 16:13:15.584748 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-10-08 16:13:15.584753 | controller |
2025-10-08 16:13:15.584759 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-10-08 16:13:15.584765 | controller | Wednesday 08 October 2025  16:13:15 +0000 (0:00:00.079)       0:01:47.877 *****
2025-10-08 16:13:15.584775 | controller | ok: [instance]
2025-10-08 16:13:15.905441 | controller |
2025-10-08 16:13:15.905472 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-10-08 16:13:15.905480 | controller | Wednesday 08 October 2025  16:13:15 +0000 (0:00:00.331)       0:01:48.208 *****
2025-10-08 16:13:15.905498 | controller | ok: [instance]
2025-10-08 16:13:16.225564 | controller |
2025-10-08 16:13:16.225593 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-10-08 16:13:16.225601 | controller | Wednesday 08 October 2025  16:13:15 +0000 (0:00:00.320)       0:01:48.529 *****
2025-10-08 16:13:16.225610 | controller | ok: [instance]
2025-10-08 16:13:16.541878 | controller |
2025-10-08 16:13:16.541909 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-10-08 16:13:16.541964 | controller | Wednesday 08 October 2025  16:13:16 +0000 (0:00:00.320)       0:01:48.849 *****
2025-10-08 16:13:16.541976 | controller | ok: [instance]
2025-10-08 16:13:16.863091 | controller |
2025-10-08 16:13:16.863137 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-10-08 16:13:16.863147 | controller | Wednesday 08 October 2025  16:13:16 +0000 (0:00:00.316)       0:01:49.165 *****
2025-10-08 16:13:16.863161 | controller | ok: [instance]
2025-10-08 16:13:17.187491 | controller |
2025-10-08 16:13:17.187525 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-10-08 16:13:17.187533 | controller | Wednesday 08 October 2025  16:13:16 +0000 (0:00:00.321)       0:01:49.487 *****
2025-10-08 16:13:17.187543 | controller | ok: [instance]
2025-10-08 16:13:17.510068 | controller |
2025-10-08 16:13:17.510104 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-10-08 16:13:17.510113 | controller | Wednesday 08 October 2025  16:13:17 +0000 (0:00:00.324)       0:01:49.811 *****
2025-10-08 16:13:17.510126 | controller | ok: [instance]
2025-10-08 16:13:17.510155 | controller |
2025-10-08 16:13:17.510165 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-10-08 16:13:17.510274 | controller | Wednesday 08 October 2025  16:13:17 +0000 (0:00:00.322)       0:01:50.134 *****
2025-10-08 16:13:17.834502 | controller | ok: [instance]
2025-10-08 16:13:18.156772 | controller |
2025-10-08 16:13:18.156805 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-10-08 16:13:18.156813 | controller | Wednesday 08 October 2025  16:13:17 +0000 (0:00:00.324)       0:01:50.458 *****
2025-10-08 16:13:18.156824 | controller | ok: [instance]
2025-10-08 16:13:18.476061 | controller |
2025-10-08 16:13:18.476103 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-10-08 16:13:18.476113 | controller | Wednesday 08 October 2025  16:13:18 +0000 (0:00:00.322)       0:01:50.780 *****
2025-10-08 16:13:18.476127 | controller | ok: [instance]
2025-10-08 16:13:18.796479 | controller |
2025-10-08 16:13:18.796513 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-10-08 16:13:18.796523 | controller | Wednesday 08 October 2025  16:13:18 +0000 (0:00:00.319)       0:01:51.100 *****
2025-10-08 16:13:18.796535 | controller | ok: [instance]
2025-10-08 16:13:19.116944 | controller |
2025-10-08 16:13:19.116976 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-10-08 16:13:19.116985 | controller | Wednesday 08 October 2025  16:13:18 +0000 (0:00:00.320)       0:01:51.420 *****
2025-10-08 16:13:19.116995 | controller | ok: [instance]
2025-10-08 16:13:19.440482 | controller |
2025-10-08 16:13:19.440515 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-10-08 16:13:19.440524 | controller | Wednesday 08 October 2025  16:13:19 +0000 (0:00:00.320)       0:01:51.740 *****
2025-10-08 16:13:19.440534 | controller | ok: [instance]
2025-10-08 16:13:19.760487 | controller |
2025-10-08 16:13:19.760522 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-10-08 16:13:19.760530 | controller | Wednesday 08 October 2025  16:13:19 +0000 (0:00:00.323)       0:01:52.064 *****
2025-10-08 16:13:19.760540 | controller | ok: [instance]
2025-10-08 16:13:20.084031 | controller |
2025-10-08 16:13:20.084073 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-10-08 16:13:20.084083 | controller | Wednesday 08 October 2025  16:13:19 +0000 (0:00:00.319)       0:01:52.384 *****
2025-10-08 16:13:20.084097 | controller | ok: [instance]
2025-10-08 16:13:20.084126 | controller |
2025-10-08 16:13:20.084137 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-10-08 16:13:20.084162 | controller | Wednesday 08 October 2025  16:13:20 +0000 (0:00:00.323)       0:01:52.708 *****
2025-10-08 16:13:20.409932 | controller | ok: [instance]
2025-10-08 16:13:20.730040 | controller |
2025-10-08 16:13:20.730070 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-10-08 16:13:20.730078 | controller | Wednesday 08 October 2025  16:13:20 +0000 (0:00:00.325)       0:01:53.033 *****
2025-10-08 16:13:20.730088 | controller | ok: [instance]
2025-10-08 16:13:21.052062 | controller |
2025-10-08 16:13:21.052103 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-10-08 16:13:21.052121 | controller | Wednesday 08 October 2025  16:13:20 +0000 (0:00:00.320)       0:01:53.353 *****
2025-10-08 16:13:21.052136 | controller | ok: [instance]
2025-10-08 16:13:21.371791 | controller |
2025-10-08 16:13:21.371822 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-10-08 16:13:21.371831 | controller | Wednesday 08 October 2025  16:13:21 +0000 (0:00:00.322)       0:01:53.676 *****
2025-10-08 16:13:21.371848 | controller | ok: [instance]
2025-10-08 16:13:21.690943 | controller |
2025-10-08 16:13:21.690973 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-10-08 16:13:21.690982 | controller | Wednesday 08 October 2025  16:13:21 +0000 (0:00:00.319)       0:01:53.995 *****
2025-10-08 16:13:21.690992 | controller | ok: [instance]
2025-10-08 16:13:22.009978 | controller |
2025-10-08 16:13:22.010008 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-10-08 16:13:22.010016 | controller | Wednesday 08 October 2025  16:13:21 +0000 (0:00:00.319)       0:01:54.314 *****
2025-10-08 16:13:22.010026 | controller | ok: [instance]
2025-10-08 16:13:22.328586 | controller |
2025-10-08 16:13:22.328617 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-10-08 16:13:22.328625 | controller | Wednesday 08 October 2025  16:13:22 +0000 (0:00:00.319)       0:01:54.633 *****
2025-10-08 16:13:22.328635 | controller | ok: [instance]
2025-10-08 16:13:22.649963 | controller |
2025-10-08 16:13:22.649998 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-10-08 16:13:22.650008 | controller | Wednesday 08 October 2025  16:13:22 +0000 (0:00:00.318)       0:01:54.952 *****
2025-10-08 16:13:22.650019 | controller | ok: [instance]
2025-10-08 16:13:22.965380 | controller |
2025-10-08 16:13:22.965410 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-10-08 16:13:22.965418 | controller | Wednesday 08 October 2025  16:13:22 +0000 (0:00:00.321)       0:01:55.273 *****
2025-10-08 16:13:22.965428 | controller | ok: [instance]
2025-10-08 16:13:22.983092 | controller |
2025-10-08 16:13:22.983122 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-10-08 16:13:22.983132 | controller | Wednesday 08 October 2025  16:13:22 +0000 (0:00:00.315)       0:01:55.589 *****
2025-10-08 16:13:22.983144 | controller | skipping: [instance]
2025-10-08 16:13:22.983172 | controller |
2025-10-08 16:13:22.983182 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-10-08 16:13:22.983277 | controller | Wednesday 08 October 2025  16:13:22 +0000 (0:00:00.018)       0:01:55.607 *****
2025-10-08 16:13:23.263403 | controller | ok: [instance]
2025-10-08 16:13:23.643912 | controller |
2025-10-08 16:13:23.643973 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-10-08 16:13:23.643981 | controller | Wednesday 08 October 2025  16:13:23 +0000 (0:00:00.279)       0:01:55.887 *****
2025-10-08 16:13:23.643991 | controller | ok: [instance]
2025-10-08 16:13:23.945486 | controller |
2025-10-08 16:13:23.945515 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-10-08 16:13:23.945523 | controller | Wednesday 08 October 2025  16:13:23 +0000 (0:00:00.380)       0:01:56.267 *****
2025-10-08 16:13:23.945533 | controller | changed: [instance]
2025-10-08 16:13:24.125391 | controller |
2025-10-08 16:13:24.125422 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-10-08 16:13:24.125430 | controller | Wednesday 08 October 2025  16:13:23 +0000 (0:00:00.301)       0:01:56.569 *****
2025-10-08 16:13:24.125440 | controller | [WARNING]: Reset is not implemented for this connection
2025-10-08 16:13:24.147994 | controller | changed: [instance]
2025-10-08 16:13:24.150688 | controller |
2025-10-08 16:13:24.150721 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-10-08 16:13:24.210855 | controller | Wednesday 08 October 2025  16:13:24 +0000 (0:00:00.175)       0:01:56.744 *****
2025-10-08 16:13:24.210890 | controller |
2025-10-08 16:13:24.210898 | controller | PLAY RECAP *********************************************************************
2025-10-08 16:13:24.210904 | controller | instance                   : ok=75   changed=23   unreachable=0    failed=0    skipped=41   rescued=0    ignored=0
2025-10-08 16:13:24.210927 | controller |
2025-10-08 16:13:24.210938 | controller | Wednesday 08 October 2025  16:13:24 +0000 (0:00:00.027)       0:01:56.771 *****
2025-10-08 16:13:24.210944 | controller | ===============================================================================
2025-10-08 16:13:24.210949 | controller | test_deps : Install selinux python libs -------------------------------- 53.00s
2025-10-08 16:13:24.210954 | controller | libvirt_manager : Install packages required for using KVM -------------- 13.05s
2025-10-08 16:13:24.210959 | controller | ci_setup : Install needed packages ------------------------------------- 10.64s
2025-10-08 16:13:24.210974 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.16s
2025-10-08 16:13:24.210980 | controller | ci_setup : Install openshift client ------------------------------------- 5.89s
2025-10-08 16:13:24.210985 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.32s
2025-10-08 16:13:24.210991 | controller | test_deps : Install python yaml libs ------------------------------------ 1.25s
2025-10-08 16:13:24.210996 | controller | ci_setup : Manage directories ------------------------------------------- 0.98s
2025-10-08 16:13:24.211001 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.94s
2025-10-08 16:13:24.211006 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.89s
2025-10-08 16:13:24.211011 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 16:13:24.211016 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2025-10-08 16:13:24.211021 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s
2025-10-08 16:13:24.211026 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.73s
2025-10-08 16:13:24.211031 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.68s
2025-10-08 16:13:24.211036 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.64s
2025-10-08 16:13:24.211041 | controller | ci_setup : Gather version of openshift client --------------------------- 0.61s
2025-10-08 16:13:24.211046 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-10-08 16:13:24.211052 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-10-08 16:13:24.211057 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.46s
2025-10-08 16:13:24.211067 | controller | INFO     Running default > converge
2025-10-08 16:13:24.620548 | controller |
2025-10-08 16:13:24.620768 | controller | PLAY [Converge] ****************************************************************
2025-10-08 16:13:24.620905 | controller |
2025-10-08 16:13:24.621087 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 16:13:24.621236 | controller | Wednesday 08 October 2025  16:13:24 +0000 (0:00:00.012)       0:00:00.012 *****
2025-10-08 16:13:25.398857 | controller | ok: [instance]
2025-10-08 16:13:25.437660 | controller |
2025-10-08 16:13:25.437695 | controller | TASK [Including the openshift provisioner node role.] **************************
2025-10-08 16:13:25.437705 | controller | Wednesday 08 October 2025  16:13:25 +0000 (0:00:00.778)       0:00:00.791 *****
2025-10-08 16:13:25.437717 | controller |
2025-10-08 16:13:25.730970 | controller | TASK [openshift_provisioner_node : Create the artifacts directory] *************
2025-10-08 16:13:25.730999 | controller | Wednesday 08 October 2025  16:13:25 +0000 (0:00:00.039)       0:00:00.830 *****
2025-10-08 16:13:25.731011 | controller | ok: [instance -> localhost]
2025-10-08 16:13:26.125636 | controller |
2025-10-08 16:13:26.125671 | controller | TASK [openshift_provisioner_node : Adding user kni] ****************************
2025-10-08 16:13:26.125680 | controller | Wednesday 08 October 2025  16:13:25 +0000 (0:00:00.293)       0:00:01.123 *****
2025-10-08 16:13:26.125690 | controller | changed: [instance]
2025-10-08 16:13:26.497540 | controller |
2025-10-08 16:13:26.497573 | controller | TASK [openshift_provisioner_node : Generate SSH keys] **************************
2025-10-08 16:13:26.497582 | controller | Wednesday 08 October 2025  16:13:26 +0000 (0:00:00.394)       0:00:01.518 *****
2025-10-08 16:13:26.497592 | controller | changed: [instance -> localhost]
2025-10-08 16:13:26.870819 | controller |
2025-10-08 16:13:26.870851 | controller | TASK [openshift_provisioner_node : Enable passwordless access] *****************
2025-10-08 16:13:26.870859 | controller | Wednesday 08 October 2025  16:13:26 +0000 (0:00:00.371)       0:00:01.890 *****
2025-10-08 16:13:26.870870 | controller | changed: [instance]
2025-10-08 16:13:49.992988 | controller |
2025-10-08 16:13:49.993019 | controller | TASK [openshift_provisioner_node : Install required packages need to execute openshift installer] ***
2025-10-08 16:13:49.993028 | controller | Wednesday 08 October 2025  16:13:26 +0000 (0:00:00.373)       0:00:02.263 *****
2025-10-08 16:13:49.993046 | controller | changed: [instance]
2025-10-08 16:13:50.429494 | controller |
2025-10-08 16:13:50.429531 | controller | TASK [openshift_provisioner_node : Create the external network connection] *****
2025-10-08 16:13:50.429540 | controller | Wednesday 08 October 2025  16:13:49 +0000 (0:00:23.122)       0:00:25.385 *****
2025-10-08 16:13:50.429551 | controller | changed: [instance]
2025-10-08 16:13:50.461877 | controller |
2025-10-08 16:13:50.461931 | controller | TASK [openshift_provisioner_node : Verify external network interface is defined] ***
2025-10-08 16:13:50.461950 | controller | Wednesday 08 October 2025  16:13:50 +0000 (0:00:00.436)       0:00:25.821 *****
2025-10-08 16:13:50.461963 | controller | skipping: [instance]
2025-10-08 16:13:50.462138 | controller |
2025-10-08 16:13:50.462162 | controller | TASK [openshift_provisioner_node : Add iface to the external network connection] ***
2025-10-08 16:13:50.490631 | controller | Wednesday 08 October 2025  16:13:50 +0000 (0:00:00.032)       0:00:25.854 *****
2025-10-08 16:13:50.490671 | controller | skipping: [instance]
2025-10-08 16:13:50.522804 | controller |
2025-10-08 16:13:50.522838 | controller | TASK [openshift_provisioner_node : Create the provisioning network connection] ***
2025-10-08 16:13:50.522847 | controller | Wednesday 08 October 2025  16:13:50 +0000 (0:00:00.028)       0:00:25.883 *****
2025-10-08 16:13:50.522865 | controller | skipping: [instance]
2025-10-08 16:13:50.522885 | controller |
2025-10-08 16:13:50.523102 | controller | TASK [openshift_provisioner_node : Add iface to the provisioning network connection] ***
2025-10-08 16:13:50.523157 | controller | Wednesday 08 October 2025  16:13:50 +0000 (0:00:00.032)       0:00:25.915 *****
2025-10-08 16:13:50.556619 | controller | skipping: [instance]
2025-10-08 16:13:50.596205 | controller |
2025-10-08 16:13:50.596241 | controller | TASK [openshift_provisioner_node : Check required virtualization networks] *****
2025-10-08 16:13:50.596250 | controller | Wednesday 08 October 2025  16:13:50 +0000 (0:00:00.033)       0:00:25.948 *****
2025-10-08 16:13:50.596261 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/add_virtual_network.yml for instance => (item=baremetal)
2025-10-08 16:13:51.501892 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/add_virtual_network.yml for instance => (item=provisioning)
2025-10-08 16:13:51.501939 | controller |
2025-10-08 16:13:51.501951 | controller | TASK [openshift_provisioner_node : Define virtual network baremetal] ***********
2025-10-08 16:13:51.501958 | controller | Wednesday 08 October 2025  16:13:50 +0000 (0:00:00.039)       0:00:25.988 *****
2025-10-08 16:13:51.501968 | controller | changed: [instance]
2025-10-08 16:13:51.501997 | controller |
2025-10-08 16:13:51.502005 | controller | TASK [openshift_provisioner_node : Start the network - baremetal] **************
2025-10-08 16:13:51.502028 | controller | Wednesday 08 October 2025  16:13:51 +0000 (0:00:00.906)       0:00:26.894 *****
2025-10-08 16:13:51.745734 | controller | changed: [instance]
2025-10-08 16:13:51.972822 | controller |
2025-10-08 16:13:51.972853 | controller | TASK [openshift_provisioner_node : Enable network autostart for baremetal] *****
2025-10-08 16:13:51.972861 | controller | Wednesday 08 October 2025  16:13:51 +0000 (0:00:00.243)       0:00:27.138 *****
2025-10-08 16:13:51.972871 | controller | changed: [instance]
2025-10-08 16:13:52.210075 | controller |
2025-10-08 16:13:52.210114 | controller | TASK [openshift_provisioner_node : Define virtual network provisioning] ********
2025-10-08 16:13:52.210124 | controller | Wednesday 08 October 2025  16:13:51 +0000 (0:00:00.227)       0:00:27.365 *****
2025-10-08 16:13:52.210137 | controller | changed: [instance]
2025-10-08 16:13:52.210174 | controller |
2025-10-08 16:13:52.210184 | controller | TASK [openshift_provisioner_node : Start the network - provisioning] ***********
2025-10-08 16:13:52.432041 | controller | Wednesday 08 October 2025  16:13:52 +0000 (0:00:00.237)       0:00:27.602 *****
2025-10-08 16:13:52.432077 | controller | changed: [instance]
2025-10-08 16:13:52.432105 | controller |
2025-10-08 16:13:52.432114 | controller | TASK [openshift_provisioner_node : Enable network autostart for provisioning] ***
2025-10-08 16:13:52.432236 | controller | Wednesday 08 October 2025  16:13:52 +0000 (0:00:00.221)       0:00:27.824 *****
2025-10-08 16:13:52.651942 | controller | changed: [instance]
2025-10-08 16:13:52.699589 | controller |
2025-10-08 16:13:52.699628 | controller | TASK [openshift_provisioner_node : Cache the MAC addresses] ********************
2025-10-08 16:13:52.699637 | controller | Wednesday 08 October 2025  16:13:52 +0000 (0:00:00.219)       0:00:28.044 *****
2025-10-08 16:13:52.699647 | controller | ok: [instance]
2025-10-08 16:13:52.964872 | controller |
2025-10-08 16:13:52.964905 | controller | TASK [openshift_provisioner_node : Read host SSH fingerprint] ******************
2025-10-08 16:13:52.964929 | controller | Wednesday 08 October 2025  16:13:52 +0000 (0:00:00.047)       0:00:28.092 *****
2025-10-08 16:13:52.964944 | controller | ok: [instance]
2025-10-08 16:13:52.997058 | controller |
2025-10-08 16:13:52.997094 | controller | TASK [openshift_provisioner_node : Set the role output parameters] *************
2025-10-08 16:13:52.997104 | controller | Wednesday 08 October 2025  16:13:52 +0000 (0:00:00.264)       0:00:28.357 *****
2025-10-08 16:13:52.997116 | controller | ok: [instance]
2025-10-08 16:13:52.997150 | controller |
2025-10-08 16:13:52.997370 | controller | TASK [openshift_provisioner_node : Cache provisioning bridge name] *************
2025-10-08 16:13:53.033167 | controller | Wednesday 08 October 2025  16:13:52 +0000 (0:00:00.032)       0:00:28.390 *****
2025-10-08 16:13:53.033191 | controller | skipping: [instance]
2025-10-08 16:13:53.316013 | controller |
2025-10-08 16:13:53.316062 | controller | TASK [Verify user is created] **************************************************
2025-10-08 16:13:53.316072 | controller | Wednesday 08 October 2025  16:13:53 +0000 (0:00:00.035)       0:00:28.425 *****
2025-10-08 16:13:53.316086 | controller | ok: [instance]
2025-10-08 16:13:53.316125 | controller |
2025-10-08 16:13:53.316135 | controller | TASK [Verify external network bridge exists] ***********************************
2025-10-08 16:13:53.316255 | controller | Wednesday 08 October 2025  16:13:53 +0000 (0:00:00.282)       0:00:28.708 *****
2025-10-08 16:13:53.417004 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-08 16:13:53.417124 | controller | changed: [instance]
2025-10-08 16:13:53.417153 | controller |
2025-10-08 16:13:53.417162 | controller | TASK [Perform cleanup] *********************************************************
2025-10-08 16:13:53.417172 | controller | Wednesday 08 October 2025  16:13:53 +0000 (0:00:00.100)       0:00:28.809 *****
2025-10-08 16:13:53.452865 | controller |
2025-10-08 16:13:53.482454 | controller | TASK [openshift_provisioner_node : Cleaning up the virtual networks] ***********
2025-10-08 16:13:53.482473 | controller | Wednesday 08 October 2025  16:13:53 +0000 (0:00:00.036)       0:00:28.845 *****
2025-10-08 16:13:53.482484 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/cleanup_virtual_network.yml for instance => (item=baremetal)
2025-10-08 16:13:53.703759 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/openshift_provisioner_node/tasks/cleanup_virtual_network.yml for instance => (item=provisioning)
2025-10-08 16:13:53.703793 | controller |
2025-10-08 16:13:53.703801 | controller | TASK [openshift_provisioner_node : Stopping virtual network baremetal] *********
2025-10-08 16:13:53.703807 | controller | Wednesday 08 October 2025  16:13:53 +0000 (0:00:00.029)       0:00:28.875 *****
2025-10-08 16:13:53.703817 | controller | ok: [instance]
2025-10-08 16:13:53.928657 | controller |
2025-10-08 16:13:53.928690 | controller | TASK [openshift_provisioner_node : Remove virtual network baremetal] ***********
2025-10-08 16:13:53.928698 | controller | Wednesday 08 October 2025  16:13:53 +0000 (0:00:00.221)       0:00:29.096 *****
2025-10-08 16:13:53.928708 | controller | ok: [instance]
2025-10-08 16:13:54.152955 | controller |
2025-10-08 16:13:54.152988 | controller | TASK [openshift_provisioner_node : Stopping virtual network provisioning] ******
2025-10-08 16:13:54.152996 | controller | Wednesday 08 October 2025  16:13:53 +0000 (0:00:00.224)       0:00:29.321 *****
2025-10-08 16:13:54.153007 | controller | ok: [instance]
2025-10-08 16:13:54.377454 | controller |
2025-10-08 16:13:54.377490 | controller | TASK [openshift_provisioner_node : Remove virtual network provisioning] ********
2025-10-08 16:13:54.377500 | controller | Wednesday 08 October 2025  16:13:54 +0000 (0:00:00.224)       0:00:29.545 *****
2025-10-08 16:13:54.377512 | controller | ok: [instance]
2025-10-08 16:13:54.602853 | controller |
2025-10-08 16:13:54.602886 | controller | TASK [openshift_provisioner_node : Remove provisioning network] ****************
2025-10-08 16:13:54.602902 | controller | Wednesday 08 October 2025  16:13:54 +0000 (0:00:00.224)       0:00:29.769 *****
2025-10-08 16:13:54.602933 | controller | ok: [instance]
2025-10-08 16:13:54.948967 | controller |
2025-10-08 16:13:54.948996 | controller | TASK [openshift_provisioner_node : Remove the user] ****************************
2025-10-08 16:13:54.949004 | controller | Wednesday 08 October 2025  16:13:54 +0000 (0:00:00.225)       0:00:29.995 *****
2025-10-08 16:13:54.949014 | controller | changed: [instance]
2025-10-08 16:13:54.951010 | controller |
2025-10-08 16:13:54.951602 | controller | PLAY RECAP *********************************************************************
2025-10-08 16:13:54.951632 | controller | instance                   : ok=28   changed=13   unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-10-08 16:13:54.951640 | controller |
2025-10-08 16:13:54.951647 | controller | Wednesday 08 October 2025  16:13:54 +0000 (0:00:00.346)       0:00:30.341 *****
2025-10-08 16:13:54.951653 | controller | ===============================================================================
2025-10-08 16:13:54.951658 | controller | openshift_provisioner_node : Install required packages need to execute openshift installer -- 23.12s
2025-10-08 16:13:54.951664 | controller | openshift_provisioner_node : Define virtual network baremetal ----------- 0.91s
2025-10-08 16:13:54.951669 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-10-08 16:13:54.951675 | controller | openshift_provisioner_node : Create the external network connection ----- 0.44s
2025-10-08 16:13:54.951680 | controller | openshift_provisioner_node : Adding user kni ---------------------------- 0.39s
2025-10-08 16:13:54.951685 | controller | openshift_provisioner_node : Enable passwordless access ----------------- 0.37s
2025-10-08 16:13:54.951690 | controller | openshift_provisioner_node : Generate SSH keys -------------------------- 0.37s
2025-10-08 16:13:54.951695 | controller | openshift_provisioner_node : Remove the user ---------------------------- 0.35s
2025-10-08 16:13:54.951706 | controller | openshift_provisioner_node : Create the artifacts directory ------------- 0.29s
2025-10-08 16:13:54.951712 | controller | Verify user is created -------------------------------------------------- 0.28s
2025-10-08 16:13:54.951717 | controller | openshift_provisioner_node : Read host SSH fingerprint ------------------ 0.26s
2025-10-08 16:13:54.951722 | controller | openshift_provisioner_node : Start the network - baremetal -------------- 0.24s
2025-10-08 16:13:54.951728 | controller | openshift_provisioner_node : Define virtual network provisioning -------- 0.24s
2025-10-08 16:13:54.951733 | controller | openshift_provisioner_node : Enable network autostart for baremetal ----- 0.23s
2025-10-08 16:13:54.951742 | controller | openshift_provisioner_node : Remove provisioning network ---------------- 0.23s
2025-10-08 16:13:55.011250 | controller | openshift_provisioner_node : Remove virtual network baremetal ----------- 0.22s
2025-10-08 16:13:55.011303 | controller | openshift_provisioner_node : Remove virtual network provisioning -------- 0.22s
2025-10-08 16:13:55.011312 | controller | openshift_provisioner_node : Stopping virtual network provisioning ------ 0.22s
2025-10-08 16:13:55.011319 | controller | openshift_provisioner_node : Start the network - provisioning ----------- 0.22s
2025-10-08 16:13:55.011324 | controller | openshift_provisioner_node : Stopping virtual network baremetal --------- 0.22s
2025-10-08 16:13:55.011335 | controller | INFO     Running default > cleanup
2025-10-08 16:13:55.011856 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 16:13:55.012555 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 16:13:55.138269 | [controller] Waiting on logger
2025-10-08 16:14:00.306376 | [controller] Waiting on logger
2025-10-08 16:14:10.738415 | [controller] Waiting on logger
2025-10-08 16:14:21.170434 | [controller] Waiting on logger
2025-10-08 16:14:25.087850 | [Zuul] Log Stream did not terminate
2025-10-08 16:14:25.088036 | controller | changed
2025-10-08 16:14:25.110803 | 
2025-10-08 16:14:25.110862 | PLAY RECAP
2025-10-08 16:14:25.110902 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 16:14:25.110948 | 
2025-10-08 16:14:25.186998 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 16:14:25.187783 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 16:14:25.739743 | 
2025-10-08 16:14:25.739873 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 16:14:25.761027 | 
2025-10-08 16:14:25.761104 | TASK [Filter out host if needed]
2025-10-08 16:14:25.770155 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 16:14:25.775091 | 
2025-10-08 16:14:25.775159 | TASK [Ensure file is present]
2025-10-08 16:14:26.168253 | controller | ok
2025-10-08 16:14:26.175373 | 
2025-10-08 16:14:26.175444 | TASK [Manage molecule report file]
2025-10-08 16:14:26.197049 | [controller] Waiting on logger
2025-10-08 16:14:35.570394 | [controller] Waiting on logger
2025-10-08 16:14:46.003447 | [controller] Waiting on logger
2025-10-08 16:14:55.346532 | [controller] Waiting on logger
2025-10-08 16:14:56.611770 | [Zuul] Log Stream did not terminate
2025-10-08 16:14:56.611971 | controller | changed
2025-10-08 16:14:56.617413 | 
2025-10-08 16:14:56.617482 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 16:14:56.870589 | controller | ok
2025-10-08 16:14:56.876606 | 
2025-10-08 16:14:56.876685 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 16:14:57.257051 | controller | changed
2025-10-08 16:14:57.263479 | 
2025-10-08 16:14:57.263553 | TASK [Copy ci-framework interesting files]
2025-10-08 16:14:57.286961 | [controller] Waiting on logger
2025-10-08 16:15:07.698350 | [controller] Waiting on logger
2025-10-08 16:15:18.130330 | [controller] Waiting on logger
2025-10-08 16:15:27.474374 | [controller] Waiting on logger
2025-10-08 16:15:27.550503 | [Zuul] Log Stream did not terminate
2025-10-08 16:15:27.550666 | controller | changed
2025-10-08 16:15:27.556088 | 
2025-10-08 16:15:27.556158 | TASK [Get SELinux listing]
2025-10-08 16:15:28.626356 | [controller] Waiting on logger
2025-10-08 16:15:34.884795 | controller | changed
2025-10-08 16:15:34.891050 | 
2025-10-08 16:15:34.891131 | TASK [Generate log index]
2025-10-08 16:15:36.075947 | controller | changed
2025-10-08 16:15:36.080894 | 
2025-10-08 16:15:36.080976 | TASK [Get some env related data]
2025-10-08 16:15:37.138948 | [controller] Waiting on logger
2025-10-08 16:15:47.571400 | [controller] Waiting on logger
2025-10-08 16:15:58.003370 | [controller] Waiting on logger
2025-10-08 16:16:07.343045 | [Zuul] Log Stream did not terminate
2025-10-08 16:16:07.343190 | controller | changed
2025-10-08 16:16:07.347638 | [controller] Waiting on logger
2025-10-08 16:16:07.349065 | 
2025-10-08 16:16:07.349136 | TASK [Generate list of logs to collect in home directory]
2025-10-08 16:16:07.759765 | controller | ok: All paths examined
2025-10-08 16:16:07.767073 | 
2025-10-08 16:16:07.767137 | LOOP [Copy logs from home directory]
2025-10-08 16:16:08.417650 | controller | changed:
2025-10-08 16:16:08.417869 | controller | {
2025-10-08 16:16:08.417900 | controller |   "atime": 1743544925.4788878,
2025-10-08 16:16:08.417950 | controller |   "ctime": 1743545329.1409318,
2025-10-08 16:16:08.417973 | controller |   "dev": 64513,
2025-10-08 16:16:08.417993 | controller |   "gid": 1000,
2025-10-08 16:16:08.418012 | controller |   "gr_name": "zuul",
2025-10-08 16:16:08.418034 | controller |   "inode": 4518807,
2025-10-08 16:16:08.418052 | controller |   "isblk": false,
2025-10-08 16:16:08.418069 | controller |   "ischr": false,
2025-10-08 16:16:08.418085 | controller |   "isdir": false,
2025-10-08 16:16:08.418103 | controller |   "isfifo": false,
2025-10-08 16:16:08.418119 | controller |   "isgid": false,
2025-10-08 16:16:08.418135 | controller |   "islnk": false,
2025-10-08 16:16:08.418151 | controller |   "isreg": true,
2025-10-08 16:16:08.418170 | controller |   "issock": false,
2025-10-08 16:16:08.418186 | controller |   "isuid": false,
2025-10-08 16:16:08.418202 | controller |   "mode": "0644",
2025-10-08 16:16:08.418218 | controller |   "mtime": 1743545329.1409318,
2025-10-08 16:16:08.418234 | controller |   "nlink": 1,
2025-10-08 16:16:08.418250 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 16:16:08.418266 | controller |   "pw_name": "zuul",
2025-10-08 16:16:08.418281 | controller |   "rgrp": true,
2025-10-08 16:16:08.418297 | controller |   "roth": true,
2025-10-08 16:16:08.418312 | controller |   "rusr": true,
2025-10-08 16:16:08.418327 | controller |   "size": 4108,
2025-10-08 16:16:08.418343 | controller |   "uid": 1000,
2025-10-08 16:16:08.418358 | controller |   "wgrp": false,
2025-10-08 16:16:08.418372 | controller |   "woth": false,
2025-10-08 16:16:08.418388 | controller |   "wusr": true,
2025-10-08 16:16:08.418402 | controller |   "xgrp": false,
2025-10-08 16:16:08.418417 | controller |   "xoth": false,
2025-10-08 16:16:08.418432 | controller |   "xusr": false
2025-10-08 16:16:08.418447 | controller | }
2025-10-08 16:16:09.069885 | controller | changed:
2025-10-08 16:16:09.070014 | controller | {
2025-10-08 16:16:09.070044 | controller |   "atime": 1743545331.0429637,
2025-10-08 16:16:09.070065 | controller |   "ctime": 1743545842.0111232,
2025-10-08 16:16:09.070084 | controller |   "dev": 64513,
2025-10-08 16:16:09.070102 | controller |   "gid": 1000,
2025-10-08 16:16:09.070119 | controller |   "gr_name": "zuul",
2025-10-08 16:16:09.070137 | controller |   "inode": 4194437,
2025-10-08 16:16:09.070153 | controller |   "isblk": false,
2025-10-08 16:16:09.070170 | controller |   "ischr": false,
2025-10-08 16:16:09.070186 | controller |   "isdir": false,
2025-10-08 16:16:09.070201 | controller |   "isfifo": false,
2025-10-08 16:16:09.070217 | controller |   "isgid": false,
2025-10-08 16:16:09.070232 | controller |   "islnk": false,
2025-10-08 16:16:09.070247 | controller |   "isreg": true,
2025-10-08 16:16:09.070263 | controller |   "issock": false,
2025-10-08 16:16:09.070280 | controller |   "isuid": false,
2025-10-08 16:16:09.070296 | controller |   "mode": "0644",
2025-10-08 16:16:09.070312 | controller |   "mtime": 1743545842.0111232,
2025-10-08 16:16:09.070334 | controller |   "nlink": 1,
2025-10-08 16:16:09.070352 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 16:16:09.070369 | controller |   "pw_name": "zuul",
2025-10-08 16:16:09.070386 | controller |   "rgrp": true,
2025-10-08 16:16:09.070402 | controller |   "roth": true,
2025-10-08 16:16:09.070418 | controller |   "rusr": true,
2025-10-08 16:16:09.070433 | controller |   "size": 4023,
2025-10-08 16:16:09.070450 | controller |   "uid": 1000,
2025-10-08 16:16:09.070465 | controller |   "wgrp": false,
2025-10-08 16:16:09.070482 | controller |   "woth": false,
2025-10-08 16:16:09.070498 | controller |   "wusr": true,
2025-10-08 16:16:09.070513 | controller |   "xgrp": false,
2025-10-08 16:16:09.070528 | controller |   "xoth": false,
2025-10-08 16:16:09.070543 | controller |   "xusr": false
2025-10-08 16:16:09.070558 | controller | }
2025-10-08 16:16:09.767943 | controller | changed:
2025-10-08 16:16:09.768057 | controller | {
2025-10-08 16:16:09.768095 | controller |   "atime": 1759939850.8778484,
2025-10-08 16:16:09.768155 | controller |   "ctime": 1759939866.83271,
2025-10-08 16:16:09.768195 | controller |   "dev": 64513,
2025-10-08 16:16:09.768247 | controller |   "gid": 1000,
2025-10-08 16:16:09.768287 | controller |   "gr_name": "zuul",
2025-10-08 16:16:09.768313 | controller |   "inode": 4518825,
2025-10-08 16:16:09.768337 | controller |   "isblk": false,
2025-10-08 16:16:09.768359 | controller |   "ischr": false,
2025-10-08 16:16:09.768384 | controller |   "isdir": false,
2025-10-08 16:16:09.768406 | controller |   "isfifo": false,
2025-10-08 16:16:09.768428 | controller |   "isgid": false,
2025-10-08 16:16:09.768449 | controller |   "islnk": false,
2025-10-08 16:16:09.768469 | controller |   "isreg": true,
2025-10-08 16:16:09.768489 | controller |   "issock": false,
2025-10-08 16:16:09.768508 | controller |   "isuid": false,
2025-10-08 16:16:09.768524 | controller |   "mode": "0644",
2025-10-08 16:16:09.768539 | controller |   "mtime": 1759939866.83271,
2025-10-08 16:16:09.768556 | controller |   "nlink": 1,
2025-10-08 16:16:09.768571 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 16:16:09.768586 | controller |   "pw_name": "zuul",
2025-10-08 16:16:09.768602 | controller |   "rgrp": true,
2025-10-08 16:16:09.768616 | controller |   "roth": true,
2025-10-08 16:16:09.768631 | controller |   "rusr": true,
2025-10-08 16:16:09.768646 | controller |   "size": 6749,
2025-10-08 16:16:09.768661 | controller |   "uid": 1000,
2025-10-08 16:16:09.768675 | controller |   "wgrp": false,
2025-10-08 16:16:09.768702 | controller |   "woth": false,
2025-10-08 16:16:09.768718 | controller |   "wusr": true,
2025-10-08 16:16:09.768734 | controller |   "xgrp": false,
2025-10-08 16:16:09.768749 | controller |   "xoth": false,
2025-10-08 16:16:09.768763 | controller |   "xusr": false
2025-10-08 16:16:09.768778 | controller | }
2025-10-08 16:16:09.776754 | 
2025-10-08 16:16:09.776852 | TASK [Copy crio stats log file]
2025-10-08 16:16:09.820431 | controller | skipping: Conditional result was False
2025-10-08 16:16:09.826650 | 
2025-10-08 16:16:09.826733 | TASK [Get SELinux related data]
2025-10-08 16:16:12.914382 | [controller] Waiting on logger
2025-10-08 16:16:25.011422 | [controller] Waiting on logger
2025-10-08 16:16:31.538288 | [controller] Waiting on logger
2025-10-08 16:16:40.194418 | [Zuul] Log Stream did not terminate
2025-10-08 16:16:40.194600 | controller | ERROR
2025-10-08 16:16:40.194706 | controller | {
2025-10-08 16:16:40.194736 | controller |   "delta": "0:00:00.009917",
2025-10-08 16:16:40.194757 | controller |   "end": "2025-10-08 16:16:10.169061",
2025-10-08 16:16:40.194776 | controller |   "msg": "non-zero return code",
2025-10-08 16:16:40.194794 | controller |   "rc": 1,
2025-10-08 16:16:40.194810 | controller |   "start": "2025-10-08 16:16:10.159144"
2025-10-08 16:16:40.194826 | controller | }
2025-10-08 16:16:40.194847 | controller | ERROR: Ignoring Errors
2025-10-08 16:16:40.200086 | 
2025-10-08 16:16:40.200152 | TASK [Create system configuration directory]
2025-10-08 16:16:40.473056 | controller | changed
2025-10-08 16:16:40.478038 | 
2025-10-08 16:16:40.478104 | TASK [Get some of the system configurations]
2025-10-08 16:16:40.499745 | [controller] Waiting on logger
2025-10-08 16:16:50.162351 | [controller] Waiting on logger
2025-10-08 16:16:54.563085 | controller | changed
2025-10-08 16:16:54.568366 | 
2025-10-08 16:16:54.568436 | TASK [Copy generated documentation if available]
2025-10-08 16:16:54.582652 | controller | skipping: Conditional result was False
2025-10-08 16:16:54.589128 | 
2025-10-08 16:16:54.589208 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 16:16:54.615240 | controller | skipping: Conditional result was False
2025-10-08 16:16:54.620960 | 
2025-10-08 16:16:54.621032 | TASK [Compress logs bigger than 2MB]
2025-10-08 16:16:54.643181 | [controller] Waiting on logger
2025-10-08 16:17:09.874437 | [controller] Waiting on logger
2025-10-08 16:17:13.266390 | [controller] Waiting on logger
2025-10-08 16:17:24.944558 | [Zuul] Log Stream did not terminate
2025-10-08 16:17:24.944761 | controller | changed
2025-10-08 16:17:24.950365 | 
2025-10-08 16:17:24.950441 | TASK [Copy files from workspace on node]
2025-10-08 16:17:24.969692 | controller | ok
2025-10-08 16:17:24.993510 | 
2025-10-08 16:17:24.993614 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 16:17:25.017330 | controller | skipping: Conditional result was False
2025-10-08 16:17:25.023528 | 
2025-10-08 16:17:25.023618 | TASK [fetch-output : Set log path for single node]
2025-10-08 16:17:25.052092 | controller | ok
2025-10-08 16:17:25.062576 | 
2025-10-08 16:17:25.062691 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 16:17:25.271192 | controller -> localhost | ok: "/var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/logs"
2025-10-08 16:17:25.271431 | controller -> localhost | changed: All items complete
2025-10-08 16:17:25.271461 | 
2025-10-08 16:17:25.464354 | controller -> localhost | changed: "/var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/artifacts"
2025-10-08 16:17:25.664397 | controller -> localhost | changed: "/var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/docs"
2025-10-08 16:17:25.673566 | 
2025-10-08 16:17:25.673642 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 16:17:26.356560 | controller | changed:
2025-10-08 16:17:26.356749 | controller | .d..t...... ./
2025-10-08 16:17:26.356782 | controller | >f+++++++++ README.html
2025-10-08 16:17:26.356814 | controller | >f+++++++++ ansible-execution.log
2025-10-08 16:17:26.356846 | controller | >f+++++++++ ansible.log
2025-10-08 16:17:26.356875 | controller | >f+++++++++ crc-setup.log
2025-10-08 16:17:26.356901 | controller | >f+++++++++ crc-start.log
2025-10-08 16:17:26.357606 | controller | >f+++++++++ dmesg.log
2025-10-08 16:17:26.357647 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 16:17:26.357685 | controller | >f+++++++++ python.log
2025-10-08 16:17:26.357709 | controller | >f+++++++++ registries.conf
2025-10-08 16:17:26.357729 | controller | >f+++++++++ report.html
2025-10-08 16:17:26.357747 | controller | >f+++++++++ selinux-denials.log
2025-10-08 16:17:26.357766 | controller | >f+++++++++ selinux-listing.log
2025-10-08 16:17:26.357785 | controller | cd+++++++++ ci-framework-data/
2025-10-08 16:17:26.357804 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 16:17:26.357822 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_verify_external_network.sh
2025-10-08 16:17:26.357840 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw
2025-10-08 16:17:26.357858 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw.pub
2025-10-08 16:17:26.357876 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 16:17:26.357893 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-08 16:17:26.357941 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-10-08 16:17:26.357964 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-10-08 16:17:26.357990 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 16:17:26.358009 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 16:17:26.358027 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 16:17:26.358043 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 16:17:26.358060 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 16:17:26.358077 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-10-08 16:17:26.358094 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 16:17:26.358112 | controller | cd+++++++++ registries.conf.d/
2025-10-08 16:17:26.358129 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 16:17:26.358231 | controller | cd+++++++++ system-config/
2025-10-08 16:17:26.358262 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 16:17:26.358283 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 16:17:26.358302 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 16:17:26.358320 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 16:17:26.358338 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 16:17:26.358354 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 16:17:26.358372 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 16:17:26.358389 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 16:17:26.358406 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 16:17:26.358422 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 16:17:26.358438 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 16:17:26.358454 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 16:17:26.358470 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 16:17:26.358489 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 16:17:26.358507 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 16:17:26.358523 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 16:17:26.358540 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 16:17:26.861236 | controller | changed: .d..t...... ./
2025-10-08 16:17:27.407223 | controller | changed: .d..t...... ./
2025-10-08 16:17:27.425573 | 
2025-10-08 16:17:27.425692 | TASK [Return artifact to Zuul]
2025-10-08 16:17:27.456745 | controller | ok
2025-10-08 16:17:27.479113 | 
2025-10-08 16:17:27.479189 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 16:17:27.479291 | 
2025-10-08 16:17:27.479319 | PLAY RECAP
2025-10-08 16:17:27.479360 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 16:17:27.479382 | 
2025-10-08 16:17:27.594019 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 16:17:27.594729 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 16:17:28.155460 | 
2025-10-08 16:17:28.155605 | PLAY [all]
2025-10-08 16:17:28.175651 | 
2025-10-08 16:17:28.175751 | TASK [include_role : fetch-output]
2025-10-08 16:17:28.219352 | controller | ok
2025-10-08 16:17:28.244121 | 
2025-10-08 16:17:28.244245 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 16:17:28.291476 | controller | skipping: Conditional result was False
2025-10-08 16:17:28.299543 | 
2025-10-08 16:17:28.299688 | TASK [fetch-output : Set log path for single node]
2025-10-08 16:17:28.328393 | controller | ok
2025-10-08 16:17:28.334136 | 
2025-10-08 16:17:28.334209 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 16:17:28.783408 | controller -> localhost | ok: "/var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/logs"
2025-10-08 16:17:29.028444 | controller -> localhost | ok: "/var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/artifacts"
2025-10-08 16:17:29.234427 | controller -> localhost | ok: "/var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/docs"
2025-10-08 16:17:29.248183 | 
2025-10-08 16:17:29.248291 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 16:17:29.906849 | controller | ok
2025-10-08 16:17:29.907086 | controller | ok: All items complete
2025-10-08 16:17:29.907117 | 
2025-10-08 16:17:30.390526 | controller | ok
2025-10-08 16:17:30.870351 | controller | ok
2025-10-08 16:17:30.891848 | 
2025-10-08 16:17:30.891979 | TASK [include_role : fetch-output-openshift]
2025-10-08 16:17:30.906459 | controller | skipping: Conditional result was False
2025-10-08 16:17:30.913735 | 
2025-10-08 16:17:30.913829 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 16:17:31.423434 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.037123
2025-10-08 16:17:31.648493 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008209
2025-10-08 16:17:31.690223 | 
2025-10-08 16:17:31.690330 | PLAY [all]
2025-10-08 16:17:31.705474 | 
2025-10-08 16:17:31.705543 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 16:17:32.213244 | controller | changed
2025-10-08 16:17:32.261012 | 
2025-10-08 16:17:32.261076 | PLAY RECAP
2025-10-08 16:17:32.261121 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 16:17:32.261146 | 
2025-10-08 16:17:32.355135 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 16:17:32.356194 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 16:17:33.051640 | 
2025-10-08 16:17:33.051776 | PLAY [localhost]
2025-10-08 16:17:33.070315 | 
2025-10-08 16:17:33.070439 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 16:17:33.600396 | localhost | changed
2025-10-08 16:17:33.605262 | 
2025-10-08 16:17:33.605396 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 16:17:33.632846 | localhost | ok
2025-10-08 16:17:33.641164 | 
2025-10-08 16:17:33.641245 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 16:17:34.055858 | localhost | changed
2025-10-08 16:17:34.062018 | 
2025-10-08 16:17:34.062113 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 16:17:34.800720 | localhost | changed
2025-10-08 16:17:34.805905 | 
2025-10-08 16:17:34.805994 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 16:17:35.168692 | localhost | Identity added: /var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/tmp/ansible.h0c1mhz5 (/var/lib/zuul/builds/bb8ba8f3a06b4b899f127670b46e06e7/work/tmp/ansible.h0c1mhz5)
2025-10-08 16:17:35.168865 | localhost | ok: Runtime: 0:00:00.006039
2025-10-08 16:17:35.173852 | 
2025-10-08 16:17:35.174053 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 16:17:35.414199 | localhost | ok: Runtime: 0:00:00.015995
2025-10-08 16:17:35.419957 | 
2025-10-08 16:17:35.420030 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 16:17:35.468319 | localhost | changed
2025-10-08 16:17:35.472547 | 
2025-10-08 16:17:35.472614 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 16:17:35.839767 | localhost | changed
2025-10-08 16:17:35.862692 | 
2025-10-08 16:17:35.862761 | PLAY [localhost]
2025-10-08 16:17:35.875756 | 
2025-10-08 16:17:35.875825 | TASK [Generate bulk log download script]
2025-10-08 16:17:35.903765 | localhost | ok
2025-10-08 16:17:35.921276 | 
2025-10-08 16:17:35.921466 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 16:17:35.980596 | localhost | ok: All assertions passed
2025-10-08 16:17:35.985894 | 
2025-10-08 16:17:35.988029 | TASK [local-log-download : Create download script]
2025-10-08 16:17:36.470982 | localhost -> localhost | changed
2025-10-08 16:17:36.481182 | 
2025-10-08 16:17:36.481265 | TASK [Register quick-download link]
2025-10-08 16:17:36.521852 | localhost | ok
2025-10-08 16:17:36.564576 | 
2025-10-08 16:17:36.564684 | PLAY [logserver.rdoproject.org]
2025-10-08 16:17:36.576067 | 
2025-10-08 16:17:36.576140 | TASK [Set zuul-log-path fact]
2025-10-08 16:17:36.603476 | logserver.rdoproject.org | ok
2025-10-08 16:17:36.619229 | 
2025-10-08 16:17:36.619322 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 16:17:36.658041 | logserver.rdoproject.org | ok
2025-10-08 16:17:36.664293 | 
2025-10-08 16:17:36.664363 | TASK [upload-logs : Create log directories]
2025-10-08 16:17:38.356624 | logserver.rdoproject.org | changed
2025-10-08 16:17:38.360126 | 
2025-10-08 16:17:38.360192 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 16:17:38.738846 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005221
2025-10-08 16:17:38.743642 | 
2025-10-08 16:17:38.743736 | TASK [upload-logs : Upload logs to log server]
2025-10-08 16:17:40.314367 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 16:17:40.317432 | 
2025-10-08 16:17:40.317499 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 16:17:40.365464 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:17:40.375608 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:17:40.385357 | 
2025-10-08 16:17:40.385454 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 16:17:40.425877 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:17:40.426148 | 
2025-10-08 16:17:40.429794 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:17:40.444098 | 
2025-10-08 16:17:40.444186 | LOOP [upload-logs : Upload console log and json output]