2026-05-06 00:54:27.096932 | Job console starting 2026-05-06 00:54:27.113398 | Updating git repos 2026-05-06 00:54:27.136840 | Cloning repos into workspace 2026-05-06 00:54:27.219154 | Restoring repo states 2026-05-06 00:54:27.238051 | Merging changes 2026-05-06 00:54:27.863880 | Checking out repos 2026-05-06 00:54:27.941930 | Preparing playbooks 2026-05-06 00:54:28.892174 | Running Ansible setup 2026-05-06 00:55:54.051769 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-06 00:55:54.825807 | 2026-05-06 00:55:54.825979 | PLAY [Base pre] 2026-05-06 00:55:54.843381 | 2026-05-06 00:55:54.843514 | TASK [Setup log path fact] 2026-05-06 00:55:54.872573 | debian-bookworm | ok 2026-05-06 00:55:54.888520 | 2026-05-06 00:55:54.888658 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 00:55:54.929727 | debian-bookworm | ok 2026-05-06 00:55:54.941203 | 2026-05-06 00:55:54.941362 | TASK [emit-job-header : Print job information] 2026-05-06 00:55:54.996891 | # Job Information 2026-05-06 00:55:54.997160 | Ansible Version: 2.16.14 2026-05-06 00:55:54.997220 | Job: cfg-generics-tox-test-stable-legacy 2026-05-06 00:55:54.997278 | Pipeline: check 2026-05-06 00:55:54.997337 | Executor: 521e9411259a 2026-05-06 00:55:54.997375 | Triggered by: https://github.com/osism/generics/pull/592 2026-05-06 00:55:54.997415 | Event ID: 1c0e4050-48e6-11f1-9953-1215d715fa57 2026-05-06 00:55:55.005049 | 2026-05-06 00:55:55.005194 | LOOP [emit-job-header : Print node information] 2026-05-06 00:55:55.120529 | debian-bookworm | ok: 2026-05-06 00:55:55.120790 | debian-bookworm | # Node Information 2026-05-06 00:55:55.120826 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-06 00:55:55.120851 | debian-bookworm | Hostname: debian 2026-05-06 00:55:55.120873 | debian-bookworm | Username: zuul 2026-05-06 00:55:55.120893 | debian-bookworm | Distro: Debian 12.13 2026-05-06 00:55:55.120917 | debian-bookworm | Provider: regiocloud-a 2026-05-06 00:55:55.120938 | debian-bookworm | Region: 2026-05-06 00:55:55.120959 | debian-bookworm | Label: debian-bookworm 2026-05-06 00:55:55.120980 | debian-bookworm | Product Name: OpenStack Nova 2026-05-06 00:55:55.121000 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6f:3d33 2026-05-06 00:55:55.134970 | 2026-05-06 00:55:55.135116 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-06 00:55:55.649960 | debian-bookworm -> localhost | changed 2026-05-06 00:55:55.657922 | 2026-05-06 00:55:55.658058 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-06 00:55:56.838371 | debian-bookworm -> localhost | changed 2026-05-06 00:55:56.855671 | 2026-05-06 00:55:56.855823 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-06 00:55:57.197133 | debian-bookworm -> localhost | ok 2026-05-06 00:55:57.204100 | 2026-05-06 00:55:57.204228 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-06 00:55:57.236162 | debian-bookworm | ok 2026-05-06 00:55:57.252108 | debian-bookworm | included: /var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-06 00:55:57.258546 | 2026-05-06 00:55:57.258662 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-06 00:55:59.102407 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-06 00:55:59.102749 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/work/5f8d9629de20482395538033e173e7f9_id_rsa 2026-05-06 00:55:59.102810 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/work/5f8d9629de20482395538033e173e7f9_id_rsa.pub 2026-05-06 00:55:59.102897 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-06 00:55:59.102941 | debian-bookworm -> localhost | SHA256:gjQ7vgiK33pZ4QOAhj+5zJv5xz8PEib5dKGqiYDW5Fo zuul-build-sshkey 2026-05-06 00:55:59.102995 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-06 00:55:59.103032 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-06 00:55:59.103067 | debian-bookworm -> localhost | |.. | 2026-05-06 00:55:59.103103 | debian-bookworm -> localhost | |o.. | 2026-05-06 00:55:59.103136 | debian-bookworm -> localhost | |.. oo . | 2026-05-06 00:55:59.103168 | debian-bookworm -> localhost | | +..=.. . | 2026-05-06 00:55:59.103201 | debian-bookworm -> localhost | | o +*o*.S | 2026-05-06 00:55:59.103239 | debian-bookworm -> localhost | |. B. B++ | 2026-05-06 00:55:59.103278 | debian-bookworm -> localhost | |+. Eo+o.. | 2026-05-06 00:55:59.103352 | debian-bookworm -> localhost | |=o*=o.o... | 2026-05-06 00:55:59.103408 | debian-bookworm -> localhost | |+o*++. ..o. | 2026-05-06 00:55:59.103445 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-06 00:55:59.103533 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.257524 2026-05-06 00:55:59.114114 | 2026-05-06 00:55:59.114271 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-06 00:55:59.148778 | debian-bookworm | ok 2026-05-06 00:55:59.160724 | debian-bookworm | included: /var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-06 00:55:59.170070 | 2026-05-06 00:55:59.170177 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-06 00:55:59.193879 | debian-bookworm | skipping: Conditional result was False 2026-05-06 00:55:59.200434 | 2026-05-06 00:55:59.200546 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-06 00:55:59.946395 | debian-bookworm | changed 2026-05-06 00:55:59.955647 | 2026-05-06 00:55:59.956048 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-06 00:56:00.237150 | debian-bookworm | ok 2026-05-06 00:56:00.245743 | 2026-05-06 00:56:00.245877 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-06 00:56:00.962032 | debian-bookworm | changed 2026-05-06 00:56:00.971551 | 2026-05-06 00:56:00.971685 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-06 00:56:01.703687 | debian-bookworm | changed 2026-05-06 00:56:01.711518 | 2026-05-06 00:56:01.711635 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-06 00:56:01.746543 | debian-bookworm | skipping: Conditional result was False 2026-05-06 00:56:01.760158 | 2026-05-06 00:56:01.760360 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-06 00:56:02.253651 | debian-bookworm -> localhost | changed 2026-05-06 00:56:02.265088 | 2026-05-06 00:56:02.265213 | TASK [add-build-sshkey : Add back temp key] 2026-05-06 00:56:02.623340 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/work/5f8d9629de20482395538033e173e7f9_id_rsa (zuul-build-sshkey) 2026-05-06 00:56:02.623641 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013969 2026-05-06 00:56:02.632716 | 2026-05-06 00:56:02.632835 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-06 00:56:03.120314 | debian-bookworm | ok 2026-05-06 00:56:03.129654 | 2026-05-06 00:56:03.129804 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-06 00:56:03.164540 | debian-bookworm | skipping: Conditional result was False 2026-05-06 00:56:03.191801 | 2026-05-06 00:56:03.192048 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-06 00:56:03.688621 | debian-bookworm | ok 2026-05-06 00:56:03.705757 | 2026-05-06 00:56:03.705976 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-06 00:56:03.754344 | debian-bookworm | ok 2026-05-06 00:56:03.763435 | 2026-05-06 00:56:03.763625 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-06 00:56:04.103569 | debian-bookworm -> localhost | ok 2026-05-06 00:56:04.111468 | 2026-05-06 00:56:04.111630 | TASK [validate-host : Collect information about the host] 2026-05-06 00:56:45.372354 | debian-bookworm | ok 2026-05-06 00:56:45.390412 | 2026-05-06 00:56:45.390568 | TASK [validate-host : Sanitize hostname] 2026-05-06 00:56:45.502173 | debian-bookworm | ok 2026-05-06 00:56:45.542690 | 2026-05-06 00:56:45.542871 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-06 00:56:46.198065 | debian-bookworm -> localhost | changed 2026-05-06 00:56:46.211634 | 2026-05-06 00:56:46.211827 | TASK [validate-host : Collect information about zuul worker] 2026-05-06 00:56:46.660138 | debian-bookworm | ok 2026-05-06 00:56:46.665871 | 2026-05-06 00:56:46.666065 | TASK [validate-host : Write out all zuul information for each host] 2026-05-06 00:56:47.211247 | debian-bookworm -> localhost | changed 2026-05-06 00:56:47.231081 | 2026-05-06 00:56:47.231309 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-06 00:56:47.533507 | debian-bookworm | ok 2026-05-06 00:56:47.544589 | 2026-05-06 00:56:47.544759 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-06 00:56:48.453156 | debian-bookworm | changed: 2026-05-06 00:56:48.453524 | debian-bookworm | cd+++++++++ src/ 2026-05-06 00:56:48.453586 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-06 00:56:48.453626 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-06 00:56:48.453660 | debian-bookworm | cd+++++++++ src/github.com/osism/generics/ 2026-05-06 00:56:48.453692 | debian-bookworm | localhost | changed 2026-05-06 01:25:56.099629 | 2026-05-06 01:25:56.099809 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-06 01:25:56.146251 | debian-bookworm | skipping: Conditional result was False 2026-05-06 01:25:56.158047 | 2026-05-06 01:25:56.158307 | TASK [fetch-tox-output : Set envlist fact] 2026-05-06 01:25:56.206898 | debian-bookworm | ok 2026-05-06 01:25:56.216380 | 2026-05-06 01:25:56.216575 | TASK [fetch-tox-output : Get tox version] 2026-05-06 01:25:56.772142 | debian-bookworm | ERROR 2026-05-06 01:25:56.772613 | debian-bookworm | { 2026-05-06 01:25:56.772677 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2026-05-06 01:25:56.772726 | debian-bookworm | "rc": 2 2026-05-06 01:25:56.772762 | debian-bookworm | } failure 2026-05-06 01:25:56.775265 | 2026-05-06 01:25:56.775371 | PLAY RECAP 2026-05-06 01:25:56.775445 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2026-05-06 01:25:56.775480 | 2026-05-06 01:25:56.913600 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-06 01:25:56.916272 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-06 01:25:57.642508 | 2026-05-06 01:25:57.642687 | PLAY [all] 2026-05-06 01:25:57.658665 | 2026-05-06 01:25:57.658825 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-06 01:25:57.911098 | debian-bookworm | changed: non-zero return code 2026-05-06 01:25:57.920379 | 2026-05-06 01:25:57.920577 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-06 01:25:57.937050 | debian-bookworm | skipping: Conditional result was False 2026-05-06 01:25:57.944699 | 2026-05-06 01:25:57.944841 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-06 01:25:57.981719 | 2026-05-06 01:25:57.982048 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-06 01:25:58.032554 | 2026-05-06 01:25:58.032853 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-06 01:25:58.058210 | debian-bookworm | skipping: Conditional result was False 2026-05-06 01:25:58.072301 | 2026-05-06 01:25:58.072475 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-06 01:25:58.111043 | 2026-05-06 01:25:58.111441 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-06 01:25:58.137261 | debian-bookworm | skipping: Conditional result was False 2026-05-06 01:25:58.149561 | 2026-05-06 01:25:58.149727 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-06 01:25:58.185322 | debian-bookworm | skipping: Conditional result was False 2026-05-06 01:25:58.199279 | 2026-05-06 01:25:58.199452 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-06 01:25:58.235475 | debian-bookworm | skipping: Conditional result was False 2026-05-06 01:25:58.252931 | 2026-05-06 01:25:58.253081 | PLAY RECAP 2026-05-06 01:25:58.253177 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-06 01:25:58.253217 | 2026-05-06 01:25:58.400095 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-06 01:25:58.404749 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 01:25:59.215027 | 2026-05-06 01:25:59.215270 | PLAY [Base post-fetch] 2026-05-06 01:25:59.231914 | 2026-05-06 01:25:59.232107 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-06 01:25:59.298935 | debian-bookworm | skipping: Conditional result was False 2026-05-06 01:25:59.305531 | 2026-05-06 01:25:59.305687 | TASK [fetch-output : Set log path for single node] 2026-05-06 01:25:59.373813 | debian-bookworm | ok 2026-05-06 01:25:59.382496 | 2026-05-06 01:25:59.382703 | LOOP [fetch-output : Ensure local output dirs] 2026-05-06 01:25:59.890813 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/work/logs" 2026-05-06 01:26:00.173694 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/work/artifacts" 2026-05-06 01:26:00.450069 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5f8d9629de20482395538033e173e7f9/work/docs" 2026-05-06 01:26:00.466117 | 2026-05-06 01:26:00.466339 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-06 01:26:01.279306 | debian-bookworm | changed: 2026-05-06 01:26:01.279748 | debian-bookworm | .d..t...... ./ 2026-05-06 01:26:01.279869 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-06 01:26:01.279970 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-06 01:26:01.280062 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-06 01:26:01.280211 | debian-bookworm | changed: All items complete 2026-05-06 01:26:01.280297 | 2026-05-06 01:26:01.866349 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 01:26:02.462770 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 01:26:02.485367 | 2026-05-06 01:26:02.485525 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-06 01:26:03.017905 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011542 2026-05-06 01:26:03.347095 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008910 2026-05-06 01:26:03.371216 | 2026-05-06 01:26:03.371346 | PLAY RECAP 2026-05-06 01:26:03.371431 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-06 01:26:03.371471 | 2026-05-06 01:26:03.508462 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 01:26:03.510709 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 01:26:04.281237 | 2026-05-06 01:26:04.281521 | PLAY [Base post] 2026-05-06 01:26:04.295697 | 2026-05-06 01:26:04.295851 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-06 01:26:04.880840 | debian-bookworm | changed 2026-05-06 01:26:04.889612 | 2026-05-06 01:26:04.889740 | PLAY RECAP 2026-05-06 01:26:04.889815 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-06 01:26:04.889889 | 2026-05-06 01:26:05.021920 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 01:26:05.024632 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-06 01:26:05.856837 | 2026-05-06 01:26:05.857085 | PLAY [Base post-logs] 2026-05-06 01:26:05.868360 | 2026-05-06 01:26:05.868513 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-06 01:26:06.334789 | localhost | changed 2026-05-06 01:26:06.357981 | 2026-05-06 01:26:06.358264 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-06 01:26:06.397993 | localhost | ok 2026-05-06 01:26:06.404815 | 2026-05-06 01:26:06.404998 | TASK [Set zuul-log-path fact] 2026-05-06 01:26:06.422124 | localhost | ok 2026-05-06 01:26:06.433261 | 2026-05-06 01:26:06.433429 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 01:26:06.459835 | localhost | ok 2026-05-06 01:26:06.465235 | 2026-05-06 01:26:06.465394 | TASK [upload-logs : Create log directories] 2026-05-06 01:26:07.051305 | localhost | changed 2026-05-06 01:26:07.057195 | 2026-05-06 01:26:07.057410 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-06 01:26:07.624751 | localhost -> localhost | ok: Runtime: 0:00:00.008326 2026-05-06 01:26:07.634436 | 2026-05-06 01:26:07.634652 | TASK [upload-logs : Upload logs to log server] 2026-05-06 01:26:08.244796 | localhost | Output suppressed because no_log was given 2026-05-06 01:26:08.247776 | 2026-05-06 01:26:08.247943 | LOOP [upload-logs : Compress console log and json output] 2026-05-06 01:26:08.302522 | localhost | skipping: Conditional result was False 2026-05-06 01:26:08.316231 | localhost | skipping: Conditional result was False 2026-05-06 01:26:08.329729 | 2026-05-06 01:26:08.329988 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-06 01:26:08.392832 | localhost | skipping: Conditional result was False 2026-05-06 01:26:08.393534 | 2026-05-06 01:26:08.396854 | localhost | skipping: Conditional result was False 2026-05-06 01:26:08.404635 | 2026-05-06 01:26:08.404890 | LOOP [upload-logs : Upload console log and json output]