2026-05-08 09:53:41.673483 | Job console starting 2026-05-08 09:53:41.687577 | Updating git repos 2026-05-08 09:53:41.765467 | Cloning repos into workspace 2026-05-08 09:53:41.852400 | Restoring repo states 2026-05-08 09:53:41.876259 | Merging changes 2026-05-08 09:53:42.518680 | Checking out repos 2026-05-08 09:53:42.566166 | Preparing playbooks 2026-05-08 09:53:43.432292 | Running Ansible setup 2026-05-08 09:53:49.169359 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-08 09:53:50.173032 | 2026-05-08 09:53:50.173237 | PLAY [Base pre] 2026-05-08 09:53:50.198236 | 2026-05-08 09:53:50.198417 | TASK [Setup log path fact] 2026-05-08 09:53:50.250197 | debian-bookworm | ok 2026-05-08 09:53:50.267234 | 2026-05-08 09:53:50.267397 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 09:53:50.297121 | debian-bookworm | ok 2026-05-08 09:53:50.307862 | 2026-05-08 09:53:50.307993 | TASK [emit-job-header : Print job information] 2026-05-08 09:53:50.350466 | # Job Information 2026-05-08 09:53:50.350880 | Ansible Version: 2.16.14 2026-05-08 09:53:50.350926 | Job: container-image-python-osism-build 2026-05-08 09:53:50.350961 | Pipeline: check 2026-05-08 09:53:50.350986 | Executor: 521e9411259a 2026-05-08 09:53:50.351006 | Triggered by: https://github.com/osism/python-osism/pull/2251 2026-05-08 09:53:50.351029 | Event ID: 919168a0-4ac3-11f1-8563-c966920aa01a 2026-05-08 09:53:50.358275 | 2026-05-08 09:53:50.358409 | LOOP [emit-job-header : Print node information] 2026-05-08 09:53:50.633189 | debian-bookworm | ok: 2026-05-08 09:53:50.633522 | debian-bookworm | # Node Information 2026-05-08 09:53:50.633566 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-08 09:53:50.633594 | debian-bookworm | Hostname: debian 2026-05-08 09:53:50.633617 | debian-bookworm | Username: zuul 2026-05-08 09:53:50.633638 | debian-bookworm | Distro: Debian 12.13 2026-05-08 09:53:50.633663 | debian-bookworm | Provider: regiocloud-a 2026-05-08 09:53:50.633684 | debian-bookworm | Region: 2026-05-08 09:53:50.633705 | debian-bookworm | Label: debian-bookworm 2026-05-08 09:53:50.633725 | debian-bookworm | Product Name: OpenStack Nova 2026-05-08 09:53:50.633745 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5b:e629 2026-05-08 09:53:50.663500 | 2026-05-08 09:53:50.664555 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-08 09:53:51.419340 | debian-bookworm -> localhost | changed 2026-05-08 09:53:51.436191 | 2026-05-08 09:53:51.436353 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-08 09:53:52.903000 | debian-bookworm -> localhost | changed 2026-05-08 09:53:52.918038 | 2026-05-08 09:53:52.918153 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-08 09:53:53.339704 | debian-bookworm -> localhost | ok 2026-05-08 09:53:53.348244 | 2026-05-08 09:53:53.348357 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-08 09:53:53.383874 | debian-bookworm | ok 2026-05-08 09:53:53.405993 | debian-bookworm | included: /var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-08 09:53:53.415053 | 2026-05-08 09:53:53.415141 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-08 09:53:55.686173 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-08 09:53:55.686530 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/work/95b21d9e96e1480999e35841a3c3f272_id_rsa 2026-05-08 09:53:55.686574 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/work/95b21d9e96e1480999e35841a3c3f272_id_rsa.pub 2026-05-08 09:53:55.686602 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-08 09:53:55.686628 | debian-bookworm -> localhost | SHA256:pW4TEMSEN6/AGf5byiSdF8czqTdUMhn9xlheK71H97A zuul-build-sshkey 2026-05-08 09:53:55.686666 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-08 09:53:55.686689 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-08 09:53:55.686711 | debian-bookworm -> localhost | | =+ .+ | 2026-05-08 09:53:55.686733 | debian-bookworm -> localhost | | o +. + o . .| 2026-05-08 09:53:55.686753 | debian-bookworm -> localhost | | o +.o ..= *...| 2026-05-08 09:53:55.686774 | debian-bookworm -> localhost | | = .ooB ..*oo| 2026-05-08 09:53:55.686857 | debian-bookworm -> localhost | | + oS= o ..++| 2026-05-08 09:53:55.686887 | debian-bookworm -> localhost | | . *.+.o E.o| 2026-05-08 09:53:55.686910 | debian-bookworm -> localhost | | + =+. . .| 2026-05-08 09:53:55.686930 | debian-bookworm -> localhost | | +. . | 2026-05-08 09:53:55.686952 | debian-bookworm -> localhost | | | 2026-05-08 09:53:55.686973 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-08 09:53:55.687037 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.208397 2026-05-08 09:53:55.697069 | 2026-05-08 09:53:55.699313 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-08 09:53:55.750933 | debian-bookworm | ok 2026-05-08 09:53:55.784039 | debian-bookworm | included: /var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-08 09:53:55.815572 | 2026-05-08 09:53:55.817228 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-08 09:53:55.882005 | debian-bookworm | skipping: Conditional result was False 2026-05-08 09:53:55.894198 | 2026-05-08 09:53:55.894372 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-08 09:53:56.711020 | debian-bookworm | changed 2026-05-08 09:53:56.717058 | 2026-05-08 09:53:56.717182 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-08 09:53:56.992909 | debian-bookworm | ok 2026-05-08 09:53:56.999772 | 2026-05-08 09:53:56.999915 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-08 09:53:57.733390 | debian-bookworm | changed 2026-05-08 09:53:57.750330 | 2026-05-08 09:53:57.750506 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-08 09:53:58.543476 | debian-bookworm | changed 2026-05-08 09:53:58.561649 | 2026-05-08 09:53:58.561833 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-08 09:53:58.651875 | debian-bookworm | skipping: Conditional result was False 2026-05-08 09:53:58.681002 | 2026-05-08 09:53:58.681717 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-08 09:54:00.075228 | debian-bookworm -> localhost | changed 2026-05-08 09:54:00.104595 | 2026-05-08 09:54:00.104778 | TASK [add-build-sshkey : Add back temp key] 2026-05-08 09:54:01.547060 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/work/95b21d9e96e1480999e35841a3c3f272_id_rsa (zuul-build-sshkey) 2026-05-08 09:54:01.547346 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.038600 2026-05-08 09:54:01.556229 | 2026-05-08 09:54:01.556359 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-08 09:54:02.307908 | debian-bookworm | ok 2026-05-08 09:54:02.334597 | 2026-05-08 09:54:02.334789 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-08 09:54:02.385771 | debian-bookworm | skipping: Conditional result was False 2026-05-08 09:54:02.411704 | 2026-05-08 09:54:02.411915 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-08 09:54:03.234027 | debian-bookworm | ok 2026-05-08 09:54:03.268124 | 2026-05-08 09:54:03.268486 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-08 09:54:03.334469 | debian-bookworm | ok 2026-05-08 09:54:03.345609 | 2026-05-08 09:54:03.347401 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-08 09:54:04.315311 | debian-bookworm -> localhost | ok 2026-05-08 09:54:04.333287 | 2026-05-08 09:54:04.333409 | TASK [validate-host : Collect information about the host] 2026-05-08 09:54:05.632889 | debian-bookworm | ok 2026-05-08 09:54:05.641624 | 2026-05-08 09:54:05.641726 | TASK [validate-host : Sanitize hostname] 2026-05-08 09:54:05.781629 | debian-bookworm | ok 2026-05-08 09:54:05.866917 | 2026-05-08 09:54:05.867038 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-08 09:54:06.917772 | debian-bookworm -> localhost | changed 2026-05-08 09:54:06.929278 | 2026-05-08 09:54:06.929383 | TASK [validate-host : Collect information about zuul worker] 2026-05-08 09:54:07.519785 | debian-bookworm | ok 2026-05-08 09:54:07.523761 | 2026-05-08 09:54:07.523871 | TASK [validate-host : Write out all zuul information for each host] 2026-05-08 09:54:08.411753 | debian-bookworm -> localhost | changed 2026-05-08 09:54:08.426135 | 2026-05-08 09:54:08.426246 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-08 09:54:08.749833 | debian-bookworm | ok 2026-05-08 09:54:08.756120 | 2026-05-08 09:54:08.756216 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-08 09:54:10.128957 | debian-bookworm | changed: 2026-05-08 09:54:10.129134 | debian-bookworm | cd+++++++++ src/ 2026-05-08 09:54:10.129168 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-08 09:54:10.129192 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-08 09:54:10.129213 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-08 09:54:10.129233 | debian-bookworm | Containerfile 2026-05-08 09:54:10.129363 | debian-bookworm | Containerfile 2026-05-08 09:54:10.170680 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/work/logs" 2026-05-08 09:54:18.164768 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/work/artifacts" 2026-05-08 09:54:18.685953 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/95b21d9e96e1480999e35841a3c3f272/work/docs" 2026-05-08 09:54:18.712327 | 2026-05-08 09:54:18.712455 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-08 09:54:19.946512 | debian-bookworm | changed: 2026-05-08 09:54:19.946712 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-08 09:54:19.946751 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-08 09:54:19.946785 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-08 09:54:19.946916 | debian-bookworm | changed: All items complete 2026-05-08 09:54:19.946954 | 2026-05-08 09:54:20.759906 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 09:54:21.533850 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 09:54:21.547828 | 2026-05-08 09:54:21.547944 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-08 09:54:22.394255 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.033155 2026-05-08 09:54:22.828249 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.023254 2026-05-08 09:54:22.845959 | 2026-05-08 09:54:22.846043 | PLAY RECAP 2026-05-08 09:54:22.846094 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-08 09:54:22.846118 | 2026-05-08 09:54:23.127893 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-08 09:54:23.128672 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 09:54:24.687726 | 2026-05-08 09:54:24.687908 | PLAY [Base post] 2026-05-08 09:54:24.702364 | 2026-05-08 09:54:24.703868 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-08 09:54:25.217821 | debian-bookworm | changed 2026-05-08 09:54:25.223564 | 2026-05-08 09:54:25.223656 | PLAY RECAP 2026-05-08 09:54:25.223712 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-08 09:54:25.224466 | 2026-05-08 09:54:25.347804 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 09:54:25.348547 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-08 09:54:26.662264 | 2026-05-08 09:54:26.662426 | PLAY [Base post-logs] 2026-05-08 09:54:26.682352 | 2026-05-08 09:54:26.682493 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-08 09:54:27.537844 | localhost | changed 2026-05-08 09:54:27.552201 | 2026-05-08 09:54:27.552365 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-08 09:54:27.601994 | localhost | ok 2026-05-08 09:54:27.605301 | 2026-05-08 09:54:27.605404 | TASK [Set zuul-log-path fact] 2026-05-08 09:54:27.631217 | localhost | ok 2026-05-08 09:54:27.643942 | 2026-05-08 09:54:27.644085 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 09:54:27.678187 | localhost | ok 2026-05-08 09:54:27.682105 | 2026-05-08 09:54:27.682241 | TASK [upload-logs : Create log directories] 2026-05-08 09:54:28.578192 | localhost | changed 2026-05-08 09:54:28.581054 | 2026-05-08 09:54:28.581153 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-08 09:54:29.316786 | localhost -> localhost | ok: Runtime: 0:00:00.006726 2026-05-08 09:54:29.320993 | 2026-05-08 09:54:29.321113 | TASK [upload-logs : Upload logs to log server] 2026-05-08 09:54:30.523775 | localhost | Output suppressed because no_log was given 2026-05-08 09:54:30.526098 | 2026-05-08 09:54:30.526228 | LOOP [upload-logs : Compress console log and json output] 2026-05-08 09:54:30.646990 | localhost | skipping: Conditional result was False 2026-05-08 09:54:30.653867 | localhost | skipping: Conditional result was False 2026-05-08 09:54:30.667787 | 2026-05-08 09:54:30.668023 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-08 09:54:30.732994 | localhost | skipping: Conditional result was False 2026-05-08 09:54:30.733314 | 2026-05-08 09:54:30.740027 | localhost | skipping: Conditional result was False 2026-05-08 09:54:30.750120 | 2026-05-08 09:54:30.750295 | LOOP [upload-logs : Upload console log and json output]