2026-07-13 20:16:09.091032 | Job console starting 2026-07-13 20:16:09.108004 | Updating git repos 2026-07-13 20:16:09.186949 | Cloning repos into workspace 2026-07-13 20:16:09.245492 | Restoring repo states 2026-07-13 20:16:09.261952 | Merging changes 2026-07-13 20:16:09.945063 | Checking out repos 2026-07-13 20:16:10.062409 | Preparing playbooks 2026-07-13 20:16:11.336601 | Running Ansible setup 2026-07-13 20:16:19.141974 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-13 20:16:20.205366 | 2026-07-13 20:16:20.205479 | PLAY [Base pre] 2026-07-13 20:16:20.220156 | 2026-07-13 20:16:20.220249 | TASK [Setup log path fact] 2026-07-13 20:16:20.249350 | debian-bookworm | ok 2026-07-13 20:16:20.262936 | 2026-07-13 20:16:20.263037 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-13 20:16:20.316853 | debian-bookworm | ok 2026-07-13 20:16:20.329583 | 2026-07-13 20:16:20.330287 | TASK [emit-job-header : Print job information] 2026-07-13 20:16:20.378608 | # Job Information 2026-07-13 20:16:20.378747 | Ansible Version: 2.16.14 2026-07-13 20:16:20.378776 | Job: python-osism-test-setup 2026-07-13 20:16:20.378803 | Pipeline: check 2026-07-13 20:16:20.378822 | Executor: 521e9411259a 2026-07-13 20:16:20.378856 | Triggered by: https://github.com/osism/python-osism/pull/2461 2026-07-13 20:16:20.378875 | Event ID: 7efe8f70-7ef7-11f1-8a26-8c8f673ed4e0 2026-07-13 20:16:20.383060 | 2026-07-13 20:16:20.383146 | LOOP [emit-job-header : Print node information] 2026-07-13 20:16:20.521053 | debian-bookworm | ok: 2026-07-13 20:16:20.521291 | debian-bookworm | # Node Information 2026-07-13 20:16:20.521349 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-13 20:16:20.521381 | debian-bookworm | Hostname: debian 2026-07-13 20:16:20.521423 | debian-bookworm | Username: zuul 2026-07-13 20:16:20.521452 | debian-bookworm | Distro: Debian 12.15 2026-07-13 20:16:20.521495 | debian-bookworm | Provider: regiocloud-a 2026-07-13 20:16:20.521529 | debian-bookworm | Region: 2026-07-13 20:16:20.521558 | debian-bookworm | Label: debian-bookworm 2026-07-13 20:16:20.521596 | debian-bookworm | Product Name: OpenStack Nova 2026-07-13 20:16:20.521630 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2a:747e 2026-07-13 20:16:20.547810 | 2026-07-13 20:16:20.547919 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-13 20:16:21.132780 | debian-bookworm -> localhost | changed 2026-07-13 20:16:21.143832 | 2026-07-13 20:16:21.143950 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-13 20:16:23.237760 | debian-bookworm -> localhost | changed 2026-07-13 20:16:23.250265 | 2026-07-13 20:16:23.250392 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-13 20:16:23.865706 | debian-bookworm -> localhost | ok 2026-07-13 20:16:23.876336 | 2026-07-13 20:16:23.876462 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-13 20:16:23.929888 | debian-bookworm | ok 2026-07-13 20:16:23.945480 | debian-bookworm | included: /var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-13 20:16:23.950703 | 2026-07-13 20:16:23.950790 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-13 20:16:25.853668 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-13 20:16:25.853895 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/work/6cd57df9112c4090a880feae850a09ab_id_rsa 2026-07-13 20:16:25.853934 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/work/6cd57df9112c4090a880feae850a09ab_id_rsa.pub 2026-07-13 20:16:25.853961 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-13 20:16:25.853986 | debian-bookworm -> localhost | SHA256:nVc3GxwHjdWWaT6IRoCKrNniqtFcvkb/bAQ2IwTfiIE zuul-build-sshkey 2026-07-13 20:16:25.854009 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-13 20:16:25.854038 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-13 20:16:25.854059 | debian-bookworm -> localhost | | .o. ... .**| 2026-07-13 20:16:25.854080 | debian-bookworm -> localhost | |E +.o . . o+*| 2026-07-13 20:16:25.854100 | debian-bookworm -> localhost | | o.+ o . . ==.| 2026-07-13 20:16:25.854119 | debian-bookworm -> localhost | | o..= . + o ++| 2026-07-13 20:16:25.854138 | debian-bookworm -> localhost | | + .o +S + . ..| 2026-07-13 20:16:25.854163 | debian-bookworm -> localhost | | * +. . . | 2026-07-13 20:16:25.854185 | debian-bookworm -> localhost | |o +... . | 2026-07-13 20:16:25.854205 | debian-bookworm -> localhost | | o ..... | 2026-07-13 20:16:25.854226 | debian-bookworm -> localhost | |= .. oo | 2026-07-13 20:16:25.854247 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-13 20:16:25.854330 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.143816 2026-07-13 20:16:25.863125 | 2026-07-13 20:16:25.863237 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-13 20:16:25.908534 | debian-bookworm | ok 2026-07-13 20:16:25.953966 | debian-bookworm | included: /var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-13 20:16:25.974810 | 2026-07-13 20:16:25.974955 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-13 20:16:26.022996 | debian-bookworm | skipping: Conditional result was False 2026-07-13 20:16:26.030498 | 2026-07-13 20:16:26.030676 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-13 20:16:27.018356 | debian-bookworm | changed 2026-07-13 20:16:27.049031 | 2026-07-13 20:16:27.049156 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-13 20:16:27.379830 | debian-bookworm | ok 2026-07-13 20:16:27.386159 | 2026-07-13 20:16:27.386264 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-13 20:16:28.273950 | debian-bookworm | changed 2026-07-13 20:16:28.292383 | 2026-07-13 20:16:28.293063 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-13 20:16:29.258689 | debian-bookworm | changed 2026-07-13 20:16:29.282717 | 2026-07-13 20:16:29.282823 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-13 20:16:29.311027 | debian-bookworm | skipping: Conditional result was False 2026-07-13 20:16:29.319019 | 2026-07-13 20:16:29.319120 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-13 20:16:29.895150 | debian-bookworm -> localhost | changed 2026-07-13 20:16:29.911772 | 2026-07-13 20:16:29.911937 | TASK [add-build-sshkey : Add back temp key] 2026-07-13 20:16:30.561459 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/work/6cd57df9112c4090a880feae850a09ab_id_rsa (zuul-build-sshkey) 2026-07-13 20:16:30.561651 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.042368 2026-07-13 20:16:30.571907 | 2026-07-13 20:16:30.572008 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-13 20:16:31.430765 | debian-bookworm | ok 2026-07-13 20:16:31.439977 | 2026-07-13 20:16:31.440088 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-13 20:16:31.497898 | debian-bookworm | skipping: Conditional result was False 2026-07-13 20:16:31.552916 | 2026-07-13 20:16:31.555193 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-13 20:16:32.362762 | debian-bookworm | ok 2026-07-13 20:16:32.395104 | 2026-07-13 20:16:32.395224 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-13 20:16:32.426521 | debian-bookworm | ok 2026-07-13 20:16:32.434120 | 2026-07-13 20:16:32.434296 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-13 20:16:33.040864 | debian-bookworm -> localhost | ok 2026-07-13 20:16:33.046916 | 2026-07-13 20:16:33.047007 | TASK [validate-host : Collect information about the host] 2026-07-13 20:16:34.264230 | debian-bookworm | ok 2026-07-13 20:16:34.294434 | 2026-07-13 20:16:34.294558 | TASK [validate-host : Sanitize hostname] 2026-07-13 20:16:34.593937 | debian-bookworm | ok 2026-07-13 20:16:34.598878 | 2026-07-13 20:16:34.599228 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-13 20:16:35.512630 | debian-bookworm -> localhost | changed 2026-07-13 20:16:35.518485 | 2026-07-13 20:16:35.518582 | TASK [validate-host : Collect information about zuul worker] 2026-07-13 20:16:36.083744 | debian-bookworm | ok 2026-07-13 20:16:36.087962 | 2026-07-13 20:16:36.088055 | TASK [validate-host : Write out all zuul information for each host] 2026-07-13 20:16:37.222131 | debian-bookworm -> localhost | changed 2026-07-13 20:16:37.234638 | 2026-07-13 20:16:37.234746 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-13 20:16:37.529541 | debian-bookworm | ok 2026-07-13 20:16:37.551576 | 2026-07-13 20:16:37.551736 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-13 20:16:39.220207 | debian-bookworm | changed: 2026-07-13 20:16:39.220404 | debian-bookworm | cd+++++++++ src/ 2026-07-13 20:16:39.220439 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-13 20:16:39.220463 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-13 20:16:39.220484 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-13 20:16:39.220504 | debian-bookworm | Containerfile 2026-07-13 20:16:39.220634 | debian-bookworm | Containerfile 2026-07-13 20:16:39.278192 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/work/logs" 2026-07-13 20:19:22.969294 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/work/artifacts" 2026-07-13 20:19:23.319249 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/6cd57df9112c4090a880feae850a09ab/work/docs" 2026-07-13 20:19:23.334897 | 2026-07-13 20:19:23.335117 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-13 20:19:24.224944 | debian-bookworm | changed: 2026-07-13 20:19:24.225351 | debian-bookworm | .d..t...... ./ 2026-07-13 20:19:24.225428 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-13 20:19:24.225470 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-13 20:19:24.225507 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-13 20:19:24.225543 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-13 20:19:24.225577 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-13 20:19:24.225648 | debian-bookworm | changed: All items complete 2026-07-13 20:19:24.225692 | 2026-07-13 20:19:24.816410 | debian-bookworm | changed: .d..t...... ./ 2026-07-13 20:19:25.417185 | debian-bookworm | changed: .d..t...... ./ 2026-07-13 20:19:25.433158 | 2026-07-13 20:19:25.433334 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-13 20:19:25.809906 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011582 2026-07-13 20:19:26.110539 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009621 2026-07-13 20:19:26.126128 | 2026-07-13 20:19:26.126450 | PLAY RECAP 2026-07-13 20:19:26.126869 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-13 20:19:26.126913 | 2026-07-13 20:19:26.288233 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-13 20:19:26.290060 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-13 20:19:27.159213 | 2026-07-13 20:19:27.159402 | PLAY [Base post] 2026-07-13 20:19:27.173874 | 2026-07-13 20:19:27.174020 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-13 20:19:28.012251 | debian-bookworm | changed 2026-07-13 20:19:28.020454 | 2026-07-13 20:19:28.020592 | PLAY RECAP 2026-07-13 20:19:28.020674 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-13 20:19:28.020760 | 2026-07-13 20:19:28.208603 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-13 20:19:28.209582 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-13 20:19:29.311844 | 2026-07-13 20:19:29.312039 | PLAY [Base post-logs] 2026-07-13 20:19:29.325897 | 2026-07-13 20:19:29.326074 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-13 20:19:29.919709 | localhost | changed 2026-07-13 20:19:29.934057 | 2026-07-13 20:19:29.934248 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-13 20:19:29.982129 | localhost | ok 2026-07-13 20:19:29.986678 | 2026-07-13 20:19:29.986817 | TASK [Set zuul-log-path fact] 2026-07-13 20:19:30.017556 | localhost | ok 2026-07-13 20:19:30.034720 | 2026-07-13 20:19:30.035222 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-13 20:19:30.077096 | localhost | ok 2026-07-13 20:19:30.082236 | 2026-07-13 20:19:30.082374 | TASK [upload-logs : Create log directories] 2026-07-13 20:19:30.726689 | localhost | changed 2026-07-13 20:19:30.733014 | 2026-07-13 20:19:30.734172 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-13 20:19:31.395039 | localhost -> localhost | ok: Runtime: 0:00:00.011895 2026-07-13 20:19:31.400008 | 2026-07-13 20:19:31.400136 | TASK [upload-logs : Upload logs to log server] 2026-07-13 20:19:32.021103 | localhost | Output suppressed because no_log was given 2026-07-13 20:19:32.033304 | 2026-07-13 20:19:32.034103 | LOOP [upload-logs : Compress console log and json output] 2026-07-13 20:19:32.095400 | localhost | skipping: Conditional result was False 2026-07-13 20:19:32.103761 | localhost | skipping: Conditional result was False 2026-07-13 20:19:32.115777 | 2026-07-13 20:19:32.115971 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-13 20:19:32.178972 | localhost | skipping: Conditional result was False 2026-07-13 20:19:32.179326 | 2026-07-13 20:19:32.183888 | localhost | skipping: Conditional result was False 2026-07-13 20:19:32.192737 | 2026-07-13 20:19:32.192946 | LOOP [upload-logs : Upload console log and json output]