2026-05-08 17:46:21.527186 | Job console starting 2026-05-08 17:46:21.545720 | Updating git repos 2026-05-08 17:46:21.608159 | Cloning repos into workspace 2026-05-08 17:46:21.700460 | Restoring repo states 2026-05-08 17:46:21.719307 | Merging changes 2026-05-08 17:46:22.331955 | Checking out repos 2026-05-08 17:46:22.371835 | Preparing playbooks 2026-05-08 17:46:23.064250 | Running Ansible setup 2026-05-08 17:46:27.949708 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-08 17:46:28.909762 | 2026-05-08 17:46:28.909925 | PLAY [Base pre] 2026-05-08 17:46:28.927141 | 2026-05-08 17:46:28.927283 | TASK [Setup log path fact] 2026-05-08 17:46:28.947804 | debian-bookworm | ok 2026-05-08 17:46:28.966897 | 2026-05-08 17:46:28.967082 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 17:46:28.997542 | debian-bookworm | ok 2026-05-08 17:46:29.007712 | 2026-05-08 17:46:29.007836 | TASK [emit-job-header : Print job information] 2026-05-08 17:46:29.058632 | # Job Information 2026-05-08 17:46:29.058864 | Ansible Version: 2.16.14 2026-05-08 17:46:29.058902 | Job: python-black 2026-05-08 17:46:29.058936 | Pipeline: check 2026-05-08 17:46:29.058960 | Executor: 521e9411259a 2026-05-08 17:46:29.058980 | Triggered by: https://github.com/osism/python-osism/pull/2270 2026-05-08 17:46:29.059004 | Event ID: b203cff0-4b05-11f1-948b-aada6bff46c8 2026-05-08 17:46:29.066915 | 2026-05-08 17:46:29.067322 | LOOP [emit-job-header : Print node information] 2026-05-08 17:46:29.216096 | debian-bookworm | ok: 2026-05-08 17:46:29.216352 | debian-bookworm | # Node Information 2026-05-08 17:46:29.216394 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-08 17:46:29.216420 | debian-bookworm | Hostname: debian 2026-05-08 17:46:29.216442 | debian-bookworm | Username: zuul 2026-05-08 17:46:29.216463 | debian-bookworm | Distro: Debian 12.13 2026-05-08 17:46:29.216490 | debian-bookworm | Provider: regiocloud-a 2026-05-08 17:46:29.216511 | debian-bookworm | Region: 2026-05-08 17:46:29.216533 | debian-bookworm | Label: debian-bookworm 2026-05-08 17:46:29.216553 | debian-bookworm | Product Name: OpenStack Nova 2026-05-08 17:46:29.216572 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe77:605a 2026-05-08 17:46:29.237761 | 2026-05-08 17:46:29.237892 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-08 17:46:29.824312 | debian-bookworm -> localhost | changed 2026-05-08 17:46:29.833457 | 2026-05-08 17:46:29.833598 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-08 17:46:31.068342 | debian-bookworm -> localhost | changed 2026-05-08 17:46:31.091395 | 2026-05-08 17:46:31.091622 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-08 17:46:31.498325 | debian-bookworm -> localhost | ok 2026-05-08 17:46:31.513733 | 2026-05-08 17:46:31.513973 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-08 17:46:31.558031 | debian-bookworm | ok 2026-05-08 17:46:31.587768 | debian-bookworm | included: /var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-08 17:46:31.595317 | 2026-05-08 17:46:31.596001 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-08 17:46:34.037062 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-08 17:46:34.038698 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/work/0b307bf73c1a4cb6b3f0a891a4cb3cc4_id_rsa 2026-05-08 17:46:34.038848 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/work/0b307bf73c1a4cb6b3f0a891a4cb3cc4_id_rsa.pub 2026-05-08 17:46:34.038884 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-08 17:46:34.038911 | debian-bookworm -> localhost | SHA256:5kSiIbWk4gSvFRyVfrsJ2U99XJFCqFfsExMSNKgxK5w zuul-build-sshkey 2026-05-08 17:46:34.038934 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-08 17:46:34.038970 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-08 17:46:34.038992 | debian-bookworm -> localhost | |..oo+. o==o. . | 2026-05-08 17:46:34.039013 | debian-bookworm -> localhost | |...=..o . .o* o | 2026-05-08 17:46:34.039033 | debian-bookworm -> localhost | |..=oo..=.. o + . | 2026-05-08 17:46:34.039052 | debian-bookworm -> localhost | |o+ .Eo+o. . o . | 2026-05-08 17:46:34.039071 | debian-bookworm -> localhost | |.. .= .So . o | 2026-05-08 17:46:34.039097 | debian-bookworm -> localhost | | o o+. . o | 2026-05-08 17:46:34.039118 | debian-bookworm -> localhost | | . =. . | 2026-05-08 17:46:34.039140 | debian-bookworm -> localhost | | o . | 2026-05-08 17:46:34.039164 | debian-bookworm -> localhost | | | 2026-05-08 17:46:34.039187 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-08 17:46:34.039252 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.695935 2026-05-08 17:46:34.059262 | 2026-05-08 17:46:34.059402 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-08 17:46:34.104386 | debian-bookworm | ok 2026-05-08 17:46:34.124080 | debian-bookworm | included: /var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-08 17:46:34.137719 | 2026-05-08 17:46:34.137846 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-08 17:46:34.195456 | debian-bookworm | skipping: Conditional result was False 2026-05-08 17:46:34.213373 | 2026-05-08 17:46:34.213537 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-08 17:46:35.056201 | debian-bookworm | changed 2026-05-08 17:46:35.062290 | 2026-05-08 17:46:35.062418 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-08 17:46:35.402892 | debian-bookworm | ok 2026-05-08 17:46:35.408939 | 2026-05-08 17:46:35.409070 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-08 17:46:36.067402 | debian-bookworm | changed 2026-05-08 17:46:36.077803 | 2026-05-08 17:46:36.077915 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-08 17:46:36.804548 | debian-bookworm | changed 2026-05-08 17:46:36.817878 | 2026-05-08 17:46:36.817980 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-08 17:46:36.869616 | debian-bookworm | skipping: Conditional result was False 2026-05-08 17:46:36.874871 | 2026-05-08 17:46:36.874963 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-08 17:46:37.823856 | debian-bookworm -> localhost | changed 2026-05-08 17:46:37.833063 | 2026-05-08 17:46:37.833148 | TASK [add-build-sshkey : Add back temp key] 2026-05-08 17:46:38.388099 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/work/0b307bf73c1a4cb6b3f0a891a4cb3cc4_id_rsa (zuul-build-sshkey) 2026-05-08 17:46:38.388284 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.027169 2026-05-08 17:46:38.394688 | 2026-05-08 17:46:38.394774 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-08 17:46:38.940507 | debian-bookworm | ok 2026-05-08 17:46:38.947043 | 2026-05-08 17:46:38.947140 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-08 17:46:38.995434 | debian-bookworm | skipping: Conditional result was False 2026-05-08 17:46:39.009271 | 2026-05-08 17:46:39.009797 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-08 17:46:39.669096 | debian-bookworm | ok 2026-05-08 17:46:39.685082 | 2026-05-08 17:46:39.685174 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-08 17:46:39.736451 | debian-bookworm | ok 2026-05-08 17:46:39.742406 | 2026-05-08 17:46:39.742502 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-08 17:46:40.270454 | debian-bookworm -> localhost | ok 2026-05-08 17:46:40.278253 | 2026-05-08 17:46:40.278354 | TASK [validate-host : Collect information about the host] 2026-05-08 17:46:41.503846 | debian-bookworm | ok 2026-05-08 17:46:41.512110 | 2026-05-08 17:46:41.512192 | TASK [validate-host : Sanitize hostname] 2026-05-08 17:46:41.616556 | debian-bookworm | ok 2026-05-08 17:46:41.686701 | 2026-05-08 17:46:41.686815 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-08 17:46:42.561094 | debian-bookworm -> localhost | changed 2026-05-08 17:46:42.565614 | 2026-05-08 17:46:42.565707 | TASK [validate-host : Collect information about zuul worker] 2026-05-08 17:46:43.059832 | debian-bookworm | ok 2026-05-08 17:46:43.065416 | 2026-05-08 17:46:43.065508 | TASK [validate-host : Write out all zuul information for each host] 2026-05-08 17:46:43.760248 | debian-bookworm -> localhost | changed 2026-05-08 17:46:43.774492 | 2026-05-08 17:46:43.774583 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-08 17:46:44.047042 | debian-bookworm | ok 2026-05-08 17:46:44.051457 | 2026-05-08 17:46:44.051541 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-08 17:46:45.162777 | debian-bookworm | changed: 2026-05-08 17:46:45.163070 | debian-bookworm | cd+++++++++ src/ 2026-05-08 17:46:45.163122 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-08 17:46:45.163158 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-08 17:46:45.163190 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-08 17:46:45.163220 | debian-bookworm | Containerfile 2026-05-08 17:46:45.163409 | debian-bookworm | Containerfile 2026-05-08 17:46:45.207372 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/work/logs" 2026-05-08 17:47:15.579725 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/work/artifacts" 2026-05-08 17:47:15.896499 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0b307bf73c1a4cb6b3f0a891a4cb3cc4/work/docs" 2026-05-08 17:47:15.912279 | 2026-05-08 17:47:15.912426 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-08 17:47:16.775591 | debian-bookworm | changed: 2026-05-08 17:47:16.775857 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-08 17:47:16.775894 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-08 17:47:16.775930 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-08 17:47:16.775979 | debian-bookworm | changed: All items complete 2026-05-08 17:47:16.776007 | 2026-05-08 17:47:17.326591 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 17:47:17.884193 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 17:47:17.908316 | 2026-05-08 17:47:17.908463 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-08 17:47:18.492752 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.013550 2026-05-08 17:47:18.813295 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.015221 2026-05-08 17:47:18.825039 | 2026-05-08 17:47:18.825154 | PLAY RECAP 2026-05-08 17:47:18.825205 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-08 17:47:18.825231 | 2026-05-08 17:47:18.987957 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-08 17:47:18.988828 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 17:47:19.782960 | 2026-05-08 17:47:19.783145 | PLAY [Base post] 2026-05-08 17:47:19.797525 | 2026-05-08 17:47:19.797724 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-08 17:47:20.432112 | debian-bookworm | changed 2026-05-08 17:47:20.440417 | 2026-05-08 17:47:20.440608 | PLAY RECAP 2026-05-08 17:47:20.440750 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-08 17:47:20.440869 | 2026-05-08 17:47:20.598779 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 17:47:20.599860 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-08 17:47:21.540665 | 2026-05-08 17:47:21.540851 | PLAY [Base post-logs] 2026-05-08 17:47:21.552045 | 2026-05-08 17:47:21.552199 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-08 17:47:22.065963 | localhost | changed 2026-05-08 17:47:22.076576 | 2026-05-08 17:47:22.076800 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-08 17:47:22.113871 | localhost | ok 2026-05-08 17:47:22.118348 | 2026-05-08 17:47:22.118507 | TASK [Set zuul-log-path fact] 2026-05-08 17:47:22.135846 | localhost | ok 2026-05-08 17:47:22.144102 | 2026-05-08 17:47:22.144225 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 17:47:22.171086 | localhost | ok 2026-05-08 17:47:22.174521 | 2026-05-08 17:47:22.174661 | TASK [upload-logs : Create log directories] 2026-05-08 17:47:22.736459 | localhost | changed 2026-05-08 17:47:22.741085 | 2026-05-08 17:47:22.741213 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-08 17:47:23.286486 | localhost -> localhost | ok: Runtime: 0:00:00.008651 2026-05-08 17:47:23.290996 | 2026-05-08 17:47:23.291119 | TASK [upload-logs : Upload logs to log server] 2026-05-08 17:47:23.881757 | localhost | Output suppressed because no_log was given 2026-05-08 17:47:23.884825 | 2026-05-08 17:47:23.884985 | LOOP [upload-logs : Compress console log and json output] 2026-05-08 17:47:23.941713 | localhost | skipping: Conditional result was False 2026-05-08 17:47:23.950098 | localhost | skipping: Conditional result was False 2026-05-08 17:47:23.957381 | 2026-05-08 17:47:23.957555 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-08 17:47:24.008733 | localhost | skipping: Conditional result was False 2026-05-08 17:47:24.009414 | 2026-05-08 17:47:24.013622 | localhost | skipping: Conditional result was False 2026-05-08 17:47:24.024844 | 2026-05-08 17:47:24.025013 | LOOP [upload-logs : Upload console log and json output]