2026-09-23 03:03:43.199487 | Job console starting 2026-09-23 03:03:43.208592 | Updating git repos 2026-09-23 03:03:43.248508 | Cloning repos into workspace 2026-09-23 03:03:43.366747 | Restoring repo states 2026-09-23 03:03:43.384962 | Merging changes 2026-09-23 03:03:43.384983 | Checking out repos 2026-09-23 03:03:43.427244 | Preparing playbooks 2026-09-23 03:03:44.447365 | Running Ansible setup 2026-09-23 03:03:50.655036 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-23 03:03:51.939274 | 2026-09-23 03:03:51.939423 | PLAY [Base pre] 2026-09-23 03:03:51.954998 | 2026-09-23 03:03:51.955115 | TASK [Setup log path fact] 2026-09-23 03:03:51.982932 | ubuntu-noble | ok 2026-09-23 03:03:51.996387 | 2026-09-23 03:03:51.996501 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-23 03:03:52.039475 | ubuntu-noble | ok 2026-09-23 03:03:52.053075 | 2026-09-23 03:03:52.053181 | TASK [emit-job-header : Print job information] 2026-09-23 03:03:52.102808 | # Job Information 2026-09-23 03:03:52.103015 | Ansible Version: 2.16.14 2026-09-23 03:03:52.103055 | Job: metalbox-unittest 2026-09-23 03:03:52.103091 | Pipeline: periodic-daily 2026-09-23 03:03:52.103117 | Executor: 521e9411259a 2026-09-23 03:03:52.103140 | Triggered by: https://github.com/osism/metalbox 2026-09-23 03:03:52.103164 | Event ID: 2fc57f448fdc43b0a681ae904cd720bc 2026-09-23 03:03:52.107901 | 2026-09-23 03:03:52.107992 | LOOP [emit-job-header : Print node information] 2026-09-23 03:03:52.218453 | ubuntu-noble | ok: 2026-09-23 03:03:52.218694 | ubuntu-noble | # Node Information 2026-09-23 03:03:52.218750 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2026-09-23 03:03:52.218792 | ubuntu-noble | Hostname: ubuntu 2026-09-23 03:03:52.218829 | ubuntu-noble | Username: zuul 2026-09-23 03:03:52.218879 | ubuntu-noble | Distro: Ubuntu 24.04 2026-09-23 03:03:52.218923 | ubuntu-noble | Provider: regiocloud-a 2026-09-23 03:03:52.218958 | ubuntu-noble | Region: 2026-09-23 03:03:52.218994 | ubuntu-noble | Label: ubuntu-noble 2026-09-23 03:03:52.219027 | ubuntu-noble | Product Name: OpenStack Nova 2026-09-23 03:03:52.219059 | ubuntu-noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe67:b817 2026-09-23 03:03:52.245198 | 2026-09-23 03:03:52.245339 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-23 03:03:52.620387 | ubuntu-noble -> localhost | changed 2026-09-23 03:03:52.627178 | 2026-09-23 03:03:52.627272 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-23 03:03:53.449388 | ubuntu-noble -> localhost | changed 2026-09-23 03:03:53.459996 | 2026-09-23 03:03:53.460103 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-23 03:03:53.686094 | ubuntu-noble -> localhost | ok 2026-09-23 03:03:53.695955 | 2026-09-23 03:03:53.696088 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-23 03:03:53.726235 | ubuntu-noble | ok 2026-09-23 03:03:53.740493 | ubuntu-noble | included: /var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-23 03:03:53.746412 | 2026-09-23 03:03:53.746500 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-23 03:03:56.042347 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-09-23 03:03:56.042541 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/work/40b166f280b240b6ae39273d91c41683_id_rsa 2026-09-23 03:03:56.042579 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/work/40b166f280b240b6ae39273d91c41683_id_rsa.pub 2026-09-23 03:03:56.042606 | ubuntu-noble -> localhost | The key fingerprint is: 2026-09-23 03:03:56.042629 | ubuntu-noble -> localhost | SHA256:eK286k7Dd4TriOgQRLc3io+UZ5W4BHlpDj2G0rfUAu8 zuul-build-sshkey 2026-09-23 03:03:56.042652 | ubuntu-noble -> localhost | The key's randomart image is: 2026-09-23 03:03:56.042673 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-09-23 03:03:56.042703 | ubuntu-noble -> localhost | | +=o.. | 2026-09-23 03:03:56.042727 | ubuntu-noble -> localhost | |o++O* o | 2026-09-23 03:03:56.042747 | ubuntu-noble -> localhost | |..**oB | 2026-09-23 03:03:56.042766 | ubuntu-noble -> localhost | |. +o* .. o | 2026-09-23 03:03:56.042786 | ubuntu-noble -> localhost | |.+ =E . S o | 2026-09-23 03:03:56.042811 | ubuntu-noble -> localhost | |..= . o + | 2026-09-23 03:03:56.042833 | ubuntu-noble -> localhost | |.. . + = . | 2026-09-23 03:03:56.042880 | ubuntu-noble -> localhost | | . . o = o | 2026-09-23 03:03:56.042905 | ubuntu-noble -> localhost | | .o .o=.o | 2026-09-23 03:03:56.042926 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-09-23 03:03:56.042977 | ubuntu-noble -> localhost | ok: Runtime: 0:00:01.939021 2026-09-23 03:03:56.049018 | 2026-09-23 03:03:56.049104 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-23 03:03:56.068528 | ubuntu-noble | ok 2026-09-23 03:03:56.078600 | ubuntu-noble | included: /var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-23 03:03:56.087402 | 2026-09-23 03:03:56.087479 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-23 03:03:56.101490 | ubuntu-noble | skipping: Conditional result was False 2026-09-23 03:03:56.107862 | 2026-09-23 03:03:56.107943 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-23 03:03:56.805204 | ubuntu-noble | changed 2026-09-23 03:03:56.811051 | 2026-09-23 03:03:56.811144 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-23 03:03:57.089278 | ubuntu-noble | ok 2026-09-23 03:03:57.097042 | 2026-09-23 03:03:57.097139 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-23 03:03:57.735624 | ubuntu-noble | changed 2026-09-23 03:03:57.740740 | 2026-09-23 03:03:57.740839 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-23 03:03:58.409346 | ubuntu-noble | changed 2026-09-23 03:03:58.417114 | 2026-09-23 03:03:58.417218 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-23 03:03:58.441128 | ubuntu-noble | skipping: Conditional result was False 2026-09-23 03:03:58.455324 | 2026-09-23 03:03:58.455467 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-23 03:03:58.935383 | ubuntu-noble -> localhost | changed 2026-09-23 03:03:58.962263 | 2026-09-23 03:03:58.962390 | TASK [add-build-sshkey : Add back temp key] 2026-09-23 03:03:59.371546 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/work/40b166f280b240b6ae39273d91c41683_id_rsa (zuul-build-sshkey) 2026-09-23 03:03:59.371992 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010798 2026-09-23 03:03:59.383607 | 2026-09-23 03:03:59.383734 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-23 03:03:59.907786 | ubuntu-noble | ok 2026-09-23 03:03:59.914903 | 2026-09-23 03:03:59.916325 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-23 03:03:59.949958 | ubuntu-noble | skipping: Conditional result was False 2026-09-23 03:03:59.960367 | 2026-09-23 03:03:59.960462 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-23 03:04:00.483335 | ubuntu-noble | ok 2026-09-23 03:04:00.496035 | 2026-09-23 03:04:00.496130 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-23 03:04:00.525801 | ubuntu-noble | ok 2026-09-23 03:04:00.533092 | 2026-09-23 03:04:00.533191 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-23 03:04:00.810502 | ubuntu-noble -> localhost | ok 2026-09-23 03:04:00.817454 | 2026-09-23 03:04:00.817544 | TASK [validate-host : Collect information about the host] 2026-09-23 03:04:02.190054 | ubuntu-noble | ok 2026-09-23 03:04:02.201952 | 2026-09-23 03:04:02.202067 | TASK [validate-host : Sanitize hostname] 2026-09-23 03:04:02.364815 | ubuntu-noble | ok 2026-09-23 03:04:02.369710 | 2026-09-23 03:04:02.369818 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-23 03:04:02.984298 | ubuntu-noble -> localhost | changed 2026-09-23 03:04:02.991256 | 2026-09-23 03:04:02.991372 | TASK [validate-host : Collect information about zuul worker] 2026-09-23 03:04:03.493202 | ubuntu-noble | ok 2026-09-23 03:04:03.499134 | 2026-09-23 03:04:03.499249 | TASK [validate-host : Write out all zuul information for each host] 2026-09-23 03:04:04.133385 | ubuntu-noble -> localhost | changed 2026-09-23 03:04:04.147372 | 2026-09-23 03:04:04.147497 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-23 03:04:04.474147 | ubuntu-noble | ok 2026-09-23 03:04:04.486260 | 2026-09-23 03:04:04.486379 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-23 03:04:05.447473 | ubuntu-noble | changed: 2026-09-23 03:04:05.447793 | ubuntu-noble | cd+++++++++ src/ 2026-09-23 03:04:05.447844 | ubuntu-noble | cd+++++++++ src/github.com/ 2026-09-23 03:04:05.447913 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2026-09-23 03:04:05.447937 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2026-09-23 03:04:05.447958 | ubuntu-noble | Containerfile 2026-09-23 03:04:05.497838 | ubuntu-noble | localhost | ok: "/var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/work/logs" 2026-09-23 03:05:02.003573 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/work/artifacts" 2026-09-23 03:05:02.456460 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/40b166f280b240b6ae39273d91c41683/work/docs" 2026-09-23 03:05:02.466978 | 2026-09-23 03:05:02.467098 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-23 03:05:03.506906 | ubuntu-noble | changed: 2026-09-23 03:05:03.507100 | ubuntu-noble | .d..t...... ./ 2026-09-23 03:05:03.507134 | ubuntu-noble | >f+++++++++ net-debug.post.txt 2026-09-23 03:05:03.507161 | ubuntu-noble | >f+++++++++ net-debug.pre.txt 2026-09-23 03:05:03.507182 | ubuntu-noble | >f+++++++++ sudo-debug.txt 2026-09-23 03:05:03.507203 | ubuntu-noble | >f+++++++++ sudo-strace.log 2026-09-23 03:05:03.507223 | ubuntu-noble | >f+++++++++ sudo-strace.log.time 2026-09-23 03:05:03.507255 | ubuntu-noble | changed: All items complete 2026-09-23 03:05:03.507277 | 2026-09-23 03:05:04.237895 | ubuntu-noble | changed: .d..t...... ./ 2026-09-23 03:05:04.846262 | ubuntu-noble | changed: .d..t...... ./ 2026-09-23 03:05:04.857495 | 2026-09-23 03:05:04.857679 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-23 03:05:04.883070 | ubuntu-noble | skipping: Conditional result was False 2026-09-23 03:05:04.888594 | ubuntu-noble | skipping: Conditional result was False 2026-09-23 03:05:04.900072 | 2026-09-23 03:05:04.900136 | PLAY RECAP 2026-09-23 03:05:04.900175 | ubuntu-noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-23 03:05:04.900196 | 2026-09-23 03:05:05.027304 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-23 03:05:05.028315 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-23 03:05:05.856897 | 2026-09-23 03:05:05.857334 | PLAY [Base post] 2026-09-23 03:05:05.881352 | 2026-09-23 03:05:05.881470 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-23 03:05:06.528054 | ubuntu-noble | changed 2026-09-23 03:05:06.533683 | 2026-09-23 03:05:06.533772 | PLAY RECAP 2026-09-23 03:05:06.534024 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-23 03:05:06.534109 | 2026-09-23 03:05:06.649422 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-23 03:05:06.650080 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-23 03:05:07.274364 | 2026-09-23 03:05:07.274470 | PLAY [Base post-logs] 2026-09-23 03:05:07.282199 | 2026-09-23 03:05:07.282286 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-23 03:05:07.707939 | localhost | changed 2026-09-23 03:05:07.716867 | 2026-09-23 03:05:07.716976 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-23 03:05:07.732022 | localhost | ok 2026-09-23 03:05:07.735233 | 2026-09-23 03:05:07.735308 | TASK [Set zuul-log-path fact] 2026-09-23 03:05:07.751649 | localhost | ok 2026-09-23 03:05:07.767952 | 2026-09-23 03:05:07.768093 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-23 03:05:07.796562 | localhost | ok 2026-09-23 03:05:07.803047 | 2026-09-23 03:05:07.803214 | TASK [upload-logs : Create log directories] 2026-09-23 03:05:08.153615 | localhost | changed 2026-09-23 03:05:08.156138 | 2026-09-23 03:05:08.156226 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-23 03:05:08.562992 | localhost -> localhost | ok: Runtime: 0:00:00.006687 2026-09-23 03:05:08.566416 | 2026-09-23 03:05:08.566506 | TASK [upload-logs : Upload logs to log server] 2026-09-23 03:05:09.055332 | localhost | Output suppressed because no_log was given 2026-09-23 03:05:09.056908 | 2026-09-23 03:05:09.056993 | LOOP [upload-logs : Compress console log and json output] 2026-09-23 03:05:09.114570 | localhost | skipping: Conditional result was False 2026-09-23 03:05:09.120054 | localhost | skipping: Conditional result was False 2026-09-23 03:05:09.132580 | 2026-09-23 03:05:09.132668 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-23 03:05:09.172207 | localhost | skipping: Conditional result was False 2026-09-23 03:05:09.172716 | 2026-09-23 03:05:09.175743 | localhost | skipping: Conditional result was False 2026-09-23 03:05:09.188172 | 2026-09-23 03:05:09.188266 | LOOP [upload-logs : Upload console log and json output]