2026-01-25 07:46:00.491710 | Job console starting...
2026-01-25 07:46:00.755981 | Updating repositories
2026-01-25 07:46:01.520038 | Preparing job workspace
2026-01-25 07:46:09.749665 | Running Ansible setup...
2026-01-25 07:46:14.156125 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-25 07:46:14.913609 | 
2026-01-25 07:46:14.913708 | PLAY [localhost]
2026-01-25 07:46:14.933852 | 
2026-01-25 07:46:14.933930 | TASK [Gathering Facts]
2026-01-25 07:46:15.899405 | localhost | ok
2026-01-25 07:46:15.916717 | 
2026-01-25 07:46:15.916848 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-25 07:46:16.275049 | localhost -> localhost | changed
2026-01-25 07:46:16.280509 | 
2026-01-25 07:46:16.280603 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-25 07:46:17.062708 | localhost -> localhost | changed
2026-01-25 07:46:17.071136 | 
2026-01-25 07:46:17.071232 | TASK [Setup log path fact]
2026-01-25 07:46:17.088433 | localhost | ok
2026-01-25 07:46:17.100563 | 
2026-01-25 07:46:17.100638 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 07:46:17.120644 | localhost | ok
2026-01-25 07:46:17.128526 | 
2026-01-25 07:46:17.128620 | TASK [emit-job-header : Print job information]
2026-01-25 07:46:17.155537 | # Job Information
2026-01-25 07:46:17.155664 | Ansible Version: 2.15.12
2026-01-25 07:46:17.155694 | Job: cifmw-molecule-shiftstack
2026-01-25 07:46:17.155718 | Pipeline: github-check
2026-01-25 07:46:17.155738 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-25 07:46:17.155758 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3619
2026-01-25 07:46:17.155778 | Log URL (when completed): https://logserver.rdoproject.org/392/rdoproject.org/392a17c260d846b8b5bfd9c41166ad35/
2026-01-25 07:46:17.155798 | Event ID: 782b3520-f9c0-11f0-9fc9-b2aac073cae4
2026-01-25 07:46:17.159630 | 
2026-01-25 07:46:17.159698 | LOOP [emit-job-header : Print node information]
2026-01-25 07:46:17.246994 | localhost | ok:
2026-01-25 07:46:17.247153 | localhost | # Node Information
2026-01-25 07:46:17.247198 | localhost | Inventory Hostname: controller
2026-01-25 07:46:17.247228 | localhost | Hostname: np0005594930
2026-01-25 07:46:17.247251 | localhost | Username: zuul
2026-01-25 07:46:17.247273 | localhost | Distro: CentOS 9
2026-01-25 07:46:17.247308 | localhost | Provider: ibm-bm4-nodepool
2026-01-25 07:46:17.247336 | localhost | Region: regionOne
2026-01-25 07:46:17.247356 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2026-01-25 07:46:17.247375 | localhost | Product Name: OpenStack Compute
2026-01-25 07:46:17.247393 | localhost | Interface IP: 192.168.26.235
2026-01-25 07:46:17.272378 | 
2026-01-25 07:46:17.272454 | PLAY [all]
2026-01-25 07:46:17.278604 | 
2026-01-25 07:46:17.278666 | TASK [Gather network facts]
2026-01-25 07:46:17.728216 | controller | ok
2026-01-25 07:46:17.744412 | 
2026-01-25 07:46:17.744494 | TASK [include_role : start-zuul-console]
2026-01-25 07:46:17.765600 | controller | ok
2026-01-25 07:46:17.777415 | 
2026-01-25 07:46:17.777489 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-25 07:46:18.189545 | controller | ok
2026-01-25 07:46:18.203938 | 
2026-01-25 07:46:18.204019 | TASK [include_role : add-build-sshkey]
2026-01-25 07:46:18.221725 | controller | ok
2026-01-25 07:46:18.237390 | 
2026-01-25 07:46:18.237513 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-25 07:46:18.474284 | controller -> localhost | ok
2026-01-25 07:46:18.481151 | 
2026-01-25 07:46:18.481247 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-25 07:46:18.510006 | controller | ok
2026-01-25 07:46:18.523698 | controller | included: /var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-25 07:46:18.530425 | 
2026-01-25 07:46:18.530498 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-25 07:46:19.021499 | controller -> localhost | Generating public/private rsa key pair.
2026-01-25 07:46:19.021689 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/392a17c260d846b8b5bfd9c41166ad35_id_rsa.
2026-01-25 07:46:19.021726 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/392a17c260d846b8b5bfd9c41166ad35_id_rsa.pub.
2026-01-25 07:46:19.021752 | controller -> localhost | The key fingerprint is:
2026-01-25 07:46:19.021773 | controller -> localhost | SHA256:9rpoV/3c8gN8VsYbP3z8MFh4xwMyOTG1EiWA5zJPGdw zuul-build-sshkey
2026-01-25 07:46:19.021792 | controller -> localhost | The key's randomart image is:
2026-01-25 07:46:19.021810 | controller -> localhost | +---[RSA 3072]----+
2026-01-25 07:46:19.021828 | controller -> localhost | |        o.o==o   |
2026-01-25 07:46:19.021846 | controller -> localhost | |       . + E+..  |
2026-01-25 07:46:19.021864 | controller -> localhost | |        o o.+o.o |
2026-01-25 07:46:19.021883 | controller -> localhost | |       o +  o oo*|
2026-01-25 07:46:19.021901 | controller -> localhost | |        S  ..+.+*|
2026-01-25 07:46:19.021918 | controller -> localhost | |       . o. ooo*+|
2026-01-25 07:46:19.021935 | controller -> localhost | |         ..  o+++|
2026-01-25 07:46:19.021952 | controller -> localhost | |      ....    +.o|
2026-01-25 07:46:19.021968 | controller -> localhost | |     ...o.     oo|
2026-01-25 07:46:19.021983 | controller -> localhost | +----[SHA256]-----+
2026-01-25 07:46:19.022023 | controller -> localhost | ok: Runtime: 0:00:00.101664
2026-01-25 07:46:19.027915 | 
2026-01-25 07:46:19.027986 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-25 07:46:19.056450 | controller | ok
2026-01-25 07:46:19.066352 | controller | included: /var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-25 07:46:19.074513 | 
2026-01-25 07:46:19.074579 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-25 07:46:19.098630 | controller | skipping: Conditional result was False
2026-01-25 07:46:19.105151 | 
2026-01-25 07:46:19.105239 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-25 07:46:19.592206 | controller | changed
2026-01-25 07:46:19.598691 | 
2026-01-25 07:46:19.598768 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-25 07:46:19.912557 | controller | ok
2026-01-25 07:46:19.917649 | 
2026-01-25 07:46:19.917730 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-25 07:46:21.177224 | controller | changed
2026-01-25 07:46:21.182793 | 
2026-01-25 07:46:21.182876 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-25 07:46:22.384029 | controller | changed
2026-01-25 07:46:22.391568 | 
2026-01-25 07:46:22.391643 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-25 07:46:22.432507 | controller | skipping: Conditional result was False
2026-01-25 07:46:22.438596 | 
2026-01-25 07:46:22.438675 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-25 07:46:23.072631 | controller -> localhost | changed
2026-01-25 07:46:23.084082 | 
2026-01-25 07:46:23.084170 | TASK [add-build-sshkey : Add back temp key]
2026-01-25 07:46:23.345467 | controller -> localhost | Identity added: /var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/392a17c260d846b8b5bfd9c41166ad35_id_rsa (zuul-build-sshkey)
2026-01-25 07:46:23.345633 | controller -> localhost | ok: Runtime: 0:00:00.007306
2026-01-25 07:46:23.351761 | 
2026-01-25 07:46:23.351832 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-25 07:46:23.824824 | controller | ok
2026-01-25 07:46:23.829547 | 
2026-01-25 07:46:23.829622 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-25 07:46:23.866500 | controller | skipping: Conditional result was False
2026-01-25 07:46:23.876635 | 
2026-01-25 07:46:23.876716 | TASK [include_role : validate-host]
2026-01-25 07:46:23.895409 | controller | ok
2026-01-25 07:46:23.916878 | 
2026-01-25 07:46:23.916958 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-25 07:46:23.956125 | controller | ok
2026-01-25 07:46:24.003273 | 
2026-01-25 07:46:24.003397 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-25 07:46:24.273523 | controller -> localhost | ok
2026-01-25 07:46:24.279494 | 
2026-01-25 07:46:24.279577 | TASK [validate-host : Collect information about the host]
2026-01-25 07:46:25.019738 | controller | ok
2026-01-25 07:46:25.028631 | 
2026-01-25 07:46:25.028716 | TASK [validate-host : Sanitize hostname]
2026-01-25 07:46:25.088627 | controller | ok
2026-01-25 07:46:25.095963 | 
2026-01-25 07:46:25.096048 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-25 07:46:25.608959 | controller -> localhost | changed
2026-01-25 07:46:25.614586 | 
2026-01-25 07:46:25.614655 | TASK [validate-host : Collect information about zuul worker]
2026-01-25 07:46:25.998683 | controller | ok
2026-01-25 07:46:26.003618 | 
2026-01-25 07:46:26.003700 | TASK [validate-host : Write out all zuul information for each host]
2026-01-25 07:46:26.406146 | controller -> localhost | changed
2026-01-25 07:46:26.416725 | 
2026-01-25 07:46:26.416813 | TASK [include_role : prepare-workspace-openshift]
2026-01-25 07:46:26.453957 | controller | skipping: Conditional result was False
2026-01-25 07:46:26.465654 | 
2026-01-25 07:46:26.465746 | TASK [include_role : remove-zuul-sshkey]
2026-01-25 07:46:26.510170 | controller | skipping: Conditional result was False
2026-01-25 07:46:26.515799 | 
2026-01-25 07:46:26.515874 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-25 07:46:26.808900 | controller | ok: "logs"
2026-01-25 07:46:26.809124 | controller | ok: All items complete
2026-01-25 07:46:26.809155 | 
2026-01-25 07:46:27.061680 | controller | ok: "artifacts"
2026-01-25 07:46:27.332242 | controller | ok: "docs"
2026-01-25 07:46:27.340814 | 
2026-01-25 07:46:27.340924 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-25 07:46:27.621655 | controller | changed: "logs"
2026-01-25 07:46:27.874273 | controller | changed: "artifacts"
2026-01-25 07:46:28.144091 | controller | changed: "docs"
2026-01-25 07:46:28.175895 | 
2026-01-25 07:46:28.175986 | PLAY RECAP
2026-01-25 07:46:28.176034 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-25 07:46:28.176062 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-25 07:46:28.176081 | 
2026-01-25 07:46:28.266921 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-25 07:46:28.267640 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-25 07:46:28.849547 | 
2026-01-25 07:46:28.849654 | PLAY [all]
2026-01-25 07:46:28.869631 | 
2026-01-25 07:46:28.869712 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-25 07:46:28.918318 | controller | ok
2026-01-25 07:46:28.923540 | 
2026-01-25 07:46:28.923711 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-25 07:46:29.351520 | controller | changed
2026-01-25 07:46:29.357081 | 
2026-01-25 07:46:29.357164 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-25 07:46:30.665976 | controller | changed
2026-01-25 07:46:30.676538 | 
2026-01-25 07:46:30.676632 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-25 07:46:31.128846 | controller | changed:
2026-01-25 07:46:31.129011 | controller | {
2026-01-25 07:46:31.129049 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-25 07:46:31.129081 | controller | }
2026-01-25 07:46:31.436512 | controller | changed:
2026-01-25 07:46:31.436593 | controller | {
2026-01-25 07:46:31.436631 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-25 07:46:31.436654 | controller | }
2026-01-25 07:46:31.728596 | controller | changed:
2026-01-25 07:46:31.728682 | controller | {
2026-01-25 07:46:31.728712 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-25 07:46:31.728735 | controller | }
2026-01-25 07:46:32.046518 | controller | changed:
2026-01-25 07:46:32.046717 | controller | {
2026-01-25 07:46:32.046752 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-25 07:46:32.046775 | controller | }
2026-01-25 07:46:32.335826 | controller | changed:
2026-01-25 07:46:32.335956 | controller | {
2026-01-25 07:46:32.335990 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-25 07:46:32.336013 | controller | }
2026-01-25 07:46:32.645940 | controller | changed:
2026-01-25 07:46:32.646021 | controller | {
2026-01-25 07:46:32.646055 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-25 07:46:32.646081 | controller | }
2026-01-25 07:46:32.949253 | controller | changed:
2026-01-25 07:46:32.949352 | controller | {
2026-01-25 07:46:32.949383 | 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"
2026-01-25 07:46:32.949405 | controller | }
2026-01-25 07:46:33.240320 | controller | changed:
2026-01-25 07:46:33.240550 | controller | {
2026-01-25 07:46:33.240705 | 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"
2026-01-25 07:46:33.240736 | controller | }
2026-01-25 07:46:33.531513 | controller | changed:
2026-01-25 07:46:33.531620 | controller | {
2026-01-25 07:46:33.531648 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-25 07:46:33.531669 | controller | }
2026-01-25 07:46:33.851032 | controller | changed:
2026-01-25 07:46:33.851217 | controller | {
2026-01-25 07:46:33.851254 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-25 07:46:33.851279 | controller | }
2026-01-25 07:46:34.143360 | controller | changed:
2026-01-25 07:46:34.143443 | controller | {
2026-01-25 07:46:34.143469 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-25 07:46:34.143490 | controller | }
2026-01-25 07:46:34.446213 | controller | changed:
2026-01-25 07:46:34.446315 | controller | {
2026-01-25 07:46:34.446350 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-25 07:46:34.446373 | controller | }
2026-01-25 07:46:34.750925 | controller | changed:
2026-01-25 07:46:34.751692 | controller | {
2026-01-25 07:46:34.751742 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-25 07:46:34.751766 | controller | }
2026-01-25 07:46:35.049653 | controller | changed:
2026-01-25 07:46:35.049731 | controller | {
2026-01-25 07:46:35.049760 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-25 07:46:35.049783 | controller | }
2026-01-25 07:46:35.329698 | controller | changed:
2026-01-25 07:46:35.329786 | controller | {
2026-01-25 07:46:35.329815 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-25 07:46:35.329837 | controller | }
2026-01-25 07:46:35.640133 | controller | changed:
2026-01-25 07:46:35.640264 | controller | {
2026-01-25 07:46:35.640459 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-25 07:46:35.640489 | controller | }
2026-01-25 07:46:35.971911 | controller | changed:
2026-01-25 07:46:35.972011 | controller | {
2026-01-25 07:46:35.972043 | 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"
2026-01-25 07:46:35.972067 | controller | }
2026-01-25 07:46:36.271894 | controller | changed:
2026-01-25 07:46:36.271982 | controller | {
2026-01-25 07:46:36.272013 | 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"
2026-01-25 07:46:36.272043 | controller | }
2026-01-25 07:46:36.559460 | controller | changed:
2026-01-25 07:46:36.559551 | controller | {
2026-01-25 07:46:36.559581 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-25 07:46:36.559603 | controller | }
2026-01-25 07:46:36.849727 | controller | changed:
2026-01-25 07:46:36.849828 | controller | {
2026-01-25 07:46:36.849859 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-25 07:46:36.849882 | controller | }
2026-01-25 07:46:37.150810 | controller | changed:
2026-01-25 07:46:37.151042 | controller | {
2026-01-25 07:46:37.151084 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-25 07:46:37.151108 | controller | }
2026-01-25 07:46:37.455993 | controller | changed:
2026-01-25 07:46:37.456090 | controller | {
2026-01-25 07:46:37.456120 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-25 07:46:37.456143 | controller | }
2026-01-25 07:46:37.752152 | controller | changed:
2026-01-25 07:46:37.752252 | controller | {
2026-01-25 07:46:37.752282 | 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"
2026-01-25 07:46:37.752326 | controller | }
2026-01-25 07:46:38.045035 | controller | changed:
2026-01-25 07:46:38.045135 | controller | {
2026-01-25 07:46:38.045163 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-25 07:46:38.045183 | controller | }
2026-01-25 07:46:38.339493 | controller | changed:
2026-01-25 07:46:38.339579 | controller | {
2026-01-25 07:46:38.339608 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-25 07:46:38.339628 | controller | }
2026-01-25 07:46:38.624256 | controller | changed:
2026-01-25 07:46:38.624359 | controller | {
2026-01-25 07:46:38.624389 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-25 07:46:38.624411 | controller | }
2026-01-25 07:46:38.648543 | 
2026-01-25 07:46:38.648622 | TASK [Set timezone to UTC]
2026-01-25 07:46:39.155657 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-25 07:46:39.160634 | 
2026-01-25 07:46:39.160723 | TASK [Create nodepool directory]
2026-01-25 07:46:39.445078 | controller | changed
2026-01-25 07:46:39.450837 | 
2026-01-25 07:46:39.450929 | TASK [Create nodepool sub_nodes file]
2026-01-25 07:46:40.460830 | controller | changed
2026-01-25 07:46:40.466805 | 
2026-01-25 07:46:40.466893 | TASK [Create nodepool sub_nodes_private file]
2026-01-25 07:46:41.479518 | controller | changed
2026-01-25 07:46:41.491026 | 
2026-01-25 07:46:41.491117 | LOOP [Populate nodepool sub_nodes file]
2026-01-25 07:46:41.540221 | 
2026-01-25 07:46:41.540381 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-25 07:46:41.569610 | 
2026-01-25 07:46:41.569762 | TASK [Create nodepool primary file]
2026-01-25 07:46:41.593497 | controller | skipping: Conditional result was False
2026-01-25 07:46:41.598971 | 
2026-01-25 07:46:41.599062 | TASK [Create nodepool node_private for this node]
2026-01-25 07:46:42.641892 | controller | changed
2026-01-25 07:46:42.649055 | 
2026-01-25 07:46:42.649151 | LOOP [Copy ssh keys to nodepool directory]
2026-01-25 07:46:43.072165 | controller | ok: Item: id_rsa Runtime: 0:00:00.005474
2026-01-25 07:46:43.072372 | 
2026-01-25 07:46:43.329438 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007396
2026-01-25 07:46:43.339598 | 
2026-01-25 07:46:43.339679 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-25 07:46:44.458926 | controller | changed
2026-01-25 07:46:44.470635 | 
2026-01-25 07:46:44.470723 | TASK [Validate sudoers config after edits]
2026-01-25 07:46:44.764829 | controller | /etc/sudoers: parsed OK
2026-01-25 07:46:44.764897 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-25 07:46:44.764937 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-25 07:46:44.764944 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-25 07:46:44.998074 | controller | ok: Runtime: 0:00:00.005910
2026-01-25 07:46:45.003834 | 
2026-01-25 07:46:45.003916 | TASK [Show the environment passed in to job shell scripts]
2026-01-25 07:46:45.311259 | controller | SHELL=/bin/bash
2026-01-25 07:46:45.311328 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-25 07:46:45.311339 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-25 07:46:45.311346 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/19/3619/357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:46:45.311361 | controller | PWD=/home/zuul
2026-01-25 07:46:45.311373 | controller | ZUUL_PIPELINE=github-check
2026-01-25 07:46:45.311379 | controller | LOGNAME=zuul
2026-01-25 07:46:45.311389 | controller | XDG_SESSION_TYPE=tty
2026-01-25 07:46:45.311400 | controller | _=/usr/bin/env
2026-01-25 07:46:45.311410 | controller | MOTD_SHOWN=pam
2026-01-25 07:46:45.311420 | controller | HOME=/home/zuul
2026-01-25 07:46:45.311430 | controller | LANG=en_US.UTF-8
2026-01-25 07:46:45.311490 | controller | SSH_CONNECTION=192.168.26.12 38074 192.168.26.235 22
2026-01-25 07:46:45.311499 | 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
2026-01-25 07:46:45.311508 | controller | ZUUL_CHANGE_IDS=3619,357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:46:45.311514 | controller | WORKSPACE=/home/zuul/workspace
2026-01-25 07:46:45.311519 | controller | XDG_SESSION_CLASS=user
2026-01-25 07:46:45.311525 | controller | SELINUX_ROLE_REQUESTED=
2026-01-25 07:46:45.311530 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-25 07:46:45.311536 | controller | USER=zuul
2026-01-25 07:46:45.311541 | controller | ZUUL_VOTING=True
2026-01-25 07:46:45.311547 | controller | BUILD_TIMEOUT=1800000
2026-01-25 07:46:45.311552 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-25 07:46:45.311557 | controller | SHLVL=1
2026-01-25 07:46:45.311577 | controller | ZUUL_PATCHSET=357a1d5bdf38af6a9dfd790ff15d1c9b02f9f293
2026-01-25 07:46:45.311587 | controller | XDG_SESSION_ID=1
2026-01-25 07:46:45.311592 | controller | ZUUL_BRANCH=main
2026-01-25 07:46:45.311598 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-25 07:46:45.311603 | controller | SSH_CLIENT=192.168.26.12 38074 22
2026-01-25 07:46:45.311608 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-25 07:46:45.311614 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-25 07:46:45.311619 | controller | which_declare=declare -f
2026-01-25 07:46:45.311625 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-25 07:46:45.311631 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-25 07:46:45.311636 | controller | ZUUL_CHANGE=3619
2026-01-25 07:46:45.311642 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-25 07:46:45.311664 | controller | ZUUL_UUID=392a17c260d846b8b5bfd9c41166ad35
2026-01-25 07:46:45.311673 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-25 07:46:45.311679 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-25 07:46:45.311685 | controller | }
2026-01-25 07:46:45.539871 | controller | ok: Runtime: 0:00:00.006905
2026-01-25 07:46:45.553260 | 
2026-01-25 07:46:45.553387 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-25 07:46:45.567881 | controller | skipping: Conditional result was False
2026-01-25 07:46:45.574671 | 
2026-01-25 07:46:45.574757 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-25 07:46:46.170583 | controller | skipping: Conditional result was False
2026-01-25 07:46:46.177054 | 
2026-01-25 07:46:46.177141 | TASK [Ensure legacy workspace directory]
2026-01-25 07:46:46.449438 | controller | changed
2026-01-25 07:46:46.487434 | 
2026-01-25 07:46:46.487522 | PLAY RECAP
2026-01-25 07:46:46.487570 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-25 07:46:46.487594 | 
2026-01-25 07:46:46.593608 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-25 07:46:46.594353 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-25 07:46:47.148856 | 
2026-01-25 07:46:47.148973 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-25 07:46:47.170718 | 
2026-01-25 07:46:47.170809 | TASK [Create zuul-output directory]
2026-01-25 07:46:47.596130 | controller | changed
2026-01-25 07:46:47.601428 | 
2026-01-25 07:46:47.601524 | TASK [Slurp Zuul inventory test]
2026-01-25 07:46:47.893739 | controller -> localhost | ok
2026-01-25 07:46:47.900695 | 
2026-01-25 07:46:47.900780 | TASK [Save zuul inventory]
2026-01-25 07:46:49.246694 | controller | changed
2026-01-25 07:46:49.253633 | 
2026-01-25 07:46:49.253723 | TASK [Save zuul vars without the change_message]
2026-01-25 07:46:50.423779 | controller | changed
2026-01-25 07:46:50.443842 | 
2026-01-25 07:46:50.443910 | PLAY RECAP
2026-01-25 07:46:50.443955 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-25 07:46:50.443978 | 
2026-01-25 07:46:50.516083 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-25 07:46:50.516896 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-25 07:46:51.134451 | 
2026-01-25 07:46:51.134553 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-25 07:46:51.156509 | 
2026-01-25 07:46:51.156595 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-25 07:46:51.196587 | controller | ok
2026-01-25 07:46:51.214556 | 
2026-01-25 07:46:51.214645 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-25 07:46:51.238498 | controller | skipping: Conditional result was False
2026-01-25 07:46:51.244016 | 
2026-01-25 07:46:51.244089 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-25 07:46:51.668708 | controller | ok
2026-01-25 07:46:51.675927 | 
2026-01-25 07:46:51.676017 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-25 07:46:52.815701 | controller | ok
2026-01-25 07:46:52.831367 | 
2026-01-25 07:46:52.831460 | TASK [Prepare workspace]
2026-01-25 07:46:52.861140 | controller | ok
2026-01-25 07:46:52.897041 | 
2026-01-25 07:46:52.897215 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-25 07:46:53.300290 | controller | ok
2026-01-25 07:46:53.307091 | 
2026-01-25 07:46:53.307173 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-25 07:46:56.101861 | controller | Output suppressed because no_log was given
2026-01-25 07:46:56.115920 | 
2026-01-25 07:46:56.116011 | LOOP [Create zuul-output directory]
2026-01-25 07:46:56.393263 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-25 07:46:56.628611 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-25 07:46:56.638082 | 
2026-01-25 07:46:56.638163 | TASK [Install required packages]
2026-01-25 07:47:21.349343 | controller | ok: Nothing to do
2026-01-25 07:47:21.356936 | 
2026-01-25 07:47:21.357020 | TASK [Install venv]
2026-01-25 07:48:21.546497 | controller | changed
2026-01-25 07:48:21.573227 | 
2026-01-25 07:48:21.573332 | PLAY RECAP
2026-01-25 07:48:21.573380 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-25 07:48:21.573403 | 
2026-01-25 07:48:21.649249 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-25 07:48:21.650072 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-25 07:48:22.172010 | 
2026-01-25 07:48:22.172110 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-25 07:48:22.193199 | 
2026-01-25 07:48:22.193316 | TASK [Gather required facts]
2026-01-25 07:48:22.792832 | controller | ok
2026-01-25 07:48:22.801696 | 
2026-01-25 07:48:22.801902 | TASK [Load environment var if instructed to]
2026-01-25 07:48:22.830695 | controller | skipping: Conditional result was False
2026-01-25 07:48:22.838703 | 
2026-01-25 07:48:22.838865 | TASK [Ensure group_vars dir exists]
2026-01-25 07:48:23.253352 | controller | ok
2026-01-25 07:48:23.258662 | 
2026-01-25 07:48:23.258743 | TASK [Print related variables]
2026-01-25 07:48:23.287395 | controller | ok:
2026-01-25 07:48:23.287508 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-25 07:48:23.287539 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack
2026-01-25 07:48:23.292456 | 
2026-01-25 07:48:23.292523 | TASK [Run molecule]
2026-01-25 07:48:24.317506 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-25 07:48:24.389387 | controller | INFO     Performing prerun with role_name_check=0...
2026-01-25 07:48:41.196972 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.197358 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.197811 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.198255 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.198720 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+357a1d5b was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0+357a1d5b (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.199157 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.199585 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.200005 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.200434 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.200858 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.201283 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.201725 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.202143 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.202587 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.203000 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-25 07:48:41.212534 | controller | INFO     Running default > prepare
2026-01-25 07:48:41.946339 | controller |
2026-01-25 07:48:41.946436 | controller | PLAY [Prepare] *****************************************************************
2026-01-25 07:48:41.946531 | controller |
2026-01-25 07:48:41.946665 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:48:41.946754 | controller | Sunday 25 January 2026  07:48:41 +0000 (0:00:00.022)       0:00:00.022 ********
2026-01-25 07:48:42.853873 | controller | ok: [instance]
2026-01-25 07:48:42.853925 | controller |
2026-01-25 07:48:42.854021 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-01-25 07:48:42.854119 | controller | Sunday 25 January 2026  07:48:42 +0000 (0:00:00.908)       0:00:00.930 ********
2026-01-25 07:48:42.874526 | controller | skipping: [instance]
2026-01-25 07:48:42.874610 | controller |
2026-01-25 07:48:42.874746 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-01-25 07:48:42.874871 | controller | Sunday 25 January 2026  07:48:42 +0000 (0:00:00.020)       0:00:00.951 ********
2026-01-25 07:48:42.917440 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-01-25 07:48:42.917512 | controller |
2026-01-25 07:48:42.917630 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-01-25 07:48:42.917729 | controller | Sunday 25 January 2026  07:48:42 +0000 (0:00:00.042)       0:00:00.994 ********
2026-01-25 07:48:43.202816 | controller | ok: [instance]
2026-01-25 07:48:43.202905 | controller |
2026-01-25 07:48:43.203015 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-01-25 07:48:43.203122 | controller | Sunday 25 January 2026  07:48:43 +0000 (0:00:00.285)       0:00:01.279 ********
2026-01-25 07:48:43.510953 | controller | ok: [instance]
2026-01-25 07:48:43.511021 | controller |
2026-01-25 07:48:43.511125 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-01-25 07:48:43.511225 | controller | Sunday 25 January 2026  07:48:43 +0000 (0:00:00.308)       0:00:01.588 ********
2026-01-25 07:48:44.198801 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-01-25 07:48:44.198836 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-01-25 07:48:44.198937 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-01-25 07:48:44.199027 | controller |
2026-01-25 07:48:44.199137 | controller | TASK [Deploy repo-setup] *******************************************************
2026-01-25 07:48:44.199226 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.687)       0:00:02.275 ********
2026-01-25 07:48:44.259673 | controller |
2026-01-25 07:48:44.259763 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-01-25 07:48:44.259919 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.061)       0:00:02.336 ********
2026-01-25 07:48:44.785574 | controller | changed: [instance] => (item=tmp)
2026-01-25 07:48:44.785648 | controller | changed: [instance] => (item=artifacts/repositories)
2026-01-25 07:48:44.785746 | controller | changed: [instance] => (item=venv/repo_setup)
2026-01-25 07:48:44.785833 | controller |
2026-01-25 07:48:44.785926 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-01-25 07:48:44.786010 | controller | Sunday 25 January 2026  07:48:44 +0000 (0:00:00.525)       0:00:02.862 ********
2026-01-25 07:48:45.628536 | controller | ok: [instance]
2026-01-25 07:48:45.628652 | controller |
2026-01-25 07:48:45.628832 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-01-25 07:48:46.660655 | controller | Sunday 25 January 2026  07:48:45 +0000 (0:00:00.842)       0:00:03.705 ********
2026-01-25 07:48:46.660691 | controller | changed: [instance]
2026-01-25 07:48:46.660699 | controller |
2026-01-25 07:48:46.660710 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-01-25 07:48:46.660718 | controller | Sunday 25 January 2026  07:48:46 +0000 (0:00:01.031)       0:00:04.737 ********
2026-01-25 07:48:54.672971 | controller | changed: [instance]
2026-01-25 07:48:55.446762 | controller |
2026-01-25 07:48:55.446829 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-01-25 07:48:55.446839 | controller | Sunday 25 January 2026  07:48:54 +0000 (0:00:08.012)       0:00:12.749 ********
2026-01-25 07:48:55.446855 | controller | changed: [instance]
2026-01-25 07:48:55.472224 | controller |
2026-01-25 07:48:55.472282 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-01-25 07:48:55.472291 | controller | Sunday 25 January 2026  07:48:55 +0000 (0:00:00.773)       0:00:13.523 ********
2026-01-25 07:48:55.472305 | controller | skipping: [instance]
2026-01-25 07:48:56.337709 | controller |
2026-01-25 07:48:56.337761 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-01-25 07:48:56.337769 | controller | Sunday 25 January 2026  07:48:55 +0000 (0:00:00.025)       0:00:13.549 ********
2026-01-25 07:48:56.337781 | controller | changed: [instance]
2026-01-25 07:48:56.378097 | controller |
2026-01-25 07:48:56.378123 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-01-25 07:48:56.378133 | controller | Sunday 25 January 2026  07:48:56 +0000 (0:00:00.865)       0:00:14.414 ********
2026-01-25 07:48:56.378144 | controller | skipping: [instance]
2026-01-25 07:48:56.420786 | controller |
2026-01-25 07:48:56.420848 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-01-25 07:48:56.420859 | controller | Sunday 25 January 2026  07:48:56 +0000 (0:00:00.040)       0:00:14.454 ********
2026-01-25 07:48:56.420876 | controller | skipping: [instance]
2026-01-25 07:48:56.471772 | controller |
2026-01-25 07:48:56.471850 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-01-25 07:48:56.471859 | controller | Sunday 25 January 2026  07:48:56 +0000 (0:00:00.042)       0:00:14.497 ********
2026-01-25 07:48:56.471877 | controller | skipping: [instance]
2026-01-25 07:48:56.472268 | controller |
2026-01-25 07:48:56.472302 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-01-25 07:48:57.065628 | controller | Sunday 25 January 2026  07:48:56 +0000 (0:00:00.051)       0:00:14.548 ********
2026-01-25 07:48:57.065676 | controller | changed: [instance]
2026-01-25 07:48:57.561477 | controller |
2026-01-25 07:48:57.561510 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-01-25 07:48:57.561518 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.593)       0:00:15.141 ********
2026-01-25 07:48:57.561528 | controller | changed: [instance]
2026-01-25 07:48:57.592579 | controller |
2026-01-25 07:48:57.592599 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-01-25 07:48:57.592606 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.496)       0:00:15.638 ********
2026-01-25 07:48:57.592615 | controller | skipping: [instance]
2026-01-25 07:48:57.626378 | controller |
2026-01-25 07:48:57.626398 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-01-25 07:48:57.626407 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.031)       0:00:15.669 ********
2026-01-25 07:48:57.626417 | controller | skipping: [instance]
2026-01-25 07:48:57.659741 | controller |
2026-01-25 07:48:57.659789 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-01-25 07:48:57.659798 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.033)       0:00:15.703 ********
2026-01-25 07:48:57.659816 | controller | skipping: [instance]
2026-01-25 07:48:57.702758 | controller |
2026-01-25 07:48:57.702813 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-01-25 07:48:57.702823 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.033)       0:00:15.736 ********
2026-01-25 07:48:57.702838 | controller | ok: [instance]
2026-01-25 07:48:57.702845 | controller |
2026-01-25 07:48:57.702850 | controller | TASK [repo_setup : Create download directory] **********************************
2026-01-25 07:48:57.702858 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.043)       0:00:15.779 ********
2026-01-25 07:48:57.733626 | controller | skipping: [instance]
2026-01-25 07:48:57.764095 | controller |
2026-01-25 07:48:57.764114 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-01-25 07:48:57.764122 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.030)       0:00:15.810 ********
2026-01-25 07:48:57.764137 | controller | skipping: [instance]
2026-01-25 07:48:57.794277 | controller |
2026-01-25 07:48:57.794295 | controller | TASK [Download the RPM] ********************************************************
2026-01-25 07:48:57.794302 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.030)       0:00:15.841 ********
2026-01-25 07:48:57.794311 | controller | skipping: [instance]
2026-01-25 07:48:57.823709 | controller |
2026-01-25 07:48:57.823731 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-01-25 07:48:57.823740 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.030)       0:00:15.871 ********
2026-01-25 07:48:57.823751 | controller | skipping: [instance]
2026-01-25 07:48:57.823916 | controller |
2026-01-25 07:48:57.824096 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-01-25 07:48:57.824244 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.029)       0:00:15.901 ********
2026-01-25 07:48:57.853886 | controller | skipping: [instance]
2026-01-25 07:48:57.854076 | controller |
2026-01-25 07:48:57.854244 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-01-25 07:48:57.854374 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.030)       0:00:15.931 ********
2026-01-25 07:48:57.889012 | controller | skipping: [instance]
2026-01-25 07:48:57.889207 | controller |
2026-01-25 07:48:57.889362 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-01-25 07:48:57.889494 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.034)       0:00:15.966 ********
2026-01-25 07:48:57.928397 | controller | skipping: [instance]
2026-01-25 07:48:57.928602 | controller |
2026-01-25 07:48:57.928791 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-01-25 07:48:57.928954 | controller | Sunday 25 January 2026  07:48:57 +0000 (0:00:00.039)       0:00:16.005 ********
2026-01-25 07:48:58.165585 | controller | ok: [instance]
2026-01-25 07:48:58.420765 | controller |
2026-01-25 07:48:58.420827 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-01-25 07:48:58.420838 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.235)       0:00:16.241 ********
2026-01-25 07:48:58.420854 | controller | changed: [instance]
2026-01-25 07:48:58.681697 | controller |
2026-01-25 07:48:58.681755 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-01-25 07:48:58.681765 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.255)       0:00:16.496 ********
2026-01-25 07:48:58.681780 | controller | changed: [instance]
2026-01-25 07:48:58.681813 | controller |
2026-01-25 07:48:58.682031 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-01-25 07:48:58.682110 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.261)       0:00:16.758 ********
2026-01-25 07:48:58.716825 | controller | skipping: [instance]
2026-01-25 07:48:58.716894 | controller |
2026-01-25 07:48:58.716903 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-01-25 07:48:58.716912 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.034)       0:00:16.792 ********
2026-01-25 07:48:58.743641 | controller | skipping: [instance]
2026-01-25 07:48:58.743874 | controller |
2026-01-25 07:48:58.743923 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-01-25 07:48:58.768210 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.027)       0:00:16.820 ********
2026-01-25 07:48:58.768246 | controller | skipping: [instance]
2026-01-25 07:48:58.792671 | controller |
2026-01-25 07:48:58.792708 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-01-25 07:48:58.792717 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.024)       0:00:16.845 ********
2026-01-25 07:48:58.792730 | controller | skipping: [instance]
2026-01-25 07:48:58.792866 | controller |
2026-01-25 07:48:58.792876 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-01-25 07:48:58.792885 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.024)       0:00:16.869 ********
2026-01-25 07:48:58.818530 | controller | skipping: [instance]
2026-01-25 07:48:58.818679 | controller |
2026-01-25 07:48:58.818809 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2026-01-25 07:48:58.818926 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.026)       0:00:16.895 ********
2026-01-25 07:48:58.849387 | controller | skipping: [instance]
2026-01-25 07:48:58.849540 | controller |
2026-01-25 07:48:58.849710 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-01-25 07:48:58.849857 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.030)       0:00:16.926 ********
2026-01-25 07:48:58.872350 | controller | skipping: [instance]
2026-01-25 07:48:58.872502 | controller |
2026-01-25 07:48:58.872680 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-01-25 07:48:58.872827 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.022)       0:00:16.949 ********
2026-01-25 07:48:58.911369 | controller | skipping: [instance]
2026-01-25 07:48:58.911518 | controller |
2026-01-25 07:48:58.911700 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-01-25 07:48:58.911854 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.039)       0:00:16.988 ********
2026-01-25 07:48:58.937247 | controller | skipping: [instance]
2026-01-25 07:48:58.937377 | controller |
2026-01-25 07:48:58.937505 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-01-25 07:48:58.937641 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.025)       0:00:17.014 ********
2026-01-25 07:48:58.967144 | controller | skipping: [instance]
2026-01-25 07:48:58.967253 | controller |
2026-01-25 07:48:58.967430 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-01-25 07:48:58.967503 | controller | Sunday 25 January 2026  07:48:58 +0000 (0:00:00.029)       0:00:17.044 ********
2026-01-25 07:48:59.017828 | controller | skipping: [instance]
2026-01-25 07:48:59.017947 | controller |
2026-01-25 07:48:59.018134 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-01-25 07:48:59.018249 | controller | Sunday 25 January 2026  07:48:59 +0000 (0:00:00.050)       0:00:17.094 ********
2026-01-25 07:48:59.052734 | controller | skipping: [instance]
2026-01-25 07:48:59.052880 | controller |
2026-01-25 07:48:59.053084 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-01-25 07:48:59.053181 | controller | Sunday 25 January 2026  07:48:59 +0000 (0:00:00.034)       0:00:17.129 ********
2026-01-25 07:48:59.088807 | controller | skipping: [instance]
2026-01-25 07:48:59.088964 | controller |
2026-01-25 07:48:59.089092 | controller | TASK [test_deps : Install additional packages] *********************************
2026-01-25 07:48:59.089228 | controller | Sunday 25 January 2026  07:48:59 +0000 (0:00:00.036)       0:00:17.165 ********
2026-01-25 07:48:59.126170 | controller | skipping: [instance]
2026-01-25 07:48:59.126330 | controller |
2026-01-25 07:48:59.126449 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-01-25 07:48:59.126586 | controller | Sunday 25 January 2026  07:48:59 +0000 (0:00:00.037)       0:00:17.203 ********
2026-01-25 07:49:51.221713 | controller | ok: [instance]
2026-01-25 07:49:51.221775 | controller |
2026-01-25 07:49:51.221803 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-01-25 07:49:51.221814 | controller | Sunday 25 January 2026  07:49:51 +0000 (0:00:52.093)       0:01:09.296 ********
2026-01-25 07:49:52.617250 | controller | ok: [instance]
2026-01-25 07:49:52.658789 | controller |
2026-01-25 07:49:52.658855 | controller | TASK [test_deps : Install extra packages] **************************************
2026-01-25 07:49:52.658867 | controller | Sunday 25 January 2026  07:49:52 +0000 (0:00:01.396)       0:01:10.693 ********
2026-01-25 07:49:52.658883 | controller | skipping: [instance]
2026-01-25 07:49:52.711679 | controller |
2026-01-25 07:49:52.711711 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2026-01-25 07:49:52.711720 | controller | Sunday 25 January 2026  07:49:52 +0000 (0:00:00.042)       0:01:10.735 ********
2026-01-25 07:49:52.711732 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-01-25 07:49:52.711766 | controller |
2026-01-25 07:49:52.712002 | controller | TASK [ci_setup : List packages to install] *************************************
2026-01-25 07:49:52.735798 | controller | Sunday 25 January 2026  07:49:52 +0000 (0:00:00.053)       0:01:10.788 ********
2026-01-25 07:49:52.735873 | controller | ok: [instance] =>
2026-01-25 07:49:52.735910 | controller |   cifmw_ci_setup_packages:
2026-01-25 07:49:52.735920 | controller |   - bash-completion
2026-01-25 07:49:52.735927 | controller |   - ca-certificates
2026-01-25 07:49:52.735934 | controller |   - git-core
2026-01-25 07:49:52.735941 | controller |   - make
2026-01-25 07:49:52.735948 | controller |   - tar
2026-01-25 07:49:52.735955 | controller |   - tmux
2026-01-25 07:49:52.735961 | controller |   - python3-pip
2026-01-25 07:49:52.735970 | controller |
2026-01-25 07:49:52.735998 | controller | TASK [ci_setup : Install needed packages] **************************************
2026-01-25 07:49:52.736093 | controller | Sunday 25 January 2026  07:49:52 +0000 (0:00:00.023)       0:01:10.812 ********
2026-01-25 07:50:00.623807 | controller | changed: [instance]
2026-01-25 07:50:00.623897 | controller |
2026-01-25 07:50:00.623925 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2026-01-25 07:50:00.624098 | controller | Sunday 25 January 2026  07:50:00 +0000 (0:00:07.888)       0:01:18.700 ********
2026-01-25 07:50:01.243581 | controller | ok: [instance]
2026-01-25 07:50:01.494787 | controller |
2026-01-25 07:50:01.494836 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2026-01-25 07:50:01.494846 | controller | Sunday 25 January 2026  07:50:01 +0000 (0:00:00.619)       0:01:19.320 ********
2026-01-25 07:50:01.494859 | controller | changed: [instance]
2026-01-25 07:50:01.494887 | controller |
2026-01-25 07:50:01.494927 | controller | TASK [ci_setup : Install openshift client] *************************************
2026-01-25 07:50:01.495116 | controller | Sunday 25 January 2026  07:50:01 +0000 (0:00:00.251)       0:01:19.571 ********
2026-01-25 07:50:07.688993 | controller | changed: [instance]
2026-01-25 07:50:07.689285 | controller |
2026-01-25 07:50:07.689305 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2026-01-25 07:50:07.723659 | controller | Sunday 25 January 2026  07:50:07 +0000 (0:00:06.193)       0:01:25.765 ********
2026-01-25 07:50:07.723682 | controller | skipping: [instance]
2026-01-25 07:50:08.029513 | controller |
2026-01-25 07:50:08.029555 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-01-25 07:50:08.029579 | controller | Sunday 25 January 2026  07:50:07 +0000 (0:00:00.035)       0:01:25.800 ********
2026-01-25 07:50:08.029593 | controller | changed: [instance]
2026-01-25 07:50:08.332686 | controller |
2026-01-25 07:50:08.332749 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2026-01-25 07:50:08.332758 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.305)       0:01:26.106 ********
2026-01-25 07:50:08.332770 | controller | changed: [instance]
2026-01-25 07:50:08.357803 | controller |
2026-01-25 07:50:08.357899 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2026-01-25 07:50:08.357910 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.302)       0:01:26.408 ********
2026-01-25 07:50:08.357924 | controller | skipping: [instance]
2026-01-25 07:50:08.381134 | controller |
2026-01-25 07:50:08.381153 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2026-01-25 07:50:08.381161 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.025)       0:01:26.434 ********
2026-01-25 07:50:08.381170 | controller | skipping: [instance]
2026-01-25 07:50:08.404298 | controller |
2026-01-25 07:50:08.404319 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2026-01-25 07:50:08.404329 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.023)       0:01:26.458 ********
2026-01-25 07:50:08.404340 | controller | skipping: [instance]
2026-01-25 07:50:08.427363 | controller |
2026-01-25 07:50:08.427385 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2026-01-25 07:50:08.427394 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.023)       0:01:26.481 ********
2026-01-25 07:50:08.427405 | controller | skipping: [instance]
2026-01-25 07:50:08.451865 | controller |
2026-01-25 07:50:08.451888 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2026-01-25 07:50:08.451897 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.022)       0:01:26.504 ********
2026-01-25 07:50:08.451908 | controller | skipping: [instance]
2026-01-25 07:50:08.452071 | controller |
2026-01-25 07:50:08.452235 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2026-01-25 07:50:08.452405 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.024)       0:01:26.528 ********
2026-01-25 07:50:08.481370 | controller | skipping: [instance]
2026-01-25 07:50:08.481547 | controller |
2026-01-25 07:50:08.481777 | controller | TASK [ci_setup : Manage directories] *******************************************
2026-01-25 07:50:08.481912 | controller | Sunday 25 January 2026  07:50:08 +0000 (0:00:00.029)       0:01:26.558 ********
2026-01-25 07:50:09.609796 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2026-01-25 07:50:09.609869 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2026-01-25 07:50:09.609877 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2026-01-25 07:50:09.609884 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2026-01-25 07:50:09.609892 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2026-01-25 07:50:09.609917 | controller |
2026-01-25 07:50:09.610171 | controller | TASK [Ensure CRC is started] ***************************************************
2026-01-25 07:53:08.773472 | controller | Sunday 25 January 2026  07:50:09 +0000 (0:00:01.127)       0:01:27.686 ********
2026-01-25 07:53:08.773525 | controller | changed: [instance]
2026-01-25 07:53:08.773559 | controller |
2026-01-25 07:53:09.607249 | controller | TASK [Create the openstack namespace] ******************************************
2026-01-25 07:53:09.607297 | controller | Sunday 25 January 2026  07:53:08 +0000 (0:02:59.158)       0:04:26.844 ********
2026-01-25 07:53:09.607323 | controller | changed: [instance]
2026-01-25 07:53:09.607352 | controller |
2026-01-25 07:53:09.607530 | controller | TASK [Create a dummy configmap] ************************************************
2026-01-25 07:53:09.607775 | controller | Sunday 25 January 2026  07:53:09 +0000 (0:00:00.839)       0:04:27.684 ********
2026-01-25 07:53:10.309197 | controller | changed: [instance]
2026-01-25 07:53:11.013227 | controller |
2026-01-25 07:53:11.013256 | controller | TASK [Create a dummy openstack config secret] **********************************
2026-01-25 07:53:11.013264 | controller | Sunday 25 January 2026  07:53:10 +0000 (0:00:00.701)       0:04:28.385 ********
2026-01-25 07:53:11.013279 | controller | changed: [instance]
2026-01-25 07:53:11.732149 | controller |
2026-01-25 07:53:11.732179 | controller | TASK [Create dummy openstack root ca secret] ***********************************
2026-01-25 07:53:11.732187 | controller | Sunday 25 January 2026  07:53:11 +0000 (0:00:00.703)       0:04:29.089 ********
2026-01-25 07:53:11.732197 | controller | changed: [instance]
2026-01-25 07:53:11.799738 | controller |
2026-01-25 07:53:11.799773 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:53:11.799782 | controller | instance                   : ok=33   changed=20   unreachable=0    failed=0    skipped=37   rescued=0    ignored=0
2026-01-25 07:53:11.799788 | controller |
2026-01-25 07:53:11.799794 | controller | Sunday 25 January 2026  07:53:11 +0000 (0:00:00.716)       0:04:29.806 ********
2026-01-25 07:53:11.799805 | controller | ===============================================================================
2026-01-25 07:53:11.799810 | controller | Ensure CRC is started ------------------------------------------------- 179.16s
2026-01-25 07:53:11.799815 | controller | test_deps : Install selinux python libs -------------------------------- 52.09s
2026-01-25 07:53:11.799821 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.01s
2026-01-25 07:53:11.799826 | controller | ci_setup : Install needed packages -------------------------------------- 7.89s
2026-01-25 07:53:11.799834 | controller | ci_setup : Install openshift client ------------------------------------- 6.19s
2026-01-25 07:53:11.799841 | controller | test_deps : Install python yaml libs ------------------------------------ 1.40s
2026-01-25 07:53:11.799846 | controller | ci_setup : Manage directories ------------------------------------------- 1.13s
2026-01-25 07:53:11.799851 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.03s
2026-01-25 07:53:11.799856 | controller | Gathering Facts --------------------------------------------------------- 0.91s
2026-01-25 07:53:11.799861 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.87s
2026-01-25 07:53:11.799873 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2026-01-25 07:53:11.799879 | controller | Create the openstack namespace ------------------------------------------ 0.84s
2026-01-25 07:53:11.799884 | controller | repo_setup : Install repo-setup package --------------------------------- 0.77s
2026-01-25 07:53:11.799889 | controller | Create dummy openstack root ca secret ----------------------------------- 0.72s
2026-01-25 07:53:11.799894 | controller | Create a dummy openstack config secret ---------------------------------- 0.70s
2026-01-25 07:53:11.799899 | controller | Create a dummy configmap ------------------------------------------------ 0.70s
2026-01-25 07:53:11.799909 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2026-01-25 07:53:11.799914 | controller | ci_setup : Gather version of openshift client --------------------------- 0.62s
2026-01-25 07:53:11.799919 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.59s
2026-01-25 07:53:11.799924 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2026-01-25 07:53:11.799935 | controller | INFO     Running default > converge
2026-01-25 07:53:12.269512 | controller |
2026-01-25 07:53:13.222721 | controller | PLAY [Converge] ****************************************************************
2026-01-25 07:53:13.222758 | controller |
2026-01-25 07:53:13.222777 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:53:13.222786 | controller | Sunday 25 January 2026  07:53:12 +0000 (0:00:00.039)       0:00:00.039 ********
2026-01-25 07:53:13.222796 | controller | ok: [instance]
2026-01-25 07:53:13.249922 | controller |
2026-01-25 07:53:13.249951 | controller | TASK [Include the shiftstack role] *********************************************
2026-01-25 07:53:13.249959 | controller | Sunday 25 January 2026  07:53:13 +0000 (0:00:00.953)       0:00:00.992 ********
2026-01-25 07:53:13.249969 | controller |
2026-01-25 07:53:13.276999 | controller | TASK [shiftstack : Include pre test shiftstack tasks] **************************
2026-01-25 07:53:13.277033 | controller | Sunday 25 January 2026  07:53:13 +0000 (0:00:00.027)       0:00:01.020 ********
2026-01-25 07:53:13.277047 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/pre_test_shiftstack.yml for instance
2026-01-25 07:53:13.303854 | controller |
2026-01-25 07:53:13.303881 | controller | TASK [shiftstack : Fail if the testconfig is not passed] ***********************
2026-01-25 07:53:13.303891 | controller | Sunday 25 January 2026  07:53:13 +0000 (0:00:00.026)       0:00:01.047 ********
2026-01-25 07:53:13.303901 | controller | ok: [instance] => changed=false
2026-01-25 07:53:14.137727 | controller |   msg: All assertions passed
2026-01-25 07:53:14.137760 | controller |
2026-01-25 07:53:14.137767 | controller | TASK [shiftstack : Remove the shiftstackclient pod if exists] ******************
2026-01-25 07:53:14.137773 | controller | Sunday 25 January 2026  07:53:13 +0000 (0:00:00.027)       0:00:01.074 ********
2026-01-25 07:53:14.137789 | controller | ok: [instance]
2026-01-25 07:53:14.465094 | controller |
2026-01-25 07:53:14.465130 | controller | TASK [shiftstack : Remove the shiftstack role data directory (if exists)] ******
2026-01-25 07:53:14.465139 | controller | Sunday 25 January 2026  07:53:14 +0000 (0:00:00.833)       0:00:01.907 ********
2026-01-25 07:53:14.465149 | controller | ok: [instance]
2026-01-25 07:53:15.262055 | controller |
2026-01-25 07:53:15.262088 | controller | TASK [shiftstack : Create the directory '{{ item }}'] **************************
2026-01-25 07:53:15.262102 | controller | Sunday 25 January 2026  07:53:14 +0000 (0:00:00.327)       0:00:02.235 ********
2026-01-25 07:53:15.262114 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack)
2026-01-25 07:53:15.292743 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs)
2026-01-25 07:53:15.292769 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/manifests)
2026-01-25 07:53:15.292778 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/artifacts)
2026-01-25 07:53:15.292786 | controller |
2026-01-25 07:53:15.292792 | controller | TASK [shiftstack : Deploy the pod 'shiftstackclient-shiftstack'] ***************
2026-01-25 07:53:15.292799 | controller | Sunday 25 January 2026  07:53:15 +0000 (0:00:00.796)       0:00:03.031 ********
2026-01-25 07:53:15.292824 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/deploy_shiftstackclient_pod.yml for instance
2026-01-25 07:53:15.715065 | controller |
2026-01-25 07:53:15.715099 | controller | TASK [shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig] ***
2026-01-25 07:53:15.715113 | controller | Sunday 25 January 2026  07:53:15 +0000 (0:00:00.031)       0:00:03.063 ********
2026-01-25 07:53:15.715124 | controller | changed: [instance]
2026-01-25 07:53:15.747385 | controller |
2026-01-25 07:53:15.747410 | controller | TASK [shiftstack : Encode kubeconfig file to Base64] ***************************
2026-01-25 07:53:15.747418 | controller | Sunday 25 January 2026  07:53:15 +0000 (0:00:00.417)       0:00:03.481 ********
2026-01-25 07:53:15.747427 | controller | ok: [instance]
2026-01-25 07:53:16.372416 | controller |
2026-01-25 07:53:16.372457 | controller | TASK [shiftstack : Render the incluster kubeconfig secret manifest] ************
2026-01-25 07:53:16.372466 | controller | Sunday 25 January 2026  07:53:15 +0000 (0:00:00.036)       0:00:03.518 ********
2026-01-25 07:53:16.372476 | controller | changed: [instance]
2026-01-25 07:53:17.069244 | controller |
2026-01-25 07:53:17.069294 | controller | TASK [shiftstack : Apply the manifest for the incluster kubeconfig secret] *****
2026-01-25 07:53:17.069304 | controller | Sunday 25 January 2026  07:53:16 +0000 (0:00:00.621)       0:00:04.139 ********
2026-01-25 07:53:17.069318 | controller | changed: [instance]
2026-01-25 07:53:17.446254 | controller |
2026-01-25 07:53:17.446299 | controller | TASK [shiftstack : Render the pvc manifest] ************************************
2026-01-25 07:53:17.446309 | controller | Sunday 25 January 2026  07:53:17 +0000 (0:00:00.699)       0:00:04.839 ********
2026-01-25 07:53:17.446318 | controller | changed: [instance]
2026-01-25 07:53:18.095811 | controller |
2026-01-25 07:53:18.095849 | controller | TASK [shiftstack : Apply the manifest for the PVC creation] ********************
2026-01-25 07:53:18.095863 | controller | Sunday 25 January 2026  07:53:17 +0000 (0:00:00.376)       0:00:05.215 ********
2026-01-25 07:53:18.095875 | controller | changed: [instance]
2026-01-25 07:53:18.472903 | controller |
2026-01-25 07:53:18.472934 | controller | TASK [shiftstack : Render the pod manifest from a template] ********************
2026-01-25 07:53:18.472948 | controller | Sunday 25 January 2026  07:53:18 +0000 (0:00:00.650)       0:00:05.866 ********
2026-01-25 07:53:18.472959 | controller | changed: [instance]
2026-01-25 07:59:20.360276 | controller |
2026-01-25 07:59:20.360332 | controller | TASK [shiftstack : Apply the manifest for the pod creation] ********************
2026-01-25 07:59:20.360341 | controller | Sunday 25 January 2026  07:53:18 +0000 (0:00:00.377)       0:00:06.243 ********
2026-01-25 07:59:20.360352 | controller | fatal: [instance]: FAILED! => changed=true
2026-01-25 07:59:20.360391 | controller |   duration: 361
2026-01-25 07:59:20.360399 | controller |   method: create
2026-01-25 07:59:20.360404 | controller |   msg: '"Pod" "shiftstackclient-shiftstack": Timed out waiting on resource'
2026-01-25 07:59:20.360410 | controller |   result:
2026-01-25 07:59:20.360417 | controller |     apiVersion: v1
2026-01-25 07:59:20.360437 | controller |     kind: Pod
2026-01-25 07:59:20.360444 | controller |     metadata:
2026-01-25 07:59:20.360662 | controller |       annotations:
2026-01-25 07:59:20.360721 | controller |         k8s.ovn.org/pod-networks: '{"default":{"ip_addresses":["10.217.0.10/23"],"mac_address":"0a:58:0a:d9:00:0a","gateway_ips":["10.217.0.1"],"routes":[{"dest":"10.217.0.0/22","nextHop":"10.217.0.1"},{"dest":"10.217.4.0/23","nextHop":"10.217.0.1"},{"dest":"169.254.0.5/32","nextHop":"10.217.0.1"},{"dest":"100.64.0.0/16","nextHop":"10.217.0.1"}],"ip_address":"10.217.0.10/23","gateway_ip":"10.217.0.1","role":"primary"}}'
2026-01-25 07:59:20.360752 | controller |         k8s.v1.cni.cncf.io/network-status: |-
2026-01-25 07:59:20.360865 | controller |           [{
2026-01-25 07:59:20.361239 | controller |               "name": "ovn-kubernetes",
2026-01-25 07:59:20.361351 | controller |               "interface": "eth0",
2026-01-25 07:59:20.361367 | controller |               "ips": [
2026-01-25 07:59:20.361374 | controller |                   "10.217.0.10"
2026-01-25 07:59:20.361383 | controller |               ],
2026-01-25 07:59:20.361514 | controller |               "mac": "0a:58:0a:d9:00:0a",
2026-01-25 07:59:20.361533 | controller |               "default": true,
2026-01-25 07:59:20.361721 | controller |               "dns": {}
2026-01-25 07:59:20.362018 | controller |           }]
2026-01-25 07:59:20.362032 | controller |         openshift.io/scc: hostpath-provisioner
2026-01-25 07:59:20.362041 | controller |       creationTimestamp: '2026-01-25T07:53:19Z'
2026-01-25 07:59:20.362214 | controller |       managedFields:
2026-01-25 07:59:20.362228 | controller |       - apiVersion: v1
2026-01-25 07:59:20.362237 | controller |         fieldsType: FieldsV1
2026-01-25 07:59:20.362390 | controller |         fieldsV1:
2026-01-25 07:59:20.362403 | controller |           f:spec:
2026-01-25 07:59:20.362412 | controller |             f:containers:
2026-01-25 07:59:20.362514 | controller |               k:{"name":"shiftstackclient-shiftstack"}:
2026-01-25 07:59:20.362614 | controller |                 .: {}
2026-01-25 07:59:20.362634 | controller |                 f:args: {}
2026-01-25 07:59:20.362773 | controller |                 f:command: {}
2026-01-25 07:59:20.363046 | controller |                 f:image: {}
2026-01-25 07:59:20.363074 | controller |                 f:imagePullPolicy: {}
2026-01-25 07:59:20.363154 | controller |                 f:name: {}
2026-01-25 07:59:20.363165 | controller |                 f:resources: {}
2026-01-25 07:59:20.363174 | controller |                 f:securityContext:
2026-01-25 07:59:20.363463 | controller |                   .: {}
2026-01-25 07:59:20.363483 | controller |                   f:privileged: {}
2026-01-25 07:59:20.363507 | controller |                 f:terminationMessagePath: {}
2026-01-25 07:59:20.363513 | controller |                 f:terminationMessagePolicy: {}
2026-01-25 07:59:20.363520 | controller |                 f:volumeMounts:
2026-01-25 07:59:20.363627 | controller |                   .: {}
2026-01-25 07:59:20.363842 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/cert/"}:
2026-01-25 07:59:20.363856 | controller |                     .: {}
2026-01-25 07:59:20.363864 | controller |                     f:mountPath: {}
2026-01-25 07:59:20.363981 | controller |                     f:name: {}
2026-01-25 07:59:20.364102 | controller |                     f:readOnly: {}
2026-01-25 07:59:20.364228 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/openstack/clouds.yaml"}:
2026-01-25 07:59:20.364344 | controller |                     .: {}
2026-01-25 07:59:20.364422 | controller |                     f:mountPath: {}
2026-01-25 07:59:20.364520 | controller |                     f:name: {}
2026-01-25 07:59:20.364634 | controller |                     f:subPath: {}
2026-01-25 07:59:20.364758 | controller |                   k:{"mountPath":"/home/cloud-admin/.original-config/openstack/secure.yaml"}:
2026-01-25 07:59:20.364871 | controller |                     .: {}
2026-01-25 07:59:20.364966 | controller |                     f:mountPath: {}
2026-01-25 07:59:20.365112 | controller |                     f:name: {}
2026-01-25 07:59:20.365167 | controller |                     f:subPath: {}
2026-01-25 07:59:20.365282 | controller |                   k:{"mountPath":"/home/cloud-admin/artifacts/installation"}:
2026-01-25 07:59:20.365389 | controller |                     .: {}
2026-01-25 07:59:20.365493 | controller |                     f:mountPath: {}
2026-01-25 07:59:20.365607 | controller |                     f:name: {}
2026-01-25 07:59:20.365727 | controller |                   k:{"mountPath":"/home/cloud-admin/incluster-kubeconfig"}:
2026-01-25 07:59:20.365833 | controller |                     .: {}
2026-01-25 07:59:20.365929 | controller |                     f:mountPath: {}
2026-01-25 07:59:20.366030 | controller |                     f:name: {}
2026-01-25 07:59:20.366128 | controller |                     f:readOnly: {}
2026-01-25 07:59:20.366250 | controller |             f:dnsPolicy: {}
2026-01-25 07:59:20.366358 | controller |             f:enableServiceLinks: {}
2026-01-25 07:59:20.366446 | controller |             f:preemptionPolicy: {}
2026-01-25 07:59:20.366545 | controller |             f:priority: {}
2026-01-25 07:59:20.366663 | controller |             f:restartPolicy: {}
2026-01-25 07:59:20.366769 | controller |             f:schedulerName: {}
2026-01-25 07:59:20.366866 | controller |             f:securityContext: {}
2026-01-25 07:59:20.366967 | controller |             f:terminationGracePeriodSeconds: {}
2026-01-25 07:59:20.367079 | controller |             f:tolerations: {}
2026-01-25 07:59:20.367165 | controller |             f:volumes:
2026-01-25 07:59:20.367271 | controller |               .: {}
2026-01-25 07:59:20.367387 | controller |               k:{"name":"incluster-kubeconfig-volume"}:
2026-01-25 07:59:20.367492 | controller |                 .: {}
2026-01-25 07:59:20.367606 | controller |                 f:name: {}
2026-01-25 07:59:20.367720 | controller |                 f:secret:
2026-01-25 07:59:20.367823 | controller |                   .: {}
2026-01-25 07:59:20.367922 | controller |                   f:defaultMode: {}
2026-01-25 07:59:20.368026 | controller |                   f:secretName: {}
2026-01-25 07:59:20.368119 | controller |               k:{"name":"installation-volume"}:
2026-01-25 07:59:20.368226 | controller |                 .: {}
2026-01-25 07:59:20.368344 | controller |                 f:name: {}
2026-01-25 07:59:20.368438 | controller |                 f:persistentVolumeClaim:
2026-01-25 07:59:20.368534 | controller |                   .: {}
2026-01-25 07:59:20.368661 | controller |                   f:claimName: {}
2026-01-25 07:59:20.368771 | controller |               k:{"name":"openstack-cert"}:
2026-01-25 07:59:20.368867 | controller |                 .: {}
2026-01-25 07:59:20.368981 | controller |                 f:name: {}
2026-01-25 07:59:20.369082 | controller |                 f:secret:
2026-01-25 07:59:20.369169 | controller |                   .: {}
2026-01-25 07:59:20.369272 | controller |                   f:defaultMode: {}
2026-01-25 07:59:20.369388 | controller |                   f:secretName: {}
2026-01-25 07:59:20.369487 | controller |               k:{"name":"openstack-config"}:
2026-01-25 07:59:20.369603 | controller |                 .: {}
2026-01-25 07:59:20.369720 | controller |                 f:configMap:
2026-01-25 07:59:20.369809 | controller |                   .: {}
2026-01-25 07:59:20.369898 | controller |                   f:defaultMode: {}
2026-01-25 07:59:20.369995 | controller |                   f:name: {}
2026-01-25 07:59:20.370106 | controller |                 f:name: {}
2026-01-25 07:59:20.370212 | controller |               k:{"name":"openstack-config-secret"}:
2026-01-25 07:59:20.370315 | controller |                 .: {}
2026-01-25 07:59:20.370416 | controller |                 f:name: {}
2026-01-25 07:59:20.370524 | controller |                 f:secret:
2026-01-25 07:59:20.370641 | controller |                   .: {}
2026-01-25 07:59:20.370748 | controller |                   f:defaultMode: {}
2026-01-25 07:59:20.370849 | controller |                   f:secretName: {}
2026-01-25 07:59:20.370942 | controller |         manager: OpenAPI-Generator
2026-01-25 07:59:20.371043 | controller |         operation: Update
2026-01-25 07:59:20.371143 | controller |         time: '2026-01-25T07:53:19Z'
2026-01-25 07:59:20.371246 | controller |       - apiVersion: v1
2026-01-25 07:59:20.371361 | controller |         fieldsType: FieldsV1
2026-01-25 07:59:20.371456 | controller |         fieldsV1:
2026-01-25 07:59:20.371556 | controller |           f:metadata:
2026-01-25 07:59:20.371674 | controller |             f:annotations:
2026-01-25 07:59:20.371780 | controller |               f:k8s.ovn.org/pod-networks: {}
2026-01-25 07:59:20.371876 | controller |         manager: crc
2026-01-25 07:59:20.371995 | controller |         operation: Update
2026-01-25 07:59:20.372099 | controller |         subresource: status
2026-01-25 07:59:20.372196 | controller |         time: '2026-01-25T07:54:24Z'
2026-01-25 07:59:20.372309 | controller |       - apiVersion: v1
2026-01-25 07:59:20.372419 | controller |         fieldsType: FieldsV1
2026-01-25 07:59:20.372524 | controller |         fieldsV1:
2026-01-25 07:59:20.372638 | controller |           f:metadata:
2026-01-25 07:59:20.372745 | controller |             f:annotations:
2026-01-25 07:59:20.372852 | controller |               f:k8s.v1.cni.cncf.io/network-status: {}
2026-01-25 07:59:20.372954 | controller |         manager: multus-daemon
2026-01-25 07:59:20.373061 | controller |         operation: Update
2026-01-25 07:59:20.373153 | controller |         subresource: status
2026-01-25 07:59:20.373267 | controller |         time: '2026-01-25T07:58:35Z'
2026-01-25 07:59:20.373383 | controller |       - apiVersion: v1
2026-01-25 07:59:20.373482 | controller |         fieldsType: FieldsV1
2026-01-25 07:59:20.373598 | controller |         fieldsV1:
2026-01-25 07:59:20.373705 | controller |           f:status:
2026-01-25 07:59:20.373811 | controller |             f:conditions:
2026-01-25 07:59:20.373924 | controller |               k:{"type":"ContainersReady"}:
2026-01-25 07:59:20.374012 | controller |                 .: {}
2026-01-25 07:59:20.374111 | controller |                 f:lastProbeTime: {}
2026-01-25 07:59:20.374213 | controller |                 f:lastTransitionTime: {}
2026-01-25 07:59:20.374324 | controller |                 f:message: {}
2026-01-25 07:59:20.374426 | controller |                 f:reason: {}
2026-01-25 07:59:20.374524 | controller |                 f:status: {}
2026-01-25 07:59:20.374654 | controller |                 f:type: {}
2026-01-25 07:59:20.374759 | controller |               k:{"type":"Initialized"}:
2026-01-25 07:59:20.374857 | controller |                 .: {}
2026-01-25 07:59:20.374959 | controller |                 f:lastProbeTime: {}
2026-01-25 07:59:20.375070 | controller |                 f:lastTransitionTime: {}
2026-01-25 07:59:20.375442 | controller |                 f:status: {}
2026-01-25 07:59:20.375558 | controller |                 f:type: {}
2026-01-25 07:59:20.375692 | controller |               k:{"type":"PodReadyToStartContainers"}:
2026-01-25 07:59:20.375800 | controller |                 .: {}
2026-01-25 07:59:20.375904 | controller |                 f:lastProbeTime: {}
2026-01-25 07:59:20.376006 | controller |                 f:lastTransitionTime: {}
2026-01-25 07:59:20.376119 | controller |                 f:status: {}
2026-01-25 07:59:20.376228 | controller |                 f:type: {}
2026-01-25 07:59:20.376345 | controller |               k:{"type":"Ready"}:
2026-01-25 07:59:20.376444 | controller |                 .: {}
2026-01-25 07:59:20.376558 | controller |                 f:lastProbeTime: {}
2026-01-25 07:59:20.376674 | controller |                 f:lastTransitionTime: {}
2026-01-25 07:59:20.376779 | controller |                 f:message: {}
2026-01-25 07:59:20.376891 | controller |                 f:reason: {}
2026-01-25 07:59:20.376987 | controller |                 f:status: {}
2026-01-25 07:59:20.377084 | controller |                 f:type: {}
2026-01-25 07:59:20.377183 | controller |             f:containerStatuses: {}
2026-01-25 07:59:20.377288 | controller |             f:hostIP: {}
2026-01-25 07:59:20.377409 | controller |             f:hostIPs: {}
2026-01-25 07:59:20.377509 | controller |             f:startTime: {}
2026-01-25 07:59:20.377626 | controller |         manager: kubelet
2026-01-25 07:59:20.377734 | controller |         operation: Update
2026-01-25 07:59:20.377839 | controller |         subresource: status
2026-01-25 07:59:20.377938 | controller |         time: '2026-01-25T07:58:38Z'
2026-01-25 07:59:20.378036 | controller |       name: shiftstackclient-shiftstack
2026-01-25 07:59:20.378135 | controller |       namespace: openstack
2026-01-25 07:59:20.378233 | controller |       resourceVersion: '34770'
2026-01-25 07:59:20.378348 | controller |       uid: 6f02b8fa-ad4d-4b94-a8b3-afa6c7553d23
2026-01-25 07:59:20.378450 | controller |     spec:
2026-01-25 07:59:20.378578 | controller |       containers:
2026-01-25 07:59:20.378661 | controller |       - args:
2026-01-25 07:59:20.378772 | controller |         - infinity
2026-01-25 07:59:20.378878 | controller |         command:
2026-01-25 07:59:20.378969 | controller |         - /bin/sleep
2026-01-25 07:59:20.379084 | controller |         image: quay.io/shiftstack-qe/shiftstack-client:latest
2026-01-25 07:59:20.379192 | controller |         imagePullPolicy: Always
2026-01-25 07:59:20.379305 | controller |         name: shiftstackclient-shiftstack
2026-01-25 07:59:20.379410 | controller |         resources: {}
2026-01-25 07:59:20.379507 | controller |         securityContext:
2026-01-25 07:59:20.379624 | controller |           capabilities:
2026-01-25 07:59:20.379729 | controller |             drop:
2026-01-25 07:59:20.379827 | controller |             - KILL
2026-01-25 07:59:20.379925 | controller |             - MKNOD
2026-01-25 07:59:20.380025 | controller |             - SETGID
2026-01-25 07:59:20.380123 | controller |             - SETUID
2026-01-25 07:59:20.380221 | controller |           privileged: true
2026-01-25 07:59:20.380335 | controller |         terminationMessagePath: /dev/termination-log
2026-01-25 07:59:20.380442 | controller |         terminationMessagePolicy: File
2026-01-25 07:59:20.380543 | controller |         volumeMounts:
2026-01-25 07:59:20.380662 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/clouds.yaml
2026-01-25 07:59:20.380775 | controller |           name: openstack-config
2026-01-25 07:59:20.380874 | controller |           subPath: clouds.yaml
2026-01-25 07:59:20.380976 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/secure.yaml
2026-01-25 07:59:20.381087 | controller |           name: openstack-config-secret
2026-01-25 07:59:20.381175 | controller |           subPath: secure.yaml
2026-01-25 07:59:20.381273 | controller |         - mountPath: /home/cloud-admin/.original-config/cert/
2026-01-25 07:59:20.381399 | controller |           name: openstack-cert
2026-01-25 07:59:20.381499 | controller |           readOnly: true
2026-01-25 07:59:20.381610 | controller |         - mountPath: /home/cloud-admin/artifacts/installation
2026-01-25 07:59:20.381724 | controller |           name: installation-volume
2026-01-25 07:59:20.381825 | controller |         - mountPath: /home/cloud-admin/incluster-kubeconfig
2026-01-25 07:59:20.381922 | controller |           name: incluster-kubeconfig-volume
2026-01-25 07:59:20.382019 | controller |           readOnly: true
2026-01-25 07:59:20.382119 | controller |         - mountPath: /var/run/secrets/kubernetes.io/serviceaccount
2026-01-25 07:59:20.382225 | controller |           name: kube-api-access-9bcs4
2026-01-25 07:59:20.382336 | controller |           readOnly: true
2026-01-25 07:59:20.382440 | controller |       dnsPolicy: ClusterFirst
2026-01-25 07:59:20.382552 | controller |       enableServiceLinks: true
2026-01-25 07:59:20.382670 | controller |       nodeName: crc
2026-01-25 07:59:20.382773 | controller |       preemptionPolicy: PreemptLowerPriority
2026-01-25 07:59:20.382873 | controller |       priority: 0
2026-01-25 07:59:20.382975 | controller |       restartPolicy: Always
2026-01-25 07:59:20.383087 | controller |       schedulerName: default-scheduler
2026-01-25 07:59:20.383186 | controller |       securityContext: {}
2026-01-25 07:59:20.383285 | controller |       serviceAccount: default
2026-01-25 07:59:20.383402 | controller |       serviceAccountName: default
2026-01-25 07:59:20.383503 | controller |       terminationGracePeriodSeconds: 0
2026-01-25 07:59:20.383618 | controller |       tolerations:
2026-01-25 07:59:20.383729 | controller |       - effect: NoExecute
2026-01-25 07:59:20.383835 | controller |         key: node.kubernetes.io/not-ready
2026-01-25 07:59:20.383935 | controller |         operator: Exists
2026-01-25 07:59:20.384037 | controller |         tolerationSeconds: 300
2026-01-25 07:59:20.384147 | controller |       - effect: NoExecute
2026-01-25 07:59:20.384252 | controller |         key: node.kubernetes.io/unreachable
2026-01-25 07:59:20.384360 | controller |         operator: Exists
2026-01-25 07:59:20.384462 | controller |         tolerationSeconds: 300
2026-01-25 07:59:20.384576 | controller |       volumes:
2026-01-25 07:59:20.384682 | controller |       - name: incluster-kubeconfig-volume
2026-01-25 07:59:20.384790 | controller |         secret:
2026-01-25 07:59:20.384891 | controller |           defaultMode: 420
2026-01-25 07:59:20.384987 | controller |           secretName: incluster-kubeconfig
2026-01-25 07:59:20.385096 | controller |       - configMap:
2026-01-25 07:59:20.385188 | controller |           defaultMode: 420
2026-01-25 07:59:20.385290 | controller |           name: openstack-config
2026-01-25 07:59:20.385411 | controller |         name: openstack-config
2026-01-25 07:59:20.385503 | controller |       - name: openstack-config-secret
2026-01-25 07:59:20.385614 | controller |         secret:
2026-01-25 07:59:20.385722 | controller |           defaultMode: 420
2026-01-25 07:59:20.385821 | controller |           secretName: openstack-config-secret
2026-01-25 07:59:20.385928 | controller |       - name: openstack-cert
2026-01-25 07:59:20.386029 | controller |         secret:
2026-01-25 07:59:20.386125 | controller |           defaultMode: 292
2026-01-25 07:59:20.386227 | controller |           secretName: rootca-public
2026-01-25 07:59:20.386335 | controller |       - name: installation-volume
2026-01-25 07:59:20.386432 | controller |         persistentVolumeClaim:
2026-01-25 07:59:20.386534 | controller |           claimName: shiftstackclient-shiftstack-pvc
2026-01-25 07:59:20.386671 | controller |       - name: kube-api-access-9bcs4
2026-01-25 07:59:20.386774 | controller |         projected:
2026-01-25 07:59:20.386860 | controller |           defaultMode: 420
2026-01-25 07:59:20.386956 | controller |           sources:
2026-01-25 07:59:20.387067 | controller |           - serviceAccountToken:
2026-01-25 07:59:20.387176 | controller |               expirationSeconds: 3607
2026-01-25 07:59:20.387266 | controller |               path: token
2026-01-25 07:59:20.387369 | controller |           - configMap:
2026-01-25 07:59:20.387466 | controller |               items:
2026-01-25 07:59:20.387570 | controller |               - key: ca.crt
2026-01-25 07:59:20.387714 | controller |                 path: ca.crt
2026-01-25 07:59:20.387812 | controller |               name: kube-root-ca.crt
2026-01-25 07:59:20.387926 | controller |           - downwardAPI:
2026-01-25 07:59:20.388019 | controller |               items:
2026-01-25 07:59:20.388130 | controller |               - fieldRef:
2026-01-25 07:59:20.388227 | controller |                   apiVersion: v1
2026-01-25 07:59:20.388344 | controller |                   fieldPath: metadata.namespace
2026-01-25 07:59:20.388439 | controller |                 path: namespace
2026-01-25 07:59:20.388549 | controller |           - configMap:
2026-01-25 07:59:20.388666 | controller |               items:
2026-01-25 07:59:20.388781 | controller |               - key: service-ca.crt
2026-01-25 07:59:20.388872 | controller |                 path: service-ca.crt
2026-01-25 07:59:20.388976 | controller |               name: openshift-service-ca.crt
2026-01-25 07:59:20.389089 | controller |     status:
2026-01-25 07:59:20.389182 | controller |       conditions:
2026-01-25 07:59:20.389287 | controller |       - lastProbeTime: null
2026-01-25 07:59:20.389412 | controller |         lastTransitionTime: '2026-01-25T07:54:24Z'
2026-01-25 07:59:20.389518 | controller |         status: 'False'
2026-01-25 07:59:20.389644 | controller |         type: PodReadyToStartContainers
2026-01-25 07:59:20.389737 | controller |       - lastProbeTime: null
2026-01-25 07:59:20.389837 | controller |         lastTransitionTime: '2026-01-25T07:54:24Z'
2026-01-25 07:59:20.389935 | controller |         status: 'True'
2026-01-25 07:59:20.390035 | controller |         type: Initialized
2026-01-25 07:59:20.390139 | controller |       - lastProbeTime: null
2026-01-25 07:59:20.390229 | controller |         lastTransitionTime: '2026-01-25T07:54:24Z'
2026-01-25 07:59:20.390385 | controller |         message: 'containers with unready status: [shiftstackclient-shiftstack]'
2026-01-25 07:59:20.390455 | controller |         reason: ContainersNotReady
2026-01-25 07:59:20.390554 | controller |         status: 'False'
2026-01-25 07:59:20.390672 | controller |         type: Ready
2026-01-25 07:59:20.390792 | controller |       - lastProbeTime: null
2026-01-25 07:59:20.390897 | controller |         lastTransitionTime: '2026-01-25T07:54:24Z'
2026-01-25 07:59:20.390980 | controller |         message: 'containers with unready status: [shiftstackclient-shiftstack]'
2026-01-25 07:59:20.391092 | controller |         reason: ContainersNotReady
2026-01-25 07:59:20.391187 | controller |         status: 'False'
2026-01-25 07:59:20.391287 | controller |         type: ContainersReady
2026-01-25 07:59:20.391402 | controller |       - lastProbeTime: null
2026-01-25 07:59:20.391505 | controller |         lastTransitionTime: '2026-01-25T07:54:24Z'
2026-01-25 07:59:20.391638 | controller |         status: 'True'
2026-01-25 07:59:20.391729 | controller |         type: PodScheduled
2026-01-25 07:59:20.391828 | controller |       containerStatuses:
2026-01-25 07:59:20.391927 | controller |       - image: quay.io/shiftstack-qe/shiftstack-client:latest
2026-01-25 07:59:20.392027 | controller |         imageID: ''
2026-01-25 07:59:20.392125 | controller |         lastState: {}
2026-01-25 07:59:20.392231 | controller |         name: shiftstackclient-shiftstack
2026-01-25 07:59:20.392342 | controller |         ready: false
2026-01-25 07:59:20.392445 | controller |         restartCount: 0
2026-01-25 07:59:20.392546 | controller |         started: false
2026-01-25 07:59:20.392671 | controller |         state:
2026-01-25 07:59:20.392776 | controller |           waiting:
2026-01-25 07:59:20.392878 | controller |             reason: ContainerCreating
2026-01-25 07:59:20.392976 | controller |         volumeMounts:
2026-01-25 07:59:20.393090 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/clouds.yaml
2026-01-25 07:59:20.393177 | controller |           name: openstack-config
2026-01-25 07:59:20.393278 | controller |         - mountPath: /home/cloud-admin/.original-config/openstack/secure.yaml
2026-01-25 07:59:20.393392 | controller |           name: openstack-config-secret
2026-01-25 07:59:20.393495 | controller |         - mountPath: /home/cloud-admin/.original-config/cert/
2026-01-25 07:59:20.393610 | controller |           name: openstack-cert
2026-01-25 07:59:20.393721 | controller |           readOnly: true
2026-01-25 07:59:20.393819 | controller |           recursiveReadOnly: Disabled
2026-01-25 07:59:20.393920 | controller |         - mountPath: /home/cloud-admin/artifacts/installation
2026-01-25 07:59:20.394028 | controller |           name: installation-volume
2026-01-25 07:59:20.394119 | controller |         - mountPath: /home/cloud-admin/incluster-kubeconfig
2026-01-25 07:59:20.394228 | controller |           name: incluster-kubeconfig-volume
2026-01-25 07:59:20.394339 | controller |           readOnly: true
2026-01-25 07:59:20.394443 | controller |           recursiveReadOnly: Disabled
2026-01-25 07:59:20.394543 | controller |         - mountPath: /var/run/secrets/kubernetes.io/serviceaccount
2026-01-25 07:59:20.394757 | controller |           name: kube-api-access-9bcs4
2026-01-25 07:59:20.394858 | controller |           readOnly: true
2026-01-25 07:59:20.394969 | controller |           recursiveReadOnly: Disabled
2026-01-25 07:59:20.395074 | controller |       hostIP: 192.168.126.11
2026-01-25 07:59:20.395164 | controller |       hostIPs:
2026-01-25 07:59:20.395265 | controller |       - ip: 192.168.126.11
2026-01-25 07:59:20.395378 | controller |       phase: Pending
2026-01-25 07:59:20.395475 | controller |       qosClass: BestEffort
2026-01-25 07:59:20.395589 | controller |       startTime: '2026-01-25T07:54:24Z'
2026-01-25 07:59:20.395805 | controller |
2026-01-25 07:59:20.395917 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:59:20.396040 | controller | instance                   : ok=14   changed=7    unreachable=0    failed=1    skipped=0    rescued=0    ignored=0
2026-01-25 07:59:20.396111 | controller |
2026-01-25 07:59:20.396218 | controller | Sunday 25 January 2026  07:59:20 +0000 (0:06:01.890)       0:06:08.133 ********
2026-01-25 07:59:20.396320 | controller | ===============================================================================
2026-01-25 07:59:20.396433 | controller | shiftstack : Apply the manifest for the pod creation ------------------ 361.89s
2026-01-25 07:59:20.396533 | controller | Gathering Facts --------------------------------------------------------- 0.95s
2026-01-25 07:59:20.396655 | controller | shiftstack : Remove the shiftstackclient pod if exists ------------------ 0.83s
2026-01-25 07:59:20.396765 | controller | shiftstack : Create the directory '{{ item }}' -------------------------- 0.80s
2026-01-25 07:59:20.396873 | controller | shiftstack : Apply the manifest for the incluster kubeconfig secret ----- 0.70s
2026-01-25 07:59:20.396982 | controller | shiftstack : Apply the manifest for the PVC creation -------------------- 0.65s
2026-01-25 07:59:20.397103 | controller | shiftstack : Render the incluster kubeconfig secret manifest ------------ 0.62s
2026-01-25 07:59:20.397172 | controller | shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig --- 0.42s
2026-01-25 07:59:20.397277 | controller | shiftstack : Render the pod manifest from a template -------------------- 0.38s
2026-01-25 07:59:20.397389 | controller | shiftstack : Render the pvc manifest ------------------------------------ 0.38s
2026-01-25 07:59:20.397488 | controller | shiftstack : Remove the shiftstack role data directory (if exists) ------ 0.33s
2026-01-25 07:59:20.397609 | controller | shiftstack : Encode kubeconfig file to Base64 --------------------------- 0.04s
2026-01-25 07:59:20.397730 | controller | shiftstack : Deploy the pod 'shiftstackclient-shiftstack' --------------- 0.03s
2026-01-25 07:59:20.397822 | controller | Include the shiftstack role --------------------------------------------- 0.03s
2026-01-25 07:59:20.397924 | controller | shiftstack : Fail if the testconfig is not passed ----------------------- 0.03s
2026-01-25 07:59:20.398023 | controller | shiftstack : Include pre test shiftstack tasks -------------------------- 0.03s
2026-01-25 07:59:20.405444 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/shiftstack/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/molecule/default/converge.yml']
2026-01-25 07:59:20.405871 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2026-01-25 07:59:20.414979 | controller | INFO     Running default > cleanup
2026-01-25 07:59:20.910676 | controller |
2026-01-25 07:59:21.833218 | controller | PLAY [Cleanup] *****************************************************************
2026-01-25 07:59:21.833257 | controller |
2026-01-25 07:59:21.833265 | controller | TASK [Gathering Facts] *********************************************************
2026-01-25 07:59:21.833271 | controller | Sunday 25 January 2026  07:59:20 +0000 (0:00:00.057)       0:00:00.057 ********
2026-01-25 07:59:21.833283 | controller | ok: [instance]
2026-01-25 07:59:21.862907 | controller |
2026-01-25 07:59:21.862943 | controller | TASK [Include the shiftstack role and run the cleanup] *************************
2026-01-25 07:59:21.862961 | controller | Sunday 25 January 2026  07:59:21 +0000 (0:00:00.922)       0:00:00.980 ********
2026-01-25 07:59:21.862973 | controller |
2026-01-25 07:59:21.886386 | controller | TASK [shiftstack : Destroy the Openshift cluster] ******************************
2026-01-25 07:59:21.886412 | controller | Sunday 25 January 2026  07:59:21 +0000 (0:00:00.030)       0:00:01.010 ********
2026-01-25 07:59:21.886425 | controller | ok: [instance] =>
2026-01-25 07:59:22.717042 | controller |   msg: 'TODO: to be added once it''s supported in shiftstack-qa automation'
2026-01-25 07:59:22.717093 | controller |
2026-01-25 07:59:22.717102 | controller | TASK [shiftstack : Delete the secret 'incluster-kubeconfig'] *******************
2026-01-25 07:59:22.717108 | controller | Sunday 25 January 2026  07:59:21 +0000 (0:00:00.023)       0:00:01.033 ********
2026-01-25 07:59:22.717120 | controller | changed: [instance]
2026-01-25 07:59:23.393254 | controller |
2026-01-25 07:59:23.393314 | controller | TASK [shiftstack : Delete the pod 'shiftstackclient-shiftstack'] ***************
2026-01-25 07:59:23.393323 | controller | Sunday 25 January 2026  07:59:22 +0000 (0:00:00.831)       0:00:01.864 ********
2026-01-25 07:59:23.393337 | controller | changed: [instance]
2026-01-25 07:59:24.042100 | controller |
2026-01-25 07:59:24.042177 | controller | TASK [Delete the openstack namespace] ******************************************
2026-01-25 07:59:24.042186 | controller | Sunday 25 January 2026  07:59:23 +0000 (0:00:00.676)       0:00:02.540 ********
2026-01-25 07:59:24.042202 | controller | changed: [instance]
2026-01-25 07:59:24.042283 | controller |
2026-01-25 07:59:24.042293 | controller | PLAY RECAP *********************************************************************
2026-01-25 07:59:24.042313 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-01-25 07:59:24.042393 | controller |
2026-01-25 07:59:24.042496 | controller | Sunday 25 January 2026  07:59:24 +0000 (0:00:00.649)       0:00:03.189 ********
2026-01-25 07:59:24.042585 | controller | ===============================================================================
2026-01-25 07:59:24.042693 | controller | Gathering Facts --------------------------------------------------------- 0.92s
2026-01-25 07:59:24.042794 | controller | shiftstack : Delete the secret 'incluster-kubeconfig' ------------------- 0.83s
2026-01-25 07:59:24.042898 | controller | shiftstack : Delete the pod 'shiftstackclient-shiftstack' --------------- 0.68s
2026-01-25 07:59:24.042991 | controller | Delete the openstack namespace ------------------------------------------ 0.65s
2026-01-25 07:59:24.043090 | controller | Include the shiftstack role and run the cleanup ------------------------- 0.03s
2026-01-25 07:59:24.043197 | controller | shiftstack : Destroy the Openshift cluster ------------------------------ 0.02s
2026-01-25 07:59:24.095994 | controller | INFO     Running default > destroy
2026-01-25 07:59:24.096322 | controller | WARNING  Skipping, instances are delegated.
2026-01-25 07:59:24.096645 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-01-25 07:59:24.098155 | controller | INFO     Writing /tmp/report.html report.
2026-01-25 07:59:24.559569 | controller | ERROR
2026-01-25 07:59:24.559687 | controller | {
2026-01-25 07:59:24.559721 | controller |   "delta": "0:11:00.465230",
2026-01-25 07:59:24.559744 | controller |   "end": "2026-01-25 07:59:24.146823",
2026-01-25 07:59:24.559768 | controller |   "msg": "non-zero return code",
2026-01-25 07:59:24.559785 | controller |   "rc": 1,
2026-01-25 07:59:24.559803 | controller |   "start": "2026-01-25 07:48:23.681593"
2026-01-25 07:59:24.559822 | controller | }
failure
2026-01-25 07:59:24.606190 | 
2026-01-25 07:59:24.606268 | PLAY RECAP
2026-01-25 07:59:24.606396 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-01-25 07:59:24.606435 | 
2026-01-25 07:59:24.667216 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-25 07:59:24.667953 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-25 07:59:25.189209 | 
2026-01-25 07:59:25.189335 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-25 07:59:25.209152 | 
2026-01-25 07:59:25.209224 | TASK [Filter out host if needed]
2026-01-25 07:59:25.217815 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-25 07:59:25.222186 | 
2026-01-25 07:59:25.222268 | TASK [Ensure file is present]
2026-01-25 07:59:25.598488 | controller | ok
2026-01-25 07:59:25.603906 | 
2026-01-25 07:59:25.603973 | TASK [Manage molecule report file]
2026-01-25 07:59:26.125980 | controller | changed
2026-01-25 07:59:26.130973 | 
2026-01-25 07:59:26.131034 | TASK [Check if we get ci-framework-data basedir]
2026-01-25 07:59:26.381837 | controller | ok
2026-01-25 07:59:26.386979 | 
2026-01-25 07:59:26.387042 | TASK [Create ci-framework-data log directory for zuul]
2026-01-25 07:59:26.759045 | controller | changed
2026-01-25 07:59:26.765202 | 
2026-01-25 07:59:26.765318 | TASK [Copy ci-framework interesting files]
2026-01-25 07:59:27.287615 | controller | changed
2026-01-25 07:59:27.292948 | 
2026-01-25 07:59:27.293017 | TASK [Get SELinux listing]
2026-01-25 07:59:27.818483 | controller | changed
2026-01-25 07:59:27.824492 | 
2026-01-25 07:59:27.824569 | TASK [Generate log index]
2026-01-25 07:59:28.990266 | controller | changed
2026-01-25 07:59:28.995492 | 
2026-01-25 07:59:28.995593 | TASK [Get some env related data]
2026-01-25 07:59:29.860757 | controller | /home/zuul/.local/bin/ansible
2026-01-25 07:59:30.520968 | controller | changed
2026-01-25 07:59:30.547088 | 
2026-01-25 07:59:30.547178 | TASK [Generate list of logs to collect in home directory]
2026-01-25 07:59:30.941373 | controller | ok: All paths examined
2026-01-25 07:59:30.947465 | 
2026-01-25 07:59:30.947544 | LOOP [Copy logs from home directory]
2026-01-25 07:59:31.628382 | controller | changed:
2026-01-25 07:59:31.628661 | controller | {
2026-01-25 07:59:31.628690 | controller |   "atime": 1743544925.4788878,
2026-01-25 07:59:31.628710 | controller |   "ctime": 1743545329.1409318,
2026-01-25 07:59:31.628728 | controller |   "dev": 64513,
2026-01-25 07:59:31.628744 | controller |   "gid": 1000,
2026-01-25 07:59:31.628759 | controller |   "gr_name": "zuul",
2026-01-25 07:59:31.628773 | controller |   "inode": 4518807,
2026-01-25 07:59:31.628804 | controller |   "isblk": false,
2026-01-25 07:59:31.628820 | controller |   "ischr": false,
2026-01-25 07:59:31.628835 | controller |   "isdir": false,
2026-01-25 07:59:31.628850 | controller |   "isfifo": false,
2026-01-25 07:59:31.628864 | controller |   "isgid": false,
2026-01-25 07:59:31.628878 | controller |   "islnk": false,
2026-01-25 07:59:31.628892 | controller |   "isreg": true,
2026-01-25 07:59:31.628905 | controller |   "issock": false,
2026-01-25 07:59:31.628918 | controller |   "isuid": false,
2026-01-25 07:59:31.628931 | controller |   "mode": "0644",
2026-01-25 07:59:31.628944 | controller |   "mtime": 1743545329.1409318,
2026-01-25 07:59:31.628957 | controller |   "nlink": 1,
2026-01-25 07:59:31.628970 | controller |   "path": "/home/zuul/crc-setup.log",
2026-01-25 07:59:31.628983 | controller |   "pw_name": "zuul",
2026-01-25 07:59:31.628996 | controller |   "rgrp": true,
2026-01-25 07:59:31.629018 | controller |   "roth": true,
2026-01-25 07:59:31.629033 | controller |   "rusr": true,
2026-01-25 07:59:31.629047 | controller |   "size": 4108,
2026-01-25 07:59:31.629060 | controller |   "uid": 1000,
2026-01-25 07:59:31.629073 | controller |   "wgrp": false,
2026-01-25 07:59:31.629086 | controller |   "woth": false,
2026-01-25 07:59:31.629099 | controller |   "wusr": true,
2026-01-25 07:59:31.629114 | controller |   "xgrp": false,
2026-01-25 07:59:31.629129 | controller |   "xoth": false,
2026-01-25 07:59:31.629142 | controller |   "xusr": false
2026-01-25 07:59:31.629155 | controller | }
2026-01-25 07:59:32.261111 | controller | changed:
2026-01-25 07:59:32.261241 | controller | {
2026-01-25 07:59:32.261281 | controller |   "atime": 1743545331.0429637,
2026-01-25 07:59:32.261324 | controller |   "ctime": 1743545842.0111232,
2026-01-25 07:59:32.261347 | controller |   "dev": 64513,
2026-01-25 07:59:32.261365 | controller |   "gid": 1000,
2026-01-25 07:59:32.261382 | controller |   "gr_name": "zuul",
2026-01-25 07:59:32.261397 | controller |   "inode": 4194437,
2026-01-25 07:59:32.261412 | controller |   "isblk": false,
2026-01-25 07:59:32.261427 | controller |   "ischr": false,
2026-01-25 07:59:32.261441 | controller |   "isdir": false,
2026-01-25 07:59:32.261455 | controller |   "isfifo": false,
2026-01-25 07:59:32.261468 | controller |   "isgid": false,
2026-01-25 07:59:32.261481 | controller |   "islnk": false,
2026-01-25 07:59:32.261493 | controller |   "isreg": true,
2026-01-25 07:59:32.261506 | controller |   "issock": false,
2026-01-25 07:59:32.261519 | controller |   "isuid": false,
2026-01-25 07:59:32.261532 | controller |   "mode": "0644",
2026-01-25 07:59:32.261546 | controller |   "mtime": 1743545842.0111232,
2026-01-25 07:59:32.261573 | controller |   "nlink": 1,
2026-01-25 07:59:32.261590 | controller |   "path": "/home/zuul/crc-start.log",
2026-01-25 07:59:32.261612 | controller |   "pw_name": "zuul",
2026-01-25 07:59:32.261627 | controller |   "rgrp": true,
2026-01-25 07:59:32.261641 | controller |   "roth": true,
2026-01-25 07:59:32.261655 | controller |   "rusr": true,
2026-01-25 07:59:32.261669 | controller |   "size": 4023,
2026-01-25 07:59:32.261682 | controller |   "uid": 1000,
2026-01-25 07:59:32.261696 | controller |   "wgrp": false,
2026-01-25 07:59:32.261708 | controller |   "woth": false,
2026-01-25 07:59:32.261721 | controller |   "wusr": true,
2026-01-25 07:59:32.261734 | controller |   "xgrp": false,
2026-01-25 07:59:32.261747 | controller |   "xoth": false,
2026-01-25 07:59:32.261759 | controller |   "xusr": false
2026-01-25 07:59:32.261772 | controller | }
2026-01-25 07:59:32.906151 | controller | changed:
2026-01-25 07:59:32.906320 | controller | {
2026-01-25 07:59:32.906353 | controller |   "atime": 1769327285.4317565,
2026-01-25 07:59:32.906374 | controller |   "ctime": 1769327301.1198673,
2026-01-25 07:59:32.906392 | controller |   "dev": 64513,
2026-01-25 07:59:32.906407 | controller |   "gid": 1000,
2026-01-25 07:59:32.906421 | controller |   "gr_name": "zuul",
2026-01-25 07:59:32.906435 | controller |   "inode": 4340277,
2026-01-25 07:59:32.906448 | controller |   "isblk": false,
2026-01-25 07:59:32.906462 | controller |   "ischr": false,
2026-01-25 07:59:32.906475 | controller |   "isdir": false,
2026-01-25 07:59:32.906489 | controller |   "isfifo": false,
2026-01-25 07:59:32.906502 | controller |   "isgid": false,
2026-01-25 07:59:32.906515 | controller |   "islnk": false,
2026-01-25 07:59:32.906528 | controller |   "isreg": true,
2026-01-25 07:59:32.906541 | controller |   "issock": false,
2026-01-25 07:59:32.906555 | controller |   "isuid": false,
2026-01-25 07:59:32.906568 | controller |   "mode": "0644",
2026-01-25 07:59:32.906583 | controller |   "mtime": 1769327301.1198673,
2026-01-25 07:59:32.906598 | controller |   "nlink": 1,
2026-01-25 07:59:32.906613 | controller |   "path": "/home/zuul/ansible.log",
2026-01-25 07:59:32.906627 | controller |   "pw_name": "zuul",
2026-01-25 07:59:32.906641 | controller |   "rgrp": true,
2026-01-25 07:59:32.906654 | controller |   "roth": true,
2026-01-25 07:59:32.906668 | controller |   "rusr": true,
2026-01-25 07:59:32.906684 | controller |   "size": 6749,
2026-01-25 07:59:32.906699 | controller |   "uid": 1000,
2026-01-25 07:59:32.906713 | controller |   "wgrp": false,
2026-01-25 07:59:32.906726 | controller |   "woth": false,
2026-01-25 07:59:32.906740 | controller |   "wusr": true,
2026-01-25 07:59:32.906754 | controller |   "xgrp": false,
2026-01-25 07:59:32.906767 | controller |   "xoth": false,
2026-01-25 07:59:32.906780 | controller |   "xusr": false
2026-01-25 07:59:32.906792 | controller | }
2026-01-25 07:59:32.916431 | 
2026-01-25 07:59:32.916606 | TASK [Copy crio stats log file]
2026-01-25 07:59:32.941806 | controller | skipping: Conditional result was False
2026-01-25 07:59:32.948001 | 
2026-01-25 07:59:32.948106 | TASK [Get SELinux related data]
2026-01-25 07:59:33.272631 | controller | <no matches>
2026-01-25 07:59:33.476978 | controller | ERROR
2026-01-25 07:59:33.477238 | controller | {
2026-01-25 07:59:33.477285 | controller |   "delta": "0:00:00.019535",
2026-01-25 07:59:33.477339 | controller |   "end": "2026-01-25 07:59:33.275503",
2026-01-25 07:59:33.477361 | controller |   "msg": "non-zero return code",
2026-01-25 07:59:33.477377 | controller |   "rc": 1,
2026-01-25 07:59:33.477392 | controller |   "start": "2026-01-25 07:59:33.255968"
2026-01-25 07:59:33.477406 | controller | }
2026-01-25 07:59:33.477427 | controller | ERROR: Ignoring Errors
2026-01-25 07:59:33.482868 | 
2026-01-25 07:59:33.482933 | TASK [Create system configuration directory]
2026-01-25 07:59:33.756511 | controller | changed
2026-01-25 07:59:33.761403 | 
2026-01-25 07:59:33.761475 | TASK [Get some of the system configurations]
2026-01-25 07:59:34.287044 | controller | changed
2026-01-25 07:59:34.292452 | 
2026-01-25 07:59:34.292529 | TASK [Copy generated documentation if available]
2026-01-25 07:59:34.316286 | controller | skipping: Conditional result was False
2026-01-25 07:59:34.322063 | 
2026-01-25 07:59:34.322129 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-25 07:59:34.346719 | controller | skipping: Conditional result was False
2026-01-25 07:59:34.352918 | 
2026-01-25 07:59:34.352987 | TASK [Compress logs bigger than 2MB]
2026-01-25 07:59:34.878812 | controller | changed
2026-01-25 07:59:34.884164 | 
2026-01-25 07:59:34.884231 | TASK [Copy files from workspace on node]
2026-01-25 07:59:34.903314 | controller | ok
2026-01-25 07:59:34.925740 | 
2026-01-25 07:59:34.925812 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-25 07:59:34.949937 | controller | skipping: Conditional result was False
2026-01-25 07:59:34.955631 | 
2026-01-25 07:59:34.955700 | TASK [fetch-output : Set log path for single node]
2026-01-25 07:59:34.984542 | controller | ok
2026-01-25 07:59:34.989452 | 
2026-01-25 07:59:34.989517 | LOOP [fetch-output : Ensure local output dirs]
2026-01-25 07:59:35.192032 | controller -> localhost | ok: "/var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/logs"
2026-01-25 07:59:35.192342 | controller -> localhost | changed: All items complete
2026-01-25 07:59:35.192377 | 
2026-01-25 07:59:35.380445 | controller -> localhost | changed: "/var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/artifacts"
2026-01-25 07:59:35.576988 | controller -> localhost | changed: "/var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/docs"
2026-01-25 07:59:35.591879 | 
2026-01-25 07:59:35.592017 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-25 07:59:36.232094 | controller | changed:
2026-01-25 07:59:36.232408 | controller | .d..t...... ./
2026-01-25 07:59:36.232451 | controller | >f+++++++++ README.html
2026-01-25 07:59:36.232477 | controller | >f+++++++++ ansible-execution.log
2026-01-25 07:59:36.232498 | controller | >f+++++++++ ansible.log
2026-01-25 07:59:36.232517 | controller | >f+++++++++ crc-setup.log
2026-01-25 07:59:36.232536 | controller | >f+++++++++ crc-start.log
2026-01-25 07:59:36.232553 | controller | >f+++++++++ dmesg.log
2026-01-25 07:59:36.232571 | controller | >f+++++++++ installed-pkgs.log
2026-01-25 07:59:36.232592 | controller | >f+++++++++ python.log
2026-01-25 07:59:36.232609 | controller | >f+++++++++ registries.conf
2026-01-25 07:59:36.232626 | controller | >f+++++++++ report.html
2026-01-25 07:59:36.232642 | controller | >f+++++++++ selinux-denials.log
2026-01-25 07:59:36.232658 | controller | >f+++++++++ selinux-listing.log
2026-01-25 07:59:36.232674 | controller | cd+++++++++ ci-framework-data/
2026-01-25 07:59:36.232689 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-25 07:59:36.232705 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-25 07:59:36.232721 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2026-01-25 07:59:36.232737 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2026-01-25 07:59:36.232753 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2026-01-25 07:59:36.232771 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-25 07:59:36.232789 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-25 07:59:36.232807 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-01-25 07:59:36.232824 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-01-25 07:59:36.232840 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-25 07:59:36.232857 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-25 07:59:36.232873 | controller | cd+++++++++ ci-framework-data/tests/
2026-01-25 07:59:36.232889 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/
2026-01-25 07:59:36.232905 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/
2026-01-25 07:59:36.232920 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/
2026-01-25 07:59:36.232941 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/manifests/
2026-01-25 07:59:36.232969 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_incluster_secret.yml
2026-01-25 07:59:36.232986 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pod.yml
2026-01-25 07:59:36.233003 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pvc.yml
2026-01-25 07:59:36.233019 | controller | cd+++++++++ registries.conf.d/
2026-01-25 07:59:36.233036 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-25 07:59:36.233052 | controller | cd+++++++++ system-config/
2026-01-25 07:59:36.233068 | controller | cd+++++++++ system-config/libvirt/
2026-01-25 07:59:36.233083 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-25 07:59:36.233098 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-25 07:59:36.233114 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-01-25 07:59:36.233129 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-01-25 07:59:36.233144 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-01-25 07:59:36.233159 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-01-25 07:59:36.233174 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-01-25 07:59:36.233190 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-01-25 07:59:36.233206 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-01-25 07:59:36.233221 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-01-25 07:59:36.233236 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-01-25 07:59:36.233261 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-01-25 07:59:36.233278 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-01-25 07:59:36.233323 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-01-25 07:59:36.233342 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-01-25 07:59:36.233359 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-01-25 07:59:36.736397 | controller | changed: .d..t...... ./
2026-01-25 07:59:37.172740 | controller | changed: .d..t...... ./
2026-01-25 07:59:37.191599 | 
2026-01-25 07:59:37.191720 | TASK [Return artifact to Zuul]
2026-01-25 07:59:37.220224 | controller | ok
2026-01-25 07:59:37.238599 | 
2026-01-25 07:59:37.238657 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-25 07:59:37.238755 | 
2026-01-25 07:59:37.238783 | PLAY RECAP
2026-01-25 07:59:37.238820 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-25 07:59:37.238842 | 
2026-01-25 07:59:37.318532 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-25 07:59:37.319271 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-25 07:59:37.820683 | 
2026-01-25 07:59:37.820781 | PLAY [all]
2026-01-25 07:59:37.839062 | 
2026-01-25 07:59:37.839138 | TASK [include_role : fetch-output]
2026-01-25 07:59:37.867412 | controller | ok
2026-01-25 07:59:37.884012 | 
2026-01-25 07:59:37.884102 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-25 07:59:37.927849 | controller | skipping: Conditional result was False
2026-01-25 07:59:37.933421 | 
2026-01-25 07:59:37.933500 | TASK [fetch-output : Set log path for single node]
2026-01-25 07:59:37.961378 | controller | ok
2026-01-25 07:59:37.966326 | 
2026-01-25 07:59:37.966403 | LOOP [fetch-output : Ensure local output dirs]
2026-01-25 07:59:38.295073 | controller -> localhost | ok: "/var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/logs"
2026-01-25 07:59:38.478970 | controller -> localhost | ok: "/var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/artifacts"
2026-01-25 07:59:38.674956 | controller -> localhost | ok: "/var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/docs"
2026-01-25 07:59:38.686207 | 
2026-01-25 07:59:38.686353 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-25 07:59:39.269060 | controller | ok
2026-01-25 07:59:39.269325 | controller | ok: All items complete
2026-01-25 07:59:39.269362 | 
2026-01-25 07:59:39.713389 | controller | ok
2026-01-25 07:59:40.191759 | controller | ok
2026-01-25 07:59:40.211479 | 
2026-01-25 07:59:40.211583 | TASK [include_role : fetch-output-openshift]
2026-01-25 07:59:40.225726 | controller | skipping: Conditional result was False
2026-01-25 07:59:40.231872 | 
2026-01-25 07:59:40.231950 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-25 07:59:40.607778 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008668
2026-01-25 07:59:40.815244 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008699
2026-01-25 07:59:40.850657 | 
2026-01-25 07:59:40.850756 | PLAY [all]
2026-01-25 07:59:40.864783 | 
2026-01-25 07:59:40.864863 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-25 07:59:41.351158 | controller | changed
2026-01-25 07:59:41.372589 | 
2026-01-25 07:59:41.372646 | PLAY RECAP
2026-01-25 07:59:41.372690 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-25 07:59:41.372711 | 
2026-01-25 07:59:41.441223 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-25 07:59:41.441977 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-25 07:59:41.995433 | 
2026-01-25 07:59:41.995543 | PLAY [localhost]
2026-01-25 07:59:42.012233 | 
2026-01-25 07:59:42.012336 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-25 07:59:42.333517 | localhost | changed
2026-01-25 07:59:42.338026 | 
2026-01-25 07:59:42.338116 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-25 07:59:42.366181 | localhost | ok
2026-01-25 07:59:42.373949 | 
2026-01-25 07:59:42.374017 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-25 07:59:42.674637 | localhost | changed
2026-01-25 07:59:42.680338 | 
2026-01-25 07:59:42.680426 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-25 07:59:43.224267 | localhost | changed
2026-01-25 07:59:43.229355 | 
2026-01-25 07:59:43.229432 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-25 07:59:43.571811 | localhost | Identity added: /var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/tmp/ansible.7p2oyn60 (/var/lib/zuul/builds/392a17c260d846b8b5bfd9c41166ad35/work/tmp/ansible.7p2oyn60)
2026-01-25 07:59:43.572044 | localhost | ok: Runtime: 0:00:00.007042
2026-01-25 07:59:43.578045 | 
2026-01-25 07:59:43.578400 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-25 07:59:43.810916 | localhost | ok: Runtime: 0:00:00.005277
2026-01-25 07:59:43.815889 | 
2026-01-25 07:59:43.816001 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-25 07:59:43.866131 | localhost | changed
2026-01-25 07:59:43.871815 | 
2026-01-25 07:59:43.871914 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-25 07:59:44.205089 | localhost | changed
2026-01-25 07:59:44.225330 | 
2026-01-25 07:59:44.225426 | PLAY [localhost]
2026-01-25 07:59:44.236582 | 
2026-01-25 07:59:44.236643 | TASK [Generate bulk log download script]
2026-01-25 07:59:44.256378 | localhost | ok
2026-01-25 07:59:44.267811 | 
2026-01-25 07:59:44.267890 | TASK [local-log-download : Check API endpoint is defined]
2026-01-25 07:59:44.295438 | localhost | ok: All assertions passed
2026-01-25 07:59:44.299971 | 
2026-01-25 07:59:44.300049 | TASK [local-log-download : Create download script]
2026-01-25 07:59:44.668283 | localhost -> localhost | changed
2026-01-25 07:59:44.676991 | 
2026-01-25 07:59:44.677066 | TASK [Register quick-download link]
2026-01-25 07:59:44.695607 | localhost | ok
2026-01-25 07:59:44.736194 | 
2026-01-25 07:59:44.736316 | PLAY [logserver.rdoproject.org]
2026-01-25 07:59:44.745813 | 
2026-01-25 07:59:44.745876 | TASK [Set zuul-log-path fact]
2026-01-25 07:59:44.762922 | logserver.rdoproject.org | ok
2026-01-25 07:59:44.772035 | 
2026-01-25 07:59:44.772114 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 07:59:44.799215 | logserver.rdoproject.org | ok
2026-01-25 07:59:44.804544 | 
2026-01-25 07:59:44.804613 | TASK [upload-logs : Create log directories]
2026-01-25 07:59:46.517115 | logserver.rdoproject.org | changed
2026-01-25 07:59:46.520212 | 
2026-01-25 07:59:46.520321 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-25 07:59:46.767636 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006188
2026-01-25 07:59:46.772421 | 
2026-01-25 07:59:46.772495 | TASK [upload-logs : Upload logs to log server]
2026-01-25 07:59:48.288016 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-25 07:59:48.290830 | 
2026-01-25 07:59:48.290896 | LOOP [upload-logs : Compress console log and json output]
2026-01-25 07:59:48.330862 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:48.339405 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:48.348191 | 
2026-01-25 07:59:48.348323 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-25 07:59:48.383829 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:48.384026 | 
2026-01-25 07:59:48.387696 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-25 07:59:48.395552 | 
2026-01-25 07:59:48.395624 | LOOP [upload-logs : Upload console log and json output]