2026-05-12 01:53:25.624860 | Job console starting 2026-05-12 01:53:25.673666 | Updating git repos 2026-05-12 01:53:25.750761 | Cloning repos into workspace 2026-05-12 01:53:25.832685 | Restoring repo states 2026-05-12 01:53:25.859132 | Merging changes 2026-05-12 01:53:26.576199 | Checking out repos 2026-05-12 01:53:26.629438 | Preparing playbooks 2026-05-12 01:53:27.803636 | Running Ansible setup 2026-05-12 01:53:34.379959 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-12 01:53:35.688031 | 2026-05-12 01:53:35.688247 | PLAY [Base pre] 2026-05-12 01:53:35.730108 | 2026-05-12 01:53:35.730302 | TASK [Setup log path fact] 2026-05-12 01:53:35.772232 | debian-bookworm | ok 2026-05-12 01:53:35.802692 | 2026-05-12 01:53:35.802951 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 01:53:35.839565 | debian-bookworm | ok 2026-05-12 01:53:35.854757 | 2026-05-12 01:53:35.854996 | TASK [emit-job-header : Print job information] 2026-05-12 01:53:35.895393 | # Job Information 2026-05-12 01:53:35.895602 | Ansible Version: 2.16.14 2026-05-12 01:53:35.895638 | Job: ansible-collection-ensure-readme 2026-05-12 01:53:35.895670 | Pipeline: check 2026-05-12 01:53:35.895692 | Executor: 521e9411259a 2026-05-12 01:53:35.895714 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1964 2026-05-12 01:53:35.895736 | Event ID: 578e9260-4da5-11f1-9068-d9ef0784296d 2026-05-12 01:53:35.901051 | 2026-05-12 01:53:35.901184 | LOOP [emit-job-header : Print node information] 2026-05-12 01:53:36.057959 | debian-bookworm | ok: 2026-05-12 01:53:36.058175 | debian-bookworm | # Node Information 2026-05-12 01:53:36.058210 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-12 01:53:36.058235 | debian-bookworm | Hostname: debian 2026-05-12 01:53:36.058258 | debian-bookworm | Username: zuul 2026-05-12 01:53:36.058279 | debian-bookworm | Distro: Debian 12.13 2026-05-12 01:53:36.058303 | debian-bookworm | Provider: regiocloud-a 2026-05-12 01:53:36.058324 | debian-bookworm | Region: 2026-05-12 01:53:36.058345 | debian-bookworm | Label: debian-bookworm 2026-05-12 01:53:36.058364 | debian-bookworm | Product Name: OpenStack Nova 2026-05-12 01:53:36.058384 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe15:e50f 2026-05-12 01:53:36.097082 | 2026-05-12 01:53:36.097271 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-12 01:53:36.783334 | debian-bookworm -> localhost | changed 2026-05-12 01:53:36.793560 | 2026-05-12 01:53:36.793708 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-12 01:53:38.744489 | debian-bookworm -> localhost | changed 2026-05-12 01:53:38.758121 | 2026-05-12 01:53:38.758314 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-12 01:53:39.223146 | debian-bookworm -> localhost | ok 2026-05-12 01:53:39.231636 | 2026-05-12 01:53:39.231775 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-12 01:53:39.284762 | debian-bookworm | ok 2026-05-12 01:53:39.350285 | debian-bookworm | included: /var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-12 01:53:39.365450 | 2026-05-12 01:53:39.365590 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-12 01:53:43.640050 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-12 01:53:43.640329 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/work/ac25b8d5b6844c14a8a357294049c44c_id_rsa 2026-05-12 01:53:43.640369 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/work/ac25b8d5b6844c14a8a357294049c44c_id_rsa.pub 2026-05-12 01:53:43.640396 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-12 01:53:43.640420 | debian-bookworm -> localhost | SHA256:nfZbkxAbTdN+4e1jVLbUBvQkSs5GFr3Q37XOk5AqtUY zuul-build-sshkey 2026-05-12 01:53:43.640455 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-12 01:53:43.640478 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-12 01:53:43.640498 | debian-bookworm -> localhost | | ==*oo| 2026-05-12 01:53:43.640520 | debian-bookworm -> localhost | | *.+oBB| 2026-05-12 01:53:43.640540 | debian-bookworm -> localhost | | B.=*X| 2026-05-12 01:53:43.640560 | debian-bookworm -> localhost | | . E *.=*| 2026-05-12 01:53:43.640579 | debian-bookworm -> localhost | | S * = =.o| 2026-05-12 01:53:43.640607 | debian-bookworm -> localhost | | o = . O.| 2026-05-12 01:53:43.640628 | debian-bookworm -> localhost | | o . = o| 2026-05-12 01:53:43.640647 | debian-bookworm -> localhost | | o . | 2026-05-12 01:53:43.640667 | debian-bookworm -> localhost | | . | 2026-05-12 01:53:43.640688 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-12 01:53:43.640747 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.343862 2026-05-12 01:53:43.647418 | 2026-05-12 01:53:43.647543 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-12 01:53:43.667065 | debian-bookworm | ok 2026-05-12 01:53:43.677599 | debian-bookworm | included: /var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-12 01:53:43.686721 | 2026-05-12 01:53:43.686821 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-12 01:53:43.702373 | debian-bookworm | skipping: Conditional result was False 2026-05-12 01:53:43.712074 | 2026-05-12 01:53:43.712203 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-12 01:53:44.392112 | debian-bookworm | changed 2026-05-12 01:53:44.397304 | 2026-05-12 01:53:44.397401 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-12 01:53:44.688123 | debian-bookworm | ok 2026-05-12 01:53:44.693770 | 2026-05-12 01:53:44.693870 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-12 01:53:45.400706 | debian-bookworm | changed 2026-05-12 01:53:45.407508 | 2026-05-12 01:53:45.407621 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-12 01:53:46.107021 | debian-bookworm | changed 2026-05-12 01:53:46.113592 | 2026-05-12 01:53:46.113710 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-12 01:53:46.137440 | debian-bookworm | skipping: Conditional result was False 2026-05-12 01:53:46.144539 | 2026-05-12 01:53:46.144645 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-12 01:53:46.675942 | debian-bookworm -> localhost | changed 2026-05-12 01:53:46.686402 | 2026-05-12 01:53:46.686508 | TASK [add-build-sshkey : Add back temp key] 2026-05-12 01:53:47.143857 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/work/ac25b8d5b6844c14a8a357294049c44c_id_rsa (zuul-build-sshkey) 2026-05-12 01:53:47.144074 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012893 2026-05-12 01:53:47.151623 | 2026-05-12 01:53:47.151713 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-12 01:53:47.554721 | debian-bookworm | ok 2026-05-12 01:53:47.560081 | 2026-05-12 01:53:47.560180 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-12 01:53:47.583408 | debian-bookworm | skipping: Conditional result was False 2026-05-12 01:53:47.598611 | 2026-05-12 01:53:47.598775 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-12 01:53:48.018799 | debian-bookworm | ok 2026-05-12 01:53:48.029072 | 2026-05-12 01:53:48.029185 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-12 01:53:48.056948 | debian-bookworm | ok 2026-05-12 01:53:48.062420 | 2026-05-12 01:53:48.063552 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-12 01:53:48.321485 | debian-bookworm -> localhost | ok 2026-05-12 01:53:48.328225 | 2026-05-12 01:53:48.328332 | TASK [validate-host : Collect information about the host] 2026-05-12 01:53:49.415979 | debian-bookworm | ok 2026-05-12 01:53:49.448155 | 2026-05-12 01:53:49.448305 | TASK [validate-host : Sanitize hostname] 2026-05-12 01:53:49.639976 | debian-bookworm | ok 2026-05-12 01:53:49.689875 | 2026-05-12 01:53:49.690041 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-12 01:53:50.487667 | debian-bookworm -> localhost | changed 2026-05-12 01:53:50.515717 | 2026-05-12 01:53:50.515942 | TASK [validate-host : Collect information about zuul worker] 2026-05-12 01:53:50.982180 | debian-bookworm | ok 2026-05-12 01:53:51.003978 | 2026-05-12 01:53:51.004191 | TASK [validate-host : Write out all zuul information for each host] 2026-05-12 01:53:51.904874 | debian-bookworm -> localhost | changed 2026-05-12 01:53:51.922896 | 2026-05-12 01:53:51.923289 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-12 01:53:52.232186 | debian-bookworm | ok 2026-05-12 01:53:52.240934 | 2026-05-12 01:53:52.241063 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-12 01:53:54.281245 | debian-bookworm | changed: 2026-05-12 01:53:54.281616 | debian-bookworm | cd+++++++++ src/ 2026-05-12 01:53:54.281664 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-12 01:53:54.281694 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-12 01:53:54.281720 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2026-05-12 01:53:54.281746 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/work/logs" 2026-05-12 01:54:12.637050 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/work/artifacts" 2026-05-12 01:54:12.932631 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ac25b8d5b6844c14a8a357294049c44c/work/docs" 2026-05-12 01:54:12.954121 | 2026-05-12 01:54:12.954388 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-12 01:54:13.863589 | debian-bookworm | changed: 2026-05-12 01:54:13.864042 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-12 01:54:13.864109 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-12 01:54:13.864162 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-12 01:54:13.864236 | debian-bookworm | changed: All items complete 2026-05-12 01:54:13.864280 | 2026-05-12 01:54:14.509877 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 01:54:15.117146 | debian-bookworm | changed: .d..t...... ./ 2026-05-12 01:54:15.139251 | 2026-05-12 01:54:15.139390 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-12 01:54:15.754973 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011454 2026-05-12 01:54:16.129822 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.016058 2026-05-12 01:54:16.145485 | 2026-05-12 01:54:16.145642 | PLAY RECAP 2026-05-12 01:54:16.145703 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-12 01:54:16.145729 | 2026-05-12 01:54:16.325887 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-12 01:54:16.326942 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 01:54:17.115528 | 2026-05-12 01:54:17.115710 | PLAY [Base post] 2026-05-12 01:54:17.130278 | 2026-05-12 01:54:17.130446 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-12 01:54:17.756673 | debian-bookworm | changed 2026-05-12 01:54:17.763865 | 2026-05-12 01:54:17.764025 | PLAY RECAP 2026-05-12 01:54:17.764093 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-12 01:54:17.764171 | 2026-05-12 01:54:17.900001 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 01:54:17.900999 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-12 01:54:18.754250 | 2026-05-12 01:54:18.754455 | PLAY [Base post-logs] 2026-05-12 01:54:18.767627 | 2026-05-12 01:54:18.767806 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-12 01:54:19.219324 | localhost | changed 2026-05-12 01:54:19.231789 | 2026-05-12 01:54:19.232002 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-12 01:54:19.268994 | localhost | ok 2026-05-12 01:54:19.272452 | 2026-05-12 01:54:19.272563 | TASK [Set zuul-log-path fact] 2026-05-12 01:54:19.288349 | localhost | ok 2026-05-12 01:54:19.297227 | 2026-05-12 01:54:19.297350 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 01:54:19.324521 | localhost | ok 2026-05-12 01:54:19.327691 | 2026-05-12 01:54:19.327793 | TASK [upload-logs : Create log directories] 2026-05-12 01:54:19.927622 | localhost | changed 2026-05-12 01:54:19.932596 | 2026-05-12 01:54:19.932778 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-12 01:54:20.467339 | localhost -> localhost | ok: Runtime: 0:00:00.009684 2026-05-12 01:54:20.471809 | 2026-05-12 01:54:20.471960 | TASK [upload-logs : Upload logs to log server] 2026-05-12 01:54:21.160847 | localhost | Output suppressed because no_log was given 2026-05-12 01:54:21.163147 | 2026-05-12 01:54:21.163279 | LOOP [upload-logs : Compress console log and json output] 2026-05-12 01:54:21.220269 | localhost | skipping: Conditional result was False 2026-05-12 01:54:21.225344 | localhost | skipping: Conditional result was False 2026-05-12 01:54:21.234158 | 2026-05-12 01:54:21.234279 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-12 01:54:21.292746 | localhost | skipping: Conditional result was False 2026-05-12 01:54:21.293059 | 2026-05-12 01:54:21.300020 | localhost | skipping: Conditional result was False 2026-05-12 01:54:21.315707 | 2026-05-12 01:54:21.315981 | LOOP [upload-logs : Upload console log and json output]