2026-09-09 17:34:22.617680 | Job console starting 2026-09-09 17:34:22.637097 | Updating git repos 2026-09-09 17:34:22.684674 | Cloning repos into workspace 2026-09-09 17:34:22.775606 | Restoring repo states 2026-09-09 17:34:22.790092 | Merging changes 2026-09-09 17:34:23.684047 | Checking out repos 2026-09-09 17:34:23.793848 | Preparing playbooks 2026-09-09 17:34:25.559657 | Running Ansible setup 2026-09-09 17:34:30.787113 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-09 17:34:32.513690 | 2026-09-09 17:34:32.521367 | PLAY [Base pre] 2026-09-09 17:34:32.557415 | 2026-09-09 17:34:32.557519 | TASK [Setup log path fact] 2026-09-09 17:34:32.597939 | debian-bookworm | ok 2026-09-09 17:34:32.624223 | 2026-09-09 17:34:32.624344 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-09 17:34:32.667632 | debian-bookworm | ok 2026-09-09 17:34:32.676426 | 2026-09-09 17:34:32.676524 | TASK [emit-job-header : Print job information] 2026-09-09 17:34:32.742867 | # Job Information 2026-09-09 17:34:32.742999 | Ansible Version: 2.16.14 2026-09-09 17:34:32.743028 | Job: python-osism-test-setup 2026-09-09 17:34:32.743056 | Pipeline: check 2026-09-09 17:34:32.743075 | Executor: 521e9411259a 2026-09-09 17:34:32.743093 | Triggered by: https://github.com/osism/python-osism/pull/2684 2026-09-09 17:34:32.743167 | Event ID: aab4de80-ac74-11f1-8b38-6a484bdffe76 2026-09-09 17:34:32.748971 | 2026-09-09 17:34:32.749138 | LOOP [emit-job-header : Print node information] 2026-09-09 17:34:33.018528 | debian-bookworm | ok: 2026-09-09 17:34:33.018754 | debian-bookworm | # Node Information 2026-09-09 17:34:33.018789 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-09 17:34:33.018811 | debian-bookworm | Hostname: debian 2026-09-09 17:34:33.018830 | debian-bookworm | Username: zuul 2026-09-09 17:34:33.018847 | debian-bookworm | Distro: Debian 12.15 2026-09-09 17:34:33.018866 | debian-bookworm | Provider: regiocloud-a 2026-09-09 17:34:33.018883 | debian-bookworm | Region: 2026-09-09 17:34:33.018900 | debian-bookworm | Label: debian-bookworm 2026-09-09 17:34:33.018917 | debian-bookworm | Product Name: OpenStack Nova 2026-09-09 17:34:33.018933 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef2:7df5 2026-09-09 17:34:33.039258 | 2026-09-09 17:34:33.039352 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-09 17:34:34.171153 | debian-bookworm -> localhost | changed 2026-09-09 17:34:34.181969 | 2026-09-09 17:34:34.182150 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-09 17:34:36.489148 | debian-bookworm -> localhost | changed 2026-09-09 17:34:36.498433 | 2026-09-09 17:34:36.498520 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-09 17:34:37.152247 | debian-bookworm -> localhost | ok 2026-09-09 17:34:37.158078 | 2026-09-09 17:34:37.158174 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-09 17:34:37.196026 | debian-bookworm | ok 2026-09-09 17:34:37.224794 | debian-bookworm | included: /var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-09 17:34:37.231398 | 2026-09-09 17:34:37.231487 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-09 17:34:38.932700 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-09 17:34:38.932863 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/work/a7069b10f29648e7b8eaff7f77e4cc75_id_rsa 2026-09-09 17:34:38.932894 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/work/a7069b10f29648e7b8eaff7f77e4cc75_id_rsa.pub 2026-09-09 17:34:38.932915 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-09 17:34:38.932934 | debian-bookworm -> localhost | SHA256:B/d4o+XXv0mSlP5PZMx8DIIUqyY+ixJV+IyVIOgF8u4 zuul-build-sshkey 2026-09-09 17:34:38.932952 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-09 17:34:38.932978 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-09 17:34:38.932996 | debian-bookworm -> localhost | |.oo .o . o. | 2026-09-09 17:34:38.933014 | debian-bookworm -> localhost | |o. o. + . o | 2026-09-09 17:34:38.933030 | debian-bookworm -> localhost | |. o * . + . . | 2026-09-09 17:34:38.933046 | debian-bookworm -> localhost | | o o o + o ..* | 2026-09-09 17:34:38.933062 | debian-bookworm -> localhost | | .. . S o =o O| 2026-09-09 17:34:38.933084 | debian-bookworm -> localhost | | .. . o . =o..+.| 2026-09-09 17:34:38.933102 | debian-bookworm -> localhost | | E. o . .+..o| 2026-09-09 17:34:38.933119 | debian-bookworm -> localhost | | . . o .+.o| 2026-09-09 17:34:38.933137 | debian-bookworm -> localhost | | .. . +=| 2026-09-09 17:34:38.933154 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-09 17:34:38.933196 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.510639 2026-09-09 17:34:38.938170 | 2026-09-09 17:34:38.938241 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-09 17:34:38.955231 | debian-bookworm | ok 2026-09-09 17:34:38.966691 | debian-bookworm | included: /var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-09 17:34:38.983434 | 2026-09-09 17:34:38.983518 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-09 17:34:39.008735 | debian-bookworm | skipping: Conditional result was False 2026-09-09 17:34:39.013911 | 2026-09-09 17:34:39.013988 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-09 17:34:39.822036 | debian-bookworm | changed 2026-09-09 17:34:39.832394 | 2026-09-09 17:34:39.832518 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-09 17:34:40.174223 | debian-bookworm | ok 2026-09-09 17:34:40.181105 | 2026-09-09 17:34:40.181205 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-09 17:34:41.030322 | debian-bookworm | changed 2026-09-09 17:34:41.043885 | 2026-09-09 17:34:41.043993 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-09 17:34:41.773915 | debian-bookworm | changed 2026-09-09 17:34:41.778365 | 2026-09-09 17:34:41.778445 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-09 17:34:41.817051 | debian-bookworm | skipping: Conditional result was False 2026-09-09 17:34:41.822578 | 2026-09-09 17:34:41.822682 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-09 17:34:42.556831 | debian-bookworm -> localhost | changed 2026-09-09 17:34:42.573231 | 2026-09-09 17:34:42.574435 | TASK [add-build-sshkey : Add back temp key] 2026-09-09 17:34:43.295123 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/work/a7069b10f29648e7b8eaff7f77e4cc75_id_rsa (zuul-build-sshkey) 2026-09-09 17:34:43.295334 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013019 2026-09-09 17:34:43.303142 | 2026-09-09 17:34:43.303239 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-09 17:34:43.696464 | debian-bookworm | ok 2026-09-09 17:34:43.701196 | 2026-09-09 17:34:43.701435 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-09 17:34:43.725209 | debian-bookworm | skipping: Conditional result was False 2026-09-09 17:34:43.734156 | 2026-09-09 17:34:43.734239 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-09 17:34:44.187486 | debian-bookworm | ok 2026-09-09 17:34:44.206385 | 2026-09-09 17:34:44.206479 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-09 17:34:44.243567 | debian-bookworm | ok 2026-09-09 17:34:44.247741 | 2026-09-09 17:34:44.247816 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-09 17:34:44.802771 | debian-bookworm -> localhost | ok 2026-09-09 17:34:44.808062 | 2026-09-09 17:34:44.808147 | TASK [validate-host : Collect information about the host] 2026-09-09 17:34:46.177893 | debian-bookworm | ok 2026-09-09 17:34:46.195813 | 2026-09-09 17:34:46.195915 | TASK [validate-host : Sanitize hostname] 2026-09-09 17:34:46.380782 | debian-bookworm | ok 2026-09-09 17:34:46.385036 | 2026-09-09 17:34:46.385120 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-09 17:34:47.122974 | debian-bookworm -> localhost | changed 2026-09-09 17:34:47.127472 | 2026-09-09 17:34:47.127548 | TASK [validate-host : Collect information about zuul worker] 2026-09-09 17:34:47.563856 | debian-bookworm | ok 2026-09-09 17:34:47.571464 | 2026-09-09 17:34:47.571592 | TASK [validate-host : Write out all zuul information for each host] 2026-09-09 17:34:48.338999 | debian-bookworm -> localhost | changed 2026-09-09 17:34:48.348243 | 2026-09-09 17:34:48.348344 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-09 17:34:48.685982 | debian-bookworm | ok 2026-09-09 17:34:48.691331 | 2026-09-09 17:34:48.691423 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-09 17:34:50.573632 | debian-bookworm | changed: 2026-09-09 17:34:50.573777 | debian-bookworm | cd+++++++++ src/ 2026-09-09 17:34:50.573806 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-09 17:34:50.573826 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-09 17:34:50.573843 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-09 17:34:50.573859 | debian-bookworm | Containerfile 2026-09-09 17:34:50.573966 | debian-bookworm | Containerfile 2026-09-09 17:34:50.584509 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/work/logs" 2026-09-09 17:37:46.232374 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/work/artifacts" 2026-09-09 17:37:46.499688 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a7069b10f29648e7b8eaff7f77e4cc75/work/docs" 2026-09-09 17:37:46.525881 | 2026-09-09 17:37:46.526054 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-09 17:37:47.254270 | debian-bookworm | changed: 2026-09-09 17:37:47.254707 | debian-bookworm | .d..t...... ./ 2026-09-09 17:37:47.254789 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-09 17:37:47.254839 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-09 17:37:47.254883 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-09 17:37:47.254925 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-09 17:37:47.254967 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-09 17:37:47.255052 | debian-bookworm | changed: All items complete 2026-09-09 17:37:47.255099 | 2026-09-09 17:37:47.785085 | debian-bookworm | changed: .d..t...... ./ 2026-09-09 17:37:48.299981 | debian-bookworm | changed: .d..t...... ./ 2026-09-09 17:37:48.314118 | 2026-09-09 17:37:48.314247 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-09 17:37:48.654184 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011189 2026-09-09 17:37:48.986895 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010245 2026-09-09 17:37:49.008481 | 2026-09-09 17:37:49.008585 | PLAY RECAP 2026-09-09 17:37:49.008664 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-09 17:37:49.008695 | 2026-09-09 17:37:49.150987 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-09 17:37:49.153066 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-09 17:37:49.904484 | 2026-09-09 17:37:49.904649 | PLAY [Base post] 2026-09-09 17:37:49.918028 | 2026-09-09 17:37:49.918154 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-09 17:37:50.835565 | debian-bookworm | changed 2026-09-09 17:37:50.841918 | 2026-09-09 17:37:50.842021 | PLAY RECAP 2026-09-09 17:37:50.842084 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-09 17:37:50.842147 | 2026-09-09 17:37:50.968104 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-09 17:37:50.968948 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-09 17:37:51.792561 | 2026-09-09 17:37:51.792769 | PLAY [Base post-logs] 2026-09-09 17:37:51.803257 | 2026-09-09 17:37:51.803384 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-09 17:37:52.254172 | localhost | changed 2026-09-09 17:37:52.264542 | 2026-09-09 17:37:52.264707 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-09 17:37:52.292705 | localhost | ok 2026-09-09 17:37:52.299216 | 2026-09-09 17:37:52.299363 | TASK [Set zuul-log-path fact] 2026-09-09 17:37:52.326732 | localhost | ok 2026-09-09 17:37:52.340442 | 2026-09-09 17:37:52.340580 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-09 17:37:52.377681 | localhost | ok 2026-09-09 17:37:52.384596 | 2026-09-09 17:37:52.384785 | TASK [upload-logs : Create log directories] 2026-09-09 17:37:52.862434 | localhost | changed 2026-09-09 17:37:52.866331 | 2026-09-09 17:37:52.866453 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-09 17:37:53.418894 | localhost -> localhost | ok: Runtime: 0:00:00.005693 2026-09-09 17:37:53.425533 | 2026-09-09 17:37:53.425749 | TASK [upload-logs : Upload logs to log server] 2026-09-09 17:37:54.010720 | localhost | Output suppressed because no_log was given 2026-09-09 17:37:54.012916 | 2026-09-09 17:37:54.013026 | LOOP [upload-logs : Compress console log and json output] 2026-09-09 17:37:54.070494 | localhost | skipping: Conditional result was False 2026-09-09 17:37:54.076860 | localhost | skipping: Conditional result was False 2026-09-09 17:37:54.083727 | 2026-09-09 17:37:54.083831 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-09 17:37:54.127125 | localhost | skipping: Conditional result was False 2026-09-09 17:37:54.127534 | 2026-09-09 17:37:54.132416 | localhost | skipping: Conditional result was False 2026-09-09 17:37:54.141159 | 2026-09-09 17:37:54.141415 | LOOP [upload-logs : Upload console log and json output]