2026-05-06 12:20:02.520660 | Job console starting 2026-05-06 12:20:02.560120 | Updating git repos 2026-05-06 12:20:02.662265 | Cloning repos into workspace 2026-05-06 12:20:02.739962 | Restoring repo states 2026-05-06 12:20:02.773482 | Merging changes 2026-05-06 12:20:03.436828 | Checking out repos 2026-05-06 12:20:03.568841 | Preparing playbooks 2026-05-06 12:20:04.995833 | Running Ansible setup 2026-05-06 12:20:10.483083 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-06 12:20:11.536075 | 2026-05-06 12:20:11.536285 | PLAY [Base pre] 2026-05-06 12:20:11.573789 | 2026-05-06 12:20:11.573964 | TASK [Setup log path fact] 2026-05-06 12:20:11.607584 | debian-bookworm | ok 2026-05-06 12:20:11.645026 | 2026-05-06 12:20:11.645262 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 12:20:11.701783 | debian-bookworm | ok 2026-05-06 12:20:11.724533 | 2026-05-06 12:20:11.724682 | TASK [emit-job-header : Print job information] 2026-05-06 12:20:11.797195 | # Job Information 2026-05-06 12:20:11.797492 | Ansible Version: 2.16.14 2026-05-06 12:20:11.797544 | Job: python-osism-test-setup 2026-05-06 12:20:11.797593 | Pipeline: check 2026-05-06 12:20:11.797627 | Executor: 521e9411259a 2026-05-06 12:20:11.797658 | Triggered by: https://github.com/osism/python-osism/pull/2265 2026-05-06 12:20:11.797690 | Event ID: e1838520-4945-11f1-9308-800028833dad 2026-05-06 12:20:11.831167 | 2026-05-06 12:20:11.831451 | LOOP [emit-job-header : Print node information] 2026-05-06 12:20:12.052574 | debian-bookworm | ok: 2026-05-06 12:20:12.053007 | debian-bookworm | # Node Information 2026-05-06 12:20:12.053050 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-06 12:20:12.053077 | debian-bookworm | Hostname: debian 2026-05-06 12:20:12.053100 | debian-bookworm | Username: zuul 2026-05-06 12:20:12.053121 | debian-bookworm | Distro: Debian 12.13 2026-05-06 12:20:12.053146 | debian-bookworm | Provider: regiocloud-a 2026-05-06 12:20:12.053166 | debian-bookworm | Region: 2026-05-06 12:20:12.053188 | debian-bookworm | Label: debian-bookworm 2026-05-06 12:20:12.053250 | debian-bookworm | Product Name: OpenStack Nova 2026-05-06 12:20:12.053272 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe67:f04 2026-05-06 12:20:12.075842 | 2026-05-06 12:20:12.076042 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-06 12:20:13.369964 | debian-bookworm -> localhost | changed 2026-05-06 12:20:13.392934 | 2026-05-06 12:20:13.393154 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-06 12:20:15.509939 | debian-bookworm -> localhost | changed 2026-05-06 12:20:15.532057 | 2026-05-06 12:20:15.532263 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-06 12:20:15.936524 | debian-bookworm -> localhost | ok 2026-05-06 12:20:15.944449 | 2026-05-06 12:20:15.944583 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-06 12:20:15.996790 | debian-bookworm | ok 2026-05-06 12:20:16.033506 | debian-bookworm | included: /var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-06 12:20:16.040196 | 2026-05-06 12:20:16.040345 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-06 12:20:17.902547 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-06 12:20:17.902750 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/work/bf099694df6e43879e755eec71f669b0_id_rsa 2026-05-06 12:20:17.902789 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/work/bf099694df6e43879e755eec71f669b0_id_rsa.pub 2026-05-06 12:20:17.902817 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-06 12:20:17.902854 | debian-bookworm -> localhost | SHA256:PEKExzqqSpCDVMBBim9A8gd3PjGXOqPQz3evHpqyqc4 zuul-build-sshkey 2026-05-06 12:20:17.902878 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-06 12:20:17.902910 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-06 12:20:17.902932 | debian-bookworm -> localhost | |+=+..o= .. | 2026-05-06 12:20:17.902954 | debian-bookworm -> localhost | |=o.o.+o+. | 2026-05-06 12:20:17.902975 | debian-bookworm -> localhost | |+....o+. | 2026-05-06 12:20:17.902995 | debian-bookworm -> localhost | |o+..+.+o | 2026-05-06 12:20:17.903016 | debian-bookworm -> localhost | |= oo =.oS | 2026-05-06 12:20:17.903038 | debian-bookworm -> localhost | |.o. . o.... | 2026-05-06 12:20:17.903059 | debian-bookworm -> localhost | | o . ... | 2026-05-06 12:20:17.903079 | debian-bookworm -> localhost | |o . .. o .. | 2026-05-06 12:20:17.903101 | debian-bookworm -> localhost | |o .E.ooo.o. | 2026-05-06 12:20:17.903121 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-06 12:20:17.903175 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.879261 2026-05-06 12:20:17.909320 | 2026-05-06 12:20:17.909417 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-06 12:20:17.947083 | debian-bookworm | ok 2026-05-06 12:20:17.961960 | debian-bookworm | included: /var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-06 12:20:17.986321 | 2026-05-06 12:20:17.986432 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-06 12:20:18.047287 | debian-bookworm | skipping: Conditional result was False 2026-05-06 12:20:18.053935 | 2026-05-06 12:20:18.054049 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-06 12:20:18.913936 | debian-bookworm | changed 2026-05-06 12:20:18.920067 | 2026-05-06 12:20:18.920150 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-06 12:20:19.214720 | debian-bookworm | ok 2026-05-06 12:20:19.224993 | 2026-05-06 12:20:19.225112 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-06 12:20:19.922771 | debian-bookworm | changed 2026-05-06 12:20:19.930382 | 2026-05-06 12:20:19.930517 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-06 12:20:20.567122 | debian-bookworm | changed 2026-05-06 12:20:20.573972 | 2026-05-06 12:20:20.574082 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-06 12:20:20.598019 | debian-bookworm | skipping: Conditional result was False 2026-05-06 12:20:20.603827 | 2026-05-06 12:20:20.603916 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-06 12:20:21.042601 | debian-bookworm -> localhost | changed 2026-05-06 12:20:21.053163 | 2026-05-06 12:20:21.053311 | TASK [add-build-sshkey : Add back temp key] 2026-05-06 12:20:21.516089 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/work/bf099694df6e43879e755eec71f669b0_id_rsa (zuul-build-sshkey) 2026-05-06 12:20:21.516329 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.033184 2026-05-06 12:20:21.523960 | 2026-05-06 12:20:21.524058 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-06 12:20:21.923557 | debian-bookworm | ok 2026-05-06 12:20:21.928415 | 2026-05-06 12:20:21.928503 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-06 12:20:21.951579 | debian-bookworm | skipping: Conditional result was False 2026-05-06 12:20:21.961422 | 2026-05-06 12:20:21.961522 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-06 12:20:22.413640 | debian-bookworm | ok 2026-05-06 12:20:22.452319 | 2026-05-06 12:20:22.452438 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-06 12:20:22.501744 | debian-bookworm | ok 2026-05-06 12:20:22.506922 | 2026-05-06 12:20:22.507023 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-06 12:20:22.871567 | debian-bookworm -> localhost | ok 2026-05-06 12:20:22.882283 | 2026-05-06 12:20:22.882424 | TASK [validate-host : Collect information about the host] 2026-05-06 12:20:23.976950 | debian-bookworm | ok 2026-05-06 12:20:23.987371 | 2026-05-06 12:20:23.987467 | TASK [validate-host : Sanitize hostname] 2026-05-06 12:20:24.117449 | debian-bookworm | ok 2026-05-06 12:20:24.149772 | 2026-05-06 12:20:24.149882 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-06 12:20:24.660935 | debian-bookworm -> localhost | changed 2026-05-06 12:20:24.666510 | 2026-05-06 12:20:24.666618 | TASK [validate-host : Collect information about zuul worker] 2026-05-06 12:20:25.096961 | debian-bookworm | ok 2026-05-06 12:20:25.101633 | 2026-05-06 12:20:25.101731 | TASK [validate-host : Write out all zuul information for each host] 2026-05-06 12:20:25.928013 | debian-bookworm -> localhost | changed 2026-05-06 12:20:25.937088 | 2026-05-06 12:20:25.937187 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-06 12:20:26.241458 | debian-bookworm | ok 2026-05-06 12:20:26.247497 | 2026-05-06 12:20:26.247598 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-06 12:20:27.348309 | debian-bookworm | changed: 2026-05-06 12:20:27.348498 | debian-bookworm | cd+++++++++ src/ 2026-05-06 12:20:27.348532 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-06 12:20:27.348556 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-06 12:20:27.348578 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-06 12:20:27.348598 | debian-bookworm | Containerfile 2026-05-06 12:20:27.348792 | debian-bookworm | Containerfile 2026-05-06 12:20:27.372038 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/work/logs" 2026-05-06 12:33:22.740575 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/work/artifacts" 2026-05-06 12:33:23.022170 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bf099694df6e43879e755eec71f669b0/work/docs" 2026-05-06 12:33:23.043243 | 2026-05-06 12:33:23.043424 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-06 12:33:23.888271 | debian-bookworm | changed: 2026-05-06 12:33:23.888794 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-06 12:33:23.888873 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-06 12:33:23.888934 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-06 12:33:23.889018 | debian-bookworm | changed: All items complete 2026-05-06 12:33:23.889084 | 2026-05-06 12:33:24.502459 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 12:33:25.112035 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 12:33:25.135278 | 2026-05-06 12:33:25.135472 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-06 12:33:25.682474 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010917 2026-05-06 12:33:25.973362 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007239 2026-05-06 12:33:25.989350 | 2026-05-06 12:33:25.989472 | PLAY RECAP 2026-05-06 12:33:25.989545 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-06 12:33:25.989581 | 2026-05-06 12:33:26.114011 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 12:33:26.116135 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 12:33:26.887167 | 2026-05-06 12:33:26.887336 | PLAY [Base post] 2026-05-06 12:33:26.901159 | 2026-05-06 12:33:26.901306 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-06 12:33:27.729163 | debian-bookworm | changed 2026-05-06 12:33:27.738069 | 2026-05-06 12:33:27.738206 | PLAY RECAP 2026-05-06 12:33:27.738282 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-06 12:33:27.738358 | 2026-05-06 12:33:27.864787 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 12:33:27.865660 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-06 12:33:28.690102 | 2026-05-06 12:33:28.690285 | PLAY [Base post-logs] 2026-05-06 12:33:28.701523 | 2026-05-06 12:33:28.701670 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-06 12:33:29.168729 | localhost | changed 2026-05-06 12:33:29.187513 | 2026-05-06 12:33:29.187723 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-06 12:33:29.216282 | localhost | ok 2026-05-06 12:33:29.223888 | 2026-05-06 12:33:29.224088 | TASK [Set zuul-log-path fact] 2026-05-06 12:33:29.242181 | localhost | ok 2026-05-06 12:33:29.255058 | 2026-05-06 12:33:29.255191 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 12:33:29.292571 | localhost | ok 2026-05-06 12:33:29.299479 | 2026-05-06 12:33:29.299710 | TASK [upload-logs : Create log directories] 2026-05-06 12:33:29.820624 | localhost | changed 2026-05-06 12:33:29.826256 | 2026-05-06 12:33:29.826461 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-06 12:33:30.341472 | localhost -> localhost | ok: Runtime: 0:00:00.007247 2026-05-06 12:33:30.350090 | 2026-05-06 12:33:30.350269 | TASK [upload-logs : Upload logs to log server] 2026-05-06 12:33:30.956117 | localhost | Output suppressed because no_log was given 2026-05-06 12:33:30.958698 | 2026-05-06 12:33:30.958864 | LOOP [upload-logs : Compress console log and json output] 2026-05-06 12:33:31.035139 | localhost | skipping: Conditional result was False 2026-05-06 12:33:31.041692 | localhost | skipping: Conditional result was False 2026-05-06 12:33:31.053337 | 2026-05-06 12:33:31.053889 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-06 12:33:31.124193 | localhost | skipping: Conditional result was False 2026-05-06 12:33:31.125016 | 2026-05-06 12:33:31.126999 | localhost | skipping: Conditional result was False 2026-05-06 12:33:31.141484 | 2026-05-06 12:33:31.141779 | LOOP [upload-logs : Upload console log and json output]