2026-09-20 03:03:01.649697 | Job console starting 2026-09-20 03:03:01.681468 | Updating git repos 2026-09-20 03:03:01.899883 | Cloning repos into workspace 2026-09-20 03:03:01.973749 | Restoring repo states 2026-09-20 03:03:01.987341 | Merging changes 2026-09-20 03:03:01.987360 | Checking out repos 2026-09-20 03:03:02.041821 | Preparing playbooks 2026-09-20 03:03:03.332381 | Running Ansible setup 2026-09-20 03:03:08.822962 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-20 03:03:09.499281 | 2026-09-20 03:03:09.499409 | PLAY [Base pre] 2026-09-20 03:03:09.516177 | 2026-09-20 03:03:09.516297 | TASK [Setup log path fact] 2026-09-20 03:03:09.534231 | ubuntu-noble | ok 2026-09-20 03:03:09.549336 | 2026-09-20 03:03:09.549458 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-20 03:03:09.577066 | ubuntu-noble | ok 2026-09-20 03:03:09.586403 | 2026-09-20 03:03:09.586490 | TASK [emit-job-header : Print job information] 2026-09-20 03:03:09.614746 | # Job Information 2026-09-20 03:03:09.614924 | Ansible Version: 2.16.14 2026-09-20 03:03:09.614960 | Job: metalbox-unittest 2026-09-20 03:03:09.614993 | Pipeline: periodic-daily 2026-09-20 03:03:09.615017 | Executor: 521e9411259a 2026-09-20 03:03:09.615037 | Triggered by: https://github.com/osism/metalbox 2026-09-20 03:03:09.615058 | Event ID: 862036ee1ec8437fa2ea20073807219a 2026-09-20 03:03:09.619655 | 2026-09-20 03:03:09.619738 | LOOP [emit-job-header : Print node information] 2026-09-20 03:03:09.747620 | ubuntu-noble | ok: 2026-09-20 03:03:09.748490 | ubuntu-noble | # Node Information 2026-09-20 03:03:09.748565 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2026-09-20 03:03:09.748596 | ubuntu-noble | Hostname: ubuntu 2026-09-20 03:03:09.748620 | ubuntu-noble | Username: zuul 2026-09-20 03:03:09.748642 | ubuntu-noble | Distro: Ubuntu 24.04 2026-09-20 03:03:09.748667 | ubuntu-noble | Provider: regiocloud-a 2026-09-20 03:03:09.748689 | ubuntu-noble | Region: 2026-09-20 03:03:09.748710 | ubuntu-noble | Label: ubuntu-noble 2026-09-20 03:03:09.748731 | ubuntu-noble | Product Name: OpenStack Nova 2026-09-20 03:03:09.748750 | ubuntu-noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feeb:8f41 2026-09-20 03:03:09.767307 | 2026-09-20 03:03:09.767409 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-20 03:03:10.215343 | ubuntu-noble -> localhost | changed 2026-09-20 03:03:10.223777 | 2026-09-20 03:03:10.223880 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-20 03:03:11.207159 | ubuntu-noble -> localhost | changed 2026-09-20 03:03:11.219937 | 2026-09-20 03:03:11.220038 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-20 03:03:11.504254 | ubuntu-noble -> localhost | ok 2026-09-20 03:03:11.517754 | 2026-09-20 03:03:11.517914 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-20 03:03:11.542762 | ubuntu-noble | ok 2026-09-20 03:03:11.559674 | ubuntu-noble | included: /var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-20 03:03:11.565446 | 2026-09-20 03:03:11.565521 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-20 03:03:12.811647 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-09-20 03:03:12.812279 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/work/05de0cb6cd6045789d038860a98f92e4_id_rsa 2026-09-20 03:03:12.812405 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/work/05de0cb6cd6045789d038860a98f92e4_id_rsa.pub 2026-09-20 03:03:12.812483 | ubuntu-noble -> localhost | The key fingerprint is: 2026-09-20 03:03:12.812552 | ubuntu-noble -> localhost | SHA256:mNYI/QLnhylUiPP3K0AodTwh43kZ4mKQ/yRwPDb24Hc zuul-build-sshkey 2026-09-20 03:03:12.812618 | ubuntu-noble -> localhost | The key's randomart image is: 2026-09-20 03:03:12.812678 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-09-20 03:03:12.813139 | ubuntu-noble -> localhost | |.o+ooo. | 2026-09-20 03:03:12.813261 | ubuntu-noble -> localhost | |+o%=== | 2026-09-20 03:03:12.813344 | ubuntu-noble -> localhost | |.X+X*.o | 2026-09-20 03:03:12.813403 | ubuntu-noble -> localhost | |o.=o*=EO | 2026-09-20 03:03:12.813461 | ubuntu-noble -> localhost | | . *.oX.S | 2026-09-20 03:03:12.813531 | ubuntu-noble -> localhost | | oo o. | 2026-09-20 03:03:12.813631 | ubuntu-noble -> localhost | | . . | 2026-09-20 03:03:12.813689 | ubuntu-noble -> localhost | | . . | 2026-09-20 03:03:12.813752 | ubuntu-noble -> localhost | | . | 2026-09-20 03:03:12.813810 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-09-20 03:03:12.814016 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.784427 2026-09-20 03:03:12.831509 | 2026-09-20 03:03:12.831668 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-20 03:03:12.867280 | ubuntu-noble | ok 2026-09-20 03:03:12.882093 | ubuntu-noble | included: /var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-20 03:03:12.890951 | 2026-09-20 03:03:12.891056 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-20 03:03:12.906725 | ubuntu-noble | skipping: Conditional result was False 2026-09-20 03:03:12.930055 | 2026-09-20 03:03:12.930171 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-20 03:03:13.763746 | ubuntu-noble | changed 2026-09-20 03:03:13.771579 | 2026-09-20 03:03:13.771693 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-20 03:03:14.065120 | ubuntu-noble | ok 2026-09-20 03:03:14.087191 | 2026-09-20 03:03:14.087315 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-20 03:03:14.850022 | ubuntu-noble | changed 2026-09-20 03:03:14.864353 | 2026-09-20 03:03:14.864478 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-20 03:03:15.645271 | ubuntu-noble | changed 2026-09-20 03:03:15.654225 | 2026-09-20 03:03:15.654345 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-20 03:03:15.678791 | ubuntu-noble | skipping: Conditional result was False 2026-09-20 03:03:15.687344 | 2026-09-20 03:03:15.687450 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-20 03:03:16.242229 | ubuntu-noble -> localhost | changed 2026-09-20 03:03:16.266236 | 2026-09-20 03:03:16.266389 | TASK [add-build-sshkey : Add back temp key] 2026-09-20 03:03:16.758303 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/work/05de0cb6cd6045789d038860a98f92e4_id_rsa (zuul-build-sshkey) 2026-09-20 03:03:16.758547 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.020216 2026-09-20 03:03:16.769376 | 2026-09-20 03:03:16.769508 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-20 03:03:17.283843 | ubuntu-noble | ok 2026-09-20 03:03:17.293994 | 2026-09-20 03:03:17.294200 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-20 03:03:17.359382 | ubuntu-noble | skipping: Conditional result was False 2026-09-20 03:03:17.377582 | 2026-09-20 03:03:17.377718 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-20 03:03:17.909104 | ubuntu-noble | ok 2026-09-20 03:03:17.933722 | 2026-09-20 03:03:17.933857 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-20 03:03:18.000139 | ubuntu-noble | ok 2026-09-20 03:03:18.013257 | 2026-09-20 03:03:18.013386 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-20 03:03:18.682770 | ubuntu-noble -> localhost | ok 2026-09-20 03:03:18.690629 | 2026-09-20 03:03:18.690737 | TASK [validate-host : Collect information about the host] 2026-09-20 03:03:20.358376 | ubuntu-noble | ok 2026-09-20 03:03:20.374403 | 2026-09-20 03:03:20.374491 | TASK [validate-host : Sanitize hostname] 2026-09-20 03:03:20.507146 | ubuntu-noble | ok 2026-09-20 03:03:20.510685 | 2026-09-20 03:03:20.514258 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-20 03:03:21.044415 | ubuntu-noble -> localhost | changed 2026-09-20 03:03:21.049641 | 2026-09-20 03:03:21.049719 | TASK [validate-host : Collect information about zuul worker] 2026-09-20 03:03:21.489014 | ubuntu-noble | ok 2026-09-20 03:03:21.492838 | 2026-09-20 03:03:21.492939 | TASK [validate-host : Write out all zuul information for each host] 2026-09-20 03:03:22.229573 | ubuntu-noble -> localhost | changed 2026-09-20 03:03:22.238751 | 2026-09-20 03:03:22.238841 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-20 03:03:22.593388 | ubuntu-noble | ok 2026-09-20 03:03:22.601031 | 2026-09-20 03:03:22.601120 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-20 03:03:23.451218 | ubuntu-noble | changed: 2026-09-20 03:03:23.451473 | ubuntu-noble | cd+++++++++ src/ 2026-09-20 03:03:23.451513 | ubuntu-noble | cd+++++++++ src/github.com/ 2026-09-20 03:03:23.451538 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2026-09-20 03:03:23.451559 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2026-09-20 03:03:23.451580 | ubuntu-noble | Containerfile 2026-09-20 03:03:23.493492 | ubuntu-noble | localhost | ok: "/var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/work/logs" 2026-09-20 03:04:19.389023 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/work/artifacts" 2026-09-20 03:04:20.479440 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/05de0cb6cd6045789d038860a98f92e4/work/docs" 2026-09-20 03:04:20.494521 | 2026-09-20 03:04:20.494660 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-20 03:04:21.858293 | ubuntu-noble | changed: 2026-09-20 03:04:21.858480 | ubuntu-noble | .d..t...... ./ 2026-09-20 03:04:21.858512 | ubuntu-noble | >f+++++++++ net-debug.post.txt 2026-09-20 03:04:21.858539 | ubuntu-noble | >f+++++++++ net-debug.pre.txt 2026-09-20 03:04:21.858560 | ubuntu-noble | >f+++++++++ sudo-debug.txt 2026-09-20 03:04:21.858581 | ubuntu-noble | >f+++++++++ sudo-strace.log 2026-09-20 03:04:21.858601 | ubuntu-noble | >f+++++++++ sudo-strace.log.time 2026-09-20 03:04:21.858633 | ubuntu-noble | changed: All items complete 2026-09-20 03:04:21.858655 | 2026-09-20 03:04:22.425530 | ubuntu-noble | changed: .d..t...... ./ 2026-09-20 03:04:23.179797 | ubuntu-noble | changed: .d..t...... ./ 2026-09-20 03:04:23.234831 | 2026-09-20 03:04:23.234970 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-20 03:04:23.278800 | ubuntu-noble | skipping: Conditional result was False 2026-09-20 03:04:23.282297 | ubuntu-noble | skipping: Conditional result was False 2026-09-20 03:04:23.289292 | 2026-09-20 03:04:23.289362 | PLAY RECAP 2026-09-20 03:04:23.289408 | ubuntu-noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-20 03:04:23.289432 | 2026-09-20 03:04:23.414493 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-20 03:04:23.415250 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-20 03:04:24.191418 | 2026-09-20 03:04:24.191546 | PLAY [Base post] 2026-09-20 03:04:24.205884 | 2026-09-20 03:04:24.206007 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-20 03:04:24.835853 | ubuntu-noble | changed 2026-09-20 03:04:24.851356 | 2026-09-20 03:04:24.851467 | PLAY RECAP 2026-09-20 03:04:24.851530 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-20 03:04:24.851594 | 2026-09-20 03:04:24.937181 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-20 03:04:24.937935 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-20 03:04:26.314097 | 2026-09-20 03:04:26.314211 | PLAY [Base post-logs] 2026-09-20 03:04:26.324920 | 2026-09-20 03:04:26.325021 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-20 03:04:27.469454 | localhost | changed 2026-09-20 03:04:27.481033 | 2026-09-20 03:04:27.481391 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-20 03:04:27.562213 | localhost | ok 2026-09-20 03:04:27.564603 | 2026-09-20 03:04:27.564676 | TASK [Set zuul-log-path fact] 2026-09-20 03:04:27.588602 | localhost | ok 2026-09-20 03:04:27.596640 | 2026-09-20 03:04:27.596736 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-20 03:04:27.642410 | localhost | ok 2026-09-20 03:04:27.645189 | 2026-09-20 03:04:27.645266 | TASK [upload-logs : Create log directories] 2026-09-20 03:04:28.475938 | localhost | changed 2026-09-20 03:04:28.478271 | 2026-09-20 03:04:28.478350 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-20 03:04:29.562410 | localhost -> localhost | ok: Runtime: 0:00:00.022645 2026-09-20 03:04:29.565333 | 2026-09-20 03:04:29.565413 | TASK [upload-logs : Upload logs to log server] 2026-09-20 03:04:30.196927 | localhost | Output suppressed because no_log was given 2026-09-20 03:04:30.198289 | 2026-09-20 03:04:30.198358 | LOOP [upload-logs : Compress console log and json output] 2026-09-20 03:04:30.279092 | localhost | skipping: Conditional result was False 2026-09-20 03:04:30.289124 | localhost | skipping: Conditional result was False 2026-09-20 03:04:30.295609 | 2026-09-20 03:04:30.295698 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-20 03:04:30.363147 | localhost | skipping: Conditional result was False 2026-09-20 03:04:30.363347 | 2026-09-20 03:04:30.382716 | localhost | skipping: Conditional result was False 2026-09-20 03:04:30.390564 | 2026-09-20 03:04:30.390659 | LOOP [upload-logs : Upload console log and json output]