2026-05-21 00:00:08.826298 | Job console starting 2026-05-21 00:00:08.842434 | Updating git repos 2026-05-21 00:00:09.100741 | Cloning repos into workspace 2026-05-21 00:00:09.361933 | Restoring repo states 2026-05-21 00:00:09.403176 | Merging changes 2026-05-21 00:00:09.403198 | Checking out repos 2026-05-21 00:00:09.840939 | Preparing playbooks 2026-05-21 00:00:11.357869 | Running Ansible setup 2026-05-21 00:00:20.371812 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 00:00:21.547853 | 2026-05-21 00:00:21.547982 | PLAY [Base pre] 2026-05-21 00:00:21.594765 | 2026-05-21 00:00:21.594897 | TASK [Setup log path fact] 2026-05-21 00:00:21.635525 | orchestrator | ok 2026-05-21 00:00:21.666822 | 2026-05-21 00:00:21.679024 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:00:21.715527 | orchestrator | ok 2026-05-21 00:00:21.732199 | 2026-05-21 00:00:21.732327 | TASK [emit-job-header : Print job information] 2026-05-21 00:00:21.786932 | # Job Information 2026-05-21 00:00:21.787128 | Ansible Version: 2.16.14 2026-05-21 00:00:21.787160 | Job: testbed-deploy-stable-in-a-nutshell-with-tempest-ubuntu-24.04 2026-05-21 00:00:21.787191 | Pipeline: periodic-midnight 2026-05-21 00:00:21.787210 | Executor: 521e9411259a 2026-05-21 00:00:21.787228 | Triggered by: https://github.com/osism/testbed 2026-05-21 00:00:21.787247 | Event ID: 4de47e166b7c47aeaf752c77d16ae21f 2026-05-21 00:00:21.793720 | 2026-05-21 00:00:21.793812 | LOOP [emit-job-header : Print node information] 2026-05-21 00:00:22.103809 | orchestrator | ok: 2026-05-21 00:00:22.104034 | orchestrator | # Node Information 2026-05-21 00:00:22.104069 | orchestrator | Inventory Hostname: orchestrator 2026-05-21 00:00:22.104091 | orchestrator | Hostname: zuul-static-regiocloud-infra-1 2026-05-21 00:00:22.104108 | orchestrator | Username: zuul-testbed04 2026-05-21 00:00:22.104125 | orchestrator | Distro: Debian 12.14 2026-05-21 00:00:22.104144 | orchestrator | Provider: static-testbed 2026-05-21 00:00:22.104161 | orchestrator | Region: 2026-05-21 00:00:22.104178 | orchestrator | Label: testbed-orchestrator 2026-05-21 00:00:22.104194 | orchestrator | Product Name: OpenStack Nova 2026-05-21 00:00:22.104210 | orchestrator | Interface IP: 81.163.193.140 2026-05-21 00:00:22.123769 | 2026-05-21 00:00:22.123872 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 00:00:23.058168 | orchestrator -> localhost | changed 2026-05-21 00:00:23.064891 | 2026-05-21 00:00:23.064981 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 00:00:25.132497 | orchestrator -> localhost | changed 2026-05-21 00:00:25.146944 | 2026-05-21 00:00:25.147040 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 00:00:25.794360 | orchestrator -> localhost | ok 2026-05-21 00:00:25.802045 | 2026-05-21 00:00:25.802159 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 00:00:25.833025 | orchestrator | ok 2026-05-21 00:00:25.862357 | orchestrator | included: /var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 00:00:25.877736 | 2026-05-21 00:00:25.877836 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 00:00:29.349019 | orchestrator -> localhost | Generating public/private rsa key pair. 2026-05-21 00:00:29.349184 | orchestrator -> localhost | Your identification has been saved in /var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/work/d48ac95c22a0451a9f10c2fab57fb3cd_id_rsa 2026-05-21 00:00:29.349216 | orchestrator -> localhost | Your public key has been saved in /var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/work/d48ac95c22a0451a9f10c2fab57fb3cd_id_rsa.pub 2026-05-21 00:00:29.349237 | orchestrator -> localhost | The key fingerprint is: 2026-05-21 00:00:29.349257 | orchestrator -> localhost | SHA256:x4MbiIOhIU5K+tC7nHuQafewkmHTMma9xIPL0aGg9iM zuul-build-sshkey 2026-05-21 00:00:29.349276 | orchestrator -> localhost | The key's randomart image is: 2026-05-21 00:00:29.349325 | orchestrator -> localhost | +---[RSA 3072]----+ 2026-05-21 00:00:29.349346 | orchestrator -> localhost | | | 2026-05-21 00:00:29.349364 | orchestrator -> localhost | | | 2026-05-21 00:00:29.349380 | orchestrator -> localhost | |oo. | 2026-05-21 00:00:29.349396 | orchestrator -> localhost | |O+ o.. . o | 2026-05-21 00:00:29.349412 | orchestrator -> localhost | |*ooXo.. S + | 2026-05-21 00:00:29.349434 | orchestrator -> localhost | |.+^.X. + . | 2026-05-21 00:00:29.349451 | orchestrator -> localhost | |.*+% * . | 2026-05-21 00:00:29.349468 | orchestrator -> localhost | | E==+ . | 2026-05-21 00:00:29.349485 | orchestrator -> localhost | | *+. | 2026-05-21 00:00:29.349501 | orchestrator -> localhost | +----[SHA256]-----+ 2026-05-21 00:00:29.349543 | orchestrator -> localhost | ok: Runtime: 0:00:01.636081 2026-05-21 00:00:29.358638 | 2026-05-21 00:00:29.358727 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 00:00:29.480126 | orchestrator | ok 2026-05-21 00:00:29.493469 | orchestrator | included: /var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 00:00:29.508270 | 2026-05-21 00:00:29.508372 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 00:00:29.558130 | orchestrator | skipping: Conditional result was False 2026-05-21 00:00:29.564032 | 2026-05-21 00:00:29.564132 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 00:00:30.531906 | orchestrator | changed 2026-05-21 00:00:30.536886 | 2026-05-21 00:00:30.536965 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 00:00:30.881743 | orchestrator | ok 2026-05-21 00:00:30.888178 | 2026-05-21 00:00:30.888265 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 00:00:31.396614 | orchestrator | ok 2026-05-21 00:00:31.401413 | 2026-05-21 00:00:31.401496 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 00:00:31.803589 | orchestrator | ok 2026-05-21 00:00:31.809610 | 2026-05-21 00:00:31.809698 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 00:00:31.837153 | orchestrator | skipping: Conditional result was False 2026-05-21 00:00:31.844807 | 2026-05-21 00:00:31.844898 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 00:00:32.446663 | orchestrator -> localhost | changed 2026-05-21 00:00:32.462054 | 2026-05-21 00:00:32.462156 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 00:00:33.357703 | orchestrator -> localhost | Identity added: /var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/work/d48ac95c22a0451a9f10c2fab57fb3cd_id_rsa (zuul-build-sshkey) 2026-05-21 00:00:33.357880 | orchestrator -> localhost | ok: Runtime: 0:00:00.036454 2026-05-21 00:00:33.363886 | 2026-05-21 00:00:33.363966 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 00:00:34.191948 | orchestrator | ok 2026-05-21 00:00:34.196744 | 2026-05-21 00:00:34.196831 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 00:00:34.258485 | orchestrator | skipping: Conditional result was False 2026-05-21 00:00:34.390222 | 2026-05-21 00:00:34.390338 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-21 00:00:35.078610 | orchestrator | ok 2026-05-21 00:00:35.104845 | 2026-05-21 00:00:35.104953 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-21 00:00:35.186968 | orchestrator | ok 2026-05-21 00:00:35.201661 | 2026-05-21 00:00:35.201761 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-21 00:00:35.796550 | orchestrator -> localhost | ok 2026-05-21 00:00:35.802533 | 2026-05-21 00:00:35.802619 | TASK [validate-host : Collect information about the host] 2026-05-21 00:00:37.146340 | orchestrator | ok 2026-05-21 00:00:37.176413 | 2026-05-21 00:00:37.176512 | TASK [validate-host : Sanitize hostname] 2026-05-21 00:00:37.285369 | orchestrator | ok 2026-05-21 00:00:37.291238 | 2026-05-21 00:00:37.291325 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-21 00:00:39.044753 | orchestrator -> localhost | changed 2026-05-21 00:00:39.050088 | 2026-05-21 00:00:39.050189 | TASK [validate-host : Collect information about zuul worker] 2026-05-21 00:00:39.676814 | orchestrator | ok 2026-05-21 00:00:39.681203 | 2026-05-21 00:00:39.681296 | TASK [validate-host : Write out all zuul information for each host] 2026-05-21 00:00:41.155207 | orchestrator -> localhost | changed 2026-05-21 00:00:41.163898 | 2026-05-21 00:00:41.163994 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-21 00:00:41.682081 | orchestrator | ok 2026-05-21 00:00:41.691104 | 2026-05-21 00:00:41.691199 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-21 00:02:04.526855 | orchestrator | changed: 2026-05-21 00:02:04.528933 | orchestrator | .d..t...... src/ 2026-05-21 00:02:04.529019 | orchestrator | .d..t...... src/github.com/ 2026-05-21 00:02:04.529046 | orchestrator | .d..t...... src/github.com/osism/ 2026-05-21 00:02:04.529069 | orchestrator | .d..t...... src/github.com/osism/ansible-collection-commons/ 2026-05-21 00:02:04.529091 | orchestrator | RedHat.yml 2026-05-21 00:02:04.545108 | orchestrator | .L..t...... src/github.com/osism/ansible-collection-commons/roles/repository/tasks/CentOS.yml -> RedHat.yml 2026-05-21 00:02:04.545125 | orchestrator | RedHat.yml 2026-05-21 00:02:04.545178 | orchestrator | localhost | ok: "/var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/work/logs" 2026-05-21 00:02:14.424314 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/work/artifacts" 2026-05-21 00:02:14.802106 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/d48ac95c22a0451a9f10c2fab57fb3cd/work/docs" 2026-05-21 00:02:14.838482 | 2026-05-21 00:02:14.838719 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 00:02:16.177011 | orchestrator | changed: 2026-05-21 00:02:16.177282 | orchestrator | .d..t...... ./ 2026-05-21 00:02:16.177323 | orchestrator | >f+++++++++ sudo-debug.txt 2026-05-21 00:02:16.177355 | orchestrator | >f+++++++++ sudo-strace.log 2026-05-21 00:02:16.177382 | orchestrator | >f+++++++++ sudo-strace.log.time 2026-05-21 00:02:16.177427 | orchestrator | changed: All items complete 2026-05-21 00:02:16.177453 | 2026-05-21 00:02:17.133824 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:02:18.099521 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:02:18.127508 | 2026-05-21 00:02:18.127662 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 00:02:18.192340 | orchestrator | skipping: Conditional result was False 2026-05-21 00:02:18.202052 | orchestrator | skipping: Conditional result was False 2026-05-21 00:02:18.225030 | 2026-05-21 00:02:18.225152 | PLAY RECAP 2026-05-21 00:02:18.225249 | orchestrator | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-21 00:02:18.225278 | 2026-05-21 00:02:18.502175 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-21 00:02:18.503380 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:02:19.652124 | 2026-05-21 00:02:19.652317 | PLAY [Base post] 2026-05-21 00:02:19.670824 | 2026-05-21 00:02:19.671007 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 00:02:20.712550 | orchestrator | changed 2026-05-21 00:02:20.740034 | 2026-05-21 00:02:20.740268 | PLAY RECAP 2026-05-21 00:02:20.740354 | orchestrator | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 00:02:20.740420 | 2026-05-21 00:02:20.972761 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:02:20.973900 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 00:02:22.118474 | 2026-05-21 00:02:22.118661 | PLAY [Base post-logs] 2026-05-21 00:02:22.138572 | 2026-05-21 00:02:22.138750 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 00:02:23.046986 | localhost | changed 2026-05-21 00:02:23.067784 | 2026-05-21 00:02:23.067975 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 00:02:23.099180 | localhost | ok 2026-05-21 00:02:23.110175 | 2026-05-21 00:02:23.110345 | TASK [Set zuul-log-path fact] 2026-05-21 00:02:23.138576 | localhost | ok 2026-05-21 00:02:23.150121 | 2026-05-21 00:02:23.150287 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:02:23.202463 | localhost | ok 2026-05-21 00:02:23.205906 | 2026-05-21 00:02:23.206022 | TASK [upload-logs : Create log directories] 2026-05-21 00:02:23.789416 | localhost | changed 2026-05-21 00:02:23.792646 | 2026-05-21 00:02:23.792771 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 00:02:24.477634 | localhost -> localhost | ok: Runtime: 0:00:00.010651 2026-05-21 00:02:24.488585 | 2026-05-21 00:02:24.488730 | TASK [upload-logs : Upload logs to log server] 2026-05-21 00:02:25.186535 | localhost | Output suppressed because no_log was given 2026-05-21 00:02:25.187949 | 2026-05-21 00:02:25.188029 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 00:02:25.227581 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.232556 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.243222 | 2026-05-21 00:02:25.243312 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 00:02:25.341941 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.342188 | 2026-05-21 00:02:25.350859 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.358611 | 2026-05-21 00:02:25.358709 | LOOP [upload-logs : Upload console log and json output]