2025-08-19 02:56:51.656281 | Job console starting
2025-08-19 02:56:51.678431 | Updating git repos
2025-08-19 02:56:52.475461 | Cloning repos into workspace
2025-08-19 02:56:58.958355 | Restoring repo states
2025-08-19 02:56:59.186857 | Merging changes
2025-08-19 02:56:59.186896 | Checking out repos
2025-08-19 02:57:04.769850 | Preparing playbooks
2025-08-19 02:57:12.224145 | Running Ansible setup
2025-08-19 02:57:20.949215 | PRE-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2025-08-19 02:57:22.355629 | 
2025-08-19 02:57:22.355837 | PLAY [Create job header and inventory]
2025-08-19 02:57:22.376104 | 
2025-08-19 02:57:22.376313 | TASK [Gathering Facts]
2025-08-19 02:57:23.841781 | localhost | ok
2025-08-19 02:57:23.854114 | 
2025-08-19 02:57:23.854291 | TASK [Setup log path fact]
2025-08-19 02:57:23.876791 | localhost | ok
2025-08-19 02:57:23.890168 | localhost | included: set-zuul-log-path-fact
2025-08-19 02:57:23.896725 | 
2025-08-19 02:57:23.896849 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-08-19 02:57:23.939267 | localhost | ok
2025-08-19 02:57:23.950182 | 
2025-08-19 02:57:23.950355 | TASK [emit-job-header : Print job information]
2025-08-19 02:57:24.002153 | # Job Information
2025-08-19 02:57:24.002437 | Ansible Version: 2.18.7
2025-08-19 02:57:24.002547 | Job: neutron-ovs-grenade-dvr-multinode
2025-08-19 02:57:24.002620 | Pipeline: periodic
2025-08-19 02:57:24.002681 | Executor: ze09.opendev.org
2025-08-19 02:57:24.002743 | Triggered by: https://opendev.org/openstack/neutron/commit/None
2025-08-19 02:57:24.002804 | Event ID: c4de525b66474c6eb8eb566fe75b500c
2025-08-19 02:57:24.007923 | 
2025-08-19 02:57:24.008099 | LOOP [emit-job-header : Print node information]
2025-08-19 02:57:24.240299 | localhost | ok:
2025-08-19 02:57:24.240684 | localhost | # Node Information
2025-08-19 02:57:24.240770 | localhost | Inventory Hostname: compute1
2025-08-19 02:57:24.240849 | localhost | Hostname: np7ef8a092bd3c4
2025-08-19 02:57:24.240948 | localhost | Username: zuul
2025-08-19 02:57:24.241058 | localhost | Distro: Ubuntu 22.04
2025-08-19 02:57:24.241494 | localhost | Provider: opendev.org%2Fopendev%2Fzuul-providers/ovh-gra1-main
2025-08-19 02:57:24.241570 | localhost | Region: BHS1
2025-08-19 02:57:24.242306 | localhost | Label: ubuntu-jammy
2025-08-19 02:57:24.242379 | localhost | Product Name: OpenStack Nova
2025-08-19 02:57:24.242439 | localhost | Interface IP: 158.69.74.227
2025-08-19 02:57:24.320907 | localhost | ok:
2025-08-19 02:57:24.321048 | localhost | # Node Information
2025-08-19 02:57:24.321115 | localhost | Inventory Hostname: controller
2025-08-19 02:57:24.321191 | localhost | Hostname: npc21e6f3629714
2025-08-19 02:57:24.321253 | localhost | Username: zuul
2025-08-19 02:57:24.321311 | localhost | Distro: Ubuntu 22.04
2025-08-19 02:57:24.321367 | localhost | Provider: opendev.org%2Fopendev%2Fzuul-providers/ovh-gra1-main
2025-08-19 02:57:24.321434 | localhost | Region: BHS1
2025-08-19 02:57:24.321544 | localhost | Label: ubuntu-jammy
2025-08-19 02:57:24.321615 | localhost | Product Name: OpenStack Nova
2025-08-19 02:57:24.321674 | localhost | Interface IP: 158.69.73.106
2025-08-19 02:57:24.334059 | 
2025-08-19 02:57:24.334260 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-08-19 02:57:24.974795 | localhost -> localhost | changed
2025-08-19 02:57:24.983611 | 
2025-08-19 02:57:24.983795 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-08-19 02:57:26.405813 | localhost -> localhost | changed
2025-08-19 02:57:26.418096 | 
2025-08-19 02:57:26.418240 | PLAY [Setup Zuul environment]
2025-08-19 02:57:26.432654 | 
2025-08-19 02:57:26.432805 | TASK [Gather network facts]
2025-08-19 02:57:27.273923 | compute1 | ok
2025-08-19 02:57:27.298039 | controller | ok
2025-08-19 02:57:27.349492 | 
2025-08-19 02:57:27.349697 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-08-19 02:57:27.710176 | compute1 -> localhost | ok
2025-08-19 02:57:27.720871 | 
2025-08-19 02:57:27.721060 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-08-19 02:57:27.768950 | compute1 | ok
2025-08-19 02:57:27.776219 | controller | ok
2025-08-19 02:57:27.811056 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-08-19 02:57:27.811223 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-08-19 02:57:27.823595 | 
2025-08-19 02:57:27.823766 | TASK [add-build-sshkey : Create Temp SSH key]
2025-08-19 02:57:29.637941 | compute1 -> localhost | Generating public/private rsa key pair.
2025-08-19 02:57:29.638206 | compute1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/492f0763d7b54cb388374107cc79cf62_id_rsa
2025-08-19 02:57:29.638275 | compute1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/492f0763d7b54cb388374107cc79cf62_id_rsa.pub
2025-08-19 02:57:29.638337 | compute1 -> localhost | The key fingerprint is:
2025-08-19 02:57:29.638398 | compute1 -> localhost | SHA256:W77wQzYcqW/CIrE/H8K9UDKPev3MG1VIerMp4LbFJ9c zuul-build-sshkey
2025-08-19 02:57:29.638457 | compute1 -> localhost | The key's randomart image is:
2025-08-19 02:57:29.638514 | compute1 -> localhost | +---[RSA 3072]----+
2025-08-19 02:57:29.638625 | compute1 -> localhost | |            .    |
2025-08-19 02:57:29.638690 | compute1 -> localhost | |           o .   |
2025-08-19 02:57:29.638747 | compute1 -> localhost | |        . ..+ .  |
2025-08-19 02:57:29.638803 | compute1 -> localhost | |       . oo. *   |
2025-08-19 02:57:29.638858 | compute1 -> localhost | |      o So*.* E  |
2025-08-19 02:57:29.638912 | compute1 -> localhost | |    .. O.*=*     |
2025-08-19 02:57:29.638967 | compute1 -> localhost | |     o=oB+o.     |
2025-08-19 02:57:29.639021 | compute1 -> localhost | |    o.+o+B+o     |
2025-08-19 02:57:29.639075 | compute1 -> localhost | |    .+.+o+Bo     |
2025-08-19 02:57:29.639129 | compute1 -> localhost | +----[SHA256]-----+
2025-08-19 02:57:29.639222 | compute1 -> localhost | ok: Runtime: 0:00:01.209721
2025-08-19 02:57:29.647674 | 
2025-08-19 02:57:29.647802 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-08-19 02:57:29.692706 | compute1 | ok
2025-08-19 02:57:29.698963 | controller | ok
2025-08-19 02:57:29.723595 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-08-19 02:57:29.723715 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-08-19 02:57:29.740050 | 
2025-08-19 02:57:29.740165 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-08-19 02:57:29.775783 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:29.787256 | controller | skipping: Conditional result was False
2025-08-19 02:57:29.796230 | 
2025-08-19 02:57:29.796350 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-08-19 02:57:30.571087 | compute1 | changed
2025-08-19 02:57:30.599151 | controller | changed
2025-08-19 02:57:30.610556 | 
2025-08-19 02:57:30.610704 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-08-19 02:57:31.004715 | compute1 | ok
2025-08-19 02:57:31.014742 | controller | ok
2025-08-19 02:57:31.023771 | 
2025-08-19 02:57:31.023920 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-08-19 02:57:32.550730 | controller | changed
2025-08-19 02:57:32.613178 | compute1 | changed
2025-08-19 02:57:32.622682 | 
2025-08-19 02:57:32.622931 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-08-19 02:57:34.185625 | controller | changed
2025-08-19 02:57:34.224780 | compute1 | changed
2025-08-19 02:57:34.232748 | 
2025-08-19 02:57:34.232895 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-08-19 02:57:34.261818 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:34.283706 | controller | skipping: Conditional result was False
2025-08-19 02:57:34.294397 | 
2025-08-19 02:57:34.294608 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-08-19 02:57:34.840015 | compute1 -> localhost | changed
2025-08-19 02:57:34.867502 | 
2025-08-19 02:57:34.867722 | TASK [add-build-sshkey : Add back temp key]
2025-08-19 02:57:35.320241 | compute1 -> localhost | Identity added: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/492f0763d7b54cb388374107cc79cf62_id_rsa (zuul-build-sshkey)
2025-08-19 02:57:35.320646 | compute1 -> localhost | ok: Runtime: 0:00:00.014480
2025-08-19 02:57:35.330481 | 
2025-08-19 02:57:35.330663 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-08-19 02:57:36.009239 | controller | ok
2025-08-19 02:57:36.037741 | compute1 | ok
2025-08-19 02:57:36.049103 | 
2025-08-19 02:57:36.049235 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-08-19 02:57:36.091943 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:36.113785 | controller | skipping: Conditional result was False
2025-08-19 02:57:36.137715 | 
2025-08-19 02:57:36.137909 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-08-19 02:57:36.773270 | controller | ok
2025-08-19 02:57:36.801108 | compute1 | ok
2025-08-19 02:57:36.826363 | 
2025-08-19 02:57:36.826569 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-08-19 02:57:37.206323 | controller | ok: "logs"
2025-08-19 02:57:37.206843 | controller | ok: All items complete
2025-08-19 02:57:37.206909 | 
2025-08-19 02:57:37.226476 | compute1 | ok: "logs"
2025-08-19 02:57:37.521571 | controller | ok: "artifacts"
2025-08-19 02:57:37.554651 | compute1 | ok: "artifacts"
2025-08-19 02:57:37.834703 | controller | ok: "docs"
2025-08-19 02:57:37.880803 | compute1 | ok: "docs"
2025-08-19 02:57:37.897687 | 
2025-08-19 02:57:37.897862 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-08-19 02:57:38.275482 | compute1 | changed: "logs"
2025-08-19 02:57:38.276045 | compute1 | ok: All items complete
2025-08-19 02:57:38.276110 | 
2025-08-19 02:57:38.303900 | controller | changed: "logs"
2025-08-19 02:57:38.584585 | compute1 | changed: "artifacts"
2025-08-19 02:57:38.644410 | controller | changed: "artifacts"
2025-08-19 02:57:38.897793 | compute1 | changed: "docs"
2025-08-19 02:57:39.023583 | controller | changed: "docs"
2025-08-19 02:57:39.050731 | 
2025-08-19 02:57:39.050862 | PLAY [Configure unbound]
2025-08-19 02:57:39.062638 | 
2025-08-19 02:57:39.062756 | TASK [configure-unbound : Check that Unbound is installed]
2025-08-19 02:57:39.415780 | compute1 | ok
2025-08-19 02:57:39.434236 | controller | ok
2025-08-19 02:57:39.442908 | 
2025-08-19 02:57:39.443030 | TASK [configure-unbound : Ensure that Unbound is installed]
2025-08-19 02:57:39.492132 | compute1 | ok: All assertions passed
2025-08-19 02:57:39.511892 | controller | ok: All assertions passed
2025-08-19 02:57:39.519068 | 
2025-08-19 02:57:39.519181 | TASK [configure-unbound : Check for IPv6]
2025-08-19 02:57:39.736412 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:39.757822 | controller | skipping: Conditional result was False
2025-08-19 02:57:39.769035 | 
2025-08-19 02:57:39.769166 | TASK [configure-unbound : Set IPv6 nameservers]
2025-08-19 02:57:39.851780 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:39.884146 | controller | skipping: Conditional result was False
2025-08-19 02:57:39.893226 | 
2025-08-19 02:57:39.893348 | TASK [configure-unbound : Set IPv4 nameservers]
2025-08-19 02:57:39.951305 | compute1 | ok
2025-08-19 02:57:39.969351 | controller | ok
2025-08-19 02:57:39.977955 | 
2025-08-19 02:57:39.978075 | LOOP [configure-unbound : Include OS-specific variables]
2025-08-19 02:57:40.031582 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_0/opendev.org/opendev/base-jobs/roles/configure-unbound/vars/Debian.yaml"
2025-08-19 02:57:40.032153 | compute1 | changed: All items complete
2025-08-19 02:57:40.032220 | 
2025-08-19 02:57:40.046204 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_0/opendev.org/opendev/base-jobs/roles/configure-unbound/vars/Debian.yaml"
2025-08-19 02:57:40.056102 | 
2025-08-19 02:57:40.056277 | TASK [configure-unbound : Ensure Unbound conf.d directory exists]
2025-08-19 02:57:40.495002 | controller | ok
2025-08-19 02:57:40.504104 | compute1 | ok
2025-08-19 02:57:40.513124 | 
2025-08-19 02:57:40.513341 | TASK [configure-unbound : Configure unbound forwarding]
2025-08-19 02:57:42.029447 | controller | changed
2025-08-19 02:57:42.039493 | compute1 | changed
2025-08-19 02:57:42.048137 | 
2025-08-19 02:57:42.048313 | TASK [configure-unbound : Configure unbound TTL]
2025-08-19 02:57:43.518666 | compute1 | changed
2025-08-19 02:57:43.537859 | controller | changed
2025-08-19 02:57:43.548036 | 
2025-08-19 02:57:43.548192 | TASK [configure-unbound : Start unbound]
2025-08-19 02:57:44.662916 | compute1 | ok
2025-08-19 02:57:44.689291 | controller | ok
2025-08-19 02:57:44.738012 | 
2025-08-19 02:57:44.738202 | TASK [configure-unbound : Restart unbound]
2025-08-19 02:57:45.447888 | controller | changed
2025-08-19 02:57:45.464019 | compute1 | changed
2025-08-19 02:57:45.473834 | 
2025-08-19 02:57:45.473938 | PLAY [Prepare workspace and configure mirrors]
2025-08-19 02:57:45.490726 | 
2025-08-19 02:57:45.490874 | TASK [validate-host : Define zuul_info_dir fact]
2025-08-19 02:57:45.542813 | compute1 | ok
2025-08-19 02:57:45.560670 | controller | ok
2025-08-19 02:57:45.568236 | 
2025-08-19 02:57:45.568350 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-08-19 02:57:45.928302 | compute1 -> localhost | ok
2025-08-19 02:57:45.938795 | 
2025-08-19 02:57:45.938910 | TASK [validate-host : Collect information about the host]
2025-08-19 02:57:47.079043 | compute1 | ok
2025-08-19 02:57:47.102170 | controller | ok
2025-08-19 02:57:47.123699 | 
2025-08-19 02:57:47.123896 | TASK [validate-host : Sanitize hostname]
2025-08-19 02:57:47.211720 | compute1 | ok
2025-08-19 02:57:47.240722 | controller | ok
2025-08-19 02:57:47.247281 | 
2025-08-19 02:57:47.247426 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-08-19 02:57:48.030146 | compute1 -> localhost | changed
2025-08-19 02:57:48.032216 | controller -> localhost | changed
2025-08-19 02:57:48.045733 | 
2025-08-19 02:57:48.045888 | TASK [validate-host : Collect information about zuul worker]
2025-08-19 02:57:49.740502 | compute1 | ok
2025-08-19 02:57:50.512359 | controller | ok
2025-08-19 02:57:50.520151 | 
2025-08-19 02:57:50.520314 | TASK [validate-host : Write out all zuul information for each host]
2025-08-19 02:57:51.208621 | compute1 -> localhost | changed
2025-08-19 02:57:51.231229 | controller -> localhost | changed
2025-08-19 02:57:51.253221 | 
2025-08-19 02:57:51.253481 | LOOP [prepare-workspace-git : Filter zuul projects if sync-only-required-projects flag is set]
2025-08-19 02:57:51.289766 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.290475 | compute1 | ok: All items complete
2025-08-19 02:57:51.290626 | 
2025-08-19 02:57:51.292234 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.294885 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.298326 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.301881 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.305299 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.307938 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.309097 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.311577 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.312667 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.315152 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.316141 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.318639 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.319538 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.322222 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.323182 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.325908 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.327418 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:51.329951 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.338601 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.338687 | 
2025-08-19 02:57:51.339715 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.340620 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.343987 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.347735 | controller | skipping: Conditional result was False
2025-08-19 02:57:51.374608 | 
2025-08-19 02:57:51.374749 | TASK [prepare-workspace-git : Don't filter zuul projects if flag is false]
2025-08-19 02:57:51.428867 | compute1 | ok
2025-08-19 02:57:51.447058 | controller | ok
2025-08-19 02:57:51.455948 | 
2025-08-19 02:57:51.456099 | TASK [prepare-workspace-git : Set initial repo states in workspace]
2025-08-19 02:57:52.454363 | controller | changed
2025-08-19 02:57:52.487114 | compute1 | changed
2025-08-19 02:57:52.497512 | 
2025-08-19 02:57:52.497914 | TASK [prepare-workspace-git : Synchronize src repos to workspace directory]
2025-08-19 02:57:54.711604 | controller -> localhost | changed
2025-08-19 02:57:54.810899 | compute1 -> localhost | changed
2025-08-19 02:57:54.825826 | 
2025-08-19 02:57:54.825974 | TASK [prepare-workspace-git : Update remote repository state]
2025-08-19 02:57:56.360015 | controller | changed
2025-08-19 02:57:56.404007 | compute1 | changed
2025-08-19 02:57:56.426265 | 
2025-08-19 02:57:56.426432 | TASK [mirror-info : Create /etc/ci]
2025-08-19 02:57:56.908610 | controller | changed
2025-08-19 02:57:56.988185 | compute1 | changed
2025-08-19 02:57:56.998054 | 
2025-08-19 02:57:56.998234 | TASK [mirror-info : Install ci_mirror script]
2025-08-19 02:57:58.481100 | controller | changed
2025-08-19 02:57:58.620936 | compute1 | changed
2025-08-19 02:57:58.639425 | 
2025-08-19 02:57:58.639623 | TASK [configure-mirrors : Gather needed facts]
2025-08-19 02:57:58.674870 | compute1 | skipping: Conditional result was False
2025-08-19 02:57:58.696739 | controller | skipping: Conditional result was False
2025-08-19 02:57:58.705175 | 
2025-08-19 02:57:58.705305 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-08-19 02:57:58.800333 | compute1 | ok
2025-08-19 02:57:58.888057 | controller | ok
2025-08-19 02:57:58.959657 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-08-19 02:57:58.959785 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-08-19 02:57:58.966957 | 
2025-08-19 02:57:58.967070 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-08-19 02:57:59.397383 | compute1 | ok
2025-08-19 02:57:59.404225 | controller | ok
2025-08-19 02:57:59.411070 | 
2025-08-19 02:57:59.411238 | LOOP [configure-mirrors : Include OS-specific variables]
2025-08-19 02:57:59.463210 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/Ubuntu.yaml"
2025-08-19 02:57:59.463781 | 
2025-08-19 02:57:59.481780 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/Ubuntu.yaml"
2025-08-19 02:57:59.490944 | 
2025-08-19 02:57:59.491117 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-08-19 02:58:01.057856 | controller | changed
2025-08-19 02:58:01.071841 | compute1 | changed
2025-08-19 02:58:01.079640 | 
2025-08-19 02:58:01.079801 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-08-19 02:58:01.128949 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Ubuntu.yaml"
2025-08-19 02:58:01.129476 | compute1 | ok: All items complete
2025-08-19 02:58:01.129614 | 
2025-08-19 02:58:01.145560 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Ubuntu.yaml"
2025-08-19 02:58:01.161109 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Ubuntu.yaml
2025-08-19 02:58:01.161245 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Ubuntu.yaml
2025-08-19 02:58:01.172196 | 
2025-08-19 02:58:01.172417 | LOOP [configure-mirrors : Install Ubuntu repository files]
2025-08-19 02:58:02.737052 | compute1 | changed: "etc/apt/sources.list"
2025-08-19 02:58:02.737515 | compute1 | ok: All items complete
2025-08-19 02:58:02.737643 | 
2025-08-19 02:58:02.761686 | controller | changed: "etc/apt/sources.list"
2025-08-19 02:58:04.148741 | controller | changed: "etc/apt/apt.conf.d/99unauthenticated"
2025-08-19 02:58:04.218150 | compute1 | changed: "etc/apt/apt.conf.d/99unauthenticated"
2025-08-19 02:58:04.253336 | 
2025-08-19 02:58:04.253556 | TASK [configure-mirrors : Update apt cache]
2025-08-19 02:58:05.117956 | controller | Ign:1 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy InRelease
2025-08-19 02:58:05.121514 | controller | Ign:2 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates InRelease
2025-08-19 02:58:05.125428 | controller | Ign:3 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports InRelease
2025-08-19 02:58:05.128981 | controller | Ign:4 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security InRelease
2025-08-19 02:58:05.135310 | controller | Get:5 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy Release [3235 B]
2025-08-19 02:58:05.140781 | controller | Get:6 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates Release [3260 B]
2025-08-19 02:58:05.145578 | controller | Get:7 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports Release [3254 B]
2025-08-19 02:58:05.150944 | controller | Get:8 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security Release [3262 B]
2025-08-19 02:58:05.157169 | controller | Ign:9 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy Release.gpg
2025-08-19 02:58:05.162618 | controller | Ign:10 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates Release.gpg
2025-08-19 02:58:05.167844 | controller | Ign:11 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports Release.gpg
2025-08-19 02:58:05.174375 | controller | Ign:12 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security Release.gpg
2025-08-19 02:58:05.181964 | controller | Get:13 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy/universe amd64 Packages [22.4 MB]
2025-08-19 02:58:05.088369 | compute1 | Ign:1 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy InRelease
2025-08-19 02:58:05.089053 | compute1 | Ign:2 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates InRelease
2025-08-19 02:58:05.090566 | compute1 | Ign:3 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports InRelease
2025-08-19 02:58:05.091558 | compute1 | Ign:4 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security InRelease
2025-08-19 02:58:05.094771 | compute1 | Get:5 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy Release [3235 B]
2025-08-19 02:58:05.096637 | compute1 | Get:6 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates Release [3260 B]
2025-08-19 02:58:05.097992 | compute1 | Get:7 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports Release [3254 B]
2025-08-19 02:58:05.099182 | compute1 | Get:8 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security Release [3262 B]
2025-08-19 02:58:05.101319 | compute1 | Ign:9 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy Release.gpg
2025-08-19 02:58:05.101981 | compute1 | Ign:10 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates Release.gpg
2025-08-19 02:58:05.103190 | compute1 | Ign:11 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports Release.gpg
2025-08-19 02:58:05.103981 | compute1 | Ign:12 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security Release.gpg
2025-08-19 02:58:05.105755 | compute1 | Get:13 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy/universe amd64 Packages [22.4 MB]
2025-08-19 02:58:05.397198 | compute1 | Get:14 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy/main amd64 Packages [2182 kB]
2025-08-19 02:58:05.610916 | controller | Get:14 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy/main amd64 Packages [2182 kB]
2025-08-19 02:58:05.622185 | controller | Get:15 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates/main amd64 Packages [3956 kB]
2025-08-19 02:58:05.668616 | controller | Get:16 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates/universe amd64 Packages [1832 kB]
2025-08-19 02:58:05.689060 | controller | Get:17 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports/universe amd64 Packages [41.6 kB]
2025-08-19 02:58:05.689652 | controller | Get:18 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports/main amd64 Packages [56.1 kB]
2025-08-19 02:58:05.690356 | controller | Get:19 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security/universe amd64 Packages [1453 kB]
2025-08-19 02:58:05.704862 | controller | Get:20 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security/main amd64 Packages [3567 kB]
2025-08-19 02:58:05.409692 | compute1 | Get:15 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates/main amd64 Packages [3956 kB]
2025-08-19 02:58:05.454821 | compute1 | Get:16 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-updates/universe amd64 Packages [1832 kB]
2025-08-19 02:58:05.483997 | compute1 | Get:17 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports/main amd64 Packages [56.1 kB]
2025-08-19 02:58:05.484682 | compute1 | Get:18 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-backports/universe amd64 Packages [41.6 kB]
2025-08-19 02:58:05.485313 | compute1 | Get:19 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security/universe amd64 Packages [1453 kB]
2025-08-19 02:58:05.499673 | compute1 | Get:20 https://mirror.bhs1.ovh.opendev.org/ubuntu jammy-security/main amd64 Packages [3567 kB]
2025-08-19 02:58:07.643496 | controller | Fetched 35.5 MB in 3s (12.7 MB/s)
2025-08-19 02:58:07.657027 | compute1 | Fetched 35.5 MB in 3s (12.7 MB/s)
2025-08-19 02:58:08.841189 | controller | Reading package lists...
2025-08-19 02:58:09.033319 | compute1 | Reading package lists...
2025-08-19 02:58:09.474607 | controller | ok: Runtime: 0:00:04.148374
2025-08-19 02:58:09.486853 | compute1 | ok: Runtime: 0:00:04.357576
2025-08-19 02:58:09.489324 | 
2025-08-19 02:58:09.489403 | PLAY RECAP
2025-08-19 02:58:09.489496 | compute1 | ok: 44 changed: 20 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2025-08-19 02:58:09.489637 | controller | ok: 39 changed: 17 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2025-08-19 02:58:09.489706 | localhost | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-08-19 02:58:09.489757 | 
2025-08-19 02:58:09.694954 | PRE-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2025-08-19 02:58:09.711270 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/multinode/pre.yaml@master]
2025-08-19 02:58:10.970077 | 
2025-08-19 02:58:10.970287 | PLAY [Configure a multi node environment]
2025-08-19 02:58:10.992332 | 
2025-08-19 02:58:10.992509 | TASK [Set up multi-node known hosts]
2025-08-19 02:58:11.032616 | compute1 | ok
2025-08-19 02:58:11.039446 | controller | ok
2025-08-19 02:58:11.067453 | compute1 | included: multi-node-known-hosts
2025-08-19 02:58:11.067643 | controller | included: multi-node-known-hosts
2025-08-19 02:58:11.075888 | 
2025-08-19 02:58:11.076003 | TASK [multi-node-known-hosts : Get known_hosts facts]
2025-08-19 02:58:12.478997 | compute1 | ok
2025-08-19 02:58:12.575704 | controller | ok
2025-08-19 02:58:12.660749 | 
2025-08-19 02:58:12.660915 | LOOP [multi-node-known-hosts : Add default known_host record for every user]
2025-08-19 02:58:13.361892 | controller | changed:
2025-08-19 02:58:13.362332 | controller | {
2025-08-19 02:58:13.362399 | controller |   "key": "158.69.73.106 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:13.362458 | controller |   "name": "158.69.73.106"
2025-08-19 02:58:13.362515 | controller | }
2025-08-19 02:58:13.375024 | compute1 | changed:
2025-08-19 02:58:13.375123 | compute1 | {
2025-08-19 02:58:13.375182 | compute1 |   "key": "158.69.73.106 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:13.375235 | compute1 |   "name": "158.69.73.106"
2025-08-19 02:58:13.375286 | compute1 | }
2025-08-19 02:58:13.691313 | controller | changed:
2025-08-19 02:58:13.691594 | controller | {
2025-08-19 02:58:13.691667 | controller |   "key": "158.69.73.106 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:13.691725 | controller |   "name": "158.69.73.106"
2025-08-19 02:58:13.691777 | controller | }
2025-08-19 02:58:13.760760 | compute1 | changed:
2025-08-19 02:58:13.760911 | compute1 | {
2025-08-19 02:58:13.760971 | compute1 |   "key": "158.69.73.106 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:13.761027 | compute1 |   "name": "158.69.73.106"
2025-08-19 02:58:13.761080 | compute1 | }
2025-08-19 02:58:14.040188 | controller | changed:
2025-08-19 02:58:14.040362 | controller | {
2025-08-19 02:58:14.040463 | controller |   "key": "158.69.73.106 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:14.040600 | controller |   "name": "158.69.73.106"
2025-08-19 02:58:14.040701 | controller | }
2025-08-19 02:58:14.118349 | compute1 | changed:
2025-08-19 02:58:14.118497 | compute1 | {
2025-08-19 02:58:14.118596 | compute1 |   "key": "158.69.73.106 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:14.118655 | compute1 |   "name": "158.69.73.106"
2025-08-19 02:58:14.118709 | compute1 | }
2025-08-19 02:58:14.384196 | controller | changed:
2025-08-19 02:58:14.384353 | controller | {
2025-08-19 02:58:14.384456 | controller |   "key": "158.69.74.227 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:14.384600 | controller |   "name": "158.69.74.227"
2025-08-19 02:58:14.384670 | controller | }
2025-08-19 02:58:14.481450 | compute1 | changed:
2025-08-19 02:58:14.483156 | compute1 | {
2025-08-19 02:58:14.483226 | compute1 |   "key": "158.69.74.227 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:14.483283 | compute1 |   "name": "158.69.74.227"
2025-08-19 02:58:14.483336 | compute1 | }
2025-08-19 02:58:14.736702 | controller | changed:
2025-08-19 02:58:14.736847 | controller | {
2025-08-19 02:58:14.736905 | controller |   "key": "158.69.74.227 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:14.736967 | controller |   "name": "158.69.74.227"
2025-08-19 02:58:14.737019 | controller | }
2025-08-19 02:58:14.858794 | compute1 | changed:
2025-08-19 02:58:14.858951 | compute1 | {
2025-08-19 02:58:14.859011 | compute1 |   "key": "158.69.74.227 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:14.859065 | compute1 |   "name": "158.69.74.227"
2025-08-19 02:58:14.859116 | compute1 | }
2025-08-19 02:58:15.082571 | controller | changed:
2025-08-19 02:58:15.082764 | controller | {
2025-08-19 02:58:15.082837 | controller |   "key": "158.69.74.227 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:15.082897 | controller |   "name": "158.69.74.227"
2025-08-19 02:58:15.082952 | controller | }
2025-08-19 02:58:15.195243 | compute1 | changed:
2025-08-19 02:58:15.195384 | compute1 | {
2025-08-19 02:58:15.195442 | compute1 |   "key": "158.69.74.227 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:15.195498 | compute1 |   "name": "158.69.74.227"
2025-08-19 02:58:15.195607 | compute1 | }
2025-08-19 02:58:15.416305 | controller | changed:
2025-08-19 02:58:15.416456 | controller | {
2025-08-19 02:58:15.416600 | controller |   "key": "2607:5300:201:2000::157 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:15.416666 | controller |   "name": "2607:5300:201:2000::157"
2025-08-19 02:58:15.416719 | controller | }
2025-08-19 02:58:15.543728 | compute1 | changed:
2025-08-19 02:58:15.543875 | compute1 | {
2025-08-19 02:58:15.543934 | compute1 |   "key": "2607:5300:201:2000::157 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:15.543989 | compute1 |   "name": "2607:5300:201:2000::157"
2025-08-19 02:58:15.544040 | compute1 | }
2025-08-19 02:58:15.779194 | controller | changed:
2025-08-19 02:58:15.779338 | controller | {
2025-08-19 02:58:15.779398 | controller |   "key": "2607:5300:201:2000::157 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:15.779453 | controller |   "name": "2607:5300:201:2000::157"
2025-08-19 02:58:15.779504 | controller | }
2025-08-19 02:58:15.920289 | compute1 | changed:
2025-08-19 02:58:15.920449 | compute1 | {
2025-08-19 02:58:15.920540 | compute1 |   "key": "2607:5300:201:2000::157 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:15.922148 | compute1 |   "name": "2607:5300:201:2000::157"
2025-08-19 02:58:15.922206 | compute1 | }
2025-08-19 02:58:16.132808 | controller | changed:
2025-08-19 02:58:16.133039 | controller | {
2025-08-19 02:58:16.133105 | controller |   "key": "2607:5300:201:2000::157 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:16.133162 | controller |   "name": "2607:5300:201:2000::157"
2025-08-19 02:58:16.133215 | controller | }
2025-08-19 02:58:16.274474 | compute1 | changed:
2025-08-19 02:58:16.274894 | compute1 | {
2025-08-19 02:58:16.274958 | compute1 |   "key": "2607:5300:201:2000::157 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:16.275014 | compute1 |   "name": "2607:5300:201:2000::157"
2025-08-19 02:58:16.275066 | compute1 | }
2025-08-19 02:58:16.469213 | controller | changed:
2025-08-19 02:58:16.469363 | controller | {
2025-08-19 02:58:16.469423 | controller |   "key": "2607:5300:201:2000::1a4 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:16.469476 | controller |   "name": "2607:5300:201:2000::1a4"
2025-08-19 02:58:16.469556 | controller | }
2025-08-19 02:58:16.615747 | compute1 | changed:
2025-08-19 02:58:16.615895 | compute1 | {
2025-08-19 02:58:16.615955 | compute1 |   "key": "2607:5300:201:2000::1a4 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:16.616010 | compute1 |   "name": "2607:5300:201:2000::1a4"
2025-08-19 02:58:16.616061 | compute1 | }
2025-08-19 02:58:16.840712 | controller | changed:
2025-08-19 02:58:16.840847 | controller | {
2025-08-19 02:58:16.840907 | controller |   "key": "2607:5300:201:2000::1a4 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:16.840962 | controller |   "name": "2607:5300:201:2000::1a4"
2025-08-19 02:58:16.841014 | controller | }
2025-08-19 02:58:16.968708 | compute1 | changed:
2025-08-19 02:58:16.968858 | compute1 | {
2025-08-19 02:58:16.968917 | compute1 |   "key": "2607:5300:201:2000::1a4 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:16.968971 | compute1 |   "name": "2607:5300:201:2000::1a4"
2025-08-19 02:58:16.969022 | compute1 | }
2025-08-19 02:58:17.175893 | controller | changed:
2025-08-19 02:58:17.176057 | controller | {
2025-08-19 02:58:17.176119 | controller |   "key": "2607:5300:201:2000::1a4 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:17.176196 | controller |   "name": "2607:5300:201:2000::1a4"
2025-08-19 02:58:17.176256 | controller | }
2025-08-19 02:58:17.353129 | compute1 | changed:
2025-08-19 02:58:17.353269 | compute1 | {
2025-08-19 02:58:17.353328 | compute1 |   "key": "2607:5300:201:2000::1a4 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:17.353384 | compute1 |   "name": "2607:5300:201:2000::1a4"
2025-08-19 02:58:17.353435 | compute1 | }
2025-08-19 02:58:17.499008 | controller | changed:
2025-08-19 02:58:17.499192 | controller | {
2025-08-19 02:58:17.499268 | controller |   "key": "fe80::f816:3eff:fe47:545a ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:17.499338 | controller |   "name": "fe80::f816:3eff:fe47:545a"
2025-08-19 02:58:17.499404 | controller | }
2025-08-19 02:58:17.688371 | compute1 | changed:
2025-08-19 02:58:17.688553 | compute1 | {
2025-08-19 02:58:17.688628 | compute1 |   "key": "fe80::f816:3eff:fe47:545a ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:17.688685 | compute1 |   "name": "fe80::f816:3eff:fe47:545a"
2025-08-19 02:58:17.688736 | compute1 | }
2025-08-19 02:58:17.825341 | controller | changed:
2025-08-19 02:58:17.825484 | controller | {
2025-08-19 02:58:17.825589 | controller |   "key": "fe80::f816:3eff:fe47:545a ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:17.825668 | controller |   "name": "fe80::f816:3eff:fe47:545a"
2025-08-19 02:58:17.825723 | controller | }
2025-08-19 02:58:18.025951 | compute1 | changed:
2025-08-19 02:58:18.026090 | compute1 | {
2025-08-19 02:58:18.026149 | compute1 |   "key": "fe80::f816:3eff:fe47:545a ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:18.026237 | compute1 |   "name": "fe80::f816:3eff:fe47:545a"
2025-08-19 02:58:18.026294 | compute1 | }
2025-08-19 02:58:18.152976 | controller | changed:
2025-08-19 02:58:18.153115 | controller | {
2025-08-19 02:58:18.153173 | controller |   "key": "fe80::f816:3eff:fe47:545a ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:18.153227 | controller |   "name": "fe80::f816:3eff:fe47:545a"
2025-08-19 02:58:18.153277 | controller | }
2025-08-19 02:58:18.373835 | compute1 | changed:
2025-08-19 02:58:18.373987 | compute1 | {
2025-08-19 02:58:18.374063 | compute1 |   "key": "fe80::f816:3eff:fe47:545a ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:18.374154 | compute1 |   "name": "fe80::f816:3eff:fe47:545a"
2025-08-19 02:58:18.374259 | compute1 | }
2025-08-19 02:58:18.490006 | controller | changed:
2025-08-19 02:58:18.490143 | controller | {
2025-08-19 02:58:18.490227 | controller |   "key": "fe80::f816:3eff:fe9c:39a9 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:18.490295 | controller |   "name": "fe80::f816:3eff:fe9c:39a9"
2025-08-19 02:58:18.490387 | controller | }
2025-08-19 02:58:18.711511 | compute1 | changed:
2025-08-19 02:58:18.711694 | compute1 | {
2025-08-19 02:58:18.711753 | compute1 |   "key": "fe80::f816:3eff:fe9c:39a9 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:18.711807 | compute1 |   "name": "fe80::f816:3eff:fe9c:39a9"
2025-08-19 02:58:18.711889 | compute1 | }
2025-08-19 02:58:18.918556 | controller | changed:
2025-08-19 02:58:18.918682 | controller | {
2025-08-19 02:58:18.918741 | controller |   "key": "fe80::f816:3eff:fe9c:39a9 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:18.918795 | controller |   "name": "fe80::f816:3eff:fe9c:39a9"
2025-08-19 02:58:18.918845 | controller | }
2025-08-19 02:58:19.060638 | compute1 | changed:
2025-08-19 02:58:19.060812 | compute1 | {
2025-08-19 02:58:19.060873 | compute1 |   "key": "fe80::f816:3eff:fe9c:39a9 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:19.060927 | compute1 |   "name": "fe80::f816:3eff:fe9c:39a9"
2025-08-19 02:58:19.060977 | compute1 | }
2025-08-19 02:58:19.228089 | controller | changed:
2025-08-19 02:58:19.228230 | controller | {
2025-08-19 02:58:19.228290 | controller |   "key": "fe80::f816:3eff:fe9c:39a9 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:19.228345 | controller |   "name": "fe80::f816:3eff:fe9c:39a9"
2025-08-19 02:58:19.228396 | controller | }
2025-08-19 02:58:19.441629 | compute1 | changed:
2025-08-19 02:58:19.441775 | compute1 | {
2025-08-19 02:58:19.441835 | compute1 |   "key": "fe80::f816:3eff:fe9c:39a9 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:19.441890 | compute1 |   "name": "fe80::f816:3eff:fe9c:39a9"
2025-08-19 02:58:19.441941 | compute1 | }
2025-08-19 02:58:19.565441 | controller | changed:
2025-08-19 02:58:19.565610 | controller | {
2025-08-19 02:58:19.565669 | controller |   "key": "np7ef8a092bd3c4 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:19.565739 | controller |   "name": "np7ef8a092bd3c4"
2025-08-19 02:58:19.565793 | controller | }
2025-08-19 02:58:19.796516 | compute1 | changed:
2025-08-19 02:58:19.796688 | compute1 | {
2025-08-19 02:58:19.796750 | compute1 |   "key": "np7ef8a092bd3c4 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF7b94sQaThJmds5MocFpDnYaWoHIMq8BKlAfIJF5vwh1Ejry9L9i/vRk8JuFES7EKcxBngSLivvyLecFschD/I=",
2025-08-19 02:58:19.796816 | compute1 |   "name": "np7ef8a092bd3c4"
2025-08-19 02:58:19.796879 | compute1 | }
2025-08-19 02:58:19.897755 | controller | changed:
2025-08-19 02:58:19.897895 | controller | {
2025-08-19 02:58:19.897954 | controller |   "key": "np7ef8a092bd3c4 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:19.898007 | controller |   "name": "np7ef8a092bd3c4"
2025-08-19 02:58:19.898058 | controller | }
2025-08-19 02:58:20.137422 | compute1 | changed:
2025-08-19 02:58:20.137607 | compute1 | {
2025-08-19 02:58:20.137669 | compute1 |   "key": "np7ef8a092bd3c4 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID5MrRvVhsyANI4edtPZ5CNnmJvGGnhBpDVK0YRTuo2N",
2025-08-19 02:58:20.137723 | compute1 |   "name": "np7ef8a092bd3c4"
2025-08-19 02:58:20.137774 | compute1 | }
2025-08-19 02:58:20.232756 | controller | changed:
2025-08-19 02:58:20.232897 | controller | {
2025-08-19 02:58:20.232956 | controller |   "key": "np7ef8a092bd3c4 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:20.233009 | controller |   "name": "np7ef8a092bd3c4"
2025-08-19 02:58:20.233060 | controller | }
2025-08-19 02:58:20.487468 | compute1 | changed:
2025-08-19 02:58:20.487637 | compute1 | {
2025-08-19 02:58:20.487699 | compute1 |   "key": "np7ef8a092bd3c4 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC1R47NtgnJ4XXetew6ItLc8tof/S30qDiG68uWQwhy8BRyqCn3m2ldACJlToW25/D0QaR+ZZkiHhGNz/zGYYq00BlYei87yflUZ5KJh5Ol6OQjljpeuWFsZgVFxb5dfvM7UqBWnu8tjZMyi3+T2zPGrxif9dTadDuaaCR/aXeqJtC6phRqE0lnJCMX02otzA3qX6FJDDsHRfrVd0GNT2IqBiS6/6AO480PIpA0HUAHb6wxEZ7ECblIA8/GtLplQGSuXVl9XAgcpCu1pz6B8BxBkqignpMlesA4UQreCY22V70pIWsRUtq5UyGL2gXya1hruGY0VZ2abLjMAw8pun8NtibBu98mnm/yh/PBMdLM657C1MIGPhYCBWtoIt3olPG6VSQbdv1IV2J7oRZqi4Uzl288Mp58q/7xVDzIKhJEyxh8f7WXSebl9I2H6YsddCT2zkHYSCsnqobqaJKpCT12Sf97IQ1WnP33QQZsTBjZtg0qHXRHUI2f3la/mLwzZ4U=",
2025-08-19 02:58:20.487768 | compute1 |   "name": "np7ef8a092bd3c4"
2025-08-19 02:58:20.487834 | compute1 | }
2025-08-19 02:58:20.577660 | controller | changed:
2025-08-19 02:58:20.577824 | controller | {
2025-08-19 02:58:20.577884 | controller |   "key": "npc21e6f3629714 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:20.577938 | controller |   "name": "npc21e6f3629714"
2025-08-19 02:58:20.577988 | controller | }
2025-08-19 02:58:20.852795 | compute1 | changed:
2025-08-19 02:58:20.852972 | compute1 | {
2025-08-19 02:58:20.853037 | compute1 |   "key": "npc21e6f3629714 ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGo8NQj4GeHOMnW2Q7DZm5i4dg6agCTbwVS2l5vrdVFHrKMsl4rVgPgrVFAoKr3fbQWzZEx7XPKMUAv4Pkwq8Vs=",
2025-08-19 02:58:20.853092 | compute1 |   "name": "npc21e6f3629714"
2025-08-19 02:58:20.853143 | compute1 | }
2025-08-19 02:58:20.943376 | controller | changed:
2025-08-19 02:58:20.943561 | controller | {
2025-08-19 02:58:20.943644 | controller |   "key": "npc21e6f3629714 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:20.943722 | controller |   "name": "npc21e6f3629714"
2025-08-19 02:58:20.943778 | controller | }
2025-08-19 02:58:21.199739 | compute1 | changed:
2025-08-19 02:58:21.199874 | compute1 | {
2025-08-19 02:58:21.199931 | compute1 |   "key": "npc21e6f3629714 ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIECS+t5cEPy93DTEQgDNpSEjJsBM/nVmpXeQq13AC+ON",
2025-08-19 02:58:21.199984 | compute1 |   "name": "npc21e6f3629714"
2025-08-19 02:58:21.200033 | compute1 | }
2025-08-19 02:58:21.276286 | controller | changed:
2025-08-19 02:58:21.276434 | controller | {
2025-08-19 02:58:21.276493 | controller |   "key": "npc21e6f3629714 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:21.276582 | controller |   "name": "npc21e6f3629714"
2025-08-19 02:58:21.276671 | controller | }
2025-08-19 02:58:21.571243 | compute1 | changed:
2025-08-19 02:58:21.571383 | compute1 | {
2025-08-19 02:58:21.571442 | compute1 |   "key": "npc21e6f3629714 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDMAYjxEkFf2fn5J9obbIRSllzq6SMdV0cLvUDnwTQpoGAi1dB1LUddJVg5pL3sh+8Kp1GW2WXq/fD57fFNSb89Y2zz5TxfTlmxHruYzvymqKLLpfTyJZcjK6bNujjYOKj9nBAX3amnTAkPwebl/XvXUnYpWGSiwDa1cij3lbc16aVOHf2r9mktSL+1A8Io8tJEp0YqZmIVtbBeJqA0+cRKRu/NSvwGMfUiXJ5lvvWJw9Vm8nXnxTUSIz3glWLXCOAFLi6JC0i42yT/1DJlp6wMGQ19YDhpJuimNhMqrvbr8rDDRdMClvHshVvhdP1AOZqTBLlPKVUNFA68aCgfxmfQoFFQ1UHcsiSDbu1t2iBIczZXZq9Xwk9iepvYuP6y46AQp3Edhrr2mO+nwdQKvqKUe0tY3Fud2GKxl7BuWD0oewW1L6MGfpe9KGOuB5KuhHNxKlwkHy4cYJcFCDSyxcbML8WTMhphwpJAlFFAhRAfex3xjAcE7Oo7nOju6nwJlAs=",
2025-08-19 02:58:21.571496 | compute1 |   "name": "npc21e6f3629714"
2025-08-19 02:58:21.571614 | compute1 | }
2025-08-19 02:58:21.571707 | compute1 | changed: All items complete
2025-08-19 02:58:21.571767 | 
2025-08-19 02:58:21.619227 | 
2025-08-19 02:58:21.619387 | TASK [Set up multi-node hosts file]
2025-08-19 02:58:21.659903 | compute1 | ok
2025-08-19 02:58:21.669289 | controller | ok
2025-08-19 02:58:21.698182 | compute1 | included: multi-node-hosts-file
2025-08-19 02:58:21.698339 | controller | included: multi-node-hosts-file
2025-08-19 02:58:21.714832 | 
2025-08-19 02:58:21.715022 | TASK [multi-node-hosts-file : Set up the list of hosts and addresses]
2025-08-19 02:58:21.792456 | compute1 | ok
2025-08-19 02:58:21.812827 | controller | ok
2025-08-19 02:58:21.823669 | 
2025-08-19 02:58:21.823823 | LOOP [multi-node-hosts-file : Add inventory hostnames to the hosts file]
2025-08-19 02:58:22.505346 | controller | changed: line added
2025-08-19 02:58:22.526684 | compute1 | changed: line added
2025-08-19 02:58:22.826262 | controller | changed: line added
2025-08-19 02:58:22.876381 | compute1 | changed: line added
2025-08-19 02:58:23.148391 | controller | changed: line added
2025-08-19 02:58:23.198722 | compute1 | changed: line added
2025-08-19 02:58:23.481818 | controller | changed: line added
2025-08-19 02:58:23.618841 | compute1 | changed: line added
2025-08-19 02:58:23.619017 | compute1 | changed: All items complete
2025-08-19 02:58:23.619077 | 
2025-08-19 02:58:23.651343 | 
2025-08-19 02:58:23.651541 | TASK [Set up multi-node firewall]
2025-08-19 02:58:23.691666 | compute1 | ok
2025-08-19 02:58:23.698814 | controller | ok
2025-08-19 02:58:23.733380 | compute1 | included: multi-node-firewall
2025-08-19 02:58:23.733502 | controller | included: multi-node-firewall
2025-08-19 02:58:23.748718 | 
2025-08-19 02:58:23.748838 | LOOP [multi-node-firewall : Include operating system specific vars]
2025-08-19 02:58:23.799428 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-firewall/vars/default.yaml"
2025-08-19 02:58:23.814605 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-firewall/vars/default.yaml"
2025-08-19 02:58:23.814747 | controller | ok: All items complete
2025-08-19 02:58:23.814807 | 
2025-08-19 02:58:23.829251 | 
2025-08-19 02:58:23.829735 | TASK [multi-node-firewall : Ensure iptables]
2025-08-19 02:58:25.237764 | controller | ok
2025-08-19 02:58:25.316711 | compute1 | ok
2025-08-19 02:58:25.325572 | 
2025-08-19 02:58:25.325698 | TASK [multi-node-firewall : Set up the host ip addresses]
2025-08-19 02:58:25.525507 | compute1 | ok
2025-08-19 02:58:25.557840 | controller | ok
2025-08-19 02:58:25.566773 | 
2025-08-19 02:58:25.566944 | LOOP [multi-node-firewall : Set up ipv4 iptables rules]
2025-08-19 02:58:26.275249 | controller | changed: "158.69.73.106"
2025-08-19 02:58:26.305796 | compute1 | changed: "158.69.73.106"
2025-08-19 02:58:26.595333 | controller | changed: "158.69.74.227"
2025-08-19 02:58:26.703651 | compute1 | changed: "158.69.74.227"
2025-08-19 02:58:26.703814 | compute1 | changed: All items complete
2025-08-19 02:58:26.703878 | 
2025-08-19 02:58:26.715420 | 
2025-08-19 02:58:26.715568 | LOOP [multi-node-firewall : Set up ipv6 iptables rules]
2025-08-19 02:58:26.773095 | 
2025-08-19 02:58:26.773368 | TASK [Persist iptables rules]
2025-08-19 02:58:26.810254 | compute1 | ok
2025-08-19 02:58:26.817680 | controller | ok
2025-08-19 02:58:26.849721 | compute1 | included: persistent-firewall
2025-08-19 02:58:26.849839 | controller | included: persistent-firewall
2025-08-19 02:58:26.862729 | 
2025-08-19 02:58:26.862841 | TASK [persistent-firewall : List current ipv4 rules]
2025-08-19 02:58:27.463799 | controller | # Generated by iptables-save v1.8.7 on Tue Aug 19 02:58:27 2025
2025-08-19 02:58:27.463913 | controller | *filter
2025-08-19 02:58:27.463934 | controller | :INPUT ACCEPT [0:0]
2025-08-19 02:58:27.463946 | controller | :FORWARD ACCEPT [0:0]
2025-08-19 02:58:27.463960 | controller | :OUTPUT ACCEPT [7592:1100050]
2025-08-19 02:58:27.463979 | controller | :openstack-INPUT - [0:0]
2025-08-19 02:58:27.463996 | controller | -A INPUT -s 158.69.74.227/32 -j ACCEPT
2025-08-19 02:58:27.464013 | controller | -A INPUT -s 158.69.73.106/32 -j ACCEPT
2025-08-19 02:58:27.464027 | controller | -A INPUT -j openstack-INPUT
2025-08-19 02:58:27.464036 | controller | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:58:27.464100 | controller | -A openstack-INPUT -p icmp -m icmp --icmp-type any -j ACCEPT
2025-08-19 02:58:27.464114 | controller | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:58:27.464122 | controller | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:58:27.464128 | controller | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:58:27.464134 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p udp -m udp --dport 69 -j ACCEPT
2025-08-19 02:58:27.464151 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 6385 -j ACCEPT
2025-08-19 02:58:27.464156 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 80 -j ACCEPT
2025-08-19 02:58:27.464162 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8000 -j ACCEPT
2025-08-19 02:58:27.464168 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8003 -j ACCEPT
2025-08-19 02:58:27.464179 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8004 -j ACCEPT
2025-08-19 02:58:27.464186 | controller | -A openstack-INPUT -m limit --limit 2/min -j LOG --log-prefix "iptables dropped: "
2025-08-19 02:58:27.464193 | controller | -A openstack-INPUT -j REJECT --reject-with icmp-host-prohibited
2025-08-19 02:58:27.464198 | controller | COMMIT
2025-08-19 02:58:27.464204 | controller | # Completed on Tue Aug 19 02:58:27 2025
2025-08-19 02:58:27.506448 | compute1 | # Generated by iptables-save v1.8.7 on Tue Aug 19 02:58:27 2025
2025-08-19 02:58:27.506543 | compute1 | *filter
2025-08-19 02:58:27.506556 | compute1 | :INPUT ACCEPT [0:0]
2025-08-19 02:58:27.506564 | compute1 | :FORWARD ACCEPT [0:0]
2025-08-19 02:58:27.506573 | compute1 | :OUTPUT ACCEPT [11986:1853785]
2025-08-19 02:58:27.506584 | compute1 | :openstack-INPUT - [0:0]
2025-08-19 02:58:27.506594 | compute1 | -A INPUT -s 158.69.74.227/32 -j ACCEPT
2025-08-19 02:58:27.506603 | compute1 | -A INPUT -s 158.69.73.106/32 -j ACCEPT
2025-08-19 02:58:27.506610 | compute1 | -A INPUT -j openstack-INPUT
2025-08-19 02:58:27.506617 | compute1 | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:58:27.506623 | compute1 | -A openstack-INPUT -p icmp -m icmp --icmp-type any -j ACCEPT
2025-08-19 02:58:27.506631 | compute1 | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:58:27.506638 | compute1 | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:58:27.506645 | compute1 | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:58:27.506651 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p udp -m udp --dport 69 -j ACCEPT
2025-08-19 02:58:27.506658 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 6385 -j ACCEPT
2025-08-19 02:58:27.506664 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 80 -j ACCEPT
2025-08-19 02:58:27.506671 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8000 -j ACCEPT
2025-08-19 02:58:27.506677 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8003 -j ACCEPT
2025-08-19 02:58:27.506695 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8004 -j ACCEPT
2025-08-19 02:58:27.506708 | compute1 | -A openstack-INPUT -m limit --limit 2/min -j LOG --log-prefix "iptables dropped: "
2025-08-19 02:58:27.506768 | compute1 | -A openstack-INPUT -j REJECT --reject-with icmp-host-prohibited
2025-08-19 02:58:27.506781 | compute1 | COMMIT
2025-08-19 02:58:27.506788 | compute1 | # Completed on Tue Aug 19 02:58:27 2025
2025-08-19 02:58:27.593125 | controller | ok: Runtime: 0:00:00.006411
2025-08-19 02:58:27.608735 | compute1 | ok: Runtime: 0:00:00.006728
2025-08-19 02:58:27.620596 | 
2025-08-19 02:58:27.620743 | TASK [persistent-firewall : List current ipv6 rules]
2025-08-19 02:58:28.104894 | controller | # Generated by ip6tables-save v1.8.7 on Tue Aug 19 02:58:28 2025
2025-08-19 02:58:28.104964 | controller | *filter
2025-08-19 02:58:28.104984 | controller | :INPUT ACCEPT [0:0]
2025-08-19 02:58:28.104998 | controller | :FORWARD ACCEPT [0:0]
2025-08-19 02:58:28.105012 | controller | :OUTPUT ACCEPT [122:9566]
2025-08-19 02:58:28.105029 | controller | :openstack-INPUT - [0:0]
2025-08-19 02:58:28.105045 | controller | -A INPUT -j openstack-INPUT
2025-08-19 02:58:28.105057 | controller | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:58:28.105073 | controller | -A openstack-INPUT -p ipv6-icmp -j ACCEPT
2025-08-19 02:58:28.105088 | controller | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:58:28.105101 | controller | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:58:28.105112 | controller | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:58:28.105119 | controller | -A openstack-INPUT -j REJECT --reject-with icmp6-adm-prohibited
2025-08-19 02:58:28.105182 | controller | COMMIT
2025-08-19 02:58:28.105195 | controller | # Completed on Tue Aug 19 02:58:28 2025
2025-08-19 02:58:28.082283 | compute1 | # Generated by ip6tables-save v1.8.7 on Tue Aug 19 02:58:28 2025
2025-08-19 02:58:28.082357 | compute1 | *filter
2025-08-19 02:58:28.082370 | compute1 | :INPUT ACCEPT [0:0]
2025-08-19 02:58:28.082378 | compute1 | :FORWARD ACCEPT [0:0]
2025-08-19 02:58:28.082387 | compute1 | :OUTPUT ACCEPT [125:9841]
2025-08-19 02:58:28.082398 | compute1 | :openstack-INPUT - [0:0]
2025-08-19 02:58:28.082408 | compute1 | -A INPUT -j openstack-INPUT
2025-08-19 02:58:28.082415 | compute1 | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:58:28.082425 | compute1 | -A openstack-INPUT -p ipv6-icmp -j ACCEPT
2025-08-19 02:58:28.082432 | compute1 | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:58:28.082438 | compute1 | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:58:28.082445 | compute1 | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:58:28.082452 | compute1 | -A openstack-INPUT -j REJECT --reject-with icmp6-adm-prohibited
2025-08-19 02:58:28.082458 | compute1 | COMMIT
2025-08-19 02:58:28.082465 | compute1 | # Completed on Tue Aug 19 02:58:28 2025
2025-08-19 02:58:28.350797 | compute1 | ok: Runtime: 0:00:00.005210
2025-08-19 02:58:28.368213 | controller | ok: Runtime: 0:00:00.005372
2025-08-19 02:58:28.378575 | 
2025-08-19 02:58:28.378774 | LOOP [persistent-firewall : Configure persistent iptables rules]
2025-08-19 02:58:28.426655 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml"
2025-08-19 02:58:28.427243 | 
2025-08-19 02:58:28.440566 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml"
2025-08-19 02:58:28.464180 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml
2025-08-19 02:58:28.464342 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml
2025-08-19 02:58:28.477594 | 
2025-08-19 02:58:28.477755 | TASK [persistent-firewall : Install iptables-persistent]
2025-08-19 02:58:29.611318 | controller | ok
2025-08-19 02:58:29.660516 | compute1 | ok
2025-08-19 02:58:29.669971 | 
2025-08-19 02:58:29.670081 | TASK [persistent-firewall : Persist ipv4 rules]
2025-08-19 02:58:31.707439 | controller | changed
2025-08-19 02:58:31.717327 | compute1 | changed
2025-08-19 02:58:31.727732 | 
2025-08-19 02:58:31.727863 | TASK [persistent-firewall : Persist ipv6 rules]
2025-08-19 02:58:33.187817 | controller | changed
2025-08-19 02:58:33.258720 | compute1 | changed
2025-08-19 02:58:33.269383 | 
2025-08-19 02:58:33.269612 | TASK [persistent-firewall : Ensure netfilter-persistent is started]
2025-08-19 02:58:34.503187 | compute1 | ok
2025-08-19 02:58:34.517688 | controller | ok
2025-08-19 02:58:34.558815 | 
2025-08-19 02:58:34.559014 | PLAY [Configure multi-node networking switch and peer nodes]
2025-08-19 02:58:34.570962 | 
2025-08-19 02:58:34.571133 | TASK [multi-node-bridge : Include common tasks]
2025-08-19 02:58:34.620864 | controller | ok
2025-08-19 02:58:34.628610 | compute1 | ok
2025-08-19 02:58:34.661905 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/common.yaml
2025-08-19 02:58:34.662080 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/common.yaml
2025-08-19 02:58:34.672633 | 
2025-08-19 02:58:34.672824 | LOOP [multi-node-bridge : Include OS-specific variables]
2025-08-19 02:58:34.728852 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/vars/Debian.yaml"
2025-08-19 02:58:34.729454 | controller | ok: All items complete
2025-08-19 02:58:34.729558 | 
2025-08-19 02:58:34.749286 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/vars/Debian.yaml"
2025-08-19 02:58:34.759876 | 
2025-08-19 02:58:34.760073 | TASK [multi-node-bridge : Set up RDO GPG key]
2025-08-19 02:58:34.790365 | controller | skipping: Conditional result was False
2025-08-19 02:58:34.813285 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:34.824918 | 
2025-08-19 02:58:34.825169 | TASK [multi-node-bridge : Set up RDO repository]
2025-08-19 02:58:34.853685 | controller | skipping: Conditional result was False
2025-08-19 02:58:34.876159 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:34.886546 | 
2025-08-19 02:58:34.886746 | LOOP [multi-node-bridge : Set package.use values for OVS on Gentoo]
2025-08-19 02:58:34.926415 | controller | skipping: Conditional result was False
2025-08-19 02:58:34.927175 | controller | ok: All items complete
2025-08-19 02:58:34.927244 | 
2025-08-19 02:58:34.928847 | controller | skipping: Conditional result was False
2025-08-19 02:58:34.929713 | controller | skipping: Conditional result was False
2025-08-19 02:58:34.945751 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:34.945926 | 
2025-08-19 02:58:34.949059 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:34.952998 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:34.966348 | 
2025-08-19 02:58:34.966603 | TASK [multi-node-bridge : Install openvswitch]
2025-08-19 02:58:39.238947 | controller | changed
2025-08-19 02:58:39.553503 | compute1 | changed
2025-08-19 02:58:39.563951 | 
2025-08-19 02:58:39.564102 | TASK [multi-node-bridge : Install openvswitch (Gentoo)]
2025-08-19 02:58:39.635563 | controller | skipping: Conditional result was False
2025-08-19 02:58:39.657272 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:39.667287 | 
2025-08-19 02:58:39.667463 | TASK [multi-node-bridge : Ensure openvswitch is started]
2025-08-19 02:58:40.387483 | controller | ok
2025-08-19 02:58:40.467114 | compute1 | ok
2025-08-19 02:58:40.481017 | 
2025-08-19 02:58:40.481198 | LOOP [multi-node-bridge : Remove RDO repository files]
2025-08-19 02:58:40.506929 | controller | skipping: Conditional result was False
2025-08-19 02:58:40.508827 | 
2025-08-19 02:58:40.510225 | controller | skipping: Conditional result was False
2025-08-19 02:58:40.534970 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:40.535558 | 
2025-08-19 02:58:40.538154 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:40.558191 | 
2025-08-19 02:58:40.558394 | TASK [multi-node-bridge : Authorize the multi-node-bridge network]
2025-08-19 02:58:40.629145 | controller | skipping: Conditional result was False
2025-08-19 02:58:40.650724 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:40.662801 | 
2025-08-19 02:58:40.663037 | TASK [multi-node-bridge : Determine bridge mtu]
2025-08-19 02:58:41.117018 | controller | 1430
2025-08-19 02:58:41.157073 | compute1 | 1430
2025-08-19 02:58:41.400135 | controller | ok: Runtime: 0:00:00.014134
2025-08-19 02:58:41.415349 | compute1 | ok: Runtime: 0:00:00.014753
2025-08-19 02:58:41.424510 | 
2025-08-19 02:58:41.424746 | TASK [multi-node-bridge : Set bridge_mtu]
2025-08-19 02:58:41.485077 | controller | ok
2025-08-19 02:58:41.506044 | compute1 | ok
2025-08-19 02:58:41.514789 | 
2025-08-19 02:58:41.514991 | TASK [multi-node-bridge : Include switch tasks]
2025-08-19 02:58:41.557343 | controller | ok
2025-08-19 02:58:41.569193 | compute1 | skipping: Conditional result was False
2025-08-19 02:58:41.585836 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/switch.yaml
2025-08-19 02:58:41.602106 | 
2025-08-19 02:58:41.602277 | TASK [multi-node-bridge : Ensure the bridge exists]
2025-08-19 02:58:42.369968 | controller | changed
2025-08-19 02:58:42.380996 | 
2025-08-19 02:58:42.381158 | TASK [multi-node-bridge : Set the bridge MTU]
2025-08-19 02:58:53.133067 | controller | ok: Runtime: 0:00:00.005494
2025-08-19 02:58:53.143888 | 
2025-08-19 02:58:53.144079 | TASK [multi-node-bridge : Verify if the bridge address is set]
2025-08-19 02:59:03.892590 | controller | ok: Runtime: 0:00:00.006424
2025-08-19 02:59:03.901940 | 
2025-08-19 02:59:03.902093 | TASK [multi-node-bridge : Set the bridge address]
2025-08-19 02:59:14.652417 | controller | ok: Runtime: 0:00:00.004693
2025-08-19 02:59:14.662907 | 
2025-08-19 02:59:14.663069 | TASK [multi-node-bridge : Bring bridge interface up]
2025-08-19 02:59:25.407689 | controller | ok: Runtime: 0:00:00.004101
2025-08-19 02:59:25.421467 | 
2025-08-19 02:59:25.421887 | TASK [multi-node-bridge : Include peer tasks]
2025-08-19 02:59:25.453996 | controller | skipping: Conditional result was False
2025-08-19 02:59:25.486073 | compute1 | ok
2025-08-19 02:59:25.509633 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/peer.yaml
2025-08-19 02:59:25.527351 | 
2025-08-19 02:59:25.527486 | TASK [multi-node-bridge : Set offset]
2025-08-19 02:59:25.624430 | compute1 | ok
2025-08-19 02:59:25.634422 | 
2025-08-19 02:59:25.634640 | TASK [multi-node-bridge : Add additional vni offset]
2025-08-19 02:59:25.722631 | compute1 | ok
2025-08-19 02:59:25.732258 | 
2025-08-19 02:59:25.732390 | TASK [multi-node-bridge : Set ip address when the node private IP looks empty]
2025-08-19 02:59:25.942842 | compute1 | skipping: Conditional result was False
2025-08-19 02:59:25.954490 | 
2025-08-19 02:59:25.954701 | TASK [multi-node-bridge : Set ip address when the node private IP was not defined]
2025-08-19 02:59:26.051967 | compute1 | ok
2025-08-19 02:59:26.062466 | 
2025-08-19 02:59:26.062642 | TASK [multi-node-bridge : Select the switch from group and the private ip]
2025-08-19 02:59:26.134975 | compute1 | ok
2025-08-19 02:59:26.204338 | 
2025-08-19 02:59:26.204556 | TASK [multi-node-bridge : Alias the primary node private IP, if it looks empty]
2025-08-19 02:59:26.345294 | compute1 | skipping: Conditional result was False
2025-08-19 02:59:26.357280 | 
2025-08-19 02:59:26.357454 | TASK [multi-node-bridge : Alias the primary node private IP, if it was not defined]
2025-08-19 02:59:26.464893 | compute1 | ok
2025-08-19 02:59:26.474476 | 
2025-08-19 02:59:26.474662 | TASK [multi-node-bridge : Add port to bridge on switch node]
2025-08-19 02:59:27.073887 | compute1 -> 158.69.73.106 | ok: Runtime: 0:00:00.028097
2025-08-19 02:59:27.084430 | 
2025-08-19 02:59:27.084665 | TASK [multi-node-bridge : Create bridge on peer node]
2025-08-19 02:59:27.651041 | compute1 | changed
2025-08-19 02:59:27.660359 | 
2025-08-19 02:59:27.660566 | TASK [multi-node-bridge : Set MTU on peer node bridge]
2025-08-19 02:59:28.396318 | compute1 | ok: Runtime: 0:00:00.005593
2025-08-19 02:59:28.406457 | 
2025-08-19 02:59:28.406658 | TASK [multi-node-bridge : Add port to bridge on peer node]
2025-08-19 02:59:29.145412 | compute1 | ok: Runtime: 0:00:00.020986
2025-08-19 02:59:29.154774 | 
2025-08-19 02:59:29.154910 | TASK [multi-node-bridge : Verify if the bridge address is set]
2025-08-19 02:59:29.886047 | compute1 | ok: Runtime: 0:00:00.005761
2025-08-19 02:59:29.896349 | 
2025-08-19 02:59:29.896551 | TASK [multi-node-bridge : Set the bridge address]
2025-08-19 02:59:30.629408 | compute1 | ok: Runtime: 0:00:00.004892
2025-08-19 02:59:30.638762 | 
2025-08-19 02:59:30.638889 | TASK [multi-node-bridge : Bring subnode bridge interface up]
2025-08-19 02:59:31.369295 | compute1 | ok: Runtime: 0:00:00.004077
2025-08-19 02:59:31.380417 | 
2025-08-19 02:59:31.380651 | TASK [Persist iptables rules]
2025-08-19 02:59:31.425694 | controller | ok
2025-08-19 02:59:31.444623 | compute1 | ok
2025-08-19 02:59:31.562452 | controller | included: persistent-firewall
2025-08-19 02:59:31.562640 | compute1 | included: persistent-firewall
2025-08-19 02:59:31.595100 | 
2025-08-19 02:59:31.595276 | TASK [persistent-firewall : List current ipv4 rules]
2025-08-19 02:59:32.138103 | controller | # Generated by iptables-save v1.8.7 on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:32.138188 | controller | *filter
2025-08-19 02:59:32.138212 | controller | :INPUT ACCEPT [0:0]
2025-08-19 02:59:32.138227 | controller | :FORWARD ACCEPT [0:0]
2025-08-19 02:59:32.138244 | controller | :OUTPUT ACCEPT [8756:1217663]
2025-08-19 02:59:32.138281 | controller | :openstack-INPUT - [0:0]
2025-08-19 02:59:32.138300 | controller | -A INPUT -s 158.69.74.227/32 -j ACCEPT
2025-08-19 02:59:32.138328 | controller | -A INPUT -s 158.69.73.106/32 -j ACCEPT
2025-08-19 02:59:32.138473 | controller | -A INPUT -j openstack-INPUT
2025-08-19 02:59:32.138499 | controller | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:59:32.138507 | controller | -A openstack-INPUT -p icmp -m icmp --icmp-type any -j ACCEPT
2025-08-19 02:59:32.138515 | controller | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:59:32.138522 | controller | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:59:32.138529 | controller | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:59:32.138535 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p udp -m udp --dport 69 -j ACCEPT
2025-08-19 02:59:32.138542 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 6385 -j ACCEPT
2025-08-19 02:59:32.138548 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 80 -j ACCEPT
2025-08-19 02:59:32.138554 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8000 -j ACCEPT
2025-08-19 02:59:32.138560 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8003 -j ACCEPT
2025-08-19 02:59:32.138573 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8004 -j ACCEPT
2025-08-19 02:59:32.138581 | controller | -A openstack-INPUT -m limit --limit 2/min -j LOG --log-prefix "iptables dropped: "
2025-08-19 02:59:32.138588 | controller | -A openstack-INPUT -j REJECT --reject-with icmp-host-prohibited
2025-08-19 02:59:32.138594 | controller | COMMIT
2025-08-19 02:59:32.138601 | controller | # Completed on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:32.105933 | compute1 | # Generated by iptables-save v1.8.7 on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:32.106129 | compute1 | *filter
2025-08-19 02:59:32.106141 | compute1 | :INPUT ACCEPT [0:0]
2025-08-19 02:59:32.106149 | compute1 | :FORWARD ACCEPT [0:0]
2025-08-19 02:59:32.106157 | compute1 | :OUTPUT ACCEPT [13174:1972359]
2025-08-19 02:59:32.106180 | compute1 | :openstack-INPUT - [0:0]
2025-08-19 02:59:32.106189 | compute1 | -A INPUT -s 158.69.74.227/32 -j ACCEPT
2025-08-19 02:59:32.106197 | compute1 | -A INPUT -s 158.69.73.106/32 -j ACCEPT
2025-08-19 02:59:32.106215 | compute1 | -A INPUT -j openstack-INPUT
2025-08-19 02:59:32.106221 | compute1 | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:59:32.106227 | compute1 | -A openstack-INPUT -p icmp -m icmp --icmp-type any -j ACCEPT
2025-08-19 02:59:32.106234 | compute1 | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:59:32.106240 | compute1 | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:59:32.106246 | compute1 | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:59:32.106251 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p udp -m udp --dport 69 -j ACCEPT
2025-08-19 02:59:32.106257 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 6385 -j ACCEPT
2025-08-19 02:59:32.106270 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 80 -j ACCEPT
2025-08-19 02:59:32.106281 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8000 -j ACCEPT
2025-08-19 02:59:32.106344 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8003 -j ACCEPT
2025-08-19 02:59:32.106371 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8004 -j ACCEPT
2025-08-19 02:59:32.106379 | compute1 | -A openstack-INPUT -m limit --limit 2/min -j LOG --log-prefix "iptables dropped: "
2025-08-19 02:59:32.106385 | compute1 | -A openstack-INPUT -j REJECT --reject-with icmp-host-prohibited
2025-08-19 02:59:32.106391 | compute1 | COMMIT
2025-08-19 02:59:32.106397 | compute1 | # Completed on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:32.325806 | compute1 | ok: Runtime: 0:00:00.008261
2025-08-19 02:59:32.342172 | controller | ok: Runtime: 0:00:00.007127
2025-08-19 02:59:32.352834 | 
2025-08-19 02:59:32.352955 | TASK [persistent-firewall : List current ipv6 rules]
2025-08-19 02:59:32.806332 | controller | # Generated by ip6tables-save v1.8.7 on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:32.806448 | controller | *filter
2025-08-19 02:59:32.806460 | controller | :INPUT ACCEPT [0:0]
2025-08-19 02:59:32.806467 | controller | :FORWARD ACCEPT [0:0]
2025-08-19 02:59:32.806476 | controller | :OUTPUT ACCEPT [142:11022]
2025-08-19 02:59:32.806487 | controller | :openstack-INPUT - [0:0]
2025-08-19 02:59:32.806496 | controller | -A INPUT -j openstack-INPUT
2025-08-19 02:59:32.806502 | controller | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:59:32.806523 | controller | -A openstack-INPUT -p ipv6-icmp -j ACCEPT
2025-08-19 02:59:32.806530 | controller | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:59:32.806540 | controller | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:59:32.806554 | controller | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:59:32.806568 | controller | -A openstack-INPUT -j REJECT --reject-with icmp6-adm-prohibited
2025-08-19 02:59:32.806582 | controller | COMMIT
2025-08-19 02:59:32.806596 | controller | # Completed on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:32.867067 | compute1 | # Generated by ip6tables-save v1.8.7 on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:32.867146 | compute1 | *filter
2025-08-19 02:59:32.867159 | compute1 | :INPUT ACCEPT [0:0]
2025-08-19 02:59:32.867166 | compute1 | :FORWARD ACCEPT [0:0]
2025-08-19 02:59:32.867176 | compute1 | :OUTPUT ACCEPT [142:11129]
2025-08-19 02:59:32.867187 | compute1 | :openstack-INPUT - [0:0]
2025-08-19 02:59:32.867196 | compute1 | -A INPUT -j openstack-INPUT
2025-08-19 02:59:32.867203 | compute1 | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 02:59:32.867213 | compute1 | -A openstack-INPUT -p ipv6-icmp -j ACCEPT
2025-08-19 02:59:32.867220 | compute1 | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 02:59:32.867227 | compute1 | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 02:59:32.867234 | compute1 | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 02:59:32.867240 | compute1 | -A openstack-INPUT -j REJECT --reject-with icmp6-adm-prohibited
2025-08-19 02:59:32.867248 | compute1 | COMMIT
2025-08-19 02:59:32.867255 | compute1 | # Completed on Tue Aug 19 02:59:32 2025
2025-08-19 02:59:33.083682 | controller | ok: Runtime: 0:00:00.006182
2025-08-19 02:59:33.099122 | compute1 | ok: Runtime: 0:00:00.006437
2025-08-19 02:59:33.108931 | 
2025-08-19 02:59:33.109099 | LOOP [persistent-firewall : Configure persistent iptables rules]
2025-08-19 02:59:33.160891 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml"
2025-08-19 02:59:33.161465 | 
2025-08-19 02:59:33.170701 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml"
2025-08-19 02:59:33.190997 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml
2025-08-19 02:59:33.191132 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml
2025-08-19 02:59:33.201597 | 
2025-08-19 02:59:33.201719 | TASK [persistent-firewall : Install iptables-persistent]
2025-08-19 02:59:34.232942 | controller | ok
2025-08-19 02:59:34.295022 | compute1 | ok
2025-08-19 02:59:34.304775 | 
2025-08-19 02:59:34.304961 | TASK [persistent-firewall : Persist ipv4 rules]
2025-08-19 02:59:35.843393 | controller | changed
2025-08-19 02:59:35.914333 | compute1 | changed
2025-08-19 02:59:35.925010 | 
2025-08-19 02:59:35.925205 | TASK [persistent-firewall : Persist ipv6 rules]
2025-08-19 02:59:37.388494 | controller | changed
2025-08-19 02:59:37.449882 | compute1 | changed
2025-08-19 02:59:37.460111 | 
2025-08-19 02:59:37.460309 | TASK [persistent-firewall : Ensure netfilter-persistent is started]
2025-08-19 02:59:38.044029 | controller | ok
2025-08-19 02:59:38.091491 | compute1 | ok
2025-08-19 02:59:38.134691 | 
2025-08-19 02:59:38.134840 | PLAY [Copy the build ssh key to authorized_keys on all hosts for root]
2025-08-19 02:59:38.146827 | 
2025-08-19 02:59:38.146952 | TASK [copy-build-sshkey : Authorize build key]
2025-08-19 02:59:38.869896 | controller | changed
2025-08-19 02:59:38.910423 | compute1 | changed
2025-08-19 02:59:38.923731 | 
2025-08-19 02:59:38.923898 | TASK [copy-build-sshkey : Get the user home folder for root]
2025-08-19 02:59:39.664940 | controller | ok
2025-08-19 02:59:39.676738 | compute1 | ok
2025-08-19 02:59:39.690991 | 
2025-08-19 02:59:39.691225 | TASK [copy-build-sshkey : Install the build private key]
2025-08-19 02:59:41.169332 | controller | changed
2025-08-19 02:59:41.190443 | compute1 | changed
2025-08-19 02:59:41.199004 | 
2025-08-19 02:59:41.199124 | TASK [copy-build-sshkey : Install the build public key]
2025-08-19 02:59:42.685443 | controller | changed
2025-08-19 02:59:42.725950 | compute1 | changed
2025-08-19 02:59:42.752836 | 
2025-08-19 02:59:42.752978 | PLAY RECAP
2025-08-19 02:59:42.753077 | compute1 | ok: 50 changed: 18 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-08-19 02:59:42.753154 | controller | ok: 43 changed: 16 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-08-19 02:59:42.753212 | 
2025-08-19 02:59:43.005167 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/multinode/pre.yaml@master]
2025-08-19 02:59:43.022218 | PRE-RUN START: [untrusted : opendev.org/openstack/grenade/playbooks/pre.yaml@master]
2025-08-19 02:59:44.390031 | 
2025-08-19 02:59:44.390227 | PLAY [all]
2025-08-19 02:59:44.429586 | 
2025-08-19 02:59:44.429793 | TASK [Gather minimum local MTU]
2025-08-19 02:59:44.540295 | compute1 | ok
2025-08-19 02:59:44.566224 | controller | ok
2025-08-19 02:59:44.574244 | 
2025-08-19 02:59:44.574413 | TASK [Calculate external_bridge_mtu]
2025-08-19 02:59:44.653600 | compute1 | ok
2025-08-19 02:59:44.682599 | controller | ok
2025-08-19 02:59:44.691083 | 
2025-08-19 02:59:44.691232 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2025-08-19 02:59:44.729324 | compute1 | skipping: Conditional result was False
2025-08-19 02:59:44.751018 | controller | skipping: Conditional result was False
2025-08-19 02:59:44.762104 | 
2025-08-19 02:59:44.762266 | TASK [configure-swap : Get ephemeral0 device node]
2025-08-19 02:59:46.034157 | controller | ok: Runtime: 0:00:00.004483
2025-08-19 02:59:46.054172 | compute1 | ok: Runtime: 0:00:00.006917
2025-08-19 02:59:46.067158 | 
2025-08-19 02:59:46.067387 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2025-08-19 02:59:46.115260 | compute1 | skipping: Conditional result was False
2025-08-19 02:59:46.137927 | controller | skipping: Conditional result was False
2025-08-19 02:59:46.150043 | 
2025-08-19 02:59:46.150257 | TASK [configure-swap : Setup swap on ephemeral storage]
2025-08-19 02:59:46.207034 | compute1 | skipping: Conditional result was False
2025-08-19 02:59:46.218968 | controller | skipping: Conditional result was False
2025-08-19 02:59:46.231733 | 
2025-08-19 02:59:46.231892 | TASK [configure-swap : Setup swap file on root device]
2025-08-19 02:59:46.346609 | compute1 | ok
2025-08-19 02:59:46.406591 | controller | ok
2025-08-19 02:59:46.432923 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_3/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2025-08-19 02:59:46.433036 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_3/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2025-08-19 02:59:46.443389 | 
2025-08-19 02:59:46.443550 | TASK [configure-swap : Calculate required swap]
2025-08-19 02:59:46.533959 | compute1 | ok
2025-08-19 02:59:46.572826 | controller | ok
2025-08-19 02:59:46.582444 | 
2025-08-19 02:59:46.582600 | TASK [configure-swap : Get root filesystem]
2025-08-19 02:59:47.115220 | compute1 | ext4
2025-08-19 02:59:47.118294 | controller | ext4
2025-08-19 02:59:47.329197 | controller | ok: Runtime: 0:00:00.005584
2025-08-19 02:59:47.344868 | compute1 | ok: Runtime: 0:00:00.005836
2025-08-19 02:59:47.354824 | 
2025-08-19 02:59:47.354960 | TASK [configure-swap : Save root filesystem]
2025-08-19 02:59:47.404764 | compute1 | ok
2025-08-19 02:59:47.426698 | controller | ok
2025-08-19 02:59:47.436725 | 
2025-08-19 02:59:47.436845 | TASK [configure-swap : Debug the root_filesystem variable]
2025-08-19 02:59:47.487491 | compute1 | ok:
2025-08-19 02:59:47.488026 | compute1 | {
2025-08-19 02:59:47.488093 | compute1 |   "root_filesystem": "ext4"
2025-08-19 02:59:47.488179 | compute1 | }
2025-08-19 02:59:47.506158 | controller | ok:
2025-08-19 02:59:47.506256 | controller | {
2025-08-19 02:59:47.506316 | controller |   "root_filesystem": "ext4"
2025-08-19 02:59:47.506369 | controller | }
2025-08-19 02:59:47.516289 | 
2025-08-19 02:59:47.516427 | TASK [configure-swap : Create swap backing file]
2025-08-19 02:59:57.776565 | compute1 | 8192+0 records in
2025-08-19 02:59:57.776793 | compute1 | 8192+0 records out
2025-08-19 02:59:57.776826 | compute1 | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.75135 s, 881 MB/s
2025-08-19 03:00:05.099228 | controller | 8192+0 records in
2025-08-19 03:00:05.099621 | controller | 8192+0 records out
2025-08-19 03:00:05.099645 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 17.0696 s, 503 MB/s
2025-08-19 03:00:05.276809 | compute1 | ok: Runtime: 0:00:09.757583
2025-08-19 03:00:05.294448 | controller | ok: Runtime: 0:00:17.074792
2025-08-19 03:00:05.306143 | 
2025-08-19 03:00:05.306317 | TASK [configure-swap : Ensure swapfile perms]
2025-08-19 03:00:06.007558 | controller | changed
2025-08-19 03:00:06.027655 | compute1 | changed
2025-08-19 03:00:06.038309 | 
2025-08-19 03:00:06.038484 | TASK [configure-swap : Make swapfile]
2025-08-19 03:00:06.491488 | compute1 | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2025-08-19 03:00:06.491568 | compute1 | no label, UUID=3f65dc4c-ca2d-4b89-9677-9d433d2b975c
2025-08-19 03:00:07.336667 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2025-08-19 03:00:07.336752 | controller | no label, UUID=bcfe7f99-408b-41ae-bfee-ab6763f81178
2025-08-19 03:00:07.776246 | compute1 | ok: Runtime: 0:00:00.011294
2025-08-19 03:00:07.792168 | controller | ok: Runtime: 0:00:00.814087
2025-08-19 03:00:07.801660 | 
2025-08-19 03:00:07.801819 | TASK [configure-swap : Write swap to fstab]
2025-08-19 03:00:08.445782 | controller | changed
2025-08-19 03:00:08.456454 | compute1 | changed
2025-08-19 03:00:08.468141 | 
2025-08-19 03:00:08.468415 | TASK [configure-swap : Add all swap]
2025-08-19 03:00:09.210187 | compute1 | ok: Runtime: 0:00:00.011024
2025-08-19 03:00:09.225544 | controller | ok: Runtime: 0:00:00.011890
2025-08-19 03:00:09.236625 | 
2025-08-19 03:00:09.236810 | TASK [configure-swap : Debug the swap_required variable]
2025-08-19 03:00:09.284451 | compute1 | ok:
2025-08-19 03:00:09.284982 | compute1 | {
2025-08-19 03:00:09.285055 | compute1 |   "swap_required": "8192"
2025-08-19 03:00:09.285115 | compute1 | }
2025-08-19 03:00:09.294283 | controller | ok:
2025-08-19 03:00:09.294405 | controller | {
2025-08-19 03:00:09.294470 | controller |   "swap_required": "8192"
2025-08-19 03:00:09.294565 | controller | }
2025-08-19 03:00:09.303550 | 
2025-08-19 03:00:09.303708 | TASK [configure-swap : Set swappiness]
2025-08-19 03:00:09.941406 | compute1 | changed
2025-08-19 03:00:09.961401 | controller | changed
2025-08-19 03:00:09.972184 | 
2025-08-19 03:00:09.972405 | TASK [configure-swap : Debug the ephemeral_device variable]
2025-08-19 03:00:10.021660 | compute1 | ok:
2025-08-19 03:00:10.022188 | compute1 | {
2025-08-19 03:00:10.022259 | compute1 |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined"
2025-08-19 03:00:10.022319 | compute1 | }
2025-08-19 03:00:10.041453 | controller | ok:
2025-08-19 03:00:10.041630 | controller | {
2025-08-19 03:00:10.041693 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined"
2025-08-19 03:00:10.041749 | controller | }
2025-08-19 03:00:10.066264 | 
2025-08-19 03:00:10.066459 | TASK [setup-stack-user : Create stack group]
2025-08-19 03:00:10.723449 | controller | changed
2025-08-19 03:00:10.747647 | compute1 | changed
2025-08-19 03:00:10.757058 | 
2025-08-19 03:00:10.757232 | TASK [setup-stack-user : Create the stack user home folder]
2025-08-19 03:00:11.171665 | compute1 | changed
2025-08-19 03:00:11.181078 | controller | changed
2025-08-19 03:00:11.190775 | 
2025-08-19 03:00:11.190892 | TASK [setup-stack-user : Create stack user]
2025-08-19 03:00:11.921853 | compute1 | changed
2025-08-19 03:00:11.930712 | controller | changed
2025-08-19 03:00:11.940037 | 
2025-08-19 03:00:11.940204 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2025-08-19 03:00:12.327058 | compute1 | changed
2025-08-19 03:00:12.358615 | controller | changed
2025-08-19 03:00:12.368790 | 
2025-08-19 03:00:12.369051 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2025-08-19 03:00:14.467950 | controller | changed
2025-08-19 03:00:14.510383 | compute1 | changed
2025-08-19 03:00:14.519217 | 
2025-08-19 03:00:14.519345 | TASK [setup-stack-user : Create .cache folder within BASE]
2025-08-19 03:00:14.901458 | compute1 | changed
2025-08-19 03:00:14.909592 | controller | changed
2025-08-19 03:00:14.937297 | 
2025-08-19 03:00:14.937468 | TASK [setup-tempest-user : Create tempest group]
2025-08-19 03:00:15.322820 | compute1 | changed
2025-08-19 03:00:15.342260 | controller | changed
2025-08-19 03:00:15.351126 | 
2025-08-19 03:00:15.351304 | TASK [setup-tempest-user : Create tempest user]
2025-08-19 03:00:15.807255 | controller | changed
2025-08-19 03:00:15.815774 | compute1 | changed
2025-08-19 03:00:15.824924 | 
2025-08-19 03:00:15.825080 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2025-08-19 03:00:17.632849 | controller | changed
2025-08-19 03:00:17.673198 | compute1 | changed
2025-08-19 03:00:17.697875 | 
2025-08-19 03:00:17.698045 | TASK [configure-grenade-branches : Set the grenade start and destination branches]
2025-08-19 03:00:17.753937 | compute1 | ok
2025-08-19 03:00:17.763110 | controller | ok
2025-08-19 03:00:17.788074 | 
2025-08-19 03:00:17.788255 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2025-08-19 03:00:18.401351 | controller | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:18.449866 | compute1 | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:18.468020 | 
2025-08-19 03:00:18.468183 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2025-08-19 03:00:18.970830 | compute1 | ok: Item: Runtime: 0:00:00.064346
2025-08-19 03:00:19.017018 | controller | ok: Item: Runtime: 0:00:00.060898
2025-08-19 03:00:19.553081 | compute1 | ok: Item: Runtime: 0:00:00.205904
2025-08-19 03:00:19.586512 | controller | ok: Item: Runtime: 0:00:00.209840
2025-08-19 03:00:20.192026 | compute1 | ok: Item: Runtime: 0:00:00.277547
2025-08-19 03:00:20.213854 | controller | ok: Item: Runtime: 0:00:00.257491
2025-08-19 03:00:21.148735 | controller | ok: Item: Runtime: 0:00:00.606108
2025-08-19 03:00:21.287808 | compute1 | ok: Item: Runtime: 0:00:00.641538
2025-08-19 03:00:23.223381 | controller | ok: Item: Runtime: 0:00:01.562012
2025-08-19 03:00:23.347022 | compute1 | ok: Item: Runtime: 0:00:01.618992
2025-08-19 03:00:24.759611 | controller | ok: Item: Runtime: 0:00:01.164738
2025-08-19 03:00:25.099148 | compute1 | ok: Item: Runtime: 0:00:01.261457
2025-08-19 03:00:25.727648 | controller | ok: Item: Runtime: 0:00:00.514741
2025-08-19 03:00:26.089308 | compute1 | ok: Item: Runtime: 0:00:00.502679
2025-08-19 03:00:26.255628 | controller | ok: Item: Runtime: 0:00:00.092968
2025-08-19 03:00:26.641498 | compute1 | ok: Item: Runtime: 0:00:00.095034
2025-08-19 03:00:28.540852 | controller | ok: Item: Runtime: 0:00:01.893706
2025-08-19 03:00:29.135374 | compute1 | ok: Item: Runtime: 0:00:02.105799
2025-08-19 03:00:29.384500 | controller | ok: Item: Runtime: 0:00:00.422388
2025-08-19 03:00:30.208841 | compute1 | ok: Item: Runtime: 0:00:00.544064
2025-08-19 03:00:30.227170 | controller | ok: Item: Runtime: 0:00:00.427296
2025-08-19 03:00:30.847837 | controller | ok: Item: Runtime: 0:00:00.234478
2025-08-19 03:00:31.172229 | compute1 | ok: Item: Runtime: 0:00:00.512074
2025-08-19 03:00:31.172417 | compute1 | changed: All items complete
2025-08-19 03:00:31.172481 | 
2025-08-19 03:00:31.867216 | compute1 | ok: Item: Runtime: 0:00:00.239960
2025-08-19 03:00:31.901558 | 
2025-08-19 03:00:31.901714 | TASK [setup-devstack-source-dirs : Find top level github projects]
2025-08-19 03:00:32.297995 | compute1 | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:32.402499 | controller | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:32.413767 | 
2025-08-19 03:00:32.413954 | TASK [setup-devstack-source-dirs : Find actual github repos]
2025-08-19 03:00:32.448714 | compute1 | skipping: Conditional result was False
2025-08-19 03:00:32.471835 | controller | skipping: Conditional result was False
2025-08-19 03:00:32.482588 | 
2025-08-19 03:00:32.482766 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2025-08-19 03:00:32.549824 | 
2025-08-19 03:00:32.550129 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2025-08-19 03:00:33.056610 | compute1 | ok: Item: Runtime: 0:00:00.013762
2025-08-19 03:00:33.057135 | 
2025-08-19 03:00:33.073506 | controller | ok: Item: Runtime: 0:00:00.014830
2025-08-19 03:00:33.421173 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:33.421391 | controller | ok: Item: Runtime: 0:00:00.031944
2025-08-19 03:00:33.464637 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:33.464808 | compute1 | ok: Item: Runtime: 0:00:00.049059
2025-08-19 03:00:33.815818 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:33.816039 | controller | ok: Item: Runtime: 0:00:00.041971
2025-08-19 03:00:33.839330 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:33.839632 | compute1 | ok: Item: Runtime: 0:00:00.035966
2025-08-19 03:00:34.344107 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:34.344264 | controller | ok: Item: Runtime: 0:00:00.201894
2025-08-19 03:00:34.471480 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:34.471681 | compute1 | ok: Item: Runtime: 0:00:00.242674
2025-08-19 03:00:35.027778 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:35.028057 | controller | ok: Item: Runtime: 0:00:00.360933
2025-08-19 03:00:35.063926 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:35.064081 | compute1 | ok: Item: Runtime: 0:00:00.338961
2025-08-19 03:00:35.682358 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:35.682641 | controller | ok: Item: Runtime: 0:00:00.287768
2025-08-19 03:00:35.755274 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:35.755585 | compute1 | ok: Item: Runtime: 0:00:00.304810
2025-08-19 03:00:36.144085 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:36.144257 | controller | ok: Item: Runtime: 0:00:00.111513
2025-08-19 03:00:36.297673 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:36.297814 | compute1 | ok: Item: Runtime: 0:00:00.130469
2025-08-19 03:00:36.592131 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:36.592301 | controller | ok: Item: Runtime: 0:00:00.014605
2025-08-19 03:00:36.745107 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:36.745391 | compute1 | ok: Item: Runtime: 0:00:00.014098
2025-08-19 03:00:37.414777 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:37.414994 | controller | ok: Item: Runtime: 0:00:00.437997
2025-08-19 03:00:37.561816 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:37.562051 | compute1 | ok: Item: Runtime: 0:00:00.441366
2025-08-19 03:00:37.895824 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:37.896011 | controller | ok: Item: Runtime: 0:00:00.120598
2025-08-19 03:00:38.007908 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:38.008069 | compute1 | ok: Item: Runtime: 0:00:00.088785
2025-08-19 03:00:38.271738 | controller | ok: Item: Runtime: 0:00:00.019719
2025-08-19 03:00:38.434598 | compute1 | ok: Item: Runtime: 0:00:00.010074
2025-08-19 03:00:38.614891 | controller | Switched to branch 'stable/2025.1'
2025-08-19 03:00:38.615091 | controller | ok: Item: Runtime: 0:00:00.020929
2025-08-19 03:00:38.856740 | compute1 | Switched to branch 'stable/2025.1'
2025-08-19 03:00:38.857012 | compute1 | ok: Item: Runtime: 0:00:00.017345
2025-08-19 03:00:38.909495 | 
2025-08-19 03:00:38.909710 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2025-08-19 03:00:41.949837 | controller | changed
2025-08-19 03:00:42.309963 | compute1 | changed
2025-08-19 03:00:42.339295 | 
2025-08-19 03:00:42.339477 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2025-08-19 03:00:42.843828 | compute1 | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:42.859696 | controller | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:42.875386 | 
2025-08-19 03:00:42.875563 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2025-08-19 03:00:43.380903 | compute1 | ok: Item: Runtime: 0:00:00.057358
2025-08-19 03:00:43.381389 | compute1 | changed: All items complete
2025-08-19 03:00:43.381457 | 
2025-08-19 03:00:43.407553 | controller | ok: Item: Runtime: 0:00:00.053283
2025-08-19 03:00:43.839499 | compute1 | ok: Item: Runtime: 0:00:00.137600
2025-08-19 03:00:43.899051 | controller | ok: Item: Runtime: 0:00:00.154808
2025-08-19 03:00:44.389346 | compute1 | ok: Item: Runtime: 0:00:00.168200
2025-08-19 03:00:44.416554 | controller | ok: Item: Runtime: 0:00:00.175225
2025-08-19 03:00:45.149356 | compute1 | ok: Item: Runtime: 0:00:00.346777
2025-08-19 03:00:45.166679 | controller | ok: Item: Runtime: 0:00:00.364146
2025-08-19 03:00:46.397605 | compute1 | ok: Item: Runtime: 0:00:00.936423
2025-08-19 03:00:46.412825 | controller | ok: Item: Runtime: 0:00:00.882419
2025-08-19 03:00:47.585231 | compute1 | ok: Item: Runtime: 0:00:00.735042
2025-08-19 03:00:47.816571 | controller | ok: Item: Runtime: 0:00:00.959224
2025-08-19 03:00:48.397248 | compute1 | ok: Item: Runtime: 0:00:00.341417
2025-08-19 03:00:48.643234 | controller | ok: Item: Runtime: 0:00:00.370435
2025-08-19 03:00:48.893628 | compute1 | ok: Item: Runtime: 0:00:00.084011
2025-08-19 03:00:49.122785 | controller | ok: Item: Runtime: 0:00:00.071939
2025-08-19 03:00:50.407959 | compute1 | ok: Item: Runtime: 0:00:01.095165
2025-08-19 03:00:50.729990 | controller | ok: Item: Runtime: 0:00:01.248698
2025-08-19 03:00:51.112060 | compute1 | ok: Item: Runtime: 0:00:00.253696
2025-08-19 03:00:51.434890 | controller | ok: Item: Runtime: 0:00:00.264843
2025-08-19 03:00:51.783807 | compute1 | ok: Item: Runtime: 0:00:00.282346
2025-08-19 03:00:52.112546 | controller | ok: Item: Runtime: 0:00:00.262861
2025-08-19 03:00:52.327977 | compute1 | ok: Item: Runtime: 0:00:00.169794
2025-08-19 03:00:52.717373 | controller | ok: Item: Runtime: 0:00:00.170659
2025-08-19 03:00:52.747269 | 
2025-08-19 03:00:52.747430 | TASK [setup-devstack-source-dirs : Find top level github projects]
2025-08-19 03:00:53.180095 | controller | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:53.192417 | compute1 | ok: Not all paths examined, check warnings for details
2025-08-19 03:00:53.206757 | 
2025-08-19 03:00:53.206960 | TASK [setup-devstack-source-dirs : Find actual github repos]
2025-08-19 03:00:53.251691 | compute1 | skipping: Conditional result was False
2025-08-19 03:00:53.274477 | controller | skipping: Conditional result was False
2025-08-19 03:00:53.285947 | 
2025-08-19 03:00:53.286145 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2025-08-19 03:00:53.359995 | 
2025-08-19 03:00:53.360293 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2025-08-19 03:00:53.846640 | compute1 | Already on 'master'
2025-08-19 03:00:53.847226 | compute1 | ok: Item: Runtime: 0:00:00.010295
2025-08-19 03:00:53.847365 | 
2025-08-19 03:00:53.887309 | controller | Already on 'master'
2025-08-19 03:00:53.887485 | controller | ok: Item: Runtime: 0:00:00.014519
2025-08-19 03:00:54.203192 | compute1 | Already on 'master'
2025-08-19 03:00:54.203426 | compute1 | ok: Item: Runtime: 0:00:00.026730
2025-08-19 03:00:54.253260 | controller | Already on 'master'
2025-08-19 03:00:54.253439 | controller | ok: Item: Runtime: 0:00:00.035909
2025-08-19 03:00:54.559347 | compute1 | Already on 'master'
2025-08-19 03:00:54.559646 | compute1 | ok: Item: Runtime: 0:00:00.029864
2025-08-19 03:00:54.608223 | controller | Already on 'master'
2025-08-19 03:00:54.608378 | controller | ok: Item: Runtime: 0:00:00.028123
2025-08-19 03:00:55.113798 | compute1 | Already on 'master'
2025-08-19 03:00:55.113978 | compute1 | ok: Item: Runtime: 0:00:00.125774
2025-08-19 03:00:55.130121 | controller | Already on 'master'
2025-08-19 03:00:55.130302 | controller | ok: Item: Runtime: 0:00:00.135282
2025-08-19 03:00:55.643159 | compute1 | Already on 'master'
2025-08-19 03:00:55.643420 | compute1 | ok: Item: Runtime: 0:00:00.231077
2025-08-19 03:00:55.671796 | controller | Already on 'master'
2025-08-19 03:00:55.671965 | controller | ok: Item: Runtime: 0:00:00.234473
2025-08-19 03:00:56.217648 | controller | Already on 'master'
2025-08-19 03:00:56.217817 | controller | ok: Item: Runtime: 0:00:00.234263
2025-08-19 03:00:56.237096 | compute1 | Already on 'master'
2025-08-19 03:00:56.237244 | compute1 | ok: Item: Runtime: 0:00:00.256133
2025-08-19 03:00:56.639230 | controller | Already on 'master'
2025-08-19 03:00:56.639538 | controller | ok: Item: Runtime: 0:00:00.079493
2025-08-19 03:00:56.657255 | compute1 | Already on 'master'
2025-08-19 03:00:56.657439 | compute1 | ok: Item: Runtime: 0:00:00.082065
2025-08-19 03:00:56.989303 | compute1 | Already on 'master'
2025-08-19 03:00:56.989575 | compute1 | ok: Item: Runtime: 0:00:00.013051
2025-08-19 03:00:57.007547 | controller | Already on 'master'
2025-08-19 03:00:57.007726 | controller | ok: Item: Runtime: 0:00:00.024153
2025-08-19 03:00:57.569767 | controller | Already on 'master'
2025-08-19 03:00:57.569952 | controller | ok: Item: Runtime: 0:00:00.263583
2025-08-19 03:00:57.592432 | compute1 | Already on 'master'
2025-08-19 03:00:57.596167 | compute1 | ok: Item: Runtime: 0:00:00.256304
2025-08-19 03:00:57.950569 | controller | Already on 'master'
2025-08-19 03:00:57.950827 | controller | ok: Item: Runtime: 0:00:00.061837
2025-08-19 03:00:58.005449 | compute1 | Already on 'master'
2025-08-19 03:00:58.005629 | compute1 | ok: Item: Runtime: 0:00:00.063637
2025-08-19 03:00:58.393322 | controller | Already on 'master'
2025-08-19 03:00:58.393495 | controller | ok: Item: Runtime: 0:00:00.065097
2025-08-19 03:00:58.474402 | compute1 | Already on 'master'
2025-08-19 03:00:58.474636 | compute1 | ok: Item: Runtime: 0:00:00.067386
2025-08-19 03:00:58.747024 | controller | Already on 'master'
2025-08-19 03:00:58.747194 | controller | ok: Item: Runtime: 0:00:00.018774
2025-08-19 03:00:58.852915 | compute1 | Already on 'master'
2025-08-19 03:00:58.853087 | compute1 | ok: Item: Runtime: 0:00:00.012971
2025-08-19 03:00:58.901054 | 
2025-08-19 03:00:58.901228 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2025-08-19 03:01:02.183786 | compute1 | changed
2025-08-19 03:01:02.328017 | controller | changed
2025-08-19 03:01:02.353969 | 
2025-08-19 03:01:02.354129 | TASK [setup-devstack-log-dir : Create logs directory]
2025-08-19 03:01:02.836880 | compute1 | changed
2025-08-19 03:01:02.877942 | controller | changed
2025-08-19 03:01:02.909803 | 
2025-08-19 03:01:02.910023 | TASK [setup-devstack-log-dir : Create logs directory]
2025-08-19 03:01:03.285403 | compute1 | changed
2025-08-19 03:01:03.307887 | controller | changed
2025-08-19 03:01:03.331839 | 
2025-08-19 03:01:03.331960 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2025-08-19 03:01:03.807795 | compute1 | /usr/bin/journalctl
2025-08-19 03:01:03.823534 | controller | /usr/bin/journalctl
2025-08-19 03:01:04.116868 | compute1 | ok: Runtime: 0:00:00.003319
2025-08-19 03:01:04.134195 | controller | ok: Runtime: 0:00:00.003741
2025-08-19 03:01:04.143698 | 
2025-08-19 03:01:04.143874 | TASK [start-fresh-logging : Get current date]
2025-08-19 03:01:04.579755 | compute1 | 2025-08-19 03:01:04
2025-08-19 03:01:04.611193 | controller | 2025-08-19 03:01:04
2025-08-19 03:01:04.881777 | compute1 | ok: Runtime: 0:00:00.004950
2025-08-19 03:01:04.900347 | controller | ok: Runtime: 0:00:00.004926
2025-08-19 03:01:04.909210 | 
2025-08-19 03:01:04.909321 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2025-08-19 03:01:06.381965 | controller | changed
2025-08-19 03:01:06.411356 | compute1 | changed
2025-08-19 03:01:06.422988 | 
2025-08-19 03:01:06.423183 | TASK [start-fresh-logging : Stop rsyslog]
2025-08-19 03:01:06.451303 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:06.473803 | controller | skipping: Conditional result was False
2025-08-19 03:01:06.484979 | 
2025-08-19 03:01:06.485174 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2025-08-19 03:01:06.719289 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:06.720413 | controller | skipping: Conditional result was False
2025-08-19 03:01:06.734400 | 
2025-08-19 03:01:06.734580 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2025-08-19 03:01:06.835452 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:06.857982 | controller | skipping: Conditional result was False
2025-08-19 03:01:06.870028 | 
2025-08-19 03:01:06.870173 | TASK [start-fresh-logging : Recreate syslog file]
2025-08-19 03:01:06.908788 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:06.920496 | controller | skipping: Conditional result was False
2025-08-19 03:01:06.931237 | 
2025-08-19 03:01:06.931373 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2025-08-19 03:01:07.099848 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:07.112130 | controller | skipping: Conditional result was False
2025-08-19 03:01:07.123072 | 
2025-08-19 03:01:07.123230 | TASK [start-fresh-logging : Recreate syslog file permissions]
2025-08-19 03:01:07.346041 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:07.347008 | controller | skipping: Conditional result was False
2025-08-19 03:01:07.357272 | 
2025-08-19 03:01:07.357402 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2025-08-19 03:01:07.395550 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:07.407184 | controller | skipping: Conditional result was False
2025-08-19 03:01:07.417378 | 
2025-08-19 03:01:07.417509 | TASK [start-fresh-logging : Recreate kern.log file]
2025-08-19 03:01:07.447860 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:07.470326 | controller | skipping: Conditional result was False
2025-08-19 03:01:07.483253 | 
2025-08-19 03:01:07.483412 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2025-08-19 03:01:07.714419 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:07.715585 | controller | skipping: Conditional result was False
2025-08-19 03:01:07.728355 | 
2025-08-19 03:01:07.728605 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2025-08-19 03:01:07.958957 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:07.960155 | controller | skipping: Conditional result was False
2025-08-19 03:01:07.973403 | 
2025-08-19 03:01:07.973617 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2025-08-19 03:01:08.011201 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:08.023483 | controller | skipping: Conditional result was False
2025-08-19 03:01:08.034045 | 
2025-08-19 03:01:08.034206 | TASK [start-fresh-logging : Start rsyslog]
2025-08-19 03:01:08.064930 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:08.087219 | controller | skipping: Conditional result was False
2025-08-19 03:01:08.116652 | 
2025-08-19 03:01:08.116839 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2025-08-19 03:01:08.955418 | controller | ok
2025-08-19 03:01:09.043464 | compute1 | ok
2025-08-19 03:01:09.074233 | 
2025-08-19 03:01:09.074422 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2025-08-19 03:01:09.649959 | controller | ok
2025-08-19 03:01:09.663499 | compute1 | ok
2025-08-19 03:01:09.694451 | 
2025-08-19 03:01:09.694666 | TASK [setup-devstack-cache : Copy cached devstack files]
2025-08-19 03:01:10.428956 | compute1 | ok: Runtime: 0:00:00.034783
2025-08-19 03:01:10.445919 | controller | ok: Runtime: 0:00:00.030460
2025-08-19 03:01:10.456335 | 
2025-08-19 03:01:10.456496 | TASK [setup-devstack-cache : Set ownership of cached files]
2025-08-19 03:01:10.872101 | compute1 | changed
2025-08-19 03:01:10.892875 | controller | changed
2025-08-19 03:01:10.913773 | 
2025-08-19 03:01:10.913888 | PLAY RECAP
2025-08-19 03:01:10.914009 | compute1 | ok: 45 changed: 28 unreachable: 0 failed: 0 skipped: 19 rescued: 0 ignored: 0
2025-08-19 03:01:10.914085 | controller | ok: 45 changed: 28 unreachable: 0 failed: 0 skipped: 19 rescued: 0 ignored: 0
2025-08-19 03:01:10.914138 | 
2025-08-19 03:01:11.171177 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/grenade/playbooks/pre.yaml@master]
2025-08-19 03:01:11.192375 | PRE-RUN START: [untrusted : opendev.org/openstack/grenade/playbooks/multinode-pre.yaml@master]
2025-08-19 03:01:12.521700 | 
2025-08-19 03:01:12.521919 | PLAY [all]
2025-08-19 03:01:12.546630 | 
2025-08-19 03:01:12.546873 | TASK [multi-node-bridge : Include common tasks]
2025-08-19 03:01:12.599339 | compute1 | ok
2025-08-19 03:01:12.606885 | controller | ok
2025-08-19 03:01:12.641909 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/common.yaml
2025-08-19 03:01:12.642110 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/common.yaml
2025-08-19 03:01:12.653778 | 
2025-08-19 03:01:12.653978 | LOOP [multi-node-bridge : Include OS-specific variables]
2025-08-19 03:01:12.716214 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/vars/Debian.yaml"
2025-08-19 03:01:12.735350 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/vars/Debian.yaml"
2025-08-19 03:01:12.735546 | controller | ok: All items complete
2025-08-19 03:01:12.735617 | 
2025-08-19 03:01:12.752299 | 
2025-08-19 03:01:12.752489 | TASK [multi-node-bridge : Set up RDO GPG key]
2025-08-19 03:01:12.788636 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:12.800286 | controller | skipping: Conditional result was False
2025-08-19 03:01:12.811735 | 
2025-08-19 03:01:12.811880 | TASK [multi-node-bridge : Set up RDO repository]
2025-08-19 03:01:12.849676 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:12.861435 | controller | skipping: Conditional result was False
2025-08-19 03:01:12.872571 | 
2025-08-19 03:01:12.872772 | LOOP [multi-node-bridge : Set package.use values for OVS on Gentoo]
2025-08-19 03:01:12.903929 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:12.905869 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:12.906862 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:12.925313 | controller | skipping: Conditional result was False
2025-08-19 03:01:12.925475 | 
2025-08-19 03:01:12.932959 | controller | skipping: Conditional result was False
2025-08-19 03:01:12.936567 | controller | skipping: Conditional result was False
2025-08-19 03:01:12.953031 | 
2025-08-19 03:01:12.953213 | TASK [multi-node-bridge : Install openvswitch]
2025-08-19 03:01:14.403142 | controller | ok
2025-08-19 03:01:14.443423 | compute1 | ok
2025-08-19 03:01:14.453349 | 
2025-08-19 03:01:14.453587 | TASK [multi-node-bridge : Install openvswitch (Gentoo)]
2025-08-19 03:01:14.523900 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:14.546355 | controller | skipping: Conditional result was False
2025-08-19 03:01:14.557942 | 
2025-08-19 03:01:14.558123 | TASK [multi-node-bridge : Ensure openvswitch is started]
2025-08-19 03:01:15.753653 | controller | ok
2025-08-19 03:01:15.771747 | compute1 | ok
2025-08-19 03:01:15.791496 | 
2025-08-19 03:01:15.791743 | LOOP [multi-node-bridge : Remove RDO repository files]
2025-08-19 03:01:15.818648 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:15.819273 | 
2025-08-19 03:01:15.823004 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:15.853025 | controller | skipping: Conditional result was False
2025-08-19 03:01:15.853914 | 
2025-08-19 03:01:15.855150 | controller | skipping: Conditional result was False
2025-08-19 03:01:15.880727 | 
2025-08-19 03:01:15.880895 | TASK [multi-node-bridge : Authorize the multi-node-bridge network]
2025-08-19 03:01:15.953650 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:15.986234 | controller | skipping: Conditional result was False
2025-08-19 03:01:15.997403 | 
2025-08-19 03:01:15.997752 | TASK [multi-node-bridge : Determine bridge mtu]
2025-08-19 03:01:16.666772 | controller | 1430
2025-08-19 03:01:16.750167 | controller | ok: Runtime: 0:00:00.029931
2025-08-19 03:01:16.768868 | compute1 | ok: Runtime: 0:00:00.023588
2025-08-19 03:01:16.779791 | 
2025-08-19 03:01:16.779950 | TASK [multi-node-bridge : Set bridge_mtu]
2025-08-19 03:01:16.839974 | compute1 | ok
2025-08-19 03:01:16.861213 | controller | ok
2025-08-19 03:01:16.869895 | 
2025-08-19 03:01:16.870034 | TASK [multi-node-bridge : Include switch tasks]
2025-08-19 03:01:16.899685 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:16.932644 | controller | ok
2025-08-19 03:01:16.947983 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/switch.yaml
2025-08-19 03:01:16.965036 | 
2025-08-19 03:01:16.965179 | TASK [multi-node-bridge : Ensure the bridge exists]
2025-08-19 03:01:16.696810 | compute1 | 1430
2025-08-19 03:01:17.671255 | controller | ok
2025-08-19 03:01:17.681199 | 
2025-08-19 03:01:17.681311 | TASK [multi-node-bridge : Set the bridge MTU]
2025-08-19 03:01:18.415894 | controller | ok: Runtime: 0:00:00.012264
2025-08-19 03:01:18.425445 | 
2025-08-19 03:01:18.425613 | TASK [multi-node-bridge : Verify if the bridge address is set]
2025-08-19 03:01:19.161742 | controller | ok: Runtime: 0:00:00.004867
2025-08-19 03:01:19.229513 | 
2025-08-19 03:01:19.229729 | TASK [multi-node-bridge : Set the bridge address]
2025-08-19 03:01:19.336764 | controller | skipping: Conditional result was False
2025-08-19 03:01:19.349156 | 
2025-08-19 03:01:19.349284 | TASK [multi-node-bridge : Bring bridge interface up]
2025-08-19 03:01:20.079838 | controller | ok: Runtime: 0:00:00.003771
2025-08-19 03:01:20.089128 | 
2025-08-19 03:01:20.089263 | TASK [multi-node-bridge : Include peer tasks]
2025-08-19 03:01:20.136319 | compute1 | ok
2025-08-19 03:01:20.138094 | controller | skipping: Conditional result was False
2025-08-19 03:01:20.160795 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/multi-node-bridge/tasks/peer.yaml
2025-08-19 03:01:20.176033 | 
2025-08-19 03:01:20.176169 | TASK [multi-node-bridge : Set offset]
2025-08-19 03:01:20.266363 | compute1 | ok
2025-08-19 03:01:20.275498 | 
2025-08-19 03:01:20.275646 | TASK [multi-node-bridge : Add additional vni offset]
2025-08-19 03:01:20.361711 | compute1 | ok
2025-08-19 03:01:20.370247 | 
2025-08-19 03:01:20.370369 | TASK [multi-node-bridge : Set ip address when the node private IP looks empty]
2025-08-19 03:01:20.499167 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:20.511094 | 
2025-08-19 03:01:20.511259 | TASK [multi-node-bridge : Set ip address when the node private IP was not defined]
2025-08-19 03:01:20.607692 | compute1 | ok
2025-08-19 03:01:20.616054 | 
2025-08-19 03:01:20.616203 | TASK [multi-node-bridge : Select the switch from group and the private ip]
2025-08-19 03:01:20.671801 | compute1 | ok
2025-08-19 03:01:20.680021 | 
2025-08-19 03:01:20.680153 | TASK [multi-node-bridge : Alias the primary node private IP, if it looks empty]
2025-08-19 03:01:20.817562 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:20.831273 | 
2025-08-19 03:01:20.831441 | TASK [multi-node-bridge : Alias the primary node private IP, if it was not defined]
2025-08-19 03:01:20.939776 | compute1 | ok
2025-08-19 03:01:20.949147 | 
2025-08-19 03:01:20.949314 | TASK [multi-node-bridge : Add port to bridge on switch node]
2025-08-19 03:01:21.495649 | compute1 -> 158.69.73.106 | ok: Runtime: 0:00:00.009057
2025-08-19 03:01:21.505250 | 
2025-08-19 03:01:21.505439 | TASK [multi-node-bridge : Create bridge on peer node]
2025-08-19 03:01:22.027843 | compute1 | ok
2025-08-19 03:01:22.036757 | 
2025-08-19 03:01:22.036873 | TASK [multi-node-bridge : Set MTU on peer node bridge]
2025-08-19 03:01:32.778211 | compute1 | ok: Runtime: 0:00:00.009866
2025-08-19 03:01:32.788320 | 
2025-08-19 03:01:32.788455 | TASK [multi-node-bridge : Add port to bridge on peer node]
2025-08-19 03:01:43.585340 | compute1 | ok: Runtime: 0:00:00.007401
2025-08-19 03:01:43.595048 | 
2025-08-19 03:01:43.595173 | TASK [multi-node-bridge : Verify if the bridge address is set]
2025-08-19 03:01:54.348614 | compute1 | ok: Runtime: 0:00:00.005827
2025-08-19 03:01:54.364849 | 
2025-08-19 03:01:54.365007 | TASK [multi-node-bridge : Set the bridge address]
2025-08-19 03:01:54.473422 | compute1 | skipping: Conditional result was False
2025-08-19 03:01:54.485892 | 
2025-08-19 03:01:54.486147 | TASK [multi-node-bridge : Bring subnode bridge interface up]
2025-08-19 03:02:05.235405 | compute1 | ok: Runtime: 0:00:00.005036
2025-08-19 03:02:05.245707 | 
2025-08-19 03:02:05.245850 | TASK [Persist iptables rules]
2025-08-19 03:02:05.291352 | compute1 | ok
2025-08-19 03:02:05.311648 | controller | ok
2025-08-19 03:02:05.369405 | compute1 | included: persistent-firewall
2025-08-19 03:02:05.369628 | controller | included: persistent-firewall
2025-08-19 03:02:05.407753 | 
2025-08-19 03:02:05.407953 | TASK [persistent-firewall : List current ipv4 rules]
2025-08-19 03:02:06.032033 | compute1 | # Generated by iptables-save v1.8.7 on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.032133 | compute1 | *filter
2025-08-19 03:02:06.032145 | compute1 | :INPUT ACCEPT [0:0]
2025-08-19 03:02:06.032152 | compute1 | :FORWARD ACCEPT [0:0]
2025-08-19 03:02:06.032161 | compute1 | :OUTPUT ACCEPT [18590:2484579]
2025-08-19 03:02:06.032172 | compute1 | :openstack-INPUT - [0:0]
2025-08-19 03:02:06.032182 | compute1 | -A INPUT -s 158.69.74.227/32 -j ACCEPT
2025-08-19 03:02:06.032190 | compute1 | -A INPUT -s 158.69.73.106/32 -j ACCEPT
2025-08-19 03:02:06.032196 | compute1 | -A INPUT -j openstack-INPUT
2025-08-19 03:02:06.032203 | compute1 | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 03:02:06.032210 | compute1 | -A openstack-INPUT -p icmp -m icmp --icmp-type any -j ACCEPT
2025-08-19 03:02:06.032218 | compute1 | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 03:02:06.032224 | compute1 | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 03:02:06.032231 | compute1 | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 03:02:06.032237 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p udp -m udp --dport 69 -j ACCEPT
2025-08-19 03:02:06.032243 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 6385 -j ACCEPT
2025-08-19 03:02:06.032249 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 80 -j ACCEPT
2025-08-19 03:02:06.032255 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8000 -j ACCEPT
2025-08-19 03:02:06.032261 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8003 -j ACCEPT
2025-08-19 03:02:06.032271 | compute1 | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8004 -j ACCEPT
2025-08-19 03:02:06.032278 | compute1 | -A openstack-INPUT -m limit --limit 2/min -j LOG --log-prefix "iptables dropped: "
2025-08-19 03:02:06.032285 | compute1 | -A openstack-INPUT -j REJECT --reject-with icmp-host-prohibited
2025-08-19 03:02:06.032291 | compute1 | COMMIT
2025-08-19 03:02:06.032297 | compute1 | # Completed on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.071893 | controller | # Generated by iptables-save v1.8.7 on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.072062 | controller | *filter
2025-08-19 03:02:06.072081 | controller | :INPUT ACCEPT [0:0]
2025-08-19 03:02:06.072091 | controller | :FORWARD ACCEPT [0:0]
2025-08-19 03:02:06.072101 | controller | :OUTPUT ACCEPT [14047:1725735]
2025-08-19 03:02:06.072116 | controller | :openstack-INPUT - [0:0]
2025-08-19 03:02:06.072134 | controller | -A INPUT -s 158.69.74.227/32 -j ACCEPT
2025-08-19 03:02:06.072167 | controller | -A INPUT -s 158.69.73.106/32 -j ACCEPT
2025-08-19 03:02:06.072268 | controller | -A INPUT -j openstack-INPUT
2025-08-19 03:02:06.072285 | controller | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 03:02:06.072293 | controller | -A openstack-INPUT -p icmp -m icmp --icmp-type any -j ACCEPT
2025-08-19 03:02:06.072301 | controller | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 03:02:06.072308 | controller | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 03:02:06.072314 | controller | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 03:02:06.072320 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p udp -m udp --dport 69 -j ACCEPT
2025-08-19 03:02:06.072326 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 6385 -j ACCEPT
2025-08-19 03:02:06.072332 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 80 -j ACCEPT
2025-08-19 03:02:06.072338 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8000 -j ACCEPT
2025-08-19 03:02:06.072344 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8003 -j ACCEPT
2025-08-19 03:02:06.072356 | controller | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8004 -j ACCEPT
2025-08-19 03:02:06.072363 | controller | -A openstack-INPUT -m limit --limit 2/min -j LOG --log-prefix "iptables dropped: "
2025-08-19 03:02:06.072370 | controller | -A openstack-INPUT -j REJECT --reject-with icmp-host-prohibited
2025-08-19 03:02:06.072376 | controller | COMMIT
2025-08-19 03:02:06.072383 | controller | # Completed on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.213624 | compute1 | ok: Runtime: 0:00:00.013649
2025-08-19 03:02:06.238010 | controller | ok: Runtime: 0:00:00.013454
2025-08-19 03:02:06.248484 | 
2025-08-19 03:02:06.248643 | TASK [persistent-firewall : List current ipv6 rules]
2025-08-19 03:02:06.722414 | compute1 | # Generated by ip6tables-save v1.8.7 on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.722491 | compute1 | *filter
2025-08-19 03:02:06.722512 | compute1 | :INPUT ACCEPT [0:0]
2025-08-19 03:02:06.722527 | compute1 | :FORWARD ACCEPT [0:0]
2025-08-19 03:02:06.722544 | compute1 | :OUTPUT ACCEPT [152:11689]
2025-08-19 03:02:06.722563 | compute1 | :openstack-INPUT - [0:0]
2025-08-19 03:02:06.722580 | compute1 | -A INPUT -j openstack-INPUT
2025-08-19 03:02:06.722595 | compute1 | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 03:02:06.722613 | compute1 | -A openstack-INPUT -p ipv6-icmp -j ACCEPT
2025-08-19 03:02:06.722627 | compute1 | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 03:02:06.722639 | compute1 | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 03:02:06.722651 | compute1 | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 03:02:06.722673 | compute1 | -A openstack-INPUT -j REJECT --reject-with icmp6-adm-prohibited
2025-08-19 03:02:06.722685 | compute1 | COMMIT
2025-08-19 03:02:06.722709 | compute1 | # Completed on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.805023 | controller | # Generated by ip6tables-save v1.8.7 on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.805082 | controller | *filter
2025-08-19 03:02:06.805092 | controller | :INPUT ACCEPT [0:0]
2025-08-19 03:02:06.805099 | controller | :FORWARD ACCEPT [0:0]
2025-08-19 03:02:06.805108 | controller | :OUTPUT ACCEPT [149:11414]
2025-08-19 03:02:06.805118 | controller | :openstack-INPUT - [0:0]
2025-08-19 03:02:06.805127 | controller | -A INPUT -j openstack-INPUT
2025-08-19 03:02:06.805134 | controller | -A openstack-INPUT -i lo -j ACCEPT
2025-08-19 03:02:06.805143 | controller | -A openstack-INPUT -p ipv6-icmp -j ACCEPT
2025-08-19 03:02:06.805150 | controller | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2025-08-19 03:02:06.805157 | controller | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2025-08-19 03:02:06.805169 | controller | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2025-08-19 03:02:06.805179 | controller | -A openstack-INPUT -j REJECT --reject-with icmp6-adm-prohibited
2025-08-19 03:02:06.805189 | controller | COMMIT
2025-08-19 03:02:06.805200 | controller | # Completed on Tue Aug 19 03:02:06 2025
2025-08-19 03:02:06.991269 | compute1 | ok: Runtime: 0:00:00.006763
2025-08-19 03:02:07.009293 | controller | ok: Runtime: 0:00:00.006443
2025-08-19 03:02:07.021441 | 
2025-08-19 03:02:07.021649 | LOOP [persistent-firewall : Configure persistent iptables rules]
2025-08-19 03:02:07.068776 | compute1 | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml"
2025-08-19 03:02:07.069362 | 
2025-08-19 03:02:07.092641 | controller | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml"
2025-08-19 03:02:07.121659 | compute1 | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml
2025-08-19 03:02:07.121787 | controller | included: /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml
2025-08-19 03:02:07.134302 | 
2025-08-19 03:02:07.134550 | TASK [persistent-firewall : Install iptables-persistent]
2025-08-19 03:02:08.212380 | compute1 | ok
2025-08-19 03:02:08.463798 | controller | ok
2025-08-19 03:02:08.476863 | 
2025-08-19 03:02:08.477046 | TASK [persistent-firewall : Persist ipv4 rules]
2025-08-19 03:02:10.650723 | compute1 | changed
2025-08-19 03:02:10.701167 | controller | changed
2025-08-19 03:02:10.712098 | 
2025-08-19 03:02:10.712220 | TASK [persistent-firewall : Persist ipv6 rules]
2025-08-19 03:02:12.205581 | compute1 | changed
2025-08-19 03:02:12.254196 | controller | changed
2025-08-19 03:02:12.268142 | 
2025-08-19 03:02:12.268349 | TASK [persistent-firewall : Ensure netfilter-persistent is started]
2025-08-19 03:02:12.877282 | compute1 | ok
2025-08-19 03:02:12.925010 | controller | ok
2025-08-19 03:02:12.976260 | 
2025-08-19 03:02:12.976443 | TASK [multi-node-setup : Ensure the infra bridge exists]
2025-08-19 03:02:13.383157 | controller | ok
2025-08-19 03:02:13.433126 | compute1 | ok
2025-08-19 03:02:13.441135 | 
2025-08-19 03:02:13.441258 | TASK [multi-node-setup : Ensure the Neutron external bridge exists]
2025-08-19 03:02:13.844731 | compute1 | changed
2025-08-19 03:02:13.853758 | controller | changed
2025-08-19 03:02:13.863293 | 
2025-08-19 03:02:13.863456 | TASK [multi-node-setup : Create patch port between bridges]
2025-08-19 03:02:14.638136 | controller | ok: Runtime: 0:00:00.009523
2025-08-19 03:02:14.655195 | compute1 | ok: Runtime: 0:00:00.010390
2025-08-19 03:02:14.673978 | 
2025-08-19 03:02:14.674123 | PLAY RECAP
2025-08-19 03:02:14.674216 | compute1 | ok: 29 changed: 9 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-08-19 03:02:14.674291 | controller | ok: 22 changed: 7 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-08-19 03:02:14.674346 | 
2025-08-19 03:02:14.874870 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/grenade/playbooks/multinode-pre.yaml@master]
2025-08-19 03:02:14.890157 | PRE-RUN START: [untrusted : opendev.org/openstack/neutron/playbooks/dvr-multinode-scenario-pre-run.yaml@master]
2025-08-19 03:02:16.268083 | ANSIBLE PARSE ERROR
2025-08-19 03:02:16.268111 | Using /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/ansible/pre_playbook_4/ansible.cfg as config file
2025-08-19 03:02:16.268123 | ERROR! couldn't resolve module/action 'openvswitch_bridge'. This often indicates a misspelling, missing collection, or incorrect module path.
2025-08-19 03:02:16.268131 | 
2025-08-19 03:02:16.268137 | The error appears to be in '/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/untrusted/project_6/opendev.org/openstack/neutron-tempest-plugin/roles/multi-node-setup/tasks/main.yaml': line 1, column 3, but may
2025-08-19 03:02:16.268144 | be elsewhere in the file depending on the exact syntax problem.
2025-08-19 03:02:16.268151 | 
2025-08-19 03:02:16.268157 | The offending line appears to be:
2025-08-19 03:02:16.268163 | 
2025-08-19 03:02:16.268169 | 
2025-08-19 03:02:16.268175 | - name: Ensure the infra bridge exists
2025-08-19 03:02:16.268182 |   ^ here
2025-08-19 03:02:16.268551 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/neutron/playbooks/dvr-multinode-scenario-pre-run.yaml@master]
2025-08-19 03:02:16.284472 | POST-RUN START: [untrusted : opendev.org/openstack/grenade/playbooks/post.yaml@master]
2025-08-19 03:02:17.725778 | 
2025-08-19 03:02:17.726027 | PLAY [all]
2025-08-19 03:02:17.762776 | 
2025-08-19 03:02:17.762992 | LOOP [prepare-grenade-logs : Rename the grenade custom config files]
2025-08-19 03:02:18.756882 | controller | ERROR: Item: localrc
2025-08-19 03:02:18.757634 | controller | {
2025-08-19 03:02:18.757768 | controller |   "ansible_loop_var": "item",
2025-08-19 03:02:18.757860 | controller |   "item": "localrc",
2025-08-19 03:02:18.758088 | controller |   "msg": "Source /opt/stack/new/grenade/localrc not found"
2025-08-19 03:02:18.758195 | controller | }
2025-08-19 03:02:18.769419 | compute1 | ERROR: Item: localrc
2025-08-19 03:02:18.769705 | compute1 | {
2025-08-19 03:02:18.769796 | compute1 |   "ansible_loop_var": "item",
2025-08-19 03:02:18.769861 | compute1 |   "item": "localrc",
2025-08-19 03:02:18.769915 | compute1 |   "msg": "Source /opt/stack/new/grenade/localrc not found"
2025-08-19 03:02:18.769967 | compute1 | }
2025-08-19 03:02:19.349823 | controller | ERROR: Item: pluginrc
2025-08-19 03:02:19.350028 | controller | {
2025-08-19 03:02:19.350119 | controller |   "ansible_loop_var": "item",
2025-08-19 03:02:19.350196 | controller |   "item": "pluginrc",
2025-08-19 03:02:19.350251 | controller |   "msg": "Source /opt/stack/new/grenade/pluginrc not found"
2025-08-19 03:02:19.350303 | controller | }
2025-08-19 03:02:19.364201 | controller | ERROR: Ignoring Errors
2025-08-19 03:02:19.436126 | compute1 | ERROR: Item: pluginrc
2025-08-19 03:02:19.436314 | compute1 | {
2025-08-19 03:02:19.436378 | compute1 |   "ansible_loop_var": "item",
2025-08-19 03:02:19.436433 | compute1 |   "item": "pluginrc",
2025-08-19 03:02:19.436485 | compute1 |   "msg": "Source /opt/stack/new/grenade/pluginrc not found"
2025-08-19 03:02:19.436782 | compute1 | }
2025-08-19 03:02:19.455095 | compute1 | ERROR: Ignoring Errors
2025-08-19 03:02:19.465870 | 
2025-08-19 03:02:19.466097 | TASK [prepare-grenade-logs : Create a link with a better name to the grenade save directory]
2025-08-19 03:02:20.117260 | controller | ERROR
2025-08-19 03:02:20.117767 | controller | {
2025-08-19 03:02:20.117839 | controller |   "msg": "src file does not exist, use \"force=yes\" if you really want to create the link: /opt/stack/save",
2025-08-19 03:02:20.117910 | controller |   "path": "/opt/stack/grenade_save",
2025-08-19 03:02:20.117966 | controller |   "src": "/opt/stack/save"
2025-08-19 03:02:20.118017 | controller | }
2025-08-19 03:02:20.118084 | controller | ERROR: Ignoring Errors
2025-08-19 03:02:20.138493 | compute1 | ERROR
2025-08-19 03:02:20.138709 | compute1 | {
2025-08-19 03:02:20.138775 | compute1 |   "msg": "src file does not exist, use \"force=yes\" if you really want to create the link: /opt/stack/save",
2025-08-19 03:02:20.138831 | compute1 |   "path": "/opt/stack/grenade_save",
2025-08-19 03:02:20.138884 | compute1 |   "src": "/opt/stack/save"
2025-08-19 03:02:20.138956 | compute1 | }
2025-08-19 03:02:20.139069 | compute1 | ERROR: Ignoring Errors
2025-08-19 03:02:20.150667 | 
2025-08-19 03:02:20.150863 | LOOP [prepare-grenade-logs : Create the save directories]
2025-08-19 03:02:20.549644 | compute1 | changed: "old"
2025-08-19 03:02:20.568399 | controller | changed: "old"
2025-08-19 03:02:20.878419 | compute1 | changed: "new"
2025-08-19 03:02:20.906056 | controller | changed: "new"
2025-08-19 03:02:20.906220 | controller | changed: All items complete
2025-08-19 03:02:20.906279 | 
2025-08-19 03:02:20.926361 | 
2025-08-19 03:02:20.926575 | LOOP [prepare-grenade-logs : Copy the files to the expected save location]
2025-08-19 03:02:33.646378 | controller | Output suppressed because no_log was given
2025-08-19 03:02:33.646708 | controller | Output suppressed because no_log was given
2025-08-19 03:02:34.002053 | compute1 | Output suppressed because no_log was given
2025-08-19 03:02:34.002311 | compute1 | Output suppressed because no_log was given
2025-08-19 03:02:34.036108 | 
2025-08-19 03:02:34.036326 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2025-08-19 03:02:34.414751 | compute1 | changed
2025-08-19 03:02:34.467018 | controller | changed
2025-08-19 03:02:34.476715 | 
2025-08-19 03:02:34.476950 | TASK [export-devstack-journal : Export legacy stack screen log files]
2025-08-19 03:02:36.259976 | controller | ok: Runtime: 0:00:00.586922
2025-08-19 03:02:36.275696 | compute1 | ok: Runtime: 0:00:00.629664
2025-08-19 03:02:36.285757 | 
2025-08-19 03:02:36.285960 | TASK [export-devstack-journal : Export legacy syslog.txt]
2025-08-19 03:02:37.028223 | compute1 | ok: Runtime: 0:00:00.013484
2025-08-19 03:02:37.047667 | controller | ok: Runtime: 0:00:00.014880
2025-08-19 03:02:37.057302 | 
2025-08-19 03:02:37.057515 | TASK [export-devstack-journal : Export journal]
2025-08-19 03:02:37.803876 | controller | ok: Runtime: 0:00:00.013625
2025-08-19 03:02:37.820373 | compute1 | ok: Runtime: 0:00:00.015579
2025-08-19 03:02:37.831299 | 
2025-08-19 03:02:37.831481 | TASK [export-devstack-journal : Save journal README]
2025-08-19 03:02:39.575980 | controller | changed
2025-08-19 03:02:39.681049 | compute1 | changed
2025-08-19 03:02:39.718553 | 
2025-08-19 03:02:39.718761 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2025-08-19 03:02:40.093504 | compute1 | changed
2025-08-19 03:02:40.138162 | controller | changed
2025-08-19 03:02:40.148489 | 
2025-08-19 03:02:40.148732 | TASK [apache-logs-conf : Find logs]
2025-08-19 03:02:40.764388 | controller | Output suppressed because no_log was given
2025-08-19 03:02:40.797134 | compute1 | Output suppressed because no_log was given
2025-08-19 03:02:40.806387 | 
2025-08-19 03:02:40.806562 | LOOP [apache-logs-conf : Dereference files]
2025-08-19 03:02:40.868200 | 
2025-08-19 03:02:40.868488 | LOOP [apache-logs-conf : Create hard links]
2025-08-19 03:02:40.928732 | 
2025-08-19 03:02:40.929076 | TASK [apache-logs-conf : Find logs]
2025-08-19 03:02:40.995827 | 
2025-08-19 03:02:40.996203 | LOOP [apache-logs-conf : Dereference files]
2025-08-19 03:02:41.062816 | 
2025-08-19 03:02:41.063139 | LOOP [apache-logs-conf : Create hard links]
2025-08-19 03:02:41.127060 | 
2025-08-19 03:02:41.127372 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2025-08-19 03:02:41.650306 | controller | changed
2025-08-19 03:02:41.661879 | compute1 | changed
2025-08-19 03:02:41.674482 | 
2025-08-19 03:02:41.674700 | TASK [apache-logs-conf : Define config paths]
2025-08-19 03:02:41.730061 | compute1 | ok
2025-08-19 03:02:41.739385 | controller | ok
2025-08-19 03:02:41.750935 | 
2025-08-19 03:02:41.751129 | TASK [apache-logs-conf : Discover configurations]
2025-08-19 03:02:42.144145 | compute1 | Output suppressed because no_log was given
2025-08-19 03:02:42.153240 | controller | Output suppressed because no_log was given
2025-08-19 03:02:42.163594 | 
2025-08-19 03:02:42.163803 | LOOP [apache-logs-conf : Dereference configurations]
2025-08-19 03:02:42.229214 | 
2025-08-19 03:02:42.229557 | LOOP [apache-logs-conf : Link configurations]
2025-08-19 03:02:42.309709 | 
2025-08-19 03:02:42.310027 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2025-08-19 03:02:42.747842 | compute1 | changed
2025-08-19 03:02:42.768629 | controller | changed
2025-08-19 03:02:42.779925 | 
2025-08-19 03:02:42.780196 | LOOP [devstack-project-conf : Check which projects have a config folder]
2025-08-19 03:02:46.825062 | controller | Output suppressed because no_log was given
2025-08-19 03:02:46.868678 | compute1 | Output suppressed because no_log was given
2025-08-19 03:02:46.883888 | 
2025-08-19 03:02:46.884064 | LOOP [devstack-project-conf : Copy configuration files]
2025-08-19 03:02:46.941501 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.942219 | 
2025-08-19 03:02:46.945907 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.953286 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.956268 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.964106 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.971227 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.978885 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.980748 | controller | skipping: Conditional result was False
2025-08-19 03:02:46.985917 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.989944 | controller | skipping: Conditional result was False
2025-08-19 03:02:46.991610 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.994817 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:46.997773 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.000049 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:47.005159 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:47.008641 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.017456 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.052509 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.052780 | 
2025-08-19 03:02:47.055709 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.058087 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.061034 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.066072 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.068493 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.070837 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.108450 | 
2025-08-19 03:02:47.108689 | TASK [devstack-project-conf : Check if openstack has a config folder]
2025-08-19 03:02:47.504119 | compute1 | ok
2025-08-19 03:02:47.515721 | controller | ok
2025-08-19 03:02:47.527449 | 
2025-08-19 03:02:47.527730 | TASK [devstack-project-conf : Copy configuration files]
2025-08-19 03:02:47.836383 | compute1 | skipping: Conditional result was False
2025-08-19 03:02:47.837567 | controller | skipping: Conditional result was False
2025-08-19 03:02:47.871354 | 
2025-08-19 03:02:47.871577 | TASK [capture-system-logs : Stage various logs and reports]
2025-08-19 03:02:48.422396 | compute1 | /usr/bin/python3: No module named pip
2025-08-19 03:02:48.447602 | controller | /usr/bin/python3: No module named pip
2025-08-19 03:02:49.260594 | compute1 | grep: /home/zuul/apache/*.log: No such file or directory
2025-08-19 03:02:49.320019 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2025-08-19 03:02:49.626279 | compute1 | ok: Runtime: 0:00:00.881325
2025-08-19 03:02:49.645465 | controller | ok: Runtime: 0:00:00.918132
2025-08-19 03:02:49.677934 | 
2025-08-19 03:02:49.678114 | LOOP [stage-output : Register sources]
2025-08-19 03:03:01.209733 | controller | Output suppressed because no_log was given
2025-08-19 03:03:01.301779 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:01.315147 | 
2025-08-19 03:03:01.315363 | TASK [stage-output : Check sudo]
2025-08-19 03:03:02.069132 | controller | ok: Runtime: 0:00:00.012495
2025-08-19 03:03:02.088029 | compute1 | ok: Runtime: 0:00:00.012847
2025-08-19 03:03:02.097950 | 
2025-08-19 03:03:02.098107 | LOOP [stage-output : Set source and destination for files and folders]
2025-08-19 03:03:02.222867 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.223500 | 
2025-08-19 03:03:02.247403 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.353079 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.354240 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.355239 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.357058 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.368890 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.369718 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.370293 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.381260 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.382076 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.382787 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.402224 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.412967 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.453248 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.464649 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.465299 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.465906 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.466614 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.476820 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.477505 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.480385 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.495719 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.496494 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.508835 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.540858 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.694247 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.714725 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.724275 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.725384 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.726102 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.727045 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.745182 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.746099 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.746857 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.747641 | compute1 | Output suppressed because no_log was given
2025-08-19 03:03:02.757504 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.769343 | controller | Output suppressed because no_log was given
2025-08-19 03:03:02.783490 | 
2025-08-19 03:03:02.783703 | TASK [stage-output : Build a list of source, dest dictionaries]
2025-08-19 03:03:02.900010 | compute1 | ok
2025-08-19 03:03:02.932828 | controller | ok
2025-08-19 03:03:02.949107 | 
2025-08-19 03:03:02.949305 | LOOP [stage-output : Ensure target folders exist]
2025-08-19 03:03:03.472861 | compute1 | changed: "docs"
2025-08-19 03:03:03.491209 | controller | changed: "docs"
2025-08-19 03:03:03.832419 | compute1 | changed: "artifacts"
2025-08-19 03:03:03.844977 | controller | changed: "artifacts"
2025-08-19 03:03:04.172922 | controller | ok: "logs"
2025-08-19 03:03:04.197395 | compute1 | ok: "logs"
2025-08-19 03:03:04.197612 | compute1 | changed: All items complete
2025-08-19 03:03:04.197682 | 
2025-08-19 03:03:04.216182 | 
2025-08-19 03:03:04.216373 | LOOP [stage-output : Copy files and folders to staging folder]
2025-08-19 03:03:04.670847 | compute1 | ok: Item: Runtime: 0:00:00.005178
2025-08-19 03:03:04.698615 | controller | ok: Item: Runtime: 0:00:00.003885
2025-08-19 03:03:05.000038 | controller | ok: Item: Runtime: 0:00:00.004079
2025-08-19 03:03:05.032034 | compute1 | ok: Item: Runtime: 0:00:00.004714
2025-08-19 03:03:05.342443 | controller | ok: Item: Runtime: 0:00:00.004499
2025-08-19 03:03:05.418705 | compute1 | ok: Item: Runtime: 0:00:00.005548
2025-08-19 03:03:05.698700 | controller | ok: Item: Runtime: 0:00:00.004157
2025-08-19 03:03:05.802804 | compute1 | ok: Item: Runtime: 0:00:00.005473
2025-08-19 03:03:06.075203 | controller | ok: Item: Runtime: 0:00:00.004368
2025-08-19 03:03:06.171914 | compute1 | ok: Item: Runtime: 0:00:00.004046
2025-08-19 03:03:06.427930 | controller | ok: Item: Runtime: 0:00:00.004568
2025-08-19 03:03:06.507182 | compute1 | ok: Item: Runtime: 0:00:00.003865
2025-08-19 03:03:06.760579 | controller | ok: Item: Runtime: 0:00:00.003797
2025-08-19 03:03:06.863217 | compute1 | ok: Item: Runtime: 0:00:00.004752
2025-08-19 03:03:07.163379 | controller | ok: Item: Runtime: 0:00:00.022651
2025-08-19 03:03:07.199188 | compute1 | ok: Item: Runtime: 0:00:00.003968
2025-08-19 03:03:07.599938 | controller | ok: Item: Runtime: 0:00:00.004807
2025-08-19 03:03:07.615724 | compute1 | ok: Item: Runtime: 0:00:00.003994
2025-08-19 03:03:07.866281 | controller | ok: Item: Runtime: 0:00:00.004519
2025-08-19 03:03:07.911106 | compute1 | ok: Item: Runtime: 0:00:00.003583
2025-08-19 03:03:08.212469 | controller | ok: Item: Runtime: 0:00:00.004081
2025-08-19 03:03:08.270424 | compute1 | ok: Item: Runtime: 0:00:00.004243
2025-08-19 03:03:08.539566 | controller | ok: Item: Runtime: 0:00:00.004511
2025-08-19 03:03:08.623754 | compute1 | ok: Item: Runtime: 0:00:00.004990
2025-08-19 03:03:08.878254 | controller | ok: Item: Runtime: 0:00:00.003731
2025-08-19 03:03:08.990631 | compute1 | ok: Item: Runtime: 0:00:00.004401
2025-08-19 03:03:09.233399 | controller | ok: Item: Runtime: 0:00:00.004750
2025-08-19 03:03:09.329908 | compute1 | ok: Item: Runtime: 0:00:00.003666
2025-08-19 03:03:09.576234 | controller | ok: Item: Runtime: 0:00:00.003678
2025-08-19 03:03:09.712613 | compute1 | ok: Item: Runtime: 0:00:00.004805
2025-08-19 03:03:09.955354 | controller | ok: Item: Runtime: 0:00:00.004603
2025-08-19 03:03:10.075308 | compute1 | ok: Item: Runtime: 0:00:00.003903
2025-08-19 03:03:10.075500 | compute1 | changed: All items complete
2025-08-19 03:03:10.075928 | 
2025-08-19 03:03:10.107940 | 
2025-08-19 03:03:10.108165 | TASK [stage-output : Make all log files readable]
2025-08-19 03:03:10.549778 | compute1 | changed
2025-08-19 03:03:10.574052 | controller | changed
2025-08-19 03:03:10.584692 | 
2025-08-19 03:03:10.584846 | TASK [stage-output : Rename log files that match extensions_to_txt]
2025-08-19 03:03:11.241440 | controller | changed: Renamed files for staging.
2025-08-19 03:03:11.300364 | compute1 | changed: Renamed files for staging.
2025-08-19 03:03:11.320466 | 
2025-08-19 03:03:11.320710 | TASK [stage-output : Discover log files for compression]
2025-08-19 03:03:11.359815 | compute1 | skipping: Conditional result was False
2025-08-19 03:03:11.382894 | controller | skipping: Conditional result was False
2025-08-19 03:03:11.397143 | 
2025-08-19 03:03:11.397404 | LOOP [stage-output : Archive everything from logs]
2025-08-19 03:03:11.491410 | 
2025-08-19 03:03:11.491789 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2025-08-19 03:03:13.183304 | controller | changed:
2025-08-19 03:03:13.183893 | controller | created directory /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/logs/controller
2025-08-19 03:03:13.184011 | controller | cd+++++++++ logs/
2025-08-19 03:03:13.184120 | controller | >f+++++++++ logs/deprecations_log.txt
2025-08-19 03:03:13.184229 | controller | >f+++++++++ logs/devstack.journal.README.txt
2025-08-19 03:03:13.184335 | controller | >f+++++++++ logs/devstack.journal.gz
2025-08-19 03:03:13.184440 | controller | >f+++++++++ logs/df.txt
2025-08-19 03:03:13.184609 | controller | >f+++++++++ logs/dpkg-l.txt
2025-08-19 03:03:13.184913 | controller | >f+++++++++ logs/iptables.txt
2025-08-19 03:03:13.185020 | controller | >f+++++++++ logs/listen53.txt
2025-08-19 03:03:13.185172 | controller | >f+++++++++ logs/pip3-freeze.txt
2025-08-19 03:03:13.185262 | controller | >f+++++++++ logs/resolv_conf.txt
2025-08-19 03:03:13.185325 | controller | >f+++++++++ logs/sudoers
2025-08-19 03:03:13.185383 | controller | >f+++++++++ logs/syslog.txt
2025-08-19 03:03:13.185440 | controller | >f+++++++++ logs/unbound_log.txt
2025-08-19 03:03:13.185496 | controller | cd+++++++++ logs/apache/
2025-08-19 03:03:13.185590 | controller | cd+++++++++ logs/apache_config/
2025-08-19 03:03:13.185650 | controller | cd+++++++++ logs/etc/
2025-08-19 03:03:13.185705 | controller | cd+++++++++ logs/new/
2025-08-19 03:03:13.185759 | controller | >f+++++++++ logs/new/local_conf.txt
2025-08-19 03:03:13.185813 | controller | cd+++++++++ logs/old/
2025-08-19 03:03:13.185865 | controller | >f+++++++++ logs/old/local_conf.txt
2025-08-19 03:03:13.185949 | controller | cd+++++++++ logs/openvswitch/
2025-08-19 03:03:13.186238 | controller | >f+++++++++ logs/openvswitch/ovs-vswitchd_log.txt
2025-08-19 03:03:13.186299 | controller | >f+++++++++ logs/openvswitch/ovsdb-server_log.txt
2025-08-19 03:03:13.186353 | controller | cd+++++++++ logs/sudoers.d/
2025-08-19 03:03:13.186407 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2025-08-19 03:03:13.186460 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2025-08-19 03:03:13.186512 | controller | >f+++++++++ logs/sudoers.d/README
2025-08-19 03:03:13.186605 | controller | >f+++++++++ logs/sudoers.d/zuul
2025-08-19 03:03:13.206445 | compute1 | changed:
2025-08-19 03:03:13.206598 | compute1 | created directory /var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/logs/compute1
2025-08-19 03:03:13.206664 | compute1 | cd+++++++++ logs/
2025-08-19 03:03:13.206721 | compute1 | >f+++++++++ logs/deprecations_log.txt
2025-08-19 03:03:13.206777 | compute1 | >f+++++++++ logs/devstack.journal.README.txt
2025-08-19 03:03:13.206831 | compute1 | >f+++++++++ logs/devstack.journal.gz
2025-08-19 03:03:13.206883 | compute1 | >f+++++++++ logs/df.txt
2025-08-19 03:03:13.206936 | compute1 | >f+++++++++ logs/dpkg-l.txt
2025-08-19 03:03:13.206988 | compute1 | >f+++++++++ logs/iptables.txt
2025-08-19 03:03:13.207040 | compute1 | >f+++++++++ logs/listen53.txt
2025-08-19 03:03:13.207092 | compute1 | >f+++++++++ logs/pip3-freeze.txt
2025-08-19 03:03:13.207144 | compute1 | >f+++++++++ logs/resolv_conf.txt
2025-08-19 03:03:13.207196 | compute1 | >f+++++++++ logs/sudoers
2025-08-19 03:03:13.207247 | compute1 | >f+++++++++ logs/syslog.txt
2025-08-19 03:03:13.207308 | compute1 | >f+++++++++ logs/unbound_log.txt
2025-08-19 03:03:13.207406 | compute1 | cd+++++++++ logs/apache/
2025-08-19 03:03:13.207503 | compute1 | cd+++++++++ logs/apache_config/
2025-08-19 03:03:13.207651 | compute1 | cd+++++++++ logs/etc/
2025-08-19 03:03:13.207751 | compute1 | cd+++++++++ logs/new/
2025-08-19 03:03:13.207810 | compute1 | >f+++++++++ logs/new/local_conf.txt
2025-08-19 03:03:13.207892 | compute1 | cd+++++++++ logs/old/
2025-08-19 03:03:13.207949 | compute1 | >f+++++++++ logs/old/local_conf.txt
2025-08-19 03:03:13.208002 | compute1 | cd+++++++++ logs/openvswitch/
2025-08-19 03:03:13.208054 | compute1 | >f+++++++++ logs/openvswitch/ovs-vswitchd_log.txt
2025-08-19 03:03:13.208128 | compute1 | >f+++++++++ logs/openvswitch/ovsdb-server_log.txt
2025-08-19 03:03:13.208220 | compute1 | cd+++++++++ logs/sudoers.d/
2025-08-19 03:03:13.208278 | compute1 | >f+++++++++ logs/sudoers.d/50_stack_sh
2025-08-19 03:03:13.208331 | compute1 | >f+++++++++ logs/sudoers.d/51_tempest_sh
2025-08-19 03:03:13.208383 | compute1 | >f+++++++++ logs/sudoers.d/README
2025-08-19 03:03:13.208436 | compute1 | >f+++++++++ logs/sudoers.d/zuul
2025-08-19 03:03:13.234483 | 
2025-08-19 03:03:13.234728 | PLAY [tempest]
2025-08-19 03:03:13.256305 | 
2025-08-19 03:03:13.256453 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-08-19 03:03:14.184395 | controller | changed: non-zero return code
2025-08-19 03:03:14.192900 | 
2025-08-19 03:03:14.193024 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-08-19 03:03:14.218310 | controller | skipping: Conditional result was False
2025-08-19 03:03:14.229320 | 
2025-08-19 03:03:14.229445 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-08-19 03:03:14.267033 | 
2025-08-19 03:03:14.267338 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-08-19 03:03:14.374675 | 
2025-08-19 03:03:14.374937 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-08-19 03:03:14.403061 | controller | skipping: Conditional result was False
2025-08-19 03:03:14.415879 | 
2025-08-19 03:03:14.416109 | LOOP [fetch-subunit-output : Generate subunit file]
2025-08-19 03:03:14.454804 | 
2025-08-19 03:03:14.455217 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-08-19 03:03:14.482257 | controller | skipping: Conditional result was False
2025-08-19 03:03:14.497351 | 
2025-08-19 03:03:14.497568 | TASK [fetch-subunit-output : Remove the temporary file]
2025-08-19 03:03:14.524355 | controller | skipping: Conditional result was False
2025-08-19 03:03:14.534724 | 
2025-08-19 03:03:14.534881 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-08-19 03:03:14.560871 | controller | skipping: Conditional result was False
2025-08-19 03:03:14.583778 | 
2025-08-19 03:03:14.583958 | TASK [process-stackviz : Devstack checks if stackviz archive exists]
2025-08-19 03:03:15.058663 | controller | ok
2025-08-19 03:03:15.068842 | 
2025-08-19 03:03:15.068967 | TASK [process-stackviz : debug]
2025-08-19 03:03:15.095474 | controller | skipping: Conditional result was False
2025-08-19 03:03:15.105880 | 
2025-08-19 03:03:15.106113 | TASK [process-stackviz : Check if subunit data exists]
2025-08-19 03:03:15.479449 | controller | ok
2025-08-19 03:03:15.494549 | 
2025-08-19 03:03:15.494741 | TASK [process-stackviz : debug]
2025-08-19 03:03:15.541331 | Subunit file could not be found at /opt/stack/tempest/testrepository.subunit
2025-08-19 03:03:15.550550 | 
2025-08-19 03:03:15.550681 | TASK [include_role : ensure-pip]
2025-08-19 03:03:15.577674 | controller | skipping: Conditional result was False
2025-08-19 03:03:15.589644 | 
2025-08-19 03:03:15.589803 | TASK [process-stackviz : pip]
2025-08-19 03:03:15.616574 | controller | skipping: Conditional result was False
2025-08-19 03:03:15.627802 | 
2025-08-19 03:03:15.628008 | TASK [process-stackviz : Deploy stackviz static html+js]
2025-08-19 03:03:15.694403 | controller | skipping: Conditional result was False
2025-08-19 03:03:15.705886 | 
2025-08-19 03:03:15.706079 | TASK [process-stackviz : Check if dstat data exists]
2025-08-19 03:03:15.733856 | controller | skipping: Conditional result was False
2025-08-19 03:03:15.753513 | 
2025-08-19 03:03:15.753888 | TASK [process-stackviz : Run stackviz with dstat]
2025-08-19 03:03:15.821439 | controller | skipping: Conditional result was False
2025-08-19 03:03:15.832615 | 
2025-08-19 03:03:15.832823 | TASK [process-stackviz : Run stackviz without dstat]
2025-08-19 03:03:15.900124 | controller | skipping: Conditional result was False
2025-08-19 03:03:15.912768 | 
2025-08-19 03:03:15.912901 | PLAY RECAP
2025-08-19 03:03:15.913004 | compute1 | ok: 27 changed: 17 unreachable: 0 failed: 0 skipped: 11 rescued: 0 ignored: 3
2025-08-19 03:03:15.913075 | controller | ok: 31 changed: 18 unreachable: 0 failed: 0 skipped: 26 rescued: 0 ignored: 3
2025-08-19 03:03:15.913128 | 
2025-08-19 03:03:16.131571 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/grenade/playbooks/post.yaml@master]
2025-08-19 03:03:16.159490 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2025-08-19 03:03:17.549809 | 
2025-08-19 03:03:17.550024 | PLAY [all]
2025-08-19 03:03:17.575118 | 
2025-08-19 03:03:17.575325 | TASK [fetch-output : Set log path for multiple nodes]
2025-08-19 03:03:17.677760 | compute1 | ok
2025-08-19 03:03:17.696434 | controller | ok
2025-08-19 03:03:17.704838 | 
2025-08-19 03:03:17.705035 | TASK [fetch-output : Set log path for single node]
2025-08-19 03:03:17.742378 | compute1 | skipping: Conditional result was False
2025-08-19 03:03:17.764468 | controller | skipping: Conditional result was False
2025-08-19 03:03:17.775310 | 
2025-08-19 03:03:17.775491 | LOOP [fetch-output : Ensure local output dirs]
2025-08-19 03:03:18.420398 | compute1 -> localhost | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/logs/compute1"
2025-08-19 03:03:18.430268 | controller -> localhost | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/logs/controller"
2025-08-19 03:03:18.767381 | compute1 -> localhost | changed: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/artifacts"
2025-08-19 03:03:18.792065 | controller -> localhost | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/artifacts"
2025-08-19 03:03:19.133930 | compute1 -> localhost | changed: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/docs"
2025-08-19 03:03:19.137027 | controller -> localhost | ok: "/var/lib/zuul/builds/492f0763d7b54cb388374107cc79cf62/work/docs"
2025-08-19 03:03:19.163187 | 
2025-08-19 03:03:19.163401 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-08-19 03:03:20.723741 | controller | changed: .d..t...... ./
2025-08-19 03:03:20.724597 | controller | ok: All items complete
2025-08-19 03:03:20.724671 | 
2025-08-19 03:03:20.805240 | compute1 | changed: .d..t...... ./
2025-08-19 03:03:22.077278 | compute1 | changed: .d..t...... ./
2025-08-19 03:03:22.086273 | controller | ok
2025-08-19 03:03:23.350555 | controller | changed: .d..t...... ./
2025-08-19 03:03:23.398554 | compute1 | ok
2025-08-19 03:03:23.440052 | 
2025-08-19 03:03:23.440235 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-08-19 03:03:23.491121 | compute1 | skipping: Conditional result was False
2025-08-19 03:03:23.491748 | compute1 | changed: All items complete
2025-08-19 03:03:23.491857 | 
2025-08-19 03:03:23.505996 | compute1 | skipping: Conditional result was False
2025-08-19 03:03:23.534984 | 
2025-08-19 03:03:23.535161 | TASK [Get df disk usage]
2025-08-19 03:03:23.743588 | compute1 | Filesystem     1K-blocks     Used Available Use% Mounted on
2025-08-19 03:03:23.743862 | compute1 | tmpfs             793224      648    792576   1% /run
2025-08-19 03:03:23.743931 | compute1 | /dev/vda1       78070540 23118072  51362500  32% /
2025-08-19 03:03:23.743990 | compute1 | tmpfs            3966116        0   3966116   0% /dev/shm
2025-08-19 03:03:23.744046 | compute1 | tmpfs               5120        0      5120   0% /run/lock
2025-08-19 03:03:23.744100 | compute1 | /dev/sr0             488      488         0 100% /mnt/config
2025-08-19 03:03:23.744154 | compute1 | tmpfs             793220        0    793220   0% /run/user/1000
2025-08-19 03:03:23.744209 | compute1 | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:23.744265 | compute1 | debug1: auto-mux: Trying existing master
2025-08-19 03:03:23.744340 | compute1 | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:23.744399 | compute1 | Shared connection to 158.69.74.227 closed.
2025-08-19 03:03:23.744488 | compute1 | changed
2025-08-19 03:03:23.761085 | controller | Filesystem     1K-blocks     Used Available Use% Mounted on
2025-08-19 03:03:23.761233 | controller | tmpfs             793224      648    792576   1% /run
2025-08-19 03:03:23.761297 | controller | /dev/vda1       78070540 23117880  51362692  32% /
2025-08-19 03:03:23.761355 | controller | tmpfs            3966116        0   3966116   0% /dev/shm
2025-08-19 03:03:23.761458 | controller | tmpfs               5120        0      5120   0% /run/lock
2025-08-19 03:03:23.761557 | controller | /dev/sr0             488      488         0 100% /mnt/config
2025-08-19 03:03:23.761623 | controller | tmpfs             793220        0    793220   0% /run/user/1000
2025-08-19 03:03:23.761679 | controller | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:23.761733 | controller | debug1: auto-mux: Trying existing master
2025-08-19 03:03:23.761792 | controller | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:23.761847 | controller | Shared connection to 158.69.73.106 closed.
2025-08-19 03:03:23.761933 | controller | changed
2025-08-19 03:03:23.772291 | 
2025-08-19 03:03:23.772455 | TASK [Get df inode usage]
2025-08-19 03:03:23.965631 | compute1 | Filesystem       Inodes  IUsed    IFree IUse% Mounted on
2025-08-19 03:03:23.966766 | compute1 | tmpfs            991529    588   990941    1% /run
2025-08-19 03:03:23.966847 | compute1 | /dev/vda1      20910080 607870 20302210    3% /
2025-08-19 03:03:23.966939 | compute1 | tmpfs            991529      1   991528    1% /dev/shm
2025-08-19 03:03:23.967001 | compute1 | tmpfs            991529      2   991527    1% /run/lock
2025-08-19 03:03:23.967059 | compute1 | /dev/sr0              0      0        0     - /mnt/config
2025-08-19 03:03:23.967115 | compute1 | tmpfs            198305     18   198287    1% /run/user/1000
2025-08-19 03:03:23.967174 | compute1 | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:23.967277 | compute1 | debug1: auto-mux: Trying existing master
2025-08-19 03:03:23.967383 | compute1 | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:23.967476 | compute1 | Shared connection to 158.69.74.227 closed.
2025-08-19 03:03:23.967689 | compute1 | changed
2025-08-19 03:03:23.994514 | controller | Filesystem       Inodes  IUsed    IFree IUse% Mounted on
2025-08-19 03:03:23.994698 | controller | tmpfs            991529    588   990941    1% /run
2025-08-19 03:03:23.994763 | controller | /dev/vda1      20910080 607870 20302210    3% /
2025-08-19 03:03:23.994821 | controller | tmpfs            991529      1   991528    1% /dev/shm
2025-08-19 03:03:23.994877 | controller | tmpfs            991529      2   991527    1% /run/lock
2025-08-19 03:03:23.994931 | controller | /dev/sr0              0      0        0     - /mnt/config
2025-08-19 03:03:23.994985 | controller | tmpfs            198305     18   198287    1% /run/user/1000
2025-08-19 03:03:23.995039 | controller | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:23.995092 | controller | debug1: auto-mux: Trying existing master
2025-08-19 03:03:23.995146 | controller | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:23.995200 | controller | Shared connection to 158.69.73.106 closed.
2025-08-19 03:03:23.995275 | controller | changed
2025-08-19 03:03:24.005866 | 
2025-08-19 03:03:24.006029 | TASK [Get l2 networking]
2025-08-19 03:03:24.196099 | compute1 | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.196508 | compute1 |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2025-08-19 03:03:24.196671 | compute1 | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc fq_codel state UP mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.196783 | compute1 |     link/ether fa:16:3e:47:54:5a brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.196919 | compute1 |     altname enp0s3
2025-08-19 03:03:24.197033 | compute1 | 3: ovs-system: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.197142 | compute1 |     link/ether 0a:b6:cb:01:ad:5f brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.197255 | compute1 | 4: br-infra: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1430 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.197366 | compute1 |     link/ether 12:db:0a:f8:67:48 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.197479 | compute1 | 5: vxlan_sys_4789: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 65000 qdisc noqueue master ovs-system state UNKNOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.197639 | compute1 |     link/ether 2a:fd:73:b7:28:6d brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.197749 | compute1 | 6: br-ex: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.197832 | compute1 |     link/ether d2:4d:5b:e7:e8:4e brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.197889 | compute1 | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:24.197944 | compute1 | debug1: auto-mux: Trying existing master
2025-08-19 03:03:24.197998 | compute1 | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:24.198052 | compute1 | Shared connection to 158.69.74.227 closed.
2025-08-19 03:03:24.198152 | compute1 | changed
2025-08-19 03:03:24.225785 | controller | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.225938 | controller |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2025-08-19 03:03:24.226002 | controller | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc fq_codel state UP mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.226059 | controller |     link/ether fa:16:3e:9c:39:a9 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.226114 | controller |     altname enp0s3
2025-08-19 03:03:24.226169 | controller | 3: ovs-system: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.226223 | controller |     link/ether ea:52:2c:2a:03:9c brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.226276 | controller | 4: br-infra: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1430 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.226330 | controller |     link/ether 8a:21:16:9e:50:41 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.226383 | controller | 5: vxlan_sys_4789: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 65000 qdisc noqueue master ovs-system state UNKNOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.226437 | controller |     link/ether 7a:26:2c:fd:7f:09 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.226490 | controller | 6: br-ex: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2025-08-19 03:03:24.226581 | controller |     link/ether 7a:3f:b2:ab:12:4d brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.226639 | controller | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:24.226693 | controller | debug1: auto-mux: Trying existing master
2025-08-19 03:03:24.226746 | controller | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:24.226799 | controller | Shared connection to 158.69.73.106 closed.
2025-08-19 03:03:24.226872 | controller | changed
2025-08-19 03:03:24.235226 | 
2025-08-19 03:03:24.235328 | TASK [Get l3 networking]
2025-08-19 03:03:24.433422 | compute1 | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
2025-08-19 03:03:24.433700 | compute1 |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2025-08-19 03:03:24.433770 | compute1 |     inet 127.0.0.1/8 scope host lo
2025-08-19 03:03:24.433831 | compute1 |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.433889 | compute1 |     inet6 ::1/128 scope host
2025-08-19 03:03:24.433964 | compute1 |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.434047 | compute1 | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc fq_codel state UP group default qlen 1000
2025-08-19 03:03:24.434109 | compute1 |     link/ether fa:16:3e:47:54:5a brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.434165 | compute1 |     altname enp0s3
2025-08-19 03:03:24.434220 | compute1 |     inet 158.69.74.227/32 brd 158.69.74.227 scope global dynamic ens3
2025-08-19 03:03:24.434274 | compute1 |        valid_lft 83626sec preferred_lft 83626sec
2025-08-19 03:03:24.434327 | compute1 |     inet6 2607:5300:201:2000::157/56 scope global
2025-08-19 03:03:24.434381 | compute1 |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.434435 | compute1 |     inet6 fe80::f816:3eff:fe47:545a/64 scope link
2025-08-19 03:03:24.434488 | compute1 |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.434582 | compute1 | 3: ovs-system: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2025-08-19 03:03:24.434641 | compute1 |     link/ether 0a:b6:cb:01:ad:5f brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.434695 | compute1 | 4: br-infra: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1430 qdisc noqueue state UNKNOWN group default qlen 1000
2025-08-19 03:03:24.434759 | compute1 |     link/ether 12:db:0a:f8:67:48 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.434813 | compute1 |     inet 172.24.4.2/23 scope global br-infra
2025-08-19 03:03:24.434866 | compute1 |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.434920 | compute1 |     inet6 fe80::10db:aff:fef8:6748/64 scope link
2025-08-19 03:03:24.434972 | compute1 |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.435025 | compute1 | 5: vxlan_sys_4789: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 65000 qdisc noqueue master ovs-system state UNKNOWN group default qlen 1000
2025-08-19 03:03:24.435079 | compute1 |     link/ether 2a:fd:73:b7:28:6d brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.435132 | compute1 |     inet6 fe80::5cd4:94ff:fe2c:efd5/64 scope link
2025-08-19 03:03:24.435185 | compute1 |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.435238 | compute1 | 6: br-ex: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2025-08-19 03:03:24.435294 | compute1 |     link/ether d2:4d:5b:e7:e8:4e brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.435351 | compute1 | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:24.435405 | compute1 | debug1: auto-mux: Trying existing master
2025-08-19 03:03:24.435457 | compute1 | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:24.435511 | compute1 | Shared connection to 158.69.74.227 closed.
2025-08-19 03:03:24.435635 | compute1 | changed
2025-08-19 03:03:24.452638 | controller | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
2025-08-19 03:03:24.452807 | controller |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2025-08-19 03:03:24.452914 | controller |     inet 127.0.0.1/8 scope host lo
2025-08-19 03:03:24.453020 | controller |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.453126 | controller |     inet6 ::1/128 scope host
2025-08-19 03:03:24.453227 | controller |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.453328 | controller | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc fq_codel state UP group default qlen 1000
2025-08-19 03:03:24.453428 | controller |     link/ether fa:16:3e:9c:39:a9 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.453567 | controller |     altname enp0s3
2025-08-19 03:03:24.453680 | controller |     inet 158.69.73.106/32 brd 158.69.73.106 scope global dynamic ens3
2025-08-19 03:03:24.453781 | controller |        valid_lft 83603sec preferred_lft 83603sec
2025-08-19 03:03:24.453873 | controller |     inet6 2607:5300:201:2000::1a4/56 scope global
2025-08-19 03:03:24.453932 | controller |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.453986 | controller |     inet6 fe80::f816:3eff:fe9c:39a9/64 scope link
2025-08-19 03:03:24.454072 | controller |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.454137 | controller | 3: ovs-system: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2025-08-19 03:03:24.454193 | controller |     link/ether ea:52:2c:2a:03:9c brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.454247 | controller | 4: br-infra: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1430 qdisc noqueue state UNKNOWN group default qlen 1000
2025-08-19 03:03:24.454303 | controller |     link/ether 8a:21:16:9e:50:41 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.454403 | controller |     inet 172.24.4.1/23 scope global br-infra
2025-08-19 03:03:24.454506 | controller |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.454664 | controller |     inet6 fe80::8821:16ff:fe9e:5041/64 scope link
2025-08-19 03:03:24.454766 | controller |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.454863 | controller | 5: vxlan_sys_4789: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 65000 qdisc noqueue master ovs-system state UNKNOWN group default qlen 1000
2025-08-19 03:03:24.454961 | controller |     link/ether 7a:26:2c:fd:7f:09 brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.455062 | controller |     inet6 fe80::e849:abff:feb4:b7b4/64 scope link
2025-08-19 03:03:24.455162 | controller |        valid_lft forever preferred_lft forever
2025-08-19 03:03:24.455261 | controller | 6: br-ex: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2025-08-19 03:03:24.455358 | controller |     link/ether 7a:3f:b2:ab:12:4d brd ff:ff:ff:ff:ff:ff
2025-08-19 03:03:24.455456 | controller | OpenSSH_9.2p1 Debian-2+deb12u7, OpenSSL 3.0.17 1 Jul 2025
2025-08-19 03:03:24.455604 | controller | debug1: auto-mux: Trying existing master
2025-08-19 03:03:24.455706 | controller | debug1: mux_client_request_session: master session id: 2
2025-08-19 03:03:24.455804 | controller | Shared connection to 158.69.73.106 closed.
2025-08-19 03:03:24.455930 | controller | changed
2025-08-19 03:03:24.459118 | 
2025-08-19 03:03:24.459206 | PLAY RECAP
2025-08-19 03:03:24.459300 | compute1 | ok: 7 changed: 6 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-08-19 03:03:24.459369 | controller | ok: 7 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-08-19 03:03:24.459422 | 
2025-08-19 03:03:24.639627 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2025-08-19 03:03:24.669675 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2025-08-19 03:03:26.104477 | 
2025-08-19 03:03:26.104724 | PLAY [all]
2025-08-19 03:03:26.134272 | 
2025-08-19 03:03:26.134506 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-08-19 03:03:26.937667 | controller | changed
2025-08-19 03:03:27.091067 | compute1 | changed
2025-08-19 03:03:27.109030 | 
2025-08-19 03:03:27.109164 | PLAY RECAP
2025-08-19 03:03:27.109294 | compute1 | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-08-19 03:03:27.109368 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-08-19 03:03:27.109422 | 
2025-08-19 03:03:27.268852 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2025-08-19 03:03:27.294692 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post-logs.yaml@master]
2025-08-19 03:03:28.781729 | 
2025-08-19 03:03:28.781948 | PLAY [localhost]
2025-08-19 03:03:28.805972 | 
2025-08-19 03:03:28.806181 | TASK [Include Zuul manifest role]
2025-08-19 03:03:28.841256 | localhost | ok
2025-08-19 03:03:28.868028 | localhost | included: generate-zuul-manifest
2025-08-19 03:03:28.875316 | 
2025-08-19 03:03:28.875484 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-08-19 03:03:29.532306 | localhost | changed
2025-08-19 03:03:29.550730 | 
2025-08-19 03:03:29.550939 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-08-19 03:03:29.597869 | localhost | ok
2025-08-19 03:03:29.612739 | 
2025-08-19 03:03:29.612942 | TASK [Generate bulk log download script]
2025-08-19 03:03:29.638814 | localhost | ok
2025-08-19 03:03:29.662897 | localhost | included: local-log-download
2025-08-19 03:03:29.678634 | 
2025-08-19 03:03:29.678812 | TASK [local-log-download : Check API endpoint is defined]
2025-08-19 03:03:29.732289 | localhost | ok: All assertions passed
2025-08-19 03:03:29.740686 | 
2025-08-19 03:03:29.740949 | TASK [local-log-download : Create download script]
2025-08-19 03:03:30.982482 | localhost -> localhost | changed
2025-08-19 03:03:30.999995 | 
2025-08-19 03:03:31.000160 | LOOP [Select random swift provider for logs upload]
2025-08-19 03:03:31.048422 | localhost | ok: "rax_iad"
2025-08-19 03:03:31.073253 | 
2025-08-19 03:03:31.073485 | TASK [Upload swift logs to rax_iad]
2025-08-19 03:03:31.108512 | localhost | Output suppressed because no_log was given
2025-08-19 03:03:31.209645 | localhost | included: upload-logs-swift
2025-08-19 03:03:31.227471 | 
2025-08-19 03:03:31.227745 | TASK [Set zuul-log-path fact]
2025-08-19 03:03:31.281845 | localhost | ok
2025-08-19 03:03:31.297244 | localhost | included: set-zuul-log-path-fact
2025-08-19 03:03:31.304768 | 
2025-08-19 03:03:31.304944 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-08-19 03:03:31.347197 | localhost | ok
2025-08-19 03:03:31.364314 | 
2025-08-19 03:03:31.364495 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-08-19 03:03:32.004221 | localhost -> localhost | ok: Runtime: 0:00:00.006814
2025-08-19 03:03:32.012453 | 
2025-08-19 03:03:32.012662 | TASK [upload-logs-swift : Upload logs to swift]