2026-05-08 03:08:13.971724 | Job console starting 2026-05-08 03:08:13.979189 | Updating git repos 2026-05-08 03:08:14.018117 | Cloning repos into workspace 2026-05-08 03:08:14.127922 | Restoring repo states 2026-05-08 03:08:14.139588 | Merging changes 2026-05-08 03:08:14.139603 | Checking out repos 2026-05-08 03:08:14.455825 | Preparing playbooks 2026-05-08 03:08:16.005015 | Running Ansible setup 2026-05-08 03:08:22.378240 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-08 03:08:23.763338 | 2026-05-08 03:08:23.763466 | PLAY [Base pre] 2026-05-08 03:08:23.809789 | 2026-05-08 03:08:23.809921 | TASK [Setup log path fact] 2026-05-08 03:08:23.838419 | debian-bookworm | ok 2026-05-08 03:08:23.866561 | 2026-05-08 03:08:23.866709 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 03:08:23.933334 | debian-bookworm | ok 2026-05-08 03:08:23.949578 | 2026-05-08 03:08:23.949694 | TASK [emit-job-header : Print job information] 2026-05-08 03:08:24.008561 | # Job Information 2026-05-08 03:08:24.008724 | Ansible Version: 2.16.14 2026-05-08 03:08:24.008759 | Job: python-osism-test-setup 2026-05-08 03:08:24.008794 | Pipeline: periodic-daily 2026-05-08 03:08:24.008816 | Executor: 521e9411259a 2026-05-08 03:08:24.008837 | Triggered by: https://github.com/osism/python-osism 2026-05-08 03:08:24.008860 | Event ID: 87ee3e5d33334df1b37a0a03abdee29a 2026-05-08 03:08:24.013965 | 2026-05-08 03:08:24.014079 | LOOP [emit-job-header : Print node information] 2026-05-08 03:08:24.230515 | debian-bookworm | ok: 2026-05-08 03:08:24.230695 | debian-bookworm | # Node Information 2026-05-08 03:08:24.230731 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-08 03:08:24.230756 | debian-bookworm | Hostname: debian 2026-05-08 03:08:24.230778 | debian-bookworm | Username: zuul 2026-05-08 03:08:24.230800 | debian-bookworm | Distro: Debian 12.13 2026-05-08 03:08:24.230825 | debian-bookworm | Provider: regiocloud-a 2026-05-08 03:08:24.230868 | debian-bookworm | Region: 2026-05-08 03:08:24.230890 | debian-bookworm | Label: debian-bookworm 2026-05-08 03:08:24.230908 | debian-bookworm | Product Name: OpenStack Nova 2026-05-08 03:08:24.230927 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe30:1a9f 2026-05-08 03:08:24.247293 | 2026-05-08 03:08:24.247405 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-08 03:08:24.800943 | debian-bookworm -> localhost | changed 2026-05-08 03:08:24.807716 | 2026-05-08 03:08:24.807814 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-08 03:08:26.308685 | debian-bookworm -> localhost | changed 2026-05-08 03:08:26.318509 | 2026-05-08 03:08:26.318608 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-08 03:08:26.838913 | debian-bookworm -> localhost | ok 2026-05-08 03:08:26.843951 | 2026-05-08 03:08:26.844058 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-08 03:08:26.882255 | debian-bookworm | ok 2026-05-08 03:08:26.900085 | debian-bookworm | included: /var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-08 03:08:26.915449 | 2026-05-08 03:08:26.915540 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-08 03:08:28.188011 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-08 03:08:28.188212 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/work/68ab6814f2324a2e96756f7ae50707ec_id_rsa 2026-05-08 03:08:28.188250 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/work/68ab6814f2324a2e96756f7ae50707ec_id_rsa.pub 2026-05-08 03:08:28.188277 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-08 03:08:28.188302 | debian-bookworm -> localhost | SHA256:ePm3nqRmw1sXdNoj6YTW+EC9kMC1FvrQayM6xyrF1Ps zuul-build-sshkey 2026-05-08 03:08:28.188325 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-08 03:08:28.188358 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-08 03:08:28.188380 | debian-bookworm -> localhost | | ...o | 2026-05-08 03:08:28.188401 | debian-bookworm -> localhost | | .+ = | 2026-05-08 03:08:28.188421 | debian-bookworm -> localhost | | .o B .. .| 2026-05-08 03:08:28.188440 | debian-bookworm -> localhost | | o o= *.o+ | 2026-05-08 03:08:28.188460 | debian-bookworm -> localhost | | + S..X *o..| 2026-05-08 03:08:28.188484 | debian-bookworm -> localhost | | +oo+ * ...| 2026-05-08 03:08:28.188504 | debian-bookworm -> localhost | | .o +o +o. | 2026-05-08 03:08:28.188523 | debian-bookworm -> localhost | | . + =E + | 2026-05-08 03:08:28.188544 | debian-bookworm -> localhost | | .. oo++ | 2026-05-08 03:08:28.188564 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-08 03:08:28.188618 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.531821 2026-05-08 03:08:28.195104 | 2026-05-08 03:08:28.195258 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-08 03:08:28.223597 | debian-bookworm | ok 2026-05-08 03:08:28.232603 | debian-bookworm | included: /var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-08 03:08:28.248100 | 2026-05-08 03:08:28.248203 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-08 03:08:28.273461 | debian-bookworm | skipping: Conditional result was False 2026-05-08 03:08:28.279446 | 2026-05-08 03:08:28.279555 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-08 03:08:29.106814 | debian-bookworm | changed 2026-05-08 03:08:29.112268 | 2026-05-08 03:08:29.112364 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-08 03:08:29.387339 | debian-bookworm | ok 2026-05-08 03:08:29.408726 | 2026-05-08 03:08:29.408841 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-08 03:08:30.161057 | debian-bookworm | changed 2026-05-08 03:08:30.170169 | 2026-05-08 03:08:30.170273 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-08 03:08:30.881131 | debian-bookworm | changed 2026-05-08 03:08:30.891432 | 2026-05-08 03:08:30.891533 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-08 03:08:30.935229 | debian-bookworm | skipping: Conditional result was False 2026-05-08 03:08:30.941898 | 2026-05-08 03:08:30.942032 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-08 03:08:31.766613 | debian-bookworm -> localhost | changed 2026-05-08 03:08:31.784375 | 2026-05-08 03:08:31.784525 | TASK [add-build-sshkey : Add back temp key] 2026-05-08 03:08:32.198810 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/work/68ab6814f2324a2e96756f7ae50707ec_id_rsa (zuul-build-sshkey) 2026-05-08 03:08:32.199371 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017265 2026-05-08 03:08:32.224471 | 2026-05-08 03:08:32.224621 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-08 03:08:32.680147 | debian-bookworm | ok 2026-05-08 03:08:32.685656 | 2026-05-08 03:08:32.685786 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-08 03:08:32.710092 | debian-bookworm | skipping: Conditional result was False 2026-05-08 03:08:32.721121 | 2026-05-08 03:08:32.721265 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-08 03:08:33.225495 | debian-bookworm | ok 2026-05-08 03:08:33.239252 | 2026-05-08 03:08:33.239381 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-08 03:08:33.268682 | debian-bookworm | ok 2026-05-08 03:08:33.274082 | 2026-05-08 03:08:33.274197 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-08 03:08:33.591619 | debian-bookworm -> localhost | ok 2026-05-08 03:08:33.598530 | 2026-05-08 03:08:33.598653 | TASK [validate-host : Collect information about the host] 2026-05-08 03:08:34.742254 | debian-bookworm | ok 2026-05-08 03:08:34.754713 | 2026-05-08 03:08:34.754896 | TASK [validate-host : Sanitize hostname] 2026-05-08 03:08:34.900460 | debian-bookworm | ok 2026-05-08 03:08:34.960249 | 2026-05-08 03:08:34.960393 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-08 03:08:36.191512 | debian-bookworm -> localhost | changed 2026-05-08 03:08:36.204112 | 2026-05-08 03:08:36.204743 | TASK [validate-host : Collect information about zuul worker] 2026-05-08 03:08:36.834020 | debian-bookworm | ok 2026-05-08 03:08:36.839904 | 2026-05-08 03:08:36.849246 | TASK [validate-host : Write out all zuul information for each host] 2026-05-08 03:08:38.396511 | debian-bookworm -> localhost | changed 2026-05-08 03:08:38.407912 | 2026-05-08 03:08:38.408063 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-08 03:08:38.733821 | debian-bookworm | ok 2026-05-08 03:08:38.744573 | 2026-05-08 03:08:38.744756 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-08 03:08:39.998075 | debian-bookworm | changed: 2026-05-08 03:08:39.998261 | debian-bookworm | cd+++++++++ src/ 2026-05-08 03:08:39.998295 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-08 03:08:39.998320 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-08 03:08:39.998341 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-08 03:08:39.998361 | debian-bookworm | Containerfile 2026-05-08 03:08:39.998494 | debian-bookworm | Containerfile 2026-05-08 03:08:40.040020 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/work/logs" 2026-05-08 03:21:29.205887 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/work/artifacts" 2026-05-08 03:21:29.480266 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/68ab6814f2324a2e96756f7ae50707ec/work/docs" 2026-05-08 03:21:29.505266 | 2026-05-08 03:21:29.505510 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-08 03:21:30.360648 | debian-bookworm | changed: 2026-05-08 03:21:30.360999 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-08 03:21:30.361057 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-08 03:21:30.361108 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-08 03:21:30.361180 | debian-bookworm | changed: All items complete 2026-05-08 03:21:30.361224 | 2026-05-08 03:21:30.964328 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 03:21:31.590002 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 03:21:31.613033 | 2026-05-08 03:21:31.613196 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-08 03:21:31.649328 | debian-bookworm | skipping: Conditional result was False 2026-05-08 03:21:31.652276 | debian-bookworm | skipping: Conditional result was False 2026-05-08 03:21:31.675459 | 2026-05-08 03:21:31.675598 | PLAY RECAP 2026-05-08 03:21:31.675684 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-08 03:21:31.675727 | 2026-05-08 03:21:31.810978 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-08 03:21:31.811986 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 03:21:32.575935 | 2026-05-08 03:21:32.576111 | PLAY [Base post] 2026-05-08 03:21:32.590688 | 2026-05-08 03:21:32.590911 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-08 03:21:33.472636 | debian-bookworm | changed 2026-05-08 03:21:33.482583 | 2026-05-08 03:21:33.482726 | PLAY RECAP 2026-05-08 03:21:33.482828 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-08 03:21:33.482947 | 2026-05-08 03:21:33.613151 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 03:21:33.614029 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-08 03:21:34.447338 | 2026-05-08 03:21:34.447534 | PLAY [Base post-logs] 2026-05-08 03:21:34.458898 | 2026-05-08 03:21:34.459046 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-08 03:21:34.935525 | localhost | changed 2026-05-08 03:21:34.951817 | 2026-05-08 03:21:34.952015 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-08 03:21:34.980009 | localhost | ok 2026-05-08 03:21:34.986031 | 2026-05-08 03:21:34.986211 | TASK [Set zuul-log-path fact] 2026-05-08 03:21:35.002358 | localhost | ok 2026-05-08 03:21:35.010337 | 2026-05-08 03:21:35.010450 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 03:21:35.045728 | localhost | ok 2026-05-08 03:21:35.049841 | 2026-05-08 03:21:35.049967 | TASK [upload-logs : Create log directories] 2026-05-08 03:21:35.593650 | localhost | changed 2026-05-08 03:21:35.596553 | 2026-05-08 03:21:35.596666 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-08 03:21:36.098619 | localhost -> localhost | ok: Runtime: 0:00:00.007239 2026-05-08 03:21:36.106344 | 2026-05-08 03:21:36.106508 | TASK [upload-logs : Upload logs to log server] 2026-05-08 03:21:36.715167 | localhost | Output suppressed because no_log was given 2026-05-08 03:21:36.718313 | 2026-05-08 03:21:36.718481 | LOOP [upload-logs : Compress console log and json output] 2026-05-08 03:21:36.771380 | localhost | skipping: Conditional result was False 2026-05-08 03:21:36.781563 | localhost | skipping: Conditional result was False 2026-05-08 03:21:36.791448 | 2026-05-08 03:21:36.791708 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-08 03:21:36.840267 | localhost | skipping: Conditional result was False 2026-05-08 03:21:36.840676 | 2026-05-08 03:21:36.845351 | localhost | skipping: Conditional result was False 2026-05-08 03:21:36.854419 | 2026-05-08 03:21:36.854616 | LOOP [upload-logs : Upload console log and json output]