2026-04-07 18:10:40.081907 | Job console starting 2026-04-07 18:10:40.140126 | Updating git repos 2026-04-07 18:10:40.179558 | Cloning repos into workspace 2026-04-07 18:10:40.279924 | Restoring repo states 2026-04-07 18:10:40.299514 | Merging changes 2026-04-07 18:10:41.183353 | Checking out repos 2026-04-07 18:10:41.307386 | Preparing playbooks 2026-04-07 18:10:42.864153 | Running Ansible setup 2026-04-07 18:12:09.359036 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-07 18:12:10.149344 | 2026-04-07 18:12:10.149530 | PLAY [Base pre] 2026-04-07 18:12:10.166751 | 2026-04-07 18:12:10.166939 | TASK [Setup log path fact] 2026-04-07 18:12:10.186636 | debian-bookworm | ok 2026-04-07 18:12:10.204291 | 2026-04-07 18:12:10.204463 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-07 18:12:10.241823 | debian-bookworm | ok 2026-04-07 18:12:10.253722 | 2026-04-07 18:12:10.253882 | TASK [emit-job-header : Print job information] 2026-04-07 18:12:10.299916 | # Job Information 2026-04-07 18:12:10.300253 | Ansible Version: 2.16.14 2026-04-07 18:12:10.300331 | Job: cfg-cookiecutter-tox-2024.2 2026-04-07 18:12:10.300388 | Pipeline: check 2026-04-07 18:12:10.300428 | Executor: 521e9411259a 2026-04-07 18:12:10.300465 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/801 2026-04-07 18:12:10.300504 | Event ID: 0fea2450-32ad-11f1-8f04-c3e3c16150be 2026-04-07 18:12:10.307770 | 2026-04-07 18:12:10.307947 | LOOP [emit-job-header : Print node information] 2026-04-07 18:12:10.415743 | debian-bookworm | ok: 2026-04-07 18:12:10.416076 | debian-bookworm | # Node Information 2026-04-07 18:12:10.416442 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-04-07 18:12:10.418264 | debian-bookworm | Hostname: debian 2026-04-07 18:12:10.418390 | debian-bookworm | Username: zuul 2026-04-07 18:12:10.418467 | debian-bookworm | Distro: Debian 12.13 2026-04-07 18:12:10.418557 | debian-bookworm | Provider: regiocloud-a 2026-04-07 18:12:10.418626 | debian-bookworm | Region: 2026-04-07 18:12:10.418690 | debian-bookworm | Label: debian-bookworm 2026-04-07 18:12:10.418750 | debian-bookworm | Product Name: OpenStack Nova 2026-04-07 18:12:10.418808 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2e:989e 2026-04-07 18:12:10.443187 | 2026-04-07 18:12:10.443366 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-07 18:12:10.987857 | debian-bookworm -> localhost | changed 2026-04-07 18:12:10.995967 | 2026-04-07 18:12:10.996092 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-07 18:12:12.083046 | debian-bookworm -> localhost | changed 2026-04-07 18:12:12.095097 | 2026-04-07 18:12:12.095257 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-07 18:12:12.414243 | debian-bookworm -> localhost | ok 2026-04-07 18:12:12.420889 | 2026-04-07 18:12:12.421026 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-07 18:12:12.452214 | debian-bookworm | ok 2026-04-07 18:12:12.476404 | debian-bookworm | included: /var/lib/zuul/builds/a401e4bfe8b54d4e935df55035ee5dd6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-07 18:12:12.483377 | 2026-04-07 18:12:12.483505 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-07 18:12:14.585717 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-04-07 18:12:14.586408 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a401e4bfe8b54d4e935df55035ee5dd6/work/a401e4bfe8b54d4e935df55035ee5dd6_id_rsa 2026-04-07 18:12:14.586579 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a401e4bfe8b54d4e935df55035ee5dd6/work/a401e4bfe8b54d4e935df55035ee5dd6_id_rsa.pub 2026-04-07 18:12:14.586666 | debian-bookworm -> localhost | The key fingerprint is: 2026-04-07 18:12:14.586747 | debian-bookworm -> localhost | SHA256:zPpyDCM/fmkxAX9ROV2dTyNJUPFgx6xKKPbvGZ4I5PM zuul-build-sshkey 2026-04-07 18:12:14.586886 | debian-bookworm -> localhost | The key's randomart image is: 2026-04-07 18:12:14.586963 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-04-07 18:12:14.587027 | debian-bookworm -> localhost | | o=O=o+| 2026-04-07 18:12:14.587091 | debian-bookworm -> localhost | | . . +o==o| 2026-04-07 18:12:14.587190 | debian-bookworm -> localhost | | o .. .ooo| 2026-04-07 18:12:14.587256 | debian-bookworm -> localhost | | =o... . .| 2026-04-07 18:12:14.587315 | debian-bookworm -> localhost | | ..So. . | 2026-04-07 18:12:14.587386 | debian-bookworm -> localhost | | . =.o. . | 2026-04-07 18:12:14.587444 | debian-bookworm -> localhost | | o.B +.. | 2026-04-07 18:12:14.587501 | debian-bookworm -> localhost | | +.X o.+ | 2026-04-07 18:12:14.587559 | debian-bookworm -> localhost | | ..*.E.= | 2026-04-07 18:12:14.587616 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-04-07 18:12:14.587764 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.544250 2026-04-07 18:12:14.600691 | 2026-04-07 18:12:14.600836 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-07 18:12:14.623304 | debian-bookworm | ok 2026-04-07 18:12:14.636058 | debian-bookworm | included: /var/lib/zuul/builds/a401e4bfe8b54d4e935df55035ee5dd6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-07 18:12:14.646855 | 2026-04-07 18:12:14.646969 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-07 18:12:14.671660 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:12:14.683466 | 2026-04-07 18:12:14.683640 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-07 18:12:15.338327 | debian-bookworm | changed 2026-04-07 18:12:15.353397 | 2026-04-07 18:12:15.353669 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-07 18:12:15.617432 | debian-bookworm | ok 2026-04-07 18:12:15.626455 | 2026-04-07 18:12:15.626614 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-07 18:12:16.254941 | debian-bookworm | changed 2026-04-07 18:12:16.266892 | 2026-04-07 18:12:16.267021 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-07 18:12:16.896899 | debian-bookworm | changed 2026-04-07 18:12:16.902941 | 2026-04-07 18:12:16.903059 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-07 18:12:16.938257 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:12:16.952447 | 2026-04-07 18:12:16.952612 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-07 18:12:17.437710 | debian-bookworm -> localhost | changed 2026-04-07 18:12:17.456738 | 2026-04-07 18:12:17.456877 | TASK [add-build-sshkey : Add back temp key] 2026-04-07 18:12:17.817541 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a401e4bfe8b54d4e935df55035ee5dd6/work/a401e4bfe8b54d4e935df55035ee5dd6_id_rsa (zuul-build-sshkey) 2026-04-07 18:12:17.818113 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018701 2026-04-07 18:12:17.834666 | 2026-04-07 18:12:17.834818 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-07 18:12:18.231501 | debian-bookworm | ok 2026-04-07 18:12:18.237090 | 2026-04-07 18:12:18.237222 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-07 18:12:18.271399 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:12:18.288745 | 2026-04-07 18:12:18.288908 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-07 18:12:18.747007 | debian-bookworm | ok 2026-04-07 18:12:18.762652 | 2026-04-07 18:12:18.762797 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-07 18:12:18.811252 | debian-bookworm | ok 2026-04-07 18:12:18.819538 | 2026-04-07 18:12:18.819677 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-07 18:12:19.156427 | debian-bookworm -> localhost | ok 2026-04-07 18:12:19.164281 | 2026-04-07 18:12:19.164409 | TASK [validate-host : Collect information about the host] 2026-04-07 18:13:00.352049 | debian-bookworm | ok 2026-04-07 18:13:00.362895 | 2026-04-07 18:13:00.363016 | TASK [validate-host : Sanitize hostname] 2026-04-07 18:13:00.496809 | debian-bookworm | ok 2026-04-07 18:13:00.545046 | 2026-04-07 18:13:00.545201 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-07 18:13:01.069785 | debian-bookworm -> localhost | changed 2026-04-07 18:13:01.083198 | 2026-04-07 18:13:01.083388 | TASK [validate-host : Collect information about zuul worker] 2026-04-07 18:13:01.516836 | debian-bookworm | ok 2026-04-07 18:13:01.524793 | 2026-04-07 18:13:01.524946 | TASK [validate-host : Write out all zuul information for each host] 2026-04-07 18:13:02.047259 | debian-bookworm -> localhost | changed 2026-04-07 18:13:02.057560 | 2026-04-07 18:13:02.057689 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-07 18:13:02.331733 | debian-bookworm | ok 2026-04-07 18:13:02.339652 | 2026-04-07 18:13:02.339780 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-07 18:13:03.235038 | debian-bookworm | changed: 2026-04-07 18:13:03.235264 | debian-bookworm | cd+++++++++ src/ 2026-04-07 18:13:03.235300 | debian-bookworm | cd+++++++++ src/github.com/ 2026-04-07 18:13:03.235324 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-04-07 18:13:03.235345 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-04-07 18:13:03.235366 | debian-bookworm | Containerfile 2026-04-07 18:13:03.235497 | debian-bookworm | localhost | changed 2026-04-07 18:15:22.246314 | 2026-04-07 18:15:22.246480 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-04-07 18:15:22.302438 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:15:22.311090 | 2026-04-07 18:15:22.311254 | TASK [fetch-tox-output : Set envlist fact] 2026-04-07 18:15:22.353099 | debian-bookworm | ok 2026-04-07 18:15:22.360555 | 2026-04-07 18:15:22.360673 | TASK [fetch-tox-output : Get tox version] 2026-04-07 18:15:22.896021 | debian-bookworm | ERROR 2026-04-07 18:15:22.896645 | debian-bookworm | { 2026-04-07 18:15:22.896749 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2026-04-07 18:15:22.896830 | debian-bookworm | "rc": 2 2026-04-07 18:15:22.896889 | debian-bookworm | } failure 2026-04-07 18:15:22.900532 | 2026-04-07 18:15:22.900663 | PLAY RECAP 2026-04-07 18:15:22.900762 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2026-04-07 18:15:22.900810 | 2026-04-07 18:15:23.024174 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-04-07 18:15:23.026686 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-07 18:15:23.754721 | 2026-04-07 18:15:23.754899 | PLAY [all] 2026-04-07 18:15:23.770082 | 2026-04-07 18:15:23.770232 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-04-07 18:15:24.021953 | debian-bookworm | changed: non-zero return code 2026-04-07 18:15:24.031107 | 2026-04-07 18:15:24.031290 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-04-07 18:15:24.066642 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:15:24.079033 | 2026-04-07 18:15:24.079261 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-04-07 18:15:24.126632 | 2026-04-07 18:15:24.126960 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-04-07 18:15:24.177224 | 2026-04-07 18:15:24.177513 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-04-07 18:15:24.212833 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:15:24.224337 | 2026-04-07 18:15:24.224506 | LOOP [fetch-subunit-output : Generate subunit file] 2026-04-07 18:15:24.271545 | 2026-04-07 18:15:24.271842 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-04-07 18:15:24.307002 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:15:24.319320 | 2026-04-07 18:15:24.319495 | TASK [fetch-subunit-output : Remove the temporary file] 2026-04-07 18:15:24.354747 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:15:24.368381 | 2026-04-07 18:15:24.368538 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-04-07 18:15:24.403893 | debian-bookworm | skipping: Conditional result was False 2026-04-07 18:15:24.417514 | 2026-04-07 18:15:24.417627 | PLAY RECAP 2026-04-07 18:15:24.417709 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-04-07 18:15:24.417748 | 2026-04-07 18:15:24.541077 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-07 18:15:24.542982 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-07 18:15:25.263976 | 2026-04-07 18:15:25.264156 | PLAY [Base post] 2026-04-07 18:15:25.277688 | 2026-04-07 18:15:25.277825 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-07 18:15:25.876047 | debian-bookworm | changed 2026-04-07 18:15:25.882453 | 2026-04-07 18:15:25.882565 | PLAY RECAP 2026-04-07 18:15:25.882626 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-07 18:15:25.882690 | 2026-04-07 18:15:25.997895 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-07 18:15:25.999003 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-07 18:15:26.769516 | 2026-04-07 18:15:26.769680 | PLAY [Base post-logs] 2026-04-07 18:15:26.780310 | 2026-04-07 18:15:26.780447 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-07 18:15:27.257737 | localhost | changed 2026-04-07 18:15:27.272492 | 2026-04-07 18:15:27.272665 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-07 18:15:27.300564 | localhost | ok 2026-04-07 18:15:27.303728 | 2026-04-07 18:15:27.303829 | TASK [Set zuul-log-path fact] 2026-04-07 18:15:27.318960 | localhost | ok 2026-04-07 18:15:27.326943 | 2026-04-07 18:15:27.327055 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-07 18:15:27.351679 | localhost | ok 2026-04-07 18:15:27.354737 | 2026-04-07 18:15:27.354858 | TASK [upload-logs : Create log directories] 2026-04-07 18:15:27.841187 | localhost | changed 2026-04-07 18:15:27.844737 | 2026-04-07 18:15:27.844855 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-07 18:15:28.357690 | localhost -> localhost | ok: Runtime: 0:00:00.007508 2026-04-07 18:15:28.365723 | 2026-04-07 18:15:28.365932 | TASK [upload-logs : Upload logs to log server] 2026-04-07 18:15:28.950298 | localhost | Output suppressed because no_log was given 2026-04-07 18:15:28.954296 | 2026-04-07 18:15:28.954487 | LOOP [upload-logs : Compress console log and json output] 2026-04-07 18:15:29.004192 | localhost | skipping: Conditional result was False 2026-04-07 18:15:29.009174 | localhost | skipping: Conditional result was False 2026-04-07 18:15:29.018515 | 2026-04-07 18:15:29.018747 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-07 18:15:29.066106 | localhost | skipping: Conditional result was False 2026-04-07 18:15:29.066752 | 2026-04-07 18:15:29.070004 | localhost | skipping: Conditional result was False 2026-04-07 18:15:29.083570 | 2026-04-07 18:15:29.084968 | LOOP [upload-logs : Upload console log and json output]