2025-09-30 08:01:13.649431 | Job console starting...
2025-09-30 08:01:14.175306 | Updating repositories
2025-09-30 08:01:15.319912 | Preparing job workspace
2025-09-30 08:01:34.596756 | Running Ansible setup...
2025-09-30 08:01:44.930298 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 08:01:48.444301 | 
2025-09-30 08:01:48.444462 | PLAY [localhost]
2025-09-30 08:01:48.483167 | 
2025-09-30 08:01:48.483272 | TASK [Gathering Facts]
2025-09-30 08:01:51.748137 | localhost | ok
2025-09-30 08:01:51.783484 | 
2025-09-30 08:01:51.783588 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-30 08:01:53.116918 | localhost -> localhost | changed
2025-09-30 08:01:53.123285 | 
2025-09-30 08:01:53.123396 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-30 08:01:56.146931 | localhost -> localhost | changed
2025-09-30 08:01:56.156264 | 
2025-09-30 08:01:56.156347 | TASK [Setup log path fact]
2025-09-30 08:01:56.199912 | localhost | ok
2025-09-30 08:01:56.240217 | 
2025-09-30 08:01:56.240303 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 08:01:56.318752 | localhost | ok
2025-09-30 08:01:56.340003 | 
2025-09-30 08:01:56.340086 | TASK [emit-job-header : Print job information]
2025-09-30 08:01:56.622265 | # Job Information
2025-09-30 08:01:56.622437 | Ansible Version: 2.15.12
2025-09-30 08:01:56.622472 | Job: cifmw-molecule-openshift_provisioner_node
2025-09-30 08:01:56.622496 | Pipeline: github-check
2025-09-30 08:01:56.622517 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-09-30 08:01:56.622538 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333
2025-09-30 08:01:56.622558 | Log URL (when completed): https://logserver.rdoproject.org/e97/rdoproject.org/e971761159db4c20ae481377ce749af1/
2025-09-30 08:01:56.622579 | Event ID: 4f943a60-9dd3-11f0-8665-ec0281ef448c
2025-09-30 08:01:56.626309 | 
2025-09-30 08:01:56.626407 | LOOP [emit-job-header : Print node information]
2025-09-30 08:01:56.808248 | localhost | ok:
2025-09-30 08:01:56.808467 | localhost | # Node Information
2025-09-30 08:01:56.808501 | localhost | Inventory Hostname: controller
2025-09-30 08:01:56.808527 | localhost | Hostname: np0005462090
2025-09-30 08:01:56.808548 | localhost | Username: zuul
2025-09-30 08:01:56.808569 | localhost | Distro: CentOS 9
2025-09-30 08:01:56.808588 | localhost | Provider: ibm-bm3-nodepool
2025-09-30 08:01:56.808607 | localhost | Region: regionOne
2025-09-30 08:01:56.808636 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-09-30 08:01:56.808656 | localhost | Product Name: OpenStack Compute
2025-09-30 08:01:56.808673 | localhost | Interface IP: 192.168.25.191
2025-09-30 08:01:56.884294 | 
2025-09-30 08:01:56.884413 | PLAY [all]
2025-09-30 08:01:56.920332 | 
2025-09-30 08:01:56.920922 | TASK [Gather network facts]
2025-09-30 08:01:57.489122 | controller | ok
2025-09-30 08:01:57.558686 | 
2025-09-30 08:01:57.558781 | TASK [include_role : start-zuul-console]
2025-09-30 08:01:57.629511 | controller | ok
2025-09-30 08:01:57.705041 | 
2025-09-30 08:01:57.705130 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-30 08:01:58.776173 | controller | ok
2025-09-30 08:01:58.801981 | 
2025-09-30 08:01:58.802062 | TASK [include_role : add-build-sshkey]
2025-09-30 08:01:58.908833 | controller | ok
2025-09-30 08:01:58.974328 | 
2025-09-30 08:01:58.974435 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-30 08:01:59.874604 | controller -> localhost | ok
2025-09-30 08:01:59.880122 | 
2025-09-30 08:01:59.880201 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-30 08:01:59.930424 | controller | ok
2025-09-30 08:01:59.970159 | controller | included: /var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-30 08:01:59.998715 | 
2025-09-30 08:01:59.998788 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-30 08:02:02.726727 | controller -> localhost | Generating public/private rsa key pair.
2025-09-30 08:02:02.726919 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/e971761159db4c20ae481377ce749af1_id_rsa.
2025-09-30 08:02:02.726953 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/e971761159db4c20ae481377ce749af1_id_rsa.pub.
2025-09-30 08:02:02.726980 | controller -> localhost | The key fingerprint is:
2025-09-30 08:02:02.727005 | controller -> localhost | SHA256:mDTZ2doTJuuBCek6xm2dg9IkonRVsY9w/DanTrFlMIU zuul-build-sshkey
2025-09-30 08:02:02.727028 | controller -> localhost | The key's randomart image is:
2025-09-30 08:02:02.727049 | controller -> localhost | +---[RSA 3072]----+
2025-09-30 08:02:02.727069 | controller -> localhost | |       o. ..     |
2025-09-30 08:02:02.727090 | controller -> localhost | |     .oo.E.      |
2025-09-30 08:02:02.727111 | controller -> localhost | |    oo+++o+      |
2025-09-30 08:02:02.727130 | controller -> localhost | |   ..oo*+*o.     |
2025-09-30 08:02:02.727149 | controller -> localhost | |....o =.S*o+     |
2025-09-30 08:02:02.727168 | controller -> localhost | |oo.* o o..O.     |
2025-09-30 08:02:02.727186 | controller -> localhost | |. * = + .+       |
2025-09-30 08:02:02.727208 | controller -> localhost | | . +   .o        |
2025-09-30 08:02:02.727229 | controller -> localhost | |         .       |
2025-09-30 08:02:02.727248 | controller -> localhost | +----[SHA256]-----+
2025-09-30 08:02:02.727292 | controller -> localhost | ok: Runtime: 0:00:01.129939
2025-09-30 08:02:02.741902 | 
2025-09-30 08:02:02.741966 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-30 08:02:02.885712 | controller | ok
2025-09-30 08:02:02.908040 | controller | included: /var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-30 08:02:02.933036 | 
2025-09-30 08:02:02.933110 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-30 08:02:03.030505 | controller | skipping: Conditional result was False
2025-09-30 08:02:03.036407 | 
2025-09-30 08:02:03.036497 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-30 08:02:03.911563 | controller | changed
2025-09-30 08:02:03.917551 | 
2025-09-30 08:02:03.917628 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-30 08:02:04.328844 | controller | ok
2025-09-30 08:02:04.345029 | 
2025-09-30 08:02:04.345116 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-30 08:02:05.876558 | controller | changed
2025-09-30 08:02:05.882372 | 
2025-09-30 08:02:05.882453 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-30 08:02:07.336528 | controller | changed
2025-09-30 08:02:07.342219 | 
2025-09-30 08:02:07.342282 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-30 08:02:07.392755 | controller | skipping: Conditional result was False
2025-09-30 08:02:07.399049 | 
2025-09-30 08:02:07.399133 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-30 08:02:08.278102 | controller -> localhost | changed
2025-09-30 08:02:08.287988 | 
2025-09-30 08:02:08.288053 | TASK [add-build-sshkey : Add back temp key]
2025-09-30 08:02:09.027737 | controller -> localhost | Identity added: /var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/e971761159db4c20ae481377ce749af1_id_rsa (zuul-build-sshkey)
2025-09-30 08:02:09.027944 | controller -> localhost | ok: Runtime: 0:00:00.023827
2025-09-30 08:02:09.040325 | 
2025-09-30 08:02:09.045315 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-30 08:02:09.578641 | controller | ok
2025-09-30 08:02:09.593058 | 
2025-09-30 08:02:09.593141 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-30 08:02:09.661512 | controller | skipping: Conditional result was False
2025-09-30 08:02:09.671192 | 
2025-09-30 08:02:09.671291 | TASK [include_role : validate-host]
2025-09-30 08:02:09.747547 | controller | ok
2025-09-30 08:02:09.794863 | 
2025-09-30 08:02:09.794977 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-30 08:02:09.864222 | controller | ok
2025-09-30 08:02:09.876673 | 
2025-09-30 08:02:09.876750 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-30 08:02:10.278796 | controller -> localhost | ok
2025-09-30 08:02:10.289947 | 
2025-09-30 08:02:10.290030 | TASK [validate-host : Collect information about the host]
2025-09-30 08:02:11.016605 | controller | ok
2025-09-30 08:02:11.028651 | 
2025-09-30 08:02:11.034003 | TASK [validate-host : Sanitize hostname]
2025-09-30 08:02:11.113789 | controller | ok
2025-09-30 08:02:11.118920 | 
2025-09-30 08:02:11.118988 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-30 08:02:11.805536 | controller -> localhost | changed
2025-09-30 08:02:11.811150 | 
2025-09-30 08:02:11.811234 | TASK [validate-host : Collect information about zuul worker]
2025-09-30 08:02:12.490149 | controller | ok
2025-09-30 08:02:12.495485 | 
2025-09-30 08:02:12.495554 | TASK [validate-host : Write out all zuul information for each host]
2025-09-30 08:02:13.006872 | controller -> localhost | changed
2025-09-30 08:02:13.017191 | 
2025-09-30 08:02:13.017262 | TASK [include_role : prepare-workspace-openshift]
2025-09-30 08:02:13.030897 | controller | skipping: Conditional result was False
2025-09-30 08:02:13.036349 | 
2025-09-30 08:02:13.036436 | TASK [include_role : remove-zuul-sshkey]
2025-09-30 08:02:13.049901 | controller | skipping: Conditional result was False
2025-09-30 08:02:13.056602 | 
2025-09-30 08:02:13.056697 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-30 08:02:13.322672 | controller | ok: "logs"
2025-09-30 08:02:13.322886 | controller | ok: All items complete
2025-09-30 08:02:13.322916 | 
2025-09-30 08:02:13.551353 | controller | ok: "artifacts"
2025-09-30 08:02:13.776782 | controller | ok: "docs"
2025-09-30 08:02:13.783829 | 
2025-09-30 08:02:13.783909 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-30 08:02:14.092198 | controller | changed: "logs"
2025-09-30 08:02:14.330078 | controller | changed: "artifacts"
2025-09-30 08:02:14.565199 | controller | changed: "docs"
2025-09-30 08:02:14.597790 | 
2025-09-30 08:02:14.597863 | PLAY RECAP
2025-09-30 08:02:14.597908 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 08:02:14.597938 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 08:02:14.597959 | 
2025-09-30 08:02:14.743410 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 08:02:14.744146 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 08:02:15.449195 | 
2025-09-30 08:02:15.449285 | PLAY [all]
2025-09-30 08:02:15.468637 | 
2025-09-30 08:02:15.468714 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 08:02:15.517953 | controller | ok
2025-09-30 08:02:15.523154 | 
2025-09-30 08:02:15.523239 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 08:02:16.068254 | controller | changed
2025-09-30 08:02:16.073719 | 
2025-09-30 08:02:16.073790 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 08:02:17.684523 | controller | changed
2025-09-30 08:02:17.694637 | 
2025-09-30 08:02:17.694717 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-30 08:02:18.166363 | controller | changed:
2025-09-30 08:02:18.166613 | controller | {
2025-09-30 08:02:18.166648 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-30 08:02:18.166683 | controller | }
2025-09-30 08:02:18.451311 | controller | changed:
2025-09-30 08:02:18.451429 | controller | {
2025-09-30 08:02:18.451466 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-30 08:02:18.451672 | controller | }
2025-09-30 08:02:18.733888 | controller | changed:
2025-09-30 08:02:18.733971 | controller | {
2025-09-30 08:02:18.734000 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-30 08:02:18.734021 | controller | }
2025-09-30 08:02:19.014858 | controller | changed:
2025-09-30 08:02:19.014942 | controller | {
2025-09-30 08:02:19.014970 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-30 08:02:19.014992 | controller | }
2025-09-30 08:02:19.298593 | controller | changed:
2025-09-30 08:02:19.298682 | controller | {
2025-09-30 08:02:19.298708 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-30 08:02:19.298728 | controller | }
2025-09-30 08:02:19.592283 | controller | changed:
2025-09-30 08:02:19.592373 | controller | {
2025-09-30 08:02:19.592553 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-30 08:02:19.592591 | controller | }
2025-09-30 08:02:19.879162 | controller | changed:
2025-09-30 08:02:19.879241 | controller | {
2025-09-30 08:02:19.879268 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-09-30 08:02:19.879290 | controller | }
2025-09-30 08:02:20.165969 | controller | changed:
2025-09-30 08:02:20.166129 | controller | {
2025-09-30 08:02:20.166157 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-09-30 08:02:20.166181 | controller | }
2025-09-30 08:02:20.454616 | controller | changed:
2025-09-30 08:02:20.454711 | controller | {
2025-09-30 08:02:20.454737 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-30 08:02:20.454758 | controller | }
2025-09-30 08:02:20.742479 | controller | changed:
2025-09-30 08:02:20.742589 | controller | {
2025-09-30 08:02:20.742617 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-30 08:02:20.742638 | controller | }
2025-09-30 08:02:21.035657 | controller | changed:
2025-09-30 08:02:21.035744 | controller | {
2025-09-30 08:02:21.035772 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-30 08:02:21.035792 | controller | }
2025-09-30 08:02:21.329933 | controller | changed:
2025-09-30 08:02:21.330018 | controller | {
2025-09-30 08:02:21.330046 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-30 08:02:21.330154 | controller | }
2025-09-30 08:02:21.634280 | controller | changed:
2025-09-30 08:02:21.634422 | controller | {
2025-09-30 08:02:21.634455 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-30 08:02:21.634479 | controller | }
2025-09-30 08:02:21.946460 | controller | changed:
2025-09-30 08:02:21.946560 | controller | {
2025-09-30 08:02:21.946591 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-30 08:02:21.946614 | controller | }
2025-09-30 08:02:22.241734 | controller | changed:
2025-09-30 08:02:22.241809 | controller | {
2025-09-30 08:02:22.241837 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-30 08:02:22.241858 | controller | }
2025-09-30 08:02:22.547804 | controller | changed:
2025-09-30 08:02:22.547968 | controller | {
2025-09-30 08:02:22.548004 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-30 08:02:22.548028 | controller | }
2025-09-30 08:02:22.853539 | controller | changed:
2025-09-30 08:02:22.853631 | controller | {
2025-09-30 08:02:22.853659 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-09-30 08:02:22.853680 | controller | }
2025-09-30 08:02:23.143461 | controller | changed:
2025-09-30 08:02:23.143543 | controller | {
2025-09-30 08:02:23.143583 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-09-30 08:02:23.143613 | controller | }
2025-09-30 08:02:23.433306 | controller | changed:
2025-09-30 08:02:23.433414 | controller | {
2025-09-30 08:02:23.433455 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-30 08:02:23.433483 | controller | }
2025-09-30 08:02:23.731313 | controller | changed:
2025-09-30 08:02:23.731403 | controller | {
2025-09-30 08:02:23.731439 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-30 08:02:23.731462 | controller | }
2025-09-30 08:02:24.035353 | controller | changed:
2025-09-30 08:02:24.035503 | controller | {
2025-09-30 08:02:24.035534 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-30 08:02:24.035568 | controller | }
2025-09-30 08:02:24.338211 | controller | changed:
2025-09-30 08:02:24.338298 | controller | {
2025-09-30 08:02:24.338325 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-30 08:02:24.338344 | controller | }
2025-09-30 08:02:24.622014 | controller | changed:
2025-09-30 08:02:24.622103 | controller | {
2025-09-30 08:02:24.622129 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-09-30 08:02:24.622150 | controller | }
2025-09-30 08:02:24.933039 | controller | changed:
2025-09-30 08:02:24.933174 | controller | {
2025-09-30 08:02:24.933204 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-30 08:02:24.933226 | controller | }
2025-09-30 08:02:25.232315 | controller | changed:
2025-09-30 08:02:25.232418 | controller | {
2025-09-30 08:02:25.232452 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-30 08:02:25.232475 | controller | }
2025-09-30 08:02:25.537505 | controller | changed:
2025-09-30 08:02:25.537597 | controller | {
2025-09-30 08:02:25.537624 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-30 08:02:25.537645 | controller | }
2025-09-30 08:02:25.567122 | 
2025-09-30 08:02:25.567200 | TASK [Set timezone to UTC]
2025-09-30 08:02:26.259241 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-30 08:02:26.264077 | 
2025-09-30 08:02:26.264156 | TASK [Create nodepool directory]
2025-09-30 08:02:26.565728 | controller | changed
2025-09-30 08:02:26.571296 | 
2025-09-30 08:02:26.571396 | TASK [Create nodepool sub_nodes file]
2025-09-30 08:02:27.745135 | controller | changed
2025-09-30 08:02:27.751188 | 
2025-09-30 08:02:27.751267 | TASK [Create nodepool sub_nodes_private file]
2025-09-30 08:02:28.913229 | controller | changed
2025-09-30 08:02:28.919488 | 
2025-09-30 08:02:28.919563 | LOOP [Populate nodepool sub_nodes file]
2025-09-30 08:02:28.982205 | 
2025-09-30 08:02:28.982356 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-30 08:02:29.027112 | 
2025-09-30 08:02:29.028785 | TASK [Create nodepool primary file]
2025-09-30 08:02:29.062324 | controller | skipping: Conditional result was False
2025-09-30 08:02:29.068825 | 
2025-09-30 08:02:29.068927 | TASK [Create nodepool node_private for this node]
2025-09-30 08:02:30.245049 | controller | changed
2025-09-30 08:02:30.251148 | 
2025-09-30 08:02:30.251227 | LOOP [Copy ssh keys to nodepool directory]
2025-09-30 08:02:30.776627 | controller | ok: Item: id_rsa Runtime: 0:00:00.004343
2025-09-30 08:02:30.776801 | 
2025-09-30 08:02:31.019250 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005260
2025-09-30 08:02:31.028071 | 
2025-09-30 08:02:31.028167 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-30 08:02:32.042535 | controller | changed
2025-09-30 08:02:32.047480 | 
2025-09-30 08:02:32.047562 | TASK [Validate sudoers config after edits]
2025-09-30 08:02:32.341568 | controller | /etc/sudoers: parsed OK
2025-09-30 08:02:32.341626 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-30 08:02:32.341634 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-30 08:02:32.341640 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-30 08:02:32.582591 | controller | ok: Runtime: 0:00:00.005110
2025-09-30 08:02:32.588586 | 
2025-09-30 08:02:32.588653 | TASK [Show the environment passed in to job shell scripts]
2025-09-30 08:02:32.883095 | controller | SHELL=/bin/bash
2025-09-30 08:02:32.883144 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-30 08:02:32.883154 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-30 08:02:32.883161 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/eb5b53fb17db92a72d33d052a56c8edd0d10ea78^openstack-k8s-operators/ci-framework:main:refs/changes/52/3352/6442e37e2e901d750c5bf184fc25fe0337bd2e95^openstack-k8s-operators/ci-framework:main:refs/changes/33/3333/b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 08:02:32.883167 | controller | PWD=/home/zuul
2025-09-30 08:02:32.883172 | controller | ZUUL_PIPELINE=github-check
2025-09-30 08:02:32.883177 | controller | LOGNAME=zuul
2025-09-30 08:02:32.883182 | controller | XDG_SESSION_TYPE=tty
2025-09-30 08:02:32.883222 | controller | _=/usr/bin/env
2025-09-30 08:02:32.883229 | controller | MOTD_SHOWN=pam
2025-09-30 08:02:32.883235 | controller | HOME=/home/zuul
2025-09-30 08:02:32.883240 | controller | LANG=en_US.UTF-8
2025-09-30 08:02:32.883245 | controller | SSH_CONNECTION=192.168.25.12 36630 192.168.25.191 22
2025-09-30 08:02:32.883250 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-09-30 08:02:32.883259 | controller | ZUUL_CHANGE_IDS=3346,eb5b53fb17db92a72d33d052a56c8edd0d10ea78 3352,6442e37e2e901d750c5bf184fc25fe0337bd2e95 3333,b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 08:02:32.883264 | controller | WORKSPACE=/home/zuul/workspace
2025-09-30 08:02:32.883270 | controller | XDG_SESSION_CLASS=user
2025-09-30 08:02:32.883275 | controller | SELINUX_ROLE_REQUESTED=
2025-09-30 08:02:32.883280 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-30 08:02:32.883285 | controller | USER=zuul
2025-09-30 08:02:32.883290 | controller | ZUUL_VOTING=True
2025-09-30 08:02:32.883296 | controller | BUILD_TIMEOUT=1800000
2025-09-30 08:02:32.883301 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-30 08:02:32.883306 | controller | SHLVL=1
2025-09-30 08:02:32.883311 | controller | ZUUL_PATCHSET=b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 08:02:32.883317 | controller | XDG_SESSION_ID=1
2025-09-30 08:02:32.883322 | controller | ZUUL_BRANCH=main
2025-09-30 08:02:32.883327 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-30 08:02:32.883333 | controller | SSH_CLIENT=192.168.25.12 36630 22
2025-09-30 08:02:32.883338 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-30 08:02:32.883343 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-30 08:02:32.883348 | controller | which_declare=declare -f
2025-09-30 08:02:32.883354 | 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-09-30 08:02:32.883359 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-30 08:02:32.883364 | controller | ZUUL_CHANGE=3333
2025-09-30 08:02:32.883369 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-30 08:02:32.883374 | controller | ZUUL_UUID=e971761159db4c20ae481377ce749af1
2025-09-30 08:02:32.883381 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-30 08:02:32.883387 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-30 08:02:32.883392 | controller | }
2025-09-30 08:02:33.129107 | controller | ok: Runtime: 0:00:00.008038
2025-09-30 08:02:33.136277 | 
2025-09-30 08:02:33.136354 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-30 08:02:33.161081 | controller | skipping: Conditional result was False
2025-09-30 08:02:33.169175 | 
2025-09-30 08:02:33.169492 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-30 08:02:33.752642 | controller | skipping: Conditional result was False
2025-09-30 08:02:33.758300 | 
2025-09-30 08:02:33.758369 | TASK [Ensure legacy workspace directory]
2025-09-30 08:02:34.009166 | controller | changed
2025-09-30 08:02:34.026799 | 
2025-09-30 08:02:34.026876 | PLAY RECAP
2025-09-30 08:02:34.026922 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 08:02:34.026944 | 
2025-09-30 08:02:34.105926 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 08:02:34.106726 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 08:02:34.640242 | 
2025-09-30 08:02:34.640485 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-30 08:02:34.660463 | 
2025-09-30 08:02:34.660748 | TASK [Create zuul-output directory]
2025-09-30 08:02:35.050503 | controller | changed
2025-09-30 08:02:35.055635 | 
2025-09-30 08:02:35.055711 | TASK [Slurp Zuul inventory test]
2025-09-30 08:02:35.353120 | controller -> localhost | ok
2025-09-30 08:02:35.359153 | 
2025-09-30 08:02:35.359224 | TASK [Save zuul inventory]
2025-09-30 08:02:36.638562 | controller | changed
2025-09-30 08:02:36.643915 | 
2025-09-30 08:02:36.643995 | TASK [Save zuul vars without the change_message]
2025-09-30 08:02:37.789534 | controller | changed
2025-09-30 08:02:37.807976 | 
2025-09-30 08:02:37.808034 | PLAY RECAP
2025-09-30 08:02:37.808075 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 08:02:37.808096 | 
2025-09-30 08:02:37.879495 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 08:02:37.880375 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 08:02:38.498745 | 
2025-09-30 08:02:38.498850 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-30 08:02:38.519594 | 
2025-09-30 08:02:38.519699 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-30 08:02:38.569079 | controller | ok
2025-09-30 08:02:38.585763 | 
2025-09-30 08:02:38.585858 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 08:02:38.609911 | controller | skipping: Conditional result was False
2025-09-30 08:02:38.615326 | 
2025-09-30 08:02:38.615415 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 08:02:39.029213 | controller | ok
2025-09-30 08:02:39.035125 | 
2025-09-30 08:02:39.035221 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 08:02:40.017066 | controller | ok
2025-09-30 08:02:40.026911 | 
2025-09-30 08:02:40.026979 | TASK [Prepare workspace]
2025-09-30 08:02:40.045854 | controller | ok
2025-09-30 08:02:40.063038 | 
2025-09-30 08:02:40.063113 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-30 08:02:40.440081 | controller | ok
2025-09-30 08:02:40.446196 | 
2025-09-30 08:02:40.446357 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-30 08:02:42.700106 | controller | Output suppressed because no_log was given
2025-09-30 08:02:42.709566 | 
2025-09-30 08:02:42.709644 | LOOP [Create zuul-output directory]
2025-09-30 08:02:42.981159 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-30 08:02:43.220237 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-30 08:02:43.230302 | 
2025-09-30 08:02:43.230516 | TASK [Install required packages]
2025-09-30 08:03:04.362103 | controller | ok: Nothing to do
2025-09-30 08:03:04.367707 | 
2025-09-30 08:03:04.367789 | TASK [Install venv]
2025-09-30 08:04:05.686293 | controller | changed
2025-09-30 08:04:05.724109 | 
2025-09-30 08:04:05.724198 | PLAY RECAP
2025-09-30 08:04:05.724243 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 08:04:05.724267 | 
2025-09-30 08:04:05.815641 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 08:04:05.816459 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 08:04:06.348613 | 
2025-09-30 08:04:06.348780 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-30 08:04:06.370234 | 
2025-09-30 08:04:06.370320 | TASK [Gather required facts]
2025-09-30 08:04:06.926919 | controller | ok
2025-09-30 08:04:06.932698 | 
2025-09-30 08:04:06.932794 | TASK [Load environment var if instructed to]
2025-09-30 08:04:06.956272 | controller | skipping: Conditional result was False
2025-09-30 08:04:06.962140 | 
2025-09-30 08:04:06.962214 | TASK [Run molecule]
2025-09-30 08:04:07.972746 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-30 08:04:08.042577 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-30 08:04:27.953500 | 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-09-30 08:04:27.953954 | 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-09-30 08:04:27.954412 | 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-09-30 08:04:27.954886 | 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-09-30 08:04:27.955365 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+2ea19224 was found installed in /home/zuul/.cache/ansible-compat/887b1e/collections/ansible_collections, only the first one will be used, 1.0.0+2ea19224 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 08:04:27.955830 | 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-09-30 08:04:27.956276 | 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-09-30 08:04:27.956740 | 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-09-30 08:04:27.957199 | 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-09-30 08:04:27.957638 | 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-09-30 08:04:27.958094 | 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-09-30 08:04:27.958514 | 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-09-30 08:04:27.958994 | 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-09-30 08:04:27.959429 | 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-09-30 08:04:27.959880 | 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-09-30 08:04:27.969166 | controller | INFO     Running default > prepare
2025-09-30 08:04:28.699933 | controller |
2025-09-30 08:04:28.700012 | controller | PLAY [Prepare] *****************************************************************
2025-09-30 08:04:28.700099 | controller |
2025-09-30 08:04:28.700197 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 08:04:28.700294 | controller | Tuesday 30 September 2025  08:04:28 +0000 (0:00:00.027)       0:00:00.027 *****
2025-09-30 08:04:29.595272 | controller | ok: [instance]
2025-09-30 08:04:29.595316 | controller |
2025-09-30 08:04:29.595441 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-30 08:04:29.595560 | controller | Tuesday 30 September 2025  08:04:29 +0000 (0:00:00.895)       0:00:00.923 *****
2025-09-30 08:04:29.613725 | controller | skipping: [instance]
2025-09-30 08:04:29.613834 | controller |
2025-09-30 08:04:29.613973 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-30 08:04:29.614109 | controller | Tuesday 30 September 2025  08:04:29 +0000 (0:00:00.018)       0:00:00.942 *****
2025-09-30 08:04:29.662254 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-09-30 08:04:29.662358 | controller |
2025-09-30 08:04:29.662479 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-30 08:04:29.662608 | controller | Tuesday 30 September 2025  08:04:29 +0000 (0:00:00.048)       0:00:00.990 *****
2025-09-30 08:04:29.981152 | controller | ok: [instance]
2025-09-30 08:04:29.981224 | controller |
2025-09-30 08:04:29.981355 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-30 08:04:29.981465 | controller | Tuesday 30 September 2025  08:04:29 +0000 (0:00:00.318)       0:00:01.309 *****
2025-09-30 08:04:30.317350 | controller | ok: [instance]
2025-09-30 08:04:30.317447 | controller |
2025-09-30 08:04:30.317574 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-30 08:04:30.317720 | controller | Tuesday 30 September 2025  08:04:30 +0000 (0:00:00.336)       0:00:01.645 *****
2025-09-30 08:04:31.055552 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-30 08:04:31.055674 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-30 08:04:31.055807 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-30 08:04:31.055926 | controller |
2025-09-30 08:04:31.056074 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-30 08:04:31.056165 | controller | Tuesday 30 September 2025  08:04:31 +0000 (0:00:00.738)       0:00:02.383 *****
2025-09-30 08:04:31.117345 | controller |
2025-09-30 08:04:31.117386 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-30 08:04:31.117483 | controller | Tuesday 30 September 2025  08:04:31 +0000 (0:00:00.061)       0:00:02.445 *****
2025-09-30 08:04:31.692839 | controller | changed: [instance] => (item=tmp)
2025-09-30 08:04:31.692893 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-30 08:04:31.693040 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-30 08:04:31.693137 | controller |
2025-09-30 08:04:31.693239 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-30 08:04:31.693402 | controller | Tuesday 30 September 2025  08:04:31 +0000 (0:00:00.575)       0:00:03.021 *****
2025-09-30 08:04:32.567263 | controller | ok: [instance]
2025-09-30 08:04:33.788737 | controller |
2025-09-30 08:04:33.788774 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-30 08:04:33.788789 | controller | Tuesday 30 September 2025  08:04:32 +0000 (0:00:00.873)       0:00:03.895 *****
2025-09-30 08:04:33.788801 | controller | changed: [instance]
2025-09-30 08:04:33.788830 | controller |
2025-09-30 08:04:33.788848 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-30 08:04:33.788997 | controller | Tuesday 30 September 2025  08:04:33 +0000 (0:00:01.221)       0:00:05.116 *****
2025-09-30 08:04:41.811014 | controller | changed: [instance]
2025-09-30 08:04:42.553198 | controller |
2025-09-30 08:04:42.553239 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-30 08:04:42.553247 | controller | Tuesday 30 September 2025  08:04:41 +0000 (0:00:08.022)       0:00:13.139 *****
2025-09-30 08:04:42.553258 | controller | changed: [instance]
2025-09-30 08:04:42.578643 | controller |
2025-09-30 08:04:42.578685 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-30 08:04:42.578694 | controller | Tuesday 30 September 2025  08:04:42 +0000 (0:00:00.742)       0:00:13.881 *****
2025-09-30 08:04:42.578705 | controller | skipping: [instance]
2025-09-30 08:04:42.578723 | controller |
2025-09-30 08:04:42.578732 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-30 08:04:42.578834 | controller | Tuesday 30 September 2025  08:04:42 +0000 (0:00:00.025)       0:00:13.907 *****
2025-09-30 08:04:43.439326 | controller | changed: [instance]
2025-09-30 08:04:43.482344 | controller |
2025-09-30 08:04:43.482381 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-30 08:04:43.482389 | controller | Tuesday 30 September 2025  08:04:43 +0000 (0:00:00.859)       0:00:14.767 *****
2025-09-30 08:04:43.482399 | controller | skipping: [instance]
2025-09-30 08:04:43.524921 | controller |
2025-09-30 08:04:43.525038 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-30 08:04:43.525051 | controller | Tuesday 30 September 2025  08:04:43 +0000 (0:00:00.043)       0:00:14.810 *****
2025-09-30 08:04:43.525074 | controller | skipping: [instance]
2025-09-30 08:04:43.565798 | controller |
2025-09-30 08:04:43.565855 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-30 08:04:43.565866 | controller | Tuesday 30 September 2025  08:04:43 +0000 (0:00:00.041)       0:00:14.852 *****
2025-09-30 08:04:43.565878 | controller | skipping: [instance]
2025-09-30 08:04:43.565909 | controller |
2025-09-30 08:04:43.565920 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-30 08:04:43.565946 | controller | Tuesday 30 September 2025  08:04:43 +0000 (0:00:00.041)       0:00:14.894 *****
2025-09-30 08:04:44.135514 | controller | changed: [instance]
2025-09-30 08:04:44.661656 | controller |
2025-09-30 08:04:44.661698 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-30 08:04:44.661708 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.569)       0:00:15.463 *****
2025-09-30 08:04:44.661721 | controller | changed: [instance]
2025-09-30 08:04:44.661787 | controller |
2025-09-30 08:04:44.661820 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-30 08:04:44.661832 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.526)       0:00:15.989 *****
2025-09-30 08:04:44.693795 | controller | skipping: [instance]
2025-09-30 08:04:44.724166 | controller |
2025-09-30 08:04:44.724201 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-30 08:04:44.724209 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.032)       0:00:16.021 *****
2025-09-30 08:04:44.724226 | controller | skipping: [instance]
2025-09-30 08:04:44.764159 | controller |
2025-09-30 08:04:44.764188 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-30 08:04:44.764196 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.030)       0:00:16.052 *****
2025-09-30 08:04:44.764206 | controller | skipping: [instance]
2025-09-30 08:04:44.811254 | controller |
2025-09-30 08:04:44.811284 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-30 08:04:44.811292 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.040)       0:00:16.092 *****
2025-09-30 08:04:44.811302 | controller | ok: [instance]
2025-09-30 08:04:44.841307 | controller |
2025-09-30 08:04:44.841333 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-30 08:04:44.841341 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.046)       0:00:16.139 *****
2025-09-30 08:04:44.841351 | controller | skipping: [instance]
2025-09-30 08:04:44.868538 | controller |
2025-09-30 08:04:44.868589 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-30 08:04:44.868598 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.030)       0:00:16.169 *****
2025-09-30 08:04:44.868624 | controller | skipping: [instance]
2025-09-30 08:04:44.868814 | controller |
2025-09-30 08:04:44.869012 | controller | TASK [Download the RPM] ********************************************************
2025-09-30 08:04:44.869185 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.027)       0:00:16.197 *****
2025-09-30 08:04:44.898523 | controller | skipping: [instance]
2025-09-30 08:04:44.898752 | controller |
2025-09-30 08:04:44.898936 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-30 08:04:44.899124 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.029)       0:00:16.227 *****
2025-09-30 08:04:44.926706 | controller | skipping: [instance]
2025-09-30 08:04:44.926893 | controller |
2025-09-30 08:04:44.927084 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-30 08:04:44.927251 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.028)       0:00:16.255 *****
2025-09-30 08:04:44.953661 | controller | skipping: [instance]
2025-09-30 08:04:44.953849 | controller |
2025-09-30 08:04:44.954043 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-30 08:04:44.954217 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.026)       0:00:16.282 *****
2025-09-30 08:04:44.985928 | controller | skipping: [instance]
2025-09-30 08:04:44.986135 | controller |
2025-09-30 08:04:44.986317 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-30 08:04:44.986479 | controller | Tuesday 30 September 2025  08:04:44 +0000 (0:00:00.032)       0:00:16.314 *****
2025-09-30 08:04:45.016638 | controller | skipping: [instance]
2025-09-30 08:04:45.016824 | controller |
2025-09-30 08:04:45.017019 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-30 08:04:45.017189 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.030)       0:00:16.345 *****
2025-09-30 08:04:45.213836 | controller | ok: [instance]
2025-09-30 08:04:45.214031 | controller |
2025-09-30 08:04:45.214188 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-30 08:04:45.214327 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.196)       0:00:16.542 *****
2025-09-30 08:04:45.448249 | controller | changed: [instance]
2025-09-30 08:04:45.685241 | controller |
2025-09-30 08:04:45.685271 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-30 08:04:45.685279 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.234)       0:00:16.776 *****
2025-09-30 08:04:45.685289 | controller | changed: [instance]
2025-09-30 08:04:45.708299 | controller |
2025-09-30 08:04:45.708326 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-30 08:04:45.708335 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.236)       0:00:17.013 *****
2025-09-30 08:04:45.708347 | controller | skipping: [instance]
2025-09-30 08:04:45.733483 | controller |
2025-09-30 08:04:45.733505 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-30 08:04:45.733512 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.023)       0:00:17.036 *****
2025-09-30 08:04:45.733522 | controller | skipping: [instance]
2025-09-30 08:04:45.759042 | controller |
2025-09-30 08:04:45.759063 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-30 08:04:45.759070 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.025)       0:00:17.062 *****
2025-09-30 08:04:45.759098 | controller | skipping: [instance]
2025-09-30 08:04:45.783080 | controller |
2025-09-30 08:04:45.783106 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-30 08:04:45.783113 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.025)       0:00:17.087 *****
2025-09-30 08:04:45.783128 | controller | skipping: [instance]
2025-09-30 08:04:45.808186 | controller |
2025-09-30 08:04:45.808209 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-30 08:04:45.808216 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.024)       0:00:17.111 *****
2025-09-30 08:04:45.808225 | controller | skipping: [instance]
2025-09-30 08:04:45.832655 | controller |
2025-09-30 08:04:45.832719 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-30 08:04:45.832738 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.025)       0:00:17.136 *****
2025-09-30 08:04:45.832753 | controller | skipping: [instance]
2025-09-30 08:04:45.832796 | controller |
2025-09-30 08:04:45.832988 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-30 08:04:45.833100 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.024)       0:00:17.161 *****
2025-09-30 08:04:45.852242 | controller | skipping: [instance]
2025-09-30 08:04:45.852351 | controller |
2025-09-30 08:04:45.852484 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-30 08:04:45.852675 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.019)       0:00:17.181 *****
2025-09-30 08:04:45.883019 | controller | skipping: [instance]
2025-09-30 08:04:45.883117 | controller |
2025-09-30 08:04:45.883231 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-30 08:04:45.883344 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.030)       0:00:17.211 *****
2025-09-30 08:04:45.903220 | controller | skipping: [instance]
2025-09-30 08:04:45.903355 | controller |
2025-09-30 08:04:45.903494 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-30 08:04:45.903663 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.020)       0:00:17.231 *****
2025-09-30 08:04:45.929757 | controller | skipping: [instance]
2025-09-30 08:04:45.929915 | controller |
2025-09-30 08:04:45.930083 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-30 08:04:45.930228 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.026)       0:00:17.258 *****
2025-09-30 08:04:45.960105 | controller | skipping: [instance]
2025-09-30 08:04:45.960255 | controller |
2025-09-30 08:04:45.960377 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-30 08:04:45.960493 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.030)       0:00:17.288 *****
2025-09-30 08:04:45.989828 | controller | skipping: [instance]
2025-09-30 08:04:45.989946 | controller |
2025-09-30 08:04:45.990091 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-30 08:04:45.990211 | controller | Tuesday 30 September 2025  08:04:45 +0000 (0:00:00.029)       0:00:17.318 *****
2025-09-30 08:04:46.019200 | controller | skipping: [instance]
2025-09-30 08:04:46.019329 | controller |
2025-09-30 08:04:46.019479 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-30 08:04:46.019594 | controller | Tuesday 30 September 2025  08:04:46 +0000 (0:00:00.029)       0:00:17.348 *****
2025-09-30 08:04:46.048403 | controller | skipping: [instance]
2025-09-30 08:04:46.048528 | controller |
2025-09-30 08:04:46.048667 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-30 08:04:46.048790 | controller | Tuesday 30 September 2025  08:04:46 +0000 (0:00:00.029)       0:00:17.377 *****
2025-09-30 08:05:31.745490 | controller | ok: [instance]
2025-09-30 08:05:32.991579 | controller |
2025-09-30 08:05:32.991611 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-30 08:05:32.991619 | controller | Tuesday 30 September 2025  08:05:31 +0000 (0:00:45.696)       0:01:03.073 *****
2025-09-30 08:05:32.991629 | controller | ok: [instance]
2025-09-30 08:05:33.023696 | controller |
2025-09-30 08:05:33.023732 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-30 08:05:33.023742 | controller | Tuesday 30 September 2025  08:05:32 +0000 (0:00:01.245)       0:01:04.319 *****
2025-09-30 08:05:33.023757 | controller | skipping: [instance]
2025-09-30 08:05:33.023788 | controller |
2025-09-30 08:05:33.023799 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-30 08:05:33.023825 | controller | Tuesday 30 September 2025  08:05:33 +0000 (0:00:00.032)       0:01:04.352 *****
2025-09-30 08:05:33.066248 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-30 08:05:33.084710 | controller |
2025-09-30 08:05:33.084742 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-30 08:05:33.084750 | controller | Tuesday 30 September 2025  08:05:33 +0000 (0:00:00.042)       0:01:04.394 *****
2025-09-30 08:05:33.084769 | controller | ok: [instance] =>
2025-09-30 08:05:33.084795 | controller |   cifmw_ci_setup_packages:
2025-09-30 08:05:33.084804 | controller |   - bash-completion
2025-09-30 08:05:33.085619 | controller |   - ca-certificates
2025-09-30 08:05:33.085641 | controller |   - git-core
2025-09-30 08:05:33.085650 | controller |   - make
2025-09-30 08:05:33.085658 | controller |   - tar
2025-09-30 08:05:33.085665 | controller |   - tmux
2025-09-30 08:05:33.085671 | controller |   - python3-pip
2025-09-30 08:05:33.085678 | controller |
2025-09-30 08:05:33.085685 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-30 08:05:33.085701 | controller | Tuesday 30 September 2025  08:05:33 +0000 (0:00:00.018)       0:01:04.413 *****
2025-09-30 08:05:41.789632 | controller | changed: [instance]
2025-09-30 08:05:41.789747 | controller |
2025-09-30 08:05:41.789776 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-30 08:05:41.789788 | controller | Tuesday 30 September 2025  08:05:41 +0000 (0:00:08.704)       0:01:13.118 *****
2025-09-30 08:05:42.369625 | controller | ok: [instance]
2025-09-30 08:05:42.369853 | controller |
2025-09-30 08:05:42.369887 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-30 08:05:42.589643 | controller | Tuesday 30 September 2025  08:05:42 +0000 (0:00:00.580)       0:01:13.698 *****
2025-09-30 08:05:42.589691 | controller | changed: [instance]
2025-09-30 08:05:42.589713 | controller |
2025-09-30 08:05:42.589959 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-30 08:05:48.268297 | controller | Tuesday 30 September 2025  08:05:42 +0000 (0:00:00.220)       0:01:13.918 *****
2025-09-30 08:05:48.268340 | controller | changed: [instance]
2025-09-30 08:05:48.303680 | controller |
2025-09-30 08:05:48.303712 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-30 08:05:48.303720 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:05.678)       0:01:19.596 *****
2025-09-30 08:05:48.303730 | controller | skipping: [instance]
2025-09-30 08:05:48.303747 | controller |
2025-09-30 08:05:48.303753 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-30 08:05:48.303760 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.035)       0:01:19.631 *****
2025-09-30 08:05:48.570178 | controller | changed: [instance]
2025-09-30 08:05:48.864348 | controller |
2025-09-30 08:05:48.864380 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-30 08:05:48.864388 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.266)       0:01:19.898 *****
2025-09-30 08:05:48.864398 | controller | changed: [instance]
2025-09-30 08:05:48.884758 | controller |
2025-09-30 08:05:48.884851 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-30 08:05:48.884863 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.294)       0:01:20.192 *****
2025-09-30 08:05:48.884880 | controller | skipping: [instance]
2025-09-30 08:05:48.905199 | controller |
2025-09-30 08:05:48.905227 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-30 08:05:48.905236 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.020)       0:01:20.213 *****
2025-09-30 08:05:48.905246 | controller | skipping: [instance]
2025-09-30 08:05:48.925564 | controller |
2025-09-30 08:05:48.925589 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-30 08:05:48.925599 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.020)       0:01:20.233 *****
2025-09-30 08:05:48.925610 | controller | skipping: [instance]
2025-09-30 08:05:48.944252 | controller |
2025-09-30 08:05:48.944278 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-30 08:05:48.944286 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.020)       0:01:20.253 *****
2025-09-30 08:05:48.944295 | controller | skipping: [instance]
2025-09-30 08:05:48.962720 | controller |
2025-09-30 08:05:48.962785 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-30 08:05:48.962797 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.018)       0:01:20.272 *****
2025-09-30 08:05:48.962812 | controller | skipping: [instance]
2025-09-30 08:05:48.986949 | controller |
2025-09-30 08:05:48.986999 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-30 08:05:48.987017 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.018)       0:01:20.291 *****
2025-09-30 08:05:48.987031 | controller | skipping: [instance]
2025-09-30 08:05:48.987101 | controller |
2025-09-30 08:05:48.987233 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-30 08:05:48.987364 | controller | Tuesday 30 September 2025  08:05:48 +0000 (0:00:00.024)       0:01:20.315 *****
2025-09-30 08:05:49.994694 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-30 08:05:49.994859 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-30 08:05:49.994888 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-30 08:05:49.994896 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-30 08:05:49.994902 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-30 08:05:49.994908 | controller |
2025-09-30 08:05:49.994914 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-09-30 08:05:49.994923 | controller | Tuesday 30 September 2025  08:05:49 +0000 (0:00:01.007)       0:01:21.322 *****
2025-09-30 08:05:50.191716 | controller | ok: [instance]
2025-09-30 08:05:50.191808 | controller |
2025-09-30 08:05:50.191821 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-09-30 08:05:50.191861 | controller | Tuesday 30 September 2025  08:05:50 +0000 (0:00:00.197)       0:01:21.520 *****
2025-09-30 08:05:50.377504 | controller | changed: [instance]
2025-09-30 08:05:50.563362 | controller |
2025-09-30 08:05:50.563394 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-09-30 08:05:50.563402 | controller | Tuesday 30 September 2025  08:05:50 +0000 (0:00:00.185)       0:01:21.705 *****
2025-09-30 08:05:50.563412 | controller | ok: [instance]
2025-09-30 08:05:50.591624 | controller |
2025-09-30 08:05:50.591654 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-09-30 08:05:50.591663 | controller | Tuesday 30 September 2025  08:05:50 +0000 (0:00:00.185)       0:01:21.891 *****
2025-09-30 08:05:50.591676 | controller | skipping: [instance]
2025-09-30 08:05:50.608638 | controller |
2025-09-30 08:05:50.608666 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-09-30 08:05:50.608676 | controller | Tuesday 30 September 2025  08:05:50 +0000 (0:00:00.028)       0:01:21.920 *****
2025-09-30 08:05:50.608688 | controller | skipping: [instance]
2025-09-30 08:05:50.608786 | controller |
2025-09-30 08:05:50.608805 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-09-30 08:05:50.608817 | controller | Tuesday 30 September 2025  08:05:50 +0000 (0:00:00.017)       0:01:21.937 *****
2025-09-30 08:05:50.629457 | controller | ok: [instance]
2025-09-30 08:05:50.655615 | controller |
2025-09-30 08:05:50.655644 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-09-30 08:05:50.655652 | controller | Tuesday 30 September 2025  08:05:50 +0000 (0:00:00.020)       0:01:21.957 *****
2025-09-30 08:05:50.655662 | controller | skipping: [instance]
2025-09-30 08:05:50.655687 | controller |
2025-09-30 08:05:50.655695 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-09-30 08:05:50.655810 | controller | Tuesday 30 September 2025  08:05:50 +0000 (0:00:00.026)       0:01:21.984 *****
2025-09-30 08:06:03.697559 | controller | changed: [instance]
2025-09-30 08:06:03.697847 | controller |
2025-09-30 08:06:03.911610 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-09-30 08:06:03.911639 | controller | Tuesday 30 September 2025  08:06:03 +0000 (0:00:13.041)       0:01:35.025 *****
2025-09-30 08:06:03.911651 | controller | changed: [instance]
2025-09-30 08:06:04.300599 | controller |
2025-09-30 08:06:04.300645 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-09-30 08:06:04.300654 | controller | Tuesday 30 September 2025  08:06:03 +0000 (0:00:00.213)       0:01:35.239 *****
2025-09-30 08:06:04.300665 | controller | changed: [instance]
2025-09-30 08:06:04.301008 | controller |
2025-09-30 08:06:04.903028 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-09-30 08:06:04.903062 | controller | Tuesday 30 September 2025  08:06:04 +0000 (0:00:00.389)       0:01:35.628 *****
2025-09-30 08:06:04.903084 | controller | changed: [instance]
2025-09-30 08:06:05.096467 | controller |
2025-09-30 08:06:05.096501 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-09-30 08:06:05.096509 | controller | Tuesday 30 September 2025  08:06:04 +0000 (0:00:00.602)       0:01:36.231 *****
2025-09-30 08:06:05.096518 | controller | ok: [instance]
2025-09-30 08:06:05.753667 | controller |
2025-09-30 08:06:05.753709 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-09-30 08:06:05.753719 | controller | Tuesday 30 September 2025  08:06:05 +0000 (0:00:00.193)       0:01:36.424 *****
2025-09-30 08:06:05.753733 | controller | ok: [instance]
2025-09-30 08:06:05.753768 | controller |
2025-09-30 08:06:05.753926 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-09-30 08:06:05.753959 | controller | Tuesday 30 September 2025  08:06:05 +0000 (0:00:00.657)       0:01:37.082 *****
2025-09-30 08:06:06.641666 | controller | changed: [instance]
2025-09-30 08:06:06.641704 | controller |
2025-09-30 08:06:06.641714 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-09-30 08:06:06.641724 | controller | Tuesday 30 September 2025  08:06:06 +0000 (0:00:00.887)       0:01:37.969 *****
2025-09-30 08:06:06.717681 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-09-30 08:06:06.717743 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-09-30 08:06:06.718784 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-09-30 08:06:07.042679 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-09-30 08:06:07.042721 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-09-30 08:06:07.042730 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-09-30 08:06:07.042737 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-09-30 08:06:07.042744 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-09-30 08:06:07.042751 | controller |
2025-09-30 08:06:07.042758 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-09-30 08:06:07.042765 | controller | Tuesday 30 September 2025  08:06:06 +0000 (0:00:00.076)       0:01:38.046 *****
2025-09-30 08:06:07.042778 | controller | ok: [instance]
2025-09-30 08:06:07.042809 | controller |
2025-09-30 08:06:07.042952 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-09-30 08:06:07.042989 | controller | Tuesday 30 September 2025  08:06:07 +0000 (0:00:00.325)       0:01:38.371 *****
2025-09-30 08:06:07.381726 | controller | ok: [instance]
2025-09-30 08:06:07.381788 | controller |
2025-09-30 08:06:07.381801 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-09-30 08:06:07.381934 | controller | Tuesday 30 September 2025  08:06:07 +0000 (0:00:00.338)       0:01:38.710 *****
2025-09-30 08:06:07.706046 | controller | ok: [instance]
2025-09-30 08:06:08.028694 | controller |
2025-09-30 08:06:08.028729 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-09-30 08:06:08.028739 | controller | Tuesday 30 September 2025  08:06:07 +0000 (0:00:00.323)       0:01:39.034 *****
2025-09-30 08:06:08.028752 | controller | ok: [instance]
2025-09-30 08:06:08.028783 | controller |
2025-09-30 08:06:08.028811 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-09-30 08:06:08.028967 | controller | Tuesday 30 September 2025  08:06:08 +0000 (0:00:00.323)       0:01:39.357 *****
2025-09-30 08:06:08.352972 | controller | ok: [instance]
2025-09-30 08:06:08.671808 | controller |
2025-09-30 08:06:08.671839 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-09-30 08:06:08.671847 | controller | Tuesday 30 September 2025  08:06:08 +0000 (0:00:00.324)       0:01:39.681 *****
2025-09-30 08:06:08.671857 | controller | ok: [instance]
2025-09-30 08:06:09.009670 | controller |
2025-09-30 08:06:09.009701 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-09-30 08:06:09.009709 | controller | Tuesday 30 September 2025  08:06:08 +0000 (0:00:00.318)       0:01:40.000 *****
2025-09-30 08:06:09.009720 | controller | ok: [instance]
2025-09-30 08:06:09.009750 | controller |
2025-09-30 08:06:09.009759 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-09-30 08:06:09.009781 | controller | Tuesday 30 September 2025  08:06:09 +0000 (0:00:00.337)       0:01:40.338 *****
2025-09-30 08:06:09.339477 | controller | ok: [instance]
2025-09-30 08:06:09.664757 | controller |
2025-09-30 08:06:09.664801 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-09-30 08:06:09.664810 | controller | Tuesday 30 September 2025  08:06:09 +0000 (0:00:00.329)       0:01:40.667 *****
2025-09-30 08:06:09.664820 | controller | ok: [instance]
2025-09-30 08:06:09.994290 | controller |
2025-09-30 08:06:09.994329 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-09-30 08:06:09.994338 | controller | Tuesday 30 September 2025  08:06:09 +0000 (0:00:00.325)       0:01:40.992 *****
2025-09-30 08:06:09.994349 | controller | ok: [instance]
2025-09-30 08:06:10.321659 | controller |
2025-09-30 08:06:10.321694 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-09-30 08:06:10.321704 | controller | Tuesday 30 September 2025  08:06:09 +0000 (0:00:00.329)       0:01:41.322 *****
2025-09-30 08:06:10.321716 | controller | ok: [instance]
2025-09-30 08:06:10.321724 | controller |
2025-09-30 08:06:10.321731 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-09-30 08:06:10.321740 | controller | Tuesday 30 September 2025  08:06:10 +0000 (0:00:00.327)       0:01:41.649 *****
2025-09-30 08:06:10.643386 | controller | ok: [instance]
2025-09-30 08:06:10.961675 | controller |
2025-09-30 08:06:10.961718 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-09-30 08:06:10.961728 | controller | Tuesday 30 September 2025  08:06:10 +0000 (0:00:00.321)       0:01:41.971 *****
2025-09-30 08:06:10.961742 | controller | ok: [instance]
2025-09-30 08:06:11.272653 | controller |
2025-09-30 08:06:11.272685 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-09-30 08:06:11.272695 | controller | Tuesday 30 September 2025  08:06:10 +0000 (0:00:00.318)       0:01:42.289 *****
2025-09-30 08:06:11.272707 | controller | ok: [instance]
2025-09-30 08:06:11.272739 | controller |
2025-09-30 08:06:11.272940 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-09-30 08:06:11.592114 | controller | Tuesday 30 September 2025  08:06:11 +0000 (0:00:00.311)       0:01:42.601 *****
2025-09-30 08:06:11.592152 | controller | ok: [instance]
2025-09-30 08:06:11.906501 | controller |
2025-09-30 08:06:11.906535 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-09-30 08:06:11.906559 | controller | Tuesday 30 September 2025  08:06:11 +0000 (0:00:00.319)       0:01:42.920 *****
2025-09-30 08:06:11.906573 | controller | ok: [instance]
2025-09-30 08:06:12.242283 | controller |
2025-09-30 08:06:12.242313 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-09-30 08:06:12.242322 | controller | Tuesday 30 September 2025  08:06:11 +0000 (0:00:00.314)       0:01:43.234 *****
2025-09-30 08:06:12.242332 | controller | ok: [instance]
2025-09-30 08:06:13.578231 | controller |
2025-09-30 08:06:13.578282 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-09-30 08:06:13.578300 | controller | Tuesday 30 September 2025  08:06:12 +0000 (0:00:00.335)       0:01:43.570 *****
2025-09-30 08:06:13.578311 | controller | ok: [instance]
2025-09-30 08:06:13.917572 | controller |
2025-09-30 08:06:13.917607 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-09-30 08:06:13.917615 | controller | Tuesday 30 September 2025  08:06:13 +0000 (0:00:01.335)       0:01:44.906 *****
2025-09-30 08:06:13.917632 | controller | ok: [instance]
2025-09-30 08:06:14.252413 | controller |
2025-09-30 08:06:14.252448 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-09-30 08:06:14.252456 | controller | Tuesday 30 September 2025  08:06:13 +0000 (0:00:00.339)       0:01:45.245 *****
2025-09-30 08:06:14.252467 | controller | ok: [instance]
2025-09-30 08:06:14.584991 | controller |
2025-09-30 08:06:14.585023 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-09-30 08:06:14.585031 | controller | Tuesday 30 September 2025  08:06:14 +0000 (0:00:00.334)       0:01:45.580 *****
2025-09-30 08:06:14.585040 | controller | ok: [instance]
2025-09-30 08:06:14.919937 | controller |
2025-09-30 08:06:14.919967 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-09-30 08:06:14.919975 | controller | Tuesday 30 September 2025  08:06:14 +0000 (0:00:00.332)       0:01:45.913 *****
2025-09-30 08:06:14.919985 | controller | ok: [instance]
2025-09-30 08:06:15.253345 | controller |
2025-09-30 08:06:15.253374 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-09-30 08:06:15.253384 | controller | Tuesday 30 September 2025  08:06:14 +0000 (0:00:00.334)       0:01:46.248 *****
2025-09-30 08:06:15.253394 | controller | ok: [instance]
2025-09-30 08:06:15.586135 | controller |
2025-09-30 08:06:15.586166 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-09-30 08:06:15.586174 | controller | Tuesday 30 September 2025  08:06:15 +0000 (0:00:00.333)       0:01:46.581 *****
2025-09-30 08:06:15.586184 | controller | ok: [instance]
2025-09-30 08:06:15.607449 | controller |
2025-09-30 08:06:15.607474 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-09-30 08:06:15.607483 | controller | Tuesday 30 September 2025  08:06:15 +0000 (0:00:00.332)       0:01:46.914 *****
2025-09-30 08:06:15.607495 | controller | skipping: [instance]
2025-09-30 08:06:15.892112 | controller |
2025-09-30 08:06:15.892149 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-09-30 08:06:15.892159 | controller | Tuesday 30 September 2025  08:06:15 +0000 (0:00:00.021)       0:01:46.935 *****
2025-09-30 08:06:15.892170 | controller | ok: [instance]
2025-09-30 08:06:16.271255 | controller |
2025-09-30 08:06:16.271301 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-09-30 08:06:16.271309 | controller | Tuesday 30 September 2025  08:06:15 +0000 (0:00:00.284)       0:01:47.220 *****
2025-09-30 08:06:16.271319 | controller | ok: [instance]
2025-09-30 08:06:16.590671 | controller |
2025-09-30 08:06:16.590706 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-09-30 08:06:16.590716 | controller | Tuesday 30 September 2025  08:06:16 +0000 (0:00:00.379)       0:01:47.599 *****
2025-09-30 08:06:16.590729 | controller | changed: [instance]
2025-09-30 08:06:16.590762 | controller |
2025-09-30 08:06:16.590772 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-09-30 08:06:16.590894 | controller | Tuesday 30 September 2025  08:06:16 +0000 (0:00:00.319)       0:01:47.919 *****
2025-09-30 08:06:16.795058 | controller | [WARNING]: Reset is not implemented for this connection
2025-09-30 08:06:16.816689 | controller | changed: [instance]
2025-09-30 08:06:16.817686 | controller |
2025-09-30 08:06:16.817723 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-09-30 08:06:16.817736 | controller | Tuesday 30 September 2025  08:06:16 +0000 (0:00:00.199)       0:01:48.118 *****
2025-09-30 08:06:16.817760 | controller |
2025-09-30 08:06:16.817766 | controller | PLAY RECAP *********************************************************************
2025-09-30 08:06:16.817772 | controller | instance                   : ok=75   changed=23   unreachable=0    failed=0    skipped=41   rescued=0    ignored=0
2025-09-30 08:06:16.817778 | controller |
2025-09-30 08:06:16.817783 | controller | Tuesday 30 September 2025  08:06:16 +0000 (0:00:00.026)       0:01:48.145 *****
2025-09-30 08:06:16.817789 | controller | ===============================================================================
2025-09-30 08:06:16.817794 | controller | test_deps : Install selinux python libs -------------------------------- 45.70s
2025-09-30 08:06:16.817799 | controller | libvirt_manager : Install packages required for using KVM -------------- 13.04s
2025-09-30 08:06:16.817805 | controller | ci_setup : Install needed packages -------------------------------------- 8.70s
2025-09-30 08:06:16.817826 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.02s
2025-09-30 08:06:16.817849 | controller | ci_setup : Install openshift client ------------------------------------- 5.68s
2025-09-30 08:06:16.819275 | controller | libvirt_manager : Ensure admin socket is enabled for secret. ------------ 1.34s
2025-09-30 08:06:16.876872 | controller | test_deps : Install python yaml libs ------------------------------------ 1.25s
2025-09-30 08:06:16.876907 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.22s
2025-09-30 08:06:16.876914 | controller | ci_setup : Manage directories ------------------------------------------- 1.01s
2025-09-30 08:06:16.876920 | controller | Gathering Facts --------------------------------------------------------- 0.90s
2025-09-30 08:06:16.876925 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.89s
2025-09-30 08:06:16.876930 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.87s
2025-09-30 08:06:16.876936 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.86s
2025-09-30 08:06:16.876941 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s
2025-09-30 08:06:16.876946 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.74s
2025-09-30 08:06:16.876951 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.66s
2025-09-30 08:06:16.876957 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.60s
2025-09-30 08:06:16.876962 | controller | ci_setup : Gather version of openshift client --------------------------- 0.58s
2025-09-30 08:06:16.876968 | controller | repo_setup : Ensure directories are present ----------------------------- 0.58s
2025-09-30 08:06:16.876973 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-09-30 08:06:16.876983 | controller | INFO     Running default > converge
2025-09-30 08:06:17.296357 | controller |
2025-09-30 08:06:17.296585 | controller | PLAY [Converge] ****************************************************************
2025-09-30 08:06:17.296738 | controller |
2025-09-30 08:06:17.296891 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 08:06:17.297032 | controller | Tuesday 30 September 2025  08:06:17 +0000 (0:00:00.013)       0:00:00.013 *****
2025-09-30 08:06:18.097674 | controller | ok: [instance]
2025-09-30 08:06:18.136696 | controller |
2025-09-30 08:06:18.136731 | controller | TASK [Including the openshift provisioner node role.] **************************
2025-09-30 08:06:18.136741 | controller | Tuesday 30 September 2025  08:06:18 +0000 (0:00:00.801)       0:00:00.814 *****
2025-09-30 08:06:18.136753 | controller |
2025-09-30 08:06:18.136793 | controller | TASK [openshift_provisioner_node : Create the artifacts directory] *************
2025-09-30 08:06:18.136806 | controller | Tuesday 30 September 2025  08:06:18 +0000 (0:00:00.039)       0:00:00.854 *****
2025-09-30 08:06:18.429994 | controller | ok: [instance -> localhost]
2025-09-30 08:06:18.812593 | controller |
2025-09-30 08:06:18.812629 | controller | TASK [openshift_provisioner_node : Adding user kni] ****************************
2025-09-30 08:06:18.812637 | controller | Tuesday 30 September 2025  08:06:18 +0000 (0:00:00.292)       0:00:01.147 *****
2025-09-30 08:06:18.812649 | controller | changed: [instance]
2025-09-30 08:06:19.170168 | controller |
2025-09-30 08:06:19.170196 | controller | TASK [openshift_provisioner_node : Generate SSH keys] **************************
2025-09-30 08:06:19.170204 | controller | Tuesday 30 September 2025  08:06:18 +0000 (0:00:00.382)       0:00:01.529 *****
2025-09-30 08:06:19.170215 | controller | changed: [instance -> localhost]
2025-09-30 08:06:19.539307 | controller |
2025-09-30 08:06:19.539338 | controller | TASK [openshift_provisioner_node : Enable passwordless access] *****************
2025-09-30 08:06:19.539347 | controller | Tuesday 30 September 2025  08:06:19 +0000 (0:00:00.357)       0:00:01.887 *****
2025-09-30 08:06:19.539357 | controller | changed: [instance]
2025-09-30 08:06:42.837348 | controller |
2025-09-30 08:06:42.837381 | controller | TASK [openshift_provisioner_node : Install required packages need to execute openshift installer] ***
2025-09-30 08:06:42.837390 | controller | Tuesday 30 September 2025  08:06:19 +0000 (0:00:00.369)       0:00:02.256 *****
2025-09-30 08:06:42.837407 | controller | changed: [instance]
2025-09-30 08:06:42.837434 | controller |
2025-09-30 08:06:42.837444 | controller | TASK [openshift_provisioner_node : Create the external network connection] *****
2025-09-30 08:06:42.837574 | controller | Tuesday 30 September 2025  08:06:42 +0000 (0:00:23.298)       0:00:25.554 *****
2025-09-30 08:06:43.263051 | controller | changed: [instance]
2025-09-30 08:06:43.294653 | controller |
2025-09-30 08:06:43.294689 | controller | TASK [openshift_provisioner_node : Verify external network interface is defined] ***
2025-09-30 08:06:43.294699 | controller | Tuesday 30 September 2025  08:06:43 +0000 (0:00:00.425)       0:00:25.980 *****
2025-09-30 08:06:43.294713 | controller | skipping: [instance]
2025-09-30 08:06:43.294744 | controller |
2025-09-30 08:06:43.294955 | controller | TASK [openshift_provisioner_node : Add iface to the external network connection] ***
2025-09-30 08:06:43.321676 | controller | Tuesday 30 September 2025  08:06:43 +0000 (0:00:00.032)       0:00:26.012 *****
2025-09-30 08:06:43.321706 | controller | skipping: [instance]
2025-09-30 08:06:43.321837 | controller |
2025-09-30 08:06:43.321863 | controller | TASK [openshift_provisioner_node : Create the provisioning network connection] ***
2025-09-30 08:06:43.321875 | controller | Tuesday 30 September 2025  08:06:43 +0000 (0:00:00.026)       0:00:26.039 *****
2025-09-30 08:06:43.370046 | controller | skipping: [instance]
2025-09-30 08:06:43.370173 | controller |
2025-09-30 08:06:43.370375 | controller | TASK [openshift_provisioner_node : Add iface to the provisioning network connection] ***
2025-09-30 08:06:43.402034 | controller | Tuesday 30 September 2025  08:06:43 +0000 (0:00:00.048)       0:00:26.087 *****
2025-09-30 08:06:43.402069 | controller | skipping: [instance]
2025-09-30 08:06:43.402097 | controller |
2025-09-30 08:06:43.402106 | controller | TASK [openshift_provisioner_node : Check required virtualization networks] *****
2025-09-30 08:06:43.402238 | controller | Tuesday 30 September 2025  08:06:43 +0000 (0:00:00.031)       0:00:26.119 *****
2025-09-30 08:06:43.438917 | 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-09-30 08:06:43.438999 | 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-09-30 08:06:43.439011 | controller |
2025-09-30 08:06:43.439107 | controller | TASK [openshift_provisioner_node : Define virtual network baremetal] ***********
2025-09-30 08:06:43.439213 | controller | Tuesday 30 September 2025  08:06:43 +0000 (0:00:00.036)       0:00:26.156 *****
2025-09-30 08:06:44.305347 | controller | changed: [instance]
2025-09-30 08:06:44.305394 | controller |
2025-09-30 08:06:44.305411 | controller | TASK [openshift_provisioner_node : Start the network - baremetal] **************
2025-09-30 08:06:44.305516 | controller | Tuesday 30 September 2025  08:06:44 +0000 (0:00:00.866)       0:00:27.022 *****
2025-09-30 08:06:44.534291 | controller | changed: [instance]
2025-09-30 08:06:44.755654 | controller |
2025-09-30 08:06:44.755698 | controller | TASK [openshift_provisioner_node : Enable network autostart for baremetal] *****
2025-09-30 08:06:44.755707 | controller | Tuesday 30 September 2025  08:06:44 +0000 (0:00:00.228)       0:00:27.251 *****
2025-09-30 08:06:44.755718 | controller | changed: [instance]
2025-09-30 08:06:44.986148 | controller |
2025-09-30 08:06:44.986182 | controller | TASK [openshift_provisioner_node : Define virtual network provisioning] ********
2025-09-30 08:06:44.986190 | controller | Tuesday 30 September 2025  08:06:44 +0000 (0:00:00.221)       0:00:27.472 *****
2025-09-30 08:06:44.986200 | controller | changed: [instance]
2025-09-30 08:06:45.209718 | controller |
2025-09-30 08:06:45.209759 | controller | TASK [openshift_provisioner_node : Start the network - provisioning] ***********
2025-09-30 08:06:45.209769 | controller | Tuesday 30 September 2025  08:06:44 +0000 (0:00:00.230)       0:00:27.703 *****
2025-09-30 08:06:45.209783 | controller | changed: [instance]
2025-09-30 08:06:45.432628 | controller |
2025-09-30 08:06:45.432683 | controller | TASK [openshift_provisioner_node : Enable network autostart for provisioning] ***
2025-09-30 08:06:45.432694 | controller | Tuesday 30 September 2025  08:06:45 +0000 (0:00:00.223)       0:00:27.926 *****
2025-09-30 08:06:45.432708 | controller | changed: [instance]
2025-09-30 08:06:45.432792 | controller |
2025-09-30 08:06:45.432834 | controller | TASK [openshift_provisioner_node : Cache the MAC addresses] ********************
2025-09-30 08:06:45.481269 | controller | Tuesday 30 September 2025  08:06:45 +0000 (0:00:00.223)       0:00:28.150 *****
2025-09-30 08:06:45.481295 | controller | ok: [instance]
2025-09-30 08:06:45.749694 | controller |
2025-09-30 08:06:45.749734 | controller | TASK [openshift_provisioner_node : Read host SSH fingerprint] ******************
2025-09-30 08:06:45.749744 | controller | Tuesday 30 September 2025  08:06:45 +0000 (0:00:00.048)       0:00:28.198 *****
2025-09-30 08:06:45.749758 | controller | ok: [instance]
2025-09-30 08:06:45.780049 | controller |
2025-09-30 08:06:45.780084 | controller | TASK [openshift_provisioner_node : Set the role output parameters] *************
2025-09-30 08:06:45.780093 | controller | Tuesday 30 September 2025  08:06:45 +0000 (0:00:00.268)       0:00:28.467 *****
2025-09-30 08:06:45.780103 | controller | ok: [instance]
2025-09-30 08:06:45.814018 | controller |
2025-09-30 08:06:45.814040 | controller | TASK [openshift_provisioner_node : Cache provisioning bridge name] *************
2025-09-30 08:06:45.814048 | controller | Tuesday 30 September 2025  08:06:45 +0000 (0:00:00.030)       0:00:28.497 *****
2025-09-30 08:06:45.814065 | controller | skipping: [instance]
2025-09-30 08:06:46.097738 | controller |
2025-09-30 08:06:46.097780 | controller | TASK [Verify user is created] **************************************************
2025-09-30 08:06:46.097788 | controller | Tuesday 30 September 2025  08:06:45 +0000 (0:00:00.033)       0:00:28.531 *****
2025-09-30 08:06:46.097800 | controller | ok: [instance]
2025-09-30 08:06:46.194710 | controller |
2025-09-30 08:06:46.194741 | controller | TASK [Verify external network bridge exists] ***********************************
2025-09-30 08:06:46.194750 | controller | Tuesday 30 September 2025  08:06:46 +0000 (0:00:00.283)       0:00:28.814 *****
2025-09-30 08:06:46.194760 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-09-30 08:06:46.230513 | controller | changed: [instance]
2025-09-30 08:06:46.230561 | controller |
2025-09-30 08:06:46.230574 | controller | TASK [Perform cleanup] *********************************************************
2025-09-30 08:06:46.230582 | controller | Tuesday 30 September 2025  08:06:46 +0000 (0:00:00.096)       0:00:28.911 *****
2025-09-30 08:06:46.230594 | controller |
2025-09-30 08:06:46.259665 | controller | TASK [openshift_provisioner_node : Cleaning up the virtual networks] ***********
2025-09-30 08:06:46.259716 | controller | Tuesday 30 September 2025  08:06:46 +0000 (0:00:00.036)       0:00:28.948 *****
2025-09-30 08:06:46.259731 | 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-09-30 08:06:46.485193 | 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-09-30 08:06:46.485224 | controller |
2025-09-30 08:06:46.485232 | controller | TASK [openshift_provisioner_node : Stopping virtual network baremetal] *********
2025-09-30 08:06:46.485238 | controller | Tuesday 30 September 2025  08:06:46 +0000 (0:00:00.028)       0:00:28.977 *****
2025-09-30 08:06:46.485248 | controller | ok: [instance]
2025-09-30 08:06:46.705671 | controller |
2025-09-30 08:06:46.705707 | controller | TASK [openshift_provisioner_node : Remove virtual network baremetal] ***********
2025-09-30 08:06:46.705717 | controller | Tuesday 30 September 2025  08:06:46 +0000 (0:00:00.225)       0:00:29.202 *****
2025-09-30 08:06:46.705730 | controller | ok: [instance]
2025-09-30 08:06:46.705763 | controller |
2025-09-30 08:06:46.705964 | controller | TASK [openshift_provisioner_node : Stopping virtual network provisioning] ******
2025-09-30 08:06:46.927472 | controller | Tuesday 30 September 2025  08:06:46 +0000 (0:00:00.220)       0:00:29.423 *****
2025-09-30 08:06:46.927505 | controller | ok: [instance]
2025-09-30 08:06:47.150035 | controller |
2025-09-30 08:06:47.150065 | controller | TASK [openshift_provisioner_node : Remove virtual network provisioning] ********
2025-09-30 08:06:47.150073 | controller | Tuesday 30 September 2025  08:06:46 +0000 (0:00:00.221)       0:00:29.644 *****
2025-09-30 08:06:47.150083 | controller | ok: [instance]
2025-09-30 08:06:47.377023 | controller |
2025-09-30 08:06:47.377054 | controller | TASK [openshift_provisioner_node : Remove provisioning network] ****************
2025-09-30 08:06:47.377069 | controller | Tuesday 30 September 2025  08:06:47 +0000 (0:00:00.222)       0:00:29.867 *****
2025-09-30 08:06:47.377079 | controller | ok: [instance]
2025-09-30 08:06:47.730750 | controller |
2025-09-30 08:06:47.730794 | controller | TASK [openshift_provisioner_node : Remove the user] ****************************
2025-09-30 08:06:47.730804 | controller | Tuesday 30 September 2025  08:06:47 +0000 (0:00:00.227)       0:00:30.094 *****
2025-09-30 08:06:47.730818 | controller | changed: [instance]
2025-09-30 08:06:47.730851 | controller |
2025-09-30 08:06:47.730861 | controller | PLAY RECAP *********************************************************************
2025-09-30 08:06:47.730888 | controller | instance                   : ok=28   changed=13   unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-09-30 08:06:47.731667 | controller |
2025-09-30 08:06:47.731720 | controller | Tuesday 30 September 2025  08:06:47 +0000 (0:00:00.353)       0:00:30.448 *****
2025-09-30 08:06:47.731729 | controller | ===============================================================================
2025-09-30 08:06:47.731735 | controller | openshift_provisioner_node : Install required packages need to execute openshift installer -- 23.30s
2025-09-30 08:06:47.731741 | controller | openshift_provisioner_node : Define virtual network baremetal ----------- 0.87s
2025-09-30 08:06:47.731747 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-09-30 08:06:47.731752 | controller | openshift_provisioner_node : Create the external network connection ----- 0.43s
2025-09-30 08:06:47.731760 | controller | openshift_provisioner_node : Adding user kni ---------------------------- 0.38s
2025-09-30 08:06:47.733255 | controller | openshift_provisioner_node : Enable passwordless access ----------------- 0.37s
2025-09-30 08:06:47.789745 | controller | openshift_provisioner_node : Generate SSH keys -------------------------- 0.36s
2025-09-30 08:06:47.789778 | controller | openshift_provisioner_node : Remove the user ---------------------------- 0.35s
2025-09-30 08:06:47.789787 | controller | openshift_provisioner_node : Create the artifacts directory ------------- 0.29s
2025-09-30 08:06:47.789799 | controller | Verify user is created -------------------------------------------------- 0.28s
2025-09-30 08:06:47.789805 | controller | openshift_provisioner_node : Read host SSH fingerprint ------------------ 0.27s
2025-09-30 08:06:47.789810 | controller | openshift_provisioner_node : Define virtual network provisioning -------- 0.23s
2025-09-30 08:06:47.789816 | controller | openshift_provisioner_node : Start the network - baremetal -------------- 0.23s
2025-09-30 08:06:47.789821 | controller | openshift_provisioner_node : Remove provisioning network ---------------- 0.23s
2025-09-30 08:06:47.789827 | controller | openshift_provisioner_node : Stopping virtual network baremetal --------- 0.23s
2025-09-30 08:06:47.789832 | controller | openshift_provisioner_node : Start the network - provisioning ----------- 0.22s
2025-09-30 08:06:47.789837 | controller | openshift_provisioner_node : Enable network autostart for provisioning --- 0.22s
2025-09-30 08:06:47.789843 | controller | openshift_provisioner_node : Remove virtual network provisioning -------- 0.22s
2025-09-30 08:06:47.789848 | controller | openshift_provisioner_node : Stopping virtual network provisioning ------ 0.22s
2025-09-30 08:06:47.789854 | controller | openshift_provisioner_node : Enable network autostart for baremetal ----- 0.22s
2025-09-30 08:06:47.789864 | controller | INFO     Running default > cleanup
2025-09-30 08:06:47.790306 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-30 08:06:47.790887 | controller | INFO     Writing /tmp/report.html report.
2025-09-30 08:06:48.181276 | [controller] Waiting on logger
2025-09-30 08:06:53.344813 | [controller] Waiting on logger
2025-09-30 08:07:03.777247 | [controller] Waiting on logger
2025-09-30 08:07:14.209798 | [controller] Waiting on logger
2025-09-30 08:07:17.885035 | [Zuul] Log Stream did not terminate
2025-09-30 08:07:17.885184 | controller | changed
2025-09-30 08:07:17.908738 | 
2025-09-30 08:07:17.908801 | PLAY RECAP
2025-09-30 08:07:17.908843 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 08:07:17.908865 | 
2025-09-30 08:07:17.986521 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 08:07:17.987303 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 08:07:18.734057 | 
2025-09-30 08:07:18.734166 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-30 08:07:18.755371 | 
2025-09-30 08:07:18.755478 | TASK [Filter out host if needed]
2025-09-30 08:07:18.764548 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-30 08:07:18.769145 | 
2025-09-30 08:07:18.769215 | TASK [Ensure file is present]
2025-09-30 08:07:19.193260 | controller | ok
2025-09-30 08:07:19.204124 | 
2025-09-30 08:07:19.204204 | TASK [Manage molecule report file]
2025-09-30 08:07:19.259788 | [controller] Waiting on logger
2025-09-30 08:07:28.609994 | [controller] Waiting on logger
2025-09-30 08:07:39.040912 | [controller] Waiting on logger
2025-09-30 08:07:48.384758 | [controller] Waiting on logger
2025-09-30 08:07:49.919059 | [Zuul] Log Stream did not terminate
2025-09-30 08:07:49.919232 | controller | changed
2025-09-30 08:07:49.924523 | 
2025-09-30 08:07:49.924586 | TASK [Check if we get ci-framework-data basedir]
2025-09-30 08:07:50.185479 | controller | ok
2025-09-30 08:07:50.190546 | 
2025-09-30 08:07:50.190614 | TASK [Create ci-framework-data log directory for zuul]
2025-09-30 08:07:50.564471 | controller | changed
2025-09-30 08:07:50.570187 | 
2025-09-30 08:07:50.570281 | TASK [Copy ci-framework interesting files]
2025-09-30 08:07:50.590419 | [controller] Waiting on logger
2025-09-30 08:08:07.008953 | [controller] Waiting on logger
2025-09-30 08:08:09.221431 | [controller] Waiting on logger
2025-09-30 08:08:20.872617 | [Zuul] Log Stream did not terminate
2025-09-30 08:08:20.872784 | controller | changed
2025-09-30 08:08:20.877875 | 
2025-09-30 08:08:20.877937 | TASK [Get SELinux listing]
2025-09-30 08:08:20.900765 | [controller] Waiting on logger
2025-09-30 08:08:24.672859 | [controller] Waiting on logger
2025-09-30 08:08:26.006767 | controller | changed
2025-09-30 08:08:26.013188 | 
2025-09-30 08:08:26.013284 | TASK [Generate log index]
2025-09-30 08:08:27.197994 | controller | changed
2025-09-30 08:08:27.203276 | 
2025-09-30 08:08:27.203360 | TASK [Get some env related data]
2025-09-30 08:08:27.224895 | [controller] Waiting on logger
2025-09-30 08:08:37.664827 | [controller] Waiting on logger
2025-09-30 08:08:48.097020 | [controller] Waiting on logger
2025-09-30 08:08:57.441139 | [controller] Waiting on logger
2025-09-30 08:08:58.456868 | [Zuul] Log Stream did not terminate
2025-09-30 08:08:58.457040 | controller | changed
2025-09-30 08:08:58.481832 | 
2025-09-30 08:08:58.481922 | TASK [Generate list of logs to collect in home directory]
2025-09-30 08:08:58.834379 | controller | ok: All paths examined
2025-09-30 08:08:58.839938 | 
2025-09-30 08:08:58.840024 | LOOP [Copy logs from home directory]
2025-09-30 08:08:59.444694 | controller | changed:
2025-09-30 08:08:59.444895 | controller | {
2025-09-30 08:08:59.444925 | controller |   "atime": 1743544925.4788878,
2025-09-30 08:08:59.444946 | controller |   "ctime": 1743545329.1409318,
2025-09-30 08:08:59.444964 | controller |   "dev": 64513,
2025-09-30 08:08:59.444982 | controller |   "gid": 1000,
2025-09-30 08:08:59.444999 | controller |   "gr_name": "zuul",
2025-09-30 08:08:59.445020 | controller |   "inode": 4518807,
2025-09-30 08:08:59.445036 | controller |   "isblk": false,
2025-09-30 08:08:59.445052 | controller |   "ischr": false,
2025-09-30 08:08:59.445067 | controller |   "isdir": false,
2025-09-30 08:08:59.445082 | controller |   "isfifo": false,
2025-09-30 08:08:59.445097 | controller |   "isgid": false,
2025-09-30 08:08:59.445112 | controller |   "islnk": false,
2025-09-30 08:08:59.445127 | controller |   "isreg": true,
2025-09-30 08:08:59.445144 | controller |   "issock": false,
2025-09-30 08:08:59.445159 | controller |   "isuid": false,
2025-09-30 08:08:59.445173 | controller |   "mode": "0644",
2025-09-30 08:08:59.445186 | controller |   "mtime": 1743545329.1409318,
2025-09-30 08:08:59.445201 | controller |   "nlink": 1,
2025-09-30 08:08:59.445216 | controller |   "path": "/home/zuul/crc-setup.log",
2025-09-30 08:08:59.445231 | controller |   "pw_name": "zuul",
2025-09-30 08:08:59.445245 | controller |   "rgrp": true,
2025-09-30 08:08:59.445276 | controller |   "roth": true,
2025-09-30 08:08:59.445293 | controller |   "rusr": true,
2025-09-30 08:08:59.445307 | controller |   "size": 4108,
2025-09-30 08:08:59.445322 | controller |   "uid": 1000,
2025-09-30 08:08:59.445336 | controller |   "wgrp": false,
2025-09-30 08:08:59.445349 | controller |   "woth": false,
2025-09-30 08:08:59.445363 | controller |   "wusr": true,
2025-09-30 08:08:59.445377 | controller |   "xgrp": false,
2025-09-30 08:08:59.445419 | controller |   "xoth": false,
2025-09-30 08:08:59.445437 | controller |   "xusr": false
2025-09-30 08:08:59.445453 | controller | }
2025-09-30 08:09:00.006753 | controller | changed:
2025-09-30 08:09:00.006850 | controller | {
2025-09-30 08:09:00.006877 | controller |   "atime": 1743545331.0429637,
2025-09-30 08:09:00.006898 | controller |   "ctime": 1743545842.0111232,
2025-09-30 08:09:00.006916 | controller |   "dev": 64513,
2025-09-30 08:09:00.006933 | controller |   "gid": 1000,
2025-09-30 08:09:00.006949 | controller |   "gr_name": "zuul",
2025-09-30 08:09:00.006964 | controller |   "inode": 4194437,
2025-09-30 08:09:00.006979 | controller |   "isblk": false,
2025-09-30 08:09:00.006994 | controller |   "ischr": false,
2025-09-30 08:09:00.007008 | controller |   "isdir": false,
2025-09-30 08:09:00.007022 | controller |   "isfifo": false,
2025-09-30 08:09:00.007036 | controller |   "isgid": false,
2025-09-30 08:09:00.007050 | controller |   "islnk": false,
2025-09-30 08:09:00.007065 | controller |   "isreg": true,
2025-09-30 08:09:00.007080 | controller |   "issock": false,
2025-09-30 08:09:00.007094 | controller |   "isuid": false,
2025-09-30 08:09:00.007108 | controller |   "mode": "0644",
2025-09-30 08:09:00.007122 | controller |   "mtime": 1743545842.0111232,
2025-09-30 08:09:00.007142 | controller |   "nlink": 1,
2025-09-30 08:09:00.007158 | controller |   "path": "/home/zuul/crc-start.log",
2025-09-30 08:09:00.007174 | controller |   "pw_name": "zuul",
2025-09-30 08:09:00.007189 | controller |   "rgrp": true,
2025-09-30 08:09:00.007204 | controller |   "roth": true,
2025-09-30 08:09:00.007218 | controller |   "rusr": true,
2025-09-30 08:09:00.007233 | controller |   "size": 4023,
2025-09-30 08:09:00.007260 | controller |   "uid": 1000,
2025-09-30 08:09:00.007277 | controller |   "wgrp": false,
2025-09-30 08:09:00.007293 | controller |   "woth": false,
2025-09-30 08:09:00.007307 | controller |   "wusr": true,
2025-09-30 08:09:00.007322 | controller |   "xgrp": false,
2025-09-30 08:09:00.007336 | controller |   "xoth": false,
2025-09-30 08:09:00.007350 | controller |   "xusr": false
2025-09-30 08:09:00.007364 | controller | }
2025-09-30 08:09:00.562396 | controller | changed:
2025-09-30 08:09:00.562489 | controller | {
2025-09-30 08:09:00.562515 | controller |   "atime": 1759219428.1963663,
2025-09-30 08:09:00.562535 | controller |   "ctime": 1759219445.2694666,
2025-09-30 08:09:00.562551 | controller |   "dev": 64513,
2025-09-30 08:09:00.562567 | controller |   "gid": 1000,
2025-09-30 08:09:00.562582 | controller |   "gr_name": "zuul",
2025-09-30 08:09:00.562597 | controller |   "inode": 4340283,
2025-09-30 08:09:00.562613 | controller |   "isblk": false,
2025-09-30 08:09:00.562629 | controller |   "ischr": false,
2025-09-30 08:09:00.562648 | controller |   "isdir": false,
2025-09-30 08:09:00.562666 | controller |   "isfifo": false,
2025-09-30 08:09:00.562682 | controller |   "isgid": false,
2025-09-30 08:09:00.562697 | controller |   "islnk": false,
2025-09-30 08:09:00.562711 | controller |   "isreg": true,
2025-09-30 08:09:00.562725 | controller |   "issock": false,
2025-09-30 08:09:00.562738 | controller |   "isuid": false,
2025-09-30 08:09:00.562751 | controller |   "mode": "0644",
2025-09-30 08:09:00.562765 | controller |   "mtime": 1759219445.2694666,
2025-09-30 08:09:00.562779 | controller |   "nlink": 1,
2025-09-30 08:09:00.562793 | controller |   "path": "/home/zuul/ansible.log",
2025-09-30 08:09:00.562808 | controller |   "pw_name": "zuul",
2025-09-30 08:09:00.562822 | controller |   "rgrp": true,
2025-09-30 08:09:00.562836 | controller |   "roth": true,
2025-09-30 08:09:00.562851 | controller |   "rusr": true,
2025-09-30 08:09:00.562866 | controller |   "size": 6749,
2025-09-30 08:09:00.562880 | controller |   "uid": 1000,
2025-09-30 08:09:00.562894 | controller |   "wgrp": false,
2025-09-30 08:09:00.562908 | controller |   "woth": false,
2025-09-30 08:09:00.562921 | controller |   "wusr": true,
2025-09-30 08:09:00.562934 | controller |   "xgrp": false,
2025-09-30 08:09:00.562948 | controller |   "xoth": false,
2025-09-30 08:09:00.562961 | controller |   "xusr": false
2025-09-30 08:09:00.562974 | controller | }
2025-09-30 08:09:00.575403 | 
2025-09-30 08:09:00.575507 | TASK [Copy crio stats log file]
2025-09-30 08:09:00.589072 | controller | skipping: Conditional result was False
2025-09-30 08:09:00.594304 | 
2025-09-30 08:09:00.594367 | TASK [Get SELinux related data]
2025-09-30 08:09:00.618952 | [controller] Waiting on logger
2025-09-30 08:09:11.008830 | [controller] Waiting on logger
2025-09-30 08:09:20.353018 | [controller] Waiting on logger
2025-09-30 08:09:30.784943 | [controller] Waiting on logger
2025-09-30 08:09:30.899499 | [Zuul] Log Stream did not terminate
2025-09-30 08:09:30.899687 | controller | ERROR
2025-09-30 08:09:30.899782 | controller | {
2025-09-30 08:09:30.899810 | controller |   "delta": "0:00:00.008893",
2025-09-30 08:09:30.899831 | controller |   "end": "2025-09-30 08:09:00.880017",
2025-09-30 08:09:30.899849 | controller |   "msg": "non-zero return code",
2025-09-30 08:09:30.899865 | controller |   "rc": 1,
2025-09-30 08:09:30.899880 | controller |   "start": "2025-09-30 08:09:00.871124"
2025-09-30 08:09:30.899896 | controller | }
2025-09-30 08:09:30.899920 | controller | ERROR: Ignoring Errors
2025-09-30 08:09:30.905576 | 
2025-09-30 08:09:30.905653 | TASK [Create system configuration directory]
2025-09-30 08:09:31.179439 | controller | changed
2025-09-30 08:09:31.184536 | 
2025-09-30 08:09:31.184603 | TASK [Get some of the system configurations]
2025-09-30 08:09:31.204592 | [controller] Waiting on logger
2025-09-30 08:09:41.600932 | [controller] Waiting on logger
2025-09-30 08:09:52.033012 | [controller] Waiting on logger
2025-09-30 08:10:01.376952 | [controller] Waiting on logger
2025-09-30 08:10:01.507135 | [Zuul] Log Stream did not terminate
2025-09-30 08:10:01.507321 | controller | changed
2025-09-30 08:10:01.512328 | 
2025-09-30 08:10:01.512407 | TASK [Copy generated documentation if available]
2025-09-30 08:10:01.525636 | controller | skipping: Conditional result was False
2025-09-30 08:10:01.531011 | 
2025-09-30 08:10:01.531072 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-30 08:10:01.544201 | controller | skipping: Conditional result was False
2025-09-30 08:10:01.549369 | 
2025-09-30 08:10:01.549449 | TASK [Compress logs bigger than 2MB]
2025-09-30 08:10:04.640934 | [controller] Waiting on logger
2025-09-30 08:10:19.206438 | [controller] Waiting on logger
2025-09-30 08:10:29.600764 | [controller] Waiting on logger
2025-09-30 08:10:31.850435 | [Zuul] Log Stream did not terminate
2025-09-30 08:10:31.850578 | controller | changed
2025-09-30 08:10:31.860090 | 
2025-09-30 08:10:31.860162 | TASK [Copy files from workspace on node]
2025-09-30 08:10:31.900125 | controller | ok
2025-09-30 08:10:31.941413 | 
2025-09-30 08:10:31.941503 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 08:10:31.986599 | controller | skipping: Conditional result was False
2025-09-30 08:10:31.992511 | 
2025-09-30 08:10:31.992591 | TASK [fetch-output : Set log path for single node]
2025-09-30 08:10:32.020981 | controller | ok
2025-09-30 08:10:32.025978 | 
2025-09-30 08:10:32.026052 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 08:10:32.284827 | controller -> localhost | ok: "/var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/logs"
2025-09-30 08:10:32.285069 | controller -> localhost | changed: All items complete
2025-09-30 08:10:32.285099 | 
2025-09-30 08:10:32.610975 | controller -> localhost | changed: "/var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/artifacts"
2025-09-30 08:10:32.909504 | controller -> localhost | changed: "/var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/docs"
2025-09-30 08:10:32.927645 | 
2025-09-30 08:10:32.927757 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 08:10:33.684572 | controller | changed:
2025-09-30 08:10:33.684760 | controller | .d..t...... ./
2025-09-30 08:10:33.684794 | controller | >f+++++++++ README.html
2025-09-30 08:10:33.684818 | controller | >f+++++++++ ansible-execution.log
2025-09-30 08:10:33.684840 | controller | >f+++++++++ ansible.log
2025-09-30 08:10:33.684860 | controller | >f+++++++++ crc-setup.log
2025-09-30 08:10:33.684879 | controller | >f+++++++++ crc-start.log
2025-09-30 08:10:33.684897 | controller | >f+++++++++ dmesg.log
2025-09-30 08:10:33.684917 | controller | >f+++++++++ installed-pkgs.log
2025-09-30 08:10:33.684936 | controller | >f+++++++++ python.log
2025-09-30 08:10:33.684955 | controller | >f+++++++++ registries.conf
2025-09-30 08:10:33.684974 | controller | >f+++++++++ report.html
2025-09-30 08:10:33.684992 | controller | >f+++++++++ selinux-denials.log
2025-09-30 08:10:33.685010 | controller | >f+++++++++ selinux-listing.log
2025-09-30 08:10:33.685027 | controller | cd+++++++++ ci-framework-data/
2025-09-30 08:10:33.685045 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-30 08:10:33.685063 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_verify_external_network.sh
2025-09-30 08:10:33.685081 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw
2025-09-30 08:10:33.685099 | controller | >f+++++++++ ci-framework-data/artifacts/kni_id_cifw.pub
2025-09-30 08:10:33.685117 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-30 08:10:33.685140 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-09-30 08:10:33.685159 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-09-30 08:10:33.685177 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-09-30 08:10:33.685194 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-30 08:10:33.685212 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-30 08:10:33.685229 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-09-30 08:10:33.685247 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-30 08:10:33.685275 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-30 08:10:33.685294 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_verify_external_network.log
2025-09-30 08:10:33.685312 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-30 08:10:33.685331 | controller | cd+++++++++ registries.conf.d/
2025-09-30 08:10:33.685349 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-30 08:10:33.685366 | controller | cd+++++++++ system-config/
2025-09-30 08:10:33.685399 | controller | cd+++++++++ system-config/libvirt/
2025-09-30 08:10:33.685426 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-30 08:10:33.685446 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-30 08:10:33.685464 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-09-30 08:10:33.685482 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-09-30 08:10:33.685499 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-09-30 08:10:33.685517 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-09-30 08:10:33.685533 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-09-30 08:10:33.685551 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-09-30 08:10:33.685570 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-09-30 08:10:33.685588 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-09-30 08:10:33.685606 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-09-30 08:10:33.685623 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-09-30 08:10:33.685640 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-09-30 08:10:33.685658 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-09-30 08:10:33.685675 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-09-30 08:10:33.685693 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-09-30 08:10:34.209985 | controller | changed: .d..t...... ./
2025-09-30 08:10:34.700528 | controller | changed: .d..t...... ./
2025-09-30 08:10:34.714201 | 
2025-09-30 08:10:34.714331 | TASK [Return artifact to Zuul]
2025-09-30 08:10:34.753061 | controller | ok
2025-09-30 08:10:34.772847 | 
2025-09-30 08:10:34.772923 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-30 08:10:34.773042 | 
2025-09-30 08:10:34.773077 | PLAY RECAP
2025-09-30 08:10:34.773124 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-30 08:10:34.773152 | 
2025-09-30 08:10:34.879247 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 08:10:34.879986 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 08:10:35.497897 | 
2025-09-30 08:10:35.497995 | PLAY [all]
2025-09-30 08:10:35.517571 | 
2025-09-30 08:10:35.517647 | TASK [include_role : fetch-output]
2025-09-30 08:10:35.547028 | controller | ok
2025-09-30 08:10:35.567697 | 
2025-09-30 08:10:35.567802 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 08:10:35.631795 | controller | skipping: Conditional result was False
2025-09-30 08:10:35.637690 | 
2025-09-30 08:10:35.637775 | TASK [fetch-output : Set log path for single node]
2025-09-30 08:10:35.697280 | controller | ok
2025-09-30 08:10:35.702138 | 
2025-09-30 08:10:35.702210 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 08:10:36.236195 | controller -> localhost | ok: "/var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/logs"
2025-09-30 08:10:36.468015 | controller -> localhost | ok: "/var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/artifacts"
2025-09-30 08:10:36.666839 | controller -> localhost | ok: "/var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/docs"
2025-09-30 08:10:36.684583 | 
2025-09-30 08:10:36.684682 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 08:10:37.307762 | controller | ok
2025-09-30 08:10:37.307940 | controller | ok: All items complete
2025-09-30 08:10:37.307970 | 
2025-09-30 08:10:37.803922 | controller | ok
2025-09-30 08:10:38.279439 | controller | ok
2025-09-30 08:10:38.295866 | 
2025-09-30 08:10:38.296000 | TASK [include_role : fetch-output-openshift]
2025-09-30 08:10:38.309127 | controller | skipping: Conditional result was False
2025-09-30 08:10:38.314841 | 
2025-09-30 08:10:38.314910 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-30 08:10:38.724409 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006529
2025-09-30 08:10:38.916988 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012163
2025-09-30 08:10:38.960889 | 
2025-09-30 08:10:38.960966 | PLAY [all]
2025-09-30 08:10:38.975338 | 
2025-09-30 08:10:38.975424 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-30 08:10:39.473318 | controller | changed
2025-09-30 08:10:39.495333 | 
2025-09-30 08:10:39.495419 | PLAY RECAP
2025-09-30 08:10:39.495469 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-30 08:10:39.495494 | 
2025-09-30 08:10:39.566485 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 08:10:39.567209 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-30 08:10:40.134886 | 
2025-09-30 08:10:40.134998 | PLAY [localhost]
2025-09-30 08:10:40.152776 | 
2025-09-30 08:10:40.152861 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-30 08:10:40.475995 | localhost | changed
2025-09-30 08:10:40.481019 | 
2025-09-30 08:10:40.481122 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-30 08:10:40.498951 | localhost | ok
2025-09-30 08:10:40.507454 | 
2025-09-30 08:10:40.507541 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-30 08:10:40.820607 | localhost | changed
2025-09-30 08:10:40.825983 | 
2025-09-30 08:10:40.826057 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-30 08:10:41.403404 | localhost | changed
2025-09-30 08:10:41.408519 | 
2025-09-30 08:10:41.410488 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-30 08:10:41.845978 | localhost | Identity added: /var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/tmp/ansible.no8kib_n (/var/lib/zuul/builds/e971761159db4c20ae481377ce749af1/work/tmp/ansible.no8kib_n)
2025-09-30 08:10:41.848000 | localhost | ok: Runtime: 0:00:00.006438
2025-09-30 08:10:41.852339 | 
2025-09-30 08:10:41.852423 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-30 08:10:42.082727 | localhost | ok: Runtime: 0:00:00.004191
2025-09-30 08:10:42.087463 | 
2025-09-30 08:10:42.087530 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-30 08:10:42.136186 | localhost | changed
2025-09-30 08:10:42.140353 | 
2025-09-30 08:10:42.140438 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-30 08:10:42.473424 | localhost | changed
2025-09-30 08:10:42.492375 | 
2025-09-30 08:10:42.492505 | PLAY [localhost]
2025-09-30 08:10:42.503553 | 
2025-09-30 08:10:42.503638 | TASK [Generate bulk log download script]
2025-09-30 08:10:42.523191 | localhost | ok
2025-09-30 08:10:42.534630 | 
2025-09-30 08:10:42.534728 | TASK [local-log-download : Check API endpoint is defined]
2025-09-30 08:10:42.562174 | localhost | ok: All assertions passed
2025-09-30 08:10:42.566992 | 
2025-09-30 08:10:42.567064 | TASK [local-log-download : Create download script]
2025-09-30 08:10:42.924175 | localhost -> localhost | changed
2025-09-30 08:10:42.932589 | 
2025-09-30 08:10:42.932658 | TASK [Register quick-download link]
2025-09-30 08:10:42.949429 | localhost | ok
2025-09-30 08:10:42.982787 | 
2025-09-30 08:10:42.982867 | PLAY [logserver.rdoproject.org]
2025-09-30 08:10:42.992212 | 
2025-09-30 08:10:42.992287 | TASK [Set zuul-log-path fact]
2025-09-30 08:10:43.007766 | logserver.rdoproject.org | ok
2025-09-30 08:10:43.016422 | 
2025-09-30 08:10:43.016491 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 08:10:43.042047 | logserver.rdoproject.org | ok
2025-09-30 08:10:43.048579 | 
2025-09-30 08:10:43.048651 | TASK [upload-logs : Create log directories]
2025-09-30 08:10:44.823430 | logserver.rdoproject.org | changed
2025-09-30 08:10:44.826822 | 
2025-09-30 08:10:44.826887 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-30 08:10:45.084964 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005700
2025-09-30 08:10:45.089733 | 
2025-09-30 08:10:45.089811 | TASK [upload-logs : Upload logs to log server]
2025-09-30 08:10:46.643057 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-30 08:10:46.646131 | 
2025-09-30 08:10:46.646212 | LOOP [upload-logs : Compress console log and json output]
2025-09-30 08:10:46.686620 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 08:10:46.695142 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 08:10:46.706497 | 
2025-09-30 08:10:46.706606 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-30 08:10:46.748376 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 08:10:46.748621 | 
2025-09-30 08:10:46.752092 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 08:10:46.764562 | 
2025-09-30 08:10:46.764664 | LOOP [upload-logs : Upload console log and json output]