2026-07-17 09:37:30.853804 | Job console starting 2026-07-17 09:37:30.863528 | Updating git repos 2026-07-17 09:37:30.910050 | Cloning repos into workspace 2026-07-17 09:37:31.008022 | Restoring repo states 2026-07-17 09:37:31.054049 | Merging changes 2026-07-17 09:37:32.060723 | Checking out repos 2026-07-17 09:37:32.163569 | Preparing playbooks 2026-07-17 09:37:33.546361 | Running Ansible setup 2026-07-17 09:37:39.447934 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-17 09:37:40.345086 | 2026-07-17 09:37:40.345221 | PLAY [Base pre] 2026-07-17 09:37:40.382656 | 2026-07-17 09:37:40.382795 | TASK [Setup log path fact] 2026-07-17 09:37:40.418569 | debian-bookworm | ok 2026-07-17 09:37:40.447596 | 2026-07-17 09:37:40.447745 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-17 09:37:40.497447 | debian-bookworm | ok 2026-07-17 09:37:40.513407 | 2026-07-17 09:37:40.513521 | TASK [emit-job-header : Print job information] 2026-07-17 09:37:40.570170 | # Job Information 2026-07-17 09:37:40.570340 | Ansible Version: 2.16.14 2026-07-17 09:37:40.570375 | Job: python-osism-test-setup 2026-07-17 09:37:40.570406 | Pipeline: check 2026-07-17 09:37:40.570429 | Executor: 521e9411259a 2026-07-17 09:37:40.570450 | Triggered by: https://github.com/osism/python-osism/pull/2292 2026-07-17 09:37:40.570473 | Event ID: 9270caa0-81c2-11f1-85dd-5a06315dd494 2026-07-17 09:37:40.575329 | 2026-07-17 09:37:40.575418 | LOOP [emit-job-header : Print node information] 2026-07-17 09:37:40.676563 | debian-bookworm | ok: 2026-07-17 09:37:40.676718 | debian-bookworm | # Node Information 2026-07-17 09:37:40.676745 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-17 09:37:40.676765 | debian-bookworm | Hostname: debian 2026-07-17 09:37:40.676782 | debian-bookworm | Username: zuul 2026-07-17 09:37:40.676799 | debian-bookworm | Distro: Debian 12.15 2026-07-17 09:37:40.676832 | debian-bookworm | Provider: regiocloud-a 2026-07-17 09:37:40.676849 | debian-bookworm | Region: 2026-07-17 09:37:40.676866 | debian-bookworm | Label: debian-bookworm 2026-07-17 09:37:40.676884 | debian-bookworm | Product Name: OpenStack Nova 2026-07-17 09:37:40.676900 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed1:713b 2026-07-17 09:37:40.690333 | 2026-07-17 09:37:40.690452 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-17 09:37:41.309919 | debian-bookworm -> localhost | changed 2026-07-17 09:37:41.316968 | 2026-07-17 09:37:41.317083 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-17 09:37:42.311778 | debian-bookworm -> localhost | changed 2026-07-17 09:37:42.328908 | 2026-07-17 09:37:42.329071 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-17 09:37:42.639708 | debian-bookworm -> localhost | ok 2026-07-17 09:37:42.645877 | 2026-07-17 09:37:42.645982 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-17 09:37:42.688305 | debian-bookworm | ok 2026-07-17 09:37:42.704154 | debian-bookworm | included: /var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-17 09:37:42.710119 | 2026-07-17 09:37:42.710214 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-17 09:37:45.299554 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-17 09:37:45.299748 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/work/be09d29e97094b439395ca62c99bb8e4_id_rsa 2026-07-17 09:37:45.299785 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/work/be09d29e97094b439395ca62c99bb8e4_id_rsa.pub 2026-07-17 09:37:45.299825 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-17 09:37:45.299851 | debian-bookworm -> localhost | SHA256:AFYzZoM81LTtXP9k827TV1LgNnhTgBrGXVsRhaQEKAM zuul-build-sshkey 2026-07-17 09:37:45.299873 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-17 09:37:45.299907 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-17 09:37:45.299930 | debian-bookworm -> localhost | | oE*O o.o.++=*| 2026-07-17 09:37:45.299952 | debian-bookworm -> localhost | | .+++*. +.o.oo.| 2026-07-17 09:37:45.299972 | debian-bookworm -> localhost | | .oo...o.o.o | 2026-07-17 09:37:45.299991 | debian-bookworm -> localhost | | + .... * .| 2026-07-17 09:37:45.300011 | debian-bookworm -> localhost | | S .o++ | 2026-07-17 09:37:45.300033 | debian-bookworm -> localhost | | +.o.| 2026-07-17 09:37:45.300054 | debian-bookworm -> localhost | | ..+| 2026-07-17 09:37:45.300073 | debian-bookworm -> localhost | | o+| 2026-07-17 09:37:45.300094 | debian-bookworm -> localhost | | .+| 2026-07-17 09:37:45.300114 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-17 09:37:45.300166 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.066877 2026-07-17 09:37:45.307384 | 2026-07-17 09:37:45.307496 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-17 09:37:45.340886 | debian-bookworm | ok 2026-07-17 09:37:45.352525 | debian-bookworm | included: /var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-17 09:37:45.361033 | 2026-07-17 09:37:45.361139 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-17 09:37:45.375931 | debian-bookworm | skipping: Conditional result was False 2026-07-17 09:37:45.381592 | 2026-07-17 09:37:45.381689 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-17 09:37:46.125787 | debian-bookworm | changed 2026-07-17 09:37:46.134764 | 2026-07-17 09:37:46.134929 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-17 09:37:46.433498 | debian-bookworm | ok 2026-07-17 09:37:46.444694 | 2026-07-17 09:37:46.444830 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-17 09:37:47.187026 | debian-bookworm | changed 2026-07-17 09:37:47.193859 | 2026-07-17 09:37:47.193962 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-17 09:37:47.868638 | debian-bookworm | changed 2026-07-17 09:37:47.883434 | 2026-07-17 09:37:47.883630 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-17 09:37:47.908786 | debian-bookworm | skipping: Conditional result was False 2026-07-17 09:37:47.915140 | 2026-07-17 09:37:47.915239 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-17 09:37:48.526874 | debian-bookworm -> localhost | changed 2026-07-17 09:37:48.543730 | 2026-07-17 09:37:48.543872 | TASK [add-build-sshkey : Add back temp key] 2026-07-17 09:37:49.098534 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/work/be09d29e97094b439395ca62c99bb8e4_id_rsa (zuul-build-sshkey) 2026-07-17 09:37:49.098749 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022605 2026-07-17 09:37:49.106143 | 2026-07-17 09:37:49.106241 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-17 09:37:49.592118 | debian-bookworm | ok 2026-07-17 09:37:49.597129 | 2026-07-17 09:37:49.597215 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-17 09:37:49.632531 | debian-bookworm | skipping: Conditional result was False 2026-07-17 09:37:49.654177 | 2026-07-17 09:37:49.654298 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-17 09:37:50.110070 | debian-bookworm | ok 2026-07-17 09:37:50.120419 | 2026-07-17 09:37:50.120512 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-17 09:37:50.160233 | debian-bookworm | ok 2026-07-17 09:37:50.165916 | 2026-07-17 09:37:50.166007 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-17 09:37:50.452512 | debian-bookworm -> localhost | ok 2026-07-17 09:37:50.459072 | 2026-07-17 09:37:50.459164 | TASK [validate-host : Collect information about the host] 2026-07-17 09:37:51.626751 | debian-bookworm | ok 2026-07-17 09:37:51.647998 | 2026-07-17 09:37:51.648117 | TASK [validate-host : Sanitize hostname] 2026-07-17 09:37:51.780836 | debian-bookworm | ok 2026-07-17 09:37:51.786707 | 2026-07-17 09:37:51.786872 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-17 09:37:52.317086 | debian-bookworm -> localhost | changed 2026-07-17 09:37:52.321830 | 2026-07-17 09:37:52.321928 | TASK [validate-host : Collect information about zuul worker] 2026-07-17 09:37:52.729716 | debian-bookworm | ok 2026-07-17 09:37:52.734388 | 2026-07-17 09:37:52.734488 | TASK [validate-host : Write out all zuul information for each host] 2026-07-17 09:37:53.432481 | debian-bookworm -> localhost | changed 2026-07-17 09:37:53.441619 | 2026-07-17 09:37:53.441947 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-17 09:37:53.724463 | debian-bookworm | ok 2026-07-17 09:37:53.733700 | 2026-07-17 09:37:53.733837 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-17 09:37:55.248308 | debian-bookworm | changed: 2026-07-17 09:37:55.248503 | debian-bookworm | cd+++++++++ src/ 2026-07-17 09:37:55.248537 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-17 09:37:55.248561 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-17 09:37:55.248582 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-17 09:37:55.248602 | debian-bookworm | Containerfile 2026-07-17 09:37:55.248732 | debian-bookworm | Containerfile 2026-07-17 09:37:55.291071 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/work/logs" 2026-07-17 09:40:31.689851 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/work/artifacts" 2026-07-17 09:40:32.421593 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/be09d29e97094b439395ca62c99bb8e4/work/docs" 2026-07-17 09:40:32.435262 | 2026-07-17 09:40:32.435435 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-17 09:40:33.561595 | debian-bookworm | changed: 2026-07-17 09:40:33.561894 | debian-bookworm | .d..t...... ./ 2026-07-17 09:40:33.561931 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-17 09:40:33.561956 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-17 09:40:33.561978 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-17 09:40:33.561999 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-17 09:40:33.562019 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-17 09:40:33.562061 | debian-bookworm | changed: All items complete 2026-07-17 09:40:33.562083 | 2026-07-17 09:40:34.732541 | debian-bookworm | changed: .d..t...... ./ 2026-07-17 09:40:35.713484 | debian-bookworm | changed: .d..t...... ./ 2026-07-17 09:40:35.748849 | 2026-07-17 09:40:35.749018 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-17 09:40:36.349906 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.047575 2026-07-17 09:40:37.236353 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.024742 2026-07-17 09:40:37.253038 | 2026-07-17 09:40:37.253317 | PLAY RECAP 2026-07-17 09:40:37.253398 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-17 09:40:37.253425 | 2026-07-17 09:40:37.795358 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-17 09:40:37.796025 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-17 09:40:38.711378 | 2026-07-17 09:40:38.711510 | PLAY [Base post] 2026-07-17 09:40:38.732256 | 2026-07-17 09:40:38.732383 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-17 09:40:39.948407 | debian-bookworm | changed 2026-07-17 09:40:39.954440 | 2026-07-17 09:40:39.954540 | PLAY RECAP 2026-07-17 09:40:39.954590 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-17 09:40:39.954641 | 2026-07-17 09:40:40.673457 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-17 09:40:40.674128 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-17 09:40:43.124143 | 2026-07-17 09:40:43.124282 | PLAY [Base post-logs] 2026-07-17 09:40:43.134361 | 2026-07-17 09:40:43.134506 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-17 09:40:44.310350 | localhost | changed 2026-07-17 09:40:44.326292 | 2026-07-17 09:40:44.326426 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-17 09:40:44.376400 | localhost | ok 2026-07-17 09:40:44.379047 | 2026-07-17 09:40:44.379123 | TASK [Set zuul-log-path fact] 2026-07-17 09:40:44.405573 | localhost | ok 2026-07-17 09:40:44.413494 | 2026-07-17 09:40:44.413596 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-17 09:40:44.464246 | localhost | ok 2026-07-17 09:40:44.466705 | 2026-07-17 09:40:44.466788 | TASK [upload-logs : Create log directories] 2026-07-17 09:40:45.574412 | localhost | changed 2026-07-17 09:40:45.576808 | 2026-07-17 09:40:45.576891 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-17 09:40:46.234642 | localhost -> localhost | ok: Runtime: 0:00:00.007091 2026-07-17 09:40:46.238089 | 2026-07-17 09:40:46.238204 | TASK [upload-logs : Upload logs to log server] 2026-07-17 09:40:46.937052 | localhost | Output suppressed because no_log was given 2026-07-17 09:40:46.938719 | 2026-07-17 09:40:46.938807 | LOOP [upload-logs : Compress console log and json output] 2026-07-17 09:40:47.048881 | localhost | skipping: Conditional result was False 2026-07-17 09:40:47.059897 | localhost | skipping: Conditional result was False 2026-07-17 09:40:47.067782 | 2026-07-17 09:40:47.067895 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-17 09:40:47.132470 | localhost | skipping: Conditional result was False 2026-07-17 09:40:47.132753 | 2026-07-17 09:40:47.138422 | localhost | skipping: Conditional result was False 2026-07-17 09:40:47.150533 | 2026-07-17 09:40:47.150650 | LOOP [upload-logs : Upload console log and json output]