2026-05-21 00:04:31.000148 | Job console starting 2026-05-21 00:04:31.021145 | Updating git repos 2026-05-21 00:04:31.109670 | Cloning repos into workspace 2026-05-21 00:04:31.356210 | Restoring repo states 2026-05-21 00:04:31.398177 | Merging changes 2026-05-21 00:04:31.398221 | Checking out repos 2026-05-21 00:04:31.787795 | Preparing playbooks 2026-05-21 00:04:32.820209 | Running Ansible setup 2026-05-21 00:04:37.133297 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 00:04:37.980288 | 2026-05-21 00:04:37.980462 | PLAY [Base pre] 2026-05-21 00:04:38.025264 | 2026-05-21 00:04:38.025502 | TASK [Setup log path fact] 2026-05-21 00:04:38.061843 | orchestrator | ok 2026-05-21 00:04:38.081116 | 2026-05-21 00:04:38.081284 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:04:38.111899 | orchestrator | ok 2026-05-21 00:04:38.124760 | 2026-05-21 00:04:38.124895 | TASK [emit-job-header : Print job information] 2026-05-21 00:04:38.187737 | # Job Information 2026-05-21 00:04:38.188057 | Ansible Version: 2.16.14 2026-05-21 00:04:38.188148 | Job: testbed-deploy-stable-in-a-nutshell-with-tempest-ubuntu-24.04 2026-05-21 00:04:38.188210 | Pipeline: periodic-midnight 2026-05-21 00:04:38.188448 | Executor: 521e9411259a 2026-05-21 00:04:38.188492 | Triggered by: https://github.com/osism/testbed 2026-05-21 00:04:38.188541 | Event ID: 4de47e166b7c47aeaf752c77d16ae21f 2026-05-21 00:04:38.199024 | 2026-05-21 00:04:38.199226 | LOOP [emit-job-header : Print node information] 2026-05-21 00:04:38.328413 | orchestrator | ok: 2026-05-21 00:04:38.328712 | orchestrator | # Node Information 2026-05-21 00:04:38.328749 | orchestrator | Inventory Hostname: orchestrator 2026-05-21 00:04:38.328774 | orchestrator | Hostname: zuul-static-regiocloud-infra-1 2026-05-21 00:04:38.328796 | orchestrator | Username: zuul-testbed04 2026-05-21 00:04:38.328817 | orchestrator | Distro: Debian 12.14 2026-05-21 00:04:38.328841 | orchestrator | Provider: static-testbed 2026-05-21 00:04:38.328862 | orchestrator | Region: 2026-05-21 00:04:38.328884 | orchestrator | Label: testbed-orchestrator 2026-05-21 00:04:38.328904 | orchestrator | Product Name: OpenStack Nova 2026-05-21 00:04:38.328923 | orchestrator | Interface IP: 81.163.193.140 2026-05-21 00:04:38.349425 | 2026-05-21 00:04:38.349676 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 00:04:38.901001 | orchestrator -> localhost | changed 2026-05-21 00:04:38.909748 | 2026-05-21 00:04:38.909879 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 00:04:40.939031 | orchestrator -> localhost | changed 2026-05-21 00:04:40.957366 | 2026-05-21 00:04:40.957475 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 00:04:41.405711 | orchestrator -> localhost | ok 2026-05-21 00:04:41.412878 | 2026-05-21 00:04:41.412983 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 00:04:41.441530 | orchestrator | ok 2026-05-21 00:04:41.475876 | orchestrator | included: /var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 00:04:41.482816 | 2026-05-21 00:04:41.482952 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 00:04:43.295164 | orchestrator -> localhost | Generating public/private rsa key pair. 2026-05-21 00:04:43.295355 | orchestrator -> localhost | Your identification has been saved in /var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/work/28a528797c5541f3b7106f758bfa23ca_id_rsa 2026-05-21 00:04:43.295394 | orchestrator -> localhost | Your public key has been saved in /var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/work/28a528797c5541f3b7106f758bfa23ca_id_rsa.pub 2026-05-21 00:04:43.295422 | orchestrator -> localhost | The key fingerprint is: 2026-05-21 00:04:43.295447 | orchestrator -> localhost | SHA256:sg1BQUtZkhQhpcu2PsLgDRT9IF4ZF884Z7X6yVhDio0 zuul-build-sshkey 2026-05-21 00:04:43.295470 | orchestrator -> localhost | The key's randomart image is: 2026-05-21 00:04:43.295500 | orchestrator -> localhost | +---[RSA 3072]----+ 2026-05-21 00:04:43.295523 | orchestrator -> localhost | | ..+*%B.. | 2026-05-21 00:04:43.295544 | orchestrator -> localhost | | o =.=*o. . | 2026-05-21 00:04:43.295577 | orchestrator -> localhost | |. + +oo= o | 2026-05-21 00:04:43.295600 | orchestrator -> localhost | | o . oB.+ | 2026-05-21 00:04:43.295621 | orchestrator -> localhost | |. +Eo+So | 2026-05-21 00:04:43.295649 | orchestrator -> localhost | |.. . . == o | 2026-05-21 00:04:43.295672 | orchestrator -> localhost | |.oo . ...+ | 2026-05-21 00:04:43.295694 | orchestrator -> localhost | | .oo. | 2026-05-21 00:04:43.295715 | orchestrator -> localhost | | ... | 2026-05-21 00:04:43.295735 | orchestrator -> localhost | +----[SHA256]-----+ 2026-05-21 00:04:43.295783 | orchestrator -> localhost | ok: Runtime: 0:00:00.997515 2026-05-21 00:04:43.303786 | 2026-05-21 00:04:43.303886 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 00:04:43.333311 | orchestrator | ok 2026-05-21 00:04:43.343413 | orchestrator | included: /var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 00:04:43.354102 | 2026-05-21 00:04:43.354201 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 00:04:43.377221 | orchestrator | skipping: Conditional result was False 2026-05-21 00:04:43.384179 | 2026-05-21 00:04:43.384275 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 00:04:43.973163 | orchestrator | changed 2026-05-21 00:04:43.979645 | 2026-05-21 00:04:43.979731 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 00:04:44.240681 | orchestrator | ok 2026-05-21 00:04:44.248216 | 2026-05-21 00:04:44.248313 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 00:04:44.699023 | orchestrator | ok 2026-05-21 00:04:44.708160 | 2026-05-21 00:04:44.708263 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 00:04:45.121892 | orchestrator | ok 2026-05-21 00:04:45.134294 | 2026-05-21 00:04:45.134395 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 00:04:45.160455 | orchestrator | skipping: Conditional result was False 2026-05-21 00:04:45.167358 | 2026-05-21 00:04:45.167465 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 00:04:45.645425 | orchestrator -> localhost | changed 2026-05-21 00:04:45.661840 | 2026-05-21 00:04:45.661954 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 00:04:46.081536 | orchestrator -> localhost | Identity added: /var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/work/28a528797c5541f3b7106f758bfa23ca_id_rsa (zuul-build-sshkey) 2026-05-21 00:04:46.081774 | orchestrator -> localhost | ok: Runtime: 0:00:00.019452 2026-05-21 00:04:46.095922 | 2026-05-21 00:04:46.096206 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 00:04:46.535668 | orchestrator | ok 2026-05-21 00:04:46.547696 | 2026-05-21 00:04:46.548382 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 00:04:46.596643 | orchestrator | skipping: Conditional result was False 2026-05-21 00:04:46.701503 | 2026-05-21 00:04:46.701710 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-21 00:04:47.211785 | orchestrator | ok 2026-05-21 00:04:47.244450 | 2026-05-21 00:04:47.244552 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-21 00:04:47.286665 | orchestrator | ok 2026-05-21 00:04:47.292269 | 2026-05-21 00:04:47.292352 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-21 00:04:47.582787 | orchestrator -> localhost | ok 2026-05-21 00:04:47.593829 | 2026-05-21 00:04:47.593928 | TASK [validate-host : Collect information about the host] 2026-05-21 00:04:48.764496 | orchestrator | ok 2026-05-21 00:04:48.778322 | 2026-05-21 00:04:48.778429 | TASK [validate-host : Sanitize hostname] 2026-05-21 00:04:48.842781 | orchestrator | ok 2026-05-21 00:04:48.851890 | 2026-05-21 00:04:48.852021 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-21 00:04:49.367413 | orchestrator -> localhost | changed 2026-05-21 00:04:49.373413 | 2026-05-21 00:04:49.373505 | TASK [validate-host : Collect information about zuul worker] 2026-05-21 00:04:49.781003 | orchestrator | ok 2026-05-21 00:04:49.786692 | 2026-05-21 00:04:49.786799 | TASK [validate-host : Write out all zuul information for each host] 2026-05-21 00:04:50.354502 | orchestrator -> localhost | changed 2026-05-21 00:04:50.381435 | 2026-05-21 00:04:50.381590 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-21 00:04:50.673636 | orchestrator | ok 2026-05-21 00:04:50.679590 | 2026-05-21 00:04:50.679683 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-21 00:06:02.247166 | orchestrator | changed: 2026-05-21 00:06:02.247430 | orchestrator | .d..t...... src/ 2026-05-21 00:06:02.247473 | orchestrator | .d..t...... src/github.com/ 2026-05-21 00:06:02.247505 | orchestrator | .d..t...... src/github.com/osism/ 2026-05-21 00:06:02.247532 | orchestrator | .d..t...... src/github.com/osism/ansible-collection-commons/ 2026-05-21 00:06:02.247556 | orchestrator | RedHat.yml 2026-05-21 00:06:02.264245 | orchestrator | .L..t...... src/github.com/osism/ansible-collection-commons/roles/repository/tasks/CentOS.yml -> RedHat.yml 2026-05-21 00:06:02.264262 | orchestrator | RedHat.yml 2026-05-21 00:06:02.264314 | orchestrator | localhost | ok: "/var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/work/logs" 2026-05-21 00:06:13.029038 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/work/artifacts" 2026-05-21 00:06:13.321580 | orchestrator -> localhost | changed: "/var/lib/zuul/builds/28a528797c5541f3b7106f758bfa23ca/work/docs" 2026-05-21 00:06:13.337720 | 2026-05-21 00:06:13.337867 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 00:06:14.271498 | orchestrator | changed: 2026-05-21 00:06:14.271870 | orchestrator | .d..t...... ./ 2026-05-21 00:06:14.271933 | orchestrator | >f+++++++++ sudo-debug.txt 2026-05-21 00:06:14.271997 | orchestrator | >f+++++++++ sudo-strace.log 2026-05-21 00:06:14.272039 | orchestrator | >f+++++++++ sudo-strace.log.time 2026-05-21 00:06:14.272097 | orchestrator | changed: All items complete 2026-05-21 00:06:14.272134 | 2026-05-21 00:06:15.088324 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:06:15.890916 | orchestrator | changed: .d..t...... ./ 2026-05-21 00:06:15.915491 | 2026-05-21 00:06:15.915633 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 00:06:15.956247 | orchestrator | skipping: Conditional result was False 2026-05-21 00:06:15.960879 | orchestrator | skipping: Conditional result was False 2026-05-21 00:06:15.970519 | 2026-05-21 00:06:15.970624 | PLAY RECAP 2026-05-21 00:06:15.970678 | orchestrator | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-21 00:06:15.970703 | 2026-05-21 00:06:16.111433 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-21 00:06:16.112541 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:06:16.937987 | 2026-05-21 00:06:16.938179 | PLAY [Base post] 2026-05-21 00:06:16.953327 | 2026-05-21 00:06:16.953488 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 00:06:18.124491 | orchestrator | changed 2026-05-21 00:06:18.136389 | 2026-05-21 00:06:18.136721 | PLAY RECAP 2026-05-21 00:06:18.136904 | orchestrator | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 00:06:18.137116 | 2026-05-21 00:06:18.285306 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 00:06:18.287136 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 00:06:19.175496 | 2026-05-21 00:06:19.175683 | PLAY [Base post-logs] 2026-05-21 00:06:19.186615 | 2026-05-21 00:06:19.186757 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 00:06:19.642078 | localhost | changed 2026-05-21 00:06:19.660421 | 2026-05-21 00:06:19.660652 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 00:06:19.701317 | localhost | ok 2026-05-21 00:06:19.710557 | 2026-05-21 00:06:19.710796 | TASK [Set zuul-log-path fact] 2026-05-21 00:06:19.741348 | localhost | ok 2026-05-21 00:06:19.757319 | 2026-05-21 00:06:19.757485 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 00:06:19.785260 | localhost | ok 2026-05-21 00:06:19.791101 | 2026-05-21 00:06:19.791998 | TASK [upload-logs : Create log directories] 2026-05-21 00:06:20.371073 | localhost | changed 2026-05-21 00:06:20.374098 | 2026-05-21 00:06:20.374213 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 00:06:20.897374 | localhost -> localhost | ok: Runtime: 0:00:00.007914 2026-05-21 00:06:20.902586 | 2026-05-21 00:06:20.902710 | TASK [upload-logs : Upload logs to log server] 2026-05-21 00:06:21.540254 | localhost | Output suppressed because no_log was given 2026-05-21 00:06:21.542541 | 2026-05-21 00:06:21.542662 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 00:06:21.604524 | localhost | skipping: Conditional result was False 2026-05-21 00:06:21.609478 | localhost | skipping: Conditional result was False 2026-05-21 00:06:21.624701 | 2026-05-21 00:06:21.624906 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 00:06:21.684528 | localhost | skipping: Conditional result was False 2026-05-21 00:06:21.684994 | 2026-05-21 00:06:21.688876 | localhost | skipping: Conditional result was False 2026-05-21 00:06:21.697710 | 2026-05-21 00:06:21.697942 | LOOP [upload-logs : Upload console log and json output]