2026-08-28 17:14:18.768594 | Job console starting 2026-08-28 17:14:18.814135 | Updating git repos 2026-08-28 17:14:18.961024 | Cloning repos into workspace 2026-08-28 17:14:19.042258 | Restoring repo states 2026-08-28 17:14:19.070171 | Merging changes 2026-08-28 17:14:20.232277 | Checking out repos 2026-08-28 17:14:20.425396 | Preparing playbooks 2026-08-28 17:14:23.128893 | Running Ansible setup 2026-08-28 17:14:30.301562 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-28 17:14:32.307152 | 2026-08-28 17:14:32.307306 | PLAY [Base pre] 2026-08-28 17:14:32.346444 | 2026-08-28 17:14:32.346574 | TASK [Setup log path fact] 2026-08-28 17:14:32.374450 | debian-bookworm | ok 2026-08-28 17:14:32.410125 | 2026-08-28 17:14:32.410259 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-28 17:14:32.451298 | debian-bookworm | ok 2026-08-28 17:14:32.459663 | 2026-08-28 17:14:32.459759 | TASK [emit-job-header : Print job information] 2026-08-28 17:14:32.549553 | # Job Information 2026-08-28 17:14:32.549717 | Ansible Version: 2.16.14 2026-08-28 17:14:32.549746 | Job: python-osism-test-setup 2026-08-28 17:14:32.549771 | Pipeline: check 2026-08-28 17:14:32.549790 | Executor: 521e9411259a 2026-08-28 17:14:32.549806 | Triggered by: https://github.com/osism/python-osism/pull/2638 2026-08-28 17:14:32.549837 | Event ID: e01a5ed0-a303-11f1-951d-aed341f68e27 2026-08-28 17:14:32.553963 | 2026-08-28 17:14:32.554048 | LOOP [emit-job-header : Print node information] 2026-08-28 17:14:32.800313 | debian-bookworm | ok: 2026-08-28 17:14:32.800485 | debian-bookworm | # Node Information 2026-08-28 17:14:32.800519 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-28 17:14:32.800544 | debian-bookworm | Hostname: debian 2026-08-28 17:14:32.800566 | debian-bookworm | Username: zuul 2026-08-28 17:14:32.800587 | debian-bookworm | Distro: Debian 12.15 2026-08-28 17:14:32.800611 | debian-bookworm | Provider: regiocloud-a 2026-08-28 17:14:32.800634 | debian-bookworm | Region: 2026-08-28 17:14:32.800655 | debian-bookworm | Label: debian-bookworm 2026-08-28 17:14:32.800675 | debian-bookworm | Product Name: OpenStack Nova 2026-08-28 17:14:32.800695 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed5:1409 2026-08-28 17:14:32.815337 | 2026-08-28 17:14:32.815452 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-28 17:14:33.743183 | debian-bookworm -> localhost | changed 2026-08-28 17:14:33.750808 | 2026-08-28 17:14:33.750947 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-28 17:14:36.319640 | debian-bookworm -> localhost | changed 2026-08-28 17:14:36.331555 | 2026-08-28 17:14:36.331672 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-28 17:14:36.901449 | debian-bookworm -> localhost | ok 2026-08-28 17:14:36.907365 | 2026-08-28 17:14:36.907469 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-28 17:14:36.946711 | debian-bookworm | ok 2026-08-28 17:14:36.988192 | debian-bookworm | included: /var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-28 17:14:36.996604 | 2026-08-28 17:14:36.996711 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-28 17:14:38.795464 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-28 17:14:38.795670 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/work/a72f3abb2060438f9c57b14c783f1444_id_rsa 2026-08-28 17:14:38.795709 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/work/a72f3abb2060438f9c57b14c783f1444_id_rsa.pub 2026-08-28 17:14:38.795736 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-28 17:14:38.795762 | debian-bookworm -> localhost | SHA256:8AWLt495BxuEsv5ukfRfQC0MrdHv/7rxRT8/0GQoRQs zuul-build-sshkey 2026-08-28 17:14:38.795785 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-28 17:14:38.795847 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-28 17:14:38.795873 | debian-bookworm -> localhost | | . .E.o | 2026-08-28 17:14:38.795897 | debian-bookworm -> localhost | | . +. B.o | 2026-08-28 17:14:38.795917 | debian-bookworm -> localhost | | + + o+.+. | 2026-08-28 17:14:38.795938 | debian-bookworm -> localhost | | *.+.....o | 2026-08-28 17:14:38.795958 | debian-bookworm -> localhost | | ..Soo .o+ .| 2026-08-28 17:14:38.795984 | debian-bookworm -> localhost | | . o+.+ .oo.| 2026-08-28 17:14:38.796005 | debian-bookworm -> localhost | | . o.+...ooo| 2026-08-28 17:14:38.796025 | debian-bookworm -> localhost | | ... .. +=| 2026-08-28 17:14:38.796046 | debian-bookworm -> localhost | | oo oo*| 2026-08-28 17:14:38.796066 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-28 17:14:38.796112 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.898633 2026-08-28 17:14:38.802302 | 2026-08-28 17:14:38.802406 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-28 17:14:38.847221 | debian-bookworm | ok 2026-08-28 17:14:38.860620 | debian-bookworm | included: /var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-28 17:14:38.889157 | 2026-08-28 17:14:38.889275 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-28 17:14:38.916568 | debian-bookworm | skipping: Conditional result was False 2026-08-28 17:14:38.923858 | 2026-08-28 17:14:38.923968 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-28 17:14:39.775485 | debian-bookworm | changed 2026-08-28 17:14:39.783106 | 2026-08-28 17:14:39.783217 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-28 17:14:40.072850 | debian-bookworm | ok 2026-08-28 17:14:40.080563 | 2026-08-28 17:14:40.080655 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-28 17:14:40.730807 | debian-bookworm | changed 2026-08-28 17:14:40.736666 | 2026-08-28 17:14:40.736765 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-28 17:14:41.460630 | debian-bookworm | changed 2026-08-28 17:14:41.469255 | 2026-08-28 17:14:41.469362 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-28 17:14:41.492861 | debian-bookworm | skipping: Conditional result was False 2026-08-28 17:14:41.500045 | 2026-08-28 17:14:41.500151 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-28 17:14:41.975757 | debian-bookworm -> localhost | changed 2026-08-28 17:14:41.992502 | 2026-08-28 17:14:41.992621 | TASK [add-build-sshkey : Add back temp key] 2026-08-28 17:14:42.714259 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/work/a72f3abb2060438f9c57b14c783f1444_id_rsa (zuul-build-sshkey) 2026-08-28 17:14:42.714468 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021536 2026-08-28 17:14:42.721844 | 2026-08-28 17:14:42.721940 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-28 17:14:43.299541 | debian-bookworm | ok 2026-08-28 17:14:43.305316 | 2026-08-28 17:14:43.305454 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-28 17:14:43.395975 | debian-bookworm | skipping: Conditional result was False 2026-08-28 17:14:43.414002 | 2026-08-28 17:14:43.414154 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-28 17:14:44.181985 | debian-bookworm | ok 2026-08-28 17:14:44.204167 | 2026-08-28 17:14:44.204323 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-28 17:14:44.264950 | debian-bookworm | ok 2026-08-28 17:14:44.272099 | 2026-08-28 17:14:44.272237 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-28 17:14:45.095075 | debian-bookworm -> localhost | ok 2026-08-28 17:14:45.111459 | 2026-08-28 17:14:45.111641 | TASK [validate-host : Collect information about the host] 2026-08-28 17:14:46.676795 | debian-bookworm | ok 2026-08-28 17:14:46.702593 | 2026-08-28 17:14:46.702772 | TASK [validate-host : Sanitize hostname] 2026-08-28 17:14:46.950537 | debian-bookworm | ok 2026-08-28 17:14:46.956038 | 2026-08-28 17:14:46.956157 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-28 17:14:47.945697 | debian-bookworm -> localhost | changed 2026-08-28 17:14:47.951498 | 2026-08-28 17:14:47.951602 | TASK [validate-host : Collect information about zuul worker] 2026-08-28 17:14:48.506089 | debian-bookworm | ok 2026-08-28 17:14:48.510794 | 2026-08-28 17:14:48.510922 | TASK [validate-host : Write out all zuul information for each host] 2026-08-28 17:14:49.202202 | debian-bookworm -> localhost | changed 2026-08-28 17:14:49.212092 | 2026-08-28 17:14:49.212197 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-28 17:14:49.544108 | debian-bookworm | ok 2026-08-28 17:14:49.553972 | 2026-08-28 17:14:49.554079 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-28 17:14:52.154581 | debian-bookworm | changed: 2026-08-28 17:14:52.155455 | debian-bookworm | cd+++++++++ src/ 2026-08-28 17:14:52.155521 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-28 17:14:52.155547 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-28 17:14:52.155571 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-28 17:14:52.155592 | debian-bookworm | Containerfile 2026-08-28 17:14:52.155726 | debian-bookworm | Containerfile 2026-08-28 17:14:52.170827 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/work/logs" 2026-08-28 17:17:24.850160 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/work/artifacts" 2026-08-28 17:17:25.180852 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a72f3abb2060438f9c57b14c783f1444/work/docs" 2026-08-28 17:17:25.199990 | 2026-08-28 17:17:25.200151 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-28 17:17:26.013940 | debian-bookworm | changed: 2026-08-28 17:17:26.014199 | debian-bookworm | .d..t...... ./ 2026-08-28 17:17:26.014235 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-28 17:17:26.014259 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-28 17:17:26.014281 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-28 17:17:26.014303 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-28 17:17:26.014323 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-28 17:17:26.014366 | debian-bookworm | changed: All items complete 2026-08-28 17:17:26.014391 | 2026-08-28 17:17:26.627832 | debian-bookworm | changed: .d..t...... ./ 2026-08-28 17:17:27.202649 | debian-bookworm | changed: .d..t...... ./ 2026-08-28 17:17:27.238973 | 2026-08-28 17:17:27.239216 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-28 17:17:27.607305 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.013425 2026-08-28 17:17:27.911606 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.012278 2026-08-28 17:17:27.933755 | 2026-08-28 17:17:27.933991 | PLAY RECAP 2026-08-28 17:17:27.934077 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-28 17:17:27.934114 | 2026-08-28 17:17:28.092065 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-28 17:17:28.092993 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-28 17:17:28.885082 | 2026-08-28 17:17:28.885379 | PLAY [Base post] 2026-08-28 17:17:28.903130 | 2026-08-28 17:17:28.903310 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-28 17:17:29.743937 | debian-bookworm | changed 2026-08-28 17:17:29.753637 | 2026-08-28 17:17:29.753784 | PLAY RECAP 2026-08-28 17:17:29.753915 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-28 17:17:29.754000 | 2026-08-28 17:17:29.901421 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-28 17:17:29.902359 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-28 17:17:30.745199 | 2026-08-28 17:17:30.745391 | PLAY [Base post-logs] 2026-08-28 17:17:30.756398 | 2026-08-28 17:17:30.756549 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-28 17:17:31.233558 | localhost | changed 2026-08-28 17:17:31.251720 | 2026-08-28 17:17:31.252011 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-28 17:17:31.292662 | localhost | ok 2026-08-28 17:17:31.302519 | 2026-08-28 17:17:31.302710 | TASK [Set zuul-log-path fact] 2026-08-28 17:17:31.320535 | localhost | ok 2026-08-28 17:17:31.330630 | 2026-08-28 17:17:31.330793 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-28 17:17:31.358523 | localhost | ok 2026-08-28 17:17:31.362703 | 2026-08-28 17:17:31.362919 | TASK [upload-logs : Create log directories] 2026-08-28 17:17:31.885599 | localhost | changed 2026-08-28 17:17:31.890591 | 2026-08-28 17:17:31.890769 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-28 17:17:32.438145 | localhost -> localhost | ok: Runtime: 0:00:00.005222 2026-08-28 17:17:32.449937 | 2026-08-28 17:17:32.450128 | TASK [upload-logs : Upload logs to log server] 2026-08-28 17:17:33.081695 | localhost | Output suppressed because no_log was given 2026-08-28 17:17:33.084786 | 2026-08-28 17:17:33.084966 | LOOP [upload-logs : Compress console log and json output] 2026-08-28 17:17:33.148160 | localhost | skipping: Conditional result was False 2026-08-28 17:17:33.157648 | localhost | skipping: Conditional result was False 2026-08-28 17:17:33.169722 | 2026-08-28 17:17:33.170116 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-28 17:17:33.244396 | localhost | skipping: Conditional result was False 2026-08-28 17:17:33.245126 | 2026-08-28 17:17:33.248069 | localhost | skipping: Conditional result was False 2026-08-28 17:17:33.256156 | 2026-08-28 17:17:33.256410 | LOOP [upload-logs : Upload console log and json output]