2026-09-22 03:06:51.086482 | Job console starting 2026-09-22 03:06:51.098872 | Updating git repos 2026-09-22 03:06:51.123080 | Cloning repos into workspace 2026-09-22 03:06:51.156446 | Restoring repo states 2026-09-22 03:06:51.162329 | Merging changes 2026-09-22 03:06:51.162347 | Checking out repos 2026-09-22 03:06:51.220947 | Preparing playbooks 2026-09-22 03:06:52.343571 | Running Ansible setup 2026-09-22 03:06:56.970966 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-22 03:06:57.828392 | 2026-09-22 03:06:57.828576 | PLAY [Base pre] 2026-09-22 03:06:57.890026 | 2026-09-22 03:06:57.890185 | TASK [Setup log path fact] 2026-09-22 03:06:57.923347 | debian-bookworm | ok 2026-09-22 03:06:57.944004 | 2026-09-22 03:06:57.944159 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-22 03:06:57.989873 | debian-bookworm | ok 2026-09-22 03:06:58.006275 | 2026-09-22 03:06:58.006407 | TASK [emit-job-header : Print job information] 2026-09-22 03:06:58.066368 | # Job Information 2026-09-22 03:06:58.066583 | Ansible Version: 2.16.14 2026-09-22 03:06:58.066619 | Job: terraform-base-validate-testbed-default 2026-09-22 03:06:58.066652 | Pipeline: periodic-daily 2026-09-22 03:06:58.066676 | Executor: 521e9411259a 2026-09-22 03:06:58.066696 | Triggered by: https://github.com/osism/terraform-base 2026-09-22 03:06:58.066718 | Event ID: b8f5835583104c8fb431bce9b5f24956 2026-09-22 03:06:58.071788 | 2026-09-22 03:06:58.071909 | LOOP [emit-job-header : Print node information] 2026-09-22 03:06:58.273927 | debian-bookworm | ok: 2026-09-22 03:06:58.274215 | debian-bookworm | # Node Information 2026-09-22 03:06:58.274255 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-22 03:06:58.274282 | debian-bookworm | Hostname: debian 2026-09-22 03:06:58.274305 | debian-bookworm | Username: zuul 2026-09-22 03:06:58.274326 | debian-bookworm | Distro: Debian 12.15 2026-09-22 03:06:58.274357 | debian-bookworm | Provider: regiocloud-a 2026-09-22 03:06:58.274383 | debian-bookworm | Region: 2026-09-22 03:06:58.274406 | debian-bookworm | Label: debian-bookworm 2026-09-22 03:06:58.274427 | debian-bookworm | Product Name: OpenStack Nova 2026-09-22 03:06:58.274530 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee3:aa6d 2026-09-22 03:06:58.290706 | 2026-09-22 03:06:58.290840 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-22 03:06:58.802022 | debian-bookworm -> localhost | changed 2026-09-22 03:06:58.820011 | 2026-09-22 03:06:58.820179 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-22 03:06:59.930551 | debian-bookworm -> localhost | changed 2026-09-22 03:06:59.948915 | 2026-09-22 03:06:59.949120 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-22 03:07:00.236989 | debian-bookworm -> localhost | ok 2026-09-22 03:07:00.244153 | 2026-09-22 03:07:00.244274 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-22 03:07:00.276606 | debian-bookworm | ok 2026-09-22 03:07:00.294146 | debian-bookworm | included: /var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-22 03:07:00.301012 | 2026-09-22 03:07:00.301121 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-22 03:07:02.331287 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-22 03:07:02.331524 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/work/7c583146819b481aa29fa7e60990e035_id_rsa 2026-09-22 03:07:02.331580 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/work/7c583146819b481aa29fa7e60990e035_id_rsa.pub 2026-09-22 03:07:02.331606 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-22 03:07:02.331630 | debian-bookworm -> localhost | SHA256:zpdw1JsMLK5PxuWY7IOjFLgQMz2j6XKlb/N5fmF2XrI zuul-build-sshkey 2026-09-22 03:07:02.331672 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-22 03:07:02.331695 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-22 03:07:02.331716 | debian-bookworm -> localhost | | | 2026-09-22 03:07:02.331738 | debian-bookworm -> localhost | | . . . | 2026-09-22 03:07:02.331758 | debian-bookworm -> localhost | |+ + . + . | 2026-09-22 03:07:02.331778 | debian-bookworm -> localhost | | * + . o o o | 2026-09-22 03:07:02.331798 | debian-bookworm -> localhost | |+ . o S o + | 2026-09-22 03:07:02.331830 | debian-bookworm -> localhost | |.. + . * B+.o . | 2026-09-22 03:07:02.331853 | debian-bookworm -> localhost | |..+ . ..Xo++ + | 2026-09-22 03:07:02.331873 | debian-bookworm -> localhost | |.. oo o*o.. E | 2026-09-22 03:07:02.331895 | debian-bookworm -> localhost | | .o+o++o | 2026-09-22 03:07:02.331915 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-22 03:07:02.331971 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.585601 2026-09-22 03:07:02.338063 | 2026-09-22 03:07:02.338156 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-22 03:07:02.366334 | debian-bookworm | ok 2026-09-22 03:07:02.375797 | debian-bookworm | included: /var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-22 03:07:02.383516 | 2026-09-22 03:07:02.383590 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-22 03:07:02.406298 | debian-bookworm | skipping: Conditional result was False 2026-09-22 03:07:02.411961 | 2026-09-22 03:07:02.412044 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-22 03:07:03.087622 | debian-bookworm | changed 2026-09-22 03:07:03.094677 | 2026-09-22 03:07:03.094785 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-22 03:07:03.362722 | debian-bookworm | ok 2026-09-22 03:07:03.370132 | 2026-09-22 03:07:03.370231 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-22 03:07:04.049690 | debian-bookworm | changed 2026-09-22 03:07:04.054752 | 2026-09-22 03:07:04.054836 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-22 03:07:04.778925 | debian-bookworm | changed 2026-09-22 03:07:04.787730 | 2026-09-22 03:07:04.787847 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-22 03:07:04.816553 | debian-bookworm | skipping: Conditional result was False 2026-09-22 03:07:04.823483 | 2026-09-22 03:07:04.823596 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-22 03:07:05.220826 | debian-bookworm -> localhost | changed 2026-09-22 03:07:05.234056 | 2026-09-22 03:07:05.234148 | TASK [add-build-sshkey : Add back temp key] 2026-09-22 03:07:05.662536 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/work/7c583146819b481aa29fa7e60990e035_id_rsa (zuul-build-sshkey) 2026-09-22 03:07:05.662744 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011996 2026-09-22 03:07:05.670124 | 2026-09-22 03:07:05.670224 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-22 03:07:06.077028 | debian-bookworm | ok 2026-09-22 03:07:06.082091 | 2026-09-22 03:07:06.082174 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-22 03:07:06.104819 | debian-bookworm | skipping: Conditional result was False 2026-09-22 03:07:06.114452 | 2026-09-22 03:07:06.114542 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-22 03:07:06.548271 | debian-bookworm | ok 2026-09-22 03:07:06.560534 | 2026-09-22 03:07:06.560621 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-22 03:07:06.597947 | debian-bookworm | ok 2026-09-22 03:07:06.602956 | 2026-09-22 03:07:06.603034 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-22 03:07:06.922987 | debian-bookworm -> localhost | ok 2026-09-22 03:07:06.929363 | 2026-09-22 03:07:06.929477 | TASK [validate-host : Collect information about the host] 2026-09-22 03:07:08.224898 | debian-bookworm | ok 2026-09-22 03:07:08.248667 | 2026-09-22 03:07:08.248815 | TASK [validate-host : Sanitize hostname] 2026-09-22 03:07:08.447364 | debian-bookworm | ok 2026-09-22 03:07:08.452584 | 2026-09-22 03:07:08.452685 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-22 03:07:09.331047 | debian-bookworm -> localhost | changed 2026-09-22 03:07:09.336970 | 2026-09-22 03:07:09.337061 | TASK [validate-host : Collect information about zuul worker] 2026-09-22 03:07:09.881192 | debian-bookworm | ok 2026-09-22 03:07:09.885989 | 2026-09-22 03:07:09.886080 | TASK [validate-host : Write out all zuul information for each host] 2026-09-22 03:07:10.434959 | debian-bookworm -> localhost | changed 2026-09-22 03:07:10.444702 | 2026-09-22 03:07:10.444792 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-22 03:07:10.728786 | debian-bookworm | ok 2026-09-22 03:07:10.737365 | 2026-09-22 03:07:10.737478 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-22 03:07:11.718012 | debian-bookworm | changed: 2026-09-22 03:07:11.718177 | debian-bookworm | cd+++++++++ src/ 2026-09-22 03:07:11.718210 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-22 03:07:11.718234 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-22 03:07:11.718255 | debian-bookworm | cd+++++++++ src/github.com/osism/terraform-base/ 2026-09-22 03:07:11.718276 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/work/logs" 2026-09-22 03:08:05.771981 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/work/artifacts" 2026-09-22 03:08:05.977257 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7c583146819b481aa29fa7e60990e035/work/docs" 2026-09-22 03:08:05.988267 | 2026-09-22 03:08:05.988444 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-22 03:08:06.986168 | debian-bookworm | changed: 2026-09-22 03:08:06.986413 | debian-bookworm | .d..t...... ./ 2026-09-22 03:08:06.986456 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-22 03:08:06.986481 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-22 03:08:06.986504 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-22 03:08:06.986527 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-22 03:08:06.986548 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-22 03:08:06.986584 | debian-bookworm | changed: All items complete 2026-09-22 03:08:06.986606 | 2026-09-22 03:08:07.801741 | debian-bookworm | changed: .d..t...... ./ 2026-09-22 03:08:08.551632 | debian-bookworm | changed: .d..t...... ./ 2026-09-22 03:08:08.571669 | 2026-09-22 03:08:08.571757 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-22 03:08:08.617339 | debian-bookworm | skipping: Conditional result was False 2026-09-22 03:08:08.620726 | debian-bookworm | skipping: Conditional result was False 2026-09-22 03:08:08.634231 | 2026-09-22 03:08:08.634292 | PLAY RECAP 2026-09-22 03:08:08.634343 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-22 03:08:08.634363 | 2026-09-22 03:08:08.818650 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-22 03:08:08.819298 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-22 03:08:09.407429 | 2026-09-22 03:08:09.407533 | PLAY [Base post] 2026-09-22 03:08:09.418170 | 2026-09-22 03:08:09.418256 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-22 03:08:09.980969 | debian-bookworm | changed 2026-09-22 03:08:09.991015 | 2026-09-22 03:08:09.991146 | PLAY RECAP 2026-09-22 03:08:09.991222 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-22 03:08:09.991294 | 2026-09-22 03:08:10.120525 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-22 03:08:10.122618 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-22 03:08:10.903039 | 2026-09-22 03:08:10.903174 | PLAY [Base post-logs] 2026-09-22 03:08:10.918916 | 2026-09-22 03:08:10.919033 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-22 03:08:11.587012 | localhost | changed 2026-09-22 03:08:11.597722 | 2026-09-22 03:08:11.597852 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-22 03:08:11.651987 | localhost | ok 2026-09-22 03:08:11.655023 | 2026-09-22 03:08:11.655108 | TASK [Set zuul-log-path fact] 2026-09-22 03:08:11.679858 | localhost | ok 2026-09-22 03:08:11.687296 | 2026-09-22 03:08:11.687408 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-22 03:08:11.746764 | localhost | ok 2026-09-22 03:08:11.749638 | 2026-09-22 03:08:11.749745 | TASK [upload-logs : Create log directories] 2026-09-22 03:08:12.229086 | localhost | changed 2026-09-22 03:08:12.231607 | 2026-09-22 03:08:12.231693 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-22 03:08:12.710624 | localhost -> localhost | ok: Runtime: 0:00:00.009353 2026-09-22 03:08:12.714295 | 2026-09-22 03:08:12.714407 | TASK [upload-logs : Upload logs to log server] 2026-09-22 03:08:13.303547 | localhost | Output suppressed because no_log was given 2026-09-22 03:08:13.305090 | 2026-09-22 03:08:13.305170 | LOOP [upload-logs : Compress console log and json output] 2026-09-22 03:08:13.374293 | localhost | skipping: Conditional result was False 2026-09-22 03:08:13.388951 | localhost | skipping: Conditional result was False 2026-09-22 03:08:13.400811 | 2026-09-22 03:08:13.400905 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-22 03:08:13.455029 | localhost | skipping: Conditional result was False 2026-09-22 03:08:13.455235 | 2026-09-22 03:08:13.460520 | localhost | skipping: Conditional result was False 2026-09-22 03:08:13.466273 | 2026-09-22 03:08:13.466380 | LOOP [upload-logs : Upload console log and json output]