2026-05-19 13:18:24.753724 | Job console starting 2026-05-19 13:18:24.814102 | Updating git repos 2026-05-19 13:18:24.899783 | Cloning repos into workspace 2026-05-19 13:18:25.093447 | Restoring repo states 2026-05-19 13:18:25.131471 | Merging changes 2026-05-19 13:18:26.101451 | Checking out repos 2026-05-19 13:18:26.230070 | Preparing playbooks 2026-05-19 13:18:28.037401 | Running Ansible setup 2026-05-19 13:18:34.013309 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-19 13:18:35.162565 | 2026-05-19 13:18:35.162671 | PLAY [Base pre] 2026-05-19 13:18:35.185643 | 2026-05-19 13:18:35.185764 | TASK [Setup log path fact] 2026-05-19 13:18:35.228597 | debian-bookworm | ok 2026-05-19 13:18:35.241235 | 2026-05-19 13:18:35.241345 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-19 13:18:35.298500 | debian-bookworm | ok 2026-05-19 13:18:35.309939 | 2026-05-19 13:18:35.310038 | TASK [emit-job-header : Print job information] 2026-05-19 13:18:35.409943 | # Job Information 2026-05-19 13:18:35.410080 | Ansible Version: 2.16.14 2026-05-19 13:18:35.410108 | Job: python-osism-test-setup 2026-05-19 13:18:35.410137 | Pipeline: check 2026-05-19 13:18:35.410156 | Executor: 521e9411259a 2026-05-19 13:18:35.410174 | Triggered by: https://github.com/osism/python-osism/pull/2287 2026-05-19 13:18:35.410192 | Event ID: 2ebf22b0-5385-11f1-86da-83ac9047c316 2026-05-19 13:18:35.415985 | 2026-05-19 13:18:35.416085 | LOOP [emit-job-header : Print node information] 2026-05-19 13:18:35.611191 | debian-bookworm | ok: 2026-05-19 13:18:35.611352 | debian-bookworm | # Node Information 2026-05-19 13:18:35.611387 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-19 13:18:35.611413 | debian-bookworm | Hostname: debian 2026-05-19 13:18:35.611436 | debian-bookworm | Username: zuul 2026-05-19 13:18:35.611457 | debian-bookworm | Distro: Debian 12.14 2026-05-19 13:18:35.611482 | debian-bookworm | Provider: regiocloud-a 2026-05-19 13:18:35.611504 | debian-bookworm | Region: 2026-05-19 13:18:35.611524 | debian-bookworm | Label: debian-bookworm 2026-05-19 13:18:35.611544 | debian-bookworm | Product Name: OpenStack Nova 2026-05-19 13:18:35.611564 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe96:8e00 2026-05-19 13:18:35.628121 | 2026-05-19 13:18:35.628220 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-19 13:18:36.771254 | debian-bookworm -> localhost | changed 2026-05-19 13:18:36.782071 | 2026-05-19 13:18:36.782169 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-19 13:18:39.335567 | debian-bookworm -> localhost | changed 2026-05-19 13:18:39.351534 | 2026-05-19 13:18:39.352742 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-19 13:18:39.966008 | debian-bookworm -> localhost | ok 2026-05-19 13:18:39.974303 | 2026-05-19 13:18:39.974417 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-19 13:18:40.041286 | debian-bookworm | ok 2026-05-19 13:18:40.072030 | debian-bookworm | included: /var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-19 13:18:40.078053 | 2026-05-19 13:18:40.078141 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-19 13:18:43.182353 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-19 13:18:43.182543 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/work/2c00a9dc5ec84e4389b2a0addc71e31b_id_rsa 2026-05-19 13:18:43.182580 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/work/2c00a9dc5ec84e4389b2a0addc71e31b_id_rsa.pub 2026-05-19 13:18:43.182606 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-19 13:18:43.182656 | debian-bookworm -> localhost | SHA256:n6xAYOeTqEkzNqrN5pdxaMaVHJmB/CAUD4UXcsCgLM0 zuul-build-sshkey 2026-05-19 13:18:43.182679 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-19 13:18:43.182735 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-19 13:18:43.182760 | debian-bookworm -> localhost | |.+**+o.+ | 2026-05-19 13:18:43.182782 | debian-bookworm -> localhost | |oo+== + | 2026-05-19 13:18:43.182802 | debian-bookworm -> localhost | |o.Eo++.o | 2026-05-19 13:18:43.182821 | debian-bookworm -> localhost | |. . ==. | 2026-05-19 13:18:43.182925 | debian-bookworm -> localhost | | *..o= S | 2026-05-19 13:18:43.182954 | debian-bookworm -> localhost | | + **... o . | 2026-05-19 13:18:43.182976 | debian-bookworm -> localhost | |. oo +. + | 2026-05-19 13:18:43.182996 | debian-bookworm -> localhost | |.o. o . . | 2026-05-19 13:18:43.183017 | debian-bookworm -> localhost | |.o+. . | 2026-05-19 13:18:43.183037 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-19 13:18:43.183113 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.934873 2026-05-19 13:18:43.189517 | 2026-05-19 13:18:43.189610 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-19 13:18:43.230048 | debian-bookworm | ok 2026-05-19 13:18:43.252551 | debian-bookworm | included: /var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-19 13:18:43.283010 | 2026-05-19 13:18:43.283117 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-19 13:18:43.296325 | debian-bookworm | skipping: Conditional result was False 2026-05-19 13:18:43.304863 | 2026-05-19 13:18:43.304969 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-19 13:18:43.985594 | debian-bookworm | changed 2026-05-19 13:18:43.992147 | 2026-05-19 13:18:43.992228 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-19 13:18:44.377120 | debian-bookworm | ok 2026-05-19 13:18:44.381806 | 2026-05-19 13:18:44.381898 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-19 13:18:45.075216 | debian-bookworm | changed 2026-05-19 13:18:45.087864 | 2026-05-19 13:18:45.087973 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-19 13:18:45.900049 | debian-bookworm | changed 2026-05-19 13:18:45.905359 | 2026-05-19 13:18:45.905451 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-19 13:18:45.969154 | debian-bookworm | skipping: Conditional result was False 2026-05-19 13:18:45.975352 | 2026-05-19 13:18:45.975450 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-19 13:18:46.800513 | debian-bookworm -> localhost | changed 2026-05-19 13:18:46.809542 | 2026-05-19 13:18:46.809631 | TASK [add-build-sshkey : Add back temp key] 2026-05-19 13:18:47.378248 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/work/2c00a9dc5ec84e4389b2a0addc71e31b_id_rsa (zuul-build-sshkey) 2026-05-19 13:18:47.378468 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010436 2026-05-19 13:18:47.388257 | 2026-05-19 13:18:47.388347 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-19 13:18:47.941687 | debian-bookworm | ok 2026-05-19 13:18:47.948189 | 2026-05-19 13:18:47.948270 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-19 13:18:47.991028 | debian-bookworm | skipping: Conditional result was False 2026-05-19 13:18:47.999981 | 2026-05-19 13:18:48.000068 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-19 13:18:48.884957 | debian-bookworm | ok 2026-05-19 13:18:48.894529 | 2026-05-19 13:18:48.894615 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-19 13:18:48.946392 | debian-bookworm | ok 2026-05-19 13:18:48.950673 | 2026-05-19 13:18:48.950779 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-19 13:18:49.436563 | debian-bookworm -> localhost | ok 2026-05-19 13:18:49.441786 | 2026-05-19 13:18:49.441868 | TASK [validate-host : Collect information about the host] 2026-05-19 13:18:50.682392 | debian-bookworm | ok 2026-05-19 13:18:50.702918 | 2026-05-19 13:18:50.703026 | TASK [validate-host : Sanitize hostname] 2026-05-19 13:18:50.896183 | debian-bookworm | ok 2026-05-19 13:18:50.970320 | 2026-05-19 13:18:50.970432 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-19 13:18:51.968739 | debian-bookworm -> localhost | changed 2026-05-19 13:18:51.974160 | 2026-05-19 13:18:51.974262 | TASK [validate-host : Collect information about zuul worker] 2026-05-19 13:18:52.449905 | debian-bookworm | ok 2026-05-19 13:18:52.467915 | 2026-05-19 13:18:52.468014 | TASK [validate-host : Write out all zuul information for each host] 2026-05-19 13:18:53.290824 | debian-bookworm -> localhost | changed 2026-05-19 13:18:53.304035 | 2026-05-19 13:18:53.304144 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-19 13:18:53.587983 | debian-bookworm | ok 2026-05-19 13:18:53.596213 | 2026-05-19 13:18:53.596320 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-19 13:18:54.965734 | debian-bookworm | changed: 2026-05-19 13:18:54.965911 | debian-bookworm | cd+++++++++ src/ 2026-05-19 13:18:54.965945 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-19 13:18:54.965970 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-19 13:18:54.965992 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-19 13:18:54.966011 | debian-bookworm | Containerfile 2026-05-19 13:18:54.966143 | debian-bookworm | Containerfile 2026-05-19 13:18:54.992354 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/work/logs" 2026-05-19 13:22:49.784577 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/work/artifacts" 2026-05-19 13:22:50.066254 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2c00a9dc5ec84e4389b2a0addc71e31b/work/docs" 2026-05-19 13:22:50.097624 | 2026-05-19 13:22:50.097873 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-19 13:22:50.944269 | debian-bookworm | changed: 2026-05-19 13:22:50.944546 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-19 13:22:50.944588 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-19 13:22:50.944629 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-19 13:22:50.944681 | debian-bookworm | changed: All items complete 2026-05-19 13:22:50.944708 | 2026-05-19 13:22:51.555594 | debian-bookworm | changed: .d..t...... ./ 2026-05-19 13:22:52.152424 | debian-bookworm | changed: .d..t...... ./ 2026-05-19 13:22:52.174089 | 2026-05-19 13:22:52.174309 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-19 13:22:52.713936 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008529 2026-05-19 13:22:52.994809 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006511 2026-05-19 13:22:53.007766 | 2026-05-19 13:22:53.007868 | PLAY RECAP 2026-05-19 13:22:53.007921 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-19 13:22:53.007945 | 2026-05-19 13:22:53.146497 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-19 13:22:53.147728 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-19 13:22:53.909324 | 2026-05-19 13:22:53.909500 | PLAY [Base post] 2026-05-19 13:22:53.923702 | 2026-05-19 13:22:53.923840 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-19 13:22:54.734057 | debian-bookworm | changed 2026-05-19 13:22:54.740533 | 2026-05-19 13:22:54.740647 | PLAY RECAP 2026-05-19 13:22:54.740712 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-19 13:22:54.740773 | 2026-05-19 13:22:54.871045 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-19 13:22:54.873085 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-19 13:22:55.717076 | 2026-05-19 13:22:55.717269 | PLAY [Base post-logs] 2026-05-19 13:22:55.727944 | 2026-05-19 13:22:55.728076 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-19 13:22:56.236658 | localhost | changed 2026-05-19 13:22:56.248917 | 2026-05-19 13:22:56.249406 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-19 13:22:56.286638 | localhost | ok 2026-05-19 13:22:56.291803 | 2026-05-19 13:22:56.291981 | TASK [Set zuul-log-path fact] 2026-05-19 13:22:56.311945 | localhost | ok 2026-05-19 13:22:56.322925 | 2026-05-19 13:22:56.323071 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-19 13:22:56.349461 | localhost | ok 2026-05-19 13:22:56.353962 | 2026-05-19 13:22:56.354103 | TASK [upload-logs : Create log directories] 2026-05-19 13:22:56.882080 | localhost | changed 2026-05-19 13:22:56.886891 | 2026-05-19 13:22:56.887056 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-19 13:22:57.430339 | localhost -> localhost | ok: Runtime: 0:00:00.007647 2026-05-19 13:22:57.434770 | 2026-05-19 13:22:57.434927 | TASK [upload-logs : Upload logs to log server] 2026-05-19 13:22:58.007431 | localhost | Output suppressed because no_log was given 2026-05-19 13:22:58.009436 | 2026-05-19 13:22:58.009547 | LOOP [upload-logs : Compress console log and json output] 2026-05-19 13:22:58.067387 | localhost | skipping: Conditional result was False 2026-05-19 13:22:58.072581 | localhost | skipping: Conditional result was False 2026-05-19 13:22:58.084342 | 2026-05-19 13:22:58.084537 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-19 13:22:58.134900 | localhost | skipping: Conditional result was False 2026-05-19 13:22:58.135796 | 2026-05-19 13:22:58.138939 | localhost | skipping: Conditional result was False 2026-05-19 13:22:58.143888 | 2026-05-19 13:22:58.144047 | LOOP [upload-logs : Upload console log and json output]