2025-10-08 08:33:15.447934 | Job console starting...
2025-10-08 08:33:15.462548 | Updating repositories
2025-10-08 08:33:15.540255 | Preparing job workspace
2025-10-08 08:33:27.670322 | Running Ansible setup...
2025-10-08 08:33:33.034663 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 08:33:33.633086 | 
2025-10-08 08:33:33.633347 | PLAY [localhost]
2025-10-08 08:33:33.642972 | 
2025-10-08 08:33:33.643093 | TASK [Gathering Facts]
2025-10-08 08:33:34.677643 | localhost | ok
2025-10-08 08:33:34.707645 | 
2025-10-08 08:33:34.707817 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 08:33:35.433280 | localhost -> localhost | changed
2025-10-08 08:33:35.441235 | 
2025-10-08 08:33:35.441310 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 08:33:36.271153 | localhost -> localhost | changed
2025-10-08 08:33:36.283782 | 
2025-10-08 08:33:36.283913 | TASK [Setup log path fact]
2025-10-08 08:33:36.301147 | localhost | ok
2025-10-08 08:33:36.314011 | 
2025-10-08 08:33:36.314082 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 08:33:36.341710 | localhost | ok
2025-10-08 08:33:36.349362 | 
2025-10-08 08:33:36.349429 | TASK [emit-job-header : Print job information]
2025-10-08 08:33:36.388535 | # Job Information
2025-10-08 08:33:36.388665 | Ansible Version: 2.15.12
2025-10-08 08:33:36.388722 | Job: cifmw-molecule-ci_multus
2025-10-08 08:33:36.388744 | Pipeline: github-check
2025-10-08 08:33:36.388764 | Executor: ze04.softwarefactory-project.io
2025-10-08 08:33:36.388785 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3373
2025-10-08 08:33:36.388804 | Log URL (when completed): https://logserver.rdoproject.org/7d6/rdoproject.org/7d6a307711964f42a9e722de9457ed08/
2025-10-08 08:33:36.388822 | Event ID: 3a070520-a421-11f0-9b5a-2d6f635919d0
2025-10-08 08:33:36.392339 | 
2025-10-08 08:33:36.392400 | LOOP [emit-job-header : Print node information]
2025-10-08 08:33:36.480412 | localhost | ok:
2025-10-08 08:33:36.480602 | localhost | # Node Information
2025-10-08 08:33:36.480630 | localhost | Inventory Hostname: controller
2025-10-08 08:33:36.480652 | localhost | Hostname: np0005475438
2025-10-08 08:33:36.480671 | localhost | Username: zuul
2025-10-08 08:33:36.480713 | localhost | Distro: CentOS 9
2025-10-08 08:33:36.480733 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-08 08:33:36.480750 | localhost | Region: RegionOne
2025-10-08 08:33:36.480766 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-10-08 08:33:36.480782 | localhost | Product Name: OpenStack Nova
2025-10-08 08:33:36.480798 | localhost | Interface IP: 38.102.83.214
2025-10-08 08:33:36.505316 | 
2025-10-08 08:33:36.505450 | PLAY [all]
2025-10-08 08:33:36.511779 | 
2025-10-08 08:33:36.511845 | TASK [Gather network facts]
2025-10-08 08:33:37.136149 | controller | ok
2025-10-08 08:33:37.168825 | 
2025-10-08 08:33:37.168929 | TASK [include_role : start-zuul-console]
2025-10-08 08:33:37.216178 | controller | ok
2025-10-08 08:33:37.237610 | 
2025-10-08 08:33:37.237740 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 08:33:37.753776 | controller | ok
2025-10-08 08:33:37.768562 | 
2025-10-08 08:33:37.768709 | TASK [include_role : add-build-sshkey]
2025-10-08 08:33:37.788970 | controller | ok
2025-10-08 08:33:37.805732 | 
2025-10-08 08:33:37.805826 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 08:33:38.140502 | controller -> localhost | ok
2025-10-08 08:33:38.146188 | 
2025-10-08 08:33:38.146251 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 08:33:38.194878 | controller | ok
2025-10-08 08:33:38.221214 | controller | included: /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 08:33:38.230417 | 
2025-10-08 08:33:38.230507 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 08:33:39.211920 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 08:33:39.212133 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/7d6a307711964f42a9e722de9457ed08_id_rsa.
2025-10-08 08:33:39.212164 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/7d6a307711964f42a9e722de9457ed08_id_rsa.pub.
2025-10-08 08:33:39.212188 | controller -> localhost | The key fingerprint is:
2025-10-08 08:33:39.212213 | controller -> localhost | SHA256:VUO2JkTvtL39o9xVF9S1EPx8HMuNIJAi0PfjKwfS3FY zuul-build-sshkey
2025-10-08 08:33:39.212240 | controller -> localhost | The key's randomart image is:
2025-10-08 08:33:39.212264 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 08:33:39.212285 | controller -> localhost | |  .o    .+o.*o..+|
2025-10-08 08:33:39.212304 | controller -> localhost | |    o o ...+.+o.o|
2025-10-08 08:33:39.212322 | controller -> localhost | |     o o  o.=.+=+|
2025-10-08 08:33:39.212339 | controller -> localhost | |        o.E= o.==|
2025-10-08 08:33:39.212357 | controller -> localhost | |     o oSo  o . +|
2025-10-08 08:33:39.212374 | controller -> localhost | |    . + +      oo|
2025-10-08 08:33:39.212409 | controller -> localhost | |     . o .    . o|
2025-10-08 08:33:39.212439 | controller -> localhost | |      . o   . ..o|
2025-10-08 08:33:39.212457 | controller -> localhost | |       o     o..o|
2025-10-08 08:33:39.212473 | controller -> localhost | +----[SHA256]-----+
2025-10-08 08:33:39.212514 | controller -> localhost | ok: Runtime: 0:00:00.186971
2025-10-08 08:33:39.218459 | 
2025-10-08 08:33:39.218524 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 08:33:39.248559 | controller | ok
2025-10-08 08:33:39.267755 | controller | included: /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 08:33:39.287696 | 
2025-10-08 08:33:39.287811 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 08:33:39.334615 | controller | skipping: Conditional result was False
2025-10-08 08:33:39.340711 | 
2025-10-08 08:33:39.340781 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 08:33:40.052935 | controller | changed
2025-10-08 08:33:40.057971 | 
2025-10-08 08:33:40.058039 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 08:33:40.517405 | controller | ok
2025-10-08 08:33:40.534053 | 
2025-10-08 08:33:40.534139 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 08:33:42.546221 | controller | changed
2025-10-08 08:33:42.551411 | 
2025-10-08 08:33:42.551477 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 08:33:44.479569 | controller | changed
2025-10-08 08:33:44.486021 | 
2025-10-08 08:33:44.486259 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 08:33:44.520727 | controller | skipping: Conditional result was False
2025-10-08 08:33:44.527888 | 
2025-10-08 08:33:44.527968 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 08:33:45.219459 | controller -> localhost | changed
2025-10-08 08:33:45.231160 | 
2025-10-08 08:33:45.231236 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 08:33:45.892056 | controller -> localhost | Identity added: /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/7d6a307711964f42a9e722de9457ed08_id_rsa (zuul-build-sshkey)
2025-10-08 08:33:45.892277 | controller -> localhost | ok: Runtime: 0:00:00.036012
2025-10-08 08:33:45.901901 | 
2025-10-08 08:33:45.901973 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 08:33:46.683197 | controller | ok
2025-10-08 08:33:46.687630 | 
2025-10-08 08:33:46.687726 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 08:33:46.711401 | controller | skipping: Conditional result was False
2025-10-08 08:33:46.720670 | 
2025-10-08 08:33:46.720757 | TASK [include_role : validate-host]
2025-10-08 08:33:46.767457 | controller | ok
2025-10-08 08:33:46.818662 | 
2025-10-08 08:33:46.818811 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 08:33:46.886540 | controller | ok
2025-10-08 08:33:46.891652 | 
2025-10-08 08:33:46.897622 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 08:33:47.322739 | controller -> localhost | ok
2025-10-08 08:33:47.331303 | 
2025-10-08 08:33:47.331409 | TASK [validate-host : Collect information about the host]
2025-10-08 08:33:48.265922 | controller | ok
2025-10-08 08:33:48.279395 | 
2025-10-08 08:33:48.279566 | TASK [validate-host : Sanitize hostname]
2025-10-08 08:33:48.336643 | controller | ok
2025-10-08 08:33:48.342266 | 
2025-10-08 08:33:48.342328 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 08:33:48.895095 | controller -> localhost | changed
2025-10-08 08:33:48.902711 | 
2025-10-08 08:33:48.902803 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 08:33:49.505482 | controller | ok
2025-10-08 08:33:49.510065 | 
2025-10-08 08:33:49.510126 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 08:33:50.098315 | controller -> localhost | changed
2025-10-08 08:33:50.110081 | 
2025-10-08 08:33:50.110164 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 08:33:50.134993 | controller | skipping: Conditional result was False
2025-10-08 08:33:50.140143 | 
2025-10-08 08:33:50.140206 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 08:33:50.174300 | controller | skipping: Conditional result was False
2025-10-08 08:33:50.180647 | 
2025-10-08 08:33:50.180739 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 08:33:50.577654 | controller | ok: "logs"
2025-10-08 08:33:50.578740 | controller | ok: All items complete
2025-10-08 08:33:50.578775 | 
2025-10-08 08:33:51.002431 | controller | ok: "artifacts"
2025-10-08 08:33:51.355853 | controller | ok: "docs"
2025-10-08 08:33:51.372263 | 
2025-10-08 08:33:51.372379 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 08:33:51.800855 | controller | changed: "logs"
2025-10-08 08:33:52.143962 | controller | changed: "artifacts"
2025-10-08 08:33:52.481804 | controller | changed: "docs"
2025-10-08 08:33:52.514865 | 
2025-10-08 08:33:52.514938 | PLAY RECAP
2025-10-08 08:33:52.514978 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 08:33:52.515004 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 08:33:52.515021 | 
2025-10-08 08:33:52.644738 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 08:33:52.645458 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 08:33:53.298304 | 
2025-10-08 08:33:53.298418 | PLAY [all]
2025-10-08 08:33:53.324414 | 
2025-10-08 08:33:53.324526 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 08:33:53.395584 | controller | ok
2025-10-08 08:33:53.403233 | 
2025-10-08 08:33:53.403391 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 08:33:54.021197 | controller | changed
2025-10-08 08:33:54.027367 | 
2025-10-08 08:33:54.027439 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 08:33:55.977252 | controller | changed
2025-10-08 08:33:55.987364 | 
2025-10-08 08:33:55.987430 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 08:33:56.578857 | controller | changed:
2025-10-08 08:33:56.581034 | controller | {
2025-10-08 08:33:56.581142 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 08:33:56.581213 | controller | }
2025-10-08 08:33:57.007544 | controller | changed:
2025-10-08 08:33:57.007811 | controller | {
2025-10-08 08:33:57.007879 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 08:33:57.007928 | controller | }
2025-10-08 08:33:57.474481 | controller | changed:
2025-10-08 08:33:57.474979 | controller | {
2025-10-08 08:33:57.475088 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 08:33:57.475136 | controller | }
2025-10-08 08:33:57.949570 | controller | changed:
2025-10-08 08:33:57.949836 | controller | {
2025-10-08 08:33:57.949892 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 08:33:57.949935 | controller | }
2025-10-08 08:33:58.405342 | controller | changed:
2025-10-08 08:33:58.405754 | controller | {
2025-10-08 08:33:58.405827 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 08:33:58.405871 | controller | }
2025-10-08 08:33:58.809391 | controller | changed:
2025-10-08 08:33:58.809551 | controller | {
2025-10-08 08:33:58.809607 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 08:33:58.809649 | controller | }
2025-10-08 08:33:59.253377 | controller | changed:
2025-10-08 08:33:59.253571 | controller | {
2025-10-08 08:33:59.253625 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-08 08:33:59.253668 | controller | }
2025-10-08 08:33:59.688573 | controller | changed:
2025-10-08 08:33:59.688737 | controller | {
2025-10-08 08:33:59.688767 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-08 08:33:59.688788 | controller | }
2025-10-08 08:34:00.127168 | controller | changed:
2025-10-08 08:34:00.127308 | controller | {
2025-10-08 08:34:00.127337 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 08:34:00.127358 | controller | }
2025-10-08 08:34:00.586987 | controller | changed:
2025-10-08 08:34:00.587065 | controller | {
2025-10-08 08:34:00.587089 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 08:34:00.587109 | controller | }
2025-10-08 08:34:01.000136 | controller | changed:
2025-10-08 08:34:01.000214 | controller | {
2025-10-08 08:34:01.000237 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 08:34:01.000360 | controller | }
2025-10-08 08:34:01.471674 | controller | changed:
2025-10-08 08:34:01.471970 | controller | {
2025-10-08 08:34:01.472003 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 08:34:01.472025 | controller | }
2025-10-08 08:34:01.904421 | controller | changed:
2025-10-08 08:34:01.904517 | controller | {
2025-10-08 08:34:01.904541 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 08:34:01.904561 | controller | }
2025-10-08 08:34:02.319350 | controller | changed:
2025-10-08 08:34:02.319456 | controller | {
2025-10-08 08:34:02.319489 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 08:34:02.319516 | controller | }
2025-10-08 08:34:02.784389 | controller | changed:
2025-10-08 08:34:02.784501 | controller | {
2025-10-08 08:34:02.784558 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 08:34:02.784588 | controller | }
2025-10-08 08:34:03.235258 | controller | changed:
2025-10-08 08:34:03.235398 | controller | {
2025-10-08 08:34:03.235451 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 08:34:03.235491 | controller | }
2025-10-08 08:34:03.646667 | controller | changed:
2025-10-08 08:34:03.646779 | controller | {
2025-10-08 08:34:03.646803 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-08 08:34:03.646823 | controller | }
2025-10-08 08:34:04.097690 | controller | changed:
2025-10-08 08:34:04.097904 | controller | {
2025-10-08 08:34:04.097936 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-08 08:34:04.097956 | controller | }
2025-10-08 08:34:04.574211 | controller | changed:
2025-10-08 08:34:04.574314 | controller | {
2025-10-08 08:34:04.574338 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 08:34:04.574356 | controller | }
2025-10-08 08:34:05.031326 | controller | changed:
2025-10-08 08:34:05.031424 | controller | {
2025-10-08 08:34:05.031447 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 08:34:05.031467 | controller | }
2025-10-08 08:34:05.473733 | controller | changed:
2025-10-08 08:34:05.473849 | controller | {
2025-10-08 08:34:05.473874 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 08:34:05.473893 | controller | }
2025-10-08 08:34:05.879795 | controller | changed:
2025-10-08 08:34:05.879883 | controller | {
2025-10-08 08:34:05.879906 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 08:34:05.879925 | controller | }
2025-10-08 08:34:06.272183 | controller | changed:
2025-10-08 08:34:06.272315 | controller | {
2025-10-08 08:34:06.272341 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-08 08:34:06.272383 | controller | }
2025-10-08 08:34:06.707886 | controller | changed:
2025-10-08 08:34:06.707978 | controller | {
2025-10-08 08:34:06.708003 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 08:34:06.708021 | controller | }
2025-10-08 08:34:07.104571 | controller | changed:
2025-10-08 08:34:07.104874 | controller | {
2025-10-08 08:34:07.104924 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 08:34:07.104952 | controller | }
2025-10-08 08:34:07.524581 | controller | changed:
2025-10-08 08:34:07.524667 | controller | {
2025-10-08 08:34:07.524708 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 08:34:07.524728 | controller | }
2025-10-08 08:34:07.543592 | 
2025-10-08 08:34:07.543665 | TASK [Set timezone to UTC]
2025-10-08 08:34:08.288223 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 08:34:08.293187 | 
2025-10-08 08:34:08.293248 | TASK [Create nodepool directory]
2025-10-08 08:34:08.710956 | controller | changed
2025-10-08 08:34:08.718038 | 
2025-10-08 08:34:08.718106 | TASK [Create nodepool sub_nodes file]
2025-10-08 08:34:10.310885 | controller | changed
2025-10-08 08:34:10.316359 | 
2025-10-08 08:34:10.316423 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 08:34:12.064909 | controller | changed
2025-10-08 08:34:12.083260 | 
2025-10-08 08:34:12.083393 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 08:34:12.143497 | 
2025-10-08 08:34:12.143654 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 08:34:12.195917 | 
2025-10-08 08:34:12.196057 | TASK [Create nodepool primary file]
2025-10-08 08:34:12.234698 | controller | skipping: Conditional result was False
2025-10-08 08:34:12.240300 | 
2025-10-08 08:34:12.240376 | TASK [Create nodepool node_private for this node]
2025-10-08 08:34:13.870624 | controller | changed
2025-10-08 08:34:13.883824 | 
2025-10-08 08:34:13.883960 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 08:34:14.496531 | controller | ok: Item: id_rsa Runtime: 0:00:00.008803
2025-10-08 08:34:14.496797 | 
2025-10-08 08:34:14.822840 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.008109
2025-10-08 08:34:14.844536 | 
2025-10-08 08:34:14.844743 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 08:34:16.510396 | controller | changed
2025-10-08 08:34:16.517787 | 
2025-10-08 08:34:16.517870 | TASK [Validate sudoers config after edits]
2025-10-08 08:34:16.951840 | controller | /etc/sudoers: parsed OK
2025-10-08 08:34:16.952090 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 08:34:16.952124 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 08:34:16.952140 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 08:34:17.066400 | controller | ok: Runtime: 0:00:00.011684
2025-10-08 08:34:17.080094 | 
2025-10-08 08:34:17.080269 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 08:34:17.512074 | controller | SHELL=/bin/bash
2025-10-08 08:34:17.512151 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 08:34:17.512171 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 08:34:17.512184 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/73/3373/1a7b2ae378ead444d1ed756aace4ec009d749f1a
2025-10-08 08:34:17.512241 | controller | PWD=/home/zuul
2025-10-08 08:34:17.512256 | controller | ZUUL_PIPELINE=github-check
2025-10-08 08:34:17.512269 | controller | LOGNAME=zuul
2025-10-08 08:34:17.512282 | controller | XDG_SESSION_TYPE=tty
2025-10-08 08:34:17.512295 | controller | _=/usr/bin/env
2025-10-08 08:34:17.512308 | controller | MOTD_SHOWN=pam
2025-10-08 08:34:17.512321 | controller | HOME=/home/zuul
2025-10-08 08:34:17.512334 | controller | LANG=en_US.UTF-8
2025-10-08 08:34:17.512347 | controller | SSH_CONNECTION=38.102.83.114 39820 38.102.83.214 22
2025-10-08 08:34:17.512360 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-08 08:34:17.512379 | controller | ZUUL_CHANGE_IDS=3373,1a7b2ae378ead444d1ed756aace4ec009d749f1a
2025-10-08 08:34:17.512392 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 08:34:17.512405 | controller | XDG_SESSION_CLASS=user
2025-10-08 08:34:17.512418 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 08:34:17.512431 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 08:34:17.512444 | controller | USER=zuul
2025-10-08 08:34:17.512457 | controller | ZUUL_VOTING=True
2025-10-08 08:34:17.512470 | controller | BUILD_TIMEOUT=1800000
2025-10-08 08:34:17.512483 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 08:34:17.512496 | controller | SHLVL=1
2025-10-08 08:34:17.512509 | controller | ZUUL_PATCHSET=1a7b2ae378ead444d1ed756aace4ec009d749f1a
2025-10-08 08:34:17.512522 | controller | XDG_SESSION_ID=1
2025-10-08 08:34:17.512535 | controller | ZUUL_BRANCH=main
2025-10-08 08:34:17.512548 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 08:34:17.512561 | controller | SSH_CLIENT=38.102.83.114 39820 22
2025-10-08 08:34:17.512577 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 08:34:17.512590 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 08:34:17.512604 | controller | which_declare=declare -f
2025-10-08 08:34:17.512617 | 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-10-08 08:34:17.512630 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 08:34:17.512643 | controller | ZUUL_CHANGE=3373
2025-10-08 08:34:17.512656 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 08:34:17.512669 | controller | ZUUL_UUID=7d6a307711964f42a9e722de9457ed08
2025-10-08 08:34:17.512682 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 08:34:17.512695 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 08:34:17.512708 | controller | }
2025-10-08 08:34:17.629254 | controller | ok: Runtime: 0:00:00.008281
2025-10-08 08:34:17.642047 | 
2025-10-08 08:34:17.642187 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 08:34:17.681166 | controller | skipping: Conditional result was False
2025-10-08 08:34:17.695239 | 
2025-10-08 08:34:17.695557 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 08:34:18.267477 | controller | skipping: Conditional result was False
2025-10-08 08:34:18.279855 | 
2025-10-08 08:34:18.280005 | TASK [Ensure legacy workspace directory]
2025-10-08 08:34:18.678030 | controller | changed
2025-10-08 08:34:18.699840 | 
2025-10-08 08:34:18.699903 | PLAY RECAP
2025-10-08 08:34:18.699943 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 08:34:18.699963 | 
2025-10-08 08:34:18.803939 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 08:34:18.805603 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-08 08:34:19.419383 | 
2025-10-08 08:34:19.419509 | PLAY [all,!compute]
2025-10-08 08:34:19.446442 | 
2025-10-08 08:34:19.446562 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-08 08:34:19.976469 | controller | skipping: Conditional result was False
2025-10-08 08:34:19.984292 | 
2025-10-08 08:34:19.984400 | TASK [Install podman]
2025-10-08 08:34:20.069453 | controller | skipping: Conditional result was False
2025-10-08 08:34:20.075508 | 
2025-10-08 08:34:20.075577 | TASK [Set var name for quay login zuul secret]
2025-10-08 08:34:20.110063 | controller | skipping: Conditional result was False
2025-10-08 08:34:20.116334 | 
2025-10-08 08:34:20.116399 | TASK [Print the username]
2025-10-08 08:34:20.647930 | controller | skipping: Conditional result was False
2025-10-08 08:34:20.655587 | 
2025-10-08 08:34:20.655901 | TASK [Fail when user or password is not set]
2025-10-08 08:34:20.721873 | controller | skipping: Conditional result was False
2025-10-08 08:34:20.734609 | 
2025-10-08 08:34:20.734960 | TASK [Set vars for quay login]
2025-10-08 08:34:20.803275 | 
2025-10-08 08:34:20.803474 | LOOP [Perform container registry login(s) with podman]
2025-10-08 08:34:20.860565 | 
2025-10-08 08:34:20.860867 | LOOP [Perform container registry login(s) with buildah]
2025-10-08 08:34:20.919598 | 
2025-10-08 08:34:20.919899 | TASK [Set Insecure registry for content provider]
2025-10-08 08:34:20.994141 | controller | skipping: Conditional result was False
2025-10-08 08:34:21.023156 | 
2025-10-08 08:34:21.023270 | PLAY RECAP
2025-10-08 08:34:21.023339 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-10-08 08:34:21.023375 | 
2025-10-08 08:34:21.127670 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-08 08:34:21.128448 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-08 08:34:21.730756 | 
2025-10-08 08:34:21.730863 | PLAY [Prefer ipv4 over ipv6]
2025-10-08 08:34:21.750980 | 
2025-10-08 08:34:21.751079 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-10-08 08:34:21.795724 | controller | skipping: Conditional result was False
2025-10-08 08:34:21.816009 | 
2025-10-08 08:34:21.816083 | PLAY [controller]
2025-10-08 08:34:21.830385 | 
2025-10-08 08:34:21.830473 | TASK [Fail when openshift_pull_sec not provided]
2025-10-08 08:34:21.853901 | controller | skipping: Conditional result was False
2025-10-08 08:34:21.860519 | 
2025-10-08 08:34:21.860588 | TASK [Clone repos in the job workspace]
2025-10-08 08:34:21.879774 | controller | ok
2025-10-08 08:34:21.897173 | 
2025-10-08 08:34:21.897284 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 08:34:22.489622 | controller | ok
2025-10-08 08:34:22.496555 | 
2025-10-08 08:34:22.496638 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 08:34:27.627753 | controller | Output suppressed because no_log was given
2025-10-08 08:34:27.639738 | 
2025-10-08 08:34:27.639809 | TASK [Create pull-secret.txt file]
2025-10-08 08:34:29.572051 | controller | Output suppressed because no_log was given
2025-10-08 08:34:29.583033 | 
2025-10-08 08:34:29.583165 | TASK [Apply CRC features and print image build date]
2025-10-08 08:34:29.612401 | controller | ok
2025-10-08 08:34:29.628309 | controller | included: /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-10-08 08:34:29.642898 | 
2025-10-08 08:34:29.642987 | TASK [Set additional parameters for crc - telemetry]
2025-10-08 08:34:30.202917 | controller | skipping: Conditional result was False
2025-10-08 08:34:30.221181 | 
2025-10-08 08:34:30.221332 | TASK [Set additional parameters for crc - monitoring]
2025-10-08 08:34:30.771089 | controller | skipping: Conditional result was False
2025-10-08 08:34:30.779500 | 
2025-10-08 08:34:30.779624 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-10-08 08:34:31.325169 | controller | skipping: Conditional result was False
2025-10-08 08:34:31.338374 | 
2025-10-08 08:34:31.338518 | TASK [Set additional parameters for crc - enable microshift preset]
2025-10-08 08:34:31.891948 | controller | skipping: Conditional result was False
2025-10-08 08:34:31.899579 | 
2025-10-08 08:34:31.899671 | TASK [Set additional parameters for crc - enable okd preset]
2025-10-08 08:34:32.439380 | controller | skipping: Conditional result was False
2025-10-08 08:34:32.462370 | 
2025-10-08 08:34:32.462553 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-10-08 08:34:33.025276 | controller | skipping: Conditional result was False
2025-10-08 08:34:33.038546 | 
2025-10-08 08:34:33.038949 | TASK [Execute crc start command]
2025-10-08 08:38:13.015716 | controller | ok: Runtime: 0:03:39.049627
2025-10-08 08:38:13.028041 | 
2025-10-08 08:38:13.028181 | TASK [Verify that OpenShift is up and running]
2025-10-08 08:38:13.057881 | controller | ok
2025-10-08 08:38:13.077408 | controller | included: /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-10-08 08:38:13.092723 | 
2025-10-08 08:38:13.092818 | TASK [Create script to login and verify services]
2025-10-08 08:38:14.682665 | controller | changed
2025-10-08 08:38:14.687985 | 
2025-10-08 08:38:14.688047 | TASK [Ensure that user is logged]
2025-10-08 08:38:15.094206 | controller | Checking login to the cluster 1...
2025-10-08 08:38:16.180155 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:16.244964 | controller |
2025-10-08 08:38:16.245073 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:16.253060 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:21.260326 | controller | Checking login to the cluster 2...
2025-10-08 08:38:21.441140 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:21.481259 | controller |
2025-10-08 08:38:21.481376 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:21.487432 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:26.491731 | controller | Checking login to the cluster 3...
2025-10-08 08:38:26.667802 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:26.693591 | controller |
2025-10-08 08:38:26.693700 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:26.698671 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:31.704383 | controller | Checking login to the cluster 4...
2025-10-08 08:38:31.836760 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:31.863390 | controller |
2025-10-08 08:38:31.863478 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:31.869179 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:36.872233 | controller | Checking login to the cluster 5...
2025-10-08 08:38:37.012264 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:37.041687 | controller |
2025-10-08 08:38:37.041774 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:37.048693 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:42.051395 | controller | Checking login to the cluster 6...
2025-10-08 08:38:42.220465 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:42.241493 | controller |
2025-10-08 08:38:42.241604 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:42.246942 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:47.249375 | controller | Checking login to the cluster 7...
2025-10-08 08:38:47.458900 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:47.485871 | controller |
2025-10-08 08:38:47.485951 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:47.490917 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:52.495564 | controller | Checking login to the cluster 8...
2025-10-08 08:38:52.642227 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:52.683076 | controller |
2025-10-08 08:38:52.683222 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:52.691738 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:38:57.696367 | controller | Checking login to the cluster 9...
2025-10-08 08:38:57.842199 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:38:57.874723 | controller |
2025-10-08 08:38:57.874833 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:38:57.882153 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:02.884924 | controller | Checking login to the cluster 10...
2025-10-08 08:39:03.060946 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:03.103597 | controller |
2025-10-08 08:39:03.103686 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:39:03.110669 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:08.115341 | controller | Checking login to the cluster 11...
2025-10-08 08:39:08.250343 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:08.287169 | controller |
2025-10-08 08:39:08.287304 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:39:08.298815 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:13.304475 | controller | Checking login to the cluster 12...
2025-10-08 08:39:13.540528 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:13.613961 | controller |
2025-10-08 08:39:13.614087 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:39:13.619304 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:18.621767 | controller | Checking login to the cluster 13...
2025-10-08 08:39:18.806279 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:19.046325 | controller |
2025-10-08 08:39:19.046442 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:39:19.057491 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:24.063466 | controller | Checking login to the cluster 14...
2025-10-08 08:39:24.258652 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:24.363180 | controller |
2025-10-08 08:39:24.363303 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:39:24.369776 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:29.376419 | controller | Checking login to the cluster 15...
2025-10-08 08:39:29.675756 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:29.958087 | controller |
2025-10-08 08:39:29.958249 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:39:29.980605 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:34.987816 | controller | Checking login to the cluster 16...
2025-10-08 08:39:35.200992 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:35.351666 | controller |
2025-10-08 08:39:35.351766 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:39:35.357721 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:40.361284 | controller | Checking login to the cluster 17...
2025-10-08 08:39:40.802629 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:41.169407 | controller |
2025-10-08 08:39:41.169501 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:39:41.179155 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:46.183424 | controller | Checking login to the cluster 18...
2025-10-08 08:39:46.484300 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:46.860415 | controller |
2025-10-08 08:39:46.860544 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:39:46.872142 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:51.875961 | controller | Checking login to the cluster 19...
2025-10-08 08:39:52.286124 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:39:54.909395 | controller |
2025-10-08 08:39:54.909513 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:39:54.920422 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:39:59.927358 | controller | Checking login to the cluster 20...
2025-10-08 08:40:01.682104 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:03.117803 | controller |
2025-10-08 08:40:03.117984 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:03.123749 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:08.128914 | controller | Checking login to the cluster 21...
2025-10-08 08:40:08.455857 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:08.921805 | controller |
2025-10-08 08:40:08.921963 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:08.928178 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:13.941871 | controller | Checking login to the cluster 22...
2025-10-08 08:40:14.728528 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:16.992975 | controller |
2025-10-08 08:40:16.993389 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:17.010775 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:22.015766 | controller | Checking login to the cluster 23...
2025-10-08 08:40:22.220376 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:22.355088 | controller |
2025-10-08 08:40:22.355179 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:22.363571 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:27.367859 | controller | Checking login to the cluster 24...
2025-10-08 08:40:27.592706 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:27.745944 | controller |
2025-10-08 08:40:27.746101 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:27.757198 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:32.760731 | controller | Checking login to the cluster 25...
2025-10-08 08:40:32.973326 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:33.108975 | controller |
2025-10-08 08:40:33.109132 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:33.119382 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:38.124710 | controller | Checking login to the cluster 26...
2025-10-08 08:40:38.653711 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:39.048508 | controller |
2025-10-08 08:40:39.048612 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:39.057975 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:44.061167 | controller | Checking login to the cluster 27...
2025-10-08 08:40:44.209621 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:44.248800 | controller |
2025-10-08 08:40:44.248916 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:44.254986 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:49.258436 | controller | Checking login to the cluster 28...
2025-10-08 08:40:49.448995 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:49.487732 | controller |
2025-10-08 08:40:49.487831 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:49.494822 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:54.497526 | controller | Checking login to the cluster 29...
2025-10-08 08:40:54.695226 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:54.720315 | controller |
2025-10-08 08:40:54.720409 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:54.727840 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:40:59.732760 | controller | Checking login to the cluster 30...
2025-10-08 08:40:59.903513 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:40:59.926594 | controller |
2025-10-08 08:40:59.926772 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:40:59.936656 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:04.941775 | controller | Checking login to the cluster 31...
2025-10-08 08:41:05.088271 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:05.126309 | controller |
2025-10-08 08:41:05.126429 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:05.131211 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:10.134591 | controller | Checking login to the cluster 32...
2025-10-08 08:41:10.313286 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:10.340014 | controller |
2025-10-08 08:41:10.340136 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:10.347703 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:15.350873 | controller | Checking login to the cluster 33...
2025-10-08 08:41:15.563518 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:15.596743 | controller |
2025-10-08 08:41:15.596873 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:15.602014 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:20.605323 | controller | Checking login to the cluster 34...
2025-10-08 08:41:20.795024 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:20.823343 | controller |
2025-10-08 08:41:20.823447 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:20.830852 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:25.834023 | controller | Checking login to the cluster 35...
2025-10-08 08:41:25.979163 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:26.002960 | controller |
2025-10-08 08:41:26.003037 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:26.009888 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:31.014237 | controller | Checking login to the cluster 36...
2025-10-08 08:41:31.170288 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:31.195734 | controller |
2025-10-08 08:41:31.195883 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:31.203482 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:36.207198 | controller | Checking login to the cluster 37...
2025-10-08 08:41:36.367358 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:36.396824 | controller |
2025-10-08 08:41:36.396991 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:36.409555 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:41.412608 | controller | Checking login to the cluster 38...
2025-10-08 08:41:41.597177 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:41.626750 | controller |
2025-10-08 08:41:41.626904 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:41.637626 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:46.640541 | controller | Checking login to the cluster 39...
2025-10-08 08:41:46.822595 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:46.850369 | controller |
2025-10-08 08:41:46.850489 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:46.856859 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:51.859689 | controller | Checking login to the cluster 40...
2025-10-08 08:41:52.038659 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:52.063303 | controller |
2025-10-08 08:41:52.063403 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:52.073194 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:41:57.076121 | controller | Checking login to the cluster 41...
2025-10-08 08:41:57.233572 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:41:57.257844 | controller |
2025-10-08 08:41:57.257976 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:41:57.262736 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:02.266084 | controller | Checking login to the cluster 42...
2025-10-08 08:42:02.467596 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:02.490768 | controller |
2025-10-08 08:42:02.490882 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:42:02.497243 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:07.501965 | controller | Checking login to the cluster 43...
2025-10-08 08:42:07.665521 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:07.758379 | controller |
2025-10-08 08:42:07.758543 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:07.766894 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:12.771150 | controller | Checking login to the cluster 44...
2025-10-08 08:42:12.926781 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:12.968132 | controller |
2025-10-08 08:42:12.968258 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:12.974121 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:17.976498 | controller | Checking login to the cluster 45...
2025-10-08 08:42:18.190525 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:18.236996 | controller |
2025-10-08 08:42:18.237146 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:18.252944 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:23.255521 | controller | Checking login to the cluster 46...
2025-10-08 08:42:23.432420 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:23.509093 | controller |
2025-10-08 08:42:23.509190 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:23.516480 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:28.520521 | controller | Checking login to the cluster 47...
2025-10-08 08:42:28.689487 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:28.851167 | controller |
2025-10-08 08:42:28.851298 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:28.868502 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:33.871665 | controller | Checking login to the cluster 48...
2025-10-08 08:42:34.070155 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:34.156513 | controller |
2025-10-08 08:42:34.156622 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:34.164000 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:39.166676 | controller | Checking login to the cluster 49...
2025-10-08 08:42:39.360732 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:39.415294 | controller |
2025-10-08 08:42:39.415430 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:39.425231 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:44.427959 | controller | Checking login to the cluster 50...
2025-10-08 08:42:44.572975 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:44.643069 | controller |
2025-10-08 08:42:44.643159 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:44.650906 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:49.654426 | controller | Checking login to the cluster 51...
2025-10-08 08:42:49.800491 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:49.896602 | controller |
2025-10-08 08:42:49.896714 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-08 08:42:49.906083 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:42:54.908558 | controller | Checking login to the cluster 52...
2025-10-08 08:42:55.101168 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:42:55.138761 | controller |
2025-10-08 08:42:55.138861 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-08 08:42:55.149687 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-08 08:43:00.152399 | controller | Checking login to the cluster 53...
2025-10-08 08:43:00.321721 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-08 08:43:02.628678 | controller |
2025-10-08 08:43:02.628787 | controller | Login successful.
2025-10-08 08:43:02.675784 | controller |
2025-10-08 08:43:02.675883 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-10-08 08:43:02.698922 | controller |
2025-10-08 08:43:02.699165 | controller | Using project "default".
2025-10-08 08:43:03.185528 | controller | changed
2025-10-08 08:43:03.195055 | 
2025-10-08 08:43:03.195136 | TASK [Delete all openshift-marketplace pods]
2025-10-08 08:43:05.470140 | controller | pod "certified-operators-f8szs" deleted
2025-10-08 08:43:05.512273 | controller | pod "community-operators-5zsqg" deleted
2025-10-08 08:43:05.541592 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2025-10-08 08:43:05.574006 | controller | pod "redhat-marketplace-ps6xk" deleted
2025-10-08 08:43:05.630957 | controller | pod "redhat-operators-hq9gc" deleted
2025-10-08 08:43:05.659124 | controller | pod "redhat-operators-tqxl9" deleted
2025-10-08 08:43:24.261444 | controller | ok
2025-10-08 08:43:24.275494 | 
2025-10-08 08:43:24.275666 | TASK [Copy script ensure_services_up.sh]
2025-10-08 08:43:24.313091 | controller | skipping: Conditional result was False
2025-10-08 08:43:24.327242 | 
2025-10-08 08:43:24.327385 | TASK [Ensure all pods are up and running]
2025-10-08 08:43:24.876559 | controller | skipping: Conditional result was False
2025-10-08 08:43:24.891486 | 
2025-10-08 08:43:24.891811 | TASK [Check if there is a image build date file]
2025-10-08 08:43:25.336648 | controller | ok
2025-10-08 08:43:25.349390 | 
2025-10-08 08:43:25.349567 | TASK [Print image date if available]
2025-10-08 08:43:25.717238 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2025-10-08 08:43:25.908325 | controller | changed
2025-10-08 08:43:25.920133 | 
2025-10-08 08:43:25.920313 | TASK [Fix machineconfigpool]
2025-10-08 08:43:25.988121 | controller | skipping: Conditional result was False
2025-10-08 08:43:25.994878 | 
2025-10-08 08:43:25.995000 | TASK [Show available nodes]
2025-10-08 08:43:26.794672 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-10-08 08:43:26.798067 | controller | crc    Ready    control-plane,master,worker   227d   v1.31.5
2025-10-08 08:43:27.030541 | controller | ok: Runtime: 0:00:00.458509
2025-10-08 08:43:27.044082 | 
2025-10-08 08:43:27.044296 | TASK [Configure insecure registry inside crc vm]
2025-10-08 08:43:27.084880 | controller | skipping: Conditional result was False
2025-10-08 08:43:27.104195 | 
2025-10-08 08:43:27.104387 | TASK [Catch CRC IP]
2025-10-08 08:43:27.657258 | controller | 192.168.130.11
2025-10-08 08:43:27.700029 | controller | changed
2025-10-08 08:43:27.712829 | 
2025-10-08 08:43:27.712966 | TASK [Check if id_ed25519 key exists]
2025-10-08 08:43:28.153907 | controller | ok
2025-10-08 08:43:28.167514 | 
2025-10-08 08:43:28.167753 | TASK [Set fact if new keypair exists]
2025-10-08 08:43:28.208216 | controller | ok
2025-10-08 08:43:28.220671 | 
2025-10-08 08:43:28.220958 | TASK [Run crc]
2025-10-08 08:43:30.062294 | controller |
2025-10-08 08:43:37.034458 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-10-08 08:43:37.034532 | controller |
2025-10-08 08:43:37.034540 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:43:37.034555 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-10-08 08:43:37.074806 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-10-08 08:43:37.074907 | controller | interpreter could change the meaning of that path. See
2025-10-08 08:43:37.074914 | controller | https://docs.ansible.com/ansible-
2025-10-08 08:43:37.074921 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-10-08 08:43:37.074943 | controller | ok: [192.168.130.11]
2025-10-08 08:43:39.039762 | controller |
2025-10-08 08:43:39.039836 | controller | TASK [Copy pull-secret] ********************************************************
2025-10-08 08:43:39.039852 | controller | changed: [192.168.130.11]
2025-10-08 08:43:40.604596 | controller |
2025-10-08 08:43:40.604707 | controller | TASK [Add crio config] *********************************************************
2025-10-08 08:43:40.604733 | controller | changed: [192.168.130.11]
2025-10-08 08:43:45.040861 | controller |
2025-10-08 08:43:45.040999 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-10-08 08:43:45.041046 | controller | changed: [192.168.130.11]
2025-10-08 08:43:45.106301 | controller |
2025-10-08 08:43:45.106541 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:43:45.106559 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:43:45.106569 | controller |
2025-10-08 08:43:45.301485 | controller | changed
2025-10-08 08:43:45.307110 | 
2025-10-08 08:43:45.307170 | TASK [Set slower etcd profile]
2025-10-08 08:43:45.376138 | controller | ok
2025-10-08 08:43:45.398315 | 
2025-10-08 08:43:45.398442 | TASK [prepare-crc-cloud : Set slower profile]
2025-10-08 08:43:45.914244 | controller | etcd.operator.openshift.io/cluster patched
2025-10-08 08:43:45.954882 | controller | ok
2025-10-08 08:43:45.960652 | 
2025-10-08 08:43:45.960734 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-10-08 08:44:15.992927 | controller | ok
2025-10-08 08:44:16.001302 | 
2025-10-08 08:44:16.001445 | TASK [prepare-crc-cloud : Get pod phase status]
2025-10-08 08:49:30.532331 | controller | ok
2025-10-08 08:49:30.548671 | 
2025-10-08 08:49:30.548769 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-10-08 08:49:30.605038 | controller | ok
2025-10-08 08:49:30.613086 | controller | included: /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-10-08 08:49:30.623049 | 
2025-10-08 08:49:30.623122 | TASK [Set facts for disk limitation]
2025-10-08 08:49:30.647880 | controller | ok
2025-10-08 08:49:30.653469 | 
2025-10-08 08:49:30.653541 | TASK [Print limits for the disk]
2025-10-08 08:49:30.684014 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-10-08 08:49:30.689048 | 
2025-10-08 08:49:30.689112 | TASK [Set limit to crc VM to the vda disk]
2025-10-08 08:49:31.125818 | controller |
2025-10-08 08:49:31.220791 | controller | changed
2025-10-08 08:49:31.232120 | 
2025-10-08 08:49:31.232338 | TASK [Pause machineconfigpool]
2025-10-08 08:49:31.276500 | controller | ok
2025-10-08 08:49:31.310751 | 
2025-10-08 08:49:31.310901 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-10-08 08:49:31.876072 | controller | ok
2025-10-08 08:49:32.451954 | controller | ok
2025-10-08 08:49:32.478356 | 
2025-10-08 08:49:32.478556 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-10-08 08:49:33.018101 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-10-08 08:49:33.024064 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      227d
2025-10-08 08:49:33.024106 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      227d
2025-10-08 08:49:33.525251 | controller | ok
2025-10-08 08:49:33.580475 | 
2025-10-08 08:49:33.580575 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-10-08 08:49:33.580731 | 
2025-10-08 08:49:33.580776 | PLAY RECAP
2025-10-08 08:49:33.580829 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-10-08 08:49:33.580859 | 
2025-10-08 08:49:33.697338 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-08 08:49:33.698410 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 08:49:34.366930 | 
2025-10-08 08:49:34.367052 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 08:49:34.391249 | 
2025-10-08 08:49:34.391388 | TASK [Create zuul-output directory]
2025-10-08 08:49:34.891269 | controller | changed
2025-10-08 08:49:34.914018 | 
2025-10-08 08:49:34.914174 | TASK [Slurp Zuul inventory test]
2025-10-08 08:49:35.225038 | controller -> localhost | ok
2025-10-08 08:49:35.239924 | 
2025-10-08 08:49:35.240043 | TASK [Save zuul inventory]
2025-10-08 08:49:36.939675 | controller | changed
2025-10-08 08:49:36.953002 | 
2025-10-08 08:49:36.953154 | TASK [Save zuul vars without the change_message]
2025-10-08 08:49:38.446222 | controller | changed
2025-10-08 08:49:38.479058 | 
2025-10-08 08:49:38.479140 | PLAY RECAP
2025-10-08 08:49:38.479195 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 08:49:38.479222 | 
2025-10-08 08:49:38.601483 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 08:49:38.602355 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 08:49:39.267229 | 
2025-10-08 08:49:39.267360 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 08:49:39.294218 | 
2025-10-08 08:49:39.294418 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 08:49:39.314486 | controller | ok
2025-10-08 08:49:39.333604 | 
2025-10-08 08:49:39.333740 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 08:49:39.368033 | controller | skipping: Conditional result was False
2025-10-08 08:49:39.373812 | 
2025-10-08 08:49:39.373878 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 08:49:39.878997 | controller | ok
2025-10-08 08:49:39.886164 | 
2025-10-08 08:49:39.886235 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 08:49:41.300278 | controller | ok
2025-10-08 08:49:41.311125 | 
2025-10-08 08:49:41.311229 | TASK [Prepare workspace]
2025-10-08 08:49:41.331236 | controller | ok
2025-10-08 08:49:41.358201 | 
2025-10-08 08:49:41.358313 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 08:49:41.964369 | controller | ok
2025-10-08 08:49:41.971783 | 
2025-10-08 08:49:41.971873 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 08:49:43.232823 | controller | Output suppressed because no_log was given
2025-10-08 08:49:43.311151 | 
2025-10-08 08:49:43.311294 | LOOP [Create zuul-output directory]
2025-10-08 08:49:43.681399 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 08:49:44.016456 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 08:49:44.042082 | 
2025-10-08 08:49:44.042315 | TASK [Install required packages]
2025-10-08 08:50:01.098656 | controller | ok: Nothing to do
2025-10-08 08:50:01.105641 | 
2025-10-08 08:50:01.105749 | TASK [Install venv]
2025-10-08 08:51:09.610025 | controller | changed
2025-10-08 08:51:09.667588 | 
2025-10-08 08:51:09.667733 | PLAY RECAP
2025-10-08 08:51:09.667800 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 08:51:09.667832 | 
2025-10-08 08:51:09.773398 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 08:51:09.775543 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 08:51:10.397818 | 
2025-10-08 08:51:10.397932 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 08:51:10.420543 | 
2025-10-08 08:51:10.420669 | TASK [Gather required facts]
2025-10-08 08:51:11.204421 | controller | ok
2025-10-08 08:51:11.217538 | 
2025-10-08 08:51:11.217765 | TASK [Load environment var if instructed to]
2025-10-08 08:51:11.257050 | controller | skipping: Conditional result was False
2025-10-08 08:51:11.276626 | 
2025-10-08 08:51:11.276937 | TASK [Run molecule]
2025-10-08 08:51:12.670038 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 08:51:12.929550 | controller | INFO     Running default > destroy
2025-10-08 08:51:12.929789 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 08:51:13.016923 | controller | INFO     Running default > create
2025-10-08 08:51:13.018317 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 08:51:13.030425 | controller | INFO     Running default > converge
2025-10-08 08:51:13.892173 | controller |
2025-10-08 08:51:13.892380 | controller | PLAY [Converge] ****************************************************************
2025-10-08 08:51:13.892559 | controller |
2025-10-08 08:51:13.892946 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:51:15.152861 | controller | Wednesday 08 October 2025  08:51:13 +0000 (0:00:00.017)       0:00:00.017 *****
2025-10-08 08:51:15.153161 | controller | ok: [instance]
2025-10-08 08:51:15.153685 | controller |
2025-10-08 08:51:15.153698 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-10-08 08:51:15.153711 | controller | Wednesday 08 October 2025  08:51:15 +0000 (0:00:01.261)       0:00:01.279 *****
2025-10-08 08:51:15.589594 | controller | changed: [instance]
2025-10-08 08:51:15.589902 | controller |
2025-10-08 08:51:15.641956 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-10-08 08:51:15.642026 | controller | Wednesday 08 October 2025  08:51:15 +0000 (0:00:00.436)       0:00:01.715 *****
2025-10-08 08:51:15.642048 | controller |
2025-10-08 08:51:15.642077 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-10-08 08:51:15.642284 | controller | Wednesday 08 October 2025  08:51:15 +0000 (0:00:00.053)       0:00:01.768 *****
2025-10-08 08:51:16.096979 | controller | ok: [instance]
2025-10-08 08:51:16.126441 | controller |
2025-10-08 08:51:16.126493 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-10-08 08:51:16.126501 | controller | Wednesday 08 October 2025  08:51:16 +0000 (0:00:00.454)       0:00:02.222 *****
2025-10-08 08:51:16.126515 | controller | ok: [instance]
2025-10-08 08:51:16.126621 | controller |
2025-10-08 08:51:16.126635 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-10-08 08:51:16.126741 | controller | Wednesday 08 October 2025  08:51:16 +0000 (0:00:00.030)       0:00:02.253 *****
2025-10-08 08:51:16.152861 | controller | skipping: [instance]
2025-10-08 08:51:16.152910 | controller |
2025-10-08 08:51:16.153018 | controller | TASK [Add the crc host dynamically] ********************************************
2025-10-08 08:51:16.153177 | controller | Wednesday 08 October 2025  08:51:16 +0000 (0:00:00.026)       0:00:02.279 *****
2025-10-08 08:51:16.178951 | controller | changed: [instance]
2025-10-08 08:51:16.179004 | controller |
2025-10-08 08:51:16.179169 | controller | TASK [Fetch crc network facts] *************************************************
2025-10-08 08:51:16.179323 | controller | Wednesday 08 October 2025  08:51:16 +0000 (0:00:00.026)       0:00:02.306 *****
2025-10-08 08:51:22.574934 | controller | ok: [instance -> crc]
2025-10-08 08:51:22.575102 | controller |
2025-10-08 08:51:22.575125 | controller | TASK [Load shared variables] ***************************************************
2025-10-08 08:51:22.575140 | controller | Wednesday 08 October 2025  08:51:22 +0000 (0:00:06.394)       0:00:08.700 *****
2025-10-08 08:51:22.613490 | controller | ok: [instance]
2025-10-08 08:51:22.613585 | controller |
2025-10-08 08:51:22.613606 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-08 08:51:22.613801 | controller | Wednesday 08 October 2025  08:51:22 +0000 (0:00:00.039)       0:00:08.739 *****
2025-10-08 08:51:22.706236 | controller |
2025-10-08 08:51:22.706394 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-08 08:51:22.706629 | controller | Wednesday 08 October 2025  08:51:22 +0000 (0:00:00.093)       0:00:08.832 *****
2025-10-08 08:51:23.105843 | controller | changed: [instance]
2025-10-08 08:51:23.105908 | controller |
2025-10-08 08:51:23.105990 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-08 08:51:23.106109 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.399)       0:00:09.232 *****
2025-10-08 08:51:23.136835 | controller | skipping: [instance]
2025-10-08 08:51:23.136920 | controller |
2025-10-08 08:51:23.137077 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-08 08:51:23.137360 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.030)       0:00:09.263 *****
2025-10-08 08:51:23.169387 | controller | skipping: [instance]
2025-10-08 08:51:23.169481 | controller |
2025-10-08 08:51:23.169588 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-08 08:51:23.169678 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.032)       0:00:09.296 *****
2025-10-08 08:51:23.204108 | controller | skipping: [instance]
2025-10-08 08:51:23.204186 | controller |
2025-10-08 08:51:23.204291 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-08 08:51:23.204404 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.034)       0:00:09.330 *****
2025-10-08 08:51:23.250375 | controller | skipping: [instance]
2025-10-08 08:51:23.250642 | controller |
2025-10-08 08:51:23.251049 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-08 08:51:23.251361 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.045)       0:00:09.376 *****
2025-10-08 08:51:23.311293 | controller | ok: [instance]
2025-10-08 08:51:23.311368 | controller |
2025-10-08 08:51:23.311520 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-08 08:51:23.311658 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.061)       0:00:09.438 *****
2025-10-08 08:51:23.529584 | 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-10-08 08:51:23.529785 | 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-10-08 08:51:23.530043 | 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-10-08 08:51:23.530351 | controller |
2025-10-08 08:51:23.530576 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-08 08:51:23.530803 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.218)       0:00:09.656 *****
2025-10-08 08:51:23.635599 | controller | ok: [instance]
2025-10-08 08:51:23.635787 | controller |
2025-10-08 08:51:23.636072 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-08 08:51:23.636313 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.105)       0:00:09.761 *****
2025-10-08 08:51:23.720037 | controller | ok: [instance]
2025-10-08 08:51:23.720237 | controller |
2025-10-08 08:51:23.720475 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-08 08:51:23.720693 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.084)       0:00:09.846 *****
2025-10-08 08:51:23.801702 | controller | ok: [instance]
2025-10-08 08:51:23.801874 | controller |
2025-10-08 08:51:23.802142 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-08 08:51:23.802386 | controller | Wednesday 08 October 2025  08:51:23 +0000 (0:00:00.081)       0:00:09.928 *****
2025-10-08 08:51:24.417359 | controller | changed: [instance]
2025-10-08 08:51:24.417440 | controller |
2025-10-08 08:51:24.417525 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-08 08:51:24.417681 | controller | Wednesday 08 October 2025  08:51:24 +0000 (0:00:00.616)       0:00:10.544 *****
2025-10-08 08:51:25.523285 | controller | changed: [instance]
2025-10-08 08:51:25.523334 | controller |
2025-10-08 08:51:25.523435 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-08 08:51:25.523550 | controller | Wednesday 08 October 2025  08:51:25 +0000 (0:00:01.105)       0:00:11.650 *****
2025-10-08 08:51:26.464687 | controller | changed: [instance]
2025-10-08 08:51:26.464738 | controller |
2025-10-08 08:51:26.464887 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:51:26.464983 | controller | instance                   : ok=16   changed=6    unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-10-08 08:51:26.465106 | controller |
2025-10-08 08:51:26.465275 | controller | Wednesday 08 October 2025  08:51:26 +0000 (0:00:00.941)       0:00:12.591 *****
2025-10-08 08:51:26.465397 | controller | ===============================================================================
2025-10-08 08:51:26.465529 | controller | Fetch crc network facts ------------------------------------------------- 6.39s
2025-10-08 08:51:26.465674 | controller | Gathering Facts --------------------------------------------------------- 1.26s
2025-10-08 08:51:26.465766 | controller | ci_multus : Create the multus namespace --------------------------------- 1.11s
2025-10-08 08:51:26.465882 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.94s
2025-10-08 08:51:26.466009 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.62s
2025-10-08 08:51:26.466152 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.45s
2025-10-08 08:51:26.466261 | controller | Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.44s
2025-10-08 08:51:26.466398 | controller | ci_multus : Create manifests directory ---------------------------------- 0.40s
2025-10-08 08:51:26.466531 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.22s
2025-10-08 08:51:26.466668 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.11s
2025-10-08 08:51:26.466807 | controller | Call ci_multus role ----------------------------------------------------- 0.09s
2025-10-08 08:51:26.466914 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
2025-10-08 08:51:26.467036 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.08s
2025-10-08 08:51:26.467211 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.06s
2025-10-08 08:51:26.467261 | controller | Check if new ssh keypair exists ----------------------------------------- 0.05s
2025-10-08 08:51:26.467361 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.05s
2025-10-08 08:51:26.467485 | controller | Load shared variables --------------------------------------------------- 0.04s
2025-10-08 08:51:26.467938 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2025-10-08 08:51:26.583610 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-10-08 08:51:26.583703 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2025-10-08 08:51:26.583724 | controller | INFO     Running default > verify
2025-10-08 08:51:26.584426 | controller | INFO     Running Ansible Verifier
2025-10-08 08:51:27.185886 | controller |
2025-10-08 08:51:27.186126 | controller | PLAY [Verify] ******************************************************************
2025-10-08 08:51:27.186423 | controller |
2025-10-08 08:51:27.186661 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:51:27.187213 | controller | Wednesday 08 October 2025  08:51:27 +0000 (0:00:00.018)       0:00:00.018 *****
2025-10-08 08:51:28.454186 | controller | ok: [instance]
2025-10-08 08:51:28.454807 | controller |
2025-10-08 08:51:28.488470 | controller | TASK [Load shared variables] ***************************************************
2025-10-08 08:51:28.488578 | controller | Wednesday 08 October 2025  08:51:28 +0000 (0:00:01.268)       0:00:01.287 *****
2025-10-08 08:51:28.488607 | controller | ok: [instance]
2025-10-08 08:51:28.919889 | controller |
2025-10-08 08:51:28.919940 | controller | TASK [Fetch files stat results] ************************************************
2025-10-08 08:51:28.919948 | controller | Wednesday 08 October 2025  08:51:28 +0000 (0:00:00.033)       0:00:01.320 *****
2025-10-08 08:51:28.919960 | controller | ok: [instance]
2025-10-08 08:51:28.919982 | controller |
2025-10-08 08:51:28.920031 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-08 08:51:28.920206 | controller | Wednesday 08 October 2025  08:51:28 +0000 (0:00:00.432)       0:00:01.753 *****
2025-10-08 08:51:28.945099 | controller | ok: [instance] => changed=false
2025-10-08 08:51:28.945231 | controller |   msg: All assertions passed
2025-10-08 08:51:28.945383 | controller |
2025-10-08 08:51:28.945536 | controller | TASK [Fetch file content] ******************************************************
2025-10-08 08:51:28.945713 | controller | Wednesday 08 October 2025  08:51:28 +0000 (0:00:00.025)       0:00:01.778 *****
2025-10-08 08:51:29.320157 | controller | ok: [instance]
2025-10-08 08:51:29.320287 | controller |
2025-10-08 08:51:29.320322 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-08 08:51:29.382995 | controller | Wednesday 08 October 2025  08:51:29 +0000 (0:00:00.375)       0:00:02.153 *****
2025-10-08 08:51:29.383086 | controller | ok: [instance]
2025-10-08 08:51:29.383125 | controller |
2025-10-08 08:51:29.383211 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-08 08:51:29.383226 | controller | Wednesday 08 October 2025  08:51:29 +0000 (0:00:00.062)       0:00:02.215 *****
2025-10-08 08:51:29.443616 | controller | ok: [instance]
2025-10-08 08:51:29.443785 | controller |
2025-10-08 08:51:29.444077 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:51:29.444434 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:51:29.444654 | controller |
2025-10-08 08:51:29.444889 | controller | Wednesday 08 October 2025  08:51:29 +0000 (0:00:00.060)       0:00:02.276 *****
2025-10-08 08:51:29.445132 | controller | ===============================================================================
2025-10-08 08:51:29.445423 | controller | Gathering Facts --------------------------------------------------------- 1.27s
2025-10-08 08:51:29.445650 | controller | Fetch files stat results ------------------------------------------------ 0.43s
2025-10-08 08:51:29.445924 | controller | Fetch file content ------------------------------------------------------ 0.38s
2025-10-08 08:51:29.446216 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.06s
2025-10-08 08:51:29.446439 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-10-08 08:51:29.446705 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-08 08:51:29.446936 | controller | Assert that expected file exist ----------------------------------------- 0.03s
2025-10-08 08:51:29.548813 | controller | INFO     Verifier completed successfully.
2025-10-08 08:51:29.559418 | controller | INFO     Running default > verify
2025-10-08 08:51:29.559676 | controller | INFO     Running Ansible Verifier
2025-10-08 08:51:30.134817 | controller |
2025-10-08 08:51:30.134929 | controller | PLAY [Verify CRC] **************************************************************
2025-10-08 08:51:30.135146 | controller |
2025-10-08 08:51:30.135330 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:51:30.135516 | controller | Wednesday 08 October 2025  08:51:30 +0000 (0:00:00.019)       0:00:00.019 *****
2025-10-08 08:51:31.266172 | controller | ok: [instance]
2025-10-08 08:51:31.266534 | controller |
2025-10-08 08:51:31.306812 | controller | TASK [Include default vars] ****************************************************
2025-10-08 08:51:31.306928 | controller | Wednesday 08 October 2025  08:51:31 +0000 (0:00:01.131)       0:00:01.151 *****
2025-10-08 08:51:31.306967 | controller | ok: [instance]
2025-10-08 08:51:31.306993 | controller |
2025-10-08 08:51:31.307017 | controller | TASK [Check if NADs were created] **********************************************
2025-10-08 08:51:31.307299 | controller | Wednesday 08 October 2025  08:51:31 +0000 (0:00:00.040)       0:00:01.191 *****
2025-10-08 08:51:32.284632 | controller | ok: [instance]
2025-10-08 08:51:32.284693 | controller |
2025-10-08 08:51:32.284814 | controller | TASK [Store output spec] *******************************************************
2025-10-08 08:51:32.284971 | controller | Wednesday 08 October 2025  08:51:32 +0000 (0:00:00.973)       0:00:02.164 *****
2025-10-08 08:51:32.329696 | controller | ok: [instance]
2025-10-08 08:51:32.329756 | controller |
2025-10-08 08:51:32.329859 | controller | TASK [Ensure both lists have the same length] **********************************
2025-10-08 08:51:32.329966 | controller | Wednesday 08 October 2025  08:51:32 +0000 (0:00:00.045)       0:00:02.210 *****
2025-10-08 08:51:32.384099 | controller | ok: [instance] => changed=false
2025-10-08 08:51:32.384269 | controller |   msg: All assertions passed
2025-10-08 08:51:32.384431 | controller |
2025-10-08 08:51:32.384623 | controller | TASK [Compare each corresponding element in the lists] *************************
2025-10-08 08:51:32.384811 | controller | Wednesday 08 October 2025  08:51:32 +0000 (0:00:00.058)       0:00:02.269 *****
2025-10-08 08:51:32.455828 | 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-10-08 08:51:32.455906 | controller |   ansible_loop_var: item
2025-10-08 08:51:32.456042 | controller |   item:
2025-10-08 08:51:32.456196 | controller |   - |-
2025-10-08 08:51:32.456337 | controller |     {
2025-10-08 08:51:32.456484 | controller |       "cniVersion": "0.3.1",
2025-10-08 08:51:32.456614 | controller |       "name": "bridge-to-linux-bridge",
2025-10-08 08:51:32.456787 | controller |       "type": "bridge",
2025-10-08 08:51:32.456907 | controller |       "bridge": "bridge-to-linux-bridge",
2025-10-08 08:51:32.457021 | controller |       "ipam": {
2025-10-08 08:51:32.457205 | controller |         "type": "whereabouts",
2025-10-08 08:51:32.457350 | controller |         "range": "192.168.122.0/24",
2025-10-08 08:51:32.457490 | controller |         "range_start": "192.168.122.30",
2025-10-08 08:51:32.457629 | controller |         "range_end": "192.168.122.70"
2025-10-08 08:51:32.457753 | controller |       }
2025-10-08 08:51:32.457877 | controller |     }
2025-10-08 08:51:32.458011 | controller |   - |-
2025-10-08 08:51:32.458167 | controller |     {
2025-10-08 08:51:32.458326 | controller |       "cniVersion": "0.3.1",
2025-10-08 08:51:32.458493 | controller |       "name": "bridge-to-linux-bridge",
2025-10-08 08:51:32.458639 | controller |       "type": "bridge",
2025-10-08 08:51:32.458779 | controller |       "bridge": "bridge-to-linux-bridge",
2025-10-08 08:51:32.458908 | controller |       "ipam": {
2025-10-08 08:51:32.459034 | controller |         "type": "whereabouts",
2025-10-08 08:51:32.459195 | controller |         "range": "192.168.122.0/24",
2025-10-08 08:51:32.459399 | controller |         "range_start": "192.168.122.30",
2025-10-08 08:51:32.459522 | controller |         "range_end": "192.168.122.70"
2025-10-08 08:51:32.459655 | controller |       }
2025-10-08 08:51:32.459773 | controller |     }
2025-10-08 08:51:32.459937 | controller |   msg: All assertions passed
2025-10-08 08:51:32.460148 | 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-10-08 08:51:32.460279 | controller |   ansible_loop_var: item
2025-10-08 08:51:32.460422 | controller |   item:
2025-10-08 08:51:32.460558 | controller |   - |-
2025-10-08 08:51:32.460672 | controller |     {
2025-10-08 08:51:32.460805 | controller |       "cniVersion": "0.3.1",
2025-10-08 08:51:32.460934 | controller |       "name": "default",
2025-10-08 08:51:32.461100 | controller |       "type": "bridge",
2025-10-08 08:51:32.461254 | controller |       "bridge": "eth0",
2025-10-08 08:51:32.461376 | controller |       "ipam": {
2025-10-08 08:51:32.461536 | controller |         "type": "whereabouts",
2025-10-08 08:51:32.461643 | controller |         "range": "192.168.122.0/24",
2025-10-08 08:51:32.461772 | controller |         "range_start": "192.168.122.30",
2025-10-08 08:51:32.461899 | controller |         "range_end": "192.168.122.70"
2025-10-08 08:51:32.462031 | controller |       }
2025-10-08 08:51:32.462816 | controller |     }
2025-10-08 08:51:32.462970 | controller |   - |-
2025-10-08 08:51:32.463136 | controller |     {
2025-10-08 08:51:32.463303 | controller |       "cniVersion": "0.3.1",
2025-10-08 08:51:32.463435 | controller |       "name": "default",
2025-10-08 08:51:32.463576 | controller |       "type": "bridge",
2025-10-08 08:51:32.463715 | controller |       "bridge": "eth0",
2025-10-08 08:51:32.463866 | controller |       "ipam": {
2025-10-08 08:51:32.464034 | controller |         "type": "whereabouts",
2025-10-08 08:51:32.464200 | controller |         "range": "192.168.122.0/24",
2025-10-08 08:51:32.464340 | controller |         "range_start": "192.168.122.30",
2025-10-08 08:51:32.464478 | controller |         "range_end": "192.168.122.70"
2025-10-08 08:51:32.464615 | controller |       }
2025-10-08 08:51:32.464768 | controller |     }
2025-10-08 08:51:32.464891 | controller |   msg: All assertions passed
2025-10-08 08:51:32.465109 | 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-10-08 08:51:32.465232 | controller |   ansible_loop_var: item
2025-10-08 08:51:32.465356 | controller |   item:
2025-10-08 08:51:32.465505 | controller |   - |-
2025-10-08 08:51:32.465631 | controller |     {
2025-10-08 08:51:32.465788 | controller |       "cniVersion": "0.3.1",
2025-10-08 08:51:32.465904 | controller |       "name": "patchnetwork",
2025-10-08 08:51:32.466084 | controller |       "type": "macvlan",
2025-10-08 08:51:32.466194 | controller |       "master": "eth2",
2025-10-08 08:51:32.466331 | controller |       "ipam": {
2025-10-08 08:51:32.466477 | controller |         "type": "whereabouts",
2025-10-08 08:51:32.466615 | controller |         "range": "192.168.122.0/24",
2025-10-08 08:51:32.466763 | controller |         "range_start": "192.168.122.30",
2025-10-08 08:51:32.466877 | controller |         "range_end": "192.168.122.70"
2025-10-08 08:51:32.467011 | controller |       }
2025-10-08 08:51:32.467168 | controller |     }
2025-10-08 08:51:32.467328 | controller |   - |-
2025-10-08 08:51:32.467457 | controller |     {
2025-10-08 08:51:32.467599 | controller |       "cniVersion": "0.3.1",
2025-10-08 08:51:32.467723 | controller |       "name": "patchnetwork",
2025-10-08 08:51:32.467877 | controller |       "type": "macvlan",
2025-10-08 08:51:32.467996 | controller |       "master": "eth2",
2025-10-08 08:51:32.468146 | controller |       "ipam": {
2025-10-08 08:51:32.468279 | controller |         "type": "whereabouts",
2025-10-08 08:51:32.468446 | controller |         "range": "192.168.122.0/24",
2025-10-08 08:51:32.468573 | controller |         "range_start": "192.168.122.30",
2025-10-08 08:51:32.468703 | controller |         "range_end": "192.168.122.70"
2025-10-08 08:51:32.468850 | controller |       }
2025-10-08 08:51:32.468970 | controller |     }
2025-10-08 08:51:32.469139 | controller |   msg: All assertions passed
2025-10-08 08:51:32.469242 | controller |
2025-10-08 08:51:32.469370 | controller | TASK [Create a test pod to attach a network] ***********************************
2025-10-08 08:51:32.469523 | controller | Wednesday 08 October 2025  08:51:32 +0000 (0:00:00.071)       0:00:02.341 *****
2025-10-08 08:52:35.140316 | controller | [WARNING]: unknown field "api_version"
2025-10-08 08:52:35.151820 | controller | FAILED - RETRYING: [instance]: Create a test pod to attach a network (30 retries left).
2025-10-08 08:52:35.152023 | controller | FAILED - RETRYING: [instance]: Create a test pod to attach a network (29 retries left).
2025-10-08 08:52:35.152293 | controller | changed: [instance]
2025-10-08 08:52:35.152477 | controller |
2025-10-08 08:52:35.152699 | controller | TASK [Assert that test pod has the additional network] *************************
2025-10-08 08:52:35.152930 | controller | Wednesday 08 October 2025  08:52:35 +0000 (0:01:02.695)       0:01:05.036 *****
2025-10-08 08:52:35.184994 | controller | ok: [instance] => changed=false
2025-10-08 08:52:35.185135 | controller |   msg: All assertions passed
2025-10-08 08:52:35.185323 | controller |
2025-10-08 08:52:35.185530 | controller | TASK [Delete test pod] *********************************************************
2025-10-08 08:52:35.185755 | controller | Wednesday 08 October 2025  08:52:35 +0000 (0:00:00.033)       0:01:05.070 *****
2025-10-08 08:52:36.059598 | controller | changed: [instance]
2025-10-08 08:52:36.059773 | controller |
2025-10-08 08:52:36.060001 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:52:36.060341 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:52:36.060589 | controller |
2025-10-08 08:52:36.060812 | controller | Wednesday 08 October 2025  08:52:36 +0000 (0:00:00.875)       0:01:05.945 *****
2025-10-08 08:52:36.061012 | controller | ===============================================================================
2025-10-08 08:52:36.061264 | controller | Create a test pod to attach a network ---------------------------------- 62.70s
2025-10-08 08:52:36.061464 | controller | Gathering Facts --------------------------------------------------------- 1.13s
2025-10-08 08:52:36.061672 | controller | Check if NADs were created ---------------------------------------------- 0.97s
2025-10-08 08:52:36.061876 | controller | Delete test pod --------------------------------------------------------- 0.88s
2025-10-08 08:52:36.062119 | controller | Compare each corresponding element in the lists ------------------------- 0.07s
2025-10-08 08:52:36.062322 | controller | Ensure both lists have the same length ---------------------------------- 0.06s
2025-10-08 08:52:36.062524 | controller | Store output spec ------------------------------------------------------- 0.05s
2025-10-08 08:52:36.062729 | controller | Include default vars ---------------------------------------------------- 0.04s
2025-10-08 08:52:36.062938 | controller | Assert that test pod has the additional network ------------------------- 0.03s
2025-10-08 08:52:36.135988 | controller | INFO     Verifier completed successfully.
2025-10-08 08:52:36.159026 | controller | INFO     Running default > side_effect
2025-10-08 08:52:36.679744 | controller |
2025-10-08 08:52:36.679960 | controller | PLAY [Clean] *******************************************************************
2025-10-08 08:52:36.680210 | controller |
2025-10-08 08:52:36.680453 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:52:36.680680 | controller | Wednesday 08 October 2025  08:52:36 +0000 (0:00:00.016)       0:00:00.016 *****
2025-10-08 08:52:38.884726 | controller | ok: [instance]
2025-10-08 08:52:38.884843 | controller |
2025-10-08 08:52:38.885112 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-08 08:52:38.885334 | controller | Wednesday 08 October 2025  08:52:38 +0000 (0:00:02.204)       0:00:02.221 *****
2025-10-08 08:52:39.268634 | controller | changed: [instance]
2025-10-08 08:52:39.268755 | controller |
2025-10-08 08:52:39.269113 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-08 08:52:39.269497 | controller | Wednesday 08 October 2025  08:52:39 +0000 (0:00:00.383)       0:00:02.605 *****
2025-10-08 08:52:40.284831 | controller | ok: [instance]
2025-10-08 08:52:40.284929 | controller |
2025-10-08 08:52:40.285132 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-08 08:52:40.285160 | controller | Wednesday 08 October 2025  08:52:40 +0000 (0:00:01.015)       0:00:03.621 *****
2025-10-08 08:52:42.931616 | controller | changed: [instance] => (item=bridge-to-linux-bridge)
2025-10-08 08:52:42.931703 | controller | changed: [instance] => (item=default)
2025-10-08 08:52:42.931809 | controller | changed: [instance] => (item=patchnetwork)
2025-10-08 08:52:42.931932 | controller |
2025-10-08 08:52:42.932121 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-08 08:52:42.932259 | controller | Wednesday 08 October 2025  08:52:42 +0000 (0:00:02.647)       0:00:06.268 *****
2025-10-08 08:52:43.322868 | controller | changed: [instance]
2025-10-08 08:52:43.322936 | controller |
2025-10-08 08:52:43.323119 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:52:43.323136 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:52:43.323202 | controller |
2025-10-08 08:52:43.323292 | controller | Wednesday 08 October 2025  08:52:43 +0000 (0:00:00.392)       0:00:06.660 *****
2025-10-08 08:52:43.323373 | controller | ===============================================================================
2025-10-08 08:52:43.323465 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 2.65s
2025-10-08 08:52:43.323571 | controller | Gathering Facts --------------------------------------------------------- 2.21s
2025-10-08 08:52:43.323679 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 1.02s
2025-10-08 08:52:43.323773 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.39s
2025-10-08 08:52:43.323878 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.38s
2025-10-08 08:52:43.431796 | controller | INFO     Running default > verify
2025-10-08 08:52:43.432074 | controller | INFO     Running Ansible Verifier
2025-10-08 08:52:44.007659 | controller |
2025-10-08 08:52:44.007732 | controller | PLAY [Verify Clean] ************************************************************
2025-10-08 08:52:44.007843 | controller |
2025-10-08 08:52:44.008032 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:52:44.008217 | controller | Wednesday 08 October 2025  08:52:44 +0000 (0:00:00.023)       0:00:00.023 *****
2025-10-08 08:52:45.202172 | controller | ok: [instance]
2025-10-08 08:52:45.235253 | controller |
2025-10-08 08:52:45.235302 | controller | TASK [Include default vars] ****************************************************
2025-10-08 08:52:45.235310 | controller | Wednesday 08 October 2025  08:52:45 +0000 (0:00:01.194)       0:00:01.217 *****
2025-10-08 08:52:45.235322 | controller | ok: [instance]
2025-10-08 08:52:45.235368 | controller |
2025-10-08 08:52:45.235379 | controller | TASK [Fetch file stat results] *************************************************
2025-10-08 08:52:45.235543 | controller | Wednesday 08 October 2025  08:52:45 +0000 (0:00:00.033)       0:00:01.251 *****
2025-10-08 08:52:45.657592 | controller | ok: [instance]
2025-10-08 08:52:45.657687 | controller |
2025-10-08 08:52:45.657811 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-08 08:52:45.658080 | controller | Wednesday 08 October 2025  08:52:45 +0000 (0:00:00.422)       0:00:01.673 *****
2025-10-08 08:52:45.723793 | controller | ok: [instance] => changed=false
2025-10-08 08:52:45.723893 | controller |   msg: All assertions passed
2025-10-08 08:52:45.723944 | controller |
2025-10-08 08:52:45.724580 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:52:45.725289 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:52:45.725368 | controller |
2025-10-08 08:52:45.725691 | controller | Wednesday 08 October 2025  08:52:45 +0000 (0:00:00.067)       0:00:01.740 *****
2025-10-08 08:52:45.725750 | controller | ===============================================================================
2025-10-08 08:52:45.725758 | controller | Gathering Facts --------------------------------------------------------- 1.19s
2025-10-08 08:52:45.725764 | controller | Fetch file stat results ------------------------------------------------- 0.42s
2025-10-08 08:52:45.725778 | controller | Assert that created file are removed by cleanup ------------------------- 0.07s
2025-10-08 08:52:45.725924 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-08 08:52:45.822286 | controller | INFO     Verifier completed successfully.
2025-10-08 08:52:45.846180 | controller | INFO     Running default > verify
2025-10-08 08:52:45.846607 | controller | INFO     Running Ansible Verifier
2025-10-08 08:52:46.415180 | controller |
2025-10-08 08:52:46.415413 | controller | PLAY [Verify Clean CRC] ********************************************************
2025-10-08 08:52:46.415666 | controller |
2025-10-08 08:52:46.415881 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:52:46.416129 | controller | Wednesday 08 October 2025  08:52:46 +0000 (0:00:00.018)       0:00:00.018 *****
2025-10-08 08:52:47.628587 | controller | ok: [instance]
2025-10-08 08:52:47.628689 | controller |
2025-10-08 08:52:47.628704 | controller | TASK [Include default vars] ****************************************************
2025-10-08 08:52:47.628775 | controller | Wednesday 08 October 2025  08:52:47 +0000 (0:00:01.213)       0:00:01.232 *****
2025-10-08 08:52:47.676600 | controller | ok: [instance]
2025-10-08 08:52:47.676668 | controller |
2025-10-08 08:52:47.676699 | controller | TASK [Check if NADs were deleted] **********************************************
2025-10-08 08:52:47.676929 | controller | Wednesday 08 October 2025  08:52:47 +0000 (0:00:00.048)       0:00:01.280 *****
2025-10-08 08:52:48.785615 | controller | ok: [instance]
2025-10-08 08:52:48.906557 | controller |
2025-10-08 08:52:48.906614 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:52:48.906625 | controller | instance                   : ok=3    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:52:48.906633 | controller |
2025-10-08 08:52:48.906640 | controller | Wednesday 08 October 2025  08:52:48 +0000 (0:00:01.108)       0:00:02.389 *****
2025-10-08 08:52:48.906648 | controller | ===============================================================================
2025-10-08 08:52:48.906656 | controller | Gathering Facts --------------------------------------------------------- 1.21s
2025-10-08 08:52:48.906663 | controller | Check if NADs were deleted ---------------------------------------------- 1.11s
2025-10-08 08:52:48.906670 | controller | Include default vars ---------------------------------------------------- 0.05s
2025-10-08 08:52:48.906686 | controller | INFO     Verifier completed successfully.
2025-10-08 08:52:48.907324 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-08 08:52:48.923457 | controller | INFO     Running local > destroy
2025-10-08 08:52:48.923998 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 08:52:48.939826 | controller | INFO     Running local > create
2025-10-08 08:52:48.941488 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 08:52:48.952847 | controller | INFO     Running local > converge
2025-10-08 08:52:49.517252 | controller |
2025-10-08 08:52:49.517552 | controller | PLAY [Converge] ****************************************************************
2025-10-08 08:52:49.517755 | controller |
2025-10-08 08:52:49.518003 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:52:49.518262 | controller | Wednesday 08 October 2025  08:52:49 +0000 (0:00:00.018)       0:00:00.018 *****
2025-10-08 08:52:51.677220 | controller | ok: [instance]
2025-10-08 08:52:51.703147 | controller |
2025-10-08 08:52:51.703198 | controller | TASK [Load shared variables] ***************************************************
2025-10-08 08:52:51.703206 | controller | Wednesday 08 October 2025  08:52:51 +0000 (0:00:02.160)       0:00:02.178 *****
2025-10-08 08:52:51.703219 | controller | ok: [instance]
2025-10-08 08:52:51.703592 | controller |
2025-10-08 08:52:51.703606 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-08 08:52:51.703615 | controller | Wednesday 08 October 2025  08:52:51 +0000 (0:00:00.026)       0:00:02.205 *****
2025-10-08 08:52:51.751021 | controller |
2025-10-08 08:52:52.172704 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-08 08:52:52.172755 | controller | Wednesday 08 October 2025  08:52:51 +0000 (0:00:00.047)       0:00:02.252 *****
2025-10-08 08:52:52.172770 | controller | changed: [instance]
2025-10-08 08:52:52.210990 | controller |
2025-10-08 08:52:52.211049 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-08 08:52:52.211096 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.420)       0:00:02.673 *****
2025-10-08 08:52:52.211117 | controller | skipping: [instance]
2025-10-08 08:52:52.253562 | controller |
2025-10-08 08:52:52.253618 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-08 08:52:52.253630 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.037)       0:00:02.711 *****
2025-10-08 08:52:52.253647 | controller | skipping: [instance]
2025-10-08 08:52:52.289469 | controller |
2025-10-08 08:52:52.289539 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-08 08:52:52.289553 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.043)       0:00:02.754 *****
2025-10-08 08:52:52.289574 | controller | skipping: [instance]
2025-10-08 08:52:52.329803 | controller |
2025-10-08 08:52:52.329858 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-08 08:52:52.329881 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.036)       0:00:02.790 *****
2025-10-08 08:52:52.329897 | controller | skipping: [instance]
2025-10-08 08:52:52.378779 | controller |
2025-10-08 08:52:52.378833 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-08 08:52:52.378846 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.040)       0:00:02.831 *****
2025-10-08 08:52:52.378870 | controller | ok: [instance]
2025-10-08 08:52:52.563329 | controller |
2025-10-08 08:52:52.563390 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-08 08:52:52.563400 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.048)       0:00:02.880 *****
2025-10-08 08:52:52.563418 | 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-10-08 08:52:52.643864 | 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-10-08 08:52:52.643916 | controller |
2025-10-08 08:52:52.643925 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-08 08:52:52.643932 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.184)       0:00:03.064 *****
2025-10-08 08:52:52.643945 | controller | ok: [instance]
2025-10-08 08:52:52.732313 | controller |
2025-10-08 08:52:52.732384 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-08 08:52:52.732392 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.075)       0:00:03.140 *****
2025-10-08 08:52:52.732406 | controller | ok: [instance]
2025-10-08 08:52:52.815995 | controller |
2025-10-08 08:52:52.816082 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-08 08:52:52.816093 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.088)       0:00:03.229 *****
2025-10-08 08:52:52.816107 | controller | ok: [instance]
2025-10-08 08:52:53.575563 | controller |
2025-10-08 08:52:53.575651 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-08 08:52:53.575661 | controller | Wednesday 08 October 2025  08:52:52 +0000 (0:00:00.082)       0:00:03.311 *****
2025-10-08 08:52:53.575674 | controller | changed: [instance]
2025-10-08 08:52:53.608147 | controller |
2025-10-08 08:52:53.608446 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-08 08:52:53.608461 | controller | Wednesday 08 October 2025  08:52:53 +0000 (0:00:00.758)       0:00:04.070 *****
2025-10-08 08:52:53.608482 | controller | skipping: [instance]
2025-10-08 08:52:53.659236 | controller |
2025-10-08 08:52:53.659321 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-08 08:52:53.659330 | controller | Wednesday 08 October 2025  08:52:53 +0000 (0:00:00.035)       0:00:04.105 *****
2025-10-08 08:52:53.659344 | controller | skipping: [instance]
2025-10-08 08:52:53.659369 | controller |
2025-10-08 08:52:53.659378 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:52:53.659548 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-10-08 08:52:53.659661 | controller |
2025-10-08 08:52:53.659781 | controller | Wednesday 08 October 2025  08:52:53 +0000 (0:00:00.055)       0:00:04.161 *****
2025-10-08 08:52:53.659894 | controller | ===============================================================================
2025-10-08 08:52:53.659990 | controller | Gathering Facts --------------------------------------------------------- 2.16s
2025-10-08 08:52:53.660130 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.76s
2025-10-08 08:52:53.660220 | controller | ci_multus : Create manifests directory ---------------------------------- 0.42s
2025-10-08 08:52:53.660319 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.18s
2025-10-08 08:52:53.660466 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.09s
2025-10-08 08:52:53.660550 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.08s
2025-10-08 08:52:53.660647 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.08s
2025-10-08 08:52:53.660773 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.06s
2025-10-08 08:52:53.660927 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.05s
2025-10-08 08:52:53.661069 | controller | Call ci_multus role ----------------------------------------------------- 0.05s
2025-10-08 08:52:53.661182 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.04s
2025-10-08 08:52:53.661276 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-10-08 08:52:53.661384 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.04s
2025-10-08 08:52:53.661512 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.04s
2025-10-08 08:52:53.661624 | controller | ci_multus : Create the multus namespace --------------------------------- 0.04s
2025-10-08 08:52:53.661720 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-08 08:52:53.761311 | controller | INFO     Running local > verify
2025-10-08 08:52:53.761517 | controller | INFO     Running Ansible Verifier
2025-10-08 08:52:54.303422 | controller |
2025-10-08 08:52:54.303649 | controller | PLAY [Verify] ******************************************************************
2025-10-08 08:52:54.303879 | controller |
2025-10-08 08:52:54.304300 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:52:54.304532 | controller | Wednesday 08 October 2025  08:52:54 +0000 (0:00:00.018)       0:00:00.018 *****
2025-10-08 08:52:55.390195 | controller | ok: [instance]
2025-10-08 08:52:55.390273 | controller |
2025-10-08 08:52:55.390481 | controller | TASK [Load shared variables] ***************************************************
2025-10-08 08:52:55.390617 | controller | Wednesday 08 October 2025  08:52:55 +0000 (0:00:01.087)       0:00:01.105 *****
2025-10-08 08:52:55.425764 | controller | ok: [instance]
2025-10-08 08:52:55.425843 | controller |
2025-10-08 08:52:55.425985 | controller | TASK [Fetch files stat results] ************************************************
2025-10-08 08:52:55.426245 | controller | Wednesday 08 October 2025  08:52:55 +0000 (0:00:00.035)       0:00:01.140 *****
2025-10-08 08:52:55.822420 | controller | ok: [instance]
2025-10-08 08:52:55.822527 | controller |
2025-10-08 08:52:55.822622 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-08 08:52:55.822633 | controller | Wednesday 08 October 2025  08:52:55 +0000 (0:00:00.396)       0:00:01.537 *****
2025-10-08 08:52:55.853034 | controller | ok: [instance] => changed=false
2025-10-08 08:52:55.853153 | controller |   msg: All assertions passed
2025-10-08 08:52:55.853166 | controller |
2025-10-08 08:52:55.853293 | controller | TASK [Fetch file content] ******************************************************
2025-10-08 08:52:55.853400 | controller | Wednesday 08 October 2025  08:52:55 +0000 (0:00:00.030)       0:00:01.568 *****
2025-10-08 08:52:56.249474 | controller | ok: [instance]
2025-10-08 08:52:56.249561 | controller |
2025-10-08 08:52:56.249654 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-08 08:52:56.249740 | controller | Wednesday 08 October 2025  08:52:56 +0000 (0:00:00.396)       0:00:01.964 *****
2025-10-08 08:52:56.291566 | controller | ok: [instance]
2025-10-08 08:52:56.291623 | controller |
2025-10-08 08:52:56.291779 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-08 08:52:56.291908 | controller | Wednesday 08 October 2025  08:52:56 +0000 (0:00:00.042)       0:00:02.006 *****
2025-10-08 08:52:56.350351 | controller | ok: [instance]
2025-10-08 08:52:56.350415 | controller |
2025-10-08 08:52:56.350608 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:52:56.350728 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:52:56.350820 | controller |
2025-10-08 08:52:56.350935 | controller | Wednesday 08 October 2025  08:52:56 +0000 (0:00:00.059)       0:00:02.066 *****
2025-10-08 08:52:56.351021 | controller | ===============================================================================
2025-10-08 08:52:56.351179 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2025-10-08 08:52:56.351315 | controller | Fetch files stat results ------------------------------------------------ 0.40s
2025-10-08 08:52:56.351515 | controller | Fetch file content ------------------------------------------------------ 0.40s
2025-10-08 08:52:56.351670 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-10-08 08:52:56.351789 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-10-08 08:52:56.351961 | controller | Load shared variables --------------------------------------------------- 0.04s
2025-10-08 08:52:56.352085 | controller | Assert that expected file exist ----------------------------------------- 0.03s
2025-10-08 08:52:56.445271 | controller | INFO     Verifier completed successfully.
2025-10-08 08:52:56.460076 | controller | INFO     Running local > side_effect
2025-10-08 08:52:57.009422 | controller |
2025-10-08 08:52:57.009533 | controller | PLAY [Clean] *******************************************************************
2025-10-08 08:52:57.009724 | controller |
2025-10-08 08:52:57.009865 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:52:57.010013 | controller | Wednesday 08 October 2025  08:52:57 +0000 (0:00:00.021)       0:00:00.021 *****
2025-10-08 08:52:58.053618 | controller | ok: [instance]
2025-10-08 08:52:58.053874 | controller |
2025-10-08 08:52:58.053955 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-08 08:52:58.053983 | controller | Wednesday 08 October 2025  08:52:58 +0000 (0:00:01.045)       0:00:01.066 *****
2025-10-08 08:52:58.433240 | controller | changed: [instance]
2025-10-08 08:52:58.433311 | controller |
2025-10-08 08:52:58.433343 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-08 08:52:58.433353 | controller | Wednesday 08 October 2025  08:52:58 +0000 (0:00:00.379)       0:00:01.446 *****
2025-10-08 08:52:58.460464 | controller | skipping: [instance]
2025-10-08 08:52:58.460576 | controller |
2025-10-08 08:52:58.460650 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-08 08:52:58.460878 | controller | Wednesday 08 October 2025  08:52:58 +0000 (0:00:00.027)       0:00:01.473 *****
2025-10-08 08:52:58.503126 | controller | skipping: [instance]
2025-10-08 08:52:58.930283 | controller |
2025-10-08 08:52:58.930340 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-08 08:52:58.930355 | controller | Wednesday 08 October 2025  08:52:58 +0000 (0:00:00.037)       0:00:01.511 *****
2025-10-08 08:52:58.930377 | controller | changed: [instance]
2025-10-08 08:52:59.029648 | controller |
2025-10-08 08:52:59.029706 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:52:59.029718 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-08 08:52:59.029727 | controller |
2025-10-08 08:52:59.029736 | controller | Wednesday 08 October 2025  08:52:58 +0000 (0:00:00.431)       0:00:01.942 *****
2025-10-08 08:52:59.029745 | controller | ===============================================================================
2025-10-08 08:52:59.029754 | controller | Gathering Facts --------------------------------------------------------- 1.05s
2025-10-08 08:52:59.029763 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.43s
2025-10-08 08:52:59.029771 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.38s
2025-10-08 08:52:59.029789 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.04s
2025-10-08 08:52:59.029798 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s
2025-10-08 08:52:59.029816 | controller | INFO     Running local > verify
2025-10-08 08:52:59.030329 | controller | INFO     Running Ansible Verifier
2025-10-08 08:52:59.554756 | controller |
2025-10-08 08:53:00.593741 | controller | PLAY [Verify Clean] ************************************************************
2025-10-08 08:53:00.593803 | controller |
2025-10-08 08:53:00.593815 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:53:00.593824 | controller | Wednesday 08 October 2025  08:52:59 +0000 (0:00:00.019)       0:00:00.019 *****
2025-10-08 08:53:00.593839 | controller | ok: [instance]
2025-10-08 08:53:00.632189 | controller |
2025-10-08 08:53:00.632245 | controller | TASK [Include default vars] ****************************************************
2025-10-08 08:53:00.632257 | controller | Wednesday 08 October 2025  08:53:00 +0000 (0:00:01.039)       0:00:01.059 *****
2025-10-08 08:53:00.632272 | controller | ok: [instance]
2025-10-08 08:53:00.632398 | controller |
2025-10-08 08:53:00.632413 | controller | TASK [Fetch file stat results] *************************************************
2025-10-08 08:53:00.632426 | controller | Wednesday 08 October 2025  08:53:00 +0000 (0:00:00.033)       0:00:01.093 *****
2025-10-08 08:53:00.996314 | controller | ok: [instance]
2025-10-08 08:53:01.049781 | controller |
2025-10-08 08:53:01.049829 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-08 08:53:01.049838 | controller | Wednesday 08 October 2025  08:53:00 +0000 (0:00:00.362)       0:00:01.456 *****
2025-10-08 08:53:01.049849 | controller | ok: [instance] => changed=false
2025-10-08 08:53:01.144492 | controller |   msg: All assertions passed
2025-10-08 08:53:01.144559 | controller |
2025-10-08 08:53:01.144576 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:53:01.144590 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:53:01.144603 | controller |
2025-10-08 08:53:01.144617 | controller | Wednesday 08 October 2025  08:53:01 +0000 (0:00:00.059)       0:00:01.516 *****
2025-10-08 08:53:01.144631 | controller | ===============================================================================
2025-10-08 08:53:01.144644 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2025-10-08 08:53:01.144657 | controller | Fetch file stat results ------------------------------------------------- 0.36s
2025-10-08 08:53:01.144670 | controller | Assert that created file are removed by cleanup ------------------------- 0.06s
2025-10-08 08:53:01.144683 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-08 08:53:01.144722 | controller | INFO     Verifier completed successfully.
2025-10-08 08:53:01.145661 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-08 08:53:01.168235 | controller | INFO     Running local_ipv6 > destroy
2025-10-08 08:53:01.169037 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 08:53:01.189757 | controller | INFO     Running local_ipv6 > create
2025-10-08 08:53:01.192318 | controller | WARNING  Skipping, instances are delegated.
2025-10-08 08:53:01.210593 | controller | INFO     Running local_ipv6 > converge
2025-10-08 08:53:01.789139 | controller |
2025-10-08 08:53:02.893711 | controller | PLAY [Converge] ****************************************************************
2025-10-08 08:53:02.893767 | controller |
2025-10-08 08:53:02.893778 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:53:02.893795 | controller | Wednesday 08 October 2025  08:53:01 +0000 (0:00:00.016)       0:00:00.016 *****
2025-10-08 08:53:02.893812 | controller | ok: [instance]
2025-10-08 08:53:02.893843 | controller |
2025-10-08 08:53:02.894014 | controller | TASK [Load shared variables] ***************************************************
2025-10-08 08:53:02.894031 | controller | Wednesday 08 October 2025  08:53:02 +0000 (0:00:01.105)       0:00:01.122 *****
2025-10-08 08:53:02.919617 | controller | ok: [instance]
2025-10-08 08:53:02.919710 | controller |
2025-10-08 08:53:02.919827 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-08 08:53:02.919943 | controller | Wednesday 08 October 2025  08:53:02 +0000 (0:00:00.026)       0:00:01.148 *****
2025-10-08 08:53:02.968771 | controller |
2025-10-08 08:53:02.968829 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-08 08:53:02.968877 | controller | Wednesday 08 October 2025  08:53:02 +0000 (0:00:00.048)       0:00:01.197 *****
2025-10-08 08:53:03.390162 | controller | changed: [instance]
2025-10-08 08:53:03.414574 | controller |
2025-10-08 08:53:03.414624 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-08 08:53:03.414633 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.411)       0:00:01.608 *****
2025-10-08 08:53:03.414646 | controller | skipping: [instance]
2025-10-08 08:53:03.414798 | controller |
2025-10-08 08:53:03.414948 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-08 08:53:03.415141 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.030)       0:00:01.638 *****
2025-10-08 08:53:03.452299 | controller | skipping: [instance]
2025-10-08 08:53:03.452484 | controller |
2025-10-08 08:53:03.452643 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-08 08:53:03.452786 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.037)       0:00:01.676 *****
2025-10-08 08:53:03.490103 | controller | skipping: [instance]
2025-10-08 08:53:03.528623 | controller |
2025-10-08 08:53:03.528667 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-08 08:53:03.528675 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.041)       0:00:01.718 *****
2025-10-08 08:53:03.528687 | controller | skipping: [instance]
2025-10-08 08:53:03.574407 | controller |
2025-10-08 08:53:03.574463 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-08 08:53:03.574471 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.037)       0:00:01.755 *****
2025-10-08 08:53:03.574484 | controller | ok: [instance]
2025-10-08 08:53:03.696133 | controller |
2025-10-08 08:53:03.696191 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-08 08:53:03.696200 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.043)       0:00:01.798 *****
2025-10-08 08:53:03.696221 | 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-10-08 08:53:03.782221 | controller | skipping: [instance]
2025-10-08 08:53:03.782287 | controller |
2025-10-08 08:53:03.782297 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-08 08:53:03.782304 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.124)       0:00:01.923 *****
2025-10-08 08:53:03.782316 | controller | ok: [instance]
2025-10-08 08:53:03.864181 | controller |
2025-10-08 08:53:03.864231 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-08 08:53:03.864239 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.082)       0:00:02.006 *****
2025-10-08 08:53:03.864252 | controller | ok: [instance]
2025-10-08 08:53:03.864711 | controller |
2025-10-08 08:53:03.947713 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-08 08:53:03.947763 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.081)       0:00:02.087 *****
2025-10-08 08:53:03.947777 | controller | ok: [instance]
2025-10-08 08:53:04.686935 | controller |
2025-10-08 08:53:04.686999 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-08 08:53:04.687011 | controller | Wednesday 08 October 2025  08:53:03 +0000 (0:00:00.083)       0:00:02.171 *****
2025-10-08 08:53:04.687027 | controller | changed: [instance]
2025-10-08 08:53:04.715196 | controller |
2025-10-08 08:53:04.715247 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-08 08:53:04.715255 | controller | Wednesday 08 October 2025  08:53:04 +0000 (0:00:00.743)       0:00:02.915 *****
2025-10-08 08:53:04.715276 | controller | skipping: [instance]
2025-10-08 08:53:04.715450 | controller |
2025-10-08 08:53:04.715463 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-08 08:53:04.715472 | controller | Wednesday 08 October 2025  08:53:04 +0000 (0:00:00.024)       0:00:02.939 *****
2025-10-08 08:53:04.761208 | controller | skipping: [instance]
2025-10-08 08:53:04.762311 | controller |
2025-10-08 08:53:04.762329 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:53:04.762337 | controller | instance                   : ok=8    changed=2    unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-10-08 08:53:04.762344 | controller |
2025-10-08 08:53:04.762350 | controller | Wednesday 08 October 2025  08:53:04 +0000 (0:00:00.049)       0:00:02.989 *****
2025-10-08 08:53:04.762358 | controller | ===============================================================================
2025-10-08 08:53:04.762364 | controller | Gathering Facts --------------------------------------------------------- 1.11s
2025-10-08 08:53:04.762371 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.74s
2025-10-08 08:53:04.762378 | controller | ci_multus : Create manifests directory ---------------------------------- 0.41s
2025-10-08 08:53:04.762384 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.12s
2025-10-08 08:53:04.762390 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.08s
2025-10-08 08:53:04.762396 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.08s
2025-10-08 08:53:04.762405 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
2025-10-08 08:53:04.889016 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.05s
2025-10-08 08:53:04.889143 | controller | Call ci_multus role ----------------------------------------------------- 0.05s
2025-10-08 08:53:04.889168 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.04s
2025-10-08 08:53:04.889188 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.04s
2025-10-08 08:53:04.889207 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.04s
2025-10-08 08:53:04.889227 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-10-08 08:53:04.889246 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2025-10-08 08:53:04.889265 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-08 08:53:04.889284 | controller | ci_multus : Create the multus namespace --------------------------------- 0.02s
2025-10-08 08:53:04.889317 | controller | INFO     Running local_ipv6 > verify
2025-10-08 08:53:04.890367 | controller | INFO     Running Ansible Verifier
2025-10-08 08:53:05.474609 | controller |
2025-10-08 08:53:06.547652 | controller | PLAY [Verify] ******************************************************************
2025-10-08 08:53:06.547708 | controller |
2025-10-08 08:53:06.547719 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:53:06.547727 | controller | Wednesday 08 October 2025  08:53:05 +0000 (0:00:00.018)       0:00:00.018 *****
2025-10-08 08:53:06.547743 | controller | ok: [instance]
2025-10-08 08:53:06.578950 | controller |
2025-10-08 08:53:06.579012 | controller | TASK [Load shared variables] ***************************************************
2025-10-08 08:53:06.579026 | controller | Wednesday 08 October 2025  08:53:06 +0000 (0:00:01.075)       0:00:01.093 *****
2025-10-08 08:53:06.579045 | controller | ok: [instance]
2025-10-08 08:53:06.977252 | controller |
2025-10-08 08:53:06.977309 | controller | TASK [Fetch files stat results] ************************************************
2025-10-08 08:53:06.977318 | controller | Wednesday 08 October 2025  08:53:06 +0000 (0:00:00.030)       0:00:01.123 *****
2025-10-08 08:53:06.977330 | controller | ok: [instance]
2025-10-08 08:53:07.007902 | controller |
2025-10-08 08:53:07.007966 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-08 08:53:07.007976 | controller | Wednesday 08 October 2025  08:53:06 +0000 (0:00:00.394)       0:00:01.518 *****
2025-10-08 08:53:07.007992 | controller | ok: [instance] => changed=false
2025-10-08 08:53:07.337346 | controller |   msg: All assertions passed
2025-10-08 08:53:07.337442 | controller |
2025-10-08 08:53:07.337453 | controller | TASK [Fetch file content] ******************************************************
2025-10-08 08:53:07.337473 | controller | Wednesday 08 October 2025  08:53:06 +0000 (0:00:00.031)       0:00:01.549 *****
2025-10-08 08:53:07.337492 | controller | ok: [instance]
2025-10-08 08:53:07.337517 | controller |
2025-10-08 08:53:07.337525 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-08 08:53:07.337534 | controller | Wednesday 08 October 2025  08:53:07 +0000 (0:00:00.331)       0:00:01.880 *****
2025-10-08 08:53:07.384934 | controller | ok: [instance]
2025-10-08 08:53:07.385044 | controller |
2025-10-08 08:53:07.385076 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-08 08:53:07.385121 | controller | Wednesday 08 October 2025  08:53:07 +0000 (0:00:00.045)       0:00:01.926 *****
2025-10-08 08:53:07.439317 | controller | ok: [instance]
2025-10-08 08:53:07.439401 | controller |
2025-10-08 08:53:07.439516 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:53:07.439529 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:53:07.439648 | controller |
2025-10-08 08:53:07.439802 | controller | Wednesday 08 October 2025  08:53:07 +0000 (0:00:00.059)       0:00:01.986 *****
2025-10-08 08:53:07.439914 | controller | ===============================================================================
2025-10-08 08:53:07.440032 | controller | Gathering Facts --------------------------------------------------------- 1.08s
2025-10-08 08:53:07.440163 | controller | Fetch files stat results ------------------------------------------------ 0.39s
2025-10-08 08:53:07.440251 | controller | Fetch file content ------------------------------------------------------ 0.33s
2025-10-08 08:53:07.440374 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-10-08 08:53:07.440505 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.05s
2025-10-08 08:53:07.440648 | controller | Assert that expected file exist ----------------------------------------- 0.03s
2025-10-08 08:53:07.440796 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-08 08:53:07.536045 | controller | INFO     Verifier completed successfully.
2025-10-08 08:53:07.547124 | controller | INFO     Running local_ipv6 > side_effect
2025-10-08 08:53:08.117425 | controller |
2025-10-08 08:53:08.117515 | controller | PLAY [Clean] *******************************************************************
2025-10-08 08:53:08.117623 | controller |
2025-10-08 08:53:08.117758 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:53:08.117867 | controller | Wednesday 08 October 2025  08:53:08 +0000 (0:00:00.017)       0:00:00.017 *****
2025-10-08 08:53:09.293049 | controller | ok: [instance]
2025-10-08 08:53:09.293404 | controller |
2025-10-08 08:53:09.736148 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-08 08:53:09.736199 | controller | Wednesday 08 October 2025  08:53:09 +0000 (0:00:01.175)       0:00:01.193 *****
2025-10-08 08:53:09.736212 | controller | changed: [instance]
2025-10-08 08:53:09.768085 | controller |
2025-10-08 08:53:09.768156 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-08 08:53:09.768168 | controller | Wednesday 08 October 2025  08:53:09 +0000 (0:00:00.443)       0:00:01.636 *****
2025-10-08 08:53:09.768186 | controller | skipping: [instance]
2025-10-08 08:53:09.802860 | controller |
2025-10-08 08:53:09.802923 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-08 08:53:09.802932 | controller | Wednesday 08 October 2025  08:53:09 +0000 (0:00:00.031)       0:00:01.668 *****
2025-10-08 08:53:09.802947 | controller | skipping: [instance]
2025-10-08 08:53:09.803101 | controller |
2025-10-08 08:53:09.803117 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-08 08:53:09.803464 | controller | Wednesday 08 October 2025  08:53:09 +0000 (0:00:00.034)       0:00:01.702 *****
2025-10-08 08:53:10.292249 | controller | changed: [instance]
2025-10-08 08:53:10.292326 | controller |
2025-10-08 08:53:10.292347 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:53:10.292367 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-08 08:53:10.292376 | controller |
2025-10-08 08:53:10.292394 | controller | Wednesday 08 October 2025  08:53:10 +0000 (0:00:00.489)       0:00:02.192 *****
2025-10-08 08:53:10.292403 | controller | ===============================================================================
2025-10-08 08:53:10.292414 | controller | Gathering Facts --------------------------------------------------------- 1.18s
2025-10-08 08:53:10.292756 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.49s
2025-10-08 08:53:10.292873 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.44s
2025-10-08 08:53:10.292888 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.03s
2025-10-08 08:53:10.424934 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s
2025-10-08 08:53:10.425099 | controller | INFO     Running local_ipv6 > verify
2025-10-08 08:53:10.425568 | controller | INFO     Running Ansible Verifier
2025-10-08 08:53:10.956145 | controller |
2025-10-08 08:53:12.109864 | controller | PLAY [Verify Clean] ************************************************************
2025-10-08 08:53:12.109965 | controller |
2025-10-08 08:53:12.109975 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 08:53:12.109981 | controller | Wednesday 08 October 2025  08:53:10 +0000 (0:00:00.016)       0:00:00.016 *****
2025-10-08 08:53:12.109994 | controller | ok: [instance]
2025-10-08 08:53:12.141529 | controller |
2025-10-08 08:53:12.141592 | controller | TASK [Include default vars] ****************************************************
2025-10-08 08:53:12.141604 | controller | Wednesday 08 October 2025  08:53:12 +0000 (0:00:01.155)       0:00:01.172 *****
2025-10-08 08:53:12.141620 | controller | ok: [instance]
2025-10-08 08:53:12.141656 | controller |
2025-10-08 08:53:12.141816 | controller | TASK [Fetch file stat results] *************************************************
2025-10-08 08:53:12.141831 | controller | Wednesday 08 October 2025  08:53:12 +0000 (0:00:00.031)       0:00:01.203 *****
2025-10-08 08:53:12.545677 | controller | ok: [instance]
2025-10-08 08:53:12.545798 | controller |
2025-10-08 08:53:12.545857 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-08 08:53:12.546023 | controller | Wednesday 08 October 2025  08:53:12 +0000 (0:00:00.404)       0:00:01.608 *****
2025-10-08 08:53:12.589783 | controller | ok: [instance] => changed=false
2025-10-08 08:53:12.589965 | controller |   msg: All assertions passed
2025-10-08 08:53:12.590269 | controller |
2025-10-08 08:53:12.590582 | controller | PLAY RECAP *********************************************************************
2025-10-08 08:53:12.590849 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-08 08:53:12.591082 | controller |
2025-10-08 08:53:12.591327 | controller | Wednesday 08 October 2025  08:53:12 +0000 (0:00:00.044)       0:00:01.652 *****
2025-10-08 08:53:12.591547 | controller | ===============================================================================
2025-10-08 08:53:12.591798 | controller | Gathering Facts --------------------------------------------------------- 1.16s
2025-10-08 08:53:12.592078 | controller | Fetch file stat results ------------------------------------------------- 0.40s
2025-10-08 08:53:12.592358 | controller | Assert that created file are removed by cleanup ------------------------- 0.04s
2025-10-08 08:53:12.592577 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-08 08:53:12.695607 | controller | INFO     Verifier completed successfully.
2025-10-08 08:53:12.696483 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-08 08:53:12.700712 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 08:53:12.999499 | controller | changed
2025-10-08 08:53:13.025094 | 
2025-10-08 08:53:13.025212 | PLAY RECAP
2025-10-08 08:53:13.025255 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 08:53:13.025277 | 
2025-10-08 08:53:13.132892 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 08:53:13.134564 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 08:53:13.824063 | 
2025-10-08 08:53:13.824174 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 08:53:13.844592 | 
2025-10-08 08:53:13.844691 | TASK [Filter out host if needed]
2025-10-08 08:53:13.853398 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 08:53:13.857941 | 
2025-10-08 08:53:13.858005 | TASK [Ensure file is present]
2025-10-08 08:53:14.397037 | controller | ok
2025-10-08 08:53:14.403069 | 
2025-10-08 08:53:14.403138 | TASK [Manage molecule report file]
2025-10-08 08:53:14.949703 | controller | changed
2025-10-08 08:53:14.955695 | 
2025-10-08 08:53:14.955763 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 08:53:15.324559 | controller | ok
2025-10-08 08:53:15.330915 | 
2025-10-08 08:53:15.331019 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 08:53:15.817474 | controller | changed
2025-10-08 08:53:15.823586 | 
2025-10-08 08:53:15.823649 | TASK [Copy ci-framework interesting files]
2025-10-08 08:53:16.246255 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 08:53:16.402379 | controller | changed
2025-10-08 08:53:16.410130 | 
2025-10-08 08:53:16.410279 | TASK [Get SELinux listing]
2025-10-08 08:53:16.952167 | controller | changed
2025-10-08 08:53:16.964253 | 
2025-10-08 08:53:16.964400 | TASK [Generate log index]
2025-10-08 08:53:18.800187 | controller | changed
2025-10-08 08:53:18.805237 | 
2025-10-08 08:53:18.805299 | TASK [Get some env related data]
2025-10-08 08:53:19.599586 | controller | /home/zuul/.local/bin/ansible
2025-10-08 08:53:20.338168 | controller | changed
2025-10-08 08:53:20.345219 | 
2025-10-08 08:53:20.345303 | TASK [Generate list of logs to collect in home directory]
2025-10-08 08:53:20.826713 | controller | ok: All paths examined
2025-10-08 08:53:20.835231 | 
2025-10-08 08:53:20.835334 | LOOP [Copy logs from home directory]
2025-10-08 08:53:21.799199 | controller | changed:
2025-10-08 08:53:21.799501 | controller | {
2025-10-08 08:53:21.799538 | controller |   "atime": 1743544925.4788878,
2025-10-08 08:53:21.799564 | controller |   "ctime": 1743545329.1409318,
2025-10-08 08:53:21.799587 | controller |   "dev": 64513,
2025-10-08 08:53:21.799610 | controller |   "gid": 1000,
2025-10-08 08:53:21.799632 | controller |   "gr_name": "zuul",
2025-10-08 08:53:21.799655 | controller |   "inode": 4518807,
2025-10-08 08:53:21.799706 | controller |   "isblk": false,
2025-10-08 08:53:21.799738 | controller |   "ischr": false,
2025-10-08 08:53:21.799761 | controller |   "isdir": false,
2025-10-08 08:53:21.799784 | controller |   "isfifo": false,
2025-10-08 08:53:21.799806 | controller |   "isgid": false,
2025-10-08 08:53:21.799828 | controller |   "islnk": false,
2025-10-08 08:53:21.799849 | controller |   "isreg": true,
2025-10-08 08:53:21.799892 | controller |   "issock": false,
2025-10-08 08:53:21.799914 | controller |   "isuid": false,
2025-10-08 08:53:21.799934 | controller |   "mode": "0644",
2025-10-08 08:53:21.799954 | controller |   "mtime": 1743545329.1409318,
2025-10-08 08:53:21.799974 | controller |   "nlink": 1,
2025-10-08 08:53:21.799994 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-08 08:53:21.800018 | controller |   "pw_name": "zuul",
2025-10-08 08:53:21.800072 | controller |   "rgrp": true,
2025-10-08 08:53:21.800103 | controller |   "roth": true,
2025-10-08 08:53:21.800124 | controller |   "rusr": true,
2025-10-08 08:53:21.800146 | controller |   "size": 4108,
2025-10-08 08:53:21.800168 | controller |   "uid": 1000,
2025-10-08 08:53:21.800192 | controller |   "wgrp": false,
2025-10-08 08:53:21.800215 | controller |   "woth": false,
2025-10-08 08:53:21.800241 | controller |   "wusr": true,
2025-10-08 08:53:21.800265 | controller |   "xgrp": false,
2025-10-08 08:53:21.800288 | controller |   "xoth": false,
2025-10-08 08:53:21.800311 | controller |   "xusr": false
2025-10-08 08:53:21.800334 | controller | }
2025-10-08 08:53:22.735728 | controller | changed:
2025-10-08 08:53:22.735925 | controller | {
2025-10-08 08:53:22.736002 | controller |   "atime": 1759912695.0906603,
2025-10-08 08:53:22.736061 | controller |   "ctime": 1759912692.62659,
2025-10-08 08:53:22.736115 | controller |   "dev": 64513,
2025-10-08 08:53:22.736166 | controller |   "gid": 1000,
2025-10-08 08:53:22.736222 | controller |   "gr_name": "zuul",
2025-10-08 08:53:22.736274 | controller |   "inode": 4194437,
2025-10-08 08:53:22.736356 | controller |   "isblk": false,
2025-10-08 08:53:22.736410 | controller |   "ischr": false,
2025-10-08 08:53:22.736463 | controller |   "isdir": false,
2025-10-08 08:53:22.736516 | controller |   "isfifo": false,
2025-10-08 08:53:22.736587 | controller |   "isgid": false,
2025-10-08 08:53:22.736654 | controller |   "islnk": false,
2025-10-08 08:53:22.736783 | controller |   "isreg": true,
2025-10-08 08:53:22.736865 | controller |   "issock": false,
2025-10-08 08:53:22.736933 | controller |   "isuid": false,
2025-10-08 08:53:22.737000 | controller |   "mode": "0644",
2025-10-08 08:53:22.737071 | controller |   "mtime": 1759912692.62659,
2025-10-08 08:53:22.737137 | controller |   "nlink": 1,
2025-10-08 08:53:22.737188 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-08 08:53:22.737237 | controller |   "pw_name": "zuul",
2025-10-08 08:53:22.737285 | controller |   "rgrp": true,
2025-10-08 08:53:22.737332 | controller |   "roth": true,
2025-10-08 08:53:22.737377 | controller |   "rusr": true,
2025-10-08 08:53:22.737423 | controller |   "size": 3404,
2025-10-08 08:53:22.737469 | controller |   "uid": 1000,
2025-10-08 08:53:22.737514 | controller |   "wgrp": false,
2025-10-08 08:53:22.737560 | controller |   "woth": false,
2025-10-08 08:53:22.737606 | controller |   "wusr": true,
2025-10-08 08:53:22.737651 | controller |   "xgrp": false,
2025-10-08 08:53:22.737939 | controller |   "xoth": false,
2025-10-08 08:53:22.738069 | controller |   "xusr": false
2025-10-08 08:53:22.738136 | controller | }
2025-10-08 08:53:23.732661 | controller | changed:
2025-10-08 08:53:23.732876 | controller | {
2025-10-08 08:53:23.732927 | controller |   "atime": 1759913449.707247,
2025-10-08 08:53:23.732967 | controller |   "ctime": 1759913468.9937673,
2025-10-08 08:53:23.733003 | controller |   "dev": 64513,
2025-10-08 08:53:23.733039 | controller |   "gid": 1000,
2025-10-08 08:53:23.733073 | controller |   "gr_name": "zuul",
2025-10-08 08:53:23.733107 | controller |   "inode": 4233885,
2025-10-08 08:53:23.733141 | controller |   "isblk": false,
2025-10-08 08:53:23.733174 | controller |   "ischr": false,
2025-10-08 08:53:23.733207 | controller |   "isdir": false,
2025-10-08 08:53:23.733242 | controller |   "isfifo": false,
2025-10-08 08:53:23.733275 | controller |   "isgid": false,
2025-10-08 08:53:23.733310 | controller |   "islnk": false,
2025-10-08 08:53:23.733344 | controller |   "isreg": true,
2025-10-08 08:53:23.733379 | controller |   "issock": false,
2025-10-08 08:53:23.733414 | controller |   "isuid": false,
2025-10-08 08:53:23.733450 | controller |   "mode": "0644",
2025-10-08 08:53:23.733484 | controller |   "mtime": 1759913468.9937673,
2025-10-08 08:53:23.733520 | controller |   "nlink": 1,
2025-10-08 08:53:23.733554 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 08:53:23.733591 | controller |   "pw_name": "zuul",
2025-10-08 08:53:23.733628 | controller |   "rgrp": true,
2025-10-08 08:53:23.733662 | controller |   "roth": true,
2025-10-08 08:53:23.733748 | controller |   "rusr": true,
2025-10-08 08:53:23.733788 | controller |   "size": 6749,
2025-10-08 08:53:23.733823 | controller |   "uid": 1000,
2025-10-08 08:53:23.733858 | controller |   "wgrp": false,
2025-10-08 08:53:23.733892 | controller |   "woth": false,
2025-10-08 08:53:23.733926 | controller |   "wusr": true,
2025-10-08 08:53:23.733959 | controller |   "xgrp": false,
2025-10-08 08:53:23.733993 | controller |   "xoth": false,
2025-10-08 08:53:23.734026 | controller |   "xusr": false
2025-10-08 08:53:23.734059 | controller | }
2025-10-08 08:53:23.756557 | 
2025-10-08 08:53:23.756813 | TASK [Copy crio stats log file]
2025-10-08 08:53:23.784042 | controller | skipping: Conditional result was False
2025-10-08 08:53:23.790702 | 
2025-10-08 08:53:23.790769 | TASK [Get SELinux related data]
2025-10-08 08:53:24.215121 | controller | <no matches>
2025-10-08 08:53:24.323994 | controller | ERROR
2025-10-08 08:53:24.324288 | controller | {
2025-10-08 08:53:24.324376 | controller |   "delta": "0:00:00.015359",
2025-10-08 08:53:24.324437 | controller |   "end": "2025-10-08 08:53:24.216553",
2025-10-08 08:53:24.324488 | controller |   "msg": "non-zero return code",
2025-10-08 08:53:24.324526 | controller |   "rc": 1,
2025-10-08 08:53:24.324562 | controller |   "start": "2025-10-08 08:53:24.201194"
2025-10-08 08:53:24.324600 | controller | }
2025-10-08 08:53:24.324654 | controller | ERROR: Ignoring Errors
2025-10-08 08:53:24.336429 | 
2025-10-08 08:53:24.336558 | TASK [Create system configuration directory]
2025-10-08 08:53:24.739201 | controller | changed
2025-10-08 08:53:24.750265 | 
2025-10-08 08:53:24.750397 | TASK [Get some of the system configurations]
2025-10-08 08:53:25.280391 | controller | changed
2025-10-08 08:53:25.285821 | 
2025-10-08 08:53:25.285882 | TASK [Copy generated documentation if available]
2025-10-08 08:53:25.320022 | controller | skipping: Conditional result was False
2025-10-08 08:53:25.326425 | 
2025-10-08 08:53:25.326507 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 08:53:25.361155 | controller | skipping: Conditional result was False
2025-10-08 08:53:25.368374 | 
2025-10-08 08:53:25.368480 | TASK [Compress logs bigger than 2MB]
2025-10-08 08:53:25.897980 | controller | changed
2025-10-08 08:53:25.910367 | 
2025-10-08 08:53:25.910497 | TASK [Copy files from workspace on node]
2025-10-08 08:53:25.939574 | controller | ok
2025-10-08 08:53:25.983729 | 
2025-10-08 08:53:25.983870 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 08:53:26.000094 | controller | skipping: Conditional result was False
2025-10-08 08:53:26.009338 | 
2025-10-08 08:53:26.009486 | TASK [fetch-output : Set log path for single node]
2025-10-08 08:53:26.043586 | controller | ok
2025-10-08 08:53:26.051241 | 
2025-10-08 08:53:26.051338 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 08:53:26.283843 | controller -> localhost | ok: "/var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/logs"
2025-10-08 08:53:26.284267 | controller -> localhost | changed: All items complete
2025-10-08 08:53:26.284327 | 
2025-10-08 08:53:26.492071 | controller -> localhost | changed: "/var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/artifacts"
2025-10-08 08:53:26.728864 | controller -> localhost | changed: "/var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/docs"
2025-10-08 08:53:26.742777 | 
2025-10-08 08:53:26.742887 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 08:53:27.660317 | controller | changed:
2025-10-08 08:53:27.660827 | controller | .d..t...... ./
2025-10-08 08:53:27.660898 | controller | >f+++++++++ README.html
2025-10-08 08:53:27.660944 | controller | >f+++++++++ ansible-execution.log
2025-10-08 08:53:27.660990 | controller | >f+++++++++ ansible.log
2025-10-08 08:53:27.661030 | controller | >f+++++++++ crc-setup.log
2025-10-08 08:53:27.661069 | controller | >f+++++++++ crc-start.log
2025-10-08 08:53:27.661107 | controller | >f+++++++++ dmesg.log
2025-10-08 08:53:27.661151 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 08:53:27.661189 | controller | >f+++++++++ python.log
2025-10-08 08:53:27.661226 | controller | >f+++++++++ registries.conf
2025-10-08 08:53:27.661263 | controller | >f+++++++++ report.html
2025-10-08 08:53:27.661299 | controller | >f+++++++++ selinux-denials.log
2025-10-08 08:53:27.661334 | controller | >f+++++++++ selinux-listing.log
2025-10-08 08:53:27.661369 | controller | cd+++++++++ ci-framework-data/
2025-10-08 08:53:27.661407 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 08:53:27.661444 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 08:53:27.661480 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-08 08:53:27.661518 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/
2025-10-08 08:53:27.661554 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/
2025-10-08 08:53:27.661592 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/ci_multus_nads.yml
2025-10-08 08:53:27.661630 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 08:53:27.661667 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 08:53:27.661742 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 08:53:27.661781 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 08:53:27.661819 | controller | cd+++++++++ registries.conf.d/
2025-10-08 08:53:27.661856 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 08:53:27.661929 | controller | cd+++++++++ system-config/
2025-10-08 08:53:27.661972 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 08:53:27.662013 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 08:53:27.662055 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 08:53:27.662095 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-08 08:53:27.662135 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-08 08:53:27.662174 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-08 08:53:27.662213 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-08 08:53:27.662252 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-08 08:53:27.662292 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-08 08:53:27.662331 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-08 08:53:27.662370 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-08 08:53:27.662422 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-08 08:53:27.662462 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-08 08:53:27.662502 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-08 08:53:27.662541 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-08 08:53:27.662581 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-08 08:53:27.662621 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-08 08:53:28.317873 | controller | changed: .d..t...... ./
2025-10-08 08:53:28.858562 | controller | changed: .d..t...... ./
2025-10-08 08:53:28.887850 | 
2025-10-08 08:53:28.887949 | TASK [Return artifact to Zuul]
2025-10-08 08:53:28.940526 | controller | ok
2025-10-08 08:53:28.968513 | 
2025-10-08 08:53:28.968595 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 08:53:28.968754 | 
2025-10-08 08:53:28.968802 | PLAY RECAP
2025-10-08 08:53:28.968853 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 08:53:28.968882 | 
2025-10-08 08:53:29.088780 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 08:53:29.089969 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-08 08:53:29.712971 | 
2025-10-08 08:53:29.713115 | PLAY [Report DLRN results after job run]
2025-10-08 08:53:29.734042 | 
2025-10-08 08:53:29.734164 | TASK [Report job status to DLRN]
2025-10-08 08:53:29.754374 | controller | ok
2025-10-08 08:53:29.782447 | 
2025-10-08 08:53:29.782604 | TASK [dlrn_report : Warning if DLRN is not running]
2025-10-08 08:53:29.847812 | controller | skipping: Conditional result was False
2025-10-08 08:53:29.858092 | 
2025-10-08 08:53:29.858296 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-10-08 08:53:29.914635 | controller | skipping: Conditional result was False
2025-10-08 08:53:29.928042 | 
2025-10-08 08:53:29.928194 | TASK [dlrn_report : Install kinit related package]
2025-10-08 08:53:29.986052 | controller | skipping: Conditional result was False
2025-10-08 08:53:29.999007 | 
2025-10-08 08:53:29.999149 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-10-08 08:53:30.056144 | controller | skipping: Conditional result was False
2025-10-08 08:53:30.063492 | 
2025-10-08 08:53:30.063559 | TASK [Set zuul-log-path fact]
2025-10-08 08:53:30.108777 | controller | skipping: Conditional result was False
2025-10-08 08:53:30.115668 | 
2025-10-08 08:53:30.115770 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-10-08 08:53:30.191020 | controller | skipping: Conditional result was False
2025-10-08 08:53:30.196949 | 
2025-10-08 08:53:30.197020 | TASK [Get hash related data from repo_setup role]
2025-10-08 08:53:30.261508 | controller | skipping: Conditional result was False
2025-10-08 08:53:30.267944 | 
2025-10-08 08:53:30.268030 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-10-08 08:53:30.798535 | controller | skipping: Conditional result was False
2025-10-08 08:53:30.811568 | 
2025-10-08 08:53:30.811751 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-10-08 08:53:30.880547 | controller | skipping: Conditional result was False
2025-10-08 08:53:30.887278 | 
2025-10-08 08:53:30.887359 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-10-08 08:53:31.421506 | controller | skipping: Conditional result was False
2025-10-08 08:53:31.473425 | 
2025-10-08 08:53:31.473524 | PLAY RECAP
2025-10-08 08:53:31.473583 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-10-08 08:53:31.473614 | 
2025-10-08 08:53:31.579596 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-08 08:53:31.581467 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 08:53:32.167549 | 
2025-10-08 08:53:32.167693 | PLAY [all]
2025-10-08 08:53:32.186833 | 
2025-10-08 08:53:32.186942 | TASK [include_role : fetch-output]
2025-10-08 08:53:32.226384 | controller | ok
2025-10-08 08:53:32.246035 | 
2025-10-08 08:53:32.246165 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 08:53:32.311214 | controller | skipping: Conditional result was False
2025-10-08 08:53:32.320036 | 
2025-10-08 08:53:32.320136 | TASK [fetch-output : Set log path for single node]
2025-10-08 08:53:32.362989 | controller | ok
2025-10-08 08:53:32.370519 | 
2025-10-08 08:53:32.370650 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 08:53:32.759383 | controller -> localhost | ok: "/var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/logs"
2025-10-08 08:53:32.967737 | controller -> localhost | ok: "/var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/artifacts"
2025-10-08 08:53:33.190093 | controller -> localhost | ok: "/var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/docs"
2025-10-08 08:53:33.199927 | 
2025-10-08 08:53:33.200044 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 08:53:34.011133 | controller | ok
2025-10-08 08:53:34.011487 | controller | ok: All items complete
2025-10-08 08:53:34.011551 | 
2025-10-08 08:53:34.710362 | controller | ok
2025-10-08 08:53:35.323535 | controller | ok
2025-10-08 08:53:35.361818 | 
2025-10-08 08:53:35.362092 | TASK [include_role : fetch-output-openshift]
2025-10-08 08:53:35.387765 | controller | skipping: Conditional result was False
2025-10-08 08:53:35.401561 | 
2025-10-08 08:53:35.401759 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 08:53:35.865052 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008061
2025-10-08 08:53:36.110040 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007164
2025-10-08 08:53:36.156095 | 
2025-10-08 08:53:36.156284 | PLAY [all]
2025-10-08 08:53:36.181081 | 
2025-10-08 08:53:36.181195 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 08:53:36.849528 | controller | changed
2025-10-08 08:53:36.897831 | 
2025-10-08 08:53:36.897937 | PLAY RECAP
2025-10-08 08:53:36.897992 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 08:53:36.898020 | 
2025-10-08 08:53:37.013079 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 08:53:37.014655 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 08:53:37.642165 | 
2025-10-08 08:53:37.642333 | PLAY [localhost]
2025-10-08 08:53:37.660388 | 
2025-10-08 08:53:37.660475 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 08:53:38.044068 | localhost | changed
2025-10-08 08:53:38.048618 | 
2025-10-08 08:53:38.048709 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 08:53:38.091560 | localhost | ok
2025-10-08 08:53:38.105261 | 
2025-10-08 08:53:38.105353 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 08:53:38.501124 | localhost | changed
2025-10-08 08:53:38.507661 | 
2025-10-08 08:53:38.507774 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 08:53:39.283984 | localhost | changed
2025-10-08 08:53:39.294812 | 
2025-10-08 08:53:39.294948 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 08:53:39.770175 | localhost | Identity added: /var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/tmp/ansible.fdie8dy7 (/var/lib/zuul/builds/7d6a307711964f42a9e722de9457ed08/work/tmp/ansible.fdie8dy7)
2025-10-08 08:53:39.770543 | localhost | ok: Runtime: 0:00:00.013661
2025-10-08 08:53:39.781370 | 
2025-10-08 08:53:39.781570 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 08:53:40.166793 | localhost | ok: Runtime: 0:00:00.009002
2025-10-08 08:53:40.179010 | 
2025-10-08 08:53:40.179165 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 08:53:40.257332 | localhost | changed
2025-10-08 08:53:40.267814 | 
2025-10-08 08:53:40.268008 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 08:53:40.697537 | localhost | changed
2025-10-08 08:53:40.717921 | 
2025-10-08 08:53:40.717993 | PLAY [localhost]
2025-10-08 08:53:40.730776 | 
2025-10-08 08:53:40.730840 | TASK [Generate bulk log download script]
2025-10-08 08:53:40.751949 | localhost | ok
2025-10-08 08:53:40.768739 | 
2025-10-08 08:53:40.768855 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 08:53:40.819500 | localhost | ok: All assertions passed
2025-10-08 08:53:40.825901 | 
2025-10-08 08:53:40.825984 | TASK [local-log-download : Create download script]
2025-10-08 08:53:41.327273 | localhost -> localhost | changed
2025-10-08 08:53:41.338068 | 
2025-10-08 08:53:41.338138 | TASK [Register quick-download link]
2025-10-08 08:53:41.379271 | localhost | ok
2025-10-08 08:53:41.394830 | 
2025-10-08 08:53:41.394913 | PLAY [logserver.rdoproject.org]
2025-10-08 08:53:41.404288 | 
2025-10-08 08:53:41.404350 | TASK [Set zuul-log-path fact]
2025-10-08 08:53:41.420571 | logserver.rdoproject.org | ok
2025-10-08 08:53:41.429854 | 
2025-10-08 08:53:41.429928 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 08:53:41.466485 | logserver.rdoproject.org | ok
2025-10-08 08:53:41.473576 | 
2025-10-08 08:53:41.473661 | TASK [upload-logs : Create log directories]
2025-10-08 08:53:42.320731 | logserver.rdoproject.org | changed
2025-10-08 08:53:42.328314 | 
2025-10-08 08:53:42.328469 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 08:53:42.620991 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009888
2025-10-08 08:53:42.630868 | 
2025-10-08 08:53:42.631011 | TASK [upload-logs : Upload logs to log server]
2025-10-08 08:53:43.388849 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 08:53:43.395266 | 
2025-10-08 08:53:43.395404 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 08:53:43.462014 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:53:43.474946 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:53:43.491521 | 
2025-10-08 08:53:43.491757 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 08:53:43.530775 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:53:43.531228 | 
2025-10-08 08:53:43.536473 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 08:53:43.548102 | 
2025-10-08 08:53:43.548304 | LOOP [upload-logs : Upload console log and json output]