2025-10-08 12:31:10.440460 | Job console starting...
2025-10-08 12:31:10.683646 | Updating repositories
2025-10-08 12:31:11.417424 | Preparing job workspace
2025-10-08 12:31:19.886726 | Running Ansible setup...
2025-10-08 12:31:22.899230 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:31:23.463653 | 
2025-10-08 12:31:23.463768 | PLAY [localhost]
2025-10-08 12:31:23.472633 | 
2025-10-08 12:31:23.472716 | TASK [Gathering Facts]
2025-10-08 12:31:24.342838 | localhost | ok
2025-10-08 12:31:24.357819 | 
2025-10-08 12:31:24.357959 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 12:31:24.717292 | localhost -> localhost | changed
2025-10-08 12:31:24.722837 | 
2025-10-08 12:31:24.722938 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 12:31:25.459029 | localhost -> localhost | changed
2025-10-08 12:31:25.467541 | 
2025-10-08 12:31:25.467620 | TASK [Setup log path fact]
2025-10-08 12:31:25.485590 | localhost | ok
2025-10-08 12:31:25.497452 | 
2025-10-08 12:31:25.497538 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:31:25.525437 | localhost | ok
2025-10-08 12:31:25.533370 | 
2025-10-08 12:31:25.533456 | TASK [emit-job-header : Print job information]
2025-10-08 12:31:25.561805 | # Job Information
2025-10-08 12:31:25.561984 | Ansible Version: 2.15.12
2025-10-08 12:31:25.562020 | Job: cifmw-molecule-os_net_setup
2025-10-08 12:31:25.562046 | Pipeline: github-check
2025-10-08 12:31:25.562068 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 12:31:25.562087 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-08 12:31:25.562107 | Log URL (when completed): https://logserver.rdoproject.org/39d/rdoproject.org/39d526651a3945a18db829f158b70865/
2025-10-08 12:31:25.562126 | Event ID: e452cdb0-a431-11f0-8caa-4725382b7326
2025-10-08 12:31:25.565851 | 
2025-10-08 12:31:25.566536 | LOOP [emit-job-header : Print node information]
2025-10-08 12:31:25.657640 | localhost | ok:
2025-10-08 12:31:25.657790 | localhost | # Node Information
2025-10-08 12:31:25.657825 | localhost | Inventory Hostname: controller
2025-10-08 12:31:25.657851 | localhost | Hostname: np0005476219
2025-10-08 12:31:25.657873 | localhost | Username: zuul
2025-10-08 12:31:25.657895 | localhost | Distro: CentOS 9
2025-10-08 12:31:25.657938 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 12:31:25.657973 | localhost | Region: regionOne
2025-10-08 12:31:25.657993 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-08 12:31:25.658013 | localhost | Product Name: OpenStack Compute
2025-10-08 12:31:25.658031 | localhost | Interface IP: 192.168.26.55
2025-10-08 12:31:25.680821 | 
2025-10-08 12:31:25.680900 | PLAY [all]
2025-10-08 12:31:25.687561 | 
2025-10-08 12:31:25.687626 | TASK [Gather network facts]
2025-10-08 12:31:26.074090 | controller | ok
2025-10-08 12:31:26.089688 | 
2025-10-08 12:31:26.089779 | TASK [include_role : start-zuul-console]
2025-10-08 12:31:26.107980 | controller | ok
2025-10-08 12:31:26.121338 | 
2025-10-08 12:31:26.121428 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 12:31:26.458256 | controller | ok
2025-10-08 12:31:26.467561 | 
2025-10-08 12:31:26.467631 | TASK [include_role : add-build-sshkey]
2025-10-08 12:31:26.485566 | controller | ok
2025-10-08 12:31:26.500704 | 
2025-10-08 12:31:26.500780 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 12:31:26.707155 | controller -> localhost | ok
2025-10-08 12:31:26.713563 | 
2025-10-08 12:31:26.713629 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 12:31:26.732810 | controller | ok
2025-10-08 12:31:26.748319 | controller | included: /var/lib/zuul/builds/39d526651a3945a18db829f158b70865/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 12:31:26.755484 | 
2025-10-08 12:31:26.755549 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 12:31:27.559162 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 12:31:27.559372 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/39d526651a3945a18db829f158b70865_id_rsa.
2025-10-08 12:31:27.559407 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/39d526651a3945a18db829f158b70865_id_rsa.pub.
2025-10-08 12:31:27.559433 | controller -> localhost | The key fingerprint is:
2025-10-08 12:31:27.559454 | controller -> localhost | SHA256:ND0dbsDLJYBpK4Rog29fjIBCOiekijo6Tx3YOpAw4K8 zuul-build-sshkey
2025-10-08 12:31:27.559478 | controller -> localhost | The key's randomart image is:
2025-10-08 12:31:27.559499 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 12:31:27.559519 | controller -> localhost | |+= .   o.o. .    |
2025-10-08 12:31:27.559539 | controller -> localhost | |@oo . +  .o+..   |
2025-10-08 12:31:27.559558 | controller -> localhost | |Xooo + .o.o++    |
2025-10-08 12:31:27.559577 | controller -> localhost | |+*+oo +. .oo     |
2025-10-08 12:31:27.559596 | controller -> localhost | |=..ooo  S        |
2025-10-08 12:31:27.559616 | controller -> localhost | |...o..           |
2025-10-08 12:31:27.559636 | controller -> localhost | |oE+ .            |
2025-10-08 12:31:27.559655 | controller -> localhost | |oo .             |
2025-10-08 12:31:27.559673 | controller -> localhost | |.o.              |
2025-10-08 12:31:27.559692 | controller -> localhost | +----[SHA256]-----+
2025-10-08 12:31:27.559735 | controller -> localhost | ok: Runtime: 0:00:00.442106
2025-10-08 12:31:27.568996 | 
2025-10-08 12:31:27.569066 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 12:31:27.604666 | controller | ok
2025-10-08 12:31:27.621744 | controller | included: /var/lib/zuul/builds/39d526651a3945a18db829f158b70865/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 12:31:27.630624 | 
2025-10-08 12:31:27.630694 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 12:31:27.655212 | controller | skipping: Conditional result was False
2025-10-08 12:31:27.661305 | 
2025-10-08 12:31:27.661376 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 12:31:28.064774 | controller | changed
2025-10-08 12:31:28.070534 | 
2025-10-08 12:31:28.070609 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 12:31:28.275977 | controller | ok
2025-10-08 12:31:28.281488 | 
2025-10-08 12:31:28.281562 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 12:31:28.857070 | controller | changed
2025-10-08 12:31:28.861850 | 
2025-10-08 12:31:28.861932 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 12:31:29.406816 | controller | changed
2025-10-08 12:31:29.411660 | 
2025-10-08 12:31:29.411726 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 12:31:29.434766 | controller | skipping: Conditional result was False
2025-10-08 12:31:29.440727 | 
2025-10-08 12:31:29.440798 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 12:31:29.769674 | controller -> localhost | changed
2025-10-08 12:31:29.779469 | 
2025-10-08 12:31:29.779551 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 12:31:30.016288 | controller -> localhost | Identity added: /var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/39d526651a3945a18db829f158b70865_id_rsa (zuul-build-sshkey)
2025-10-08 12:31:30.016460 | controller -> localhost | ok: Runtime: 0:00:00.006973
2025-10-08 12:31:30.021965 | 
2025-10-08 12:31:30.022027 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 12:31:30.313371 | controller | ok
2025-10-08 12:31:30.317806 | 
2025-10-08 12:31:30.317871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 12:31:30.341851 | controller | skipping: Conditional result was False
2025-10-08 12:31:30.351074 | 
2025-10-08 12:31:30.351140 | TASK [include_role : validate-host]
2025-10-08 12:31:30.368417 | controller | ok
2025-10-08 12:31:30.388455 | 
2025-10-08 12:31:30.388543 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 12:31:30.416358 | controller | ok
2025-10-08 12:31:30.421638 | 
2025-10-08 12:31:30.421706 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 12:31:30.627345 | controller -> localhost | ok
2025-10-08 12:31:30.633283 | 
2025-10-08 12:31:30.633364 | TASK [validate-host : Collect information about the host]
2025-10-08 12:31:31.277422 | controller | ok
2025-10-08 12:31:31.294238 | 
2025-10-08 12:31:31.294331 | TASK [validate-host : Sanitize hostname]
2025-10-08 12:31:31.439067 | controller | ok
2025-10-08 12:31:31.499672 | 
2025-10-08 12:31:31.499809 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 12:31:32.081938 | controller -> localhost | changed
2025-10-08 12:31:32.087746 | 
2025-10-08 12:31:32.087833 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 12:31:32.461165 | controller | ok
2025-10-08 12:31:32.466953 | 
2025-10-08 12:31:32.467023 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 12:31:32.899835 | controller -> localhost | changed
2025-10-08 12:31:32.910242 | 
2025-10-08 12:31:32.910322 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 12:31:32.924158 | controller | skipping: Conditional result was False
2025-10-08 12:31:32.930616 | 
2025-10-08 12:31:32.930721 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 12:31:32.948054 | controller | skipping: Conditional result was False
2025-10-08 12:31:32.963149 | 
2025-10-08 12:31:32.963243 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 12:31:33.175145 | controller | ok: "logs"
2025-10-08 12:31:33.175388 | controller | ok: All items complete
2025-10-08 12:31:33.175419 | 
2025-10-08 12:31:33.351035 | controller | ok: "artifacts"
2025-10-08 12:31:33.517605 | controller | ok: "docs"
2025-10-08 12:31:33.524200 | 
2025-10-08 12:31:33.524299 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 12:31:33.715954 | controller | changed: "logs"
2025-10-08 12:31:33.881077 | controller | changed: "artifacts"
2025-10-08 12:31:34.048691 | controller | changed: "docs"
2025-10-08 12:31:34.091887 | 
2025-10-08 12:31:34.092015 | PLAY RECAP
2025-10-08 12:31:34.092063 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:31:34.092092 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:31:34.092111 | 
2025-10-08 12:31:34.177022 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:31:34.177763 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:31:34.769756 | 
2025-10-08 12:31:34.769860 | PLAY [all]
2025-10-08 12:31:34.790544 | 
2025-10-08 12:31:34.790620 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:31:34.839701 | controller | ok
2025-10-08 12:31:34.844898 | 
2025-10-08 12:31:34.845012 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:31:35.280666 | controller | changed
2025-10-08 12:31:35.286353 | 
2025-10-08 12:31:35.286446 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:31:36.100070 | controller | changed
2025-10-08 12:31:36.116309 | 
2025-10-08 12:31:36.116442 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 12:31:36.473396 | controller | changed:
2025-10-08 12:31:36.473633 | controller | {
2025-10-08 12:31:36.473670 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 12:31:36.473702 | controller | }
2025-10-08 12:31:36.673601 | controller | changed:
2025-10-08 12:31:36.673677 | controller | {
2025-10-08 12:31:36.673706 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 12:31:36.673728 | controller | }
2025-10-08 12:31:36.874097 | controller | changed:
2025-10-08 12:31:36.874175 | controller | {
2025-10-08 12:31:36.874202 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 12:31:36.874223 | controller | }
2025-10-08 12:31:37.070346 | controller | changed:
2025-10-08 12:31:37.070421 | controller | {
2025-10-08 12:31:37.070449 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 12:31:37.070471 | controller | }
2025-10-08 12:31:37.269840 | controller | changed:
2025-10-08 12:31:37.269937 | controller | {
2025-10-08 12:31:37.269981 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 12:31:37.270005 | controller | }
2025-10-08 12:31:37.471812 | controller | changed:
2025-10-08 12:31:37.472100 | controller | {
2025-10-08 12:31:37.472148 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 12:31:37.472175 | controller | }
2025-10-08 12:31:37.673384 | controller | changed:
2025-10-08 12:31:37.673557 | controller | {
2025-10-08 12:31:37.673587 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-08 12:31:37.673610 | controller | }
2025-10-08 12:31:37.878081 | controller | changed:
2025-10-08 12:31:37.878164 | controller | {
2025-10-08 12:31:37.878193 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-08 12:31:37.878215 | controller | }
2025-10-08 12:31:38.079668 | controller | changed:
2025-10-08 12:31:38.079770 | controller | {
2025-10-08 12:31:38.079808 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 12:31:38.079831 | controller | }
2025-10-08 12:31:38.280411 | controller | changed:
2025-10-08 12:31:38.280564 | controller | {
2025-10-08 12:31:38.280863 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 12:31:38.280923 | controller | }
2025-10-08 12:31:38.482745 | controller | changed:
2025-10-08 12:31:38.482824 | controller | {
2025-10-08 12:31:38.482852 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 12:31:38.482873 | controller | }
2025-10-08 12:31:38.729983 | controller | changed:
2025-10-08 12:31:38.730061 | controller | {
2025-10-08 12:31:38.730088 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 12:31:38.730110 | controller | }
2025-10-08 12:31:38.927117 | controller | changed:
2025-10-08 12:31:38.927193 | controller | {
2025-10-08 12:31:38.927220 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 12:31:38.927242 | controller | }
2025-10-08 12:31:39.143325 | controller | changed:
2025-10-08 12:31:39.143587 | controller | {
2025-10-08 12:31:39.143621 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 12:31:39.143707 | controller | }
2025-10-08 12:31:39.349452 | controller | changed:
2025-10-08 12:31:39.349534 | controller | {
2025-10-08 12:31:39.349561 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 12:31:39.349582 | controller | }
2025-10-08 12:31:39.552226 | controller | changed:
2025-10-08 12:31:39.552376 | controller | {
2025-10-08 12:31:39.552408 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 12:31:39.552430 | controller | }
2025-10-08 12:31:39.757114 | controller | changed:
2025-10-08 12:31:39.757201 | controller | {
2025-10-08 12:31:39.757230 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-08 12:31:39.757254 | controller | }
2025-10-08 12:31:39.958071 | controller | changed:
2025-10-08 12:31:39.958147 | controller | {
2025-10-08 12:31:39.958175 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-08 12:31:39.958203 | controller | }
2025-10-08 12:31:40.170299 | controller | changed:
2025-10-08 12:31:40.170380 | controller | {
2025-10-08 12:31:40.170415 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 12:31:40.170442 | controller | }
2025-10-08 12:31:40.370995 | controller | changed:
2025-10-08 12:31:40.371089 | controller | {
2025-10-08 12:31:40.371117 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 12:31:40.371138 | controller | }
2025-10-08 12:31:40.572233 | controller | changed:
2025-10-08 12:31:40.572313 | controller | {
2025-10-08 12:31:40.572341 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 12:31:40.572362 | controller | }
2025-10-08 12:31:40.771357 | controller | changed:
2025-10-08 12:31:40.771440 | controller | {
2025-10-08 12:31:40.771466 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 12:31:40.771488 | controller | }
2025-10-08 12:31:40.973543 | controller | changed:
2025-10-08 12:31:40.973617 | controller | {
2025-10-08 12:31:40.973643 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-08 12:31:40.973664 | controller | }
2025-10-08 12:31:41.180748 | controller | changed:
2025-10-08 12:31:41.180824 | controller | {
2025-10-08 12:31:41.180853 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 12:31:41.180873 | controller | }
2025-10-08 12:31:41.387995 | controller | changed:
2025-10-08 12:31:41.388073 | controller | {
2025-10-08 12:31:41.388102 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 12:31:41.388123 | controller | }
2025-10-08 12:31:41.591391 | controller | changed:
2025-10-08 12:31:41.591481 | controller | {
2025-10-08 12:31:41.591510 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 12:31:41.591531 | controller | }
2025-10-08 12:31:41.610004 | 
2025-10-08 12:31:41.610117 | TASK [Set timezone to UTC]
2025-10-08 12:31:42.000327 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 12:31:42.007133 | 
2025-10-08 12:31:42.007214 | TASK [Create nodepool directory]
2025-10-08 12:31:42.209282 | controller | changed
2025-10-08 12:31:42.215504 | 
2025-10-08 12:31:42.215591 | TASK [Create nodepool sub_nodes file]
2025-10-08 12:31:42.753196 | controller | changed
2025-10-08 12:31:42.758642 | 
2025-10-08 12:31:42.758725 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 12:31:43.244602 | controller | changed
2025-10-08 12:31:43.253638 | 
2025-10-08 12:31:43.253724 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 12:31:43.285779 | 
2025-10-08 12:31:43.285937 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 12:31:43.315250 | 
2025-10-08 12:31:43.315480 | TASK [Create nodepool primary file]
2025-10-08 12:31:43.339051 | controller | skipping: Conditional result was False
2025-10-08 12:31:43.344788 | 
2025-10-08 12:31:43.344893 | TASK [Create nodepool node_private for this node]
2025-10-08 12:31:43.897190 | controller | changed
2025-10-08 12:31:43.904969 | 
2025-10-08 12:31:43.905033 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 12:31:44.303149 | controller | ok: Item: id_rsa Runtime: 0:00:00.005596
2025-10-08 12:31:44.303335 | 
2025-10-08 12:31:44.473407 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004177
2025-10-08 12:31:44.482190 | 
2025-10-08 12:31:44.482298 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 12:31:45.018375 | controller | changed
2025-10-08 12:31:45.023704 | 
2025-10-08 12:31:45.023768 | TASK [Validate sudoers config after edits]
2025-10-08 12:31:45.243410 | controller | /etc/sudoers: parsed OK
2025-10-08 12:31:45.243496 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 12:31:45.243508 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 12:31:45.243514 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 12:31:45.551117 | controller | ok: Runtime: 0:00:00.005263
2025-10-08 12:31:45.556589 | 
2025-10-08 12:31:45.556660 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 12:31:45.765674 | controller | SHELL=/bin/bash
2025-10-08 12:31:45.765736 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 12:31:45.765747 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 12:31:45.765794 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/42/1042/4d1575d1c5aa317d07bf95973aac6faacde3d75b^openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:31:45.765858 | controller | PWD=/home/zuul
2025-10-08 12:31:45.765867 | controller | ZUUL_PIPELINE=github-check
2025-10-08 12:31:45.765873 | controller | LOGNAME=zuul
2025-10-08 12:31:45.765878 | controller | XDG_SESSION_TYPE=tty
2025-10-08 12:31:45.765884 | controller | _=/usr/bin/env
2025-10-08 12:31:45.765889 | controller | MOTD_SHOWN=pam
2025-10-08 12:31:45.765895 | controller | HOME=/home/zuul
2025-10-08 12:31:45.765900 | controller | LANG=en_US.UTF-8
2025-10-08 12:31:45.765905 | controller | SSH_CONNECTION=192.168.26.12 42920 192.168.26.55 22
2025-10-08 12:31:45.765911 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-08 12:31:45.765917 | controller | ZUUL_CHANGE_IDS=1042,4d1575d1c5aa317d07bf95973aac6faacde3d75b 3368,a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:31:45.765923 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 12:31:45.765928 | controller | XDG_SESSION_CLASS=user
2025-10-08 12:31:45.765934 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 12:31:45.765939 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 12:31:45.765945 | controller | USER=zuul
2025-10-08 12:31:45.765951 | controller | ZUUL_VOTING=True
2025-10-08 12:31:45.765956 | controller | BUILD_TIMEOUT=1800000
2025-10-08 12:31:45.765962 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 12:31:45.765967 | controller | SHLVL=1
2025-10-08 12:31:45.765973 | controller | ZUUL_PATCHSET=a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:31:45.765978 | controller | XDG_SESSION_ID=1
2025-10-08 12:31:45.765983 | controller | ZUUL_BRANCH=main
2025-10-08 12:31:45.765989 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 12:31:45.765995 | controller | SSH_CLIENT=192.168.26.12 42920 22
2025-10-08 12:31:45.766000 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 12:31:45.766005 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 12:31:45.766011 | controller | which_declare=declare -f
2025-10-08 12:31:45.766017 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 12:31:45.766023 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 12:31:45.766029 | controller | ZUUL_CHANGE=3368
2025-10-08 12:31:45.766034 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 12:31:45.766040 | controller | ZUUL_UUID=39d526651a3945a18db829f158b70865
2025-10-08 12:31:45.766045 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 12:31:45.766051 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 12:31:45.766056 | controller | }
2025-10-08 12:31:46.093869 | controller | ok: Runtime: 0:00:00.005975
2025-10-08 12:31:46.099883 | 
2025-10-08 12:31:46.099986 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 12:31:46.143494 | controller | skipping: Conditional result was False
2025-10-08 12:31:46.149222 | 
2025-10-08 12:31:46.149306 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 12:31:46.683961 | controller | skipping: Conditional result was False
2025-10-08 12:31:46.689749 | 
2025-10-08 12:31:46.689832 | TASK [Ensure legacy workspace directory]
2025-10-08 12:31:46.880973 | controller | changed
2025-10-08 12:31:46.902062 | 
2025-10-08 12:31:46.902168 | PLAY RECAP
2025-10-08 12:31:46.902216 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:31:46.902240 | 
2025-10-08 12:31:46.999065 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:31:46.999804 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:31:47.635601 | 
2025-10-08 12:31:47.635712 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 12:31:47.657176 | 
2025-10-08 12:31:47.657269 | TASK [Create zuul-output directory]
2025-10-08 12:31:48.001111 | controller | changed
2025-10-08 12:31:48.006350 | 
2025-10-08 12:31:48.006453 | TASK [Slurp Zuul inventory test]
2025-10-08 12:31:48.367477 | controller -> localhost | ok
2025-10-08 12:31:48.374656 | 
2025-10-08 12:31:48.374741 | TASK [Save zuul inventory]
2025-10-08 12:31:49.128453 | controller | changed
2025-10-08 12:31:49.133269 | 
2025-10-08 12:31:49.133337 | TASK [Save zuul vars without the change_message]
2025-10-08 12:31:49.725494 | controller | changed
2025-10-08 12:31:49.746461 | 
2025-10-08 12:31:49.746542 | PLAY RECAP
2025-10-08 12:31:49.746588 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:31:49.746612 | 
2025-10-08 12:31:49.890658 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:31:49.891524 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:31:50.495676 | 
2025-10-08 12:31:50.495775 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 12:31:50.516600 | 
2025-10-08 12:31:50.516693 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 12:31:50.546516 | controller | ok
2025-10-08 12:31:50.564091 | 
2025-10-08 12:31:50.564192 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:31:50.588343 | controller | skipping: Conditional result was False
2025-10-08 12:31:50.594212 | 
2025-10-08 12:31:50.594286 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:31:50.917254 | controller | ok
2025-10-08 12:31:50.923338 | 
2025-10-08 12:31:50.923419 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:31:51.596414 | controller | ok
2025-10-08 12:31:51.609995 | 
2025-10-08 12:31:51.610076 | TASK [Prepare workspace]
2025-10-08 12:31:51.628978 | controller | ok
2025-10-08 12:31:51.648116 | 
2025-10-08 12:31:51.648194 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 12:31:51.991739 | controller | ok
2025-10-08 12:31:52.007708 | 
2025-10-08 12:31:52.007832 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 12:31:54.887327 | controller | Output suppressed because no_log was given
2025-10-08 12:31:54.898924 | 
2025-10-08 12:31:54.899012 | LOOP [Create zuul-output directory]
2025-10-08 12:31:55.098789 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 12:31:55.243047 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 12:31:55.258762 | 
2025-10-08 12:31:55.258872 | TASK [Install required packages]
2025-10-08 12:32:45.092313 | controller | changed
2025-10-08 12:32:45.097854 | 
2025-10-08 12:32:45.097998 | TASK [Install venv]
2025-10-08 12:33:46.169224 | controller | changed
2025-10-08 12:33:46.221181 | 
2025-10-08 12:33:46.221296 | PLAY RECAP
2025-10-08 12:33:46.221343 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:33:46.221368 | 
2025-10-08 12:33:46.288820 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:33:46.289720 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:33:46.839406 | 
2025-10-08 12:33:46.839513 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 12:33:46.870761 | 
2025-10-08 12:33:46.870884 | TASK [Gather required facts]
2025-10-08 12:33:47.367163 | controller | ok
2025-10-08 12:33:47.373180 | 
2025-10-08 12:33:47.373285 | TASK [Load environment var if instructed to]
2025-10-08 12:33:47.397291 | controller | skipping: Conditional result was False
2025-10-08 12:33:47.403668 | 
2025-10-08 12:33:47.403748 | TASK [Run molecule]
2025-10-08 12:33:48.367499 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 12:33:48.438587 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 12:34:05.595977 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.596450 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.596915 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.597409 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.597881 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+a7a306bf was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 1.0.0+a7a306bf (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.598335 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.598764 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.599251 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.599694 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.600148 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.600596 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.601025 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.601496 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.601929 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.602417 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/c32a66/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:34:05.611858 | controller | INFO     Running default > prepare
2025-10-08 12:34:06.284592 | controller |
2025-10-08 12:34:06.284648 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 12:34:06.284760 | controller |
2025-10-08 12:34:06.284867 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:34:06.284972 | controller | Wednesday 08 October 2025  12:34:06 +0000 (0:00:00.013)       0:00:00.013 *****
2025-10-08 12:34:07.123534 | controller | ok: [instance]
2025-10-08 12:34:07.123592 | controller |
2025-10-08 12:34:07.123672 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 12:34:07.123775 | controller | Wednesday 08 October 2025  12:34:07 +0000 (0:00:00.839)       0:00:00.852 *****
2025-10-08 12:34:07.141636 | controller | skipping: [instance]
2025-10-08 12:34:07.141764 | controller |
2025-10-08 12:34:07.141884 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 12:34:07.141993 | controller | Wednesday 08 October 2025  12:34:07 +0000 (0:00:00.018)       0:00:00.870 *****
2025-10-08 12:34:07.183424 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 12:34:07.183514 | controller |
2025-10-08 12:34:07.183628 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 12:34:07.183734 | controller | Wednesday 08 October 2025  12:34:07 +0000 (0:00:00.041)       0:00:00.912 *****
2025-10-08 12:34:07.477547 | controller | ok: [instance]
2025-10-08 12:34:07.477599 | controller |
2025-10-08 12:34:07.477693 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 12:34:07.477799 | controller | Wednesday 08 October 2025  12:34:07 +0000 (0:00:00.294)       0:00:01.206 *****
2025-10-08 12:34:07.786130 | controller | ok: [instance]
2025-10-08 12:34:07.786223 | controller |
2025-10-08 12:34:07.786337 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 12:34:07.786440 | controller | Wednesday 08 October 2025  12:34:07 +0000 (0:00:00.308)       0:00:01.515 *****
2025-10-08 12:34:08.476208 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 12:34:08.476304 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 12:34:08.476419 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 12:34:08.476520 | controller |
2025-10-08 12:34:08.476625 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 12:34:08.476730 | controller | Wednesday 08 October 2025  12:34:08 +0000 (0:00:00.690)       0:00:02.205 *****
2025-10-08 12:34:08.534546 | controller |
2025-10-08 12:34:08.534654 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 12:34:08.534782 | controller | Wednesday 08 October 2025  12:34:08 +0000 (0:00:00.058)       0:00:02.263 *****
2025-10-08 12:34:09.074016 | controller | changed: [instance] => (item=tmp)
2025-10-08 12:34:09.074284 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 12:34:09.074327 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 12:34:09.074343 | controller |
2025-10-08 12:34:09.074443 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 12:34:09.074550 | controller | Wednesday 08 October 2025  12:34:09 +0000 (0:00:00.539)       0:00:02.803 *****
2025-10-08 12:34:09.858587 | controller | ok: [instance]
2025-10-08 12:34:11.056177 | controller |
2025-10-08 12:34:11.056207 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 12:34:11.056215 | controller | Wednesday 08 October 2025  12:34:09 +0000 (0:00:00.784)       0:00:03.587 *****
2025-10-08 12:34:11.056225 | controller | changed: [instance]
2025-10-08 12:34:19.026917 | controller |
2025-10-08 12:34:19.026952 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 12:34:19.026964 | controller | Wednesday 08 October 2025  12:34:11 +0000 (0:00:01.197)       0:00:04.785 *****
2025-10-08 12:34:19.026975 | controller | changed: [instance]
2025-10-08 12:34:19.754596 | controller |
2025-10-08 12:34:19.754633 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 12:34:19.754642 | controller | Wednesday 08 October 2025  12:34:19 +0000 (0:00:07.970)       0:00:12.755 *****
2025-10-08 12:34:19.754652 | controller | changed: [instance]
2025-10-08 12:34:19.774902 | controller |
2025-10-08 12:34:19.774930 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 12:34:19.774937 | controller | Wednesday 08 October 2025  12:34:19 +0000 (0:00:00.727)       0:00:13.483 *****
2025-10-08 12:34:19.774947 | controller | skipping: [instance]
2025-10-08 12:34:20.603166 | controller |
2025-10-08 12:34:20.603197 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 12:34:20.603206 | controller | Wednesday 08 October 2025  12:34:19 +0000 (0:00:00.020)       0:00:13.504 *****
2025-10-08 12:34:20.603217 | controller | changed: [instance]
2025-10-08 12:34:20.639118 | controller |
2025-10-08 12:34:20.639157 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 12:34:20.639168 | controller | Wednesday 08 October 2025  12:34:20 +0000 (0:00:00.827)       0:00:14.331 *****
2025-10-08 12:34:20.639180 | controller | skipping: [instance]
2025-10-08 12:34:20.676097 | controller |
2025-10-08 12:34:20.676122 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 12:34:20.676130 | controller | Wednesday 08 October 2025  12:34:20 +0000 (0:00:00.036)       0:00:14.368 *****
2025-10-08 12:34:20.676139 | controller | skipping: [instance]
2025-10-08 12:34:20.712579 | controller |
2025-10-08 12:34:20.712598 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 12:34:20.712605 | controller | Wednesday 08 October 2025  12:34:20 +0000 (0:00:00.036)       0:00:14.404 *****
2025-10-08 12:34:20.712614 | controller | skipping: [instance]
2025-10-08 12:34:21.278905 | controller |
2025-10-08 12:34:21.278935 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 12:34:21.278943 | controller | Wednesday 08 October 2025  12:34:20 +0000 (0:00:00.037)       0:00:14.441 *****
2025-10-08 12:34:21.278953 | controller | changed: [instance]
2025-10-08 12:34:21.704806 | controller |
2025-10-08 12:34:21.704838 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 12:34:21.704847 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.566)       0:00:15.007 *****
2025-10-08 12:34:21.704858 | controller | changed: [instance]
2025-10-08 12:34:21.733937 | controller |
2025-10-08 12:34:21.733960 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 12:34:21.733968 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.425)       0:00:15.433 *****
2025-10-08 12:34:21.733977 | controller | skipping: [instance]
2025-10-08 12:34:21.761789 | controller |
2025-10-08 12:34:21.761812 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 12:34:21.761821 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.029)       0:00:15.462 *****
2025-10-08 12:34:21.761834 | controller | skipping: [instance]
2025-10-08 12:34:21.788966 | controller |
2025-10-08 12:34:21.788995 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 12:34:21.789003 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.027)       0:00:15.490 *****
2025-10-08 12:34:21.789013 | controller | skipping: [instance]
2025-10-08 12:34:21.826214 | controller |
2025-10-08 12:34:21.826255 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 12:34:21.826265 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.027)       0:00:15.518 *****
2025-10-08 12:34:21.826278 | controller | ok: [instance]
2025-10-08 12:34:21.826300 | controller |
2025-10-08 12:34:21.826496 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 12:34:21.853674 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.037)       0:00:15.555 *****
2025-10-08 12:34:21.853707 | controller | skipping: [instance]
2025-10-08 12:34:21.879125 | controller |
2025-10-08 12:34:21.879164 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 12:34:21.879174 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.027)       0:00:15.582 *****
2025-10-08 12:34:21.879196 | controller | skipping: [instance]
2025-10-08 12:34:21.904179 | controller |
2025-10-08 12:34:21.904210 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 12:34:21.904220 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.025)       0:00:15.608 *****
2025-10-08 12:34:21.904232 | controller | skipping: [instance]
2025-10-08 12:34:21.904419 | controller |
2025-10-08 12:34:21.904441 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 12:34:21.930141 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.025)       0:00:15.633 *****
2025-10-08 12:34:21.930182 | controller | skipping: [instance]
2025-10-08 12:34:21.955638 | controller |
2025-10-08 12:34:21.955668 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 12:34:21.955677 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.025)       0:00:15.659 *****
2025-10-08 12:34:21.955689 | controller | skipping: [instance]
2025-10-08 12:34:21.982042 | controller |
2025-10-08 12:34:21.982067 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 12:34:21.982091 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.025)       0:00:15.684 *****
2025-10-08 12:34:21.982104 | controller | skipping: [instance]
2025-10-08 12:34:22.006175 | controller |
2025-10-08 12:34:22.006200 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 12:34:22.006208 | controller | Wednesday 08 October 2025  12:34:21 +0000 (0:00:00.026)       0:00:15.711 *****
2025-10-08 12:34:22.006218 | controller | skipping: [instance]
2025-10-08 12:34:22.006235 | controller |
2025-10-08 12:34:22.006243 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 12:34:22.006329 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.024)       0:00:15.735 *****
2025-10-08 12:34:22.197178 | controller | ok: [instance]
2025-10-08 12:34:22.197431 | controller |
2025-10-08 12:34:22.197448 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 12:34:22.396741 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.190)       0:00:15.926 *****
2025-10-08 12:34:22.396775 | controller | changed: [instance]
2025-10-08 12:34:22.610892 | controller |
2025-10-08 12:34:22.610920 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 12:34:22.610928 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.199)       0:00:16.125 *****
2025-10-08 12:34:22.610939 | controller | changed: [instance]
2025-10-08 12:34:22.631356 | controller |
2025-10-08 12:34:22.631382 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 12:34:22.631390 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.214)       0:00:16.339 *****
2025-10-08 12:34:22.631400 | controller | skipping: [instance]
2025-10-08 12:34:22.652252 | controller |
2025-10-08 12:34:22.652282 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 12:34:22.652290 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.020)       0:00:16.360 *****
2025-10-08 12:34:22.652300 | controller | skipping: [instance]
2025-10-08 12:34:22.652319 | controller |
2025-10-08 12:34:22.652419 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 12:34:22.652573 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.021)       0:00:16.381 *****
2025-10-08 12:34:22.673815 | controller | skipping: [instance]
2025-10-08 12:34:22.694742 | controller |
2025-10-08 12:34:22.694773 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:34:22.694781 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.021)       0:00:16.402 *****
2025-10-08 12:34:22.694791 | controller | skipping: [instance]
2025-10-08 12:34:22.715394 | controller |
2025-10-08 12:34:22.715420 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 12:34:22.715433 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.020)       0:00:16.423 *****
2025-10-08 12:34:22.715443 | controller | skipping: [instance]
2025-10-08 12:34:22.735798 | controller |
2025-10-08 12:34:22.735820 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 12:34:22.735834 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.020)       0:00:16.444 *****
2025-10-08 12:34:22.735844 | controller | skipping: [instance]
2025-10-08 12:34:22.750968 | controller |
2025-10-08 12:34:22.750992 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 12:34:22.751001 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.020)       0:00:16.464 *****
2025-10-08 12:34:22.751012 | controller | skipping: [instance]
2025-10-08 12:34:22.779174 | controller |
2025-10-08 12:34:22.779201 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 12:34:22.779209 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.015)       0:00:16.480 *****
2025-10-08 12:34:22.779219 | controller | skipping: [instance]
2025-10-08 12:34:22.795256 | controller |
2025-10-08 12:34:22.795286 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 12:34:22.795294 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.028)       0:00:16.508 *****
2025-10-08 12:34:22.795304 | controller | skipping: [instance]
2025-10-08 12:34:22.795381 | controller |
2025-10-08 12:34:22.795512 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 12:34:22.795636 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.016)       0:00:16.524 *****
2025-10-08 12:34:22.814458 | controller | skipping: [instance]
2025-10-08 12:34:22.814598 | controller |
2025-10-08 12:34:22.814745 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 12:34:22.814886 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.019)       0:00:16.543 *****
2025-10-08 12:34:22.842865 | controller | skipping: [instance]
2025-10-08 12:34:22.843002 | controller |
2025-10-08 12:34:22.843220 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 12:34:22.843356 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.028)       0:00:16.572 *****
2025-10-08 12:34:22.871747 | controller | skipping: [instance]
2025-10-08 12:34:22.900138 | controller |
2025-10-08 12:34:22.900181 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 12:34:22.900190 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.028)       0:00:16.600 *****
2025-10-08 12:34:22.900200 | controller | skipping: [instance]
2025-10-08 12:34:22.900515 | controller |
2025-10-08 12:34:22.929363 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 12:34:22.929391 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.028)       0:00:16.629 *****
2025-10-08 12:34:22.929403 | controller | skipping: [instance]
2025-10-08 12:35:05.860111 | controller |
2025-10-08 12:35:05.860163 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 12:35:05.860172 | controller | Wednesday 08 October 2025  12:34:22 +0000 (0:00:00.028)       0:00:16.658 *****
2025-10-08 12:35:05.860186 | controller | ok: [instance]
2025-10-08 12:35:07.028367 | controller |
2025-10-08 12:35:07.028404 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 12:35:07.028413 | controller | Wednesday 08 October 2025  12:35:05 +0000 (0:00:42.930)       0:00:59.589 *****
2025-10-08 12:35:07.028425 | controller | ok: [instance]
2025-10-08 12:35:07.076252 | controller |
2025-10-08 12:35:07.076286 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 12:35:07.076295 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:01.168)       0:01:00.757 *****
2025-10-08 12:35:07.076305 | controller | skipping: [instance]
2025-10-08 12:35:07.133149 | controller |
2025-10-08 12:35:07.133181 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:35:07.133190 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-08 12:35:07.133196 | controller |
2025-10-08 12:35:07.133202 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.045)       0:01:00.802 *****
2025-10-08 12:35:07.133207 | controller | ===============================================================================
2025-10-08 12:35:07.133213 | controller | test_deps : Install selinux python libs -------------------------------- 42.93s
2025-10-08 12:35:07.133224 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.97s
2025-10-08 12:35:07.133230 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.20s
2025-10-08 12:35:07.133236 | controller | test_deps : Install python yaml libs ------------------------------------ 1.17s
2025-10-08 12:35:07.133246 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-10-08 12:35:07.133252 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2025-10-08 12:35:07.133257 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.78s
2025-10-08 12:35:07.133263 | controller | repo_setup : Install repo-setup package --------------------------------- 0.73s
2025-10-08 12:35:07.133268 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-10-08 12:35:07.133273 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-08 12:35:07.133278 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-08 12:35:07.133284 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
2025-10-08 12:35:07.133289 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-08 12:35:07.133294 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.29s
2025-10-08 12:35:07.133299 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.21s
2025-10-08 12:35:07.133305 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2025-10-08 12:35:07.133310 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-10-08 12:35:07.133315 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-08 12:35:07.133321 | controller | test_deps : Install extra packages -------------------------------------- 0.05s
2025-10-08 12:35:07.133326 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-10-08 12:35:07.133336 | controller | INFO     Running default > converge
2025-10-08 12:35:07.563189 | controller |
2025-10-08 12:35:07.563615 | controller | PLAY [Converge] ****************************************************************
2025-10-08 12:35:07.563635 | controller |
2025-10-08 12:35:07.593582 | controller | TASK [Include os_net_setup] ****************************************************
2025-10-08 12:35:07.593614 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.045)       0:00:00.045 *****
2025-10-08 12:35:07.593628 | controller |
2025-10-08 12:35:07.624196 | controller | TASK [os_net_setup : Delete existing subnets] **********************************
2025-10-08 12:35:07.624227 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.030)       0:00:00.076 *****
2025-10-08 12:35:07.624240 | controller | skipping: [instance] => (item=[{'name': 'public', 'external': True, 'shared': False, 'is_default': True, 'provider_network_type': 'flat', 'provider_physical_network': 'datacentre', 'availability_zone_hints': [], 'subnets': [{'name': 'public_subnet', 'cidr': '192.168.122.0/24', 'allocation_pool_start': '192.168.122.171', 'allocation_pool_end': '192.168.122.250', 'gateway_ip': '192.168.122.1', 'enable_dhcp': True}]}, {'name': 'public_subnet', 'cidr': '192.168.122.0/24', 'allocation_pool_start': '192.168.122.171', 'allocation_pool_end': '192.168.122.250', 'gateway_ip': '192.168.122.1', 'enable_dhcp': True}])
2025-10-08 12:35:07.624266 | controller | skipping: [instance]
2025-10-08 12:35:07.624588 | controller |
2025-10-08 12:35:07.659180 | controller | TASK [os_net_setup : Delete existing subnet pools] *****************************
2025-10-08 12:35:07.659217 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.030)       0:00:00.106 *****
2025-10-08 12:35:07.659230 | controller | skipping: [instance] => (item={'name': 'shared-pool-ipv4', 'default_prefix_length': 26, 'prefixes': '10.1.0.0/20', 'is_default': True, 'is_shared': True})
2025-10-08 12:35:07.659257 | controller | skipping: [instance] => (item={'name': 'shared-pool-ipv6', 'default_prefix_length': 64, 'prefixes': 'fdfe:381f:8400::/56', 'is_default': True, 'is_shared': True})
2025-10-08 12:35:07.659677 | controller | skipping: [instance]
2025-10-08 12:35:07.691234 | controller |
2025-10-08 12:35:07.691286 | controller | TASK [os_net_setup : Delete existing networks] *********************************
2025-10-08 12:35:07.691306 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.034)       0:00:00.141 *****
2025-10-08 12:35:07.691323 | controller | skipping: [instance] => (item={'name': 'public', 'external': True, 'shared': False, 'is_default': True, 'provider_network_type': 'flat', 'provider_physical_network': 'datacentre', 'availability_zone_hints': [], 'subnets': [{'name': 'public_subnet', 'cidr': '192.168.122.0/24', 'allocation_pool_start': '192.168.122.171', 'allocation_pool_end': '192.168.122.250', 'gateway_ip': '192.168.122.1', 'enable_dhcp': True}]})
2025-10-08 12:35:07.743207 | controller | skipping: [instance]
2025-10-08 12:35:07.743244 | controller |
2025-10-08 12:35:07.743255 | controller | TASK [os_net_setup : Print network creation commands] **************************
2025-10-08 12:35:07.743264 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.031)       0:00:00.173 *****
2025-10-08 12:35:07.743277 | controller | ok: [instance] =>
2025-10-08 12:35:07.743309 | controller |   msg: |-
2025-10-08 12:35:07.743318 | controller |     set -euo pipefail
2025-10-08 12:35:07.743326 | controller |     oc exec -n openstack openstackclient -- openstack network create \
2025-10-08 12:35:07.743333 | controller |         --external \
2025-10-08 12:35:07.743340 | controller |         --default \
2025-10-08 12:35:07.743347 | controller |         --provider-network-type flat \
2025-10-08 12:35:07.743354 | controller |         --provider-physical-network datacentre \
2025-10-08 12:35:07.743361 | controller |         --no-share \
2025-10-08 12:35:07.743368 | controller |         public
2025-10-08 12:35:07.743377 | controller |
2025-10-08 12:35:07.743405 | controller | TASK [os_net_setup : Create networks] ******************************************
2025-10-08 12:35:07.743565 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.051)       0:00:00.224 *****
2025-10-08 12:35:07.771198 | controller | skipping: [instance]
2025-10-08 12:35:07.771288 | controller |
2025-10-08 12:35:07.771299 | controller | TASK [os_net_setup : Print subnet command creation] ****************************
2025-10-08 12:35:07.771309 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.028)       0:00:00.253 *****
2025-10-08 12:35:07.831289 | controller | ok: [instance] =>
2025-10-08 12:35:07.831377 | controller |   msg: |-
2025-10-08 12:35:07.831390 | controller |     set -euo pipefail
2025-10-08 12:35:07.831398 | controller |     oc exec -n openstack openstackclient -- openstack subnet create \
2025-10-08 12:35:07.831405 | controller |         --allocation-pool start=192.168.122.171,end=192.168.122.250 \
2025-10-08 12:35:07.831410 | controller |         --subnet-range 192.168.122.0/24 \
2025-10-08 12:35:07.831417 | controller |         --gateway 192.168.122.1 \
2025-10-08 12:35:07.831436 | controller |         --network public \
2025-10-08 12:35:07.831461 | controller |         public_subnet
2025-10-08 12:35:07.831470 | controller |
2025-10-08 12:35:07.831701 | controller | TASK [os_net_setup : Create subnets] *******************************************
2025-10-08 12:35:07.859113 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.059)       0:00:00.313 *****
2025-10-08 12:35:07.859156 | controller | skipping: [instance]
2025-10-08 12:35:07.898265 | controller |
2025-10-08 12:35:07.898325 | controller | TASK [os_net_setup : Print subnet pools command creation] **********************
2025-10-08 12:35:07.898335 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.028)       0:00:00.341 *****
2025-10-08 12:35:07.898352 | controller | ok: [instance] =>
2025-10-08 12:35:07.898385 | controller |   msg: |-
2025-10-08 12:35:07.898402 | controller |     set -euo pipefail
2025-10-08 12:35:07.898410 | controller |     oc exec -n openstack openstackclient -- openstack subnet pool create \
2025-10-08 12:35:07.898418 | controller |         --default-prefix-length 26 \
2025-10-08 12:35:07.898428 | controller |         --pool-prefix 10.1.0.0/20 \
2025-10-08 12:35:07.898455 | controller |         --default \
2025-10-08 12:35:07.898464 | controller |         --share \
2025-10-08 12:35:07.899159 | controller |         shared-pool-ipv4
2025-10-08 12:35:07.899534 | controller |     oc exec -n openstack openstackclient -- openstack subnet pool create \
2025-10-08 12:35:07.899569 | controller |         --default-prefix-length 64 \
2025-10-08 12:35:07.899578 | controller |         --pool-prefix fdfe:381f:8400::/56 \
2025-10-08 12:35:07.899592 | controller |         --default \
2025-10-08 12:35:07.899599 | controller |         --share \
2025-10-08 12:35:07.899605 | controller |         shared-pool-ipv6
2025-10-08 12:35:07.899616 | controller |
2025-10-08 12:35:07.948180 | controller | TASK [os_net_setup : Create subnet pools] **************************************
2025-10-08 12:35:07.948217 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.038)       0:00:00.380 *****
2025-10-08 12:35:07.948230 | controller | skipping: [instance]
2025-10-08 12:35:07.948349 | controller |
2025-10-08 12:35:07.948361 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:35:07.948367 | controller | instance                   : ok=3    changed=0    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-10-08 12:35:07.948373 | controller |
2025-10-08 12:35:07.948379 | controller | Wednesday 08 October 2025  12:35:07 +0000 (0:00:00.049)       0:00:00.429 *****
2025-10-08 12:35:07.948384 | controller | ===============================================================================
2025-10-08 12:35:07.948390 | controller | os_net_setup : Print subnet command creation ---------------------------- 0.06s
2025-10-08 12:35:07.948395 | controller | os_net_setup : Print network creation commands -------------------------- 0.05s
2025-10-08 12:35:07.948401 | controller | os_net_setup : Create subnet pools -------------------------------------- 0.05s
2025-10-08 12:35:07.948406 | controller | os_net_setup : Print subnet pools command creation ---------------------- 0.04s
2025-10-08 12:35:07.948414 | controller | os_net_setup : Delete existing subnet pools ----------------------------- 0.03s
2025-10-08 12:35:07.948569 | controller | os_net_setup : Delete existing networks --------------------------------- 0.03s
2025-10-08 12:35:07.948709 | controller | os_net_setup : Delete existing subnets ---------------------------------- 0.03s
2025-10-08 12:35:07.948848 | controller | Include os_net_setup ---------------------------------------------------- 0.03s
2025-10-08 12:35:07.948984 | controller | os_net_setup : Create subnets ------------------------------------------- 0.03s
2025-10-08 12:35:07.949161 | controller | os_net_setup : Create networks ------------------------------------------ 0.03s
2025-10-08 12:35:08.003706 | controller | INFO     Running default > cleanup
2025-10-08 12:35:08.004291 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 12:35:08.004898 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 12:35:08.538792 | controller | changed
2025-10-08 12:35:08.561275 | 
2025-10-08 12:35:08.561355 | PLAY RECAP
2025-10-08 12:35:08.561395 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:35:08.561418 | 
2025-10-08 12:35:08.655793 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:35:08.656641 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:35:09.209107 | 
2025-10-08 12:35:09.209212 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 12:35:09.230314 | 
2025-10-08 12:35:09.230508 | TASK [Filter out host if needed]
2025-10-08 12:35:09.239214 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 12:35:09.243768 | 
2025-10-08 12:35:09.243838 | TASK [Ensure file is present]
2025-10-08 12:35:09.566139 | controller | ok
2025-10-08 12:35:09.573543 | 
2025-10-08 12:35:09.573613 | TASK [Manage molecule report file]
2025-10-08 12:35:10.099385 | controller | changed
2025-10-08 12:35:10.106691 | 
2025-10-08 12:35:10.106895 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 12:35:10.299315 | controller | ok
2025-10-08 12:35:10.305402 | 
2025-10-08 12:35:10.305473 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 12:35:10.617477 | controller | changed
2025-10-08 12:35:10.622602 | 
2025-10-08 12:35:10.622669 | TASK [Copy ci-framework interesting files]
2025-10-08 12:35:10.818420 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 12:35:11.148580 | controller | changed
2025-10-08 12:35:11.154454 | 
2025-10-08 12:35:11.154528 | TASK [Get SELinux listing]
2025-10-08 12:35:11.681351 | controller | changed
2025-10-08 12:35:11.687257 | 
2025-10-08 12:35:11.687343 | TASK [Generate log index]
2025-10-08 12:35:12.329238 | controller | changed
2025-10-08 12:35:12.335001 | 
2025-10-08 12:35:12.335073 | TASK [Get some env related data]
2025-10-08 12:35:12.876323 | controller | /home/zuul/.local/bin/ansible
2025-10-08 12:35:13.866581 | controller | changed
2025-10-08 12:35:13.872190 | 
2025-10-08 12:35:13.872275 | TASK [Generate list of logs to collect in home directory]
2025-10-08 12:35:14.189701 | controller | ok: All paths examined
2025-10-08 12:35:14.202275 | 
2025-10-08 12:35:14.202348 | LOOP [Copy logs from home directory]
2025-10-08 12:35:14.588257 | controller | changed:
2025-10-08 12:35:14.588459 | controller | {
2025-10-08 12:35:14.588492 | controller |   "atime": 1759926809.5636792,
2025-10-08 12:35:14.588514 | controller |   "ctime": 1759926825.7388215,
2025-10-08 12:35:14.588532 | controller |   "dev": 64513,
2025-10-08 12:35:14.588549 | controller |   "gid": 1000,
2025-10-08 12:35:14.588565 | controller |   "gr_name": "zuul",
2025-10-08 12:35:14.588582 | controller |   "inode": 4449914,
2025-10-08 12:35:14.588598 | controller |   "isblk": false,
2025-10-08 12:35:14.588614 | controller |   "ischr": false,
2025-10-08 12:35:14.588628 | controller |   "isdir": false,
2025-10-08 12:35:14.588642 | controller |   "isfifo": false,
2025-10-08 12:35:14.588658 | controller |   "isgid": false,
2025-10-08 12:35:14.588672 | controller |   "islnk": false,
2025-10-08 12:35:14.588686 | controller |   "isreg": true,
2025-10-08 12:35:14.588700 | controller |   "issock": false,
2025-10-08 12:35:14.588714 | controller |   "isuid": false,
2025-10-08 12:35:14.588727 | controller |   "mode": "0644",
2025-10-08 12:35:14.588741 | controller |   "mtime": 1759926825.7388215,
2025-10-08 12:35:14.588754 | controller |   "nlink": 1,
2025-10-08 12:35:14.588768 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 12:35:14.588784 | controller |   "pw_name": "zuul",
2025-10-08 12:35:14.588800 | controller |   "rgrp": true,
2025-10-08 12:35:14.588816 | controller |   "roth": true,
2025-10-08 12:35:14.588831 | controller |   "rusr": true,
2025-10-08 12:35:14.588845 | controller |   "size": 6749,
2025-10-08 12:35:14.588865 | controller |   "uid": 1000,
2025-10-08 12:35:14.588880 | controller |   "wgrp": false,
2025-10-08 12:35:14.588894 | controller |   "woth": false,
2025-10-08 12:35:14.589090 | controller |   "wusr": true,
2025-10-08 12:35:14.589118 | controller |   "xgrp": false,
2025-10-08 12:35:14.589137 | controller |   "xoth": false,
2025-10-08 12:35:14.589154 | controller |   "xusr": false
2025-10-08 12:35:14.589170 | controller | }
2025-10-08 12:35:14.600874 | 
2025-10-08 12:35:14.601003 | TASK [Copy crio stats log file]
2025-10-08 12:35:14.635169 | controller | skipping: Conditional result was False
2025-10-08 12:35:14.640870 | 
2025-10-08 12:35:14.640979 | TASK [Get SELinux related data]
2025-10-08 12:35:14.855350 | controller | <no matches>
2025-10-08 12:35:15.168819 | controller | ERROR
2025-10-08 12:35:15.168985 | controller | {
2025-10-08 12:35:15.169029 | controller |   "delta": "0:00:00.016370",
2025-10-08 12:35:15.169052 | controller |   "end": "2025-10-08 12:35:14.858887",
2025-10-08 12:35:15.169078 | controller |   "msg": "non-zero return code",
2025-10-08 12:35:15.169113 | controller |   "rc": 1,
2025-10-08 12:35:15.169133 | controller |   "start": "2025-10-08 12:35:14.842517"
2025-10-08 12:35:15.169150 | controller | }
2025-10-08 12:35:15.169173 | controller | ERROR: Ignoring Errors
2025-10-08 12:35:15.175576 | 
2025-10-08 12:35:15.175659 | TASK [Create system configuration directory]
2025-10-08 12:35:15.368558 | controller | changed
2025-10-08 12:35:15.373665 | 
2025-10-08 12:35:15.373738 | TASK [Get some of the system configurations]
2025-10-08 12:35:15.903438 | controller | changed
2025-10-08 12:35:15.908976 | 
2025-10-08 12:35:15.909057 | TASK [Copy generated documentation if available]
2025-10-08 12:35:15.932882 | controller | skipping: Conditional result was False
2025-10-08 12:35:15.938698 | 
2025-10-08 12:35:15.938816 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 12:35:15.953570 | controller | skipping: Conditional result was False
2025-10-08 12:35:15.960624 | 
2025-10-08 12:35:15.960737 | TASK [Compress logs bigger than 2MB]
2025-10-08 12:35:16.488501 | controller | changed
2025-10-08 12:35:16.494188 | 
2025-10-08 12:35:16.494312 | TASK [Copy files from workspace on node]
2025-10-08 12:35:16.513316 | controller | ok
2025-10-08 12:35:16.541429 | 
2025-10-08 12:35:16.541525 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:35:16.566102 | controller | skipping: Conditional result was False
2025-10-08 12:35:16.572570 | 
2025-10-08 12:35:16.572677 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:35:16.605367 | controller | ok
2025-10-08 12:35:16.610971 | 
2025-10-08 12:35:16.611069 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:35:16.887891 | controller -> localhost | ok: "/var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/logs"
2025-10-08 12:35:16.888187 | controller -> localhost | changed: All items complete
2025-10-08 12:35:16.888220 | 
2025-10-08 12:35:17.087497 | controller -> localhost | changed: "/var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/artifacts"
2025-10-08 12:35:17.305660 | controller -> localhost | changed: "/var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/docs"
2025-10-08 12:35:17.318808 | 
2025-10-08 12:35:17.318934 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:35:17.848259 | controller | changed:
2025-10-08 12:35:17.848455 | controller | .d..t...... ./
2025-10-08 12:35:17.848486 | controller | >f+++++++++ README.html
2025-10-08 12:35:17.848510 | controller | >f+++++++++ ansible-execution.log
2025-10-08 12:35:17.848534 | controller | >f+++++++++ ansible.log
2025-10-08 12:35:17.848555 | controller | >f+++++++++ dmesg.log
2025-10-08 12:35:17.848574 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 12:35:17.848595 | controller | >f+++++++++ python.log
2025-10-08 12:35:17.848616 | controller | >f+++++++++ registries.conf
2025-10-08 12:35:17.848635 | controller | >f+++++++++ report.html
2025-10-08 12:35:17.848656 | controller | >f+++++++++ selinux-denials.log
2025-10-08 12:35:17.848680 | controller | >f+++++++++ selinux-listing.log
2025-10-08 12:35:17.848700 | controller | cd+++++++++ ci-framework-data/
2025-10-08 12:35:17.848718 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 12:35:17.848736 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 12:35:17.848755 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 12:35:17.848772 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 12:35:17.848790 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-08 12:35:17.848806 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-08 12:35:17.848822 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 12:35:17.848839 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 12:35:17.848855 | controller | cd+++++++++ registries.conf.d/
2025-10-08 12:35:17.848871 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 12:35:17.848888 | controller | cd+++++++++ system-config/
2025-10-08 12:35:17.848905 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 12:35:17.848966 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 12:35:17.848986 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 12:35:18.222153 | controller | changed: .d..t...... ./
2025-10-08 12:35:18.582260 | controller | changed: .d..t...... ./
2025-10-08 12:35:18.596692 | 
2025-10-08 12:35:18.596805 | TASK [Return artifact to Zuul]
2025-10-08 12:35:18.625279 | controller | ok
2025-10-08 12:35:18.644028 | 
2025-10-08 12:35:18.644093 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 12:35:18.644203 | 
2025-10-08 12:35:18.644237 | PLAY RECAP
2025-10-08 12:35:18.644280 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 12:35:18.644308 | 
2025-10-08 12:35:18.721717 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:35:18.722532 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:35:19.268875 | 
2025-10-08 12:35:19.269019 | PLAY [all]
2025-10-08 12:35:19.296203 | 
2025-10-08 12:35:19.296364 | TASK [include_role : fetch-output]
2025-10-08 12:35:19.347014 | controller | ok
2025-10-08 12:35:19.373471 | 
2025-10-08 12:35:19.373616 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:35:19.428589 | controller | skipping: Conditional result was False
2025-10-08 12:35:19.434669 | 
2025-10-08 12:35:19.434751 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:35:19.473438 | controller | ok
2025-10-08 12:35:19.479434 | 
2025-10-08 12:35:19.479517 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:35:19.811538 | controller -> localhost | ok: "/var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/logs"
2025-10-08 12:35:20.000103 | controller -> localhost | ok: "/var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/artifacts"
2025-10-08 12:35:20.211811 | controller -> localhost | ok: "/var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/docs"
2025-10-08 12:35:20.223198 | 
2025-10-08 12:35:20.223312 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:35:20.757011 | controller | ok
2025-10-08 12:35:20.757214 | controller | ok: All items complete
2025-10-08 12:35:20.757252 | 
2025-10-08 12:35:21.149305 | controller | ok
2025-10-08 12:35:21.537831 | controller | ok
2025-10-08 12:35:21.569184 | 
2025-10-08 12:35:21.569305 | TASK [include_role : fetch-output-openshift]
2025-10-08 12:35:21.597267 | controller | skipping: Conditional result was False
2025-10-08 12:35:21.605209 | 
2025-10-08 12:35:21.605314 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 12:35:22.009139 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006741
2025-10-08 12:35:22.233261 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008517
2025-10-08 12:35:22.266475 | 
2025-10-08 12:35:22.266604 | PLAY [all]
2025-10-08 12:35:22.280978 | 
2025-10-08 12:35:22.281049 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 12:35:22.678318 | controller | changed
2025-10-08 12:35:22.704116 | 
2025-10-08 12:35:22.704214 | PLAY RECAP
2025-10-08 12:35:22.704262 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 12:35:22.704287 | 
2025-10-08 12:35:22.793312 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:35:22.794119 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 12:35:23.442295 | 
2025-10-08 12:35:23.442397 | PLAY [localhost]
2025-10-08 12:35:23.462469 | 
2025-10-08 12:35:23.462566 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 12:35:23.809073 | localhost | changed
2025-10-08 12:35:23.813862 | 
2025-10-08 12:35:23.813976 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 12:35:23.841889 | localhost | ok
2025-10-08 12:35:23.850351 | 
2025-10-08 12:35:23.850432 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 12:35:24.192850 | localhost | changed
2025-10-08 12:35:24.198599 | 
2025-10-08 12:35:24.198673 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 12:35:24.775519 | localhost | changed
2025-10-08 12:35:24.780517 | 
2025-10-08 12:35:24.780604 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 12:35:25.134884 | localhost | Identity added: /var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/tmp/ansible.1a5s_vxu (/var/lib/zuul/builds/39d526651a3945a18db829f158b70865/work/tmp/ansible.1a5s_vxu)
2025-10-08 12:35:25.135105 | localhost | ok: Runtime: 0:00:00.006552
2025-10-08 12:35:25.140165 | 
2025-10-08 12:35:25.140322 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 12:35:25.382858 | localhost | ok: Runtime: 0:00:00.004618
2025-10-08 12:35:25.387824 | 
2025-10-08 12:35:25.387894 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 12:35:25.437779 | localhost | changed
2025-10-08 12:35:25.442502 | 
2025-10-08 12:35:25.442574 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 12:35:25.785814 | localhost | changed
2025-10-08 12:35:25.806854 | 
2025-10-08 12:35:25.807029 | PLAY [localhost]
2025-10-08 12:35:25.820669 | 
2025-10-08 12:35:25.820733 | TASK [Generate bulk log download script]
2025-10-08 12:35:25.838985 | localhost | ok
2025-10-08 12:35:25.858835 | 
2025-10-08 12:35:25.858945 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 12:35:25.898045 | localhost | ok: All assertions passed
2025-10-08 12:35:25.903368 | 
2025-10-08 12:35:25.903437 | TASK [local-log-download : Create download script]
2025-10-08 12:35:26.341477 | localhost -> localhost | changed
2025-10-08 12:35:26.350728 | 
2025-10-08 12:35:26.350797 | TASK [Register quick-download link]
2025-10-08 12:35:26.369225 | localhost | ok
2025-10-08 12:35:26.405357 | 
2025-10-08 12:35:26.405502 | PLAY [logserver.rdoproject.org]
2025-10-08 12:35:26.415448 | 
2025-10-08 12:35:26.415508 | TASK [Set zuul-log-path fact]
2025-10-08 12:35:26.431677 | logserver.rdoproject.org | ok
2025-10-08 12:35:26.443491 | 
2025-10-08 12:35:26.443567 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:35:26.470070 | logserver.rdoproject.org | ok
2025-10-08 12:35:26.476062 | 
2025-10-08 12:35:26.476137 | TASK [upload-logs : Create log directories]
2025-10-08 12:35:28.268493 | logserver.rdoproject.org | changed
2025-10-08 12:35:28.271997 | 
2025-10-08 12:35:28.272068 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 12:35:28.550775 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.011985
2025-10-08 12:35:28.556341 | 
2025-10-08 12:35:28.556519 | TASK [upload-logs : Upload logs to log server]
2025-10-08 12:35:30.032469 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 12:35:30.037235 | 
2025-10-08 12:35:30.037304 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 12:35:30.081846 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:35:30.085267 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:35:30.096063 | 
2025-10-08 12:35:30.096180 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 12:35:30.136508 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:35:30.136771 | 
2025-10-08 12:35:30.138851 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:35:30.144495 | 
2025-10-08 12:35:30.144576 | LOOP [upload-logs : Upload console log and json output]