2026-05-21 00:00:08.451937 | Job console starting 2026-05-21 00:00:08.471600 | Updating git repos 2026-05-21 00:00:08.565253 | Cloning repos into workspace 2026-05-21 00:00:08.883473 | Restoring repo states 2026-05-21 00:00:08.919402 | Merging changes 2026-05-21 00:00:08.919421 | Checking out repos 2026-05-21 00:00:09.497147 | Preparing playbooks 2026-05-21 00:00:11.006231 | Running Ansible setup 2026-05-21 00:00:19.410821 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 00:00:20.730236 | 2026-05-21 00:00:20.730389 | PLAY [Base pre] 2026-05-21 00:00:20.775024 | 2026-05-21 00:00:20.775139 | TASK [Setup log path fact] 2026-05-21 00:00:20.829799 | orchestrator | ok 2026-05-21 00:00:20.853915 | 2026-05-21 00:00:20.854281 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:00:20.887489 | orchestrator | ok 2026-05-21 00:00:20.900159 | 2026-05-21 00:00:20.900347 | TASK [emit-job-header : Print job information] 2026-05-21 00:00:20.966560 | # Job Information 2026-05-21 00:00:20.966749 | Ansible Version: 2.16.14 2026-05-21 00:00:20.966788 | Job: testbed-deploy-current-in-a-nutshell-with-tempest-ubuntu-24.04 2026-05-21 00:00:20.966817 | Pipeline: periodic-midnight 2026-05-21 00:00:20.966858 | Executor: 521e9411259a 2026-05-21 00:00:20.966878 | Triggered by: https://github.com/osism/testbed 2026-05-21 00:00:20.966897 | Event ID: 4de47e166b7c47aeaf752c77d16ae21f 2026-05-21 00:00:20.973270 | 2026-05-21 00:00:20.973369 | LOOP [emit-job-header : Print node information] 2026-05-21 00:00:21.385994 | orchestrator | ok: 2026-05-21 00:00:21.386239 | orchestrator | # Node Information 2026-05-21 00:00:21.386281 | orchestrator | Inventory Hostname: orchestrator 2026-05-21 00:00:21.386498 | orchestrator | Hostname: zuul-static-regiocloud-infra-1 2026-05-21 00:00:21.386527 | orchestrator | Username: zuul-testbed06 2026-05-21 00:00:21.386549 | orchestrator | Distro: Debian 12.14 2026-05-21 00:00:21.386609 | orchestrator | Provider: static-testbed 2026-05-21 00:00:21.386672 | orchestrator | Region: 2026-05-21 00:00:21.386692 | orchestrator | Label: testbed-orchestrator 2026-05-21 00:00:21.386711 | orchestrator | Product Name: OpenStack Nova 2026-05-21 00:00:21.386728 | orchestrator | Interface IP: 81.163.193.140 2026-05-21 00:00:21.405837 | 2026-05-21 00:00:21.405938 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 00:00:22.767638 | orchestrator -> localhost | changed 2026-05-21 00:00:22.775254 | 2026-05-21 00:00:22.775379 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 00:00:25.081987 | orchestrator -> localhost | changed 2026-05-21 00:00:25.096894 | 2026-05-21 00:00:25.096994 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 00:00:25.812309 | orchestrator -> localhost | ok 2026-05-21 00:00:25.817889 | 2026-05-21 00:00:25.817985 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 00:00:25.865798 | orchestrator | ok 2026-05-21 00:00:25.896856 | orchestrator | included: /var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/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.935286 | 2026-05-21 00:00:25.935416 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 00:00:29.352712 | orchestrator -> localhost | Generating public/private rsa key pair. 2026-05-21 00:00:29.352883 | orchestrator -> localhost | Your identification has been saved in /var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/work/f733d4244afb42d1bfe595505261e620_id_rsa 2026-05-21 00:00:29.352994 | orchestrator -> localhost | Your public key has been saved in /var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/work/f733d4244afb42d1bfe595505261e620_id_rsa.pub 2026-05-21 00:00:29.353025 | orchestrator -> localhost | The key fingerprint is: 2026-05-21 00:00:29.353046 | orchestrator -> localhost | SHA256:8WTPj640e5o8FxP0uPfsN+TBnnQxh8hlKfbjJY+zemw zuul-build-sshkey 2026-05-21 00:00:29.353066 | orchestrator -> localhost | The key's randomart image is: 2026-05-21 00:00:29.353093 | orchestrator -> localhost | +---[RSA 3072]----+ 2026-05-21 00:00:29.353113 | orchestrator -> localhost | | . | 2026-05-21 00:00:29.353130 | orchestrator -> localhost | | + + | 2026-05-21 00:00:29.353147 | orchestrator -> localhost | | . o+ O . | 2026-05-21 00:00:29.353163 | orchestrator -> localhost | | = o= *oo| 2026-05-21 00:00:29.353180 | orchestrator -> localhost | | S . o+.*+| 2026-05-21 00:00:29.353229 | orchestrator -> localhost | | +o==o| 2026-05-21 00:00:29.353284 | orchestrator -> localhost | | o .=*=+| 2026-05-21 00:00:29.353315 | orchestrator -> localhost | | o.=o E=+| 2026-05-21 00:00:29.353335 | orchestrator -> localhost | | **o+ .+| 2026-05-21 00:00:29.353352 | orchestrator -> localhost | +----[SHA256]-----+ 2026-05-21 00:00:29.353401 | orchestrator -> localhost | ok: Runtime: 0:00:01.781109 2026-05-21 00:00:29.359842 | 2026-05-21 00:00:29.359998 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 00:00:29.407827 | orchestrator | ok 2026-05-21 00:00:29.460898 | orchestrator | included: /var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 00:00:29.510042 | 2026-05-21 00:00:29.510143 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 00:00:29.577418 | orchestrator | skipping: Conditional result was False 2026-05-21 00:00:29.585056 | 2026-05-21 00:00:29.585159 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 00:00:30.905601 | orchestrator | changed 2026-05-21 00:00:30.911529 | 2026-05-21 00:00:30.911616 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 00:00:31.208426 | orchestrator | ok 2026-05-21 00:00:31.225230 | 2026-05-21 00:00:31.225361 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 00:00:31.655368 | orchestrator | ok 2026-05-21 00:00:31.679191 | 2026-05-21 00:00:31.679283 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 00:00:32.095951 | orchestrator | ok 2026-05-21 00:00:32.101061 | 2026-05-21 00:00:32.101141 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 00:00:32.125744 | orchestrator | skipping: Conditional result was False 2026-05-21 00:00:32.138434 | 2026-05-21 00:00:32.138531 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 00:00:32.859122 | orchestrator -> localhost | changed 2026-05-21 00:00:32.883770 | 2026-05-21 00:00:32.883871 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 00:00:34.031882 | orchestrator -> localhost | Identity added: /var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/work/f733d4244afb42d1bfe595505261e620_id_rsa (zuul-build-sshkey) 2026-05-21 00:00:34.032066 | orchestrator -> localhost | ok: Runtime: 0:00:00.045272 2026-05-21 00:00:34.037991 | 2026-05-21 00:00:34.038076 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 00:00:34.743196 | orchestrator | ok 2026-05-21 00:00:34.761593 | 2026-05-21 00:00:34.761698 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 00:00:34.815101 | orchestrator | skipping: Conditional result was False 2026-05-21 00:00:34.933252 | 2026-05-21 00:00:34.933386 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-21 00:00:35.649814 | orchestrator | ok 2026-05-21 00:00:35.674904 | 2026-05-21 00:00:35.675014 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-21 00:00:35.715754 | orchestrator | ok 2026-05-21 00:00:35.727998 | 2026-05-21 00:00:35.728377 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-21 00:00:36.263315 | orchestrator -> localhost | ok 2026-05-21 00:00:36.269624 | 2026-05-21 00:00:36.269715 | TASK [validate-host : Collect information about the host] 2026-05-21 00:00:37.766313 | orchestrator | ok 2026-05-21 00:00:37.809242 | 2026-05-21 00:00:37.809368 | TASK [validate-host : Sanitize hostname] 2026-05-21 00:00:37.966893 | orchestrator | ok 2026-05-21 00:00:37.971418 | 2026-05-21 00:00:37.971501 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-21 00:00:39.431634 | orchestrator -> localhost | changed 2026-05-21 00:00:39.436689 | 2026-05-21 00:00:39.436773 | TASK [validate-host : Collect information about zuul worker] 2026-05-21 00:00:40.021726 | orchestrator | ok 2026-05-21 00:00:40.026314 | 2026-05-21 00:00:40.026396 | TASK [validate-host : Write out all zuul information for each host] 2026-05-21 00:00:41.543906 | orchestrator -> localhost | changed 2026-05-21 00:00:41.552410 | 2026-05-21 00:00:41.552497 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-21 00:00:41.856587 | orchestrator | ok 2026-05-21 00:00:41.861590 | 2026-05-21 00:00:41.861683 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-21 00:02:05.001719 | orchestrator | changed: 2026-05-21 00:02:05.003364 | orchestrator | .d..t...... src/ 2026-05-21 00:02:05.003425 | orchestrator | .d..t...... src/github.com/ 2026-05-21 00:02:05.003451 | orchestrator | .d..t...... src/github.com/osism/ 2026-05-21 00:02:05.003472 | orchestrator | .d..t...... src/github.com/osism/ansible-collection-commons/ 2026-05-21 00:02:05.003493 | orchestrator | RedHat.yml 2026-05-21 00:02:05.020486 | orchestrator | .L..t...... src/github.com/osism/ansible-collection-commons/roles/repository/tasks/CentOS.yml -> RedHat.yml 2026-05-21 00:02:05.020503 | orchestrator | RedHat.yml 2026-05-21 00:02:05.020555 | orchestrator | localhost | ok: "/var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/work/logs" 2026-05-21 00:02:14.443774 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/work/artifacts" 2026-05-21 00:02:14.936638 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/f733d4244afb42d1bfe595505261e620/work/docs" 2026-05-21 00:02:14.966811 | 2026-05-21 00:02:14.973684 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 00:02:16.394785 | orchestrator | changed: 2026-05-21 00:02:16.395140 | orchestrator | .d..t...... ./ 2026-05-21 00:02:16.395181 | orchestrator | >f+++++++++ sudo-debug.txt 2026-05-21 00:02:16.395230 | orchestrator | >f+++++++++ sudo-strace.log 2026-05-21 00:02:16.395259 | orchestrator | >f+++++++++ sudo-strace.log.time 2026-05-21 00:02:16.395305 | orchestrator | changed: All items complete 2026-05-21 00:02:16.395332 | 2026-05-21 00:02:17.228077 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:02:18.414308 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:02:18.444101 | 2026-05-21 00:02:18.451958 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 00:02:18.529538 | orchestrator | skipping: Conditional result was False 2026-05-21 00:02:18.553878 | orchestrator | skipping: Conditional result was False 2026-05-21 00:02:18.569196 | 2026-05-21 00:02:18.569401 | PLAY RECAP 2026-05-21 00:02:18.569462 | orchestrator | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-21 00:02:18.569488 | 2026-05-21 00:02:18.739487 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-21 00:02:18.741835 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:02:19.657623 | 2026-05-21 00:02:19.657788 | PLAY [Base post] 2026-05-21 00:02:19.676286 | 2026-05-21 00:02:19.676448 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 00:02:20.846692 | orchestrator | changed 2026-05-21 00:02:20.866611 | 2026-05-21 00:02:20.867754 | PLAY RECAP 2026-05-21 00:02:20.867900 | orchestrator | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 00:02:20.868019 | 2026-05-21 00:02:21.217645 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:02:21.218775 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 00:02:22.245533 | 2026-05-21 00:02:22.245723 | PLAY [Base post-logs] 2026-05-21 00:02:22.265985 | 2026-05-21 00:02:22.266159 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 00:02:23.187756 | localhost | changed 2026-05-21 00:02:23.198695 | 2026-05-21 00:02:23.198873 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 00:02:23.249301 | localhost | ok 2026-05-21 00:02:23.252965 | 2026-05-21 00:02:23.253077 | TASK [Set zuul-log-path fact] 2026-05-21 00:02:23.279514 | localhost | ok 2026-05-21 00:02:23.293579 | 2026-05-21 00:02:23.293967 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:02:23.332185 | localhost | ok 2026-05-21 00:02:23.347434 | 2026-05-21 00:02:23.347863 | TASK [upload-logs : Create log directories] 2026-05-21 00:02:23.978563 | localhost | changed 2026-05-21 00:02:23.981833 | 2026-05-21 00:02:23.981951 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 00:02:24.837686 | localhost -> localhost | ok: Runtime: 0:00:00.013778 2026-05-21 00:02:24.841448 | 2026-05-21 00:02:24.841546 | TASK [upload-logs : Upload logs to log server] 2026-05-21 00:02:25.585322 | localhost | Output suppressed because no_log was given 2026-05-21 00:02:25.588149 | 2026-05-21 00:02:25.588269 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 00:02:25.636663 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.642722 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.653801 | 2026-05-21 00:02:25.653902 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 00:02:25.712976 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.713207 | 2026-05-21 00:02:25.718684 | localhost | skipping: Conditional result was False 2026-05-21 00:02:25.729679 | 2026-05-21 00:02:25.729783 | LOOP [upload-logs : Upload console log and json output]