2026-05-19 12:17:57.613350 | Job console starting 2026-05-19 12:17:57.623852 | Updating git repos 2026-05-19 12:17:57.702773 | Cloning repos into workspace 2026-05-19 12:17:57.760148 | Restoring repo states 2026-05-19 12:17:57.777262 | Merging changes 2026-05-19 12:17:58.564649 | Checking out repos 2026-05-19 12:17:58.726451 | Preparing playbooks 2026-05-19 12:18:00.966464 | Running Ansible setup 2026-05-19 12:18:07.313735 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-19 12:18:08.823128 | 2026-05-19 12:18:08.823311 | PLAY [Base pre] 2026-05-19 12:18:08.861102 | 2026-05-19 12:18:08.861271 | TASK [Setup log path fact] 2026-05-19 12:18:08.899145 | debian-bookworm | ok 2026-05-19 12:18:08.927061 | 2026-05-19 12:18:08.927237 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-19 12:18:08.994931 | debian-bookworm | ok 2026-05-19 12:18:09.007267 | 2026-05-19 12:18:09.007408 | TASK [emit-job-header : Print job information] 2026-05-19 12:18:09.105669 | # Job Information 2026-05-19 12:18:09.105874 | Ansible Version: 2.16.14 2026-05-19 12:18:09.105912 | Job: python-osism-test-setup 2026-05-19 12:18:09.105945 | Pipeline: check 2026-05-19 12:18:09.105969 | Executor: 521e9411259a 2026-05-19 12:18:09.105990 | Triggered by: https://github.com/osism/python-osism/pull/2282 2026-05-19 12:18:09.106014 | Event ID: bde35b40-537c-11f1-90ba-f96148db6acf 2026-05-19 12:18:09.111255 | 2026-05-19 12:18:09.111387 | LOOP [emit-job-header : Print node information] 2026-05-19 12:18:09.444957 | debian-bookworm | ok: 2026-05-19 12:18:09.445248 | debian-bookworm | # Node Information 2026-05-19 12:18:09.445288 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-19 12:18:09.445314 | debian-bookworm | Hostname: debian 2026-05-19 12:18:09.445337 | debian-bookworm | Username: zuul 2026-05-19 12:18:09.445358 | debian-bookworm | Distro: Debian 12.14 2026-05-19 12:18:09.445382 | debian-bookworm | Provider: regiocloud-a 2026-05-19 12:18:09.445404 | debian-bookworm | Region: 2026-05-19 12:18:09.445467 | debian-bookworm | Label: debian-bookworm 2026-05-19 12:18:09.445489 | debian-bookworm | Product Name: OpenStack Nova 2026-05-19 12:18:09.445508 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefe:8649 2026-05-19 12:18:09.484276 | 2026-05-19 12:18:09.484531 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-19 12:18:10.590685 | debian-bookworm -> localhost | changed 2026-05-19 12:18:10.597522 | 2026-05-19 12:18:10.597635 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-19 12:18:12.267372 | debian-bookworm -> localhost | changed 2026-05-19 12:18:12.275954 | 2026-05-19 12:18:12.276044 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-19 12:18:12.692709 | debian-bookworm -> localhost | ok 2026-05-19 12:18:12.697687 | 2026-05-19 12:18:12.697775 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-19 12:18:12.737145 | debian-bookworm | ok 2026-05-19 12:18:12.768051 | debian-bookworm | included: /var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-19 12:18:12.782911 | 2026-05-19 12:18:12.783013 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-19 12:18:13.980397 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-19 12:18:13.980625 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/work/8abffa155284489f843233721ebf9f6d_id_rsa 2026-05-19 12:18:13.980658 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/work/8abffa155284489f843233721ebf9f6d_id_rsa.pub 2026-05-19 12:18:13.980681 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-19 12:18:13.980701 | debian-bookworm -> localhost | SHA256:kvyiTN+Y4M9/+jZI4w3kpbuDTOdEGov6vsgio0Y3IIo zuul-build-sshkey 2026-05-19 12:18:13.980720 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-19 12:18:13.980746 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-19 12:18:13.980764 | debian-bookworm -> localhost | | | 2026-05-19 12:18:13.980781 | debian-bookworm -> localhost | | | 2026-05-19 12:18:13.980798 | debian-bookworm -> localhost | | | 2026-05-19 12:18:13.980815 | debian-bookworm -> localhost | |.. ...o . | 2026-05-19 12:18:13.980831 | debian-bookworm -> localhost | |+ . .+BSo | 2026-05-19 12:18:13.980853 | debian-bookworm -> localhost | |E. o. +oO | 2026-05-19 12:18:13.980870 | debian-bookworm -> localhost | |. ..+o.B.* | 2026-05-19 12:18:13.980887 | debian-bookworm -> localhost | |+o.= =o=* = | 2026-05-19 12:18:13.980904 | debian-bookworm -> localhost | |=oooBo*.=B.. | 2026-05-19 12:18:13.980921 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-19 12:18:13.980965 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.422117 2026-05-19 12:18:13.986012 | 2026-05-19 12:18:13.986086 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-19 12:18:14.013135 | debian-bookworm | ok 2026-05-19 12:18:14.021223 | debian-bookworm | included: /var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-19 12:18:14.052015 | 2026-05-19 12:18:14.052114 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-19 12:18:14.074738 | debian-bookworm | skipping: Conditional result was False 2026-05-19 12:18:14.079467 | 2026-05-19 12:18:14.079544 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-19 12:18:14.878714 | debian-bookworm | changed 2026-05-19 12:18:14.883022 | 2026-05-19 12:18:14.883099 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-19 12:18:15.170873 | debian-bookworm | ok 2026-05-19 12:18:15.175332 | 2026-05-19 12:18:15.175410 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-19 12:18:15.828765 | debian-bookworm | changed 2026-05-19 12:18:15.834343 | 2026-05-19 12:18:15.834461 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-19 12:18:16.377600 | debian-bookworm | changed 2026-05-19 12:18:16.387385 | 2026-05-19 12:18:16.387493 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-19 12:18:16.420526 | debian-bookworm | skipping: Conditional result was False 2026-05-19 12:18:16.428473 | 2026-05-19 12:18:16.428598 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-19 12:18:16.838511 | debian-bookworm -> localhost | changed 2026-05-19 12:18:16.850180 | 2026-05-19 12:18:16.850328 | TASK [add-build-sshkey : Add back temp key] 2026-05-19 12:18:17.181038 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/work/8abffa155284489f843233721ebf9f6d_id_rsa (zuul-build-sshkey) 2026-05-19 12:18:17.181288 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012247 2026-05-19 12:18:17.189891 | 2026-05-19 12:18:17.190005 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-19 12:18:17.574637 | debian-bookworm | ok 2026-05-19 12:18:17.588422 | 2026-05-19 12:18:17.588609 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-19 12:18:17.635952 | debian-bookworm | skipping: Conditional result was False 2026-05-19 12:18:17.647637 | 2026-05-19 12:18:17.647758 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-19 12:18:18.100823 | debian-bookworm | ok 2026-05-19 12:18:18.112325 | 2026-05-19 12:18:18.112468 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-19 12:18:18.175769 | debian-bookworm | ok 2026-05-19 12:18:18.185225 | 2026-05-19 12:18:18.185338 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-19 12:18:18.513614 | debian-bookworm -> localhost | ok 2026-05-19 12:18:18.529725 | 2026-05-19 12:18:18.529897 | TASK [validate-host : Collect information about the host] 2026-05-19 12:18:19.955099 | debian-bookworm | ok 2026-05-19 12:18:19.979252 | 2026-05-19 12:18:19.979397 | TASK [validate-host : Sanitize hostname] 2026-05-19 12:18:20.204628 | debian-bookworm | ok 2026-05-19 12:18:20.303907 | 2026-05-19 12:18:20.304060 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-19 12:18:21.713489 | debian-bookworm -> localhost | changed 2026-05-19 12:18:21.719959 | 2026-05-19 12:18:21.720081 | TASK [validate-host : Collect information about zuul worker] 2026-05-19 12:18:22.195834 | debian-bookworm | ok 2026-05-19 12:18:22.207867 | 2026-05-19 12:18:22.208046 | TASK [validate-host : Write out all zuul information for each host] 2026-05-19 12:18:23.165901 | debian-bookworm -> localhost | changed 2026-05-19 12:18:23.183607 | 2026-05-19 12:18:23.183757 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-19 12:18:23.498810 | debian-bookworm | ok 2026-05-19 12:18:23.503225 | 2026-05-19 12:18:23.503302 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-19 12:18:24.597183 | debian-bookworm | changed: 2026-05-19 12:18:24.597361 | debian-bookworm | cd+++++++++ src/ 2026-05-19 12:18:24.597395 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-19 12:18:24.597419 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-19 12:18:24.597452 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-19 12:18:24.597473 | debian-bookworm | Containerfile 2026-05-19 12:18:24.597601 | debian-bookworm | Containerfile 2026-05-19 12:18:24.617442 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/work/logs" 2026-05-19 12:23:10.086464 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/work/artifacts" 2026-05-19 12:23:10.301190 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8abffa155284489f843233721ebf9f6d/work/docs" 2026-05-19 12:23:10.327834 | 2026-05-19 12:23:10.327989 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-19 12:23:11.091024 | debian-bookworm | changed: 2026-05-19 12:23:11.091435 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-19 12:23:11.091555 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-19 12:23:11.091613 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-19 12:23:11.091698 | debian-bookworm | changed: All items complete 2026-05-19 12:23:11.091738 | 2026-05-19 12:23:11.668743 | debian-bookworm | changed: .d..t...... ./ 2026-05-19 12:23:12.286258 | debian-bookworm | changed: .d..t...... ./ 2026-05-19 12:23:12.322575 | 2026-05-19 12:23:12.322812 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-19 12:23:12.867986 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.013788 2026-05-19 12:23:13.137230 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008203 2026-05-19 12:23:13.156252 | 2026-05-19 12:23:13.156372 | PLAY RECAP 2026-05-19 12:23:13.156442 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-19 12:23:13.156479 | 2026-05-19 12:23:13.312037 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-19 12:23:13.314206 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-19 12:23:14.062599 | 2026-05-19 12:23:14.062789 | PLAY [Base post] 2026-05-19 12:23:14.076539 | 2026-05-19 12:23:14.076719 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-19 12:23:14.906561 | debian-bookworm | changed 2026-05-19 12:23:14.921373 | 2026-05-19 12:23:14.921575 | PLAY RECAP 2026-05-19 12:23:14.921729 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-19 12:23:14.921853 | 2026-05-19 12:23:15.043150 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-19 12:23:15.045149 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-19 12:23:15.853040 | 2026-05-19 12:23:15.853221 | PLAY [Base post-logs] 2026-05-19 12:23:15.864382 | 2026-05-19 12:23:15.864548 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-19 12:23:16.360151 | localhost | changed 2026-05-19 12:23:16.374509 | 2026-05-19 12:23:16.374717 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-19 12:23:16.412888 | localhost | ok 2026-05-19 12:23:16.418388 | 2026-05-19 12:23:16.418554 | TASK [Set zuul-log-path fact] 2026-05-19 12:23:16.446759 | localhost | ok 2026-05-19 12:23:16.459791 | 2026-05-19 12:23:16.459930 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-19 12:23:16.499122 | localhost | ok 2026-05-19 12:23:16.506930 | 2026-05-19 12:23:16.507113 | TASK [upload-logs : Create log directories] 2026-05-19 12:23:17.034572 | localhost | changed 2026-05-19 12:23:17.037802 | 2026-05-19 12:23:17.037923 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-19 12:23:17.558707 | localhost -> localhost | ok: Runtime: 0:00:00.006468 2026-05-19 12:23:17.562869 | 2026-05-19 12:23:17.562985 | TASK [upload-logs : Upload logs to log server] 2026-05-19 12:23:18.136743 | localhost | Output suppressed because no_log was given 2026-05-19 12:23:18.139536 | 2026-05-19 12:23:18.139726 | LOOP [upload-logs : Compress console log and json output] 2026-05-19 12:23:18.193633 | localhost | skipping: Conditional result was False 2026-05-19 12:23:18.199572 | localhost | skipping: Conditional result was False 2026-05-19 12:23:18.208770 | 2026-05-19 12:23:18.208897 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-19 12:23:18.263119 | localhost | skipping: Conditional result was False 2026-05-19 12:23:18.263662 | 2026-05-19 12:23:18.267218 | localhost | skipping: Conditional result was False 2026-05-19 12:23:18.280969 | 2026-05-19 12:23:18.281203 | LOOP [upload-logs : Upload console log and json output]