2026-09-03 03:02:59.767619 | Job console starting 2026-09-03 03:02:59.778660 | Updating git repos 2026-09-03 03:02:59.810145 | Cloning repos into workspace 2026-09-03 03:02:59.844281 | Restoring repo states 2026-09-03 03:02:59.848577 | Merging changes 2026-09-03 03:02:59.848590 | Checking out repos 2026-09-03 03:02:59.887170 | Preparing playbooks 2026-09-03 03:03:00.625739 | Running Ansible setup 2026-09-03 03:03:05.169048 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-03 03:03:05.939338 | 2026-09-03 03:03:05.939499 | PLAY [Base pre] 2026-09-03 03:03:05.959428 | 2026-09-03 03:03:05.959569 | TASK [Setup log path fact] 2026-09-03 03:03:05.988992 | ubuntu-noble | ok 2026-09-03 03:03:06.006814 | 2026-09-03 03:03:06.006973 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-03 03:03:06.046671 | ubuntu-noble | ok 2026-09-03 03:03:06.059970 | 2026-09-03 03:03:06.060094 | TASK [emit-job-header : Print job information] 2026-09-03 03:03:06.127742 | # Job Information 2026-09-03 03:03:06.128265 | Ansible Version: 2.16.14 2026-09-03 03:03:06.128377 | Job: metalbox-unittest 2026-09-03 03:03:06.128418 | Pipeline: periodic-daily 2026-09-03 03:03:06.128442 | Executor: 521e9411259a 2026-09-03 03:03:06.128463 | Triggered by: https://github.com/osism/metalbox 2026-09-03 03:03:06.128485 | Event ID: 142cf720003b4800beef2765f20d5689 2026-09-03 03:03:06.135758 | 2026-09-03 03:03:06.136048 | LOOP [emit-job-header : Print node information] 2026-09-03 03:03:06.303967 | ubuntu-noble | ok: 2026-09-03 03:03:06.304219 | ubuntu-noble | # Node Information 2026-09-03 03:03:06.304258 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2026-09-03 03:03:06.304283 | ubuntu-noble | Hostname: ubuntu 2026-09-03 03:03:06.304306 | ubuntu-noble | Username: zuul 2026-09-03 03:03:06.304327 | ubuntu-noble | Distro: Ubuntu 24.04 2026-09-03 03:03:06.304350 | ubuntu-noble | Provider: regiocloud-a 2026-09-03 03:03:06.304371 | ubuntu-noble | Region: 2026-09-03 03:03:06.304392 | ubuntu-noble | Label: ubuntu-noble 2026-09-03 03:03:06.304412 | ubuntu-noble | Product Name: OpenStack Nova 2026-09-03 03:03:06.304431 | ubuntu-noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec2:bf34 2026-09-03 03:03:06.316221 | 2026-09-03 03:03:06.316405 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-03 03:03:06.889010 | ubuntu-noble -> localhost | changed 2026-09-03 03:03:06.896857 | 2026-09-03 03:03:06.896981 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-03 03:03:08.031768 | ubuntu-noble -> localhost | changed 2026-09-03 03:03:08.040288 | 2026-09-03 03:03:08.040368 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-03 03:03:08.317700 | ubuntu-noble -> localhost | ok 2026-09-03 03:03:08.323545 | 2026-09-03 03:03:08.323644 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-03 03:03:08.353740 | ubuntu-noble | ok 2026-09-03 03:03:08.369344 | ubuntu-noble | included: /var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-03 03:03:08.375752 | 2026-09-03 03:03:08.375833 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-03 03:03:09.804377 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-09-03 03:03:09.804560 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/work/e588fa2a5ddd436d8d8194ed68b46da8_id_rsa 2026-09-03 03:03:09.804598 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/work/e588fa2a5ddd436d8d8194ed68b46da8_id_rsa.pub 2026-09-03 03:03:09.804625 | ubuntu-noble -> localhost | The key fingerprint is: 2026-09-03 03:03:09.804649 | ubuntu-noble -> localhost | SHA256:FI5DAVOJx0L9qKM72VIuhnSuwZWdW8WfOf+votQJbXk zuul-build-sshkey 2026-09-03 03:03:09.804671 | ubuntu-noble -> localhost | The key's randomart image is: 2026-09-03 03:03:09.804692 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-09-03 03:03:09.804723 | ubuntu-noble -> localhost | | .+*+o. | 2026-09-03 03:03:09.804745 | ubuntu-noble -> localhost | | oo=o.. | 2026-09-03 03:03:09.804765 | ubuntu-noble -> localhost | | ooooo | 2026-09-03 03:03:09.804785 | ubuntu-noble -> localhost | | o ooo ..o. | 2026-09-03 03:03:09.804805 | ubuntu-noble -> localhost | | o + .S .=+ E | 2026-09-03 03:03:09.804829 | ubuntu-noble -> localhost | |....+ o +oo | 2026-09-03 03:03:09.804850 | ubuntu-noble -> localhost | |.+o* o . o. | 2026-09-03 03:03:09.804870 | ubuntu-noble -> localhost | |. O.o . . . | 2026-09-03 03:03:09.804891 | ubuntu-noble -> localhost | | oo= .. ..oo| 2026-09-03 03:03:09.804911 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-09-03 03:03:09.804960 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.993351 2026-09-03 03:03:09.810949 | 2026-09-03 03:03:09.811038 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-03 03:03:09.838528 | ubuntu-noble | ok 2026-09-03 03:03:09.849382 | ubuntu-noble | included: /var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-03 03:03:09.864308 | 2026-09-03 03:03:09.864397 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-03 03:03:09.876897 | ubuntu-noble | skipping: Conditional result was False 2026-09-03 03:03:09.881504 | 2026-09-03 03:03:09.881576 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-03 03:03:10.620854 | ubuntu-noble | changed 2026-09-03 03:03:10.627447 | 2026-09-03 03:03:10.627576 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-03 03:03:10.929982 | ubuntu-noble | ok 2026-09-03 03:03:10.934472 | 2026-09-03 03:03:10.934550 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-03 03:03:11.643198 | ubuntu-noble | changed 2026-09-03 03:03:11.649300 | 2026-09-03 03:03:11.649387 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-03 03:03:12.394040 | ubuntu-noble | changed 2026-09-03 03:03:12.403624 | 2026-09-03 03:03:12.403762 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-03 03:03:12.427624 | ubuntu-noble | skipping: Conditional result was False 2026-09-03 03:03:12.433210 | 2026-09-03 03:03:12.433300 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-03 03:03:12.850218 | ubuntu-noble -> localhost | changed 2026-09-03 03:03:12.864540 | 2026-09-03 03:03:12.864687 | TASK [add-build-sshkey : Add back temp key] 2026-09-03 03:03:13.194082 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/work/e588fa2a5ddd436d8d8194ed68b46da8_id_rsa (zuul-build-sshkey) 2026-09-03 03:03:13.194315 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015596 2026-09-03 03:03:13.201599 | 2026-09-03 03:03:13.201688 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-03 03:03:13.618188 | ubuntu-noble | ok 2026-09-03 03:03:13.624198 | 2026-09-03 03:03:13.624291 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-03 03:03:13.647641 | ubuntu-noble | skipping: Conditional result was False 2026-09-03 03:03:13.659583 | 2026-09-03 03:03:13.659673 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-03 03:03:14.133522 | ubuntu-noble | ok 2026-09-03 03:03:14.144414 | 2026-09-03 03:03:14.144519 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-03 03:03:14.182577 | ubuntu-noble | ok 2026-09-03 03:03:14.187907 | 2026-09-03 03:03:14.187992 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-03 03:03:14.456318 | ubuntu-noble -> localhost | ok 2026-09-03 03:03:14.462362 | 2026-09-03 03:03:14.462450 | TASK [validate-host : Collect information about the host] 2026-09-03 03:03:15.713594 | ubuntu-noble | ok 2026-09-03 03:03:15.726887 | 2026-09-03 03:03:15.726982 | TASK [validate-host : Sanitize hostname] 2026-09-03 03:03:15.840556 | ubuntu-noble | ok 2026-09-03 03:03:15.849216 | 2026-09-03 03:03:15.849522 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-03 03:03:16.426798 | ubuntu-noble -> localhost | changed 2026-09-03 03:03:16.432334 | 2026-09-03 03:03:16.432420 | TASK [validate-host : Collect information about zuul worker] 2026-09-03 03:03:16.905067 | ubuntu-noble | ok 2026-09-03 03:03:16.909678 | 2026-09-03 03:03:16.909766 | TASK [validate-host : Write out all zuul information for each host] 2026-09-03 03:03:17.483685 | ubuntu-noble -> localhost | changed 2026-09-03 03:03:17.500981 | 2026-09-03 03:03:17.501123 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-03 03:03:17.843738 | ubuntu-noble | ok 2026-09-03 03:03:17.849990 | 2026-09-03 03:03:17.850080 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-03 03:03:18.665283 | ubuntu-noble | changed: 2026-09-03 03:03:18.665430 | ubuntu-noble | cd+++++++++ src/ 2026-09-03 03:03:18.665458 | ubuntu-noble | cd+++++++++ src/github.com/ 2026-09-03 03:03:18.665478 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2026-09-03 03:03:18.665496 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2026-09-03 03:03:18.665512 | ubuntu-noble | Containerfile 2026-09-03 03:03:18.684336 | ubuntu-noble | localhost | ok: "/var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/work/logs" 2026-09-03 03:04:11.557754 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/work/artifacts" 2026-09-03 03:04:11.879022 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e588fa2a5ddd436d8d8194ed68b46da8/work/docs" 2026-09-03 03:04:11.899730 | 2026-09-03 03:04:11.899837 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-03 03:04:12.759370 | ubuntu-noble | changed: 2026-09-03 03:04:12.759558 | ubuntu-noble | .d..t...... ./ 2026-09-03 03:04:12.759588 | ubuntu-noble | >f+++++++++ net-debug.post.txt 2026-09-03 03:04:12.759609 | ubuntu-noble | >f+++++++++ net-debug.pre.txt 2026-09-03 03:04:12.759627 | ubuntu-noble | >f+++++++++ sudo-debug.txt 2026-09-03 03:04:12.759644 | ubuntu-noble | >f+++++++++ sudo-strace.log 2026-09-03 03:04:12.759661 | ubuntu-noble | >f+++++++++ sudo-strace.log.time 2026-09-03 03:04:12.759693 | ubuntu-noble | changed: All items complete 2026-09-03 03:04:12.759714 | 2026-09-03 03:04:13.372551 | ubuntu-noble | changed: .d..t...... ./ 2026-09-03 03:04:13.929642 | ubuntu-noble | changed: .d..t...... ./ 2026-09-03 03:04:13.952721 | 2026-09-03 03:04:13.952831 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-03 03:04:14.000971 | ubuntu-noble | skipping: Conditional result was False 2026-09-03 03:04:14.006111 | ubuntu-noble | skipping: Conditional result was False 2026-09-03 03:04:14.037749 | 2026-09-03 03:04:14.037828 | PLAY RECAP 2026-09-03 03:04:14.037880 | ubuntu-noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-03 03:04:14.037905 | 2026-09-03 03:04:14.160033 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-03 03:04:14.162616 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-03 03:04:15.360753 | 2026-09-03 03:04:15.360867 | PLAY [Base post] 2026-09-03 03:04:15.389831 | 2026-09-03 03:04:15.390466 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-03 03:04:16.159750 | ubuntu-noble | changed 2026-09-03 03:04:16.170428 | 2026-09-03 03:04:16.170518 | PLAY RECAP 2026-09-03 03:04:16.170570 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-03 03:04:16.170618 | 2026-09-03 03:04:16.321958 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-03 03:04:16.322569 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-03 03:04:17.487012 | 2026-09-03 03:04:17.487125 | PLAY [Base post-logs] 2026-09-03 03:04:17.500252 | 2026-09-03 03:04:17.500358 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-03 03:04:18.213412 | localhost | changed 2026-09-03 03:04:18.225408 | 2026-09-03 03:04:18.225808 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-03 03:04:18.250575 | localhost | ok 2026-09-03 03:04:18.256550 | 2026-09-03 03:04:18.256629 | TASK [Set zuul-log-path fact] 2026-09-03 03:04:18.270578 | localhost | ok 2026-09-03 03:04:18.277846 | 2026-09-03 03:04:18.277924 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-03 03:04:18.301878 | localhost | ok 2026-09-03 03:04:18.304203 | 2026-09-03 03:04:18.304276 | TASK [upload-logs : Create log directories] 2026-09-03 03:04:18.912605 | localhost | changed 2026-09-03 03:04:18.914784 | 2026-09-03 03:04:18.914876 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-03 03:04:19.328354 | localhost -> localhost | ok: Runtime: 0:00:00.003756 2026-09-03 03:04:19.331309 | 2026-09-03 03:04:19.331390 | TASK [upload-logs : Upload logs to log server] 2026-09-03 03:04:19.866705 | localhost | Output suppressed because no_log was given 2026-09-03 03:04:19.868321 | 2026-09-03 03:04:19.868409 | LOOP [upload-logs : Compress console log and json output] 2026-09-03 03:04:19.924469 | localhost | skipping: Conditional result was False 2026-09-03 03:04:19.929834 | localhost | skipping: Conditional result was False 2026-09-03 03:04:19.934376 | 2026-09-03 03:04:19.934465 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-03 03:04:19.979800 | localhost | skipping: Conditional result was False 2026-09-03 03:04:19.980027 | 2026-09-03 03:04:19.985481 | localhost | skipping: Conditional result was False 2026-09-03 03:04:19.990048 | 2026-09-03 03:04:19.990170 | LOOP [upload-logs : Upload console log and json output]