2025-09-30 05:12:59.089368 | Job console starting...
2025-09-30 05:12:59.334117 | Updating repositories
2025-09-30 05:13:00.018601 | Preparing job workspace
2025-09-30 05:13:08.161253 | Running Ansible setup...
2025-09-30 05:13:11.745523 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 05:13:12.412749 | 
2025-09-30 05:13:12.412856 | PLAY [localhost]
2025-09-30 05:13:12.427280 | 
2025-09-30 05:13:12.427358 | TASK [Gathering Facts]
2025-09-30 05:13:13.433532 | localhost | ok
2025-09-30 05:13:13.457750 | 
2025-09-30 05:13:13.457874 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-30 05:13:13.943037 | localhost -> localhost | changed
2025-09-30 05:13:13.948533 | 
2025-09-30 05:13:13.948605 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-30 05:13:15.133357 | localhost -> localhost | changed
2025-09-30 05:13:15.141697 | 
2025-09-30 05:13:15.141773 | TASK [Setup log path fact]
2025-09-30 05:13:15.189257 | localhost | ok
2025-09-30 05:13:15.214814 | 
2025-09-30 05:13:15.214940 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 05:13:15.248181 | localhost | ok
2025-09-30 05:13:15.261991 | 
2025-09-30 05:13:15.262149 | TASK [emit-job-header : Print job information]
2025-09-30 05:13:15.290637 | # Job Information
2025-09-30 05:13:15.290866 | Ansible Version: 2.15.12
2025-09-30 05:13:15.290903 | Job: cifmw-molecule-pkg_build
2025-09-30 05:13:15.290964 | Pipeline: github-check
2025-09-30 05:13:15.291042 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-30 05:13:15.291069 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333
2025-09-30 05:13:15.291113 | Log URL (when completed): https://logserver.rdoproject.org/091/rdoproject.org/09108751206f40feb188a1d84abec651/
2025-09-30 05:13:15.291138 | Event ID: 3089cae0-9dba-11f0-9d66-59f03df1d747
2025-09-30 05:13:15.295313 | 
2025-09-30 05:13:15.295393 | LOOP [emit-job-header : Print node information]
2025-09-30 05:13:15.401793 | localhost | ok:
2025-09-30 05:13:15.402002 | localhost | # Node Information
2025-09-30 05:13:15.402035 | localhost | Inventory Hostname: controller
2025-09-30 05:13:15.402065 | localhost | Hostname: np0005461686
2025-09-30 05:13:15.402099 | localhost | Username: zuul
2025-09-30 05:13:15.402121 | localhost | Distro: CentOS 9
2025-09-30 05:13:15.402141 | localhost | Provider: ibm-bm4-nodepool
2025-09-30 05:13:15.402159 | localhost | Region: regionOne
2025-09-30 05:13:15.402176 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-30 05:13:15.402194 | localhost | Product Name: OpenStack Compute
2025-09-30 05:13:15.402212 | localhost | Interface IP: 192.168.26.199
2025-09-30 05:13:15.438700 | 
2025-09-30 05:13:15.438794 | PLAY [all]
2025-09-30 05:13:15.447803 | 
2025-09-30 05:13:15.447868 | TASK [Gather network facts]
2025-09-30 05:13:15.849135 | controller | ok
2025-09-30 05:13:15.887296 | 
2025-09-30 05:13:15.887412 | TASK [include_role : start-zuul-console]
2025-09-30 05:13:15.917148 | controller | ok
2025-09-30 05:13:15.949669 | 
2025-09-30 05:13:15.949780 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-30 05:13:16.441286 | controller | ok
2025-09-30 05:13:16.457353 | 
2025-09-30 05:13:16.457435 | TASK [include_role : add-build-sshkey]
2025-09-30 05:13:16.505353 | controller | ok
2025-09-30 05:13:16.542394 | 
2025-09-30 05:13:16.542480 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-30 05:13:17.084651 | controller -> localhost | ok
2025-09-30 05:13:17.103936 | 
2025-09-30 05:13:17.104031 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-30 05:13:17.132343 | controller | ok
2025-09-30 05:13:17.147892 | controller | included: /var/lib/zuul/builds/09108751206f40feb188a1d84abec651/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-30 05:13:17.154301 | 
2025-09-30 05:13:17.154374 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-30 05:13:17.779263 | controller -> localhost | Generating public/private rsa key pair.
2025-09-30 05:13:17.779468 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/09108751206f40feb188a1d84abec651_id_rsa.
2025-09-30 05:13:17.779519 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/09108751206f40feb188a1d84abec651_id_rsa.pub.
2025-09-30 05:13:17.779548 | controller -> localhost | The key fingerprint is:
2025-09-30 05:13:17.779572 | controller -> localhost | SHA256:CtFFnpcaAjZYSttwT+AAPtCcifzx8PJi0ESIosjL+PA zuul-build-sshkey
2025-09-30 05:13:17.779593 | controller -> localhost | The key's randomart image is:
2025-09-30 05:13:17.779614 | controller -> localhost | +---[RSA 3072]----+
2025-09-30 05:13:17.779633 | controller -> localhost | |==**B...o        |
2025-09-30 05:13:17.779652 | controller -> localhost | |*+*& * o . .     |
2025-09-30 05:13:17.779670 | controller -> localhost | |=o* O + + o      |
2025-09-30 05:13:17.779695 | controller -> localhost | |oo.+ + . +       |
2025-09-30 05:13:17.779713 | controller -> localhost | |o o +   S        |
2025-09-30 05:13:17.779731 | controller -> localhost | |oo o o .         |
2025-09-30 05:13:17.779749 | controller -> localhost | | +. . .          |
2025-09-30 05:13:17.779766 | controller -> localhost | |  E              |
2025-09-30 05:13:17.779785 | controller -> localhost | |                 |
2025-09-30 05:13:17.779805 | controller -> localhost | +----[SHA256]-----+
2025-09-30 05:13:17.779857 | controller -> localhost | ok: Runtime: 0:00:00.127831
2025-09-30 05:13:17.785740 | 
2025-09-30 05:13:17.785809 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-30 05:13:17.814884 | controller | ok
2025-09-30 05:13:17.845471 | controller | included: /var/lib/zuul/builds/09108751206f40feb188a1d84abec651/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-30 05:13:17.853841 | 
2025-09-30 05:13:17.853936 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-30 05:13:17.877730 | controller | skipping: Conditional result was False
2025-09-30 05:13:17.883034 | 
2025-09-30 05:13:17.883107 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-30 05:13:18.262794 | controller | changed
2025-09-30 05:13:18.268644 | 
2025-09-30 05:13:18.268728 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-30 05:13:18.477689 | controller | ok
2025-09-30 05:13:18.498988 | 
2025-09-30 05:13:18.499080 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-30 05:13:19.202958 | controller | changed
2025-09-30 05:13:19.212642 | 
2025-09-30 05:13:19.212743 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-30 05:13:19.955649 | controller | changed
2025-09-30 05:13:19.962865 | 
2025-09-30 05:13:19.962981 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-30 05:13:20.035062 | controller | skipping: Conditional result was False
2025-09-30 05:13:20.041076 | 
2025-09-30 05:13:20.041149 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-30 05:13:21.023198 | controller -> localhost | changed
2025-09-30 05:13:21.032645 | 
2025-09-30 05:13:21.032710 | TASK [add-build-sshkey : Add back temp key]
2025-09-30 05:13:21.487308 | controller -> localhost | Identity added: /var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/09108751206f40feb188a1d84abec651_id_rsa (zuul-build-sshkey)
2025-09-30 05:13:21.487490 | controller -> localhost | ok: Runtime: 0:00:00.025027
2025-09-30 05:13:21.493178 | 
2025-09-30 05:13:21.493245 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-30 05:13:21.949041 | controller | ok
2025-09-30 05:13:21.953555 | 
2025-09-30 05:13:21.953621 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-30 05:13:22.006863 | controller | skipping: Conditional result was False
2025-09-30 05:13:22.016365 | 
2025-09-30 05:13:22.016443 | TASK [include_role : validate-host]
2025-09-30 05:13:22.080362 | controller | ok
2025-09-30 05:13:22.131472 | 
2025-09-30 05:13:22.131590 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-30 05:13:22.197982 | controller | ok
2025-09-30 05:13:22.250664 | 
2025-09-30 05:13:22.250764 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-30 05:13:22.459726 | controller -> localhost | ok
2025-09-30 05:13:22.465712 | 
2025-09-30 05:13:22.465778 | TASK [validate-host : Collect information about the host]
2025-09-30 05:13:23.092101 | controller | ok
2025-09-30 05:13:23.100397 | 
2025-09-30 05:13:23.100462 | TASK [validate-host : Sanitize hostname]
2025-09-30 05:13:23.171273 | controller | ok
2025-09-30 05:13:23.186351 | 
2025-09-30 05:13:23.186422 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-30 05:13:23.727649 | controller -> localhost | changed
2025-09-30 05:13:23.733250 | 
2025-09-30 05:13:23.733324 | TASK [validate-host : Collect information about zuul worker]
2025-09-30 05:13:24.201042 | controller | ok
2025-09-30 05:13:24.206393 | 
2025-09-30 05:13:24.206491 | TASK [validate-host : Write out all zuul information for each host]
2025-09-30 05:13:24.988330 | controller -> localhost | changed
2025-09-30 05:13:25.002674 | 
2025-09-30 05:13:25.002758 | TASK [include_role : prepare-workspace-openshift]
2025-09-30 05:13:25.047035 | controller | skipping: Conditional result was False
2025-09-30 05:13:25.052650 | 
2025-09-30 05:13:25.052733 | TASK [include_role : remove-zuul-sshkey]
2025-09-30 05:13:25.096813 | controller | skipping: Conditional result was False
2025-09-30 05:13:25.102315 | 
2025-09-30 05:13:25.102391 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-30 05:13:25.348215 | controller | ok: "logs"
2025-09-30 05:13:25.348432 | controller | ok: All items complete
2025-09-30 05:13:25.348469 | 
2025-09-30 05:13:25.531466 | controller | ok: "artifacts"
2025-09-30 05:13:25.719182 | controller | ok: "docs"
2025-09-30 05:13:25.733497 | 
2025-09-30 05:13:25.733980 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-30 05:13:25.975386 | controller | changed: "logs"
2025-09-30 05:13:26.147239 | controller | changed: "artifacts"
2025-09-30 05:13:26.329818 | controller | changed: "docs"
2025-09-30 05:13:26.372177 | 
2025-09-30 05:13:26.372303 | PLAY RECAP
2025-09-30 05:13:26.372354 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 05:13:26.372385 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 05:13:26.372405 | 
2025-09-30 05:13:26.544565 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 05:13:26.545326 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 05:13:27.402854 | 
2025-09-30 05:13:27.403016 | PLAY [all]
2025-09-30 05:13:27.439802 | 
2025-09-30 05:13:27.441219 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 05:13:27.554270 | controller | ok
2025-09-30 05:13:27.559671 | 
2025-09-30 05:13:27.559776 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 05:13:28.073829 | controller | changed
2025-09-30 05:13:28.079561 | 
2025-09-30 05:13:28.079643 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 05:13:29.123370 | controller | changed
2025-09-30 05:13:29.135615 | 
2025-09-30 05:13:29.135690 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-30 05:13:29.538260 | controller | changed:
2025-09-30 05:13:29.538651 | controller | {
2025-09-30 05:13:29.538692 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-30 05:13:29.538725 | controller | }
2025-09-30 05:13:29.768605 | controller | changed:
2025-09-30 05:13:29.768693 | controller | {
2025-09-30 05:13:29.768726 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-30 05:13:29.768750 | controller | }
2025-09-30 05:13:29.975495 | controller | changed:
2025-09-30 05:13:29.975595 | controller | {
2025-09-30 05:13:29.975624 | 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 05:13:29.975647 | controller | }
2025-09-30 05:13:30.187843 | controller | changed:
2025-09-30 05:13:30.187981 | controller | {
2025-09-30 05:13:30.188012 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-30 05:13:30.188035 | controller | }
2025-09-30 05:13:30.409622 | controller | changed:
2025-09-30 05:13:30.409707 | controller | {
2025-09-30 05:13:30.409735 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-30 05:13:30.409757 | controller | }
2025-09-30 05:13:30.656575 | controller | changed:
2025-09-30 05:13:30.656668 | controller | {
2025-09-30 05:13:30.656698 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-30 05:13:30.656720 | controller | }
2025-09-30 05:13:30.888421 | controller | changed:
2025-09-30 05:13:30.888495 | controller | {
2025-09-30 05:13:30.888533 | 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 05:13:30.888556 | controller | }
2025-09-30 05:13:31.110545 | controller | changed:
2025-09-30 05:13:31.110640 | controller | {
2025-09-30 05:13:31.110670 | 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 05:13:31.110692 | controller | }
2025-09-30 05:13:31.326283 | controller | changed:
2025-09-30 05:13:31.326371 | controller | {
2025-09-30 05:13:31.326401 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-30 05:13:31.326423 | controller | }
2025-09-30 05:13:31.545662 | controller | changed:
2025-09-30 05:13:31.545743 | controller | {
2025-09-30 05:13:31.545771 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-30 05:13:31.545793 | controller | }
2025-09-30 05:13:31.839146 | controller | changed:
2025-09-30 05:13:31.839235 | controller | {
2025-09-30 05:13:31.839264 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-30 05:13:31.839287 | controller | }
2025-09-30 05:13:31.981852 | controller | changed:
2025-09-30 05:13:31.981959 | controller | {
2025-09-30 05:13:31.981988 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-30 05:13:31.982018 | controller | }
2025-09-30 05:13:32.194627 | controller | changed:
2025-09-30 05:13:32.194720 | controller | {
2025-09-30 05:13:32.194747 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-30 05:13:32.194768 | controller | }
2025-09-30 05:13:32.397435 | controller | changed:
2025-09-30 05:13:32.397544 | controller | {
2025-09-30 05:13:32.397573 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-30 05:13:32.397593 | controller | }
2025-09-30 05:13:32.601064 | controller | changed:
2025-09-30 05:13:32.601147 | controller | {
2025-09-30 05:13:32.601174 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-30 05:13:32.601194 | controller | }
2025-09-30 05:13:32.847803 | controller | changed:
2025-09-30 05:13:32.847894 | controller | {
2025-09-30 05:13:32.847996 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-30 05:13:32.848022 | controller | }
2025-09-30 05:13:33.116777 | controller | changed:
2025-09-30 05:13:33.116866 | controller | {
2025-09-30 05:13:33.116896 | 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 05:13:33.116955 | controller | }
2025-09-30 05:13:33.316759 | controller | changed:
2025-09-30 05:13:33.316848 | controller | {
2025-09-30 05:13:33.316875 | 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 05:13:33.317094 | controller | }
2025-09-30 05:13:33.554454 | controller | changed:
2025-09-30 05:13:33.554556 | controller | {
2025-09-30 05:13:33.554585 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-30 05:13:33.554606 | controller | }
2025-09-30 05:13:33.783878 | controller | changed:
2025-09-30 05:13:33.785560 | controller | {
2025-09-30 05:13:33.785595 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-30 05:13:33.785619 | controller | }
2025-09-30 05:13:34.004186 | controller | changed:
2025-09-30 05:13:34.004272 | controller | {
2025-09-30 05:13:34.004298 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-30 05:13:34.004319 | controller | }
2025-09-30 05:13:34.222758 | controller | changed:
2025-09-30 05:13:34.222850 | controller | {
2025-09-30 05:13:34.222885 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-30 05:13:34.223006 | controller | }
2025-09-30 05:13:34.448725 | controller | changed:
2025-09-30 05:13:34.448810 | controller | {
2025-09-30 05:13:34.448837 | 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 05:13:34.448860 | controller | }
2025-09-30 05:13:34.668422 | controller | changed:
2025-09-30 05:13:34.668524 | controller | {
2025-09-30 05:13:34.668555 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-30 05:13:34.668577 | controller | }
2025-09-30 05:13:34.888415 | controller | changed:
2025-09-30 05:13:34.888503 | controller | {
2025-09-30 05:13:34.888547 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-30 05:13:34.888569 | controller | }
2025-09-30 05:13:35.112485 | controller | changed:
2025-09-30 05:13:35.112577 | controller | {
2025-09-30 05:13:35.112605 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-30 05:13:35.112627 | controller | }
2025-09-30 05:13:35.145391 | 
2025-09-30 05:13:35.145483 | TASK [Set timezone to UTC]
2025-09-30 05:13:35.672307 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-30 05:13:35.682542 | 
2025-09-30 05:13:35.682616 | TASK [Create nodepool directory]
2025-09-30 05:13:35.913688 | controller | changed
2025-09-30 05:13:35.921940 | 
2025-09-30 05:13:35.922033 | TASK [Create nodepool sub_nodes file]
2025-09-30 05:13:36.503967 | controller | changed
2025-09-30 05:13:36.511467 | 
2025-09-30 05:13:36.511583 | TASK [Create nodepool sub_nodes_private file]
2025-09-30 05:13:37.112017 | controller | changed
2025-09-30 05:13:37.117471 | 
2025-09-30 05:13:37.117564 | LOOP [Populate nodepool sub_nodes file]
2025-09-30 05:13:37.150552 | 
2025-09-30 05:13:37.150701 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-30 05:13:37.179848 | 
2025-09-30 05:13:37.180008 | TASK [Create nodepool primary file]
2025-09-30 05:13:37.203462 | controller | skipping: Conditional result was False
2025-09-30 05:13:37.209943 | 
2025-09-30 05:13:37.210036 | TASK [Create nodepool node_private for this node]
2025-09-30 05:13:37.798562 | controller | changed
2025-09-30 05:13:37.804617 | 
2025-09-30 05:13:37.804702 | LOOP [Copy ssh keys to nodepool directory]
2025-09-30 05:13:38.176987 | controller | ok: Item: id_rsa Runtime: 0:00:00.006657
2025-09-30 05:13:38.177168 | 
2025-09-30 05:13:38.368721 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007073
2025-09-30 05:13:38.377487 | 
2025-09-30 05:13:38.377615 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-30 05:13:39.022231 | controller | changed
2025-09-30 05:13:39.027259 | 
2025-09-30 05:13:39.027322 | TASK [Validate sudoers config after edits]
2025-09-30 05:13:39.289560 | controller | /etc/sudoers: parsed OK
2025-09-30 05:13:39.289671 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-30 05:13:39.289685 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-30 05:13:39.289692 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-30 05:13:39.578418 | controller | ok: Runtime: 0:00:00.005688
2025-09-30 05:13:39.584287 | 
2025-09-30 05:13:39.584357 | TASK [Show the environment passed in to job shell scripts]
2025-09-30 05:13:39.846286 | controller | SHELL=/bin/bash
2025-09-30 05:13:39.846338 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-30 05:13:39.846347 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-30 05:13:39.846354 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/eb5b53fb17db92a72d33d052a56c8edd0d10ea78^openstack-k8s-operators/ci-framework:main:refs/changes/33/3333/b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 05:13:39.846363 | controller | PWD=/home/zuul
2025-09-30 05:13:39.846369 | controller | ZUUL_PIPELINE=github-check
2025-09-30 05:13:39.846417 | controller | LOGNAME=zuul
2025-09-30 05:13:39.846426 | controller | XDG_SESSION_TYPE=tty
2025-09-30 05:13:39.846432 | controller | _=/usr/bin/env
2025-09-30 05:13:39.846437 | controller | MOTD_SHOWN=pam
2025-09-30 05:13:39.846442 | controller | HOME=/home/zuul
2025-09-30 05:13:39.846448 | controller | LANG=en_US.UTF-8
2025-09-30 05:13:39.846453 | controller | SSH_CONNECTION=192.168.26.12 48582 192.168.26.199 22
2025-09-30 05:13:39.846460 | 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 05:13:39.846465 | controller | ZUUL_CHANGE_IDS=3346,eb5b53fb17db92a72d33d052a56c8edd0d10ea78 3333,b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 05:13:39.846471 | controller | WORKSPACE=/home/zuul/workspace
2025-09-30 05:13:39.846477 | controller | XDG_SESSION_CLASS=user
2025-09-30 05:13:39.846482 | controller | SELINUX_ROLE_REQUESTED=
2025-09-30 05:13:39.846488 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-30 05:13:39.846493 | controller | USER=zuul
2025-09-30 05:13:39.846498 | controller | ZUUL_VOTING=True
2025-09-30 05:13:39.846504 | controller | BUILD_TIMEOUT=1800000
2025-09-30 05:13:39.846509 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-30 05:13:39.846515 | controller | SHLVL=1
2025-09-30 05:13:39.846520 | controller | ZUUL_PATCHSET=b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 05:13:39.846526 | controller | XDG_SESSION_ID=1
2025-09-30 05:13:39.846531 | controller | ZUUL_BRANCH=main
2025-09-30 05:13:39.846537 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-30 05:13:39.846542 | controller | SSH_CLIENT=192.168.26.12 48582 22
2025-09-30 05:13:39.846547 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-30 05:13:39.846553 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-30 05:13:39.846558 | controller | which_declare=declare -f
2025-09-30 05:13:39.846564 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-30 05:13:39.846570 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-30 05:13:39.846576 | controller | ZUUL_CHANGE=3333
2025-09-30 05:13:39.846581 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-30 05:13:39.846587 | controller | ZUUL_UUID=09108751206f40feb188a1d84abec651
2025-09-30 05:13:39.846592 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-30 05:13:39.846599 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-30 05:13:39.846605 | controller | }
2025-09-30 05:13:40.134253 | controller | ok: Runtime: 0:00:00.006122
2025-09-30 05:13:40.142164 | 
2025-09-30 05:13:40.142250 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-30 05:13:40.197525 | controller | skipping: Conditional result was False
2025-09-30 05:13:40.203209 | 
2025-09-30 05:13:40.203291 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-30 05:13:40.774766 | controller | skipping: Conditional result was False
2025-09-30 05:13:40.783387 | 
2025-09-30 05:13:40.783558 | TASK [Ensure legacy workspace directory]
2025-09-30 05:13:40.978343 | controller | changed
2025-09-30 05:13:40.999614 | 
2025-09-30 05:13:40.999700 | PLAY RECAP
2025-09-30 05:13:40.999749 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 05:13:40.999790 | 
2025-09-30 05:13:41.085571 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 05:13:41.086342 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-30 05:13:41.670659 | 
2025-09-30 05:13:41.670757 | PLAY [Load global variables]
2025-09-30 05:13:41.691321 | 
2025-09-30 05:13:41.691442 | TASK [Read group_vars all file]
2025-09-30 05:13:41.711385 | controller | ok
2025-09-30 05:13:41.727196 | 
2025-09-30 05:13:41.727290 | TASK [cifmw_helpers : Check if file is available]
2025-09-30 05:13:42.020131 | controller | ok
2025-09-30 05:13:42.025896 | 
2025-09-30 05:13:42.026112 | TASK [cifmw_helpers : Read vars]
2025-09-30 05:13:42.051016 | 
2025-09-30 05:13:42.051170 | LOOP [cifmw_helpers : Set vars as fact]
2025-09-30 05:13:42.109665 | 
2025-09-30 05:13:42.109795 | PLAY RECAP
2025-09-30 05:13:42.109840 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-30 05:13:42.109864 | 
2025-09-30 05:13:42.224332 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-30 05:13:42.225121 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 05:13:42.854447 | 
2025-09-30 05:13:42.854582 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-30 05:13:42.899145 | 
2025-09-30 05:13:42.899273 | TASK [Create zuul-output directory]
2025-09-30 05:13:43.241832 | controller | changed
2025-09-30 05:13:43.247239 | 
2025-09-30 05:13:43.247324 | TASK [Slurp Zuul inventory test]
2025-09-30 05:13:43.752838 | controller -> localhost | ok
2025-09-30 05:13:43.759971 | 
2025-09-30 05:13:43.760045 | TASK [Save zuul inventory]
2025-09-30 05:13:44.672823 | controller | changed
2025-09-30 05:13:44.678952 | 
2025-09-30 05:13:44.679036 | TASK [Save zuul vars without the change_message]
2025-09-30 05:13:45.336017 | controller | changed
2025-09-30 05:13:45.363414 | 
2025-09-30 05:13:45.363502 | PLAY RECAP
2025-09-30 05:13:45.363571 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 05:13:45.363597 | 
2025-09-30 05:13:45.505476 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 05:13:45.506471 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 05:13:46.247084 | 
2025-09-30 05:13:46.247192 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-30 05:13:46.284165 | 
2025-09-30 05:13:46.284270 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-30 05:13:46.304869 | controller | ok
2025-09-30 05:13:46.323164 | 
2025-09-30 05:13:46.323292 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 05:13:46.348631 | controller | skipping: Conditional result was False
2025-09-30 05:13:46.354446 | 
2025-09-30 05:13:46.354551 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 05:13:46.680625 | controller | ok
2025-09-30 05:13:46.699494 | 
2025-09-30 05:13:46.699603 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 05:13:47.371077 | controller | ok
2025-09-30 05:13:47.395961 | 
2025-09-30 05:13:47.399427 | TASK [Prepare workspace]
2025-09-30 05:13:47.431984 | controller | ok
2025-09-30 05:13:47.462846 | 
2025-09-30 05:13:47.462974 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-30 05:13:47.824298 | controller | ok
2025-09-30 05:13:47.837107 | 
2025-09-30 05:13:47.837198 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-30 05:13:50.177189 | controller | Output suppressed because no_log was given
2025-09-30 05:13:50.187853 | 
2025-09-30 05:13:50.187985 | LOOP [Create zuul-output directory]
2025-09-30 05:13:50.386832 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-30 05:13:50.566114 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-30 05:13:50.577182 | 
2025-09-30 05:13:50.577251 | TASK [Install required packages]
2025-09-30 05:14:44.538094 | controller | changed
2025-09-30 05:14:44.543084 | 
2025-09-30 05:14:44.543158 | TASK [Install venv]
2025-09-30 05:15:45.962042 | controller | changed
2025-09-30 05:15:46.009036 | 
2025-09-30 05:15:46.009126 | PLAY RECAP
2025-09-30 05:15:46.009181 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 05:15:46.009211 | 
2025-09-30 05:15:46.092940 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 05:15:46.094139 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 05:15:46.718116 | 
2025-09-30 05:15:46.718220 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-30 05:15:46.739455 | 
2025-09-30 05:15:46.739556 | TASK [Gather required facts]
2025-09-30 05:15:47.292114 | controller | ok
2025-09-30 05:15:47.298554 | 
2025-09-30 05:15:47.298655 | TASK [Load environment var if instructed to]
2025-09-30 05:15:47.324012 | controller | skipping: Conditional result was False
2025-09-30 05:15:47.331560 | 
2025-09-30 05:15:47.331677 | TASK [Run molecule]
2025-09-30 05:15:48.364253 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-30 05:15:48.433367 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-30 05:16:06.113489 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.114055 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.114569 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.115066 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.115597 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+e2f9c552 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 1.0.0+e2f9c552 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.116095 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.116576 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.117057 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.117544 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.118043 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.118523 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.119015 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.119499 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.119986 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.120467 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/a8a9aa/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 05:16:06.130288 | controller | INFO     Running default > prepare
2025-09-30 05:16:06.844623 | controller |
2025-09-30 05:16:07.640694 | controller | PLAY [Prepare] *****************************************************************
2025-09-30 05:16:07.640726 | controller |
2025-09-30 05:16:07.640734 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 05:16:07.640740 | controller | Tuesday 30 September 2025  05:16:06 +0000 (0:00:00.029)       0:00:00.029 *****
2025-09-30 05:16:07.640750 | controller | ok: [instance]
2025-09-30 05:16:07.658277 | controller |
2025-09-30 05:16:07.658303 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-09-30 05:16:07.658312 | controller | Tuesday 30 September 2025  05:16:07 +0000 (0:00:00.796)       0:00:00.825 *****
2025-09-30 05:16:07.658323 | controller | skipping: [instance]
2025-09-30 05:16:07.699718 | controller |
2025-09-30 05:16:07.699752 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-09-30 05:16:07.699760 | controller | Tuesday 30 September 2025  05:16:07 +0000 (0:00:00.017)       0:00:00.843 *****
2025-09-30 05:16:07.699769 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-09-30 05:16:07.700015 | controller |
2025-09-30 05:16:07.700028 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-09-30 05:16:07.987605 | controller | Tuesday 30 September 2025  05:16:07 +0000 (0:00:00.041)       0:00:00.884 *****
2025-09-30 05:16:07.987638 | controller | ok: [instance]
2025-09-30 05:16:08.302272 | controller |
2025-09-30 05:16:08.302302 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-09-30 05:16:08.302310 | controller | Tuesday 30 September 2025  05:16:07 +0000 (0:00:00.288)       0:00:01.172 *****
2025-09-30 05:16:08.302320 | controller | ok: [instance]
2025-09-30 05:16:08.998725 | controller |
2025-09-30 05:16:08.998759 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-09-30 05:16:08.998767 | controller | Tuesday 30 September 2025  05:16:08 +0000 (0:00:00.314)       0:00:01.487 *****
2025-09-30 05:16:08.998778 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-09-30 05:16:09.050845 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-09-30 05:16:09.050885 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-09-30 05:16:09.050894 | controller |
2025-09-30 05:16:09.050902 | controller | TASK [Deploy repo-setup] *******************************************************
2025-09-30 05:16:09.050909 | controller | Tuesday 30 September 2025  05:16:08 +0000 (0:00:00.696)       0:00:02.183 *****
2025-09-30 05:16:09.050920 | controller |
2025-09-30 05:16:09.050951 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-30 05:16:09.051098 | controller | Tuesday 30 September 2025  05:16:09 +0000 (0:00:00.052)       0:00:02.236 *****
2025-09-30 05:16:09.582070 | controller | changed: [instance] => (item=tmp)
2025-09-30 05:16:09.582136 | controller | changed: [instance] => (item=artifacts/repositories)
2025-09-30 05:16:09.582253 | controller | changed: [instance] => (item=venv/repo_setup)
2025-09-30 05:16:09.582347 | controller |
2025-09-30 05:16:09.582449 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-30 05:16:09.582578 | controller | Tuesday 30 September 2025  05:16:09 +0000 (0:00:00.531)       0:00:02.767 *****
2025-09-30 05:16:10.348513 | controller | ok: [instance]
2025-09-30 05:16:10.348869 | controller |
2025-09-30 05:16:11.513793 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-30 05:16:11.513823 | controller | Tuesday 30 September 2025  05:16:10 +0000 (0:00:00.766)       0:00:03.534 *****
2025-09-30 05:16:11.513835 | controller | changed: [instance]
2025-09-30 05:16:11.514191 | controller |
2025-09-30 05:16:19.493242 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-30 05:16:19.493277 | controller | Tuesday 30 September 2025  05:16:11 +0000 (0:00:01.165)       0:00:04.699 *****
2025-09-30 05:16:19.493289 | controller | changed: [instance]
2025-09-30 05:16:20.195838 | controller |
2025-09-30 05:16:20.195873 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-30 05:16:20.195881 | controller | Tuesday 30 September 2025  05:16:19 +0000 (0:00:07.979)       0:00:12.678 *****
2025-09-30 05:16:20.195891 | controller | changed: [instance]
2025-09-30 05:16:20.217442 | controller |
2025-09-30 05:16:20.217466 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-30 05:16:20.217473 | controller | Tuesday 30 September 2025  05:16:20 +0000 (0:00:00.702)       0:00:13.381 *****
2025-09-30 05:16:20.217482 | controller | skipping: [instance]
2025-09-30 05:16:21.030716 | controller |
2025-09-30 05:16:21.030749 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-30 05:16:21.030757 | controller | Tuesday 30 September 2025  05:16:20 +0000 (0:00:00.021)       0:00:13.402 *****
2025-09-30 05:16:21.030767 | controller | changed: [instance]
2025-09-30 05:16:21.030791 | controller |
2025-09-30 05:16:21.030799 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-30 05:16:21.030934 | controller | Tuesday 30 September 2025  05:16:21 +0000 (0:00:00.813)       0:00:14.216 *****
2025-09-30 05:16:21.067407 | controller | skipping: [instance]
2025-09-30 05:16:21.103743 | controller |
2025-09-30 05:16:21.103777 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-30 05:16:21.103785 | controller | Tuesday 30 September 2025  05:16:21 +0000 (0:00:00.036)       0:00:14.252 *****
2025-09-30 05:16:21.103795 | controller | skipping: [instance]
2025-09-30 05:16:21.104178 | controller |
2025-09-30 05:16:21.104203 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-30 05:16:21.104214 | controller | Tuesday 30 September 2025  05:16:21 +0000 (0:00:00.036)       0:00:14.289 *****
2025-09-30 05:16:21.140381 | controller | skipping: [instance]
2025-09-30 05:16:21.712294 | controller |
2025-09-30 05:16:21.712325 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-30 05:16:21.712333 | controller | Tuesday 30 September 2025  05:16:21 +0000 (0:00:00.036)       0:00:14.325 *****
2025-09-30 05:16:21.712343 | controller | changed: [instance]
2025-09-30 05:16:22.155349 | controller |
2025-09-30 05:16:22.155379 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-30 05:16:22.155387 | controller | Tuesday 30 September 2025  05:16:21 +0000 (0:00:00.571)       0:00:14.897 *****
2025-09-30 05:16:22.155397 | controller | changed: [instance]
2025-09-30 05:16:22.185511 | controller |
2025-09-30 05:16:22.185551 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-30 05:16:22.185560 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.443)       0:00:15.340 *****
2025-09-30 05:16:22.185569 | controller | skipping: [instance]
2025-09-30 05:16:22.214745 | controller |
2025-09-30 05:16:22.214777 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-30 05:16:22.214786 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.030)       0:00:15.370 *****
2025-09-30 05:16:22.214798 | controller | skipping: [instance]
2025-09-30 05:16:22.243683 | controller |
2025-09-30 05:16:22.243718 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-30 05:16:22.243728 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.029)       0:00:15.400 *****
2025-09-30 05:16:22.243744 | controller | skipping: [instance]
2025-09-30 05:16:22.281526 | controller |
2025-09-30 05:16:22.281569 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-30 05:16:22.281577 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.028)       0:00:15.428 *****
2025-09-30 05:16:22.281587 | controller | ok: [instance]
2025-09-30 05:16:22.308981 | controller |
2025-09-30 05:16:22.309006 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-30 05:16:22.309014 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.038)       0:00:15.467 *****
2025-09-30 05:16:22.309024 | controller | skipping: [instance]
2025-09-30 05:16:22.336443 | controller |
2025-09-30 05:16:22.336469 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-30 05:16:22.336476 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.027)       0:00:15.494 *****
2025-09-30 05:16:22.336495 | controller | skipping: [instance]
2025-09-30 05:16:22.363102 | controller |
2025-09-30 05:16:22.363124 | controller | TASK [Download the RPM] ********************************************************
2025-09-30 05:16:22.363131 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.027)       0:00:15.521 *****
2025-09-30 05:16:22.363140 | controller | skipping: [instance]
2025-09-30 05:16:22.389585 | controller |
2025-09-30 05:16:22.389608 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-30 05:16:22.389616 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.026)       0:00:15.548 *****
2025-09-30 05:16:22.389625 | controller | skipping: [instance]
2025-09-30 05:16:22.416086 | controller |
2025-09-30 05:16:22.416109 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-30 05:16:22.416116 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.026)       0:00:15.575 *****
2025-09-30 05:16:22.416125 | controller | skipping: [instance]
2025-09-30 05:16:22.444727 | controller |
2025-09-30 05:16:22.444758 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-30 05:16:22.444768 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.026)       0:00:15.601 *****
2025-09-30 05:16:22.444780 | controller | skipping: [instance]
2025-09-30 05:16:22.444806 | controller |
2025-09-30 05:16:22.444816 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-30 05:16:22.444883 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.028)       0:00:15.630 *****
2025-09-30 05:16:22.473271 | controller | skipping: [instance]
2025-09-30 05:16:22.656567 | controller |
2025-09-30 05:16:22.656597 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-30 05:16:22.656605 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.028)       0:00:15.658 *****
2025-09-30 05:16:22.656614 | controller | ok: [instance]
2025-09-30 05:16:22.868359 | controller |
2025-09-30 05:16:22.868388 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-30 05:16:22.868397 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.183)       0:00:15.841 *****
2025-09-30 05:16:22.868407 | controller | changed: [instance]
2025-09-30 05:16:23.090630 | controller |
2025-09-30 05:16:23.090673 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-30 05:16:23.090681 | controller | Tuesday 30 September 2025  05:16:22 +0000 (0:00:00.211)       0:00:16.053 *****
2025-09-30 05:16:23.090691 | controller | changed: [instance]
2025-09-30 05:16:23.110949 | controller |
2025-09-30 05:16:23.110973 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-30 05:16:23.110980 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.222)       0:00:16.275 *****
2025-09-30 05:16:23.110990 | controller | skipping: [instance]
2025-09-30 05:16:23.133209 | controller |
2025-09-30 05:16:23.133233 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-30 05:16:23.133240 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.020)       0:00:16.296 *****
2025-09-30 05:16:23.133249 | controller | skipping: [instance]
2025-09-30 05:16:23.154240 | controller |
2025-09-30 05:16:23.154267 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-30 05:16:23.154275 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.022)       0:00:16.318 *****
2025-09-30 05:16:23.154284 | controller | skipping: [instance]
2025-09-30 05:16:23.176753 | controller |
2025-09-30 05:16:23.176788 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-30 05:16:23.176798 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.021)       0:00:16.339 *****
2025-09-30 05:16:23.176810 | controller | skipping: [instance]
2025-09-30 05:16:23.176838 | controller |
2025-09-30 05:16:23.176848 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-30 05:16:23.176979 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.022)       0:00:16.362 *****
2025-09-30 05:16:23.198607 | controller | skipping: [instance]
2025-09-30 05:16:23.220089 | controller |
2025-09-30 05:16:23.220107 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-30 05:16:23.220122 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.021)       0:00:16.384 *****
2025-09-30 05:16:23.220131 | controller | skipping: [instance]
2025-09-30 05:16:23.237000 | controller |
2025-09-30 05:16:23.237018 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-30 05:16:23.237025 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.021)       0:00:16.405 *****
2025-09-30 05:16:23.237034 | controller | skipping: [instance]
2025-09-30 05:16:23.266154 | controller |
2025-09-30 05:16:23.266185 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-30 05:16:23.266193 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.016)       0:00:16.422 *****
2025-09-30 05:16:23.266201 | controller | skipping: [instance]
2025-09-30 05:16:23.282743 | controller |
2025-09-30 05:16:23.282779 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-30 05:16:23.282789 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.029)       0:00:16.451 *****
2025-09-30 05:16:23.282800 | controller | skipping: [instance]
2025-09-30 05:16:23.282828 | controller |
2025-09-30 05:16:23.282839 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-30 05:16:23.282975 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.016)       0:00:16.468 *****
2025-09-30 05:16:23.303743 | controller | skipping: [instance]
2025-09-30 05:16:23.303831 | controller |
2025-09-30 05:16:23.303855 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-09-30 05:16:23.303866 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.020)       0:00:16.489 *****
2025-09-30 05:16:23.332739 | controller | skipping: [instance]
2025-09-30 05:16:23.333580 | controller |
2025-09-30 05:16:23.334135 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-09-30 05:16:23.363694 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.029)       0:00:16.518 *****
2025-09-30 05:16:23.363716 | controller | skipping: [instance]
2025-09-30 05:16:23.363873 | controller |
2025-09-30 05:16:23.363885 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-09-30 05:16:23.363894 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.030)       0:00:16.549 *****
2025-09-30 05:16:23.394699 | controller | skipping: [instance]
2025-09-30 05:16:23.423003 | controller |
2025-09-30 05:16:23.423022 | controller | TASK [test_deps : Install additional packages] *********************************
2025-09-30 05:16:23.423029 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.030)       0:00:16.580 *****
2025-09-30 05:16:23.423038 | controller | skipping: [instance]
2025-09-30 05:17:11.451974 | controller |
2025-09-30 05:17:11.452006 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-09-30 05:17:11.452013 | controller | Tuesday 30 September 2025  05:16:23 +0000 (0:00:00.028)       0:00:16.608 *****
2025-09-30 05:17:11.452023 | controller | ok: [instance]
2025-09-30 05:17:12.635021 | controller |
2025-09-30 05:17:12.635051 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-09-30 05:17:12.635059 | controller | Tuesday 30 September 2025  05:17:11 +0000 (0:00:48.028)       0:01:04.637 *****
2025-09-30 05:17:12.635069 | controller | ok: [instance]
2025-09-30 05:17:12.667755 | controller |
2025-09-30 05:17:12.667795 | controller | TASK [test_deps : Install extra packages] **************************************
2025-09-30 05:17:12.667805 | controller | Tuesday 30 September 2025  05:17:12 +0000 (0:00:01.183)       0:01:05.820 *****
2025-09-30 05:17:12.667818 | controller | skipping: [instance]
2025-09-30 05:17:12.710743 | controller |
2025-09-30 05:17:12.710768 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-09-30 05:17:12.710777 | controller | Tuesday 30 September 2025  05:17:12 +0000 (0:00:00.032)       0:01:05.853 *****
2025-09-30 05:17:12.710789 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-09-30 05:17:12.710878 | controller |
2025-09-30 05:17:12.710897 | controller | TASK [ci_setup : List packages to install] *************************************
2025-09-30 05:17:12.710909 | controller | Tuesday 30 September 2025  05:17:12 +0000 (0:00:00.043)       0:01:05.896 *****
2025-09-30 05:17:12.731735 | controller | ok: [instance] =>
2025-09-30 05:17:12.732273 | controller |   cifmw_ci_setup_packages:
2025-09-30 05:17:12.732289 | controller |   - bash-completion
2025-09-30 05:17:12.732296 | controller |   - ca-certificates
2025-09-30 05:17:12.732301 | controller |   - git-core
2025-09-30 05:17:12.732307 | controller |   - make
2025-09-30 05:17:12.732315 | controller |   - tar
2025-09-30 05:17:16.769733 | controller |   - tmux
2025-09-30 05:17:16.769764 | controller |   - python3-pip
2025-09-30 05:17:16.769772 | controller |
2025-09-30 05:17:16.769778 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-09-30 05:17:16.769784 | controller | Tuesday 30 September 2025  05:17:12 +0000 (0:00:00.020)       0:01:05.917 *****
2025-09-30 05:17:16.769794 | controller | changed: [instance]
2025-09-30 05:17:16.944308 | controller |
2025-09-30 05:17:16.944338 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-09-30 05:17:16.944351 | controller | Tuesday 30 September 2025  05:17:16 +0000 (0:00:04.037)       0:01:09.955 *****
2025-09-30 05:17:16.944362 | controller | ok: [instance]
2025-09-30 05:17:17.139762 | controller |
2025-09-30 05:17:17.139795 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-09-30 05:17:17.139803 | controller | Tuesday 30 September 2025  05:17:16 +0000 (0:00:00.174)       0:01:10.129 *****
2025-09-30 05:17:17.139813 | controller | changed: [instance]
2025-09-30 05:17:23.140736 | controller |
2025-09-30 05:17:23.140768 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-09-30 05:17:23.140776 | controller | Tuesday 30 September 2025  05:17:17 +0000 (0:00:00.195)       0:01:10.325 *****
2025-09-30 05:17:23.140787 | controller | changed: [instance]
2025-09-30 05:17:23.176094 | controller |
2025-09-30 05:17:23.176116 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-09-30 05:17:23.176124 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:06.000)       0:01:16.326 *****
2025-09-30 05:17:23.176135 | controller | ok: [instance]
2025-09-30 05:17:23.436617 | controller |
2025-09-30 05:17:23.436664 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-09-30 05:17:23.436675 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.035)       0:01:16.361 *****
2025-09-30 05:17:23.436685 | controller | changed: [instance]
2025-09-30 05:17:23.711111 | controller |
2025-09-30 05:17:23.711141 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-09-30 05:17:23.711149 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.260)       0:01:16.621 *****
2025-09-30 05:17:23.711159 | controller | changed: [instance]
2025-09-30 05:17:23.727664 | controller |
2025-09-30 05:17:23.727687 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-09-30 05:17:23.727695 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.274)       0:01:16.896 *****
2025-09-30 05:17:23.727705 | controller | skipping: [instance]
2025-09-30 05:17:23.744786 | controller |
2025-09-30 05:17:23.744810 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-09-30 05:17:23.744819 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.016)       0:01:16.913 *****
2025-09-30 05:17:23.744830 | controller | skipping: [instance]
2025-09-30 05:17:23.762435 | controller |
2025-09-30 05:17:23.762455 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-09-30 05:17:23.762462 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.016)       0:01:16.930 *****
2025-09-30 05:17:23.762471 | controller | skipping: [instance]
2025-09-30 05:17:23.778882 | controller |
2025-09-30 05:17:23.778901 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-09-30 05:17:23.778909 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.017)       0:01:16.948 *****
2025-09-30 05:17:23.778918 | controller | skipping: [instance]
2025-09-30 05:17:23.795133 | controller |
2025-09-30 05:17:23.795152 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-09-30 05:17:23.795159 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.016)       0:01:16.964 *****
2025-09-30 05:17:23.795168 | controller | skipping: [instance]
2025-09-30 05:17:23.816352 | controller |
2025-09-30 05:17:23.816370 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-09-30 05:17:23.816384 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.016)       0:01:16.980 *****
2025-09-30 05:17:23.816393 | controller | skipping: [instance]
2025-09-30 05:17:24.814711 | controller |
2025-09-30 05:17:24.814754 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-09-30 05:17:24.814762 | controller | Tuesday 30 September 2025  05:17:23 +0000 (0:00:00.021)       0:01:17.002 *****
2025-09-30 05:17:24.814772 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-09-30 05:17:24.814799 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-09-30 05:17:24.815391 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-09-30 05:17:25.347593 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-09-30 05:17:25.347622 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-09-30 05:17:25.347629 | controller |
2025-09-30 05:17:25.347635 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-09-30 05:17:25.347658 | controller | Tuesday 30 September 2025  05:17:24 +0000 (0:00:00.998)       0:01:18.000 *****
2025-09-30 05:17:25.347671 | controller | ok: [instance] => (item=tmp)
2025-09-30 05:17:26.521393 | controller | ok: [instance] => (item=artifacts/repositories)
2025-09-30 05:17:26.521425 | controller | ok: [instance] => (item=venv/repo_setup)
2025-09-30 05:17:26.521432 | controller |
2025-09-30 05:17:26.521439 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-09-30 05:17:26.521445 | controller | Tuesday 30 September 2025  05:17:25 +0000 (0:00:00.532)       0:01:18.532 *****
2025-09-30 05:17:26.521454 | controller | ok: [instance]
2025-09-30 05:17:26.986716 | controller |
2025-09-30 05:17:26.986768 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-09-30 05:17:26.986784 | controller | Tuesday 30 September 2025  05:17:26 +0000 (0:00:01.173)       0:01:19.706 *****
2025-09-30 05:17:26.986797 | controller | ok: [instance]
2025-09-30 05:17:28.150579 | controller |
2025-09-30 05:17:28.150608 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-09-30 05:17:28.150616 | controller | Tuesday 30 September 2025  05:17:26 +0000 (0:00:00.465)       0:01:20.171 *****
2025-09-30 05:17:28.150626 | controller | ok: [instance]
2025-09-30 05:17:28.336467 | controller |
2025-09-30 05:17:28.336497 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-09-30 05:17:28.336506 | controller | Tuesday 30 September 2025  05:17:28 +0000 (0:00:01.164)       0:01:21.336 *****
2025-09-30 05:17:28.336515 | controller | ok: [instance]
2025-09-30 05:17:28.357202 | controller |
2025-09-30 05:17:28.357239 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-09-30 05:17:28.357246 | controller | Tuesday 30 September 2025  05:17:28 +0000 (0:00:00.185)       0:01:21.521 *****
2025-09-30 05:17:28.357256 | controller | skipping: [instance]
2025-09-30 05:17:29.177732 | controller |
2025-09-30 05:17:29.177773 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-09-30 05:17:29.177781 | controller | Tuesday 30 September 2025  05:17:28 +0000 (0:00:00.020)       0:01:21.542 *****
2025-09-30 05:17:29.177791 | controller | changed: [instance]
2025-09-30 05:17:29.177988 | controller |
2025-09-30 05:17:29.178002 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-09-30 05:17:29.213205 | controller | Tuesday 30 September 2025  05:17:29 +0000 (0:00:00.820)       0:01:22.363 *****
2025-09-30 05:17:29.213232 | controller | skipping: [instance]
2025-09-30 05:17:29.248320 | controller |
2025-09-30 05:17:29.248342 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-09-30 05:17:29.248350 | controller | Tuesday 30 September 2025  05:17:29 +0000 (0:00:00.035)       0:01:22.398 *****
2025-09-30 05:17:29.248359 | controller | skipping: [instance]
2025-09-30 05:17:29.283721 | controller |
2025-09-30 05:17:29.283757 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-09-30 05:17:29.283765 | controller | Tuesday 30 September 2025  05:17:29 +0000 (0:00:00.035)       0:01:22.433 *****
2025-09-30 05:17:29.283774 | controller | skipping: [instance]
2025-09-30 05:17:29.283870 | controller |
2025-09-30 05:17:29.283880 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-09-30 05:17:29.283889 | controller | Tuesday 30 September 2025  05:17:29 +0000 (0:00:00.035)       0:01:22.469 *****
2025-09-30 05:17:29.834849 | controller | changed: [instance]
2025-09-30 05:17:30.182612 | controller |
2025-09-30 05:17:30.182665 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-09-30 05:17:30.182678 | controller | Tuesday 30 September 2025  05:17:29 +0000 (0:00:00.550)       0:01:23.020 *****
2025-09-30 05:17:30.182688 | controller | changed: [instance]
2025-09-30 05:17:30.209508 | controller |
2025-09-30 05:17:30.209532 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-09-30 05:17:30.209540 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.347)       0:01:23.368 *****
2025-09-30 05:17:30.209550 | controller | skipping: [instance]
2025-09-30 05:17:30.236680 | controller |
2025-09-30 05:17:30.236703 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-09-30 05:17:30.236711 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.026)       0:01:23.394 *****
2025-09-30 05:17:30.236722 | controller | skipping: [instance]
2025-09-30 05:17:30.263782 | controller |
2025-09-30 05:17:30.263821 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-09-30 05:17:30.263834 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.027)       0:01:23.422 *****
2025-09-30 05:17:30.263844 | controller | skipping: [instance]
2025-09-30 05:17:30.263870 | controller |
2025-09-30 05:17:30.263879 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-09-30 05:17:30.263940 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.027)       0:01:23.449 *****
2025-09-30 05:17:30.300036 | controller | ok: [instance]
2025-09-30 05:17:30.325295 | controller |
2025-09-30 05:17:30.325317 | controller | TASK [repo_setup : Create download directory] **********************************
2025-09-30 05:17:30.325325 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.036)       0:01:23.485 *****
2025-09-30 05:17:30.325334 | controller | skipping: [instance]
2025-09-30 05:17:30.350653 | controller |
2025-09-30 05:17:30.350678 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-09-30 05:17:30.350686 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.025)       0:01:23.510 *****
2025-09-30 05:17:30.350699 | controller | skipping: [instance]
2025-09-30 05:17:30.376713 | controller |
2025-09-30 05:17:30.376760 | controller | TASK [Download the RPM] ********************************************************
2025-09-30 05:17:30.376769 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.025)       0:01:23.536 *****
2025-09-30 05:17:30.376781 | controller | skipping: [instance]
2025-09-30 05:17:30.402260 | controller |
2025-09-30 05:17:30.402290 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-09-30 05:17:30.402299 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.025)       0:01:23.562 *****
2025-09-30 05:17:30.402311 | controller | skipping: [instance]
2025-09-30 05:17:30.427567 | controller |
2025-09-30 05:17:30.427591 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-09-30 05:17:30.427598 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.025)       0:01:23.587 *****
2025-09-30 05:17:30.427607 | controller | skipping: [instance]
2025-09-30 05:17:30.454710 | controller |
2025-09-30 05:17:30.454731 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-09-30 05:17:30.454753 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.025)       0:01:23.613 *****
2025-09-30 05:17:30.454763 | controller | skipping: [instance]
2025-09-30 05:17:30.454940 | controller |
2025-09-30 05:17:30.454958 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-09-30 05:17:30.480720 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.027)       0:01:23.640 *****
2025-09-30 05:17:30.480784 | controller | skipping: [instance]
2025-09-30 05:17:30.480899 | controller |
2025-09-30 05:17:30.480909 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-09-30 05:17:30.480917 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.025)       0:01:23.666 *****
2025-09-30 05:17:30.661413 | controller | ok: [instance]
2025-09-30 05:17:30.865219 | controller |
2025-09-30 05:17:30.865248 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-09-30 05:17:30.865256 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.180)       0:01:23.846 *****
2025-09-30 05:17:30.865266 | controller | changed: [instance]
2025-09-30 05:17:31.075717 | controller |
2025-09-30 05:17:31.075759 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-09-30 05:17:31.075767 | controller | Tuesday 30 September 2025  05:17:30 +0000 (0:00:00.203)       0:01:24.050 *****
2025-09-30 05:17:31.075777 | controller | changed: [instance]
2025-09-30 05:17:31.075799 | controller |
2025-09-30 05:17:31.075806 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-09-30 05:17:31.075813 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.210)       0:01:24.261 *****
2025-09-30 05:17:31.096661 | controller | skipping: [instance]
2025-09-30 05:17:31.129896 | controller |
2025-09-30 05:17:31.129921 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-09-30 05:17:31.129929 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.021)       0:01:24.282 *****
2025-09-30 05:17:31.129938 | controller | skipping: [instance]
2025-09-30 05:17:31.163288 | controller |
2025-09-30 05:17:31.163307 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-09-30 05:17:31.163314 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.033)       0:01:24.315 *****
2025-09-30 05:17:31.163323 | controller | skipping: [instance]
2025-09-30 05:17:31.196763 | controller |
2025-09-30 05:17:31.196807 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-09-30 05:17:31.196817 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.033)       0:01:24.348 *****
2025-09-30 05:17:31.196830 | controller | skipping: [instance]
2025-09-30 05:17:31.196863 | controller |
2025-09-30 05:17:31.196941 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-09-30 05:17:31.197079 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.033)       0:01:24.382 *****
2025-09-30 05:17:31.229550 | controller | skipping: [instance]
2025-09-30 05:17:31.263166 | controller |
2025-09-30 05:17:31.263189 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-09-30 05:17:31.263197 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.032)       0:01:24.415 *****
2025-09-30 05:17:31.263206 | controller | skipping: [instance]
2025-09-30 05:17:31.563961 | controller |
2025-09-30 05:17:31.563996 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-09-30 05:17:31.564004 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.033)       0:01:24.448 *****
2025-09-30 05:17:31.564014 | controller | ok: [instance]
2025-09-30 05:17:33.099441 | controller |
2025-09-30 05:17:33.099472 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-09-30 05:17:33.099480 | controller | Tuesday 30 September 2025  05:17:31 +0000 (0:00:00.300)       0:01:24.749 *****
2025-09-30 05:17:33.099490 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-09-30 05:17:33.532756 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-09-30 05:17:33.532788 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-09-30 05:17:33.532796 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-09-30 05:17:33.532802 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-09-30 05:17:33.532808 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-09-30 05:17:33.532813 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-09-30 05:17:33.532818 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-09-30 05:17:33.532824 | controller |
2025-09-30 05:17:33.532835 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-09-30 05:17:33.532841 | controller | Tuesday 30 September 2025  05:17:33 +0000 (0:00:01.534)       0:01:26.284 *****
2025-09-30 05:17:33.532856 | controller | changed: [instance]
2025-09-30 05:17:33.533175 | controller |
2025-09-30 05:17:33.772728 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-09-30 05:17:33.772769 | controller | Tuesday 30 September 2025  05:17:33 +0000 (0:00:00.434)       0:01:26.718 *****
2025-09-30 05:17:33.772782 | controller | changed: [instance]
2025-09-30 05:17:33.772806 | controller |
2025-09-30 05:17:33.772813 | controller | PLAY RECAP *********************************************************************
2025-09-30 05:17:33.772819 | controller | instance                   : ok=45   changed=23   unreachable=0    failed=0    skipped=56   rescued=0    ignored=0
2025-09-30 05:17:33.772824 | controller |
2025-09-30 05:17:33.772830 | controller | Tuesday 30 September 2025  05:17:33 +0000 (0:00:00.239)       0:01:26.957 *****
2025-09-30 05:17:33.772835 | controller | ===============================================================================
2025-09-30 05:17:33.772841 | controller | test_deps : Install selinux python libs -------------------------------- 48.03s
2025-09-30 05:17:33.772846 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.98s
2025-09-30 05:17:33.772853 | controller | ci_setup : Install openshift client ------------------------------------- 6.00s
2025-09-30 05:17:33.774463 | controller | ci_setup : Install needed packages -------------------------------------- 4.04s
2025-09-30 05:17:33.842722 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.54s
2025-09-30 05:17:33.842765 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2025-09-30 05:17:33.842773 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.17s
2025-09-30 05:17:33.842778 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.17s
2025-09-30 05:17:33.842784 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.16s
2025-09-30 05:17:33.842789 | controller | ci_setup : Manage directories ------------------------------------------- 1.00s
2025-09-30 05:17:33.842794 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-09-30 05:17:33.842799 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.81s
2025-09-30 05:17:33.842804 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-09-30 05:17:33.842809 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-09-30 05:17:33.842815 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-09-30 05:17:33.842820 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
2025-09-30 05:17:33.842825 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-09-30 05:17:33.842830 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-09-30 05:17:33.842836 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-09-30 05:17:33.842841 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-09-30 05:17:33.842852 | controller | INFO     Running default > converge
2025-09-30 05:17:34.265892 | controller |
2025-09-30 05:17:35.044358 | controller | PLAY [Converge] ****************************************************************
2025-09-30 05:17:35.044392 | controller |
2025-09-30 05:17:35.044399 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 05:17:35.044405 | controller | Tuesday 30 September 2025  05:17:34 +0000 (0:00:00.014)       0:00:00.014 *****
2025-09-30 05:17:35.044415 | controller | ok: [instance]
2025-09-30 05:17:35.514961 | controller |
2025-09-30 05:17:35.514992 | controller | TASK [pkg_build : Ensure directories exist] ************************************
2025-09-30 05:17:35.515005 | controller | Tuesday 30 September 2025  05:17:35 +0000 (0:00:00.779)       0:00:00.793 *****
2025-09-30 05:17:35.515016 | controller | changed: [instance] => (item=volumes/packages/gating_repo)
2025-09-30 05:17:35.723166 | controller | ok: [instance] => (item=artifacts)
2025-09-30 05:17:35.723197 | controller |
2025-09-30 05:17:35.723205 | controller | TASK [pkg_build : Ensure per-package build directories exist] ******************
2025-09-30 05:17:35.723218 | controller | Tuesday 30 September 2025  05:17:35 +0000 (0:00:00.470)       0:00:01.263 *****
2025-09-30 05:17:35.723228 | controller | changed: [instance] => (item=neutron-tempest-plugin)
2025-09-30 05:17:35.929969 | controller |
2025-09-30 05:17:35.929999 | controller | TASK [pkg_build : Ensure per-package log directories exist] ********************
2025-09-30 05:17:35.930008 | controller | Tuesday 30 September 2025  05:17:35 +0000 (0:00:00.208)       0:00:01.472 *****
2025-09-30 05:17:35.930018 | controller | changed: [instance] => (item=neutron-tempest-plugin)
2025-09-30 05:17:36.478800 | controller |
2025-09-30 05:17:36.478833 | controller | TASK [pkg_build : Generate Containerfile.buildpkg] *****************************
2025-09-30 05:17:36.478841 | controller | Tuesday 30 September 2025  05:17:35 +0000 (0:00:00.206)       0:00:01.678 *****
2025-09-30 05:17:36.478851 | controller | changed: [instance]
2025-09-30 05:18:39.367385 | controller |
2025-09-30 05:18:39.367417 | controller | TASK [pkg_build : Build container image] ***************************************
2025-09-30 05:18:39.367426 | controller | Tuesday 30 September 2025  05:17:36 +0000 (0:00:00.548)       0:00:02.227 *****
2025-09-30 05:18:39.367436 | controller | changed: [instance]
2025-09-30 05:18:39.763993 | controller |
2025-09-30 05:18:39.764023 | controller | TASK [pkg_build : Generate playbooks for package build] ************************
2025-09-30 05:18:39.764032 | controller | Tuesday 30 September 2025  05:18:39 +0000 (0:01:02.888)       0:01:05.116 *****
2025-09-30 05:18:39.764041 | controller | changed: [instance]
2025-09-30 05:18:41.385733 | controller |
2025-09-30 05:18:41.385775 | controller | TASK [Get repository to build from] ********************************************
2025-09-30 05:18:41.385783 | controller | Tuesday 30 September 2025  05:18:39 +0000 (0:00:00.396)       0:01:05.512 *****
2025-09-30 05:18:41.385793 | controller | changed: [instance]
2025-09-30 05:18:41.385895 | controller |
2025-09-30 05:18:41.385909 | controller | TASK [Build package] ***********************************************************
2025-09-30 05:18:41.385918 | controller | Tuesday 30 September 2025  05:18:41 +0000 (0:00:01.621)       0:01:07.134 *****
2025-09-30 05:18:41.409119 | controller |
2025-09-30 05:18:41.442617 | controller | TASK [pkg_build : Generate volume list] ****************************************
2025-09-30 05:18:41.442667 | controller | Tuesday 30 September 2025  05:18:41 +0000 (0:00:00.023)       0:01:07.158 *****
2025-09-30 05:18:41.442691 | controller | ok: [instance]
2025-09-30 05:25:50.722775 | controller |
2025-09-30 05:25:50.722809 | controller | TASK [pkg_build : Build package using container] *******************************
2025-09-30 05:25:50.722817 | controller | Tuesday 30 September 2025  05:18:41 +0000 (0:00:00.033)       0:01:07.191 *****
2025-09-30 05:25:50.722828 | controller | changed: [instance] => (item=neutron-tempest-plugin)
2025-09-30 05:25:50.722852 | controller |
2025-09-30 05:25:50.722859 | controller | PLAY RECAP *********************************************************************
2025-09-30 05:25:50.722865 | controller | instance                   : ok=10   changed=8    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-09-30 05:25:50.722870 | controller |
2025-09-30 05:25:50.722876 | controller | Tuesday 30 September 2025  05:25:50 +0000 (0:07:09.279)       0:08:16.471 *****
2025-09-30 05:25:50.722881 | controller | ===============================================================================
2025-09-30 05:25:50.722887 | controller | pkg_build : Build package using container ----------------------------- 429.28s
2025-09-30 05:25:50.722892 | controller | pkg_build : Build container image -------------------------------------- 62.89s
2025-09-30 05:25:50.722897 | controller | Get repository to build from -------------------------------------------- 1.62s
2025-09-30 05:25:50.722904 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-09-30 05:25:50.722925 | controller | pkg_build : Generate Containerfile.buildpkg ----------------------------- 0.55s
2025-09-30 05:25:50.723485 | controller | pkg_build : Ensure directories exist ------------------------------------ 0.47s
2025-09-30 05:25:50.768074 | controller | pkg_build : Generate playbooks for package build ------------------------ 0.40s
2025-09-30 05:25:50.768107 | controller | pkg_build : Ensure per-package build directories exist ------------------ 0.21s
2025-09-30 05:25:50.768114 | controller | pkg_build : Ensure per-package log directories exist -------------------- 0.21s
2025-09-30 05:25:50.768138 | controller | pkg_build : Generate volume list ---------------------------------------- 0.03s
2025-09-30 05:25:50.768144 | controller | Build package ----------------------------------------------------------- 0.02s
2025-09-30 05:25:50.768155 | controller | INFO     Running default > cleanup
2025-09-30 05:25:50.768616 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-30 05:25:50.769184 | controller | INFO     Writing /tmp/report.html report.
2025-09-30 05:25:51.014789 | controller | changed
2025-09-30 05:25:51.035732 | 
2025-09-30 05:25:51.035805 | PLAY RECAP
2025-09-30 05:25:51.035847 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 05:25:51.035870 | 
2025-09-30 05:25:51.103311 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 05:25:51.104143 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 05:25:51.636125 | 
2025-09-30 05:25:51.636264 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-30 05:25:51.657054 | 
2025-09-30 05:25:51.657133 | TASK [Filter out host if needed]
2025-09-30 05:25:51.665611 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-30 05:25:51.670021 | 
2025-09-30 05:25:51.670087 | TASK [Ensure file is present]
2025-09-30 05:25:51.992437 | controller | ok
2025-09-30 05:25:51.999206 | 
2025-09-30 05:25:51.999275 | TASK [Manage molecule report file]
2025-09-30 05:25:52.524992 | controller | changed
2025-09-30 05:25:52.530003 | 
2025-09-30 05:25:52.530070 | TASK [Check if we get ci-framework-data basedir]
2025-09-30 05:25:52.710337 | controller | ok
2025-09-30 05:25:52.715308 | 
2025-09-30 05:25:52.715371 | TASK [Create ci-framework-data log directory for zuul]
2025-09-30 05:25:53.017004 | controller | changed
2025-09-30 05:25:53.022640 | 
2025-09-30 05:25:53.022706 | TASK [Copy ci-framework interesting files]
2025-09-30 05:25:53.216260 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-30 05:25:53.549215 | controller | changed
2025-09-30 05:25:53.554378 | 
2025-09-30 05:25:53.554444 | TASK [Get SELinux listing]
2025-09-30 05:25:54.080323 | controller | changed
2025-09-30 05:25:54.086736 | 
2025-09-30 05:25:54.086820 | TASK [Generate log index]
2025-09-30 05:25:54.713576 | controller | changed
2025-09-30 05:25:54.718674 | 
2025-09-30 05:25:54.718745 | TASK [Get some env related data]
2025-09-30 05:25:55.259665 | controller | /home/zuul/.local/bin/ansible
2025-09-30 05:25:56.244694 | controller | changed
2025-09-30 05:25:56.250195 | 
2025-09-30 05:25:56.250272 | TASK [Generate list of logs to collect in home directory]
2025-09-30 05:25:56.532652 | controller | ok: All paths examined
2025-09-30 05:25:56.537611 | 
2025-09-30 05:25:56.537677 | LOOP [Copy logs from home directory]
2025-09-30 05:25:56.833194 | controller | changed:
2025-09-30 05:25:56.833306 | controller | {
2025-09-30 05:25:56.833333 | controller |   "atime": 1759209329.8515303,
2025-09-30 05:25:56.833352 | controller |   "ctime": 1759209345.5347264,
2025-09-30 05:25:56.833369 | controller |   "dev": 64513,
2025-09-30 05:25:56.833385 | controller |   "gid": 1000,
2025-09-30 05:25:56.833400 | controller |   "gr_name": "zuul",
2025-09-30 05:25:56.833416 | controller |   "inode": 4330882,
2025-09-30 05:25:56.833430 | controller |   "isblk": false,
2025-09-30 05:25:56.833445 | controller |   "ischr": false,
2025-09-30 05:25:56.833460 | controller |   "isdir": false,
2025-09-30 05:25:56.833474 | controller |   "isfifo": false,
2025-09-30 05:25:56.833489 | controller |   "isgid": false,
2025-09-30 05:25:56.833504 | controller |   "islnk": false,
2025-09-30 05:25:56.833517 | controller |   "isreg": true,
2025-09-30 05:25:56.833531 | controller |   "issock": false,
2025-09-30 05:25:56.833543 | controller |   "isuid": false,
2025-09-30 05:25:56.833556 | controller |   "mode": "0644",
2025-09-30 05:25:56.833569 | controller |   "mtime": 1759209345.5347264,
2025-09-30 05:25:56.833582 | controller |   "nlink": 1,
2025-09-30 05:25:56.833596 | controller |   "path": "/home/zuul/ansible.log",
2025-09-30 05:25:56.833612 | controller |   "pw_name": "zuul",
2025-09-30 05:25:56.833626 | controller |   "rgrp": true,
2025-09-30 05:25:56.833641 | controller |   "roth": true,
2025-09-30 05:25:56.833655 | controller |   "rusr": true,
2025-09-30 05:25:56.833668 | controller |   "size": 6749,
2025-09-30 05:25:56.833682 | controller |   "uid": 1000,
2025-09-30 05:25:56.833695 | controller |   "wgrp": false,
2025-09-30 05:25:56.833709 | controller |   "woth": false,
2025-09-30 05:25:56.833726 | controller |   "wusr": true,
2025-09-30 05:25:56.833741 | controller |   "xgrp": false,
2025-09-30 05:25:56.833756 | controller |   "xoth": false,
2025-09-30 05:25:56.833770 | controller |   "xusr": false
2025-09-30 05:25:56.833787 | controller | }
2025-09-30 05:25:56.848115 | 
2025-09-30 05:25:56.848248 | TASK [Copy crio stats log file]
2025-09-30 05:25:56.861830 | controller | skipping: Conditional result was False
2025-09-30 05:25:56.867239 | 
2025-09-30 05:25:56.867303 | TASK [Get SELinux related data]
2025-09-30 05:25:57.069770 | controller | <no matches>
2025-09-30 05:25:57.394017 | controller | ERROR
2025-09-30 05:25:57.394163 | controller | {
2025-09-30 05:25:57.394194 | controller |   "delta": "0:00:00.007647",
2025-09-30 05:25:57.394216 | controller |   "end": "2025-09-30 05:25:57.070729",
2025-09-30 05:25:57.394234 | controller |   "msg": "non-zero return code",
2025-09-30 05:25:57.394251 | controller |   "rc": 1,
2025-09-30 05:25:57.394266 | controller |   "start": "2025-09-30 05:25:57.063082"
2025-09-30 05:25:57.394281 | controller | }
2025-09-30 05:25:57.394302 | controller | ERROR: Ignoring Errors
2025-09-30 05:25:57.399395 | 
2025-09-30 05:25:57.399456 | TASK [Create system configuration directory]
2025-09-30 05:25:57.579438 | controller | changed
2025-09-30 05:25:57.584240 | 
2025-09-30 05:25:57.584305 | TASK [Get some of the system configurations]
2025-09-30 05:25:58.107891 | controller | changed
2025-09-30 05:25:58.113217 | 
2025-09-30 05:25:58.113279 | TASK [Copy generated documentation if available]
2025-09-30 05:25:58.126598 | controller | skipping: Conditional result was False
2025-09-30 05:25:58.132193 | 
2025-09-30 05:25:58.132253 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-30 05:25:58.145428 | controller | skipping: Conditional result was False
2025-09-30 05:25:58.151350 | 
2025-09-30 05:25:58.151414 | TASK [Compress logs bigger than 2MB]
2025-09-30 05:25:58.677425 | controller | changed
2025-09-30 05:25:58.682887 | 
2025-09-30 05:25:58.682989 | TASK [Copy files from workspace on node]
2025-09-30 05:25:58.701393 | controller | ok
2025-09-30 05:25:58.724031 | 
2025-09-30 05:25:58.724101 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 05:25:58.737498 | controller | skipping: Conditional result was False
2025-09-30 05:25:58.743554 | 
2025-09-30 05:25:58.743618 | TASK [fetch-output : Set log path for single node]
2025-09-30 05:25:58.771977 | controller | ok
2025-09-30 05:25:58.777846 | 
2025-09-30 05:25:58.777955 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 05:25:58.971311 | controller -> localhost | ok: "/var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/logs"
2025-09-30 05:25:58.971548 | controller -> localhost | changed: All items complete
2025-09-30 05:25:58.971579 | 
2025-09-30 05:25:59.153678 | controller -> localhost | changed: "/var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/artifacts"
2025-09-30 05:25:59.329880 | controller -> localhost | changed: "/var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/docs"
2025-09-30 05:25:59.346270 | 
2025-09-30 05:25:59.346384 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 05:25:59.942189 | controller | changed:
2025-09-30 05:25:59.942369 | controller | .d..t...... ./
2025-09-30 05:25:59.942400 | controller | >f+++++++++ README.html
2025-09-30 05:25:59.942422 | controller | >f+++++++++ ansible-execution.log
2025-09-30 05:25:59.942443 | controller | >f+++++++++ ansible.log
2025-09-30 05:25:59.942462 | controller | >f+++++++++ dmesg.log
2025-09-30 05:25:59.942479 | controller | >f+++++++++ installed-pkgs.log
2025-09-30 05:25:59.942501 | controller | >f+++++++++ python.log
2025-09-30 05:25:59.942520 | controller | >f+++++++++ registries.conf
2025-09-30 05:25:59.942538 | controller | >f+++++++++ report.html
2025-09-30 05:25:59.942558 | controller | >f+++++++++ selinux-denials.log
2025-09-30 05:25:59.942575 | controller | >f+++++++++ selinux-listing.log
2025-09-30 05:25:59.942593 | controller | cd+++++++++ ci-framework-data/
2025-09-30 05:25:59.942610 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-30 05:25:59.942627 | controller | >f+++++++++ ci-framework-data/artifacts/Containerfile.buildpkg
2025-09-30 05:25:59.942645 | controller | >f+++++++++ ci-framework-data/artifacts/build-packages.yml
2025-09-30 05:25:59.942662 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-30 05:25:59.942680 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-09-30 05:25:59.942697 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-09-30 05:25:59.942714 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-09-30 05:25:59.942731 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-30 05:25:59.942748 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-30 05:25:59.942765 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-09-30 05:25:59.942782 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean-antelope-testing.repo
2025-09-30 05:25:59.942800 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo
2025-09-30 05:25:59.942817 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-09-30 05:25:59.942833 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/repo-setup-centos-appstream.repo
2025-09-30 05:25:59.942850 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/repo-setup-centos-baseos.repo
2025-09-30 05:25:59.942866 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/repo-setup-centos-highavailability.repo
2025-09-30 05:25:59.942882 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/repo-setup-centos-powertools.repo
2025-09-30 05:25:59.942958 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-30 05:25:59.942986 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-30 05:25:59.943005 | controller | >f+++++++++ ci-framework-data/logs/neutron-tempest-plugin-builder.log
2025-09-30 05:25:59.943028 | controller | cd+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/
2025-09-30 05:25:59.943046 | controller | >f+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/build.log
2025-09-30 05:25:59.943062 | controller | >f+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/hw_info.log
2025-09-30 05:25:59.943079 | controller | >f+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/installed_pkgs.log
2025-09-30 05:25:59.943095 | controller | >f+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/mock.log
2025-09-30 05:25:59.943111 | controller | >f+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/root.log
2025-09-30 05:25:59.943127 | controller | >f+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/rpmbuild.log
2025-09-30 05:25:59.943143 | controller | >f+++++++++ ci-framework-data/logs/build_neutron-tempest-plugin/state.log
2025-09-30 05:25:59.943159 | controller | cd+++++++++ registries.conf.d/
2025-09-30 05:25:59.943174 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-30 05:25:59.943191 | controller | cd+++++++++ system-config/
2025-09-30 05:25:59.943207 | controller | cd+++++++++ system-config/libvirt/
2025-09-30 05:25:59.943223 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-30 05:25:59.943239 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-30 05:26:00.319451 | controller | changed: .d..t...... ./
2025-09-30 05:26:00.677838 | controller | changed: .d..t...... ./
2025-09-30 05:26:00.693019 | 
2025-09-30 05:26:00.693134 | TASK [Return artifact to Zuul]
2025-09-30 05:26:00.721457 | controller | ok
2025-09-30 05:26:00.741241 | 
2025-09-30 05:26:00.741310 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-30 05:26:00.741419 | 
2025-09-30 05:26:00.741448 | PLAY RECAP
2025-09-30 05:26:00.741484 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-30 05:26:00.741507 | 
2025-09-30 05:26:00.827461 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 05:26:00.828249 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 05:26:01.336100 | 
2025-09-30 05:26:01.336243 | PLAY [all]
2025-09-30 05:26:01.354611 | 
2025-09-30 05:26:01.354698 | TASK [include_role : fetch-output]
2025-09-30 05:26:01.382800 | controller | ok
2025-09-30 05:26:01.399045 | 
2025-09-30 05:26:01.399144 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 05:26:01.442874 | controller | skipping: Conditional result was False
2025-09-30 05:26:01.448396 | 
2025-09-30 05:26:01.448468 | TASK [fetch-output : Set log path for single node]
2025-09-30 05:26:01.477184 | controller | ok
2025-09-30 05:26:01.482020 | 
2025-09-30 05:26:01.482090 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 05:26:01.804827 | controller -> localhost | ok: "/var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/logs"
2025-09-30 05:26:01.986733 | controller -> localhost | ok: "/var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/artifacts"
2025-09-30 05:26:02.171009 | controller -> localhost | ok: "/var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/docs"
2025-09-30 05:26:02.181296 | 
2025-09-30 05:26:02.181402 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 05:26:02.684465 | controller | ok
2025-09-30 05:26:02.684663 | controller | ok: All items complete
2025-09-30 05:26:02.684695 | 
2025-09-30 05:26:03.047444 | controller | ok
2025-09-30 05:26:03.413455 | controller | ok
2025-09-30 05:26:03.433308 | 
2025-09-30 05:26:03.433410 | TASK [include_role : fetch-output-openshift]
2025-09-30 05:26:03.447450 | controller | skipping: Conditional result was False
2025-09-30 05:26:03.453100 | 
2025-09-30 05:26:03.453167 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-30 05:26:03.799258 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007024
2025-09-30 05:26:03.987417 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006953
2025-09-30 05:26:04.017362 | 
2025-09-30 05:26:04.017455 | PLAY [all]
2025-09-30 05:26:04.031012 | 
2025-09-30 05:26:04.031090 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-30 05:26:04.426043 | controller | changed
2025-09-30 05:26:04.447174 | 
2025-09-30 05:26:04.447255 | PLAY RECAP
2025-09-30 05:26:04.447297 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-30 05:26:04.447319 | 
2025-09-30 05:26:04.523633 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 05:26:04.524407 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-30 05:26:05.057878 | 
2025-09-30 05:26:05.058002 | PLAY [localhost]
2025-09-30 05:26:05.075004 | 
2025-09-30 05:26:05.075081 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-30 05:26:05.405884 | localhost | changed
2025-09-30 05:26:05.410390 | 
2025-09-30 05:26:05.410467 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-30 05:26:05.437846 | localhost | ok
2025-09-30 05:26:05.445544 | 
2025-09-30 05:26:05.445615 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-30 05:26:05.737396 | localhost | changed
2025-09-30 05:26:05.742757 | 
2025-09-30 05:26:05.742827 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-30 05:26:06.286727 | localhost | changed
2025-09-30 05:26:06.291720 | 
2025-09-30 05:26:06.291789 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-30 05:26:06.612969 | localhost | Identity added: /var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/tmp/ansible.ndimoa8l (/var/lib/zuul/builds/09108751206f40feb188a1d84abec651/work/tmp/ansible.ndimoa8l)
2025-09-30 05:26:06.613134 | localhost | ok: Runtime: 0:00:00.006017
2025-09-30 05:26:06.617228 | 
2025-09-30 05:26:06.617295 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-30 05:26:06.836926 | localhost | ok: Runtime: 0:00:00.004502
2025-09-30 05:26:06.841538 | 
2025-09-30 05:26:06.841607 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-30 05:26:06.889164 | localhost | changed
2025-09-30 05:26:06.894138 | 
2025-09-30 05:26:06.894203 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-30 05:26:07.204552 | localhost | changed
2025-09-30 05:26:07.223669 | 
2025-09-30 05:26:07.223724 | PLAY [localhost]
2025-09-30 05:26:07.234754 | 
2025-09-30 05:26:07.234817 | TASK [Generate bulk log download script]
2025-09-30 05:26:07.252500 | localhost | ok
2025-09-30 05:26:07.263673 | 
2025-09-30 05:26:07.263742 | TASK [local-log-download : Check API endpoint is defined]
2025-09-30 05:26:07.290776 | localhost | ok: All assertions passed
2025-09-30 05:26:07.295296 | 
2025-09-30 05:26:07.295359 | TASK [local-log-download : Create download script]
2025-09-30 05:26:07.642306 | localhost -> localhost | changed
2025-09-30 05:26:07.651265 | 
2025-09-30 05:26:07.651347 | TASK [Register quick-download link]
2025-09-30 05:26:07.668330 | localhost | ok
2025-09-30 05:26:07.709048 | 
2025-09-30 05:26:07.709133 | PLAY [logserver.rdoproject.org]
2025-09-30 05:26:07.718325 | 
2025-09-30 05:26:07.718389 | TASK [Set zuul-log-path fact]
2025-09-30 05:26:07.733836 | logserver.rdoproject.org | ok
2025-09-30 05:26:07.742924 | 
2025-09-30 05:26:07.742991 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 05:26:07.768743 | logserver.rdoproject.org | ok
2025-09-30 05:26:07.774229 | 
2025-09-30 05:26:07.774292 | TASK [upload-logs : Create log directories]
2025-09-30 05:26:09.632024 | logserver.rdoproject.org | changed
2025-09-30 05:26:09.635185 | 
2025-09-30 05:26:09.635253 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-30 05:26:09.851504 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004874
2025-09-30 05:26:09.856210 | 
2025-09-30 05:26:09.856284 | TASK [upload-logs : Upload logs to log server]
2025-09-30 05:26:11.509059 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-30 05:26:11.512040 | 
2025-09-30 05:26:11.512107 | LOOP [upload-logs : Compress console log and json output]
2025-09-30 05:26:11.550698 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 05:26:11.559040 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 05:26:11.569697 | 
2025-09-30 05:26:11.569764 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-30 05:26:11.605982 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 05:26:11.606180 | 
2025-09-30 05:26:11.609396 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 05:26:11.616843 | 
2025-09-30 05:26:11.616936 | LOOP [upload-logs : Upload console log and json output]