2026-09-28 01:36:45.353623 | Job console starting 2026-09-28 01:36:45.361847 | Updating git repos 2026-09-28 01:36:45.410265 | Cloning repos into workspace 2026-09-28 01:36:45.446167 | Restoring repo states 2026-09-28 01:36:45.463081 | Merging changes 2026-09-28 01:36:46.173603 | Checking out repos 2026-09-28 01:36:46.223792 | Preparing playbooks 2026-09-28 01:36:47.333095 | Running Ansible setup 2026-09-28 01:36:53.006564 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-28 01:36:53.707000 | 2026-09-28 01:36:53.707120 | PLAY [Base pre] 2026-09-28 01:36:53.723199 | 2026-09-28 01:36:53.723299 | TASK [Setup log path fact] 2026-09-28 01:36:53.757827 | debian-bookworm | ok 2026-09-28 01:36:53.776149 | 2026-09-28 01:36:53.776260 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-28 01:36:53.814091 | debian-bookworm | ok 2026-09-28 01:36:53.824695 | 2026-09-28 01:36:53.824824 | TASK [emit-job-header : Print job information] 2026-09-28 01:36:53.863335 | # Job Information 2026-09-28 01:36:53.863481 | Ansible Version: 2.16.14 2026-09-28 01:36:53.863515 | Job: python-osism-test-setup 2026-09-28 01:36:53.863547 | Pipeline: check 2026-09-28 01:36:53.863569 | Executor: 521e9411259a 2026-09-28 01:36:53.863590 | Triggered by: https://github.com/osism/python-osism/pull/2731 2026-09-28 01:36:53.863613 | Event ID: f73b0dd0-badc-11f1-9981-6dfcd8b240f1 2026-09-28 01:36:53.868427 | 2026-09-28 01:36:53.868518 | LOOP [emit-job-header : Print node information] 2026-09-28 01:36:53.988969 | debian-bookworm | ok: 2026-09-28 01:36:53.989239 | debian-bookworm | # Node Information 2026-09-28 01:36:53.989290 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-28 01:36:53.989542 | debian-bookworm | Hostname: debian 2026-09-28 01:36:53.989598 | debian-bookworm | Username: zuul 2026-09-28 01:36:53.989627 | debian-bookworm | Distro: Debian 12.15 2026-09-28 01:36:53.989658 | debian-bookworm | Provider: regiocloud-a 2026-09-28 01:36:53.989684 | debian-bookworm | Region: 2026-09-28 01:36:53.989712 | debian-bookworm | Label: debian-bookworm 2026-09-28 01:36:53.989747 | debian-bookworm | Product Name: OpenStack Nova 2026-09-28 01:36:53.989774 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe0f:eb4e 2026-09-28 01:36:54.016286 | 2026-09-28 01:36:54.016425 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-28 01:36:54.496378 | debian-bookworm -> localhost | changed 2026-09-28 01:36:54.503167 | 2026-09-28 01:36:54.503263 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-28 01:36:55.368351 | debian-bookworm -> localhost | changed 2026-09-28 01:36:55.379128 | 2026-09-28 01:36:55.379225 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-28 01:36:55.614463 | debian-bookworm -> localhost | ok 2026-09-28 01:36:55.620628 | 2026-09-28 01:36:55.620730 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-28 01:36:55.639884 | debian-bookworm | ok 2026-09-28 01:36:55.655065 | debian-bookworm | included: /var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-28 01:36:55.661011 | 2026-09-28 01:36:55.661096 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-28 01:36:56.948573 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-28 01:36:56.948890 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/work/379cfd3684b4486983767127cdca6138_id_rsa 2026-09-28 01:36:56.948946 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/work/379cfd3684b4486983767127cdca6138_id_rsa.pub 2026-09-28 01:36:56.948984 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-28 01:36:56.949019 | debian-bookworm -> localhost | SHA256:WGKUuvt6mQBX8eSj8HOiqczUM6sAc1yB6ftTYtzItG0 zuul-build-sshkey 2026-09-28 01:36:56.949049 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-28 01:36:56.949087 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-28 01:36:56.949115 | debian-bookworm -> localhost | | o. oo. | 2026-09-28 01:36:56.949142 | debian-bookworm -> localhost | | o oo+ | 2026-09-28 01:36:56.949168 | debian-bookworm -> localhost | | . ooo = | 2026-09-28 01:36:56.949193 | debian-bookworm -> localhost | | .o.++ = . | 2026-09-28 01:36:56.949218 | debian-bookworm -> localhost | |o oB ** S | 2026-09-28 01:36:56.949248 | debian-bookworm -> localhost | |.o..XoE+ | 2026-09-28 01:36:56.949274 | debian-bookworm -> localhost | |. .o** o | 2026-09-28 01:36:56.949299 | debian-bookworm -> localhost | | = .+++ | 2026-09-28 01:36:56.949325 | debian-bookworm -> localhost | | =.o=. | 2026-09-28 01:36:56.949351 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-28 01:36:56.949420 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.837648 2026-09-28 01:36:56.959070 | 2026-09-28 01:36:56.959166 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-28 01:36:56.999897 | debian-bookworm | ok 2026-09-28 01:36:57.019103 | debian-bookworm | included: /var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-28 01:36:57.028170 | 2026-09-28 01:36:57.028258 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-28 01:36:57.041766 | debian-bookworm | skipping: Conditional result was False 2026-09-28 01:36:57.047086 | 2026-09-28 01:36:57.047171 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-28 01:36:57.671990 | debian-bookworm | changed 2026-09-28 01:36:57.677497 | 2026-09-28 01:36:57.677606 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-28 01:36:57.941263 | debian-bookworm | ok 2026-09-28 01:36:57.947194 | 2026-09-28 01:36:57.947285 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-28 01:36:58.630992 | debian-bookworm | changed 2026-09-28 01:36:58.639560 | 2026-09-28 01:36:58.639665 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-28 01:36:59.387431 | debian-bookworm | changed 2026-09-28 01:36:59.391884 | 2026-09-28 01:36:59.391959 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-28 01:36:59.417906 | debian-bookworm | skipping: Conditional result was False 2026-09-28 01:36:59.422806 | 2026-09-28 01:36:59.422918 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-28 01:36:59.885303 | debian-bookworm -> localhost | changed 2026-09-28 01:36:59.898425 | 2026-09-28 01:36:59.898522 | TASK [add-build-sshkey : Add back temp key] 2026-09-28 01:37:00.204392 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/work/379cfd3684b4486983767127cdca6138_id_rsa (zuul-build-sshkey) 2026-09-28 01:37:00.204616 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015834 2026-09-28 01:37:00.211974 | 2026-09-28 01:37:00.212073 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-28 01:37:00.596812 | debian-bookworm | ok 2026-09-28 01:37:00.603407 | 2026-09-28 01:37:00.603518 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-28 01:37:00.637015 | debian-bookworm | skipping: Conditional result was False 2026-09-28 01:37:00.658434 | 2026-09-28 01:37:00.658535 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-28 01:37:01.158178 | debian-bookworm | ok 2026-09-28 01:37:01.169243 | 2026-09-28 01:37:01.169340 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-28 01:37:01.201140 | debian-bookworm | ok 2026-09-28 01:37:01.206765 | 2026-09-28 01:37:01.206859 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-28 01:37:01.456011 | debian-bookworm -> localhost | ok 2026-09-28 01:37:01.463559 | 2026-09-28 01:37:01.463662 | TASK [validate-host : Collect information about the host] 2026-09-28 01:37:02.679929 | debian-bookworm | ok 2026-09-28 01:37:02.692698 | 2026-09-28 01:37:02.692846 | TASK [validate-host : Sanitize hostname] 2026-09-28 01:37:02.853045 | debian-bookworm | ok 2026-09-28 01:37:02.857954 | 2026-09-28 01:37:02.858069 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-28 01:37:03.483786 | debian-bookworm -> localhost | changed 2026-09-28 01:37:03.490117 | 2026-09-28 01:37:03.490230 | TASK [validate-host : Collect information about zuul worker] 2026-09-28 01:37:03.938306 | debian-bookworm | ok 2026-09-28 01:37:03.946625 | 2026-09-28 01:37:03.946808 | TASK [validate-host : Write out all zuul information for each host] 2026-09-28 01:37:04.575014 | debian-bookworm -> localhost | changed 2026-09-28 01:37:04.585173 | 2026-09-28 01:37:04.585283 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-28 01:37:04.866194 | debian-bookworm | ok 2026-09-28 01:37:04.872547 | 2026-09-28 01:37:04.872649 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-28 01:37:06.816322 | debian-bookworm | changed: 2026-09-28 01:37:06.816616 | debian-bookworm | cd+++++++++ src/ 2026-09-28 01:37:06.816675 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-28 01:37:06.816716 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-28 01:37:06.816773 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-28 01:37:06.817495 | debian-bookworm | Containerfile 2026-09-28 01:37:06.817811 | debian-bookworm | Containerfile 2026-09-28 01:37:06.831027 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/work/logs" 2026-09-28 01:39:55.842934 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/work/artifacts" 2026-09-28 01:39:56.116690 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/379cfd3684b4486983767127cdca6138/work/docs" 2026-09-28 01:39:56.136716 | 2026-09-28 01:39:56.136872 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-28 01:39:56.890085 | debian-bookworm | changed: 2026-09-28 01:39:56.890355 | debian-bookworm | .d..t...... ./ 2026-09-28 01:39:56.890405 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-28 01:39:56.891204 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-28 01:39:56.891248 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-28 01:39:56.891273 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-28 01:39:56.891295 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-28 01:39:56.891345 | debian-bookworm | changed: All items complete 2026-09-28 01:39:56.891370 | 2026-09-28 01:39:57.402167 | debian-bookworm | changed: .d..t...... ./ 2026-09-28 01:39:57.970615 | debian-bookworm | changed: .d..t...... ./ 2026-09-28 01:39:57.991383 | 2026-09-28 01:39:57.991495 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-28 01:39:58.348969 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011335 2026-09-28 01:39:58.626773 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009901 2026-09-28 01:39:58.640905 | 2026-09-28 01:39:58.641071 | PLAY RECAP 2026-09-28 01:39:58.641128 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-28 01:39:58.641155 | 2026-09-28 01:39:58.768369 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-28 01:39:58.769250 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-28 01:39:59.490207 | 2026-09-28 01:39:59.490355 | PLAY [Base post] 2026-09-28 01:39:59.503834 | 2026-09-28 01:39:59.503968 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-28 01:40:00.263365 | debian-bookworm | changed 2026-09-28 01:40:00.273189 | 2026-09-28 01:40:00.273322 | PLAY RECAP 2026-09-28 01:40:00.273413 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-28 01:40:00.273502 | 2026-09-28 01:40:00.396622 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-28 01:40:00.397490 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-28 01:40:01.204177 | 2026-09-28 01:40:01.204342 | PLAY [Base post-logs] 2026-09-28 01:40:01.215247 | 2026-09-28 01:40:01.215382 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-28 01:40:01.697804 | localhost | changed 2026-09-28 01:40:01.708497 | 2026-09-28 01:40:01.708662 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-28 01:40:01.734375 | localhost | ok 2026-09-28 01:40:01.737936 | 2026-09-28 01:40:01.738052 | TASK [Set zuul-log-path fact] 2026-09-28 01:40:01.753818 | localhost | ok 2026-09-28 01:40:01.766163 | 2026-09-28 01:40:01.766282 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-28 01:40:01.791213 | localhost | ok 2026-09-28 01:40:01.794611 | 2026-09-28 01:40:01.794768 | TASK [upload-logs : Create log directories] 2026-09-28 01:40:02.304824 | localhost | changed 2026-09-28 01:40:02.309781 | 2026-09-28 01:40:02.309927 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-28 01:40:02.811791 | localhost -> localhost | ok: Runtime: 0:00:00.004928 2026-09-28 01:40:02.821157 | 2026-09-28 01:40:02.821433 | TASK [upload-logs : Upload logs to log server] 2026-09-28 01:40:03.410980 | localhost | Output suppressed because no_log was given 2026-09-28 01:40:03.413680 | 2026-09-28 01:40:03.413810 | LOOP [upload-logs : Compress console log and json output] 2026-09-28 01:40:03.463392 | localhost | skipping: Conditional result was False 2026-09-28 01:40:03.471276 | localhost | skipping: Conditional result was False 2026-09-28 01:40:03.485842 | 2026-09-28 01:40:03.486070 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-28 01:40:03.531694 | localhost | skipping: Conditional result was False 2026-09-28 01:40:03.532033 | 2026-09-28 01:40:03.538655 | localhost | skipping: Conditional result was False 2026-09-28 01:40:03.549285 | 2026-09-28 01:40:03.549495 | LOOP [upload-logs : Upload console log and json output]