2026-08-29 03:03:59.922816 | Job console starting 2026-08-29 03:03:59.937987 | Updating git repos 2026-08-29 03:03:59.983033 | Cloning repos into workspace 2026-08-29 03:04:00.163173 | Restoring repo states 2026-08-29 03:04:00.182385 | Merging changes 2026-08-29 03:04:00.182409 | Checking out repos 2026-08-29 03:04:00.317416 | Preparing playbooks 2026-08-29 03:04:02.059212 | Running Ansible setup 2026-08-29 03:04:07.847431 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-29 03:04:08.896319 | 2026-08-29 03:04:08.896948 | PLAY [Base pre] 2026-08-29 03:04:08.913855 | 2026-08-29 03:04:08.913967 | TASK [Setup log path fact] 2026-08-29 03:04:08.942197 | ubuntu-noble | ok 2026-08-29 03:04:08.957773 | 2026-08-29 03:04:08.957902 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-29 03:04:08.985751 | ubuntu-noble | ok 2026-08-29 03:04:08.995313 | 2026-08-29 03:04:08.995410 | TASK [emit-job-header : Print job information] 2026-08-29 03:04:09.038923 | # Job Information 2026-08-29 03:04:09.039217 | Ansible Version: 2.16.14 2026-08-29 03:04:09.039287 | Job: metalbox-unittest 2026-08-29 03:04:09.039356 | Pipeline: periodic-daily 2026-08-29 03:04:09.039403 | Executor: 521e9411259a 2026-08-29 03:04:09.039447 | Triggered by: https://github.com/osism/metalbox 2026-08-29 03:04:09.039495 | Event ID: 7808a578ffde47428f352eaa585c3d32 2026-08-29 03:04:09.048760 | 2026-08-29 03:04:09.048910 | LOOP [emit-job-header : Print node information] 2026-08-29 03:04:09.165901 | ubuntu-noble | ok: 2026-08-29 03:04:09.166114 | ubuntu-noble | # Node Information 2026-08-29 03:04:09.166156 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2026-08-29 03:04:09.166187 | ubuntu-noble | Hostname: ubuntu 2026-08-29 03:04:09.166215 | ubuntu-noble | Username: zuul 2026-08-29 03:04:09.166242 | ubuntu-noble | Distro: Ubuntu 24.04 2026-08-29 03:04:09.166271 | ubuntu-noble | Provider: regiocloud-a 2026-08-29 03:04:09.166298 | ubuntu-noble | Region: 2026-08-29 03:04:09.166324 | ubuntu-noble | Label: ubuntu-noble 2026-08-29 03:04:09.166349 | ubuntu-noble | Product Name: OpenStack Nova 2026-08-29 03:04:09.166375 | ubuntu-noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe60:6811 2026-08-29 03:04:09.176557 | 2026-08-29 03:04:09.176711 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-29 03:04:09.648116 | ubuntu-noble -> localhost | changed 2026-08-29 03:04:09.655403 | 2026-08-29 03:04:09.655516 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-29 03:04:10.969476 | ubuntu-noble -> localhost | changed 2026-08-29 03:04:10.988710 | 2026-08-29 03:04:10.988822 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-29 03:04:11.761371 | ubuntu-noble -> localhost | ok 2026-08-29 03:04:11.767696 | 2026-08-29 03:04:11.767799 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-29 03:04:11.799185 | ubuntu-noble | ok 2026-08-29 03:04:11.852950 | ubuntu-noble | included: /var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-29 03:04:11.866738 | 2026-08-29 03:04:11.866900 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-29 03:04:15.473680 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-08-29 03:04:15.473944 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/work/e7686461a9fa45fda49c204456f21efa_id_rsa 2026-08-29 03:04:15.473987 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/work/e7686461a9fa45fda49c204456f21efa_id_rsa.pub 2026-08-29 03:04:15.474016 | ubuntu-noble -> localhost | The key fingerprint is: 2026-08-29 03:04:15.474041 | ubuntu-noble -> localhost | SHA256:r+kMnzFXyePFbV/C2e22ni7tpW1QAg5kvm0P9LWJsZA zuul-build-sshkey 2026-08-29 03:04:15.474065 | ubuntu-noble -> localhost | The key's randomart image is: 2026-08-29 03:04:15.474086 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-08-29 03:04:15.474119 | ubuntu-noble -> localhost | | .o | 2026-08-29 03:04:15.474143 | ubuntu-noble -> localhost | | o. o | 2026-08-29 03:04:15.474163 | ubuntu-noble -> localhost | | .E.o .| 2026-08-29 03:04:15.474183 | ubuntu-noble -> localhost | | =o====| 2026-08-29 03:04:15.474203 | ubuntu-noble -> localhost | | S . OoB=*| 2026-08-29 03:04:15.474228 | ubuntu-noble -> localhost | | . + =.+o| 2026-08-29 03:04:15.474249 | ubuntu-noble -> localhost | | . o o . o.=| 2026-08-29 03:04:15.474268 | ubuntu-noble -> localhost | | + B . ==| 2026-08-29 03:04:15.474289 | ubuntu-noble -> localhost | | .B *B.| 2026-08-29 03:04:15.474310 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-08-29 03:04:15.474360 | ubuntu-noble -> localhost | ok: Runtime: 0:00:02.427806 2026-08-29 03:04:15.481160 | 2026-08-29 03:04:15.481255 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-29 03:04:15.517777 | ubuntu-noble | ok 2026-08-29 03:04:15.538307 | ubuntu-noble | included: /var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-29 03:04:15.546987 | 2026-08-29 03:04:15.547080 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-29 03:04:15.560741 | ubuntu-noble | skipping: Conditional result was False 2026-08-29 03:04:15.566825 | 2026-08-29 03:04:15.566930 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-29 03:04:16.629470 | ubuntu-noble | changed 2026-08-29 03:04:16.636581 | 2026-08-29 03:04:16.636694 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-29 03:04:16.945680 | ubuntu-noble | ok 2026-08-29 03:04:16.953695 | 2026-08-29 03:04:16.953789 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-29 03:04:17.731082 | ubuntu-noble | changed 2026-08-29 03:04:17.743941 | 2026-08-29 03:04:17.744191 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-29 03:04:18.515376 | ubuntu-noble | changed 2026-08-29 03:04:18.521863 | 2026-08-29 03:04:18.521960 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-29 03:04:18.553272 | ubuntu-noble | skipping: Conditional result was False 2026-08-29 03:04:18.559511 | 2026-08-29 03:04:18.559707 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-29 03:04:19.135219 | ubuntu-noble -> localhost | changed 2026-08-29 03:04:19.152638 | 2026-08-29 03:04:19.152785 | TASK [add-build-sshkey : Add back temp key] 2026-08-29 03:04:19.792519 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/work/e7686461a9fa45fda49c204456f21efa_id_rsa (zuul-build-sshkey) 2026-08-29 03:04:19.792770 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.026509 2026-08-29 03:04:19.800429 | 2026-08-29 03:04:19.800526 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-29 03:04:20.244591 | ubuntu-noble | ok 2026-08-29 03:04:20.249837 | 2026-08-29 03:04:20.249926 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-29 03:04:20.285286 | ubuntu-noble | skipping: Conditional result was False 2026-08-29 03:04:20.296058 | 2026-08-29 03:04:20.296150 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-29 03:04:20.737081 | ubuntu-noble | ok 2026-08-29 03:04:20.751794 | 2026-08-29 03:04:20.751903 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-29 03:04:20.781049 | ubuntu-noble | ok 2026-08-29 03:04:20.786636 | 2026-08-29 03:04:20.786728 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-29 03:04:21.102014 | ubuntu-noble -> localhost | ok 2026-08-29 03:04:21.108850 | 2026-08-29 03:04:21.108981 | TASK [validate-host : Collect information about the host] 2026-08-29 03:04:23.701517 | ubuntu-noble | ok 2026-08-29 03:04:23.733056 | 2026-08-29 03:04:23.736038 | TASK [validate-host : Sanitize hostname] 2026-08-29 03:04:24.172129 | ubuntu-noble | ok 2026-08-29 03:04:24.179541 | 2026-08-29 03:04:24.179691 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-29 03:04:25.266213 | ubuntu-noble -> localhost | changed 2026-08-29 03:04:25.270975 | 2026-08-29 03:04:25.271060 | TASK [validate-host : Collect information about zuul worker] 2026-08-29 03:04:25.881096 | ubuntu-noble | ok 2026-08-29 03:04:25.886547 | 2026-08-29 03:04:25.886647 | TASK [validate-host : Write out all zuul information for each host] 2026-08-29 03:04:26.649049 | ubuntu-noble -> localhost | changed 2026-08-29 03:04:26.662914 | 2026-08-29 03:04:26.663017 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-29 03:04:26.948067 | ubuntu-noble | ok 2026-08-29 03:04:26.953430 | 2026-08-29 03:04:26.953521 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-29 03:04:27.759288 | ubuntu-noble | changed: 2026-08-29 03:04:27.759479 | ubuntu-noble | cd+++++++++ src/ 2026-08-29 03:04:27.759514 | ubuntu-noble | cd+++++++++ src/github.com/ 2026-08-29 03:04:27.759539 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2026-08-29 03:04:27.759560 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2026-08-29 03:04:27.759580 | ubuntu-noble | Containerfile 2026-08-29 03:04:27.800965 | ubuntu-noble | localhost | ok: "/var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/work/logs" 2026-08-29 03:05:22.938360 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/work/artifacts" 2026-08-29 03:05:23.767881 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e7686461a9fa45fda49c204456f21efa/work/docs" 2026-08-29 03:05:23.777060 | 2026-08-29 03:05:23.777170 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-29 03:05:24.702146 | ubuntu-noble | changed: 2026-08-29 03:05:24.702342 | ubuntu-noble | .d..t...... ./ 2026-08-29 03:05:24.702376 | ubuntu-noble | >f+++++++++ net-debug.post.txt 2026-08-29 03:05:24.702401 | ubuntu-noble | >f+++++++++ net-debug.pre.txt 2026-08-29 03:05:24.702423 | ubuntu-noble | >f+++++++++ sudo-debug.txt 2026-08-29 03:05:24.702444 | ubuntu-noble | >f+++++++++ sudo-strace.log 2026-08-29 03:05:24.702464 | ubuntu-noble | >f+++++++++ sudo-strace.log.time 2026-08-29 03:05:24.702505 | ubuntu-noble | changed: All items complete 2026-08-29 03:05:24.702532 | 2026-08-29 03:05:25.418208 | ubuntu-noble | changed: .d..t...... ./ 2026-08-29 03:05:26.146926 | ubuntu-noble | changed: .d..t...... ./ 2026-08-29 03:05:26.167088 | 2026-08-29 03:05:26.167201 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-29 03:05:26.213270 | ubuntu-noble | skipping: Conditional result was False 2026-08-29 03:05:26.218493 | ubuntu-noble | skipping: Conditional result was False 2026-08-29 03:05:26.229584 | 2026-08-29 03:05:26.229666 | PLAY RECAP 2026-08-29 03:05:26.229709 | ubuntu-noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-29 03:05:26.229730 | 2026-08-29 03:05:26.361872 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-29 03:05:26.362523 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-29 03:05:27.041428 | 2026-08-29 03:05:27.041553 | PLAY [Base post] 2026-08-29 03:05:27.055725 | 2026-08-29 03:05:27.055828 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-29 03:05:27.639216 | ubuntu-noble | changed 2026-08-29 03:05:27.649382 | 2026-08-29 03:05:27.649502 | PLAY RECAP 2026-08-29 03:05:27.649598 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-29 03:05:27.649656 | 2026-08-29 03:05:27.792580 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-29 03:05:27.793757 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-29 03:05:28.688833 | 2026-08-29 03:05:28.688966 | PLAY [Base post-logs] 2026-08-29 03:05:28.697169 | 2026-08-29 03:05:28.697275 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-29 03:05:29.202222 | localhost | changed 2026-08-29 03:05:29.210526 | 2026-08-29 03:05:29.210667 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-29 03:05:29.260053 | localhost | ok 2026-08-29 03:05:29.262483 | 2026-08-29 03:05:29.262578 | TASK [Set zuul-log-path fact] 2026-08-29 03:05:29.276825 | localhost | ok 2026-08-29 03:05:29.283404 | 2026-08-29 03:05:29.283486 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-29 03:05:29.308002 | localhost | ok 2026-08-29 03:05:29.310426 | 2026-08-29 03:05:29.310501 | TASK [upload-logs : Create log directories] 2026-08-29 03:05:29.967846 | localhost | changed 2026-08-29 03:05:29.970409 | 2026-08-29 03:05:29.970502 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-29 03:05:30.561415 | localhost -> localhost | ok: Runtime: 0:00:00.007747 2026-08-29 03:05:30.569034 | 2026-08-29 03:05:30.569149 | TASK [upload-logs : Upload logs to log server] 2026-08-29 03:05:31.253716 | localhost | Output suppressed because no_log was given 2026-08-29 03:05:31.255483 | 2026-08-29 03:05:31.255597 | LOOP [upload-logs : Compress console log and json output] 2026-08-29 03:05:31.311700 | localhost | skipping: Conditional result was False 2026-08-29 03:05:31.321349 | localhost | skipping: Conditional result was False 2026-08-29 03:05:31.331845 | 2026-08-29 03:05:31.331965 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-29 03:05:31.382391 | localhost | skipping: Conditional result was False 2026-08-29 03:05:31.382771 | 2026-08-29 03:05:31.390070 | localhost | skipping: Conditional result was False 2026-08-29 03:05:31.398124 | 2026-08-29 03:05:31.398237 | LOOP [upload-logs : Upload console log and json output]