2026-04-08 04:35:57.796745 | Job console starting 2026-04-08 04:35:57.816593 | Updating git repos 2026-04-08 04:35:57.828817 | Cloning repos into workspace 2026-04-08 04:35:57.893039 | Restoring repo states 2026-04-08 04:35:57.908575 | Merging changes 2026-04-08 04:35:58.582803 | Checking out repos 2026-04-08 04:35:58.608696 | Preparing playbooks 2026-04-08 04:35:59.292812 | Running Ansible setup 2026-04-08 04:37:25.950380 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-08 04:37:26.780518 | 2026-04-08 04:37:26.780715 | PLAY [Base pre] 2026-04-08 04:37:26.797831 | 2026-04-08 04:37:26.797977 | TASK [Setup log path fact] 2026-04-08 04:37:26.819472 | debian-bookworm | ok 2026-04-08 04:37:26.835454 | 2026-04-08 04:37:26.835608 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-08 04:37:26.865382 | debian-bookworm | ok 2026-04-08 04:37:26.875430 | 2026-04-08 04:37:26.875600 | TASK [emit-job-header : Print job information] 2026-04-08 04:37:26.915519 | # Job Information 2026-04-08 04:37:26.915736 | Ansible Version: 2.16.14 2026-04-08 04:37:26.915782 | Job: cfg-cookiecutter-tox-2024.2 2026-04-08 04:37:26.915817 | Pipeline: check 2026-04-08 04:37:26.915841 | Executor: 521e9411259a 2026-04-08 04:37:26.915863 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/827 2026-04-08 04:37:26.915885 | Event ID: 53d2b680-3304-11f1-967c-28e591774ef3 2026-04-08 04:37:26.920933 | 2026-04-08 04:37:26.921037 | LOOP [emit-job-header : Print node information] 2026-04-08 04:37:27.044687 | debian-bookworm | ok: 2026-04-08 04:37:27.044967 | debian-bookworm | # Node Information 2026-04-08 04:37:27.045015 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-04-08 04:37:27.045052 | debian-bookworm | Hostname: debian 2026-04-08 04:37:27.045084 | debian-bookworm | Username: zuul 2026-04-08 04:37:27.045114 | debian-bookworm | Distro: Debian 12.13 2026-04-08 04:37:27.045147 | debian-bookworm | Provider: regiocloud-a 2026-04-08 04:37:27.045178 | debian-bookworm | Region: 2026-04-08 04:37:27.045207 | debian-bookworm | Label: debian-bookworm 2026-04-08 04:37:27.045234 | debian-bookworm | Product Name: OpenStack Nova 2026-04-08 04:37:27.045262 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1b:156f 2026-04-08 04:37:27.076662 | 2026-04-08 04:37:27.076833 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-08 04:37:27.620776 | debian-bookworm -> localhost | changed 2026-04-08 04:37:27.634082 | 2026-04-08 04:37:27.634241 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-08 04:37:28.739290 | debian-bookworm -> localhost | changed 2026-04-08 04:37:28.760077 | 2026-04-08 04:37:28.760234 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-08 04:37:29.102990 | debian-bookworm -> localhost | ok 2026-04-08 04:37:29.109765 | 2026-04-08 04:37:29.109898 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-08 04:37:29.139861 | debian-bookworm | ok 2026-04-08 04:37:29.159680 | debian-bookworm | included: /var/lib/zuul/builds/7215181f5db543a1834994aefb8fa401/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-08 04:37:29.166386 | 2026-04-08 04:37:29.166512 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-08 04:37:30.526209 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-04-08 04:37:30.526517 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7215181f5db543a1834994aefb8fa401/work/7215181f5db543a1834994aefb8fa401_id_rsa 2026-04-08 04:37:30.526598 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7215181f5db543a1834994aefb8fa401/work/7215181f5db543a1834994aefb8fa401_id_rsa.pub 2026-04-08 04:37:30.526639 | debian-bookworm -> localhost | The key fingerprint is: 2026-04-08 04:37:30.526677 | debian-bookworm -> localhost | SHA256:3mNwKfxIvRU2eAwi5EOk2+TLdCVWhHSyroWXuPH2q+s zuul-build-sshkey 2026-04-08 04:37:30.526725 | debian-bookworm -> localhost | The key's randomart image is: 2026-04-08 04:37:30.526758 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-04-08 04:37:30.526788 | debian-bookworm -> localhost | | o=.+o= | 2026-04-08 04:37:30.526819 | debian-bookworm -> localhost | | + ..* + | 2026-04-08 04:37:30.526878 | debian-bookworm -> localhost | | . + + o * | 2026-04-08 04:37:30.526908 | debian-bookworm -> localhost | | = B = + o | 2026-04-08 04:37:30.526938 | debian-bookworm -> localhost | | . B S + . | 2026-04-08 04:37:30.526972 | debian-bookworm -> localhost | | o % B o | 2026-04-08 04:37:30.527001 | debian-bookworm -> localhost | | = = * | 2026-04-08 04:37:30.527029 | debian-bookworm -> localhost | | . o . | 2026-04-08 04:37:30.527059 | debian-bookworm -> localhost | | .Eoo. | 2026-04-08 04:37:30.527088 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-04-08 04:37:30.527163 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.831941 2026-04-08 04:37:30.536413 | 2026-04-08 04:37:30.536579 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-08 04:37:30.560123 | debian-bookworm | ok 2026-04-08 04:37:30.570951 | debian-bookworm | included: /var/lib/zuul/builds/7215181f5db543a1834994aefb8fa401/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-08 04:37:30.580228 | 2026-04-08 04:37:30.580337 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-08 04:37:30.604878 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:37:30.613295 | 2026-04-08 04:37:30.613423 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-08 04:37:31.345352 | debian-bookworm | changed 2026-04-08 04:37:31.355268 | 2026-04-08 04:37:31.355436 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-08 04:37:31.604231 | debian-bookworm | ok 2026-04-08 04:37:31.613314 | 2026-04-08 04:37:31.613487 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-08 04:37:32.302896 | debian-bookworm | changed 2026-04-08 04:37:32.316789 | 2026-04-08 04:37:32.316987 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-08 04:37:32.987244 | debian-bookworm | changed 2026-04-08 04:37:32.993347 | 2026-04-08 04:37:32.993475 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-08 04:37:33.033745 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:37:33.042234 | 2026-04-08 04:37:33.042373 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-08 04:37:33.594511 | debian-bookworm -> localhost | changed 2026-04-08 04:37:33.614334 | 2026-04-08 04:37:33.614478 | TASK [add-build-sshkey : Add back temp key] 2026-04-08 04:37:33.973377 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7215181f5db543a1834994aefb8fa401/work/7215181f5db543a1834994aefb8fa401_id_rsa (zuul-build-sshkey) 2026-04-08 04:37:33.973685 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020521 2026-04-08 04:37:33.982170 | 2026-04-08 04:37:33.982299 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-08 04:37:34.436928 | debian-bookworm | ok 2026-04-08 04:37:34.443045 | 2026-04-08 04:37:34.443163 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-08 04:37:34.481900 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:37:34.503796 | 2026-04-08 04:37:34.503973 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-08 04:37:34.938799 | debian-bookworm | ok 2026-04-08 04:37:34.950268 | 2026-04-08 04:37:34.950387 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-08 04:37:34.980729 | debian-bookworm | ok 2026-04-08 04:37:34.986047 | 2026-04-08 04:37:34.986167 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-08 04:37:35.322141 | debian-bookworm -> localhost | ok 2026-04-08 04:37:35.328986 | 2026-04-08 04:37:35.329169 | TASK [validate-host : Collect information about the host] 2026-04-08 04:38:16.585059 | debian-bookworm | ok 2026-04-08 04:38:16.600032 | 2026-04-08 04:38:16.600189 | TASK [validate-host : Sanitize hostname] 2026-04-08 04:38:16.743434 | debian-bookworm | ok 2026-04-08 04:38:16.796168 | 2026-04-08 04:38:16.796335 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-08 04:38:17.451857 | debian-bookworm -> localhost | changed 2026-04-08 04:38:17.462981 | 2026-04-08 04:38:17.463175 | TASK [validate-host : Collect information about zuul worker] 2026-04-08 04:38:17.901051 | debian-bookworm | ok 2026-04-08 04:38:17.915189 | 2026-04-08 04:38:17.915425 | TASK [validate-host : Write out all zuul information for each host] 2026-04-08 04:38:18.511416 | debian-bookworm -> localhost | changed 2026-04-08 04:38:18.529805 | 2026-04-08 04:38:18.529945 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-08 04:38:18.839371 | debian-bookworm | ok 2026-04-08 04:38:18.853334 | 2026-04-08 04:38:18.854023 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-08 04:38:19.816805 | debian-bookworm | changed: 2026-04-08 04:38:19.817017 | debian-bookworm | cd+++++++++ src/ 2026-04-08 04:38:19.817050 | debian-bookworm | cd+++++++++ src/github.com/ 2026-04-08 04:38:19.817074 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-04-08 04:38:19.817095 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-04-08 04:38:19.817115 | debian-bookworm | Containerfile 2026-04-08 04:38:19.817244 | debian-bookworm | localhost | changed 2026-04-08 04:40:38.695180 | 2026-04-08 04:40:38.695346 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-04-08 04:40:38.760942 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:40:38.773810 | 2026-04-08 04:40:38.773993 | TASK [fetch-tox-output : Set envlist fact] 2026-04-08 04:40:38.831322 | debian-bookworm | ok 2026-04-08 04:40:38.840134 | 2026-04-08 04:40:38.840289 | TASK [fetch-tox-output : Get tox version] 2026-04-08 04:40:39.375344 | debian-bookworm | ERROR 2026-04-08 04:40:39.375976 | debian-bookworm | { 2026-04-08 04:40:39.376087 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2026-04-08 04:40:39.376183 | debian-bookworm | "rc": 2 2026-04-08 04:40:39.376249 | debian-bookworm | } failure 2026-04-08 04:40:39.380266 | 2026-04-08 04:40:39.380486 | PLAY RECAP 2026-04-08 04:40:39.380637 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2026-04-08 04:40:39.380708 | 2026-04-08 04:40:39.511352 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-04-08 04:40:39.512627 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-08 04:40:40.294604 | 2026-04-08 04:40:40.294798 | PLAY [all] 2026-04-08 04:40:40.311542 | 2026-04-08 04:40:40.311726 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-04-08 04:40:40.558664 | debian-bookworm | changed: non-zero return code 2026-04-08 04:40:40.565873 | 2026-04-08 04:40:40.566018 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-04-08 04:40:40.591491 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:40:40.601375 | 2026-04-08 04:40:40.601564 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-04-08 04:40:40.638665 | 2026-04-08 04:40:40.639111 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-04-08 04:40:40.677693 | 2026-04-08 04:40:40.677971 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-04-08 04:40:40.703034 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:40:40.718016 | 2026-04-08 04:40:40.718195 | LOOP [fetch-subunit-output : Generate subunit file] 2026-04-08 04:40:40.758124 | 2026-04-08 04:40:40.758430 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-04-08 04:40:40.783591 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:40:40.798117 | 2026-04-08 04:40:40.798286 | TASK [fetch-subunit-output : Remove the temporary file] 2026-04-08 04:40:40.823057 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:40:40.833160 | 2026-04-08 04:40:40.833316 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-04-08 04:40:40.857583 | debian-bookworm | skipping: Conditional result was False 2026-04-08 04:40:40.870566 | 2026-04-08 04:40:40.870682 | PLAY RECAP 2026-04-08 04:40:40.870761 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-04-08 04:40:40.870803 | 2026-04-08 04:40:41.005326 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-04-08 04:40:41.006459 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-08 04:40:41.865080 | 2026-04-08 04:40:41.865255 | PLAY [Base post] 2026-04-08 04:40:41.879203 | 2026-04-08 04:40:41.879352 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-08 04:40:42.444674 | debian-bookworm | changed 2026-04-08 04:40:42.453696 | 2026-04-08 04:40:42.453821 | PLAY RECAP 2026-04-08 04:40:42.453895 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-08 04:40:42.453973 | 2026-04-08 04:40:42.579655 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-08 04:40:42.582289 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-08 04:40:43.399660 | 2026-04-08 04:40:43.399856 | PLAY [Base post-logs] 2026-04-08 04:40:43.411401 | 2026-04-08 04:40:43.411575 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-08 04:40:43.898399 | localhost | changed 2026-04-08 04:40:43.913906 | 2026-04-08 04:40:43.914076 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-08 04:40:43.941051 | localhost | ok 2026-04-08 04:40:43.946352 | 2026-04-08 04:40:43.946502 | TASK [Set zuul-log-path fact] 2026-04-08 04:40:43.962973 | localhost | ok 2026-04-08 04:40:43.972428 | 2026-04-08 04:40:43.972601 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-08 04:40:43.999218 | localhost | ok 2026-04-08 04:40:44.005335 | 2026-04-08 04:40:44.005510 | TASK [upload-logs : Create log directories] 2026-04-08 04:40:44.513223 | localhost | changed 2026-04-08 04:40:44.517661 | 2026-04-08 04:40:44.517811 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-08 04:40:45.069744 | localhost -> localhost | ok: Runtime: 0:00:00.006798 2026-04-08 04:40:45.073880 | 2026-04-08 04:40:45.074061 | TASK [upload-logs : Upload logs to log server] 2026-04-08 04:40:45.667415 | localhost | Output suppressed because no_log was given 2026-04-08 04:40:45.670296 | 2026-04-08 04:40:45.670464 | LOOP [upload-logs : Compress console log and json output] 2026-04-08 04:40:45.751016 | localhost | skipping: Conditional result was False 2026-04-08 04:40:45.755979 | localhost | skipping: Conditional result was False 2026-04-08 04:40:45.763188 | 2026-04-08 04:40:45.763411 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-08 04:40:45.811933 | localhost | skipping: Conditional result was False 2026-04-08 04:40:45.812551 | 2026-04-08 04:40:45.815961 | localhost | skipping: Conditional result was False 2026-04-08 04:40:45.828192 | 2026-04-08 04:40:45.828379 | LOOP [upload-logs : Upload console log and json output]