2026-09-16 16:03:11.725116 | Job console starting 2026-09-16 16:03:11.743298 | Updating git repos 2026-09-16 16:03:11.819124 | Cloning repos into workspace 2026-09-16 16:03:11.902411 | Restoring repo states 2026-09-16 16:03:11.945289 | Merging changes 2026-09-16 16:03:13.362721 | Checking out repos 2026-09-16 16:03:13.529790 | Preparing playbooks 2026-09-16 16:03:15.910663 | Running Ansible setup 2026-09-16 16:03:28.071818 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-16 16:03:29.187977 | 2026-09-16 16:03:29.188088 | PLAY [Base pre] 2026-09-16 16:03:29.227746 | 2026-09-16 16:03:29.227849 | TASK [Setup log path fact] 2026-09-16 16:03:29.255256 | debian-bookworm | ok 2026-09-16 16:03:29.272242 | 2026-09-16 16:03:29.272337 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-16 16:03:29.308859 | debian-bookworm | ok 2026-09-16 16:03:29.317121 | 2026-09-16 16:03:29.317200 | TASK [emit-job-header : Print job information] 2026-09-16 16:03:29.375883 | # Job Information 2026-09-16 16:03:29.376015 | Ansible Version: 2.16.14 2026-09-16 16:03:29.376043 | Job: python-osism-test-setup 2026-09-16 16:03:29.376068 | Pipeline: check 2026-09-16 16:03:29.376087 | Executor: 521e9411259a 2026-09-16 16:03:29.376104 | Triggered by: https://github.com/osism/python-osism/pull/2566 2026-09-16 16:03:29.376122 | Event ID: 15533660-b1e8-11f1-948f-17082344fe3b 2026-09-16 16:03:29.380950 | 2026-09-16 16:03:29.381035 | LOOP [emit-job-header : Print node information] 2026-09-16 16:03:29.587539 | debian-bookworm | ok: 2026-09-16 16:03:29.593598 | debian-bookworm | # Node Information 2026-09-16 16:03:29.593661 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-16 16:03:29.593711 | debian-bookworm | Hostname: debian 2026-09-16 16:03:29.593733 | debian-bookworm | Username: zuul 2026-09-16 16:03:29.593753 | debian-bookworm | Distro: Debian 12.15 2026-09-16 16:03:29.593783 | debian-bookworm | Provider: regiocloud-a 2026-09-16 16:03:29.593805 | debian-bookworm | Region: 2026-09-16 16:03:29.593823 | debian-bookworm | Label: debian-bookworm 2026-09-16 16:03:29.593840 | debian-bookworm | Product Name: OpenStack Nova 2026-09-16 16:03:29.593856 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe98:b16f 2026-09-16 16:03:29.605658 | 2026-09-16 16:03:29.605758 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-16 16:03:30.492587 | debian-bookworm -> localhost | changed 2026-09-16 16:03:30.498335 | 2026-09-16 16:03:30.498419 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-16 16:03:31.897764 | debian-bookworm -> localhost | changed 2026-09-16 16:03:31.906857 | 2026-09-16 16:03:31.906939 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-16 16:03:32.275426 | debian-bookworm -> localhost | ok 2026-09-16 16:03:32.280577 | 2026-09-16 16:03:32.280664 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-16 16:03:32.298817 | debian-bookworm | ok 2026-09-16 16:03:32.317084 | debian-bookworm | included: /var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-16 16:03:32.326127 | 2026-09-16 16:03:32.326207 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-16 16:03:33.822108 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-16 16:03:33.822310 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/work/795cad1a5a9b421fa0b9b7847013a04c_id_rsa 2026-09-16 16:03:33.822345 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/work/795cad1a5a9b421fa0b9b7847013a04c_id_rsa.pub 2026-09-16 16:03:33.822368 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-16 16:03:33.822391 | debian-bookworm -> localhost | SHA256:WO8cUlodwuAYme9a0NEmZgRqRhxTZh2beUEZkub/MB0 zuul-build-sshkey 2026-09-16 16:03:33.822411 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-16 16:03:33.822440 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-16 16:03:33.822460 | debian-bookworm -> localhost | | .+o**=B=o. | 2026-09-16 16:03:33.822479 | debian-bookworm -> localhost | | ..=o+O==+ . | 2026-09-16 16:03:33.822498 | debian-bookworm -> localhost | | + .B*++ . | 2026-09-16 16:03:33.822516 | debian-bookworm -> localhost | | o .o+* E | 2026-09-16 16:03:33.822534 | debian-bookworm -> localhost | | .oS.o. . | 2026-09-16 16:03:33.822556 | debian-bookworm -> localhost | | o++.. | 2026-09-16 16:03:33.822573 | debian-bookworm -> localhost | | o o+ | 2026-09-16 16:03:33.822589 | debian-bookworm -> localhost | | . . | 2026-09-16 16:03:33.822606 | debian-bookworm -> localhost | | | 2026-09-16 16:03:33.822623 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-16 16:03:33.822667 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.669599 2026-09-16 16:03:33.829275 | 2026-09-16 16:03:33.829355 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-16 16:03:33.887518 | debian-bookworm | ok 2026-09-16 16:03:33.897491 | debian-bookworm | included: /var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-16 16:03:33.912357 | 2026-09-16 16:03:33.912446 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-16 16:03:33.948067 | debian-bookworm | skipping: Conditional result was False 2026-09-16 16:03:33.953894 | 2026-09-16 16:03:33.953986 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-16 16:03:34.817238 | debian-bookworm | changed 2026-09-16 16:03:34.821864 | 2026-09-16 16:03:34.821942 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-16 16:03:35.091563 | debian-bookworm | ok 2026-09-16 16:03:35.096134 | 2026-09-16 16:03:35.096215 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-16 16:03:35.862031 | debian-bookworm | changed 2026-09-16 16:03:35.866348 | 2026-09-16 16:03:35.866428 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-16 16:03:36.612604 | debian-bookworm | changed 2026-09-16 16:03:36.617386 | 2026-09-16 16:03:36.617464 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-16 16:03:36.652736 | debian-bookworm | skipping: Conditional result was False 2026-09-16 16:03:36.657827 | 2026-09-16 16:03:36.657913 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-16 16:03:37.291437 | debian-bookworm -> localhost | changed 2026-09-16 16:03:37.312401 | 2026-09-16 16:03:37.312496 | TASK [add-build-sshkey : Add back temp key] 2026-09-16 16:03:37.890417 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/work/795cad1a5a9b421fa0b9b7847013a04c_id_rsa (zuul-build-sshkey) 2026-09-16 16:03:37.890592 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012905 2026-09-16 16:03:37.896641 | 2026-09-16 16:03:37.896746 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-16 16:03:38.321254 | debian-bookworm | ok 2026-09-16 16:03:38.325413 | 2026-09-16 16:03:38.325497 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-16 16:03:38.360517 | debian-bookworm | skipping: Conditional result was False 2026-09-16 16:03:38.369043 | 2026-09-16 16:03:38.369140 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-16 16:03:38.738799 | debian-bookworm | ok 2026-09-16 16:03:38.752306 | 2026-09-16 16:03:38.752392 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-16 16:03:38.789255 | debian-bookworm | ok 2026-09-16 16:03:38.793749 | 2026-09-16 16:03:38.793832 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-16 16:03:39.291987 | debian-bookworm -> localhost | ok 2026-09-16 16:03:39.297300 | 2026-09-16 16:03:39.297385 | TASK [validate-host : Collect information about the host] 2026-09-16 16:03:40.467664 | debian-bookworm | ok 2026-09-16 16:03:40.477024 | 2026-09-16 16:03:40.477106 | TASK [validate-host : Sanitize hostname] 2026-09-16 16:03:40.659216 | debian-bookworm | ok 2026-09-16 16:03:40.665778 | 2026-09-16 16:03:40.665872 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-16 16:03:41.423182 | debian-bookworm -> localhost | changed 2026-09-16 16:03:41.427860 | 2026-09-16 16:03:41.427944 | TASK [validate-host : Collect information about zuul worker] 2026-09-16 16:03:41.993387 | debian-bookworm | ok 2026-09-16 16:03:41.999057 | 2026-09-16 16:03:41.999145 | TASK [validate-host : Write out all zuul information for each host] 2026-09-16 16:03:43.885303 | debian-bookworm -> localhost | changed 2026-09-16 16:03:43.906827 | 2026-09-16 16:03:43.906922 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-16 16:03:44.218700 | debian-bookworm | ok 2026-09-16 16:03:44.225896 | 2026-09-16 16:03:44.225991 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-16 16:03:46.527657 | debian-bookworm | changed: 2026-09-16 16:03:46.529040 | debian-bookworm | cd+++++++++ src/ 2026-09-16 16:03:46.529116 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-16 16:03:46.529145 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-16 16:03:46.529168 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-16 16:03:46.529378 | debian-bookworm | Containerfile 2026-09-16 16:03:46.529895 | debian-bookworm | Containerfile 2026-09-16 16:03:46.593467 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/work/logs" 2026-09-16 16:06:28.308629 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/work/artifacts" 2026-09-16 16:06:28.577211 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/795cad1a5a9b421fa0b9b7847013a04c/work/docs" 2026-09-16 16:06:28.608956 | 2026-09-16 16:06:28.609088 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-16 16:06:29.379637 | debian-bookworm | changed: 2026-09-16 16:06:29.379903 | debian-bookworm | .d..t...... ./ 2026-09-16 16:06:29.379944 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-16 16:06:29.379970 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-16 16:06:29.379992 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-16 16:06:29.380015 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-16 16:06:29.380036 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-16 16:06:29.380080 | debian-bookworm | changed: All items complete 2026-09-16 16:06:29.380106 | 2026-09-16 16:06:29.942585 | debian-bookworm | changed: .d..t...... ./ 2026-09-16 16:06:30.493919 | debian-bookworm | changed: .d..t...... ./ 2026-09-16 16:06:30.524506 | 2026-09-16 16:06:30.524654 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-16 16:06:30.874414 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010004 2026-09-16 16:06:31.145428 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008545 2026-09-16 16:06:31.160597 | 2026-09-16 16:06:31.160694 | PLAY RECAP 2026-09-16 16:06:31.160750 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-16 16:06:31.160777 | 2026-09-16 16:06:31.306018 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-16 16:06:31.307027 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-16 16:06:32.081340 | 2026-09-16 16:06:32.082153 | PLAY [Base post] 2026-09-16 16:06:32.096809 | 2026-09-16 16:06:32.096966 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-16 16:06:32.906387 | debian-bookworm | changed 2026-09-16 16:06:32.913806 | 2026-09-16 16:06:32.929671 | PLAY RECAP 2026-09-16 16:06:32.929773 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-16 16:06:32.929836 | 2026-09-16 16:06:33.123232 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-16 16:06:33.124060 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-16 16:06:33.932249 | 2026-09-16 16:06:33.932417 | PLAY [Base post-logs] 2026-09-16 16:06:33.943346 | 2026-09-16 16:06:33.943476 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-16 16:06:34.438089 | localhost | changed 2026-09-16 16:06:34.447999 | 2026-09-16 16:06:34.448144 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-16 16:06:34.473621 | localhost | ok 2026-09-16 16:06:34.476615 | 2026-09-16 16:06:34.476772 | TASK [Set zuul-log-path fact] 2026-09-16 16:06:34.501954 | localhost | ok 2026-09-16 16:06:34.509900 | 2026-09-16 16:06:34.510011 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-16 16:06:34.541137 | localhost | ok 2026-09-16 16:06:34.544118 | 2026-09-16 16:06:34.544219 | TASK [upload-logs : Create log directories] 2026-09-16 16:06:35.117747 | localhost | changed 2026-09-16 16:06:35.120528 | 2026-09-16 16:06:35.120638 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-16 16:06:35.769447 | localhost -> localhost | ok: Runtime: 0:00:00.008142 2026-09-16 16:06:35.786618 | 2026-09-16 16:06:35.786748 | TASK [upload-logs : Upload logs to log server] 2026-09-16 16:06:36.434488 | localhost | Output suppressed because no_log was given 2026-09-16 16:06:36.440890 | 2026-09-16 16:06:36.441026 | LOOP [upload-logs : Compress console log and json output] 2026-09-16 16:06:36.510457 | localhost | skipping: Conditional result was False 2026-09-16 16:06:36.514271 | localhost | skipping: Conditional result was False 2026-09-16 16:06:36.523151 | 2026-09-16 16:06:36.523274 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-16 16:06:36.568140 | localhost | skipping: Conditional result was False 2026-09-16 16:06:36.569256 | 2026-09-16 16:06:36.572707 | localhost | skipping: Conditional result was False 2026-09-16 16:06:36.581208 | 2026-09-16 16:06:36.581312 | LOOP [upload-logs : Upload console log and json output]