2026-04-29 05:39:23.014786 | Job console starting 2026-04-29 05:39:23.044674 | Updating git repos 2026-04-29 05:39:23.143498 | Cloning repos into workspace 2026-04-29 05:39:23.214619 | Restoring repo states 2026-04-29 05:39:23.259571 | Merging changes 2026-04-29 05:39:24.067482 | Checking out repos 2026-04-29 05:39:24.175288 | Preparing playbooks 2026-04-29 05:39:25.644986 | Running Ansible setup 2026-04-29 05:39:31.746270 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 05:39:32.891108 | 2026-04-29 05:39:32.891298 | PLAY [Base pre] 2026-04-29 05:39:32.921387 | 2026-04-29 05:39:32.921550 | TASK [Setup log path fact] 2026-04-29 05:39:32.942902 | debian-bookworm | ok 2026-04-29 05:39:32.974572 | 2026-04-29 05:39:32.974768 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 05:39:33.054393 | debian-bookworm | ok 2026-04-29 05:39:33.073433 | 2026-04-29 05:39:33.073572 | TASK [emit-job-header : Print job information] 2026-04-29 05:39:33.174991 | # Job Information 2026-04-29 05:39:33.175181 | Ansible Version: 2.16.14 2026-04-29 05:39:33.175217 | Job: python-black 2026-04-29 05:39:33.175250 | Pipeline: check 2026-04-29 05:39:33.175273 | Executor: 521e9411259a 2026-04-29 05:39:33.175295 | Triggered by: https://github.com/osism/python-osism/pull/2238 2026-04-29 05:39:33.175317 | Event ID: c12408d0-438d-11f1-8309-2d20a644e793 2026-04-29 05:39:33.186672 | 2026-04-29 05:39:33.186877 | LOOP [emit-job-header : Print node information] 2026-04-29 05:39:33.430410 | debian-bookworm | ok: 2026-04-29 05:39:33.430700 | debian-bookworm | # Node Information 2026-04-29 05:39:33.430756 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-04-29 05:39:33.430782 | debian-bookworm | Hostname: debian 2026-04-29 05:39:33.430804 | debian-bookworm | Username: zuul 2026-04-29 05:39:33.430825 | debian-bookworm | Distro: Debian 12.13 2026-04-29 05:39:33.430880 | debian-bookworm | Provider: regiocloud-a 2026-04-29 05:39:33.430906 | debian-bookworm | Region: 2026-04-29 05:39:33.430927 | debian-bookworm | Label: debian-bookworm 2026-04-29 05:39:33.430946 | debian-bookworm | Product Name: OpenStack Nova 2026-04-29 05:39:33.430966 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec6:ed9b 2026-04-29 05:39:33.445470 | 2026-04-29 05:39:33.445622 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-29 05:39:34.699536 | debian-bookworm -> localhost | changed 2026-04-29 05:39:34.708650 | 2026-04-29 05:39:34.708828 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-29 05:39:37.001861 | debian-bookworm -> localhost | changed 2026-04-29 05:39:37.010226 | 2026-04-29 05:39:37.010313 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-29 05:39:37.587088 | debian-bookworm -> localhost | ok 2026-04-29 05:39:37.592023 | 2026-04-29 05:39:37.592106 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-29 05:39:37.632608 | debian-bookworm | ok 2026-04-29 05:39:37.659688 | debian-bookworm | included: /var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-29 05:39:37.671784 | 2026-04-29 05:39:37.671880 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-29 05:39:40.945212 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-04-29 05:39:40.945682 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/work/f8e0a3228fa74d759b3674d07f009a05_id_rsa 2026-04-29 05:39:40.945813 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/work/f8e0a3228fa74d759b3674d07f009a05_id_rsa.pub 2026-04-29 05:39:40.945891 | debian-bookworm -> localhost | The key fingerprint is: 2026-04-29 05:39:40.945963 | debian-bookworm -> localhost | SHA256:w2w3QT8tRQylDfwfU5cIcmTBXLZ7FX3Uw67RVnk/TeY zuul-build-sshkey 2026-04-29 05:39:40.946028 | debian-bookworm -> localhost | The key's randomart image is: 2026-04-29 05:39:40.946119 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-04-29 05:39:40.946187 | debian-bookworm -> localhost | | .=B*B*o*| 2026-04-29 05:39:40.946251 | debian-bookworm -> localhost | | .++oBo=%| 2026-04-29 05:39:40.946311 | debian-bookworm -> localhost | | . =o=+@| 2026-04-29 05:39:40.946367 | debian-bookworm -> localhost | | o . +oBE| 2026-04-29 05:39:40.946425 | debian-bookworm -> localhost | | S o .+o+| 2026-04-29 05:39:40.946495 | debian-bookworm -> localhost | | . o . .. .| 2026-04-29 05:39:40.946555 | debian-bookworm -> localhost | | | 2026-04-29 05:39:40.946614 | debian-bookworm -> localhost | | | 2026-04-29 05:39:40.946675 | debian-bookworm -> localhost | | | 2026-04-29 05:39:40.946750 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-04-29 05:39:40.946920 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.212005 2026-04-29 05:39:40.961138 | 2026-04-29 05:39:40.961525 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-29 05:39:40.991398 | debian-bookworm | ok 2026-04-29 05:39:41.002975 | debian-bookworm | included: /var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-29 05:39:41.022348 | 2026-04-29 05:39:41.022504 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-29 05:39:41.058225 | debian-bookworm | skipping: Conditional result was False 2026-04-29 05:39:41.073945 | 2026-04-29 05:39:41.074103 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-29 05:39:41.719212 | debian-bookworm | changed 2026-04-29 05:39:41.733469 | 2026-04-29 05:39:41.733579 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-29 05:39:42.007518 | debian-bookworm | ok 2026-04-29 05:39:42.016168 | 2026-04-29 05:39:42.016275 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-29 05:39:42.668823 | debian-bookworm | changed 2026-04-29 05:39:42.676685 | 2026-04-29 05:39:42.676851 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-29 05:39:43.334800 | debian-bookworm | changed 2026-04-29 05:39:43.343443 | 2026-04-29 05:39:43.343538 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-29 05:39:43.387120 | debian-bookworm | skipping: Conditional result was False 2026-04-29 05:39:43.392925 | 2026-04-29 05:39:43.393015 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-29 05:39:44.178964 | debian-bookworm -> localhost | changed 2026-04-29 05:39:44.193741 | 2026-04-29 05:39:44.193853 | TASK [add-build-sshkey : Add back temp key] 2026-04-29 05:39:44.696385 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/work/f8e0a3228fa74d759b3674d07f009a05_id_rsa (zuul-build-sshkey) 2026-04-29 05:39:44.696846 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009946 2026-04-29 05:39:44.719669 | 2026-04-29 05:39:44.719785 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-29 05:39:45.213845 | debian-bookworm | ok 2026-04-29 05:39:45.218903 | 2026-04-29 05:39:45.219000 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-29 05:39:45.243174 | debian-bookworm | skipping: Conditional result was False 2026-04-29 05:39:45.253508 | 2026-04-29 05:39:45.253608 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-29 05:39:45.764397 | debian-bookworm | ok 2026-04-29 05:39:45.774431 | 2026-04-29 05:39:45.774531 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-29 05:39:45.802471 | debian-bookworm | ok 2026-04-29 05:39:45.807292 | 2026-04-29 05:39:45.807378 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-29 05:39:46.236592 | debian-bookworm -> localhost | ok 2026-04-29 05:39:46.242737 | 2026-04-29 05:39:46.247024 | TASK [validate-host : Collect information about the host] 2026-04-29 05:39:47.370775 | debian-bookworm | ok 2026-04-29 05:39:47.394732 | 2026-04-29 05:39:47.394848 | TASK [validate-host : Sanitize hostname] 2026-04-29 05:39:47.517612 | debian-bookworm | ok 2026-04-29 05:39:47.574099 | 2026-04-29 05:39:47.574199 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-29 05:39:48.647138 | debian-bookworm -> localhost | changed 2026-04-29 05:39:48.652780 | 2026-04-29 05:39:48.652887 | TASK [validate-host : Collect information about zuul worker] 2026-04-29 05:39:49.093804 | debian-bookworm | ok 2026-04-29 05:39:49.100419 | 2026-04-29 05:39:49.100539 | TASK [validate-host : Write out all zuul information for each host] 2026-04-29 05:39:49.745621 | debian-bookworm -> localhost | changed 2026-04-29 05:39:49.767981 | 2026-04-29 05:39:49.768247 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-29 05:39:50.079499 | debian-bookworm | ok 2026-04-29 05:39:50.084750 | 2026-04-29 05:39:50.084841 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-29 05:39:51.081878 | debian-bookworm | changed: 2026-04-29 05:39:51.082034 | debian-bookworm | cd+++++++++ src/ 2026-04-29 05:39:51.082063 | debian-bookworm | cd+++++++++ src/github.com/ 2026-04-29 05:39:51.082083 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-04-29 05:39:51.082100 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-04-29 05:39:51.082117 | debian-bookworm | Containerfile 2026-04-29 05:39:51.082224 | debian-bookworm | Containerfile 2026-04-29 05:39:51.086380 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/work/logs" 2026-04-29 05:40:20.731802 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/work/artifacts" 2026-04-29 05:40:21.015983 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f8e0a3228fa74d759b3674d07f009a05/work/docs" 2026-04-29 05:40:21.037059 | 2026-04-29 05:40:21.037276 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-29 05:40:21.863095 | debian-bookworm | changed: 2026-04-29 05:40:21.863478 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-04-29 05:40:21.863544 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-04-29 05:40:21.863611 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-04-29 05:40:21.863754 | debian-bookworm | changed: All items complete 2026-04-29 05:40:21.863805 | 2026-04-29 05:40:22.466893 | debian-bookworm | changed: .d..t...... ./ 2026-04-29 05:40:23.015547 | debian-bookworm | changed: .d..t...... ./ 2026-04-29 05:40:23.037543 | 2026-04-29 05:40:23.037727 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-29 05:40:23.590610 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009832 2026-04-29 05:40:23.912168 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.017501 2026-04-29 05:40:23.927215 | 2026-04-29 05:40:23.927338 | PLAY RECAP 2026-04-29 05:40:23.927397 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-29 05:40:23.927423 | 2026-04-29 05:40:24.087164 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-29 05:40:24.088039 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 05:40:25.039292 | 2026-04-29 05:40:25.040014 | PLAY [Base post] 2026-04-29 05:40:25.054701 | 2026-04-29 05:40:25.054885 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-29 05:40:25.644830 | debian-bookworm | changed 2026-04-29 05:40:25.652313 | 2026-04-29 05:40:25.652440 | PLAY RECAP 2026-04-29 05:40:25.652504 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 05:40:25.652566 | 2026-04-29 05:40:25.787325 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 05:40:25.788207 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-29 05:40:26.675933 | 2026-04-29 05:40:26.676095 | PLAY [Base post-logs] 2026-04-29 05:40:26.687338 | 2026-04-29 05:40:26.687471 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-29 05:40:27.163289 | localhost | changed 2026-04-29 05:40:27.173545 | 2026-04-29 05:40:27.173737 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-29 05:40:27.216595 | localhost | ok 2026-04-29 05:40:27.223765 | 2026-04-29 05:40:27.225130 | TASK [Set zuul-log-path fact] 2026-04-29 05:40:27.244092 | localhost | ok 2026-04-29 05:40:27.260522 | 2026-04-29 05:40:27.260720 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 05:40:27.288572 | localhost | ok 2026-04-29 05:40:27.292446 | 2026-04-29 05:40:27.292581 | TASK [upload-logs : Create log directories] 2026-04-29 05:40:27.827839 | localhost | changed 2026-04-29 05:40:27.831974 | 2026-04-29 05:40:27.832108 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-29 05:40:28.357822 | localhost -> localhost | ok: Runtime: 0:00:00.007417 2026-04-29 05:40:28.363431 | 2026-04-29 05:40:28.363559 | TASK [upload-logs : Upload logs to log server] 2026-04-29 05:40:28.940758 | localhost | Output suppressed because no_log was given 2026-04-29 05:40:28.942634 | 2026-04-29 05:40:28.942767 | LOOP [upload-logs : Compress console log and json output] 2026-04-29 05:40:28.994776 | localhost | skipping: Conditional result was False 2026-04-29 05:40:29.000673 | localhost | skipping: Conditional result was False 2026-04-29 05:40:29.017146 | 2026-04-29 05:40:29.017388 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-29 05:40:29.067403 | localhost | skipping: Conditional result was False 2026-04-29 05:40:29.068218 | 2026-04-29 05:40:29.071541 | localhost | skipping: Conditional result was False 2026-04-29 05:40:29.079962 | 2026-04-29 05:40:29.080179 | LOOP [upload-logs : Upload console log and json output]