2026-08-30 03:03:52.805772 | Job console starting 2026-08-30 03:03:52.816486 | Updating git repos 2026-08-30 03:03:52.849718 | Cloning repos into workspace 2026-08-30 03:03:52.924202 | Restoring repo states 2026-08-30 03:03:52.930050 | Merging changes 2026-08-30 03:03:52.930071 | Checking out repos 2026-08-30 03:03:53.011412 | Preparing playbooks 2026-08-30 03:03:54.752045 | Running Ansible setup 2026-08-30 03:04:01.761136 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-30 03:04:02.574941 | 2026-08-30 03:04:02.575078 | PLAY [Base pre] 2026-08-30 03:04:02.608883 | 2026-08-30 03:04:02.609007 | TASK [Setup log path fact] 2026-08-30 03:04:02.660364 | ubuntu-noble | ok 2026-08-30 03:04:02.683298 | 2026-08-30 03:04:02.683435 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-30 03:04:02.727946 | ubuntu-noble | ok 2026-08-30 03:04:02.736718 | 2026-08-30 03:04:02.736820 | TASK [emit-job-header : Print job information] 2026-08-30 03:04:02.765485 | # Job Information 2026-08-30 03:04:02.765694 | Ansible Version: 2.16.14 2026-08-30 03:04:02.765728 | Job: metalbox-unittest 2026-08-30 03:04:02.765764 | Pipeline: periodic-daily 2026-08-30 03:04:02.765788 | Executor: 521e9411259a 2026-08-30 03:04:02.765806 | Triggered by: https://github.com/osism/metalbox 2026-08-30 03:04:02.765824 | Event ID: 53da35e278014b54aa952912fbb2def3 2026-08-30 03:04:02.770128 | 2026-08-30 03:04:02.770228 | LOOP [emit-job-header : Print node information] 2026-08-30 03:04:03.015604 | ubuntu-noble | ok: 2026-08-30 03:04:03.015794 | ubuntu-noble | # Node Information 2026-08-30 03:04:03.015829 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2026-08-30 03:04:03.015855 | ubuntu-noble | Hostname: ubuntu 2026-08-30 03:04:03.015878 | ubuntu-noble | Username: zuul 2026-08-30 03:04:03.015900 | ubuntu-noble | Distro: Ubuntu 24.04 2026-08-30 03:04:03.015924 | ubuntu-noble | Provider: regiocloud-a 2026-08-30 03:04:03.015946 | ubuntu-noble | Region: 2026-08-30 03:04:03.015968 | ubuntu-noble | Label: ubuntu-noble 2026-08-30 03:04:03.015988 | ubuntu-noble | Product Name: OpenStack Nova 2026-08-30 03:04:03.016008 | ubuntu-noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe25:627d 2026-08-30 03:04:03.030305 | 2026-08-30 03:04:03.030419 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-30 03:04:04.015975 | ubuntu-noble -> localhost | changed 2026-08-30 03:04:04.022157 | 2026-08-30 03:04:04.022256 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-30 03:04:06.454205 | ubuntu-noble -> localhost | changed 2026-08-30 03:04:06.464275 | 2026-08-30 03:04:06.464408 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-30 03:04:07.035893 | ubuntu-noble -> localhost | ok 2026-08-30 03:04:07.041415 | 2026-08-30 03:04:07.041525 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-30 03:04:07.102129 | ubuntu-noble | ok 2026-08-30 03:04:07.152371 | ubuntu-noble | included: /var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-30 03:04:07.181489 | 2026-08-30 03:04:07.182641 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-30 03:04:10.454335 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-08-30 03:04:10.454564 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/work/d68954adf8b5486ba4842b3a891b5ec2_id_rsa 2026-08-30 03:04:10.454603 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/work/d68954adf8b5486ba4842b3a891b5ec2_id_rsa.pub 2026-08-30 03:04:10.454631 | ubuntu-noble -> localhost | The key fingerprint is: 2026-08-30 03:04:10.454655 | ubuntu-noble -> localhost | SHA256:H2JUuDAbc6vNGaVghsr1rVMKlCejxsJX7y0YmWdvngY zuul-build-sshkey 2026-08-30 03:04:10.454678 | ubuntu-noble -> localhost | The key's randomart image is: 2026-08-30 03:04:10.454700 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-08-30 03:04:10.454732 | ubuntu-noble -> localhost | | o .. | 2026-08-30 03:04:10.454756 | ubuntu-noble -> localhost | | B.X o.. | 2026-08-30 03:04:10.454777 | ubuntu-noble -> localhost | |.o =.B+X.= | 2026-08-30 03:04:10.454797 | ubuntu-noble -> localhost | |..*..+o=B | 2026-08-30 03:04:10.454817 | ubuntu-noble -> localhost | | o. .*BSo. | 2026-08-30 03:04:10.454856 | ubuntu-noble -> localhost | | .=E+* . | 2026-08-30 03:04:10.454878 | ubuntu-noble -> localhost | | .= o | 2026-08-30 03:04:10.454899 | ubuntu-noble -> localhost | | + | 2026-08-30 03:04:10.454920 | ubuntu-noble -> localhost | | . | 2026-08-30 03:04:10.454940 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-08-30 03:04:10.454993 | ubuntu-noble -> localhost | ok: Runtime: 0:00:02.061799 2026-08-30 03:04:10.465112 | 2026-08-30 03:04:10.465231 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-30 03:04:10.493152 | ubuntu-noble | ok 2026-08-30 03:04:10.501486 | ubuntu-noble | included: /var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-30 03:04:10.523738 | 2026-08-30 03:04:10.523835 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-30 03:04:10.536825 | ubuntu-noble | skipping: Conditional result was False 2026-08-30 03:04:10.541423 | 2026-08-30 03:04:10.541501 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-30 03:04:11.296577 | ubuntu-noble | changed 2026-08-30 03:04:11.305919 | 2026-08-30 03:04:11.306030 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-30 03:04:11.624692 | ubuntu-noble | ok 2026-08-30 03:04:11.637052 | 2026-08-30 03:04:11.637172 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-30 03:04:12.386334 | ubuntu-noble | changed 2026-08-30 03:04:12.391974 | 2026-08-30 03:04:12.392068 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-30 03:04:13.277635 | ubuntu-noble | changed 2026-08-30 03:04:13.290945 | 2026-08-30 03:04:13.291061 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-30 03:04:13.340541 | ubuntu-noble | skipping: Conditional result was False 2026-08-30 03:04:13.348642 | 2026-08-30 03:04:13.348939 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-30 03:04:14.285881 | ubuntu-noble -> localhost | changed 2026-08-30 03:04:14.319178 | 2026-08-30 03:04:14.319334 | TASK [add-build-sshkey : Add back temp key] 2026-08-30 03:04:15.123189 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/work/d68954adf8b5486ba4842b3a891b5ec2_id_rsa (zuul-build-sshkey) 2026-08-30 03:04:15.123450 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.026513 2026-08-30 03:04:15.134427 | 2026-08-30 03:04:15.134543 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-30 03:04:15.670054 | ubuntu-noble | ok 2026-08-30 03:04:15.678105 | 2026-08-30 03:04:15.678237 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-30 03:04:15.712380 | ubuntu-noble | skipping: Conditional result was False 2026-08-30 03:04:15.726419 | 2026-08-30 03:04:15.726538 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-30 03:04:16.227452 | ubuntu-noble | ok 2026-08-30 03:04:16.246638 | 2026-08-30 03:04:16.246765 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-30 03:04:16.283203 | ubuntu-noble | ok 2026-08-30 03:04:16.288223 | 2026-08-30 03:04:16.288338 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-30 03:04:16.663181 | ubuntu-noble -> localhost | ok 2026-08-30 03:04:16.669555 | 2026-08-30 03:04:16.669655 | TASK [validate-host : Collect information about the host] 2026-08-30 03:04:18.027142 | ubuntu-noble | ok 2026-08-30 03:04:18.044039 | 2026-08-30 03:04:18.044166 | TASK [validate-host : Sanitize hostname] 2026-08-30 03:04:18.165770 | ubuntu-noble | ok 2026-08-30 03:04:18.170211 | 2026-08-30 03:04:18.170333 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-30 03:04:19.367479 | ubuntu-noble -> localhost | changed 2026-08-30 03:04:19.373453 | 2026-08-30 03:04:19.373556 | TASK [validate-host : Collect information about zuul worker] 2026-08-30 03:04:19.941016 | ubuntu-noble | ok 2026-08-30 03:04:19.946748 | 2026-08-30 03:04:19.946866 | TASK [validate-host : Write out all zuul information for each host] 2026-08-30 03:04:20.712998 | ubuntu-noble -> localhost | changed 2026-08-30 03:04:20.733036 | 2026-08-30 03:04:20.733170 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-30 03:04:21.074050 | ubuntu-noble | ok 2026-08-30 03:04:21.079617 | 2026-08-30 03:04:21.079717 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-30 03:04:22.203902 | ubuntu-noble | changed: 2026-08-30 03:04:22.204096 | ubuntu-noble | cd+++++++++ src/ 2026-08-30 03:04:22.204132 | ubuntu-noble | cd+++++++++ src/github.com/ 2026-08-30 03:04:22.204157 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2026-08-30 03:04:22.204179 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2026-08-30 03:04:22.204199 | ubuntu-noble | Containerfile 2026-08-30 03:04:22.271740 | ubuntu-noble | localhost | ok: "/var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/work/logs" 2026-08-30 03:05:19.128600 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/work/artifacts" 2026-08-30 03:05:19.472886 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/d68954adf8b5486ba4842b3a891b5ec2/work/docs" 2026-08-30 03:05:19.490505 | 2026-08-30 03:05:19.490650 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-30 03:05:20.243953 | ubuntu-noble | changed: 2026-08-30 03:05:20.244124 | ubuntu-noble | .d..t...... ./ 2026-08-30 03:05:20.244152 | ubuntu-noble | >f+++++++++ net-debug.post.txt 2026-08-30 03:05:20.244173 | ubuntu-noble | >f+++++++++ net-debug.pre.txt 2026-08-30 03:05:20.244191 | ubuntu-noble | >f+++++++++ sudo-debug.txt 2026-08-30 03:05:20.244208 | ubuntu-noble | >f+++++++++ sudo-strace.log 2026-08-30 03:05:20.244225 | ubuntu-noble | >f+++++++++ sudo-strace.log.time 2026-08-30 03:05:20.244255 | ubuntu-noble | changed: All items complete 2026-08-30 03:05:20.244274 | 2026-08-30 03:05:20.859390 | ubuntu-noble | changed: .d..t...... ./ 2026-08-30 03:05:21.654690 | ubuntu-noble | changed: .d..t...... ./ 2026-08-30 03:05:21.677720 | 2026-08-30 03:05:21.677839 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-30 03:05:21.745239 | ubuntu-noble | skipping: Conditional result was False 2026-08-30 03:05:21.753247 | ubuntu-noble | skipping: Conditional result was False 2026-08-30 03:05:21.762975 | 2026-08-30 03:05:21.763086 | PLAY RECAP 2026-08-30 03:05:21.763556 | ubuntu-noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-30 03:05:21.763617 | 2026-08-30 03:05:21.937660 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-30 03:05:21.938422 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-30 03:05:23.265354 | 2026-08-30 03:05:23.265505 | PLAY [Base post] 2026-08-30 03:05:23.288214 | 2026-08-30 03:05:23.288376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-30 03:05:24.609282 | ubuntu-noble | changed 2026-08-30 03:05:24.668948 | 2026-08-30 03:05:24.696663 | PLAY RECAP 2026-08-30 03:05:24.698385 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-30 03:05:24.699333 | 2026-08-30 03:05:25.016017 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-30 03:05:25.017251 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-30 03:05:26.462946 | 2026-08-30 03:05:26.463071 | PLAY [Base post-logs] 2026-08-30 03:05:26.480622 | 2026-08-30 03:05:26.480968 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-30 03:05:27.343318 | localhost | changed 2026-08-30 03:05:27.351667 | 2026-08-30 03:05:27.351796 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-30 03:05:27.404799 | localhost | ok 2026-08-30 03:05:27.407770 | 2026-08-30 03:05:27.407847 | TASK [Set zuul-log-path fact] 2026-08-30 03:05:27.432238 | localhost | ok 2026-08-30 03:05:27.439068 | 2026-08-30 03:05:27.439161 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-30 03:05:27.463952 | localhost | ok 2026-08-30 03:05:27.466390 | 2026-08-30 03:05:27.466466 | TASK [upload-logs : Create log directories] 2026-08-30 03:05:28.206054 | localhost | changed 2026-08-30 03:05:28.208706 | 2026-08-30 03:05:28.208802 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-30 03:05:28.670069 | localhost -> localhost | ok: Runtime: 0:00:00.017958 2026-08-30 03:05:28.673681 | 2026-08-30 03:05:28.673781 | TASK [upload-logs : Upload logs to log server] 2026-08-30 03:05:29.175983 | localhost | Output suppressed because no_log was given 2026-08-30 03:05:29.177687 | 2026-08-30 03:05:29.177774 | LOOP [upload-logs : Compress console log and json output] 2026-08-30 03:05:29.243741 | localhost | skipping: Conditional result was False 2026-08-30 03:05:29.262678 | localhost | skipping: Conditional result was False 2026-08-30 03:05:29.266568 | 2026-08-30 03:05:29.266678 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-30 03:05:29.322345 | localhost | skipping: Conditional result was False 2026-08-30 03:05:29.322604 | 2026-08-30 03:05:29.329155 | localhost | skipping: Conditional result was False 2026-08-30 03:05:29.332751 | 2026-08-30 03:05:29.332856 | LOOP [upload-logs : Upload console log and json output]