2026-05-11 21:02:31.729906 | Job console starting 2026-05-11 21:02:31.761726 | Updating git repos 2026-05-11 21:02:31.901319 | Cloning repos into workspace 2026-05-11 21:02:31.993343 | Restoring repo states 2026-05-11 21:02:32.031691 | Merging changes 2026-05-11 21:02:32.692702 | Checking out repos 2026-05-11 21:02:32.741409 | Preparing playbooks 2026-05-11 21:02:33.823861 | Running Ansible setup 2026-05-11 21:02:39.196438 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 21:02:40.379274 | 2026-05-11 21:02:40.379483 | PLAY [Base pre] 2026-05-11 21:02:40.418912 | 2026-05-11 21:02:40.419105 | TASK [Setup log path fact] 2026-05-11 21:02:40.443775 | debian-bookworm | ok 2026-05-11 21:02:40.477401 | 2026-05-11 21:02:40.477594 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 21:02:40.533483 | debian-bookworm | ok 2026-05-11 21:02:40.554115 | 2026-05-11 21:02:40.554302 | TASK [emit-job-header : Print job information] 2026-05-11 21:02:40.628324 | # Job Information 2026-05-11 21:02:40.628672 | Ansible Version: 2.16.14 2026-05-11 21:02:40.628718 | Job: python-black 2026-05-11 21:02:40.628757 | Pipeline: check 2026-05-11 21:02:40.628784 | Executor: 521e9411259a 2026-05-11 21:02:40.628804 | Triggered by: https://github.com/osism/container-image-osism-ansible/pull/743 2026-05-11 21:02:40.628826 | Event ID: b2678c10-4d7c-11f1-9f65-39976f8438af 2026-05-11 21:02:40.634254 | 2026-05-11 21:02:40.634388 | LOOP [emit-job-header : Print node information] 2026-05-11 21:02:40.868979 | debian-bookworm | ok: 2026-05-11 21:02:40.869238 | debian-bookworm | # Node Information 2026-05-11 21:02:40.869275 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-11 21:02:40.869301 | debian-bookworm | Hostname: debian 2026-05-11 21:02:40.869323 | debian-bookworm | Username: zuul 2026-05-11 21:02:40.869344 | debian-bookworm | Distro: Debian 12.13 2026-05-11 21:02:40.869368 | debian-bookworm | Provider: regiocloud-a 2026-05-11 21:02:40.869389 | debian-bookworm | Region: 2026-05-11 21:02:40.869410 | debian-bookworm | Label: debian-bookworm 2026-05-11 21:02:40.869430 | debian-bookworm | Product Name: OpenStack Nova 2026-05-11 21:02:40.869449 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe48:9e21 2026-05-11 21:02:40.898567 | 2026-05-11 21:02:40.898757 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 21:02:41.596896 | debian-bookworm -> localhost | changed 2026-05-11 21:02:41.606321 | 2026-05-11 21:02:41.606431 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 21:02:43.300138 | debian-bookworm -> localhost | changed 2026-05-11 21:02:43.317369 | 2026-05-11 21:02:43.317468 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 21:02:43.686194 | debian-bookworm -> localhost | ok 2026-05-11 21:02:43.692172 | 2026-05-11 21:02:43.692274 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 21:02:43.713712 | debian-bookworm | ok 2026-05-11 21:02:43.731938 | debian-bookworm | included: /var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 21:02:43.747134 | 2026-05-11 21:02:43.747242 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 21:02:44.791576 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-11 21:02:44.791790 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/work/6f0f7a7b34a2478e98be567d52813792_id_rsa 2026-05-11 21:02:44.791823 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/work/6f0f7a7b34a2478e98be567d52813792_id_rsa.pub 2026-05-11 21:02:44.791845 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-11 21:02:44.791865 | debian-bookworm -> localhost | SHA256:g0TYOQR9oe9AeAEXgM/ClckphYrAS9ZPaBGnkLOfNyo zuul-build-sshkey 2026-05-11 21:02:44.791894 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-11 21:02:44.791913 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-11 21:02:44.791930 | debian-bookworm -> localhost | |..oBO&*o.. | 2026-05-11 21:02:44.791947 | debian-bookworm -> localhost | |.*=+X==o. | 2026-05-11 21:02:44.791964 | debian-bookworm -> localhost | |=o=*+ =o | 2026-05-11 21:02:44.791980 | debian-bookworm -> localhost | |ooo o= o | 2026-05-11 21:02:44.791995 | debian-bookworm -> localhost | | ... o S | 2026-05-11 21:02:44.792015 | debian-bookworm -> localhost | | o o o . | 2026-05-11 21:02:44.792032 | debian-bookworm -> localhost | | o . . | 2026-05-11 21:02:44.792049 | debian-bookworm -> localhost | | E . | 2026-05-11 21:02:44.792066 | debian-bookworm -> localhost | | . | 2026-05-11 21:02:44.792082 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-11 21:02:44.792125 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.386500 2026-05-11 21:02:44.797226 | 2026-05-11 21:02:44.797300 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 21:02:44.824473 | debian-bookworm | ok 2026-05-11 21:02:44.834289 | debian-bookworm | included: /var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 21:02:44.850209 | 2026-05-11 21:02:44.850290 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 21:02:44.883432 | debian-bookworm | skipping: Conditional result was False 2026-05-11 21:02:44.888193 | 2026-05-11 21:02:44.888278 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 21:02:45.550990 | debian-bookworm | changed 2026-05-11 21:02:45.557298 | 2026-05-11 21:02:45.557389 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 21:02:45.843428 | debian-bookworm | ok 2026-05-11 21:02:45.867948 | 2026-05-11 21:02:45.868059 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 21:02:46.554569 | debian-bookworm | changed 2026-05-11 21:02:46.561182 | 2026-05-11 21:02:46.561277 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 21:02:47.247883 | debian-bookworm | changed 2026-05-11 21:02:47.253361 | 2026-05-11 21:02:47.253460 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 21:02:47.288205 | debian-bookworm | skipping: Conditional result was False 2026-05-11 21:02:47.294354 | 2026-05-11 21:02:47.294457 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 21:02:47.743971 | debian-bookworm -> localhost | changed 2026-05-11 21:02:47.755075 | 2026-05-11 21:02:47.755181 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 21:02:48.152413 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/work/6f0f7a7b34a2478e98be567d52813792_id_rsa (zuul-build-sshkey) 2026-05-11 21:02:48.152662 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012030 2026-05-11 21:02:48.160730 | 2026-05-11 21:02:48.160832 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 21:02:48.589883 | debian-bookworm | ok 2026-05-11 21:02:48.594932 | 2026-05-11 21:02:48.595024 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 21:02:48.618192 | debian-bookworm | skipping: Conditional result was False 2026-05-11 21:02:48.628213 | 2026-05-11 21:02:48.628305 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-11 21:02:49.091341 | debian-bookworm | ok 2026-05-11 21:02:49.109386 | 2026-05-11 21:02:49.109493 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-11 21:02:49.159575 | debian-bookworm | ok 2026-05-11 21:02:49.176487 | 2026-05-11 21:02:49.176602 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-11 21:02:49.551306 | debian-bookworm -> localhost | ok 2026-05-11 21:02:49.559890 | 2026-05-11 21:02:49.560013 | TASK [validate-host : Collect information about the host] 2026-05-11 21:02:50.805791 | debian-bookworm | ok 2026-05-11 21:02:50.819157 | 2026-05-11 21:02:50.819312 | TASK [validate-host : Sanitize hostname] 2026-05-11 21:02:50.953571 | debian-bookworm | ok 2026-05-11 21:02:51.032189 | 2026-05-11 21:02:51.032347 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-11 21:02:51.780983 | debian-bookworm -> localhost | changed 2026-05-11 21:02:51.787384 | 2026-05-11 21:02:51.787532 | TASK [validate-host : Collect information about zuul worker] 2026-05-11 21:02:52.365684 | debian-bookworm | ok 2026-05-11 21:02:52.370677 | 2026-05-11 21:02:52.370791 | TASK [validate-host : Write out all zuul information for each host] 2026-05-11 21:02:53.109768 | debian-bookworm -> localhost | changed 2026-05-11 21:02:53.126824 | 2026-05-11 21:02:53.126986 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-11 21:02:53.476157 | debian-bookworm | ok 2026-05-11 21:02:53.483028 | 2026-05-11 21:02:53.483157 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-11 21:02:54.464091 | debian-bookworm | changed: 2026-05-11 21:02:54.464322 | debian-bookworm | cd+++++++++ src/ 2026-05-11 21:02:54.464372 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-11 21:02:54.464397 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-11 21:02:54.464419 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-osism-ansible/ 2026-05-11 21:02:54.464439 | debian-bookworm | Containerfile 2026-05-11 21:02:54.464586 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/work/logs" 2026-05-11 21:03:21.021181 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/work/artifacts" 2026-05-11 21:03:21.295999 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/6f0f7a7b34a2478e98be567d52813792/work/docs" 2026-05-11 21:03:21.316619 | 2026-05-11 21:03:21.316835 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 21:03:22.166519 | debian-bookworm | changed: 2026-05-11 21:03:22.166920 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-11 21:03:22.166988 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-11 21:03:22.167037 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-11 21:03:22.167118 | debian-bookworm | changed: All items complete 2026-05-11 21:03:22.167162 | 2026-05-11 21:03:22.744591 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 21:03:23.362909 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 21:03:23.380859 | 2026-05-11 21:03:23.381018 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 21:03:23.939961 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008755 2026-05-11 21:03:24.260238 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.023867 2026-05-11 21:03:24.274716 | 2026-05-11 21:03:24.275046 | PLAY RECAP 2026-05-11 21:03:24.275125 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-11 21:03:24.275152 | 2026-05-11 21:03:24.425157 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 21:03:24.426046 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 21:03:25.190516 | 2026-05-11 21:03:25.190768 | PLAY [Base post] 2026-05-11 21:03:25.208921 | 2026-05-11 21:03:25.209071 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 21:03:25.794557 | debian-bookworm | changed 2026-05-11 21:03:25.815232 | 2026-05-11 21:03:25.815394 | PLAY RECAP 2026-05-11 21:03:25.815462 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 21:03:25.815526 | 2026-05-11 21:03:25.964567 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 21:03:25.965454 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 21:03:26.831148 | 2026-05-11 21:03:26.831323 | PLAY [Base post-logs] 2026-05-11 21:03:26.842371 | 2026-05-11 21:03:26.842528 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 21:03:27.304167 | localhost | changed 2026-05-11 21:03:27.314549 | 2026-05-11 21:03:27.314725 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 21:03:27.351233 | localhost | ok 2026-05-11 21:03:27.355399 | 2026-05-11 21:03:27.355525 | TASK [Set zuul-log-path fact] 2026-05-11 21:03:27.371619 | localhost | ok 2026-05-11 21:03:27.381708 | 2026-05-11 21:03:27.381836 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 21:03:27.409877 | localhost | ok 2026-05-11 21:03:27.414792 | 2026-05-11 21:03:27.414959 | TASK [upload-logs : Create log directories] 2026-05-11 21:03:27.973584 | localhost | changed 2026-05-11 21:03:27.976439 | 2026-05-11 21:03:27.976545 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 21:03:28.620935 | localhost -> localhost | ok: Runtime: 0:00:00.006967 2026-05-11 21:03:28.632604 | 2026-05-11 21:03:28.632760 | TASK [upload-logs : Upload logs to log server] 2026-05-11 21:03:29.247976 | localhost | Output suppressed because no_log was given 2026-05-11 21:03:29.251694 | 2026-05-11 21:03:29.251899 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 21:03:29.311194 | localhost | skipping: Conditional result was False 2026-05-11 21:03:29.316846 | localhost | skipping: Conditional result was False 2026-05-11 21:03:29.326394 | 2026-05-11 21:03:29.326572 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 21:03:29.396383 | localhost | skipping: Conditional result was False 2026-05-11 21:03:29.396714 | 2026-05-11 21:03:29.402039 | localhost | skipping: Conditional result was False 2026-05-11 21:03:29.418663 | 2026-05-11 21:03:29.419087 | LOOP [upload-logs : Upload console log and json output]