2026-09-10 03:05:45.366946 | Job console starting 2026-09-10 03:05:45.381886 | Updating git repos 2026-09-10 03:05:45.407833 | Cloning repos into workspace 2026-09-10 03:05:45.445001 | Restoring repo states 2026-09-10 03:05:45.448515 | Merging changes 2026-09-10 03:05:45.448527 | Checking out repos 2026-09-10 03:05:45.517158 | Preparing playbooks 2026-09-10 03:05:46.369666 | Running Ansible setup 2026-09-10 03:05:50.760933 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 03:05:51.796963 | 2026-09-10 03:05:51.797087 | PLAY [Base pre] 2026-09-10 03:05:51.813832 | 2026-09-10 03:05:51.813947 | TASK [Setup log path fact] 2026-09-10 03:05:51.831628 | debian-bookworm | ok 2026-09-10 03:05:51.847222 | 2026-09-10 03:05:51.847341 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 03:05:51.875792 | debian-bookworm | ok 2026-09-10 03:05:51.885861 | 2026-09-10 03:05:51.885951 | TASK [emit-job-header : Print job information] 2026-09-10 03:05:51.924497 | # Job Information 2026-09-10 03:05:51.924637 | Ansible Version: 2.16.14 2026-09-10 03:05:51.924670 | Job: terraform-base-validate-testbed-default 2026-09-10 03:05:51.924703 | Pipeline: periodic-daily 2026-09-10 03:05:51.924726 | Executor: 521e9411259a 2026-09-10 03:05:51.924776 | Triggered by: https://github.com/osism/terraform-base 2026-09-10 03:05:51.924800 | Event ID: d36945b75f4d4c189fb4af4615a02f47 2026-09-10 03:05:51.929502 | 2026-09-10 03:05:51.929606 | LOOP [emit-job-header : Print node information] 2026-09-10 03:05:52.026914 | debian-bookworm | ok: 2026-09-10 03:05:52.027127 | debian-bookworm | # Node Information 2026-09-10 03:05:52.027162 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-10 03:05:52.027187 | debian-bookworm | Hostname: debian 2026-09-10 03:05:52.027209 | debian-bookworm | Username: zuul 2026-09-10 03:05:52.027231 | debian-bookworm | Distro: Debian 12.15 2026-09-10 03:05:52.027254 | debian-bookworm | Provider: regiocloud-a 2026-09-10 03:05:52.027274 | debian-bookworm | Region: 2026-09-10 03:05:52.027295 | debian-bookworm | Label: debian-bookworm 2026-09-10 03:05:52.027316 | debian-bookworm | Product Name: OpenStack Nova 2026-09-10 03:05:52.027336 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1d:fad8 2026-09-10 03:05:52.051321 | 2026-09-10 03:05:52.051428 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-10 03:05:52.583728 | debian-bookworm -> localhost | changed 2026-09-10 03:05:52.590346 | 2026-09-10 03:05:52.590428 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-10 03:05:53.668040 | debian-bookworm -> localhost | changed 2026-09-10 03:05:53.677010 | 2026-09-10 03:05:53.677094 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-10 03:05:53.883131 | debian-bookworm -> localhost | ok 2026-09-10 03:05:53.889226 | 2026-09-10 03:05:53.889325 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-10 03:05:53.908520 | debian-bookworm | ok 2026-09-10 03:05:53.923684 | debian-bookworm | included: /var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-10 03:05:53.930457 | 2026-09-10 03:05:53.930544 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-10 03:05:57.025208 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-10 03:05:57.025362 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/work/d1b518f85c044cbe8a1f5bc5d6d58fee_id_rsa 2026-09-10 03:05:57.025393 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/work/d1b518f85c044cbe8a1f5bc5d6d58fee_id_rsa.pub 2026-09-10 03:05:57.025414 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-10 03:05:57.025434 | debian-bookworm -> localhost | SHA256:/EBjVVx+NApBBFA8CTVSbwsGgSp7ql6IRCaSsfdrk/0 zuul-build-sshkey 2026-09-10 03:05:57.025459 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-10 03:05:57.025478 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-10 03:05:57.025495 | debian-bookworm -> localhost | |. .*O**Bo....| 2026-09-10 03:05:57.025513 | debian-bookworm -> localhost | | + . o=o o....| 2026-09-10 03:05:57.025530 | debian-bookworm -> localhost | |=o. . +o.o .. .| 2026-09-10 03:05:57.025567 | debian-bookworm -> localhost | |=o o +..o . . | 2026-09-10 03:05:57.025587 | debian-bookworm -> localhost | | .o . S . | 2026-09-10 03:05:57.025611 | debian-bookworm -> localhost | |o... + o | 2026-09-10 03:05:57.025629 | debian-bookworm -> localhost | |..o.= . . | 2026-09-10 03:05:57.025647 | debian-bookworm -> localhost | | ... . . | 2026-09-10 03:05:57.025665 | debian-bookworm -> localhost | |+. E | 2026-09-10 03:05:57.025682 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-10 03:05:57.025726 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.569407 2026-09-10 03:05:57.031629 | 2026-09-10 03:05:57.031710 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-10 03:05:57.069249 | debian-bookworm | ok 2026-09-10 03:05:57.087160 | debian-bookworm | included: /var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-10 03:05:57.114531 | 2026-09-10 03:05:57.114623 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-10 03:05:57.175334 | debian-bookworm | skipping: Conditional result was False 2026-09-10 03:05:57.185388 | 2026-09-10 03:05:57.185484 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-10 03:05:58.276562 | debian-bookworm | changed 2026-09-10 03:05:58.282018 | 2026-09-10 03:05:58.282108 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-10 03:05:58.522225 | debian-bookworm | ok 2026-09-10 03:05:58.527129 | 2026-09-10 03:05:58.527212 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-10 03:05:59.169886 | debian-bookworm | changed 2026-09-10 03:05:59.176877 | 2026-09-10 03:05:59.176999 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-10 03:05:59.886783 | debian-bookworm | changed 2026-09-10 03:05:59.891214 | 2026-09-10 03:05:59.891288 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-10 03:05:59.903666 | debian-bookworm | skipping: Conditional result was False 2026-09-10 03:05:59.908483 | 2026-09-10 03:05:59.908552 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-10 03:06:00.579421 | debian-bookworm -> localhost | changed 2026-09-10 03:06:00.589478 | 2026-09-10 03:06:00.589800 | TASK [add-build-sshkey : Add back temp key] 2026-09-10 03:06:00.966447 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/work/d1b518f85c044cbe8a1f5bc5d6d58fee_id_rsa (zuul-build-sshkey) 2026-09-10 03:06:00.966669 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009597 2026-09-10 03:06:00.974627 | 2026-09-10 03:06:00.974725 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-10 03:06:01.385820 | debian-bookworm | ok 2026-09-10 03:06:01.394253 | 2026-09-10 03:06:01.394366 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-10 03:06:01.428394 | debian-bookworm | skipping: Conditional result was False 2026-09-10 03:06:01.496703 | 2026-09-10 03:06:01.497225 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-10 03:06:01.991142 | debian-bookworm | ok 2026-09-10 03:06:02.003785 | 2026-09-10 03:06:02.003883 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-10 03:06:02.031987 | debian-bookworm | ok 2026-09-10 03:06:02.038283 | 2026-09-10 03:06:02.038373 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-10 03:06:02.303968 | debian-bookworm -> localhost | ok 2026-09-10 03:06:02.310002 | 2026-09-10 03:06:02.310096 | TASK [validate-host : Collect information about the host] 2026-09-10 03:06:03.347176 | debian-bookworm | ok 2026-09-10 03:06:03.360142 | 2026-09-10 03:06:03.360225 | TASK [validate-host : Sanitize hostname] 2026-09-10 03:06:03.466202 | debian-bookworm | ok 2026-09-10 03:06:03.474590 | 2026-09-10 03:06:03.474668 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-10 03:06:04.043975 | debian-bookworm -> localhost | changed 2026-09-10 03:06:04.048606 | 2026-09-10 03:06:04.048687 | TASK [validate-host : Collect information about zuul worker] 2026-09-10 03:06:04.493385 | debian-bookworm | ok 2026-09-10 03:06:04.509190 | 2026-09-10 03:06:04.509407 | TASK [validate-host : Write out all zuul information for each host] 2026-09-10 03:06:05.085518 | debian-bookworm -> localhost | changed 2026-09-10 03:06:05.099284 | 2026-09-10 03:06:05.099513 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-10 03:06:05.400599 | debian-bookworm | ok 2026-09-10 03:06:05.409021 | 2026-09-10 03:06:05.409117 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-10 03:06:06.255987 | debian-bookworm | changed: 2026-09-10 03:06:06.256145 | debian-bookworm | cd+++++++++ src/ 2026-09-10 03:06:06.256179 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-10 03:06:06.256202 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-10 03:06:06.256225 | debian-bookworm | cd+++++++++ src/github.com/osism/terraform-base/ 2026-09-10 03:06:06.256246 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/work/logs" 2026-09-10 03:06:59.580273 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/work/artifacts" 2026-09-10 03:06:59.921706 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d1b518f85c044cbe8a1f5bc5d6d58fee/work/docs" 2026-09-10 03:06:59.938001 | 2026-09-10 03:06:59.938114 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-10 03:07:00.661275 | debian-bookworm | changed: 2026-09-10 03:07:00.661459 | debian-bookworm | .d..t...... ./ 2026-09-10 03:07:00.661493 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-10 03:07:00.661517 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-10 03:07:00.661539 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-10 03:07:00.661592 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-10 03:07:00.661614 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-10 03:07:00.661657 | debian-bookworm | changed: All items complete 2026-09-10 03:07:00.661685 | 2026-09-10 03:07:01.201765 | debian-bookworm | changed: .d..t...... ./ 2026-09-10 03:07:01.770355 | debian-bookworm | changed: .d..t...... ./ 2026-09-10 03:07:01.787473 | 2026-09-10 03:07:01.787561 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-10 03:07:01.808655 | debian-bookworm | skipping: Conditional result was False 2026-09-10 03:07:01.813912 | debian-bookworm | skipping: Conditional result was False 2026-09-10 03:07:01.831548 | 2026-09-10 03:07:01.831640 | PLAY RECAP 2026-09-10 03:07:01.831714 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-10 03:07:01.831751 | 2026-09-10 03:07:01.917671 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-10 03:07:01.919036 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 03:07:02.492240 | 2026-09-10 03:07:02.492348 | PLAY [Base post] 2026-09-10 03:07:02.510562 | 2026-09-10 03:07:02.510665 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-10 03:07:03.066859 | debian-bookworm | changed 2026-09-10 03:07:03.082251 | 2026-09-10 03:07:03.082344 | PLAY RECAP 2026-09-10 03:07:03.082398 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 03:07:03.082451 | 2026-09-10 03:07:03.203821 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 03:07:03.204579 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-10 03:07:04.017310 | 2026-09-10 03:07:04.017444 | PLAY [Base post-logs] 2026-09-10 03:07:04.027582 | 2026-09-10 03:07:04.027702 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-10 03:07:04.491093 | localhost | changed 2026-09-10 03:07:04.500305 | 2026-09-10 03:07:04.500430 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-10 03:07:04.524864 | localhost | ok 2026-09-10 03:07:04.527776 | 2026-09-10 03:07:04.527869 | TASK [Set zuul-log-path fact] 2026-09-10 03:07:04.542894 | localhost | ok 2026-09-10 03:07:04.550772 | 2026-09-10 03:07:04.550875 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 03:07:04.574768 | localhost | ok 2026-09-10 03:07:04.578554 | 2026-09-10 03:07:04.578649 | TASK [upload-logs : Create log directories] 2026-09-10 03:07:05.119608 | localhost | changed 2026-09-10 03:07:05.122788 | 2026-09-10 03:07:05.122908 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-10 03:07:05.664694 | localhost -> localhost | ok: Runtime: 0:00:00.004272 2026-09-10 03:07:05.669213 | 2026-09-10 03:07:05.669300 | TASK [upload-logs : Upload logs to log server] 2026-09-10 03:07:06.163251 | localhost | Output suppressed because no_log was given 2026-09-10 03:07:06.169852 | 2026-09-10 03:07:06.170002 | LOOP [upload-logs : Compress console log and json output] 2026-09-10 03:07:06.215093 | localhost | skipping: Conditional result was False 2026-09-10 03:07:06.233516 | localhost | skipping: Conditional result was False 2026-09-10 03:07:06.238299 | 2026-09-10 03:07:06.238389 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-10 03:07:06.277095 | localhost | skipping: Conditional result was False 2026-09-10 03:07:06.277321 | 2026-09-10 03:07:06.281506 | localhost | skipping: Conditional result was False 2026-09-10 03:07:06.293187 | 2026-09-10 03:07:06.293272 | LOOP [upload-logs : Upload console log and json output]