2026-04-05 03:05:38.986530 | Job console starting 2026-04-05 03:05:39.010559 | Updating git repos 2026-04-05 03:05:39.061900 | Cloning repos into workspace 2026-04-05 03:05:39.157752 | Restoring repo states 2026-04-05 03:05:39.192830 | Merging changes 2026-04-05 03:05:39.192854 | Checking out repos 2026-04-05 03:05:39.253791 | Preparing playbooks 2026-04-05 03:05:40.380550 | Running Ansible setup 2026-04-05 03:07:06.428884 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-05 03:07:08.021525 | 2026-04-05 03:07:08.021701 | PLAY [Base pre] 2026-04-05 03:07:08.065677 | 2026-04-05 03:07:08.065883 | TASK [Setup log path fact] 2026-04-05 03:07:08.105965 | debian-bookworm | ok 2026-04-05 03:07:08.137066 | 2026-04-05 03:07:08.137239 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-05 03:07:08.212464 | debian-bookworm | ok 2026-04-05 03:07:08.222296 | 2026-04-05 03:07:08.222408 | TASK [emit-job-header : Print job information] 2026-04-05 03:07:08.288200 | # Job Information 2026-04-05 03:07:08.288361 | Ansible Version: 2.16.14 2026-04-05 03:07:08.288395 | Job: cfg-generics-tox-test-stable 2026-04-05 03:07:08.288426 | Pipeline: periodic-daily 2026-04-05 03:07:08.288449 | Executor: 521e9411259a 2026-04-05 03:07:08.288469 | Triggered by: https://github.com/osism/generics 2026-04-05 03:07:08.288490 | Event ID: 0645f4bb3f2c4b59b0777df5bd232a9c 2026-04-05 03:07:08.293767 | 2026-04-05 03:07:08.293900 | LOOP [emit-job-header : Print node information] 2026-04-05 03:07:08.598513 | debian-bookworm | ok: 2026-04-05 03:07:08.598700 | debian-bookworm | # Node Information 2026-04-05 03:07:08.598767 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-04-05 03:07:08.598803 | debian-bookworm | Hostname: debian 2026-04-05 03:07:08.598827 | debian-bookworm | Username: zuul 2026-04-05 03:07:08.598863 | debian-bookworm | Distro: Debian 12.13 2026-04-05 03:07:08.598888 | debian-bookworm | Provider: regiocloud-a 2026-04-05 03:07:08.598908 | debian-bookworm | Region: 2026-04-05 03:07:08.598930 | debian-bookworm | Label: debian-bookworm 2026-04-05 03:07:08.598949 | debian-bookworm | Product Name: OpenStack Nova 2026-04-05 03:07:08.598968 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef0:4964 2026-04-05 03:07:08.623445 | 2026-04-05 03:07:08.623570 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-05 03:07:09.411735 | debian-bookworm -> localhost | changed 2026-04-05 03:07:09.421942 | 2026-04-05 03:07:09.422057 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-05 03:07:10.841093 | debian-bookworm -> localhost | changed 2026-04-05 03:07:10.850826 | 2026-04-05 03:07:10.850987 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-05 03:07:11.289642 | debian-bookworm -> localhost | ok 2026-04-05 03:07:11.294609 | 2026-04-05 03:07:11.294708 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-05 03:07:11.313236 | debian-bookworm | ok 2026-04-05 03:07:11.329858 | debian-bookworm | included: /var/lib/zuul/builds/5f67bc89d0764309961b16a19057250c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-05 03:07:11.343893 | 2026-04-05 03:07:11.343982 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-05 03:07:12.592253 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-04-05 03:07:12.592624 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5f67bc89d0764309961b16a19057250c/work/5f67bc89d0764309961b16a19057250c_id_rsa 2026-04-05 03:07:12.592665 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5f67bc89d0764309961b16a19057250c/work/5f67bc89d0764309961b16a19057250c_id_rsa.pub 2026-04-05 03:07:12.592693 | debian-bookworm -> localhost | The key fingerprint is: 2026-04-05 03:07:12.592719 | debian-bookworm -> localhost | SHA256:rdUHSN9GuM78SM1sOI5x7+YpepHxoB97uR06lVW+Phs zuul-build-sshkey 2026-04-05 03:07:12.592741 | debian-bookworm -> localhost | The key's randomart image is: 2026-04-05 03:07:12.592771 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-04-05 03:07:12.592801 | debian-bookworm -> localhost | | . .. | 2026-04-05 03:07:12.592824 | debian-bookworm -> localhost | | . o.o .| 2026-04-05 03:07:12.592844 | debian-bookworm -> localhost | | . o.o..| 2026-04-05 03:07:12.592864 | debian-bookworm -> localhost | | . .+o o| 2026-04-05 03:07:12.592883 | debian-bookworm -> localhost | | S o=.X. +| 2026-04-05 03:07:12.592910 | debian-bookworm -> localhost | | oo %.*+ | 2026-04-05 03:07:12.592932 | debian-bookworm -> localhost | | . B XoE | 2026-04-05 03:07:12.592952 | debian-bookworm -> localhost | | . B.B=o| 2026-04-05 03:07:12.592974 | debian-bookworm -> localhost | | .o B*o+| 2026-04-05 03:07:12.592995 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-04-05 03:07:12.593046 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.836953 2026-04-05 03:07:12.607032 | 2026-04-05 03:07:12.607137 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-05 03:07:12.645566 | debian-bookworm | ok 2026-04-05 03:07:12.664313 | debian-bookworm | included: /var/lib/zuul/builds/5f67bc89d0764309961b16a19057250c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-05 03:07:12.677683 | 2026-04-05 03:07:12.677829 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-05 03:07:12.716550 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:07:12.722477 | 2026-04-05 03:07:12.722593 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-05 03:07:13.374435 | debian-bookworm | changed 2026-04-05 03:07:13.380807 | 2026-04-05 03:07:13.380911 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-05 03:07:13.732067 | debian-bookworm | ok 2026-04-05 03:07:13.744541 | 2026-04-05 03:07:13.744661 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-05 03:07:14.543455 | debian-bookworm | changed 2026-04-05 03:07:14.554038 | 2026-04-05 03:07:14.554198 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-05 03:07:15.440593 | debian-bookworm | changed 2026-04-05 03:07:15.449437 | 2026-04-05 03:07:15.449546 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-05 03:07:15.502997 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:07:15.509451 | 2026-04-05 03:07:15.509552 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-05 03:07:16.415870 | debian-bookworm -> localhost | changed 2026-04-05 03:07:16.428810 | 2026-04-05 03:07:16.428914 | TASK [add-build-sshkey : Add back temp key] 2026-04-05 03:07:16.957545 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5f67bc89d0764309961b16a19057250c/work/5f67bc89d0764309961b16a19057250c_id_rsa (zuul-build-sshkey) 2026-04-05 03:07:16.957766 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013697 2026-04-05 03:07:16.965468 | 2026-04-05 03:07:16.965573 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-05 03:07:17.370233 | debian-bookworm | ok 2026-04-05 03:07:17.381726 | 2026-04-05 03:07:17.382895 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-05 03:07:17.422285 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:07:17.432752 | 2026-04-05 03:07:17.432886 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-05 03:07:18.056957 | debian-bookworm | ok 2026-04-05 03:07:18.067168 | 2026-04-05 03:07:18.067265 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-05 03:07:18.127176 | debian-bookworm | ok 2026-04-05 03:07:18.132613 | 2026-04-05 03:07:18.132714 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-05 03:07:18.863932 | debian-bookworm -> localhost | ok 2026-04-05 03:07:18.870269 | 2026-04-05 03:07:18.870359 | TASK [validate-host : Collect information about the host] 2026-04-05 03:08:00.029617 | debian-bookworm | ok 2026-04-05 03:08:00.044127 | 2026-04-05 03:08:00.044272 | TASK [validate-host : Sanitize hostname] 2026-04-05 03:08:00.180834 | debian-bookworm | ok 2026-04-05 03:08:00.239712 | 2026-04-05 03:08:00.239887 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-05 03:08:00.950238 | debian-bookworm -> localhost | changed 2026-04-05 03:08:00.963219 | 2026-04-05 03:08:00.963351 | TASK [validate-host : Collect information about zuul worker] 2026-04-05 03:08:01.520611 | debian-bookworm | ok 2026-04-05 03:08:01.531997 | 2026-04-05 03:08:01.532162 | TASK [validate-host : Write out all zuul information for each host] 2026-04-05 03:08:02.226331 | debian-bookworm -> localhost | changed 2026-04-05 03:08:02.236551 | 2026-04-05 03:08:02.236680 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-05 03:08:02.513636 | debian-bookworm | ok 2026-04-05 03:08:02.523653 | 2026-04-05 03:08:02.523802 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-05 03:08:03.602334 | debian-bookworm | changed: 2026-04-05 03:08:03.602598 | debian-bookworm | cd+++++++++ src/ 2026-04-05 03:08:03.602635 | debian-bookworm | cd+++++++++ src/github.com/ 2026-04-05 03:08:03.602660 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-04-05 03:08:03.602682 | debian-bookworm | cd+++++++++ src/github.com/osism/generics/ 2026-04-05 03:08:03.602702 | debian-bookworm | localhost | changed 2026-04-05 03:10:24.139554 | 2026-04-05 03:10:24.139660 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-04-05 03:10:24.208287 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:10:24.214039 | 2026-04-05 03:10:24.214145 | TASK [fetch-tox-output : Set envlist fact] 2026-04-05 03:10:24.262558 | debian-bookworm | ok 2026-04-05 03:10:24.267937 | 2026-04-05 03:10:24.268027 | TASK [fetch-tox-output : Get tox version] 2026-04-05 03:10:25.359164 | debian-bookworm | ERROR 2026-04-05 03:10:25.359402 | debian-bookworm | { 2026-04-05 03:10:25.359441 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2026-04-05 03:10:25.359473 | debian-bookworm | "rc": 2 2026-04-05 03:10:25.359512 | debian-bookworm | } failure 2026-04-05 03:10:25.360721 | 2026-04-05 03:10:25.360785 | PLAY RECAP 2026-04-05 03:10:25.360833 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2026-04-05 03:10:25.360858 | 2026-04-05 03:10:25.572378 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-04-05 03:10:25.573350 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-05 03:10:26.576777 | 2026-04-05 03:10:26.576917 | PLAY [all] 2026-04-05 03:10:26.607079 | 2026-04-05 03:10:26.607210 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-04-05 03:10:26.898484 | debian-bookworm | changed: non-zero return code 2026-04-05 03:10:26.907735 | 2026-04-05 03:10:26.907858 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-04-05 03:10:26.941499 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:10:26.946937 | 2026-04-05 03:10:26.947044 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-04-05 03:10:26.986279 | 2026-04-05 03:10:26.986456 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-04-05 03:10:27.025834 | 2026-04-05 03:10:27.026005 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-04-05 03:10:27.059007 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:10:27.064688 | 2026-04-05 03:10:27.064800 | LOOP [fetch-subunit-output : Generate subunit file] 2026-04-05 03:10:27.103482 | 2026-04-05 03:10:27.103664 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-04-05 03:10:27.166948 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:10:27.173193 | 2026-04-05 03:10:27.173311 | TASK [fetch-subunit-output : Remove the temporary file] 2026-04-05 03:10:27.206417 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:10:27.212080 | 2026-04-05 03:10:27.212188 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-04-05 03:10:27.245349 | debian-bookworm | skipping: Conditional result was False 2026-04-05 03:10:27.251767 | 2026-04-05 03:10:27.251850 | PLAY RECAP 2026-04-05 03:10:27.251899 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-04-05 03:10:27.252416 | 2026-04-05 03:10:27.461511 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-05 03:10:27.462439 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-05 03:10:28.534074 | 2026-04-05 03:10:28.534210 | PLAY [Base post] 2026-04-05 03:10:28.572805 | 2026-04-05 03:10:28.572933 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-05 03:10:29.209252 | debian-bookworm | changed 2026-04-05 03:10:29.219242 | 2026-04-05 03:10:29.219352 | PLAY RECAP 2026-04-05 03:10:29.219413 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-05 03:10:29.219514 | 2026-04-05 03:10:29.336273 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-05 03:10:29.337218 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-05 03:10:30.649336 | 2026-04-05 03:10:30.649483 | PLAY [Base post-logs] 2026-04-05 03:10:30.660536 | 2026-04-05 03:10:30.660660 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-05 03:10:31.626719 | localhost | changed 2026-04-05 03:10:31.638896 | 2026-04-05 03:10:31.639027 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-05 03:10:31.707373 | localhost | ok 2026-04-05 03:10:31.710619 | 2026-04-05 03:10:31.710716 | TASK [Set zuul-log-path fact] 2026-04-05 03:10:31.761511 | localhost | ok 2026-04-05 03:10:31.770400 | 2026-04-05 03:10:31.770521 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-05 03:10:31.809548 | localhost | ok 2026-04-05 03:10:31.812314 | 2026-04-05 03:10:31.812406 | TASK [upload-logs : Create log directories] 2026-04-05 03:10:32.841269 | localhost | changed 2026-04-05 03:10:32.843908 | 2026-04-05 03:10:32.844000 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-05 03:10:33.904563 | localhost -> localhost | ok: Runtime: 0:00:00.020248 2026-04-05 03:10:33.908719 | 2026-04-05 03:10:33.908871 | TASK [upload-logs : Upload logs to log server] 2026-04-05 03:10:34.632154 | localhost | Output suppressed because no_log was given 2026-04-05 03:10:34.637261 | 2026-04-05 03:10:34.637356 | LOOP [upload-logs : Compress console log and json output] 2026-04-05 03:10:34.696225 | localhost | skipping: Conditional result was False 2026-04-05 03:10:34.703974 | localhost | skipping: Conditional result was False 2026-04-05 03:10:34.709641 | 2026-04-05 03:10:34.709826 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-05 03:10:34.766985 | localhost | skipping: Conditional result was False 2026-04-05 03:10:34.767415 | 2026-04-05 03:10:34.773259 | localhost | skipping: Conditional result was False 2026-04-05 03:10:34.777774 | 2026-04-05 03:10:34.777871 | LOOP [upload-logs : Upload console log and json output]