2026-04-29 12:33:12.664245 | Job console starting 2026-04-29 12:33:12.674758 | Updating git repos 2026-04-29 12:33:12.739778 | Cloning repos into workspace 2026-04-29 12:33:12.793351 | Restoring repo states 2026-04-29 12:33:12.840445 | Merging changes 2026-04-29 12:33:13.659096 | Checking out repos 2026-04-29 12:33:13.735713 | Preparing playbooks 2026-04-29 12:33:14.702130 | Running Ansible setup 2026-04-29 12:33:19.773880 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 12:33:20.649392 | 2026-04-29 12:33:20.649532 | PLAY [Base pre] 2026-04-29 12:33:20.666343 | 2026-04-29 12:33:20.666467 | TASK [Setup log path fact] 2026-04-29 12:33:20.701540 | debian-bookworm | ok 2026-04-29 12:33:20.718614 | 2026-04-29 12:33:20.718738 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 12:33:20.746545 | debian-bookworm | ok 2026-04-29 12:33:20.756019 | 2026-04-29 12:33:20.756127 | TASK [emit-job-header : Print job information] 2026-04-29 12:33:20.786344 | # Job Information 2026-04-29 12:33:20.786510 | Ansible Version: 2.16.14 2026-04-29 12:33:20.786551 | Job: python-osism-test-setup 2026-04-29 12:33:20.786592 | Pipeline: check 2026-04-29 12:33:20.786621 | Executor: 521e9411259a 2026-04-29 12:33:20.786646 | Triggered by: https://github.com/osism/python-osism/pull/1917 2026-04-29 12:33:20.786673 | Event ID: 52d10920-43c7-11f1-93c6-3b0fa38477a4 2026-04-29 12:33:20.791844 | 2026-04-29 12:33:20.791934 | LOOP [emit-job-header : Print node information] 2026-04-29 12:33:20.891705 | debian-bookworm | ok: 2026-04-29 12:33:20.891854 | debian-bookworm | # Node Information 2026-04-29 12:33:20.891882 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-04-29 12:33:20.891902 | debian-bookworm | Hostname: debian 2026-04-29 12:33:20.891920 | debian-bookworm | Username: zuul 2026-04-29 12:33:20.891937 | debian-bookworm | Distro: Debian 12.13 2026-04-29 12:33:20.891958 | debian-bookworm | Provider: regiocloud-a 2026-04-29 12:33:20.891975 | debian-bookworm | Region: 2026-04-29 12:33:20.891992 | debian-bookworm | Label: debian-bookworm 2026-04-29 12:33:20.892008 | debian-bookworm | Product Name: OpenStack Nova 2026-04-29 12:33:20.892024 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe37:4266 2026-04-29 12:33:20.910790 | 2026-04-29 12:33:20.910923 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-29 12:33:21.331166 | debian-bookworm -> localhost | changed 2026-04-29 12:33:21.340257 | 2026-04-29 12:33:21.340371 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-29 12:33:22.397191 | debian-bookworm -> localhost | changed 2026-04-29 12:33:22.409978 | 2026-04-29 12:33:22.410106 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-29 12:33:23.037564 | debian-bookworm -> localhost | ok 2026-04-29 12:33:23.046402 | 2026-04-29 12:33:23.046544 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-29 12:33:23.128775 | debian-bookworm | ok 2026-04-29 12:33:23.185941 | debian-bookworm | included: /var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-29 12:33:23.194372 | 2026-04-29 12:33:23.194499 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-29 12:33:24.702175 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-04-29 12:33:24.702415 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/work/ed5769cf29e84fd594a49b94aeb3790b_id_rsa 2026-04-29 12:33:24.702455 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/work/ed5769cf29e84fd594a49b94aeb3790b_id_rsa.pub 2026-04-29 12:33:24.702481 | debian-bookworm -> localhost | The key fingerprint is: 2026-04-29 12:33:24.702505 | debian-bookworm -> localhost | SHA256:i6tXf/GXmlVykbOw6HUF1V+vOtg+R5hZPWCaJ1U048w zuul-build-sshkey 2026-04-29 12:33:24.702527 | debian-bookworm -> localhost | The key's randomart image is: 2026-04-29 12:33:24.702561 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-04-29 12:33:24.702583 | debian-bookworm -> localhost | | +*o| 2026-04-29 12:33:24.702605 | debian-bookworm -> localhost | | ++.*| 2026-04-29 12:33:24.702625 | debian-bookworm -> localhost | | =..E=| 2026-04-29 12:33:24.702646 | debian-bookworm -> localhost | | +..+.O| 2026-04-29 12:33:24.702666 | debian-bookworm -> localhost | | S .oB.++| 2026-04-29 12:33:24.702694 | debian-bookworm -> localhost | | .... * +o.| 2026-04-29 12:33:24.702724 | debian-bookworm -> localhost | | .... + = ..| 2026-04-29 12:33:24.702751 | debian-bookworm -> localhost | | .. o * =..| 2026-04-29 12:33:24.702773 | debian-bookworm -> localhost | | .o. o.*.. | 2026-04-29 12:33:24.702793 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-04-29 12:33:24.702863 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.778368 2026-04-29 12:33:24.709655 | 2026-04-29 12:33:24.709774 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-29 12:33:24.740147 | debian-bookworm | ok 2026-04-29 12:33:24.750196 | debian-bookworm | included: /var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-29 12:33:24.772334 | 2026-04-29 12:33:24.772461 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-29 12:33:24.848282 | debian-bookworm | skipping: Conditional result was False 2026-04-29 12:33:24.865470 | 2026-04-29 12:33:24.868151 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-29 12:33:25.908604 | debian-bookworm | changed 2026-04-29 12:33:25.914411 | 2026-04-29 12:33:25.914526 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-29 12:33:26.146601 | debian-bookworm | ok 2026-04-29 12:33:26.154142 | 2026-04-29 12:33:26.154278 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-29 12:33:26.792087 | debian-bookworm | changed 2026-04-29 12:33:26.802012 | 2026-04-29 12:33:26.802194 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-29 12:33:27.448339 | debian-bookworm | changed 2026-04-29 12:33:27.473862 | 2026-04-29 12:33:27.474056 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-29 12:33:27.503843 | debian-bookworm | skipping: Conditional result was False 2026-04-29 12:33:27.512199 | 2026-04-29 12:33:27.512347 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-29 12:33:28.192009 | debian-bookworm -> localhost | changed 2026-04-29 12:33:28.236937 | 2026-04-29 12:33:28.237145 | TASK [add-build-sshkey : Add back temp key] 2026-04-29 12:33:28.744742 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/work/ed5769cf29e84fd594a49b94aeb3790b_id_rsa (zuul-build-sshkey) 2026-04-29 12:33:28.745010 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012412 2026-04-29 12:33:28.754589 | 2026-04-29 12:33:28.754725 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-29 12:33:29.157290 | debian-bookworm | ok 2026-04-29 12:33:29.168167 | 2026-04-29 12:33:29.168594 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-29 12:33:29.206249 | debian-bookworm | skipping: Conditional result was False 2026-04-29 12:33:29.243137 | 2026-04-29 12:33:29.243430 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-29 12:33:29.771911 | debian-bookworm | ok 2026-04-29 12:33:29.787543 | 2026-04-29 12:33:29.787685 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-29 12:33:29.821367 | debian-bookworm | ok 2026-04-29 12:33:29.828988 | 2026-04-29 12:33:29.829162 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-29 12:33:30.238896 | debian-bookworm -> localhost | ok 2026-04-29 12:33:30.247885 | 2026-04-29 12:33:30.248003 | TASK [validate-host : Collect information about the host] 2026-04-29 12:33:31.429238 | debian-bookworm | ok 2026-04-29 12:33:31.440131 | 2026-04-29 12:33:31.440265 | TASK [validate-host : Sanitize hostname] 2026-04-29 12:33:31.549879 | debian-bookworm | ok 2026-04-29 12:33:31.621690 | 2026-04-29 12:33:31.621834 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-29 12:33:32.227396 | debian-bookworm -> localhost | changed 2026-04-29 12:33:32.233631 | 2026-04-29 12:33:32.233754 | TASK [validate-host : Collect information about zuul worker] 2026-04-29 12:33:32.676428 | debian-bookworm | ok 2026-04-29 12:33:32.683286 | 2026-04-29 12:33:32.683419 | TASK [validate-host : Write out all zuul information for each host] 2026-04-29 12:33:33.568622 | debian-bookworm -> localhost | changed 2026-04-29 12:33:33.580912 | 2026-04-29 12:33:33.581071 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-29 12:33:33.851632 | debian-bookworm | ok 2026-04-29 12:33:33.857590 | 2026-04-29 12:33:33.857701 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-29 12:33:35.159000 | debian-bookworm | changed: 2026-04-29 12:33:35.159445 | debian-bookworm | cd+++++++++ src/ 2026-04-29 12:33:35.159488 | debian-bookworm | cd+++++++++ src/github.com/ 2026-04-29 12:33:35.159524 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-04-29 12:33:35.159637 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-04-29 12:33:35.159661 | debian-bookworm | Containerfile 2026-04-29 12:33:35.159798 | debian-bookworm | Containerfile 2026-04-29 12:33:35.185356 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/work/logs" 2026-04-29 12:46:30.014169 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/work/artifacts" 2026-04-29 12:46:30.343507 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ed5769cf29e84fd594a49b94aeb3790b/work/docs" 2026-04-29 12:46:30.375411 | 2026-04-29 12:46:30.375641 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-29 12:46:31.295132 | debian-bookworm | changed: 2026-04-29 12:46:31.295391 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-04-29 12:46:31.295425 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-04-29 12:46:31.295453 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-04-29 12:46:31.295496 | debian-bookworm | changed: All items complete 2026-04-29 12:46:31.295519 | 2026-04-29 12:46:31.916119 | debian-bookworm | changed: .d..t...... ./ 2026-04-29 12:46:32.519738 | debian-bookworm | changed: .d..t...... ./ 2026-04-29 12:46:32.547829 | 2026-04-29 12:46:32.548077 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-29 12:46:33.125181 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009474 2026-04-29 12:46:33.439833 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011522 2026-04-29 12:46:33.452555 | 2026-04-29 12:46:33.452655 | PLAY RECAP 2026-04-29 12:46:33.452711 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-29 12:46:33.452737 | 2026-04-29 12:46:33.603462 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-29 12:46:33.604531 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 12:46:34.430408 | 2026-04-29 12:46:34.430606 | PLAY [Base post] 2026-04-29 12:46:34.445498 | 2026-04-29 12:46:34.445671 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-29 12:46:35.355809 | debian-bookworm | changed 2026-04-29 12:46:35.363879 | 2026-04-29 12:46:35.364032 | PLAY RECAP 2026-04-29 12:46:35.364102 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 12:46:35.364169 | 2026-04-29 12:46:35.510598 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 12:46:35.512757 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-29 12:46:36.371130 | 2026-04-29 12:46:36.371351 | PLAY [Base post-logs] 2026-04-29 12:46:36.383727 | 2026-04-29 12:46:36.383890 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-29 12:46:36.911470 | localhost | changed 2026-04-29 12:46:36.930813 | 2026-04-29 12:46:36.931104 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-29 12:46:36.964855 | localhost | ok 2026-04-29 12:46:36.972126 | 2026-04-29 12:46:36.972335 | TASK [Set zuul-log-path fact] 2026-04-29 12:46:36.992439 | localhost | ok 2026-04-29 12:46:37.008414 | 2026-04-29 12:46:37.008610 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 12:46:37.048583 | localhost | ok 2026-04-29 12:46:37.056185 | 2026-04-29 12:46:37.056422 | TASK [upload-logs : Create log directories] 2026-04-29 12:46:37.612207 | localhost | changed 2026-04-29 12:46:37.617747 | 2026-04-29 12:46:37.617939 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-29 12:46:38.183284 | localhost -> localhost | ok: Runtime: 0:00:00.008186 2026-04-29 12:46:38.194312 | 2026-04-29 12:46:38.194518 | TASK [upload-logs : Upload logs to log server] 2026-04-29 12:46:38.802578 | localhost | Output suppressed because no_log was given 2026-04-29 12:46:38.805907 | 2026-04-29 12:46:38.806118 | LOOP [upload-logs : Compress console log and json output] 2026-04-29 12:46:38.870982 | localhost | skipping: Conditional result was False 2026-04-29 12:46:38.876314 | localhost | skipping: Conditional result was False 2026-04-29 12:46:38.890069 | 2026-04-29 12:46:38.890318 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-29 12:46:38.945737 | localhost | skipping: Conditional result was False 2026-04-29 12:46:38.946404 | 2026-04-29 12:46:38.949873 | localhost | skipping: Conditional result was False 2026-04-29 12:46:38.964091 | 2026-04-29 12:46:38.964317 | LOOP [upload-logs : Upload console log and json output]