2026-09-22 03:05:06.825160 | Job console starting 2026-09-22 03:05:06.836063 | Updating git repos 2026-09-22 03:05:06.855543 | Cloning repos into workspace 2026-09-22 03:05:06.909594 | Restoring repo states 2026-09-22 03:05:06.916853 | Merging changes 2026-09-22 03:05:06.916867 | Checking out repos 2026-09-22 03:05:06.974216 | Preparing playbooks 2026-09-22 03:05:07.958692 | Running Ansible setup 2026-09-22 03:05:12.607550 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-22 03:05:13.497723 | 2026-09-22 03:05:13.497848 | PLAY [Base pre] 2026-09-22 03:05:13.521496 | 2026-09-22 03:05:13.521727 | TASK [Setup log path fact] 2026-09-22 03:05:13.573105 | ubuntu-noble | ok 2026-09-22 03:05:13.601715 | 2026-09-22 03:05:13.602148 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-22 03:05:13.668340 | ubuntu-noble | ok 2026-09-22 03:05:13.687195 | 2026-09-22 03:05:13.687305 | TASK [emit-job-header : Print job information] 2026-09-22 03:05:13.763297 | # Job Information 2026-09-22 03:05:13.763690 | Ansible Version: 2.16.14 2026-09-22 03:05:13.763742 | Job: metalbox-unittest 2026-09-22 03:05:13.763789 | Pipeline: periodic-daily 2026-09-22 03:05:13.763889 | Executor: 521e9411259a 2026-09-22 03:05:13.763913 | Triggered by: https://github.com/osism/metalbox 2026-09-22 03:05:13.763936 | Event ID: c5f3a8eadb644e9e8e825ce3b8e26b97 2026-09-22 03:05:13.783190 | 2026-09-22 03:05:13.783328 | LOOP [emit-job-header : Print node information] 2026-09-22 03:05:13.963994 | ubuntu-noble | ok: 2026-09-22 03:05:13.964253 | ubuntu-noble | # Node Information 2026-09-22 03:05:13.964375 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2026-09-22 03:05:13.964404 | ubuntu-noble | Hostname: ubuntu 2026-09-22 03:05:13.964468 | ubuntu-noble | Username: zuul 2026-09-22 03:05:13.964491 | ubuntu-noble | Distro: Ubuntu 24.04 2026-09-22 03:05:13.964517 | ubuntu-noble | Provider: regiocloud-a 2026-09-22 03:05:13.964662 | ubuntu-noble | Region: 2026-09-22 03:05:13.964695 | ubuntu-noble | Label: ubuntu-noble 2026-09-22 03:05:13.964718 | ubuntu-noble | Product Name: OpenStack Nova 2026-09-22 03:05:13.964784 | ubuntu-noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feff:f151 2026-09-22 03:05:13.985809 | 2026-09-22 03:05:13.986021 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-22 03:05:14.530022 | ubuntu-noble -> localhost | changed 2026-09-22 03:05:14.536890 | 2026-09-22 03:05:14.536987 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-22 03:05:15.996341 | ubuntu-noble -> localhost | changed 2026-09-22 03:05:16.021967 | 2026-09-22 03:05:16.022069 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-22 03:05:16.426304 | ubuntu-noble -> localhost | ok 2026-09-22 03:05:16.432704 | 2026-09-22 03:05:16.432812 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-22 03:05:16.482841 | ubuntu-noble | ok 2026-09-22 03:05:16.508520 | ubuntu-noble | included: /var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-22 03:05:16.528388 | 2026-09-22 03:05:16.528473 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-22 03:05:17.725741 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-09-22 03:05:17.725910 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/work/2527f9be96894b44817d7099b6548916_id_rsa 2026-09-22 03:05:17.725942 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/work/2527f9be96894b44817d7099b6548916_id_rsa.pub 2026-09-22 03:05:17.725964 | ubuntu-noble -> localhost | The key fingerprint is: 2026-09-22 03:05:17.725984 | ubuntu-noble -> localhost | SHA256:uUQe7HN8tFmGY4IEeQwxHKmAEpaN4oNa5SqFsYKLgL8 zuul-build-sshkey 2026-09-22 03:05:17.726003 | ubuntu-noble -> localhost | The key's randomart image is: 2026-09-22 03:05:17.726022 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-09-22 03:05:17.726048 | ubuntu-noble -> localhost | |.++ .*O. | 2026-09-22 03:05:17.726067 | ubuntu-noble -> localhost | |*o... ++o. . | 2026-09-22 03:05:17.726085 | ubuntu-noble -> localhost | |B+ + . .= . = o | 2026-09-22 03:05:17.726101 | ubuntu-noble -> localhost | |Ooo o + + + * | 2026-09-22 03:05:17.726117 | ubuntu-noble -> localhost | |==.. S o + | 2026-09-22 03:05:17.726141 | ubuntu-noble -> localhost | |= o . + . | 2026-09-22 03:05:17.726199 | ubuntu-noble -> localhost | | . . . | 2026-09-22 03:05:17.726223 | ubuntu-noble -> localhost | | E | 2026-09-22 03:05:17.726242 | ubuntu-noble -> localhost | | | 2026-09-22 03:05:17.726260 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-09-22 03:05:17.726309 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.481383 2026-09-22 03:05:17.731358 | 2026-09-22 03:05:17.731432 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-22 03:05:17.768913 | ubuntu-noble | ok 2026-09-22 03:05:17.777114 | ubuntu-noble | included: /var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-22 03:05:17.793881 | 2026-09-22 03:05:17.793954 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-22 03:05:17.836918 | ubuntu-noble | skipping: Conditional result was False 2026-09-22 03:05:17.842381 | 2026-09-22 03:05:17.842457 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-22 03:05:18.618057 | ubuntu-noble | changed 2026-09-22 03:05:18.622426 | 2026-09-22 03:05:18.622497 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-22 03:05:18.952994 | ubuntu-noble | ok 2026-09-22 03:05:18.964441 | 2026-09-22 03:05:18.964545 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-22 03:05:19.698243 | ubuntu-noble | changed 2026-09-22 03:05:19.703378 | 2026-09-22 03:05:19.703460 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-22 03:05:20.429873 | ubuntu-noble | changed 2026-09-22 03:05:20.437425 | 2026-09-22 03:05:20.437520 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-22 03:05:20.460410 | ubuntu-noble | skipping: Conditional result was False 2026-09-22 03:05:20.466711 | 2026-09-22 03:05:20.466795 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-22 03:05:20.866198 | ubuntu-noble -> localhost | changed 2026-09-22 03:05:20.877011 | 2026-09-22 03:05:20.877100 | TASK [add-build-sshkey : Add back temp key] 2026-09-22 03:05:21.193615 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/work/2527f9be96894b44817d7099b6548916_id_rsa (zuul-build-sshkey) 2026-09-22 03:05:21.194484 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.018367 2026-09-22 03:05:21.209853 | 2026-09-22 03:05:21.209979 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-22 03:05:21.639866 | ubuntu-noble | ok 2026-09-22 03:05:21.649079 | 2026-09-22 03:05:21.649179 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-22 03:05:21.675761 | ubuntu-noble | skipping: Conditional result was False 2026-09-22 03:05:21.685829 | 2026-09-22 03:05:21.685915 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-22 03:05:22.188997 | ubuntu-noble | ok 2026-09-22 03:05:22.203455 | 2026-09-22 03:05:22.203541 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-22 03:05:22.242199 | ubuntu-noble | ok 2026-09-22 03:05:22.247683 | 2026-09-22 03:05:22.247769 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-22 03:05:22.512088 | ubuntu-noble -> localhost | ok 2026-09-22 03:05:22.518198 | 2026-09-22 03:05:22.518279 | TASK [validate-host : Collect information about the host] 2026-09-22 03:05:23.717134 | ubuntu-noble | ok 2026-09-22 03:05:23.731743 | 2026-09-22 03:05:23.731841 | TASK [validate-host : Sanitize hostname] 2026-09-22 03:05:23.835360 | ubuntu-noble | ok 2026-09-22 03:05:23.839666 | 2026-09-22 03:05:23.839749 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-22 03:05:24.361376 | ubuntu-noble -> localhost | changed 2026-09-22 03:05:24.368070 | 2026-09-22 03:05:24.368177 | TASK [validate-host : Collect information about zuul worker] 2026-09-22 03:05:24.828979 | ubuntu-noble | ok 2026-09-22 03:05:24.833433 | 2026-09-22 03:05:24.833520 | TASK [validate-host : Write out all zuul information for each host] 2026-09-22 03:05:25.747110 | ubuntu-noble -> localhost | changed 2026-09-22 03:05:25.760310 | 2026-09-22 03:05:25.760857 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-22 03:05:26.105193 | ubuntu-noble | ok 2026-09-22 03:05:26.111775 | 2026-09-22 03:05:26.111937 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-22 03:05:27.386979 | ubuntu-noble | changed: 2026-09-22 03:05:27.387147 | ubuntu-noble | cd+++++++++ src/ 2026-09-22 03:05:27.390952 | ubuntu-noble | cd+++++++++ src/github.com/ 2026-09-22 03:05:27.390990 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2026-09-22 03:05:27.391014 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2026-09-22 03:05:27.391036 | ubuntu-noble | Containerfile 2026-09-22 03:05:27.436145 | ubuntu-noble | localhost | ok: "/var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/work/logs" 2026-09-22 03:06:24.513311 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/work/artifacts" 2026-09-22 03:06:24.902005 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/2527f9be96894b44817d7099b6548916/work/docs" 2026-09-22 03:06:24.917385 | 2026-09-22 03:06:24.917477 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-22 03:06:25.974832 | ubuntu-noble | changed: 2026-09-22 03:06:25.975040 | ubuntu-noble | .d..t...... ./ 2026-09-22 03:06:25.975076 | ubuntu-noble | >f+++++++++ net-debug.post.txt 2026-09-22 03:06:25.975102 | ubuntu-noble | >f+++++++++ net-debug.pre.txt 2026-09-22 03:06:25.975120 | ubuntu-noble | >f+++++++++ sudo-debug.txt 2026-09-22 03:06:25.975138 | ubuntu-noble | >f+++++++++ sudo-strace.log 2026-09-22 03:06:25.975155 | ubuntu-noble | >f+++++++++ sudo-strace.log.time 2026-09-22 03:06:25.975185 | ubuntu-noble | changed: All items complete 2026-09-22 03:06:25.975207 | 2026-09-22 03:06:26.508143 | ubuntu-noble | changed: .d..t...... ./ 2026-09-22 03:06:27.105983 | ubuntu-noble | changed: .d..t...... ./ 2026-09-22 03:06:27.129237 | 2026-09-22 03:06:27.129334 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-22 03:06:27.152863 | ubuntu-noble | skipping: Conditional result was False 2026-09-22 03:06:27.157583 | ubuntu-noble | skipping: Conditional result was False 2026-09-22 03:06:27.168333 | 2026-09-22 03:06:27.168389 | PLAY RECAP 2026-09-22 03:06:27.168429 | ubuntu-noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-22 03:06:27.168448 | 2026-09-22 03:06:27.258356 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-22 03:06:27.259001 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-22 03:06:28.021603 | 2026-09-22 03:06:28.021790 | PLAY [Base post] 2026-09-22 03:06:28.053323 | 2026-09-22 03:06:28.053503 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-22 03:06:28.811798 | ubuntu-noble | changed 2026-09-22 03:06:28.822632 | 2026-09-22 03:06:28.822733 | PLAY RECAP 2026-09-22 03:06:28.822792 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-22 03:06:28.822854 | 2026-09-22 03:06:28.950567 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-22 03:06:28.951173 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-22 03:06:29.816442 | 2026-09-22 03:06:29.816608 | PLAY [Base post-logs] 2026-09-22 03:06:29.827018 | 2026-09-22 03:06:29.827132 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-22 03:06:30.224909 | localhost | changed 2026-09-22 03:06:30.232860 | 2026-09-22 03:06:30.232972 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-22 03:06:30.256754 | localhost | ok 2026-09-22 03:06:30.259615 | 2026-09-22 03:06:30.259688 | TASK [Set zuul-log-path fact] 2026-09-22 03:06:30.273435 | localhost | ok 2026-09-22 03:06:30.286483 | 2026-09-22 03:06:30.286579 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-22 03:06:30.330824 | localhost | ok 2026-09-22 03:06:30.333081 | 2026-09-22 03:06:30.333148 | TASK [upload-logs : Create log directories] 2026-09-22 03:06:30.858236 | localhost | changed 2026-09-22 03:06:30.860644 | 2026-09-22 03:06:30.860723 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-22 03:06:31.405453 | localhost -> localhost | ok: Runtime: 0:00:00.028623 2026-09-22 03:06:31.410593 | 2026-09-22 03:06:31.410690 | TASK [upload-logs : Upload logs to log server] 2026-09-22 03:06:31.974582 | localhost | Output suppressed because no_log was given 2026-09-22 03:06:31.976020 | 2026-09-22 03:06:31.976090 | LOOP [upload-logs : Compress console log and json output] 2026-09-22 03:06:32.011155 | localhost | skipping: Conditional result was False 2026-09-22 03:06:32.016998 | localhost | skipping: Conditional result was False 2026-09-22 03:06:32.020785 | 2026-09-22 03:06:32.020864 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-22 03:06:32.054542 | localhost | skipping: Conditional result was False 2026-09-22 03:06:32.054731 | 2026-09-22 03:06:32.059973 | localhost | skipping: Conditional result was False 2026-09-22 03:06:32.067059 | 2026-09-22 03:06:32.067134 | LOOP [upload-logs : Upload console log and json output]