2026-09-14 03:04:11.918551 | Job console starting 2026-09-14 03:04:11.925536 | Updating git repos 2026-09-14 03:04:11.944513 | Cloning repos into workspace 2026-09-14 03:04:12.015163 | Restoring repo states 2026-09-14 03:04:12.025146 | Merging changes 2026-09-14 03:04:12.025286 | Checking out repos 2026-09-14 03:04:12.108019 | Preparing playbooks 2026-09-14 03:04:13.095914 | Running Ansible setup 2026-09-14 03:04:17.814522 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-14 03:04:18.643966 | 2026-09-14 03:04:18.644081 | PLAY [Base pre] 2026-09-14 03:04:18.694770 | 2026-09-14 03:04:18.694884 | TASK [Setup log path fact] 2026-09-14 03:04:18.723802 | ubuntu-noble | ok 2026-09-14 03:04:18.737064 | 2026-09-14 03:04:18.737167 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-14 03:04:18.794408 | ubuntu-noble | ok 2026-09-14 03:04:18.805942 | 2026-09-14 03:04:18.806030 | TASK [emit-job-header : Print job information] 2026-09-14 03:04:18.838699 | # Job Information 2026-09-14 03:04:18.838917 | Ansible Version: 2.16.14 2026-09-14 03:04:18.838959 | Job: metalbox-unittest 2026-09-14 03:04:18.838996 | Pipeline: periodic-daily 2026-09-14 03:04:18.839020 | Executor: 521e9411259a 2026-09-14 03:04:18.839041 | Triggered by: https://github.com/osism/metalbox 2026-09-14 03:04:18.839063 | Event ID: 2d4ae13742ee4f6982436e88c6160bb8 2026-09-14 03:04:18.847107 | 2026-09-14 03:04:18.847214 | LOOP [emit-job-header : Print node information] 2026-09-14 03:04:18.981730 | ubuntu-noble | ok: 2026-09-14 03:04:18.981981 | ubuntu-noble | # Node Information 2026-09-14 03:04:18.982036 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2026-09-14 03:04:18.982078 | ubuntu-noble | Hostname: ubuntu 2026-09-14 03:04:18.982113 | ubuntu-noble | Username: zuul 2026-09-14 03:04:18.982148 | ubuntu-noble | Distro: Ubuntu 24.04 2026-09-14 03:04:18.982189 | ubuntu-noble | Provider: regiocloud-a 2026-09-14 03:04:18.982266 | ubuntu-noble | Region: 2026-09-14 03:04:18.982309 | ubuntu-noble | Label: ubuntu-noble 2026-09-14 03:04:18.982342 | ubuntu-noble | Product Name: OpenStack Nova 2026-09-14 03:04:18.982374 | ubuntu-noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe0a:879b 2026-09-14 03:04:18.998861 | 2026-09-14 03:04:18.998966 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-14 03:04:19.699848 | ubuntu-noble -> localhost | changed 2026-09-14 03:04:19.706607 | 2026-09-14 03:04:19.706713 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-14 03:04:21.778873 | ubuntu-noble -> localhost | changed 2026-09-14 03:04:21.797912 | 2026-09-14 03:04:21.798022 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-14 03:04:22.156891 | ubuntu-noble -> localhost | ok 2026-09-14 03:04:22.162988 | 2026-09-14 03:04:22.163093 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-14 03:04:22.192323 | ubuntu-noble | ok 2026-09-14 03:04:22.216654 | ubuntu-noble | included: /var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-14 03:04:22.236440 | 2026-09-14 03:04:22.236545 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-14 03:04:23.796220 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-09-14 03:04:23.796412 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/work/3fea98503bad4ea296641899d522c8b8_id_rsa 2026-09-14 03:04:23.796444 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/work/3fea98503bad4ea296641899d522c8b8_id_rsa.pub 2026-09-14 03:04:23.796466 | ubuntu-noble -> localhost | The key fingerprint is: 2026-09-14 03:04:23.796486 | ubuntu-noble -> localhost | SHA256:3/P5FCc7onddGj+RhGo+fzv5gVOGGWg0enQ0gj5M44E zuul-build-sshkey 2026-09-14 03:04:23.796505 | ubuntu-noble -> localhost | The key's randomart image is: 2026-09-14 03:04:23.796522 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-09-14 03:04:23.796548 | ubuntu-noble -> localhost | | . .=.+ | 2026-09-14 03:04:23.796566 | ubuntu-noble -> localhost | | E =+ = . | 2026-09-14 03:04:23.796583 | ubuntu-noble -> localhost | | =.o+ .. | 2026-09-14 03:04:23.796599 | ubuntu-noble -> localhost | | =o .+. | 2026-09-14 03:04:23.796614 | ubuntu-noble -> localhost | | S . .oo+o| 2026-09-14 03:04:23.796632 | ubuntu-noble -> localhost | | . + ==+| 2026-09-14 03:04:23.796648 | ubuntu-noble -> localhost | | + +oo*=| 2026-09-14 03:04:23.796665 | ubuntu-noble -> localhost | | +.++O+| 2026-09-14 03:04:23.796681 | ubuntu-noble -> localhost | | ..oo+oB| 2026-09-14 03:04:23.796698 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-09-14 03:04:23.796740 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.521261 2026-09-14 03:04:23.801873 | 2026-09-14 03:04:23.801957 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-14 03:04:23.828463 | ubuntu-noble | ok 2026-09-14 03:04:23.838716 | ubuntu-noble | included: /var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-14 03:04:23.857868 | 2026-09-14 03:04:23.857958 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-14 03:04:23.883249 | ubuntu-noble | skipping: Conditional result was False 2026-09-14 03:04:23.888733 | 2026-09-14 03:04:23.888822 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-14 03:04:24.603433 | ubuntu-noble | changed 2026-09-14 03:04:24.608559 | 2026-09-14 03:04:24.608645 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-14 03:04:24.888724 | ubuntu-noble | ok 2026-09-14 03:04:24.897415 | 2026-09-14 03:04:24.897539 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-14 03:04:25.654636 | ubuntu-noble | changed 2026-09-14 03:04:25.663317 | 2026-09-14 03:04:25.663448 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-14 03:04:26.424821 | ubuntu-noble | changed 2026-09-14 03:04:26.438976 | 2026-09-14 03:04:26.439070 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-14 03:04:26.461831 | ubuntu-noble | skipping: Conditional result was False 2026-09-14 03:04:26.467720 | 2026-09-14 03:04:26.467802 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-14 03:04:26.821820 | ubuntu-noble -> localhost | changed 2026-09-14 03:04:26.837320 | 2026-09-14 03:04:26.837445 | TASK [add-build-sshkey : Add back temp key] 2026-09-14 03:04:27.164091 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/work/3fea98503bad4ea296641899d522c8b8_id_rsa (zuul-build-sshkey) 2026-09-14 03:04:27.164487 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.018921 2026-09-14 03:04:27.176409 | 2026-09-14 03:04:27.176529 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-14 03:04:27.568227 | ubuntu-noble | ok 2026-09-14 03:04:27.575967 | 2026-09-14 03:04:27.576095 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-14 03:04:27.610317 | ubuntu-noble | skipping: Conditional result was False 2026-09-14 03:04:27.620421 | 2026-09-14 03:04:27.620517 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-14 03:04:28.061808 | ubuntu-noble | ok 2026-09-14 03:04:28.072311 | 2026-09-14 03:04:28.072395 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-14 03:04:28.090386 | ubuntu-noble | ok 2026-09-14 03:04:28.095142 | 2026-09-14 03:04:28.095221 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-14 03:04:28.382041 | ubuntu-noble -> localhost | ok 2026-09-14 03:04:28.391293 | 2026-09-14 03:04:28.391399 | TASK [validate-host : Collect information about the host] 2026-09-14 03:04:29.533686 | ubuntu-noble | ok 2026-09-14 03:04:29.547232 | 2026-09-14 03:04:29.547331 | TASK [validate-host : Sanitize hostname] 2026-09-14 03:04:29.668333 | ubuntu-noble | ok 2026-09-14 03:04:29.672364 | 2026-09-14 03:04:29.672435 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-14 03:04:30.280646 | ubuntu-noble -> localhost | changed 2026-09-14 03:04:30.285324 | 2026-09-14 03:04:30.285403 | TASK [validate-host : Collect information about zuul worker] 2026-09-14 03:04:30.762013 | ubuntu-noble | ok 2026-09-14 03:04:30.768532 | 2026-09-14 03:04:30.768642 | TASK [validate-host : Write out all zuul information for each host] 2026-09-14 03:04:31.265978 | ubuntu-noble -> localhost | changed 2026-09-14 03:04:31.284794 | 2026-09-14 03:04:31.284933 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-14 03:04:31.606325 | ubuntu-noble | ok 2026-09-14 03:04:31.627173 | 2026-09-14 03:04:31.627303 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-14 03:04:32.443460 | ubuntu-noble | changed: 2026-09-14 03:04:32.443704 | ubuntu-noble | cd+++++++++ src/ 2026-09-14 03:04:32.443752 | ubuntu-noble | cd+++++++++ src/github.com/ 2026-09-14 03:04:32.443787 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2026-09-14 03:04:32.443818 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2026-09-14 03:04:32.443847 | ubuntu-noble | Containerfile 2026-09-14 03:04:32.468591 | ubuntu-noble | localhost | ok: "/var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/work/logs" 2026-09-14 03:05:25.757023 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/work/artifacts" 2026-09-14 03:05:26.254271 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/3fea98503bad4ea296641899d522c8b8/work/docs" 2026-09-14 03:05:26.273051 | 2026-09-14 03:05:26.273340 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-14 03:05:27.247629 | ubuntu-noble | changed: 2026-09-14 03:05:27.247812 | ubuntu-noble | .d..t...... ./ 2026-09-14 03:05:27.247846 | ubuntu-noble | >f+++++++++ net-debug.post.txt 2026-09-14 03:05:27.247873 | ubuntu-noble | >f+++++++++ net-debug.pre.txt 2026-09-14 03:05:27.247896 | ubuntu-noble | >f+++++++++ sudo-debug.txt 2026-09-14 03:05:27.247919 | ubuntu-noble | >f+++++++++ sudo-strace.log 2026-09-14 03:05:27.247939 | ubuntu-noble | >f+++++++++ sudo-strace.log.time 2026-09-14 03:05:27.247977 | ubuntu-noble | changed: All items complete 2026-09-14 03:05:27.248052 | 2026-09-14 03:05:28.139901 | ubuntu-noble | changed: .d..t...... ./ 2026-09-14 03:05:29.103910 | ubuntu-noble | changed: .d..t...... ./ 2026-09-14 03:05:29.125962 | 2026-09-14 03:05:29.126068 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-14 03:05:29.174086 | ubuntu-noble | skipping: Conditional result was False 2026-09-14 03:05:29.187035 | ubuntu-noble | skipping: Conditional result was False 2026-09-14 03:05:29.211227 | 2026-09-14 03:05:29.211302 | PLAY RECAP 2026-09-14 03:05:29.211380 | ubuntu-noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-14 03:05:29.211405 | 2026-09-14 03:05:29.539075 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-14 03:05:29.539690 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-14 03:05:32.149382 | 2026-09-14 03:05:32.149502 | PLAY [Base post] 2026-09-14 03:05:32.193648 | 2026-09-14 03:05:32.193763 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-14 03:05:33.105104 | ubuntu-noble | changed 2026-09-14 03:05:33.112568 | 2026-09-14 03:05:33.112650 | PLAY RECAP 2026-09-14 03:05:33.112766 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-14 03:05:33.112820 | 2026-09-14 03:05:33.255732 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-14 03:05:33.256366 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-14 03:05:34.219117 | 2026-09-14 03:05:34.219231 | PLAY [Base post-logs] 2026-09-14 03:05:34.228103 | 2026-09-14 03:05:34.228197 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-14 03:05:34.832978 | localhost | changed 2026-09-14 03:05:34.845941 | 2026-09-14 03:05:34.846070 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-14 03:05:34.882167 | localhost | ok 2026-09-14 03:05:34.884945 | 2026-09-14 03:05:34.885025 | TASK [Set zuul-log-path fact] 2026-09-14 03:05:34.909963 | localhost | ok 2026-09-14 03:05:34.919832 | 2026-09-14 03:05:34.919945 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-14 03:05:34.968614 | localhost | ok 2026-09-14 03:05:34.971377 | 2026-09-14 03:05:34.971462 | TASK [upload-logs : Create log directories] 2026-09-14 03:05:35.639114 | localhost | changed 2026-09-14 03:05:35.641189 | 2026-09-14 03:05:35.641261 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-14 03:05:36.305225 | localhost -> localhost | ok: Runtime: 0:00:00.009821 2026-09-14 03:05:36.308187 | 2026-09-14 03:05:36.308260 | TASK [upload-logs : Upload logs to log server] 2026-09-14 03:05:36.877628 | localhost | Output suppressed because no_log was given 2026-09-14 03:05:36.878937 | 2026-09-14 03:05:36.879006 | LOOP [upload-logs : Compress console log and json output] 2026-09-14 03:05:36.930156 | localhost | skipping: Conditional result was False 2026-09-14 03:05:36.935264 | localhost | skipping: Conditional result was False 2026-09-14 03:05:36.943430 | 2026-09-14 03:05:36.943505 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-14 03:05:37.017752 | localhost | skipping: Conditional result was False 2026-09-14 03:05:37.017940 | 2026-09-14 03:05:37.030527 | localhost | skipping: Conditional result was False 2026-09-14 03:05:37.038257 | 2026-09-14 03:05:37.038357 | LOOP [upload-logs : Upload console log and json output]