2026-05-04 20:32:49.980418 | Job console starting 2026-05-04 20:32:50.003642 | Updating git repos 2026-05-04 20:32:50.057105 | Cloning repos into workspace 2026-05-04 20:32:50.149056 | Restoring repo states 2026-05-04 20:32:50.172026 | Merging changes 2026-05-04 20:32:50.926979 | Checking out repos 2026-05-04 20:32:51.115675 | Preparing playbooks 2026-05-04 20:32:52.923450 | Running Ansible setup 2026-05-04 20:33:00.009818 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-04 20:33:02.211343 | 2026-05-04 20:33:02.211476 | PLAY [Base pre] 2026-05-04 20:33:02.254562 | 2026-05-04 20:33:02.254696 | TASK [Setup log path fact] 2026-05-04 20:33:02.327481 | debian-bookworm | ok 2026-05-04 20:33:02.356397 | 2026-05-04 20:33:02.356538 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-04 20:33:02.416293 | debian-bookworm | ok 2026-05-04 20:33:02.451594 | 2026-05-04 20:33:02.451722 | TASK [emit-job-header : Print job information] 2026-05-04 20:33:02.544253 | # Job Information 2026-05-04 20:33:02.544421 | Ansible Version: 2.16.14 2026-05-04 20:33:02.544457 | Job: python-osism-test-setup 2026-05-04 20:33:02.544490 | Pipeline: check 2026-05-04 20:33:02.544513 | Executor: 521e9411259a 2026-05-04 20:33:02.544534 | Triggered by: https://github.com/osism/python-osism/pull/2255 2026-05-04 20:33:02.544556 | Event ID: 6405f6d0-47f8-11f1-9265-9b34938edbd5 2026-05-04 20:33:02.553092 | 2026-05-04 20:33:02.553206 | LOOP [emit-job-header : Print node information] 2026-05-04 20:33:02.818585 | debian-bookworm | ok: 2026-05-04 20:33:02.818750 | debian-bookworm | # Node Information 2026-05-04 20:33:02.818785 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-04 20:33:02.818811 | debian-bookworm | Hostname: debian 2026-05-04 20:33:02.818873 | debian-bookworm | Username: zuul 2026-05-04 20:33:02.818896 | debian-bookworm | Distro: Debian 12.13 2026-05-04 20:33:02.818920 | debian-bookworm | Provider: regiocloud-a 2026-05-04 20:33:02.818954 | debian-bookworm | Region: 2026-05-04 20:33:02.819090 | debian-bookworm | Label: debian-bookworm 2026-05-04 20:33:02.819118 | debian-bookworm | Product Name: OpenStack Nova 2026-05-04 20:33:02.819141 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe00:3a6e 2026-05-04 20:33:02.852677 | 2026-05-04 20:33:02.854027 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-04 20:33:04.091422 | debian-bookworm -> localhost | changed 2026-05-04 20:33:04.097322 | 2026-05-04 20:33:04.097421 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-04 20:33:05.781495 | debian-bookworm -> localhost | changed 2026-05-04 20:33:05.790219 | 2026-05-04 20:33:05.790333 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-04 20:33:06.486262 | debian-bookworm -> localhost | ok 2026-05-04 20:33:06.491210 | 2026-05-04 20:33:06.491294 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-04 20:33:06.519474 | debian-bookworm | ok 2026-05-04 20:33:06.551333 | debian-bookworm | included: /var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-04 20:33:06.556557 | 2026-05-04 20:33:06.556645 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-04 20:33:09.038557 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-04 20:33:09.038749 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/work/0862d20fe9e8459aa3ccf349b2e78a5b_id_rsa 2026-05-04 20:33:09.038781 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/work/0862d20fe9e8459aa3ccf349b2e78a5b_id_rsa.pub 2026-05-04 20:33:09.038803 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-04 20:33:09.038822 | debian-bookworm -> localhost | SHA256:q+sT21AmM/5OEWLfDs84xckFbMofhTnSfBnGy5VPEFM zuul-build-sshkey 2026-05-04 20:33:09.038933 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-04 20:33:09.038963 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-04 20:33:09.038982 | debian-bookworm -> localhost | | +.+oo+=E| 2026-05-04 20:33:09.039000 | debian-bookworm -> localhost | | . Oo= o..| 2026-05-04 20:33:09.039017 | debian-bookworm -> localhost | | o..+ =.o o | 2026-05-04 20:33:09.039032 | debian-bookworm -> localhost | | .+o==.oo .| 2026-05-04 20:33:09.039049 | debian-bookworm -> localhost | | . *S.*. | 2026-05-04 20:33:09.039069 | debian-bookworm -> localhost | | + X. | 2026-05-04 20:33:09.039085 | debian-bookworm -> localhost | | *= + | 2026-05-04 20:33:09.039101 | debian-bookworm -> localhost | | ooo. | 2026-05-04 20:33:09.039118 | debian-bookworm -> localhost | | .++. | 2026-05-04 20:33:09.039135 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-04 20:33:09.039181 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.147431 2026-05-04 20:33:09.045453 | 2026-05-04 20:33:09.045534 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-04 20:33:09.082740 | debian-bookworm | ok 2026-05-04 20:33:09.090171 | debian-bookworm | included: /var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-04 20:33:09.108392 | 2026-05-04 20:33:09.108482 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-04 20:33:09.141332 | debian-bookworm | skipping: Conditional result was False 2026-05-04 20:33:09.147388 | 2026-05-04 20:33:09.147478 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-04 20:33:09.983089 | debian-bookworm | changed 2026-05-04 20:33:09.987704 | 2026-05-04 20:33:09.987792 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-04 20:33:10.263356 | debian-bookworm | ok 2026-05-04 20:33:10.267810 | 2026-05-04 20:33:10.267890 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-04 20:33:10.996689 | debian-bookworm | changed 2026-05-04 20:33:11.002503 | 2026-05-04 20:33:11.002597 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-04 20:33:11.757162 | debian-bookworm | changed 2026-05-04 20:33:11.761537 | 2026-05-04 20:33:11.761623 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-04 20:33:11.796473 | debian-bookworm | skipping: Conditional result was False 2026-05-04 20:33:11.807562 | 2026-05-04 20:33:11.807663 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-04 20:33:12.538756 | debian-bookworm -> localhost | changed 2026-05-04 20:33:12.548807 | 2026-05-04 20:33:12.548933 | TASK [add-build-sshkey : Add back temp key] 2026-05-04 20:33:13.042692 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/work/0862d20fe9e8459aa3ccf349b2e78a5b_id_rsa (zuul-build-sshkey) 2026-05-04 20:33:13.042896 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029962 2026-05-04 20:33:13.049260 | 2026-05-04 20:33:13.049363 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-04 20:33:13.545014 | debian-bookworm | ok 2026-05-04 20:33:13.549532 | 2026-05-04 20:33:13.549610 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-04 20:33:13.588066 | debian-bookworm | skipping: Conditional result was False 2026-05-04 20:33:13.596817 | 2026-05-04 20:33:13.596912 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-04 20:33:14.242705 | debian-bookworm | ok 2026-05-04 20:33:14.263207 | 2026-05-04 20:33:14.263318 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-04 20:33:14.310803 | debian-bookworm | ok 2026-05-04 20:33:14.316257 | 2026-05-04 20:33:14.316373 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-04 20:33:14.831715 | debian-bookworm -> localhost | ok 2026-05-04 20:33:14.837682 | 2026-05-04 20:33:14.837764 | TASK [validate-host : Collect information about the host] 2026-05-04 20:33:16.291695 | debian-bookworm | ok 2026-05-04 20:33:16.303714 | 2026-05-04 20:33:16.303810 | TASK [validate-host : Sanitize hostname] 2026-05-04 20:33:16.422819 | debian-bookworm | ok 2026-05-04 20:33:16.497114 | 2026-05-04 20:33:16.497213 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-04 20:33:17.337972 | debian-bookworm -> localhost | changed 2026-05-04 20:33:17.342794 | 2026-05-04 20:33:17.342910 | TASK [validate-host : Collect information about zuul worker] 2026-05-04 20:33:17.798104 | debian-bookworm | ok 2026-05-04 20:33:17.801891 | 2026-05-04 20:33:17.801974 | TASK [validate-host : Write out all zuul information for each host] 2026-05-04 20:33:18.657442 | debian-bookworm -> localhost | changed 2026-05-04 20:33:18.665111 | 2026-05-04 20:33:18.665198 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-04 20:33:18.989541 | debian-bookworm | ok 2026-05-04 20:33:18.994515 | 2026-05-04 20:33:18.994614 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-04 20:33:20.354068 | debian-bookworm | changed: 2026-05-04 20:33:20.354223 | debian-bookworm | cd+++++++++ src/ 2026-05-04 20:33:20.354252 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-04 20:33:20.354272 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-04 20:33:20.354299 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-04 20:33:20.354317 | debian-bookworm | Containerfile 2026-05-04 20:33:20.354424 | debian-bookworm | Containerfile 2026-05-04 20:33:20.379697 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/work/logs" 2026-05-04 20:46:31.795726 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/work/artifacts" 2026-05-04 20:46:32.095784 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0862d20fe9e8459aa3ccf349b2e78a5b/work/docs" 2026-05-04 20:46:32.119929 | 2026-05-04 20:46:32.120235 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-04 20:46:32.995755 | debian-bookworm | changed: 2026-05-04 20:46:32.996130 | debian-bookworm | .d..t...... ./ 2026-05-04 20:46:32.996269 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-04 20:46:32.996325 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-04 20:46:32.996367 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-04 20:46:32.996436 | debian-bookworm | changed: All items complete 2026-05-04 20:46:32.996480 | 2026-05-04 20:46:33.644956 | debian-bookworm | changed: .d..t...... ./ 2026-05-04 20:46:34.242805 | debian-bookworm | changed: .d..t...... ./ 2026-05-04 20:46:34.264850 | 2026-05-04 20:46:34.265003 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-04 20:46:34.821925 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009703 2026-05-04 20:46:35.097525 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010951 2026-05-04 20:46:35.120986 | 2026-05-04 20:46:35.121189 | PLAY RECAP 2026-05-04 20:46:35.121277 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-04 20:46:35.121319 | 2026-05-04 20:46:35.263184 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-04 20:46:35.264532 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-04 20:46:36.004805 | 2026-05-04 20:46:36.004990 | PLAY [Base post] 2026-05-04 20:46:36.019912 | 2026-05-04 20:46:36.020076 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-04 20:46:36.925872 | debian-bookworm | changed 2026-05-04 20:46:36.936392 | 2026-05-04 20:46:36.936540 | PLAY RECAP 2026-05-04 20:46:36.936624 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-04 20:46:36.936706 | 2026-05-04 20:46:37.069398 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-04 20:46:37.070276 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-04 20:46:37.925370 | 2026-05-04 20:46:37.925565 | PLAY [Base post-logs] 2026-05-04 20:46:37.937083 | 2026-05-04 20:46:37.937271 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-04 20:46:38.412415 | localhost | changed 2026-05-04 20:46:38.427035 | 2026-05-04 20:46:38.427242 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-04 20:46:38.466055 | localhost | ok 2026-05-04 20:46:38.474763 | 2026-05-04 20:46:38.475043 | TASK [Set zuul-log-path fact] 2026-05-04 20:46:38.493398 | localhost | ok 2026-05-04 20:46:38.505524 | 2026-05-04 20:46:38.505685 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-04 20:46:38.532970 | localhost | ok 2026-05-04 20:46:38.537391 | 2026-05-04 20:46:38.537524 | TASK [upload-logs : Create log directories] 2026-05-04 20:46:39.069705 | localhost | changed 2026-05-04 20:46:39.075363 | 2026-05-04 20:46:39.075537 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-04 20:46:39.621540 | localhost -> localhost | ok: Runtime: 0:00:00.008968 2026-05-04 20:46:39.629048 | 2026-05-04 20:46:39.629257 | TASK [upload-logs : Upload logs to log server] 2026-05-04 20:46:40.259168 | localhost | Output suppressed because no_log was given 2026-05-04 20:46:40.263854 | 2026-05-04 20:46:40.264077 | LOOP [upload-logs : Compress console log and json output] 2026-05-04 20:46:40.329549 | localhost | skipping: Conditional result was False 2026-05-04 20:46:40.333184 | localhost | skipping: Conditional result was False 2026-05-04 20:46:40.347335 | 2026-05-04 20:46:40.347531 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-04 20:46:40.398577 | localhost | skipping: Conditional result was False 2026-05-04 20:46:40.399063 | 2026-05-04 20:46:40.402607 | localhost | skipping: Conditional result was False 2026-05-04 20:46:40.409230 | 2026-05-04 20:46:40.409471 | LOOP [upload-logs : Upload console log and json output]