2025-12-01 09:02:03.857964 | Job console starting...
2025-12-01 09:02:04.068736 | Updating repositories
2025-12-01 09:02:04.861042 | Preparing job workspace
2025-12-01 09:02:11.943807 | Running Ansible setup...
2025-12-01 09:02:15.361993 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 09:02:15.873226 | 
2025-12-01 09:02:15.873326 | PLAY [localhost]
2025-12-01 09:02:15.881203 | 
2025-12-01 09:02:15.881276 | TASK [Gathering Facts]
2025-12-01 09:02:16.675578 | localhost | ok
2025-12-01 09:02:16.689108 | 
2025-12-01 09:02:16.689212 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 09:02:16.987169 | localhost -> localhost | changed
2025-12-01 09:02:16.992629 | 
2025-12-01 09:02:16.992706 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 09:02:17.644765 | localhost -> localhost | changed
2025-12-01 09:02:17.653106 | 
2025-12-01 09:02:17.653252 | TASK [Setup log path fact]
2025-12-01 09:02:17.670465 | localhost | ok
2025-12-01 09:02:17.681489 | 
2025-12-01 09:02:17.681563 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 09:02:17.699266 | localhost | ok
2025-12-01 09:02:17.706526 | 
2025-12-01 09:02:17.706597 | TASK [emit-job-header : Print job information]
2025-12-01 09:02:17.733586 | # Job Information
2025-12-01 09:02:17.733705 | Ansible Version: 2.15.12
2025-12-01 09:02:17.733736 | Job: cifmw-molecule-reproducer
2025-12-01 09:02:17.733759 | Pipeline: github-check
2025-12-01 09:02:17.733779 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-01 09:02:17.733798 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-01 09:02:17.733818 | Log URL (when completed): https://logserver.rdoproject.org/898/rdoproject.org/898b614aca654111b842515d9cbe567b/
2025-12-01 09:02:17.733837 | Event ID: 44f1e2f0-ce94-11f0-8bee-9ee9a0e8ee70
2025-12-01 09:02:17.737626 | 
2025-12-01 09:02:17.737698 | LOOP [emit-job-header : Print node information]
2025-12-01 09:02:17.821264 | localhost | ok:
2025-12-01 09:02:17.821428 | localhost | # Node Information
2025-12-01 09:02:17.821456 | localhost | Inventory Hostname: controller
2025-12-01 09:02:17.821479 | localhost | Hostname: np0005540818
2025-12-01 09:02:17.821499 | localhost | Username: zuul
2025-12-01 09:02:17.821520 | localhost | Distro: CentOS 9
2025-12-01 09:02:17.821538 | localhost | Provider: ibm-bm4-nodepool
2025-12-01 09:02:17.821555 | localhost | Region: regionOne
2025-12-01 09:02:17.821571 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-12-01 09:02:17.821588 | localhost | Product Name: OpenStack Compute
2025-12-01 09:02:17.821603 | localhost | Interface IP: 192.168.26.37
2025-12-01 09:02:17.839130 | 
2025-12-01 09:02:17.839196 | PLAY [all]
2025-12-01 09:02:17.845063 | 
2025-12-01 09:02:17.845127 | TASK [Gather network facts]
2025-12-01 09:02:18.239819 | controller | ok
2025-12-01 09:02:18.253641 | 
2025-12-01 09:02:18.253725 | TASK [include_role : start-zuul-console]
2025-12-01 09:02:18.271554 | controller | ok
2025-12-01 09:02:18.283509 | 
2025-12-01 09:02:18.283578 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 09:02:18.626345 | controller | ok
2025-12-01 09:02:18.635037 | 
2025-12-01 09:02:18.635104 | TASK [include_role : add-build-sshkey]
2025-12-01 09:02:18.652604 | controller | ok
2025-12-01 09:02:18.665211 | 
2025-12-01 09:02:18.665277 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 09:02:18.851253 | controller -> localhost | ok
2025-12-01 09:02:18.857249 | 
2025-12-01 09:02:18.857341 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 09:02:18.875169 | controller | ok
2025-12-01 09:02:18.888599 | controller | included: /var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 09:02:18.894028 | 
2025-12-01 09:02:18.894092 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 09:02:19.261520 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 09:02:19.261733 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/898b614aca654111b842515d9cbe567b_id_rsa.
2025-12-01 09:02:19.261768 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/898b614aca654111b842515d9cbe567b_id_rsa.pub.
2025-12-01 09:02:19.261803 | controller -> localhost | The key fingerprint is:
2025-12-01 09:02:19.261827 | controller -> localhost | SHA256:qVRpJqvXTbhKddU+IECVFVlmG+w8NnGm4EO4t5NSEJU zuul-build-sshkey
2025-12-01 09:02:19.261847 | controller -> localhost | The key's randomart image is:
2025-12-01 09:02:19.261866 | controller -> localhost | +---[RSA 3072]----+
2025-12-01 09:02:19.261884 | controller -> localhost | |       .o.o*+*=  |
2025-12-01 09:02:19.261925 | controller -> localhost | |         o+ E+ooo|
2025-12-01 09:02:19.261951 | controller -> localhost | |      . = .=o+o= |
2025-12-01 09:02:19.261971 | controller -> localhost | |       * o.o=oB  |
2025-12-01 09:02:19.261989 | controller -> localhost | |      o S oo =oo |
2025-12-01 09:02:19.262016 | controller -> localhost | |     o + =. +  . |
2025-12-01 09:02:19.262035 | controller -> localhost | |    . + o .. .   |
2025-12-01 09:02:19.262055 | controller -> localhost | |     o .         |
2025-12-01 09:02:19.262072 | controller -> localhost | |      .          |
2025-12-01 09:02:19.262088 | controller -> localhost | +----[SHA256]-----+
2025-12-01 09:02:19.262132 | controller -> localhost | ok: Runtime: 0:00:00.042647
2025-12-01 09:02:19.267756 | 
2025-12-01 09:02:19.267823 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 09:02:19.286100 | controller | ok
2025-12-01 09:02:19.298072 | controller | included: /var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 09:02:19.305633 | 
2025-12-01 09:02:19.305699 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 09:02:19.319087 | controller | skipping: Conditional result was False
2025-12-01 09:02:19.324971 | 
2025-12-01 09:02:19.325052 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 09:02:19.770229 | controller | changed
2025-12-01 09:02:19.774960 | 
2025-12-01 09:02:19.775042 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 09:02:20.015730 | controller | ok
2025-12-01 09:02:20.020526 | 
2025-12-01 09:02:20.020594 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 09:02:21.051782 | controller | changed
2025-12-01 09:02:21.056795 | 
2025-12-01 09:02:21.056888 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 09:02:22.149427 | controller | changed
2025-12-01 09:02:22.154224 | 
2025-12-01 09:02:22.154292 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 09:02:22.167876 | controller | skipping: Conditional result was False
2025-12-01 09:02:22.173497 | 
2025-12-01 09:02:22.173565 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 09:02:22.463134 | controller -> localhost | changed
2025-12-01 09:02:22.473065 | 
2025-12-01 09:02:22.473151 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 09:02:22.710532 | controller -> localhost | Identity added: /var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/898b614aca654111b842515d9cbe567b_id_rsa (zuul-build-sshkey)
2025-12-01 09:02:22.710750 | controller -> localhost | ok: Runtime: 0:00:00.021531
2025-12-01 09:02:22.715942 | 
2025-12-01 09:02:22.716016 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 09:02:23.070037 | controller | ok
2025-12-01 09:02:23.074624 | 
2025-12-01 09:02:23.074690 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 09:02:23.098350 | controller | skipping: Conditional result was False
2025-12-01 09:02:23.108076 | 
2025-12-01 09:02:23.108142 | TASK [include_role : validate-host]
2025-12-01 09:02:23.125488 | controller | ok
2025-12-01 09:02:23.148392 | 
2025-12-01 09:02:23.148463 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-01 09:02:23.176319 | controller | ok
2025-12-01 09:02:23.180753 | 
2025-12-01 09:02:23.180819 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-01 09:02:23.376419 | controller -> localhost | ok
2025-12-01 09:02:23.381947 | 
2025-12-01 09:02:23.382023 | TASK [validate-host : Collect information about the host]
2025-12-01 09:02:24.069263 | controller | ok
2025-12-01 09:02:24.076863 | 
2025-12-01 09:02:24.076939 | TASK [validate-host : Sanitize hostname]
2025-12-01 09:02:24.114783 | controller | ok
2025-12-01 09:02:24.119343 | 
2025-12-01 09:02:24.119405 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-01 09:02:24.477459 | controller -> localhost | changed
2025-12-01 09:02:24.482784 | 
2025-12-01 09:02:24.482869 | TASK [validate-host : Collect information about zuul worker]
2025-12-01 09:02:24.855350 | controller | ok
2025-12-01 09:02:24.860019 | 
2025-12-01 09:02:24.860094 | TASK [validate-host : Write out all zuul information for each host]
2025-12-01 09:02:25.216653 | controller -> localhost | changed
2025-12-01 09:02:25.226744 | 
2025-12-01 09:02:25.226817 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 09:02:25.240061 | controller | skipping: Conditional result was False
2025-12-01 09:02:25.244082 | 
2025-12-01 09:02:25.244141 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 09:02:25.256469 | controller | skipping: Conditional result was False
2025-12-01 09:02:25.261732 | 
2025-12-01 09:02:25.261799 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 09:02:25.499290 | controller | ok: "logs"
2025-12-01 09:02:25.499530 | controller | ok: All items complete
2025-12-01 09:02:25.499559 | 
2025-12-01 09:02:25.719252 | controller | ok: "artifacts"
2025-12-01 09:02:25.945027 | controller | ok: "docs"
2025-12-01 09:02:25.950943 | 
2025-12-01 09:02:25.951057 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 09:02:26.211891 | controller | changed: "logs"
2025-12-01 09:02:26.457318 | controller | changed: "artifacts"
2025-12-01 09:02:26.698787 | controller | changed: "docs"
2025-12-01 09:02:26.725351 | 
2025-12-01 09:02:26.725439 | PLAY RECAP
2025-12-01 09:02:26.725484 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 09:02:26.725512 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 09:02:26.725532 | 
2025-12-01 09:02:26.802417 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 09:02:26.803137 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 09:02:27.321146 | 
2025-12-01 09:02:27.321247 | PLAY [all]
2025-12-01 09:02:27.339148 | 
2025-12-01 09:02:27.339218 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 09:02:27.387447 | controller | ok
2025-12-01 09:02:27.391873 | 
2025-12-01 09:02:27.391961 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 09:02:27.795397 | controller | changed
2025-12-01 09:02:27.800847 | 
2025-12-01 09:02:27.800935 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 09:02:29.081118 | controller | changed
2025-12-01 09:02:29.090631 | 
2025-12-01 09:02:29.090702 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-01 09:02:29.532761 | controller | changed:
2025-12-01 09:02:29.532935 | controller | {
2025-12-01 09:02:29.532974 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-01 09:02:29.533016 | controller | }
2025-12-01 09:02:29.821818 | controller | changed:
2025-12-01 09:02:29.821872 | controller | {
2025-12-01 09:02:29.821900 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-01 09:02:29.821943 | controller | }
2025-12-01 09:02:30.110457 | controller | changed:
2025-12-01 09:02:30.110507 | controller | {
2025-12-01 09:02:30.110535 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-01 09:02:30.110555 | controller | }
2025-12-01 09:02:30.404800 | controller | changed:
2025-12-01 09:02:30.404894 | controller | {
2025-12-01 09:02:30.404954 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-01 09:02:30.404979 | controller | }
2025-12-01 09:02:30.690855 | controller | changed:
2025-12-01 09:02:30.690906 | controller | {
2025-12-01 09:02:30.690961 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-01 09:02:30.690984 | controller | }
2025-12-01 09:02:30.977300 | controller | changed:
2025-12-01 09:02:30.977352 | controller | {
2025-12-01 09:02:30.977379 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-01 09:02:30.977399 | controller | }
2025-12-01 09:02:31.272807 | controller | changed:
2025-12-01 09:02:31.272859 | controller | {
2025-12-01 09:02:31.272884 | 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-12-01 09:02:31.272905 | controller | }
2025-12-01 09:02:31.563220 | controller | changed:
2025-12-01 09:02:31.563370 | controller | {
2025-12-01 09:02:31.563540 | 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-12-01 09:02:31.563600 | controller | }
2025-12-01 09:02:31.856008 | controller | changed:
2025-12-01 09:02:31.856357 | controller | {
2025-12-01 09:02:31.856406 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-01 09:02:31.856430 | controller | }
2025-12-01 09:02:32.185683 | controller | changed:
2025-12-01 09:02:32.185823 | controller | {
2025-12-01 09:02:32.185856 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-01 09:02:32.185879 | controller | }
2025-12-01 09:02:32.440660 | controller | changed:
2025-12-01 09:02:32.440746 | controller | {
2025-12-01 09:02:32.440773 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-01 09:02:32.440793 | controller | }
2025-12-01 09:02:32.736534 | controller | changed:
2025-12-01 09:02:32.736613 | controller | {
2025-12-01 09:02:32.736640 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-01 09:02:32.736661 | controller | }
2025-12-01 09:02:33.049539 | controller | changed:
2025-12-01 09:02:33.049598 | controller | {
2025-12-01 09:02:33.049623 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-01 09:02:33.049643 | controller | }
2025-12-01 09:02:33.347388 | controller | changed:
2025-12-01 09:02:33.347448 | controller | {
2025-12-01 09:02:33.347474 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-01 09:02:33.347494 | controller | }
2025-12-01 09:02:33.638276 | controller | changed:
2025-12-01 09:02:33.638357 | controller | {
2025-12-01 09:02:33.638382 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-01 09:02:33.638403 | controller | }
2025-12-01 09:02:33.939721 | controller | changed:
2025-12-01 09:02:33.939821 | controller | {
2025-12-01 09:02:33.939848 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-01 09:02:33.939869 | controller | }
2025-12-01 09:02:34.232191 | controller | changed:
2025-12-01 09:02:34.232339 | controller | {
2025-12-01 09:02:34.232431 | 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-12-01 09:02:34.232456 | controller | }
2025-12-01 09:02:34.518678 | controller | changed:
2025-12-01 09:02:34.518757 | controller | {
2025-12-01 09:02:34.518785 | 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-12-01 09:02:34.518817 | controller | }
2025-12-01 09:02:34.804899 | controller | changed:
2025-12-01 09:02:34.805061 | controller | {
2025-12-01 09:02:34.805091 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-01 09:02:34.805118 | controller | }
2025-12-01 09:02:35.102164 | controller | changed:
2025-12-01 09:02:35.102280 | controller | {
2025-12-01 09:02:35.102310 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-01 09:02:35.102333 | controller | }
2025-12-01 09:02:35.406132 | controller | changed:
2025-12-01 09:02:35.406255 | controller | {
2025-12-01 09:02:35.406285 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-01 09:02:35.406306 | controller | }
2025-12-01 09:02:35.702243 | controller | changed:
2025-12-01 09:02:35.702330 | controller | {
2025-12-01 09:02:35.702358 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-01 09:02:35.702380 | controller | }
2025-12-01 09:02:35.999166 | controller | changed:
2025-12-01 09:02:35.999272 | controller | {
2025-12-01 09:02:35.999300 | 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-12-01 09:02:35.999323 | controller | }
2025-12-01 09:02:36.289573 | controller | changed:
2025-12-01 09:02:36.289629 | controller | {
2025-12-01 09:02:36.289654 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-01 09:02:36.289673 | controller | }
2025-12-01 09:02:36.576061 | controller | changed:
2025-12-01 09:02:36.576183 | controller | {
2025-12-01 09:02:36.576210 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-01 09:02:36.576232 | controller | }
2025-12-01 09:02:36.869883 | controller | changed:
2025-12-01 09:02:36.869989 | controller | {
2025-12-01 09:02:36.870025 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-01 09:02:36.870045 | controller | }
2025-12-01 09:02:36.888107 | 
2025-12-01 09:02:36.888211 | TASK [Set timezone to UTC]
2025-12-01 09:02:37.395405 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-01 09:02:37.400166 | 
2025-12-01 09:02:37.400232 | TASK [Create nodepool directory]
2025-12-01 09:02:37.683375 | controller | changed
2025-12-01 09:02:37.688530 | 
2025-12-01 09:02:37.688607 | TASK [Create nodepool sub_nodes file]
2025-12-01 09:02:38.664504 | controller | changed
2025-12-01 09:02:38.669434 | 
2025-12-01 09:02:38.669505 | TASK [Create nodepool sub_nodes_private file]
2025-12-01 09:02:39.641028 | controller | changed
2025-12-01 09:02:39.646151 | 
2025-12-01 09:02:39.646231 | LOOP [Populate nodepool sub_nodes file]
2025-12-01 09:02:39.676495 | 
2025-12-01 09:02:39.676621 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-01 09:02:39.706318 | 
2025-12-01 09:02:39.706451 | TASK [Create nodepool primary file]
2025-12-01 09:02:39.730394 | controller | skipping: Conditional result was False
2025-12-01 09:02:39.735642 | 
2025-12-01 09:02:39.735708 | TASK [Create nodepool node_private for this node]
2025-12-01 09:02:40.720768 | controller | changed
2025-12-01 09:02:40.730262 | 
2025-12-01 09:02:40.730456 | LOOP [Copy ssh keys to nodepool directory]
2025-12-01 09:02:41.126691 | controller | ok: Item: id_rsa Runtime: 0:00:00.005170
2025-12-01 09:02:41.126903 | 
2025-12-01 09:02:41.359565 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005034
2025-12-01 09:02:41.374607 | 
2025-12-01 09:02:41.374719 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-01 09:02:42.336562 | controller | changed
2025-12-01 09:02:42.341592 | 
2025-12-01 09:02:42.341657 | TASK [Validate sudoers config after edits]
2025-12-01 09:02:42.629196 | controller | /etc/sudoers: parsed OK
2025-12-01 09:02:42.629255 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-01 09:02:42.629265 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-01 09:02:42.629271 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-01 09:02:42.871281 | controller | ok: Runtime: 0:00:00.005313
2025-12-01 09:02:42.876803 | 
2025-12-01 09:02:42.876870 | TASK [Show the environment passed in to job shell scripts]
2025-12-01 09:02:43.138756 | controller | SHELL=/bin/bash
2025-12-01 09:02:43.138798 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-01 09:02:43.138808 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-01 09:02:43.138816 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/ac200803ea9acc6548ecc5f46f5c52fe49b8e1e4
2025-12-01 09:02:43.138821 | controller | PWD=/home/zuul
2025-12-01 09:02:43.138827 | controller | ZUUL_PIPELINE=github-check
2025-12-01 09:02:43.138832 | controller | LOGNAME=zuul
2025-12-01 09:02:43.138837 | controller | XDG_SESSION_TYPE=tty
2025-12-01 09:02:43.138891 | controller | _=/usr/bin/env
2025-12-01 09:02:43.138900 | controller | MOTD_SHOWN=pam
2025-12-01 09:02:43.138905 | controller | HOME=/home/zuul
2025-12-01 09:02:43.138910 | controller | LANG=en_US.UTF-8
2025-12-01 09:02:43.138915 | controller | SSH_CONNECTION=192.168.26.12 52318 192.168.26.37 22
2025-12-01 09:02:43.138921 | 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-12-01 09:02:43.138929 | controller | ZUUL_CHANGE_IDS=3505,ac200803ea9acc6548ecc5f46f5c52fe49b8e1e4
2025-12-01 09:02:43.138934 | controller | WORKSPACE=/home/zuul/workspace
2025-12-01 09:02:43.138939 | controller | XDG_SESSION_CLASS=user
2025-12-01 09:02:43.138945 | controller | SELINUX_ROLE_REQUESTED=
2025-12-01 09:02:43.138950 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-01 09:02:43.138955 | controller | USER=zuul
2025-12-01 09:02:43.138960 | controller | ZUUL_VOTING=True
2025-12-01 09:02:43.138966 | controller | BUILD_TIMEOUT=5400000
2025-12-01 09:02:43.138971 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-01 09:02:43.138976 | controller | SHLVL=1
2025-12-01 09:02:43.138981 | controller | ZUUL_PATCHSET=ac200803ea9acc6548ecc5f46f5c52fe49b8e1e4
2025-12-01 09:02:43.138986 | controller | XDG_SESSION_ID=1
2025-12-01 09:02:43.138992 | controller | ZUUL_BRANCH=main
2025-12-01 09:02:43.138997 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-01 09:02:43.139002 | controller | SSH_CLIENT=192.168.26.12 52318 22
2025-12-01 09:02:43.139008 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-01 09:02:43.139013 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-01 09:02:43.139019 | controller | which_declare=declare -f
2025-12-01 09:02:43.139024 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-01 09:02:43.139030 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-01 09:02:43.139035 | controller | ZUUL_CHANGE=3505
2025-12-01 09:02:43.139040 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-01 09:02:43.139045 | controller | ZUUL_UUID=898b614aca654111b842515d9cbe567b
2025-12-01 09:02:43.139050 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-01 09:02:43.139056 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-01 09:02:43.139061 | controller | }
2025-12-01 09:02:43.405405 | controller | ok: Runtime: 0:00:00.005964
2025-12-01 09:02:43.410595 | 
2025-12-01 09:02:43.410661 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-01 09:02:43.425218 | controller | skipping: Conditional result was False
2025-12-01 09:02:43.430720 | 
2025-12-01 09:02:43.430785 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-01 09:02:43.975852 | controller | skipping: Conditional result was False
2025-12-01 09:02:43.981546 | 
2025-12-01 09:02:43.981618 | TASK [Ensure legacy workspace directory]
2025-12-01 09:02:44.245016 | controller | changed
2025-12-01 09:02:44.263119 | 
2025-12-01 09:02:44.263187 | PLAY RECAP
2025-12-01 09:02:44.263238 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 09:02:44.263267 | 
2025-12-01 09:02:44.336291 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 09:02:44.339181 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 09:02:44.845141 | 
2025-12-01 09:02:44.845252 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-01 09:02:44.865347 | 
2025-12-01 09:02:44.865422 | TASK [Create zuul-output directory]
2025-12-01 09:02:45.230443 | controller | changed
2025-12-01 09:02:45.235416 | 
2025-12-01 09:02:45.235491 | TASK [Slurp Zuul inventory test]
2025-12-01 09:02:45.522980 | controller -> localhost | ok
2025-12-01 09:02:45.529545 | 
2025-12-01 09:02:45.529614 | TASK [Save zuul inventory]
2025-12-01 09:02:46.693743 | controller | changed
2025-12-01 09:02:46.699536 | 
2025-12-01 09:02:46.699608 | TASK [Save zuul vars without the change_message]
2025-12-01 09:02:47.782968 | controller | changed
2025-12-01 09:02:47.802601 | 
2025-12-01 09:02:47.802679 | PLAY RECAP
2025-12-01 09:02:47.802726 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 09:02:47.802759 | 
2025-12-01 09:02:47.873231 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 09:02:47.874169 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 09:02:48.459780 | 
2025-12-01 09:02:48.459968 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-01 09:02:48.480230 | 
2025-12-01 09:02:48.480307 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-01 09:02:48.504026 | controller | ok
2025-12-01 09:02:48.521479 | 
2025-12-01 09:02:48.521573 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 09:02:48.548083 | controller | skipping: Conditional result was False
2025-12-01 09:02:48.554808 | 
2025-12-01 09:02:48.554880 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 09:02:48.970073 | controller | ok
2025-12-01 09:02:48.975658 | 
2025-12-01 09:02:48.975728 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 09:02:49.953116 | controller | ok
2025-12-01 09:02:49.963227 | 
2025-12-01 09:02:49.963291 | TASK [Prepare workspace]
2025-12-01 09:02:49.983104 | controller | ok
2025-12-01 09:02:49.999018 | 
2025-12-01 09:02:49.999088 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 09:02:50.372823 | controller | ok
2025-12-01 09:02:50.378731 | 
2025-12-01 09:02:50.378796 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 09:02:52.948404 | controller | Output suppressed because no_log was given
2025-12-01 09:02:52.957348 | 
2025-12-01 09:02:52.957416 | LOOP [Create zuul-output directory]
2025-12-01 09:02:53.217646 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-01 09:02:53.440115 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-01 09:02:53.451875 | 
2025-12-01 09:02:53.452089 | TASK [Install required packages]
2025-12-01 09:03:25.541753 | controller | ok: Nothing to do
2025-12-01 09:03:25.547458 | 
2025-12-01 09:03:25.547524 | TASK [Install venv]
2025-12-01 09:04:22.178004 | controller | changed
2025-12-01 09:04:22.203792 | 
2025-12-01 09:04:22.204160 | PLAY RECAP
2025-12-01 09:04:22.204238 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-01 09:04:22.204262 | 
2025-12-01 09:04:22.292970 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 09:04:22.293970 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 09:04:22.830470 | 
2025-12-01 09:04:22.830603 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-01 09:04:22.852437 | 
2025-12-01 09:04:22.852518 | TASK [Gather required facts]
2025-12-01 09:04:23.382668 | controller | ok
2025-12-01 09:04:23.388610 | 
2025-12-01 09:04:23.388731 | TASK [Load environment var if instructed to]
2025-12-01 09:04:23.415440 | controller | skipping: Conditional result was False
2025-12-01 09:04:23.421123 | 
2025-12-01 09:04:23.421199 | TASK [Ensure group_vars dir exists]
2025-12-01 09:04:23.786244 | controller | ok
2025-12-01 09:04:23.791601 | 
2025-12-01 09:04:23.791670 | TASK [Print related variables]
2025-12-01 09:04:23.824905 | controller | ok:
2025-12-01 09:04:23.825133 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-01 09:04:23.825164 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer
2025-12-01 09:04:23.831209 | 
2025-12-01 09:04:23.831277 | TASK [Run molecule]
2025-12-01 09:04:24.828899 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-01 09:04:25.099322 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-01 09:04:41.139764 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.140154 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.140583 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.141005 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.141437 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+60d5d122 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0+60d5d122 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.141865 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.142280 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.142690 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.143085 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.143476 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.143875 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.144285 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.144716 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.145126 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.145559 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/82c225/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 09:04:41.154248 | controller | INFO     Running crc_layout > prepare
2025-12-01 09:04:41.800109 | controller |
2025-12-01 09:04:41.800151 | controller | PLAY [Prepare] *****************************************************************
2025-12-01 09:04:41.800235 | controller |
2025-12-01 09:04:41.800324 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 09:04:41.800418 | controller | Monday 01 December 2025  09:04:41 +0000 (0:00:00.018)       0:00:00.018 *******
2025-12-01 09:04:42.609295 | controller | ok: [instance]
2025-12-01 09:04:42.609340 | controller |
2025-12-01 09:04:42.609513 | controller | TASK [Create custom basedir] ***************************************************
2025-12-01 09:04:42.609565 | controller | Monday 01 December 2025  09:04:42 +0000 (0:00:00.809)       0:00:00.828 *******
2025-12-01 09:04:42.926015 | controller | changed: [instance]
2025-12-01 09:04:42.926109 | controller |
2025-12-01 09:04:42.926208 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-01 09:04:42.926312 | controller | Monday 01 December 2025  09:04:42 +0000 (0:00:00.316)       0:00:01.145 *******
2025-12-01 09:04:42.943047 | controller | skipping: [instance]
2025-12-01 09:04:42.943164 | controller |
2025-12-01 09:04:42.943297 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-01 09:04:42.943424 | controller | Monday 01 December 2025  09:04:42 +0000 (0:00:00.017)       0:00:01.162 *******
2025-12-01 09:04:42.983424 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-01 09:04:42.983512 | controller |
2025-12-01 09:04:42.983706 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-01 09:04:42.983790 | controller | Monday 01 December 2025  09:04:42 +0000 (0:00:00.040)       0:00:01.202 *******
2025-12-01 09:04:43.259158 | controller | ok: [instance]
2025-12-01 09:04:43.259221 | controller |
2025-12-01 09:04:43.259327 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-01 09:04:43.259427 | controller | Monday 01 December 2025  09:04:43 +0000 (0:00:00.275)       0:00:01.478 *******
2025-12-01 09:04:43.464368 | controller | ok: [instance]
2025-12-01 09:04:43.464441 | controller |
2025-12-01 09:04:43.464556 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-01 09:04:43.464666 | controller | Monday 01 December 2025  09:04:43 +0000 (0:00:00.205)       0:00:01.683 *******
2025-12-01 09:04:44.138615 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-01 09:04:44.138664 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-01 09:04:44.139092 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-01 09:04:44.197372 | controller |
2025-12-01 09:04:44.197391 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-01 09:04:44.197400 | controller | Monday 01 December 2025  09:04:44 +0000 (0:00:00.674)       0:00:02.357 *******
2025-12-01 09:04:44.197409 | controller |
2025-12-01 09:04:44.719390 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-01 09:04:44.719420 | controller | Monday 01 December 2025  09:04:44 +0000 (0:00:00.058)       0:00:02.416 *******
2025-12-01 09:04:44.719432 | controller | changed: [instance] => (item=tmp)
2025-12-01 09:04:45.518475 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-01 09:04:45.518506 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-01 09:04:45.518514 | controller |
2025-12-01 09:04:45.518520 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-01 09:04:45.518528 | controller | Monday 01 December 2025  09:04:44 +0000 (0:00:00.521)       0:00:02.938 *******
2025-12-01 09:04:45.518538 | controller | ok: [instance]
2025-12-01 09:04:46.619366 | controller |
2025-12-01 09:04:46.619404 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-01 09:04:46.619412 | controller | Monday 01 December 2025  09:04:45 +0000 (0:00:00.799)       0:00:03.737 *******
2025-12-01 09:04:46.619422 | controller | changed: [instance]
2025-12-01 09:04:54.282621 | controller |
2025-12-01 09:04:54.282673 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-01 09:04:54.282684 | controller | Monday 01 December 2025  09:04:46 +0000 (0:00:01.100)       0:00:04.838 *******
2025-12-01 09:04:54.282696 | controller | changed: [instance]
2025-12-01 09:04:54.981408 | controller |
2025-12-01 09:04:54.981444 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-01 09:04:54.981453 | controller | Monday 01 December 2025  09:04:54 +0000 (0:00:07.663)       0:00:12.501 *******
2025-12-01 09:04:54.981501 | controller | changed: [instance]
2025-12-01 09:04:55.006448 | controller |
2025-12-01 09:04:55.006495 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-01 09:04:55.006504 | controller | Monday 01 December 2025  09:04:54 +0000 (0:00:00.698)       0:00:13.200 *******
2025-12-01 09:04:55.006515 | controller | skipping: [instance]
2025-12-01 09:04:55.778538 | controller |
2025-12-01 09:04:55.778568 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-01 09:04:55.778576 | controller | Monday 01 December 2025  09:04:55 +0000 (0:00:00.025)       0:00:13.225 *******
2025-12-01 09:04:55.778586 | controller | changed: [instance]
2025-12-01 09:04:55.815562 | controller |
2025-12-01 09:04:55.815586 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-01 09:04:55.815595 | controller | Monday 01 December 2025  09:04:55 +0000 (0:00:00.771)       0:00:13.997 *******
2025-12-01 09:04:55.815606 | controller | skipping: [instance]
2025-12-01 09:04:55.851039 | controller |
2025-12-01 09:04:55.851072 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-01 09:04:55.851082 | controller | Monday 01 December 2025  09:04:55 +0000 (0:00:00.036)       0:00:14.034 *******
2025-12-01 09:04:55.851092 | controller | skipping: [instance]
2025-12-01 09:04:55.886139 | controller |
2025-12-01 09:04:55.886161 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-01 09:04:55.886171 | controller | Monday 01 December 2025  09:04:55 +0000 (0:00:00.035)       0:00:14.069 *******
2025-12-01 09:04:55.886181 | controller | skipping: [instance]
2025-12-01 09:04:56.422365 | controller |
2025-12-01 09:04:56.422388 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-01 09:04:56.422395 | controller | Monday 01 December 2025  09:04:55 +0000 (0:00:00.035)       0:00:14.105 *******
2025-12-01 09:04:56.422404 | controller | changed: [instance]
2025-12-01 09:04:56.861575 | controller |
2025-12-01 09:04:56.861609 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-01 09:04:56.861617 | controller | Monday 01 December 2025  09:04:56 +0000 (0:00:00.536)       0:00:14.641 *******
2025-12-01 09:04:56.861628 | controller | changed: [instance]
2025-12-01 09:04:56.891223 | controller |
2025-12-01 09:04:56.891260 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-01 09:04:56.891270 | controller | Monday 01 December 2025  09:04:56 +0000 (0:00:00.439)       0:00:15.080 *******
2025-12-01 09:04:56.891282 | controller | skipping: [instance]
2025-12-01 09:04:56.921046 | controller |
2025-12-01 09:04:56.921087 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-01 09:04:56.921096 | controller | Monday 01 December 2025  09:04:56 +0000 (0:00:00.029)       0:00:15.110 *******
2025-12-01 09:04:56.921111 | controller | skipping: [instance]
2025-12-01 09:04:56.949981 | controller |
2025-12-01 09:04:56.950005 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-01 09:04:56.950013 | controller | Monday 01 December 2025  09:04:56 +0000 (0:00:00.029)       0:00:15.140 *******
2025-12-01 09:04:56.950024 | controller | skipping: [instance]
2025-12-01 09:04:56.986889 | controller |
2025-12-01 09:04:56.986912 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-01 09:04:56.986921 | controller | Monday 01 December 2025  09:04:56 +0000 (0:00:00.028)       0:00:15.169 *******
2025-12-01 09:04:56.986932 | controller | ok: [instance]
2025-12-01 09:04:57.013986 | controller |
2025-12-01 09:04:57.014008 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-01 09:04:57.014016 | controller | Monday 01 December 2025  09:04:56 +0000 (0:00:00.036)       0:00:15.205 *******
2025-12-01 09:04:57.014036 | controller | skipping: [instance]
2025-12-01 09:04:57.039839 | controller |
2025-12-01 09:04:57.039861 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-01 09:04:57.039869 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.027)       0:00:15.233 *******
2025-12-01 09:04:57.039880 | controller | skipping: [instance]
2025-12-01 09:04:57.040044 | controller |
2025-12-01 09:04:57.040235 | controller | TASK [Download the RPM] ********************************************************
2025-12-01 09:04:57.040402 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.026)       0:00:15.259 *******
2025-12-01 09:04:57.066958 | controller | skipping: [instance]
2025-12-01 09:04:57.067147 | controller |
2025-12-01 09:04:57.067316 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-01 09:04:57.067507 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.027)       0:00:15.286 *******
2025-12-01 09:04:57.099493 | controller | skipping: [instance]
2025-12-01 09:04:57.099705 | controller |
2025-12-01 09:04:57.099861 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-01 09:04:57.099998 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.032)       0:00:15.318 *******
2025-12-01 09:04:57.125720 | controller | skipping: [instance]
2025-12-01 09:04:57.125887 | controller |
2025-12-01 09:04:57.126028 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-01 09:04:57.126177 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.026)       0:00:15.345 *******
2025-12-01 09:04:57.154185 | controller | skipping: [instance]
2025-12-01 09:04:57.154366 | controller |
2025-12-01 09:04:57.154575 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-01 09:04:57.154750 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.028)       0:00:15.373 *******
2025-12-01 09:04:57.179836 | controller | skipping: [instance]
2025-12-01 09:04:57.180075 | controller |
2025-12-01 09:04:57.180315 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-01 09:04:57.180578 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.025)       0:00:15.399 *******
2025-12-01 09:04:57.363865 | controller | ok: [instance]
2025-12-01 09:04:57.364086 | controller |
2025-12-01 09:04:57.364267 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-01 09:04:57.364414 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.183)       0:00:15.583 *******
2025-12-01 09:04:57.587524 | controller | changed: [instance]
2025-12-01 09:04:57.587568 | controller |
2025-12-01 09:04:57.587800 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-01 09:04:57.816102 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.223)       0:00:15.806 *******
2025-12-01 09:04:57.816128 | controller | changed: [instance]
2025-12-01 09:04:57.837579 | controller |
2025-12-01 09:04:57.837602 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-01 09:04:57.837611 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.228)       0:00:16.035 *******
2025-12-01 09:04:57.837622 | controller | skipping: [instance]
2025-12-01 09:04:57.860387 | controller |
2025-12-01 09:04:57.860405 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-01 09:04:57.860413 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.021)       0:00:16.056 *******
2025-12-01 09:04:57.860428 | controller | skipping: [instance]
2025-12-01 09:04:57.882276 | controller |
2025-12-01 09:04:57.882294 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-01 09:04:57.882301 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.022)       0:00:16.079 *******
2025-12-01 09:04:57.882310 | controller | skipping: [instance]
2025-12-01 09:04:57.903600 | controller |
2025-12-01 09:04:57.903644 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-01 09:04:57.903654 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.022)       0:00:16.101 *******
2025-12-01 09:04:57.903669 | controller | skipping: [instance]
2025-12-01 09:04:57.925520 | controller |
2025-12-01 09:04:57.925558 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-01 09:04:57.925573 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.021)       0:00:16.122 *******
2025-12-01 09:04:57.925585 | controller | skipping: [instance]
2025-12-01 09:04:57.947232 | controller |
2025-12-01 09:04:57.947256 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-01 09:04:57.947266 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.021)       0:00:16.144 *******
2025-12-01 09:04:57.947281 | controller | skipping: [instance]
2025-12-01 09:04:57.964092 | controller |
2025-12-01 09:04:57.964114 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-01 09:04:57.964124 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.021)       0:00:16.166 *******
2025-12-01 09:04:57.964134 | controller | skipping: [instance]
2025-12-01 09:04:57.964306 | controller |
2025-12-01 09:04:57.964503 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-01 09:04:57.964659 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.017)       0:00:16.183 *******
2025-12-01 09:04:57.992588 | controller | skipping: [instance]
2025-12-01 09:04:57.992773 | controller |
2025-12-01 09:04:57.992944 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-01 09:04:57.993132 | controller | Monday 01 December 2025  09:04:57 +0000 (0:00:00.028)       0:00:16.211 *******
2025-12-01 09:04:58.009092 | controller | skipping: [instance]
2025-12-01 09:04:58.009277 | controller |
2025-12-01 09:04:58.009447 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-01 09:04:58.009627 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.016)       0:00:16.228 *******
2025-12-01 09:04:58.031592 | controller | skipping: [instance]
2025-12-01 09:04:58.031674 | controller |
2025-12-01 09:04:58.031854 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-01 09:04:58.031975 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.022)       0:00:16.250 *******
2025-12-01 09:04:58.063152 | controller | skipping: [instance]
2025-12-01 09:04:58.063279 | controller |
2025-12-01 09:04:58.063497 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-01 09:04:58.063608 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.031)       0:00:16.282 *******
2025-12-01 09:04:58.091068 | controller | skipping: [instance]
2025-12-01 09:04:58.091183 | controller |
2025-12-01 09:04:58.091324 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-01 09:04:58.091452 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.028)       0:00:16.310 *******
2025-12-01 09:04:58.120454 | controller | skipping: [instance]
2025-12-01 09:04:58.120601 | controller |
2025-12-01 09:04:58.120781 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-01 09:04:58.120877 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.029)       0:00:16.339 *******
2025-12-01 09:04:58.149301 | controller | skipping: [instance]
2025-12-01 09:04:58.149432 | controller |
2025-12-01 09:04:58.149640 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-01 09:04:58.149755 | controller | Monday 01 December 2025  09:04:58 +0000 (0:00:00.028)       0:00:16.368 *******
2025-12-01 09:05:52.723065 | controller | ok: [instance]
2025-12-01 09:05:53.918222 | controller |
2025-12-01 09:05:53.918252 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-01 09:05:53.918260 | controller | Monday 01 December 2025  09:05:52 +0000 (0:00:54.573)       0:01:10.941 *******
2025-12-01 09:05:53.918270 | controller | ok: [instance]
2025-12-01 09:05:53.949343 | controller |
2025-12-01 09:05:53.949370 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-01 09:05:53.949379 | controller | Monday 01 December 2025  09:05:53 +0000 (0:00:01.195)       0:01:12.137 *******
2025-12-01 09:05:53.949391 | controller | skipping: [instance]
2025-12-01 09:05:53.990954 | controller |
2025-12-01 09:05:53.990976 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-12-01 09:05:53.990984 | controller | Monday 01 December 2025  09:05:53 +0000 (0:00:00.031)       0:01:12.168 *******
2025-12-01 09:05:53.990993 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-12-01 09:05:54.010573 | controller |
2025-12-01 09:05:54.010606 | controller | TASK [ci_setup : List packages to install] *************************************
2025-12-01 09:05:54.010623 | controller | Monday 01 December 2025  09:05:53 +0000 (0:00:00.041)       0:01:12.209 *******
2025-12-01 09:05:54.010636 | controller | ok: [instance] =>
2025-12-01 09:05:54.010666 | controller |   cifmw_ci_setup_packages:
2025-12-01 09:05:54.010675 | controller |   - bash-completion
2025-12-01 09:05:54.010681 | controller |   - ca-certificates
2025-12-01 09:05:54.010688 | controller |   - git-core
2025-12-01 09:05:54.010695 | controller |   - make
2025-12-01 09:05:54.010701 | controller |   - tar
2025-12-01 09:05:54.010708 | controller |   - tmux
2025-12-01 09:05:54.010714 | controller |   - python3-pip
2025-12-01 09:05:54.010721 | controller |
2025-12-01 09:05:54.010729 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-12-01 09:05:54.010770 | controller | Monday 01 December 2025  09:05:54 +0000 (0:00:00.019)       0:01:12.229 *******
2025-12-01 09:06:04.745002 | controller | changed: [instance]
2025-12-01 09:06:05.336292 | controller |
2025-12-01 09:06:05.336323 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-12-01 09:06:05.336331 | controller | Monday 01 December 2025  09:06:04 +0000 (0:00:10.734)       0:01:22.963 *******
2025-12-01 09:06:05.336342 | controller | ok: [instance]
2025-12-01 09:06:05.552737 | controller |
2025-12-01 09:06:05.552769 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-12-01 09:06:05.552784 | controller | Monday 01 December 2025  09:06:05 +0000 (0:00:00.591)       0:01:23.555 *******
2025-12-01 09:06:05.552794 | controller | changed: [instance]
2025-12-01 09:06:05.553048 | controller |
2025-12-01 09:06:11.911687 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-12-01 09:06:11.911718 | controller | Monday 01 December 2025  09:06:05 +0000 (0:00:00.216)       0:01:23.771 *******
2025-12-01 09:06:11.911730 | controller | changed: [instance]
2025-12-01 09:06:11.948648 | controller |
2025-12-01 09:06:11.948700 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-12-01 09:06:11.948714 | controller | Monday 01 December 2025  09:06:11 +0000 (0:00:06.358)       0:01:30.130 *******
2025-12-01 09:06:11.948732 | controller | ok: [instance]
2025-12-01 09:06:12.203592 | controller |
2025-12-01 09:06:12.203638 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-12-01 09:06:12.203650 | controller | Monday 01 December 2025  09:06:11 +0000 (0:00:00.036)       0:01:30.167 *******
2025-12-01 09:06:12.203665 | controller | changed: [instance]
2025-12-01 09:06:12.467991 | controller |
2025-12-01 09:06:12.468020 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-12-01 09:06:12.468028 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.255)       0:01:30.422 *******
2025-12-01 09:06:12.468038 | controller | changed: [instance]
2025-12-01 09:06:12.485581 | controller |
2025-12-01 09:06:12.485617 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-12-01 09:06:12.485627 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.264)       0:01:30.687 *******
2025-12-01 09:06:12.485640 | controller | skipping: [instance]
2025-12-01 09:06:12.485672 | controller |
2025-12-01 09:06:12.486008 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-12-01 09:06:12.519583 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.017)       0:01:30.704 *******
2025-12-01 09:06:12.519606 | controller | skipping: [instance]
2025-12-01 09:06:12.519637 | controller |
2025-12-01 09:06:12.519645 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-12-01 09:06:12.519655 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.033)       0:01:30.738 *******
2025-12-01 09:06:12.536208 | controller | skipping: [instance]
2025-12-01 09:06:12.550984 | controller |
2025-12-01 09:06:12.551002 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-12-01 09:06:12.551009 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.016)       0:01:30.755 *******
2025-12-01 09:06:12.551019 | controller | skipping: [instance]
2025-12-01 09:06:12.566338 | controller |
2025-12-01 09:06:12.566353 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-12-01 09:06:12.566368 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.014)       0:01:30.769 *******
2025-12-01 09:06:12.566388 | controller | skipping: [instance]
2025-12-01 09:06:12.566624 | controller |
2025-12-01 09:06:12.566859 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-12-01 09:06:12.567070 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.015)       0:01:30.785 *******
2025-12-01 09:06:12.587436 | controller | skipping: [instance]
2025-12-01 09:06:12.587714 | controller |
2025-12-01 09:06:12.587946 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-12-01 09:06:12.588150 | controller | Monday 01 December 2025  09:06:12 +0000 (0:00:00.021)       0:01:30.806 *******
2025-12-01 09:06:13.533615 | controller | changed: [instance] => (item=/opt/basedir/artifacts/manifests/openstack/cr)
2025-12-01 09:06:13.533668 | controller | changed: [instance] => (item=/opt/basedir/logs)
2025-12-01 09:06:13.533679 | controller | ok: [instance] => (item=/opt/basedir/tmp)
2025-12-01 09:06:13.533701 | controller | changed: [instance] => (item=/opt/basedir/volumes)
2025-12-01 09:06:13.533721 | controller | changed: [instance] => (item=/opt/basedir/artifacts/parameters)
2025-12-01 09:06:13.534018 | controller |
2025-12-01 09:06:13.864599 | controller | TASK [Create secret file] ******************************************************
2025-12-01 09:06:13.864632 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.945)       0:01:31.752 *******
2025-12-01 09:06:13.864652 | controller | changed: [instance]
2025-12-01 09:06:13.864686 | controller |
2025-12-01 09:06:13.867195 | controller | PLAY RECAP *********************************************************************
2025-12-01 09:06:13.924606 | controller | instance                   : ok=31   changed=17   unreachable=0    failed=0    skipped=36   rescued=0    ignored=0
2025-12-01 09:06:13.924627 | controller |
2025-12-01 09:06:13.924635 | controller | Monday 01 December 2025  09:06:13 +0000 (0:00:00.330)       0:01:32.083 *******
2025-12-01 09:06:13.924641 | controller | ===============================================================================
2025-12-01 09:06:13.924646 | controller | test_deps : Install selinux python libs -------------------------------- 54.57s
2025-12-01 09:06:13.924652 | controller | ci_setup : Install needed packages ------------------------------------- 10.73s
2025-12-01 09:06:13.924657 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.66s
2025-12-01 09:06:13.924662 | controller | ci_setup : Install openshift client ------------------------------------- 6.36s
2025-12-01 09:06:13.924667 | controller | test_deps : Install python yaml libs ------------------------------------ 1.20s
2025-12-01 09:06:13.924672 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.10s
2025-12-01 09:06:13.924677 | controller | ci_setup : Manage directories ------------------------------------------- 0.95s
2025-12-01 09:06:13.924683 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-12-01 09:06:13.924688 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.80s
2025-12-01 09:06:13.924693 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s
2025-12-01 09:06:13.924698 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-12-01 09:06:13.924703 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.67s
2025-12-01 09:06:13.924709 | controller | ci_setup : Gather version of openshift client --------------------------- 0.59s
2025-12-01 09:06:13.924714 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2025-12-01 09:06:13.924719 | controller | repo_setup : Ensure directories are present ----------------------------- 0.52s
2025-12-01 09:06:13.924724 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-12-01 09:06:13.924729 | controller | Create secret file ------------------------------------------------------ 0.33s
2025-12-01 09:06:13.924734 | controller | Create custom basedir --------------------------------------------------- 0.32s
2025-12-01 09:06:13.924740 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-12-01 09:06:13.924750 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.26s
2025-12-01 09:06:13.924759 | controller | INFO     Running crc_layout > converge
2025-12-01 09:06:14.520961 | controller |
2025-12-01 09:06:14.521132 | controller | PLAY [Converge] ****************************************************************
2025-12-01 09:06:14.521253 | controller |
2025-12-01 09:06:14.521376 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 09:06:14.521536 | controller | Monday 01 December 2025  09:06:14 +0000 (0:00:00.025)       0:00:00.025 *******
2025-12-01 09:06:15.313276 | controller | ok: [instance]
2025-12-01 09:06:15.335968 | controller |
2025-12-01 09:06:15.335992 | controller | TASK [Load networking definition from scenario file] ***************************
2025-12-01 09:06:15.336002 | controller | Monday 01 December 2025  09:06:15 +0000 (0:00:00.792)       0:00:00.817 *******
2025-12-01 09:06:15.336014 | controller | ok: [instance]
2025-12-01 09:06:16.852203 | controller |
2025-12-01 09:06:16.852236 | controller | TASK [discover_latest_image : Get latest image] ********************************
2025-12-01 09:06:16.852244 | controller | Monday 01 December 2025  09:06:15 +0000 (0:00:00.022)       0:00:00.840 *******
2025-12-01 09:06:16.852254 | controller | changed: [instance]
2025-12-01 09:06:16.878613 | controller |
2025-12-01 09:06:16.878652 | controller | TASK [discover_latest_image : Export facts accordingly] ************************
2025-12-01 09:06:16.878670 | controller | Monday 01 December 2025  09:06:16 +0000 (0:00:01.515)       0:00:02.356 *******
2025-12-01 09:06:16.878683 | controller | ok: [instance]
2025-12-01 09:06:16.878716 | controller |
2025-12-01 09:06:16.879091 | controller | TASK [reproducer : Load CI job environment] ************************************
2025-12-01 09:06:16.899571 | controller | Monday 01 December 2025  09:06:16 +0000 (0:00:00.027)       0:00:02.383 *******
2025-12-01 09:06:16.899617 | controller | skipping: [instance]
2025-12-01 09:06:16.899736 | controller |
2025-12-01 09:06:16.899757 | controller | TASK [Build final libvirt layout] **********************************************
2025-12-01 09:06:16.899770 | controller | Monday 01 December 2025  09:06:16 +0000 (0:00:00.020)       0:00:02.404 *******
2025-12-01 09:06:16.949963 | controller |
2025-12-01 09:06:17.031136 | controller | TASK [libvirt_manager : Chose right parameter for layout definition] ***********
2025-12-01 09:06:17.031154 | controller | Monday 01 December 2025  09:06:16 +0000 (0:00:00.050)       0:00:02.454 *******
2025-12-01 09:06:17.031164 | controller | ok: [instance]
2025-12-01 09:06:17.090884 | controller |
2025-12-01 09:06:17.090917 | controller | TASK [libvirt_manager : Patch the layout if needed] ****************************
2025-12-01 09:06:17.090930 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.080)       0:00:02.535 *******
2025-12-01 09:06:17.090946 | controller | skipping: [instance]
2025-12-01 09:06:17.091201 | controller |
2025-12-01 09:06:17.091533 | controller | TASK [reproducer : Assert no conflicting parameters were passed] ***************
2025-12-01 09:06:17.091747 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.060)       0:00:02.595 *******
2025-12-01 09:06:17.117307 | controller | ok: [instance]
2025-12-01 09:06:17.117536 | controller |
2025-12-01 09:06:17.117730 | controller | TASK [reproducer : Assert that deprecated cifmw_reproducer_internal_ca parameters was not passed] ***
2025-12-01 09:06:17.117871 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.026)       0:00:02.622 *******
2025-12-01 09:06:17.141801 | controller | ok: [instance] => changed=false
2025-12-01 09:06:17.142009 | controller |   msg: All assertions passed
2025-12-01 09:06:17.142172 | controller |
2025-12-01 09:06:17.142336 | controller | TASK [reproducer : Set _use_crc based on actual layout] ************************
2025-12-01 09:06:17.142546 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.024)       0:00:02.646 *******
2025-12-01 09:06:17.187474 | controller | ok: [instance]
2025-12-01 09:06:17.187638 | controller |
2025-12-01 09:06:17.187779 | controller | TASK [reproducer : Ensure directories are present] *****************************
2025-12-01 09:06:17.187918 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.045)       0:00:02.692 *******
2025-12-01 09:06:17.636296 | controller | ok: [instance] => (item=artifacts)
2025-12-01 09:06:17.711671 | controller | ok: [instance] => (item=logs)
2025-12-01 09:06:17.711713 | controller |
2025-12-01 09:06:17.711723 | controller | TASK [Bootstrap libvirt if needed] *********************************************
2025-12-01 09:06:17.711739 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.448)       0:00:03.140 *******
2025-12-01 09:06:17.711753 | controller |
2025-12-01 09:06:17.907573 | controller | TASK [libvirt_manager : Ensure output directory exists] ************************
2025-12-01 09:06:17.907618 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.075)       0:00:03.216 *******
2025-12-01 09:06:17.907634 | controller | ok: [instance]
2025-12-01 09:06:18.195531 | controller |
2025-12-01 09:06:18.195566 | controller | TASK [libvirt_manager : Get vmx/svm extensions from /proc/cpuinfo] *************
2025-12-01 09:06:18.195574 | controller | Monday 01 December 2025  09:06:17 +0000 (0:00:00.195)       0:00:03.411 *******
2025-12-01 09:06:18.195584 | controller | changed: [instance]
2025-12-01 09:06:18.195610 | controller |
2025-12-01 09:06:18.195836 | controller | TASK [libvirt_manager : Check if KVM is enabled] *******************************
2025-12-01 09:06:18.481195 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.288)       0:00:03.700 *******
2025-12-01 09:06:18.481225 | controller | ok: [instance]
2025-12-01 09:06:18.511577 | controller |
2025-12-01 09:06:18.511599 | controller | TASK [libvirt_manager : Fail if KVM not enabled] *******************************
2025-12-01 09:06:18.511607 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.285)       0:00:03.985 *******
2025-12-01 09:06:18.511616 | controller | skipping: [instance]
2025-12-01 09:06:18.528969 | controller |
2025-12-01 09:06:18.528987 | controller | TASK [libvirt_manager : Check if VMX virtualization] ***************************
2025-12-01 09:06:18.528994 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.030)       0:00:04.015 *******
2025-12-01 09:06:18.529003 | controller | skipping: [instance]
2025-12-01 09:06:18.548599 | controller |
2025-12-01 09:06:18.548621 | controller | TASK [libvirt_manager : Check if SVM virtualization] ***************************
2025-12-01 09:06:18.548631 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.017)       0:00:04.033 *******
2025-12-01 09:06:18.548642 | controller | ok: [instance]
2025-12-01 09:06:18.548670 | controller |
2025-12-01 09:06:18.548680 | controller | TASK [libvirt_manager : Enable VMX virtualization] *****************************
2025-12-01 09:06:18.548772 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.019)       0:00:04.053 *******
2025-12-01 09:06:18.577140 | controller | skipping: [instance]
2025-12-01 09:06:28.289790 | controller |
2025-12-01 09:06:28.289822 | controller | TASK [libvirt_manager : Install packages required for using KVM] ***************
2025-12-01 09:06:28.289837 | controller | Monday 01 December 2025  09:06:18 +0000 (0:00:00.028)       0:00:04.081 *******
2025-12-01 09:06:28.289848 | controller | changed: [instance]
2025-12-01 09:06:28.289966 | controller |
2025-12-01 09:06:28.290019 | controller | TASK [libvirt_manager : Create rules.d polkit folder] **************************
2025-12-01 09:06:28.290030 | controller | Monday 01 December 2025  09:06:28 +0000 (0:00:09.713)       0:00:13.794 *******
2025-12-01 09:06:28.506142 | controller | changed: [instance]
2025-12-01 09:06:29.113432 | controller |
2025-12-01 09:06:29.113473 | controller | TASK [libvirt_manager : Enable UNIX socket PolicyKit auth] *********************
2025-12-01 09:06:29.113484 | controller | Monday 01 December 2025  09:06:28 +0000 (0:00:00.215)       0:00:14.010 *******
2025-12-01 09:06:29.113494 | controller | changed: [instance]
2025-12-01 09:06:29.682158 | controller |
2025-12-01 09:06:29.682195 | controller | TASK [libvirt_manager : Restart service polkit service] ************************
2025-12-01 09:06:29.682204 | controller | Monday 01 December 2025  09:06:29 +0000 (0:00:00.607)       0:00:14.617 *******
2025-12-01 09:06:29.682216 | controller | changed: [instance]
2025-12-01 09:06:29.876558 | controller |
2025-12-01 09:06:29.876589 | controller | TASK [libvirt_manager : Remove directory for service override] *****************
2025-12-01 09:06:29.876597 | controller | Monday 01 December 2025  09:06:29 +0000 (0:00:00.568)       0:00:15.186 *******
2025-12-01 09:06:29.876607 | controller | ok: [instance]
2025-12-01 09:06:29.876633 | controller |
2025-12-01 09:06:29.876898 | controller | TASK [libvirt_manager : Reload systemctl and start/enable virtproxyd.service] ***
2025-12-01 09:06:30.513272 | controller | Monday 01 December 2025  09:06:29 +0000 (0:00:00.194)       0:00:15.381 *******
2025-12-01 09:06:30.513306 | controller | ok: [instance]
2025-12-01 09:06:31.425985 | controller |
2025-12-01 09:06:31.426019 | controller | TASK [libvirt_manager : Ensure firewalld service is up] ************************
2025-12-01 09:06:31.426038 | controller | Monday 01 December 2025  09:06:30 +0000 (0:00:00.636)       0:00:16.017 *******
2025-12-01 09:06:31.426048 | controller | changed: [instance]
2025-12-01 09:06:31.510623 | controller |
2025-12-01 09:06:31.510659 | controller | TASK [libvirt_manager : Ensure libvirt modular driver daemons are started.] ****
2025-12-01 09:06:31.510669 | controller | Monday 01 December 2025  09:06:31 +0000 (0:00:00.912)       0:00:16.930 *******
2025-12-01 09:06:31.510682 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=qemu)
2025-12-01 09:06:31.510718 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=interface)
2025-12-01 09:06:31.510726 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=network)
2025-12-01 09:06:31.510733 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nodedev)
2025-12-01 09:06:31.510739 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=nwfilter)
2025-12-01 09:06:31.510745 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=secret)
2025-12-01 09:06:31.510752 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=storage)
2025-12-01 09:06:31.510760 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/drivers.yml for instance => (item=proxy)
2025-12-01 09:06:31.510941 | controller |
2025-12-01 09:06:31.510964 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for qemu.] *********
2025-12-01 09:06:31.825203 | controller | Monday 01 December 2025  09:06:31 +0000 (0:00:00.084)       0:00:17.014 *******
2025-12-01 09:06:31.825229 | controller | ok: [instance]
2025-12-01 09:06:32.140914 | controller |
2025-12-01 09:06:32.140937 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for qemu.] **********
2025-12-01 09:06:32.140945 | controller | Monday 01 December 2025  09:06:31 +0000 (0:00:00.314)       0:00:17.329 *******
2025-12-01 09:06:32.140954 | controller | ok: [instance]
2025-12-01 09:06:32.458299 | controller |
2025-12-01 09:06:32.458328 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for qemu.] **************
2025-12-01 09:06:32.458336 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.315)       0:00:17.645 *******
2025-12-01 09:06:32.458346 | controller | ok: [instance]
2025-12-01 09:06:32.781056 | controller |
2025-12-01 09:06:32.781090 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for interface.] ****
2025-12-01 09:06:32.781099 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.317)       0:00:17.962 *******
2025-12-01 09:06:32.781109 | controller | ok: [instance]
2025-12-01 09:06:32.781136 | controller |
2025-12-01 09:06:32.781143 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for interface.] *****
2025-12-01 09:06:32.781150 | controller | Monday 01 December 2025  09:06:32 +0000 (0:00:00.322)       0:00:18.284 *******
2025-12-01 09:06:33.097604 | controller | ok: [instance]
2025-12-01 09:06:33.097661 | controller |
2025-12-01 09:06:33.097674 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for interface.] *********
2025-12-01 09:06:33.097829 | controller | Monday 01 December 2025  09:06:33 +0000 (0:00:00.317)       0:00:18.602 *******
2025-12-01 09:06:33.406191 | controller | ok: [instance]
2025-12-01 09:06:33.718145 | controller |
2025-12-01 09:06:33.718172 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for network.] ******
2025-12-01 09:06:33.718180 | controller | Monday 01 December 2025  09:06:33 +0000 (0:00:00.308)       0:00:18.910 *******
2025-12-01 09:06:33.718190 | controller | ok: [instance]
2025-12-01 09:06:34.030303 | controller |
2025-12-01 09:06:34.030330 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for network.] *******
2025-12-01 09:06:34.030339 | controller | Monday 01 December 2025  09:06:33 +0000 (0:00:00.312)       0:00:19.222 *******
2025-12-01 09:06:34.030354 | controller | ok: [instance]
2025-12-01 09:06:34.339301 | controller |
2025-12-01 09:06:34.339321 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for network.] ***********
2025-12-01 09:06:34.339328 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.312)       0:00:19.534 *******
2025-12-01 09:06:34.339338 | controller | ok: [instance]
2025-12-01 09:06:34.654919 | controller |
2025-12-01 09:06:34.654940 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nodedev.] ******
2025-12-01 09:06:34.654947 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.308)       0:00:19.843 *******
2025-12-01 09:06:34.654956 | controller | ok: [instance]
2025-12-01 09:06:34.967539 | controller |
2025-12-01 09:06:34.967559 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nodedev.] *******
2025-12-01 09:06:34.967566 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.315)       0:00:20.159 *******
2025-12-01 09:06:34.967575 | controller | ok: [instance]
2025-12-01 09:06:35.302610 | controller |
2025-12-01 09:06:35.302645 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nodedev.] ***********
2025-12-01 09:06:35.302655 | controller | Monday 01 December 2025  09:06:34 +0000 (0:00:00.312)       0:00:20.472 *******
2025-12-01 09:06:35.302667 | controller | ok: [instance]
2025-12-01 09:06:35.615932 | controller |
2025-12-01 09:06:35.615956 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for nwfilter.] *****
2025-12-01 09:06:35.615964 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.334)       0:00:20.807 *******
2025-12-01 09:06:35.615973 | controller | ok: [instance]
2025-12-01 09:06:35.931493 | controller |
2025-12-01 09:06:35.931525 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for nwfilter.] ******
2025-12-01 09:06:35.931533 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.313)       0:00:21.120 *******
2025-12-01 09:06:35.931543 | controller | ok: [instance]
2025-12-01 09:06:36.247851 | controller |
2025-12-01 09:06:36.247876 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for nwfilter.] **********
2025-12-01 09:06:36.247885 | controller | Monday 01 December 2025  09:06:35 +0000 (0:00:00.315)       0:00:21.435 *******
2025-12-01 09:06:36.247895 | controller | ok: [instance]
2025-12-01 09:06:36.558633 | controller |
2025-12-01 09:06:36.558652 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for secret.] *******
2025-12-01 09:06:36.558659 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.316)       0:00:21.752 *******
2025-12-01 09:06:36.558668 | controller | ok: [instance]
2025-12-01 09:06:36.875132 | controller |
2025-12-01 09:06:36.875158 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for secret.] ********
2025-12-01 09:06:36.875166 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.310)       0:00:22.062 *******
2025-12-01 09:06:36.875175 | controller | ok: [instance]
2025-12-01 09:06:37.189832 | controller |
2025-12-01 09:06:37.189850 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for secret.] ************
2025-12-01 09:06:37.189858 | controller | Monday 01 December 2025  09:06:36 +0000 (0:00:00.316)       0:00:22.379 *******
2025-12-01 09:06:37.189867 | controller | ok: [instance]
2025-12-01 09:06:37.505284 | controller |
2025-12-01 09:06:37.505309 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for storage.] ******
2025-12-01 09:06:37.505319 | controller | Monday 01 December 2025  09:06:37 +0000 (0:00:00.314)       0:00:22.694 *******
2025-12-01 09:06:37.505331 | controller | ok: [instance]
2025-12-01 09:06:37.817579 | controller |
2025-12-01 09:06:37.817612 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for storage.] *******
2025-12-01 09:06:37.817620 | controller | Monday 01 December 2025  09:06:37 +0000 (0:00:00.315)       0:00:23.009 *******
2025-12-01 09:06:37.817631 | controller | ok: [instance]
2025-12-01 09:06:38.122474 | controller |
2025-12-01 09:06:38.122505 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for storage.] ***********
2025-12-01 09:06:38.122514 | controller | Monday 01 December 2025  09:06:37 +0000 (0:00:00.312)       0:00:23.322 *******
2025-12-01 09:06:38.122523 | controller | ok: [instance]
2025-12-01 09:06:38.436249 | controller |
2025-12-01 09:06:38.436272 | controller | TASK [libvirt_manager : Ensure read-write socket is enabled for proxy.] ********
2025-12-01 09:06:38.436280 | controller | Monday 01 December 2025  09:06:38 +0000 (0:00:00.304)       0:00:23.626 *******
2025-12-01 09:06:38.436297 | controller | ok: [instance]
2025-12-01 09:06:38.750300 | controller |
2025-12-01 09:06:38.750326 | controller | TASK [libvirt_manager : Ensure read-only socket is enabled for proxy.] *********
2025-12-01 09:06:38.750342 | controller | Monday 01 December 2025  09:06:38 +0000 (0:00:00.313)       0:00:23.940 *******
2025-12-01 09:06:38.750355 | controller | ok: [instance]
2025-12-01 09:06:39.062609 | controller |
2025-12-01 09:06:39.062652 | controller | TASK [libvirt_manager : Ensure admin socket is enabled for proxy.] *************
2025-12-01 09:06:39.062661 | controller | Monday 01 December 2025  09:06:38 +0000 (0:00:00.313)       0:00:24.254 *******
2025-12-01 09:06:39.062673 | controller | ok: [instance]
2025-12-01 09:06:39.083569 | controller |
2025-12-01 09:06:39.083611 | controller | TASK [libvirt_manager : Manage service for older releases] *********************
2025-12-01 09:06:39.083625 | controller | Monday 01 December 2025  09:06:39 +0000 (0:00:00.312)       0:00:24.566 *******
2025-12-01 09:06:39.083644 | controller | skipping: [instance]
2025-12-01 09:06:39.358505 | controller |
2025-12-01 09:06:39.358529 | controller | TASK [libvirt_manager : Get libvirt group users] *******************************
2025-12-01 09:06:39.358538 | controller | Monday 01 December 2025  09:06:39 +0000 (0:00:00.020)       0:00:24.587 *******
2025-12-01 09:06:39.358547 | controller | ok: [instance]
2025-12-01 09:06:39.724589 | controller |
2025-12-01 09:06:39.724628 | controller | TASK [libvirt_manager : Add user if not exists in libvirt group] ***************
2025-12-01 09:06:39.724638 | controller | Monday 01 December 2025  09:06:39 +0000 (0:00:00.275)       0:00:24.862 *******
2025-12-01 09:06:39.724650 | controller | ok: [instance]
2025-12-01 09:06:39.724679 | controller |
2025-12-01 09:06:39.724689 | controller | TASK [libvirt_manager : Allow QEMU on home directory for the storage access] ***
2025-12-01 09:06:39.724800 | controller | Monday 01 December 2025  09:06:39 +0000 (0:00:00.366)       0:00:25.229 *******
2025-12-01 09:06:40.026250 | controller | changed: [instance]
2025-12-01 09:06:40.305235 | controller |
2025-12-01 09:06:40.305260 | controller | TASK [libvirt_manager : Inject system connection parameters in bashrc] *********
2025-12-01 09:06:40.305271 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.301)       0:00:25.530 *******
2025-12-01 09:06:40.305283 | controller | [WARNING]: Reset is not implemented for this connection
2025-12-01 09:06:40.315201 | controller | changed: [instance]
2025-12-01 09:06:40.404205 | controller |
2025-12-01 09:06:40.404223 | controller | TASK [libvirt_manager : Ensure we reload user env] *****************************
2025-12-01 09:06:40.404230 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.273)       0:00:25.804 *******
2025-12-01 09:06:40.404236 | controller |
2025-12-01 09:06:40.404242 | controller | TASK [reproducer : Deploy networks in libvirt] *********************************
2025-12-01 09:06:40.404247 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.015)       0:00:25.819 *******
2025-12-01 09:06:40.404255 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/prepare_networking.yml for instance
2025-12-01 09:06:40.446328 | controller |
2025-12-01 09:06:40.446351 | controller | TASK [reproducer : Ensure networks are consistent] *****************************
2025-12-01 09:06:40.446360 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.089)       0:00:25.908 *******
2025-12-01 09:06:40.446375 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/network_assertions.yml for instance
2025-12-01 09:06:40.492574 | controller |
2025-12-01 09:06:40.492622 | controller | TASK [reproducer : Ensure all VM types have defined nets parameter] ************
2025-12-01 09:06:40.492633 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.042)       0:00:25.950 *******
2025-12-01 09:06:40.492647 | controller | ok: [instance] => (item=compute)
2025-12-01 09:06:40.492689 | controller | ok: [instance] => (item=controller)
2025-12-01 09:06:40.492698 | controller | ok: [instance] => (item=crc)
2025-12-01 09:06:40.492706 | controller |
2025-12-01 09:06:40.493030 | controller | TASK [reproducer : Ensure all standard attached networks are defined] **********
2025-12-01 09:06:40.533200 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.046)       0:00:25.996 *******
2025-12-01 09:06:40.533222 | controller | ok: [instance]
2025-12-01 09:06:40.575335 | controller |
2025-12-01 09:06:40.575353 | controller | TASK [reproducer : Ensure we don't have extra network defined] *****************
2025-12-01 09:06:40.575367 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.040)       0:00:26.037 *******
2025-12-01 09:06:40.575676 | controller | ok: [instance] => changed=false
2025-12-01 09:06:40.576040 | controller |   msg: All assertions passed
2025-12-01 09:06:40.576390 | controller |
2025-12-01 09:06:40.577357 | controller | TASK [Ensure we get latest OCP config] *****************************************
2025-12-01 09:06:40.577922 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.042)       0:00:26.079 *******
2025-12-01 09:06:40.616827 | controller |
2025-12-01 09:06:40.617094 | controller | TASK [devscripts : Gather the configurations to be passed to dev-scripts.] *****
2025-12-01 09:06:40.617310 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.041)       0:00:26.121 *******
2025-12-01 09:06:40.688169 | controller | ok: [instance] => (item={'working_dir': '/home/dev-scripts', 'assets_extra_folder': '/home/dev-scripts/assets', 'openshift_release_type': 'ga', 'openshift_version': '4.16.0', 'cluster_name': 'ocp', 'base_domain': 'openstack.lab', 'ntp_servers': 'clock.corp.redhat.com', 'provisioning_network_profile': 'Managed', 'provisioning_network': '172.22.0.0/24', 'cluster_subnet_v4': '192.168.16.0/20', 'cluster_host_prefix_v4': '23', 'service_subnet_v4': '172.30.0.0/16', 'external_subnet_v4': '192.168.111.0/24', 'num_masters': 3, 'num_workers': 0, 'ip_stack': 'v4', 'network_type': 'OVNKubernetes'})
2025-12-01 09:06:40.688372 | controller | ok: [instance] => (item={})
2025-12-01 09:06:40.688556 | controller |
2025-12-01 09:06:40.688704 | controller | TASK [devscripts : Replace OCP version if "stable-" alias used] ****************
2025-12-01 09:06:40.688842 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.071)       0:00:26.193 *******
2025-12-01 09:06:40.705809 | controller | skipping: [instance]
2025-12-01 09:06:40.706087 | controller |
2025-12-01 09:06:40.706344 | controller | TASK [devscripts : Ensure output directory exists] *****************************
2025-12-01 09:06:40.706554 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.017)       0:00:26.210 *******
2025-12-01 09:06:40.920840 | controller | ok: [instance]
2025-12-01 09:06:41.264607 | controller |
2025-12-01 09:06:41.264647 | controller | TASK [devscripts : Output built config for easier debugging] *******************
2025-12-01 09:06:41.264656 | controller | Monday 01 December 2025  09:06:40 +0000 (0:00:00.214)       0:00:26.425 *******
2025-12-01 09:06:41.264667 | controller | changed: [instance]
2025-12-01 09:06:41.982575 | controller |
2025-12-01 09:06:41.982609 | controller | TASK [ci_network : Remove the local dns configuration] *************************
2025-12-01 09:06:41.982619 | controller | Monday 01 December 2025  09:06:41 +0000 (0:00:00.343)       0:00:26.768 *******
2025-12-01 09:06:41.982632 | controller | ok: [instance] => (item=/etc/NetworkManager/conf.d/00-use-dnsmasq.conf)
2025-12-01 09:06:41.982663 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/98-cifmw-static-leases.conf)
2025-12-01 09:06:41.983115 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/99-cifmw-dns-forwarders.conf)
2025-12-01 09:06:42.008632 | controller | ok: [instance] => (item=/etc/NetworkManager/dnsmasq.d/97-cifmw-local-domain.conf)
2025-12-01 09:06:42.008679 | controller |
2025-12-01 09:06:42.008692 | controller | TASK [ci_network : Reload the NetworkManager to pick the changes] **************
2025-12-01 09:06:42.008703 | controller | Monday 01 December 2025  09:06:41 +0000 (0:00:00.718)       0:00:27.487 *******
2025-12-01 09:06:42.008719 | controller | skipping: [instance]
2025-12-01 09:06:42.008763 | controller |
2025-12-01 09:06:42.008778 | controller | TASK [reproducer : Ensure NetworkManager is configured to use dnsmasq] *********
2025-12-01 09:06:42.008816 | controller | Monday 01 December 2025  09:06:42 +0000 (0:00:00.026)       0:00:27.513 *******
2025-12-01 09:06:42.368575 | controller | changed: [instance]
2025-12-01 09:06:42.368705 | controller |
2025-12-01 09:06:42.368733 | controller | TASK [reproducer : Flush handlers for NetworkManager restart] ******************
2025-12-01 09:06:42.368741 | controller | Monday 01 December 2025  09:06:42 +0000 (0:00:00.350)       0:00:27.864 *******
2025-12-01 09:06:42.368747 | controller |
2025-12-01 09:06:42.368753 | controller | RUNNING HANDLER [reproducer : Restart NetworkManager] **************************
2025-12-01 09:06:42.368762 | controller | Monday 01 December 2025  09:06:42 +0000 (0:00:00.008)       0:00:27.872 *******
2025-12-01 09:06:42.784657 | controller | changed: [instance]
2025-12-01 09:06:42.857588 | controller |
2025-12-01 09:06:42.857632 | controller | TASK [Ensure no default networks exists] ***************************************
2025-12-01 09:06:42.857643 | controller | Monday 01 December 2025  09:06:42 +0000 (0:00:00.416)       0:00:28.288 *******
2025-12-01 09:06:42.857657 | controller |
2025-12-01 09:06:42.857691 | controller | TASK [libvirt_manager : Deactivate crc] ****************************************
2025-12-01 09:06:42.857850 | controller | Monday 01 December 2025  09:06:42 +0000 (0:00:00.073)       0:00:28.362 *******
2025-12-01 09:06:44.138243 | controller | ok: [instance]
2025-12-01 09:06:44.383119 | controller |
2025-12-01 09:06:44.383143 | controller | TASK [libvirt_manager : Removing crc] ******************************************
2025-12-01 09:06:44.383152 | controller | Monday 01 December 2025  09:06:44 +0000 (0:00:01.280)       0:00:29.642 *******
2025-12-01 09:06:44.383163 | controller | ok: [instance]
2025-12-01 09:06:44.922076 | controller |
2025-12-01 09:06:44.922112 | controller | TASK [libvirt_manager : Deactivate default] ************************************
2025-12-01 09:06:44.922121 | controller | Monday 01 December 2025  09:06:44 +0000 (0:00:00.245)       0:00:29.887 *******
2025-12-01 09:06:44.922132 | controller | ok: [instance]
2025-12-01 09:06:45.166036 | controller |
2025-12-01 09:06:45.166056 | controller | TASK [libvirt_manager : Removing default] **************************************
2025-12-01 09:06:45.166064 | controller | Monday 01 December 2025  09:06:44 +0000 (0:00:00.538)       0:00:30.426 *******
2025-12-01 09:06:45.166073 | controller | ok: [instance]
2025-12-01 09:06:46.353326 | controller |
2025-12-01 09:06:46.353349 | controller | TASK [reproducer : Ensure firewalld is installed] ******************************
2025-12-01 09:06:46.353356 | controller | Monday 01 December 2025  09:06:45 +0000 (0:00:00.244)       0:00:30.670 *******
2025-12-01 09:06:46.353365 | controller | ok: [instance]
2025-12-01 09:06:47.668566 | controller |
2025-12-01 09:06:47.668607 | controller | TASK [reproducer : Ensure firewalld service is enabled and started] ************
2025-12-01 09:06:47.668617 | controller | Monday 01 December 2025  09:06:46 +0000 (0:00:01.186)       0:00:31.857 *******
2025-12-01 09:06:47.668638 | controller | ok: [instance]
2025-12-01 09:06:47.668673 | controller |
2025-12-01 09:06:47.668823 | controller | TASK [reproducer : Ensure br_netfilter module is loaded] ***********************
2025-12-01 09:06:47.668856 | controller | Monday 01 December 2025  09:06:47 +0000 (0:00:01.315)       0:00:33.173 *******
2025-12-01 09:06:47.978212 | controller | changed: [instance]
2025-12-01 09:06:48.261124 | controller |
2025-12-01 09:06:48.261162 | controller | TASK [reproducer : Ensure IP forwarding is enabled] ****************************
2025-12-01 09:06:48.261173 | controller | Monday 01 December 2025  09:06:47 +0000 (0:00:00.309)       0:00:33.482 *******
2025-12-01 09:06:48.261185 | controller | changed: [instance]
2025-12-01 09:06:48.831407 | controller |
2025-12-01 09:06:48.831449 | controller | TASK [reproducer : Ensure the required parameters are loaded] ******************
2025-12-01 09:06:48.831471 | controller | Monday 01 December 2025  09:06:48 +0000 (0:00:00.282)       0:00:33.765 *******
2025-12-01 09:06:48.831484 | controller | changed: [instance] => (item=arptables)
2025-12-01 09:06:49.152596 | controller | changed: [instance] => (item=iptables)
2025-12-01 09:06:49.152633 | controller | changed: [instance] => (item=ip6tables)
2025-12-01 09:06:49.152644 | controller |
2025-12-01 09:06:49.152652 | controller | TASK [reproducer : Ensure firewall service is enabled and started] *************
2025-12-01 09:06:49.152660 | controller | Monday 01 December 2025  09:06:48 +0000 (0:00:00.570)       0:00:34.335 *******
2025-12-01 09:06:49.152673 | controller | ok: [instance]
2025-12-01 09:06:49.152706 | controller |
2025-12-01 09:06:49.152736 | controller | TASK [reproducer : Set cifmw_ci_nmstate_instance_config if needed] *************
2025-12-01 09:06:49.152863 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.321)       0:00:34.656 *******
2025-12-01 09:06:49.242137 | controller | ok: [instance]
2025-12-01 09:06:49.283535 | controller |
2025-12-01 09:06:49.283556 | controller | TASK [Ensure the required network configurations are applied] ******************
2025-12-01 09:06:49.283565 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.089)       0:00:34.746 *******
2025-12-01 09:06:49.283576 | controller |
2025-12-01 09:06:49.306611 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-12-01 09:06:49.306655 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.041)       0:00:34.788 *******
2025-12-01 09:06:49.306669 | controller | skipping: [instance]
2025-12-01 09:06:49.306719 | controller |
2025-12-01 09:06:49.306737 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-12-01 09:06:49.306747 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.023)       0:00:34.811 *******
2025-12-01 09:06:49.327398 | controller | skipping: [instance]
2025-12-01 09:06:49.347577 | controller |
2025-12-01 09:06:49.347608 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-12-01 09:06:49.347618 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.020)       0:00:34.831 *******
2025-12-01 09:06:49.347631 | controller | skipping: [instance]
2025-12-01 09:06:49.347817 | controller |
2025-12-01 09:06:49.347842 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-12-01 09:06:49.374313 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.020)       0:00:34.852 *******
2025-12-01 09:06:49.374334 | controller | skipping: [instance]
2025-12-01 09:06:49.412541 | controller |
2025-12-01 09:06:49.412563 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] **********************
2025-12-01 09:06:49.412572 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.026)       0:00:34.878 *******
2025-12-01 09:06:49.412582 | controller | ok: [instance]
2025-12-01 09:06:49.412755 | controller |
2025-12-01 09:06:49.412924 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] ***
2025-12-01 09:06:49.413065 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.038)       0:00:34.917 *******
2025-12-01 09:06:49.445320 | controller | skipping: [instance]
2025-12-01 09:06:49.445652 | controller |
2025-12-01 09:06:49.445919 | controller | TASK [ci_nmstate : Get k8s nodes] **********************************************
2025-12-01 09:06:49.446093 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.032)       0:00:34.949 *******
2025-12-01 09:06:49.467156 | controller | skipping: [instance]
2025-12-01 09:06:49.467424 | controller |
2025-12-01 09:06:49.467754 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ********
2025-12-01 09:06:49.468014 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.021)       0:00:34.971 *******
2025-12-01 09:06:49.524010 | controller | ok: [instance]
2025-12-01 09:06:49.524192 | controller |
2025-12-01 09:06:49.524364 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] *************************
2025-12-01 09:06:49.524573 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.056)       0:00:35.028 *******
2025-12-01 09:06:49.546630 | controller | skipping: [instance]
2025-12-01 09:06:49.546812 | controller |
2025-12-01 09:06:49.546994 | controller | TASK [ci_nmstate : Provision unmanaged nodes with nmstate] *********************
2025-12-01 09:06:49.547160 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.022)       0:00:35.051 *******
2025-12-01 09:06:49.593755 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_unmanaged_provision_node.yml for instance => (item=localhost)
2025-12-01 09:06:49.593906 | controller |
2025-12-01 09:06:49.594054 | controller | TASK [ci_nmstate : Ensure we have needed facts] ********************************
2025-12-01 09:06:49.594187 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.047)       0:00:35.098 *******
2025-12-01 09:06:49.913625 | controller | ok: [instance -> localhost]
2025-12-01 09:06:49.913686 | controller |
2025-12-01 09:06:49.913698 | controller | TASK [ci_nmstate : Install required packages on localhost] *********************
2025-12-01 09:06:49.913725 | controller | Monday 01 December 2025  09:06:49 +0000 (0:00:00.319)       0:00:35.418 *******
2025-12-01 09:06:57.607401 | controller | changed: [instance -> localhost]
2025-12-01 09:06:57.803240 | controller |
2025-12-01 09:06:57.803268 | controller | TASK [ci_nmstate : Create the target NMstate dump directory] *******************
2025-12-01 09:06:57.803277 | controller | Monday 01 December 2025  09:06:57 +0000 (0:00:07.693)       0:00:43.111 *******
2025-12-01 09:06:57.803286 | controller | changed: [instance]
2025-12-01 09:06:58.139608 | controller |
2025-12-01 09:06:58.139644 | controller | TASK [ci_nmstate : Save nmstate state for localhost] ***************************
2025-12-01 09:06:58.139654 | controller | Monday 01 December 2025  09:06:57 +0000 (0:00:00.196)       0:00:43.307 *******
2025-12-01 09:06:58.139677 | controller | changed: [instance]
2025-12-01 09:06:59.744553 | controller |
2025-12-01 09:06:59.744583 | controller | TASK [ci_nmstate : Apply the desidered state on localhost] *********************
2025-12-01 09:06:59.744592 | controller | Monday 01 December 2025  09:06:58 +0000 (0:00:00.336)       0:00:43.643 *******
2025-12-01 09:06:59.744602 | controller | changed: [instance -> localhost]
2025-12-01 09:07:00.085429 | controller |
2025-12-01 09:07:00.085454 | controller | TASK [ci_nmstate : Save nmstate debugging data for localhost] ******************
2025-12-01 09:07:00.085490 | controller | Monday 01 December 2025  09:06:59 +0000 (0:00:01.604)       0:00:45.248 *******
2025-12-01 09:07:00.085501 | controller | changed: [instance]
2025-12-01 09:07:00.115582 | controller |
2025-12-01 09:07:00.115622 | controller | TASK [reproducer : Select right domain] ****************************************
2025-12-01 09:07:00.115633 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.341)       0:00:45.589 *******
2025-12-01 09:07:00.115646 | controller | ok: [instance]
2025-12-01 09:07:00.115695 | controller |
2025-12-01 09:07:00.115844 | controller | TASK [Create the virtual networks] *********************************************
2025-12-01 09:07:00.115869 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.030)       0:00:45.620 *******
2025-12-01 09:07:00.251653 | controller |
2025-12-01 09:07:00.270778 | controller | TASK [libvirt_manager : Ensure fact is existing, and empty] ********************
2025-12-01 09:07:00.270801 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.135)       0:00:45.756 *******
2025-12-01 09:07:00.270813 | controller | ok: [instance]
2025-12-01 09:07:00.456488 | controller |
2025-12-01 09:07:00.456516 | controller | TASK [libvirt_manager : Check if we get an existing interfaces-info.yml] *******
2025-12-01 09:07:00.456526 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.019)       0:00:45.775 *******
2025-12-01 09:07:00.456537 | controller | ok: [instance]
2025-12-01 09:07:00.486378 | controller |
2025-12-01 09:07:00.486400 | controller | TASK [libvirt_manager : Load file if exists] ***********************************
2025-12-01 09:07:00.486409 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.185)       0:00:45.960 *******
2025-12-01 09:07:00.486420 | controller | skipping: [instance]
2025-12-01 09:07:00.516091 | controller |
2025-12-01 09:07:00.516110 | controller | TASK [libvirt_manager : Update fact with existing content] *********************
2025-12-01 09:07:00.516118 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.029)       0:00:45.990 *******
2025-12-01 09:07:00.516126 | controller | skipping: [instance]
2025-12-01 09:07:00.570067 | controller |
2025-12-01 09:07:00.570088 | controller | TASK [libvirt_manager : Generate VM list] **************************************
2025-12-01 09:07:00.570095 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.029)       0:00:46.020 *******
2025-12-01 09:07:00.570104 | controller | ok: [instance]
2025-12-01 09:07:00.692107 | controller |
2025-12-01 09:07:00.692128 | controller | TASK [libvirt_manager : Generate/update mapping all nodes] *********************
2025-12-01 09:07:00.692136 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.053)       0:00:46.074 *******
2025-12-01 09:07:00.692145 | controller | ok: [instance] => (item={'key': 'compute-0', 'value': 'compute'})
2025-12-01 09:07:00.726069 | controller | ok: [instance] => (item={'key': 'controller-0', 'value': 'controller'})
2025-12-01 09:07:00.726091 | controller | ok: [instance] => (item={'key': 'crc-0', 'value': 'crc'})
2025-12-01 09:07:00.726101 | controller |
2025-12-01 09:07:00.726108 | controller | TASK [libvirt_manager : Append baremetal nodes if needed] **********************
2025-12-01 09:07:00.726114 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.121)       0:00:46.196 *******
2025-12-01 09:07:00.726132 | controller | skipping: [instance]
2025-12-01 09:07:01.068225 | controller |
2025-12-01 09:07:01.068256 | controller | TASK [libvirt_manager : Dump MAC mapping] **************************************
2025-12-01 09:07:01.068265 | controller | Monday 01 December 2025  09:07:00 +0000 (0:00:00.034)       0:00:46.230 *******
2025-12-01 09:07:01.068275 | controller | changed: [instance]
2025-12-01 09:07:01.148620 | controller |
2025-12-01 09:07:01.148660 | controller | TASK [libvirt_manager : Add libvirt hosts into inventory and dump inventory file] ***
2025-12-01 09:07:01.148670 | controller | Monday 01 December 2025  09:07:01 +0000 (0:00:00.341)       0:00:46.572 *******
2025-12-01 09:07:01.148696 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding compute-0 to computes)
2025-12-01 09:07:01.148734 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding controller-0 to controllers)
2025-12-01 09:07:01.148742 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/add_vm_to_inventory.yml for instance => (item=Adding crc-0 to ocps)
2025-12-01 09:07:01.148749 | controller |
2025-12-01 09:07:01.148758 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 09:07:01.204545 | controller | Monday 01 December 2025  09:07:01 +0000 (0:00:00.080)       0:00:46.652 *******
2025-12-01 09:07:01.204582 | controller | changed: [instance]
2025-12-01 09:07:01.204911 | controller |
2025-12-01 09:07:01.502926 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 09:07:01.502945 | controller | Monday 01 December 2025  09:07:01 +0000 (0:00:00.056)       0:00:46.709 *******
2025-12-01 09:07:01.502956 | controller | changed: [instance]
2025-12-01 09:07:01.736112 | controller |
2025-12-01 09:07:01.736131 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 09:07:01.736139 | controller | Monday 01 December 2025  09:07:01 +0000 (0:00:00.298)       0:00:47.007 *******
2025-12-01 09:07:01.736147 | controller | changed: [instance]
2025-12-01 09:07:01.792539 | controller |
2025-12-01 09:07:01.792574 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 09:07:01.792583 | controller | Monday 01 December 2025  09:07:01 +0000 (0:00:00.233)       0:00:47.240 *******
2025-12-01 09:07:01.792594 | controller | changed: [instance]
2025-12-01 09:07:01.792813 | controller |
2025-12-01 09:07:01.792837 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 09:07:02.004668 | controller | Monday 01 December 2025  09:07:01 +0000 (0:00:00.056)       0:00:47.297 *******
2025-12-01 09:07:02.004721 | controller | changed: [instance]
2025-12-01 09:07:02.004755 | controller |
2025-12-01 09:07:02.004766 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 09:07:02.004829 | controller | Monday 01 December 2025  09:07:02 +0000 (0:00:00.211)       0:00:47.509 *******
2025-12-01 09:07:02.228434 | controller | changed: [instance]
2025-12-01 09:07:02.287626 | controller |
2025-12-01 09:07:02.287663 | controller | TASK [libvirt_manager : Add host to runtime inventory] *************************
2025-12-01 09:07:02.287677 | controller | Monday 01 December 2025  09:07:02 +0000 (0:00:00.223)       0:00:47.732 *******
2025-12-01 09:07:02.287693 | controller | changed: [instance]
2025-12-01 09:07:02.287704 | controller |
2025-12-01 09:07:02.287713 | controller | TASK [libvirt_manager : Ensure group section exists] ***************************
2025-12-01 09:07:02.287726 | controller | Monday 01 December 2025  09:07:02 +0000 (0:00:00.059)       0:00:47.792 *******
2025-12-01 09:07:02.496309 | controller | changed: [instance]
2025-12-01 09:07:02.721582 | controller |
2025-12-01 09:07:02.721614 | controller | TASK [libvirt_manager : Append host under proper group] ************************
2025-12-01 09:07:02.721622 | controller | Monday 01 December 2025  09:07:02 +0000 (0:00:00.208)       0:00:48.000 *******
2025-12-01 09:07:02.721632 | controller | changed: [instance]
2025-12-01 09:07:02.748626 | controller |
2025-12-01 09:07:02.748668 | controller | TASK [libvirt_manager : Convert ini inventory file into yaml] ******************
2025-12-01 09:07:02.748679 | controller | Monday 01 December 2025  09:07:02 +0000 (0:00:00.225)       0:00:48.225 *******
2025-12-01 09:07:02.748693 | controller | fatal: [instance]: FAILED! =>
2025-12-01 09:07:02.748728 | controller |   msg: |-
2025-12-01 09:07:02.748742 | controller |     The conditional check '(_cifmw_libvirt_manager_layout.vms[_vm_type].amount | default(1) | int) > 0' failed. The error was: error while evaluating conditional ((_cifmw_libvirt_manager_layout.vms[_vm_type].amount | default(1) | int) > 0): '_vm_type' is undefined. '_vm_type' is undefined
2025-12-01 09:07:02.748752 | controller |
2025-12-01 09:07:02.748759 | controller |     The error appears to be in '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/libvirt_manager/tasks/generate_networking_data.yml': line 137, column 7, but may
2025-12-01 09:07:02.748774 | controller |     be elsewhere in the file depending on the exact syntax problem.
2025-12-01 09:07:02.748803 | controller |
2025-12-01 09:07:02.749243 | controller |     The offending line appears to be:
2025-12-01 09:07:02.755548 | controller |
2025-12-01 09:07:02.755573 | controller |
2025-12-01 09:07:02.755581 | controller |         - name: Convert ini inventory file into yaml
2025-12-01 09:07:02.755588 | controller |           ^ here
2025-12-01 09:07:02.755600 | controller |
2025-12-01 09:07:02.755760 | controller | TASK [Create dnsmasq dir] ******************************************************
2025-12-01 09:07:02.943381 | controller | Monday 01 December 2025  09:07:02 +0000 (0:00:00.034)       0:00:48.260 *******
2025-12-01 09:07:02.943406 | controller | changed: [instance]
2025-12-01 09:07:03.145295 | controller |
2025-12-01 09:07:03.145317 | controller | TASK [Get journalctl in a file] ************************************************
2025-12-01 09:07:03.145327 | controller | Monday 01 December 2025  09:07:02 +0000 (0:00:00.187)       0:00:48.447 *******
2025-12-01 09:07:03.145338 | controller | changed: [instance]
2025-12-01 09:07:03.509361 | controller |
2025-12-01 09:07:03.509392 | controller | TASK [Dump journalctl output] **************************************************
2025-12-01 09:07:03.509400 | controller | Monday 01 December 2025  09:07:03 +0000 (0:00:00.201)       0:00:48.649 *******
2025-12-01 09:07:03.509410 | controller | changed: [instance]
2025-12-01 09:07:03.848952 | controller |
2025-12-01 09:07:03.848988 | controller | TASK [Copy all dnsmasq config] *************************************************
2025-12-01 09:07:03.848996 | controller | Monday 01 December 2025  09:07:03 +0000 (0:00:00.363)       0:00:49.013 *******
2025-12-01 09:07:03.849007 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.conf) => changed=false
2025-12-01 09:07:03.849032 | controller |   ansible_loop_var: item
2025-12-01 09:07:03.849511 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.conf /home/zuul/ci-framework-data/artifacts/dnsmasq
2025-12-01 09:07:03.849758 | controller |   item: /etc/cifmw-dnsmasq.conf
2025-12-01 09:07:03.849792 | controller |   msg: |-
2025-12-01 09:07:03.849800 | controller |     rsync: [sender] link_stat "/etc/cifmw-dnsmasq.conf" failed: No such file or directory (2)
2025-12-01 09:07:03.849811 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2025-12-01 09:07:04.164385 | controller |   rc: 23
2025-12-01 09:07:04.164422 | controller | failed: [instance] (item=/etc/cifmw-dnsmasq.d/) => changed=false
2025-12-01 09:07:04.164447 | controller |   ansible_loop_var: item
2025-12-01 09:07:04.164898 | controller |   cmd: /usr/bin/rsync --delay-updates -F --compress --archive --out-format='<<CHANGED>>%i %n%L' /etc/cifmw-dnsmasq.d/ /home/zuul/ci-framework-data/artifacts/dnsmasq
2025-12-01 09:07:04.164917 | controller |   item: /etc/cifmw-dnsmasq.d/
2025-12-01 09:07:04.169396 | controller |   msg: |-
2025-12-01 09:07:04.169428 | controller |     rsync: [sender] change_dir "/etc/cifmw-dnsmasq.d" failed: No such file or directory (2)
2025-12-01 09:07:04.169436 | controller |     rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1327) [sender=3.2.5]
2025-12-01 09:07:04.169442 | controller |   rc: 23
2025-12-01 09:07:04.169453 | controller |
2025-12-01 09:07:04.169515 | controller | PLAY RECAP *********************************************************************
2025-12-01 09:07:04.169532 | controller | instance                   : ok=108  changed=33   unreachable=0    failed=2    skipped=18   rescued=0    ignored=0
2025-12-01 09:07:04.169540 | controller |
2025-12-01 09:07:04.169545 | controller | Monday 01 December 2025  09:07:04 +0000 (0:00:00.658)       0:00:49.672 *******
2025-12-01 09:07:04.169551 | controller | ===============================================================================
2025-12-01 09:07:04.169556 | controller | libvirt_manager : Install packages required for using KVM --------------- 9.71s
2025-12-01 09:07:04.169561 | controller | ci_nmstate : Install required packages on localhost --------------------- 7.69s
2025-12-01 09:07:04.169566 | controller | ci_nmstate : Apply the desidered state on localhost --------------------- 1.60s
2025-12-01 09:07:04.169571 | controller | discover_latest_image : Get latest image -------------------------------- 1.52s
2025-12-01 09:07:04.169581 | controller | reproducer : Ensure firewalld service is enabled and started ------------ 1.32s
2025-12-01 09:07:04.169586 | controller | libvirt_manager : Deactivate crc ---------------------------------------- 1.28s
2025-12-01 09:07:04.169591 | controller | reproducer : Ensure firewalld is installed ------------------------------ 1.19s
2025-12-01 09:07:04.169596 | controller | libvirt_manager : Ensure firewalld service is up ------------------------ 0.91s
2025-12-01 09:07:04.169601 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-12-01 09:07:04.169606 | controller | ci_network : Remove the local dns configuration ------------------------- 0.72s
2025-12-01 09:07:04.169611 | controller | Copy all dnsmasq config ------------------------------------------------- 0.66s
2025-12-01 09:07:04.169617 | controller | libvirt_manager : Reload systemctl and start/enable virtproxyd.service --- 0.64s
2025-12-01 09:07:04.169622 | controller | libvirt_manager : Enable UNIX socket PolicyKit auth --------------------- 0.61s
2025-12-01 09:07:04.169627 | controller | reproducer : Ensure the required parameters are loaded ------------------ 0.57s
2025-12-01 09:07:04.169635 | controller | libvirt_manager : Restart service polkit service ------------------------ 0.57s
2025-12-01 09:07:04.169663 | controller | libvirt_manager : Deactivate default ------------------------------------ 0.54s
2025-12-01 09:07:04.169992 | controller | reproducer : Ensure directories are present ----------------------------- 0.45s
2025-12-01 09:07:04.170022 | controller | reproducer : Restart NetworkManager ------------------------------------- 0.42s
2025-12-01 09:07:04.227860 | controller | libvirt_manager : Add user if not exists in libvirt group --------------- 0.37s
2025-12-01 09:07:04.227883 | controller | Dump journalctl output -------------------------------------------------- 0.36s
2025-12-01 09:07:04.227895 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/reproducer/crc_layout/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/crc_layout/converge.yml']
2025-12-01 09:07:04.228334 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-01 09:07:04.237103 | controller | INFO     Running crc_layout > cleanup
2025-12-01 09:07:04.237590 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-01 09:07:04.245064 | controller | INFO     Running crc_layout > destroy
2025-12-01 09:07:04.245416 | controller | WARNING  Skipping, instances are delegated.
2025-12-01 09:07:04.245780 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-01 09:07:04.247301 | controller | INFO     Writing /tmp/report.html report.
2025-12-01 09:07:04.559178 | [controller] Waiting on logger
2025-12-01 09:07:09.746336 | [controller] Waiting on logger
2025-12-01 09:07:20.178675 | [controller] Waiting on logger
2025-12-01 09:07:29.559529 | [controller] Waiting on logger
2025-12-01 09:07:34.323306 | [Zuul] Log Stream did not terminate
2025-12-01 09:07:34.323927 | controller | ERROR
2025-12-01 09:07:34.324044 | controller | {
2025-12-01 09:07:34.324079 | controller |   "delta": "0:02:40.078817",
2025-12-01 09:07:34.324100 | controller |   "end": "2025-12-01 09:07:04.293320",
2025-12-01 09:07:34.324119 | controller |   "msg": "non-zero return code",
2025-12-01 09:07:34.324137 | controller |   "rc": 1,
2025-12-01 09:07:34.324156 | controller |   "start": "2025-12-01 09:04:24.214503"
2025-12-01 09:07:34.324172 | controller | }
failure
2025-12-01 09:07:34.372581 | 
2025-12-01 09:07:34.372672 | PLAY RECAP
2025-12-01 09:07:34.372717 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-01 09:07:34.372740 | 
2025-12-01 09:07:34.444438 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 09:07:34.445501 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 09:07:34.975607 | 
2025-12-01 09:07:34.975724 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-01 09:07:34.996271 | 
2025-12-01 09:07:34.996347 | TASK [Filter out host if needed]
2025-12-01 09:07:35.005013 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 09:07:35.009438 | 
2025-12-01 09:07:35.009505 | TASK [Ensure file is present]
2025-12-01 09:07:35.373177 | controller | ok
2025-12-01 09:07:35.379465 | 
2025-12-01 09:07:35.379531 | TASK [Manage molecule report file]
2025-12-01 09:07:35.398966 | [controller] Waiting on logger
2025-12-01 09:07:44.754405 | [controller] Waiting on logger
2025-12-01 09:07:55.186429 | [controller] Waiting on logger
2025-12-01 09:08:04.567276 | [controller] Waiting on logger
2025-12-01 09:08:05.752788 | [Zuul] Log Stream did not terminate
2025-12-01 09:08:05.753102 | controller | changed
2025-12-01 09:08:05.758636 | 
2025-12-01 09:08:05.758704 | TASK [Check if we get ci-framework-data basedir]
2025-12-01 09:08:06.002169 | controller | ok
2025-12-01 09:08:06.007663 | 
2025-12-01 09:08:06.007730 | TASK [Create ci-framework-data log directory for zuul]
2025-12-01 09:08:06.375199 | controller | changed
2025-12-01 09:08:06.380690 | 
2025-12-01 09:08:06.380759 | TASK [Copy ci-framework interesting files]
2025-12-01 09:08:11.408047 | controller | changed
2025-12-01 09:08:11.412903 | 
2025-12-01 09:08:11.412983 | TASK [Get SELinux listing]
2025-12-01 09:08:11.431313 | [controller] Waiting on logger
2025-12-01 09:08:20.786831 | [controller] Waiting on logger
2025-12-01 09:08:31.218492 | [controller] Waiting on logger
2025-12-01 09:08:40.599446 | [controller] Waiting on logger
2025-12-01 09:08:41.672419 | [Zuul] Log Stream did not terminate
2025-12-01 09:08:41.672698 | controller | changed
2025-12-01 09:08:41.679087 | 
2025-12-01 09:08:41.679151 | TASK [Generate log index]
2025-12-01 09:08:42.754276 | controller | changed
2025-12-01 09:08:42.758800 | 
2025-12-01 09:08:42.758857 | TASK [Get some env related data]
2025-12-01 09:08:42.788251 | [controller] Waiting on logger
2025-12-01 09:09:00.274347 | [controller] Waiting on logger
2025-12-01 09:09:02.450421 | [controller] Waiting on logger
2025-12-01 09:09:13.984484 | [Zuul] Log Stream did not terminate
2025-12-01 09:09:13.984776 | controller | changed
2025-12-01 09:09:13.989804 | 
2025-12-01 09:09:13.989870 | TASK [Generate list of logs to collect in home directory]
2025-12-01 09:09:14.365135 | controller | ok: All paths examined
2025-12-01 09:09:14.369887 | 
2025-12-01 09:09:14.369956 | LOOP [Copy logs from home directory]
2025-12-01 09:09:14.911420 | controller | changed:
2025-12-01 09:09:14.911652 | controller | {
2025-12-01 09:09:14.911680 | controller |   "atime": 1743544925.4788878,
2025-12-01 09:09:14.911705 | controller |   "ctime": 1743545329.1409318,
2025-12-01 09:09:14.911723 | controller |   "dev": 64513,
2025-12-01 09:09:14.911739 | controller |   "gid": 1000,
2025-12-01 09:09:14.911754 | controller |   "gr_name": "zuul",
2025-12-01 09:09:14.911768 | controller |   "inode": 4518807,
2025-12-01 09:09:14.911783 | controller |   "isblk": false,
2025-12-01 09:09:14.911805 | controller |   "ischr": false,
2025-12-01 09:09:14.911823 | controller |   "isdir": false,
2025-12-01 09:09:14.911841 | controller |   "isfifo": false,
2025-12-01 09:09:14.911875 | controller |   "isgid": false,
2025-12-01 09:09:14.911898 | controller |   "islnk": false,
2025-12-01 09:09:14.912052 | controller |   "isreg": true,
2025-12-01 09:09:14.912085 | controller |   "issock": false,
2025-12-01 09:09:14.912105 | controller |   "isuid": false,
2025-12-01 09:09:14.912124 | controller |   "mode": "0644",
2025-12-01 09:09:14.912141 | controller |   "mtime": 1743545329.1409318,
2025-12-01 09:09:14.912157 | controller |   "nlink": 1,
2025-12-01 09:09:14.912172 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-01 09:09:14.912186 | controller |   "pw_name": "zuul",
2025-12-01 09:09:14.912200 | controller |   "rgrp": true,
2025-12-01 09:09:14.912214 | controller |   "roth": true,
2025-12-01 09:09:14.912228 | controller |   "rusr": true,
2025-12-01 09:09:14.912242 | controller |   "size": 4108,
2025-12-01 09:09:14.912256 | controller |   "uid": 1000,
2025-12-01 09:09:14.912270 | controller |   "wgrp": false,
2025-12-01 09:09:14.912283 | controller |   "woth": false,
2025-12-01 09:09:14.912298 | controller |   "wusr": true,
2025-12-01 09:09:14.912312 | controller |   "xgrp": false,
2025-12-01 09:09:14.912326 | controller |   "xoth": false,
2025-12-01 09:09:14.912343 | controller |   "xusr": false
2025-12-01 09:09:14.912356 | controller | }
2025-12-01 09:09:15.435360 | controller | changed:
2025-12-01 09:09:15.435423 | controller | {
2025-12-01 09:09:15.435452 | controller |   "atime": 1743545331.0429637,
2025-12-01 09:09:15.435469 | controller |   "ctime": 1743545842.0111232,
2025-12-01 09:09:15.435485 | controller |   "dev": 64513,
2025-12-01 09:09:15.435500 | controller |   "gid": 1000,
2025-12-01 09:09:15.435515 | controller |   "gr_name": "zuul",
2025-12-01 09:09:15.435530 | controller |   "inode": 4194437,
2025-12-01 09:09:15.435544 | controller |   "isblk": false,
2025-12-01 09:09:15.435558 | controller |   "ischr": false,
2025-12-01 09:09:15.435584 | controller |   "isdir": false,
2025-12-01 09:09:15.435598 | controller |   "isfifo": false,
2025-12-01 09:09:15.435611 | controller |   "isgid": false,
2025-12-01 09:09:15.435624 | controller |   "islnk": false,
2025-12-01 09:09:15.435637 | controller |   "isreg": true,
2025-12-01 09:09:15.435649 | controller |   "issock": false,
2025-12-01 09:09:15.435661 | controller |   "isuid": false,
2025-12-01 09:09:15.435673 | controller |   "mode": "0644",
2025-12-01 09:09:15.435686 | controller |   "mtime": 1743545842.0111232,
2025-12-01 09:09:15.435698 | controller |   "nlink": 1,
2025-12-01 09:09:15.435711 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-01 09:09:15.435724 | controller |   "pw_name": "zuul",
2025-12-01 09:09:15.435736 | controller |   "rgrp": true,
2025-12-01 09:09:15.435749 | controller |   "roth": true,
2025-12-01 09:09:15.435761 | controller |   "rusr": true,
2025-12-01 09:09:15.435773 | controller |   "size": 4023,
2025-12-01 09:09:15.435785 | controller |   "uid": 1000,
2025-12-01 09:09:15.435797 | controller |   "wgrp": false,
2025-12-01 09:09:15.435810 | controller |   "woth": false,
2025-12-01 09:09:15.435822 | controller |   "wusr": true,
2025-12-01 09:09:15.435834 | controller |   "xgrp": false,
2025-12-01 09:09:15.435846 | controller |   "xoth": false,
2025-12-01 09:09:15.435868 | controller |   "xusr": false
2025-12-01 09:09:15.435883 | controller | }
2025-12-01 09:09:15.954826 | controller | changed:
2025-12-01 09:09:15.954931 | controller | {
2025-12-01 09:09:15.954961 | controller |   "atime": 1764579846.5590892,
2025-12-01 09:09:15.954980 | controller |   "ctime": 1764579861.7523043,
2025-12-01 09:09:15.955004 | controller |   "dev": 64513,
2025-12-01 09:09:15.955021 | controller |   "gid": 1000,
2025-12-01 09:09:15.955037 | controller |   "gr_name": "zuul",
2025-12-01 09:09:15.955051 | controller |   "inode": 4340282,
2025-12-01 09:09:15.955066 | controller |   "isblk": false,
2025-12-01 09:09:15.955082 | controller |   "ischr": false,
2025-12-01 09:09:15.955098 | controller |   "isdir": false,
2025-12-01 09:09:15.955113 | controller |   "isfifo": false,
2025-12-01 09:09:15.955130 | controller |   "isgid": false,
2025-12-01 09:09:15.955146 | controller |   "islnk": false,
2025-12-01 09:09:15.955169 | controller |   "isreg": true,
2025-12-01 09:09:15.955183 | controller |   "issock": false,
2025-12-01 09:09:15.955198 | controller |   "isuid": false,
2025-12-01 09:09:15.955212 | controller |   "mode": "0644",
2025-12-01 09:09:15.955225 | controller |   "mtime": 1764579861.7523043,
2025-12-01 09:09:15.955239 | controller |   "nlink": 1,
2025-12-01 09:09:15.955253 | controller |   "path": "/home/zuul/ansible.log",
2025-12-01 09:09:15.955267 | controller |   "pw_name": "zuul",
2025-12-01 09:09:15.955281 | controller |   "rgrp": true,
2025-12-01 09:09:15.955295 | controller |   "roth": true,
2025-12-01 09:09:15.955308 | controller |   "rusr": true,
2025-12-01 09:09:15.955322 | controller |   "size": 6749,
2025-12-01 09:09:15.955336 | controller |   "uid": 1000,
2025-12-01 09:09:15.955350 | controller |   "wgrp": false,
2025-12-01 09:09:15.955364 | controller |   "woth": false,
2025-12-01 09:09:15.955378 | controller |   "wusr": true,
2025-12-01 09:09:15.955391 | controller |   "xgrp": false,
2025-12-01 09:09:15.955405 | controller |   "xoth": false,
2025-12-01 09:09:15.955419 | controller |   "xusr": false
2025-12-01 09:09:15.955432 | controller | }
2025-12-01 09:09:15.964537 | 
2025-12-01 09:09:15.964599 | TASK [Copy crio stats log file]
2025-12-01 09:09:15.977708 | controller | skipping: Conditional result was False
2025-12-01 09:09:15.982461 | 
2025-12-01 09:09:15.982527 | TASK [Get SELinux related data]
2025-12-01 09:09:20.946421 | [controller] Waiting on logger
2025-12-01 09:09:21.003000 | controller | ERROR
2025-12-01 09:09:21.003142 | controller | {
2025-12-01 09:09:21.003171 | controller |   "delta": "0:00:00.009424",
2025-12-01 09:09:21.003191 | controller |   "end": "2025-12-01 09:09:16.246452",
2025-12-01 09:09:21.003206 | controller |   "msg": "non-zero return code",
2025-12-01 09:09:21.003221 | controller |   "rc": 1,
2025-12-01 09:09:21.003235 | controller |   "start": "2025-12-01 09:09:16.237028"
2025-12-01 09:09:21.003250 | controller | }
2025-12-01 09:09:21.003271 | controller | ERROR: Ignoring Errors
2025-12-01 09:09:21.008103 | 
2025-12-01 09:09:21.008164 | TASK [Create system configuration directory]
2025-12-01 09:09:21.248920 | controller | changed
2025-12-01 09:09:21.253565 | 
2025-12-01 09:09:21.253623 | TASK [Get some of the system configurations]
2025-12-01 09:09:23.122491 | [controller] Waiting on logger
2025-12-01 09:09:26.284134 | controller | changed
2025-12-01 09:09:26.289170 | 
2025-12-01 09:09:26.289232 | TASK [Copy generated documentation if available]
2025-12-01 09:09:26.302607 | controller | skipping: Conditional result was False
2025-12-01 09:09:26.307598 | 
2025-12-01 09:09:26.307663 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-01 09:09:26.320430 | controller | skipping: Conditional result was False
2025-12-01 09:09:26.325202 | 
2025-12-01 09:09:26.325259 | TASK [Compress logs bigger than 2MB]
2025-12-01 09:09:26.343704 | [controller] Waiting on logger
2025-12-01 09:09:35.703348 | [controller] Waiting on logger
2025-12-01 09:09:46.098452 | [controller] Waiting on logger
2025-12-01 09:09:56.530465 | [controller] Waiting on logger
2025-12-01 09:09:56.583156 | [Zuul] Log Stream did not terminate
2025-12-01 09:09:56.583365 | controller | changed
2025-12-01 09:09:56.588315 | 
2025-12-01 09:09:56.588373 | TASK [Copy files from workspace on node]
2025-12-01 09:09:56.606072 | controller | ok
2025-12-01 09:09:56.625994 | 
2025-12-01 09:09:56.626063 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 09:09:56.639713 | controller | skipping: Conditional result was False
2025-12-01 09:09:56.645690 | 
2025-12-01 09:09:56.645752 | TASK [fetch-output : Set log path for single node]
2025-12-01 09:09:56.673599 | controller | ok
2025-12-01 09:09:56.678099 | 
2025-12-01 09:09:56.678159 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 09:09:56.857306 | controller -> localhost | ok: "/var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/logs"
2025-12-01 09:09:56.857554 | controller -> localhost | changed: All items complete
2025-12-01 09:09:56.857583 | 
2025-12-01 09:09:57.023869 | controller -> localhost | changed: "/var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/artifacts"
2025-12-01 09:09:57.191172 | controller -> localhost | changed: "/var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/docs"
2025-12-01 09:09:57.204408 | 
2025-12-01 09:09:57.204509 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 09:09:57.773990 | controller | changed:
2025-12-01 09:09:57.774266 | controller | .d..t...... ./
2025-12-01 09:09:57.774303 | controller | >f+++++++++ README.html
2025-12-01 09:09:57.774325 | controller | >f+++++++++ ansible-execution.log
2025-12-01 09:09:57.774344 | controller | >f+++++++++ ansible.log
2025-12-01 09:09:57.774362 | controller | >f+++++++++ crc-setup.log
2025-12-01 09:09:57.774378 | controller | >f+++++++++ crc-start.log
2025-12-01 09:09:57.774394 | controller | >f+++++++++ dmesg.log
2025-12-01 09:09:57.774411 | controller | >f+++++++++ installed-pkgs.log
2025-12-01 09:09:57.774427 | controller | >f+++++++++ python.log
2025-12-01 09:09:57.774442 | controller | >f+++++++++ registries.conf
2025-12-01 09:09:57.774457 | controller | >f+++++++++ report.html
2025-12-01 09:09:57.774472 | controller | >f+++++++++ selinux-denials.log
2025-12-01 09:09:57.774489 | controller | >f+++++++++ selinux-listing.log
2025-12-01 09:09:57.774505 | controller | cd+++++++++ ci-framework-data/
2025-12-01 09:09:57.774520 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-01 09:09:57.774535 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-01 09:09:57.774550 | controller | cd+++++++++ ci-framework-data/artifacts/dnsmasq/
2025-12-01 09:09:57.774563 | controller | >f+++++++++ ci-framework-data/artifacts/dnsmasq/journalctl.log
2025-12-01 09:09:57.774577 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-01 09:09:57.774591 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-01 09:09:57.774605 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-01 09:09:57.774619 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-01 09:09:57.774633 | controller | cd+++++++++ registries.conf.d/
2025-12-01 09:09:57.774647 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-01 09:09:57.774661 | controller | cd+++++++++ system-config/
2025-12-01 09:09:57.774675 | controller | cd+++++++++ system-config/libvirt/
2025-12-01 09:09:57.774688 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-01 09:09:57.774702 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-01 09:09:57.774715 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-01 09:09:57.774728 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-01 09:09:57.774742 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-01 09:09:57.774755 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-01 09:09:57.774768 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-01 09:09:57.774781 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-01 09:09:57.774794 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-01 09:09:57.774807 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-01 09:09:57.774820 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-01 09:09:57.774838 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-01 09:09:57.774861 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-01 09:09:57.774877 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-01 09:09:57.774891 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-01 09:09:57.774904 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-01 09:09:58.176650 | controller | changed: .d..t...... ./
2025-12-01 09:09:58.597630 | controller | changed: .d..t...... ./
2025-12-01 09:09:58.618375 | 
2025-12-01 09:09:58.618482 | TASK [Return artifact to Zuul]
2025-12-01 09:09:58.646324 | controller | ok
2025-12-01 09:09:58.664938 | 
2025-12-01 09:09:58.664988 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-01 09:09:58.665081 | 
2025-12-01 09:09:58.665107 | PLAY RECAP
2025-12-01 09:09:58.665142 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-01 09:09:58.665162 | 
2025-12-01 09:09:58.741093 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 09:09:58.742951 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 09:09:59.220117 | 
2025-12-01 09:09:59.220209 | PLAY [all]
2025-12-01 09:09:59.237140 | 
2025-12-01 09:09:59.237205 | TASK [include_role : fetch-output]
2025-12-01 09:09:59.265241 | controller | ok
2025-12-01 09:09:59.280416 | 
2025-12-01 09:09:59.280488 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 09:09:59.324394 | controller | skipping: Conditional result was False
2025-12-01 09:09:59.329679 | 
2025-12-01 09:09:59.329747 | TASK [fetch-output : Set log path for single node]
2025-12-01 09:09:59.357808 | controller | ok
2025-12-01 09:09:59.362711 | 
2025-12-01 09:09:59.362780 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 09:09:59.661116 | controller -> localhost | ok: "/var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/logs"
2025-12-01 09:09:59.829061 | controller -> localhost | ok: "/var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/artifacts"
2025-12-01 09:09:59.993993 | controller -> localhost | ok: "/var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/docs"
2025-12-01 09:10:00.010106 | 
2025-12-01 09:10:00.010211 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 09:10:00.550768 | controller | ok
2025-12-01 09:10:00.550999 | controller | ok: All items complete
2025-12-01 09:10:00.551031 | 
2025-12-01 09:10:00.981360 | controller | ok
2025-12-01 09:10:01.396463 | controller | ok
2025-12-01 09:10:01.413879 | 
2025-12-01 09:10:01.414016 | TASK [include_role : fetch-output-openshift]
2025-12-01 09:10:01.428065 | controller | skipping: Conditional result was False
2025-12-01 09:10:01.433744 | 
2025-12-01 09:10:01.433810 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 09:10:01.758357 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007106
2025-12-01 09:10:01.939250 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007340
2025-12-01 09:10:01.967511 | 
2025-12-01 09:10:01.967601 | PLAY [all]
2025-12-01 09:10:01.980961 | 
2025-12-01 09:10:01.981031 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-01 09:10:02.427346 | controller | changed
2025-12-01 09:10:02.448291 | 
2025-12-01 09:10:02.448342 | PLAY RECAP
2025-12-01 09:10:02.448386 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-01 09:10:02.448406 | 
2025-12-01 09:10:02.515138 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 09:10:02.515837 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-01 09:10:03.037664 | 
2025-12-01 09:10:03.037767 | PLAY [localhost]
2025-12-01 09:10:03.054118 | 
2025-12-01 09:10:03.054191 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 09:10:03.335988 | localhost | changed
2025-12-01 09:10:03.340289 | 
2025-12-01 09:10:03.340370 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 09:10:03.368244 | localhost | ok
2025-12-01 09:10:03.375450 | 
2025-12-01 09:10:03.375518 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-01 09:10:03.655312 | localhost | changed
2025-12-01 09:10:03.660809 | 
2025-12-01 09:10:03.660895 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-01 09:10:04.153979 | localhost | changed
2025-12-01 09:10:04.158973 | 
2025-12-01 09:10:04.159045 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-01 09:10:04.469982 | localhost | Identity added: /var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/tmp/ansible.3km6_b3q (/var/lib/zuul/builds/898b614aca654111b842515d9cbe567b/work/tmp/ansible.3km6_b3q)
2025-12-01 09:10:04.470127 | localhost | ok: Runtime: 0:00:00.005881
2025-12-01 09:10:04.474197 | 
2025-12-01 09:10:04.474262 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-01 09:10:04.684286 | localhost | ok: Runtime: 0:00:00.004076
2025-12-01 09:10:04.688795 | 
2025-12-01 09:10:04.688872 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-01 09:10:04.736666 | localhost | changed
2025-12-01 09:10:04.741354 | 
2025-12-01 09:10:04.741422 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-01 09:10:05.042041 | localhost | changed
2025-12-01 09:10:05.060033 | 
2025-12-01 09:10:05.060094 | PLAY [localhost]
2025-12-01 09:10:05.070556 | 
2025-12-01 09:10:05.070616 | TASK [Generate bulk log download script]
2025-12-01 09:10:05.088493 | localhost | ok
2025-12-01 09:10:05.099073 | 
2025-12-01 09:10:05.099138 | TASK [local-log-download : Check API endpoint is defined]
2025-12-01 09:10:05.126382 | localhost | ok: All assertions passed
2025-12-01 09:10:05.130450 | 
2025-12-01 09:10:05.130516 | TASK [local-log-download : Create download script]
2025-12-01 09:10:05.458606 | localhost -> localhost | changed
2025-12-01 09:10:05.467106 | 
2025-12-01 09:10:05.467186 | TASK [Register quick-download link]
2025-12-01 09:10:05.484447 | localhost | ok
2025-12-01 09:10:05.521676 | 
2025-12-01 09:10:05.521735 | PLAY [logserver.rdoproject.org]
2025-12-01 09:10:05.530047 | 
2025-12-01 09:10:05.530102 | TASK [Set zuul-log-path fact]
2025-12-01 09:10:05.545324 | logserver.rdoproject.org | ok
2025-12-01 09:10:05.553078 | 
2025-12-01 09:10:05.553139 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 09:10:05.568718 | logserver.rdoproject.org | ok
2025-12-01 09:10:05.573539 | 
2025-12-01 09:10:05.573605 | TASK [upload-logs : Create log directories]
2025-12-01 09:10:07.261458 | logserver.rdoproject.org | changed
2025-12-01 09:10:07.264488 | 
2025-12-01 09:10:07.264549 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-01 09:10:07.470407 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004420
2025-12-01 09:10:07.474514 | 
2025-12-01 09:10:07.474592 | TASK [upload-logs : Upload logs to log server]
2025-12-01 09:10:09.211091 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-01 09:10:09.213800 | 
2025-12-01 09:10:09.213878 | LOOP [upload-logs : Compress console log and json output]
2025-12-01 09:10:09.259474 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:10:09.267824 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:10:09.272133 | 
2025-12-01 09:10:09.272204 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-01 09:10:09.316041 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:10:09.316258 | 
2025-12-01 09:10:09.319904 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 09:10:09.332100 | 
2025-12-01 09:10:09.332169 | LOOP [upload-logs : Upload console log and json output]