2025-12-05 10:49:15.473180 | Job console starting...
2025-12-05 10:49:15.483488 | Updating repositories
2025-12-05 10:49:15.528854 | Preparing job workspace
2025-12-05 10:49:22.965494 | Running Ansible setup...
2025-12-05 10:49:27.941791 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 10:49:28.523759 | 
2025-12-05 10:49:28.523879 | PLAY [localhost]
2025-12-05 10:49:28.533216 | 
2025-12-05 10:49:28.533332 | TASK [Gathering Facts]
2025-12-05 10:49:29.454332 | localhost | ok
2025-12-05 10:49:29.476452 | 
2025-12-05 10:49:29.476645 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 10:49:29.866531 | localhost -> localhost | changed
2025-12-05 10:49:29.878959 | 
2025-12-05 10:49:29.879114 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 10:49:30.740000 | localhost -> localhost | changed
2025-12-05 10:49:30.748295 | 
2025-12-05 10:49:30.748382 | TASK [Setup log path fact]
2025-12-05 10:49:30.766102 | localhost | ok
2025-12-05 10:49:30.777832 | 
2025-12-05 10:49:30.777912 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 10:49:30.805474 | localhost | ok
2025-12-05 10:49:30.813382 | 
2025-12-05 10:49:30.813450 | TASK [emit-job-header : Print job information]
2025-12-05 10:49:30.841282 | # Job Information
2025-12-05 10:49:30.841416 | Ansible Version: 2.15.12
2025-12-05 10:49:30.841441 | Job: cifmw-molecule-ci_multus
2025-12-05 10:49:30.841464 | Pipeline: github-check
2025-12-05 10:49:30.841491 | Executor: ze02.softwarefactory-project.io
2025-12-05 10:49:30.841512 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3533
2025-12-05 10:49:30.841531 | Log URL (when completed): https://logserver.rdoproject.org/7d2/rdoproject.org/7d20eef238424c798b8990421b79b82f/
2025-12-05 10:49:30.841549 | Event ID: eebfa700-d1c7-11f0-8a2e-4617705e1a2e
2025-12-05 10:49:30.845171 | 
2025-12-05 10:49:30.845233 | LOOP [emit-job-header : Print node information]
2025-12-05 10:49:30.938883 | localhost | ok:
2025-12-05 10:49:30.939252 | localhost | # Node Information
2025-12-05 10:49:30.939313 | localhost | Inventory Hostname: controller
2025-12-05 10:49:30.939372 | localhost | Hostname: np0005546792
2025-12-05 10:49:30.939415 | localhost | Username: zuul
2025-12-05 10:49:30.939458 | localhost | Distro: CentOS 9
2025-12-05 10:49:30.939497 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-05 10:49:30.939533 | localhost | Region: RegionOne
2025-12-05 10:49:30.939569 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-12-05 10:49:30.939604 | localhost | Product Name: OpenStack Nova
2025-12-05 10:49:30.939638 | localhost | Interface IP: 38.102.83.110
2025-12-05 10:49:30.979839 | 
2025-12-05 10:49:30.979940 | PLAY [all]
2025-12-05 10:49:30.988604 | 
2025-12-05 10:49:30.988714 | TASK [Gather network facts]
2025-12-05 10:49:31.549684 | controller | ok
2025-12-05 10:49:31.566530 | 
2025-12-05 10:49:31.566610 | TASK [include_role : start-zuul-console]
2025-12-05 10:49:31.627005 | controller | ok
2025-12-05 10:49:31.641881 | 
2025-12-05 10:49:31.641977 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 10:49:32.134519 | controller | ok
2025-12-05 10:49:32.161123 | 
2025-12-05 10:49:32.161297 | TASK [include_role : add-build-sshkey]
2025-12-05 10:49:32.204780 | controller | ok
2025-12-05 10:49:32.239322 | 
2025-12-05 10:49:32.239485 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 10:49:32.593451 | controller -> localhost | ok
2025-12-05 10:49:32.601759 | 
2025-12-05 10:49:32.601894 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 10:49:32.637374 | controller | ok
2025-12-05 10:49:32.658584 | controller | included: /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 10:49:32.666307 | 
2025-12-05 10:49:32.666388 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 10:49:33.272875 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 10:49:33.273265 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/7d20eef238424c798b8990421b79b82f_id_rsa.
2025-12-05 10:49:33.273356 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/7d20eef238424c798b8990421b79b82f_id_rsa.pub.
2025-12-05 10:49:33.273419 | controller -> localhost | The key fingerprint is:
2025-12-05 10:49:33.273477 | controller -> localhost | SHA256:ba80RHH3azRHnBc08NgCenPzCLXZTWZCxFne/bErku0 zuul-build-sshkey
2025-12-05 10:49:33.273532 | controller -> localhost | The key's randomart image is:
2025-12-05 10:49:33.273586 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 10:49:33.273642 | controller -> localhost | |          ...*BBO|
2025-12-05 10:49:33.273762 | controller -> localhost | |          .oo.XXB|
2025-12-05 10:49:33.273824 | controller -> localhost | |         ..+ B BO|
2025-12-05 10:49:33.273882 | controller -> localhost | |         o. + * B|
2025-12-05 10:49:33.273939 | controller -> localhost | |        S +  . =.|
2025-12-05 10:49:33.273994 | controller -> localhost | |         o .o . .|
2025-12-05 10:49:33.274043 | controller -> localhost | |          oo.o . |
2025-12-05 10:49:33.274089 | controller -> localhost | |         . oo .  |
2025-12-05 10:49:33.274129 | controller -> localhost | |          .  E   |
2025-12-05 10:49:33.274167 | controller -> localhost | +----[SHA256]-----+
2025-12-05 10:49:33.274254 | controller -> localhost | ok: Runtime: 0:00:00.094181
2025-12-05 10:49:33.288256 | 
2025-12-05 10:49:33.288397 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 10:49:33.326752 | controller | ok
2025-12-05 10:49:33.349503 | controller | included: /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 10:49:33.363973 | 
2025-12-05 10:49:33.364088 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 10:49:33.389686 | controller | skipping: Conditional result was False
2025-12-05 10:49:33.397722 | 
2025-12-05 10:49:33.397829 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 10:49:34.075114 | controller | changed
2025-12-05 10:49:34.086483 | 
2025-12-05 10:49:34.086611 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 10:49:34.511754 | controller | ok
2025-12-05 10:49:34.522836 | 
2025-12-05 10:49:34.522969 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 10:49:36.492777 | controller | changed
2025-12-05 10:49:36.497818 | 
2025-12-05 10:49:36.497883 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 10:49:38.055990 | controller | changed
2025-12-05 10:49:38.075033 | 
2025-12-05 10:49:38.075150 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 10:49:38.099835 | controller | skipping: Conditional result was False
2025-12-05 10:49:38.106402 | 
2025-12-05 10:49:38.106474 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 10:49:38.516717 | controller -> localhost | changed
2025-12-05 10:49:38.526285 | 
2025-12-05 10:49:38.526359 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 10:49:38.827539 | controller -> localhost | Identity added: /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/7d20eef238424c798b8990421b79b82f_id_rsa (zuul-build-sshkey)
2025-12-05 10:49:38.827759 | controller -> localhost | ok: Runtime: 0:00:00.010007
2025-12-05 10:49:38.833230 | 
2025-12-05 10:49:38.833298 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 10:49:39.297171 | controller | ok
2025-12-05 10:49:39.307731 | 
2025-12-05 10:49:39.307871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 10:49:39.345461 | controller | skipping: Conditional result was False
2025-12-05 10:49:39.367492 | 
2025-12-05 10:49:39.367648 | TASK [include_role : validate-host]
2025-12-05 10:49:39.408658 | controller | ok
2025-12-05 10:49:39.474546 | 
2025-12-05 10:49:39.474686 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 10:49:39.513966 | controller | ok
2025-12-05 10:49:39.518763 | 
2025-12-05 10:49:39.518825 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 10:49:39.840094 | controller -> localhost | ok
2025-12-05 10:49:39.855832 | 
2025-12-05 10:49:39.856039 | TASK [validate-host : Collect information about the host]
2025-12-05 10:49:40.675961 | controller | ok
2025-12-05 10:49:40.697503 | 
2025-12-05 10:49:40.697644 | TASK [validate-host : Sanitize hostname]
2025-12-05 10:49:40.777162 | controller | ok
2025-12-05 10:49:40.784505 | 
2025-12-05 10:49:40.784637 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 10:49:41.238285 | controller -> localhost | changed
2025-12-05 10:49:41.250657 | 
2025-12-05 10:49:41.250856 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 10:49:41.773995 | controller | ok
2025-12-05 10:49:41.780372 | 
2025-12-05 10:49:41.780466 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 10:49:42.242445 | controller -> localhost | changed
2025-12-05 10:49:42.252971 | 
2025-12-05 10:49:42.253038 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 10:49:42.277083 | controller | skipping: Conditional result was False
2025-12-05 10:49:42.282190 | 
2025-12-05 10:49:42.282250 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 10:49:42.306281 | controller | skipping: Conditional result was False
2025-12-05 10:49:42.318580 | 
2025-12-05 10:49:42.318767 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 10:49:42.657765 | controller | ok: "logs"
2025-12-05 10:49:42.658175 | controller | ok: All items complete
2025-12-05 10:49:42.658247 | 
2025-12-05 10:49:42.985712 | controller | ok: "artifacts"
2025-12-05 10:49:43.303318 | controller | ok: "docs"
2025-12-05 10:49:43.316375 | 
2025-12-05 10:49:43.316546 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 10:49:43.693335 | controller | changed: "logs"
2025-12-05 10:49:44.011239 | controller | changed: "artifacts"
2025-12-05 10:49:44.340156 | controller | changed: "docs"
2025-12-05 10:49:44.378136 | 
2025-12-05 10:49:44.378275 | PLAY RECAP
2025-12-05 10:49:44.378380 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 10:49:44.378427 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 10:49:44.378455 | 
2025-12-05 10:49:44.553169 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 10:49:44.556511 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 10:49:45.265407 | 
2025-12-05 10:49:45.265569 | PLAY [all]
2025-12-05 10:49:45.287375 | 
2025-12-05 10:49:45.287479 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 10:49:45.380200 | controller | ok
2025-12-05 10:49:45.386544 | 
2025-12-05 10:49:45.386633 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 10:49:45.990115 | controller | changed
2025-12-05 10:49:45.996045 | 
2025-12-05 10:49:45.996115 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 10:49:47.865301 | controller | changed
2025-12-05 10:49:47.878109 | 
2025-12-05 10:49:47.878200 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 10:49:48.470219 | controller | changed:
2025-12-05 10:49:48.470390 | controller | {
2025-12-05 10:49:48.470421 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 10:49:48.470448 | controller | }
2025-12-05 10:49:48.869215 | controller | changed:
2025-12-05 10:49:48.869564 | controller | {
2025-12-05 10:49:48.869613 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 10:49:48.869645 | controller | }
2025-12-05 10:49:49.257534 | controller | changed:
2025-12-05 10:49:49.257634 | controller | {
2025-12-05 10:49:49.257658 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-05 10:49:49.257697 | controller | }
2025-12-05 10:49:49.653993 | controller | changed:
2025-12-05 10:49:49.654095 | controller | {
2025-12-05 10:49:49.654119 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 10:49:49.654137 | controller | }
2025-12-05 10:49:50.014448 | controller | changed:
2025-12-05 10:49:50.014554 | controller | {
2025-12-05 10:49:50.014578 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 10:49:50.014598 | controller | }
2025-12-05 10:49:50.377027 | controller | changed:
2025-12-05 10:49:50.377144 | controller | {
2025-12-05 10:49:50.377169 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 10:49:50.377188 | controller | }
2025-12-05 10:49:50.748884 | controller | changed:
2025-12-05 10:49:50.748989 | controller | {
2025-12-05 10:49:50.749015 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-05 10:49:50.749036 | controller | }
2025-12-05 10:49:51.200613 | controller | changed:
2025-12-05 10:49:51.200744 | controller | {
2025-12-05 10:49:51.200772 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-05 10:49:51.200792 | controller | }
2025-12-05 10:49:51.539183 | controller | changed:
2025-12-05 10:49:51.539307 | controller | {
2025-12-05 10:49:51.539342 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 10:49:51.539370 | controller | }
2025-12-05 10:49:51.966116 | controller | changed:
2025-12-05 10:49:51.966221 | controller | {
2025-12-05 10:49:51.966245 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 10:49:51.966263 | controller | }
2025-12-05 10:49:52.346518 | controller | changed:
2025-12-05 10:49:52.346697 | controller | {
2025-12-05 10:49:52.346737 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 10:49:52.346758 | controller | }
2025-12-05 10:49:52.698768 | controller | changed:
2025-12-05 10:49:52.698891 | controller | {
2025-12-05 10:49:52.698923 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 10:49:52.698948 | controller | }
2025-12-05 10:49:53.063044 | controller | changed:
2025-12-05 10:49:53.063131 | controller | {
2025-12-05 10:49:53.063155 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 10:49:53.063181 | controller | }
2025-12-05 10:49:53.470247 | controller | changed:
2025-12-05 10:49:53.470353 | controller | {
2025-12-05 10:49:53.470384 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 10:49:53.470407 | controller | }
2025-12-05 10:49:53.867061 | controller | changed:
2025-12-05 10:49:53.867215 | controller | {
2025-12-05 10:49:53.867265 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 10:49:53.867308 | controller | }
2025-12-05 10:49:54.252323 | controller | changed:
2025-12-05 10:49:54.252434 | controller | {
2025-12-05 10:49:54.252466 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 10:49:54.252491 | controller | }
2025-12-05 10:49:54.646075 | controller | changed:
2025-12-05 10:49:54.646177 | controller | {
2025-12-05 10:49:54.646201 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-05 10:49:54.646222 | controller | }
2025-12-05 10:49:55.099829 | controller | changed:
2025-12-05 10:49:55.099914 | controller | {
2025-12-05 10:49:55.099937 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-05 10:49:55.099956 | controller | }
2025-12-05 10:49:55.559699 | controller | changed:
2025-12-05 10:49:55.559837 | controller | {
2025-12-05 10:49:55.559861 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 10:49:55.559879 | controller | }
2025-12-05 10:49:56.033847 | controller | changed:
2025-12-05 10:49:56.033943 | controller | {
2025-12-05 10:49:56.033967 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 10:49:56.033987 | controller | }
2025-12-05 10:49:56.514389 | controller | changed:
2025-12-05 10:49:56.514486 | controller | {
2025-12-05 10:49:56.514510 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 10:49:56.514528 | controller | }
2025-12-05 10:49:56.984563 | controller | changed:
2025-12-05 10:49:56.984656 | controller | {
2025-12-05 10:49:56.984699 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 10:49:56.984720 | controller | }
2025-12-05 10:49:57.452075 | controller | changed:
2025-12-05 10:49:57.452190 | controller | {
2025-12-05 10:49:57.452367 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-05 10:49:57.452415 | controller | }
2025-12-05 10:49:57.943954 | controller | changed:
2025-12-05 10:49:57.944054 | controller | {
2025-12-05 10:49:57.944077 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 10:49:57.944095 | controller | }
2025-12-05 10:49:58.403949 | controller | changed:
2025-12-05 10:49:58.404043 | controller | {
2025-12-05 10:49:58.404066 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 10:49:58.404087 | controller | }
2025-12-05 10:49:58.813454 | controller | changed:
2025-12-05 10:49:58.813566 | controller | {
2025-12-05 10:49:58.813600 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 10:49:58.813628 | controller | }
2025-12-05 10:49:58.831571 | 
2025-12-05 10:49:58.831708 | TASK [Set timezone to UTC]
2025-12-05 10:49:59.621560 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 10:49:59.627500 | 
2025-12-05 10:49:59.627574 | TASK [Create nodepool directory]
2025-12-05 10:50:00.053915 | controller | changed
2025-12-05 10:50:00.059454 | 
2025-12-05 10:50:00.059516 | TASK [Create nodepool sub_nodes file]
2025-12-05 10:50:01.523092 | controller | changed
2025-12-05 10:50:01.528337 | 
2025-12-05 10:50:01.528404 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 10:50:03.074928 | controller | changed
2025-12-05 10:50:03.083784 | 
2025-12-05 10:50:03.083919 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 10:50:03.117710 | 
2025-12-05 10:50:03.117902 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 10:50:03.152333 | 
2025-12-05 10:50:03.152530 | TASK [Create nodepool primary file]
2025-12-05 10:50:03.177245 | controller | skipping: Conditional result was False
2025-12-05 10:50:03.185143 | 
2025-12-05 10:50:03.185239 | TASK [Create nodepool node_private for this node]
2025-12-05 10:50:04.650986 | controller | changed
2025-12-05 10:50:04.656926 | 
2025-12-05 10:50:04.656983 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 10:50:05.182825 | controller | ok: Item: id_rsa Runtime: 0:00:00.008279
2025-12-05 10:50:05.183043 | 
2025-12-05 10:50:05.526409 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.018297
2025-12-05 10:50:05.536527 | 
2025-12-05 10:50:05.536639 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 10:50:07.147173 | controller | changed
2025-12-05 10:50:07.152352 | 
2025-12-05 10:50:07.152425 | TASK [Validate sudoers config after edits]
2025-12-05 10:50:07.563084 | controller | /etc/sudoers: parsed OK
2025-12-05 10:50:07.563219 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 10:50:07.563233 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 10:50:07.563242 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 10:50:07.682873 | controller | ok: Runtime: 0:00:00.005984
2025-12-05 10:50:07.697554 | 
2025-12-05 10:50:07.697789 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 10:50:08.151181 | controller | SHELL=/bin/bash
2025-12-05 10:50:08.151262 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 10:50:08.151275 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 10:50:08.151283 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/34/3534/1088d6d967ec40daf4d458b96d952747a4005ed9^openstack-k8s-operators/ci-framework:main:refs/changes/33/3533/04826bcadcfb4679a4764897bf49af7ab47a0408
2025-12-05 10:50:08.151377 | controller | PWD=/home/zuul
2025-12-05 10:50:08.151389 | controller | ZUUL_PIPELINE=github-check
2025-12-05 10:50:08.151397 | controller | LOGNAME=zuul
2025-12-05 10:50:08.151405 | controller | XDG_SESSION_TYPE=tty
2025-12-05 10:50:08.151413 | controller | _=/usr/bin/env
2025-12-05 10:50:08.151421 | controller | MOTD_SHOWN=pam
2025-12-05 10:50:08.151429 | controller | HOME=/home/zuul
2025-12-05 10:50:08.151436 | controller | LANG=en_US.UTF-8
2025-12-05 10:50:08.151444 | controller | SSH_CONNECTION=38.102.83.114 46050 38.102.83.110 22
2025-12-05 10:50:08.151452 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-05 10:50:08.151460 | controller | ZUUL_CHANGE_IDS=3534,1088d6d967ec40daf4d458b96d952747a4005ed9 3533,04826bcadcfb4679a4764897bf49af7ab47a0408
2025-12-05 10:50:08.151468 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 10:50:08.151476 | controller | XDG_SESSION_CLASS=user
2025-12-05 10:50:08.151485 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 10:50:08.151492 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 10:50:08.151500 | controller | USER=zuul
2025-12-05 10:50:08.151508 | controller | ZUUL_VOTING=True
2025-12-05 10:50:08.151516 | controller | BUILD_TIMEOUT=1800000
2025-12-05 10:50:08.151524 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 10:50:08.151533 | controller | SHLVL=1
2025-12-05 10:50:08.151541 | controller | ZUUL_PATCHSET=04826bcadcfb4679a4764897bf49af7ab47a0408
2025-12-05 10:50:08.151549 | controller | XDG_SESSION_ID=1
2025-12-05 10:50:08.151557 | controller | ZUUL_BRANCH=main
2025-12-05 10:50:08.151565 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 10:50:08.151573 | controller | SSH_CLIENT=38.102.83.114 46050 22
2025-12-05 10:50:08.151610 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 10:50:08.151619 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 10:50:08.151627 | controller | which_declare=declare -f
2025-12-05 10:50:08.151637 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-05 10:50:08.151644 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 10:50:08.151652 | controller | ZUUL_CHANGE=3533
2025-12-05 10:50:08.151660 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 10:50:08.151668 | controller | ZUUL_UUID=7d20eef238424c798b8990421b79b82f
2025-12-05 10:50:08.151679 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 10:50:08.151688 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 10:50:08.151696 | controller | }
2025-12-05 10:50:08.293751 | controller | ok: Runtime: 0:00:00.007749
2025-12-05 10:50:08.302504 | 
2025-12-05 10:50:08.302638 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 10:50:08.328286 | controller | skipping: Conditional result was False
2025-12-05 10:50:08.337050 | 
2025-12-05 10:50:08.337133 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 10:50:08.877364 | controller | skipping: Conditional result was False
2025-12-05 10:50:08.883343 | 
2025-12-05 10:50:08.883430 | TASK [Ensure legacy workspace directory]
2025-12-05 10:50:09.227728 | controller | changed
2025-12-05 10:50:09.246828 | 
2025-12-05 10:50:09.246882 | PLAY RECAP
2025-12-05 10:50:09.246924 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 10:50:09.246943 | 
2025-12-05 10:50:09.344742 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 10:50:09.346264 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-05 10:50:09.927368 | 
2025-12-05 10:50:09.927566 | PLAY [all,!compute]
2025-12-05 10:50:09.957194 | 
2025-12-05 10:50:09.957308 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-12-05 10:50:10.493575 | controller | skipping: Conditional result was False
2025-12-05 10:50:10.505799 | 
2025-12-05 10:50:10.505961 | TASK [Install podman]
2025-12-05 10:50:10.584553 | controller | skipping: Conditional result was False
2025-12-05 10:50:10.597765 | 
2025-12-05 10:50:10.597905 | TASK [Set var name for quay login zuul secret]
2025-12-05 10:50:10.655781 | controller | skipping: Conditional result was False
2025-12-05 10:50:10.663835 | 
2025-12-05 10:50:10.663927 | TASK [Print the username]
2025-12-05 10:50:11.203342 | controller | skipping: Conditional result was False
2025-12-05 10:50:11.209243 | 
2025-12-05 10:50:11.209313 | TASK [Fail when user or password is not set]
2025-12-05 10:50:11.273795 | controller | skipping: Conditional result was False
2025-12-05 10:50:11.279509 | 
2025-12-05 10:50:11.279589 | TASK [Set vars for quay login]
2025-12-05 10:50:11.362040 | 
2025-12-05 10:50:11.362246 | LOOP [Perform container registry login(s) with podman]
2025-12-05 10:50:11.425337 | 
2025-12-05 10:50:11.425528 | LOOP [Perform container registry login(s) with buildah]
2025-12-05 10:50:11.477338 | 
2025-12-05 10:50:11.477492 | TASK [Set Insecure registry for content provider]
2025-12-05 10:50:11.512880 | controller | skipping: Conditional result was False
2025-12-05 10:50:11.531978 | 
2025-12-05 10:50:11.532045 | PLAY RECAP
2025-12-05 10:50:11.532092 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-12-05 10:50:11.532117 | 
2025-12-05 10:50:11.634599 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-05 10:50:11.635441 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-05 10:50:12.238517 | 
2025-12-05 10:50:12.238635 | PLAY [Prefer ipv4 over ipv6]
2025-12-05 10:50:12.258641 | 
2025-12-05 10:50:12.258775 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-12-05 10:50:12.313522 | controller | skipping: Conditional result was False
2025-12-05 10:50:12.335140 | 
2025-12-05 10:50:12.335265 | PLAY [controller]
2025-12-05 10:50:12.350620 | 
2025-12-05 10:50:12.350815 | TASK [Fail when openshift_pull_sec not provided]
2025-12-05 10:50:12.375634 | controller | skipping: Conditional result was False
2025-12-05 10:50:12.382504 | 
2025-12-05 10:50:12.382633 | TASK [Clone repos in the job workspace]
2025-12-05 10:50:12.402293 | controller | ok
2025-12-05 10:50:12.421351 | 
2025-12-05 10:50:12.421463 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 10:50:12.892652 | controller | ok
2025-12-05 10:50:12.903988 | 
2025-12-05 10:50:12.904160 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 10:50:18.743514 | controller | Output suppressed because no_log was given
2025-12-05 10:50:18.757131 | 
2025-12-05 10:50:18.757217 | TASK [Create pull-secret.txt file]
2025-12-05 10:50:20.736720 | controller | Output suppressed because no_log was given
2025-12-05 10:50:20.783053 | 
2025-12-05 10:50:20.783315 | TASK [Apply CRC features and print image build date]
2025-12-05 10:50:20.813300 | controller | ok
2025-12-05 10:50:20.833086 | controller | included: /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-12-05 10:50:20.848379 | 
2025-12-05 10:50:20.848467 | TASK [Set additional parameters for crc - telemetry]
2025-12-05 10:50:21.387113 | controller | skipping: Conditional result was False
2025-12-05 10:50:21.400218 | 
2025-12-05 10:50:21.400370 | TASK [Set additional parameters for crc - monitoring]
2025-12-05 10:50:21.947495 | controller | skipping: Conditional result was False
2025-12-05 10:50:21.961130 | 
2025-12-05 10:50:21.961287 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-12-05 10:50:22.518217 | controller | skipping: Conditional result was False
2025-12-05 10:50:22.531084 | 
2025-12-05 10:50:22.531241 | TASK [Set additional parameters for crc - enable microshift preset]
2025-12-05 10:50:23.079440 | controller | skipping: Conditional result was False
2025-12-05 10:50:23.085012 | 
2025-12-05 10:50:23.085078 | TASK [Set additional parameters for crc - enable okd preset]
2025-12-05 10:50:23.620467 | controller | skipping: Conditional result was False
2025-12-05 10:50:23.625957 | 
2025-12-05 10:50:23.626021 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-12-05 10:50:24.154518 | controller | skipping: Conditional result was False
2025-12-05 10:50:24.160173 | 
2025-12-05 10:50:24.160239 | TASK [Execute crc start command]
2025-12-05 11:03:26.203438 | controller | ok: Runtime: 0:13:01.029551
2025-12-05 11:03:26.214602 | 
2025-12-05 11:03:26.214773 | TASK [Verify that OpenShift is up and running]
2025-12-05 11:03:26.242922 | controller | ok
2025-12-05 11:03:26.261537 | controller | included: /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-12-05 11:03:26.275528 | 
2025-12-05 11:03:26.275654 | TASK [Create script to login and verify services]
2025-12-05 11:03:27.750134 | controller | changed
2025-12-05 11:03:27.762147 | 
2025-12-05 11:03:27.762273 | TASK [Ensure that user is logged]
2025-12-05 11:03:28.156680 | controller | Checking login to the cluster 1...
2025-12-05 11:03:28.481611 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-05 11:03:28.843490 | controller |
2025-12-05 11:03:28.843582 | controller | Login successful.
2025-12-05 11:03:28.886072 | controller |
2025-12-05 11:03:28.886168 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-12-05 11:03:28.907685 | controller |
2025-12-05 11:03:28.907855 | controller | Using project "default".
2025-12-05 11:03:29.319736 | controller | changed
2025-12-05 11:03:29.326943 | 
2025-12-05 11:03:29.327025 | TASK [Delete all openshift-marketplace pods]
2025-12-05 11:03:30.013884 | controller | pod "certified-operators-mz9qw" deleted
2025-12-05 11:03:30.053805 | controller | pod "community-operators-rl9mj" deleted
2025-12-05 11:03:30.094816 | controller | pod "marketplace-operator-79b997595-bv56r" deleted
2025-12-05 11:03:30.113466 | controller | pod "redhat-marketplace-ww44x" deleted
2025-12-05 11:03:30.149328 | controller | pod "redhat-operators-x9gsj" deleted
2025-12-05 11:03:32.367349 | controller | ok
2025-12-05 11:03:32.378600 | 
2025-12-05 11:03:32.378761 | TASK [Copy script ensure_services_up.sh]
2025-12-05 11:03:32.446554 | controller | skipping: Conditional result was False
2025-12-05 11:03:32.458060 | 
2025-12-05 11:03:32.458186 | TASK [Ensure all pods are up and running]
2025-12-05 11:03:33.008583 | controller | skipping: Conditional result was False
2025-12-05 11:03:33.020772 | 
2025-12-05 11:03:33.020909 | TASK [Check if there is a image build date file]
2025-12-05 11:03:33.396407 | controller | ok
2025-12-05 11:03:33.408105 | 
2025-12-05 11:03:33.408233 | TASK [Print image date if available]
2025-12-05 11:03:33.824163 | controller | Mon Nov 24 08:30:59 AM EST 2025
2025-12-05 11:03:33.958812 | controller | changed
2025-12-05 11:03:33.965499 | 
2025-12-05 11:03:33.965580 | TASK [Fix machineconfigpool]
2025-12-05 11:03:34.031108 | controller | skipping: Conditional result was False
2025-12-05 11:03:34.038329 | 
2025-12-05 11:03:34.038413 | TASK [Show available nodes]
2025-12-05 11:03:34.766054 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-12-05 11:03:34.768547 | controller | crc    Ready    control-plane,master,worker   285d   v1.31.5
2025-12-05 11:03:35.105603 | controller | ok: Runtime: 0:00:00.276249
2025-12-05 11:03:35.110957 | 
2025-12-05 11:03:35.111018 | TASK [Configure insecure registry inside crc vm]
2025-12-05 11:03:35.145395 | controller | skipping: Conditional result was False
2025-12-05 11:03:35.151078 | 
2025-12-05 11:03:35.151140 | TASK [Catch CRC IP]
2025-12-05 11:03:35.609900 | controller | 192.168.130.11
2025-12-05 11:03:35.718561 | controller | changed
2025-12-05 11:03:35.730145 | 
2025-12-05 11:03:35.730276 | TASK [Check if id_ed25519 key exists]
2025-12-05 11:03:36.194499 | controller | ok
2025-12-05 11:03:36.206437 | 
2025-12-05 11:03:36.206620 | TASK [Set fact if new keypair exists]
2025-12-05 11:03:36.254263 | controller | ok
2025-12-05 11:03:36.264361 | 
2025-12-05 11:03:36.264487 | TASK [Run crc]
2025-12-05 11:03:37.954782 | controller |
2025-12-05 11:03:49.284336 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-12-05 11:03:49.284394 | controller |
2025-12-05 11:03:49.284405 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:03:49.284425 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-12-05 11:03:49.337757 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-12-05 11:03:49.337827 | controller | interpreter could change the meaning of that path. See
2025-12-05 11:03:49.337837 | controller | https://docs.ansible.com/ansible-
2025-12-05 11:03:49.337846 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-12-05 11:03:49.337863 | controller | ok: [192.168.130.11]
2025-12-05 11:03:51.233485 | controller |
2025-12-05 11:03:51.233562 | controller | TASK [Copy pull-secret] ********************************************************
2025-12-05 11:03:51.233578 | controller | changed: [192.168.130.11]
2025-12-05 11:03:52.766977 | controller |
2025-12-05 11:03:52.767028 | controller | TASK [Add crio config] *********************************************************
2025-12-05 11:03:52.767041 | controller | changed: [192.168.130.11]
2025-12-05 11:03:56.993512 | controller |
2025-12-05 11:03:56.993573 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-12-05 11:03:56.993605 | controller | changed: [192.168.130.11]
2025-12-05 11:03:57.059853 | controller |
2025-12-05 11:03:57.059926 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:03:57.059937 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:03:57.059965 | controller |
2025-12-05 11:03:57.345807 | controller | changed
2025-12-05 11:03:57.357055 | 
2025-12-05 11:03:57.357178 | TASK [Set slower etcd profile]
2025-12-05 11:03:57.434878 | controller | ok
2025-12-05 11:03:57.485575 | 
2025-12-05 11:03:57.485804 | TASK [prepare-crc-cloud : Set slower profile]
2025-12-05 11:03:58.098431 | controller | etcd.operator.openshift.io/cluster patched
2025-12-05 11:03:58.529243 | controller | ok
2025-12-05 11:03:58.552043 | 
2025-12-05 11:03:58.552195 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-12-05 11:04:28.601606 | controller | ok
2025-12-05 11:04:28.606748 | 
2025-12-05 11:04:28.606808 | TASK [prepare-crc-cloud : Get pod phase status]
2025-12-05 11:10:13.788561 | controller | ok
2025-12-05 11:10:13.811806 | 
2025-12-05 11:10:13.811931 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-12-05 11:10:13.882129 | controller | ok
2025-12-05 11:10:13.897556 | controller | included: /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-12-05 11:10:13.917579 | 
2025-12-05 11:10:13.917683 | TASK [Set facts for disk limitation]
2025-12-05 11:10:13.949178 | controller | ok
2025-12-05 11:10:13.956417 | 
2025-12-05 11:10:13.956498 | TASK [Print limits for the disk]
2025-12-05 11:10:14.000377 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-12-05 11:10:14.006868 | 
2025-12-05 11:10:14.006948 | TASK [Set limit to crc VM to the vda disk]
2025-12-05 11:10:14.410359 | controller |
2025-12-05 11:10:14.547266 | controller | changed
2025-12-05 11:10:14.554114 | 
2025-12-05 11:10:14.554277 | TASK [Pause machineconfigpool]
2025-12-05 11:10:14.627923 | controller | ok
2025-12-05 11:10:14.660646 | 
2025-12-05 11:10:14.660841 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-12-05 11:10:15.302464 | controller | ok
2025-12-05 11:10:15.875804 | controller | ok
2025-12-05 11:10:15.890699 | 
2025-12-05 11:10:15.890905 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-12-05 11:10:16.492204 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-12-05 11:10:16.492305 | controller | master   rendered-master-63af5c6b4fa17c607a1f2008d8a3ac33   True      False      False      1              1                   1                     0                      285d
2025-12-05 11:10:16.492316 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      285d
2025-12-05 11:10:16.942804 | controller | ok
2025-12-05 11:10:16.995865 | 
2025-12-05 11:10:16.995955 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-12-05 11:10:16.996072 | 
2025-12-05 11:10:16.996107 | PLAY RECAP
2025-12-05 11:10:16.996158 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-12-05 11:10:16.996184 | 
2025-12-05 11:10:17.103490 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-05 11:10:17.104320 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 11:10:17.667186 | 
2025-12-05 11:10:17.667293 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 11:10:17.689583 | 
2025-12-05 11:10:17.689684 | TASK [Create zuul-output directory]
2025-12-05 11:10:18.175926 | controller | changed
2025-12-05 11:10:18.181302 | 
2025-12-05 11:10:18.181377 | TASK [Slurp Zuul inventory test]
2025-12-05 11:10:18.560789 | controller -> localhost | ok
2025-12-05 11:10:18.575103 | 
2025-12-05 11:10:18.575238 | TASK [Save zuul inventory]
2025-12-05 11:10:20.321928 | controller | changed
2025-12-05 11:10:20.334389 | 
2025-12-05 11:10:20.334533 | TASK [Save zuul vars without the change_message]
2025-12-05 11:10:21.974084 | controller | changed
2025-12-05 11:10:22.016995 | 
2025-12-05 11:10:22.017129 | PLAY RECAP
2025-12-05 11:10:22.017210 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 11:10:22.017253 | 
2025-12-05 11:10:22.116156 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 11:10:22.116988 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 11:10:22.706065 | 
2025-12-05 11:10:22.706174 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 11:10:22.728337 | 
2025-12-05 11:10:22.728445 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 11:10:22.749072 | controller | ok
2025-12-05 11:10:22.766708 | 
2025-12-05 11:10:22.766803 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 11:10:22.810789 | controller | skipping: Conditional result was False
2025-12-05 11:10:22.816549 | 
2025-12-05 11:10:22.816615 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 11:10:23.328167 | controller | ok
2025-12-05 11:10:23.341640 | 
2025-12-05 11:10:23.341824 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 11:10:24.738586 | controller | ok
2025-12-05 11:10:24.760904 | 
2025-12-05 11:10:24.761038 | TASK [Prepare workspace]
2025-12-05 11:10:24.789867 | controller | ok
2025-12-05 11:10:24.822167 | 
2025-12-05 11:10:24.822263 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 11:10:25.291523 | controller | ok
2025-12-05 11:10:25.299949 | 
2025-12-05 11:10:25.300034 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 11:10:26.555653 | controller | Output suppressed because no_log was given
2025-12-05 11:10:26.578806 | 
2025-12-05 11:10:26.579177 | LOOP [Create zuul-output directory]
2025-12-05 11:10:26.931542 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 11:10:27.244599 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 11:10:27.255283 | 
2025-12-05 11:10:27.255421 | TASK [Install required packages]
2025-12-05 11:10:30.063097 | controller | ok: Nothing to do
2025-12-05 11:10:30.074647 | 
2025-12-05 11:10:30.074802 | TASK [Install venv]
2025-12-05 11:13:06.167307 | controller | changed
2025-12-05 11:13:06.218174 | 
2025-12-05 11:13:06.218289 | PLAY RECAP
2025-12-05 11:13:06.218347 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 11:13:06.218377 | 
2025-12-05 11:13:06.322802 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 11:13:06.324484 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-05 11:13:06.890565 | 
2025-12-05 11:13:06.890685 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-05 11:13:06.911345 | 
2025-12-05 11:13:06.911421 | TASK [Gather required facts]
2025-12-05 11:13:07.632836 | controller | ok
2025-12-05 11:13:07.645446 | 
2025-12-05 11:13:07.645597 | TASK [Load environment var if instructed to]
2025-12-05 11:13:07.683498 | controller | skipping: Conditional result was False
2025-12-05 11:13:07.695978 | 
2025-12-05 11:13:07.696140 | TASK [Ensure group_vars dir exists]
2025-12-05 11:13:08.184794 | controller | ok
2025-12-05 11:13:08.196657 | 
2025-12-05 11:13:08.196830 | TASK [Print related variables]
2025-12-05 11:13:08.245689 | controller | ok:
2025-12-05 11:13:08.245904 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-05 11:13:08.245962 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_multus
2025-12-05 11:13:08.256901 | 
2025-12-05 11:13:08.257044 | TASK [Run molecule]
2025-12-05 11:13:09.670067 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-05 11:13:09.949122 | controller | INFO     Running default > destroy
2025-12-05 11:13:09.949407 | controller | WARNING  Skipping, instances are delegated.
2025-12-05 11:13:09.959675 | controller | INFO     Running default > create
2025-12-05 11:13:09.960670 | controller | WARNING  Skipping, instances are delegated.
2025-12-05 11:13:09.973545 | controller | INFO     Running default > converge
2025-12-05 11:13:10.918655 | controller |
2025-12-05 11:13:10.918732 | controller | PLAY [Converge] ****************************************************************
2025-12-05 11:13:10.918790 | controller |
2025-12-05 11:13:10.918953 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:10.919104 | controller | Friday 05 December 2025  11:13:10 +0000 (0:00:00.016)       0:00:00.016 *******
2025-12-05 11:13:12.027565 | controller | ok: [instance]
2025-12-05 11:13:12.027640 | controller |
2025-12-05 11:13:12.027814 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-12-05 11:13:12.027993 | controller | Friday 05 December 2025  11:13:12 +0000 (0:00:01.110)       0:00:01.126 *******
2025-12-05 11:13:12.424707 | controller | changed: [instance]
2025-12-05 11:13:12.424765 | controller |
2025-12-05 11:13:12.424853 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-12-05 11:13:12.424975 | controller | Friday 05 December 2025  11:13:12 +0000 (0:00:00.397)       0:00:01.524 *******
2025-12-05 11:13:12.456097 | controller |
2025-12-05 11:13:12.456215 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-12-05 11:13:12.456328 | controller | Friday 05 December 2025  11:13:12 +0000 (0:00:00.030)       0:00:01.554 *******
2025-12-05 11:13:12.847508 | controller | ok: [instance]
2025-12-05 11:13:12.847573 | controller |
2025-12-05 11:13:12.847607 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-12-05 11:13:12.847740 | controller | Friday 05 December 2025  11:13:12 +0000 (0:00:00.387)       0:00:01.942 *******
2025-12-05 11:13:12.875011 | controller | ok: [instance]
2025-12-05 11:13:12.875087 | controller |
2025-12-05 11:13:12.875114 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-12-05 11:13:12.875240 | controller | Friday 05 December 2025  11:13:12 +0000 (0:00:00.028)       0:00:01.971 *******
2025-12-05 11:13:12.905862 | controller | skipping: [instance]
2025-12-05 11:13:12.905933 | controller |
2025-12-05 11:13:12.906092 | controller | TASK [Add the crc host dynamically] ********************************************
2025-12-05 11:13:12.906109 | controller | Friday 05 December 2025  11:13:12 +0000 (0:00:00.033)       0:00:02.004 *******
2025-12-05 11:13:12.931149 | controller | changed: [instance]
2025-12-05 11:13:12.931216 | controller |
2025-12-05 11:13:12.931326 | controller | TASK [Fetch crc network facts] *************************************************
2025-12-05 11:13:12.931472 | controller | Friday 05 December 2025  11:13:12 +0000 (0:00:00.025)       0:00:02.030 *******
2025-12-05 11:13:18.957351 | controller | ok: [instance -> crc]
2025-12-05 11:13:18.957449 | controller |
2025-12-05 11:13:18.957570 | controller | TASK [Load shared variables] ***************************************************
2025-12-05 11:13:18.957587 | controller | Friday 05 December 2025  11:13:18 +0000 (0:00:06.026)       0:00:08.056 *******
2025-12-05 11:13:19.025225 | controller | ok: [instance]
2025-12-05 11:13:19.025295 | controller |
2025-12-05 11:13:19.025331 | controller | TASK [Call ci_multus role] *****************************************************
2025-12-05 11:13:19.025448 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.068)       0:00:08.124 *******
2025-12-05 11:13:19.072710 | controller |
2025-12-05 11:13:19.072768 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-12-05 11:13:19.072830 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.047)       0:00:08.172 *******
2025-12-05 11:13:19.415822 | controller | changed: [instance]
2025-12-05 11:13:19.415992 | controller |
2025-12-05 11:13:19.416272 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-12-05 11:13:19.416486 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.342)       0:00:08.514 *******
2025-12-05 11:13:19.443788 | controller | skipping: [instance]
2025-12-05 11:13:19.443898 | controller |
2025-12-05 11:13:19.444111 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-12-05 11:13:19.444288 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.028)       0:00:08.543 *******
2025-12-05 11:13:19.471547 | controller | skipping: [instance]
2025-12-05 11:13:19.471720 | controller |
2025-12-05 11:13:19.471973 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-12-05 11:13:19.472208 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.027)       0:00:08.570 *******
2025-12-05 11:13:19.506619 | controller | skipping: [instance]
2025-12-05 11:13:19.506681 | controller |
2025-12-05 11:13:19.506795 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-12-05 11:13:19.506901 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.034)       0:00:08.605 *******
2025-12-05 11:13:19.541760 | controller | skipping: [instance]
2025-12-05 11:13:19.541816 | controller |
2025-12-05 11:13:19.542956 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-12-05 11:13:19.581131 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.036)       0:00:08.641 *******
2025-12-05 11:13:19.581213 | controller | ok: [instance]
2025-12-05 11:13:19.581462 | controller |
2025-12-05 11:13:19.581484 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-12-05 11:13:19.808466 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.039)       0:00:08.680 *******
2025-12-05 11:13:19.808566 | controller | ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
2025-12-05 11:13:19.808626 | controller | ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'macvlan'}}}})
2025-12-05 11:13:19.808655 | controller | ok: [instance] => (item={'bridge-to-linux-bridge': {'gw_v4': '192.168.122.1', 'network_name': 'bridge-to-linux-bridge', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth1', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge', 'multus_attach': 'linux-bridge'}}}})
2025-12-05 11:13:19.808731 | controller |
2025-12-05 11:13:19.808825 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-12-05 11:13:19.808923 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.226)       0:00:08.907 *******
2025-12-05 11:13:19.912929 | controller | ok: [instance]
2025-12-05 11:13:19.913028 | controller |
2025-12-05 11:13:19.913254 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-12-05 11:13:19.913491 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.104)       0:00:09.012 *******
2025-12-05 11:13:19.986165 | controller | ok: [instance]
2025-12-05 11:13:19.986210 | controller |
2025-12-05 11:13:19.986439 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-12-05 11:13:20.059117 | controller | Friday 05 December 2025  11:13:19 +0000 (0:00:00.073)       0:00:09.085 *******
2025-12-05 11:13:20.059170 | controller | ok: [instance]
2025-12-05 11:13:20.059208 | controller |
2025-12-05 11:13:20.059476 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-12-05 11:13:20.669697 | controller | Friday 05 December 2025  11:13:20 +0000 (0:00:00.072)       0:00:09.158 *******
2025-12-05 11:13:20.669791 | controller | changed: [instance]
2025-12-05 11:13:21.894222 | controller |
2025-12-05 11:13:21.894267 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-12-05 11:13:21.894277 | controller | Friday 05 December 2025  11:13:20 +0000 (0:00:00.609)       0:00:09.768 *******
2025-12-05 11:13:21.894298 | controller | changed: [instance]
2025-12-05 11:13:22.802989 | controller |
2025-12-05 11:13:22.803087 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-12-05 11:13:22.803100 | controller | Friday 05 December 2025  11:13:21 +0000 (0:00:01.224)       0:00:10.992 *******
2025-12-05 11:13:22.803124 | controller | changed: [instance]
2025-12-05 11:13:22.803134 | controller |
2025-12-05 11:13:22.803144 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:22.803153 | controller | instance                   : ok=16   changed=6    unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-12-05 11:13:22.803162 | controller |
2025-12-05 11:13:22.803174 | controller | Friday 05 December 2025  11:13:22 +0000 (0:00:00.908)       0:00:11.900 *******
2025-12-05 11:13:22.803186 | controller | ===============================================================================
2025-12-05 11:13:22.803202 | controller | Fetch crc network facts ------------------------------------------------- 6.03s
2025-12-05 11:13:22.803318 | controller | ci_multus : Create the multus namespace --------------------------------- 1.22s
2025-12-05 11:13:22.803462 | controller | Gathering Facts --------------------------------------------------------- 1.11s
2025-12-05 11:13:22.803597 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.91s
2025-12-05 11:13:22.803742 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.61s
2025-12-05 11:13:22.803894 | controller | Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.40s
2025-12-05 11:13:22.803999 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.39s
2025-12-05 11:13:22.804197 | controller | ci_multus : Create manifests directory ---------------------------------- 0.34s
2025-12-05 11:13:22.804273 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.23s
2025-12-05 11:13:22.804379 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.10s
2025-12-05 11:13:22.804504 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.07s
2025-12-05 11:13:22.804592 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.07s
2025-12-05 11:13:22.804697 | controller | Load shared variables --------------------------------------------------- 0.07s
2025-12-05 11:13:22.804797 | controller | Call ci_multus role ----------------------------------------------------- 0.05s
2025-12-05 11:13:22.804909 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.04s
2025-12-05 11:13:22.805017 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-12-05 11:13:22.805112 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2025-12-05 11:13:22.805221 | controller | recognize_ssh_keypair : Set fact if new keypair does not exists --------- 0.03s
2025-12-05 11:13:22.805320 | controller | Check if new ssh keypair exists ----------------------------------------- 0.03s
2025-12-05 11:13:22.805390 | controller | recognize_ssh_keypair : Set fact if new keypair exists ------------------ 0.03s
2025-12-05 11:13:22.893242 | controller | INFO     Running default > verify
2025-12-05 11:13:22.893533 | controller | INFO     Running Ansible Verifier
2025-12-05 11:13:23.431092 | controller |
2025-12-05 11:13:23.431339 | controller | PLAY [Verify] ******************************************************************
2025-12-05 11:13:23.431491 | controller |
2025-12-05 11:13:23.431656 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:23.432153 | controller | Friday 05 December 2025  11:13:23 +0000 (0:00:00.017)       0:00:00.017 *******
2025-12-05 11:13:24.525688 | controller | ok: [instance]
2025-12-05 11:13:24.525759 | controller |
2025-12-05 11:13:24.525901 | controller | TASK [Load shared variables] ***************************************************
2025-12-05 11:13:24.526065 | controller | Friday 05 December 2025  11:13:24 +0000 (0:00:01.095)       0:00:01.112 *******
2025-12-05 11:13:24.548475 | controller | ok: [instance]
2025-12-05 11:13:24.548590 | controller |
2025-12-05 11:13:24.548750 | controller | TASK [Fetch files stat results] ************************************************
2025-12-05 11:13:24.548901 | controller | Friday 05 December 2025  11:13:24 +0000 (0:00:00.022)       0:00:01.134 *******
2025-12-05 11:13:24.909139 | controller | ok: [instance]
2025-12-05 11:13:24.909193 | controller |
2025-12-05 11:13:24.909307 | controller | TASK [Assert that expected file exist] *****************************************
2025-12-05 11:13:24.909413 | controller | Friday 05 December 2025  11:13:24 +0000 (0:00:00.360)       0:00:01.495 *******
2025-12-05 11:13:24.932549 | controller | ok: [instance] => changed=false
2025-12-05 11:13:24.932612 | controller |   msg: All assertions passed
2025-12-05 11:13:24.932744 | controller |
2025-12-05 11:13:24.932891 | controller | TASK [Fetch file content] ******************************************************
2025-12-05 11:13:24.933028 | controller | Friday 05 December 2025  11:13:24 +0000 (0:00:00.023)       0:00:01.518 *******
2025-12-05 11:13:25.266572 | controller | ok: [instance]
2025-12-05 11:13:25.266645 | controller |
2025-12-05 11:13:25.266819 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-12-05 11:13:25.266988 | controller | Friday 05 December 2025  11:13:25 +0000 (0:00:00.333)       0:00:01.852 *******
2025-12-05 11:13:25.305280 | controller | ok: [instance]
2025-12-05 11:13:25.305352 | controller |
2025-12-05 11:13:25.305527 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-12-05 11:13:25.305691 | controller | Friday 05 December 2025  11:13:25 +0000 (0:00:00.038)       0:00:01.891 *******
2025-12-05 11:13:25.355260 | controller | ok: [instance]
2025-12-05 11:13:25.355352 | controller |
2025-12-05 11:13:25.355507 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:25.355691 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:13:25.355823 | controller |
2025-12-05 11:13:25.355984 | controller | Friday 05 December 2025  11:13:25 +0000 (0:00:00.050)       0:00:01.942 *******
2025-12-05 11:13:25.356117 | controller | ===============================================================================
2025-12-05 11:13:25.356271 | controller | Gathering Facts --------------------------------------------------------- 1.10s
2025-12-05 11:13:25.356419 | controller | Fetch files stat results ------------------------------------------------ 0.36s
2025-12-05 11:13:25.356585 | controller | Fetch file content ------------------------------------------------------ 0.33s
2025-12-05 11:13:25.356739 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.05s
2025-12-05 11:13:25.356886 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-12-05 11:13:25.357082 | controller | Assert that expected file exist ----------------------------------------- 0.02s
2025-12-05 11:13:25.357222 | controller | Load shared variables --------------------------------------------------- 0.02s
2025-12-05 11:13:25.439477 | controller | INFO     Verifier completed successfully.
2025-12-05 11:13:25.452408 | controller | INFO     Running default > verify
2025-12-05 11:13:25.452723 | controller | INFO     Running Ansible Verifier
2025-12-05 11:13:26.053664 | controller |
2025-12-05 11:13:26.054031 | controller | PLAY [Verify CRC] **************************************************************
2025-12-05 11:13:27.109879 | controller |
2025-12-05 11:13:27.109911 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:27.109920 | controller | Friday 05 December 2025  11:13:26 +0000 (0:00:00.018)       0:00:00.018 *******
2025-12-05 11:13:27.109930 | controller | ok: [instance]
2025-12-05 11:13:27.141599 | controller |
2025-12-05 11:13:27.141631 | controller | TASK [Include default vars] ****************************************************
2025-12-05 11:13:27.141641 | controller | Friday 05 December 2025  11:13:27 +0000 (0:00:01.056)       0:00:01.074 *******
2025-12-05 11:13:27.141652 | controller | ok: [instance]
2025-12-05 11:13:28.140081 | controller |
2025-12-05 11:13:28.140123 | controller | TASK [Check if NADs were created] **********************************************
2025-12-05 11:13:28.140134 | controller | Friday 05 December 2025  11:13:27 +0000 (0:00:00.031)       0:00:01.106 *******
2025-12-05 11:13:28.140159 | controller | ok: [instance]
2025-12-05 11:13:28.140597 | controller |
2025-12-05 11:13:28.187143 | controller | TASK [Store output spec] *******************************************************
2025-12-05 11:13:28.187190 | controller | Friday 05 December 2025  11:13:28 +0000 (0:00:00.991)       0:00:02.098 *******
2025-12-05 11:13:28.187220 | controller | ok: [instance]
2025-12-05 11:13:28.240139 | controller |
2025-12-05 11:13:28.240174 | controller | TASK [Ensure both lists have the same length] **********************************
2025-12-05 11:13:28.240182 | controller | Friday 05 December 2025  11:13:28 +0000 (0:00:00.051)       0:00:02.149 *******
2025-12-05 11:13:28.240193 | controller | ok: [instance] => changed=false
2025-12-05 11:13:28.317033 | controller |   msg: All assertions passed
2025-12-05 11:13:28.317070 | controller |
2025-12-05 11:13:28.317078 | controller | TASK [Compare each corresponding element in the lists] *************************
2025-12-05 11:13:28.317086 | controller | Friday 05 December 2025  11:13:28 +0000 (0:00:00.055)       0:00:02.204 *******
2025-12-05 11:13:28.317103 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}', '{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
2025-12-05 11:13:28.317229 | controller |   ansible_loop_var: item
2025-12-05 11:13:28.317240 | controller |   item:
2025-12-05 11:13:28.317247 | controller |   - |-
2025-12-05 11:13:28.317253 | controller |     {
2025-12-05 11:13:28.317259 | controller |       "cniVersion": "0.3.1",
2025-12-05 11:13:28.317265 | controller |       "name": "bridge-to-linux-bridge",
2025-12-05 11:13:28.317271 | controller |       "type": "bridge",
2025-12-05 11:13:28.317277 | controller |       "bridge": "bridge-to-linux-bridge",
2025-12-05 11:13:28.317284 | controller |       "ipam": {
2025-12-05 11:13:28.317290 | controller |         "type": "whereabouts",
2025-12-05 11:13:28.317296 | controller |         "range": "192.168.122.0/24",
2025-12-05 11:13:28.317302 | controller |         "range_start": "192.168.122.30",
2025-12-05 11:13:28.317308 | controller |         "range_end": "192.168.122.70"
2025-12-05 11:13:28.317330 | controller |       }
2025-12-05 11:13:28.317336 | controller |     }
2025-12-05 11:13:28.317342 | controller |   - |-
2025-12-05 11:13:28.317348 | controller |     {
2025-12-05 11:13:28.317354 | controller |       "cniVersion": "0.3.1",
2025-12-05 11:13:28.317360 | controller |       "name": "bridge-to-linux-bridge",
2025-12-05 11:13:28.317365 | controller |       "type": "bridge",
2025-12-05 11:13:28.317371 | controller |       "bridge": "bridge-to-linux-bridge",
2025-12-05 11:13:28.317378 | controller |       "ipam": {
2025-12-05 11:13:28.317384 | controller |         "type": "whereabouts",
2025-12-05 11:13:28.317391 | controller |         "range": "192.168.122.0/24",
2025-12-05 11:13:28.317398 | controller |         "range_start": "192.168.122.30",
2025-12-05 11:13:28.317405 | controller |         "range_end": "192.168.122.70"
2025-12-05 11:13:28.317412 | controller |       }
2025-12-05 11:13:28.317419 | controller |     }
2025-12-05 11:13:28.317425 | controller |   msg: All assertions passed
2025-12-05 11:13:28.317449 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
2025-12-05 11:13:28.317465 | controller |   ansible_loop_var: item
2025-12-05 11:13:28.317471 | controller |   item:
2025-12-05 11:13:28.317477 | controller |   - |-
2025-12-05 11:13:28.317483 | controller |     {
2025-12-05 11:13:28.317489 | controller |       "cniVersion": "0.3.1",
2025-12-05 11:13:28.317495 | controller |       "name": "default",
2025-12-05 11:13:28.317506 | controller |       "type": "bridge",
2025-12-05 11:13:28.317513 | controller |       "bridge": "eth0",
2025-12-05 11:13:28.317519 | controller |       "ipam": {
2025-12-05 11:13:28.317525 | controller |         "type": "whereabouts",
2025-12-05 11:13:28.317531 | controller |         "range": "192.168.122.0/24",
2025-12-05 11:13:28.317537 | controller |         "range_start": "192.168.122.30",
2025-12-05 11:13:28.317543 | controller |         "range_end": "192.168.122.70"
2025-12-05 11:13:28.317549 | controller |       }
2025-12-05 11:13:28.317555 | controller |     }
2025-12-05 11:13:28.317561 | controller |   - |-
2025-12-05 11:13:28.317567 | controller |     {
2025-12-05 11:13:28.317573 | controller |       "cniVersion": "0.3.1",
2025-12-05 11:13:28.317579 | controller |       "name": "default",
2025-12-05 11:13:28.317585 | controller |       "type": "bridge",
2025-12-05 11:13:28.317591 | controller |       "bridge": "eth0",
2025-12-05 11:13:28.317597 | controller |       "ipam": {
2025-12-05 11:13:28.317603 | controller |         "type": "whereabouts",
2025-12-05 11:13:28.317609 | controller |         "range": "192.168.122.0/24",
2025-12-05 11:13:28.317615 | controller |         "range_start": "192.168.122.30",
2025-12-05 11:13:28.317621 | controller |         "range_end": "192.168.122.70"
2025-12-05 11:13:28.317626 | controller |       }
2025-12-05 11:13:28.317632 | controller |     }
2025-12-05 11:13:28.317638 | controller |   msg: All assertions passed
2025-12-05 11:13:28.317645 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}']) => changed=false
2025-12-05 11:13:28.317651 | controller |   ansible_loop_var: item
2025-12-05 11:13:28.317665 | controller |   item:
2025-12-05 11:13:28.317672 | controller |   - |-
2025-12-05 11:13:28.317686 | controller |     {
2025-12-05 11:13:28.317700 | controller |       "cniVersion": "0.3.1",
2025-12-05 11:13:28.317714 | controller |       "name": "patchnetwork",
2025-12-05 11:13:28.317724 | controller |       "type": "macvlan",
2025-12-05 11:13:28.317730 | controller |       "master": "eth2",
2025-12-05 11:13:28.317736 | controller |       "ipam": {
2025-12-05 11:13:28.317742 | controller |         "type": "whereabouts",
2025-12-05 11:13:28.317748 | controller |         "range": "192.168.122.0/24",
2025-12-05 11:13:28.317763 | controller |         "range_start": "192.168.122.30",
2025-12-05 11:13:28.317780 | controller |         "range_end": "192.168.122.70"
2025-12-05 11:13:28.317990 | controller |       }
2025-12-05 11:13:28.318165 | controller |     }
2025-12-05 11:13:28.318305 | controller |   - |-
2025-12-05 11:13:28.318443 | controller |     {
2025-12-05 11:13:28.318596 | controller |       "cniVersion": "0.3.1",
2025-12-05 11:13:28.318781 | controller |       "name": "patchnetwork",
2025-12-05 11:13:28.318994 | controller |       "type": "macvlan",
2025-12-05 11:13:28.319182 | controller |       "master": "eth2",
2025-12-05 11:13:28.319367 | controller |       "ipam": {
2025-12-05 11:13:28.319552 | controller |         "type": "whereabouts",
2025-12-05 11:13:28.319770 | controller |         "range": "192.168.122.0/24",
2025-12-05 11:13:28.319986 | controller |         "range_start": "192.168.122.30",
2025-12-05 11:13:28.320223 | controller |         "range_end": "192.168.122.70"
2025-12-05 11:13:28.320432 | controller |       }
2025-12-05 11:13:28.320616 | controller |     }
2025-12-05 11:13:28.320804 | controller |   msg: All assertions passed
2025-12-05 11:13:28.321293 | controller |
2025-12-05 11:13:28.321474 | controller | TASK [Create a test pod to attach a network] ***********************************
2025-12-05 11:13:28.321618 | controller | Friday 05 December 2025  11:13:28 +0000 (0:00:00.069)       0:00:02.274 *******
2025-12-05 11:13:39.406871 | controller | [WARNING]: unknown field "api_version"
2025-12-05 11:13:39.417197 | controller | changed: [instance]
2025-12-05 11:13:39.442862 | controller |
2025-12-05 11:13:39.442896 | controller | TASK [Assert that test pod has the additional network] *************************
2025-12-05 11:13:39.442904 | controller | Friday 05 December 2025  11:13:39 +0000 (0:00:11.107)       0:00:13.381 *******
2025-12-05 11:13:39.442915 | controller | ok: [instance] => changed=false
2025-12-05 11:13:40.228909 | controller |   msg: All assertions passed
2025-12-05 11:13:40.228965 | controller |
2025-12-05 11:13:40.228977 | controller | TASK [Delete test pod] *********************************************************
2025-12-05 11:13:40.228995 | controller | Friday 05 December 2025  11:13:39 +0000 (0:00:00.025)       0:00:13.407 *******
2025-12-05 11:13:40.229007 | controller | changed: [instance]
2025-12-05 11:13:40.229055 | controller |
2025-12-05 11:13:40.229066 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:40.229075 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:13:40.229083 | controller |
2025-12-05 11:13:40.229089 | controller | Friday 05 December 2025  11:13:40 +0000 (0:00:00.785)       0:00:14.193 *******
2025-12-05 11:13:40.229095 | controller | ===============================================================================
2025-12-05 11:13:40.229101 | controller | Create a test pod to attach a network ---------------------------------- 11.11s
2025-12-05 11:13:40.229109 | controller | Gathering Facts --------------------------------------------------------- 1.06s
2025-12-05 11:13:40.229345 | controller | Check if NADs were created ---------------------------------------------- 0.99s
2025-12-05 11:13:40.229360 | controller | Delete test pod --------------------------------------------------------- 0.79s
2025-12-05 11:13:40.229578 | controller | Compare each corresponding element in the lists ------------------------- 0.07s
2025-12-05 11:13:40.229604 | controller | Ensure both lists have the same length ---------------------------------- 0.06s
2025-12-05 11:13:40.229713 | controller | Store output spec ------------------------------------------------------- 0.05s
2025-12-05 11:13:40.229725 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-12-05 11:13:40.229854 | controller | Assert that test pod has the additional network ------------------------- 0.03s
2025-12-05 11:13:40.300125 | controller | INFO     Verifier completed successfully.
2025-12-05 11:13:40.310284 | controller | INFO     Running default > side_effect
2025-12-05 11:13:40.889014 | controller |
2025-12-05 11:13:41.908067 | controller | PLAY [Clean] *******************************************************************
2025-12-05 11:13:41.908104 | controller |
2025-12-05 11:13:41.908112 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:41.908118 | controller | Friday 05 December 2025  11:13:40 +0000 (0:00:00.021)       0:00:00.021 *******
2025-12-05 11:13:41.908128 | controller | ok: [instance]
2025-12-05 11:13:42.266930 | controller |
2025-12-05 11:13:42.267034 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-12-05 11:13:42.267045 | controller | Friday 05 December 2025  11:13:41 +0000 (0:00:01.019)       0:00:01.040 *******
2025-12-05 11:13:42.267068 | controller | changed: [instance]
2025-12-05 11:13:43.286080 | controller |
2025-12-05 11:13:43.286122 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-12-05 11:13:43.286131 | controller | Friday 05 December 2025  11:13:42 +0000 (0:00:00.359)       0:00:01.400 *******
2025-12-05 11:13:43.286144 | controller | ok: [instance]
2025-12-05 11:13:43.286263 | controller |
2025-12-05 11:13:43.286277 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-12-05 11:13:43.286398 | controller | Friday 05 December 2025  11:13:43 +0000 (0:00:01.019)       0:00:02.419 *******
2025-12-05 11:13:45.877840 | controller | changed: [instance] => (item=bridge-to-linux-bridge)
2025-12-05 11:13:45.878004 | controller | changed: [instance] => (item=default)
2025-12-05 11:13:45.878031 | controller | changed: [instance] => (item=patchnetwork)
2025-12-05 11:13:45.878069 | controller |
2025-12-05 11:13:45.878084 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-12-05 11:13:45.878231 | controller | Friday 05 December 2025  11:13:45 +0000 (0:00:02.591)       0:00:05.010 *******
2025-12-05 11:13:46.256917 | controller | changed: [instance]
2025-12-05 11:13:46.257804 | controller |
2025-12-05 11:13:46.257847 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:46.257857 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:13:46.257866 | controller |
2025-12-05 11:13:46.257879 | controller | Friday 05 December 2025  11:13:46 +0000 (0:00:00.380)       0:00:05.390 *******
2025-12-05 11:13:46.346018 | controller | ===============================================================================
2025-12-05 11:13:46.346065 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 2.59s
2025-12-05 11:13:46.346075 | controller | Gathering Facts --------------------------------------------------------- 1.02s
2025-12-05 11:13:46.346084 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 1.02s
2025-12-05 11:13:46.346092 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.38s
2025-12-05 11:13:46.346101 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.36s
2025-12-05 11:13:46.346116 | controller | INFO     Running default > verify
2025-12-05 11:13:46.346762 | controller | INFO     Running Ansible Verifier
2025-12-05 11:13:46.877637 | controller |
2025-12-05 11:13:47.914519 | controller | PLAY [Verify Clean] ************************************************************
2025-12-05 11:13:47.914552 | controller |
2025-12-05 11:13:47.914559 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:47.914566 | controller | Friday 05 December 2025  11:13:46 +0000 (0:00:00.018)       0:00:00.018 *******
2025-12-05 11:13:47.914576 | controller | ok: [instance]
2025-12-05 11:13:47.914816 | controller |
2025-12-05 11:13:47.914838 | controller | TASK [Include default vars] ****************************************************
2025-12-05 11:13:47.944476 | controller | Friday 05 December 2025  11:13:47 +0000 (0:00:01.038)       0:00:01.056 *******
2025-12-05 11:13:47.944510 | controller | ok: [instance]
2025-12-05 11:13:48.291609 | controller |
2025-12-05 11:13:48.291643 | controller | TASK [Fetch file stat results] *************************************************
2025-12-05 11:13:48.291651 | controller | Friday 05 December 2025  11:13:47 +0000 (0:00:00.029)       0:00:01.086 *******
2025-12-05 11:13:48.291662 | controller | ok: [instance]
2025-12-05 11:13:48.291688 | controller |
2025-12-05 11:13:48.291924 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-12-05 11:13:48.350797 | controller | Friday 05 December 2025  11:13:48 +0000 (0:00:00.347)       0:00:01.433 *******
2025-12-05 11:13:48.350853 | controller | ok: [instance] => changed=false
2025-12-05 11:13:48.427192 | controller |   msg: All assertions passed
2025-12-05 11:13:48.427224 | controller |
2025-12-05 11:13:48.427232 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:48.427239 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:13:48.427246 | controller |
2025-12-05 11:13:48.427252 | controller | Friday 05 December 2025  11:13:48 +0000 (0:00:00.056)       0:00:01.490 *******
2025-12-05 11:13:48.427258 | controller | ===============================================================================
2025-12-05 11:13:48.427264 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2025-12-05 11:13:48.427270 | controller | Fetch file stat results ------------------------------------------------- 0.35s
2025-12-05 11:13:48.427276 | controller | Assert that created file are removed by cleanup ------------------------- 0.06s
2025-12-05 11:13:48.427282 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-12-05 11:13:48.427293 | controller | INFO     Verifier completed successfully.
2025-12-05 11:13:48.438911 | controller | INFO     Running default > verify
2025-12-05 11:13:48.439311 | controller | INFO     Running Ansible Verifier
2025-12-05 11:13:48.976221 | controller |
2025-12-05 11:13:49.987606 | controller | PLAY [Verify Clean CRC] ********************************************************
2025-12-05 11:13:49.987657 | controller |
2025-12-05 11:13:49.987672 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:49.987685 | controller | Friday 05 December 2025  11:13:48 +0000 (0:00:00.022)       0:00:00.022 *******
2025-12-05 11:13:49.987705 | controller | ok: [instance]
2025-12-05 11:13:50.014759 | controller |
2025-12-05 11:13:50.014801 | controller | TASK [Include default vars] ****************************************************
2025-12-05 11:13:50.014811 | controller | Friday 05 December 2025  11:13:49 +0000 (0:00:01.011)       0:00:01.033 *******
2025-12-05 11:13:50.014837 | controller | ok: [instance]
2025-12-05 11:13:51.012613 | controller |
2025-12-05 11:13:51.012665 | controller | TASK [Check if NADs were deleted] **********************************************
2025-12-05 11:13:51.012678 | controller | Friday 05 December 2025  11:13:50 +0000 (0:00:00.027)       0:00:01.061 *******
2025-12-05 11:13:51.012702 | controller | ok: [instance]
2025-12-05 11:13:51.012738 | controller |
2025-12-05 11:13:51.012757 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:51.012768 | controller | instance                   : ok=3    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:13:51.012905 | controller |
2025-12-05 11:13:51.013256 | controller | Friday 05 December 2025  11:13:51 +0000 (0:00:00.998)       0:00:02.059 *******
2025-12-05 11:13:51.013275 | controller | ===============================================================================
2025-12-05 11:13:51.013358 | controller | Gathering Facts --------------------------------------------------------- 1.01s
2025-12-05 11:13:51.013373 | controller | Check if NADs were deleted ---------------------------------------------- 1.00s
2025-12-05 11:13:51.013383 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-12-05 11:13:51.086606 | controller | INFO     Verifier completed successfully.
2025-12-05 11:13:51.086772 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-05 11:13:51.101095 | controller | INFO     Running local > destroy
2025-12-05 11:13:51.101352 | controller | WARNING  Skipping, instances are delegated.
2025-12-05 11:13:51.110855 | controller | INFO     Running local > create
2025-12-05 11:13:51.111807 | controller | WARNING  Skipping, instances are delegated.
2025-12-05 11:13:51.120236 | controller | INFO     Running local > converge
2025-12-05 11:13:51.657186 | controller |
2025-12-05 11:13:51.657410 | controller | PLAY [Converge] ****************************************************************
2025-12-05 11:13:51.657595 | controller |
2025-12-05 11:13:51.657774 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:51.658033 | controller | Friday 05 December 2025  11:13:51 +0000 (0:00:00.016)       0:00:00.016 *******
2025-12-05 11:13:52.953780 | controller | ok: [instance]
2025-12-05 11:13:52.953820 | controller |
2025-12-05 11:13:52.953831 | controller | TASK [Load shared variables] ***************************************************
2025-12-05 11:13:52.953972 | controller | Friday 05 December 2025  11:13:52 +0000 (0:00:01.296)       0:00:01.313 *******
2025-12-05 11:13:52.978552 | controller | ok: [instance]
2025-12-05 11:13:53.023132 | controller |
2025-12-05 11:13:53.023181 | controller | TASK [Call ci_multus role] *****************************************************
2025-12-05 11:13:53.023194 | controller | Friday 05 December 2025  11:13:52 +0000 (0:00:00.024)       0:00:01.338 *******
2025-12-05 11:13:53.023210 | controller |
2025-12-05 11:13:53.023249 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-12-05 11:13:53.023263 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.044)       0:00:01.383 *******
2025-12-05 11:13:53.415609 | controller | changed: [instance]
2025-12-05 11:13:53.444005 | controller |
2025-12-05 11:13:53.444048 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-12-05 11:13:53.444059 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.392)       0:00:01.775 *******
2025-12-05 11:13:53.444073 | controller | skipping: [instance]
2025-12-05 11:13:53.444082 | controller |
2025-12-05 11:13:53.444092 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-12-05 11:13:53.444182 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.028)       0:00:01.803 *******
2025-12-05 11:13:53.469794 | controller | skipping: [instance]
2025-12-05 11:13:53.469852 | controller |
2025-12-05 11:13:53.470104 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-12-05 11:13:53.498469 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.025)       0:00:01.829 *******
2025-12-05 11:13:53.498512 | controller | skipping: [instance]
2025-12-05 11:13:53.533787 | controller |
2025-12-05 11:13:53.533823 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-12-05 11:13:53.533832 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.028)       0:00:01.858 *******
2025-12-05 11:13:53.533844 | controller | skipping: [instance]
2025-12-05 11:13:53.571656 | controller |
2025-12-05 11:13:53.571691 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-12-05 11:13:53.571701 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.035)       0:00:01.893 *******
2025-12-05 11:13:53.571713 | controller | ok: [instance]
2025-12-05 11:13:53.747342 | controller |
2025-12-05 11:13:53.747378 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-12-05 11:13:53.747387 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.038)       0:00:01.931 *******
2025-12-05 11:13:53.747400 | controller | ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
2025-12-05 11:13:53.747483 | controller | ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}]}}}})
2025-12-05 11:13:53.747519 | controller |
2025-12-05 11:13:53.747532 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-12-05 11:13:53.821926 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.175)       0:00:02.107 *******
2025-12-05 11:13:53.822024 | controller | ok: [instance]
2025-12-05 11:13:53.822038 | controller |
2025-12-05 11:13:53.822222 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-12-05 11:13:53.822356 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.074)       0:00:02.182 *******
2025-12-05 11:13:53.899302 | controller | ok: [instance]
2025-12-05 11:13:53.899427 | controller |
2025-12-05 11:13:53.899623 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-12-05 11:13:53.899794 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.077)       0:00:02.259 *******
2025-12-05 11:13:53.995100 | controller | ok: [instance]
2025-12-05 11:13:53.995233 | controller |
2025-12-05 11:13:53.995252 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-12-05 11:13:53.995264 | controller | Friday 05 December 2025  11:13:53 +0000 (0:00:00.094)       0:00:02.354 *******
2025-12-05 11:13:55.109659 | controller | changed: [instance]
2025-12-05 11:13:55.109697 | controller |
2025-12-05 11:13:55.109706 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-12-05 11:13:55.109717 | controller | Friday 05 December 2025  11:13:55 +0000 (0:00:01.115)       0:00:03.469 *******
2025-12-05 11:13:55.134084 | controller | skipping: [instance]
2025-12-05 11:13:55.134437 | controller |
2025-12-05 11:13:55.134479 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-12-05 11:13:55.182289 | controller | Friday 05 December 2025  11:13:55 +0000 (0:00:00.024)       0:00:03.493 *******
2025-12-05 11:13:55.182337 | controller | skipping: [instance]
2025-12-05 11:13:55.182372 | controller |
2025-12-05 11:13:55.182385 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:55.182597 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-12-05 11:13:55.183121 | controller |
2025-12-05 11:13:55.184626 | controller | Friday 05 December 2025  11:13:55 +0000 (0:00:00.048)       0:00:03.542 *******
2025-12-05 11:13:55.184652 | controller | ===============================================================================
2025-12-05 11:13:55.184666 | controller | Gathering Facts --------------------------------------------------------- 1.30s
2025-12-05 11:13:55.184766 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 1.12s
2025-12-05 11:13:55.184777 | controller | ci_multus : Create manifests directory ---------------------------------- 0.39s
2025-12-05 11:13:55.184785 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.18s
2025-12-05 11:13:55.184794 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.10s
2025-12-05 11:13:55.184802 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
2025-12-05 11:13:55.184810 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.07s
2025-12-05 11:13:55.184817 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.05s
2025-12-05 11:13:55.184826 | controller | Call ci_multus role ----------------------------------------------------- 0.04s
2025-12-05 11:13:55.184837 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.04s
2025-12-05 11:13:55.185103 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-12-05 11:13:55.185449 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2025-12-05 11:13:55.185735 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2025-12-05 11:13:55.186078 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-12-05 11:13:55.186371 | controller | Load shared variables --------------------------------------------------- 0.02s
2025-12-05 11:13:55.186656 | controller | ci_multus : Create the multus namespace --------------------------------- 0.02s
2025-12-05 11:13:55.265613 | controller | INFO     Running local > verify
2025-12-05 11:13:55.265995 | controller | INFO     Running Ansible Verifier
2025-12-05 11:13:55.798039 | controller |
2025-12-05 11:13:55.798339 | controller | PLAY [Verify] ******************************************************************
2025-12-05 11:13:55.798524 | controller |
2025-12-05 11:13:55.798709 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:55.798885 | controller | Friday 05 December 2025  11:13:55 +0000 (0:00:00.016)       0:00:00.016 *******
2025-12-05 11:13:56.875846 | controller | ok: [instance]
2025-12-05 11:13:56.896398 | controller |
2025-12-05 11:13:56.896431 | controller | TASK [Load shared variables] ***************************************************
2025-12-05 11:13:56.896439 | controller | Friday 05 December 2025  11:13:56 +0000 (0:00:01.077)       0:00:01.094 *******
2025-12-05 11:13:56.896457 | controller | ok: [instance]
2025-12-05 11:13:57.283651 | controller |
2025-12-05 11:13:57.283692 | controller | TASK [Fetch files stat results] ************************************************
2025-12-05 11:13:57.283703 | controller | Friday 05 December 2025  11:13:56 +0000 (0:00:00.020)       0:00:01.115 *******
2025-12-05 11:13:57.283716 | controller | ok: [instance]
2025-12-05 11:13:57.316714 | controller |
2025-12-05 11:13:57.316751 | controller | TASK [Assert that expected file exist] *****************************************
2025-12-05 11:13:57.316759 | controller | Friday 05 December 2025  11:13:57 +0000 (0:00:00.385)       0:00:01.500 *******
2025-12-05 11:13:57.316770 | controller | ok: [instance] => changed=false
2025-12-05 11:13:57.715940 | controller |   msg: All assertions passed
2025-12-05 11:13:57.715992 | controller |
2025-12-05 11:13:57.716000 | controller | TASK [Fetch file content] ******************************************************
2025-12-05 11:13:57.716006 | controller | Friday 05 December 2025  11:13:57 +0000 (0:00:00.034)       0:00:01.535 *******
2025-12-05 11:13:57.716017 | controller | ok: [instance]
2025-12-05 11:13:57.751334 | controller |
2025-12-05 11:13:57.751374 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-12-05 11:13:57.751383 | controller | Friday 05 December 2025  11:13:57 +0000 (0:00:00.399)       0:00:01.935 *******
2025-12-05 11:13:57.751395 | controller | ok: [instance]
2025-12-05 11:13:57.751736 | controller |
2025-12-05 11:13:57.751810 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-12-05 11:13:57.810047 | controller | Friday 05 December 2025  11:13:57 +0000 (0:00:00.035)       0:00:01.970 *******
2025-12-05 11:13:57.810113 | controller | ok: [instance]
2025-12-05 11:13:57.810188 | controller |
2025-12-05 11:13:57.811381 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:13:57.811440 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:13:57.880582 | controller |
2025-12-05 11:13:57.880629 | controller | Friday 05 December 2025  11:13:57 +0000 (0:00:00.059)       0:00:02.029 *******
2025-12-05 11:13:57.880645 | controller | ===============================================================================
2025-12-05 11:13:57.880654 | controller | Gathering Facts --------------------------------------------------------- 1.08s
2025-12-05 11:13:57.880663 | controller | Fetch file content ------------------------------------------------------ 0.40s
2025-12-05 11:13:57.880671 | controller | Fetch files stat results ------------------------------------------------ 0.39s
2025-12-05 11:13:57.880679 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-12-05 11:13:57.880687 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-12-05 11:13:57.880695 | controller | Assert that expected file exist ----------------------------------------- 0.03s
2025-12-05 11:13:57.880703 | controller | Load shared variables --------------------------------------------------- 0.02s
2025-12-05 11:13:57.880717 | controller | INFO     Verifier completed successfully.
2025-12-05 11:13:57.891027 | controller | INFO     Running local > side_effect
2025-12-05 11:13:58.428404 | controller |
2025-12-05 11:13:59.515228 | controller | PLAY [Clean] *******************************************************************
2025-12-05 11:13:59.515264 | controller |
2025-12-05 11:13:59.515272 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:13:59.515278 | controller | Friday 05 December 2025  11:13:58 +0000 (0:00:00.018)       0:00:00.018 *******
2025-12-05 11:13:59.515289 | controller | ok: [instance]
2025-12-05 11:13:59.924113 | controller |
2025-12-05 11:13:59.924156 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-12-05 11:13:59.924167 | controller | Friday 05 December 2025  11:13:59 +0000 (0:00:01.087)       0:00:01.105 *******
2025-12-05 11:13:59.924181 | controller | changed: [instance]
2025-12-05 11:13:59.924290 | controller |
2025-12-05 11:13:59.924307 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-12-05 11:13:59.970032 | controller | Friday 05 December 2025  11:13:59 +0000 (0:00:00.408)       0:00:01.514 *******
2025-12-05 11:13:59.970081 | controller | skipping: [instance]
2025-12-05 11:14:00.001529 | controller |
2025-12-05 11:14:00.001562 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-12-05 11:14:00.001577 | controller | Friday 05 December 2025  11:13:59 +0000 (0:00:00.045)       0:00:01.559 *******
2025-12-05 11:14:00.001588 | controller | skipping: [instance]
2025-12-05 11:14:00.001793 | controller |
2025-12-05 11:14:00.001853 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-12-05 11:14:00.394370 | controller | Friday 05 December 2025  11:13:59 +0000 (0:00:00.032)       0:00:01.592 *******
2025-12-05 11:14:00.394429 | controller | changed: [instance]
2025-12-05 11:14:00.394456 | controller |
2025-12-05 11:14:00.394772 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:14:00.395312 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-12-05 11:14:00.395333 | controller |
2025-12-05 11:14:00.395606 | controller | Friday 05 December 2025  11:14:00 +0000 (0:00:00.393)       0:00:01.985 *******
2025-12-05 11:14:00.395622 | controller | ===============================================================================
2025-12-05 11:14:00.395630 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2025-12-05 11:14:00.395650 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.41s
2025-12-05 11:14:00.395919 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.39s
2025-12-05 11:14:00.395994 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.05s
2025-12-05 11:14:00.496062 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.03s
2025-12-05 11:14:00.496122 | controller | INFO     Running local > verify
2025-12-05 11:14:00.496607 | controller | INFO     Running Ansible Verifier
2025-12-05 11:14:01.037019 | controller |
2025-12-05 11:14:02.156999 | controller | PLAY [Verify Clean] ************************************************************
2025-12-05 11:14:02.157062 | controller |
2025-12-05 11:14:02.157073 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:14:02.157082 | controller | Friday 05 December 2025  11:14:01 +0000 (0:00:00.019)       0:00:00.019 *******
2025-12-05 11:14:02.157100 | controller | ok: [instance]
2025-12-05 11:14:02.157415 | controller |
2025-12-05 11:14:02.157466 | controller | TASK [Include default vars] ****************************************************
2025-12-05 11:14:02.157477 | controller | Friday 05 December 2025  11:14:02 +0000 (0:00:01.120)       0:00:01.139 *******
2025-12-05 11:14:02.187791 | controller | ok: [instance]
2025-12-05 11:14:02.187833 | controller |
2025-12-05 11:14:02.187841 | controller | TASK [Fetch file stat results] *************************************************
2025-12-05 11:14:02.187850 | controller | Friday 05 December 2025  11:14:02 +0000 (0:00:00.030)       0:00:01.170 *******
2025-12-05 11:14:02.536836 | controller | ok: [instance]
2025-12-05 11:14:02.536905 | controller |
2025-12-05 11:14:02.537182 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-12-05 11:14:02.591094 | controller | Friday 05 December 2025  11:14:02 +0000 (0:00:00.349)       0:00:01.519 *******
2025-12-05 11:14:02.591138 | controller | ok: [instance] => changed=false
2025-12-05 11:14:02.591792 | controller |   msg: All assertions passed
2025-12-05 11:14:02.591827 | controller |
2025-12-05 11:14:02.591839 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:14:02.591865 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:14:02.591872 | controller |
2025-12-05 11:14:02.591878 | controller | Friday 05 December 2025  11:14:02 +0000 (0:00:00.054)       0:00:01.574 *******
2025-12-05 11:14:02.591885 | controller | ===============================================================================
2025-12-05 11:14:02.591892 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2025-12-05 11:14:02.592317 | controller | Fetch file stat results ------------------------------------------------- 0.35s
2025-12-05 11:14:02.668620 | controller | Assert that created file are removed by cleanup ------------------------- 0.05s
2025-12-05 11:14:02.668667 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-12-05 11:14:02.668684 | controller | INFO     Verifier completed successfully.
2025-12-05 11:14:02.669322 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-05 11:14:02.683824 | controller | INFO     Running local_ipv6 > destroy
2025-12-05 11:14:02.684334 | controller | WARNING  Skipping, instances are delegated.
2025-12-05 11:14:02.694126 | controller | INFO     Running local_ipv6 > create
2025-12-05 11:14:02.695112 | controller | WARNING  Skipping, instances are delegated.
2025-12-05 11:14:02.703870 | controller | INFO     Running local_ipv6 > converge
2025-12-05 11:14:03.274190 | controller |
2025-12-05 11:14:04.343425 | controller | PLAY [Converge] ****************************************************************
2025-12-05 11:14:04.343465 | controller |
2025-12-05 11:14:04.343475 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:14:04.343484 | controller | Friday 05 December 2025  11:14:03 +0000 (0:00:00.020)       0:00:00.020 *******
2025-12-05 11:14:04.343497 | controller | ok: [instance]
2025-12-05 11:14:04.367157 | controller |
2025-12-05 11:14:04.367225 | controller | TASK [Load shared variables] ***************************************************
2025-12-05 11:14:04.367252 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:01.069)       0:00:01.089 *******
2025-12-05 11:14:04.367268 | controller | ok: [instance]
2025-12-05 11:14:04.409395 | controller |
2025-12-05 11:14:04.409456 | controller | TASK [Call ci_multus role] *****************************************************
2025-12-05 11:14:04.409467 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.024)       0:00:01.113 *******
2025-12-05 11:14:04.409485 | controller |
2025-12-05 11:14:04.409547 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-12-05 11:14:04.409576 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.041)       0:00:01.155 *******
2025-12-05 11:14:04.790627 | controller | changed: [instance]
2025-12-05 11:14:04.823312 | controller |
2025-12-05 11:14:04.823354 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-12-05 11:14:04.823364 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.380)       0:00:01.536 *******
2025-12-05 11:14:04.823378 | controller | skipping: [instance]
2025-12-05 11:14:04.823494 | controller |
2025-12-05 11:14:04.823536 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-12-05 11:14:04.823553 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.033)       0:00:01.569 *******
2025-12-05 11:14:04.854262 | controller | skipping: [instance]
2025-12-05 11:14:04.854392 | controller |
2025-12-05 11:14:04.854434 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-12-05 11:14:04.854754 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.030)       0:00:01.600 *******
2025-12-05 11:14:04.886468 | controller | skipping: [instance]
2025-12-05 11:14:04.921876 | controller |
2025-12-05 11:14:04.921908 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-12-05 11:14:04.921916 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.032)       0:00:01.633 *******
2025-12-05 11:14:04.921926 | controller | skipping: [instance]
2025-12-05 11:14:04.963494 | controller |
2025-12-05 11:14:04.963533 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-12-05 11:14:04.963541 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.035)       0:00:01.668 *******
2025-12-05 11:14:04.963552 | controller | ok: [instance]
2025-12-05 11:14:05.089215 | controller |
2025-12-05 11:14:05.089313 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-12-05 11:14:05.089325 | controller | Friday 05 December 2025  11:14:04 +0000 (0:00:00.041)       0:00:01.709 *******
2025-12-05 11:14:05.089361 | controller | skipping: [instance] => (item={'default': {'interface_name': 'eth1', 'network_name': 'default', 'gw_v6': 'fdc0:8b54:108a:c949:0000:0000:0000:0001', 'network_v6': 'fdc0:8b54:108a:c949::/64', 'tools': {'multus': {'ipv6_ranges': [{'start': 'fdc0:8b54:108a:c949:0000:0000:0000:001e', 'end': 'fdc0:8b54:108a:c949:0000:0000:0000:0027'}]}}}})
2025-12-05 11:14:05.089417 | controller | skipping: [instance]
2025-12-05 11:14:05.089432 | controller |
2025-12-05 11:14:05.157055 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-12-05 11:14:05.157436 | controller | Friday 05 December 2025  11:14:05 +0000 (0:00:00.126)       0:00:01.836 *******
2025-12-05 11:14:05.157478 | controller | ok: [instance]
2025-12-05 11:14:05.157513 | controller |
2025-12-05 11:14:05.157522 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-12-05 11:14:05.157532 | controller | Friday 05 December 2025  11:14:05 +0000 (0:00:00.067)       0:00:01.904 *******
2025-12-05 11:14:05.232758 | controller | ok: [instance]
2025-12-05 11:14:05.233204 | controller |
2025-12-05 11:14:05.233836 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-12-05 11:14:05.315356 | controller | Friday 05 December 2025  11:14:05 +0000 (0:00:00.075)       0:00:01.979 *******
2025-12-05 11:14:05.315505 | controller | ok: [instance]
2025-12-05 11:14:05.315554 | controller |
2025-12-05 11:14:05.315568 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-12-05 11:14:05.315711 | controller | Friday 05 December 2025  11:14:05 +0000 (0:00:00.082)       0:00:02.061 *******
2025-12-05 11:14:06.055149 | controller | changed: [instance]
2025-12-05 11:14:06.055300 | controller |
2025-12-05 11:14:06.055561 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-12-05 11:14:06.055834 | controller | Friday 05 December 2025  11:14:06 +0000 (0:00:00.739)       0:00:02.801 *******
2025-12-05 11:14:06.086497 | controller | skipping: [instance]
2025-12-05 11:14:06.086641 | controller |
2025-12-05 11:14:06.087522 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-12-05 11:14:06.087824 | controller | Friday 05 December 2025  11:14:06 +0000 (0:00:00.031)       0:00:02.832 *******
2025-12-05 11:14:06.132691 | controller | skipping: [instance]
2025-12-05 11:14:06.132784 | controller |
2025-12-05 11:14:06.132973 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:14:06.133150 | controller | instance                   : ok=8    changed=2    unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-12-05 11:14:06.133296 | controller |
2025-12-05 11:14:06.133442 | controller | Friday 05 December 2025  11:14:06 +0000 (0:00:00.046)       0:00:02.879 *******
2025-12-05 11:14:06.133618 | controller | ===============================================================================
2025-12-05 11:14:06.133756 | controller | Gathering Facts --------------------------------------------------------- 1.07s
2025-12-05 11:14:06.133904 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.74s
2025-12-05 11:14:06.134068 | controller | ci_multus : Create manifests directory ---------------------------------- 0.38s
2025-12-05 11:14:06.134211 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.13s
2025-12-05 11:14:06.134413 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.08s
2025-12-05 11:14:06.134574 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
2025-12-05 11:14:06.134743 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.07s
2025-12-05 11:14:06.134876 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.05s
2025-12-05 11:14:06.135049 | controller | Call ci_multus role ----------------------------------------------------- 0.04s
2025-12-05 11:14:06.135193 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.04s
2025-12-05 11:14:06.135320 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-12-05 11:14:06.135470 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2025-12-05 11:14:06.135605 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2025-12-05 11:14:06.135792 | controller | ci_multus : Create the multus namespace --------------------------------- 0.03s
2025-12-05 11:14:06.135927 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-12-05 11:14:06.136090 | controller | Load shared variables --------------------------------------------------- 0.02s
2025-12-05 11:14:06.220302 | controller | INFO     Running local_ipv6 > verify
2025-12-05 11:14:06.220669 | controller | INFO     Running Ansible Verifier
2025-12-05 11:14:06.739357 | controller |
2025-12-05 11:14:06.739431 | controller | PLAY [Verify] ******************************************************************
2025-12-05 11:14:06.739554 | controller |
2025-12-05 11:14:06.739685 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:14:06.739822 | controller | Friday 05 December 2025  11:14:06 +0000 (0:00:00.016)       0:00:00.016 *******
2025-12-05 11:14:07.758401 | controller | ok: [instance]
2025-12-05 11:14:07.758672 | controller |
2025-12-05 11:14:07.785232 | controller | TASK [Load shared variables] ***************************************************
2025-12-05 11:14:07.785273 | controller | Friday 05 December 2025  11:14:07 +0000 (0:00:01.019)       0:00:01.035 *******
2025-12-05 11:14:07.785287 | controller | ok: [instance]
2025-12-05 11:14:07.785360 | controller |
2025-12-05 11:14:07.785394 | controller | TASK [Fetch files stat results] ************************************************
2025-12-05 11:14:07.785407 | controller | Friday 05 December 2025  11:14:07 +0000 (0:00:00.026)       0:00:01.062 *******
2025-12-05 11:14:08.149991 | controller | ok: [instance]
2025-12-05 11:14:08.150048 | controller |
2025-12-05 11:14:08.150061 | controller | TASK [Assert that expected file exist] *****************************************
2025-12-05 11:14:08.150144 | controller | Friday 05 December 2025  11:14:08 +0000 (0:00:00.364)       0:00:01.427 *******
2025-12-05 11:14:08.173899 | controller | ok: [instance] => changed=false
2025-12-05 11:14:08.173975 | controller |   msg: All assertions passed
2025-12-05 11:14:08.174055 | controller |
2025-12-05 11:14:08.174169 | controller | TASK [Fetch file content] ******************************************************
2025-12-05 11:14:08.174274 | controller | Friday 05 December 2025  11:14:08 +0000 (0:00:00.024)       0:00:01.451 *******
2025-12-05 11:14:08.496471 | controller | ok: [instance]
2025-12-05 11:14:08.496556 | controller |
2025-12-05 11:14:08.496693 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-12-05 11:14:08.496802 | controller | Friday 05 December 2025  11:14:08 +0000 (0:00:00.322)       0:00:01.773 *******
2025-12-05 11:14:08.535423 | controller | ok: [instance]
2025-12-05 11:14:08.535488 | controller |
2025-12-05 11:14:08.535590 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-12-05 11:14:08.535702 | controller | Friday 05 December 2025  11:14:08 +0000 (0:00:00.038)       0:00:01.812 *******
2025-12-05 11:14:08.591870 | controller | ok: [instance]
2025-12-05 11:14:08.591927 | controller |
2025-12-05 11:14:08.592003 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:14:08.592194 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:14:08.592438 | controller |
2025-12-05 11:14:08.592496 | controller | Friday 05 December 2025  11:14:08 +0000 (0:00:00.056)       0:00:01.869 *******
2025-12-05 11:14:08.592512 | controller | ===============================================================================
2025-12-05 11:14:08.592602 | controller | Gathering Facts --------------------------------------------------------- 1.02s
2025-12-05 11:14:08.592723 | controller | Fetch files stat results ------------------------------------------------ 0.36s
2025-12-05 11:14:08.592882 | controller | Fetch file content ------------------------------------------------------ 0.32s
2025-12-05 11:14:08.592909 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-12-05 11:14:08.593045 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-12-05 11:14:08.593170 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-12-05 11:14:08.593279 | controller | Assert that expected file exist ----------------------------------------- 0.02s
2025-12-05 11:14:08.673843 | controller | INFO     Verifier completed successfully.
2025-12-05 11:14:08.683176 | controller | INFO     Running local_ipv6 > side_effect
2025-12-05 11:14:09.225862 | controller |
2025-12-05 11:14:09.225978 | controller | PLAY [Clean] *******************************************************************
2025-12-05 11:14:09.226050 | controller |
2025-12-05 11:14:09.226204 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:14:09.226322 | controller | Friday 05 December 2025  11:14:09 +0000 (0:00:00.017)       0:00:00.017 *******
2025-12-05 11:14:10.274124 | controller | ok: [instance]
2025-12-05 11:14:10.274213 | controller |
2025-12-05 11:14:10.274426 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-12-05 11:14:10.274715 | controller | Friday 05 December 2025  11:14:10 +0000 (0:00:01.048)       0:00:01.065 *******
2025-12-05 11:14:10.669349 | controller | changed: [instance]
2025-12-05 11:14:10.697106 | controller |
2025-12-05 11:14:10.697152 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-12-05 11:14:10.697161 | controller | Friday 05 December 2025  11:14:10 +0000 (0:00:00.394)       0:00:01.459 *******
2025-12-05 11:14:10.697174 | controller | skipping: [instance]
2025-12-05 11:14:10.697316 | controller |
2025-12-05 11:14:10.697352 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-12-05 11:14:10.730828 | controller | Friday 05 December 2025  11:14:10 +0000 (0:00:00.028)       0:00:01.488 *******
2025-12-05 11:14:10.730879 | controller | skipping: [instance]
2025-12-05 11:14:11.119999 | controller |
2025-12-05 11:14:11.120077 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-12-05 11:14:11.120088 | controller | Friday 05 December 2025  11:14:10 +0000 (0:00:00.033)       0:00:01.522 *******
2025-12-05 11:14:11.120109 | controller | changed: [instance]
2025-12-05 11:14:11.120161 | controller |
2025-12-05 11:14:11.120175 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:14:11.120356 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-12-05 11:14:11.120374 | controller |
2025-12-05 11:14:11.120597 | controller | Friday 05 December 2025  11:14:11 +0000 (0:00:00.389)       0:00:01.911 *******
2025-12-05 11:14:11.120634 | controller | ===============================================================================
2025-12-05 11:14:11.120729 | controller | Gathering Facts --------------------------------------------------------- 1.05s
2025-12-05 11:14:11.120739 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.39s
2025-12-05 11:14:11.120830 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.39s
2025-12-05 11:14:11.120957 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.03s
2025-12-05 11:14:11.121046 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s
2025-12-05 11:14:11.207933 | controller | INFO     Running local_ipv6 > verify
2025-12-05 11:14:11.208299 | controller | INFO     Running Ansible Verifier
2025-12-05 11:14:11.746001 | controller |
2025-12-05 11:14:11.746082 | controller | PLAY [Verify Clean] ************************************************************
2025-12-05 11:14:11.746170 | controller |
2025-12-05 11:14:11.746251 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 11:14:11.746355 | controller | Friday 05 December 2025  11:14:11 +0000 (0:00:00.019)       0:00:00.019 *******
2025-12-05 11:14:12.744011 | controller | ok: [instance]
2025-12-05 11:14:12.744105 | controller |
2025-12-05 11:14:12.744239 | controller | TASK [Include default vars] ****************************************************
2025-12-05 11:14:12.744364 | controller | Friday 05 December 2025  11:14:12 +0000 (0:00:00.997)       0:00:01.017 *******
2025-12-05 11:14:12.774055 | controller | ok: [instance]
2025-12-05 11:14:12.774148 | controller |
2025-12-05 11:14:12.774327 | controller | TASK [Fetch file stat results] *************************************************
2025-12-05 11:14:12.774479 | controller | Friday 05 December 2025  11:14:12 +0000 (0:00:00.029)       0:00:01.047 *******
2025-12-05 11:14:13.123128 | controller | ok: [instance]
2025-12-05 11:14:13.123269 | controller |
2025-12-05 11:14:13.123695 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-12-05 11:14:13.167514 | controller | Friday 05 December 2025  11:14:13 +0000 (0:00:00.349)       0:00:01.396 *******
2025-12-05 11:14:13.167563 | controller | ok: [instance] => changed=false
2025-12-05 11:14:13.167766 | controller |   msg: All assertions passed
2025-12-05 11:14:13.167785 | controller |
2025-12-05 11:14:13.167932 | controller | PLAY RECAP *********************************************************************
2025-12-05 11:14:13.168111 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 11:14:13.168247 | controller |
2025-12-05 11:14:13.168406 | controller | Friday 05 December 2025  11:14:13 +0000 (0:00:00.044)       0:00:01.441 *******
2025-12-05 11:14:13.168561 | controller | ===============================================================================
2025-12-05 11:14:13.168712 | controller | Gathering Facts --------------------------------------------------------- 1.00s
2025-12-05 11:14:13.168807 | controller | Fetch file stat results ------------------------------------------------- 0.35s
2025-12-05 11:14:13.168962 | controller | Assert that created file are removed by cleanup ------------------------- 0.04s
2025-12-05 11:14:13.169116 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-12-05 11:14:13.237378 | controller | INFO     Verifier completed successfully.
2025-12-05 11:14:13.237778 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-05 11:14:13.239907 | controller | INFO     Writing /tmp/report.html report.
2025-12-05 11:14:13.876231 | controller | changed
2025-12-05 11:14:13.902823 | 
2025-12-05 11:14:13.902885 | PLAY RECAP
2025-12-05 11:14:13.902926 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 11:14:13.902946 | 
2025-12-05 11:14:14.010097 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-05 11:14:14.010984 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 11:14:14.698885 | 
2025-12-05 11:14:14.699071 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 11:14:14.723851 | 
2025-12-05 11:14:14.724038 | TASK [Filter out host if needed]
2025-12-05 11:14:14.734291 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 11:14:14.739267 | 
2025-12-05 11:14:14.739336 | TASK [Ensure file is present]
2025-12-05 11:14:15.281835 | controller | ok
2025-12-05 11:14:15.303321 | 
2025-12-05 11:14:15.303618 | TASK [Manage molecule report file]
2025-12-05 11:14:16.356128 | controller | changed
2025-12-05 11:14:16.368112 | 
2025-12-05 11:14:16.368269 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 11:14:16.726771 | controller | ok
2025-12-05 11:14:16.739756 | 
2025-12-05 11:14:16.739890 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 11:14:17.286720 | controller | changed
2025-12-05 11:14:17.299608 | 
2025-12-05 11:14:17.299784 | TASK [Copy ci-framework interesting files]
2025-12-05 11:14:17.671194 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 11:14:17.843959 | controller | changed
2025-12-05 11:14:17.849849 | 
2025-12-05 11:14:17.849917 | TASK [Get SELinux listing]
2025-12-05 11:14:18.379400 | controller | changed
2025-12-05 11:14:18.391846 | 
2025-12-05 11:14:18.391987 | TASK [Generate log index]
2025-12-05 11:14:20.237233 | controller | changed
2025-12-05 11:14:20.248785 | 
2025-12-05 11:14:20.248919 | TASK [Get some env related data]
2025-12-05 11:14:21.194088 | controller | /home/zuul/.local/bin/ansible
2025-12-05 11:14:22.305699 | controller | changed
2025-12-05 11:14:22.317083 | 
2025-12-05 11:14:22.317213 | TASK [Generate list of logs to collect in home directory]
2025-12-05 11:14:22.795290 | controller | ok: All paths examined
2025-12-05 11:14:22.808368 | 
2025-12-05 11:14:22.808505 | LOOP [Copy logs from home directory]
2025-12-05 11:14:23.760036 | controller | changed:
2025-12-05 11:14:23.760314 | controller | {
2025-12-05 11:14:23.760350 | controller |   "atime": 1763989167.9348345,
2025-12-05 11:14:23.760375 | controller |   "ctime": 1763990251.6129293,
2025-12-05 11:14:23.760398 | controller |   "dev": 64513,
2025-12-05 11:14:23.760421 | controller |   "gid": 1000,
2025-12-05 11:14:23.760443 | controller |   "gr_name": "zuul",
2025-12-05 11:14:23.760467 | controller |   "inode": 5131423,
2025-12-05 11:14:23.760490 | controller |   "isblk": false,
2025-12-05 11:14:23.760512 | controller |   "ischr": false,
2025-12-05 11:14:23.760533 | controller |   "isdir": false,
2025-12-05 11:14:23.760554 | controller |   "isfifo": false,
2025-12-05 11:14:23.760576 | controller |   "isgid": false,
2025-12-05 11:14:23.760610 | controller |   "islnk": false,
2025-12-05 11:14:23.760635 | controller |   "isreg": true,
2025-12-05 11:14:23.760656 | controller |   "issock": false,
2025-12-05 11:14:23.760716 | controller |   "isuid": false,
2025-12-05 11:14:23.760741 | controller |   "mode": "0644",
2025-12-05 11:14:23.760762 | controller |   "mtime": 1763990251.6129293,
2025-12-05 11:14:23.760783 | controller |   "nlink": 1,
2025-12-05 11:14:23.760805 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-05 11:14:23.760830 | controller |   "pw_name": "zuul",
2025-12-05 11:14:23.760853 | controller |   "rgrp": true,
2025-12-05 11:14:23.760876 | controller |   "roth": true,
2025-12-05 11:14:23.760898 | controller |   "rusr": true,
2025-12-05 11:14:23.760920 | controller |   "size": 4108,
2025-12-05 11:14:23.760943 | controller |   "uid": 1000,
2025-12-05 11:14:23.760965 | controller |   "wgrp": false,
2025-12-05 11:14:23.760986 | controller |   "woth": false,
2025-12-05 11:14:23.761010 | controller |   "wusr": true,
2025-12-05 11:14:23.761033 | controller |   "xgrp": false,
2025-12-05 11:14:23.761054 | controller |   "xoth": false,
2025-12-05 11:14:23.761075 | controller |   "xusr": false
2025-12-05 11:14:23.761096 | controller | }
2025-12-05 11:14:24.756698 | controller | changed:
2025-12-05 11:14:24.756876 | controller | {
2025-12-05 11:14:24.756927 | controller |   "atime": 1764932608.1539917,
2025-12-05 11:14:24.756967 | controller |   "ctime": 1764932605.8049297,
2025-12-05 11:14:24.757005 | controller |   "dev": 64513,
2025-12-05 11:14:24.757039 | controller |   "gid": 1000,
2025-12-05 11:14:24.757073 | controller |   "gr_name": "zuul",
2025-12-05 11:14:24.757106 | controller |   "inode": 5131425,
2025-12-05 11:14:24.757139 | controller |   "isblk": false,
2025-12-05 11:14:24.757171 | controller |   "ischr": false,
2025-12-05 11:14:24.757204 | controller |   "isdir": false,
2025-12-05 11:14:24.757236 | controller |   "isfifo": false,
2025-12-05 11:14:24.757268 | controller |   "isgid": false,
2025-12-05 11:14:24.757300 | controller |   "islnk": false,
2025-12-05 11:14:24.757332 | controller |   "isreg": true,
2025-12-05 11:14:24.757365 | controller |   "issock": false,
2025-12-05 11:14:24.757396 | controller |   "isuid": false,
2025-12-05 11:14:24.757429 | controller |   "mode": "0644",
2025-12-05 11:14:24.757461 | controller |   "mtime": 1764932605.8049297,
2025-12-05 11:14:24.757493 | controller |   "nlink": 1,
2025-12-05 11:14:24.757526 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-05 11:14:24.757559 | controller |   "pw_name": "zuul",
2025-12-05 11:14:24.757592 | controller |   "rgrp": true,
2025-12-05 11:14:24.757625 | controller |   "roth": true,
2025-12-05 11:14:24.757658 | controller |   "rusr": true,
2025-12-05 11:14:24.757744 | controller |   "size": 4262,
2025-12-05 11:14:24.757803 | controller |   "uid": 1000,
2025-12-05 11:14:24.757839 | controller |   "wgrp": false,
2025-12-05 11:14:24.757872 | controller |   "woth": false,
2025-12-05 11:14:24.757904 | controller |   "wusr": true,
2025-12-05 11:14:24.757937 | controller |   "xgrp": false,
2025-12-05 11:14:24.757969 | controller |   "xoth": false,
2025-12-05 11:14:24.758003 | controller |   "xusr": false
2025-12-05 11:14:24.758100 | controller | }
2025-12-05 11:14:25.608693 | controller | changed:
2025-12-05 11:14:25.608846 | controller | {
2025-12-05 11:14:25.608897 | controller |   "atime": 1764933156.3834715,
2025-12-05 11:14:25.608936 | controller |   "ctime": 1764933185.5022426,
2025-12-05 11:14:25.608972 | controller |   "dev": 64513,
2025-12-05 11:14:25.609007 | controller |   "gid": 1000,
2025-12-05 11:14:25.609041 | controller |   "gr_name": "zuul",
2025-12-05 11:14:25.609074 | controller |   "inode": 4751296,
2025-12-05 11:14:25.609107 | controller |   "isblk": false,
2025-12-05 11:14:25.609139 | controller |   "ischr": false,
2025-12-05 11:14:25.609171 | controller |   "isdir": false,
2025-12-05 11:14:25.609202 | controller |   "isfifo": false,
2025-12-05 11:14:25.609232 | controller |   "isgid": false,
2025-12-05 11:14:25.609264 | controller |   "islnk": false,
2025-12-05 11:14:25.609295 | controller |   "isreg": true,
2025-12-05 11:14:25.609327 | controller |   "issock": false,
2025-12-05 11:14:25.609359 | controller |   "isuid": false,
2025-12-05 11:14:25.609391 | controller |   "mode": "0644",
2025-12-05 11:14:25.609423 | controller |   "mtime": 1764933185.5022426,
2025-12-05 11:14:25.609454 | controller |   "nlink": 1,
2025-12-05 11:14:25.609486 | controller |   "path": "/home/zuul/ansible.log",
2025-12-05 11:14:25.609521 | controller |   "pw_name": "zuul",
2025-12-05 11:14:25.609556 | controller |   "rgrp": true,
2025-12-05 11:14:25.609590 | controller |   "roth": true,
2025-12-05 11:14:25.609629 | controller |   "rusr": true,
2025-12-05 11:14:25.609695 | controller |   "size": 6749,
2025-12-05 11:14:25.609741 | controller |   "uid": 1000,
2025-12-05 11:14:25.609776 | controller |   "wgrp": false,
2025-12-05 11:14:25.609809 | controller |   "woth": false,
2025-12-05 11:14:25.609841 | controller |   "wusr": true,
2025-12-05 11:14:25.609873 | controller |   "xgrp": false,
2025-12-05 11:14:25.609904 | controller |   "xoth": false,
2025-12-05 11:14:25.609935 | controller |   "xusr": false
2025-12-05 11:14:25.609967 | controller | }
2025-12-05 11:14:25.626113 | 
2025-12-05 11:14:25.626244 | TASK [Copy crio stats log file]
2025-12-05 11:14:25.655649 | controller | skipping: Conditional result was False
2025-12-05 11:14:25.670025 | 
2025-12-05 11:14:25.670157 | TASK [Get SELinux related data]
2025-12-05 11:14:26.071165 | controller | <no matches>
2025-12-05 11:14:26.215401 | controller | ERROR
2025-12-05 11:14:26.215608 | controller | {
2025-12-05 11:14:26.215748 | controller |   "delta": "0:00:00.013011",
2025-12-05 11:14:26.215802 | controller |   "end": "2025-12-05 11:14:26.072600",
2025-12-05 11:14:26.215842 | controller |   "msg": "non-zero return code",
2025-12-05 11:14:26.215879 | controller |   "rc": 1,
2025-12-05 11:14:26.215914 | controller |   "start": "2025-12-05 11:14:26.059589"
2025-12-05 11:14:26.215949 | controller | }
2025-12-05 11:14:26.215995 | controller | ERROR: Ignoring Errors
2025-12-05 11:14:26.228174 | 
2025-12-05 11:14:26.228302 | TASK [Create system configuration directory]
2025-12-05 11:14:26.616072 | controller | changed
2025-12-05 11:14:26.627306 | 
2025-12-05 11:14:26.627439 | TASK [Get some of the system configurations]
2025-12-05 11:14:27.171102 | controller | changed
2025-12-05 11:14:27.182878 | 
2025-12-05 11:14:27.183011 | TASK [Copy generated documentation if available]
2025-12-05 11:14:27.220817 | controller | skipping: Conditional result was False
2025-12-05 11:14:27.230511 | 
2025-12-05 11:14:27.230709 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 11:14:27.256581 | controller | skipping: Conditional result was False
2025-12-05 11:14:27.265822 | 
2025-12-05 11:14:27.265906 | TASK [Compress logs bigger than 2MB]
2025-12-05 11:14:27.805156 | controller | changed
2025-12-05 11:14:27.826418 | 
2025-12-05 11:14:27.826823 | TASK [Copy files from workspace on node]
2025-12-05 11:14:27.854136 | controller | ok
2025-12-05 11:14:27.891261 | 
2025-12-05 11:14:27.891463 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 11:14:27.928277 | controller | skipping: Conditional result was False
2025-12-05 11:14:27.934871 | 
2025-12-05 11:14:27.934940 | TASK [fetch-output : Set log path for single node]
2025-12-05 11:14:27.978569 | controller | ok
2025-12-05 11:14:27.983952 | 
2025-12-05 11:14:27.984018 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 11:14:28.215722 | controller -> localhost | ok: "/var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/logs"
2025-12-05 11:14:28.216000 | controller -> localhost | changed: All items complete
2025-12-05 11:14:28.216026 | 
2025-12-05 11:14:28.433503 | controller -> localhost | changed: "/var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/artifacts"
2025-12-05 11:14:28.680300 | controller -> localhost | changed: "/var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/docs"
2025-12-05 11:14:28.704548 | 
2025-12-05 11:14:28.704897 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 11:14:31.626812 | controller | changed:
2025-12-05 11:14:31.627244 | controller | .d..t...... ./
2025-12-05 11:14:31.627308 | controller | >f+++++++++ README.html
2025-12-05 11:14:31.627352 | controller | >f+++++++++ ansible-execution.log
2025-12-05 11:14:31.627393 | controller | >f+++++++++ ansible.log
2025-12-05 11:14:31.627432 | controller | >f+++++++++ crc-setup.log
2025-12-05 11:14:31.627469 | controller | >f+++++++++ crc-start.log
2025-12-05 11:14:31.627505 | controller | >f+++++++++ dmesg.log
2025-12-05 11:14:31.627547 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 11:14:31.627585 | controller | >f+++++++++ python.log
2025-12-05 11:14:31.627622 | controller | >f+++++++++ registries.conf
2025-12-05 11:14:31.627657 | controller | >f+++++++++ report.html
2025-12-05 11:14:31.627737 | controller | >f+++++++++ selinux-denials.log
2025-12-05 11:14:31.627775 | controller | >f+++++++++ selinux-listing.log
2025-12-05 11:14:31.627810 | controller | cd+++++++++ ci-framework-data/
2025-12-05 11:14:31.627845 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 11:14:31.627880 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 11:14:31.627915 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-12-05 11:14:31.627950 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/
2025-12-05 11:14:31.627985 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/
2025-12-05 11:14:31.628021 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/ci_multus_nads.yml
2025-12-05 11:14:31.628056 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 11:14:31.628091 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 11:14:31.628126 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 11:14:31.628163 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-05 11:14:31.628199 | controller | cd+++++++++ registries.conf.d/
2025-12-05 11:14:31.628236 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 11:14:31.628272 | controller | cd+++++++++ system-config/
2025-12-05 11:14:31.628307 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 11:14:31.628343 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 11:14:31.628378 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 11:14:31.628413 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-05 11:14:31.628447 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-05 11:14:31.628481 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-05 11:14:31.628515 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-05 11:14:31.628549 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-05 11:14:31.628583 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-05 11:14:31.628618 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-05 11:14:31.628652 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-05 11:14:31.628754 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-05 11:14:31.628797 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-05 11:14:31.628833 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-05 11:14:31.628868 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-05 11:14:31.628903 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-05 11:14:31.628938 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-05 11:14:32.260058 | controller | changed: .d..t...... ./
2025-12-05 11:14:32.859522 | controller | changed: .d..t...... ./
2025-12-05 11:14:32.887508 | 
2025-12-05 11:14:32.887765 | TASK [Return artifact to Zuul]
2025-12-05 11:14:32.946786 | controller | ok
2025-12-05 11:14:32.985544 | 
2025-12-05 11:14:32.985618 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 11:14:32.985787 | 
2025-12-05 11:14:32.985830 | PLAY RECAP
2025-12-05 11:14:32.985886 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-05 11:14:32.985912 | 
2025-12-05 11:14:33.110207 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 11:14:33.112171 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-05 11:14:33.691288 | 
2025-12-05 11:14:33.691397 | PLAY [Report DLRN results after job run]
2025-12-05 11:14:33.711824 | 
2025-12-05 11:14:33.711902 | TASK [Report job status to DLRN]
2025-12-05 11:14:33.732543 | controller | ok
2025-12-05 11:14:33.757643 | 
2025-12-05 11:14:33.757755 | TASK [dlrn_report : Warning if DLRN is not running]
2025-12-05 11:14:33.822709 | controller | skipping: Conditional result was False
2025-12-05 11:14:33.835079 | 
2025-12-05 11:14:33.835224 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-12-05 11:14:33.892539 | controller | skipping: Conditional result was False
2025-12-05 11:14:33.905295 | 
2025-12-05 11:14:33.905449 | TASK [dlrn_report : Install kinit related package]
2025-12-05 11:14:33.963231 | controller | skipping: Conditional result was False
2025-12-05 11:14:33.975793 | 
2025-12-05 11:14:33.975936 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-12-05 11:14:34.033056 | controller | skipping: Conditional result was False
2025-12-05 11:14:34.048035 | 
2025-12-05 11:14:34.048173 | TASK [Set zuul-log-path fact]
2025-12-05 11:14:34.106075 | controller | skipping: Conditional result was False
2025-12-05 11:14:34.118735 | 
2025-12-05 11:14:34.118888 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-12-05 11:14:34.176437 | controller | skipping: Conditional result was False
2025-12-05 11:14:34.188512 | 
2025-12-05 11:14:34.188655 | TASK [Get hash related data from repo_setup role]
2025-12-05 11:14:34.246160 | controller | skipping: Conditional result was False
2025-12-05 11:14:34.259231 | 
2025-12-05 11:14:34.259377 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-12-05 11:14:34.810420 | controller | skipping: Conditional result was False
2025-12-05 11:14:34.823952 | 
2025-12-05 11:14:34.824108 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-12-05 11:14:34.902294 | controller | skipping: Conditional result was False
2025-12-05 11:14:34.914573 | 
2025-12-05 11:14:34.914741 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-12-05 11:14:35.466160 | controller | skipping: Conditional result was False
2025-12-05 11:14:35.518357 | 
2025-12-05 11:14:35.518446 | PLAY RECAP
2025-12-05 11:14:35.518514 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-12-05 11:14:35.518551 | 
2025-12-05 11:14:35.631170 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-05 11:14:35.633468 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 11:14:36.295684 | 
2025-12-05 11:14:36.295881 | PLAY [all]
2025-12-05 11:14:36.316982 | 
2025-12-05 11:14:36.317074 | TASK [include_role : fetch-output]
2025-12-05 11:14:36.369356 | controller | ok
2025-12-05 11:14:36.388544 | 
2025-12-05 11:14:36.388624 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 11:14:36.465454 | controller | skipping: Conditional result was False
2025-12-05 11:14:36.471544 | 
2025-12-05 11:14:36.471613 | TASK [fetch-output : Set log path for single node]
2025-12-05 11:14:36.512934 | controller | ok
2025-12-05 11:14:36.518009 | 
2025-12-05 11:14:36.518074 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 11:14:36.922030 | controller -> localhost | ok: "/var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/logs"
2025-12-05 11:14:37.179377 | controller -> localhost | ok: "/var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/artifacts"
2025-12-05 11:14:37.441843 | controller -> localhost | ok: "/var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/docs"
2025-12-05 11:14:37.456790 | 
2025-12-05 11:14:37.457013 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 11:14:38.247502 | controller | ok
2025-12-05 11:14:38.248110 | controller | ok: All items complete
2025-12-05 11:14:38.248175 | 
2025-12-05 11:14:38.835373 | controller | ok
2025-12-05 11:14:39.442259 | controller | ok
2025-12-05 11:14:39.473196 | 
2025-12-05 11:14:39.473330 | TASK [include_role : fetch-output-openshift]
2025-12-05 11:14:39.500657 | controller | skipping: Conditional result was False
2025-12-05 11:14:39.512750 | 
2025-12-05 11:14:39.512889 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 11:14:39.945064 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011620
2025-12-05 11:14:40.184258 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012549
2025-12-05 11:14:40.221405 | 
2025-12-05 11:14:40.221490 | PLAY [all]
2025-12-05 11:14:40.241562 | 
2025-12-05 11:14:40.241650 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 11:14:40.829983 | controller | changed
2025-12-05 11:14:40.884497 | 
2025-12-05 11:14:40.884590 | PLAY RECAP
2025-12-05 11:14:40.884644 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 11:14:40.884678 | 
2025-12-05 11:14:40.982746 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 11:14:40.983790 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 11:14:41.599873 | 
2025-12-05 11:14:41.600059 | PLAY [localhost]
2025-12-05 11:14:41.617653 | 
2025-12-05 11:14:41.617747 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 11:14:42.062982 | localhost | changed
2025-12-05 11:14:42.073130 | 
2025-12-05 11:14:42.073291 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 11:14:42.108734 | localhost | ok
2025-12-05 11:14:42.126462 | 
2025-12-05 11:14:42.126601 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 11:14:42.573318 | localhost | changed
2025-12-05 11:14:42.584416 | 
2025-12-05 11:14:42.584561 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 11:14:43.387738 | localhost | changed
2025-12-05 11:14:43.399923 | 
2025-12-05 11:14:43.400068 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 11:14:43.849098 | localhost | Identity added: /var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/tmp/ansible.k3q1bsm2 (/var/lib/zuul/builds/7d20eef238424c798b8990421b79b82f/work/tmp/ansible.k3q1bsm2)
2025-12-05 11:14:43.849310 | localhost | ok: Runtime: 0:00:00.019980
2025-12-05 11:14:43.853700 | 
2025-12-05 11:14:43.853765 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 11:14:44.193573 | localhost | ok: Runtime: 0:00:00.009209
2025-12-05 11:14:44.205591 | 
2025-12-05 11:14:44.205773 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 11:14:44.292858 | localhost | changed
2025-12-05 11:14:44.302476 | 
2025-12-05 11:14:44.302618 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 11:14:44.767845 | localhost | changed
2025-12-05 11:14:44.803945 | 
2025-12-05 11:14:44.804048 | PLAY [localhost]
2025-12-05 11:14:44.821020 | 
2025-12-05 11:14:44.821102 | TASK [Generate bulk log download script]
2025-12-05 11:14:44.852238 | localhost | ok
2025-12-05 11:14:44.868999 | 
2025-12-05 11:14:44.869089 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 11:14:44.920669 | localhost | ok: All assertions passed
2025-12-05 11:14:44.926954 | 
2025-12-05 11:14:44.927055 | TASK [local-log-download : Create download script]
2025-12-05 11:14:45.419654 | localhost -> localhost | changed
2025-12-05 11:14:45.432936 | 
2025-12-05 11:14:45.433031 | TASK [Register quick-download link]
2025-12-05 11:14:45.500267 | localhost | ok
2025-12-05 11:14:45.519054 | 
2025-12-05 11:14:45.519113 | PLAY [logserver.rdoproject.org]
2025-12-05 11:14:45.528748 | 
2025-12-05 11:14:45.528807 | TASK [Set zuul-log-path fact]
2025-12-05 11:14:45.548738 | logserver.rdoproject.org | ok
2025-12-05 11:14:45.558973 | 
2025-12-05 11:14:45.559039 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 11:14:45.595941 | logserver.rdoproject.org | ok
2025-12-05 11:14:45.601145 | 
2025-12-05 11:14:45.601210 | TASK [upload-logs : Create log directories]
2025-12-05 11:14:46.467481 | logserver.rdoproject.org | changed
2025-12-05 11:14:46.486070 | 
2025-12-05 11:14:46.486213 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 11:14:46.819970 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009679
2025-12-05 11:14:46.830042 | 
2025-12-05 11:14:46.830179 | TASK [upload-logs : Upload logs to log server]
2025-12-05 11:14:47.639254 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 11:14:47.645474 | 
2025-12-05 11:14:47.645612 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 11:14:47.715413 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:14:47.726460 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:14:47.741270 | 
2025-12-05 11:14:47.741491 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 11:14:47.792041 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:14:47.792510 | 
2025-12-05 11:14:47.794584 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:14:47.806251 | 
2025-12-05 11:14:47.806451 | LOOP [upload-logs : Upload console log and json output]