2025-10-10 22:39:19.057541 | Job console starting 2025-10-10 22:39:19.104281 | Updating git repos 2025-10-10 22:39:19.140604 | Cloning repos into workspace 2025-10-10 22:39:19.189613 | Restoring repo states 2025-10-10 22:39:19.194310 | Merging changes 2025-10-10 22:39:19.695622 | Checking out repos 2025-10-10 22:39:19.730516 | Preparing playbooks 2025-10-10 22:39:20.562383 | Running Ansible setup 2025-10-10 22:40:46.965495 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-10 22:40:47.713219 | 2025-10-10 22:40:47.713371 | PLAY [Base pre] 2025-10-10 22:40:47.729208 | 2025-10-10 22:40:47.729326 | TASK [Setup log path fact] 2025-10-10 22:40:47.748589 | debian-bookworm | ok 2025-10-10 22:40:47.763506 | 2025-10-10 22:40:47.763632 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-10 22:40:47.803149 | debian-bookworm | ok 2025-10-10 22:40:47.812801 | 2025-10-10 22:40:47.812908 | TASK [emit-job-header : Print job information] 2025-10-10 22:40:47.851712 | # Job Information 2025-10-10 22:40:47.851883 | Ansible Version: 2.16.14 2025-10-10 22:40:47.851920 | Job: openstack-project-manager-tox 2025-10-10 22:40:47.851952 | Pipeline: check 2025-10-10 22:40:47.851975 | Executor: 521e9411259a 2025-10-10 22:40:47.851996 | Triggered by: https://github.com/osism/openstack-project-manager/pull/244 2025-10-10 22:40:47.852017 | Event ID: ef92ca50-a629-11f0-8bf8-4f3101a5b87b 2025-10-10 22:40:47.856880 | 2025-10-10 22:40:47.856989 | LOOP [emit-job-header : Print node information] 2025-10-10 22:40:47.967741 | debian-bookworm | ok: 2025-10-10 22:40:47.968084 | debian-bookworm | # Node Information 2025-10-10 22:40:47.968160 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-10 22:40:47.968214 | debian-bookworm | Hostname: debian 2025-10-10 22:40:47.968259 | debian-bookworm | Username: zuul 2025-10-10 22:40:47.968302 | debian-bookworm | Distro: Debian 12.12 2025-10-10 22:40:47.968352 | debian-bookworm | Provider: regiocloud-a 2025-10-10 22:40:47.968396 | debian-bookworm | Region: 2025-10-10 22:40:47.968440 | debian-bookworm | Label: debian-bookworm 2025-10-10 22:40:47.968498 | debian-bookworm | Product Name: OpenStack Nova 2025-10-10 22:40:47.968542 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feaf:44c8 2025-10-10 22:40:47.984186 | 2025-10-10 22:40:47.984310 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-10 22:40:48.473608 | debian-bookworm -> localhost | changed 2025-10-10 22:40:48.484368 | 2025-10-10 22:40:48.484657 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-10 22:40:49.561139 | debian-bookworm -> localhost | changed 2025-10-10 22:40:49.580857 | 2025-10-10 22:40:49.581001 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-10 22:40:49.858811 | debian-bookworm -> localhost | ok 2025-10-10 22:40:49.865139 | 2025-10-10 22:40:49.865246 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-10 22:40:49.896307 | debian-bookworm | ok 2025-10-10 22:40:49.913631 | debian-bookworm | included: /var/lib/zuul/builds/ab27a3a379fd4f389b3f670e77b49732/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-10 22:40:49.919566 | 2025-10-10 22:40:49.919658 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-10 22:40:51.631908 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-10 22:40:51.632453 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ab27a3a379fd4f389b3f670e77b49732/work/ab27a3a379fd4f389b3f670e77b49732_id_rsa 2025-10-10 22:40:51.632599 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ab27a3a379fd4f389b3f670e77b49732/work/ab27a3a379fd4f389b3f670e77b49732_id_rsa.pub 2025-10-10 22:40:51.632676 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-10 22:40:51.632746 | debian-bookworm -> localhost | SHA256:3wTR+RmPy8jsbwCYuiN4zDbRtd7fTEwoA7pWUnNdN2A zuul-build-sshkey 2025-10-10 22:40:51.632833 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-10 22:40:51.632896 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-10 22:40:51.632955 | debian-bookworm -> localhost | | .. Eo..| 2025-10-10 22:40:51.633018 | debian-bookworm -> localhost | | o+....| 2025-10-10 22:40:51.633076 | debian-bookworm -> localhost | | +oo .. = | 2025-10-10 22:40:51.633133 | debian-bookworm -> localhost | | o++.. .+ .| 2025-10-10 22:40:51.633190 | debian-bookworm -> localhost | | .ooS.o+oo.. | 2025-10-10 22:40:51.633259 | debian-bookworm -> localhost | | . o+.. ==oo | 2025-10-10 22:40:51.633319 | debian-bookworm -> localhost | | + .oo .....o | 2025-10-10 22:40:51.633376 | debian-bookworm -> localhost | | . B.o . . .+. | 2025-10-10 22:40:51.633435 | debian-bookworm -> localhost | | o o . ..o+ | 2025-10-10 22:40:51.633517 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-10 22:40:51.633662 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.205139 2025-10-10 22:40:51.647834 | 2025-10-10 22:40:51.647978 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-10 22:40:51.685860 | debian-bookworm | ok 2025-10-10 22:40:51.699163 | debian-bookworm | included: /var/lib/zuul/builds/ab27a3a379fd4f389b3f670e77b49732/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-10 22:40:51.708656 | 2025-10-10 22:40:51.708748 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-10 22:40:51.732249 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:40:51.739575 | 2025-10-10 22:40:51.739679 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-10 22:40:52.384354 | debian-bookworm | changed 2025-10-10 22:40:52.392430 | 2025-10-10 22:40:52.392567 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-10 22:40:52.651362 | debian-bookworm | ok 2025-10-10 22:40:52.660113 | 2025-10-10 22:40:52.660240 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-10 22:40:53.343404 | debian-bookworm | changed 2025-10-10 22:40:53.352441 | 2025-10-10 22:40:53.352584 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-10 22:40:53.995963 | debian-bookworm | changed 2025-10-10 22:40:54.003915 | 2025-10-10 22:40:54.004046 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-10 22:40:54.029592 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:40:54.037976 | 2025-10-10 22:40:54.038096 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-10 22:40:54.471342 | debian-bookworm -> localhost | changed 2025-10-10 22:40:54.484904 | 2025-10-10 22:40:54.485027 | TASK [add-build-sshkey : Add back temp key] 2025-10-10 22:40:54.824250 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ab27a3a379fd4f389b3f670e77b49732/work/ab27a3a379fd4f389b3f670e77b49732_id_rsa (zuul-build-sshkey) 2025-10-10 22:40:54.824814 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015222 2025-10-10 22:40:54.840487 | 2025-10-10 22:40:54.840626 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-10 22:40:55.233392 | debian-bookworm | ok 2025-10-10 22:40:55.242160 | 2025-10-10 22:40:55.242312 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-10 22:40:55.277625 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:40:55.297840 | 2025-10-10 22:40:55.297978 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-10 22:40:55.726301 | debian-bookworm | ok 2025-10-10 22:40:55.740658 | 2025-10-10 22:40:55.740783 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-10 22:40:55.784964 | debian-bookworm | ok 2025-10-10 22:40:55.792127 | 2025-10-10 22:40:55.792249 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-10 22:40:56.079702 | debian-bookworm -> localhost | ok 2025-10-10 22:40:56.093657 | 2025-10-10 22:40:56.093803 | TASK [validate-host : Collect information about the host] 2025-10-10 22:41:37.195472 | debian-bookworm | ok 2025-10-10 22:41:37.209264 | 2025-10-10 22:41:37.209380 | TASK [validate-host : Sanitize hostname] 2025-10-10 22:41:37.317173 | debian-bookworm | ok 2025-10-10 22:41:37.357169 | 2025-10-10 22:41:37.357292 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-10 22:41:37.938044 | debian-bookworm -> localhost | changed 2025-10-10 22:41:37.951465 | 2025-10-10 22:41:37.951635 | TASK [validate-host : Collect information about zuul worker] 2025-10-10 22:41:38.381608 | debian-bookworm | ok 2025-10-10 22:41:38.389223 | 2025-10-10 22:41:38.389353 | TASK [validate-host : Write out all zuul information for each host] 2025-10-10 22:41:38.913320 | debian-bookworm -> localhost | changed 2025-10-10 22:41:38.932372 | 2025-10-10 22:41:38.932540 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-10 22:41:39.214532 | debian-bookworm | ok 2025-10-10 22:41:39.221405 | 2025-10-10 22:41:39.221540 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-10 22:41:40.056728 | debian-bookworm | changed: 2025-10-10 22:41:40.057056 | debian-bookworm | cd+++++++++ src/ 2025-10-10 22:41:40.058239 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-10 22:41:40.058323 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-10 22:41:40.058385 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-project-manager/ 2025-10-10 22:41:40.058444 | debian-bookworm | localhost | changed 2025-10-10 22:43:58.514198 | 2025-10-10 22:43:58.514375 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-10 22:43:58.570482 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:43:58.581950 | 2025-10-10 22:43:58.582110 | TASK [fetch-tox-output : Set envlist fact] 2025-10-10 22:43:58.638701 | debian-bookworm | ok 2025-10-10 22:43:58.647677 | 2025-10-10 22:43:58.647797 | TASK [fetch-tox-output : Get tox version] 2025-10-10 22:43:59.178086 | debian-bookworm | ERROR 2025-10-10 22:43:59.178776 | debian-bookworm | { 2025-10-10 22:43:59.178951 | debian-bookworm | "msg": "[Errno 2] No such file or directory: b'tox'", 2025-10-10 22:43:59.179043 | debian-bookworm | "rc": 2 2025-10-10 22:43:59.179107 | debian-bookworm | } failure 2025-10-10 22:43:59.182956 | 2025-10-10 22:43:59.183112 | PLAY RECAP 2025-10-10 22:43:59.183229 | debian-bookworm | ok: 3 changed: 1 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2025-10-10 22:43:59.183280 | 2025-10-10 22:43:59.327035 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-10 22:43:59.328650 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-10 22:44:00.071618 | 2025-10-10 22:44:00.071804 | PLAY [all] 2025-10-10 22:44:00.087797 | 2025-10-10 22:44:00.087928 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-10 22:44:00.316898 | debian-bookworm | changed: non-zero return code 2025-10-10 22:44:00.325023 | 2025-10-10 22:44:00.325194 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-10 22:44:00.349722 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:44:00.358250 | 2025-10-10 22:44:00.358395 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-10 22:44:00.394292 | 2025-10-10 22:44:00.394613 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-10 22:44:00.434914 | 2025-10-10 22:44:00.435168 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-10 22:44:00.459857 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:44:00.470348 | 2025-10-10 22:44:00.470511 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-10 22:44:00.503888 | 2025-10-10 22:44:00.504143 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-10 22:44:00.528099 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:44:00.539821 | 2025-10-10 22:44:00.539972 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-10 22:44:00.564055 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:44:00.574525 | 2025-10-10 22:44:00.574686 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-10 22:44:00.599069 | debian-bookworm | skipping: Conditional result was False 2025-10-10 22:44:00.607430 | 2025-10-10 22:44:00.607544 | PLAY RECAP 2025-10-10 22:44:00.607610 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-10 22:44:00.607656 | 2025-10-10 22:44:00.726208 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-10 22:44:00.728911 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-10 22:44:01.444230 | 2025-10-10 22:44:01.444388 | PLAY [Base post] 2025-10-10 22:44:01.457436 | 2025-10-10 22:44:01.457573 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-10 22:44:02.009610 | debian-bookworm | changed 2025-10-10 22:44:02.018760 | 2025-10-10 22:44:02.018913 | PLAY RECAP 2025-10-10 22:44:02.018992 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-10 22:44:02.019069 | 2025-10-10 22:44:02.130991 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-10 22:44:02.132061 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-10 22:44:02.881713 | 2025-10-10 22:44:02.881876 | PLAY [Base post-logs] 2025-10-10 22:44:02.892061 | 2025-10-10 22:44:02.892192 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-10 22:44:03.337425 | localhost | changed 2025-10-10 22:44:03.354775 | 2025-10-10 22:44:03.354975 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-10 22:44:03.393453 | localhost | ok 2025-10-10 22:44:03.400355 | 2025-10-10 22:44:03.400537 | TASK [Set zuul-log-path fact] 2025-10-10 22:44:03.418907 | localhost | ok 2025-10-10 22:44:03.431310 | 2025-10-10 22:44:03.431440 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-10 22:44:03.467772 | localhost | ok 2025-10-10 22:44:03.472557 | 2025-10-10 22:44:03.472696 | TASK [upload-logs : Create log directories] 2025-10-10 22:44:03.971177 | localhost | changed 2025-10-10 22:44:03.977077 | 2025-10-10 22:44:03.977258 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-10 22:44:04.483986 | localhost -> localhost | ok: Runtime: 0:00:00.007028 2025-10-10 22:44:04.488146 | 2025-10-10 22:44:04.488267 | TASK [upload-logs : Upload logs to log server] 2025-10-10 22:44:05.023684 | localhost | Output suppressed because no_log was given 2025-10-10 22:44:05.027635 | 2025-10-10 22:44:05.027820 | LOOP [upload-logs : Compress console log and json output] 2025-10-10 22:44:05.079730 | localhost | skipping: Conditional result was False 2025-10-10 22:44:05.085512 | localhost | skipping: Conditional result was False 2025-10-10 22:44:05.099223 | 2025-10-10 22:44:05.099454 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-10 22:44:05.143911 | localhost | skipping: Conditional result was False 2025-10-10 22:44:05.144409 | 2025-10-10 22:44:05.148541 | localhost | skipping: Conditional result was False 2025-10-10 22:44:05.162215 | 2025-10-10 22:44:05.162553 | LOOP [upload-logs : Upload console log and json output]