2026-05-21 00:02:29.367672 | Job console starting 2026-05-21 00:02:29.390014 | Updating git repos 2026-05-21 00:02:29.489583 | Cloning repos into workspace 2026-05-21 00:02:29.769222 | Restoring repo states 2026-05-21 00:02:29.801886 | Merging changes 2026-05-21 00:02:29.801906 | Checking out repos 2026-05-21 00:02:30.256696 | Preparing playbooks 2026-05-21 00:02:31.336861 | Running Ansible setup 2026-05-21 00:02:36.941216 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 00:02:37.763430 | 2026-05-21 00:02:37.763599 | PLAY [Base pre] 2026-05-21 00:02:37.783257 | 2026-05-21 00:02:37.783422 | TASK [Setup log path fact] 2026-05-21 00:02:37.810552 | orchestrator | ok 2026-05-21 00:02:37.838643 | 2026-05-21 00:02:37.838815 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:02:37.884450 | orchestrator | ok 2026-05-21 00:02:37.904266 | 2026-05-21 00:02:37.904417 | TASK [emit-job-header : Print job information] 2026-05-21 00:02:37.955367 | # Job Information 2026-05-21 00:02:37.955569 | Ansible Version: 2.16.14 2026-05-21 00:02:37.955605 | Job: testbed-deploy-current-in-a-nutshell-with-tempest-ubuntu-24.04 2026-05-21 00:02:37.955638 | Pipeline: periodic-midnight 2026-05-21 00:02:37.955661 | Executor: 521e9411259a 2026-05-21 00:02:37.955681 | Triggered by: https://github.com/osism/testbed 2026-05-21 00:02:37.955702 | Event ID: 4de47e166b7c47aeaf752c77d16ae21f 2026-05-21 00:02:37.962989 | 2026-05-21 00:02:37.963123 | LOOP [emit-job-header : Print node information] 2026-05-21 00:02:38.081602 | orchestrator | ok: 2026-05-21 00:02:38.081793 | orchestrator | # Node Information 2026-05-21 00:02:38.081826 | orchestrator | Inventory Hostname: orchestrator 2026-05-21 00:02:38.081851 | orchestrator | Hostname: zuul-static-regiocloud-infra-1 2026-05-21 00:02:38.081873 | orchestrator | Username: zuul-testbed03 2026-05-21 00:02:38.081893 | orchestrator | Distro: Debian 12.14 2026-05-21 00:02:38.081916 | orchestrator | Provider: static-testbed 2026-05-21 00:02:38.081937 | orchestrator | Region: 2026-05-21 00:02:38.081958 | orchestrator | Label: testbed-orchestrator 2026-05-21 00:02:38.081977 | orchestrator | Product Name: OpenStack Nova 2026-05-21 00:02:38.081996 | orchestrator | Interface IP: 81.163.193.140 2026-05-21 00:02:38.101066 | 2026-05-21 00:02:38.101249 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 00:02:38.646143 | orchestrator -> localhost | changed 2026-05-21 00:02:38.669054 | 2026-05-21 00:02:38.669273 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 00:02:39.912647 | orchestrator -> localhost | changed 2026-05-21 00:02:39.929474 | 2026-05-21 00:02:39.929608 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 00:02:40.238285 | orchestrator -> localhost | ok 2026-05-21 00:02:40.246955 | 2026-05-21 00:02:40.247095 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 00:02:40.284567 | orchestrator | ok 2026-05-21 00:02:40.307899 | orchestrator | included: /var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 00:02:40.318553 | 2026-05-21 00:02:40.318713 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 00:02:41.728706 | orchestrator -> localhost | Generating public/private rsa key pair. 2026-05-21 00:02:41.729038 | orchestrator -> localhost | Your identification has been saved in /var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/work/ddb38349e1fa40eb81a2f8f96bdd8fdd_id_rsa 2026-05-21 00:02:41.729082 | orchestrator -> localhost | Your public key has been saved in /var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/work/ddb38349e1fa40eb81a2f8f96bdd8fdd_id_rsa.pub 2026-05-21 00:02:41.729245 | orchestrator -> localhost | The key fingerprint is: 2026-05-21 00:02:41.729278 | orchestrator -> localhost | SHA256:AtJHLzDaMeBIAV6HR3uzwsy/XE3yB1YudMSjVQGwZy8 zuul-build-sshkey 2026-05-21 00:02:41.729305 | orchestrator -> localhost | The key's randomart image is: 2026-05-21 00:02:41.729361 | orchestrator -> localhost | +---[RSA 3072]----+ 2026-05-21 00:02:41.729396 | orchestrator -> localhost | |ooooBo. .oooo.| 2026-05-21 00:02:41.729426 | orchestrator -> localhost | |oo.=.B.. o+ | 2026-05-21 00:02:41.729449 | orchestrator -> localhost | |..+ =.oo. oo=. | 2026-05-21 00:02:41.729471 | orchestrator -> localhost | | .+o..o ..* . | 2026-05-21 00:02:41.729492 | orchestrator -> localhost | | =..S. = E . | 2026-05-21 00:02:41.729527 | orchestrator -> localhost | | o. * o . | 2026-05-21 00:02:41.729548 | orchestrator -> localhost | | . . o . | 2026-05-21 00:02:41.729568 | orchestrator -> localhost | | . o . | 2026-05-21 00:02:41.729588 | orchestrator -> localhost | | o | 2026-05-21 00:02:41.729608 | orchestrator -> localhost | +----[SHA256]-----+ 2026-05-21 00:02:41.729702 | orchestrator -> localhost | ok: Runtime: 0:00:00.847510 2026-05-21 00:02:41.739442 | 2026-05-21 00:02:41.739588 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 00:02:41.773007 | orchestrator | ok 2026-05-21 00:02:41.787450 | orchestrator | included: /var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 00:02:41.800331 | 2026-05-21 00:02:41.800464 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 00:02:41.836834 | orchestrator | skipping: Conditional result was False 2026-05-21 00:02:41.845597 | 2026-05-21 00:02:41.845739 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 00:02:42.489922 | orchestrator | changed 2026-05-21 00:02:42.497936 | 2026-05-21 00:02:42.498052 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 00:02:42.788702 | orchestrator | ok 2026-05-21 00:02:42.799019 | 2026-05-21 00:02:42.799136 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 00:02:43.220887 | orchestrator | ok 2026-05-21 00:02:43.232464 | 2026-05-21 00:02:43.232630 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 00:02:43.633517 | orchestrator | ok 2026-05-21 00:02:43.642668 | 2026-05-21 00:02:43.642802 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 00:02:43.677378 | orchestrator | skipping: Conditional result was False 2026-05-21 00:02:43.685833 | 2026-05-21 00:02:43.685958 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 00:02:44.196281 | orchestrator -> localhost | changed 2026-05-21 00:02:44.216110 | 2026-05-21 00:02:44.216320 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 00:02:44.588080 | orchestrator -> localhost | Identity added: /var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/work/ddb38349e1fa40eb81a2f8f96bdd8fdd_id_rsa (zuul-build-sshkey) 2026-05-21 00:02:44.588388 | orchestrator -> localhost | ok: Runtime: 0:00:00.015178 2026-05-21 00:02:44.604462 | 2026-05-21 00:02:44.604601 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 00:02:45.051931 | orchestrator | ok 2026-05-21 00:02:45.060793 | 2026-05-21 00:02:45.060937 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 00:02:45.086379 | orchestrator | skipping: Conditional result was False 2026-05-21 00:02:45.148248 | 2026-05-21 00:02:45.148395 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-21 00:02:45.552626 | orchestrator | ok 2026-05-21 00:02:45.571583 | 2026-05-21 00:02:45.571739 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-21 00:02:45.612025 | orchestrator | ok 2026-05-21 00:02:45.620221 | 2026-05-21 00:02:45.620355 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-21 00:02:45.918232 | orchestrator -> localhost | ok 2026-05-21 00:02:45.926473 | 2026-05-21 00:02:45.926594 | TASK [validate-host : Collect information about the host] 2026-05-21 00:02:47.188342 | orchestrator | ok 2026-05-21 00:02:47.221372 | 2026-05-21 00:02:47.221711 | TASK [validate-host : Sanitize hostname] 2026-05-21 00:02:47.321127 | orchestrator | ok 2026-05-21 00:02:47.331121 | 2026-05-21 00:02:47.331298 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-21 00:02:48.108119 | orchestrator -> localhost | changed 2026-05-21 00:02:48.115130 | 2026-05-21 00:02:48.115299 | TASK [validate-host : Collect information about zuul worker] 2026-05-21 00:02:48.595785 | orchestrator | ok 2026-05-21 00:02:48.605641 | 2026-05-21 00:02:48.605773 | TASK [validate-host : Write out all zuul information for each host] 2026-05-21 00:02:49.817585 | orchestrator -> localhost | changed 2026-05-21 00:02:49.837496 | 2026-05-21 00:02:49.838359 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-21 00:02:50.179831 | orchestrator | ok 2026-05-21 00:02:50.195245 | 2026-05-21 00:02:50.195414 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-21 00:04:09.318857 | orchestrator | changed: 2026-05-21 00:04:09.319097 | orchestrator | .d..t...... src/ 2026-05-21 00:04:09.319149 | orchestrator | .d..t...... src/github.com/ 2026-05-21 00:04:09.319174 | orchestrator | .d..t...... src/github.com/osism/ 2026-05-21 00:04:09.319197 | orchestrator | .d..t...... src/github.com/osism/ansible-collection-commons/ 2026-05-21 00:04:09.319219 | orchestrator | RedHat.yml 2026-05-21 00:04:09.340026 | orchestrator | .L..t...... src/github.com/osism/ansible-collection-commons/roles/repository/tasks/CentOS.yml -> RedHat.yml 2026-05-21 00:04:09.340044 | orchestrator | RedHat.yml 2026-05-21 00:04:09.340099 | orchestrator | localhost | ok: "/var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/work/logs" 2026-05-21 00:04:19.998039 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/work/artifacts" 2026-05-21 00:04:20.318236 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/ddb38349e1fa40eb81a2f8f96bdd8fdd/work/docs" 2026-05-21 00:04:20.348620 | 2026-05-21 00:04:20.348885 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 00:04:21.311833 | orchestrator | changed: 2026-05-21 00:04:21.312116 | orchestrator | .d..t...... ./ 2026-05-21 00:04:21.312158 | orchestrator | >f+++++++++ sudo-debug.txt 2026-05-21 00:04:21.312190 | orchestrator | >f+++++++++ sudo-strace.log 2026-05-21 00:04:21.312218 | orchestrator | >f+++++++++ sudo-strace.log.time 2026-05-21 00:04:21.312257 | orchestrator | changed: All items complete 2026-05-21 00:04:21.312280 | 2026-05-21 00:04:22.034750 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:04:22.755250 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:04:22.785709 | 2026-05-21 00:04:22.785879 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 00:04:22.827436 | orchestrator | skipping: Conditional result was False 2026-05-21 00:04:22.830509 | orchestrator | skipping: Conditional result was False 2026-05-21 00:04:22.839616 | 2026-05-21 00:04:22.839715 | PLAY RECAP 2026-05-21 00:04:22.839773 | orchestrator | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-21 00:04:22.839798 | 2026-05-21 00:04:22.980026 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-21 00:04:22.981361 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:04:23.734240 | 2026-05-21 00:04:23.734418 | PLAY [Base post] 2026-05-21 00:04:23.749971 | 2026-05-21 00:04:23.750149 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 00:04:25.238004 | orchestrator | changed 2026-05-21 00:04:25.247277 | 2026-05-21 00:04:25.247399 | PLAY RECAP 2026-05-21 00:04:25.247464 | orchestrator | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 00:04:25.247527 | 2026-05-21 00:04:25.403231 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:04:25.405114 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 00:04:26.238871 | 2026-05-21 00:04:26.239045 | PLAY [Base post-logs] 2026-05-21 00:04:26.250232 | 2026-05-21 00:04:26.250384 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 00:04:26.697371 | localhost | changed 2026-05-21 00:04:26.716652 | 2026-05-21 00:04:26.716864 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 00:04:26.757077 | localhost | ok 2026-05-21 00:04:26.763231 | 2026-05-21 00:04:26.763393 | TASK [Set zuul-log-path fact] 2026-05-21 00:04:26.793559 | localhost | ok 2026-05-21 00:04:26.810213 | 2026-05-21 00:04:26.810379 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:04:26.848469 | localhost | ok 2026-05-21 00:04:26.853636 | 2026-05-21 00:04:26.853791 | TASK [upload-logs : Create log directories] 2026-05-21 00:04:27.379990 | localhost | changed 2026-05-21 00:04:27.383147 | 2026-05-21 00:04:27.383259 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 00:04:27.920724 | localhost -> localhost | ok: Runtime: 0:00:00.009915 2026-05-21 00:04:27.928256 | 2026-05-21 00:04:27.928415 | TASK [upload-logs : Upload logs to log server] 2026-05-21 00:04:28.504245 | localhost | Output suppressed because no_log was given 2026-05-21 00:04:28.506482 | 2026-05-21 00:04:28.506607 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 00:04:28.563150 | localhost | skipping: Conditional result was False 2026-05-21 00:04:28.571383 | localhost | skipping: Conditional result was False 2026-05-21 00:04:28.576299 | 2026-05-21 00:04:28.576416 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 00:04:28.627030 | localhost | skipping: Conditional result was False 2026-05-21 00:04:28.627375 | 2026-05-21 00:04:28.632830 | localhost | skipping: Conditional result was False 2026-05-21 00:04:28.645335 | 2026-05-21 00:04:28.645533 | LOOP [upload-logs : Upload console log and json output]