2026-06-01 09:04:19.384115 | Job console starting 2026-06-01 09:04:19.421497 | Updating git repos 2026-06-01 09:04:19.506647 | Cloning repos into workspace 2026-06-01 09:04:19.591566 | Restoring repo states 2026-06-01 09:04:19.620814 | Merging changes 2026-06-01 09:04:20.370697 | Checking out repos 2026-06-01 09:04:20.448907 | Preparing playbooks 2026-06-01 09:04:21.453960 | Running Ansible setup 2026-06-01 09:04:26.699842 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-01 09:04:27.559589 | 2026-06-01 09:04:27.559806 | PLAY [Base pre] 2026-06-01 09:04:27.576995 | 2026-06-01 09:04:27.577167 | TASK [Setup log path fact] 2026-06-01 09:04:27.606493 | debian-bookworm | ok 2026-06-01 09:04:27.625246 | 2026-06-01 09:04:27.625421 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-01 09:04:27.656129 | debian-bookworm | ok 2026-06-01 09:04:27.667962 | 2026-06-01 09:04:27.668108 | TASK [emit-job-header : Print job information] 2026-06-01 09:04:27.708121 | # Job Information 2026-06-01 09:04:27.708319 | Ansible Version: 2.16.14 2026-06-01 09:04:27.708355 | Job: ansible-collection-ensure-readme 2026-06-01 09:04:27.708387 | Pipeline: check 2026-06-01 09:04:27.708410 | Executor: 521e9411259a 2026-06-01 09:04:27.708431 | Triggered by: https://github.com/osism/ansible-collection-services/pull/2089 2026-06-01 09:04:27.708454 | Event ID: d9aa1276-5d98-11f1-9b6d-8fcb69ac7433 2026-06-01 09:04:27.713559 | 2026-06-01 09:04:27.713682 | LOOP [emit-job-header : Print node information] 2026-06-01 09:04:27.830221 | debian-bookworm | ok: 2026-06-01 09:04:27.830531 | debian-bookworm | # Node Information 2026-06-01 09:04:27.830574 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-01 09:04:27.830600 | debian-bookworm | Hostname: debian 2026-06-01 09:04:27.830623 | debian-bookworm | Username: zuul 2026-06-01 09:04:27.830644 | debian-bookworm | Distro: Debian 12.14 2026-06-01 09:04:27.830677 | debian-bookworm | Provider: regiocloud-a 2026-06-01 09:04:27.830704 | debian-bookworm | Region: 2026-06-01 09:04:27.830726 | debian-bookworm | Label: debian-bookworm 2026-06-01 09:04:27.830745 | debian-bookworm | Product Name: OpenStack Nova 2026-06-01 09:04:27.830765 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe63:ae4a 2026-06-01 09:04:27.851680 | 2026-06-01 09:04:27.851849 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-01 09:04:28.652300 | debian-bookworm -> localhost | changed 2026-06-01 09:04:28.659847 | 2026-06-01 09:04:28.659999 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-01 09:04:30.227311 | debian-bookworm -> localhost | changed 2026-06-01 09:04:30.248874 | 2026-06-01 09:04:30.249024 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-01 09:04:30.861240 | debian-bookworm -> localhost | ok 2026-06-01 09:04:30.869627 | 2026-06-01 09:04:30.869818 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-01 09:04:30.919909 | debian-bookworm | ok 2026-06-01 09:04:30.976371 | debian-bookworm | included: /var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-01 09:04:30.983336 | 2026-06-01 09:04:30.983463 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-01 09:04:33.310141 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-01 09:04:33.310321 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/work/db27b98a630b4b0884839ad877c6bb86_id_rsa 2026-06-01 09:04:33.310351 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/work/db27b98a630b4b0884839ad877c6bb86_id_rsa.pub 2026-06-01 09:04:33.310372 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-01 09:04:33.310391 | debian-bookworm -> localhost | SHA256:OBsOa9Hqmg2idzDd9b7AmXRVyg+f6xzyUbKAjK6lZ6Y zuul-build-sshkey 2026-06-01 09:04:33.310418 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-01 09:04:33.310436 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-01 09:04:33.310453 | debian-bookworm -> localhost | | . | 2026-06-01 09:04:33.310471 | debian-bookworm -> localhost | | . o | 2026-06-01 09:04:33.310487 | debian-bookworm -> localhost | | = | 2026-06-01 09:04:33.310503 | debian-bookworm -> localhost | | . ..o o + . | 2026-06-01 09:04:33.310519 | debian-bookworm -> localhost | | .o.=.S.+ . = .| 2026-06-01 09:04:33.310538 | debian-bookworm -> localhost | | o .*.B +. . = | 2026-06-01 09:04:33.310555 | debian-bookworm -> localhost | |. .o+ o B. . = | 2026-06-01 09:04:33.310570 | debian-bookworm -> localhost | |...*. ++.. = o | 2026-06-01 09:04:33.310587 | debian-bookworm -> localhost | |..ooo E= .. + | 2026-06-01 09:04:33.310603 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-01 09:04:33.310641 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.049130 2026-06-01 09:04:33.315794 | 2026-06-01 09:04:33.315880 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-01 09:04:33.342560 | debian-bookworm | ok 2026-06-01 09:04:33.350409 | debian-bookworm | included: /var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-01 09:04:33.367470 | 2026-06-01 09:04:33.367545 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-01 09:04:33.394525 | debian-bookworm | skipping: Conditional result was False 2026-06-01 09:04:33.399177 | 2026-06-01 09:04:33.399262 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-01 09:04:33.974392 | debian-bookworm | changed 2026-06-01 09:04:33.979537 | 2026-06-01 09:04:33.979621 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-01 09:04:34.241959 | debian-bookworm | ok 2026-06-01 09:04:34.247961 | 2026-06-01 09:04:34.248160 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-01 09:04:34.928918 | debian-bookworm | changed 2026-06-01 09:04:34.937561 | 2026-06-01 09:04:34.937661 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-01 09:04:35.625004 | debian-bookworm | changed 2026-06-01 09:04:35.633796 | 2026-06-01 09:04:35.633949 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-01 09:04:35.656012 | debian-bookworm | skipping: Conditional result was False 2026-06-01 09:04:35.662528 | 2026-06-01 09:04:35.662637 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-01 09:04:36.101582 | debian-bookworm -> localhost | changed 2026-06-01 09:04:36.114502 | 2026-06-01 09:04:36.114608 | TASK [add-build-sshkey : Add back temp key] 2026-06-01 09:04:36.433140 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/work/db27b98a630b4b0884839ad877c6bb86_id_rsa (zuul-build-sshkey) 2026-06-01 09:04:36.433359 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017677 2026-06-01 09:04:36.441410 | 2026-06-01 09:04:36.441562 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-01 09:04:36.875739 | debian-bookworm | ok 2026-06-01 09:04:36.880577 | 2026-06-01 09:04:36.880665 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-01 09:04:36.903701 | debian-bookworm | skipping: Conditional result was False 2026-06-01 09:04:36.913478 | 2026-06-01 09:04:36.913575 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-01 09:04:37.336439 | debian-bookworm | ok 2026-06-01 09:04:37.352777 | 2026-06-01 09:04:37.352962 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-01 09:04:37.381866 | debian-bookworm | ok 2026-06-01 09:04:37.386727 | 2026-06-01 09:04:37.386825 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-01 09:04:37.708761 | debian-bookworm -> localhost | ok 2026-06-01 09:04:37.715854 | 2026-06-01 09:04:37.715990 | TASK [validate-host : Collect information about the host] 2026-06-01 09:04:38.980689 | debian-bookworm | ok 2026-06-01 09:04:39.008471 | 2026-06-01 09:04:39.008636 | TASK [validate-host : Sanitize hostname] 2026-06-01 09:04:39.147752 | debian-bookworm | ok 2026-06-01 09:04:39.213113 | 2026-06-01 09:04:39.213265 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-01 09:04:39.829129 | debian-bookworm -> localhost | changed 2026-06-01 09:04:39.835480 | 2026-06-01 09:04:39.835617 | TASK [validate-host : Collect information about zuul worker] 2026-06-01 09:04:40.340571 | debian-bookworm | ok 2026-06-01 09:04:40.345655 | 2026-06-01 09:04:40.345799 | TASK [validate-host : Write out all zuul information for each host] 2026-06-01 09:04:41.347829 | debian-bookworm -> localhost | changed 2026-06-01 09:04:41.380443 | 2026-06-01 09:04:41.380639 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-01 09:04:41.790175 | debian-bookworm | ok 2026-06-01 09:04:41.797231 | 2026-06-01 09:04:41.797370 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-01 09:04:43.292560 | debian-bookworm | changed: 2026-06-01 09:04:43.292868 | debian-bookworm | cd+++++++++ src/ 2026-06-01 09:04:43.292918 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-01 09:04:43.292953 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-01 09:04:43.292984 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2026-06-01 09:04:43.293013 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/work/logs" 2026-06-01 09:05:03.242652 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/work/artifacts" 2026-06-01 09:05:03.710097 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/db27b98a630b4b0884839ad877c6bb86/work/docs" 2026-06-01 09:05:03.720977 | 2026-06-01 09:05:03.721114 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-01 09:05:05.020959 | debian-bookworm | changed: 2026-06-01 09:05:05.021210 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-01 09:05:05.021248 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-01 09:05:05.021283 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-01 09:05:05.021333 | debian-bookworm | changed: All items complete 2026-06-01 09:05:05.021360 | 2026-06-01 09:05:06.311951 | debian-bookworm | changed: .d..t...... ./ 2026-06-01 09:05:07.317089 | debian-bookworm | changed: .d..t...... ./ 2026-06-01 09:05:07.337341 | 2026-06-01 09:05:07.337434 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-01 09:05:08.899881 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.051499 2026-06-01 09:05:09.396778 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.020914 2026-06-01 09:05:09.411511 | 2026-06-01 09:05:09.411581 | PLAY RECAP 2026-06-01 09:05:09.411620 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-01 09:05:09.411641 | 2026-06-01 09:05:09.615271 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-01 09:05:09.616138 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-01 09:05:10.957735 | 2026-06-01 09:05:10.957870 | PLAY [Base post] 2026-06-01 09:05:11.000086 | 2026-06-01 09:05:11.000211 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-01 09:05:11.713535 | debian-bookworm | changed 2026-06-01 09:05:11.725756 | 2026-06-01 09:05:11.725874 | PLAY RECAP 2026-06-01 09:05:11.725927 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-01 09:05:11.725979 | 2026-06-01 09:05:11.827692 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-01 09:05:11.828331 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-01 09:05:13.154115 | 2026-06-01 09:05:13.154241 | PLAY [Base post-logs] 2026-06-01 09:05:13.178333 | 2026-06-01 09:05:13.178458 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-01 09:05:14.031049 | localhost | changed 2026-06-01 09:05:14.041753 | 2026-06-01 09:05:14.041879 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-01 09:05:14.089961 | localhost | ok 2026-06-01 09:05:14.092409 | 2026-06-01 09:05:14.092480 | TASK [Set zuul-log-path fact] 2026-06-01 09:05:14.106234 | localhost | ok 2026-06-01 09:05:14.112454 | 2026-06-01 09:05:14.112547 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-01 09:05:14.136429 | localhost | ok 2026-06-01 09:05:14.138795 | 2026-06-01 09:05:14.138895 | TASK [upload-logs : Create log directories] 2026-06-01 09:05:14.869149 | localhost | changed 2026-06-01 09:05:14.871282 | 2026-06-01 09:05:14.871359 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-01 09:05:15.493112 | localhost -> localhost | ok: Runtime: 0:00:00.004191 2026-06-01 09:05:15.497129 | 2026-06-01 09:05:15.497211 | TASK [upload-logs : Upload logs to log server] 2026-06-01 09:05:16.488953 | localhost | Output suppressed because no_log was given 2026-06-01 09:05:16.491928 | 2026-06-01 09:05:16.492018 | LOOP [upload-logs : Compress console log and json output] 2026-06-01 09:05:16.635264 | localhost | skipping: Conditional result was False 2026-06-01 09:05:16.664177 | localhost | skipping: Conditional result was False 2026-06-01 09:05:16.671336 | 2026-06-01 09:05:16.671446 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-01 09:05:16.791721 | localhost | skipping: Conditional result was False 2026-06-01 09:05:16.792032 | 2026-06-01 09:05:16.803130 | localhost | skipping: Conditional result was False 2026-06-01 09:05:16.814129 | 2026-06-01 09:05:16.814225 | LOOP [upload-logs : Upload console log and json output]