2026-08-27 12:38:41.491657 | Job console starting 2026-08-27 12:38:41.531898 | Updating git repos 2026-08-27 12:38:41.676964 | Cloning repos into workspace 2026-08-27 12:38:41.807788 | Restoring repo states 2026-08-27 12:38:41.834990 | Merging changes 2026-08-27 12:38:42.810419 | Checking out repos 2026-08-27 12:38:42.952450 | Preparing playbooks 2026-08-27 12:38:45.443046 | Running Ansible setup 2026-08-27 12:38:51.850224 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-27 12:38:53.737133 | 2026-08-27 12:38:53.737260 | PLAY [Base pre] 2026-08-27 12:38:53.754781 | 2026-08-27 12:38:53.755046 | TASK [Setup log path fact] 2026-08-27 12:38:53.773080 | debian-bookworm | ok 2026-08-27 12:38:53.792421 | 2026-08-27 12:38:53.792523 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-27 12:38:53.819188 | debian-bookworm | ok 2026-08-27 12:38:53.832736 | 2026-08-27 12:38:53.832827 | TASK [emit-job-header : Print job information] 2026-08-27 12:38:53.947422 | # Job Information 2026-08-27 12:38:53.947568 | Ansible Version: 2.16.14 2026-08-27 12:38:53.947625 | Job: python-osism-test-setup 2026-08-27 12:38:53.947652 | Pipeline: check 2026-08-27 12:38:53.947671 | Executor: 521e9411259a 2026-08-27 12:38:53.947688 | Triggered by: https://github.com/osism/python-osism/pull/2630 2026-08-27 12:38:53.947707 | Event ID: 3535a5f0-a214-11f1-94f6-b3654b4ab383 2026-08-27 12:38:53.951711 | 2026-08-27 12:38:53.951790 | LOOP [emit-job-header : Print node information] 2026-08-27 12:38:54.123688 | debian-bookworm | ok: 2026-08-27 12:38:54.123884 | debian-bookworm | # Node Information 2026-08-27 12:38:54.123917 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-27 12:38:54.123939 | debian-bookworm | Hostname: debian 2026-08-27 12:38:54.123958 | debian-bookworm | Username: zuul 2026-08-27 12:38:54.123976 | debian-bookworm | Distro: Debian 12.15 2026-08-27 12:38:54.123996 | debian-bookworm | Provider: regiocloud-a 2026-08-27 12:38:54.124014 | debian-bookworm | Region: 2026-08-27 12:38:54.124032 | debian-bookworm | Label: debian-bookworm 2026-08-27 12:38:54.124048 | debian-bookworm | Product Name: OpenStack Nova 2026-08-27 12:38:54.124065 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe0b:85eb 2026-08-27 12:38:54.141600 | 2026-08-27 12:38:54.141711 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-27 12:38:54.771932 | debian-bookworm -> localhost | changed 2026-08-27 12:38:54.779030 | 2026-08-27 12:38:54.779122 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-27 12:38:57.158717 | debian-bookworm -> localhost | changed 2026-08-27 12:38:57.169417 | 2026-08-27 12:38:57.169875 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-27 12:38:57.652446 | debian-bookworm -> localhost | ok 2026-08-27 12:38:57.657532 | 2026-08-27 12:38:57.657636 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-27 12:38:57.697320 | debian-bookworm | ok 2026-08-27 12:38:57.730865 | debian-bookworm | included: /var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-27 12:38:57.738113 | 2026-08-27 12:38:57.738195 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-27 12:39:00.476180 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-27 12:39:00.476469 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/work/5d9e824dbe0247be97d10e38cc7e24bb_id_rsa 2026-08-27 12:39:00.476538 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/work/5d9e824dbe0247be97d10e38cc7e24bb_id_rsa.pub 2026-08-27 12:39:00.476575 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-27 12:39:00.476625 | debian-bookworm -> localhost | SHA256:I+MsGI3ir+puN7AQLT74nmoPfscEtQ4HyzexPTzJPwg zuul-build-sshkey 2026-08-27 12:39:00.476656 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-27 12:39:00.476701 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-27 12:39:00.476729 | debian-bookworm -> localhost | | | 2026-08-27 12:39:00.476756 | debian-bookworm -> localhost | | . o | 2026-08-27 12:39:00.476786 | debian-bookworm -> localhost | | .. + B . | 2026-08-27 12:39:00.476813 | debian-bookworm -> localhost | |o .B E B | 2026-08-27 12:39:00.476838 | debian-bookworm -> localhost | |+oo B = S | 2026-08-27 12:39:00.476873 | debian-bookworm -> localhost | |+= o = + + | 2026-08-27 12:39:00.476901 | debian-bookworm -> localhost | |.+= + o . | 2026-08-27 12:39:00.476929 | debian-bookworm -> localhost | |.++= + | 2026-08-27 12:39:00.476958 | debian-bookworm -> localhost | |OBBoo | 2026-08-27 12:39:00.476983 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-27 12:39:00.477053 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.010702 2026-08-27 12:39:00.489994 | 2026-08-27 12:39:00.490126 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-27 12:39:00.538499 | debian-bookworm | ok 2026-08-27 12:39:00.555912 | debian-bookworm | included: /var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-27 12:39:00.575901 | 2026-08-27 12:39:00.576012 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-27 12:39:00.610000 | debian-bookworm | skipping: Conditional result was False 2026-08-27 12:39:00.615554 | 2026-08-27 12:39:00.615666 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-27 12:39:01.327850 | debian-bookworm | changed 2026-08-27 12:39:01.351042 | 2026-08-27 12:39:01.351660 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-27 12:39:01.646100 | debian-bookworm | ok 2026-08-27 12:39:01.658253 | 2026-08-27 12:39:01.658355 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-27 12:39:02.394942 | debian-bookworm | changed 2026-08-27 12:39:02.403976 | 2026-08-27 12:39:02.404076 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-27 12:39:03.177297 | debian-bookworm | changed 2026-08-27 12:39:03.187422 | 2026-08-27 12:39:03.187529 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-27 12:39:03.236689 | debian-bookworm | skipping: Conditional result was False 2026-08-27 12:39:03.243060 | 2026-08-27 12:39:03.243167 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-27 12:39:04.268010 | debian-bookworm -> localhost | changed 2026-08-27 12:39:04.279892 | 2026-08-27 12:39:04.280001 | TASK [add-build-sshkey : Add back temp key] 2026-08-27 12:39:05.197740 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/work/5d9e824dbe0247be97d10e38cc7e24bb_id_rsa (zuul-build-sshkey) 2026-08-27 12:39:05.197936 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.035214 2026-08-27 12:39:05.204668 | 2026-08-27 12:39:05.204772 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-27 12:39:05.914258 | debian-bookworm | ok 2026-08-27 12:39:05.920100 | 2026-08-27 12:39:05.920188 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-27 12:39:05.957545 | debian-bookworm | skipping: Conditional result was False 2026-08-27 12:39:05.975844 | 2026-08-27 12:39:05.975948 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-27 12:39:06.563745 | debian-bookworm | ok 2026-08-27 12:39:06.580419 | 2026-08-27 12:39:06.580536 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-27 12:39:06.628345 | debian-bookworm | ok 2026-08-27 12:39:06.632717 | 2026-08-27 12:39:06.632794 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-27 12:39:07.024362 | debian-bookworm -> localhost | ok 2026-08-27 12:39:07.033045 | 2026-08-27 12:39:07.033145 | TASK [validate-host : Collect information about the host] 2026-08-27 12:39:08.429848 | debian-bookworm | ok 2026-08-27 12:39:08.452139 | 2026-08-27 12:39:08.452248 | TASK [validate-host : Sanitize hostname] 2026-08-27 12:39:08.741164 | debian-bookworm | ok 2026-08-27 12:39:08.752538 | 2026-08-27 12:39:08.752701 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-27 12:39:09.637124 | debian-bookworm -> localhost | changed 2026-08-27 12:39:09.643564 | 2026-08-27 12:39:09.643712 | TASK [validate-host : Collect information about zuul worker] 2026-08-27 12:39:10.696439 | debian-bookworm | ok 2026-08-27 12:39:10.713154 | 2026-08-27 12:39:10.713255 | TASK [validate-host : Write out all zuul information for each host] 2026-08-27 12:39:12.393413 | debian-bookworm -> localhost | changed 2026-08-27 12:39:12.402103 | 2026-08-27 12:39:12.402200 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-27 12:39:12.687045 | debian-bookworm | ok 2026-08-27 12:39:12.693800 | 2026-08-27 12:39:12.693898 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-27 12:39:15.264781 | debian-bookworm | changed: 2026-08-27 12:39:15.265070 | debian-bookworm | cd+++++++++ src/ 2026-08-27 12:39:15.265204 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-27 12:39:15.265254 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-27 12:39:15.265279 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-27 12:39:15.265300 | debian-bookworm | Containerfile 2026-08-27 12:39:15.265433 | debian-bookworm | Containerfile 2026-08-27 12:39:15.315333 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/work/logs" 2026-08-27 12:42:05.122085 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/work/artifacts" 2026-08-27 12:42:05.387852 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5d9e824dbe0247be97d10e38cc7e24bb/work/docs" 2026-08-27 12:42:05.402709 | 2026-08-27 12:42:05.402886 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-27 12:42:06.250026 | debian-bookworm | changed: 2026-08-27 12:42:06.250375 | debian-bookworm | .d..t...... ./ 2026-08-27 12:42:06.250434 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-27 12:42:06.250478 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-27 12:42:06.250518 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-27 12:42:06.250556 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-27 12:42:06.250593 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-27 12:42:06.250675 | debian-bookworm | changed: All items complete 2026-08-27 12:42:06.250716 | 2026-08-27 12:42:06.856454 | debian-bookworm | changed: .d..t...... ./ 2026-08-27 12:42:07.417355 | debian-bookworm | changed: .d..t...... ./ 2026-08-27 12:42:07.439997 | 2026-08-27 12:42:07.440158 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-27 12:42:07.800850 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009600 2026-08-27 12:42:08.117276 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007680 2026-08-27 12:42:08.145346 | 2026-08-27 12:42:08.145855 | PLAY RECAP 2026-08-27 12:42:08.146274 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-27 12:42:08.146517 | 2026-08-27 12:42:08.320694 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-27 12:42:08.322018 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-27 12:42:09.121043 | 2026-08-27 12:42:09.121303 | PLAY [Base post] 2026-08-27 12:42:09.137547 | 2026-08-27 12:42:09.137713 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-27 12:42:09.956994 | debian-bookworm | changed 2026-08-27 12:42:09.969956 | 2026-08-27 12:42:09.970142 | PLAY RECAP 2026-08-27 12:42:09.970218 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-27 12:42:09.970283 | 2026-08-27 12:42:10.155501 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-27 12:42:10.156794 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-27 12:42:11.021203 | 2026-08-27 12:42:11.021408 | PLAY [Base post-logs] 2026-08-27 12:42:11.035310 | 2026-08-27 12:42:11.035480 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-27 12:42:11.565418 | localhost | changed 2026-08-27 12:42:11.580506 | 2026-08-27 12:42:11.580837 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-27 12:42:11.609836 | localhost | ok 2026-08-27 12:42:11.615901 | 2026-08-27 12:42:11.616058 | TASK [Set zuul-log-path fact] 2026-08-27 12:42:11.633458 | localhost | ok 2026-08-27 12:42:11.649066 | 2026-08-27 12:42:11.649272 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-27 12:42:11.689301 | localhost | ok 2026-08-27 12:42:11.695668 | 2026-08-27 12:42:11.695826 | TASK [upload-logs : Create log directories] 2026-08-27 12:42:12.246024 | localhost | changed 2026-08-27 12:42:12.249710 | 2026-08-27 12:42:12.249834 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-27 12:42:12.889019 | localhost -> localhost | ok: Runtime: 0:00:00.007948 2026-08-27 12:42:12.894766 | 2026-08-27 12:42:12.894941 | TASK [upload-logs : Upload logs to log server] 2026-08-27 12:42:13.503165 | localhost | Output suppressed because no_log was given 2026-08-27 12:42:13.506513 | 2026-08-27 12:42:13.506699 | LOOP [upload-logs : Compress console log and json output] 2026-08-27 12:42:13.566340 | localhost | skipping: Conditional result was False 2026-08-27 12:42:13.571848 | localhost | skipping: Conditional result was False 2026-08-27 12:42:13.584168 | 2026-08-27 12:42:13.584429 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-27 12:42:13.636730 | localhost | skipping: Conditional result was False 2026-08-27 12:42:13.637329 | 2026-08-27 12:42:13.640928 | localhost | skipping: Conditional result was False 2026-08-27 12:42:13.650329 | 2026-08-27 12:42:13.650771 | LOOP [upload-logs : Upload console log and json output]